builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-044 starttime: 1482499146.78 results: success (0) buildid: 20161223051838 builduid: 64f4e99093e1445fbb178f64a4fcfeb9 revision: 2a762f5632902fe62907175d2872c2c307cf9ef8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:06.778835) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:06.779226) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:06.779760) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:06.875866) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:06.876132) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-23 05:19:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 30.4M=0s 2016-12-23 05:19:08 (30.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.157803 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:07.051084) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:07.051390) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015912 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:07.102173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:07.102517) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 2a762f5632902fe62907175d2872c2c307cf9ef8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 2a762f5632902fe62907175d2872c2c307cf9ef8 --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=88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-23 05:19:08,384 truncating revision to first 12 chars 2016-12-23 05:19:08,384 Setting DEBUG logging. 2016-12-23 05:19:08,384 attempt 1/10 2016-12-23 05:19:08,384 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/2a762f563290?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 05:19:08,791 unpacking tar archive at: mozilla-inbound-2a762f563290/testing/mozharness/ program finished with exit code 0 elapsedTime=0.722572 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:07.838488) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:07.838890) ========= script_repo_revision: 2a762f5632902fe62907175d2872c2c307cf9ef8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:07.839282) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:07.839583) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 05:19:07.849447) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-23 05:19:07.849715) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:19:09 INFO - MultiFileLogger online at 20161223 05:19:09 in /builds/slave/test 05:19:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 05:19:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:19:09 INFO - {'append_to_log': False, 05:19:09 INFO - 'base_work_dir': '/builds/slave/test', 05:19:09 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 05:19:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:19:09 INFO - 'branch': 'Mozilla-Inbound', 05:19:09 INFO - 'buildbot_json_path': 'buildprops.json', 05:19:09 INFO - 'config_files': ('talos/linux_config.py',), 05:19:09 INFO - 'default_actions': ('clobber', 05:19:09 INFO - 'read-buildbot-config', 05:19:09 INFO - 'download-and-extract', 05:19:09 INFO - 'populate-webroot', 05:19:09 INFO - 'create-virtualenv', 05:19:09 INFO - 'install', 05:19:09 INFO - 'run-tests'), 05:19:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:19:09 INFO - 'download_minidump_stackwalk': True, 05:19:09 INFO - 'download_symbols': 'ondemand', 05:19:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:19:09 INFO - 'tooltool.py': '/tools/tooltool.py', 05:19:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:19:09 INFO - '/tools/misc-python/virtualenv.py')}, 05:19:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:19:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:19:09 INFO - 'installer_path': 'installer.exe', 05:19:09 INFO - 'log_level': 'info', 05:19:09 INFO - 'log_name': 'talos', 05:19:09 INFO - 'log_to_console': True, 05:19:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:19:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:19:09 INFO - 'opt_config_files': (), 05:19:09 INFO - 'pip_index': False, 05:19:09 INFO - 'sps_profile': False, 05:19:09 INFO - 'sps_profile_interval': 0, 05:19:09 INFO - 'suite': 'dromaeojs', 05:19:09 INFO - 'system_bits': '32', 05:19:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:19:09 INFO - 'title': 'talos-linux64-ix-044', 05:19:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:19:09 INFO - 'use_talos_json': True, 05:19:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:19:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:19:09 INFO - 'work_dir': 'build'} 05:19:09 INFO - [mozharness: 2016-12-23 13:19:09.233027Z] Running clobber step. 05:19:09 INFO - Running pre-action listener: _resource_record_pre_action 05:19:09 INFO - Running main action method: clobber 05:19:09 INFO - rmtree: /builds/slave/test/build 05:19:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:19:09 INFO - Running post-action listener: _resource_record_post_action 05:19:09 INFO - [mozharness: 2016-12-23 13:19:09.695454Z] Finished clobber step (success) 05:19:09 INFO - [mozharness: 2016-12-23 13:19:09.695555Z] Running read-buildbot-config step. 05:19:09 INFO - Running pre-action listener: _resource_record_pre_action 05:19:09 INFO - Running main action method: read_buildbot_config 05:19:09 INFO - Using buildbot properties: 05:19:09 INFO - { 05:19:09 INFO - "project": "", 05:19:09 INFO - "product": "firefox", 05:19:09 INFO - "script_repo_revision": "production", 05:19:09 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 05:19:09 INFO - "repository": "", 05:19:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 05:19:09 INFO - "buildid": "20161223051838", 05:19:09 INFO - "stage_platform": "linux64-pgo", 05:19:09 INFO - "basedir": "/builds/slave/test", 05:19:09 INFO - "buildnumber": 3113, 05:19:09 INFO - "platform": "ubuntu64_hw", 05:19:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:19:09 INFO - "slavebuilddir": "test", 05:19:09 INFO - "branch": "mozilla-inbound", 05:19:09 INFO - "revision": "2a762f5632902fe62907175d2872c2c307cf9ef8", 05:19:09 INFO - "slavename": "talos-linux64-ix-044", 05:19:09 INFO - "builduid": "64f4e99093e1445fbb178f64a4fcfeb9", 05:19:09 INFO - "repo_path": "integration/mozilla-inbound" 05:19:09 INFO - } 05:19:09 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 05:19:09 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 05:19:09 INFO - Running post-action listener: _resource_record_post_action 05:19:09 INFO - [mozharness: 2016-12-23 13:19:09.697932Z] Finished read-buildbot-config step (success) 05:19:09 INFO - [mozharness: 2016-12-23 13:19:09.698023Z] Running download-and-extract step. 05:19:09 INFO - Running pre-action listener: _resource_record_pre_action 05:19:09 INFO - Running main action method: download_and_extract 05:19:09 INFO - mkdir: /builds/slave/test/build/tests 05:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:09 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:19:09 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/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 05:19:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/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 05:19:09 INFO - Downloaded 1361 bytes. 05:19:09 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:19:09 INFO - Using the following test package requirements: 05:19:09 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:19:09 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:19:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:19:09 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:19:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:19:09 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:19:09 INFO - u'jsshell-linux-x86_64.zip'], 05:19:09 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:19:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:19:09 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:19:09 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:19:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:19:09 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:19:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:19:09 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:19:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:19:09 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:19:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:19:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:19:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:19:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:19:09 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:19:09 INFO - Expected file size: 21828041 05:19:09 INFO - Obtained file size: 21828041 05:19:14 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 05:19:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:19:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:19:14 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:19:14 INFO - Expected file size: 21828041 05:19:14 INFO - Obtained file size: 21828041 05:19:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 05:19:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:19:18 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:19:19 INFO - Expected file size: 14409776 05:19:19 INFO - Obtained file size: 14409776 05:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:19 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:19:19 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/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 05:19:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/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 05:19:20 INFO - Downloaded 61388500 bytes. 05:19:20 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:19:20 INFO - mkdir: /builds/slave/test/properties 05:19:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:19:20 INFO - Writing to file /builds/slave/test/properties/build_url 05:19:20 INFO - Contents: 05:19:20 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:19:21 INFO - Running post-action listener: _resource_record_post_action 05:19:21 INFO - Running post-action listener: set_extra_try_arguments 05:19:21 INFO - [mozharness: 2016-12-23 13:19:21.327521Z] Finished download-and-extract step (success) 05:19:21 INFO - [mozharness: 2016-12-23 13:19:21.327752Z] Running populate-webroot step. 05:19:21 INFO - Running pre-action listener: _resource_record_pre_action 05:19:21 INFO - Running main action method: populate_webroot 05:19:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:19:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:19:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:19:21 INFO - '--tppagecycles', 05:19:21 INFO - '7'], 05:19:21 INFO - 'tests': ['tsvgm']}}, 05:19:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:19:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:19:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:19:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:19:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:19:21 INFO - 'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:19:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:19:21 INFO - 'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['damp', 'tps']}, 05:19:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:19:21 INFO - 'tests': ['damp', 'tps']}, 05:19:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['dromaeo_dom']}, 05:19:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:19:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['basic_compositor_video']}, 05:19:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:19:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['a11yr', 05:19:21 INFO - 'ts_paint', 05:19:21 INFO - 'tpaint', 05:19:21 INFO - 'sessionrestore', 05:19:21 INFO - 'sessionrestore_no_auto_restore', 05:19:21 INFO - 'tabpaint']}, 05:19:21 INFO - 'other-e10s': {'tests': ['a11yr', 05:19:21 INFO - 'ts_paint', 05:19:21 INFO - 'tpaint', 05:19:21 INFO - 'sessionrestore', 05:19:21 INFO - 'sessionrestore_no_auto_restore', 05:19:21 INFO - 'tabpaint']}, 05:19:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:19:21 INFO - 'ts_paint', 05:19:21 INFO - 'tpaint', 05:19:21 INFO - 'sessionrestore', 05:19:21 INFO - 'sessionrestore_no_auto_restore', 05:19:21 INFO - 'tabpaint']}, 05:19:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:19:21 INFO - 'ts_paint', 05:19:21 INFO - 'tpaint', 05:19:21 INFO - 'sessionrestore', 05:19:21 INFO - 'sessionrestore_no_auto_restore', 05:19:21 INFO - 'tabpaint']}, 05:19:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['a11yr', 05:19:21 INFO - 'ts_paint', 05:19:21 INFO - 'tpaint', 05:19:21 INFO - 'sessionrestore', 05:19:21 INFO - 'sessionrestore_no_auto_restore', 05:19:21 INFO - 'tabpaint']}, 05:19:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['a11yr', 05:19:21 INFO - 'ts_paint', 05:19:21 INFO - 'tpaint', 05:19:21 INFO - 'sessionrestore', 05:19:21 INFO - 'sessionrestore_no_auto_restore', 05:19:21 INFO - 'tabpaint']}, 05:19:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['tsvgx', 05:19:21 INFO - 'tsvgr_opacity', 05:19:21 INFO - 'tart', 05:19:21 INFO - 'tscrollx', 05:19:21 INFO - 'cart', 05:19:21 INFO - 'tsvg_static']}, 05:19:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:19:21 INFO - 'tsvgr_opacity', 05:19:21 INFO - 'tart', 05:19:21 INFO - 'tscrollx', 05:19:21 INFO - 'cart', 05:19:21 INFO - 'tsvg_static']}, 05:19:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:19:21 INFO - 'talos_options': ['--disable-e10s'], 05:19:21 INFO - 'tests': ['tp5o']}, 05:19:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:19:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:19:21 INFO - 'talos_options': ['--disable-e10s', 05:19:21 INFO - '--xperf_path', 05:19:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:21 INFO - 'tests': ['tp5n']}, 05:19:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:19:21 INFO - 'talos_options': ['--xperf_path', 05:19:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:21 INFO - 'tests': ['tp5n']}}, 05:19:21 INFO - 'talos.zip': {'path': '', 05:19:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:19:21 INFO - Running post-action listener: _resource_record_post_action 05:19:21 INFO - [mozharness: 2016-12-23 13:19:21.345688Z] Finished populate-webroot step (success) 05:19:21 INFO - [mozharness: 2016-12-23 13:19:21.345778Z] Running create-virtualenv step. 05:19:21 INFO - Running pre-action listener: _resource_record_pre_action 05:19:21 INFO - Running main action method: create_virtualenv 05:19:21 INFO - Creating virtualenv /builds/slave/test/build/venv 05:19:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:19:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:19:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:19:21 INFO - Using real prefix '/usr' 05:19:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:19:21 INFO - Installing distribute.............................................................................................................................................................................................done. 05:19:23 INFO - Installing pip.................done. 05:19:23 INFO - Return code: 0 05:19:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:19:24 INFO - Reading from file tmpfile_stdout 05:19:24 INFO - Output received: 05:19:24 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) 05:19:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19: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', 'pip>=1.5'] in /builds/slave/test/build 05:19: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 pip>=1.5 05:19:24 INFO - Using env: {'DISPLAY': ':0', 05:19:24 INFO - 'HOME': '/home/cltbld', 05:19:24 INFO - 'LANG': 'en_US.UTF-8', 05:19:24 INFO - 'LANGUAGE': 'en_US:en', 05:19:24 INFO - 'LOGNAME': 'cltbld', 05:19:24 INFO - 'MAIL': '/var/mail/cltbld', 05:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:24 INFO - 'MOZ_NO_REMOTE': '1', 05:19:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:24 INFO - 'NO_EM_RESTART': '1', 05:19:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:24 INFO - 'PWD': '/builds/slave/test', 05:19:24 INFO - 'SHELL': '/bin/bash', 05:19:24 INFO - 'SHLVL': '1', 05:19:24 INFO - 'TERM': 'linux', 05:19:24 INFO - 'TMOUT': '86400', 05:19:24 INFO - 'USER': 'cltbld', 05:19:24 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:24 INFO - '_': '/tools/buildbot/bin/python'} 05:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:19:24 INFO - Cleaning up... 05:19:24 INFO - Return code: 0 05:19:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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', '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 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:19: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 psutil>=3.1.1 05:19:24 INFO - Using env: {'DISPLAY': ':0', 05:19:24 INFO - 'HOME': '/home/cltbld', 05:19:24 INFO - 'LANG': 'en_US.UTF-8', 05:19:24 INFO - 'LANGUAGE': 'en_US:en', 05:19:24 INFO - 'LOGNAME': 'cltbld', 05:19:24 INFO - 'MAIL': '/var/mail/cltbld', 05:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:24 INFO - 'MOZ_NO_REMOTE': '1', 05:19:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:24 INFO - 'NO_EM_RESTART': '1', 05:19:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:24 INFO - 'PWD': '/builds/slave/test', 05:19:24 INFO - 'SHELL': '/bin/bash', 05:19:24 INFO - 'SHLVL': '1', 05:19:24 INFO - 'TERM': 'linux', 05:19:24 INFO - 'TMOUT': '86400', 05:19:24 INFO - 'USER': 'cltbld', 05:19:24 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:24 INFO - '_': '/tools/buildbot/bin/python'} 05:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:24 INFO - Downloading/unpacking psutil>=3.1.1 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:19:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:26 INFO - Installing collected packages: psutil 05:19:26 INFO - Running setup.py install for psutil 05:19:26 INFO - building 'psutil._psutil_linux' extension 05:19:26 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 05:19:26 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 05:19:26 INFO - building 'psutil._psutil_posix' extension 05:19:26 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 05:19:27 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 05:19:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:27 INFO - Successfully installed psutil 05:19:27 INFO - Cleaning up... 05:19:27 INFO - Return code: 0 05:19:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:27 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 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:19:27 INFO - Using env: {'DISPLAY': ':0', 05:19:27 INFO - 'HOME': '/home/cltbld', 05:19:27 INFO - 'LANG': 'en_US.UTF-8', 05:19:27 INFO - 'LANGUAGE': 'en_US:en', 05:19:27 INFO - 'LOGNAME': 'cltbld', 05:19:27 INFO - 'MAIL': '/var/mail/cltbld', 05:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:27 INFO - 'MOZ_NO_REMOTE': '1', 05:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:27 INFO - 'NO_EM_RESTART': '1', 05:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:27 INFO - 'PWD': '/builds/slave/test', 05:19:27 INFO - 'SHELL': '/bin/bash', 05:19:27 INFO - 'SHLVL': '1', 05:19:27 INFO - 'TERM': 'linux', 05:19:27 INFO - 'TMOUT': '86400', 05:19:27 INFO - 'USER': 'cltbld', 05:19:27 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:27 INFO - '_': '/tools/buildbot/bin/python'} 05:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:19:29 INFO - Installing collected packages: mozsystemmonitor 05:19:29 INFO - Running setup.py install for mozsystemmonitor 05:19:29 INFO - Successfully installed mozsystemmonitor 05:19:29 INFO - Cleaning up... 05:19:29 INFO - Return code: 0 05:19:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:19:29 INFO - Using env: {'DISPLAY': ':0', 05:19:29 INFO - 'HOME': '/home/cltbld', 05:19:29 INFO - 'LANG': 'en_US.UTF-8', 05:19:29 INFO - 'LANGUAGE': 'en_US:en', 05:19:29 INFO - 'LOGNAME': 'cltbld', 05:19:29 INFO - 'MAIL': '/var/mail/cltbld', 05:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:29 INFO - 'MOZ_NO_REMOTE': '1', 05:19:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:29 INFO - 'NO_EM_RESTART': '1', 05:19:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:29 INFO - 'PWD': '/builds/slave/test', 05:19:29 INFO - 'SHELL': '/bin/bash', 05:19:29 INFO - 'SHLVL': '1', 05:19:29 INFO - 'TERM': 'linux', 05:19:29 INFO - 'TMOUT': '86400', 05:19:29 INFO - 'USER': 'cltbld', 05:19:29 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:29 INFO - '_': '/tools/buildbot/bin/python'} 05:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:29 INFO - Downloading/unpacking jsonschema==2.5.1 05:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:19:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:32 INFO - Downloading functools32-3.2.3-2.tar.gz 05:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:19:32 INFO - warning: no files found matching '*.txt' 05:19:32 INFO - no previously-included directories found matching 'build' 05:19:32 INFO - no previously-included directories found matching 'dist' 05:19:32 INFO - no previously-included directories found matching '.git*' 05:19:32 INFO - Installing collected packages: jsonschema, functools32 05:19:32 INFO - Running setup.py install for functools32 05:19:32 INFO - warning: no files found matching '*.txt' 05:19:32 INFO - no previously-included directories found matching 'build' 05:19:32 INFO - no previously-included directories found matching 'dist' 05:19:32 INFO - no previously-included directories found matching '.git*' 05:19:32 INFO - Successfully installed jsonschema functools32 05:19:32 INFO - Cleaning up... 05:19:32 INFO - Return code: 0 05:19:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:32 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 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:19:32 INFO - Using env: {'DISPLAY': ':0', 05:19:32 INFO - 'HOME': '/home/cltbld', 05:19:32 INFO - 'LANG': 'en_US.UTF-8', 05:19:32 INFO - 'LANGUAGE': 'en_US:en', 05:19:32 INFO - 'LOGNAME': 'cltbld', 05:19:32 INFO - 'MAIL': '/var/mail/cltbld', 05:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:32 INFO - 'MOZ_NO_REMOTE': '1', 05:19:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:32 INFO - 'NO_EM_RESTART': '1', 05:19:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:32 INFO - 'PWD': '/builds/slave/test', 05:19:32 INFO - 'SHELL': '/bin/bash', 05:19:32 INFO - 'SHLVL': '1', 05:19:32 INFO - 'TERM': 'linux', 05:19:32 INFO - 'TMOUT': '86400', 05:19:32 INFO - 'USER': 'cltbld', 05:19:32 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:32 INFO - '_': '/tools/buildbot/bin/python'} 05:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:19:32 INFO - Cleaning up... 05:19:32 INFO - Return code: 0 05:19:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:32 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 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:19:32 INFO - Using env: {'DISPLAY': ':0', 05:19:32 INFO - 'HOME': '/home/cltbld', 05:19:32 INFO - 'LANG': 'en_US.UTF-8', 05:19:32 INFO - 'LANGUAGE': 'en_US:en', 05:19:32 INFO - 'LOGNAME': 'cltbld', 05:19:32 INFO - 'MAIL': '/var/mail/cltbld', 05:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:32 INFO - 'MOZ_NO_REMOTE': '1', 05:19:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:32 INFO - 'NO_EM_RESTART': '1', 05:19:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:32 INFO - 'PWD': '/builds/slave/test', 05:19:32 INFO - 'SHELL': '/bin/bash', 05:19:32 INFO - 'SHLVL': '1', 05:19:32 INFO - 'TERM': 'linux', 05:19:32 INFO - 'TMOUT': '86400', 05:19:32 INFO - 'USER': 'cltbld', 05:19:32 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:32 INFO - '_': '/tools/buildbot/bin/python'} 05:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:32 INFO - Downloading/unpacking blobuploader==1.2.4 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:34 INFO - Downloading blobuploader-1.2.4.tar.gz 05:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:19:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:19:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:35 INFO - Downloading docopt-0.6.1.tar.gz 05:19:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:19:35 INFO - Installing collected packages: blobuploader, requests, docopt 05:19:35 INFO - Running setup.py install for blobuploader 05:19:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:19:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:19:35 INFO - Running setup.py install for requests 05:19:36 INFO - Running setup.py install for docopt 05:19:36 INFO - Successfully installed blobuploader requests docopt 05:19:36 INFO - Cleaning up... 05:19:36 INFO - Return code: 0 05:19:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:36 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 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:36 INFO - Using env: {'DISPLAY': ':0', 05:19:36 INFO - 'HOME': '/home/cltbld', 05:19:36 INFO - 'LANG': 'en_US.UTF-8', 05:19:36 INFO - 'LANGUAGE': 'en_US:en', 05:19:36 INFO - 'LOGNAME': 'cltbld', 05:19:36 INFO - 'MAIL': '/var/mail/cltbld', 05:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:36 INFO - 'MOZ_NO_REMOTE': '1', 05:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:36 INFO - 'NO_EM_RESTART': '1', 05:19:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:36 INFO - 'PWD': '/builds/slave/test', 05:19:36 INFO - 'SHELL': '/bin/bash', 05:19:36 INFO - 'SHLVL': '1', 05:19:36 INFO - 'TERM': 'linux', 05:19:36 INFO - 'TMOUT': '86400', 05:19:36 INFO - 'USER': 'cltbld', 05:19:36 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:36 INFO - '_': '/tools/buildbot/bin/python'} 05:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:36 INFO - Running setup.py (path:/tmp/pip-8EBlO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:36 INFO - Running setup.py (path:/tmp/pip-YpwbVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:36 INFO - Running setup.py (path:/tmp/pip-OJj_Qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:36 INFO - Running setup.py (path:/tmp/pip-E4UMsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:36 INFO - Running setup.py (path:/tmp/pip-MHThRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:36 INFO - Running setup.py (path:/tmp/pip-T1ax7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:36 INFO - Running setup.py (path:/tmp/pip-vvprCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:36 INFO - Running setup.py (path:/tmp/pip-t0C_k8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:37 INFO - Running setup.py (path:/tmp/pip-fFEL2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:37 INFO - Running setup.py (path:/tmp/pip-QpNQmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:37 INFO - Running setup.py (path:/tmp/pip-2KDs21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:37 INFO - Running setup.py (path:/tmp/pip-7LwaiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:37 INFO - Running setup.py (path:/tmp/pip-mEwJDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:37 INFO - Running setup.py (path:/tmp/pip-C69jNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:37 INFO - Running setup.py (path:/tmp/pip-Z7HyuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:37 INFO - Running setup.py (path:/tmp/pip-v_5qm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:37 INFO - Running setup.py (path:/tmp/pip-kxXRID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:19:37 INFO - Running setup.py install for manifestparser 05:19:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:19:37 INFO - Running setup.py install for mozcrash 05:19:37 INFO - Running setup.py install for mozdebug 05:19:38 INFO - Running setup.py install for mozdevice 05:19:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for mozfile 05:19:38 INFO - Running setup.py install for mozhttpd 05:19:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for mozinfo 05:19:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for mozInstall 05:19:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for mozleak 05:19:38 INFO - Running setup.py install for mozlog 05:19:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for moznetwork 05:19:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for mozprocess 05:19:38 INFO - Running setup.py install for mozprofile 05:19:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:19:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:19:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:19:39 INFO - Running setup.py install for mozrunner 05:19:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:19:39 INFO - Running setup.py install for mozscreenshot 05:19:39 INFO - Running setup.py install for moztest 05:19:39 INFO - Running setup.py install for mozversion 05:19:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:19:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:19:39 INFO - Cleaning up... 05:19:39 INFO - Return code: 0 05:19:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:39 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 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:39 INFO - Using env: {'DISPLAY': ':0', 05:19:39 INFO - 'HOME': '/home/cltbld', 05:19:39 INFO - 'LANG': 'en_US.UTF-8', 05:19:39 INFO - 'LANGUAGE': 'en_US:en', 05:19:39 INFO - 'LOGNAME': 'cltbld', 05:19:39 INFO - 'MAIL': '/var/mail/cltbld', 05:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:39 INFO - 'MOZ_NO_REMOTE': '1', 05:19:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:39 INFO - 'NO_EM_RESTART': '1', 05:19:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:39 INFO - 'PWD': '/builds/slave/test', 05:19:39 INFO - 'SHELL': '/bin/bash', 05:19:39 INFO - 'SHLVL': '1', 05:19:39 INFO - 'TERM': 'linux', 05:19:39 INFO - 'TMOUT': '86400', 05:19:39 INFO - 'USER': 'cltbld', 05:19:39 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:39 INFO - '_': '/tools/buildbot/bin/python'} 05:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:39 INFO - Running setup.py (path:/tmp/pip-jCoUbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:39 INFO - Running setup.py (path:/tmp/pip-ji7_cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:39 INFO - Running setup.py (path:/tmp/pip-Vu2blW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:39 INFO - Running setup.py (path:/tmp/pip-Eqm2dv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:40 INFO - Running setup.py (path:/tmp/pip-LhRHge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:40 INFO - Running setup.py (path:/tmp/pip-fXz8Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:40 INFO - Running setup.py (path:/tmp/pip-Yw4tPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:40 INFO - Running setup.py (path:/tmp/pip-GEbuhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:40 INFO - Running setup.py (path:/tmp/pip-O_SScf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:40 INFO - Running setup.py (path:/tmp/pip-fE2Ik0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:40 INFO - Running setup.py (path:/tmp/pip-29qN4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:40 INFO - Running setup.py (path:/tmp/pip-dJFFM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:40 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)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:40 INFO - Running setup.py (path:/tmp/pip-5ob75P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:40 INFO - Running setup.py (path:/tmp/pip-sQlEaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:40 INFO - Running setup.py (path:/tmp/pip-f6fen5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:40 INFO - Running setup.py (path:/tmp/pip-JUN083-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:40 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)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:40 INFO - Running setup.py (path:/tmp/pip-J44aZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:42 INFO - Downloading blessings-1.6.tar.gz 05:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:43 INFO - Installing collected packages: blessings 05:19:43 INFO - Running setup.py install for blessings 05:19:43 INFO - Successfully installed blessings 05:19:43 INFO - Cleaning up... 05:19:43 INFO - Return code: 0 05:19:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:43 INFO - Reading from file tmpfile_stdout 05:19:43 INFO - Current package versions: 05:19:43 INFO - argparse == 1.2.1 05:19:43 INFO - blessings == 1.6 05:19:43 INFO - blobuploader == 1.2.4 05:19:43 INFO - docopt == 0.6.1 05:19:43 INFO - functools32 == 3.2.3-2 05:19:43 INFO - jsonschema == 2.5.1 05:19:43 INFO - manifestparser == 1.1 05:19:43 INFO - mozInstall == 1.12 05:19:43 INFO - mozcrash == 1.0 05:19:43 INFO - mozdebug == 0.1 05:19:43 INFO - mozdevice == 0.49 05:19:43 INFO - mozfile == 1.2 05:19:43 INFO - mozhttpd == 0.7 05:19:43 INFO - mozinfo == 0.9 05:19:43 INFO - mozleak == 0.1 05:19:43 INFO - mozlog == 3.4 05:19:43 INFO - moznetwork == 0.27 05:19:43 INFO - mozprocess == 0.23 05:19:43 INFO - mozprofile == 0.28 05:19:43 INFO - mozrunner == 6.13 05:19:43 INFO - mozscreenshot == 0.1 05:19:43 INFO - mozsystemmonitor == 0.3 05:19:43 INFO - moztest == 0.7 05:19:43 INFO - mozversion == 1.4 05:19:43 INFO - psutil == 3.1.1 05:19:43 INFO - requests == 1.2.3 05:19:43 INFO - wsgiref == 0.1.2 05:19:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:43 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 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:43 INFO - Using env: {'DISPLAY': ':0', 05:19:43 INFO - 'HOME': '/home/cltbld', 05:19:43 INFO - 'LANG': 'en_US.UTF-8', 05:19:43 INFO - 'LANGUAGE': 'en_US:en', 05:19:43 INFO - 'LOGNAME': 'cltbld', 05:19:43 INFO - 'MAIL': '/var/mail/cltbld', 05:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:43 INFO - 'MOZ_NO_REMOTE': '1', 05:19:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:43 INFO - 'NO_EM_RESTART': '1', 05:19:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:43 INFO - 'PWD': '/builds/slave/test', 05:19:43 INFO - 'SHELL': '/bin/bash', 05:19:43 INFO - 'SHLVL': '1', 05:19:43 INFO - 'TERM': 'linux', 05:19:43 INFO - 'TMOUT': '86400', 05:19:43 INFO - 'USER': 'cltbld', 05:19:43 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:43 INFO - '_': '/tools/buildbot/bin/python'} 05:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:43 INFO - Cleaning up... 05:19:43 INFO - Return code: 0 05:19:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:43 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 0x15d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1943630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', '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 05:19:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:43 INFO - Using env: {'DISPLAY': ':0', 05:19:43 INFO - 'HOME': '/home/cltbld', 05:19:43 INFO - 'LANG': 'en_US.UTF-8', 05:19:43 INFO - 'LANGUAGE': 'en_US:en', 05:19:43 INFO - 'LOGNAME': 'cltbld', 05:19:43 INFO - 'MAIL': '/var/mail/cltbld', 05:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:43 INFO - 'MOZ_NO_REMOTE': '1', 05:19:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:43 INFO - 'NO_EM_RESTART': '1', 05:19:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:43 INFO - 'PWD': '/builds/slave/test', 05:19:43 INFO - 'SHELL': '/bin/bash', 05:19:43 INFO - 'SHLVL': '1', 05:19:43 INFO - 'TERM': 'linux', 05:19:43 INFO - 'TMOUT': '86400', 05:19:43 INFO - 'USER': 'cltbld', 05:19:43 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:43 INFO - '_': '/tools/buildbot/bin/python'} 05:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:19:43 INFO - Cleaning up... 05:19:43 INFO - Return code: 0 05:19:43 INFO - Running post-action listener: _resource_record_post_action 05:19:43 INFO - Running post-action listener: _start_resource_monitoring 05:19:43 INFO - Starting resource monitoring. 05:19:43 INFO - [mozharness: 2016-12-23 13:19:43.923010Z] Finished create-virtualenv step (success) 05:19:43 INFO - [mozharness: 2016-12-23 13:19:43.923593Z] Running install step. 05:19:43 INFO - Running pre-action listener: _resource_record_pre_action 05:19:43 INFO - Running main action method: install 05:19:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:44 INFO - Reading from file tmpfile_stdout 05:19:44 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:44 INFO - Reading from file tmpfile_stdout 05:19:44 INFO - Output received: 05:19:44 INFO - Usage: mozinstall [options] installer 05:19:44 INFO - Options: 05:19:44 INFO - -h, --help show this help message and exit 05:19:44 INFO - -d DEST, --destination=DEST 05:19:44 INFO - Directory to install application into. [default: 05:19:44 INFO - "/builds/slave/test"] 05:19:44 INFO - --app=APP Application being installed. [default: firefox] 05:19:44 INFO - mkdir: /builds/slave/test/build/application 05:19:44 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'] 05:19:44 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 05:20:01 INFO - Reading from file tmpfile_stdout 05:20:01 INFO - Output received: 05:20:01 INFO - /builds/slave/test/build/application/firefox/firefox 05:20:01 INFO - Running post-action listener: _resource_record_post_action 05:20:01 INFO - [mozharness: 2016-12-23 13:20:01.565437Z] Finished install step (success) 05:20:01 INFO - [mozharness: 2016-12-23 13:20:01.565711Z] Running run-tests step. 05:20:01 INFO - Running pre-action listener: _resource_record_pre_action 05:20:01 INFO - Running main action method: run_tests 05:20:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:20:01 INFO - Python 2.7.3 05:20:01 INFO - Return code: 0 05:20:01 INFO - grabbing minidump binary from tooltool 05:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:01 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 0x19cc850>, '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 0x19416d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1941b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:20:01 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 05:20:01 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 05:20:01 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 05:20:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:20:01 INFO - Return code: 0 05:20:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:20:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:20:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:20:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:20:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:20:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:20:01 INFO - Using env: {'DISPLAY': ':0', 05:20:01 INFO - 'HOME': '/home/cltbld', 05:20:01 INFO - 'LANG': 'en_US.UTF-8', 05:20:01 INFO - 'LANGUAGE': 'en_US:en', 05:20:01 INFO - 'LOGNAME': 'cltbld', 05:20:01 INFO - 'MAIL': '/var/mail/cltbld', 05:20:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:01 INFO - 'MOZ_NO_REMOTE': '1', 05:20:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:01 INFO - 'NO_EM_RESTART': '1', 05:20:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:01 INFO - 'PWD': '/builds/slave/test', 05:20:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:01 INFO - 'SHELL': '/bin/bash', 05:20:01 INFO - 'SHLVL': '1', 05:20:01 INFO - 'TERM': 'linux', 05:20:01 INFO - 'TMOUT': '86400', 05:20:01 INFO - 'USER': 'cltbld', 05:20:01 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796', 05:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:01 INFO - '_': '/tools/buildbot/bin/python'} 05:20:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:20:01 INFO - mozversion application_buildid: 20161223033425 05:20:01 INFO - mozversion application_changeset: 2a762f5632902fe62907175d2872c2c307cf9ef8 05:20:01 INFO - mozversion application_display_name: Nightly 05:20:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:20:01 INFO - mozversion application_name: Firefox 05:20:01 INFO - mozversion application_remotingname: firefox 05:20:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:20:01 INFO - mozversion application_vendor: Mozilla 05:20:01 INFO - mozversion application_version: 53.0a1 05:20:01 INFO - mozversion platform_buildid: 20161223033425 05:20:01 INFO - mozversion platform_changeset: 2a762f5632902fe62907175d2872c2c307cf9ef8 05:20:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:20:01 INFO - mozversion platform_version: 53.0a1 05:20:01 INFO - using testdate: 1482499201 05:20:01 INFO - actual date: 1482499201 05:20:01 INFO - starting webserver on 'localhost:33875' 05:20:01 INFO - SUITE-START | Running 2 tests 05:20:01 INFO - TEST-START | dromaeo_css 05:20:01 INFO - operating with platform_type : linux_ 05:20:01 INFO - Initialising browser for dromaeo_css test... 05:20:01 INFO - TEST-INFO | started process 32255 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp67p417/profile http://localhost:33875/getInfo.html) 05:20:02 INFO - PROCESS | 32255 | 1482499202321 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:20:04 INFO - PROCESS | 32255 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:20:04 INFO - PROCESS | 32255 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:20:05 INFO - PROCESS | 32255 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:20:05 INFO - PROCESS | 32255 | __metrics Screen width/height:1600/1200 05:20:05 INFO - PROCESS | 32255 | colorDepth:24 05:20:05 INFO - PROCESS | 32255 | Browser inner width/height: 1024/697 05:20:05 INFO - PROCESS | 32255 | __metrics 05:20:06 INFO - PROCESS | 32255 | 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:20:06 INFO - PROCESS | 32255 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:20:06 INFO - PROCESS | 32255 | console.error: PushService: 05:20:06 INFO - PROCESS | 32255 | stateChangeProcessEnqueue: Error transitioning state 05:20:06 INFO - PROCESS | 32255 | UnknownError 05:20:07 INFO - TEST-INFO | 32255: exit 0 05:20:07 INFO - Browser initialized. 05:20:07 INFO - Running cycle 1/1 for dromaeo_css test... 05:20:07 INFO - TEST-INFO | started process 32341 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp67p417/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:19 INFO - PROCESS | 32341 | RSS: Main: 146530304 05:20:19 INFO - PROCESS | 32341 | 05:21:10 INFO - PROCESS | 32341 | 1482499270421 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:23:19 INFO - PROCESS | 32341 | Cycle 1(1): loaded http://localhost:33875/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33875/tests/dromaeo/cssquery-ext.html) 05:23:21 INFO - PROCESS | 32341 | RSS: Main: 399745024 05:23:21 INFO - PROCESS | 32341 | 05:25:07 INFO - PROCESS | 32341 | ************************* 05:25:07 INFO - PROCESS | 32341 | A coding exception was thrown and uncaught in a Task. 05:25:07 INFO - PROCESS | 32341 | 05:25:07 INFO - PROCESS | 32341 | Full message: ReferenceError: fetch is not defined 05:25:07 INFO - PROCESS | 32341 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 05:25:07 INFO - PROCESS | 32341 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 05:25:07 INFO - PROCESS | 32341 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 05:25:07 INFO - PROCESS | 32341 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:25:07 INFO - PROCESS | 32341 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:25:07 INFO - PROCESS | 32341 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:25:07 INFO - PROCESS | 32341 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 05:25:07 INFO - PROCESS | 32341 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:25:07 INFO - PROCESS | 32341 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:25:07 INFO - PROCESS | 32341 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:25:07 INFO - PROCESS | 32341 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 05:25:07 INFO - PROCESS | 32341 | 05:25:07 INFO - PROCESS | 32341 | ************************* 05:26:24 INFO - PROCESS | 32341 | Cycle 1(1): loaded http://localhost:33875/tests/dromaeo/cssquery-ext.html (next: http://localhost:33875/tests/dromaeo/cssquery-jquery.html) 05:26:25 INFO - PROCESS | 32341 | RSS: Main: 252407808 05:26:25 INFO - PROCESS | 32341 | 05:29:27 INFO - PROCESS | 32341 | Cycle 1(1): loaded http://localhost:33875/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33875/tests/dromaeo/cssquery-mootools.html) 05:29:27 INFO - PROCESS | 32341 | RSS: Main: 222244864 05:29:27 INFO - PROCESS | 32341 | 05:32:29 INFO - PROCESS | 32341 | Cycle 1(1): loaded http://localhost:33875/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33875/tests/dromaeo/cssquery-prototype.html) 05:32:30 INFO - PROCESS | 32341 | RSS: Main: 182325248 05:32:30 INFO - PROCESS | 32341 | 05:35:33 INFO - PROCESS | 32341 | Cycle 1(1): loaded http://localhost:33875/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33875/tests/dromaeo/cssquery-yui.html) 05:35:33 INFO - PROCESS | 32341 | RSS: Main: 184999936 05:35:33 INFO - PROCESS | 32341 | 05:38:36 INFO - PROCESS | 32341 | Cycle 1(1): loaded http://localhost:33875/tests/dromaeo/cssquery-yui.html (next: http://localhost:33875/tests/dromaeo/cssquery-dojo.html) 05:38:36 INFO - PROCESS | 32341 | RSS: Main: 287735808 05:38:36 INFO - PROCESS | 32341 | 05:38:36 INFO - PROCESS | 32341 | __start_tp_report 05:38:36 INFO - PROCESS | 32341 | _x_x_mozilla_page_load 05:38:36 INFO - PROCESS | 32341 | _x_x_mozilla_page_load_details 05:38:36 INFO - PROCESS | 32341 | |i|pagename|runs| 05:38:36 INFO - PROCESS | 32341 | |0;dojo.html;3089.1196009137393;3132.123005558444;3137.9058628241146;3150.354177393634;3152.747780177586;5023.572996295319;5027.296178535008;5094.133997220472;5178.611604129691;5214.327422631017;5095.872603184922;5108.438071812107;5120.4623514530995;5124.282600435942;5127.512886275807;4630.467496237942;4669.6731228814;4690.132325519789;4722.031983443386;4764.8094076237085;4620.9768951155365;4685.062987402516;4717.646176536757;4719.9292010619865;4765.90468190636;4725.79492229481;4732.1482133216;4732.929006064912;4776.832810851621;4777.18787806072;4564.6348292136545;4576.748278844662;4581.221192397301;4739.194336962724;4787.0904528139545;4625.283081122431;4631.976840115812;4642.79107440166;4699.271612900006;4711.882202944919;5121.590272778206;5124.359455068116;5133.743312834381;5134.281200631877;5191.896162076738;5447.618666693333;5495.725213739297;5496.038193316193;5500.119980803052;5548.278723765925;5453.536449401806;5501.587380946405;5540.778368865241;5550.195221692832;5613.635113717636;5205.115130427836;5219.034478621467;5221.686698798084;5247.501487358695;5258.0535503609335;5736.827895163152;5738.167965644958;5738.368779434259;5738.885222295531;5750.482456578928;5318;5340.332458442695;5408.810691625853;5484.808031718871;5534.363548192009;5523.199136125239;5573.498385145356;5574.554035677136;5597.104463285855;5626.493615574536;5502.642328248651;5553.333599968029;5554.277943867352;5557.138643510262;5558.722063896789;5387.973060134675;5402.324709411324;5405.027095122916;5416.21464887597;5430.91853622196;4173.39485774567;4176.227397931392;4205.684573656927;4215.768132752728;4216.304309788912;3734.7945862977253;3737.6262373762406;3739.8317075731657;3771.0949372151035;3771.6039815818945;5162;5217.58259339252;5294.814681486129;5321.840344789663;5333.200019997032;5534.944650553523;5597.552195824324;5599.216109744639;5646.407127251661;5650.74571644277;5341.225522299244;5344.091504444254;5349.063913815066;5350.357957045179;5353.866153346197;4471.910561788746;4507.5267096955;4589.931151032737;4634.72191668501;4670.299455081765;5743.8851222976145;5763.7982670606325;5766.279215098113;5767.88464230713;5771.971140144273;5443.482869127427;5455.536279416136;5461.781528738806;5462.781488740565;5465.699386533779;5776.855578610568;5780.161876527795;5780.248567686174;5781.132830075522;5783.363829978614;5316.388615309289;5354.544863686475;5359.3032905722;5359.571234301325;5363.302770639792;5482.835514934558;5503;5503.504684578407;5505.119180931032;5509.3664228614225;5365.651232669864;5367.624266301322;5367.838964831061;5369.140937449989;5392.730363481889;5349.973250133725;5429.104197807475;5436.266104076058;5439.48324909133;5440.7551660174595;5409.891802164014;5430.212619170119;5433.429489903503;5438.265834112344;5482.259894914136;5318.122509785842;5352.518273355417;5370.248165256945;5391.083515802401;5410.540104091197;5306.071437498345;5306.920396193983;5308.150695888639;5317.787288508571;5352.304200453916 05:38:36 INFO - PROCESS | 32341 | |1;ext.html;552.5110277404497;591.5238233222254;593.0333556303226;593.6912805341225;596.7463827873153;38845.61154388455;41303.58696413039;41505.58494415055;44984.32523512142;45043.324350134775;43999.340009899875;44485;44497.33254001185;44516;44528.777356113176;8128.211793405441;8776.868346974801;8936.463812171258;8941.50821704806;8951.149640784115;8017.318527925133;10426.113780328682;10657.04086632203;10658.467076646137;11045.558177672883;10769.192310576698;10812.135029197645;10822.512986915568;10823.458827058656;10826.783464330709;10518.737031574234;10563.894361056367;10571.31286466381;10580.576776928914;10582.894171058344;10499.475026248734;10552.683419497427;10555.630552930686;10557.102646275076;10572.207084468695;40782.59217407818;41331.7933410331;41371.172576548604;41398.1720365594;41679.7916010421;4092.9386059209137;4121.835126594932;4126.20510078114;4141.047559061402;4183.372494125905;30617.23456913595;30720.231994200323;31005.534916976263;31062.223444413845;31214.37571248585;4158.480189976253;4226.556211597799;4379.211741886459;4438.556144385568;4453.265211240141;330256;330436.9999999976;330929;331942;332836.3358183218;54302.45697543053;54492.72753636286;54890.17664735032;54936.93540565842;55018.72490637521;58320;58418.70790646019;58481.415185847596;58542;58625;13914.791278130837;14158.787618185735;14168.291585420893;14183.929080354532;14186.290685465685;10500.264981451377;10805.783884322269;11355.829662555068;11753.941230293794;11795.35125568102;15457.227138643022;15814.204590898331;15937.521874343789;16087.356505739874;16099.114548699934;6328.386261503288;6361.840953976188;6378.681065946684;6410.67946602668;6437.613743175424;866.9219770220584;889.1908363389284;894.9373543851998;913.8903331600118;919.4828532920734;29699.10902672923;31083.68916310853;31204.531932021036;31415.84292078525;31768.364632707217;1056.9207309451822;1068.6793961811425;1072.742541789965;1081.5295346524288;1121.5121422181214;15451.768223476656;16404.917975410048;16544.172791360623;16588.087655179083;16594.83405165957;481404;486811;487139;487523;487641;56532.43467565354;56593;56650.716746416;56687.71656141693;56727.71636141875;1287.2405280884323;1343.6909510812568;1353.7495563320817;1361.3261435589448;1364.6793003644136;28061.579076313465;29605.555916660836;29768.553471698375;30006;30588;48254.75872620614;48574;48665;48813.75593122154;48846;392814;394404;395359;395563;395616;37831.24337513289;37941.81029094947;38254.61745382511;39046.609533904295;39072.80463597664;42896.35655465233;43326.56673433226;43445.565544345416;43947;44184.779076104416;36377.45433818442;36564;36629.63370366262;36646;37237.81381093077;36308.09229769171;36623.45064824083;36676.26647467089;38081.42877856779;38122.42816357702;36482.8175859119;36857.44713829241;37203.255934881694;37639.24721505609;37683;31540.684593154692;33474.66525334813;33525.32949340951;33738.49392259069;34031.4895276576;25403.491930161665;25931.740682592932;25950.870245649407;26037.479250415265;26180.51463853027 05:38:36 INFO - PROCESS | 32341 | |2;jquery.html;365.60514644184286;370.79791513625065;372.85645026664736;375.2963194011229;388.39992212032405;14475.855241447596;17489.12554372281;18265.726014109783;18437.07814609271;19309;19044.71432928507;19355.709664355047;19475.805241947575;19521.12154953027;19579.412617621496;5324.946750532484;5559.054960656677;5565.693886836222;5572.0527510323345;5573.359063707685;4253.106847562016;4555.886102847422;4559.202139625569;5655.773769049254;5733.971330143364;6387.744490220385;6494.837629059288;6497.252815926158;6500.739970401179;6509.609423434586;5619.550435965133;5665.801696940628;5679.318481782213;6228.096925945735;6475.320091390407;5064.898702025939;5067.6452648314635;5078.13671675816;5124.461931497176;5149.970005998815;21453;22179.11283548656;22255.666165007537;22288.219912303153;22419.663705044437;2772.1683494951635;2821.84479853608;2823.9717602823916;2834.9291267718268;2836.3334616365137;13845.376958036912;14465;14498.42006319746;14668.706625867422;15035.624109397244;1976.594798066393;2038.2865996900966;2038.9601303335257;2042.7344445222175;2048.9692654610194;72585.63707181482;73263.63368183178;75222.24777752208;76358;76642.61678691626;10500.264981451377;10556.102731267929;10604.94697526523;10625.25623206368;10675.662168915542;14544.41822327095;14554.816777483462;14560.199189044704;14571.271436428136;14580.562583122522;7813.765587032398;7851.960740196377;7882.005899705015;8102.0277566692375;8116.6347514362;7502.099748030271;7535.171131175566;7717.919491271115;7723.382129429632;7749.7600383939425;6412.583182093149;6416.32628573993;6470.352964703492;6519.902201466982;6538.378854008862;1795.2729144696418;1868.560888191274;1879.4925370149983;1900.2209094271282;1916.467554234226;428.5436010648649;432.55662945481134;443.1292510217438;851.535912927456;879.564615515324;10851.511681974172;10972.890271097347;11082.501287441923;11226.214165008534;11263.380514071805;435.904101097758;443.16684632890605;444.28470163037485;446.5846762510831;459.15744608643513;5797.797077102366;5965.492933100648;5970.223870896755;5991.191189189491;6156.261248650101;429642;431990;438006;438107;439527;71421.64289178625;79334.60332698416;79618;79678.60160699276;80435.59782201052;430.79752516317905;430.8879691280227;433.0710625707839;433.21587925854305;442.40938347306064;9799.51002449889;9951.950240248752;10018.899811002088;10273;10292.433916134685;35362.646373535936;35915.820420897726;35953.64046359503;36260.28047896471;36526.63473365339;49680.50319496759;50408;51068.48931510637;51713;52744.472555273955;7569.3566046884425;7912.28789408956;8048.919510804817;8189.754307370788;8567.528785916835;7676.577788221478;7972.880406794019;12412.343613036737;13346.19922804635;13454.663633409244;10310.381377117397;10511.57953681844;11209.71975700614;11262.436878156223;11837.940810295893;10642.148628109579;10896.346219226873;10954.342739435659;10989.230753847154;11367.488463019019;10856.463124504173;11382.829257560978;11460.828087578526;12092.879071209527;12190.939045304716;8165.183481651788;8264;8361.83276334482;8453.746387608382;8797.12028797115;9664.420134791935;11087.722806929569;11152.55389784432;11686.70783230426;11692.181547291948 05:38:36 INFO - PROCESS | 32341 | |3;mootools.html;1340.4973135074347;1361.115275071203;1368.1654190943516;1369.1784929042572;1403.7192561487702;7581.090269167701;7998.360131189505;8005.079415867177;8006.1593532679035;8019.599020049003;7059.7882063538045;7293.234210407897;7516.398688104952;7672.117706463759;7889.289963903248;2068.2037415595028;2099.8530102892805;2102.684597310408;2102.8422868284865;2103.453102193433;2091.389787704206;2100.59038487495;2102.8212601928853;2111.8944052797374;2112.9260475883348;1828.9359872404468;1887.7829049659329;2044.1823270691768;2083.7707852136327;2099.5590925905576;2009.9196032158695;2010.1255953660204;2011.7887621799794;2012.7785943546198;2012.8892910889895;1989.592133612606;1994.9301774437824;2011.4569066352149;2012.7785943546198;2014.2748610500207;7894.21057894206;7949.562774047425;7981.561014144219;7992.680292788282;8012.318952889012;4567.474740404829;4696.201645720202;4703.482616912137;4713.033828065239;4721.5750582447745;2823.1248313022825;2948.2039849240587;2969.2873710309586;2981.0311648714037;3017.5322824961945;1176.2001838749648;1217.7929751942102;1231.7905955987414;1264.7344057747885;1265.0701734225338;10721.571137154504;10744.73138171544;10745.731356716067;10759.085477734401;10760.085392741626;3931.6461518463475;4030.294698427822;4128.958710412918;4200.222958752641;4205.621494065549;8421.957890210631;8626.611802468904;8630.309575233967;8664.783380415414;8683.913160868311;4560.95439045612;4568.784139692247;4574.908501830011;4582.95417045832;4584.358189853423;3891.9805400973382;3947.881563553098;4314.611684948307;4317.179735850241;4318.913621727548;3336.2493438976035;3427.108951672583;3429.7770644908;3452.741044421628;3488.947665785015;1036.6009086501601;1037.8028174646793;1050.7583255851198;1051.2115913065202;1052.5105825791045;796.2276591706036;819.4919150126959;821.6138414944967;829.4732844643695;829.7469271872137;4945.23348880924;4991.07665081961;4991.176455884811;5069.264956581275;5137.486251374833;520.1677316293975;526.229074405997;536.246573564143;536.8792021795143;551.5697755750521;3392.1858753898887;3392.2537041850755;3495.877644282438;3511.5610548681416;3579.1767893384144;9258.72223833286;9476.715698529055;9584;9591.328606997444;9592.184664303611;9522.95238523803;9529.428234305964;9534.523273836281;9543.809123817624;9547.522623868917;7116.359527642537;7136;7156.606386648784;7221.350078492961;7353.669084891086;4753.9762301188275;4809.735464549483;4815.133276010351;4851.1753001990455;4918.6065114791445;8545.700900468702;8572.3999320047;8598;8603.225709686158;8617.095205003636;8221.301189398968;8530.14698530142;8544.40189186751;8719.694810681849;8779.297656187362;6312.368763123651;6476.158099447041;6870.31296870309;7043.964780176067;7064.258252883579;6893.551919125241;6967.616781077089;6976.6860491277;7047.788566343018;7267.236940121423;6602.834929126618;6850.109485766819;6856.828579285558;6857.451403887777;6973.058637083929;6377.394147555975;6450.354964503512;6562.5406221564035;6566.531966013568;6693.732250709917;5455.0726376515295;5735.369109398046;5802.506786923159;5809.5933284671355;5813.447722466359;4269.701120921506;4274.268921086965;4325.84859529915;4333.8699839004885;4363.323684828857;4363.585459381354;4405.163019026375;4510.526394728507;4550.522195169459;4633.513481084437 05:38:36 INFO - PROCESS | 32341 | |4;prototype.html;371.21117625046776;371.25192736635665;374.8331992263442;377.94897688812;382.33473755665153;4964.950350496494;5079.6190285728535;5093.974530127353;5098.898022039557;5113.437521872591;4917.418355363427;5035.420926593443;5062.316587260719;5067.315912351832;5099.311592934952;3857.267119247334;3948.427478015686;3977.1647953929564;4096.627629744039;4123.773192474413;4054.2296963576828;4063.1873625274916;4073.388991651246;4082.6733861291027;4088.4480595119726;4052.371882358239;4053.209624123297;4061.8220715992325;4071.6335529802313;4083.3462447708725;3972.245273398074;4087.2438598859308;4092.3656833190903;4101.856435024774;4107.650849677781;3592.6586974237407;3593.6047034826147;3608.855645774166;3615.222727113683;3622.366085934977;4937.086638971802;4942.851714448572;4958.281289033225;4979.278004689336;5037.672551284155;4657.208274593328;4676.649251306166;4680.461746899116;4685.812567497296;4694.910764709705;5232.843014709565;5367.597430192751;5382.519807053052;5391.81128660499;5404.162354835007;4044.170944956277;4054.979725101385;4058.939115913264;4064.959350406488;4070.267351876661;5646.463585959327;5647.999999999959;5663.5186009189265;5669.461401166883;5678;4037.6164264394833;4152.647025002848;4166.583341665809;4167.5832416758685;4175.603317684815;4231.132617813341;4233.597808208216;4245.915081698348;4254.680898932593;4268.743875367488;4712.9057418851435;4714.834980775657;4727.976360118246;4734.218853889139;4757.310190022427;5103.770330335144;5221.477852214748;5327.653702509325;5350.224217488442;5394.406615272317;1373.8900887928942;1384.5808046751958;1387.5629176809273;1391.965200869966;1396.218117854005;997.9850302245472;1022.6471867205802;1036.388530766851;1044.1907521670767;1047.5547892145808;5102.897942041139;5127.435982041972;5135.229715542624;5148.176291793295;5158.535731784083;5296.285001524745;5302.257683924254;5318.069337865935;5329.1206950852675;5332.9733351333;4891.95108048915;5067.923981140286;5080.390353157644;5082.085224659523;5086.262491938722;2811.4658214939095;2811.9156425307274;2813.7889658275712;2818.210900947738;2828.844413557274;5592.567233253548;5600.943990560124;5657.312776431162;5677.602567820213;5714.342850572154;5216.035033518735;5279.333936362113;5373.2208829719475;5424.728763561885;5441.517428845542;5586.145559563888;5633.549316054706;5642.85892852682;5644;5661;5023.7488125592845;5252.527272545489;5307.310049693515;5322.148456246982;5328.17413300939;5135.768890399866;5150.484951504969;5158.871028224325;5199.246109314204;5227.268182454383;5504.339479262354;5517.36550296721;5530.668159910419;5631.747301079601;5634.731855523704;3941.5861334560605;4080.6123418275215;4091.795410229417;4092.9795351023054;4121.670266378623;5256.055259553989;5261.57907367402;5269.446708095748;5291.497307755757;5416.783328666809;5237.109691352555;5321.042212401805;5372.785088596569;5383.327084114486;5403.4326395727885;5388.54197393226;5399.190121481809;5427.945720542744;5437.864053398538;5444.646098003617;5101.311322971352;5376.408595054619;5415.566754659697;5430.15832545956;5430.51125398716;5449.855530338673;5449.891002180014;5456.236126942311;5465.152901300305;5465.808696695596;5343.919841202307;5344.839654810362;5351.705656188947;5352.678839269656;5367.892642147057 05:38:36 INFO - PROCESS | 32341 | |5;yui.html;717.5730440387968;744.2520267131532;764.6100488750739;810.4084018666373;859.4714250735798;14903;15050.473233436833;15402.922985385072;15480.225988700577;15909.920450397747;13437.937237657401;13628.182309061463;13774.380152893118;13875.930620346884;13883.375248113834;807.907090684573;823.8187598728271;826.017039722731;827.0695467598952;827.8178800663875;656.101141436233;702.8313204830855;754.2645920227761;812.3785304242259;818.2431251092745;863.8056437301619;864.1531299326664;867.0635713429513;867.3364875869933;867.453504292295;689.055993289192;854.4616891358467;856.8286342731448;857.4340934982913;857.8970523537153;535.5608401111091;569.2400645138745;610.206731249374;612.8958077126899;617.3024482334932;15109.697806043927;15116.39534418622;15120.5463836084;15140.924295378563;15163.62090947724;13764;14104;14147.080439771382;14156.646083847883;14228.644283892985;12802.7183173457;13125.72946931885;13209.339533023407;13263.469461221539;13428.93285533566;4382.780860956971;4422.292433210703;4463.799129039201;4497;4577.153226653079;78682.60658696727;78917.21082789155;80804.59597702033;81079.59460202721;81509.59245203795;11242.606508772154;11250.7187320316;11256.437178141061;11351.545938162546;11366.374849383197;19441.22235110608;19486.902565487082;19740.506487337643;19793;19926.90036549808;7860.646270917822;7946.053216961765;7994.480358776777;8108.675652973815;8166.632501537445;4074.8166332514515;4102.671786257092;4104.42538044674;4213.2837417639075;4259.254630439661;3218.7907785993834;3270.3132342207928;3272;3277.3772983133126;3294.522294267366;546.0171690956263;554.7173381534955;559.82085732566;563.1159080243978;563.18057226735;678.2098854834162;699.0876905638089;699.7026263837848;711.3420086420041;723.1828034321288;9011.556736933124;9321.906780932239;9605.18355939739;10063.69808905734;10172.186225101974;630.6598478233753;662.9071929929813;686.7562015484531;725.7496163823472;742.3022358982539;4527.501974782771;4563.908721825611;4656.976715116471;4662.557057079572;4902.7058376497525;78992.60503697445;79469.20530794734;80390.19609803827;81038.59480702676;81711.18288817035;57646;67103.66448167825;69480.30519694739;70568.6471567639;71471.84978553659;51006;53087.46912530825;57148.42851571514;64382;65677.67161164164;9992.970420927219;10025.899741002788;10149.949250253701;10169.79660406773;10211.50115450405;40661;40961.1807763849;41820.58179418289;41822.58177418187;42837;51565.7421712904;52272.340936134104;52461.7376913113;52545.737271313395;52732.73633631807;11843.881561184278;12065.939670301592;12277.140600157903;12389.93805030969;12419.130660854014;14550;14734.63163420923;14857.925710371379;14874.702505950037;14883.62790930192;13643.931780341034;13719.382627781573;13771.242581658105;13874.791878122038;13894.652633684238;12785.16896401731;12853.678658033625;12864.163829351062;12913.418896149884;13013.934930325287;13863.930680346937;13886.09740366873;14037.649058773612;14099.295035247991;15020;10837.512311945824;10968.451577421256;11230.270032447865;11305.208635395444;11354.432278385882;10420.290724411556;10486.947565262124;10534.26260161812;10573.62992295266;10701.625443109766 05:38:36 INFO - PROCESS | 32341 | __end_tp_report 05:38:36 INFO - PROCESS | 32341 | __start_cc_report 05:38:36 INFO - PROCESS | 32341 | _x_x_mozilla_cycle_collect,2473 05:38:36 INFO - PROCESS | 32341 | __end_cc_report 05:38:36 INFO - PROCESS | 32341 | __startTimestamp1482500316640__endTimestamp 05:38:36 INFO - PROCESS | 32341 | 05:38:36 INFO - PROCESS | 32341 | ------- Summary: start ------- 05:38:36 INFO - PROCESS | 32341 | Number of tests: 6 05:38:36 INFO - PROCESS | 32341 | 05:38:36 INFO - PROCESS | 32341 | [#0] dojo.html Cycles:165 Average:5133.86 Median:5350.36 stddev:580.19 (10.8%) stddev-sans-first:559.35 05:38:36 INFO - PROCESS | 32341 | Values: 3089.1 3132.1 3137.9 3150.4 3152.7 5023.6 5027.3 5094.1 5178.6 5214.3 5095.9 5108.4 5120.5 5124.3 5127.5 4630.5 4669.7 4690.1 4722.0 4764.8 4621.0 4685.1 4717.6 4719.9 4765.9 4725.8 4732.1 4732.9 4776.8 4777.2 4564.6 4576.7 4581.2 4739.2 4787.1 4625.3 4632.0 4642.8 4699.3 4711.9 5121.6 5124.4 5133.7 5134.3 5191.9 5447.6 5495.7 5496.0 5500.1 5548.3 5453.5 5501.6 5540.8 5550.2 5613.6 5205.1 5219.0 5221.7 5247.5 5258.1 5736.8 5738.2 5738.4 5738.9 5750.5 5318.0 5340.3 5408.8 5484.8 5534.4 5523.2 5573.5 5574.6 5597.1 5626.5 5502.6 5553.3 5554.3 5557.1 5558.7 5388.0 5402.3 5405.0 5416.2 5430.9 4173.4 4176.2 4205.7 4215.8 4216.3 3734.8 3737.6 3739.8 3771.1 3771.6 5162.0 5217.6 5294.8 5321.8 5333.2 5534.9 5597.6 5599.2 5646.4 5650.7 5341.2 5344.1 5349.1 5350.4 5353.9 4471.9 4507.5 4589.9 4634.7 4670.3 5743.9 5763.8 5766.3 5767.9 5772.0 5443.5 5455.5 5461.8 5462.8 5465.7 5776.9 5780.2 5780.2 5781.1 5783.4 5316.4 5354.5 5359.3 5359.6 5363.3 5482.8 5503.0 5503.5 5505.1 5509.4 5365.7 5367.6 5367.8 5369.1 5392.7 5350.0 5429.1 5436.3 5439.5 5440.8 5409.9 5430.2 5433.4 5438.3 5482.3 5318.1 5352.5 5370.2 5391.1 5410.5 5306.1 5306.9 5308.2 5317.8 5352.3 05:38:36 INFO - PROCESS | 32341 | 05:38:36 INFO - PROCESS | 32341 | [#1] ext.html Cycles:180 Average:56376.38 Median:37661.12 stddev:108111.20 (287.1%) stddev-sans-first:108333.23 05:38:36 INFO - PROCESS | 32341 | Values: 552.5 591.5 593.0 593.7 596.7 38845.6 41303.6 41505.6 44984.3 45043.3 43999.3 44485.0 44497.3 44516.0 44528.8 8128.2 8776.9 8936.5 8941.5 8951.1 8017.3 10426.1 10657.0 10658.5 11045.6 10769.2 10812.1 10822.5 10823.5 10826.8 10518.7 10563.9 10571.3 10580.6 10582.9 10499.5 10552.7 10555.6 10557.1 10572.2 40782.6 41331.8 41371.2 41398.2 41679.8 4092.9 4121.8 4126.2 4141.0 4183.4 30617.2 30720.2 31005.5 31062.2 31214.4 4158.5 4226.6 4379.2 4438.6 4453.3 330256.0 330437.0 330929.0 331942.0 332836.3 54302.5 54492.7 54890.2 54936.9 55018.7 58320.0 58418.7 58481.4 58542.0 58625.0 13914.8 14158.8 14168.3 14183.9 14186.3 10500.3 10805.8 11355.8 11753.9 11795.4 15457.2 15814.2 15937.5 16087.4 16099.1 6328.4 6361.8 6378.7 6410.7 6437.6 866.9 889.2 894.9 913.9 919.5 29699.1 31083.7 31204.5 31415.8 31768.4 1056.9 1068.7 1072.7 1081.5 1121.5 15451.8 16404.9 16544.2 16588.1 16594.8 481404.0 486811.0 487139.0 487523.0 487641.0 56532.4 56593.0 56650.7 56687.7 56727.7 1287.2 1343.7 1353.7 1361.3 1364.7 28061.6 29605.6 29768.6 30006.0 30588.0 48254.8 48574.0 48665.0 48813.8 48846.0 392814.0 394404.0 395359.0 395563.0 395616.0 37831.2 37941.8 38254.6 39046.6 39072.8 42896.4 43326.6 43445.6 43947.0 44184.8 36377.5 36564.0 36629.6 36646.0 37237.8 36308.1 36623.5 36676.3 38081.4 38122.4 36482.8 36857.4 37203.3 37639.2 37683.0 31540.7 33474.7 33525.3 33738.5 34031.5 25403.5 25931.7 25950.9 26037.5 26180.5 05:38:36 INFO - PROCESS | 32341 | 05:38:36 INFO - PROCESS | 32341 | [#2] jquery.html Cycles:180 Average:25891.71 Median:216211.28 stddev:71729.93 (33.2%) stddev-sans-first:71905.55 05:38:36 INFO - PROCESS | 32341 | Values: 365.6 370.8 372.9 375.3 388.4 14475.9 17489.1 18265.7 18437.1 19309.0 19044.7 19355.7 19475.8 19521.1 19579.4 5324.9 5559.1 5565.7 5572.1 5573.4 4253.1 4555.9 4559.2 5655.8 5734.0 6387.7 6494.8 6497.3 6500.7 6509.6 5619.6 5665.8 5679.3 6228.1 6475.3 5064.9 5067.6 5078.1 5124.5 5150.0 21453.0 22179.1 22255.7 22288.2 22419.7 2772.2 2821.8 2824.0 2834.9 2836.3 13845.4 14465.0 14498.4 14668.7 15035.6 1976.6 2038.3 2039.0 2042.7 2049.0 72585.6 73263.6 75222.2 76358.0 76642.6 10500.3 10556.1 10604.9 10625.3 10675.7 14544.4 14554.8 14560.2 14571.3 14580.6 7813.8 7852.0 7882.0 8102.0 8116.6 7502.1 7535.2 7717.9 7723.4 7749.8 6412.6 6416.3 6470.4 6519.9 6538.4 1795.3 1868.6 1879.5 1900.2 1916.5 428.5 432.6 443.1 851.5 879.6 10851.5 10972.9 11082.5 11226.2 11263.4 435.9 443.2 444.3 446.6 459.2 5797.8 5965.5 5970.2 5991.2 6156.3 429642.0 431990.0 438006.0 438107.0 439527.0 71421.6 79334.6 79618.0 79678.6 80435.6 430.8 430.9 433.1 433.2 442.4 9799.5 9952.0 10018.9 10273.0 10292.4 35362.6 35915.8 35953.6 36260.3 36526.6 49680.5 50408.0 51068.5 51713.0 52744.5 7569.4 7912.3 8048.9 8189.8 8567.5 7676.6 7972.9 12412.3 13346.2 13454.7 10310.4 10511.6 11209.7 11262.4 11837.9 10642.1 10896.3 10954.3 10989.2 11367.5 10856.5 11382.8 11460.8 12092.9 12190.9 8165.2 8264.0 8361.8 8453.7 8797.1 9664.4 11087.7 11152.6 11686.7 11692.2 05:38:36 INFO - PROCESS | 32341 | 05:38:36 INFO - PROCESS | 32341 | [#3] mootools.html Cycles:180 Average:5005.49 Median:4781.86 stddev:2848.03 (59.6%) stddev-sans-first:2842.71 05:38:36 INFO - PROCESS | 32341 | Values: 1340.5 1361.1 1368.2 1369.2 1403.7 7581.1 7998.4 8005.1 8006.2 8019.6 7059.8 7293.2 7516.4 7672.1 7889.3 2068.2 2099.9 2102.7 2102.8 2103.5 2091.4 2100.6 2102.8 2111.9 2112.9 1828.9 1887.8 2044.2 2083.8 2099.6 2009.9 2010.1 2011.8 2012.8 2012.9 1989.6 1994.9 2011.5 2012.8 2014.3 7894.2 7949.6 7981.6 7992.7 8012.3 4567.5 4696.2 4703.5 4713.0 4721.6 2823.1 2948.2 2969.3 2981.0 3017.5 1176.2 1217.8 1231.8 1264.7 1265.1 10721.6 10744.7 10745.7 10759.1 10760.1 3931.6 4030.3 4129.0 4200.2 4205.6 8422.0 8626.6 8630.3 8664.8 8683.9 4561.0 4568.8 4574.9 4583.0 4584.4 3892.0 3947.9 4314.6 4317.2 4318.9 3336.2 3427.1 3429.8 3452.7 3488.9 1036.6 1037.8 1050.8 1051.2 1052.5 796.2 819.5 821.6 829.5 829.7 4945.2 4991.1 4991.2 5069.3 5137.5 520.2 526.2 536.2 536.9 551.6 3392.2 3392.3 3495.9 3511.6 3579.2 9258.7 9476.7 9584.0 9591.3 9592.2 9523.0 9529.4 9534.5 9543.8 9547.5 7116.4 7136.0 7156.6 7221.4 7353.7 4754.0 4809.7 4815.1 4851.2 4918.6 8545.7 8572.4 8598.0 8603.2 8617.1 8221.3 8530.1 8544.4 8719.7 8779.3 6312.4 6476.2 6870.3 7044.0 7064.3 6893.6 6967.6 6976.7 7047.8 7267.2 6602.8 6850.1 6856.8 6857.5 6973.1 6377.4 6450.4 6562.5 6566.5 6693.7 5455.1 5735.4 5802.5 5809.6 5813.4 4269.7 4274.3 4325.8 4333.9 4363.3 4363.6 4405.2 4510.5 4550.5 4633.5 05:38:36 INFO - PROCESS | 32341 | 05:38:36 INFO - PROCESS | 32341 | [#4] prototype.html Cycles:180 Average:4529.09 Median:5090.12 stddev:1281.87 (25.2%) stddev-sans-first:1246.90 05:38:36 INFO - PROCESS | 32341 | Values: 371.2 371.3 374.8 377.9 382.3 4965.0 5079.6 5094.0 5098.9 5113.4 4917.4 5035.4 5062.3 5067.3 5099.3 3857.3 3948.4 3977.2 4096.6 4123.8 4054.2 4063.2 4073.4 4082.7 4088.4 4052.4 4053.2 4061.8 4071.6 4083.3 3972.2 4087.2 4092.4 4101.9 4107.7 3592.7 3593.6 3608.9 3615.2 3622.4 4937.1 4942.9 4958.3 4979.3 5037.7 4657.2 4676.6 4680.5 4685.8 4694.9 5232.8 5367.6 5382.5 5391.8 5404.2 4044.2 4055.0 4058.9 4065.0 4070.3 5646.5 5648.0 5663.5 5669.5 5678.0 4037.6 4152.6 4166.6 4167.6 4175.6 4231.1 4233.6 4245.9 4254.7 4268.7 4712.9 4714.8 4728.0 4734.2 4757.3 5103.8 5221.5 5327.7 5350.2 5394.4 1373.9 1384.6 1387.6 1392.0 1396.2 998.0 1022.6 1036.4 1044.2 1047.6 5102.9 5127.4 5135.2 5148.2 5158.5 5296.3 5302.3 5318.1 5329.1 5333.0 4892.0 5067.9 5080.4 5082.1 5086.3 2811.5 2811.9 2813.8 2818.2 2828.8 5592.6 5600.9 5657.3 5677.6 5714.3 5216.0 5279.3 5373.2 5424.7 5441.5 5586.1 5633.5 5642.9 5644.0 5661.0 5023.7 5252.5 5307.3 5322.1 5328.2 5135.8 5150.5 5158.9 5199.2 5227.3 5504.3 5517.4 5530.7 5631.7 5634.7 3941.6 4080.6 4091.8 4093.0 4121.7 5256.1 5261.6 5269.4 5291.5 5416.8 5237.1 5321.0 5372.8 5383.3 5403.4 5388.5 5399.2 5427.9 5437.9 5444.6 5101.3 5376.4 5415.6 5430.2 5430.5 5449.9 5449.9 5456.2 5465.2 5465.8 5343.9 5344.8 5351.7 5352.7 5367.9 05:38:36 INFO - PROCESS | 32341 | 05:38:36 INFO - PROCESS | 32341 | [#5] yui.html Cycles:180 Average:17299.55 Median:23609.89 stddev:22167.56 (93.9%) stddev-sans-first:22194.77 05:38:36 INFO - PROCESS | 32341 | Values: 717.6 744.3 764.6 810.4 859.5 14903.0 15050.5 15402.9 15480.2 15909.9 13437.9 13628.2 13774.4 13875.9 13883.4 807.9 823.8 826.0 827.1 827.8 656.1 702.8 754.3 812.4 818.2 863.8 864.2 867.1 867.3 867.5 689.1 854.5 856.8 857.4 857.9 535.6 569.2 610.2 612.9 617.3 15109.7 15116.4 15120.5 15140.9 15163.6 13764.0 14104.0 14147.1 14156.6 14228.6 12802.7 13125.7 13209.3 13263.5 13428.9 4382.8 4422.3 4463.8 4497.0 4577.2 78682.6 78917.2 80804.6 81079.6 81509.6 11242.6 11250.7 11256.4 11351.5 11366.4 19441.2 19486.9 19740.5 19793.0 19926.9 7860.6 7946.1 7994.5 8108.7 8166.6 4074.8 4102.7 4104.4 4213.3 4259.3 3218.8 3270.3 3272.0 3277.4 3294.5 546.0 554.7 559.8 563.1 563.2 678.2 699.1 699.7 711.3 723.2 9011.6 9321.9 9605.2 10063.7 10172.2 630.7 662.9 686.8 725.7 742.3 4527.5 4563.9 4657.0 4662.6 4902.7 78992.6 79469.2 80390.2 81038.6 81711.2 57646.0 67103.7 69480.3 70568.6 71471.8 51006.0 53087.5 57148.4 64382.0 65677.7 9993.0 10025.9 10149.9 10169.8 10211.5 40661.0 40961.2 41820.6 41822.6 42837.0 51565.7 52272.3 52461.7 52545.7 52732.7 11843.9 12065.9 12277.1 12389.9 12419.1 14550.0 14734.6 14857.9 14874.7 14883.6 13643.9 13719.4 13771.2 13874.8 13894.7 12785.2 12853.7 12864.2 12913.4 13013.9 13863.9 13886.1 14037.6 14099.3 15020.0 10837.5 10968.5 11230.3 11305.2 11354.4 10420.3 10486.9 10534.3 10573.6 10701.6 05:38:36 INFO - PROCESS | 32341 | -------- Summary: end -------- 05:38:36 INFO - PROCESS | 32341 | 05:38:37 INFO - TEST-INFO | 32341: exit 0 05:38:37 INFO - TEST-OK | dromaeo_css | took 1115575ms 05:38:37 INFO - TEST-START | kraken 05:38:37 INFO - operating with platform_type : linux_ 05:38:37 INFO - Initialising browser for kraken test... 05:38:37 INFO - TEST-INFO | started process 32644 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8h0RPs/profile http://localhost:33875/getInfo.html) 05:38:37 INFO - PROCESS | 32644 | 1482500317870 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:38:40 INFO - PROCESS | 32644 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:38:40 INFO - PROCESS | 32644 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:38:40 INFO - PROCESS | 32644 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:38:40 INFO - PROCESS | 32644 | __metrics Screen width/height:1600/1200 05:38:40 INFO - PROCESS | 32644 | colorDepth:24 05:38:40 INFO - PROCESS | 32644 | Browser inner width/height: 1024/697 05:38:40 INFO - PROCESS | 32644 | __metrics 05:38:41 INFO - PROCESS | 32644 | 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:38:41 INFO - PROCESS | 32644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:38:42 INFO - PROCESS | 32644 | console.error: PushService: 05:38:42 INFO - PROCESS | 32644 | stateChangeProcessEnqueue: Error transitioning state 05:38:42 INFO - PROCESS | 32644 | UnknownError 05:38:42 INFO - TEST-INFO | 32644: exit 0 05:38:42 INFO - Browser initialized. 05:38:42 INFO - Running cycle 1/1 for kraken test... 05:38:42 INFO - TEST-INFO | started process 32730 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8h0RPs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:54 INFO - PROCESS | 32730 | RSS: Main: 147304448 05:38:54 INFO - PROCESS | 32730 | 05:38:57 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33875/tests/kraken/driver.html?testName=audio-beat-detection) 05:38:57 INFO - PROCESS | 32730 | RSS: Main: 200847360 05:38:57 INFO - PROCESS | 32730 | 05:39:00 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33875/tests/kraken/driver.html?testName=audio-dft) 05:39:01 INFO - PROCESS | 32730 | RSS: Main: 180600832 05:39:01 INFO - PROCESS | 32730 | 05:39:04 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33875/tests/kraken/driver.html?testName=audio-fft) 05:39:04 INFO - PROCESS | 32730 | RSS: Main: 262725632 05:39:04 INFO - PROCESS | 32730 | 05:39:06 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33875/tests/kraken/driver.html?testName=audio-oscillator) 05:39:07 INFO - PROCESS | 32730 | RSS: Main: 173322240 05:39:07 INFO - PROCESS | 32730 | 05:39:09 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33875/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:39:10 INFO - PROCESS | 32730 | RSS: Main: 176967680 05:39:10 INFO - PROCESS | 32730 | 05:39:13 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33875/tests/kraken/driver.html?testName=imaging-darkroom) 05:39:13 INFO - PROCESS | 32730 | RSS: Main: 217411584 05:39:13 INFO - PROCESS | 32730 | 05:39:17 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33875/tests/kraken/driver.html?testName=imaging-desaturate) 05:39:17 INFO - PROCESS | 32730 | RSS: Main: 217255936 05:39:17 INFO - PROCESS | 32730 | 05:39:20 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33875/tests/kraken/driver.html?testName=json-parse-financial) 05:39:20 INFO - PROCESS | 32730 | RSS: Main: 215695360 05:39:20 INFO - PROCESS | 32730 | 05:39:22 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33875/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:23 INFO - PROCESS | 32730 | RSS: Main: 178286592 05:39:23 INFO - PROCESS | 32730 | 05:39:24 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33875/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:25 INFO - PROCESS | 32730 | RSS: Main: 184406016 05:39:25 INFO - PROCESS | 32730 | 05:39:27 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33875/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:28 INFO - PROCESS | 32730 | RSS: Main: 193396736 05:39:28 INFO - PROCESS | 32730 | 05:39:30 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33875/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:39:31 INFO - PROCESS | 32730 | RSS: Main: 202592256 05:39:31 INFO - PROCESS | 32730 | 05:39:34 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33875/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:39:34 INFO - PROCESS | 32730 | RSS: Main: 200101888 05:39:34 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | Cycle 1(1): loaded http://localhost:33875/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33875/tests/kraken/driver.html?testName=ai-astar) 05:39:36 INFO - PROCESS | 32730 | RSS: Main: 251375616 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | __start_tp_report 05:39:36 INFO - PROCESS | 32730 | _x_x_mozilla_page_load 05:39:36 INFO - PROCESS | 32730 | _x_x_mozilla_page_load_details 05:39:36 INFO - PROCESS | 32730 | |i|pagename|runs| 05:39:36 INFO - PROCESS | 32730 | |0;ai-astar;105;110;111;110;112;112;110;110;112;111 05:39:36 INFO - PROCESS | 32730 | |1;audio-beat-detection;152;122;124;170;125;125;119;118;142;119 05:39:36 INFO - PROCESS | 32730 | |2;audio-dft;160;162;160;161;166;162;160;161;161;162 05:39:36 INFO - PROCESS | 32730 | |3;audio-fft;74;76;76;109;76;75;75;76;90;75 05:39:36 INFO - PROCESS | 32730 | |4;audio-oscillator;83;83;102;83;83;84;96;84;83;103 05:39:36 INFO - PROCESS | 32730 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 05:39:36 INFO - PROCESS | 32730 | |6;imaging-darkroom;146;147;146;146;146;146;146;146;146;146 05:39:36 INFO - PROCESS | 32730 | |7;imaging-desaturate;96;96;96;96;95;96;96;96;96;95 05:39:36 INFO - PROCESS | 32730 | |8;json-parse-financial;51;51;55;52;52;53;51;59;51;52 05:39:36 INFO - PROCESS | 32730 | |9;json-stringify-tinderbox;65;70;66;65;66;66;66;66;65;67 05:39:36 INFO - PROCESS | 32730 | |10;stanford-crypto-aes;83;87;81;81;80;80;80;80;79;80 05:39:36 INFO - PROCESS | 32730 | |11;stanford-crypto-ccm;137;124;133;131;123;133;127;123;159;123 05:39:36 INFO - PROCESS | 32730 | |12;stanford-crypto-pbkdf2;165;166;164;162;162;163;161;164;164;163 05:39:36 INFO - PROCESS | 32730 | |13;stanford-crypto-sha256-iterative;61;62;62;62;61;61;61;61;61;65 05:39:36 INFO - PROCESS | 32730 | __end_tp_report 05:39:36 INFO - PROCESS | 32730 | __start_cc_report 05:39:36 INFO - PROCESS | 32730 | _x_x_mozilla_cycle_collect,1559 05:39:36 INFO - PROCESS | 32730 | __end_cc_report 05:39:36 INFO - PROCESS | 32730 | __startTimestamp1482500376626__endTimestamp 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | ------- Summary: start ------- 05:39:36 INFO - PROCESS | 32730 | Number of tests: 14 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:2.06 (1.9%) stddev-sans-first:0.93 05:39:36 INFO - PROCESS | 32730 | Values: 105.0 110.0 111.0 110.0 112.0 112.0 110.0 110.0 112.0 111.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:125.00 stddev:17.44 (14.0%) stddev-sans-first:16.87 05:39:36 INFO - PROCESS | 32730 | Values: 152.0 122.0 124.0 170.0 125.0 125.0 119.0 118.0 142.0 119.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:1.78 (1.1%) stddev-sans-first:1.80 05:39:36 INFO - PROCESS | 32730 | Values: 160.0 162.0 160.0 161.0 166.0 162.0 160.0 161.0 161.0 162.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#3] audio-fft Cycles:10 Average:80.20 Median:76.00 stddev:11.13 (14.6%) stddev-sans-first:11.58 05:39:36 INFO - PROCESS | 32730 | Values: 74.0 76.0 76.0 109.0 76.0 75.0 75.0 76.0 90.0 75.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:83.00 stddev:8.44 (10.2%) stddev-sans-first:8.72 05:39:36 INFO - PROCESS | 32730 | Values: 83.0 83.0 102.0 83.0 83.0 84.0 96.0 84.0 83.0 103.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 05:39:36 INFO - PROCESS | 32730 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 05:39:36 INFO - PROCESS | 32730 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:39:36 INFO - PROCESS | 32730 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 95.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:52.00 stddev:2.54 (4.9%) stddev-sans-first:2.62 05:39:36 INFO - PROCESS | 32730 | Values: 51.0 51.0 55.0 52.0 52.0 53.0 51.0 59.0 51.0 52.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:1.48 (2.2%) stddev-sans-first:1.50 05:39:36 INFO - PROCESS | 32730 | Values: 65.0 70.0 66.0 65.0 66.0 66.0 66.0 66.0 65.0 67.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:80.50 stddev:2.33 (2.9%) stddev-sans-first:2.37 05:39:36 INFO - PROCESS | 32730 | Values: 83.0 87.0 81.0 81.0 80.0 80.0 80.0 80.0 79.0 80.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#11] stanford-crypto-ccm Cycles:10 Average:131.30 Median:132.00 stddev:10.98 (8.3%) stddev-sans-first:11.45 05:39:36 INFO - PROCESS | 32730 | Values: 137.0 124.0 133.0 131.0 123.0 133.0 127.0 123.0 159.0 123.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:164.00 stddev:1.51 (0.9%) stddev-sans-first:1.48 05:39:36 INFO - PROCESS | 32730 | Values: 165.0 166.0 164.0 162.0 162.0 163.0 161.0 164.0 164.0 163.0 05:39:36 INFO - PROCESS | 32730 | 05:39:36 INFO - PROCESS | 32730 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:61.50 stddev:1.25 (2.0%) stddev-sans-first:1.30 05:39:36 INFO - PROCESS | 32730 | Values: 61.0 62.0 62.0 62.0 61.0 61.0 61.0 61.0 61.0 65.0 05:39:36 INFO - PROCESS | 32730 | -------- Summary: end -------- 05:39:36 INFO - PROCESS | 32730 | 05:39:37 INFO - TEST-INFO | 32730: exit 0 05:39:37 INFO - TEST-OK | kraken | took 60295ms 05:39:37 INFO - SUITE-END | took 1175s 05:39:37 INFO - Completed test suite (00:19:35) 05:39:37 INFO - javascript benchmark 05:39:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3089.1196009137393, 3132.123005558444, 3137.9058628241146, 3150.354177393634, 3152.747780177586, 5023.572996295319, 5027.296178535008, 5094.133997220472, 5178.611604129691, 5214.327422631017, 5095.872603184922, 5108.438071812107, 5120.4623514530995, 5124.282600435942, 5127.512886275807, 4630.467496237942, 4669.6731228814, 4690.132325519789, 4722.031983443386, 4764.8094076237085, 4620.9768951155365, 4685.062987402516, 4717.646176536757, 4719.9292010619865, 4765.90468190636, 4725.79492229481, 4732.1482133216, 4732.929006064912, 4776.832810851621, 4777.18787806072, 4564.6348292136545, 4576.748278844662, 4581.221192397301, 4739.194336962724, 4787.0904528139545, 4625.283081122431, 4631.976840115812, 4642.79107440166, 4699.271612900006, 4711.882202944919, 5121.590272778206, 5124.359455068116, 5133.743312834381, 5134.281200631877, 5191.896162076738, 5447.618666693333, 5495.725213739297, 5496.038193316193, 5500.119980803052, 5548.278723765925, 5453.536449401806, 5501.587380946405, 5540.778368865241, 5550.195221692832, 5613.635113717636, 5205.115130427836, 5219.034478621467, 5221.686698798084, 5247.501487358695, 5258.0535503609335, 5736.827895163152, 5738.167965644958, 5738.368779434259, 5738.885222295531, 5750.482456578928, 5318.0, 5340.332458442695, 5408.810691625853, 5484.808031718871, 5534.363548192009, 5523.199136125239, 5573.498385145356, 5574.554035677136, 5597.104463285855, 5626.493615574536, 5502.642328248651, 5553.333599968029, 5554.277943867352, 5557.138643510262, 5558.722063896789, 5387.973060134675, 5402.324709411324, 5405.027095122916, 5416.21464887597, 5430.91853622196, 4173.39485774567, 4176.227397931392, 4205.684573656927, 4215.768132752728, 4216.304309788912, 3734.7945862977253, 3737.6262373762406, 3739.8317075731657, 3771.0949372151035, 3771.6039815818945, 5162.0, 5217.58259339252, 5294.814681486129, 5321.840344789663, 5333.200019997032, 5534.944650553523, 5597.552195824324, 5599.216109744639, 5646.407127251661, 5650.74571644277, 5341.225522299244, 5344.091504444254, 5349.063913815066, 5350.357957045179, 5353.866153346197, 4471.910561788746, 4507.5267096955, 4589.931151032737, 4634.72191668501, 4670.299455081765, 5743.8851222976145, 5763.7982670606325, 5766.279215098113, 5767.88464230713, 5771.971140144273, 5443.482869127427, 5455.536279416136, 5461.781528738806, 5462.781488740565, 5465.699386533779, 5776.855578610568, 5780.161876527795, 5780.248567686174, 5781.132830075522, 5783.363829978614, 5316.388615309289, 5354.544863686475, 5359.3032905722, 5359.571234301325, 5363.302770639792, 5482.835514934558, 5503.0, 5503.504684578407, 5505.119180931032, 5509.3664228614225, 5365.651232669864, 5367.624266301322, 5367.838964831061, 5369.140937449989, 5392.730363481889, 5349.973250133725, 5429.104197807475, 5436.266104076058, 5439.48324909133, 5440.7551660174595, 5409.891802164014, 5430.212619170119, 5433.429489903503, 5438.265834112344, 5482.259894914136, 5318.122509785842, 5352.518273355417, 5370.248165256945, 5391.083515802401, 5410.540104091197, 5306.071437498345, 5306.920396193983, 5308.150695888639, 5317.787288508571, 5352.304200453916], "value": 5095.780225895065, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.5110277404497, 591.5238233222254, 593.0333556303226, 593.6912805341225, 596.7463827873153, 38845.61154388455, 41303.58696413039, 41505.58494415055, 44984.32523512142, 45043.324350134775, 43999.340009899875, 44485.0, 44497.33254001185, 44516.0, 44528.777356113176, 8128.211793405441, 8776.868346974801, 8936.463812171258, 8941.50821704806, 8951.149640784115, 8017.318527925133, 10426.113780328682, 10657.04086632203, 10658.467076646137, 11045.558177672883, 10769.192310576698, 10812.135029197645, 10822.512986915568, 10823.458827058656, 10826.783464330709, 10518.737031574234, 10563.894361056367, 10571.31286466381, 10580.576776928914, 10582.894171058344, 10499.475026248734, 10552.683419497427, 10555.630552930686, 10557.102646275076, 10572.207084468695, 40782.59217407818, 41331.7933410331, 41371.172576548604, 41398.1720365594, 41679.7916010421, 4092.9386059209137, 4121.835126594932, 4126.20510078114, 4141.047559061402, 4183.372494125905, 30617.23456913595, 30720.231994200323, 31005.534916976263, 31062.223444413845, 31214.37571248585, 4158.480189976253, 4226.556211597799, 4379.211741886459, 4438.556144385568, 4453.265211240141, 330256.0, 330436.9999999976, 330929.0, 331942.0, 332836.3358183218, 54302.45697543053, 54492.72753636286, 54890.17664735032, 54936.93540565842, 55018.72490637521, 58320.0, 58418.70790646019, 58481.415185847596, 58542.0, 58625.0, 13914.791278130837, 14158.787618185735, 14168.291585420893, 14183.929080354532, 14186.290685465685, 10500.264981451377, 10805.783884322269, 11355.829662555068, 11753.941230293794, 11795.35125568102, 15457.227138643022, 15814.204590898331, 15937.521874343789, 16087.356505739874, 16099.114548699934, 6328.386261503288, 6361.840953976188, 6378.681065946684, 6410.67946602668, 6437.613743175424, 866.9219770220584, 889.1908363389284, 894.9373543851998, 913.8903331600118, 919.4828532920734, 29699.10902672923, 31083.68916310853, 31204.531932021036, 31415.84292078525, 31768.364632707217, 1056.9207309451822, 1068.6793961811425, 1072.742541789965, 1081.5295346524288, 1121.5121422181214, 15451.768223476656, 16404.917975410048, 16544.172791360623, 16588.087655179083, 16594.83405165957, 481404.0, 486811.0, 487139.0, 487523.0, 487641.0, 56532.43467565354, 56593.0, 56650.716746416, 56687.71656141693, 56727.71636141875, 1287.2405280884323, 1343.6909510812568, 1353.7495563320817, 1361.3261435589448, 1364.6793003644136, 28061.579076313465, 29605.555916660836, 29768.553471698375, 30006.0, 30588.0, 48254.75872620614, 48574.0, 48665.0, 48813.75593122154, 48846.0, 392814.0, 394404.0, 395359.0, 395563.0, 395616.0, 37831.24337513289, 37941.81029094947, 38254.61745382511, 39046.609533904295, 39072.80463597664, 42896.35655465233, 43326.56673433226, 43445.565544345416, 43947.0, 44184.779076104416, 36377.45433818442, 36564.0, 36629.63370366262, 36646.0, 37237.81381093077, 36308.09229769171, 36623.45064824083, 36676.26647467089, 38081.42877856779, 38122.42816357702, 36482.8175859119, 36857.44713829241, 37203.255934881694, 37639.24721505609, 37683.0, 31540.684593154692, 33474.66525334813, 33525.32949340951, 33738.49392259069, 34031.4895276576, 25403.491930161665, 25931.740682592932, 25950.870245649407, 26037.479250415265, 26180.51463853027], "value": 19625.36020943232, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.60514644184286, 370.79791513625065, 372.85645026664736, 375.2963194011229, 388.39992212032405, 14475.855241447596, 17489.12554372281, 18265.726014109783, 18437.07814609271, 19309.0, 19044.71432928507, 19355.709664355047, 19475.805241947575, 19521.12154953027, 19579.412617621496, 5324.946750532484, 5559.054960656677, 5565.693886836222, 5572.0527510323345, 5573.359063707685, 4253.106847562016, 4555.886102847422, 4559.202139625569, 5655.773769049254, 5733.971330143364, 6387.744490220385, 6494.837629059288, 6497.252815926158, 6500.739970401179, 6509.609423434586, 5619.550435965133, 5665.801696940628, 5679.318481782213, 6228.096925945735, 6475.320091390407, 5064.898702025939, 5067.6452648314635, 5078.13671675816, 5124.461931497176, 5149.970005998815, 21453.0, 22179.11283548656, 22255.666165007537, 22288.219912303153, 22419.663705044437, 2772.1683494951635, 2821.84479853608, 2823.9717602823916, 2834.9291267718268, 2836.3334616365137, 13845.376958036912, 14465.0, 14498.42006319746, 14668.706625867422, 15035.624109397244, 1976.594798066393, 2038.2865996900966, 2038.9601303335257, 2042.7344445222175, 2048.9692654610194, 72585.63707181482, 73263.63368183178, 75222.24777752208, 76358.0, 76642.61678691626, 10500.264981451377, 10556.102731267929, 10604.94697526523, 10625.25623206368, 10675.662168915542, 14544.41822327095, 14554.816777483462, 14560.199189044704, 14571.271436428136, 14580.562583122522, 7813.765587032398, 7851.960740196377, 7882.005899705015, 8102.0277566692375, 8116.6347514362, 7502.099748030271, 7535.171131175566, 7717.919491271115, 7723.382129429632, 7749.7600383939425, 6412.583182093149, 6416.32628573993, 6470.352964703492, 6519.902201466982, 6538.378854008862, 1795.2729144696418, 1868.560888191274, 1879.4925370149983, 1900.2209094271282, 1916.467554234226, 428.5436010648649, 432.55662945481134, 443.1292510217438, 851.535912927456, 879.564615515324, 10851.511681974172, 10972.890271097347, 11082.501287441923, 11226.214165008534, 11263.380514071805, 435.904101097758, 443.16684632890605, 444.28470163037485, 446.5846762510831, 459.15744608643513, 5797.797077102366, 5965.492933100648, 5970.223870896755, 5991.191189189491, 6156.261248650101, 429642.0, 431990.0, 438006.0, 438107.0, 439527.0, 71421.64289178625, 79334.60332698416, 79618.0, 79678.60160699276, 80435.59782201052, 430.79752516317905, 430.8879691280227, 433.0710625707839, 433.21587925854305, 442.40938347306064, 9799.51002449889, 9951.950240248752, 10018.899811002088, 10273.0, 10292.433916134685, 35362.646373535936, 35915.820420897726, 35953.64046359503, 36260.28047896471, 36526.63473365339, 49680.50319496759, 50408.0, 51068.48931510637, 51713.0, 52744.472555273955, 7569.3566046884425, 7912.28789408956, 8048.919510804817, 8189.754307370788, 8567.528785916835, 7676.577788221478, 7972.880406794019, 12412.343613036737, 13346.19922804635, 13454.663633409244, 10310.381377117397, 10511.57953681844, 11209.71975700614, 11262.436878156223, 11837.940810295893, 10642.148628109579, 10896.346219226873, 10954.342739435659, 10989.230753847154, 11367.488463019019, 10856.463124504173, 11382.829257560978, 11460.828087578526, 12092.879071209527, 12190.939045304716, 8165.183481651788, 8264.0, 8361.83276334482, 8453.746387608382, 8797.12028797115, 9664.420134791935, 11087.722806929569, 11152.55389784432, 11686.70783230426, 11692.181547291948], "value": 8140.88920663355, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.4973135074347, 1361.115275071203, 1368.1654190943516, 1369.1784929042572, 1403.7192561487702, 7581.090269167701, 7998.360131189505, 8005.079415867177, 8006.1593532679035, 8019.599020049003, 7059.7882063538045, 7293.234210407897, 7516.398688104952, 7672.117706463759, 7889.289963903248, 2068.2037415595028, 2099.8530102892805, 2102.684597310408, 2102.8422868284865, 2103.453102193433, 2091.389787704206, 2100.59038487495, 2102.8212601928853, 2111.8944052797374, 2112.9260475883348, 1828.9359872404468, 1887.7829049659329, 2044.1823270691768, 2083.7707852136327, 2099.5590925905576, 2009.9196032158695, 2010.1255953660204, 2011.7887621799794, 2012.7785943546198, 2012.8892910889895, 1989.592133612606, 1994.9301774437824, 2011.4569066352149, 2012.7785943546198, 2014.2748610500207, 7894.21057894206, 7949.562774047425, 7981.561014144219, 7992.680292788282, 8012.318952889012, 4567.474740404829, 4696.201645720202, 4703.482616912137, 4713.033828065239, 4721.5750582447745, 2823.1248313022825, 2948.2039849240587, 2969.2873710309586, 2981.0311648714037, 3017.5322824961945, 1176.2001838749648, 1217.7929751942102, 1231.7905955987414, 1264.7344057747885, 1265.0701734225338, 10721.571137154504, 10744.73138171544, 10745.731356716067, 10759.085477734401, 10760.085392741626, 3931.6461518463475, 4030.294698427822, 4128.958710412918, 4200.222958752641, 4205.621494065549, 8421.957890210631, 8626.611802468904, 8630.309575233967, 8664.783380415414, 8683.913160868311, 4560.95439045612, 4568.784139692247, 4574.908501830011, 4582.95417045832, 4584.358189853423, 3891.9805400973382, 3947.881563553098, 4314.611684948307, 4317.179735850241, 4318.913621727548, 3336.2493438976035, 3427.108951672583, 3429.7770644908, 3452.741044421628, 3488.947665785015, 1036.6009086501601, 1037.8028174646793, 1050.7583255851198, 1051.2115913065202, 1052.5105825791045, 796.2276591706036, 819.4919150126959, 821.6138414944967, 829.4732844643695, 829.7469271872137, 4945.23348880924, 4991.07665081961, 4991.176455884811, 5069.264956581275, 5137.486251374833, 520.1677316293975, 526.229074405997, 536.246573564143, 536.8792021795143, 551.5697755750521, 3392.1858753898887, 3392.2537041850755, 3495.877644282438, 3511.5610548681416, 3579.1767893384144, 9258.72223833286, 9476.715698529055, 9584.0, 9591.328606997444, 9592.184664303611, 9522.95238523803, 9529.428234305964, 9534.523273836281, 9543.809123817624, 9547.522623868917, 7116.359527642537, 7136.0, 7156.606386648784, 7221.350078492961, 7353.669084891086, 4753.9762301188275, 4809.735464549483, 4815.133276010351, 4851.1753001990455, 4918.6065114791445, 8545.700900468702, 8572.3999320047, 8598.0, 8603.225709686158, 8617.095205003636, 8221.301189398968, 8530.14698530142, 8544.40189186751, 8719.694810681849, 8779.297656187362, 6312.368763123651, 6476.158099447041, 6870.31296870309, 7043.964780176067, 7064.258252883579, 6893.551919125241, 6967.616781077089, 6976.6860491277, 7047.788566343018, 7267.236940121423, 6602.834929126618, 6850.109485766819, 6856.828579285558, 6857.451403887777, 6973.058637083929, 6377.394147555975, 6450.354964503512, 6562.5406221564035, 6566.531966013568, 6693.732250709917, 5455.0726376515295, 5735.369109398046, 5802.506786923159, 5809.5933284671355, 5813.447722466359, 4269.701120921506, 4274.268921086965, 4325.84859529915, 4333.8699839004885, 4363.323684828857, 4363.585459381354, 4405.163019026375, 4510.526394728507, 4550.522195169459, 4633.513481084437], "value": 3986.5479886592075, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.21117625046776, 371.25192736635665, 374.8331992263442, 377.94897688812, 382.33473755665153, 4964.950350496494, 5079.6190285728535, 5093.974530127353, 5098.898022039557, 5113.437521872591, 4917.418355363427, 5035.420926593443, 5062.316587260719, 5067.315912351832, 5099.311592934952, 3857.267119247334, 3948.427478015686, 3977.1647953929564, 4096.627629744039, 4123.773192474413, 4054.2296963576828, 4063.1873625274916, 4073.388991651246, 4082.6733861291027, 4088.4480595119726, 4052.371882358239, 4053.209624123297, 4061.8220715992325, 4071.6335529802313, 4083.3462447708725, 3972.245273398074, 4087.2438598859308, 4092.3656833190903, 4101.856435024774, 4107.650849677781, 3592.6586974237407, 3593.6047034826147, 3608.855645774166, 3615.222727113683, 3622.366085934977, 4937.086638971802, 4942.851714448572, 4958.281289033225, 4979.278004689336, 5037.672551284155, 4657.208274593328, 4676.649251306166, 4680.461746899116, 4685.812567497296, 4694.910764709705, 5232.843014709565, 5367.597430192751, 5382.519807053052, 5391.81128660499, 5404.162354835007, 4044.170944956277, 4054.979725101385, 4058.939115913264, 4064.959350406488, 4070.267351876661, 5646.463585959327, 5647.999999999959, 5663.5186009189265, 5669.461401166883, 5678.0, 4037.6164264394833, 4152.647025002848, 4166.583341665809, 4167.5832416758685, 4175.603317684815, 4231.132617813341, 4233.597808208216, 4245.915081698348, 4254.680898932593, 4268.743875367488, 4712.9057418851435, 4714.834980775657, 4727.976360118246, 4734.218853889139, 4757.310190022427, 5103.770330335144, 5221.477852214748, 5327.653702509325, 5350.224217488442, 5394.406615272317, 1373.8900887928942, 1384.5808046751958, 1387.5629176809273, 1391.965200869966, 1396.218117854005, 997.9850302245472, 1022.6471867205802, 1036.388530766851, 1044.1907521670767, 1047.5547892145808, 5102.897942041139, 5127.435982041972, 5135.229715542624, 5148.176291793295, 5158.535731784083, 5296.285001524745, 5302.257683924254, 5318.069337865935, 5329.1206950852675, 5332.9733351333, 4891.95108048915, 5067.923981140286, 5080.390353157644, 5082.085224659523, 5086.262491938722, 2811.4658214939095, 2811.9156425307274, 2813.7889658275712, 2818.210900947738, 2828.844413557274, 5592.567233253548, 5600.943990560124, 5657.312776431162, 5677.602567820213, 5714.342850572154, 5216.035033518735, 5279.333936362113, 5373.2208829719475, 5424.728763561885, 5441.517428845542, 5586.145559563888, 5633.549316054706, 5642.85892852682, 5644.0, 5661.0, 5023.7488125592845, 5252.527272545489, 5307.310049693515, 5322.148456246982, 5328.17413300939, 5135.768890399866, 5150.484951504969, 5158.871028224325, 5199.246109314204, 5227.268182454383, 5504.339479262354, 5517.36550296721, 5530.668159910419, 5631.747301079601, 5634.731855523704, 3941.5861334560605, 4080.6123418275215, 4091.795410229417, 4092.9795351023054, 4121.670266378623, 5256.055259553989, 5261.57907367402, 5269.446708095748, 5291.497307755757, 5416.783328666809, 5237.109691352555, 5321.042212401805, 5372.785088596569, 5383.327084114486, 5403.4326395727885, 5388.54197393226, 5399.190121481809, 5427.945720542744, 5437.864053398538, 5444.646098003617, 5101.311322971352, 5376.408595054619, 5415.566754659697, 5430.15832545956, 5430.51125398716, 5449.855530338673, 5449.891002180014, 5456.236126942311, 5465.152901300305, 5465.808696695596, 5343.919841202307, 5344.839654810362, 5351.705656188947, 5352.678839269656, 5367.892642147057], "value": 4140.041319780396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.5730440387968, 744.2520267131532, 764.6100488750739, 810.4084018666373, 859.4714250735798, 14903.0, 15050.473233436833, 15402.922985385072, 15480.225988700577, 15909.920450397747, 13437.937237657401, 13628.182309061463, 13774.380152893118, 13875.930620346884, 13883.375248113834, 807.907090684573, 823.8187598728271, 826.017039722731, 827.0695467598952, 827.8178800663875, 656.101141436233, 702.8313204830855, 754.2645920227761, 812.3785304242259, 818.2431251092745, 863.8056437301619, 864.1531299326664, 867.0635713429513, 867.3364875869933, 867.453504292295, 689.055993289192, 854.4616891358467, 856.8286342731448, 857.4340934982913, 857.8970523537153, 535.5608401111091, 569.2400645138745, 610.206731249374, 612.8958077126899, 617.3024482334932, 15109.697806043927, 15116.39534418622, 15120.5463836084, 15140.924295378563, 15163.62090947724, 13764.0, 14104.0, 14147.080439771382, 14156.646083847883, 14228.644283892985, 12802.7183173457, 13125.72946931885, 13209.339533023407, 13263.469461221539, 13428.93285533566, 4382.780860956971, 4422.292433210703, 4463.799129039201, 4497.0, 4577.153226653079, 78682.60658696727, 78917.21082789155, 80804.59597702033, 81079.59460202721, 81509.59245203795, 11242.606508772154, 11250.7187320316, 11256.437178141061, 11351.545938162546, 11366.374849383197, 19441.22235110608, 19486.902565487082, 19740.506487337643, 19793.0, 19926.90036549808, 7860.646270917822, 7946.053216961765, 7994.480358776777, 8108.675652973815, 8166.632501537445, 4074.8166332514515, 4102.671786257092, 4104.42538044674, 4213.2837417639075, 4259.254630439661, 3218.7907785993834, 3270.3132342207928, 3272.0, 3277.3772983133126, 3294.522294267366, 546.0171690956263, 554.7173381534955, 559.82085732566, 563.1159080243978, 563.18057226735, 678.2098854834162, 699.0876905638089, 699.7026263837848, 711.3420086420041, 723.1828034321288, 9011.556736933124, 9321.906780932239, 9605.18355939739, 10063.69808905734, 10172.186225101974, 630.6598478233753, 662.9071929929813, 686.7562015484531, 725.7496163823472, 742.3022358982539, 4527.501974782771, 4563.908721825611, 4656.976715116471, 4662.557057079572, 4902.7058376497525, 78992.60503697445, 79469.20530794734, 80390.19609803827, 81038.59480702676, 81711.18288817035, 57646.0, 67103.66448167825, 69480.30519694739, 70568.6471567639, 71471.84978553659, 51006.0, 53087.46912530825, 57148.42851571514, 64382.0, 65677.67161164164, 9992.970420927219, 10025.899741002788, 10149.949250253701, 10169.79660406773, 10211.50115450405, 40661.0, 40961.1807763849, 41820.58179418289, 41822.58177418187, 42837.0, 51565.7421712904, 52272.340936134104, 52461.7376913113, 52545.737271313395, 52732.73633631807, 11843.881561184278, 12065.939670301592, 12277.140600157903, 12389.93805030969, 12419.130660854014, 14550.0, 14734.63163420923, 14857.925710371379, 14874.702505950037, 14883.62790930192, 13643.931780341034, 13719.382627781573, 13771.242581658105, 13874.791878122038, 13894.652633684238, 12785.16896401731, 12853.678658033625, 12864.163829351062, 12913.418896149884, 13013.934930325287, 13863.930680346937, 13886.09740366873, 14037.649058773612, 14099.295035247991, 15020.0, 10837.512311945824, 10968.451577421256, 11230.270032447865, 11305.208635395444, 11354.432278385882, 10420.290724411556, 10486.947565262124, 10534.26260161812, 10573.62992295266, 10701.625443109766], "value": 7110.603985770528, "unit": "score"}], "name": "dromaeo_css", "value": 6761.500631595964, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 110.0, 111.0, 110.0, 112.0, 112.0, 110.0, 110.0, 112.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 124.0, 170.0, 125.0, 125.0, 119.0, 118.0, 142.0, 119.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 160.0, 161.0, 166.0, 162.0, 160.0, 161.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 109.0, 76.0, 75.0, 75.0, 76.0, 90.0, 75.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 102.0, 83.0, 83.0, 84.0, 96.0, 84.0, 83.0, 103.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 55.0, 52.0, 52.0, 53.0, 51.0, 59.0, 51.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 81.0, 81.0, 80.0, 80.0, 80.0, 80.0, 79.0, 80.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 124.0, 133.0, 131.0, 123.0, 133.0, 127.0, 123.0, 159.0, 123.0], "value": 131.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 164.0, 162.0, 162.0, 163.0, 161.0, 164.0, 164.0, 163.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 61.0, 61.0, 61.0, 61.0, 61.0, 65.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1487.3, "alertThreshold": 2.0}]} 05:39:37 INFO - Return code: 0 05:39:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:39:37 INFO - # TBPL SUCCESS # 05:39:37 INFO - Running post-action listener: _resource_record_post_action 05:39:37 INFO - [mozharness: 2016-12-23 13:39:37.913847Z] Finished run-tests step (success) 05:39:37 INFO - Running post-run listener: _resource_record_post_run 05:39:37 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 415088640; Read time: 0; Write time: 380460 05:39:37 INFO - TinderboxPrint: CPU usage
13.1% 05:39:37 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:39:37 INFO - TinderboxPrint: I/O write bytes / time
415,088,640 / 380,460 05:39:37 INFO - TinderboxPrint: CPU idle
8,276.8 (86.9%) 05:39:37 INFO - TinderboxPrint: CPU user
1,199.8 (12.6%) 05:39:37 INFO - TinderboxPrint: Swap in / out
0 / 0 05:39:37 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 117317632; Read time: 0; Write time: 102972 05:39:37 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 297771008; Read time: 0; Write time: 277488 05:39:38 INFO - Running post-run listener: _upload_blobber_files 05:39:38 INFO - Blob upload gear active. 05:39:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:39:38 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:39:38 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:39:38 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:39:38 INFO - (blobuploader) - INFO - Open directory for files ... 05:39:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:39:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:39:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:39 INFO - (blobuploader) - INFO - Done attempting. 05:39:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:39:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:39:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:40 INFO - (blobuploader) - INFO - Done attempting. 05:39:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:39:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:39:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:44 INFO - (blobuploader) - INFO - Done attempting. 05:39:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:39:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:39:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:45 INFO - (blobuploader) - INFO - Done attempting. 05:39:45 INFO - (blobuploader) - INFO - Iteration through files over. 05:39:45 INFO - Return code: 0 05:39:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:39:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:39:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e8d1a965afb664b5ac73c7c03077f22d176bf3cbe6ca2486aac4000d69abdb897ab0699d9346d82058c0302bf40e5401a7bae38a1a5c8d01ed98ca5decbb32d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87914d14a024e4ba309ece2bd56a7cbf6087df46fd1ae552a64554027d015815e2e66409e21e60b93c3562c6423c07922430985277339f3adbe91bdfa5e0be22", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9138ddaec0096aafde50cc71ed1564f565d938fb2419802d89542230e3360600144dda97878d47f80fe859629a8506fa928dab1bf613a92d02edb99a1eeff9a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1be643a1a2578a741447125c4a11754ac1017ca00252916d6a4aeed684a8117287c8647fc266269ed20a909db9bf65a5ce5878e6961b676785a6f089197c56a7"} 05:39:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:39:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:39:45 INFO - Contents: 05:39:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e8d1a965afb664b5ac73c7c03077f22d176bf3cbe6ca2486aac4000d69abdb897ab0699d9346d82058c0302bf40e5401a7bae38a1a5c8d01ed98ca5decbb32d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87914d14a024e4ba309ece2bd56a7cbf6087df46fd1ae552a64554027d015815e2e66409e21e60b93c3562c6423c07922430985277339f3adbe91bdfa5e0be22", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9138ddaec0096aafde50cc71ed1564f565d938fb2419802d89542230e3360600144dda97878d47f80fe859629a8506fa928dab1bf613a92d02edb99a1eeff9a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1be643a1a2578a741447125c4a11754ac1017ca00252916d6a4aeed684a8117287c8647fc266269ed20a909db9bf65a5ce5878e6961b676785a6f089197c56a7"} 05:39:45 INFO - Running post-run listener: copy_logs_to_upload_dir 05:39:45 INFO - Copying logs to upload dir... 05:39:45 INFO - mkdir: /builds/slave/test/build/upload/logs 05:39:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.200598 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-23 05:39:44.056816) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 05:39:44.060735) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e8d1a965afb664b5ac73c7c03077f22d176bf3cbe6ca2486aac4000d69abdb897ab0699d9346d82058c0302bf40e5401a7bae38a1a5c8d01ed98ca5decbb32d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87914d14a024e4ba309ece2bd56a7cbf6087df46fd1ae552a64554027d015815e2e66409e21e60b93c3562c6423c07922430985277339f3adbe91bdfa5e0be22", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9138ddaec0096aafde50cc71ed1564f565d938fb2419802d89542230e3360600144dda97878d47f80fe859629a8506fa928dab1bf613a92d02edb99a1eeff9a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1be643a1a2578a741447125c4a11754ac1017ca00252916d6a4aeed684a8117287c8647fc266269ed20a909db9bf65a5ce5878e6961b676785a6f089197c56a7"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011837 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482492865/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e8d1a965afb664b5ac73c7c03077f22d176bf3cbe6ca2486aac4000d69abdb897ab0699d9346d82058c0302bf40e5401a7bae38a1a5c8d01ed98ca5decbb32d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87914d14a024e4ba309ece2bd56a7cbf6087df46fd1ae552a64554027d015815e2e66409e21e60b93c3562c6423c07922430985277339f3adbe91bdfa5e0be22", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9138ddaec0096aafde50cc71ed1564f565d938fb2419802d89542230e3360600144dda97878d47f80fe859629a8506fa928dab1bf613a92d02edb99a1eeff9a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1be643a1a2578a741447125c4a11754ac1017ca00252916d6a4aeed684a8117287c8647fc266269ed20a909db9bf65a5ce5878e6961b676785a6f089197c56a7"}' ========= master_lag: 0.57 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 05:39:44.644698) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:39:44.645135) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1482488789.798325-917674796 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 05:39:44.662045) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 05:39:44.665392) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 05:39:44.665698) ========= ========= Total master_lag: 0.66 =========