builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1481288944.1 results: success (0) buildid: 20161209050811 builduid: dfdec7de79fc4faaa1b65173cf765ef4 revision: 005adbf78cd24afd81a24902ad8802d1b1d0c5aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:04.103454) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:04.104040) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:04.104497) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:04.199646) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:04.199915) ========= 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=db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-09 05:09:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.0M=0s 2016-12-09 05:09:04 (70.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114247 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:04.331255) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:04.331554) ========= 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=db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017387 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:04.380344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 05:09:04.380653) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 005adbf78cd24afd81a24902ad8802d1b1d0c5aa --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 005adbf78cd24afd81a24902ad8802d1b1d0c5aa --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=db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-09 05:09:04,679 truncating revision to first 12 chars 2016-12-09 05:09:04,680 Setting DEBUG logging. 2016-12-09 05:09:04,680 attempt 1/10 2016-12-09 05:09:04,680 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/005adbf78cd2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-09 05:09:05,870 unpacking tar archive at: mozilla-inbound-005adbf78cd2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.563591 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 05:09:05.957654) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:05.957988) ========= script_repo_revision: 005adbf78cd24afd81a24902ad8802d1b1d0c5aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:05.958382) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:05.958656) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 05:09:05.967340) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-12-09 05:09:05.967683) ========= /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=db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:09:06 INFO - MultiFileLogger online at 20161209 05:09:06 in /builds/slave/test 05:09:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 05:09:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:09:06 INFO - {'append_to_log': False, 05:09:06 INFO - 'base_work_dir': '/builds/slave/test', 05:09:06 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 05:09:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:09:06 INFO - 'branch': 'Mozilla-Inbound', 05:09:06 INFO - 'buildbot_json_path': 'buildprops.json', 05:09:06 INFO - 'config_files': ('talos/linux_config.py',), 05:09:06 INFO - 'default_actions': ('clobber', 05:09:06 INFO - 'read-buildbot-config', 05:09:06 INFO - 'download-and-extract', 05:09:06 INFO - 'populate-webroot', 05:09:06 INFO - 'create-virtualenv', 05:09:06 INFO - 'install', 05:09:06 INFO - 'run-tests'), 05:09:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:09:06 INFO - 'download_minidump_stackwalk': True, 05:09:06 INFO - 'download_symbols': 'ondemand', 05:09:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:09:06 INFO - 'tooltool.py': '/tools/tooltool.py', 05:09:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:09:06 INFO - '/tools/misc-python/virtualenv.py')}, 05:09:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:09:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:09:06 INFO - 'installer_path': 'installer.exe', 05:09:06 INFO - 'log_level': 'info', 05:09:06 INFO - 'log_name': 'talos', 05:09:06 INFO - 'log_to_console': True, 05:09:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:09:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:09:06 INFO - 'opt_config_files': (), 05:09:06 INFO - 'pip_index': False, 05:09:06 INFO - 'sps_profile': False, 05:09:06 INFO - 'sps_profile_interval': 0, 05:09:06 INFO - 'suite': 'dromaeojs', 05:09:06 INFO - 'system_bits': '32', 05:09:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:09:06 INFO - 'title': 'talos-linux64-ix-031', 05:09:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:09:06 INFO - 'use_talos_json': True, 05:09:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:09:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:09:06 INFO - 'work_dir': 'build'} 05:09:06 INFO - [mozharness: 2016-12-09 13:09:06.363992Z] Running clobber step. 05:09:06 INFO - Running pre-action listener: _resource_record_pre_action 05:09:06 INFO - Running main action method: clobber 05:09:06 INFO - rmtree: /builds/slave/test/build 05:09:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:09:06 INFO - Running post-action listener: _resource_record_post_action 05:09:06 INFO - [mozharness: 2016-12-09 13:09:06.828668Z] Finished clobber step (success) 05:09:06 INFO - [mozharness: 2016-12-09 13:09:06.828767Z] Running read-buildbot-config step. 05:09:06 INFO - Running pre-action listener: _resource_record_pre_action 05:09:06 INFO - Running main action method: read_buildbot_config 05:09:06 INFO - Using buildbot properties: 05:09:06 INFO - { 05:09:06 INFO - "project": "", 05:09:06 INFO - "product": "firefox", 05:09:06 INFO - "script_repo_revision": "production", 05:09:06 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 05:09:06 INFO - "repository": "", 05:09:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 05:09:06 INFO - "buildid": "20161209050811", 05:09:06 INFO - "stage_platform": "linux64-pgo", 05:09:06 INFO - "basedir": "/builds/slave/test", 05:09:06 INFO - "buildnumber": 3018, 05:09:06 INFO - "platform": "ubuntu64_hw", 05:09:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:09:06 INFO - "slavebuilddir": "test", 05:09:06 INFO - "branch": "mozilla-inbound", 05:09:06 INFO - "revision": "005adbf78cd24afd81a24902ad8802d1b1d0c5aa", 05:09:06 INFO - "slavename": "talos-linux64-ix-031", 05:09:06 INFO - "builduid": "dfdec7de79fc4faaa1b65173cf765ef4", 05:09:06 INFO - "repo_path": "integration/mozilla-inbound" 05:09:06 INFO - } 05:09:06 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 05:09:06 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 05:09:06 INFO - Running post-action listener: _resource_record_post_action 05:09:06 INFO - [mozharness: 2016-12-09 13:09:06.831098Z] Finished read-buildbot-config step (success) 05:09:06 INFO - [mozharness: 2016-12-09 13:09:06.831186Z] Running download-and-extract step. 05:09:06 INFO - Running pre-action listener: _resource_record_pre_action 05:09:06 INFO - Running main action method: download_and_extract 05:09:06 INFO - mkdir: /builds/slave/test/build/tests 05:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:06 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:09:06 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:09:06 INFO - Downloaded 1361 bytes. 05:09:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:09:06 INFO - Using the following test package requirements: 05:09:06 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:09:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:09:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:09:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:06 INFO - u'jsshell-linux-x86_64.zip'], 05:09:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:09:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:09:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:09:06 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:09:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:09:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:09:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:09:06 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/1481283050/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:09:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:09:06 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:09:10 INFO - Expected file size: 21785306 05:09:10 INFO - Obtained file size: 21785306 05:09:14 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:09:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:09:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:09:14 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:09:15 INFO - Expected file size: 21785306 05:09:15 INFO - Obtained file size: 21785306 05:09:20 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/1481283050/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 05:09:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:09:20 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:09:23 INFO - Expected file size: 14409734 05:09:23 INFO - Obtained file size: 14409734 05:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:24 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:09:24 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:09:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:09:29 INFO - Downloaded 59859618 bytes. 05:09:29 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:09:29 INFO - mkdir: /builds/slave/test/properties 05:09:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:29 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:29 INFO - Contents: 05:09:29 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:09:29 INFO - Running post-action listener: _resource_record_post_action 05:09:29 INFO - Running post-action listener: set_extra_try_arguments 05:09:29 INFO - [mozharness: 2016-12-09 13:09:29.600789Z] Finished download-and-extract step (success) 05:09:29 INFO - [mozharness: 2016-12-09 13:09:29.601017Z] Running populate-webroot step. 05:09:29 INFO - Running pre-action listener: _resource_record_pre_action 05:09:29 INFO - Running main action method: populate_webroot 05:09:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:29 INFO - '--tppagecycles', 05:09:29 INFO - '7'], 05:09:29 INFO - 'tests': ['tsvgm']}}, 05:09:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:09:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:09:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:09:29 INFO - 'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:09:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:09:29 INFO - 'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['damp', 'tps']}, 05:09:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:09:29 INFO - 'tests': ['damp', 'tps']}, 05:09:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['dromaeo_dom']}, 05:09:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['basic_compositor_video']}, 05:09:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:09:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['a11yr', 05:09:29 INFO - 'ts_paint', 05:09:29 INFO - 'tpaint', 05:09:29 INFO - 'sessionrestore', 05:09:29 INFO - 'sessionrestore_no_auto_restore', 05:09:29 INFO - 'tabpaint']}, 05:09:29 INFO - 'other-e10s': {'tests': ['a11yr', 05:09:29 INFO - 'ts_paint', 05:09:29 INFO - 'tpaint', 05:09:29 INFO - 'sessionrestore', 05:09:29 INFO - 'sessionrestore_no_auto_restore', 05:09:29 INFO - 'tabpaint']}, 05:09:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:09:29 INFO - 'ts_paint', 05:09:29 INFO - 'tpaint', 05:09:29 INFO - 'sessionrestore', 05:09:29 INFO - 'sessionrestore_no_auto_restore', 05:09:29 INFO - 'tabpaint']}, 05:09:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:09:29 INFO - 'ts_paint', 05:09:29 INFO - 'tpaint', 05:09:29 INFO - 'sessionrestore', 05:09:29 INFO - 'sessionrestore_no_auto_restore', 05:09:29 INFO - 'tabpaint']}, 05:09:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['a11yr', 05:09:29 INFO - 'ts_paint', 05:09:29 INFO - 'tpaint', 05:09:29 INFO - 'sessionrestore', 05:09:29 INFO - 'sessionrestore_no_auto_restore', 05:09:29 INFO - 'tabpaint']}, 05:09:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['a11yr', 05:09:29 INFO - 'ts_paint', 05:09:29 INFO - 'tpaint', 05:09:29 INFO - 'sessionrestore', 05:09:29 INFO - 'sessionrestore_no_auto_restore', 05:09:29 INFO - 'tabpaint']}, 05:09:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['tsvgx', 05:09:29 INFO - 'tsvgr_opacity', 05:09:29 INFO - 'tart', 05:09:29 INFO - 'tscrollx', 05:09:29 INFO - 'cart', 05:09:29 INFO - 'tsvg_static']}, 05:09:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:09:29 INFO - 'tsvgr_opacity', 05:09:29 INFO - 'tart', 05:09:29 INFO - 'tscrollx', 05:09:29 INFO - 'cart', 05:09:29 INFO - 'tsvg_static']}, 05:09:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:09:29 INFO - 'talos_options': ['--disable-e10s'], 05:09:29 INFO - 'tests': ['tp5o']}, 05:09:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:09:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:09:29 INFO - 'talos_options': ['--disable-e10s', 05:09:29 INFO - '--xperf_path', 05:09:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:29 INFO - 'tests': ['tp5n']}, 05:09:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:09:29 INFO - 'talos_options': ['--xperf_path', 05:09:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:29 INFO - 'tests': ['tp5n']}}, 05:09:29 INFO - 'talos.zip': {'path': '', 05:09:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:29 INFO - Running post-action listener: _resource_record_post_action 05:09:29 INFO - [mozharness: 2016-12-09 13:09:29.623094Z] Finished populate-webroot step (success) 05:09:29 INFO - [mozharness: 2016-12-09 13:09:29.623181Z] Running create-virtualenv step. 05:09:29 INFO - Running pre-action listener: _resource_record_pre_action 05:09:29 INFO - Running main action method: create_virtualenv 05:09:29 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:09:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:29 INFO - Using real prefix '/usr' 05:09:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:09:32 INFO - Installing pip.................done. 05:09:32 INFO - Return code: 0 05:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:09:32 INFO - Reading from file tmpfile_stdout 05:09:32 INFO - Output received: 05:09:32 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:09:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:09:32 INFO - Using env: {'DISPLAY': ':0', 05:09:32 INFO - 'HOME': '/home/cltbld', 05:09:32 INFO - 'LANG': 'en_US.UTF-8', 05:09:32 INFO - 'LANGUAGE': 'en_US:en', 05:09:32 INFO - 'LOGNAME': 'cltbld', 05:09:32 INFO - 'MAIL': '/var/mail/cltbld', 05:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:32 INFO - 'MOZ_NO_REMOTE': '1', 05:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:32 INFO - 'NO_EM_RESTART': '1', 05:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:32 INFO - 'PWD': '/builds/slave/test', 05:09:32 INFO - 'SHELL': '/bin/bash', 05:09:32 INFO - 'SHLVL': '1', 05:09:32 INFO - 'TERM': 'linux', 05:09:32 INFO - 'TMOUT': '86400', 05:09:32 INFO - 'USER': 'cltbld', 05:09:32 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:32 INFO - '_': '/tools/buildbot/bin/python'} 05:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:09:32 INFO - Cleaning up... 05:09:32 INFO - Return code: 0 05:09:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:09:32 INFO - Using env: {'DISPLAY': ':0', 05:09:32 INFO - 'HOME': '/home/cltbld', 05:09:32 INFO - 'LANG': 'en_US.UTF-8', 05:09:32 INFO - 'LANGUAGE': 'en_US:en', 05:09:32 INFO - 'LOGNAME': 'cltbld', 05:09:32 INFO - 'MAIL': '/var/mail/cltbld', 05:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:32 INFO - 'MOZ_NO_REMOTE': '1', 05:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:32 INFO - 'NO_EM_RESTART': '1', 05:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:32 INFO - 'PWD': '/builds/slave/test', 05:09:32 INFO - 'SHELL': '/bin/bash', 05:09:32 INFO - 'SHLVL': '1', 05:09:32 INFO - 'TERM': 'linux', 05:09:32 INFO - 'TMOUT': '86400', 05:09:32 INFO - 'USER': 'cltbld', 05:09:32 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:32 INFO - '_': '/tools/buildbot/bin/python'} 05:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:32 INFO - Downloading/unpacking psutil>=3.1.1 05:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:34 INFO - Installing collected packages: psutil 05:09:34 INFO - Running setup.py install for psutil 05:09:34 INFO - building 'psutil._psutil_linux' extension 05:09:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:09:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:09:35 INFO - building 'psutil._psutil_posix' extension 05:09:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:09:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:09:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:35 INFO - Successfully installed psutil 05:09:35 INFO - Cleaning up... 05:09:35 INFO - Return code: 0 05:09:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:09:35 INFO - Using env: {'DISPLAY': ':0', 05:09:35 INFO - 'HOME': '/home/cltbld', 05:09:35 INFO - 'LANG': 'en_US.UTF-8', 05:09:35 INFO - 'LANGUAGE': 'en_US:en', 05:09:35 INFO - 'LOGNAME': 'cltbld', 05:09:35 INFO - 'MAIL': '/var/mail/cltbld', 05:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:35 INFO - 'MOZ_NO_REMOTE': '1', 05:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:35 INFO - 'NO_EM_RESTART': '1', 05:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:35 INFO - 'PWD': '/builds/slave/test', 05:09:35 INFO - 'SHELL': '/bin/bash', 05:09:35 INFO - 'SHLVL': '1', 05:09:35 INFO - 'TERM': 'linux', 05:09:35 INFO - 'TMOUT': '86400', 05:09:35 INFO - 'USER': 'cltbld', 05:09:35 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:35 INFO - '_': '/tools/buildbot/bin/python'} 05:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:09:37 INFO - Installing collected packages: mozsystemmonitor 05:09:37 INFO - Running setup.py install for mozsystemmonitor 05:09:37 INFO - Successfully installed mozsystemmonitor 05:09:37 INFO - Cleaning up... 05:09:37 INFO - Return code: 0 05:09:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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', '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 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:09: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 jsonschema==2.5.1 05:09:37 INFO - Using env: {'DISPLAY': ':0', 05:09:37 INFO - 'HOME': '/home/cltbld', 05:09:37 INFO - 'LANG': 'en_US.UTF-8', 05:09:37 INFO - 'LANGUAGE': 'en_US:en', 05:09:37 INFO - 'LOGNAME': 'cltbld', 05:09:37 INFO - 'MAIL': '/var/mail/cltbld', 05:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:37 INFO - 'MOZ_NO_REMOTE': '1', 05:09:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:37 INFO - 'NO_EM_RESTART': '1', 05:09:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:37 INFO - 'PWD': '/builds/slave/test', 05:09:37 INFO - 'SHELL': '/bin/bash', 05:09:37 INFO - 'SHLVL': '1', 05:09:37 INFO - 'TERM': 'linux', 05:09:37 INFO - 'TMOUT': '86400', 05:09:37 INFO - 'USER': 'cltbld', 05:09:37 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:37 INFO - '_': '/tools/buildbot/bin/python'} 05:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:38 INFO - Downloading/unpacking jsonschema==2.5.1 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:40 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:40 INFO - warning: no files found matching '*.txt' 05:09:40 INFO - no previously-included directories found matching 'build' 05:09:40 INFO - no previously-included directories found matching 'dist' 05:09:40 INFO - no previously-included directories found matching '.git*' 05:09:40 INFO - Installing collected packages: jsonschema, functools32 05:09:40 INFO - Running setup.py install for functools32 05:09:40 INFO - warning: no files found matching '*.txt' 05:09:40 INFO - no previously-included directories found matching 'build' 05:09:40 INFO - no previously-included directories found matching 'dist' 05:09:40 INFO - no previously-included directories found matching '.git*' 05:09:40 INFO - Successfully installed jsonschema functools32 05:09:40 INFO - Cleaning up... 05:09:40 INFO - Return code: 0 05:09:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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', '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 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:09: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 functools32==3.2.3-2 05:09:40 INFO - Using env: {'DISPLAY': ':0', 05:09:40 INFO - 'HOME': '/home/cltbld', 05:09:40 INFO - 'LANG': 'en_US.UTF-8', 05:09:40 INFO - 'LANGUAGE': 'en_US:en', 05:09:40 INFO - 'LOGNAME': 'cltbld', 05:09:40 INFO - 'MAIL': '/var/mail/cltbld', 05:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:40 INFO - 'MOZ_NO_REMOTE': '1', 05:09:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:40 INFO - 'NO_EM_RESTART': '1', 05:09:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:40 INFO - 'PWD': '/builds/slave/test', 05:09:40 INFO - 'SHELL': '/bin/bash', 05:09:40 INFO - 'SHLVL': '1', 05:09:40 INFO - 'TERM': 'linux', 05:09:40 INFO - 'TMOUT': '86400', 05:09:40 INFO - 'USER': 'cltbld', 05:09:40 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:40 INFO - '_': '/tools/buildbot/bin/python'} 05:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:09:40 INFO - Cleaning up... 05:09:40 INFO - Return code: 0 05:09:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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', '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 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:09: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 blobuploader==1.2.4 05:09:40 INFO - Using env: {'DISPLAY': ':0', 05:09:40 INFO - 'HOME': '/home/cltbld', 05:09:40 INFO - 'LANG': 'en_US.UTF-8', 05:09:40 INFO - 'LANGUAGE': 'en_US:en', 05:09:40 INFO - 'LOGNAME': 'cltbld', 05:09:40 INFO - 'MAIL': '/var/mail/cltbld', 05:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:40 INFO - 'MOZ_NO_REMOTE': '1', 05:09:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:40 INFO - 'NO_EM_RESTART': '1', 05:09:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:40 INFO - 'PWD': '/builds/slave/test', 05:09:40 INFO - 'SHELL': '/bin/bash', 05:09:40 INFO - 'SHLVL': '1', 05:09:40 INFO - 'TERM': 'linux', 05:09:40 INFO - 'TMOUT': '86400', 05:09:40 INFO - 'USER': 'cltbld', 05:09:40 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:40 INFO - '_': '/tools/buildbot/bin/python'} 05:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:40 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09: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 05:09: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 05:09: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 05:09: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 05:09:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:44 INFO - Downloading docopt-0.6.1.tar.gz 05:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:44 INFO - Running setup.py install for blobuploader 05:09:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:44 INFO - Running setup.py install for requests 05:09:44 INFO - Running setup.py install for docopt 05:09:44 INFO - Successfully installed blobuploader requests docopt 05:09:44 INFO - Cleaning up... 05:09:44 INFO - Return code: 0 05:09:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:44 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 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:44 INFO - Using env: {'DISPLAY': ':0', 05:09:44 INFO - 'HOME': '/home/cltbld', 05:09:44 INFO - 'LANG': 'en_US.UTF-8', 05:09:44 INFO - 'LANGUAGE': 'en_US:en', 05:09:44 INFO - 'LOGNAME': 'cltbld', 05:09:44 INFO - 'MAIL': '/var/mail/cltbld', 05:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:44 INFO - 'MOZ_NO_REMOTE': '1', 05:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:44 INFO - 'NO_EM_RESTART': '1', 05:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:44 INFO - 'PWD': '/builds/slave/test', 05:09:44 INFO - 'SHELL': '/bin/bash', 05:09:44 INFO - 'SHLVL': '1', 05:09:44 INFO - 'TERM': 'linux', 05:09:44 INFO - 'TMOUT': '86400', 05:09:44 INFO - 'USER': 'cltbld', 05:09:44 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:44 INFO - '_': '/tools/buildbot/bin/python'} 05:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:45 INFO - Running setup.py (path:/tmp/pip-nt2AjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:45 INFO - Running setup.py (path:/tmp/pip-02sn2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:45 INFO - Running setup.py (path:/tmp/pip-Q6d0oS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:45 INFO - Running setup.py (path:/tmp/pip-1hwoop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:45 INFO - Running setup.py (path:/tmp/pip-ExdSdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:45 INFO - Running setup.py (path:/tmp/pip-9g5sx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:45 INFO - Running setup.py (path:/tmp/pip-4Fdg3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:45 INFO - Running setup.py (path:/tmp/pip-BsAkSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:45 INFO - Running setup.py (path:/tmp/pip-PYhRv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:45 INFO - Running setup.py (path:/tmp/pip-A9sW7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:45 INFO - Running setup.py (path:/tmp/pip-cPpRlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:45 INFO - Running setup.py (path:/tmp/pip-Yxyf6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:45 INFO - Running setup.py (path:/tmp/pip-0idpoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:45 INFO - Running setup.py (path:/tmp/pip-RnMN50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:45 INFO - Running setup.py (path:/tmp/pip-yq0YPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:46 INFO - Running setup.py (path:/tmp/pip-mdPTSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:46 INFO - Running setup.py (path:/tmp/pip-_q3EEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:46 INFO - Running setup.py install for manifestparser 05:09:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:46 INFO - Running setup.py install for mozcrash 05:09:46 INFO - Running setup.py install for mozdebug 05:09:46 INFO - Running setup.py install for mozdevice 05:09:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:46 INFO - Running setup.py install for mozfile 05:09:46 INFO - Running setup.py install for mozhttpd 05:09:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:46 INFO - Running setup.py install for mozinfo 05:09:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:46 INFO - Running setup.py install for mozInstall 05:09:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:47 INFO - Running setup.py install for mozleak 05:09:47 INFO - Running setup.py install for mozlog 05:09:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:47 INFO - Running setup.py install for moznetwork 05:09:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:47 INFO - Running setup.py install for mozprocess 05:09:47 INFO - Running setup.py install for mozprofile 05:09:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:47 INFO - Running setup.py install for mozrunner 05:09:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:47 INFO - Running setup.py install for mozscreenshot 05:09:47 INFO - Running setup.py install for moztest 05:09:47 INFO - Running setup.py install for mozversion 05:09:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:47 INFO - Cleaning up... 05:09:47 INFO - Return code: 0 05:09:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:48 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 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:48 INFO - Using env: {'DISPLAY': ':0', 05:09:48 INFO - 'HOME': '/home/cltbld', 05:09:48 INFO - 'LANG': 'en_US.UTF-8', 05:09:48 INFO - 'LANGUAGE': 'en_US:en', 05:09:48 INFO - 'LOGNAME': 'cltbld', 05:09:48 INFO - 'MAIL': '/var/mail/cltbld', 05:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:48 INFO - 'MOZ_NO_REMOTE': '1', 05:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:48 INFO - 'NO_EM_RESTART': '1', 05:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:48 INFO - 'PWD': '/builds/slave/test', 05:09:48 INFO - 'SHELL': '/bin/bash', 05:09:48 INFO - 'SHLVL': '1', 05:09:48 INFO - 'TERM': 'linux', 05:09:48 INFO - 'TMOUT': '86400', 05:09:48 INFO - 'USER': 'cltbld', 05:09:48 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:48 INFO - '_': '/tools/buildbot/bin/python'} 05:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:48 INFO - Running setup.py (path:/tmp/pip-NK_9pD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:48 INFO - Running setup.py (path:/tmp/pip-mZIJa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:48 INFO - Running setup.py (path:/tmp/pip-01h8ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:48 INFO - Running setup.py (path:/tmp/pip-nPxWN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:48 INFO - Running setup.py (path:/tmp/pip-IeSI7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:48 INFO - Running setup.py (path:/tmp/pip-cDFvi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:48 INFO - Running setup.py (path:/tmp/pip-vuGRDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:48 INFO - Running setup.py (path:/tmp/pip-Fd_Bhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:48 INFO - Running setup.py (path:/tmp/pip-H8UNnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:48 INFO - Running setup.py (path:/tmp/pip-H_eDkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:48 INFO - Running setup.py (path:/tmp/pip-fJRcAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:49 INFO - Running setup.py (path:/tmp/pip-ABJJMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:49 INFO - Running setup.py (path:/tmp/pip-En687m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:49 INFO - Running setup.py (path:/tmp/pip-QsKwQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:49 INFO - Running setup.py (path:/tmp/pip-pfP_ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:49 INFO - Running setup.py (path:/tmp/pip-BFrtIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:49 INFO - Running setup.py (path:/tmp/pip-I2ApgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:51 INFO - Downloading blessings-1.6.tar.gz 05:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:51 INFO - Installing collected packages: blessings 05:09:51 INFO - Running setup.py install for blessings 05:09:51 INFO - Successfully installed blessings 05:09:51 INFO - Cleaning up... 05:09:51 INFO - Return code: 0 05:09:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:51 INFO - Reading from file tmpfile_stdout 05:09:51 INFO - Current package versions: 05:09:51 INFO - argparse == 1.2.1 05:09:51 INFO - blessings == 1.6 05:09:51 INFO - blobuploader == 1.2.4 05:09:51 INFO - docopt == 0.6.1 05:09:51 INFO - functools32 == 3.2.3-2 05:09:51 INFO - jsonschema == 2.5.1 05:09:51 INFO - manifestparser == 1.1 05:09:51 INFO - mozInstall == 1.12 05:09:51 INFO - mozcrash == 1.0 05:09:51 INFO - mozdebug == 0.1 05:09:51 INFO - mozdevice == 0.49 05:09:51 INFO - mozfile == 1.2 05:09:51 INFO - mozhttpd == 0.7 05:09:51 INFO - mozinfo == 0.9 05:09:51 INFO - mozleak == 0.1 05:09:51 INFO - mozlog == 3.4 05:09:51 INFO - moznetwork == 0.27 05:09:51 INFO - mozprocess == 0.23 05:09:51 INFO - mozprofile == 0.28 05:09:51 INFO - mozrunner == 6.13 05:09:51 INFO - mozscreenshot == 0.1 05:09:51 INFO - mozsystemmonitor == 0.3 05:09:51 INFO - moztest == 0.7 05:09:51 INFO - mozversion == 1.4 05:09:51 INFO - psutil == 3.1.1 05:09:51 INFO - requests == 1.2.3 05:09:51 INFO - wsgiref == 0.1.2 05:09:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:51 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 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:51 INFO - Using env: {'DISPLAY': ':0', 05:09:51 INFO - 'HOME': '/home/cltbld', 05:09:51 INFO - 'LANG': 'en_US.UTF-8', 05:09:51 INFO - 'LANGUAGE': 'en_US:en', 05:09:51 INFO - 'LOGNAME': 'cltbld', 05:09:51 INFO - 'MAIL': '/var/mail/cltbld', 05:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:51 INFO - 'MOZ_NO_REMOTE': '1', 05:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:51 INFO - 'NO_EM_RESTART': '1', 05:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:51 INFO - 'PWD': '/builds/slave/test', 05:09:51 INFO - 'SHELL': '/bin/bash', 05:09:51 INFO - 'SHLVL': '1', 05:09:51 INFO - 'TERM': 'linux', 05:09:51 INFO - 'TMOUT': '86400', 05:09:51 INFO - 'USER': 'cltbld', 05:09:51 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:51 INFO - '_': '/tools/buildbot/bin/python'} 05:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:09:52 INFO - Cleaning up... 05:09:52 INFO - Return code: 0 05:09:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:52 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 0x7fbdeacd5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, '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': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:09:52 INFO - Using env: {'DISPLAY': ':0', 05:09:52 INFO - 'HOME': '/home/cltbld', 05:09:52 INFO - 'LANG': 'en_US.UTF-8', 05:09:52 INFO - 'LANGUAGE': 'en_US:en', 05:09:52 INFO - 'LOGNAME': 'cltbld', 05:09:52 INFO - 'MAIL': '/var/mail/cltbld', 05:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:52 INFO - 'MOZ_NO_REMOTE': '1', 05:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:52 INFO - 'NO_EM_RESTART': '1', 05:09:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:52 INFO - 'PWD': '/builds/slave/test', 05:09:52 INFO - 'SHELL': '/bin/bash', 05:09:52 INFO - 'SHLVL': '1', 05:09:52 INFO - 'TERM': 'linux', 05:09:52 INFO - 'TMOUT': '86400', 05:09:52 INFO - 'USER': 'cltbld', 05:09:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:52 INFO - '_': '/tools/buildbot/bin/python'} 05:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:09:52 INFO - Cleaning up... 05:09:52 INFO - Return code: 0 05:09:52 INFO - Running post-action listener: _resource_record_post_action 05:09:52 INFO - Running post-action listener: _start_resource_monitoring 05:09:52 INFO - Starting resource monitoring. 05:09:52 INFO - [mozharness: 2016-12-09 13:09:52.407892Z] Finished create-virtualenv step (success) 05:09:52 INFO - [mozharness: 2016-12-09 13:09:52.408363Z] Running install step. 05:09:52 INFO - Running pre-action listener: _resource_record_pre_action 05:09:52 INFO - Running main action method: install 05:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:52 INFO - Reading from file tmpfile_stdout 05:09:52 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:52 INFO - Reading from file tmpfile_stdout 05:09:52 INFO - Output received: 05:09:52 INFO - Usage: mozinstall [options] installer 05:09:52 INFO - Options: 05:09:52 INFO - -h, --help show this help message and exit 05:09:52 INFO - -d DEST, --destination=DEST 05:09:52 INFO - Directory to install application into. [default: 05:09:52 INFO - "/builds/slave/test"] 05:09:52 INFO - --app=APP Application being installed. [default: firefox] 05:09:52 INFO - mkdir: /builds/slave/test/build/application 05:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:10:09 INFO - Reading from file tmpfile_stdout 05:10:09 INFO - Output received: 05:10:09 INFO - /builds/slave/test/build/application/firefox/firefox 05:10:09 INFO - Running post-action listener: _resource_record_post_action 05:10:09 INFO - [mozharness: 2016-12-09 13:10:09.690296Z] Finished install step (success) 05:10:09 INFO - [mozharness: 2016-12-09 13:10:09.690576Z] Running run-tests step. 05:10:09 INFO - Running pre-action listener: _resource_record_pre_action 05:10:09 INFO - Running main action method: run_tests 05:10:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:10:09 INFO - Python 2.7.3 05:10:09 INFO - Return code: 0 05:10:09 INFO - grabbing minidump binary from tooltool 05:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:09 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 0x10a9f20>, '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 0x1016c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1016f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:10:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:10:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:10:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:10:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:10:09 INFO - Return code: 0 05:10:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:10:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:10:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:10:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:10:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:10:09 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/1481283050/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:10:09 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/1481283050/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:10:09 INFO - Using env: {'DISPLAY': ':0', 05:10:09 INFO - 'HOME': '/home/cltbld', 05:10:09 INFO - 'LANG': 'en_US.UTF-8', 05:10:09 INFO - 'LANGUAGE': 'en_US:en', 05:10:09 INFO - 'LOGNAME': 'cltbld', 05:10:09 INFO - 'MAIL': '/var/mail/cltbld', 05:10:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:10:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:09 INFO - 'MOZ_NO_REMOTE': '1', 05:10:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:10:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:09 INFO - 'NO_EM_RESTART': '1', 05:10:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:09 INFO - 'PWD': '/builds/slave/test', 05:10:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:10:09 INFO - 'SHELL': '/bin/bash', 05:10:09 INFO - 'SHLVL': '1', 05:10:09 INFO - 'TERM': 'linux', 05:10:09 INFO - 'TMOUT': '86400', 05:10:09 INFO - 'USER': 'cltbld', 05:10:09 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876', 05:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:09 INFO - '_': '/tools/buildbot/bin/python'} 05:10:09 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/1481283050/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:10:09 INFO - mozversion application_buildid: 20161209033050 05:10:09 INFO - mozversion application_changeset: 005adbf78cd24afd81a24902ad8802d1b1d0c5aa 05:10:09 INFO - mozversion application_display_name: Nightly 05:10:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:10:09 INFO - mozversion application_name: Firefox 05:10:09 INFO - mozversion application_remotingname: firefox 05:10:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:10:09 INFO - mozversion application_vendor: Mozilla 05:10:09 INFO - mozversion application_version: 53.0a1 05:10:09 INFO - mozversion platform_buildid: 20161209033050 05:10:09 INFO - mozversion platform_changeset: 005adbf78cd24afd81a24902ad8802d1b1d0c5aa 05:10:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:10:09 INFO - mozversion platform_version: 53.0a1 05:10:09 INFO - using testdate: 1481289009 05:10:09 INFO - actual date: 1481289009 05:10:09 INFO - starting webserver on 'localhost:52001' 05:10:09 INFO - SUITE-START | Running 2 tests 05:10:09 INFO - TEST-START | dromaeo_css 05:10:09 INFO - operating with platform_type : linux_ 05:10:09 INFO - Initialising browser for dromaeo_css test... 05:10:09 INFO - TEST-INFO | started process 6695 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptlJLKV/profile http://localhost:52001/getInfo.html) 05:10:14 INFO - PROCESS | 6695 | __metrics Screen width/height:1600/1200 05:10:14 INFO - PROCESS | 6695 | colorDepth:24 05:10:14 INFO - PROCESS | 6695 | Browser inner width/height: 1024/697 05:10:14 INFO - PROCESS | 6695 | __metrics 05:10:14 INFO - PROCESS | 6695 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:10:14 INFO - PROCESS | 6695 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:10:16 INFO - TEST-INFO | 6695: exit 0 05:10:16 INFO - Browser initialized. 05:10:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:16 INFO - TEST-INFO | started process 6775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptlJLKV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:28 INFO - PROCESS | 6775 | RSS: Main: 172552192 05:10:28 INFO - PROCESS | 6775 | 05:11:19 INFO - PROCESS | 6775 | 1481289079262 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:13:30 INFO - PROCESS | 6775 | Cycle 1(1): loaded http://localhost:52001/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52001/tests/dromaeo/cssquery-ext.html) 05:13:32 INFO - PROCESS | 6775 | RSS: Main: 348233728 05:13:32 INFO - PROCESS | 6775 | 05:16:34 INFO - PROCESS | 6775 | Cycle 1(1): loaded http://localhost:52001/tests/dromaeo/cssquery-ext.html (next: http://localhost:52001/tests/dromaeo/cssquery-jquery.html) 05:16:35 INFO - PROCESS | 6775 | RSS: Main: 278761472 05:16:35 INFO - PROCESS | 6775 | 05:19:37 INFO - PROCESS | 6775 | Cycle 1(1): loaded http://localhost:52001/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52001/tests/dromaeo/cssquery-mootools.html) 05:19:38 INFO - PROCESS | 6775 | RSS: Main: 254808064 05:19:38 INFO - PROCESS | 6775 | 05:22:40 INFO - PROCESS | 6775 | Cycle 1(1): loaded http://localhost:52001/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52001/tests/dromaeo/cssquery-prototype.html) 05:22:40 INFO - PROCESS | 6775 | RSS: Main: 230662144 05:22:40 INFO - PROCESS | 6775 | 05:25:43 INFO - PROCESS | 6775 | Cycle 1(1): loaded http://localhost:52001/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52001/tests/dromaeo/cssquery-yui.html) 05:25:44 INFO - PROCESS | 6775 | RSS: Main: 223485952 05:25:44 INFO - PROCESS | 6775 | 05:28:47 INFO - PROCESS | 6775 | Cycle 1(1): loaded http://localhost:52001/tests/dromaeo/cssquery-yui.html (next: http://localhost:52001/tests/dromaeo/cssquery-dojo.html) 05:28:47 INFO - PROCESS | 6775 | RSS: Main: 315924480 05:28:47 INFO - PROCESS | 6775 | 05:28:47 INFO - PROCESS | 6775 | __start_tp_report 05:28:47 INFO - PROCESS | 6775 | _x_x_mozilla_page_load 05:28:47 INFO - PROCESS | 6775 | _x_x_mozilla_page_load_details 05:28:47 INFO - PROCESS | 6775 | |i|pagename|runs| 05:28:47 INFO - PROCESS | 6775 | |0;dojo.html;3232.2888964427825;3237.2877966847286;3247.9350412991757;3261.98369008155;3312.552805371274;5223.112070947938;5277.076511610472;5342.973285133578;5363.302770639821;5384.703841288722;5295.258663787064;5298.019866324733;5315.920261196085;5321.760520776565;5326.414094449608;4800.591949684281;4837.782299796518;4849.321095046696;4864.5135486451245;4877.561019508261;4867.099586576477;4920.458749537548;4922.55696987271;4927.728974906378;4931.605471562267;4909.140900342443;4913.950860491385;4941.2094064949615;4956.529129732669;4967.10592093423;5000.149974504325;5017.2724954881705;5026.170681837492;5026.170681837492;5059.241113832918;4763.428388593391;4796.592289655384;4807.61539076873;4921.335619691332;4928.507149285043;5186.948130518685;5230.790768369261;5237.607179461517;5255.710935898524;5319.627626066177;5597.720113994284;5629.296337957755;5629.549636029149;5637.8590535236535;5651;5678.375378708339;5685.971570142165;5687.289088863892;5687.488126068673;5690.573207009491;5208.114620514521;5251.264822924755;5278.815241466607;5320.467953204726;5320.52115309624;5833.7083145842535;5842.444967728144;5843.18195452637;5844.444777746107;5880.764769409176;5606.6916319602815;5615.2700148981185;5618.101103823368;5618.775248989995;5623.072193088176;5497.202905578669;5595.356533998561;5659.971700141556;5686.658800471985;5710.143478478179;5680.233168522279;5721.313442386941;5722.7710891563875;5741.655500669973;5761.48146666802;5750.281214848144;5752.539796816244;5755.971220143872;5757.193992841006;5798.275215598051;4293.377460268243;4310.245707001262;4311.439512863308;4382.978085109554;4436.401085853433;3971.62269584389;4001.3997900314603;4020.4170395292517;4024.899377515527;4032.6169013943627;5444.618876678674;5448.046591846411;5449.972750136303;5452.236686863842;5455.509004189642;5546.889062218733;5593.888122237614;5681.2330335404195;5721.713914304268;5743.597948143673;5438.700871452108;5480.150576660624;5491;5515.255440515479;5551.361593416728;5005.424376196711;5010.674306170087;5013.423456302499;5018.096742586369;5058.772355244023;5964.075568286922;5968.22413086296;5969.4777831652655;5977.880442391215;6026.427489388501;5537;5584.218209450599;5695.430456954271;5717.085266357363;5792.160136780059;5950.047992321381;5956.076808094752;5956.970215148896;5998.310194327708;6013.098035294741;5560.5551555876245;5661.207430959751;5665.03694371966;5676.318841739017;5677.602567820213;5580.525655319343;5636.1545768133465;5673.716314184357;5760.279965004374;5766.192733017297;5595.720213989202;5639.943600563942;5645.209670646195;5687.516561092188;5693.4875861172695;5618.915716264341;5627.690477023639;5628.521575666115;5632.9436705632415;5633.492985631313;5308.150695888639;5545.805896793593;5557.499825015768;5560.916586251128;5607.299087614048;5399.78400863961;5575.553955683455;5580.079286917613;5580.497755201889;5584.274044374205;5409.269748584048;5462.371827239918;5463.672179669233;5466.781328746805;5467.7266136692215 05:28:47 INFO - PROCESS | 6775 | |1;ext.html;546.5436360638867;576.538768984812;578.3320265093813;582.9825105246839;602.5571205164206;30300.24249393766;37982.25221847269;39629.99999999996;39900.201995960066;44292.778536107355;42109.789451052704;42379.3643095353;42565;42579.36130958038;42732.786336068355;7762.184970578092;8465.111163327869;8651;8656.523891186014;8698.47809131451;7778.766637000899;9919.355241909252;10157.898421015769;10160.237982151368;10500.2649814513;10246.333988290737;10280.383177009404;10291.125254353388;10291.176705863512;10292.382457052563;10191.082802547731;10215.744606384826;10256.487175641263;10299.53652085658;10303.175745940382;10080.596776128947;10124.190064794799;10147;10149.89850101497;10152.03555662211;39954.60045399538;40306;40311.39532907009;40333.19333613341;40358;3052.923676908073;3126.0621813455873;3239.4654881944452;3385.458326667746;3452.3613131570487;30131.849340753157;30812;30833;30882.845585771927;30962.225944351347;3919.2161567686294;3990.7206495545324;4048.5344185418758;4189.560096189886;4257.978710106429;334890;335668;335839;336874.3156284227;337554;55575.82998805233;55786;56066.439335606934;56312.43687563154;56326.436735632116;59647.40352596418;59698.70150649219;59702;59809.10286345708;59812.700936495035;15467.303971321318;15690.607734806721;15703.293351799197;15708.68582628364;15716.92141539285;10732.248742587944;10851.457427128613;11121.443927803577;11565.421728913521;11593;16201.351945922032;16749.832501674828;16808.915955420143;16813.747793783103;17073.48779536616;5861.17943487912;5884.676342801102;5989.970050149721;5996.850078748066;6173.84565385869;846.6994217052858;953.6709835106877;964.5900492290889;982.4547376206132;997.5062344139698;30887.69112308848;31372.372552549277;31789.840637450197;31825.8408707955;32007;1049.2078480746986;1057.8783439904355;1080.1682704317789;1106.303029091667;1135.5287555664472;15869.060316384535;16321.673566528603;16544.75182872258;16559.586010349598;16656.500304990626;463751.7686880943;467121;468466;469489;470551;55734.44265557374;55957.440425595225;55981.16028259498;56070.71964640151;56088.43911560914;1164.4352489042808;1192.8031874740743;1221.1818081885158;1255.9748805023848;1258.383392137846;29932.700672993862;32433.189170270933;32477;32541;32959.176020599676;47398;48028;48094.75952620214;48121.278180826615;48139.75930120327;358767;359940;360012;360078;360340;24570;25113.497730045663;25159.622605661294;26774.866125669247;39736.2052758949;40200.79960916779;40483.3927491082;40795.59204407918;41649.16701665889;41901.371479428446;33013.83493082519;33168.1707957303;34436.65563344335;34709.65290347165;34916.30167396587;33635.49546756849;33884.3223135531;34197.658023420445;34608.48087278642;34727;35258.29483410369;36093.45859812157;36111.81944090368;36240.81879590585;36318.63681363153;30367.39265214639;31698.207544811565;31777.20556986094;31850.522242165924;32222.19444513906;24722.50554988926;25033.74966250387;25171.87414062918;25572.20994530365;25671.229863104138 05:28:47 INFO - PROCESS | 6775 | |2;jquery.html;356.83942225998305;375.59435809325913;385.926673931953;393.1233349630324;413.18808541216504;13996.02027858049;17338.393156239534;17603.47189584313;18170.454886353396;19150.808491915075;18300.816991830077;18824.529386765338;19350.12924418401;19351;19473;5859.267591551045;6150.077488376734;6155.446009859112;6160.291566469846;6224.751009959597;5916.3787802280785;6083.239595050619;6083.878322433571;6092.81721548352;6100.481459075984;6082.209312789331;6087.4825639820665;6096.993995990656;6102.664353460558;6105.20632317798;5813.244278243822;6120.877582448348;6161.19904412428;6174.691265436755;6175.197224360849;5846.941530584682;5935.37678543751;6002.189704389895;6007.128966299906;6010.218671572711;23200;23520.882395587912;23879.403014924592;24044.158454454184;24197.758022419726;2390.5935990881594;2433.634954756783;2758.3104223944015;2857.8713957871946;3123.890663826778;14423;14642.487512937103;15005;15048.924755376263;15693.529194124194;1956.1197461142401;1992.0776450724975;1993.2027189124321;1998.5703073839195;2069.623700239339;84549.15450845474;86692.56653716754;86766.5661671694;87512;88770.81261027948;16531.66936661284;16678.749818752727;16730.749038764185;16975.74536381955;17367.652646947245;28879.855600722283;28898.27754306159;29003.96972211967;29024;29100.41799164005;8615.310775137974;8746.68799680053;9019;9031.729048128567;9075.410098343587;7209.2195777654115;7680.462367634212;7716.729914452967;7858.528488290606;7983.880241796378;8056.677732890736;8241.299489543337;8599.5700214989;8601.698940537051;8624.792529045939;3223.3069889973767;3315.9133126934985;3374.6794054564775;3427.1089516725333;3507;822.7120507822215;965.0494263150791;978.2369751593768;985.4875464758286;996.7988573768591;17462.388816391365;18324.908375458304;18513.81486185121;18580.721289180674;18885;899.7255836969656;904.8461761500561;913.1142791492242;922.7831459606987;952.4999375328008;9710.75723106928;10300.587976480856;10313.174946004301;10488.265821392582;10510.73723156912;390943;399637;400320;401396;411106;67947;71867.2813271871;84777.57611212028;90422;90943.54528227316;893.2407453664463;922.9446233225893;945.4847108325841;946.394307643109;948.4972964328916;16498.84618247696;16501.504954851374;16756.413525526546;17404.564885877953;17714.379996700052;38664.226715466095;39461.80269098636;39686.40470393004;39919.80040099781;39986;54097.72951135377;54201;55246.44753552574;55809.72095139661;56032.43967560435;7582.696692132253;7643.350315223268;7671.8849217262905;7841.764747057598;8087.312578430664;12090.455929483018;12163.635090947286;12235.93882030584;12280.125429296842;12813.93593032029;9672.661456848977;10033.347832390871;10092.34399764013;10383.117435018108;11315.490802914053;9926.404415735078;10104.545295461576;10338;10579.10077643409;10592.523336450033;10447.895521044997;10590.099841513558;10663.253572250178;10912.781744365227;11112.888871111509;7631.809204769926;7797.961010194913;7901.328387087162;7930.920690793249;7968.721094761656;5204.895902081861;9352.17632561644;10113.14038306752;10407.583696652351;10469.267151299337 05:28:47 INFO - PROCESS | 6775 | |3;mootools.html;1293.2111412038648;1376.7728324826405;1390.0547627613225;1483.2361333913036;1709.7093494106;7410.11078670559;8059.153788852167;8345.582720863948;8353.665853365866;8527.78680532987;8298.70954516592;8393.958030209855;8399;8407.24334809867;8408;2155.2133471282987;2195.2206966526815;2198.329509499602;2199.8350123740784;2199.9340019799433;1935.525796179931;1989;2081.750189977205;2128.3082998025625;2138.112683236455;2137.070374387147;2144.667576525633;2151.95696086079;2152.7201463809683;2152.7524334701475;2138.700581918533;2203.6584329429043;2211.1708109458955;2211.7677643847487;2213.6347502662043;2149.6882951971875;2153.95692086159;2159.6328624133935;2169.913203471859;2173.3588591365583;8243.917560824435;8387.790305242357;8422.073571907085;8443.240108390275;8456.57717114148;5031.949680503185;5120;5162.070827251093;5175.094358487249;5183.870403239912;3036.104349216986;3208.951865722016;3260.3642289753507;3271.2149084219855;3303.587051618548;1016.4866742295168;1080.4111759091236;1081.810683130457;1111.9110471162287;1115.1023426141953;11636.301821890713;11636.418179091012;11661.766764664659;11663.766724665544;11732.12009099321;4243.575642435793;4565.0641618468135;4581.427321584802;4595.563421474954;4637.907241855144;9805.558749856273;9821.263405244636;9824.803503930025;9828.4102953823;9835.213182945346;5090.414602320707;5196.558292545176;5199.376074870964;5202.323697919246;5204.8178313758835;4585.234684646435;4720.858374248778;4760.571548560646;4762.9523704762505;4765.761711914391;3629.6551827576336;3768.962310376916;3771.642208804828;3841;3859.3246181918053;1149.8217776244696;1155.0759392485977;1168.6085161470833;1171.2386948483384;1192.9522819087313;690.6719308328504;727.8071311102563;739.7928579997609;893.1515060692238;910.7131253655077;5412.83761487156;5427.6200665953;5452.672839629556;5550.72246387679;5582.441755824385;437.21956307990087;437.9693421460467;441.401900424926;441.5231549926072;442.87156724550164;3241.33552621712;3462.2210002749684;3540.946885796715;3543.574771027493;3575.606683264839;9803.411795292306;10407.375557466576;10423.635172768916;10427.374357538572;10429.582816687247;10382.117520010883;10384.584616615402;10401.375917444977;10407.58369665205;10409.53157107932;7744.070711514654;7782.416318776114;7790.766277011586;7883.2116788321855;7888.447808653339;5352.5450336721815;5369.543588794996;5371.919421208763;5393.7033463159905;5439.347278326626;9406.341556091007;9438.339316248072;9458.148766611039;9470.763230919007;9599.040095990624;9028.27773778083;9381.390209636353;9405.341626086109;9561.187299079656;9659.9034009659;6667.466602671678;6856.760013399707;7087.574745515286;7226.277372262732;7296.7446139384865;7839.294463498312;8028.87956680662;8149.266566009088;8155.306798922158;8188.344932405274;7034.613096279549;7124.893126603209;7133.536320139175;7236.276372362721;7290.744823931349;7166.247544007804;7225.710971561079;7229.530080544748;7264.237255088351;7321.926780732125;6680.665966701548;6778.796636100925;6783.694733737092;6809.080774095632;6816.250212476524;4439.778011099367;4566.70316429431;4773.66584339093;4823.927641085456;4876.317315575893;4550.385697930737;4611.700239484423;4666.300054991779;4713.62125587589;4719.858404247878 05:28:47 INFO - PROCESS | 6775 | |4;prototype.html;362.9038304849215;376.21746766725204;380.36099353086814;383.58189573365024;390.61524398467503;5070.974645126778;5205.334293026223;5289.89420211596;5291.4973077557615;5293.232481290211;5049.24261360796;5126.692693363197;5128.794848206067;5166.948330516694;5175;4062.5531191568903;4096.508418989718;4101.282275601773;4106.137711080677;4107.260693075245;3989.501312335958;4109.342505199169;4112.547619761824;4121.155163191538;4136.214119317335;4051.3315302974975;4064.207479541476;4068.1456894052135;4070.7354021988476;4091.6931230157707;4046.858359957402;4054;4059.1272876331436;4067.3085575452246;4078.3066878630702;3588.8923332300074;3590.701950751521;3595.442706380515;3597.262561174979;3606.6934310583633;4954.479779623124;4981.430849282465;5034.565148932573;5043.949560504421;5082.3138876251605;4545.886352841172;4558.840440584597;4590.380298659672;4612.331211974279;4620.561046700558;5341.824798544314;5352.598555108343;5359.410464848864;5391.730413479349;5403.216533602645;3818.369968955119;3887.4751908492276;3897.9805100974313;3912.9413058803857;3925.8233379497706;5531.197976293415;5603.411641777596;5634.4365563443325;5637.943620563824;5649.435056494359;3941.428492868575;3985.123272879962;3989.401589761559;4007.6393124618926;4013;3845.1732877431486;3993.84024639017;4010.1177740897538;4064.3903414488063;4071.206114807643;4546.363509108727;4549.09018196362;4594.793234304464;4601.72389656615;4610.377599024089;5421.728913554307;5460.808871689472;5461.808836690776;5467.633091727068;5480.424555421703;1271.4469205895466;1299.7140629061591;1314.704191556892;1357.362039841273;1360.7958806179151;930.50683137937;940.5767404667927;949.7558198759647;952.5713428956858;970.1947383671536;5185.222216667455;5186.5850731941355;5197.272381866542;5204.947950520446;5219.042858927902;5495.642783219078;5500.58745594082;5503.679117011889;5514.6139770216505;5516.889662206733;4855.053264613437;5104.3364362633365;5105.8723531912;5194.6104042196985;5197.948020519746;2838.602595636571;2858.8999385021425;2886.7401933825636;2892.4359749848577;2898.62317898676;5525.557955363562;5550.86122846924;5561.860953476195;5584.86037849057;5610.579206559524;5341.940125504644;5490.56075513958;5499.7250137492965;5500.972495137498;5525.568749750097;5549.556035517149;5559.72201389937;5563.693996830132;5564.4713752193475;5576.97211513948;5236.869078273074;5373.758180881948;5383.703896285742;5395.379531353945;5418.3497980241045;5412.511559321219;5414.29614150158;5439.809606663748;5449.509544141047;5449.591280653887;5550.361708403585;5574.219609254788;5578.804741834017;5585.5531557476115;5586.692731899784;3786.9775160707004;3818.503594532693;3848.4804551385205;3904.316744569632;3907.531096268466;5310.840674779763;5330.733463326896;5351.866203344947;5387.272718182995;5406.459354064562;5377.462253774591;5389.487998640123;5474.7810087597645;5483.177523371526;5490.972545137249;5442.156465747816;5471.425500322409;5483.369412517612;5487.4238204987905;5492.203630473638;5107.744612769272;5394;5418.45815418455;5424.728763561885;5434.945650543444;5501.312335958005;5521.420250873601;5566.385748133158;5573.801632649;5585.776568937197;5444.156155795858;5457.6179667424485;5465.426130256425;5469.398366179637;5472.124460086367 05:28:47 INFO - PROCESS | 6775 | |5;yui.html;607.5807692692044;649.4998850884818;662.678600878574;760.9536886780677;776.7320274505296;14994.250287485624;16362.918185409071;16799.076050817217;17355.95864248146;17726.468205953843;15285.77071343928;15595.454159104434;15724.79852820606;16170.514884553482;16441.67116657666;778.4122987144698;796.9681212751483;798.5129071266551;798.884161796542;801.1107670485785;659.676758388391;676.5669971218424;770.6262462705583;792.43341011177;796.0049937578027;816.0900595835631;818.705266104205;823.8352329534117;827.0819390476566;827.4993628854517;816.5549775372436;817.7342363731751;819.0826274572457;819.6229734322219;819.6885183630212;505.0479845491239;516.7338820507441;521.6895946911619;600.7867207141445;603.6981509245377;11489.770204595945;13823.239721815296;14193.503227387095;14202.928985355007;14690;15142.772858407132;15188.088714677155;15213.163276019815;15280.465183718516;15327.693446131016;11702.473388697445;11798.174127811057;11849.111316651199;11933.16467847251;12308.07689423288;3644.125409901631;3691.870784522556;3738.065483629093;3835.4438606402196;3837.175007373428;73575;75254.62372688156;77644;77995.6100219501;79261;11565.363904985139;11641.708957275967;11646.76706465866;11768.117391195694;11943.343116128523;22193.11227550912;22366.888165559067;22370.217042403765;22557.210497632834;22693.546129077655;7964.1637628049375;8109.472884262504;8268.793280168044;8312.418130730912;8417.579121043924;4174.686898482626;4228.82976873586;4338.262495375794;4356.386168299058;4456.331550267486;3406.267652454734;3446.3968805458944;3459.809710465898;3484.5295885055193;3485.111296619346;547.6057238788067;548.4926443040172;553.3525774843442;560.1597603594608;565.0337922153082;664;671.9798406047827;689.396777819408;698.7938558125263;701.6000879498637;8933.955330223307;8934.50860202695;9017.729468115967;9650.71047868551;9685.903140968641;562.0894151474637;577.1400613086471;599.235974132984;630.152444961526;684.4524380495683;3986.6013398660484;4482.856871497747;4541.2733962565835;4583.816647334069;4681.625469962463;74674;74954.75497695249;76094;79247;79510;67444.3255567438;67648.3235167642;67870.66064669743;68040.6597967007;68391.99999999901;60640;60685;61049.38950610526;61519.38480615226;63200;8126.715564955198;8175.667584720431;8345.457545259782;8485.278751305961;8606.78483037929;41835.16329673451;42255.577444226394;42486.57513424826;42772;43037.35443968406;50918.745406272734;52446.70639523282;55528;58255;59935.40064599473;10899.237053406185;11018.724531886766;11215.887841121485;11617;11757.412129393326;12812.231266124063;13071.346432678518;13115.606531804062;13587.796183057064;13899.305034748424;11976;12465.127441079037;12836.935815320863;13046.54337098235;13291.734165316833;11295.491702873554;11927.522899083939;11966;11999.880001199876;12187.390630468264;11341.376224307742;11634.70913227176;11772.05823534098;11989.76020479603;12195.146339756131;8860.689875854314;9174.541272936192;9234.215091717278;9364.812703746024;9499.525023748924;10463.801186781066;10542.631007914993;10912.454377281263;10950.45247737594;11028 05:28:47 INFO - PROCESS | 6775 | __end_tp_report 05:28:47 INFO - PROCESS | 6775 | __start_cc_report 05:28:47 INFO - PROCESS | 6775 | _x_x_mozilla_cycle_collect,1698 05:28:47 INFO - PROCESS | 6775 | __end_cc_report 05:28:47 INFO - PROCESS | 6775 | __startTimestamp1481290127756__endTimestamp 05:28:47 INFO - PROCESS | 6775 | 05:28:47 INFO - PROCESS | 6775 | ------- Summary: start ------- 05:28:47 INFO - PROCESS | 6775 | Number of tests: 6 05:28:47 INFO - PROCESS | 6775 | 05:28:47 INFO - PROCESS | 6775 | [#0] dojo.html Cycles:165 Average:5321.33 Median:5537.00 stddev:570.61 (10.3%) stddev-sans-first:548.32 05:28:47 INFO - PROCESS | 6775 | Values: 3232.3 3237.3 3247.9 3262.0 3312.6 5223.1 5277.1 5343.0 5363.3 5384.7 5295.3 5298.0 5315.9 5321.8 5326.4 4800.6 4837.8 4849.3 4864.5 4877.6 4867.1 4920.5 4922.6 4927.7 4931.6 4909.1 4914.0 4941.2 4956.5 4967.1 5000.1 5017.3 5026.2 5026.2 5059.2 4763.4 4796.6 4807.6 4921.3 4928.5 5186.9 5230.8 5237.6 5255.7 5319.6 5597.7 5629.3 5629.5 5637.9 5651.0 5678.4 5686.0 5687.3 5687.5 5690.6 5208.1 5251.3 5278.8 5320.5 5320.5 5833.7 5842.4 5843.2 5844.4 5880.8 5606.7 5615.3 5618.1 5618.8 5623.1 5497.2 5595.4 5660.0 5686.7 5710.1 5680.2 5721.3 5722.8 5741.7 5761.5 5750.3 5752.5 5756.0 5757.2 5798.3 4293.4 4310.2 4311.4 4383.0 4436.4 3971.6 4001.4 4020.4 4024.9 4032.6 5444.6 5448.0 5450.0 5452.2 5455.5 5546.9 5593.9 5681.2 5721.7 5743.6 5438.7 5480.2 5491.0 5515.3 5551.4 5005.4 5010.7 5013.4 5018.1 5058.8 5964.1 5968.2 5969.5 5977.9 6026.4 5537.0 5584.2 5695.4 5717.1 5792.2 5950.0 5956.1 5957.0 5998.3 6013.1 5560.6 5661.2 5665.0 5676.3 5677.6 5580.5 5636.2 5673.7 5760.3 5766.2 5595.7 5639.9 5645.2 5687.5 5693.5 5618.9 5627.7 5628.5 5632.9 5633.5 5308.2 5545.8 5557.5 5560.9 5607.3 5399.8 5575.6 5580.1 5580.5 5584.3 5409.3 5462.4 5463.7 5466.8 5467.7 05:28:47 INFO - PROCESS | 6775 | 05:28:47 INFO - PROCESS | 6775 | [#1] ext.html Cycles:180 Average:54314.50 Median:34040.99 stddev:103630.61 (304.4%) stddev-sans-first:103842.69 05:28:47 INFO - PROCESS | 6775 | Values: 546.5 576.5 578.3 583.0 602.6 30300.2 37982.3 39630.0 39900.2 44292.8 42109.8 42379.4 42565.0 42579.4 42732.8 7762.2 8465.1 8651.0 8656.5 8698.5 7778.8 9919.4 10157.9 10160.2 10500.3 10246.3 10280.4 10291.1 10291.2 10292.4 10191.1 10215.7 10256.5 10299.5 10303.2 10080.6 10124.2 10147.0 10149.9 10152.0 39954.6 40306.0 40311.4 40333.2 40358.0 3052.9 3126.1 3239.5 3385.5 3452.4 30131.8 30812.0 30833.0 30882.8 30962.2 3919.2 3990.7 4048.5 4189.6 4258.0 334890.0 335668.0 335839.0 336874.3 337554.0 55575.8 55786.0 56066.4 56312.4 56326.4 59647.4 59698.7 59702.0 59809.1 59812.7 15467.3 15690.6 15703.3 15708.7 15716.9 10732.2 10851.5 11121.4 11565.4 11593.0 16201.4 16749.8 16808.9 16813.7 17073.5 5861.2 5884.7 5990.0 5996.9 6173.8 846.7 953.7 964.6 982.5 997.5 30887.7 31372.4 31789.8 31825.8 32007.0 1049.2 1057.9 1080.2 1106.3 1135.5 15869.1 16321.7 16544.8 16559.6 16656.5 463751.8 467121.0 468466.0 469489.0 470551.0 55734.4 55957.4 55981.2 56070.7 56088.4 1164.4 1192.8 1221.2 1256.0 1258.4 29932.7 32433.2 32477.0 32541.0 32959.2 47398.0 48028.0 48094.8 48121.3 48139.8 358767.0 359940.0 360012.0 360078.0 360340.0 24570.0 25113.5 25159.6 26774.9 39736.2 40200.8 40483.4 40795.6 41649.2 41901.4 33013.8 33168.2 34436.7 34709.7 34916.3 33635.5 33884.3 34197.7 34608.5 34727.0 35258.3 36093.5 36111.8 36240.8 36318.6 30367.4 31698.2 31777.2 31850.5 32222.2 24722.5 25033.7 25171.9 25572.2 25671.2 05:28:47 INFO - PROCESS | 6775 | 05:28:47 INFO - PROCESS | 6775 | [#2] jquery.html Cycles:180 Average:26800.19 Median:220153.00 stddev:66427.67 (30.2%) stddev-sans-first:66584.35 05:28:47 INFO - PROCESS | 6775 | Values: 356.8 375.6 385.9 393.1 413.2 13996.0 17338.4 17603.5 18170.5 19150.8 18300.8 18824.5 19350.1 19351.0 19473.0 5859.3 6150.1 6155.4 6160.3 6224.8 5916.4 6083.2 6083.9 6092.8 6100.5 6082.2 6087.5 6097.0 6102.7 6105.2 5813.2 6120.9 6161.2 6174.7 6175.2 5846.9 5935.4 6002.2 6007.1 6010.2 23200.0 23520.9 23879.4 24044.2 24197.8 2390.6 2433.6 2758.3 2857.9 3123.9 14423.0 14642.5 15005.0 15048.9 15693.5 1956.1 1992.1 1993.2 1998.6 2069.6 84549.2 86692.6 86766.6 87512.0 88770.8 16531.7 16678.7 16730.7 16975.7 17367.7 28879.9 28898.3 29004.0 29024.0 29100.4 8615.3 8746.7 9019.0 9031.7 9075.4 7209.2 7680.5 7716.7 7858.5 7983.9 8056.7 8241.3 8599.6 8601.7 8624.8 3223.3 3315.9 3374.7 3427.1 3507.0 822.7 965.0 978.2 985.5 996.8 17462.4 18324.9 18513.8 18580.7 18885.0 899.7 904.8 913.1 922.8 952.5 9710.8 10300.6 10313.2 10488.3 10510.7 390943.0 399637.0 400320.0 401396.0 411106.0 67947.0 71867.3 84777.6 90422.0 90943.5 893.2 922.9 945.5 946.4 948.5 16498.8 16501.5 16756.4 17404.6 17714.4 38664.2 39461.8 39686.4 39919.8 39986.0 54097.7 54201.0 55246.4 55809.7 56032.4 7582.7 7643.4 7671.9 7841.8 8087.3 12090.5 12163.6 12235.9 12280.1 12813.9 9672.7 10033.3 10092.3 10383.1 11315.5 9926.4 10104.5 10338.0 10579.1 10592.5 10447.9 10590.1 10663.3 10912.8 11112.9 7631.8 7798.0 7901.3 7930.9 7968.7 5204.9 9352.2 10113.1 10407.6 10469.3 05:28:47 INFO - PROCESS | 6775 | 05:28:47 INFO - PROCESS | 6775 | [#3] mootools.html Cycles:180 Average:5444.28 Median:4769.71 stddev:3147.24 (66.0%) stddev-sans-first:3140.60 05:28:47 INFO - PROCESS | 6775 | Values: 1293.2 1376.8 1390.1 1483.2 1709.7 7410.1 8059.2 8345.6 8353.7 8527.8 8298.7 8394.0 8399.0 8407.2 8408.0 2155.2 2195.2 2198.3 2199.8 2199.9 1935.5 1989.0 2081.8 2128.3 2138.1 2137.1 2144.7 2152.0 2152.7 2152.8 2138.7 2203.7 2211.2 2211.8 2213.6 2149.7 2154.0 2159.6 2169.9 2173.4 8243.9 8387.8 8422.1 8443.2 8456.6 5031.9 5120.0 5162.1 5175.1 5183.9 3036.1 3209.0 3260.4 3271.2 3303.6 1016.5 1080.4 1081.8 1111.9 1115.1 11636.3 11636.4 11661.8 11663.8 11732.1 4243.6 4565.1 4581.4 4595.6 4637.9 9805.6 9821.3 9824.8 9828.4 9835.2 5090.4 5196.6 5199.4 5202.3 5204.8 4585.2 4720.9 4760.6 4763.0 4765.8 3629.7 3769.0 3771.6 3841.0 3859.3 1149.8 1155.1 1168.6 1171.2 1193.0 690.7 727.8 739.8 893.2 910.7 5412.8 5427.6 5452.7 5550.7 5582.4 437.2 438.0 441.4 441.5 442.9 3241.3 3462.2 3540.9 3543.6 3575.6 9803.4 10407.4 10423.6 10427.4 10429.6 10382.1 10384.6 10401.4 10407.6 10409.5 7744.1 7782.4 7790.8 7883.2 7888.4 5352.5 5369.5 5371.9 5393.7 5439.3 9406.3 9438.3 9458.1 9470.8 9599.0 9028.3 9381.4 9405.3 9561.2 9659.9 6667.5 6856.8 7087.6 7226.3 7296.7 7839.3 8028.9 8149.3 8155.3 8188.3 7034.6 7124.9 7133.5 7236.3 7290.7 7166.2 7225.7 7229.5 7264.2 7321.9 6680.7 6778.8 6783.7 6809.1 6816.3 4439.8 4566.7 4773.7 4823.9 4876.3 4550.4 4611.7 4666.3 4713.6 4719.9 05:28:47 INFO - PROCESS | 6775 | 05:28:47 INFO - PROCESS | 6775 | [#4] prototype.html Cycles:180 Average:4543.32 Median:5205.14 stddev:1321.06 (25.4%) stddev-sans-first:1286.97 05:28:47 INFO - PROCESS | 6775 | Values: 362.9 376.2 380.4 383.6 390.6 5071.0 5205.3 5289.9 5291.5 5293.2 5049.2 5126.7 5128.8 5166.9 5175.0 4062.6 4096.5 4101.3 4106.1 4107.3 3989.5 4109.3 4112.5 4121.2 4136.2 4051.3 4064.2 4068.1 4070.7 4091.7 4046.9 4054.0 4059.1 4067.3 4078.3 3588.9 3590.7 3595.4 3597.3 3606.7 4954.5 4981.4 5034.6 5043.9 5082.3 4545.9 4558.8 4590.4 4612.3 4620.6 5341.8 5352.6 5359.4 5391.7 5403.2 3818.4 3887.5 3898.0 3912.9 3925.8 5531.2 5603.4 5634.4 5637.9 5649.4 3941.4 3985.1 3989.4 4007.6 4013.0 3845.2 3993.8 4010.1 4064.4 4071.2 4546.4 4549.1 4594.8 4601.7 4610.4 5421.7 5460.8 5461.8 5467.6 5480.4 1271.4 1299.7 1314.7 1357.4 1360.8 930.5 940.6 949.8 952.6 970.2 5185.2 5186.6 5197.3 5204.9 5219.0 5495.6 5500.6 5503.7 5514.6 5516.9 4855.1 5104.3 5105.9 5194.6 5197.9 2838.6 2858.9 2886.7 2892.4 2898.6 5525.6 5550.9 5561.9 5584.9 5610.6 5341.9 5490.6 5499.7 5501.0 5525.6 5549.6 5559.7 5563.7 5564.5 5577.0 5236.9 5373.8 5383.7 5395.4 5418.3 5412.5 5414.3 5439.8 5449.5 5449.6 5550.4 5574.2 5578.8 5585.6 5586.7 3787.0 3818.5 3848.5 3904.3 3907.5 5310.8 5330.7 5351.9 5387.3 5406.5 5377.5 5389.5 5474.8 5483.2 5491.0 5442.2 5471.4 5483.4 5487.4 5492.2 5107.7 5394.0 5418.5 5424.7 5434.9 5501.3 5521.4 5566.4 5573.8 5585.8 5444.2 5457.6 5465.4 5469.4 5472.1 05:28:47 INFO - PROCESS | 6775 | 05:28:47 INFO - PROCESS | 6775 | [#5] yui.html Cycles:180 Average:17148.31 Median:25711.90 stddev:22087.69 (85.9%) stddev-sans-first:22114.73 05:28:47 INFO - PROCESS | 6775 | Values: 607.6 649.5 662.7 761.0 776.7 14994.3 16362.9 16799.1 17356.0 17726.5 15285.8 15595.5 15724.8 16170.5 16441.7 778.4 797.0 798.5 798.9 801.1 659.7 676.6 770.6 792.4 796.0 816.1 818.7 823.8 827.1 827.5 816.6 817.7 819.1 819.6 819.7 505.0 516.7 521.7 600.8 603.7 11489.8 13823.2 14193.5 14202.9 14690.0 15142.8 15188.1 15213.2 15280.5 15327.7 11702.5 11798.2 11849.1 11933.2 12308.1 3644.1 3691.9 3738.1 3835.4 3837.2 73575.0 75254.6 77644.0 77995.6 79261.0 11565.4 11641.7 11646.8 11768.1 11943.3 22193.1 22366.9 22370.2 22557.2 22693.5 7964.2 8109.5 8268.8 8312.4 8417.6 4174.7 4228.8 4338.3 4356.4 4456.3 3406.3 3446.4 3459.8 3484.5 3485.1 547.6 548.5 553.4 560.2 565.0 664.0 672.0 689.4 698.8 701.6 8934.0 8934.5 9017.7 9650.7 9685.9 562.1 577.1 599.2 630.2 684.5 3986.6 4482.9 4541.3 4583.8 4681.6 74674.0 74954.8 76094.0 79247.0 79510.0 67444.3 67648.3 67870.7 68040.7 68392.0 60640.0 60685.0 61049.4 61519.4 63200.0 8126.7 8175.7 8345.5 8485.3 8606.8 41835.2 42255.6 42486.6 42772.0 43037.4 50918.7 52446.7 55528.0 58255.0 59935.4 10899.2 11018.7 11215.9 11617.0 11757.4 12812.2 13071.3 13115.6 13587.8 13899.3 11976.0 12465.1 12836.9 13046.5 13291.7 11295.5 11927.5 11966.0 11999.9 12187.4 11341.4 11634.7 11772.1 11989.8 12195.1 8860.7 9174.5 9234.2 9364.8 9499.5 10463.8 10542.6 10912.5 10950.5 11028.0 05:28:47 INFO - PROCESS | 6775 | -------- Summary: end -------- 05:28:47 INFO - PROCESS | 6775 | 05:28:48 INFO - TEST-INFO | 6775: exit 0 05:28:48 INFO - TEST-OK | dromaeo_css | took 1118762ms 05:28:48 INFO - TEST-START | kraken 05:28:48 INFO - operating with platform_type : linux_ 05:28:48 INFO - Initialising browser for kraken test... 05:28:48 INFO - TEST-INFO | started process 7088 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3n6V3r/profile http://localhost:52001/getInfo.html) 05:28:52 INFO - PROCESS | 7088 | __metrics Screen width/height:1600/1200 05:28:52 INFO - PROCESS | 7088 | colorDepth:24 05:28:52 INFO - PROCESS | 7088 | Browser inner width/height: 1024/697 05:28:52 INFO - PROCESS | 7088 | __metrics 05:28:53 INFO - PROCESS | 7088 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:28:53 INFO - PROCESS | 7088 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:28:53 INFO - PROCESS | 7088 | console.error: PushService: 05:28:53 INFO - PROCESS | 7088 | stateChangeProcessEnqueue: Error transitioning state 05:28:53 INFO - PROCESS | 7088 | UnknownError 05:28:54 INFO - TEST-INFO | 7088: exit 0 05:28:54 INFO - Browser initialized. 05:28:54 INFO - Running cycle 1/1 for kraken test... 05:28:54 INFO - TEST-INFO | started process 7173 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3n6V3r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:06 INFO - PROCESS | 7173 | RSS: Main: 172642304 05:29:06 INFO - PROCESS | 7173 | 05:29:09 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52001/tests/kraken/driver.html?testName=audio-beat-detection) 05:29:10 INFO - PROCESS | 7173 | RSS: Main: 234369024 05:29:10 INFO - PROCESS | 7173 | 05:29:12 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52001/tests/kraken/driver.html?testName=audio-dft) 05:29:13 INFO - PROCESS | 7173 | RSS: Main: 216989696 05:29:13 INFO - PROCESS | 7173 | 05:29:16 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52001/tests/kraken/driver.html?testName=audio-fft) 05:29:17 INFO - PROCESS | 7173 | RSS: Main: 299360256 05:29:17 INFO - PROCESS | 7173 | 05:29:19 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52001/tests/kraken/driver.html?testName=audio-oscillator) 05:29:19 INFO - PROCESS | 7173 | RSS: Main: 210522112 05:29:19 INFO - PROCESS | 7173 | 05:29:21 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52001/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:22 INFO - PROCESS | 7173 | RSS: Main: 213884928 05:29:22 INFO - PROCESS | 7173 | 05:29:25 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52001/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:26 INFO - PROCESS | 7173 | RSS: Main: 252932096 05:29:26 INFO - PROCESS | 7173 | 05:29:29 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52001/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:30 INFO - PROCESS | 7173 | RSS: Main: 252289024 05:29:30 INFO - PROCESS | 7173 | 05:29:32 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52001/tests/kraken/driver.html?testName=json-parse-financial) 05:29:33 INFO - PROCESS | 7173 | RSS: Main: 251924480 05:29:33 INFO - PROCESS | 7173 | 05:29:34 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52001/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:35 INFO - PROCESS | 7173 | RSS: Main: 214192128 05:29:35 INFO - PROCESS | 7173 | 05:29:37 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52001/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:37 INFO - PROCESS | 7173 | RSS: Main: 221069312 05:29:37 INFO - PROCESS | 7173 | 05:29:40 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52001/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:40 INFO - PROCESS | 7173 | RSS: Main: 226283520 05:29:40 INFO - PROCESS | 7173 | 05:29:43 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52001/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:44 INFO - PROCESS | 7173 | RSS: Main: 224321536 05:29:44 INFO - PROCESS | 7173 | 05:29:46 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52001/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:47 INFO - PROCESS | 7173 | RSS: Main: 227823616 05:29:47 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | Cycle 1(1): loaded http://localhost:52001/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52001/tests/kraken/driver.html?testName=ai-astar) 05:29:49 INFO - PROCESS | 7173 | RSS: Main: 281079808 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | __start_tp_report 05:29:49 INFO - PROCESS | 7173 | _x_x_mozilla_page_load 05:29:49 INFO - PROCESS | 7173 | _x_x_mozilla_page_load_details 05:29:49 INFO - PROCESS | 7173 | |i|pagename|runs| 05:29:49 INFO - PROCESS | 7173 | |0;ai-astar;111;113;113;112;114;112;112;113;113;112 05:29:49 INFO - PROCESS | 7173 | |1;audio-beat-detection;157;124;123;171;124;128;121;123;144;122 05:29:49 INFO - PROCESS | 7173 | |2;audio-dft;160;161;161;159;162;161;162;164;160;161 05:29:49 INFO - PROCESS | 7173 | |3;audio-fft;74;75;76;103;76;77;77;74;94;75 05:29:49 INFO - PROCESS | 7173 | |4;audio-oscillator;85;84;103;84;83;83;97;84;85;104 05:29:49 INFO - PROCESS | 7173 | |5;imaging-gaussian-blur;128;127;127;127;127;127;127;128;127;127 05:29:49 INFO - PROCESS | 7173 | |6;imaging-darkroom;145;147;145;146;146;146;145;145;146;145 05:29:49 INFO - PROCESS | 7173 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;95 05:29:49 INFO - PROCESS | 7173 | |8;json-parse-financial;53;52;52;53;52;54;53;53;53;54 05:29:49 INFO - PROCESS | 7173 | |9;json-stringify-tinderbox;63;63;62;63;62;62;62;62;62;62 05:29:49 INFO - PROCESS | 7173 | |10;stanford-crypto-aes;84;89;84;85;91;86;89;86;113;86 05:29:49 INFO - PROCESS | 7173 | |11;stanford-crypto-ccm;143;130;132;131;131;131;131;135;150;137 05:29:49 INFO - PROCESS | 7173 | |12;stanford-crypto-pbkdf2;175;166;166;170;167;167;165;165;167;166 05:29:49 INFO - PROCESS | 7173 | |13;stanford-crypto-sha256-iterative;65;65;69;64;66;64;65;65;67;62 05:29:49 INFO - PROCESS | 7173 | __end_tp_report 05:29:49 INFO - PROCESS | 7173 | __start_cc_report 05:29:49 INFO - PROCESS | 7173 | _x_x_mozilla_cycle_collect,1665 05:29:49 INFO - PROCESS | 7173 | __end_cc_report 05:29:49 INFO - PROCESS | 7173 | __startTimestamp1481290189443__endTimestamp 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | ------- Summary: start ------- 05:29:49 INFO - PROCESS | 7173 | Number of tests: 14 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:0.85 (0.8%) stddev-sans-first:0.71 05:29:49 INFO - PROCESS | 7173 | Values: 111.0 113.0 113.0 112.0 114.0 112.0 112.0 113.0 113.0 112.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:126.00 stddev:17.60 (14.0%) stddev-sans-first:16.53 05:29:49 INFO - PROCESS | 7173 | Values: 157.0 124.0 123.0 171.0 124.0 128.0 121.0 123.0 144.0 122.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.37 (0.9%) stddev-sans-first:1.39 05:29:49 INFO - PROCESS | 7173 | Values: 160.0 161.0 161.0 159.0 162.0 161.0 162.0 164.0 160.0 161.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#3] audio-fft Cycles:10 Average:80.10 Median:76.00 stddev:9.98 (13.1%) stddev-sans-first:10.34 05:29:49 INFO - PROCESS | 7173 | Values: 74.0 75.0 76.0 103.0 76.0 77.0 77.0 74.0 94.0 75.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:84.00 stddev:8.59 (10.2%) stddev-sans-first:8.97 05:29:49 INFO - PROCESS | 7173 | Values: 85.0 84.0 103.0 84.0 83.0 83.0 97.0 84.0 85.0 104.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#5] imaging-gaussian-blur Cycles:10 Average:127.20 Median:127.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 05:29:49 INFO - PROCESS | 7173 | Values: 128.0 127.0 127.0 127.0 127.0 127.0 127.0 128.0 127.0 127.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 05:29:49 INFO - PROCESS | 7173 | Values: 145.0 147.0 145.0 146.0 146.0 146.0 145.0 145.0 146.0 145.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 05:29:49 INFO - PROCESS | 7173 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 05:29:49 INFO - PROCESS | 7173 | Values: 53.0 52.0 52.0 53.0 52.0 54.0 53.0 53.0 53.0 54.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:62.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 05:29:49 INFO - PROCESS | 7173 | Values: 63.0 63.0 62.0 63.0 62.0 62.0 62.0 62.0 62.0 62.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:86.00 stddev:8.64 (10.0%) stddev-sans-first:8.95 05:29:49 INFO - PROCESS | 7173 | Values: 84.0 89.0 84.0 85.0 91.0 86.0 89.0 86.0 113.0 86.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#11] stanford-crypto-ccm Cycles:10 Average:135.10 Median:133.50 stddev:6.59 (4.9%) stddev-sans-first:6.34 05:29:49 INFO - PROCESS | 7173 | Values: 143.0 130.0 132.0 131.0 131.0 131.0 131.0 135.0 150.0 137.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:167.00 stddev:3.03 (1.8%) stddev-sans-first:1.51 05:29:49 INFO - PROCESS | 7173 | Values: 175.0 166.0 166.0 170.0 167.0 167.0 165.0 165.0 167.0 166.0 05:29:49 INFO - PROCESS | 7173 | 05:29:49 INFO - PROCESS | 7173 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:1.87 (2.9%) stddev-sans-first:1.99 05:29:49 INFO - PROCESS | 7173 | Values: 65.0 65.0 69.0 64.0 66.0 64.0 65.0 65.0 67.0 62.0 05:29:49 INFO - PROCESS | 7173 | -------- Summary: end -------- 05:29:49 INFO - PROCESS | 7173 | 05:29:50 INFO - TEST-INFO | 7173: exit 0 05:29:50 INFO - TEST-OK | kraken | took 62028ms 05:29:50 INFO - SUITE-END | took 1180s 05:29:51 INFO - Completed test suite (00:19:41) 05:29:51 INFO - javascript benchmark 05:29:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3232.2888964427825, 3237.2877966847286, 3247.9350412991757, 3261.98369008155, 3312.552805371274, 5223.112070947938, 5277.076511610472, 5342.973285133578, 5363.302770639821, 5384.703841288722, 5295.258663787064, 5298.019866324733, 5315.920261196085, 5321.760520776565, 5326.414094449608, 4800.591949684281, 4837.782299796518, 4849.321095046696, 4864.5135486451245, 4877.561019508261, 4867.099586576477, 4920.458749537548, 4922.55696987271, 4927.728974906378, 4931.605471562267, 4909.140900342443, 4913.950860491385, 4941.2094064949615, 4956.529129732669, 4967.10592093423, 5000.149974504325, 5017.2724954881705, 5026.170681837492, 5026.170681837492, 5059.241113832918, 4763.428388593391, 4796.592289655384, 4807.61539076873, 4921.335619691332, 4928.507149285043, 5186.948130518685, 5230.790768369261, 5237.607179461517, 5255.710935898524, 5319.627626066177, 5597.720113994284, 5629.296337957755, 5629.549636029149, 5637.8590535236535, 5651.0, 5678.375378708339, 5685.971570142165, 5687.289088863892, 5687.488126068673, 5690.573207009491, 5208.114620514521, 5251.264822924755, 5278.815241466607, 5320.467953204726, 5320.52115309624, 5833.7083145842535, 5842.444967728144, 5843.18195452637, 5844.444777746107, 5880.764769409176, 5606.6916319602815, 5615.2700148981185, 5618.101103823368, 5618.775248989995, 5623.072193088176, 5497.202905578669, 5595.356533998561, 5659.971700141556, 5686.658800471985, 5710.143478478179, 5680.233168522279, 5721.313442386941, 5722.7710891563875, 5741.655500669973, 5761.48146666802, 5750.281214848144, 5752.539796816244, 5755.971220143872, 5757.193992841006, 5798.275215598051, 4293.377460268243, 4310.245707001262, 4311.439512863308, 4382.978085109554, 4436.401085853433, 3971.62269584389, 4001.3997900314603, 4020.4170395292517, 4024.899377515527, 4032.6169013943627, 5444.618876678674, 5448.046591846411, 5449.972750136303, 5452.236686863842, 5455.509004189642, 5546.889062218733, 5593.888122237614, 5681.2330335404195, 5721.713914304268, 5743.597948143673, 5438.700871452108, 5480.150576660624, 5491.0, 5515.255440515479, 5551.361593416728, 5005.424376196711, 5010.674306170087, 5013.423456302499, 5018.096742586369, 5058.772355244023, 5964.075568286922, 5968.22413086296, 5969.4777831652655, 5977.880442391215, 6026.427489388501, 5537.0, 5584.218209450599, 5695.430456954271, 5717.085266357363, 5792.160136780059, 5950.047992321381, 5956.076808094752, 5956.970215148896, 5998.310194327708, 6013.098035294741, 5560.5551555876245, 5661.207430959751, 5665.03694371966, 5676.318841739017, 5677.602567820213, 5580.525655319343, 5636.1545768133465, 5673.716314184357, 5760.279965004374, 5766.192733017297, 5595.720213989202, 5639.943600563942, 5645.209670646195, 5687.516561092188, 5693.4875861172695, 5618.915716264341, 5627.690477023639, 5628.521575666115, 5632.9436705632415, 5633.492985631313, 5308.150695888639, 5545.805896793593, 5557.499825015768, 5560.916586251128, 5607.299087614048, 5399.78400863961, 5575.553955683455, 5580.079286917613, 5580.497755201889, 5584.274044374205, 5409.269748584048, 5462.371827239918, 5463.672179669233, 5466.781328746805, 5467.7266136692215], "value": 5285.680764517038, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.5436360638867, 576.538768984812, 578.3320265093813, 582.9825105246839, 602.5571205164206, 30300.24249393766, 37982.25221847269, 39629.99999999996, 39900.201995960066, 44292.778536107355, 42109.789451052704, 42379.3643095353, 42565.0, 42579.36130958038, 42732.786336068355, 7762.184970578092, 8465.111163327869, 8651.0, 8656.523891186014, 8698.47809131451, 7778.766637000899, 9919.355241909252, 10157.898421015769, 10160.237982151368, 10500.2649814513, 10246.333988290737, 10280.383177009404, 10291.125254353388, 10291.176705863512, 10292.382457052563, 10191.082802547731, 10215.744606384826, 10256.487175641263, 10299.53652085658, 10303.175745940382, 10080.596776128947, 10124.190064794799, 10147.0, 10149.89850101497, 10152.03555662211, 39954.60045399538, 40306.0, 40311.39532907009, 40333.19333613341, 40358.0, 3052.923676908073, 3126.0621813455873, 3239.4654881944452, 3385.458326667746, 3452.3613131570487, 30131.849340753157, 30812.0, 30833.0, 30882.845585771927, 30962.225944351347, 3919.2161567686294, 3990.7206495545324, 4048.5344185418758, 4189.560096189886, 4257.978710106429, 334890.0, 335668.0, 335839.0, 336874.3156284227, 337554.0, 55575.82998805233, 55786.0, 56066.439335606934, 56312.43687563154, 56326.436735632116, 59647.40352596418, 59698.70150649219, 59702.0, 59809.10286345708, 59812.700936495035, 15467.303971321318, 15690.607734806721, 15703.293351799197, 15708.68582628364, 15716.92141539285, 10732.248742587944, 10851.457427128613, 11121.443927803577, 11565.421728913521, 11593.0, 16201.351945922032, 16749.832501674828, 16808.915955420143, 16813.747793783103, 17073.48779536616, 5861.17943487912, 5884.676342801102, 5989.970050149721, 5996.850078748066, 6173.84565385869, 846.6994217052858, 953.6709835106877, 964.5900492290889, 982.4547376206132, 997.5062344139698, 30887.69112308848, 31372.372552549277, 31789.840637450197, 31825.8408707955, 32007.0, 1049.2078480746986, 1057.8783439904355, 1080.1682704317789, 1106.303029091667, 1135.5287555664472, 15869.060316384535, 16321.673566528603, 16544.75182872258, 16559.586010349598, 16656.500304990626, 463751.7686880943, 467121.0, 468466.0, 469489.0, 470551.0, 55734.44265557374, 55957.440425595225, 55981.16028259498, 56070.71964640151, 56088.43911560914, 1164.4352489042808, 1192.8031874740743, 1221.1818081885158, 1255.9748805023848, 1258.383392137846, 29932.700672993862, 32433.189170270933, 32477.0, 32541.0, 32959.176020599676, 47398.0, 48028.0, 48094.75952620214, 48121.278180826615, 48139.75930120327, 358767.0, 359940.0, 360012.0, 360078.0, 360340.0, 24570.0, 25113.497730045663, 25159.622605661294, 26774.866125669247, 39736.2052758949, 40200.79960916779, 40483.3927491082, 40795.59204407918, 41649.16701665889, 41901.371479428446, 33013.83493082519, 33168.1707957303, 34436.65563344335, 34709.65290347165, 34916.30167396587, 33635.49546756849, 33884.3223135531, 34197.658023420445, 34608.48087278642, 34727.0, 35258.29483410369, 36093.45859812157, 36111.81944090368, 36240.81879590585, 36318.63681363153, 30367.39265214639, 31698.207544811565, 31777.20556986094, 31850.522242165924, 32222.19444513906, 24722.50554988926, 25033.74966250387, 25171.87414062918, 25572.20994530365, 25671.229863104138], "value": 18944.931417981625, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.83942225998305, 375.59435809325913, 385.926673931953, 393.1233349630324, 413.18808541216504, 13996.02027858049, 17338.393156239534, 17603.47189584313, 18170.454886353396, 19150.808491915075, 18300.816991830077, 18824.529386765338, 19350.12924418401, 19351.0, 19473.0, 5859.267591551045, 6150.077488376734, 6155.446009859112, 6160.291566469846, 6224.751009959597, 5916.3787802280785, 6083.239595050619, 6083.878322433571, 6092.81721548352, 6100.481459075984, 6082.209312789331, 6087.4825639820665, 6096.993995990656, 6102.664353460558, 6105.20632317798, 5813.244278243822, 6120.877582448348, 6161.19904412428, 6174.691265436755, 6175.197224360849, 5846.941530584682, 5935.37678543751, 6002.189704389895, 6007.128966299906, 6010.218671572711, 23200.0, 23520.882395587912, 23879.403014924592, 24044.158454454184, 24197.758022419726, 2390.5935990881594, 2433.634954756783, 2758.3104223944015, 2857.8713957871946, 3123.890663826778, 14423.0, 14642.487512937103, 15005.0, 15048.924755376263, 15693.529194124194, 1956.1197461142401, 1992.0776450724975, 1993.2027189124321, 1998.5703073839195, 2069.623700239339, 84549.15450845474, 86692.56653716754, 86766.5661671694, 87512.0, 88770.81261027948, 16531.66936661284, 16678.749818752727, 16730.749038764185, 16975.74536381955, 17367.652646947245, 28879.855600722283, 28898.27754306159, 29003.96972211967, 29024.0, 29100.41799164005, 8615.310775137974, 8746.68799680053, 9019.0, 9031.729048128567, 9075.410098343587, 7209.2195777654115, 7680.462367634212, 7716.729914452967, 7858.528488290606, 7983.880241796378, 8056.677732890736, 8241.299489543337, 8599.5700214989, 8601.698940537051, 8624.792529045939, 3223.3069889973767, 3315.9133126934985, 3374.6794054564775, 3427.1089516725333, 3507.0, 822.7120507822215, 965.0494263150791, 978.2369751593768, 985.4875464758286, 996.7988573768591, 17462.388816391365, 18324.908375458304, 18513.81486185121, 18580.721289180674, 18885.0, 899.7255836969656, 904.8461761500561, 913.1142791492242, 922.7831459606987, 952.4999375328008, 9710.75723106928, 10300.587976480856, 10313.174946004301, 10488.265821392582, 10510.73723156912, 390943.0, 399637.0, 400320.0, 401396.0, 411106.0, 67947.0, 71867.2813271871, 84777.57611212028, 90422.0, 90943.54528227316, 893.2407453664463, 922.9446233225893, 945.4847108325841, 946.394307643109, 948.4972964328916, 16498.84618247696, 16501.504954851374, 16756.413525526546, 17404.564885877953, 17714.379996700052, 38664.226715466095, 39461.80269098636, 39686.40470393004, 39919.80040099781, 39986.0, 54097.72951135377, 54201.0, 55246.44753552574, 55809.72095139661, 56032.43967560435, 7582.696692132253, 7643.350315223268, 7671.8849217262905, 7841.764747057598, 8087.312578430664, 12090.455929483018, 12163.635090947286, 12235.93882030584, 12280.125429296842, 12813.93593032029, 9672.661456848977, 10033.347832390871, 10092.34399764013, 10383.117435018108, 11315.490802914053, 9926.404415735078, 10104.545295461576, 10338.0, 10579.10077643409, 10592.523336450033, 10447.895521044997, 10590.099841513558, 10663.253572250178, 10912.781744365227, 11112.888871111509, 7631.809204769926, 7797.961010194913, 7901.328387087162, 7930.920690793249, 7968.721094761656, 5204.895902081861, 9352.17632561644, 10113.14038306752, 10407.583696652351, 10469.267151299337], "value": 9609.21236663484, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.2111412038648, 1376.7728324826405, 1390.0547627613225, 1483.2361333913036, 1709.7093494106, 7410.11078670559, 8059.153788852167, 8345.582720863948, 8353.665853365866, 8527.78680532987, 8298.70954516592, 8393.958030209855, 8399.0, 8407.24334809867, 8408.0, 2155.2133471282987, 2195.2206966526815, 2198.329509499602, 2199.8350123740784, 2199.9340019799433, 1935.525796179931, 1989.0, 2081.750189977205, 2128.3082998025625, 2138.112683236455, 2137.070374387147, 2144.667576525633, 2151.95696086079, 2152.7201463809683, 2152.7524334701475, 2138.700581918533, 2203.6584329429043, 2211.1708109458955, 2211.7677643847487, 2213.6347502662043, 2149.6882951971875, 2153.95692086159, 2159.6328624133935, 2169.913203471859, 2173.3588591365583, 8243.917560824435, 8387.790305242357, 8422.073571907085, 8443.240108390275, 8456.57717114148, 5031.949680503185, 5120.0, 5162.070827251093, 5175.094358487249, 5183.870403239912, 3036.104349216986, 3208.951865722016, 3260.3642289753507, 3271.2149084219855, 3303.587051618548, 1016.4866742295168, 1080.4111759091236, 1081.810683130457, 1111.9110471162287, 1115.1023426141953, 11636.301821890713, 11636.418179091012, 11661.766764664659, 11663.766724665544, 11732.12009099321, 4243.575642435793, 4565.0641618468135, 4581.427321584802, 4595.563421474954, 4637.907241855144, 9805.558749856273, 9821.263405244636, 9824.803503930025, 9828.4102953823, 9835.213182945346, 5090.414602320707, 5196.558292545176, 5199.376074870964, 5202.323697919246, 5204.8178313758835, 4585.234684646435, 4720.858374248778, 4760.571548560646, 4762.9523704762505, 4765.761711914391, 3629.6551827576336, 3768.962310376916, 3771.642208804828, 3841.0, 3859.3246181918053, 1149.8217776244696, 1155.0759392485977, 1168.6085161470833, 1171.2386948483384, 1192.9522819087313, 690.6719308328504, 727.8071311102563, 739.7928579997609, 893.1515060692238, 910.7131253655077, 5412.83761487156, 5427.6200665953, 5452.672839629556, 5550.72246387679, 5582.441755824385, 437.21956307990087, 437.9693421460467, 441.401900424926, 441.5231549926072, 442.87156724550164, 3241.33552621712, 3462.2210002749684, 3540.946885796715, 3543.574771027493, 3575.606683264839, 9803.411795292306, 10407.375557466576, 10423.635172768916, 10427.374357538572, 10429.582816687247, 10382.117520010883, 10384.584616615402, 10401.375917444977, 10407.58369665205, 10409.53157107932, 7744.070711514654, 7782.416318776114, 7790.766277011586, 7883.2116788321855, 7888.447808653339, 5352.5450336721815, 5369.543588794996, 5371.919421208763, 5393.7033463159905, 5439.347278326626, 9406.341556091007, 9438.339316248072, 9458.148766611039, 9470.763230919007, 9599.040095990624, 9028.27773778083, 9381.390209636353, 9405.341626086109, 9561.187299079656, 9659.9034009659, 6667.466602671678, 6856.760013399707, 7087.574745515286, 7226.277372262732, 7296.7446139384865, 7839.294463498312, 8028.87956680662, 8149.266566009088, 8155.306798922158, 8188.344932405274, 7034.613096279549, 7124.893126603209, 7133.536320139175, 7236.276372362721, 7290.744823931349, 7166.247544007804, 7225.710971561079, 7229.530080544748, 7264.237255088351, 7321.926780732125, 6680.665966701548, 6778.796636100925, 6783.694733737092, 6809.080774095632, 6816.250212476524, 4439.778011099367, 4566.70316429431, 4773.66584339093, 4823.927641085456, 4876.317315575893, 4550.385697930737, 4611.700239484423, 4666.300054991779, 4713.62125587589, 4719.858404247878], "value": 4256.675081760543, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.9038304849215, 376.21746766725204, 380.36099353086814, 383.58189573365024, 390.61524398467503, 5070.974645126778, 5205.334293026223, 5289.89420211596, 5291.4973077557615, 5293.232481290211, 5049.24261360796, 5126.692693363197, 5128.794848206067, 5166.948330516694, 5175.0, 4062.5531191568903, 4096.508418989718, 4101.282275601773, 4106.137711080677, 4107.260693075245, 3989.501312335958, 4109.342505199169, 4112.547619761824, 4121.155163191538, 4136.214119317335, 4051.3315302974975, 4064.207479541476, 4068.1456894052135, 4070.7354021988476, 4091.6931230157707, 4046.858359957402, 4054.0, 4059.1272876331436, 4067.3085575452246, 4078.3066878630702, 3588.8923332300074, 3590.701950751521, 3595.442706380515, 3597.262561174979, 3606.6934310583633, 4954.479779623124, 4981.430849282465, 5034.565148932573, 5043.949560504421, 5082.3138876251605, 4545.886352841172, 4558.840440584597, 4590.380298659672, 4612.331211974279, 4620.561046700558, 5341.824798544314, 5352.598555108343, 5359.410464848864, 5391.730413479349, 5403.216533602645, 3818.369968955119, 3887.4751908492276, 3897.9805100974313, 3912.9413058803857, 3925.8233379497706, 5531.197976293415, 5603.411641777596, 5634.4365563443325, 5637.943620563824, 5649.435056494359, 3941.428492868575, 3985.123272879962, 3989.401589761559, 4007.6393124618926, 4013.0, 3845.1732877431486, 3993.84024639017, 4010.1177740897538, 4064.3903414488063, 4071.206114807643, 4546.363509108727, 4549.09018196362, 4594.793234304464, 4601.72389656615, 4610.377599024089, 5421.728913554307, 5460.808871689472, 5461.808836690776, 5467.633091727068, 5480.424555421703, 1271.4469205895466, 1299.7140629061591, 1314.704191556892, 1357.362039841273, 1360.7958806179151, 930.50683137937, 940.5767404667927, 949.7558198759647, 952.5713428956858, 970.1947383671536, 5185.222216667455, 5186.5850731941355, 5197.272381866542, 5204.947950520446, 5219.042858927902, 5495.642783219078, 5500.58745594082, 5503.679117011889, 5514.6139770216505, 5516.889662206733, 4855.053264613437, 5104.3364362633365, 5105.8723531912, 5194.6104042196985, 5197.948020519746, 2838.602595636571, 2858.8999385021425, 2886.7401933825636, 2892.4359749848577, 2898.62317898676, 5525.557955363562, 5550.86122846924, 5561.860953476195, 5584.86037849057, 5610.579206559524, 5341.940125504644, 5490.56075513958, 5499.7250137492965, 5500.972495137498, 5525.568749750097, 5549.556035517149, 5559.72201389937, 5563.693996830132, 5564.4713752193475, 5576.97211513948, 5236.869078273074, 5373.758180881948, 5383.703896285742, 5395.379531353945, 5418.3497980241045, 5412.511559321219, 5414.29614150158, 5439.809606663748, 5449.509544141047, 5449.591280653887, 5550.361708403585, 5574.219609254788, 5578.804741834017, 5585.5531557476115, 5586.692731899784, 3786.9775160707004, 3818.503594532693, 3848.4804551385205, 3904.316744569632, 3907.531096268466, 5310.840674779763, 5330.733463326896, 5351.866203344947, 5387.272718182995, 5406.459354064562, 5377.462253774591, 5389.487998640123, 5474.7810087597645, 5483.177523371526, 5490.972545137249, 5442.156465747816, 5471.425500322409, 5483.369412517612, 5487.4238204987905, 5492.203630473638, 5107.744612769272, 5394.0, 5418.45815418455, 5424.728763561885, 5434.945650543444, 5501.312335958005, 5521.420250873601, 5566.385748133158, 5573.801632649, 5585.776568937197, 5444.156155795858, 5457.6179667424485, 5465.426130256425, 5469.398366179637, 5472.124460086367], "value": 4136.0145001305855, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [607.5807692692044, 649.4998850884818, 662.678600878574, 760.9536886780677, 776.7320274505296, 14994.250287485624, 16362.918185409071, 16799.076050817217, 17355.95864248146, 17726.468205953843, 15285.77071343928, 15595.454159104434, 15724.79852820606, 16170.514884553482, 16441.67116657666, 778.4122987144698, 796.9681212751483, 798.5129071266551, 798.884161796542, 801.1107670485785, 659.676758388391, 676.5669971218424, 770.6262462705583, 792.43341011177, 796.0049937578027, 816.0900595835631, 818.705266104205, 823.8352329534117, 827.0819390476566, 827.4993628854517, 816.5549775372436, 817.7342363731751, 819.0826274572457, 819.6229734322219, 819.6885183630212, 505.0479845491239, 516.7338820507441, 521.6895946911619, 600.7867207141445, 603.6981509245377, 11489.770204595945, 13823.239721815296, 14193.503227387095, 14202.928985355007, 14690.0, 15142.772858407132, 15188.088714677155, 15213.163276019815, 15280.465183718516, 15327.693446131016, 11702.473388697445, 11798.174127811057, 11849.111316651199, 11933.16467847251, 12308.07689423288, 3644.125409901631, 3691.870784522556, 3738.065483629093, 3835.4438606402196, 3837.175007373428, 73575.0, 75254.62372688156, 77644.0, 77995.6100219501, 79261.0, 11565.363904985139, 11641.708957275967, 11646.76706465866, 11768.117391195694, 11943.343116128523, 22193.11227550912, 22366.888165559067, 22370.217042403765, 22557.210497632834, 22693.546129077655, 7964.1637628049375, 8109.472884262504, 8268.793280168044, 8312.418130730912, 8417.579121043924, 4174.686898482626, 4228.82976873586, 4338.262495375794, 4356.386168299058, 4456.331550267486, 3406.267652454734, 3446.3968805458944, 3459.809710465898, 3484.5295885055193, 3485.111296619346, 547.6057238788067, 548.4926443040172, 553.3525774843442, 560.1597603594608, 565.0337922153082, 664.0, 671.9798406047827, 689.396777819408, 698.7938558125263, 701.6000879498637, 8933.955330223307, 8934.50860202695, 9017.729468115967, 9650.71047868551, 9685.903140968641, 562.0894151474637, 577.1400613086471, 599.235974132984, 630.152444961526, 684.4524380495683, 3986.6013398660484, 4482.856871497747, 4541.2733962565835, 4583.816647334069, 4681.625469962463, 74674.0, 74954.75497695249, 76094.0, 79247.0, 79510.0, 67444.3255567438, 67648.3235167642, 67870.66064669743, 68040.6597967007, 68391.99999999901, 60640.0, 60685.0, 61049.38950610526, 61519.38480615226, 63200.0, 8126.715564955198, 8175.667584720431, 8345.457545259782, 8485.278751305961, 8606.78483037929, 41835.16329673451, 42255.577444226394, 42486.57513424826, 42772.0, 43037.35443968406, 50918.745406272734, 52446.70639523282, 55528.0, 58255.0, 59935.40064599473, 10899.237053406185, 11018.724531886766, 11215.887841121485, 11617.0, 11757.412129393326, 12812.231266124063, 13071.346432678518, 13115.606531804062, 13587.796183057064, 13899.305034748424, 11976.0, 12465.127441079037, 12836.935815320863, 13046.54337098235, 13291.734165316833, 11295.491702873554, 11927.522899083939, 11966.0, 11999.880001199876, 12187.390630468264, 11341.376224307742, 11634.70913227176, 11772.05823534098, 11989.76020479603, 12195.146339756131, 8860.689875854314, 9174.541272936192, 9234.215091717278, 9364.812703746024, 9499.525023748924, 10463.801186781066, 10542.631007914993, 10912.454377281263, 10950.45247737594, 11028.0], "value": 6887.070976782064, "unit": "score"}], "name": "dromaeo_css", "value": 6990.411546240185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 113.0, 112.0, 114.0, 112.0, 112.0, 113.0, 113.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 124.0, 123.0, 171.0, 124.0, 128.0, 121.0, 123.0, 144.0, 122.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 159.0, 162.0, 161.0, 162.0, 164.0, 160.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 103.0, 76.0, 77.0, 77.0, 74.0, 94.0, 75.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 103.0, 84.0, 83.0, 83.0, 97.0, 84.0, 85.0, 104.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0, 128.0, 127.0, 127.0], "value": 127.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 53.0, 52.0, 54.0, 53.0, 53.0, 53.0, 54.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 84.0, 85.0, 91.0, 86.0, 89.0, 86.0, 113.0, 86.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 130.0, 132.0, 131.0, 131.0, 131.0, 131.0, 135.0, 150.0, 137.0], "value": 135.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 166.0, 166.0, 170.0, 167.0, 167.0, 165.0, 165.0, 167.0, 166.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 69.0, 64.0, 66.0, 64.0, 65.0, 65.0, 67.0, 62.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1516.7, "alertThreshold": 2.0}]} 05:29:51 INFO - Return code: 0 05:29:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:29:51 INFO - # TBPL SUCCESS # 05:29:51 INFO - Running post-action listener: _resource_record_post_action 05:29:51 INFO - [mozharness: 2016-12-09 13:29:51.270925Z] Finished run-tests step (success) 05:29:51 INFO - Running post-run listener: _resource_record_post_run 05:29:51 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393351168; Read time: 16; Write time: 217840 05:29:51 INFO - TinderboxPrint: CPU usage
13.2% 05:29:51 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 05:29:51 INFO - TinderboxPrint: I/O write bytes / time
393,351,168 / 217,840 05:29:51 INFO - TinderboxPrint: CPU idle
8,293.1 (86.8%) 05:29:51 INFO - TinderboxPrint: CPU user
1,212.5 (12.7%) 05:29:51 INFO - TinderboxPrint: Swap in / out
0 / 0 05:29:51 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 104120320; Read time: 0; Write time: 50952 05:29:51 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 289230848; Read time: 0; Write time: 166888 05:29:51 INFO - Running post-run listener: _upload_blobber_files 05:29:51 INFO - Blob upload gear active. 05:29:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:29:52 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:29:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:29:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:56 INFO - (blobuploader) - INFO - Done attempting. 05:29:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:29:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:29:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:57 INFO - (blobuploader) - INFO - Done attempting. 05:29:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:29:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:58 INFO - (blobuploader) - INFO - Done attempting. 05:29:58 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:58 INFO - Return code: 0 05:29:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4ecfb2a5966ac2117041b26932fca427507fc9df7d2cdeb21c62b7106a3a160413c0cc89e4ff7f1aca011daeb889f5586089ea6c1edb475ac1594231bd6908e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e9918f08940a34b3ce3b8e3d32eee92b914d549008c93a38fcee7fbb651aae18776cf69e6136fd67d0a58dae9e9a813a58f6059c84c13b194f1c56be33420d23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/461975fee576b9ef69e932ca127ef60f311e3b84ab8ed11cb89061f722e39ca4760d0e816794e657562c3a9a4b4566bab861d0ecb712712f98966880677b3ce4"} 05:29:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:58 INFO - Contents: 05:29:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4ecfb2a5966ac2117041b26932fca427507fc9df7d2cdeb21c62b7106a3a160413c0cc89e4ff7f1aca011daeb889f5586089ea6c1edb475ac1594231bd6908e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e9918f08940a34b3ce3b8e3d32eee92b914d549008c93a38fcee7fbb651aae18776cf69e6136fd67d0a58dae9e9a813a58f6059c84c13b194f1c56be33420d23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/461975fee576b9ef69e932ca127ef60f311e3b84ab8ed11cb89061f722e39ca4760d0e816794e657562c3a9a4b4566bab861d0ecb712712f98966880677b3ce4"} 05:29:58 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:58 INFO - Copying logs to upload dir... 05:29:58 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.179246 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-12-09 05:29:58.351538) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-12-09 05:29:58.358244) ========= 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=db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876 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/4ecfb2a5966ac2117041b26932fca427507fc9df7d2cdeb21c62b7106a3a160413c0cc89e4ff7f1aca011daeb889f5586089ea6c1edb475ac1594231bd6908e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e9918f08940a34b3ce3b8e3d32eee92b914d549008c93a38fcee7fbb651aae18776cf69e6136fd67d0a58dae9e9a813a58f6059c84c13b194f1c56be33420d23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/461975fee576b9ef69e932ca127ef60f311e3b84ab8ed11cb89061f722e39ca4760d0e816794e657562c3a9a4b4566bab861d0ecb712712f98966880677b3ce4"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011683 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481283050/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/4ecfb2a5966ac2117041b26932fca427507fc9df7d2cdeb21c62b7106a3a160413c0cc89e4ff7f1aca011daeb889f5586089ea6c1edb475ac1594231bd6908e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e9918f08940a34b3ce3b8e3d32eee92b914d549008c93a38fcee7fbb651aae18776cf69e6136fd67d0a58dae9e9a813a58f6059c84c13b194f1c56be33420d23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/461975fee576b9ef69e932ca127ef60f311e3b84ab8ed11cb89061f722e39ca4760d0e816794e657562c3a9a4b4566bab861d0ecb712712f98966880677b3ce4"}' ========= master_lag: 1.96 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-12-09 05:30:00.331231) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 05:30:00.331573) ========= 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=db0483c52d987ecb441a263b000001ad-1481284448.7240-172071876 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 05:30:00.348011) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 05:30:00.349045) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 05:30:00.349315) ========= ========= Total master_lag: 2.24 =========