builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1483568602.59 results: success (0) buildid: 20170104142209 builduid: 6a0b8756f264420e96ef4c9cc88a0b6e revision: e743dff9dca1d365a32defb2819ffaa9c1543992 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:22.594938) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:22.595373) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:22.595647) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:22.610148) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:22.610418) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-04 14:23:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-01-04 14:23:23 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097869 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:22.721977) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:22.722298) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018264 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:22.772015) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:22.772371) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev e743dff9dca1d365a32defb2819ffaa9c1543992 --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 e743dff9dca1d365a32defb2819ffaa9c1543992 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-04 14:23:23,197 truncating revision to first 12 chars 2017-01-04 14:23:23,197 Setting DEBUG logging. 2017-01-04 14:23:23,197 attempt 1/10 2017-01-04 14:23:23,197 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/e743dff9dca1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 14:23:23,521 unpacking tar archive at: mozilla-inbound-e743dff9dca1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686479 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:23.472246) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:23.472564) ========= script_repo_revision: e743dff9dca1d365a32defb2819ffaa9c1543992 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:23.472949) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:23.473228) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 14:23:23.482079) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-01-04 14:23:23.482375) ========= /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=8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:23:23 INFO - MultiFileLogger online at 20170104 14:23:23 in /builds/slave/test 14:23:23 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 14:23:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:23:24 INFO - {'append_to_log': False, 14:23:24 INFO - 'base_work_dir': '/builds/slave/test', 14:23:24 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 14:23:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:23:24 INFO - 'branch': 'Mozilla-Inbound', 14:23:24 INFO - 'buildbot_json_path': 'buildprops.json', 14:23:24 INFO - 'config_files': ('talos/linux_config.py',), 14:23:24 INFO - 'default_actions': ('clobber', 14:23:24 INFO - 'read-buildbot-config', 14:23:24 INFO - 'download-and-extract', 14:23:24 INFO - 'populate-webroot', 14:23:24 INFO - 'create-virtualenv', 14:23:24 INFO - 'install', 14:23:24 INFO - 'run-tests'), 14:23:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:23:24 INFO - 'download_minidump_stackwalk': True, 14:23:24 INFO - 'download_symbols': 'ondemand', 14:23:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:23:24 INFO - 'tooltool.py': '/tools/tooltool.py', 14:23:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:23:24 INFO - '/tools/misc-python/virtualenv.py')}, 14:23:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:23:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:23:24 INFO - 'installer_path': 'installer.exe', 14:23:24 INFO - 'log_level': 'info', 14:23:24 INFO - 'log_name': 'talos', 14:23:24 INFO - 'log_to_console': True, 14:23:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:23:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:23:24 INFO - 'opt_config_files': (), 14:23:24 INFO - 'pip_index': False, 14:23:24 INFO - 'sps_profile': False, 14:23:24 INFO - 'sps_profile_interval': 0, 14:23:24 INFO - 'suite': 'dromaeojs', 14:23:24 INFO - 'system_bits': '32', 14:23:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:23:24 INFO - 'title': 'talos-linux64-ix-038', 14:23:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:23:24 INFO - 'use_talos_json': True, 14:23:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:23:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:23:24 INFO - 'work_dir': 'build'} 14:23:24 INFO - [mozharness: 2017-01-04 22:23:24.005893Z] Running clobber step. 14:23:24 INFO - Running pre-action listener: _resource_record_pre_action 14:23:24 INFO - Running main action method: clobber 14:23:24 INFO - rmtree: /builds/slave/test/build 14:23:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:23:24 INFO - Running post-action listener: _resource_record_post_action 14:23:24 INFO - [mozharness: 2017-01-04 22:23:24.466671Z] Finished clobber step (success) 14:23:24 INFO - [mozharness: 2017-01-04 22:23:24.466774Z] Running read-buildbot-config step. 14:23:24 INFO - Running pre-action listener: _resource_record_pre_action 14:23:24 INFO - Running main action method: read_buildbot_config 14:23:24 INFO - Using buildbot properties: 14:23:24 INFO - { 14:23:24 INFO - "project": "", 14:23:24 INFO - "product": "firefox", 14:23:24 INFO - "script_repo_revision": "production", 14:23:24 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 14:23:24 INFO - "repository": "", 14:23:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 14:23:24 INFO - "buildid": "20170104142209", 14:23:24 INFO - "stage_platform": "linux64-pgo", 14:23:24 INFO - "basedir": "/builds/slave/test", 14:23:24 INFO - "buildnumber": 2376, 14:23:24 INFO - "platform": "ubuntu64_hw", 14:23:24 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:23:24 INFO - "slavebuilddir": "test", 14:23:24 INFO - "branch": "mozilla-inbound", 14:23:24 INFO - "revision": "e743dff9dca1d365a32defb2819ffaa9c1543992", 14:23:24 INFO - "slavename": "talos-linux64-ix-038", 14:23:24 INFO - "builduid": "6a0b8756f264420e96ef4c9cc88a0b6e", 14:23:24 INFO - "repo_path": "integration/mozilla-inbound" 14:23:24 INFO - } 14:23:24 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 14:23:24 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 14:23:24 INFO - Running post-action listener: _resource_record_post_action 14:23:24 INFO - [mozharness: 2017-01-04 22:23:24.469137Z] Finished read-buildbot-config step (success) 14:23:24 INFO - [mozharness: 2017-01-04 22:23:24.469225Z] Running download-and-extract step. 14:23:24 INFO - Running pre-action listener: _resource_record_pre_action 14:23:24 INFO - Running main action method: download_and_extract 14:23:24 INFO - mkdir: /builds/slave/test/build/tests 14:23: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')]} 14:23:24 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:23:24 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/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 14:23:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/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 14:23:24 INFO - Downloaded 1361 bytes. 14:23:24 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:23:24 INFO - Using the following test package requirements: 14:23:24 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:23:24 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:23:24 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:23:24 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:24 INFO - u'jsshell-linux-x86_64.zip'], 14:23:24 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:23:24 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:23:24 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:23:24 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:23:24 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:23:24 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:23:24 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:23:24 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/1483562110/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:23:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:23:24 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:23:24 INFO - Expected file size: 21834370 14:23:24 INFO - Obtained file size: 21834370 14:23:28 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 14:23:28 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/1483562110/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:23:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:23:28 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:23:29 INFO - Expected file size: 21834370 14:23:29 INFO - Obtained file size: 21834370 14:23:33 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/1483562110/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 14:23:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:23:33 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:23:33 INFO - Expected file size: 14403730 14:23:33 INFO - Obtained file size: 14403730 14:23:34 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')]} 14:23:34 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:23:34 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/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 14:23:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/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 14:23:34 INFO - Downloaded 61430760 bytes. 14:23:34 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:23:34 INFO - mkdir: /builds/slave/test/properties 14:23:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:23:34 INFO - Writing to file /builds/slave/test/properties/build_url 14:23:34 INFO - Contents: 14:23:34 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:23:34 INFO - Running post-action listener: _resource_record_post_action 14:23:34 INFO - Running post-action listener: set_extra_try_arguments 14:23:34 INFO - [mozharness: 2017-01-04 22:23:34.888214Z] Finished download-and-extract step (success) 14:23:34 INFO - [mozharness: 2017-01-04 22:23:34.888443Z] Running populate-webroot step. 14:23:34 INFO - Running pre-action listener: _resource_record_pre_action 14:23:34 INFO - Running main action method: populate_webroot 14:23:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:23:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:23:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:23:34 INFO - '--tppagecycles', 14:23:34 INFO - '7'], 14:23:34 INFO - 'tests': ['tsvgm']}}, 14:23:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:23:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:23:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:23:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:23:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:23:34 INFO - 'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:23:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:23:34 INFO - 'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['damp', 'tps']}, 14:23:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:23:34 INFO - 'tests': ['damp', 'tps']}, 14:23:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['dromaeo_dom']}, 14:23:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:23:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['basic_compositor_video']}, 14:23:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:23:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['a11yr', 14:23:34 INFO - 'ts_paint', 14:23:34 INFO - 'tpaint', 14:23:34 INFO - 'sessionrestore', 14:23:34 INFO - 'sessionrestore_no_auto_restore', 14:23:34 INFO - 'tabpaint']}, 14:23:34 INFO - 'other-e10s': {'tests': ['a11yr', 14:23:34 INFO - 'ts_paint', 14:23:34 INFO - 'tpaint', 14:23:34 INFO - 'sessionrestore', 14:23:34 INFO - 'sessionrestore_no_auto_restore', 14:23:34 INFO - 'tabpaint']}, 14:23:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:23:34 INFO - 'ts_paint', 14:23:34 INFO - 'tpaint', 14:23:34 INFO - 'sessionrestore', 14:23:34 INFO - 'sessionrestore_no_auto_restore', 14:23:34 INFO - 'tabpaint']}, 14:23:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:23:34 INFO - 'ts_paint', 14:23:34 INFO - 'tpaint', 14:23:34 INFO - 'sessionrestore', 14:23:34 INFO - 'sessionrestore_no_auto_restore', 14:23:34 INFO - 'tabpaint']}, 14:23:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['a11yr', 14:23:34 INFO - 'ts_paint', 14:23:34 INFO - 'tpaint', 14:23:34 INFO - 'sessionrestore', 14:23:34 INFO - 'sessionrestore_no_auto_restore', 14:23:34 INFO - 'tabpaint']}, 14:23:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['a11yr', 14:23:34 INFO - 'ts_paint', 14:23:34 INFO - 'tpaint', 14:23:34 INFO - 'sessionrestore', 14:23:34 INFO - 'sessionrestore_no_auto_restore', 14:23:34 INFO - 'tabpaint']}, 14:23:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['tsvgx', 14:23:34 INFO - 'tsvgr_opacity', 14:23:34 INFO - 'tart', 14:23:34 INFO - 'tscrollx', 14:23:34 INFO - 'cart', 14:23:34 INFO - 'tsvg_static']}, 14:23:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:23:34 INFO - 'tsvgr_opacity', 14:23:34 INFO - 'tart', 14:23:34 INFO - 'tscrollx', 14:23:34 INFO - 'cart', 14:23:34 INFO - 'tsvg_static']}, 14:23:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:23:34 INFO - 'talos_options': ['--disable-e10s'], 14:23:34 INFO - 'tests': ['tp5o']}, 14:23:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:23:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:23:34 INFO - 'talos_options': ['--disable-e10s', 14:23:34 INFO - '--xperf_path', 14:23:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:34 INFO - 'tests': ['tp5n']}, 14:23:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:23:34 INFO - 'talos_options': ['--xperf_path', 14:23:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:34 INFO - 'tests': ['tp5n']}}, 14:23:34 INFO - 'talos.zip': {'path': '', 14:23:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:23:34 INFO - Running post-action listener: _resource_record_post_action 14:23:34 INFO - [mozharness: 2017-01-04 22:23:34.907268Z] Finished populate-webroot step (success) 14:23:34 INFO - [mozharness: 2017-01-04 22:23:34.907357Z] Running create-virtualenv step. 14:23:34 INFO - Running pre-action listener: _resource_record_pre_action 14:23:34 INFO - Running main action method: create_virtualenv 14:23:34 INFO - Creating virtualenv /builds/slave/test/build/venv 14:23:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:23:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:23:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:23:35 INFO - Using real prefix '/usr' 14:23:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:23:35 INFO - Installing distribute.............................................................................................................................................................................................done. 14:23:37 INFO - Installing pip.................done. 14:23:37 INFO - Return code: 0 14:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:23:37 INFO - Reading from file tmpfile_stdout 14:23:37 INFO - Output received: 14:23:37 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) 14:23:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:23:37 INFO - Using env: {'DISPLAY': ':0', 14:23:37 INFO - 'HOME': '/home/cltbld', 14:23:37 INFO - 'LANG': 'en_US.UTF-8', 14:23:37 INFO - 'LANGUAGE': 'en_US:en', 14:23:37 INFO - 'LOGNAME': 'cltbld', 14:23:37 INFO - 'MAIL': '/var/mail/cltbld', 14:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:37 INFO - 'MOZ_NO_REMOTE': '1', 14:23:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:37 INFO - 'NO_EM_RESTART': '1', 14:23:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:37 INFO - 'PWD': '/builds/slave/test', 14:23:37 INFO - 'SHELL': '/bin/bash', 14:23:37 INFO - 'SHLVL': '1', 14:23:37 INFO - 'TERM': 'linux', 14:23:37 INFO - 'TMOUT': '86400', 14:23:37 INFO - 'USER': 'cltbld', 14:23:37 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:37 INFO - '_': '/tools/buildbot/bin/python'} 14:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:23:37 INFO - Cleaning up... 14:23:37 INFO - Return code: 0 14:23:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:23:37 INFO - Using env: {'DISPLAY': ':0', 14:23:37 INFO - 'HOME': '/home/cltbld', 14:23:37 INFO - 'LANG': 'en_US.UTF-8', 14:23:37 INFO - 'LANGUAGE': 'en_US:en', 14:23:37 INFO - 'LOGNAME': 'cltbld', 14:23:37 INFO - 'MAIL': '/var/mail/cltbld', 14:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:37 INFO - 'MOZ_NO_REMOTE': '1', 14:23:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:37 INFO - 'NO_EM_RESTART': '1', 14:23:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:37 INFO - 'PWD': '/builds/slave/test', 14:23:37 INFO - 'SHELL': '/bin/bash', 14:23:37 INFO - 'SHLVL': '1', 14:23:37 INFO - 'TERM': 'linux', 14:23:37 INFO - 'TMOUT': '86400', 14:23:37 INFO - 'USER': 'cltbld', 14:23:37 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:37 INFO - '_': '/tools/buildbot/bin/python'} 14:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:38 INFO - Downloading/unpacking psutil>=3.1.1 14:23:38 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 14:23:38 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 14:23:38 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 14:23:38 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 14:23:38 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 14:23:38 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 14:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:23:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:40 INFO - Installing collected packages: psutil 14:23:40 INFO - Running setup.py install for psutil 14:23:40 INFO - building 'psutil._psutil_linux' extension 14:23:40 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 14:23:40 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 14:23:40 INFO - building 'psutil._psutil_posix' extension 14:23:40 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 14:23:40 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 14:23:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:40 INFO - Successfully installed psutil 14:23:40 INFO - Cleaning up... 14:23:40 INFO - Return code: 0 14:23:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:23:40 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')]} 14:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:40 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')]} 14:23:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:40 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 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23:40 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 14:23:40 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 14:23:40 INFO - Using env: {'DISPLAY': ':0', 14:23:40 INFO - 'HOME': '/home/cltbld', 14:23:40 INFO - 'LANG': 'en_US.UTF-8', 14:23:40 INFO - 'LANGUAGE': 'en_US:en', 14:23:40 INFO - 'LOGNAME': 'cltbld', 14:23:40 INFO - 'MAIL': '/var/mail/cltbld', 14:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:40 INFO - 'MOZ_NO_REMOTE': '1', 14:23:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:40 INFO - 'NO_EM_RESTART': '1', 14:23:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:40 INFO - 'PWD': '/builds/slave/test', 14:23:40 INFO - 'SHELL': '/bin/bash', 14:23:40 INFO - 'SHLVL': '1', 14:23:40 INFO - 'TERM': 'linux', 14:23:40 INFO - 'TMOUT': '86400', 14:23:40 INFO - 'USER': 'cltbld', 14:23:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:40 INFO - '_': '/tools/buildbot/bin/python'} 14:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:23:41 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 14:23:41 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 14:23:41 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 14:23:41 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 14:23:41 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 14:23:41 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 14:23:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:23:43 INFO - Installing collected packages: mozsystemmonitor 14:23:43 INFO - Running setup.py install for mozsystemmonitor 14:23:43 INFO - Successfully installed mozsystemmonitor 14:23:43 INFO - Cleaning up... 14:23:43 INFO - Return code: 0 14:23:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23: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==2.5.1'] in /builds/slave/test/build 14:23: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==2.5.1 14:23:43 INFO - Using env: {'DISPLAY': ':0', 14:23:43 INFO - 'HOME': '/home/cltbld', 14:23:43 INFO - 'LANG': 'en_US.UTF-8', 14:23:43 INFO - 'LANGUAGE': 'en_US:en', 14:23:43 INFO - 'LOGNAME': 'cltbld', 14:23:43 INFO - 'MAIL': '/var/mail/cltbld', 14:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:43 INFO - 'MOZ_NO_REMOTE': '1', 14:23:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:43 INFO - 'NO_EM_RESTART': '1', 14:23:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:43 INFO - 'PWD': '/builds/slave/test', 14:23:43 INFO - 'SHELL': '/bin/bash', 14:23:43 INFO - 'SHLVL': '1', 14:23:43 INFO - 'TERM': 'linux', 14:23:43 INFO - 'TMOUT': '86400', 14:23:43 INFO - 'USER': 'cltbld', 14:23:43 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:43 INFO - '_': '/tools/buildbot/bin/python'} 14:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:43 INFO - Downloading/unpacking jsonschema==2.5.1 14:23:43 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 14:23:43 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 14:23:43 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 14:23:43 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 14:23:43 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 14:23:43 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 14:23:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:23:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:45 INFO - Downloading functools32-3.2.3-2.tar.gz 14:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:23:45 INFO - warning: no files found matching '*.txt' 14:23:45 INFO - no previously-included directories found matching 'build' 14:23:45 INFO - no previously-included directories found matching 'dist' 14:23:45 INFO - no previously-included directories found matching '.git*' 14:23:45 INFO - Installing collected packages: jsonschema, functools32 14:23:45 INFO - Running setup.py install for functools32 14:23:45 INFO - warning: no files found matching '*.txt' 14:23:45 INFO - no previously-included directories found matching 'build' 14:23:45 INFO - no previously-included directories found matching 'dist' 14:23:45 INFO - no previously-included directories found matching '.git*' 14:23:45 INFO - Successfully installed jsonschema functools32 14:23:45 INFO - Cleaning up... 14:23:46 INFO - Return code: 0 14:23:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:46 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 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23:46 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 14:23:46 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 14:23:46 INFO - Using env: {'DISPLAY': ':0', 14:23:46 INFO - 'HOME': '/home/cltbld', 14:23:46 INFO - 'LANG': 'en_US.UTF-8', 14:23:46 INFO - 'LANGUAGE': 'en_US:en', 14:23:46 INFO - 'LOGNAME': 'cltbld', 14:23:46 INFO - 'MAIL': '/var/mail/cltbld', 14:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:46 INFO - 'MOZ_NO_REMOTE': '1', 14:23:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:46 INFO - 'NO_EM_RESTART': '1', 14:23:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:46 INFO - 'PWD': '/builds/slave/test', 14:23:46 INFO - 'SHELL': '/bin/bash', 14:23:46 INFO - 'SHLVL': '1', 14:23:46 INFO - 'TERM': 'linux', 14:23:46 INFO - 'TMOUT': '86400', 14:23:46 INFO - 'USER': 'cltbld', 14:23:46 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:46 INFO - '_': '/tools/buildbot/bin/python'} 14:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:23:46 INFO - Cleaning up... 14:23:46 INFO - Return code: 0 14:23:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:46 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 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23:46 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 14:23:46 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 14:23:46 INFO - Using env: {'DISPLAY': ':0', 14:23:46 INFO - 'HOME': '/home/cltbld', 14:23:46 INFO - 'LANG': 'en_US.UTF-8', 14:23:46 INFO - 'LANGUAGE': 'en_US:en', 14:23:46 INFO - 'LOGNAME': 'cltbld', 14:23:46 INFO - 'MAIL': '/var/mail/cltbld', 14:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:46 INFO - 'MOZ_NO_REMOTE': '1', 14:23:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:46 INFO - 'NO_EM_RESTART': '1', 14:23:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:46 INFO - 'PWD': '/builds/slave/test', 14:23:46 INFO - 'SHELL': '/bin/bash', 14:23:46 INFO - 'SHLVL': '1', 14:23:46 INFO - 'TERM': 'linux', 14:23:46 INFO - 'TMOUT': '86400', 14:23:46 INFO - 'USER': 'cltbld', 14:23:46 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:46 INFO - '_': '/tools/buildbot/bin/python'} 14:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:46 INFO - Downloading/unpacking blobuploader==1.2.4 14:23:46 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 14:23:46 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 14:23:46 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 14:23:46 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 14:23:46 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 14:23:46 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 14:23:48 INFO - Downloading blobuploader-1.2.4.tar.gz 14:23:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:23:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:23:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:49 INFO - Downloading docopt-0.6.1.tar.gz 14:23:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:23:49 INFO - Installing collected packages: blobuploader, requests, docopt 14:23:49 INFO - Running setup.py install for blobuploader 14:23:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:23:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:23:49 INFO - Running setup.py install for requests 14:23:49 INFO - Running setup.py install for docopt 14:23:49 INFO - Successfully installed blobuploader requests docopt 14:23:49 INFO - Cleaning up... 14:23:49 INFO - Return code: 0 14:23:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:49 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 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23:49 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 14:23:49 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 14:23:49 INFO - Using env: {'DISPLAY': ':0', 14:23:49 INFO - 'HOME': '/home/cltbld', 14:23:49 INFO - 'LANG': 'en_US.UTF-8', 14:23:49 INFO - 'LANGUAGE': 'en_US:en', 14:23:49 INFO - 'LOGNAME': 'cltbld', 14:23:49 INFO - 'MAIL': '/var/mail/cltbld', 14:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:49 INFO - 'MOZ_NO_REMOTE': '1', 14:23:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:49 INFO - 'NO_EM_RESTART': '1', 14:23:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:49 INFO - 'PWD': '/builds/slave/test', 14:23:49 INFO - 'SHELL': '/bin/bash', 14:23:49 INFO - 'SHLVL': '1', 14:23:49 INFO - 'TERM': 'linux', 14:23:49 INFO - 'TMOUT': '86400', 14:23:49 INFO - 'USER': 'cltbld', 14:23:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:49 INFO - '_': '/tools/buildbot/bin/python'} 14:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:50 INFO - Running setup.py (path:/tmp/pip-BMcnZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:50 INFO - Running setup.py (path:/tmp/pip-9v1DQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:50 INFO - Running setup.py (path:/tmp/pip-mkWy61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:50 INFO - Running setup.py (path:/tmp/pip-u5yCOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:50 INFO - Running setup.py (path:/tmp/pip-5q5IOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:50 INFO - Running setup.py (path:/tmp/pip-Sv1zFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:50 INFO - Running setup.py (path:/tmp/pip-C464Rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:50 INFO - Running setup.py (path:/tmp/pip-Oadm_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:50 INFO - Running setup.py (path:/tmp/pip-ihPcKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:50 INFO - Running setup.py (path:/tmp/pip-ky9rw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:50 INFO - Running setup.py (path:/tmp/pip-_XQNw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:50 INFO - Running setup.py (path:/tmp/pip-xtlNpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:50 INFO - Running setup.py (path:/tmp/pip-8BJGhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:51 INFO - Running setup.py (path:/tmp/pip-Fnl2qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:51 INFO - Running setup.py (path:/tmp/pip-EuFh4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:51 INFO - Running setup.py (path:/tmp/pip-3x8tfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:51 INFO - Running setup.py (path:/tmp/pip-c6a5Al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:23:51 INFO - Running setup.py install for manifestparser 14:23:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:23:51 INFO - Running setup.py install for mozcrash 14:23:51 INFO - Running setup.py install for mozdebug 14:23:51 INFO - Running setup.py install for mozdevice 14:23:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:23:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:23:51 INFO - Running setup.py install for mozfile 14:23:51 INFO - Running setup.py install for mozhttpd 14:23:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:23:51 INFO - Running setup.py install for mozinfo 14:23:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Running setup.py install for mozInstall 14:23:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Running setup.py install for mozleak 14:23:52 INFO - Running setup.py install for mozlog 14:23:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Running setup.py install for moznetwork 14:23:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Running setup.py install for mozprocess 14:23:52 INFO - Running setup.py install for mozprofile 14:23:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Running setup.py install for mozrunner 14:23:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:23:52 INFO - Running setup.py install for mozscreenshot 14:23:52 INFO - Running setup.py install for moztest 14:23:52 INFO - Running setup.py install for mozversion 14:23:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:23:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:23:53 INFO - Cleaning up... 14:23:53 INFO - Return code: 0 14:23:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:53 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 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23:53 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 14:23:53 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 14:23:53 INFO - Using env: {'DISPLAY': ':0', 14:23:53 INFO - 'HOME': '/home/cltbld', 14:23:53 INFO - 'LANG': 'en_US.UTF-8', 14:23:53 INFO - 'LANGUAGE': 'en_US:en', 14:23:53 INFO - 'LOGNAME': 'cltbld', 14:23:53 INFO - 'MAIL': '/var/mail/cltbld', 14:23:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:53 INFO - 'MOZ_NO_REMOTE': '1', 14:23:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:53 INFO - 'NO_EM_RESTART': '1', 14:23:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:53 INFO - 'PWD': '/builds/slave/test', 14:23:53 INFO - 'SHELL': '/bin/bash', 14:23:53 INFO - 'SHLVL': '1', 14:23:53 INFO - 'TERM': 'linux', 14:23:53 INFO - 'TMOUT': '86400', 14:23:53 INFO - 'USER': 'cltbld', 14:23:53 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:53 INFO - '_': '/tools/buildbot/bin/python'} 14:23:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:53 INFO - Running setup.py (path:/tmp/pip-A7SSMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:53 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)) 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:53 INFO - Running setup.py (path:/tmp/pip-8EvYO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:53 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)) 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:53 INFO - Running setup.py (path:/tmp/pip-2aFzxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:53 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)) 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:53 INFO - Running setup.py (path:/tmp/pip-fzXrXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:53 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)) 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:53 INFO - Running setup.py (path:/tmp/pip-QaINgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:53 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)) 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:53 INFO - Running setup.py (path:/tmp/pip-rT5qCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:53 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)) 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:53 INFO - Running setup.py (path:/tmp/pip-eDQmsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:53 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)) 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:53 INFO - Running setup.py (path:/tmp/pip-9MenDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:53 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)) 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:53 INFO - Running setup.py (path:/tmp/pip-joc2T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:53 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)) 14:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:53 INFO - Running setup.py (path:/tmp/pip-aPndPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:54 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)) 14:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:54 INFO - Running setup.py (path:/tmp/pip-aqz6iA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:54 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)) 14:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:54 INFO - Running setup.py (path:/tmp/pip-JYUeGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:54 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)) 14:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:54 INFO - Running setup.py (path:/tmp/pip-ys6n1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:54 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)) 14:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:54 INFO - Running setup.py (path:/tmp/pip-t70MdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:54 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)) 14:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:54 INFO - Running setup.py (path:/tmp/pip-tmXdY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:54 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)) 14:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:54 INFO - Running setup.py (path:/tmp/pip-gLUEbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:54 INFO - Running setup.py (path:/tmp/pip-sG8J9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:54 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)) 14:23:54 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)) 14:23:54 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)) 14:23:54 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)) 14:23:54 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)) 14:23:54 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)) 14:23:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:56 INFO - Downloading blessings-1.6.tar.gz 14:23:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:23:56 INFO - Installing collected packages: blessings 14:23:56 INFO - Running setup.py install for blessings 14:23:56 INFO - Successfully installed blessings 14:23:56 INFO - Cleaning up... 14:23:56 INFO - Return code: 0 14:23:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:23:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:57 INFO - Reading from file tmpfile_stdout 14:23:57 INFO - Current package versions: 14:23:57 INFO - argparse == 1.2.1 14:23:57 INFO - blessings == 1.6 14:23:57 INFO - blobuploader == 1.2.4 14:23:57 INFO - docopt == 0.6.1 14:23:57 INFO - functools32 == 3.2.3-2 14:23:57 INFO - jsonschema == 2.5.1 14:23:57 INFO - manifestparser == 1.1 14:23:57 INFO - mozInstall == 1.12 14:23:57 INFO - mozcrash == 1.0 14:23:57 INFO - mozdebug == 0.1 14:23:57 INFO - mozdevice == 0.49 14:23:57 INFO - mozfile == 1.2 14:23:57 INFO - mozhttpd == 0.7 14:23:57 INFO - mozinfo == 0.9 14:23:57 INFO - mozleak == 0.1 14:23:57 INFO - mozlog == 3.4 14:23:57 INFO - moznetwork == 0.27 14:23:57 INFO - mozprocess == 0.23 14:23:57 INFO - mozprofile == 0.28 14:23:57 INFO - mozrunner == 6.13 14:23:57 INFO - mozscreenshot == 0.1 14:23:57 INFO - mozsystemmonitor == 0.3 14:23:57 INFO - moztest == 0.8 14:23:57 INFO - mozversion == 1.4 14:23:57 INFO - psutil == 3.1.1 14:23:57 INFO - requests == 1.2.3 14:23:57 INFO - wsgiref == 0.1.2 14:23:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:57 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 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23:57 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 14:23:57 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 14:23:57 INFO - Using env: {'DISPLAY': ':0', 14:23:57 INFO - 'HOME': '/home/cltbld', 14:23:57 INFO - 'LANG': 'en_US.UTF-8', 14:23:57 INFO - 'LANGUAGE': 'en_US:en', 14:23:57 INFO - 'LOGNAME': 'cltbld', 14:23:57 INFO - 'MAIL': '/var/mail/cltbld', 14:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:57 INFO - 'MOZ_NO_REMOTE': '1', 14:23:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:57 INFO - 'NO_EM_RESTART': '1', 14:23:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:57 INFO - 'PWD': '/builds/slave/test', 14:23:57 INFO - 'SHELL': '/bin/bash', 14:23:57 INFO - 'SHLVL': '1', 14:23:57 INFO - 'TERM': 'linux', 14:23:57 INFO - 'TMOUT': '86400', 14:23:57 INFO - 'USER': 'cltbld', 14:23:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:57 INFO - '_': '/tools/buildbot/bin/python'} 14:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:57 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)) 14:23:57 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)) 14:23:57 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)) 14:23:57 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)) 14:23:57 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)) 14:23:57 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)) 14:23:57 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)) 14:23:57 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)) 14:23:57 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)) 14:23:57 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)) 14:23:57 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)) 14:23:57 INFO - Cleaning up... 14:23:57 INFO - Return code: 0 14:23:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:57 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 0x16b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', '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 14:23:57 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 14:23:57 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 14:23:57 INFO - Using env: {'DISPLAY': ':0', 14:23:57 INFO - 'HOME': '/home/cltbld', 14:23:57 INFO - 'LANG': 'en_US.UTF-8', 14:23:57 INFO - 'LANGUAGE': 'en_US:en', 14:23:57 INFO - 'LOGNAME': 'cltbld', 14:23:57 INFO - 'MAIL': '/var/mail/cltbld', 14:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:57 INFO - 'MOZ_NO_REMOTE': '1', 14:23:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:57 INFO - 'NO_EM_RESTART': '1', 14:23:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:57 INFO - 'PWD': '/builds/slave/test', 14:23:57 INFO - 'SHELL': '/bin/bash', 14:23:57 INFO - 'SHLVL': '1', 14:23:57 INFO - 'TERM': 'linux', 14:23:57 INFO - 'TMOUT': '86400', 14:23:57 INFO - 'USER': 'cltbld', 14:23:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:57 INFO - '_': '/tools/buildbot/bin/python'} 14:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:23:57 INFO - Cleaning up... 14:23:57 INFO - Return code: 0 14:23:57 INFO - Running post-action listener: _resource_record_post_action 14:23:57 INFO - Running post-action listener: _start_resource_monitoring 14:23:57 INFO - Starting resource monitoring. 14:23:57 INFO - [mozharness: 2017-01-04 22:23:57.556317Z] Finished create-virtualenv step (success) 14:23:57 INFO - [mozharness: 2017-01-04 22:23:57.556794Z] Running install step. 14:23:57 INFO - Running pre-action listener: _resource_record_pre_action 14:23:57 INFO - Running main action method: install 14:23:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:57 INFO - Reading from file tmpfile_stdout 14:23:57 INFO - Detecting whether we're running mozinstall >=1.0... 14:23:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:23:57 INFO - Reading from file tmpfile_stdout 14:23:57 INFO - Output received: 14:23:57 INFO - Usage: mozinstall [options] installer 14:23:57 INFO - Options: 14:23:57 INFO - -h, --help show this help message and exit 14:23:57 INFO - -d DEST, --destination=DEST 14:23:57 INFO - Directory to install application into. [default: 14:23:57 INFO - "/builds/slave/test"] 14:23:57 INFO - --app=APP Application being installed. [default: firefox] 14:23:57 INFO - mkdir: /builds/slave/test/build/application 14:23:57 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'] 14:23:57 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 14:24:15 INFO - Reading from file tmpfile_stdout 14:24:15 INFO - Output received: 14:24:15 INFO - /builds/slave/test/build/application/firefox/firefox 14:24:15 INFO - Running post-action listener: _resource_record_post_action 14:24:15 INFO - [mozharness: 2017-01-04 22:24:15.198998Z] Finished install step (success) 14:24:15 INFO - [mozharness: 2017-01-04 22:24:15.199269Z] Running run-tests step. 14:24:15 INFO - Running pre-action listener: _resource_record_pre_action 14:24:15 INFO - Running main action method: run_tests 14:24:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:24:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:24:15 INFO - Python 2.7.3 14:24:15 INFO - Return code: 0 14:24:15 INFO - grabbing minidump binary from tooltool 14:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:24:15 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 0x1ab3850>, '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 0x1a286d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a28b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:24:15 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 14:24:15 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 14:24:15 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 14:24:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:24:15 INFO - Return code: 0 14:24:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:24:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:24:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:24:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:24:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:24:15 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/1483562110/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:24:15 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/1483562110/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:24:15 INFO - Using env: {'DISPLAY': ':0', 14:24:15 INFO - 'HOME': '/home/cltbld', 14:24:15 INFO - 'LANG': 'en_US.UTF-8', 14:24:15 INFO - 'LANGUAGE': 'en_US:en', 14:24:15 INFO - 'LOGNAME': 'cltbld', 14:24:15 INFO - 'MAIL': '/var/mail/cltbld', 14:24:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:24:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:24:15 INFO - 'MOZ_NO_REMOTE': '1', 14:24:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:24:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:24:15 INFO - 'NO_EM_RESTART': '1', 14:24:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:24:15 INFO - 'PWD': '/builds/slave/test', 14:24:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:24:15 INFO - 'SHELL': '/bin/bash', 14:24:15 INFO - 'SHLVL': '1', 14:24:15 INFO - 'TERM': 'linux', 14:24:15 INFO - 'TMOUT': '86400', 14:24:15 INFO - 'USER': 'cltbld', 14:24:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861', 14:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:24:15 INFO - '_': '/tools/buildbot/bin/python'} 14:24:15 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/1483562110/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:24:15 INFO - mozversion application_buildid: 20170104123510 14:24:15 INFO - mozversion application_changeset: e743dff9dca1d365a32defb2819ffaa9c1543992 14:24:15 INFO - mozversion application_display_name: Nightly 14:24:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:24:15 INFO - mozversion application_name: Firefox 14:24:15 INFO - mozversion application_remotingname: firefox 14:24:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:24:15 INFO - mozversion application_vendor: Mozilla 14:24:15 INFO - mozversion application_version: 53.0a1 14:24:15 INFO - mozversion platform_buildid: 20170104123510 14:24:15 INFO - mozversion platform_changeset: e743dff9dca1d365a32defb2819ffaa9c1543992 14:24:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:24:15 INFO - mozversion platform_version: 53.0a1 14:24:15 INFO - using testdate: 1483568655 14:24:15 INFO - actual date: 1483568655 14:24:15 INFO - starting webserver on 'localhost:45498' 14:24:15 INFO - SUITE-START | Running 2 tests 14:24:15 INFO - TEST-START | dromaeo_css 14:24:15 INFO - operating with platform_type : linux_ 14:24:15 INFO - Initialising browser for dromaeo_css test... 14:24:15 INFO - TEST-INFO | started process 27933 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWDgrdk/profile http://localhost:45498/getInfo.html) 14:24:15 INFO - PROCESS | 27933 | 1483568655962 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:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 14:24:20 INFO - PROCESS | 27933 | __metrics Screen width/height:1600/1200 14:24:20 INFO - PROCESS | 27933 | colorDepth:24 14:24:20 INFO - PROCESS | 27933 | Browser inner width/height: 1024/697 14:24:20 INFO - PROCESS | 27933 | __metrics 14:24:21 INFO - PROCESS | 27933 | 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" 14:24:21 INFO - PROCESS | 27933 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:24:21 INFO - PROCESS | 27933 | console.error: 14:24:21 INFO - PROCESS | 27933 | Message: Error: Connection closed before committing the transaction. 14:24:21 INFO - PROCESS | 27933 | Stack: 14:24:21 INFO - PROCESS | 27933 | executeTransaction/promise resource://gre/modules/Promise-backend.js:917:23 14:24:21 INFO - PROCESS | 27933 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:801:7 14:24:21 INFO - PROCESS | 27933 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:734:11 14:24:21 INFO - PROCESS | 27933 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:765:7 14:24:21 INFO - PROCESS | 27933 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:702:7 14:24:21 INFO - PROCESS | 27933 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 14:24:21 INFO - PROCESS | 27933 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 14:24:21 INFO - PROCESS | 27933 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 14:24:21 INFO - PROCESS | 27933 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:385:5 14:24:21 INFO - PROCESS | 27933 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 14:24:21 INFO - PROCESS | 27933 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 14:24:21 INFO - PROCESS | 27933 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 14:24:21 INFO - PROCESS | 27933 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 14:24:21 INFO - PROCESS | 27933 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 14:24:21 INFO - PROCESS | 27933 | 14:24:22 INFO - PROCESS | 27933 | console.error: PushService: 14:24:22 INFO - PROCESS | 27933 | stateChangeProcessEnqueue: Error transitioning state 14:24:22 INFO - PROCESS | 27933 | UnknownError 14:24:22 INFO - TEST-INFO | 27933: exit 0 14:24:22 INFO - Browser initialized. 14:24:22 INFO - Running cycle 1/1 for dromaeo_css test... 14:24:22 INFO - TEST-INFO | started process 28016 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWDgrdk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:24:34 INFO - PROCESS | 28016 | RSS: Main: 151818240 14:24:34 INFO - PROCESS | 28016 | 14:25:26 INFO - PROCESS | 28016 | 1483568726184 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] 14:27:35 INFO - PROCESS | 28016 | Cycle 1(1): loaded http://localhost:45498/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45498/tests/dromaeo/cssquery-ext.html) 14:27:36 INFO - PROCESS | 28016 | RSS: Main: 288522240 14:27:36 INFO - PROCESS | 28016 | 14:29:23 INFO - PROCESS | 28016 | ************************* 14:29:23 INFO - PROCESS | 28016 | A coding exception was thrown and uncaught in a Task. 14:29:23 INFO - PROCESS | 28016 | 14:29:23 INFO - PROCESS | 28016 | Full message: ReferenceError: fetch is not defined 14:29:23 INFO - PROCESS | 28016 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 14:29:23 INFO - PROCESS | 28016 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 14:29:23 INFO - PROCESS | 28016 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 14:29:23 INFO - PROCESS | 28016 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 14:29:23 INFO - PROCESS | 28016 | TaskImpl@resource://gre/modules/Task.jsm:277:3 14:29:23 INFO - PROCESS | 28016 | asyncFunction@resource://gre/modules/Task.jsm:252:14 14:29:23 INFO - PROCESS | 28016 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 14:29:23 INFO - PROCESS | 28016 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 14:29:23 INFO - PROCESS | 28016 | TaskImpl@resource://gre/modules/Task.jsm:277:3 14:29:23 INFO - PROCESS | 28016 | asyncFunction@resource://gre/modules/Task.jsm:252:14 14:29:23 INFO - PROCESS | 28016 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 14:29:23 INFO - PROCESS | 28016 | 14:29:23 INFO - PROCESS | 28016 | ************************* 14:30:38 INFO - PROCESS | 28016 | Cycle 1(1): loaded http://localhost:45498/tests/dromaeo/cssquery-ext.html (next: http://localhost:45498/tests/dromaeo/cssquery-jquery.html) 14:30:39 INFO - PROCESS | 28016 | RSS: Main: 239026176 14:30:39 INFO - PROCESS | 28016 | 14:33:41 INFO - PROCESS | 28016 | Cycle 1(1): loaded http://localhost:45498/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45498/tests/dromaeo/cssquery-mootools.html) 14:33:42 INFO - PROCESS | 28016 | RSS: Main: 181080064 14:33:42 INFO - PROCESS | 28016 | 14:36:44 INFO - PROCESS | 28016 | Cycle 1(1): loaded http://localhost:45498/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45498/tests/dromaeo/cssquery-prototype.html) 14:36:44 INFO - PROCESS | 28016 | RSS: Main: 223608832 14:36:44 INFO - PROCESS | 28016 | 14:39:47 INFO - PROCESS | 28016 | Cycle 1(1): loaded http://localhost:45498/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45498/tests/dromaeo/cssquery-yui.html) 14:39:47 INFO - PROCESS | 28016 | RSS: Main: 182489088 14:39:47 INFO - PROCESS | 28016 | 14:42:50 INFO - PROCESS | 28016 | Cycle 1(1): loaded http://localhost:45498/tests/dromaeo/cssquery-yui.html (next: http://localhost:45498/tests/dromaeo/cssquery-dojo.html) 14:42:51 INFO - PROCESS | 28016 | RSS: Main: 277504000 14:42:51 INFO - PROCESS | 28016 | 14:42:51 INFO - PROCESS | 28016 | __start_tp_report 14:42:51 INFO - PROCESS | 28016 | _x_x_mozilla_page_load 14:42:51 INFO - PROCESS | 28016 | _x_x_mozilla_page_load_details 14:42:51 INFO - PROCESS | 28016 | |i|pagename|runs| 14:42:51 INFO - PROCESS | 28016 | |0;dojo.html;3154.763392745544;3170.984145079274;3203.8718451261952;3206.1183174626976;3208.3262514871876;5265.526102650761;5332.786688532459;5336.546393556552;5363.517283444489;5392.2990011298525;5390.649607775492;5407.675539467635;5410.188471729242;5423.755930983105;5423.755930983105;4834.057358815015;4857.562819346267;4882;4901.656884018103;4938;4844.709317440947;4938.333325001133;4977.925331120036;4996.825111121111;5036.899262014757;4998.075356059123;5007.37407824022;5007.424146223177;5014.348134742479;5054.772535235905;368.1999160496038;4840.588549973257;4850.442199147073;4931.90136197274;5005.2492126181;4842;4851.757412129415;4896.534829191221;4919.7540122993705;4932.334134891779;5381.434949330303;5387.973060134714;5389.67661940281;5392.865178370533;5432.972835135839;5575.972120139373;5615.550755939515;5673.886522269573;5724.341700704389;5795.130730390433;5724.398938111522;5792.218050563204;5798.797042103506;5866.384029676865;5869.7652093916195;5399.028174928511;5402.648827826218;5406.729663516809;5410.648307860016;5411.56707463402;5747.023006088975;5772.480476757112;5911.201987731687;5933.732982015819;5954.225950626477;5648.350439699487;5683.175939488751;5706.029974904276;5728.083506638918;5762.423757624288;5809.564282678816;5809.941900581025;5810.738516766755;5819.127130930395;5822;5543.861403464865;5757.050086735641;5802;5804.535637149018;5811.070228763378;5612.186232996192;5698.116791897262;5704.429557044263;5776.1624564437925;5860.208871802337;4365.105153443536;4370.890727731832;4373.234683930299;4381.539938306496;4409.030013397048;3806.5241844769403;3808.314503389361;3881.146147847469;3882.7476214045996;3924.843006279774;5456.181572764117;5506.091494903296;5519.006578815852;5558.333000040021;5602.215689803431;5815.709214539256;5826.41735826414;5835.67903765297;5836.7957121501395;5838.649681019152;5519.144532597454;5527.032769265363;5527.419620939824;5536.557075433956;5539.584531160179;4637.907241855144;4690.155771961012;4770.499097594772;4815.3258543803895;4824;6072.240969878765;6107.144999700045;6108.267007959073;6114.2662880453745;6142.877142457126;5786.739596718074;5829.795957141479;5847.415258474289;5848.970755146197;5854.209681692917;5921.940780592139;5924.763009479572;5935.732892019956;5978.611390259619;6096.329403765671;5702.45826646468;5716.885662286814;5723.914141287801;5765.250517432707;5768.163616275533;5782.421757824352;5800.332961709289;5820.214271073521;5827.5629327801435;5837.09525023622;5498.230247765236;5543.833684989457;5636.1827535008015;5651.915221271602;5655.547556195576;5662.2639056922335;5691.231683722644;5736.942630573641;5778.537716982547;5786;5656.23640808502;5698.544116470755;5727.341355744143;5730.111832665943;5736.483716465538;5494.203340515683;5555.2778138841695;5762.366139724711;5768.394318596655;5770.422957704364;5512.724363781875;5528.585356098229;5543.528800051879;5625.971870140786;5669.886602268014 14:42:51 INFO - PROCESS | 28016 | |1;ext.html;543.2943565063729;588.3204898342414;597.3250227243215;600.4205941266678;613.6318209074559;34502.65497345029;44919.326210106876;45238;45425.54574454254;45546.54453455468;46629.30056049154;46952;47008.764956175255;47010.059798804;47012;8676.913230867705;8943.10568943107;9055.32085093619;9261.610758386229;9267.026962168977;10830.675079747582;11041.503132359023;11053.557857685722;11414.600488982887;11438.599649012287;11127.221094523387;11191.328520288767;11191.440427978609;11201.159913006557;11217;11027.503762330674;11040.227184097117;11101.722456938642;11111.277766945163;11112;10875.129989600813;10952.34285942838;10965.50655220509;10966.616168434146;10974;43994.78002609999;44320.89197770049;44455.55544444579;44617.10765784699;44656.3301550477;4215.725977811432;4255.468066491689;4319.179355922396;4385.254506733831;4396.076823866992;33504.32991340184;33718.662813372044;33911.15222119442;34098.48852267218;35496.29007419837;4314.180305741899;4320.891977700551;4350.825966961318;4373.628241599468;4509.075639493896;229327;229435;229503;229693.85153074295;229834.99999999834;61522;61566;61985.690071550256;61994.38005619976;62169;62455.37544624496;62564.37435625677;62578.687106564175;62772.372276276656;62871;15956.521304360887;16512.66974660524;16540.586485337964;16541.58646033859;16551.08969006692;10542.42016689074;10686.412247326472;10690.679279621623;10855;11379;17143.228554715068;17566.91216543909;17801.7329740054;18076.457706268833;18079.096045197686;6279.717412716439;6449.709763060673;6511.827870983174;6517.152770139946;6536.509761767887;938.6761567259284;947.8388673925449;950.3110245072268;952.7951490429591;961.7691753978995;30346.69653303483;32062;32602;32621;33202;1032.9380237185792;1099.2580008494394;1105.0717397386234;1106.8893110688866;1110.9611163609236;17098.230579623945;17306;17312.826871731122;17322.913385433243;17344.47966561005;165956;166243;166692;166923;167005;62221.68889155616;62906;63027.36972630215;63077.684611576646;63088;1408.8802451791507;1437.2669938331317;1452.6150570098932;1460.0801495058258;1463.5316698656327;29580.55629165521;30435.695643043287;31267.84366078155;31704;31858.68141318557;53419.465805343;53819;54164.45835541752;54183.729081354344;54214.728926355114;375750;376810;376996;377801;378017;36621.26757464783;36828.631713682524;37647.24705505929;41014.589854101076;42813.35779963365;47553;48722.75638621784;53250.46749532455;53567.785932844155;56144.71927640335;36483;37065;38063.80968095142;41320;42723.14553708971;37358.6264137366;37464.06339841526;37475.81262093672;37631.62368376391;38100.237995240495;38124.428133577465;38433.615663844124;39006;39264.803675981435;39392.80303598464;31849.522257165696;33837;34761.304773904885;34922.47616285809;34959;25675.614865776653;25799.355016123995;25812.870935645202;25872.353191170372;26196 14:42:51 INFO - PROCESS | 28016 | |2;jquery.html;387.96314350136726;394.9545802232742;395.3950455802622;403.4996604210778;420.81273833144263;13802.723945521084;18236.361727339547;19422.70865937012;19910.10404531796;20018.799812001893;16768.245428955695;19891.801081989175;20083.196672133134;20988.37034888952;21213.681794773092;5801.303843538771;6021.2473440819895;6029.638221706689;6032.69836508175;6043.123747056674;5811.186433899257;5936;5978.40215978403;5988.101784732282;6012.158297838306;5917.142014407908;5943.554233432488;5950.107483877431;5952.375000624937;5956.076808094752;5725.57058220633;5934.050551911695;5941.792037278696;5962.105684147369;5968.432998865101;5310.230016647603;5311.389190243094;5316.867078323035;5320.361556613231;5320.388155362144;24310.149144780025;24334.634980475308;24663.506729865483;24841.62737558938;25014.749852501423;2863.8568071596337;2866.097179388507;2897.840618765967;2905.8837646494117;2940.529515277545;11615;14293.428262869473;14833.777493337608;15143;15164.09015459076;1950.4343740315292;1986.7814540400545;2000.6198822223876;2000.839932805372;2051.415346626219;101978;103573;104573;105414;105995;11716.23844450108;11778.234414763014;11783.29300241971;11917.821232681517;12201.084918631139;16859.24133413999;17015.659686806193;17041.829581704023;17050.82949170517;17074.743878841826;7283;7548;8216.958915205385;8538.359623028298;8539.14608539141;8045.235702608243;8052;8067.556284404297;8255.711050113217;8279.710210142615;5064.1897296433135;5074.441811400743;5117.360329958755;5182.97408512955;5289.64610748221;2053.1274208461346;2068.2037415594805;2080.246636435737;2109.1141720477435;2142.1110239250847;447.87683387068165;461.8591329644423;461.89145550795536;841.3984001439001;874.7098030405101;11448.599299024661;17665.29338826458;20260.392188234375;20851.8957405212;20904.686429703568;924.2790623313826;929.340168480373;930.6417029443578;931.7763736703142;938.6855403439924;10058.748531286776;10269.435181064964;10403.47982600867;10469.476526173661;10560.419176945341;701752;717733;727133;735421;737887;65705.34294657088;83375.58312208521;100318.49840750749;101329.49335253277;102155.4892225549;986.1814693804125;1027.6249169053392;1048.6853943816977;1054.7679510507676;1057.2916146182076;16871.240794164045;17415.129243537518;17594.384196553572;17726.734098988785;18304.908475457538;43755.562444376425;44328.335074973256;45587.54412455833;45828.541714582425;45844;55329.17006244829;55778.72110639421;58160.70919645544;63305.68347158235;64633.67683161554;8842.602082906413;9163.08369163105;9205.355625106446;9215.493147876663;9623.422594644064;9892.703218903445;13639.249841258428;13895.166290022631;14211.857881421054;14601.488947886774;11681.123915706186;11708.414579270831;11908;12082.758344832879;12115.697107572028;11583.826242606536;11684.182107252751;11912.940435297767;12174.452149653463;12457.563985260473;11866.881331186923;11929.164958452826;11939.880601193878;12027.15809893299;12307.56923507673;8751.518666473405;8783.780405489913;8952.507612081397;8971.102889710977;9015.5041472717;10971.286866353661;10999.725006874893;11175;11254.155938304497;11535.307881527135 14:42:51 INFO - PROCESS | 28016 | |3;mootools.html;1381.336958260035;1390.909590876593;1399.3353157250303;1399.5031763723878;1417.7589809732344;7843.882341764871;8323.209295116969;8328.333733301337;8345.540995245266;8355.4986700798;7385.113786345646;7555.433342499321;7856.607169641509;8143.104258531557;8202.753917382473;2318.547883162784;2347.4014126397747;2347.6595893595418;2350.576896158691;2353.788159065684;2303.585354636165;2333.9649905251435;2341.426350544119;2344.038944032947;2349.835511514195;2005.2780998840406;2063.772984971652;2211.7345918489805;2267.285804971437;2312.976870231293;2276.35123989663;2281.178775640768;2282.3609389371004;2283.5318759654233;2286.4512516995883;2227.2984010036785;2248.111995761665;2256.5261295128043;2259.435141214696;2263;8311.376646751458;8481.151884811532;8484.575771211477;8485.448445851;8488.108748581433;4837.879053023667;4942;4943.1102401567705;4944.283078953567;4957.925631115536;3111.502159654467;3217.7264932480766;3363.6972672459597;3378.2736711606876;3388.915277118067;1309.43039777697;1309.6136639691315;1338.0834128621827;1348.548236340827;1439.5249567642652;11260.268082574607;11275.49260283281;11314.490847911864;11329;11333.206675532716;4292.463442069741;4500.594946454835;4535.501094879561;4600.2869555218995;4640.721556706609;9475.763105922408;9478.620855165716;9491.050894910592;9492;9503.809923801486;5077.593792496591;5088.007838471536;5090.618203634742;5090.9236361454605;5091.338126043591;4162.229987452331;4292.785360731951;4670.229412147026;4687.484376718558;4767.856964291077;3964.60353964607;3965.5241371035086;4058.5941405859176;4061.634452899253;4071.2875246832277;1233.444949772606;1253.7743206222967;1259.8740125987329;1261.4512686981193;1264.5890085722176;953.7853982853802;962.6630679262203;977.7360112769654;984.3011461862015;991.3655260633201;5746.712664366849;5747.195392645033;5901.58688891782;5904.1734157218025;5904.439078287556;622.0327390907182;625.8247690646626;631.4222486424961;631.8041407163794;642.331974746268;3964.801759911993;4003.039270575041;4017.859374921922;4042.3330150524835;4045.554988951213;9533.237341012702;9931.106200441995;9931.304808663468;9943.900560994442;9963.402195868272;9817.410955342702;9828.901710982798;9838.704838854703;9841.261905356983;9853.556589953327;7727.536347819148;7783.727569535039;7788.844223115506;7796.688132474751;7803.102643196105;5824.15549745293;5831.979403604268;5851.444112809277;6038.064100064497;6140.17107690456;8981.326400520118;9002.684906028258;9043.864342034743;9067.546622668971;9070.229030532479;9557.808843823224;9563.187129094105;9569.282303827102;9597.952010239904;9641.614335426504;6762.188537375547;6925.792226233221;6930.930690693029;7300.12398512182;7438.59087750179;7483.812904677427;7690.0771907369235;8103.351731861319;8145.8370832584205;8232.50604963704;7297.890531642136;7363.8159046024275;7465.962670186615;7484.101907771103;7524.699012039458;7183.640817959185;7298.37963773064;7348.742794002184;7367.300106489874;7487.850242995219;6310.211223597051;6416.197975253093;6416.967915160394;6420.839479013062;6432.807015789534;4956.405231372259;5014.17266151081;5116.053530096944;5160.071187186342;5163.483651634957;5066.873328166678;5105.412877519133;5147.253648221062;5156.226566014978;5166.070107380708 14:42:51 INFO - PROCESS | 28016 | |4;prototype.html;389.08370786797093;397.8567715622376;398.55959165122533;403.4291477559254;406.97965101744944;5175.24958880962;5245.842624721255;5256.316678831751;5261.579073674106;5266.605004624659;4991.725455099968;5037.546620804127;5103.642745007851;5123.180291153416;5225.86935326617;4153.584641535853;4164.27125253081;4176.45706058212;4180.79096045198;4186;4170.624643782059;4184.958150418487;4214.957850421502;4223.303154979425;4228.661707063443;3876.8449262029485;4002.3796311571755;4156.2726522858375;4173.749575025508;4174.20690068886;4134.772587507686;4146.232946904832;4155.877912963498;4158.209940111399;4176.227397931392;3754.6057663945166;3755.887323380303;3756.586775454698;3771;3782.092297848524;4891.559759621616;5060.038592667418;5108.106081435734;5114.462981386938;5120.743962801845;4657.580817726387;4661.3241080043545;4671.906561868744;4681.297805329194;4688.601468875149;5396.83809485716;5407.729183641841;5415.214793854909;5454.236406903036;5485.4640700603895;3798.4302354646748;3989.68082553395;3995.4805875236325;4002.779847108408;4004.199160167978;5643.68959707216;5678.914816277759;5688.402717714622;5731.914021289643;5765.625234359795;4066.552679205285;4069.4709687740406;4091.3044782387065;4093.89765255871;4109.198706252252;4056.499095334745;4134.4082528226845;4161.2301724181125;4167.874963751093;4183.39340795589;4647.930281045787;4661.324108004321;4662.137504561667;4683.414573178353;4699.78850951708;5415.972920135374;5417.835165439371;5434.130539113723;5439.673619582838;5440.959640134949;1373.5124030969062;1374.924379159156;1395.958121256364;1396.4414234306155;1411.2097225553723;950.3442624589012;956.383132879289;962.0139357158828;963.8650588917557;973.2116985241977;5087.872803179876;5107.131787596118;5109.412417572027;5218.321618189611;5245.6852588844795;5331.280277162611;5414.512693857571;5458.962797068544;5481.5340696041285;5548.72256387179;5054.595632349403;5103.515166059218;5150.609335479475;5171.870703232374;5176.938727560841;2846.7864910131825;2866.254773758838;2889.1765846734;2894.292367503141;2900;5474.124140137638;5647.29408823897;5653.830385088454;5669.319681638147;5687.630304030225;5453.372862120827;5479.205515200274;5522.6134170607675;5524.530414914697;5554.472714914704;5593.356763972114;5672.7730890763905;5718.599698021182;5721.342045664802;5771.2397718695365;5201.791928322825;5355.002397315032;5383.703896285742;5403.189521571796;5409.864753381197;5305.840824775263;5309.044372013074;5315.654482458628;5331.760070796746;5366.838994830161;5501.587380946525;5671.1077350550695;5703.173039909107;5721.685307308138;5734.512566431733;3891.9416208756325;3937.5668676444993;3983.48214732083;4020.19091408873;4028.8187031584234;5364.854058376524;5457.590680699042;5505.752241149078;5511.917321240265;5521.779128834802;5357.705326207096;5408.621396502364;5413.621046526705;5416.241726158262;5427.918581221364;5411.188321751769;5412.891742165056;5414.972925135349;5432.918506222489;5433.5924805640525;5194.857131431079;5359.490848369399;5389.191621256686;5409.945900540944;5442.591805614674;5413.83758487246;5454.2091396746455;5488.506034456918;5489.615726899079;5497.87293604818;5226.386614943696;5252.606054546;5347.331583552056;5357.464253574612;5359.846119257607 14:42:51 INFO - PROCESS | 28016 | |5;yui.html;746.2500187311748;748.7267147491167;785.2343964634482;850.8425941200876;884.0673089890167;15027.248637568118;16076.115813630246;16110.516684499455;16112.436064737736;16171.272292746826;15149.091054536735;15357.462488812895;15390.15354155521;15406.768898466504;16563.83436165641;651.9739210431577;661.3948237362831;742.7437534050771;746.7871656577877;748.973785917493;769.8537277917178;770.2720928722332;771.9421043421738;772.528757457953;772.64072206424;796.77690246731;797.9241972012678;798.6246464161835;798.7843282313772;799.160881074869;675.6554157379747;684.685044879356;779.0845756236399;781.8866264391688;796.0646240667222;580.8373655376502;581.2850194261083;581.5609215042658;581.7353104337516;582.2721598002497;16073.598160045976;16540.007599544067;16583.50249492517;16592.502224933272;16607.66784664312;14310.284485775774;15122.621934451618;15445.768313475306;15603.687926241411;15664.216789160497;12472.314022728746;12571.143782706205;12695.936520317431;12770.936145319307;12794.808077878839;3690.76010059348;3748.287825313209;3754.699624030071;3805.56236032857;3808.923821523582;76950.23049769487;78460;78696.60651696761;79205;80728;11849.526018959317;11898.583549575724;11906.642800715992;11931.897706596348;12225.938870305592;20578.588428231353;21235.893820530797;21630.675539866916;22612.773872261067;22747.772522274896;7936;8011.599420028975;8042;8119.4722343047515;8196.098429172785;4532.818687252539;4680.67235293533;4706.647001474903;4759;4768.7615619218905;3626.9818650906577;3673.9265214695556;3688.5942546319884;3708.4808126862263;3717.237966216919;594.4382558482279;602.6775675013846;603.4749767702127;612.8222815383579;619.9938000620026;680.3332733920786;683.7435961514432;704.2218348724688;707.3987110955646;721.4372789224414;9155.633774649074;9652.75868103289;9758.609655613696;9776.951115244521;9838.212942964547;739.988900166498;741.2402287655196;762.4167511853436;770.8976164085411;774.6901239504131;4553.931691024637;4581.9541804582195;4618.907621847611;4638.397008388888;4669.976650116796;67006;82176;84847;85924.57037714896;85987;57527.71236143876;60784.69607652022;63056.36943630505;66681.6665916677;67674;59312;59861.10208346878;60327.69836150879;60795;62187;9900.207983361457;10257.179425645782;10423.739406514898;10493.9475302623;10537.894621053998;41120.38319425151;41977.790111049246;42100.36849447199;42952.35571466368;43084.569154308054;50115.24827127523;50432;50896.23655645242;53757.73121134526;56273;13086.869131308566;13707.520236791665;13740.65648358799;13952.860471395563;14081.929590351983;15175.16536590498;15603;15752.369905204121;15821.841781582038;16054.518364449084;14691.265436728334;14701.41194352214;14910.701785964004;15454.227288635298;15886.52340429789;12777.425015874494;13220.206787592775;13772.793408099087;13773.380197890918;13880.305984700926;14109.788353174505;14527.927360363554;15132.773008404663;15312.081275123528;15441.613959651098;11601.883981160081;12015.158938874194;12137.878621214028;12614.495420183457;12614.864662180447;10513;10552.630657926935;12000.699982500508;12009.819852702041;12253.448594813077 14:42:51 INFO - PROCESS | 28016 | __end_tp_report 14:42:51 INFO - PROCESS | 28016 | __start_cc_report 14:42:51 INFO - PROCESS | 28016 | _x_x_mozilla_cycle_collect,1221 14:42:51 INFO - PROCESS | 28016 | __end_cc_report 14:42:51 INFO - PROCESS | 28016 | __startTimestamp1483569771116__endTimestamp 14:42:51 INFO - PROCESS | 28016 | 14:42:51 INFO - PROCESS | 28016 | ------- Summary: start ------- 14:42:51 INFO - PROCESS | 28016 | Number of tests: 6 14:42:51 INFO - PROCESS | 28016 | 14:42:51 INFO - PROCESS | 28016 | [#0] dojo.html Cycles:165 Average:5347.15 Median:5575.97 stddev:734.12 (13.2%) stddev-sans-first:715.94 14:42:51 INFO - PROCESS | 28016 | Values: 3154.8 3171.0 3203.9 3206.1 3208.3 5265.5 5332.8 5336.5 5363.5 5392.3 5390.6 5407.7 5410.2 5423.8 5423.8 4834.1 4857.6 4882.0 4901.7 4938.0 4844.7 4938.3 4977.9 4996.8 5036.9 4998.1 5007.4 5007.4 5014.3 5054.8 368.2 4840.6 4850.4 4931.9 5005.2 4842.0 4851.8 4896.5 4919.8 4932.3 5381.4 5388.0 5389.7 5392.9 5433.0 5576.0 5615.6 5673.9 5724.3 5795.1 5724.4 5792.2 5798.8 5866.4 5869.8 5399.0 5402.6 5406.7 5410.6 5411.6 5747.0 5772.5 5911.2 5933.7 5954.2 5648.4 5683.2 5706.0 5728.1 5762.4 5809.6 5809.9 5810.7 5819.1 5822.0 5543.9 5757.1 5802.0 5804.5 5811.1 5612.2 5698.1 5704.4 5776.2 5860.2 4365.1 4370.9 4373.2 4381.5 4409.0 3806.5 3808.3 3881.1 3882.7 3924.8 5456.2 5506.1 5519.0 5558.3 5602.2 5815.7 5826.4 5835.7 5836.8 5838.6 5519.1 5527.0 5527.4 5536.6 5539.6 4637.9 4690.2 4770.5 4815.3 4824.0 6072.2 6107.1 6108.3 6114.3 6142.9 5786.7 5829.8 5847.4 5849.0 5854.2 5921.9 5924.8 5935.7 5978.6 6096.3 5702.5 5716.9 5723.9 5765.3 5768.2 5782.4 5800.3 5820.2 5827.6 5837.1 5498.2 5543.8 5636.2 5651.9 5655.5 5662.3 5691.2 5736.9 5778.5 5786.0 5656.2 5698.5 5727.3 5730.1 5736.5 5494.2 5555.3 5762.4 5768.4 5770.4 5512.7 5528.6 5543.5 5626.0 5669.9 14:42:51 INFO - PROCESS | 28016 | 14:42:51 INFO - PROCESS | 28016 | [#1] ext.html Cycles:180 Average:45763.86 Median:37411.34 stddev:71542.04 (191.2%) stddev-sans-first:71662.16 14:42:51 INFO - PROCESS | 28016 | Values: 543.3 588.3 597.3 600.4 613.6 34502.7 44919.3 45238.0 45425.5 45546.5 46629.3 46952.0 47008.8 47010.1 47012.0 8676.9 8943.1 9055.3 9261.6 9267.0 10830.7 11041.5 11053.6 11414.6 11438.6 11127.2 11191.3 11191.4 11201.2 11217.0 11027.5 11040.2 11101.7 11111.3 11112.0 10875.1 10952.3 10965.5 10966.6 10974.0 43994.8 44320.9 44455.6 44617.1 44656.3 4215.7 4255.5 4319.2 4385.3 4396.1 33504.3 33718.7 33911.2 34098.5 35496.3 4314.2 4320.9 4350.8 4373.6 4509.1 229327.0 229435.0 229503.0 229693.9 229835.0 61522.0 61566.0 61985.7 61994.4 62169.0 62455.4 62564.4 62578.7 62772.4 62871.0 15956.5 16512.7 16540.6 16541.6 16551.1 10542.4 10686.4 10690.7 10855.0 11379.0 17143.2 17566.9 17801.7 18076.5 18079.1 6279.7 6449.7 6511.8 6517.2 6536.5 938.7 947.8 950.3 952.8 961.8 30346.7 32062.0 32602.0 32621.0 33202.0 1032.9 1099.3 1105.1 1106.9 1111.0 17098.2 17306.0 17312.8 17322.9 17344.5 165956.0 166243.0 166692.0 166923.0 167005.0 62221.7 62906.0 63027.4 63077.7 63088.0 1408.9 1437.3 1452.6 1460.1 1463.5 29580.6 30435.7 31267.8 31704.0 31858.7 53419.5 53819.0 54164.5 54183.7 54214.7 375750.0 376810.0 376996.0 377801.0 378017.0 36621.3 36828.6 37647.2 41014.6 42813.4 47553.0 48722.8 53250.5 53567.8 56144.7 36483.0 37065.0 38063.8 41320.0 42723.1 37358.6 37464.1 37475.8 37631.6 38100.2 38124.4 38433.6 39006.0 39264.8 39392.8 31849.5 33837.0 34761.3 34922.5 34959.0 25675.6 25799.4 25812.9 25872.4 26196.0 14:42:51 INFO - PROCESS | 28016 | 14:42:51 INFO - PROCESS | 28016 | [#2] jquery.html Cycles:180 Average:36503.58 Median:13939.30 stddev:118793.54 (852.2%) stddev-sans-first:119095.83 14:42:51 INFO - PROCESS | 28016 | Values: 388.0 395.0 395.4 403.5 420.8 13802.7 18236.4 19422.7 19910.1 20018.8 16768.2 19891.8 20083.2 20988.4 21213.7 5801.3 6021.2 6029.6 6032.7 6043.1 5811.2 5936.0 5978.4 5988.1 6012.2 5917.1 5943.6 5950.1 5952.4 5956.1 5725.6 5934.1 5941.8 5962.1 5968.4 5310.2 5311.4 5316.9 5320.4 5320.4 24310.1 24334.6 24663.5 24841.6 25014.7 2863.9 2866.1 2897.8 2905.9 2940.5 11615.0 14293.4 14833.8 15143.0 15164.1 1950.4 1986.8 2000.6 2000.8 2051.4 101978.0 103573.0 104573.0 105414.0 105995.0 11716.2 11778.2 11783.3 11917.8 12201.1 16859.2 17015.7 17041.8 17050.8 17074.7 7283.0 7548.0 8217.0 8538.4 8539.1 8045.2 8052.0 8067.6 8255.7 8279.7 5064.2 5074.4 5117.4 5183.0 5289.6 2053.1 2068.2 2080.2 2109.1 2142.1 447.9 461.9 461.9 841.4 874.7 11448.6 17665.3 20260.4 20851.9 20904.7 924.3 929.3 930.6 931.8 938.7 10058.7 10269.4 10403.5 10469.5 10560.4 701752.0 717733.0 727133.0 735421.0 737887.0 65705.3 83375.6 100318.5 101329.5 102155.5 986.2 1027.6 1048.7 1054.8 1057.3 16871.2 17415.1 17594.4 17726.7 18304.9 43755.6 44328.3 45587.5 45828.5 45844.0 55329.2 55778.7 58160.7 63305.7 64633.7 8842.6 9163.1 9205.4 9215.5 9623.4 9892.7 13639.2 13895.2 14211.9 14601.5 11681.1 11708.4 11908.0 12082.8 12115.7 11583.8 11684.2 11912.9 12174.5 12457.6 11866.9 11929.2 11939.9 12027.2 12307.6 8751.5 8783.8 8952.5 8971.1 9015.5 10971.3 10999.7 11175.0 11254.2 11535.3 14:42:51 INFO - PROCESS | 28016 | 14:42:51 INFO - PROCESS | 28016 | [#3] mootools.html Cycles:180 Average:5470.07 Median:5746.95 stddev:2992.98 (52.1%) stddev-sans-first:2985.60 14:42:51 INFO - PROCESS | 28016 | Values: 1381.3 1390.9 1399.3 1399.5 1417.8 7843.9 8323.2 8328.3 8345.5 8355.5 7385.1 7555.4 7856.6 8143.1 8202.8 2318.5 2347.4 2347.7 2350.6 2353.8 2303.6 2334.0 2341.4 2344.0 2349.8 2005.3 2063.8 2211.7 2267.3 2313.0 2276.4 2281.2 2282.4 2283.5 2286.5 2227.3 2248.1 2256.5 2259.4 2263.0 8311.4 8481.2 8484.6 8485.4 8488.1 4837.9 4942.0 4943.1 4944.3 4957.9 3111.5 3217.7 3363.7 3378.3 3388.9 1309.4 1309.6 1338.1 1348.5 1439.5 11260.3 11275.5 11314.5 11329.0 11333.2 4292.5 4500.6 4535.5 4600.3 4640.7 9475.8 9478.6 9491.1 9492.0 9503.8 5077.6 5088.0 5090.6 5090.9 5091.3 4162.2 4292.8 4670.2 4687.5 4767.9 3964.6 3965.5 4058.6 4061.6 4071.3 1233.4 1253.8 1259.9 1261.5 1264.6 953.8 962.7 977.7 984.3 991.4 5746.7 5747.2 5901.6 5904.2 5904.4 622.0 625.8 631.4 631.8 642.3 3964.8 4003.0 4017.9 4042.3 4045.6 9533.2 9931.1 9931.3 9943.9 9963.4 9817.4 9828.9 9838.7 9841.3 9853.6 7727.5 7783.7 7788.8 7796.7 7803.1 5824.2 5832.0 5851.4 6038.1 6140.2 8981.3 9002.7 9043.9 9067.5 9070.2 9557.8 9563.2 9569.3 9598.0 9641.6 6762.2 6925.8 6930.9 7300.1 7438.6 7483.8 7690.1 8103.4 8145.8 8232.5 7297.9 7363.8 7466.0 7484.1 7524.7 7183.6 7298.4 7348.7 7367.3 7487.9 6310.2 6416.2 6417.0 6420.8 6432.8 4956.4 5014.2 5116.1 5160.1 5163.5 5066.9 5105.4 5147.3 5156.2 5166.1 14:42:51 INFO - PROCESS | 28016 | 14:42:51 INFO - PROCESS | 28016 | [#4] prototype.html Cycles:180 Average:4577.80 Median:5222.10 stddev:1305.56 (25.0%) stddev-sans-first:1270.81 14:42:51 INFO - PROCESS | 28016 | Values: 389.1 397.9 398.6 403.4 407.0 5175.2 5245.8 5256.3 5261.6 5266.6 4991.7 5037.5 5103.6 5123.2 5225.9 4153.6 4164.3 4176.5 4180.8 4186.0 4170.6 4185.0 4215.0 4223.3 4228.7 3876.8 4002.4 4156.3 4173.7 4174.2 4134.8 4146.2 4155.9 4158.2 4176.2 3754.6 3755.9 3756.6 3771.0 3782.1 4891.6 5060.0 5108.1 5114.5 5120.7 4657.6 4661.3 4671.9 4681.3 4688.6 5396.8 5407.7 5415.2 5454.2 5485.5 3798.4 3989.7 3995.5 4002.8 4004.2 5643.7 5678.9 5688.4 5731.9 5765.6 4066.6 4069.5 4091.3 4093.9 4109.2 4056.5 4134.4 4161.2 4167.9 4183.4 4647.9 4661.3 4662.1 4683.4 4699.8 5416.0 5417.8 5434.1 5439.7 5441.0 1373.5 1374.9 1396.0 1396.4 1411.2 950.3 956.4 962.0 963.9 973.2 5087.9 5107.1 5109.4 5218.3 5245.7 5331.3 5414.5 5459.0 5481.5 5548.7 5054.6 5103.5 5150.6 5171.9 5176.9 2846.8 2866.3 2889.2 2894.3 2900.0 5474.1 5647.3 5653.8 5669.3 5687.6 5453.4 5479.2 5522.6 5524.5 5554.5 5593.4 5672.8 5718.6 5721.3 5771.2 5201.8 5355.0 5383.7 5403.2 5409.9 5305.8 5309.0 5315.7 5331.8 5366.8 5501.6 5671.1 5703.2 5721.7 5734.5 3891.9 3937.6 3983.5 4020.2 4028.8 5364.9 5457.6 5505.8 5511.9 5521.8 5357.7 5408.6 5413.6 5416.2 5427.9 5411.2 5412.9 5415.0 5432.9 5433.6 5194.9 5359.5 5389.2 5409.9 5442.6 5413.8 5454.2 5488.5 5489.6 5497.9 5226.4 5252.6 5347.3 5357.5 5359.8 14:42:51 INFO - PROCESS | 28016 | 14:42:51 INFO - PROCESS | 28016 | [#5] yui.html Cycles:180 Average:17654.49 Median:4567.94 stddev:21975.93 (481.1%) stddev-sans-first:22000.90 14:42:51 INFO - PROCESS | 28016 | Values: 746.3 748.7 785.2 850.8 884.1 15027.2 16076.1 16110.5 16112.4 16171.3 15149.1 15357.5 15390.2 15406.8 16563.8 652.0 661.4 742.7 746.8 749.0 769.9 770.3 771.9 772.5 772.6 796.8 797.9 798.6 798.8 799.2 675.7 684.7 779.1 781.9 796.1 580.8 581.3 581.6 581.7 582.3 16073.6 16540.0 16583.5 16592.5 16607.7 14310.3 15122.6 15445.8 15603.7 15664.2 12472.3 12571.1 12695.9 12770.9 12794.8 3690.8 3748.3 3754.7 3805.6 3808.9 76950.2 78460.0 78696.6 79205.0 80728.0 11849.5 11898.6 11906.6 11931.9 12225.9 20578.6 21235.9 21630.7 22612.8 22747.8 7936.0 8011.6 8042.0 8119.5 8196.1 4532.8 4680.7 4706.6 4759.0 4768.8 3627.0 3673.9 3688.6 3708.5 3717.2 594.4 602.7 603.5 612.8 620.0 680.3 683.7 704.2 707.4 721.4 9155.6 9652.8 9758.6 9777.0 9838.2 740.0 741.2 762.4 770.9 774.7 4553.9 4582.0 4618.9 4638.4 4670.0 67006.0 82176.0 84847.0 85924.6 85987.0 57527.7 60784.7 63056.4 66681.7 67674.0 59312.0 59861.1 60327.7 60795.0 62187.0 9900.2 10257.2 10423.7 10493.9 10537.9 41120.4 41977.8 42100.4 42952.4 43084.6 50115.2 50432.0 50896.2 53757.7 56273.0 13086.9 13707.5 13740.7 13952.9 14081.9 15175.2 15603.0 15752.4 15821.8 16054.5 14691.3 14701.4 14910.7 15454.2 15886.5 12777.4 13220.2 13772.8 13773.4 13880.3 14109.8 14527.9 15132.8 15312.1 15441.6 11601.9 12015.2 12137.9 12614.5 12614.9 10513.0 10552.6 12000.7 12009.8 12253.4 14:42:51 INFO - PROCESS | 28016 | -------- Summary: end -------- 14:42:51 INFO - PROCESS | 28016 | 14:42:51 INFO - TEST-INFO | 28016: exit 0 14:42:51 INFO - TEST-OK | dromaeo_css | took 1116509ms 14:42:51 INFO - TEST-START | kraken 14:42:51 INFO - operating with platform_type : linux_ 14:42:51 INFO - Initialising browser for kraken test... 14:42:52 INFO - TEST-INFO | started process 28317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgBDYOr/profile http://localhost:45498/getInfo.html) 14:42:52 INFO - PROCESS | 28317 | 1483569772437 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:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 14:42:56 INFO - PROCESS | 28317 | __metrics Screen width/height:1600/1200 14:42:56 INFO - PROCESS | 28317 | colorDepth:24 14:42:56 INFO - PROCESS | 28317 | Browser inner width/height: 1024/697 14:42:56 INFO - PROCESS | 28317 | __metrics 14:42:57 INFO - PROCESS | 28317 | 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" 14:42:57 INFO - PROCESS | 28317 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:42:58 INFO - PROCESS | 28317 | console.error: PushService: 14:42:58 INFO - PROCESS | 28317 | stateChangeProcessEnqueue: Error transitioning state 14:42:58 INFO - PROCESS | 28317 | UnknownError 14:42:59 INFO - TEST-INFO | 28317: exit 0 14:42:59 INFO - Browser initialized. 14:42:59 INFO - Running cycle 1/1 for kraken test... 14:42:59 INFO - TEST-INFO | started process 28403 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgBDYOr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:43:10 INFO - PROCESS | 28403 | RSS: Main: 147320832 14:43:10 INFO - PROCESS | 28403 | 14:43:13 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45498/tests/kraken/driver.html?testName=audio-beat-detection) 14:43:14 INFO - PROCESS | 28403 | RSS: Main: 201441280 14:43:14 INFO - PROCESS | 28403 | 14:43:16 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45498/tests/kraken/driver.html?testName=audio-dft) 14:43:17 INFO - PROCESS | 28403 | RSS: Main: 181469184 14:43:17 INFO - PROCESS | 28403 | 14:43:20 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45498/tests/kraken/driver.html?testName=audio-fft) 14:43:21 INFO - PROCESS | 28403 | RSS: Main: 263196672 14:43:21 INFO - PROCESS | 28403 | 14:43:23 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45498/tests/kraken/driver.html?testName=audio-oscillator) 14:43:23 INFO - PROCESS | 28403 | RSS: Main: 174968832 14:43:23 INFO - PROCESS | 28403 | 14:43:25 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45498/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:43:26 INFO - PROCESS | 28403 | RSS: Main: 178659328 14:43:26 INFO - PROCESS | 28403 | 14:43:29 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45498/tests/kraken/driver.html?testName=imaging-darkroom) 14:43:30 INFO - PROCESS | 28403 | RSS: Main: 217423872 14:43:30 INFO - PROCESS | 28403 | 14:43:33 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45498/tests/kraken/driver.html?testName=imaging-desaturate) 14:43:33 INFO - PROCESS | 28403 | RSS: Main: 217395200 14:43:34 INFO - PROCESS | 28403 | 14:43:36 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45498/tests/kraken/driver.html?testName=json-parse-financial) 14:43:37 INFO - PROCESS | 28403 | RSS: Main: 215531520 14:43:37 INFO - PROCESS | 28403 | 14:43:38 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45498/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:43:39 INFO - PROCESS | 28403 | RSS: Main: 178073600 14:43:39 INFO - PROCESS | 28403 | 14:43:41 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45498/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:43:41 INFO - PROCESS | 28403 | RSS: Main: 185196544 14:43:41 INFO - PROCESS | 28403 | 14:43:43 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45498/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:43:44 INFO - PROCESS | 28403 | RSS: Main: 193613824 14:43:44 INFO - PROCESS | 28403 | 14:43:47 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45498/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:43:47 INFO - PROCESS | 28403 | RSS: Main: 203530240 14:43:47 INFO - PROCESS | 28403 | 14:43:50 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45498/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:43:51 INFO - PROCESS | 28403 | RSS: Main: 200273920 14:43:51 INFO - PROCESS | 28403 | 14:43:52 INFO - PROCESS | 28403 | Cycle 1(1): loaded http://localhost:45498/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45498/tests/kraken/driver.html?testName=ai-astar) 14:43:53 INFO - PROCESS | 28403 | RSS: Main: 251260928 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | __start_tp_report 14:43:53 INFO - PROCESS | 28403 | _x_x_mozilla_page_load 14:43:53 INFO - PROCESS | 28403 | _x_x_mozilla_page_load_details 14:43:53 INFO - PROCESS | 28403 | |i|pagename|runs| 14:43:53 INFO - PROCESS | 28403 | |0;ai-astar;112;113;111;112;110;111;110;111;113;110 14:43:53 INFO - PROCESS | 28403 | |1;audio-beat-detection;152;119;123;159;123;119;118;117;137;121 14:43:53 INFO - PROCESS | 28403 | |2;audio-dft;161;160;161;161;161;164;160;160;161;161 14:43:53 INFO - PROCESS | 28403 | |3;audio-fft;74;75;75;120;76;76;76;76;106;75 14:43:53 INFO - PROCESS | 28403 | |4;audio-oscillator;82;83;109;84;84;83;107;83;85;108 14:43:53 INFO - PROCESS | 28403 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 14:43:53 INFO - PROCESS | 28403 | |6;imaging-darkroom;147;147;146;146;146;148;148;147;147;149 14:43:53 INFO - PROCESS | 28403 | |7;imaging-desaturate;96;96;96;95;96;96;95;96;96;98 14:43:53 INFO - PROCESS | 28403 | |8;json-parse-financial;52;51;51;53;53;52;53;54;52;53 14:43:53 INFO - PROCESS | 28403 | |9;json-stringify-tinderbox;67;67;68;67;68;68;68;68;67;67 14:43:53 INFO - PROCESS | 28403 | |10;stanford-crypto-aes;86;80;81;81;81;81;81;82;86;86 14:43:53 INFO - PROCESS | 28403 | |11;stanford-crypto-ccm;126;126;136;141;132;125;134;130;171;129 14:43:53 INFO - PROCESS | 28403 | |12;stanford-crypto-pbkdf2;168;174;166;167;166;166;162;162;163;163 14:43:53 INFO - PROCESS | 28403 | |13;stanford-crypto-sha256-iterative;63;61;61;62;62;61;61;61;66;60 14:43:53 INFO - PROCESS | 28403 | __end_tp_report 14:43:53 INFO - PROCESS | 28403 | __start_cc_report 14:43:53 INFO - PROCESS | 28403 | _x_x_mozilla_cycle_collect,1590 14:43:53 INFO - PROCESS | 28403 | __end_cc_report 14:43:53 INFO - PROCESS | 28403 | __startTimestamp1483569833136__endTimestamp 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | ------- Summary: start ------- 14:43:53 INFO - PROCESS | 28403 | Number of tests: 14 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:1.16 (1.0%) stddev-sans-first:1.20 14:43:53 INFO - PROCESS | 28403 | Values: 112.0 113.0 111.0 112.0 110.0 111.0 110.0 111.0 113.0 110.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:123.00 stddev:15.25 (12.4%) stddev-sans-first:13.67 14:43:53 INFO - PROCESS | 28403 | Values: 152.0 119.0 123.0 159.0 123.0 119.0 118.0 117.0 137.0 121.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:1.22 14:43:53 INFO - PROCESS | 28403 | Values: 161.0 160.0 161.0 161.0 161.0 164.0 160.0 160.0 161.0 161.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.50 stddev:16.22 (21.5%) stddev-sans-first:16.88 14:43:53 INFO - PROCESS | 28403 | Values: 74.0 75.0 75.0 120.0 76.0 76.0 76.0 76.0 106.0 75.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:83.00 stddev:11.91 (14.3%) stddev-sans-first:12.19 14:43:53 INFO - PROCESS | 28403 | Values: 82.0 83.0 109.0 84.0 84.0 83.0 107.0 83.0 85.0 108.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 14:43:53 INFO - PROCESS | 28403 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.99 (0.7%) stddev-sans-first:1.05 14:43:53 INFO - PROCESS | 28403 | Values: 147.0 147.0 146.0 146.0 146.0 148.0 148.0 147.0 147.0 149.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 14:43:53 INFO - PROCESS | 28403 | Values: 96.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 96.0 98.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:53.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 14:43:53 INFO - PROCESS | 28403 | Values: 52.0 51.0 51.0 53.0 53.0 52.0 53.0 54.0 52.0 53.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:68.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 14:43:53 INFO - PROCESS | 28403 | Values: 67.0 67.0 68.0 67.0 68.0 68.0 68.0 68.0 67.0 67.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:81.50 stddev:2.46 (3.0%) stddev-sans-first:2.26 14:43:53 INFO - PROCESS | 28403 | Values: 86.0 80.0 81.0 81.0 81.0 81.0 81.0 82.0 86.0 86.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:133.00 stddev:13.61 (10.2%) stddev-sans-first:14.04 14:43:53 INFO - PROCESS | 28403 | Values: 126.0 126.0 136.0 141.0 132.0 125.0 134.0 130.0 171.0 129.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:166.00 stddev:3.62 (2.2%) stddev-sans-first:3.75 14:43:53 INFO - PROCESS | 28403 | Values: 168.0 174.0 166.0 167.0 166.0 166.0 162.0 162.0 163.0 163.0 14:43:53 INFO - PROCESS | 28403 | 14:43:53 INFO - PROCESS | 28403 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:1.69 (2.7%) stddev-sans-first:1.73 14:43:53 INFO - PROCESS | 28403 | Values: 63.0 61.0 61.0 62.0 62.0 61.0 61.0 61.0 66.0 60.0 14:43:53 INFO - PROCESS | 28403 | -------- Summary: end -------- 14:43:53 INFO - PROCESS | 28403 | 14:43:54 INFO - TEST-INFO | 28403: exit 0 14:43:54 INFO - TEST-OK | kraken | took 62197ms 14:43:54 INFO - SUITE-END | took 1178s 14:43:54 INFO - Completed test suite (00:19:38) 14:43:54 INFO - javascript benchmark 14:43:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3154.763392745544, 3170.984145079274, 3203.8718451261952, 3206.1183174626976, 3208.3262514871876, 5265.526102650761, 5332.786688532459, 5336.546393556552, 5363.517283444489, 5392.2990011298525, 5390.649607775492, 5407.675539467635, 5410.188471729242, 5423.755930983105, 5423.755930983105, 4834.057358815015, 4857.562819346267, 4882.0, 4901.656884018103, 4938.0, 4844.709317440947, 4938.333325001133, 4977.925331120036, 4996.825111121111, 5036.899262014757, 4998.075356059123, 5007.37407824022, 5007.424146223177, 5014.348134742479, 5054.772535235905, 368.1999160496038, 4840.588549973257, 4850.442199147073, 4931.90136197274, 5005.2492126181, 4842.0, 4851.757412129415, 4896.534829191221, 4919.7540122993705, 4932.334134891779, 5381.434949330303, 5387.973060134714, 5389.67661940281, 5392.865178370533, 5432.972835135839, 5575.972120139373, 5615.550755939515, 5673.886522269573, 5724.341700704389, 5795.130730390433, 5724.398938111522, 5792.218050563204, 5798.797042103506, 5866.384029676865, 5869.7652093916195, 5399.028174928511, 5402.648827826218, 5406.729663516809, 5410.648307860016, 5411.56707463402, 5747.023006088975, 5772.480476757112, 5911.201987731687, 5933.732982015819, 5954.225950626477, 5648.350439699487, 5683.175939488751, 5706.029974904276, 5728.083506638918, 5762.423757624288, 5809.564282678816, 5809.941900581025, 5810.738516766755, 5819.127130930395, 5822.0, 5543.861403464865, 5757.050086735641, 5802.0, 5804.535637149018, 5811.070228763378, 5612.186232996192, 5698.116791897262, 5704.429557044263, 5776.1624564437925, 5860.208871802337, 4365.105153443536, 4370.890727731832, 4373.234683930299, 4381.539938306496, 4409.030013397048, 3806.5241844769403, 3808.314503389361, 3881.146147847469, 3882.7476214045996, 3924.843006279774, 5456.181572764117, 5506.091494903296, 5519.006578815852, 5558.333000040021, 5602.215689803431, 5815.709214539256, 5826.41735826414, 5835.67903765297, 5836.7957121501395, 5838.649681019152, 5519.144532597454, 5527.032769265363, 5527.419620939824, 5536.557075433956, 5539.584531160179, 4637.907241855144, 4690.155771961012, 4770.499097594772, 4815.3258543803895, 4824.0, 6072.240969878765, 6107.144999700045, 6108.267007959073, 6114.2662880453745, 6142.877142457126, 5786.739596718074, 5829.795957141479, 5847.415258474289, 5848.970755146197, 5854.209681692917, 5921.940780592139, 5924.763009479572, 5935.732892019956, 5978.611390259619, 6096.329403765671, 5702.45826646468, 5716.885662286814, 5723.914141287801, 5765.250517432707, 5768.163616275533, 5782.421757824352, 5800.332961709289, 5820.214271073521, 5827.5629327801435, 5837.09525023622, 5498.230247765236, 5543.833684989457, 5636.1827535008015, 5651.915221271602, 5655.547556195576, 5662.2639056922335, 5691.231683722644, 5736.942630573641, 5778.537716982547, 5786.0, 5656.23640808502, 5698.544116470755, 5727.341355744143, 5730.111832665943, 5736.483716465538, 5494.203340515683, 5555.2778138841695, 5762.366139724711, 5768.394318596655, 5770.422957704364, 5512.724363781875, 5528.585356098229, 5543.528800051879, 5625.971870140786, 5669.886602268014], "value": 5299.214120608987, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.2943565063729, 588.3204898342414, 597.3250227243215, 600.4205941266678, 613.6318209074559, 34502.65497345029, 44919.326210106876, 45238.0, 45425.54574454254, 45546.54453455468, 46629.30056049154, 46952.0, 47008.764956175255, 47010.059798804, 47012.0, 8676.913230867705, 8943.10568943107, 9055.32085093619, 9261.610758386229, 9267.026962168977, 10830.675079747582, 11041.503132359023, 11053.557857685722, 11414.600488982887, 11438.599649012287, 11127.221094523387, 11191.328520288767, 11191.440427978609, 11201.159913006557, 11217.0, 11027.503762330674, 11040.227184097117, 11101.722456938642, 11111.277766945163, 11112.0, 10875.129989600813, 10952.34285942838, 10965.50655220509, 10966.616168434146, 10974.0, 43994.78002609999, 44320.89197770049, 44455.55544444579, 44617.10765784699, 44656.3301550477, 4215.725977811432, 4255.468066491689, 4319.179355922396, 4385.254506733831, 4396.076823866992, 33504.32991340184, 33718.662813372044, 33911.15222119442, 34098.48852267218, 35496.29007419837, 4314.180305741899, 4320.891977700551, 4350.825966961318, 4373.628241599468, 4509.075639493896, 229327.0, 229435.0, 229503.0, 229693.85153074295, 229834.99999999834, 61522.0, 61566.0, 61985.690071550256, 61994.38005619976, 62169.0, 62455.37544624496, 62564.37435625677, 62578.687106564175, 62772.372276276656, 62871.0, 15956.521304360887, 16512.66974660524, 16540.586485337964, 16541.58646033859, 16551.08969006692, 10542.42016689074, 10686.412247326472, 10690.679279621623, 10855.0, 11379.0, 17143.228554715068, 17566.91216543909, 17801.7329740054, 18076.457706268833, 18079.096045197686, 6279.717412716439, 6449.709763060673, 6511.827870983174, 6517.152770139946, 6536.509761767887, 938.6761567259284, 947.8388673925449, 950.3110245072268, 952.7951490429591, 961.7691753978995, 30346.69653303483, 32062.0, 32602.0, 32621.0, 33202.0, 1032.9380237185792, 1099.2580008494394, 1105.0717397386234, 1106.8893110688866, 1110.9611163609236, 17098.230579623945, 17306.0, 17312.826871731122, 17322.913385433243, 17344.47966561005, 165956.0, 166243.0, 166692.0, 166923.0, 167005.0, 62221.68889155616, 62906.0, 63027.36972630215, 63077.684611576646, 63088.0, 1408.8802451791507, 1437.2669938331317, 1452.6150570098932, 1460.0801495058258, 1463.5316698656327, 29580.55629165521, 30435.695643043287, 31267.84366078155, 31704.0, 31858.68141318557, 53419.465805343, 53819.0, 54164.45835541752, 54183.729081354344, 54214.728926355114, 375750.0, 376810.0, 376996.0, 377801.0, 378017.0, 36621.26757464783, 36828.631713682524, 37647.24705505929, 41014.589854101076, 42813.35779963365, 47553.0, 48722.75638621784, 53250.46749532455, 53567.785932844155, 56144.71927640335, 36483.0, 37065.0, 38063.80968095142, 41320.0, 42723.14553708971, 37358.6264137366, 37464.06339841526, 37475.81262093672, 37631.62368376391, 38100.237995240495, 38124.428133577465, 38433.615663844124, 39006.0, 39264.803675981435, 39392.80303598464, 31849.522257165696, 33837.0, 34761.304773904885, 34922.47616285809, 34959.0, 25675.614865776653, 25799.355016123995, 25812.870935645202, 25872.353191170372, 26196.0], "value": 19795.267065827687, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.96314350136726, 394.9545802232742, 395.3950455802622, 403.4996604210778, 420.81273833144263, 13802.723945521084, 18236.361727339547, 19422.70865937012, 19910.10404531796, 20018.799812001893, 16768.245428955695, 19891.801081989175, 20083.196672133134, 20988.37034888952, 21213.681794773092, 5801.303843538771, 6021.2473440819895, 6029.638221706689, 6032.69836508175, 6043.123747056674, 5811.186433899257, 5936.0, 5978.40215978403, 5988.101784732282, 6012.158297838306, 5917.142014407908, 5943.554233432488, 5950.107483877431, 5952.375000624937, 5956.076808094752, 5725.57058220633, 5934.050551911695, 5941.792037278696, 5962.105684147369, 5968.432998865101, 5310.230016647603, 5311.389190243094, 5316.867078323035, 5320.361556613231, 5320.388155362144, 24310.149144780025, 24334.634980475308, 24663.506729865483, 24841.62737558938, 25014.749852501423, 2863.8568071596337, 2866.097179388507, 2897.840618765967, 2905.8837646494117, 2940.529515277545, 11615.0, 14293.428262869473, 14833.777493337608, 15143.0, 15164.09015459076, 1950.4343740315292, 1986.7814540400545, 2000.6198822223876, 2000.839932805372, 2051.415346626219, 101978.0, 103573.0, 104573.0, 105414.0, 105995.0, 11716.23844450108, 11778.234414763014, 11783.29300241971, 11917.821232681517, 12201.084918631139, 16859.24133413999, 17015.659686806193, 17041.829581704023, 17050.82949170517, 17074.743878841826, 7283.0, 7548.0, 8216.958915205385, 8538.359623028298, 8539.14608539141, 8045.235702608243, 8052.0, 8067.556284404297, 8255.711050113217, 8279.710210142615, 5064.1897296433135, 5074.441811400743, 5117.360329958755, 5182.97408512955, 5289.64610748221, 2053.1274208461346, 2068.2037415594805, 2080.246636435737, 2109.1141720477435, 2142.1110239250847, 447.87683387068165, 461.8591329644423, 461.89145550795536, 841.3984001439001, 874.7098030405101, 11448.599299024661, 17665.29338826458, 20260.392188234375, 20851.8957405212, 20904.686429703568, 924.2790623313826, 929.340168480373, 930.6417029443578, 931.7763736703142, 938.6855403439924, 10058.748531286776, 10269.435181064964, 10403.47982600867, 10469.476526173661, 10560.419176945341, 701752.0, 717733.0, 727133.0, 735421.0, 737887.0, 65705.34294657088, 83375.58312208521, 100318.49840750749, 101329.49335253277, 102155.4892225549, 986.1814693804125, 1027.6249169053392, 1048.6853943816977, 1054.7679510507676, 1057.2916146182076, 16871.240794164045, 17415.129243537518, 17594.384196553572, 17726.734098988785, 18304.908475457538, 43755.562444376425, 44328.335074973256, 45587.54412455833, 45828.541714582425, 45844.0, 55329.17006244829, 55778.72110639421, 58160.70919645544, 63305.68347158235, 64633.67683161554, 8842.602082906413, 9163.08369163105, 9205.355625106446, 9215.493147876663, 9623.422594644064, 9892.703218903445, 13639.249841258428, 13895.166290022631, 14211.857881421054, 14601.488947886774, 11681.123915706186, 11708.414579270831, 11908.0, 12082.758344832879, 12115.697107572028, 11583.826242606536, 11684.182107252751, 11912.940435297767, 12174.452149653463, 12457.563985260473, 11866.881331186923, 11929.164958452826, 11939.880601193878, 12027.15809893299, 12307.56923507673, 8751.518666473405, 8783.780405489913, 8952.507612081397, 8971.102889710977, 9015.5041472717, 10971.286866353661, 10999.725006874893, 11175.0, 11254.155938304497, 11535.307881527135], "value": 9574.490184808532, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.336958260035, 1390.909590876593, 1399.3353157250303, 1399.5031763723878, 1417.7589809732344, 7843.882341764871, 8323.209295116969, 8328.333733301337, 8345.540995245266, 8355.4986700798, 7385.113786345646, 7555.433342499321, 7856.607169641509, 8143.104258531557, 8202.753917382473, 2318.547883162784, 2347.4014126397747, 2347.6595893595418, 2350.576896158691, 2353.788159065684, 2303.585354636165, 2333.9649905251435, 2341.426350544119, 2344.038944032947, 2349.835511514195, 2005.2780998840406, 2063.772984971652, 2211.7345918489805, 2267.285804971437, 2312.976870231293, 2276.35123989663, 2281.178775640768, 2282.3609389371004, 2283.5318759654233, 2286.4512516995883, 2227.2984010036785, 2248.111995761665, 2256.5261295128043, 2259.435141214696, 2263.0, 8311.376646751458, 8481.151884811532, 8484.575771211477, 8485.448445851, 8488.108748581433, 4837.879053023667, 4942.0, 4943.1102401567705, 4944.283078953567, 4957.925631115536, 3111.502159654467, 3217.7264932480766, 3363.6972672459597, 3378.2736711606876, 3388.915277118067, 1309.43039777697, 1309.6136639691315, 1338.0834128621827, 1348.548236340827, 1439.5249567642652, 11260.268082574607, 11275.49260283281, 11314.490847911864, 11329.0, 11333.206675532716, 4292.463442069741, 4500.594946454835, 4535.501094879561, 4600.2869555218995, 4640.721556706609, 9475.763105922408, 9478.620855165716, 9491.050894910592, 9492.0, 9503.809923801486, 5077.593792496591, 5088.007838471536, 5090.618203634742, 5090.9236361454605, 5091.338126043591, 4162.229987452331, 4292.785360731951, 4670.229412147026, 4687.484376718558, 4767.856964291077, 3964.60353964607, 3965.5241371035086, 4058.5941405859176, 4061.634452899253, 4071.2875246832277, 1233.444949772606, 1253.7743206222967, 1259.8740125987329, 1261.4512686981193, 1264.5890085722176, 953.7853982853802, 962.6630679262203, 977.7360112769654, 984.3011461862015, 991.3655260633201, 5746.712664366849, 5747.195392645033, 5901.58688891782, 5904.1734157218025, 5904.439078287556, 622.0327390907182, 625.8247690646626, 631.4222486424961, 631.8041407163794, 642.331974746268, 3964.801759911993, 4003.039270575041, 4017.859374921922, 4042.3330150524835, 4045.554988951213, 9533.237341012702, 9931.106200441995, 9931.304808663468, 9943.900560994442, 9963.402195868272, 9817.410955342702, 9828.901710982798, 9838.704838854703, 9841.261905356983, 9853.556589953327, 7727.536347819148, 7783.727569535039, 7788.844223115506, 7796.688132474751, 7803.102643196105, 5824.15549745293, 5831.979403604268, 5851.444112809277, 6038.064100064497, 6140.17107690456, 8981.326400520118, 9002.684906028258, 9043.864342034743, 9067.546622668971, 9070.229030532479, 9557.808843823224, 9563.187129094105, 9569.282303827102, 9597.952010239904, 9641.614335426504, 6762.188537375547, 6925.792226233221, 6930.930690693029, 7300.12398512182, 7438.59087750179, 7483.812904677427, 7690.0771907369235, 8103.351731861319, 8145.8370832584205, 8232.50604963704, 7297.890531642136, 7363.8159046024275, 7465.962670186615, 7484.101907771103, 7524.699012039458, 7183.640817959185, 7298.37963773064, 7348.742794002184, 7367.300106489874, 7487.850242995219, 6310.211223597051, 6416.197975253093, 6416.967915160394, 6420.839479013062, 6432.807015789534, 4956.405231372259, 5014.17266151081, 5116.053530096944, 5160.071187186342, 5163.483651634957, 5066.873328166678, 5105.412877519133, 5147.253648221062, 5156.226566014978, 5166.070107380708], "value": 4413.663547000891, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.08370786797093, 397.8567715622376, 398.55959165122533, 403.4291477559254, 406.97965101744944, 5175.24958880962, 5245.842624721255, 5256.316678831751, 5261.579073674106, 5266.605004624659, 4991.725455099968, 5037.546620804127, 5103.642745007851, 5123.180291153416, 5225.86935326617, 4153.584641535853, 4164.27125253081, 4176.45706058212, 4180.79096045198, 4186.0, 4170.624643782059, 4184.958150418487, 4214.957850421502, 4223.303154979425, 4228.661707063443, 3876.8449262029485, 4002.3796311571755, 4156.2726522858375, 4173.749575025508, 4174.20690068886, 4134.772587507686, 4146.232946904832, 4155.877912963498, 4158.209940111399, 4176.227397931392, 3754.6057663945166, 3755.887323380303, 3756.586775454698, 3771.0, 3782.092297848524, 4891.559759621616, 5060.038592667418, 5108.106081435734, 5114.462981386938, 5120.743962801845, 4657.580817726387, 4661.3241080043545, 4671.906561868744, 4681.297805329194, 4688.601468875149, 5396.83809485716, 5407.729183641841, 5415.214793854909, 5454.236406903036, 5485.4640700603895, 3798.4302354646748, 3989.68082553395, 3995.4805875236325, 4002.779847108408, 4004.199160167978, 5643.68959707216, 5678.914816277759, 5688.402717714622, 5731.914021289643, 5765.625234359795, 4066.552679205285, 4069.4709687740406, 4091.3044782387065, 4093.89765255871, 4109.198706252252, 4056.499095334745, 4134.4082528226845, 4161.2301724181125, 4167.874963751093, 4183.39340795589, 4647.930281045787, 4661.324108004321, 4662.137504561667, 4683.414573178353, 4699.78850951708, 5415.972920135374, 5417.835165439371, 5434.130539113723, 5439.673619582838, 5440.959640134949, 1373.5124030969062, 1374.924379159156, 1395.958121256364, 1396.4414234306155, 1411.2097225553723, 950.3442624589012, 956.383132879289, 962.0139357158828, 963.8650588917557, 973.2116985241977, 5087.872803179876, 5107.131787596118, 5109.412417572027, 5218.321618189611, 5245.6852588844795, 5331.280277162611, 5414.512693857571, 5458.962797068544, 5481.5340696041285, 5548.72256387179, 5054.595632349403, 5103.515166059218, 5150.609335479475, 5171.870703232374, 5176.938727560841, 2846.7864910131825, 2866.254773758838, 2889.1765846734, 2894.292367503141, 2900.0, 5474.124140137638, 5647.29408823897, 5653.830385088454, 5669.319681638147, 5687.630304030225, 5453.372862120827, 5479.205515200274, 5522.6134170607675, 5524.530414914697, 5554.472714914704, 5593.356763972114, 5672.7730890763905, 5718.599698021182, 5721.342045664802, 5771.2397718695365, 5201.791928322825, 5355.002397315032, 5383.703896285742, 5403.189521571796, 5409.864753381197, 5305.840824775263, 5309.044372013074, 5315.654482458628, 5331.760070796746, 5366.838994830161, 5501.587380946525, 5671.1077350550695, 5703.173039909107, 5721.685307308138, 5734.512566431733, 3891.9416208756325, 3937.5668676444993, 3983.48214732083, 4020.19091408873, 4028.8187031584234, 5364.854058376524, 5457.590680699042, 5505.752241149078, 5511.917321240265, 5521.779128834802, 5357.705326207096, 5408.621396502364, 5413.621046526705, 5416.241726158262, 5427.918581221364, 5411.188321751769, 5412.891742165056, 5414.972925135349, 5432.918506222489, 5433.5924805640525, 5194.857131431079, 5359.490848369399, 5389.191621256686, 5409.945900540944, 5442.591805614674, 5413.83758487246, 5454.2091396746455, 5488.506034456918, 5489.615726899079, 5497.87293604818, 5226.386614943696, 5252.606054546, 5347.331583552056, 5357.464253574612, 5359.846119257607], "value": 4181.160019173722, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.2500187311748, 748.7267147491167, 785.2343964634482, 850.8425941200876, 884.0673089890167, 15027.248637568118, 16076.115813630246, 16110.516684499455, 16112.436064737736, 16171.272292746826, 15149.091054536735, 15357.462488812895, 15390.15354155521, 15406.768898466504, 16563.83436165641, 651.9739210431577, 661.3948237362831, 742.7437534050771, 746.7871656577877, 748.973785917493, 769.8537277917178, 770.2720928722332, 771.9421043421738, 772.528757457953, 772.64072206424, 796.77690246731, 797.9241972012678, 798.6246464161835, 798.7843282313772, 799.160881074869, 675.6554157379747, 684.685044879356, 779.0845756236399, 781.8866264391688, 796.0646240667222, 580.8373655376502, 581.2850194261083, 581.5609215042658, 581.7353104337516, 582.2721598002497, 16073.598160045976, 16540.007599544067, 16583.50249492517, 16592.502224933272, 16607.66784664312, 14310.284485775774, 15122.621934451618, 15445.768313475306, 15603.687926241411, 15664.216789160497, 12472.314022728746, 12571.143782706205, 12695.936520317431, 12770.936145319307, 12794.808077878839, 3690.76010059348, 3748.287825313209, 3754.699624030071, 3805.56236032857, 3808.923821523582, 76950.23049769487, 78460.0, 78696.60651696761, 79205.0, 80728.0, 11849.526018959317, 11898.583549575724, 11906.642800715992, 11931.897706596348, 12225.938870305592, 20578.588428231353, 21235.893820530797, 21630.675539866916, 22612.773872261067, 22747.772522274896, 7936.0, 8011.599420028975, 8042.0, 8119.4722343047515, 8196.098429172785, 4532.818687252539, 4680.67235293533, 4706.647001474903, 4759.0, 4768.7615619218905, 3626.9818650906577, 3673.9265214695556, 3688.5942546319884, 3708.4808126862263, 3717.237966216919, 594.4382558482279, 602.6775675013846, 603.4749767702127, 612.8222815383579, 619.9938000620026, 680.3332733920786, 683.7435961514432, 704.2218348724688, 707.3987110955646, 721.4372789224414, 9155.633774649074, 9652.75868103289, 9758.609655613696, 9776.951115244521, 9838.212942964547, 739.988900166498, 741.2402287655196, 762.4167511853436, 770.8976164085411, 774.6901239504131, 4553.931691024637, 4581.9541804582195, 4618.907621847611, 4638.397008388888, 4669.976650116796, 67006.0, 82176.0, 84847.0, 85924.57037714896, 85987.0, 57527.71236143876, 60784.69607652022, 63056.36943630505, 66681.6665916677, 67674.0, 59312.0, 59861.10208346878, 60327.69836150879, 60795.0, 62187.0, 9900.207983361457, 10257.179425645782, 10423.739406514898, 10493.9475302623, 10537.894621053998, 41120.38319425151, 41977.790111049246, 42100.36849447199, 42952.35571466368, 43084.569154308054, 50115.24827127523, 50432.0, 50896.23655645242, 53757.73121134526, 56273.0, 13086.869131308566, 13707.520236791665, 13740.65648358799, 13952.860471395563, 14081.929590351983, 15175.16536590498, 15603.0, 15752.369905204121, 15821.841781582038, 16054.518364449084, 14691.265436728334, 14701.41194352214, 14910.701785964004, 15454.227288635298, 15886.52340429789, 12777.425015874494, 13220.206787592775, 13772.793408099087, 13773.380197890918, 13880.305984700926, 14109.788353174505, 14527.927360363554, 15132.773008404663, 15312.081275123528, 15441.613959651098, 11601.883981160081, 12015.158938874194, 12137.878621214028, 12614.495420183457, 12614.864662180447, 10513.0, 10552.630657926935, 12000.699982500508, 12009.819852702041, 12253.448594813077], "value": 7299.650152829294, "unit": "score"}], "name": "dromaeo_css", "value": 7165.10094229148, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 111.0, 112.0, 110.0, 111.0, 110.0, 111.0, 113.0, 110.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 119.0, 123.0, 159.0, 123.0, 119.0, 118.0, 117.0, 137.0, 121.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 161.0, 164.0, 160.0, 160.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 120.0, 76.0, 76.0, 76.0, 76.0, 106.0, 75.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 109.0, 84.0, 84.0, 83.0, 107.0, 83.0, 85.0, 108.0], "value": 90.8, "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": [147.0, 147.0, 146.0, 146.0, 146.0, 148.0, 148.0, 147.0, 147.0, 149.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 98.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 53.0, 53.0, 52.0, 53.0, 54.0, 52.0, 53.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0], "value": 67.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 81.0, 81.0, 81.0, 81.0, 81.0, 82.0, 86.0, 86.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 126.0, 136.0, 141.0, 132.0, 125.0, 134.0, 130.0, 171.0, 129.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 174.0, 166.0, 167.0, 166.0, 166.0, 162.0, 162.0, 163.0, 163.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 62.0, 62.0, 61.0, 61.0, 61.0, 66.0, 60.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1499.8, "alertThreshold": 2.0}]} 14:43:54 INFO - Return code: 0 14:43:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:43:54 INFO - # TBPL SUCCESS # 14:43:54 INFO - Running post-action listener: _resource_record_post_action 14:43:54 INFO - [mozharness: 2017-01-04 22:43:54.428674Z] Finished run-tests step (success) 14:43:54 INFO - Running post-run listener: _resource_record_post_run 14:43:54 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 398647296; Read time: 16; Write time: 1376656 14:43:54 INFO - TinderboxPrint: CPU usage
13.2% 14:43:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 14:43:54 INFO - TinderboxPrint: I/O write bytes / time
398,647,296 / 1,376,656 14:43:54 INFO - TinderboxPrint: CPU idle
8,285.4 (86.8%) 14:43:54 INFO - TinderboxPrint: CPU user
1,198.9 (12.6%) 14:43:54 INFO - TinderboxPrint: Swap in / out
0 / 0 14:43:54 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 105697280; Read time: 0; Write time: 441896 14:43:54 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 292950016; Read time: 0; Write time: 934760 14:43:55 INFO - Running post-run listener: _upload_blobber_files 14:43:55 INFO - Blob upload gear active. 14:43:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:43:55 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 14:43:55 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'] 14:43:55 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 14:43:55 INFO - (blobuploader) - INFO - Open directory for files ... 14:43:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:43:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:43:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:56 INFO - (blobuploader) - INFO - Done attempting. 14:43:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:43:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:43:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:59 INFO - (blobuploader) - INFO - Done attempting. 14:43:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:43:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:44:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:44:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:44:00 INFO - (blobuploader) - INFO - Done attempting. 14:44:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:44:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:44:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:44:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:44:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:44:01 INFO - (blobuploader) - INFO - Done attempting. 14:44:01 INFO - (blobuploader) - INFO - Iteration through files over. 14:44:01 INFO - Return code: 0 14:44:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:44:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:44:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/42a775853048c5268b015d66565f52ae71ac3e3b541e68eaa90f45963db32e733569158ef75e8b7a8a157a3a0d8aaf7c938e27e97675b7304d069861cffbef33", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9778e31afd5416ee0e934009cff82c8d36b9a9602f072b5c9003beb6625274496993e66a01b40560548c6151bb95d637c02d336f670c1d9af68f97304789a5d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/18a4958fae586331a8f441ab2ab9238be227154eac55046129bbc7035657eed425c4eaec83e888cfdbe399649a55dc35dea87eead3a31a502f13d998f456f5e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c4bf8facfab5589453541f020ca41a0c30b0b0084db1797b780581ed33caa218efe2d6dedd3ea548541b39f9215a6548cc542f063026412309f9abc323b4a085"} 14:44:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:44:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:44:01 INFO - Contents: 14:44:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/42a775853048c5268b015d66565f52ae71ac3e3b541e68eaa90f45963db32e733569158ef75e8b7a8a157a3a0d8aaf7c938e27e97675b7304d069861cffbef33", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9778e31afd5416ee0e934009cff82c8d36b9a9602f072b5c9003beb6625274496993e66a01b40560548c6151bb95d637c02d336f670c1d9af68f97304789a5d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/18a4958fae586331a8f441ab2ab9238be227154eac55046129bbc7035657eed425c4eaec83e888cfdbe399649a55dc35dea87eead3a31a502f13d998f456f5e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c4bf8facfab5589453541f020ca41a0c30b0b0084db1797b780581ed33caa218efe2d6dedd3ea548541b39f9215a6548cc542f063026412309f9abc323b4a085"} 14:44:01 INFO - Running post-run listener: copy_logs_to_upload_dir 14:44:01 INFO - Copying logs to upload dir... 14:44:01 INFO - mkdir: /builds/slave/test/build/upload/logs 14:44:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.466597 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-01-04 14:44:00.965958) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 14:44:00.969527) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861 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/42a775853048c5268b015d66565f52ae71ac3e3b541e68eaa90f45963db32e733569158ef75e8b7a8a157a3a0d8aaf7c938e27e97675b7304d069861cffbef33", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9778e31afd5416ee0e934009cff82c8d36b9a9602f072b5c9003beb6625274496993e66a01b40560548c6151bb95d637c02d336f670c1d9af68f97304789a5d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/18a4958fae586331a8f441ab2ab9238be227154eac55046129bbc7035657eed425c4eaec83e888cfdbe399649a55dc35dea87eead3a31a502f13d998f456f5e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c4bf8facfab5589453541f020ca41a0c30b0b0084db1797b780581ed33caa218efe2d6dedd3ea548541b39f9215a6548cc542f063026412309f9abc323b4a085"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011343 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483562110/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/42a775853048c5268b015d66565f52ae71ac3e3b541e68eaa90f45963db32e733569158ef75e8b7a8a157a3a0d8aaf7c938e27e97675b7304d069861cffbef33", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9778e31afd5416ee0e934009cff82c8d36b9a9602f072b5c9003beb6625274496993e66a01b40560548c6151bb95d637c02d336f670c1d9af68f97304789a5d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/18a4958fae586331a8f441ab2ab9238be227154eac55046129bbc7035657eed425c4eaec83e888cfdbe399649a55dc35dea87eead3a31a502f13d998f456f5e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c4bf8facfab5589453541f020ca41a0c30b0b0084db1797b780581ed33caa218efe2d6dedd3ea548541b39f9215a6548cc542f063026412309f9abc323b4a085"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 14:44:00.995357) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 14:44:00.995670) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1483568602.294333-1294118861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004374 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 14:44:01.079970) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 14:44:01.080298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 14:44:01.080587) ========= ========= Total master_lag: 0.17 =========