builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1482369421.11 results: success (0) buildid: 20161221171648 builduid: 85e3580d2cb146e993921e21e250f086 revision: d9d75cfe7af3c6d3aa5aba56cb72b976d5ab98c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.111687) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.112378) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.112937) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.128082) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.128382) ========= 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=df17445c3590ff7897617a0e00000225-1482369270.228303-794034786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-21 17:17:01-- 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% 238M=0s 2016-12-21 17:17:02 (238 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133016 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.275640) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.275951) ========= 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=df17445c3590ff7897617a0e00000225-1482369270.228303-794034786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022471 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.325970) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.326329) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d9d75cfe7af3c6d3aa5aba56cb72b976d5ab98c3 --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 d9d75cfe7af3c6d3aa5aba56cb72b976d5ab98c3 --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=df17445c3590ff7897617a0e00000225-1482369270.228303-794034786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-21 17:17:02,192 truncating revision to first 12 chars 2016-12-21 17:17:02,192 Setting DEBUG logging. 2016-12-21 17:17:02,193 attempt 1/10 2016-12-21 17:17:02,193 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/d9d75cfe7af3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 17:17:02,523 unpacking tar archive at: mozilla-inbound-d9d75cfe7af3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.643264 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.983328) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.983634) ========= script_repo_revision: d9d75cfe7af3c6d3aa5aba56cb72b976d5ab98c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.984055) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.984330) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 17:17:01.993568) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-21 17:17:01.993913) ========= /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=df17445c3590ff7897617a0e00000225-1482369270.228303-794034786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:17:02 INFO - MultiFileLogger online at 20161221 17:17:02 in /builds/slave/test 17:17:02 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 17:17:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:17:02 INFO - {'append_to_log': False, 17:17:02 INFO - 'base_work_dir': '/builds/slave/test', 17:17:02 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 17:17:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:17:02 INFO - 'branch': 'Mozilla-Inbound', 17:17:02 INFO - 'buildbot_json_path': 'buildprops.json', 17:17:02 INFO - 'config_files': ('talos/linux_config.py',), 17:17:02 INFO - 'default_actions': ('clobber', 17:17:02 INFO - 'read-buildbot-config', 17:17:02 INFO - 'download-and-extract', 17:17:02 INFO - 'populate-webroot', 17:17:02 INFO - 'create-virtualenv', 17:17:02 INFO - 'install', 17:17:02 INFO - 'run-tests'), 17:17:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:17:02 INFO - 'download_minidump_stackwalk': True, 17:17:02 INFO - 'download_symbols': 'ondemand', 17:17:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:17:02 INFO - 'tooltool.py': '/tools/tooltool.py', 17:17:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:17:02 INFO - '/tools/misc-python/virtualenv.py')}, 17:17:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:17:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:17:02 INFO - 'installer_path': 'installer.exe', 17:17:02 INFO - 'log_level': 'info', 17:17:02 INFO - 'log_name': 'talos', 17:17:02 INFO - 'log_to_console': True, 17:17:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:17:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:17:02 INFO - 'opt_config_files': (), 17:17:02 INFO - 'pip_index': False, 17:17:02 INFO - 'sps_profile': False, 17:17:02 INFO - 'sps_profile_interval': 0, 17:17:02 INFO - 'suite': 'dromaeojs', 17:17:02 INFO - 'system_bits': '32', 17:17:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:17:02 INFO - 'title': 'talos-linux64-ix-021', 17:17:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:17:02 INFO - 'use_talos_json': True, 17:17:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:17:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:17:02 INFO - 'work_dir': 'build'} 17:17:02 INFO - [mozharness: 2016-12-22 01:17:02.955833Z] Running clobber step. 17:17:02 INFO - Running pre-action listener: _resource_record_pre_action 17:17:02 INFO - Running main action method: clobber 17:17:02 INFO - rmtree: /builds/slave/test/build 17:17:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:17:03 INFO - Running post-action listener: _resource_record_post_action 17:17:03 INFO - [mozharness: 2016-12-22 01:17:03.424033Z] Finished clobber step (success) 17:17:03 INFO - [mozharness: 2016-12-22 01:17:03.424136Z] Running read-buildbot-config step. 17:17:03 INFO - Running pre-action listener: _resource_record_pre_action 17:17:03 INFO - Running main action method: read_buildbot_config 17:17:03 INFO - Using buildbot properties: 17:17:03 INFO - { 17:17:03 INFO - "project": "", 17:17:03 INFO - "product": "firefox", 17:17:03 INFO - "script_repo_revision": "production", 17:17:03 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 17:17:03 INFO - "repository": "", 17:17:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 17:17:03 INFO - "buildid": "20161221171648", 17:17:03 INFO - "stage_platform": "linux64-pgo", 17:17:03 INFO - "basedir": "/builds/slave/test", 17:17:03 INFO - "buildnumber": 2322, 17:17:03 INFO - "platform": "ubuntu64_hw", 17:17:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:17:03 INFO - "slavebuilddir": "test", 17:17:03 INFO - "branch": "mozilla-inbound", 17:17:03 INFO - "revision": "d9d75cfe7af3c6d3aa5aba56cb72b976d5ab98c3", 17:17:03 INFO - "slavename": "talos-linux64-ix-021", 17:17:03 INFO - "builduid": "85e3580d2cb146e993921e21e250f086", 17:17:03 INFO - "repo_path": "integration/mozilla-inbound" 17:17:03 INFO - } 17:17:03 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 17:17:03 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 17:17:03 INFO - Running post-action listener: _resource_record_post_action 17:17:03 INFO - [mozharness: 2016-12-22 01:17:03.426857Z] Finished read-buildbot-config step (success) 17:17:03 INFO - [mozharness: 2016-12-22 01:17:03.426947Z] Running download-and-extract step. 17:17:03 INFO - Running pre-action listener: _resource_record_pre_action 17:17:03 INFO - Running main action method: download_and_extract 17:17:03 INFO - mkdir: /builds/slave/test/build/tests 17:17:03 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')]} 17:17:03 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:17:03 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/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 17:17:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/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 17:17:03 INFO - Downloaded 1361 bytes. 17:17:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:17:03 INFO - Using the following test package requirements: 17:17:03 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 17:17:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:17:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:17:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:03 INFO - u'jsshell-linux-x86_64.zip'], 17:17:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:17:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 17:17:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:17:03 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:17:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:17:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:17:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:17:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:17:03 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/1482363302/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 17:17:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:17:03 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:17:03 INFO - Expected file size: 21826109 17:17:03 INFO - Obtained file size: 21826109 17:17:08 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 17:17:08 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/1482363302/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 17:17:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:17:08 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:17:08 INFO - Expected file size: 21826109 17:17:08 INFO - Obtained file size: 21826109 17:17:12 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/1482363302/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 17:17:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:17:12 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 17:17:15 INFO - Expected file size: 14409734 17:17:15 INFO - Obtained file size: 14409734 17:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:15 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:17:15 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/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 17:17:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/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 17:17:20 INFO - Downloaded 61375647 bytes. 17:17:20 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:17:20 INFO - mkdir: /builds/slave/test/properties 17:17:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:17:20 INFO - Writing to file /builds/slave/test/properties/build_url 17:17:20 INFO - Contents: 17:17:20 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:17:21 INFO - Running post-action listener: _resource_record_post_action 17:17:21 INFO - Running post-action listener: set_extra_try_arguments 17:17:21 INFO - [mozharness: 2016-12-22 01:17:21.135478Z] Finished download-and-extract step (success) 17:17:21 INFO - [mozharness: 2016-12-22 01:17:21.135706Z] Running populate-webroot step. 17:17:21 INFO - Running pre-action listener: _resource_record_pre_action 17:17:21 INFO - Running main action method: populate_webroot 17:17:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:17:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:17:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:17:21 INFO - '--tppagecycles', 17:17:21 INFO - '7'], 17:17:21 INFO - 'tests': ['tsvgm']}}, 17:17:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:17:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:17:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:17:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:17:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:17:21 INFO - 'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:17:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:17:21 INFO - 'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['damp', 'tps']}, 17:17:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:17:21 INFO - 'tests': ['damp', 'tps']}, 17:17:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['dromaeo_dom']}, 17:17:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:17:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['basic_compositor_video']}, 17:17:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:17:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['a11yr', 17:17:21 INFO - 'ts_paint', 17:17:21 INFO - 'tpaint', 17:17:21 INFO - 'sessionrestore', 17:17:21 INFO - 'sessionrestore_no_auto_restore', 17:17:21 INFO - 'tabpaint']}, 17:17:21 INFO - 'other-e10s': {'tests': ['a11yr', 17:17:21 INFO - 'ts_paint', 17:17:21 INFO - 'tpaint', 17:17:21 INFO - 'sessionrestore', 17:17:21 INFO - 'sessionrestore_no_auto_restore', 17:17:21 INFO - 'tabpaint']}, 17:17:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:17:21 INFO - 'ts_paint', 17:17:21 INFO - 'tpaint', 17:17:21 INFO - 'sessionrestore', 17:17:21 INFO - 'sessionrestore_no_auto_restore', 17:17:21 INFO - 'tabpaint']}, 17:17:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:17:21 INFO - 'ts_paint', 17:17:21 INFO - 'tpaint', 17:17:21 INFO - 'sessionrestore', 17:17:21 INFO - 'sessionrestore_no_auto_restore', 17:17:21 INFO - 'tabpaint']}, 17:17:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['a11yr', 17:17:21 INFO - 'ts_paint', 17:17:21 INFO - 'tpaint', 17:17:21 INFO - 'sessionrestore', 17:17:21 INFO - 'sessionrestore_no_auto_restore', 17:17:21 INFO - 'tabpaint']}, 17:17:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['a11yr', 17:17:21 INFO - 'ts_paint', 17:17:21 INFO - 'tpaint', 17:17:21 INFO - 'sessionrestore', 17:17:21 INFO - 'sessionrestore_no_auto_restore', 17:17:21 INFO - 'tabpaint']}, 17:17:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['tsvgx', 17:17:21 INFO - 'tsvgr_opacity', 17:17:21 INFO - 'tart', 17:17:21 INFO - 'tscrollx', 17:17:21 INFO - 'cart', 17:17:21 INFO - 'tsvg_static']}, 17:17:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:17:21 INFO - 'tsvgr_opacity', 17:17:21 INFO - 'tart', 17:17:21 INFO - 'tscrollx', 17:17:21 INFO - 'cart', 17:17:21 INFO - 'tsvg_static']}, 17:17:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:17:21 INFO - 'talos_options': ['--disable-e10s'], 17:17:21 INFO - 'tests': ['tp5o']}, 17:17:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:17:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:17:21 INFO - 'talos_options': ['--disable-e10s', 17:17:21 INFO - '--xperf_path', 17:17:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:21 INFO - 'tests': ['tp5n']}, 17:17:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:17:21 INFO - 'talos_options': ['--xperf_path', 17:17:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:21 INFO - 'tests': ['tp5n']}}, 17:17:21 INFO - 'talos.zip': {'path': '', 17:17:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:17:21 INFO - Running post-action listener: _resource_record_post_action 17:17:21 INFO - [mozharness: 2016-12-22 01:17:21.154650Z] Finished populate-webroot step (success) 17:17:21 INFO - [mozharness: 2016-12-22 01:17:21.154738Z] Running create-virtualenv step. 17:17:21 INFO - Running pre-action listener: _resource_record_pre_action 17:17:21 INFO - Running main action method: create_virtualenv 17:17:21 INFO - Creating virtualenv /builds/slave/test/build/venv 17:17:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:17:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:17:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:17:21 INFO - Using real prefix '/usr' 17:17:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:17:21 INFO - Installing distribute.............................................................................................................................................................................................done. 17:17:23 INFO - Installing pip.................done. 17:17:23 INFO - Return code: 0 17:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:17:23 INFO - Reading from file tmpfile_stdout 17:17:23 INFO - Output received: 17:17:23 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) 17:17:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:17:23 INFO - Using env: {'DISPLAY': ':0', 17:17:23 INFO - 'HOME': '/home/cltbld', 17:17:23 INFO - 'LANG': 'en_US.UTF-8', 17:17:23 INFO - 'LANGUAGE': 'en_US:en', 17:17:23 INFO - 'LOGNAME': 'cltbld', 17:17:23 INFO - 'MAIL': '/var/mail/cltbld', 17:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:23 INFO - 'MOZ_NO_REMOTE': '1', 17:17:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:23 INFO - 'NO_EM_RESTART': '1', 17:17:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:23 INFO - 'PWD': '/builds/slave/test', 17:17:23 INFO - 'SHELL': '/bin/bash', 17:17:23 INFO - 'SHLVL': '1', 17:17:23 INFO - 'TERM': 'linux', 17:17:23 INFO - 'TMOUT': '86400', 17:17:23 INFO - 'USER': 'cltbld', 17:17:23 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:23 INFO - '_': '/tools/buildbot/bin/python'} 17:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:17:24 INFO - Cleaning up... 17:17:24 INFO - Return code: 0 17:17:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:17:24 INFO - Using env: {'DISPLAY': ':0', 17:17:24 INFO - 'HOME': '/home/cltbld', 17:17:24 INFO - 'LANG': 'en_US.UTF-8', 17:17:24 INFO - 'LANGUAGE': 'en_US:en', 17:17:24 INFO - 'LOGNAME': 'cltbld', 17:17:24 INFO - 'MAIL': '/var/mail/cltbld', 17:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:24 INFO - 'MOZ_NO_REMOTE': '1', 17:17:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:24 INFO - 'NO_EM_RESTART': '1', 17:17:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:24 INFO - 'PWD': '/builds/slave/test', 17:17:24 INFO - 'SHELL': '/bin/bash', 17:17:24 INFO - 'SHLVL': '1', 17:17:24 INFO - 'TERM': 'linux', 17:17:24 INFO - 'TMOUT': '86400', 17:17:24 INFO - 'USER': 'cltbld', 17:17:24 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:24 INFO - '_': '/tools/buildbot/bin/python'} 17:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:24 INFO - Downloading/unpacking psutil>=3.1.1 17:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:17:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:26 INFO - Installing collected packages: psutil 17:17:26 INFO - Running setup.py install for psutil 17:17:26 INFO - building 'psutil._psutil_linux' extension 17:17:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:17:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:17:26 INFO - building 'psutil._psutil_posix' extension 17:17:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:17:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:17:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:26 INFO - Successfully installed psutil 17:17:26 INFO - Cleaning up... 17:17:27 INFO - Return code: 0 17:17:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:17:27 INFO - Using env: {'DISPLAY': ':0', 17:17:27 INFO - 'HOME': '/home/cltbld', 17:17:27 INFO - 'LANG': 'en_US.UTF-8', 17:17:27 INFO - 'LANGUAGE': 'en_US:en', 17:17:27 INFO - 'LOGNAME': 'cltbld', 17:17:27 INFO - 'MAIL': '/var/mail/cltbld', 17:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:27 INFO - 'MOZ_NO_REMOTE': '1', 17:17:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:27 INFO - 'NO_EM_RESTART': '1', 17:17:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:27 INFO - 'PWD': '/builds/slave/test', 17:17:27 INFO - 'SHELL': '/bin/bash', 17:17:27 INFO - 'SHLVL': '1', 17:17:27 INFO - 'TERM': 'linux', 17:17:27 INFO - 'TMOUT': '86400', 17:17:27 INFO - 'USER': 'cltbld', 17:17:27 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:27 INFO - '_': '/tools/buildbot/bin/python'} 17:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:17:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:17:29 INFO - Installing collected packages: mozsystemmonitor 17:17:29 INFO - Running setup.py install for mozsystemmonitor 17:17:29 INFO - Successfully installed mozsystemmonitor 17:17:29 INFO - Cleaning up... 17:17:29 INFO - Return code: 0 17:17:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:17:29 INFO - Using env: {'DISPLAY': ':0', 17:17:29 INFO - 'HOME': '/home/cltbld', 17:17:29 INFO - 'LANG': 'en_US.UTF-8', 17:17:29 INFO - 'LANGUAGE': 'en_US:en', 17:17:29 INFO - 'LOGNAME': 'cltbld', 17:17:29 INFO - 'MAIL': '/var/mail/cltbld', 17:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:29 INFO - 'MOZ_NO_REMOTE': '1', 17:17:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:29 INFO - 'NO_EM_RESTART': '1', 17:17:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:29 INFO - 'PWD': '/builds/slave/test', 17:17:29 INFO - 'SHELL': '/bin/bash', 17:17:29 INFO - 'SHLVL': '1', 17:17:29 INFO - 'TERM': 'linux', 17:17:29 INFO - 'TMOUT': '86400', 17:17:29 INFO - 'USER': 'cltbld', 17:17:29 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:29 INFO - '_': '/tools/buildbot/bin/python'} 17:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:29 INFO - Downloading/unpacking jsonschema==2.5.1 17:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:17:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:31 INFO - Downloading functools32-3.2.3-2.tar.gz 17:17:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:17:31 INFO - warning: no files found matching '*.txt' 17:17:31 INFO - no previously-included directories found matching 'build' 17:17:31 INFO - no previously-included directories found matching 'dist' 17:17:31 INFO - no previously-included directories found matching '.git*' 17:17:31 INFO - Installing collected packages: jsonschema, functools32 17:17:31 INFO - Running setup.py install for functools32 17:17:32 INFO - warning: no files found matching '*.txt' 17:17:32 INFO - no previously-included directories found matching 'build' 17:17:32 INFO - no previously-included directories found matching 'dist' 17:17:32 INFO - no previously-included directories found matching '.git*' 17:17:32 INFO - Successfully installed jsonschema functools32 17:17:32 INFO - Cleaning up... 17:17:32 INFO - Return code: 0 17:17:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:17:32 INFO - Using env: {'DISPLAY': ':0', 17:17:32 INFO - 'HOME': '/home/cltbld', 17:17:32 INFO - 'LANG': 'en_US.UTF-8', 17:17:32 INFO - 'LANGUAGE': 'en_US:en', 17:17:32 INFO - 'LOGNAME': 'cltbld', 17:17:32 INFO - 'MAIL': '/var/mail/cltbld', 17:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:32 INFO - 'MOZ_NO_REMOTE': '1', 17:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:32 INFO - 'NO_EM_RESTART': '1', 17:17:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:32 INFO - 'PWD': '/builds/slave/test', 17:17:32 INFO - 'SHELL': '/bin/bash', 17:17:32 INFO - 'SHLVL': '1', 17:17:32 INFO - 'TERM': 'linux', 17:17:32 INFO - 'TMOUT': '86400', 17:17:32 INFO - 'USER': 'cltbld', 17:17:32 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:32 INFO - '_': '/tools/buildbot/bin/python'} 17:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:17:32 INFO - Cleaning up... 17:17:32 INFO - Return code: 0 17:17:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:17:32 INFO - Using env: {'DISPLAY': ':0', 17:17:32 INFO - 'HOME': '/home/cltbld', 17:17:32 INFO - 'LANG': 'en_US.UTF-8', 17:17:32 INFO - 'LANGUAGE': 'en_US:en', 17:17:32 INFO - 'LOGNAME': 'cltbld', 17:17:32 INFO - 'MAIL': '/var/mail/cltbld', 17:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:32 INFO - 'MOZ_NO_REMOTE': '1', 17:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:32 INFO - 'NO_EM_RESTART': '1', 17:17:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:32 INFO - 'PWD': '/builds/slave/test', 17:17:32 INFO - 'SHELL': '/bin/bash', 17:17:32 INFO - 'SHLVL': '1', 17:17:32 INFO - 'TERM': 'linux', 17:17:32 INFO - 'TMOUT': '86400', 17:17:32 INFO - 'USER': 'cltbld', 17:17:32 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:32 INFO - '_': '/tools/buildbot/bin/python'} 17:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:32 INFO - Downloading/unpacking blobuploader==1.2.4 17:17: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 17:17: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 17:17: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 17:17: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 17:17: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 17:17: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 17:17:34 INFO - Downloading blobuploader-1.2.4.tar.gz 17:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:17:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:17:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:17: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 17:17: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 17:17: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 17:17: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 17:17: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 17:17: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 17:17:35 INFO - Downloading docopt-0.6.1.tar.gz 17:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:17:35 INFO - Installing collected packages: blobuploader, requests, docopt 17:17:35 INFO - Running setup.py install for blobuploader 17:17:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:17:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:17:35 INFO - Running setup.py install for requests 17:17:35 INFO - Running setup.py install for docopt 17:17:35 INFO - Successfully installed blobuploader requests docopt 17:17:35 INFO - Cleaning up... 17:17:35 INFO - Return code: 0 17:17:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:35 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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:35 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 17:17:35 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 17:17:35 INFO - Using env: {'DISPLAY': ':0', 17:17:36 INFO - 'HOME': '/home/cltbld', 17:17:36 INFO - 'LANG': 'en_US.UTF-8', 17:17:36 INFO - 'LANGUAGE': 'en_US:en', 17:17:36 INFO - 'LOGNAME': 'cltbld', 17:17:36 INFO - 'MAIL': '/var/mail/cltbld', 17:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:36 INFO - 'MOZ_NO_REMOTE': '1', 17:17:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:36 INFO - 'NO_EM_RESTART': '1', 17:17:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:36 INFO - 'PWD': '/builds/slave/test', 17:17:36 INFO - 'SHELL': '/bin/bash', 17:17:36 INFO - 'SHLVL': '1', 17:17:36 INFO - 'TERM': 'linux', 17:17:36 INFO - 'TMOUT': '86400', 17:17:36 INFO - 'USER': 'cltbld', 17:17:36 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:36 INFO - '_': '/tools/buildbot/bin/python'} 17:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:36 INFO - Running setup.py (path:/tmp/pip-g7SRsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:36 INFO - Running setup.py (path:/tmp/pip-tdVJ0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:36 INFO - Running setup.py (path:/tmp/pip-Ujkn6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:36 INFO - Running setup.py (path:/tmp/pip-FivjqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:36 INFO - Running setup.py (path:/tmp/pip-xBeDHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:36 INFO - Running setup.py (path:/tmp/pip-A6fMGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:36 INFO - Running setup.py (path:/tmp/pip-jM_G0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:36 INFO - Running setup.py (path:/tmp/pip-86Iory-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:36 INFO - Running setup.py (path:/tmp/pip-zbSJM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:36 INFO - Running setup.py (path:/tmp/pip-wEoDgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:36 INFO - Running setup.py (path:/tmp/pip-50MFia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:37 INFO - Running setup.py (path:/tmp/pip-OfpQl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:37 INFO - Running setup.py (path:/tmp/pip-M3sf1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:37 INFO - Running setup.py (path:/tmp/pip-DLSNMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:37 INFO - Running setup.py (path:/tmp/pip-Kf15Os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:37 INFO - Running setup.py (path:/tmp/pip-8kfCyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:37 INFO - Running setup.py (path:/tmp/pip-iRkTGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:17:37 INFO - Running setup.py install for manifestparser 17:17:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:17:37 INFO - Running setup.py install for mozcrash 17:17:37 INFO - Running setup.py install for mozdebug 17:17:37 INFO - Running setup.py install for mozdevice 17:17:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:17:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:17:37 INFO - Running setup.py install for mozfile 17:17:37 INFO - Running setup.py install for mozhttpd 17:17:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Running setup.py install for mozinfo 17:17:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Running setup.py install for mozInstall 17:17:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Running setup.py install for mozleak 17:17:38 INFO - Running setup.py install for mozlog 17:17:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Running setup.py install for moznetwork 17:17:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Running setup.py install for mozprocess 17:17:38 INFO - Running setup.py install for mozprofile 17:17:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Running setup.py install for mozrunner 17:17:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:17:38 INFO - Running setup.py install for mozscreenshot 17:17:38 INFO - Running setup.py install for moztest 17:17:39 INFO - Running setup.py install for mozversion 17:17:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:17:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:17:39 INFO - Cleaning up... 17:17:39 INFO - Return code: 0 17:17:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:17:39 INFO - Using env: {'DISPLAY': ':0', 17:17:39 INFO - 'HOME': '/home/cltbld', 17:17:39 INFO - 'LANG': 'en_US.UTF-8', 17:17:39 INFO - 'LANGUAGE': 'en_US:en', 17:17:39 INFO - 'LOGNAME': 'cltbld', 17:17:39 INFO - 'MAIL': '/var/mail/cltbld', 17:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:39 INFO - 'MOZ_NO_REMOTE': '1', 17:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:39 INFO - 'NO_EM_RESTART': '1', 17:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:39 INFO - 'PWD': '/builds/slave/test', 17:17:39 INFO - 'SHELL': '/bin/bash', 17:17:39 INFO - 'SHLVL': '1', 17:17:39 INFO - 'TERM': 'linux', 17:17:39 INFO - 'TMOUT': '86400', 17:17:39 INFO - 'USER': 'cltbld', 17:17:39 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:39 INFO - '_': '/tools/buildbot/bin/python'} 17:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:39 INFO - Running setup.py (path:/tmp/pip-UF1wTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:39 INFO - Running setup.py (path:/tmp/pip-rHJabb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:39 INFO - Running setup.py (path:/tmp/pip-wtlavX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:39 INFO - Running setup.py (path:/tmp/pip-B4T39A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:39 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)) 17:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:39 INFO - Running setup.py (path:/tmp/pip-OOJbmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:39 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)) 17:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:39 INFO - Running setup.py (path:/tmp/pip-UZUMfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:39 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)) 17:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:39 INFO - Running setup.py (path:/tmp/pip-8EZLL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:39 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)) 17:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:39 INFO - Running setup.py (path:/tmp/pip-GuU89j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:39 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)) 17:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:39 INFO - Running setup.py (path:/tmp/pip-kDvnw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:40 INFO - Running setup.py (path:/tmp/pip-Av5vN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:40 INFO - Running setup.py (path:/tmp/pip-guMklv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:40 INFO - Running setup.py (path:/tmp/pip-vPTxCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:40 INFO - Running setup.py (path:/tmp/pip-Q1jGLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:40 INFO - Running setup.py (path:/tmp/pip-bww1vN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:40 INFO - Running setup.py (path:/tmp/pip-rDh3oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:40 INFO - Running setup.py (path:/tmp/pip-wY_Xky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:40 INFO - Running setup.py (path:/tmp/pip-Xk8QGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:17:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:17: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 17:17: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 17:17: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 17:17: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 17:17: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 17:17: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 17:17:42 INFO - Downloading blessings-1.6.tar.gz 17:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:17:42 INFO - Installing collected packages: blessings 17:17:42 INFO - Running setup.py install for blessings 17:17:42 INFO - Successfully installed blessings 17:17:42 INFO - Cleaning up... 17:17:43 INFO - Return code: 0 17:17:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:17:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:43 INFO - Reading from file tmpfile_stdout 17:17:43 INFO - Current package versions: 17:17:43 INFO - argparse == 1.2.1 17:17:43 INFO - blessings == 1.6 17:17:43 INFO - blobuploader == 1.2.4 17:17:43 INFO - docopt == 0.6.1 17:17:43 INFO - functools32 == 3.2.3-2 17:17:43 INFO - jsonschema == 2.5.1 17:17:43 INFO - manifestparser == 1.1 17:17:43 INFO - mozInstall == 1.12 17:17:43 INFO - mozcrash == 1.0 17:17:43 INFO - mozdebug == 0.1 17:17:43 INFO - mozdevice == 0.49 17:17:43 INFO - mozfile == 1.2 17:17:43 INFO - mozhttpd == 0.7 17:17:43 INFO - mozinfo == 0.9 17:17:43 INFO - mozleak == 0.1 17:17:43 INFO - mozlog == 3.4 17:17:43 INFO - moznetwork == 0.27 17:17:43 INFO - mozprocess == 0.23 17:17:43 INFO - mozprofile == 0.28 17:17:43 INFO - mozrunner == 6.13 17:17:43 INFO - mozscreenshot == 0.1 17:17:43 INFO - mozsystemmonitor == 0.3 17:17:43 INFO - moztest == 0.7 17:17:43 INFO - mozversion == 1.4 17:17:43 INFO - psutil == 3.1.1 17:17:43 INFO - requests == 1.2.3 17:17:43 INFO - wsgiref == 0.1.2 17:17:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:17:43 INFO - Using env: {'DISPLAY': ':0', 17:17:43 INFO - 'HOME': '/home/cltbld', 17:17:43 INFO - 'LANG': 'en_US.UTF-8', 17:17:43 INFO - 'LANGUAGE': 'en_US:en', 17:17:43 INFO - 'LOGNAME': 'cltbld', 17:17:43 INFO - 'MAIL': '/var/mail/cltbld', 17:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:43 INFO - 'MOZ_NO_REMOTE': '1', 17:17:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:43 INFO - 'NO_EM_RESTART': '1', 17:17:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:43 INFO - 'PWD': '/builds/slave/test', 17:17:43 INFO - 'SHELL': '/bin/bash', 17:17:43 INFO - 'SHLVL': '1', 17:17:43 INFO - 'TERM': 'linux', 17:17:43 INFO - 'TMOUT': '86400', 17:17:43 INFO - 'USER': 'cltbld', 17:17:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:43 INFO - '_': '/tools/buildbot/bin/python'} 17:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:17:43 INFO - Cleaning up... 17:17:43 INFO - Return code: 0 17:17:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, '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': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', '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 17:17:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:17:43 INFO - Using env: {'DISPLAY': ':0', 17:17:43 INFO - 'HOME': '/home/cltbld', 17:17:43 INFO - 'LANG': 'en_US.UTF-8', 17:17:43 INFO - 'LANGUAGE': 'en_US:en', 17:17:43 INFO - 'LOGNAME': 'cltbld', 17:17:43 INFO - 'MAIL': '/var/mail/cltbld', 17:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:43 INFO - 'MOZ_NO_REMOTE': '1', 17:17:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:43 INFO - 'NO_EM_RESTART': '1', 17:17:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:43 INFO - 'PWD': '/builds/slave/test', 17:17:43 INFO - 'SHELL': '/bin/bash', 17:17:43 INFO - 'SHLVL': '1', 17:17:43 INFO - 'TERM': 'linux', 17:17:43 INFO - 'TMOUT': '86400', 17:17:43 INFO - 'USER': 'cltbld', 17:17:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:43 INFO - '_': '/tools/buildbot/bin/python'} 17:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:17:43 INFO - Cleaning up... 17:17:43 INFO - Return code: 0 17:17:43 INFO - Running post-action listener: _resource_record_post_action 17:17:43 INFO - Running post-action listener: _start_resource_monitoring 17:17:43 INFO - Starting resource monitoring. 17:17:43 INFO - [mozharness: 2016-12-22 01:17:43.805289Z] Finished create-virtualenv step (success) 17:17:43 INFO - [mozharness: 2016-12-22 01:17:43.805776Z] Running install step. 17:17:43 INFO - Running pre-action listener: _resource_record_pre_action 17:17:43 INFO - Running main action method: install 17:17:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:44 INFO - Reading from file tmpfile_stdout 17:17:44 INFO - Detecting whether we're running mozinstall >=1.0... 17:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:17:44 INFO - Reading from file tmpfile_stdout 17:17:44 INFO - Output received: 17:17:44 INFO - Usage: mozinstall [options] installer 17:17:44 INFO - Options: 17:17:44 INFO - -h, --help show this help message and exit 17:17:44 INFO - -d DEST, --destination=DEST 17:17:44 INFO - Directory to install application into. [default: 17:17:44 INFO - "/builds/slave/test"] 17:17:44 INFO - --app=APP Application being installed. [default: firefox] 17:17:44 INFO - mkdir: /builds/slave/test/build/application 17:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:18:01 INFO - Reading from file tmpfile_stdout 17:18:01 INFO - Output received: 17:18:01 INFO - /builds/slave/test/build/application/firefox/firefox 17:18:01 INFO - Running post-action listener: _resource_record_post_action 17:18:01 INFO - [mozharness: 2016-12-22 01:18:01.468021Z] Finished install step (success) 17:18:01 INFO - [mozharness: 2016-12-22 01:18:01.468294Z] Running run-tests step. 17:18:01 INFO - Running pre-action listener: _resource_record_pre_action 17:18:01 INFO - Running main action method: run_tests 17:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:18:01 INFO - Python 2.7.3 17:18:01 INFO - Return code: 0 17:18:01 INFO - grabbing minidump binary from tooltool 17:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:01 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3850>, '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 0x1d486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d48b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:18:01 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:18:01 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:18:01 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:18:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:18:01 INFO - Return code: 0 17:18:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:18:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:18:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:18:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:18:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 17:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 17:18:01 INFO - Using env: {'DISPLAY': ':0', 17:18:01 INFO - 'HOME': '/home/cltbld', 17:18:01 INFO - 'LANG': 'en_US.UTF-8', 17:18:01 INFO - 'LANGUAGE': 'en_US:en', 17:18:01 INFO - 'LOGNAME': 'cltbld', 17:18:01 INFO - 'MAIL': '/var/mail/cltbld', 17:18:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:18:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:01 INFO - 'MOZ_NO_REMOTE': '1', 17:18:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:01 INFO - 'NO_EM_RESTART': '1', 17:18:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:01 INFO - 'PWD': '/builds/slave/test', 17:18:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:18:01 INFO - 'SHELL': '/bin/bash', 17:18:01 INFO - 'SHLVL': '1', 17:18:01 INFO - 'TERM': 'linux', 17:18:01 INFO - 'TMOUT': '86400', 17:18:01 INFO - 'USER': 'cltbld', 17:18:01 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482369270.228303-794034786', 17:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:01 INFO - '_': '/tools/buildbot/bin/python'} 17:18:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 17:18:01 INFO - mozversion application_buildid: 20161221153502 17:18:01 INFO - mozversion application_changeset: d9d75cfe7af3c6d3aa5aba56cb72b976d5ab98c3 17:18:01 INFO - mozversion application_display_name: Nightly 17:18:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:18:01 INFO - mozversion application_name: Firefox 17:18:01 INFO - mozversion application_remotingname: firefox 17:18:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:18:01 INFO - mozversion application_vendor: Mozilla 17:18:01 INFO - mozversion application_version: 53.0a1 17:18:01 INFO - mozversion platform_buildid: 20161221153502 17:18:01 INFO - mozversion platform_changeset: d9d75cfe7af3c6d3aa5aba56cb72b976d5ab98c3 17:18:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:18:01 INFO - mozversion platform_version: 53.0a1 17:18:01 INFO - using testdate: 1482369481 17:18:01 INFO - actual date: 1482369481 17:18:01 INFO - starting webserver on 'localhost:51776' 17:18:01 INFO - SUITE-START | Running 2 tests 17:18:01 INFO - TEST-START | dromaeo_css 17:18:01 INFO - operating with platform_type : linux_ 17:18:01 INFO - Initialising browser for dromaeo_css test... 17:18:01 INFO - TEST-INFO | started process 958 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLvekbY/profile http://localhost:51776/getInfo.html) 17:18:06 INFO - PROCESS | 958 | __metrics Screen width/height:1600/1200 17:18:06 INFO - PROCESS | 958 | colorDepth:24 17:18:06 INFO - PROCESS | 958 | Browser inner width/height: 1024/697 17:18:06 INFO - PROCESS | 958 | __metrics 17:18:07 INFO - PROCESS | 958 | 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" 17:18:07 INFO - PROCESS | 958 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:18:08 INFO - PROCESS | 958 | console.error: PushService: 17:18:08 INFO - PROCESS | 958 | stateChangeProcessEnqueue: Error transitioning state 17:18:08 INFO - PROCESS | 958 | UnknownError 17:18:08 INFO - TEST-INFO | 958: exit 0 17:18:08 INFO - Browser initialized. 17:18:08 INFO - Running cycle 1/1 for dromaeo_css test... 17:18:08 INFO - TEST-INFO | started process 1045 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLvekbY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:18:20 INFO - PROCESS | 1045 | RSS: Main: 150310912 17:18:20 INFO - PROCESS | 1045 | 17:19:11 INFO - PROCESS | 1045 | 1482369551728 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] 17:21:21 INFO - PROCESS | 1045 | Cycle 1(1): loaded http://localhost:51776/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51776/tests/dromaeo/cssquery-ext.html) 17:21:23 INFO - PROCESS | 1045 | RSS: Main: 285945856 17:21:23 INFO - PROCESS | 1045 | 17:24:26 INFO - PROCESS | 1045 | Cycle 1(1): loaded http://localhost:51776/tests/dromaeo/cssquery-ext.html (next: http://localhost:51776/tests/dromaeo/cssquery-jquery.html) 17:24:26 INFO - PROCESS | 1045 | RSS: Main: 253480960 17:24:26 INFO - PROCESS | 1045 | 17:27:28 INFO - PROCESS | 1045 | Cycle 1(1): loaded http://localhost:51776/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51776/tests/dromaeo/cssquery-mootools.html) 17:27:29 INFO - PROCESS | 1045 | RSS: Main: 187400192 17:27:29 INFO - PROCESS | 1045 | 17:30:31 INFO - PROCESS | 1045 | Cycle 1(1): loaded http://localhost:51776/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51776/tests/dromaeo/cssquery-prototype.html) 17:30:31 INFO - PROCESS | 1045 | RSS: Main: 250503168 17:30:31 INFO - PROCESS | 1045 | 17:33:34 INFO - PROCESS | 1045 | Cycle 1(1): loaded http://localhost:51776/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51776/tests/dromaeo/cssquery-yui.html) 17:33:35 INFO - PROCESS | 1045 | RSS: Main: 188497920 17:33:35 INFO - PROCESS | 1045 | 17:36:37 INFO - PROCESS | 1045 | Cycle 1(1): loaded http://localhost:51776/tests/dromaeo/cssquery-yui.html (next: http://localhost:51776/tests/dromaeo/cssquery-dojo.html) 17:36:38 INFO - PROCESS | 1045 | RSS: Main: 284127232 17:36:38 INFO - PROCESS | 1045 | 17:36:38 INFO - PROCESS | 1045 | __start_tp_report 17:36:38 INFO - PROCESS | 1045 | _x_x_mozilla_page_load 17:36:38 INFO - PROCESS | 1045 | _x_x_mozilla_page_load_details 17:36:38 INFO - PROCESS | 1045 | |i|pagename|runs| 17:36:38 INFO - PROCESS | 1045 | |0;dojo.html;3107;3113.7353324967376;3132.937341253174;3165.256164801273;3167.334859679467;5117.053345131153;5182.818601348954;5264.8157314494;5271.894562108756;5291.973540132303;5224.686518808874;5243.68537887727;5244.370675518943;5247.580193584514;5279;4718.97640511797;4808.110499557593;4843.7335946522835;4851.490593487686;4877.561019508244;4732;4801.591864691505;4841.443234028079;4841.636877234221;4880.682755620891;4856.150173719602;4867.829625963092;4908.852734417973;4911.582515486188;4955.033768415161;4710.976445117787;4741.028089241731;4751.928721069186;4847.175980083394;4849.709017458964;4729.810807567693;4732.645051621143;4767.499412561666;4809.927851082237;4827.203511420611;5214.817481388133;5228.320318358627;5251.816186433494;5260.395054568736;5295.4704529547125;5635.042042852725;5638.267025286727;5639.379668236491;5642.605017648766;5682.460166284196;5585.329760428734;5609.270794796691;5618.859528511779;5642.407547207526;5701.486866182022;5289.1008528549855;5317.096093664087;5321.600879933943;5362.651427657229;5368.141097424433;5797.072468405035;5797.275340582427;5807.0998995155815;5807.274090738658;5821.27234095738;5436.429174936654;5476.479734425223;5527.143292789624;5580.581456390787;5588.161775733673;5563.193336966198;5595.664260144323;5601.63589366698;5605.383407825136;5694.886102277931;5590.636608620427;5599.132134519156;5603.495685388335;5604;5605.579581531401;5538.363088244822;5613.522850557667;5613.8596535086135;5617.522510586646;5659.5755318351285;4178.206140833232;4192.727472714325;4197.601227883346;4257.3613957905945;4264.829406823755;3780.0738818989525;3780.5652349979555;3782.0733920189728;3811.0662887592716;3821.732478726518;5387.595930305243;5389.892202155935;5391.353037635509;5396.892062158735;5427.891442171134;5534.501894829485;5572;5577.581681373913;5654.886902261932;5691.3170419549915;5273.789048438096;5344.225087362311;5359.785608575691;5359.919601205985;5361.651492652965;4655;4661.021185551004;4661.650376221797;4666.65000124992;4667.6265898728025;5878.088896221093;5882.999890018707;5890.970545147246;5891.027980383189;5894.705264736832;5601.719914004202;5644.955683198621;5646.9152962704775;5652.180433837153;5654.519365853784;5888.381719919346;5907.232059832194;5915.112733090071;5915.733791979285;5915.852103697442;5499.7250137492165;5500.147477141049;5513.724313784375;5557;5562.694051827189;5661.179129026185;5661.631993920382;5667.546596272371;5670.149477578396;5671.914921276267;5364.973175134255;5405.135178371442;5523.499972665769;5524.447555244444;5527.806526771544;5461.644993075437;5464.863378415413;5487.067198576331;5545.140503222007;5588.3573389060775;5519.337679478488;5524.779008839601;5531.197976293334;5531.474509921551;5532.004239236976;5424.674519528841;5459.727013649381;5567.10926251814;5582.7766889323975;5590.105583106683;5379.408265090915;5389.110796718497;5410.188471729115;5421.430749771375;5421.484958928895 17:36:38 INFO - PROCESS | 1045 | |1;ext.html;540.7026135625406;558.3021223470662;571.6141604417019;573.9626924249924;578.0895090232884;32303.838480807593;32896.34207315855;43444.13111737767;45253;45558;44063.33904991428;44266.557334426645;44356.3346549802;44373.7781311093;44390;8149.307308878722;8692.869606955917;8874.600642971081;8876;8892.599833007498;8137.633806478722;10247.897521024768;10469.11012563933;10487.528061237263;10802.72993175173;10568.841467377995;10635.946820265888;10636.468176591177;10638.946805265963;10642.521086551085;10484.895151048468;10527.157827373792;10547.05076543111;10563.207759418074;10599.152067834555;10357.48212589375;10386.065254127165;10405.47972601367;10423.322484038588;10429.113525350354;40942.59057409447;41085.58914410877;41228.1754364914;41241.38137927934;41271.587284127076;3506.7019303359243;3523.841427135785;3560.0743806610208;3685.7419980601367;3691.741578089535;31757.682423175702;31816.204594885083;32140.357192856245;32781.508277375855;33564.328713425835;4263.5736426357425;4302.763348015857;4332.761698106603;4339.826406943718;4350.782460876943;316324;316890;317814;317935.9999999977;318042;54175.45824541704;54593;54774.178387324224;54816.858881107124;54829.1775623366;56799.740019497876;56847.14729279064;56906.43093569094;56916.14625780617;57033.42966570364;13898.305084745722;14262.786058209134;14262.786058209134;14270.785938210935;14285.64285892861;10096;10369.066783989478;11328.093752499944;11645.475953582109;11742.88257117418;14890.627734306729;15422.845771542141;15471.381144754083;15526.689466210839;15603.687926241411;5953.374895635982;5956.1065840123465;5998.520118390518;6040.214772079602;6109.327973922865;888.9555522223862;954.1126752120456;995.915347195482;1020.642775028734;1042.9739256518496;30838.53742193869;30990.535141972887;31105.377892442477;31278.257090188017;31348.37303253922;1031.437866362834;1045.5660900726114;1061.946902654864;1075.5697720911699;1080.8054550181043;16441.506754797374;16495.752563711554;16540.609669587535;16636.50090497287;16651.6669666606;449664;453844;454244;456253;458885;57620.71189644109;57621;57676.134857977166;57682.13476797851;57802.421975779704;1248.6004478566772;1310.9541166059141;1330.8469526004633;1354.8848347890344;1387.66695992963;28150.718492815628;32285.35429291448;32534.67465325411;32543;33011.504827427125;48851.755741221066;49491.75254123707;49508.75245623749;49594;49680.25479617736;289795;290484;290619;291232;291291;24908.62637060409;25155.371115722253;25623.23130306019;26102.779444111635;38983.415248770725;40485.39271910983;40742;40808;40945.385819213334;41168.79415602903;35383;35568.886222755034;35598;35867.461988070725;36290.27419451649;35427.645723543465;35463;35790.28419431649;35922.64077359193;36361.45457818082;35196.47205291872;35493.1126721834;36276.27447451089;36660;36932.44601330929;30771.230719232197;32073.87741429039;32511.67488325181;32660.34679306353;32663.836680816443;25250.242492725247;25297;25357.239282821545;25382.365440864127;25521.284325141813 17:36:38 INFO - PROCESS | 1045 | |2;jquery.html;381.4183370360202;397.2967846910966;402.9456023436836;406.4654978703005;418.8764314527214;13358.465661373546;15227.238638068095;18346.633067338662;18479.722804157915;18975.430737077877;17562.912185439087;17640.735388969177;17949.820501794977;18153.90923045386;18172.18225179867;5570.108782594785;5772.4227577242145;5777.133429985493;5779.393163717812;5781.682007489587;5044.419891712463;6387.2654644715985;6424.614523128627;6522.728068026737;6543.541952063358;6255.937440625581;6392.23293204816;6484.0598113273545;6491.902621460682;6492.772752953648;6387.361263873622;6409.198850143732;6412.230532336102;6416.422521973045;6417.165768450095;5558.249636299089;5704.743286552115;5722.62802917809;5741.224934633813;5744.798932037359;21963.890180549155;22278.220262290903;22305.665415018786;22307.888460557595;22766.65850012251;2277.749447560767;2320.7447180810095;2354.4702441950594;2638.300850274679;2665.906978138954;13905;14181.78727319091;14323.283835808272;14395.496157634432;14604.488842890449;1949.7855235924037;1957.5204075001716;1978.5251539630528;2016.1028342387644;2034.4608678700156;73945;75098;78350.21649783543;78365;79056.60471697604;10475.42385168823;10616;10682.412467314372;10940.671779846618;10995.450227488594;14517.346719397652;14773.113613183243;14780.039297445632;14898.1806000671;15011.324490397958;7584.54492730438;7793;7856.371490280878;7918.168592297841;7939.20607939213;7405;7501;7541.849163016709;7619.08570971487;7710.11333696632;4481.5294394088805;4489.910201795946;4552.590266875997;4558.111168322212;4569.977150114228;1842.1986435900426;1854.7125195594704;1907.2943011085988;1934.8839069655867;1940.0687669918518;868.8140687961014;917.2341095185461;976.6337623391229;1003.2124782046204;1015.1067060986433;17471.650566988843;18631.347902823607;18780.90609546971;18907.243710251434;19150.138243779063;968.1577027985697;977.9266555008403;982.5332966840837;1009.6869970309227;1062.7130674717937;10201.265784816598;10434.269601127848;10631.255812093234;10658.73353166177;10679.359238445562;199074;199663;201265;343750;396783;64509;88896.555517222;89562.55218723995;92913.53543232376;93187;904.9638014479478;930.5858892792778;936.864154697565;939.7039932421266;949.7530642032983;15301.15843628611;16172.838271617133;17396.130193490528;17425.738613921054;17461.563460913578;37916;40720.592794072865;41187.1762564753;41281;41794.79102604467;56031.43968560262;56138.43861561332;56547.434525654215;57058;57708.71145644245;8211.507309561446;8441;8458.111898250841;8458.957705211435;8630.654773808978;12163.148579599343;13137.671558211121;13470.730585388434;13471.528496502575;13473;11425.657230283105;11462.14033947474;11610.593629223275;11798.289248058813;11936.58221962227;11033.779324413626;11069.5018724156;11087.889121108685;11421.1434142438;11547.769044618892;10868.51091700891;11502;11780.352080635646;11949.283043017447;12288.50845966187;8474.703385381483;8494.1081186477;8507.914920850711;8577.8713319299;8595.957020214859;11375.601853935405;11586.88413115858;11661.53353865836;11850.52597896109;11905.880941190477 17:36:38 INFO - PROCESS | 1045 | |3;mootools.html;1437.1664434627914;1446.5371081253995;1611.2588209423664;1750.4748575427368;1762.5505496098501;8189.959050204756;8693.95653021734;8701.608427620757;8707.608157632905;8708.12918708129;7599.696012159521;7842.333401660851;8170.468919520227;8433.156684331563;8544.829103417927;2316.8494047886916;2357.163207061494;2357.316378250314;2357.905683772647;2365.2431222008963;2328.2549584133085;2339.789418952294;2368.6210206366986;2376.7979721723673;2378.95242095158;2335;2346.2609278077434;2364.408897775556;2375.6080246759266;2384.988075059631;2226.799588037068;2292.64464008079;2308.215206829686;2308.4228942764307;2317.756635553268;2235.284708893155;2255.458689914425;2268.829837762158;2272.3864556569797;2374.750651181618;8230.753077407688;8296.170382961716;8299.543525106117;8307.08622051574;8316.209960053786;4956;5104.64267501275;5111.386633603991;5146.459621739701;5165.948340516622;3206.1022913584;3251.2387414874474;3430.3482338355634;3432.8798492052906;3441.8107004114763;1109.7003808971613;1109.8085189527176;1114.3258328711095;1126.1328776841867;1151.756103408317;11732.589359372381;11820.349880756641;11848.88151118495;11857.110716696283;11862.347570883598;4345.695801293879;4548.704334218265;4608.101420223024;4621.468531118897;4636.466806317249;9848.753781155528;9882.505874706236;9948.154406875497;9950.452725100044;9958.751031224278;5138.743062846843;5167.819126330561;5187.221916712523;5215.348081489814;5269.314989051399;4210.863066971961;4260.467441569804;4674.205385084544;4731.621470282369;4818.927716084261;3861.710371722132;3929.5088113985753;3950.3679411294056;3998.260321840469;4047.291723948297;1285.6721536008445;1300.9639913535286;1302.5636411801963;1307.7907534794388;1315.2437348524636;846.6105591428012;869.7347309070794;993.6720882108887;998.6854140945579;1007.0131271354114;5429.864253393618;5510.752016159283;5602.915956260659;5603.887922241533;5940.732667029994;956.6986399284342;959.64972784933;965;981.215027977615;996.0637001218831;4154.335306350969;4260.360945858146;4303.591158839905;4439.889002774957;4466.9106617867465;10004.799904002024;10284.485775711184;10293.125084367763;10298.536565854554;10312;9921.355111917703;10073.445960472245;10202.693919182437;10213;10227.87493375728;7425.1832298447125;7654.502457340255;7695.923040769632;7873.094594121635;7896.84206315882;5830.154627578892;5832.912506312324;5874.412558744091;5997.82006539787;6104.969475152596;9379.671711490339;9535.38020028696;9568.952155239458;9622.037796220602;9632.373895696757;8895.24390426795;9258.95370523143;9265;9342.813143737223;9455.101765332283;6761.391474767294;6950.339717726808;7301.780946571611;7579.090509138939;7758.806029849299;8058.3553315733425;8264.669413223404;8274.627641756257;8278.254957053894;8283.08886022549;6923.307669233036;6967.407770339578;7154.713811447484;7163.498555101093;7169.569825810259;6978.162620485574;7528.962355188189;7560;7628.46600737943;7682.846343072995;6573.3426657333885;6742.157230346207;6744.865102698017;6895.827604309732;6898.689558969959;4907.190313598217;5094.465081166572;5209.270702101633;5380.919286210788;5685.090385538294;5201.323827902348;5315.388730295911;5357.357117145967;5380.246765452918;5396.7571459283645 17:36:38 INFO - PROCESS | 1045 | |4;prototype.html;377.8011970332665;388.98444062237513;389.3653345047572;396.8253968253967;399.23944884994097;5032.370953630796;5065.075271396864;5077.669951453153;5117.846464606063;5133.511281728299;5025.698458092517;5043.360108699996;5046.44489106198;5047.9399326141465;5048.29323894655;3847.3267178243836;3976.940345894814;4009.5789942056103;4091.4067460218175;4120.732152410099;3984.1234928315725;4015.4980627421573;4017.6183262590007;4031.1736094100784;4036.1927614477077;3980.6417422431973;3995.840166393341;3997.8400863965408;3998.5601583825755;3998.700097492685;3922.5096862892137;4015.959840401588;4029.738067025634;4030.4961879765174;4030.919381612351;3513.789172649649;3525.2773181497732;3545.2732189901008;3545.3618348697223;3551.6093229744965;4894.360389269599;4908.01839632075;4957.776900039471;4959.132151873408;4971.751412429364;4575.977120114411;4583.472900616405;4595.908081838378;4599.402077729907;4619.74591397473;5117.5394214520875;5239.318888544503;5266.394364648077;5293;5307.787688492455;3874.2638898609457;3879.5538513071074;3885.494885664874;3899.395593682984;3911.7116486855225;5302.859885124678;5433.402325744165;5458.4814442628285;5548.447714051541;5548.944510554924;3941.2511622791576;3962.8018599069933;3969.4244334571904;3973.6026397360033;3981.9402708959387;4028.2144981728284;4037.1723796621623;4045.838166473367;4048.4129801178665;4051.1897620476025;4391.978040109779;4504.189245935698;4556.498785133633;4597.747123908217;4625.583697467244;5255.527002569787;5269.973650131725;5284.841454756363;5304.048483879535;5304.522592966651;1430.763923952536;1433.1544388810444;1433.6917562723856;1433.8852891768634;1435.2967046147312;955.7371722776293;997.2321312589265;1024.293237666023;1040.8542804007445;1056.9207309451822;4966.726830024383;4986.650934434555;4995.100881841304;5007.39911210657;5025.246213067996;5264;5270.947290527122;5271.789128434896;5273.736313184326;5274.208868669731;4806.134895718735;4956.8512944611;4976.676516026447;4984.501549844987;4999.625028122905;2761.8895244190408;2762.682291536499;2774.639296891391;2784.902528411496;2798.6921438641734;5451.209574611659;5465.617406781566;5467.398586155519;5490.752916118784;5494.697791621499;5296.232046353257;5368.973155134199;5535.474129957647;5553.527950124285;5557.194206839986;5656.208130861683;5699.2590963174525;5708.372079071299;5711.771529138788;5712.457316554921;5247.1866860636665;5483.177523371526;5484.91772623403;5517.834464966058;5545.5599536203945;5387.326584176978;5413.70224637633;5427.99582077317;5438.102713052302;5483.670979741229;5503.284573005483;5536.944630553643;5638.577106716931;5685.744141513559;5686.488216060575;3977.42327362525;3998.1808277234372;4023.879283621496;4024.7182697212083;4045.8786236412957;5306.575473961997;5517.310336207974;5530.5022547970875;5532.3637781655625;5543.805966791143;5278.551323137576;5279.498447647467;5305.840824775263;5306.893862122659;5325.4940780625775;5286.550643195218;5291.391489978701;5302.920456193083;5324.148136298329;5340.6528575644015;5092.668976516515;5332.466753324792;5344.25180474726;5357.1785592898605;5358.732063396893;5377.623566350318;5385.46145385474;5392.245085688085;5393.757280922447;5395.298611180521;5250.264962905273;5259.763310650953;5270.156774915995;5275.973620131875;5282.418933917189 17:36:38 INFO - PROCESS | 1045 | |5;yui.html;736.8636802191595;744.0898080586517;747.8653842308382;763.027140395995;810.6878851642116;14328.641783955394;15725.528234152966;15761.448349307777;15777.447789327362;15906.602334941617;14513;14649.121052736817;14679.63300917475;14697.706045879077;14702.485413010547;661.9768308109217;693.2201273567237;781.452982911961;786.268770043859;812.8455593437228;814.0841553252591;824.8597738384489;827.0158511371449;827.7847759582522;828.3166387730116;882.5984177199359;884.8672699095155;885.7475619448459;887.0287035695902;887.9112088791134;701.5194591704699;738.4793720427086;805.6535689653447;873.5151990645189;879.0901417033372;620.9844753881189;623.5510432488601;625.1154616218046;625.1841347042108;625.4652272307203;14430.927845360811;14768.926155369261;14770.187639679812;14772.926135369255;14816;13126.803097953538;13452.1256118352;13622.182669039888;13655.088287639739;13733.587992360246;13027.478900843955;13059;13064.804027939588;13399.799003014963;13401.396937137852;4360;4388.253996820548;4464.6428285737065;4467.128909862578;4500.48244451889;82121.58939205266;82961.02233865987;83079;83615;87938.56030719806;10671.25301228922;10679.519421626046;10775.407352595526;10800.891991080145;11026.44867756609;21978.340649780534;22227.66658500124;22304.330870073925;22306.330810075724;22317.553648927256;7604.961975190199;7616.276453736886;7710.383169346439;7781.572013539197;7964.561949092856;4216.2199993001395;4231.703780735319;4244.978775106105;4246.808893599796;4356.738595684269;3410.4031794435873;3509.6139424663265;3526.3828829954655;3538.5930617978747;3540.451230059345;568.2045136808418;578.9015867302485;588.2881713127164;592.7865968251426;594.5006194796306;593.2850914647839;695.7337645485269;707.6497133918743;718.2817182817183;727.6325455644866;9117.270618350516;9241.630334786667;9390.483523406278;9437.19783818383;9899;677.9966100169468;682.7337338438014;692.5083190934392;695.3394275438253;708.0971761004657;4418.425604671372;4616.584507394284;4712.53911287502;4751.52484751529;4766.761661916959;76984;78312.60843695745;78905.21094789094;79590;79707;69816.30183698099;70808.2919170812;71208.2879171212;72449.56628252902;74292.25707742962;65145;66357.33642663609;67411.66294168498;68113.31886681169;69430;8665.220130188314;8759.649614015369;9013.504257265913;9080.545972701206;9125.133112354315;44675;45243;45768;46209.53790462052;46590;56179;60579.091313631216;60693.69653151706;61773.69113154405;63923;11639.6508104757;11662.70843228926;11680.766384672428;11917.940410297893;11919.702007449883;12713;12742.171758835646;13003.08978371505;13006.219626822422;13412.396442160321;11862.347570883685;12376.381180940736;12513.624591262276;12639.87360126387;12722.427490762753;11538.48076836561;11730.413479326171;11751.882481175078;12220.083493738182;12556.937215313865;11640.27511196409;11909.436068122364;12104.216030313253;12147.734635896642;12168.939155304166;9340.392874463138;9440.905590944003;9745.891775561859;9899.455529945923;10040.949795250977;8984.326175536991;9520;10176.643817466353;10291.433971131659;10446.843297350393 17:36:38 INFO - PROCESS | 1045 | __end_tp_report 17:36:38 INFO - PROCESS | 1045 | __start_cc_report 17:36:38 INFO - PROCESS | 1045 | _x_x_mozilla_cycle_collect,1360 17:36:38 INFO - PROCESS | 1045 | __end_cc_report 17:36:38 INFO - PROCESS | 1045 | __startTimestamp1482370598238__endTimestamp 17:36:38 INFO - PROCESS | 1045 | 17:36:38 INFO - PROCESS | 1045 | ------- Summary: start ------- 17:36:38 INFO - PROCESS | 1045 | Number of tests: 6 17:36:38 INFO - PROCESS | 1045 | 17:36:38 INFO - PROCESS | 1045 | [#0] dojo.html Cycles:165 Average:5236.33 Median:5427.89 stddev:598.27 (11.0%) stddev-sans-first:576.31 17:36:38 INFO - PROCESS | 1045 | Values: 3107.0 3113.7 3132.9 3165.3 3167.3 5117.1 5182.8 5264.8 5271.9 5292.0 5224.7 5243.7 5244.4 5247.6 5279.0 4719.0 4808.1 4843.7 4851.5 4877.6 4732.0 4801.6 4841.4 4841.6 4880.7 4856.2 4867.8 4908.9 4911.6 4955.0 4711.0 4741.0 4751.9 4847.2 4849.7 4729.8 4732.6 4767.5 4809.9 4827.2 5214.8 5228.3 5251.8 5260.4 5295.5 5635.0 5638.3 5639.4 5642.6 5682.5 5585.3 5609.3 5618.9 5642.4 5701.5 5289.1 5317.1 5321.6 5362.7 5368.1 5797.1 5797.3 5807.1 5807.3 5821.3 5436.4 5476.5 5527.1 5580.6 5588.2 5563.2 5595.7 5601.6 5605.4 5694.9 5590.6 5599.1 5603.5 5604.0 5605.6 5538.4 5613.5 5613.9 5617.5 5659.6 4178.2 4192.7 4197.6 4257.4 4264.8 3780.1 3780.6 3782.1 3811.1 3821.7 5387.6 5389.9 5391.4 5396.9 5427.9 5534.5 5572.0 5577.6 5654.9 5691.3 5273.8 5344.2 5359.8 5359.9 5361.7 4655.0 4661.0 4661.7 4666.7 4667.6 5878.1 5883.0 5891.0 5891.0 5894.7 5601.7 5645.0 5646.9 5652.2 5654.5 5888.4 5907.2 5915.1 5915.7 5915.9 5499.7 5500.1 5513.7 5557.0 5562.7 5661.2 5661.6 5667.5 5670.1 5671.9 5365.0 5405.1 5523.5 5524.4 5527.8 5461.6 5464.9 5487.1 5545.1 5588.4 5519.3 5524.8 5531.2 5531.5 5532.0 5424.7 5459.7 5567.1 5582.8 5590.1 5379.4 5389.1 5410.2 5421.4 5421.5 17:36:38 INFO - PROCESS | 1045 | 17:36:38 INFO - PROCESS | 1045 | [#1] ext.html Cycles:180 Average:51703.28 Median:33287.92 stddev:95338.81 (286.4%) stddev-sans-first:95528.87 17:36:38 INFO - PROCESS | 1045 | Values: 540.7 558.3 571.6 574.0 578.1 32303.8 32896.3 43444.1 45253.0 45558.0 44063.3 44266.6 44356.3 44373.8 44390.0 8149.3 8692.9 8874.6 8876.0 8892.6 8137.6 10247.9 10469.1 10487.5 10802.7 10568.8 10635.9 10636.5 10638.9 10642.5 10484.9 10527.2 10547.1 10563.2 10599.2 10357.5 10386.1 10405.5 10423.3 10429.1 40942.6 41085.6 41228.2 41241.4 41271.6 3506.7 3523.8 3560.1 3685.7 3691.7 31757.7 31816.2 32140.4 32781.5 33564.3 4263.6 4302.8 4332.8 4339.8 4350.8 316324.0 316890.0 317814.0 317936.0 318042.0 54175.5 54593.0 54774.2 54816.9 54829.2 56799.7 56847.1 56906.4 56916.1 57033.4 13898.3 14262.8 14262.8 14270.8 14285.6 10096.0 10369.1 11328.1 11645.5 11742.9 14890.6 15422.8 15471.4 15526.7 15603.7 5953.4 5956.1 5998.5 6040.2 6109.3 889.0 954.1 995.9 1020.6 1043.0 30838.5 30990.5 31105.4 31278.3 31348.4 1031.4 1045.6 1061.9 1075.6 1080.8 16441.5 16495.8 16540.6 16636.5 16651.7 449664.0 453844.0 454244.0 456253.0 458885.0 57620.7 57621.0 57676.1 57682.1 57802.4 1248.6 1311.0 1330.8 1354.9 1387.7 28150.7 32285.4 32534.7 32543.0 33011.5 48851.8 49491.8 49508.8 49594.0 49680.3 289795.0 290484.0 290619.0 291232.0 291291.0 24908.6 25155.4 25623.2 26102.8 38983.4 40485.4 40742.0 40808.0 40945.4 41168.8 35383.0 35568.9 35598.0 35867.5 36290.3 35427.6 35463.0 35790.3 35922.6 36361.5 35196.5 35493.1 36276.3 36660.0 36932.4 30771.2 32073.9 32511.7 32660.3 32663.8 25250.2 25297.0 25357.2 25382.4 25521.3 17:36:38 INFO - PROCESS | 1045 | 17:36:38 INFO - PROCESS | 1045 | [#2] jquery.html Cycles:180 Average:22423.90 Median:2652.10 stddev:48112.01 (1814.1%) stddev-sans-first:48218.51 17:36:38 INFO - PROCESS | 1045 | Values: 381.4 397.3 402.9 406.5 418.9 13358.5 15227.2 18346.6 18479.7 18975.4 17562.9 17640.7 17949.8 18153.9 18172.2 5570.1 5772.4 5777.1 5779.4 5781.7 5044.4 6387.3 6424.6 6522.7 6543.5 6255.9 6392.2 6484.1 6491.9 6492.8 6387.4 6409.2 6412.2 6416.4 6417.2 5558.2 5704.7 5722.6 5741.2 5744.8 21963.9 22278.2 22305.7 22307.9 22766.7 2277.7 2320.7 2354.5 2638.3 2665.9 13905.0 14181.8 14323.3 14395.5 14604.5 1949.8 1957.5 1978.5 2016.1 2034.5 73945.0 75098.0 78350.2 78365.0 79056.6 10475.4 10616.0 10682.4 10940.7 10995.5 14517.3 14773.1 14780.0 14898.2 15011.3 7584.5 7793.0 7856.4 7918.2 7939.2 7405.0 7501.0 7541.8 7619.1 7710.1 4481.5 4489.9 4552.6 4558.1 4570.0 1842.2 1854.7 1907.3 1934.9 1940.1 868.8 917.2 976.6 1003.2 1015.1 17471.7 18631.3 18780.9 18907.2 19150.1 968.2 977.9 982.5 1009.7 1062.7 10201.3 10434.3 10631.3 10658.7 10679.4 199074.0 199663.0 201265.0 343750.0 396783.0 64509.0 88896.6 89562.6 92913.5 93187.0 905.0 930.6 936.9 939.7 949.8 15301.2 16172.8 17396.1 17425.7 17461.6 37916.0 40720.6 41187.2 41281.0 41794.8 56031.4 56138.4 56547.4 57058.0 57708.7 8211.5 8441.0 8458.1 8459.0 8630.7 12163.1 13137.7 13470.7 13471.5 13473.0 11425.7 11462.1 11610.6 11798.3 11936.6 11033.8 11069.5 11087.9 11421.1 11547.8 10868.5 11502.0 11780.4 11949.3 12288.5 8474.7 8494.1 8507.9 8577.9 8596.0 11375.6 11586.9 11661.5 11850.5 11905.9 17:36:38 INFO - PROCESS | 1045 | 17:36:38 INFO - PROCESS | 1045 | [#3] mootools.html Cycles:180 Average:5588.20 Median:5205.30 stddev:3064.84 (58.9%) stddev-sans-first:3057.56 17:36:38 INFO - PROCESS | 1045 | Values: 1437.2 1446.5 1611.3 1750.5 1762.6 8190.0 8694.0 8701.6 8707.6 8708.1 7599.7 7842.3 8170.5 8433.2 8544.8 2316.8 2357.2 2357.3 2357.9 2365.2 2328.3 2339.8 2368.6 2376.8 2379.0 2335.0 2346.3 2364.4 2375.6 2385.0 2226.8 2292.6 2308.2 2308.4 2317.8 2235.3 2255.5 2268.8 2272.4 2374.8 8230.8 8296.2 8299.5 8307.1 8316.2 4956.0 5104.6 5111.4 5146.5 5165.9 3206.1 3251.2 3430.3 3432.9 3441.8 1109.7 1109.8 1114.3 1126.1 1151.8 11732.6 11820.3 11848.9 11857.1 11862.3 4345.7 4548.7 4608.1 4621.5 4636.5 9848.8 9882.5 9948.2 9950.5 9958.8 5138.7 5167.8 5187.2 5215.3 5269.3 4210.9 4260.5 4674.2 4731.6 4818.9 3861.7 3929.5 3950.4 3998.3 4047.3 1285.7 1301.0 1302.6 1307.8 1315.2 846.6 869.7 993.7 998.7 1007.0 5429.9 5510.8 5602.9 5603.9 5940.7 956.7 959.6 965.0 981.2 996.1 4154.3 4260.4 4303.6 4439.9 4466.9 10004.8 10284.5 10293.1 10298.5 10312.0 9921.4 10073.4 10202.7 10213.0 10227.9 7425.2 7654.5 7695.9 7873.1 7896.8 5830.2 5832.9 5874.4 5997.8 6105.0 9379.7 9535.4 9569.0 9622.0 9632.4 8895.2 9259.0 9265.0 9342.8 9455.1 6761.4 6950.3 7301.8 7579.1 7758.8 8058.4 8264.7 8274.6 8278.3 8283.1 6923.3 6967.4 7154.7 7163.5 7169.6 6978.2 7529.0 7560.0 7628.5 7682.8 6573.3 6742.2 6744.9 6895.8 6898.7 4907.2 5094.5 5209.3 5380.9 5685.1 5201.3 5315.4 5357.4 5380.2 5396.8 17:36:38 INFO - PROCESS | 1045 | 17:36:38 INFO - PROCESS | 1045 | [#4] prototype.html Cycles:180 Average:4490.64 Median:5044.90 stddev:1284.51 (25.5%) stddev-sans-first:1250.47 17:36:38 INFO - PROCESS | 1045 | Values: 377.8 389.0 389.4 396.8 399.2 5032.4 5065.1 5077.7 5117.8 5133.5 5025.7 5043.4 5046.4 5047.9 5048.3 3847.3 3976.9 4009.6 4091.4 4120.7 3984.1 4015.5 4017.6 4031.2 4036.2 3980.6 3995.8 3997.8 3998.6 3998.7 3922.5 4016.0 4029.7 4030.5 4030.9 3513.8 3525.3 3545.3 3545.4 3551.6 4894.4 4908.0 4957.8 4959.1 4971.8 4576.0 4583.5 4595.9 4599.4 4619.7 5117.5 5239.3 5266.4 5293.0 5307.8 3874.3 3879.6 3885.5 3899.4 3911.7 5302.9 5433.4 5458.5 5548.4 5548.9 3941.3 3962.8 3969.4 3973.6 3981.9 4028.2 4037.2 4045.8 4048.4 4051.2 4392.0 4504.2 4556.5 4597.7 4625.6 5255.5 5270.0 5284.8 5304.0 5304.5 1430.8 1433.2 1433.7 1433.9 1435.3 955.7 997.2 1024.3 1040.9 1056.9 4966.7 4986.7 4995.1 5007.4 5025.2 5264.0 5270.9 5271.8 5273.7 5274.2 4806.1 4956.9 4976.7 4984.5 4999.6 2761.9 2762.7 2774.6 2784.9 2798.7 5451.2 5465.6 5467.4 5490.8 5494.7 5296.2 5369.0 5535.5 5553.5 5557.2 5656.2 5699.3 5708.4 5711.8 5712.5 5247.2 5483.2 5484.9 5517.8 5545.6 5387.3 5413.7 5428.0 5438.1 5483.7 5503.3 5536.9 5638.6 5685.7 5686.5 3977.4 3998.2 4023.9 4024.7 4045.9 5306.6 5517.3 5530.5 5532.4 5543.8 5278.6 5279.5 5305.8 5306.9 5325.5 5286.6 5291.4 5302.9 5324.1 5340.7 5092.7 5332.5 5344.3 5357.2 5358.7 5377.6 5385.5 5392.2 5393.8 5395.3 5250.3 5259.8 5270.2 5276.0 5282.4 17:36:38 INFO - PROCESS | 1045 | 17:36:38 INFO - PROCESS | 1045 | [#5] yui.html Cycles:180 Average:17855.46 Median:573.55 stddev:23694.39 (4131.2%) stddev-sans-first:23725.99 17:36:38 INFO - PROCESS | 1045 | Values: 736.9 744.1 747.9 763.0 810.7 14328.6 15725.5 15761.4 15777.4 15906.6 14513.0 14649.1 14679.6 14697.7 14702.5 662.0 693.2 781.5 786.3 812.8 814.1 824.9 827.0 827.8 828.3 882.6 884.9 885.7 887.0 887.9 701.5 738.5 805.7 873.5 879.1 621.0 623.6 625.1 625.2 625.5 14430.9 14768.9 14770.2 14772.9 14816.0 13126.8 13452.1 13622.2 13655.1 13733.6 13027.5 13059.0 13064.8 13399.8 13401.4 4360.0 4388.3 4464.6 4467.1 4500.5 82121.6 82961.0 83079.0 83615.0 87938.6 10671.3 10679.5 10775.4 10800.9 11026.4 21978.3 22227.7 22304.3 22306.3 22317.6 7605.0 7616.3 7710.4 7781.6 7964.6 4216.2 4231.7 4245.0 4246.8 4356.7 3410.4 3509.6 3526.4 3538.6 3540.5 568.2 578.9 588.3 592.8 594.5 593.3 695.7 707.6 718.3 727.6 9117.3 9241.6 9390.5 9437.2 9899.0 678.0 682.7 692.5 695.3 708.1 4418.4 4616.6 4712.5 4751.5 4766.8 76984.0 78312.6 78905.2 79590.0 79707.0 69816.3 70808.3 71208.3 72449.6 74292.3 65145.0 66357.3 67411.7 68113.3 69430.0 8665.2 8759.6 9013.5 9080.5 9125.1 44675.0 45243.0 45768.0 46209.5 46590.0 56179.0 60579.1 60693.7 61773.7 63923.0 11639.7 11662.7 11680.8 11917.9 11919.7 12713.0 12742.2 13003.1 13006.2 13412.4 11862.3 12376.4 12513.6 12639.9 12722.4 11538.5 11730.4 11751.9 12220.1 12556.9 11640.3 11909.4 12104.2 12147.7 12168.9 9340.4 9440.9 9745.9 9899.5 10040.9 8984.3 9520.0 10176.6 10291.4 10446.8 17:36:38 INFO - PROCESS | 1045 | -------- Summary: end -------- 17:36:38 INFO - PROCESS | 1045 | 17:36:38 INFO - TEST-INFO | 1045: exit 0 17:36:39 INFO - TEST-OK | dromaeo_css | took 1117251ms 17:36:39 INFO - TEST-START | kraken 17:36:39 INFO - operating with platform_type : linux_ 17:36:39 INFO - Initialising browser for kraken test... 17:36:39 INFO - TEST-INFO | started process 1429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF6j81K/profile http://localhost:51776/getInfo.html) 17:36:43 INFO - PROCESS | 1429 | __metrics Screen width/height:1600/1200 17:36:43 INFO - PROCESS | 1429 | colorDepth:24 17:36:43 INFO - PROCESS | 1429 | Browser inner width/height: 1024/697 17:36:43 INFO - PROCESS | 1429 | __metrics 17:36:44 INFO - PROCESS | 1429 | 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" 17:36:44 INFO - PROCESS | 1429 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:36:44 INFO - PROCESS | 1429 | console.error: PushService: 17:36:44 INFO - PROCESS | 1429 | stateChangeProcessEnqueue: Error transitioning state 17:36:44 INFO - PROCESS | 1429 | UnknownError 17:36:45 INFO - TEST-INFO | 1429: exit 0 17:36:45 INFO - Browser initialized. 17:36:45 INFO - Running cycle 1/1 for kraken test... 17:36:45 INFO - TEST-INFO | started process 1515 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF6j81K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:36:57 INFO - PROCESS | 1515 | RSS: Main: 150831104 17:36:57 INFO - PROCESS | 1515 | 17:36:59 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51776/tests/kraken/driver.html?testName=audio-beat-detection) 17:37:00 INFO - PROCESS | 1515 | RSS: Main: 202956800 17:37:00 INFO - PROCESS | 1515 | 17:37:03 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51776/tests/kraken/driver.html?testName=audio-dft) 17:37:04 INFO - PROCESS | 1515 | RSS: Main: 183562240 17:37:04 INFO - PROCESS | 1515 | 17:37:06 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51776/tests/kraken/driver.html?testName=audio-fft) 17:37:07 INFO - PROCESS | 1515 | RSS: Main: 264220672 17:37:07 INFO - PROCESS | 1515 | 17:37:09 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51776/tests/kraken/driver.html?testName=audio-oscillator) 17:37:10 INFO - PROCESS | 1515 | RSS: Main: 175308800 17:37:10 INFO - PROCESS | 1515 | 17:37:12 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51776/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:37:12 INFO - PROCESS | 1515 | RSS: Main: 179720192 17:37:12 INFO - PROCESS | 1515 | 17:37:15 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51776/tests/kraken/driver.html?testName=imaging-darkroom) 17:37:16 INFO - PROCESS | 1515 | RSS: Main: 219623424 17:37:16 INFO - PROCESS | 1515 | 17:37:19 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51776/tests/kraken/driver.html?testName=imaging-desaturate) 17:37:20 INFO - PROCESS | 1515 | RSS: Main: 218738688 17:37:20 INFO - PROCESS | 1515 | 17:37:23 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51776/tests/kraken/driver.html?testName=json-parse-financial) 17:37:23 INFO - PROCESS | 1515 | RSS: Main: 217362432 17:37:23 INFO - PROCESS | 1515 | 17:37:25 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51776/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:37:25 INFO - PROCESS | 1515 | RSS: Main: 180383744 17:37:25 INFO - PROCESS | 1515 | 17:37:27 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51776/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:37:28 INFO - PROCESS | 1515 | RSS: Main: 187604992 17:37:28 INFO - PROCESS | 1515 | 17:37:30 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51776/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:37:30 INFO - PROCESS | 1515 | RSS: Main: 195510272 17:37:30 INFO - PROCESS | 1515 | 17:37:33 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51776/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:37:34 INFO - PROCESS | 1515 | RSS: Main: 204419072 17:37:34 INFO - PROCESS | 1515 | 17:37:37 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51776/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:37:37 INFO - PROCESS | 1515 | RSS: Main: 201773056 17:37:37 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | Cycle 1(1): loaded http://localhost:51776/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51776/tests/kraken/driver.html?testName=ai-astar) 17:37:39 INFO - PROCESS | 1515 | RSS: Main: 251617280 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | __start_tp_report 17:37:39 INFO - PROCESS | 1515 | _x_x_mozilla_page_load 17:37:39 INFO - PROCESS | 1515 | _x_x_mozilla_page_load_details 17:37:39 INFO - PROCESS | 1515 | |i|pagename|runs| 17:37:39 INFO - PROCESS | 1515 | |0;ai-astar;112;112;111;112;112;112;112;112;111;112 17:37:39 INFO - PROCESS | 1515 | |1;audio-beat-detection;146;119;124;153;134;121;119;119;137;120 17:37:39 INFO - PROCESS | 1515 | |2;audio-dft;161;161;161;161;160;161;159;161;161;162 17:37:39 INFO - PROCESS | 1515 | |3;audio-fft;74;75;75;109;76;75;77;77;96;76 17:37:39 INFO - PROCESS | 1515 | |4;audio-oscillator;83;84;113;84;83;83;94;83;84;111 17:37:39 INFO - PROCESS | 1515 | |5;imaging-gaussian-blur;117;117;117;117;117;116;116;116;116;117 17:37:39 INFO - PROCESS | 1515 | |6;imaging-darkroom;146;146;146;147;147;147;147;147;147;147 17:37:39 INFO - PROCESS | 1515 | |7;imaging-desaturate;96;96;95;95;95;96;95;95;95;96 17:37:39 INFO - PROCESS | 1515 | |8;json-parse-financial;52;55;50;51;52;51;54;52;52;52 17:37:39 INFO - PROCESS | 1515 | |9;json-stringify-tinderbox;65;66;65;66;66;65;65;65;66;65 17:37:39 INFO - PROCESS | 1515 | |10;stanford-crypto-aes;81;82;81;81;82;81;82;81;80;80 17:37:39 INFO - PROCESS | 1515 | |11;stanford-crypto-ccm;126;123;139;127;127;134;128;124;173;122 17:37:39 INFO - PROCESS | 1515 | |12;stanford-crypto-pbkdf2;167;170;168;166;171;182;171;169;163;169 17:37:39 INFO - PROCESS | 1515 | |13;stanford-crypto-sha256-iterative;61;63;61;61;62;62;65;63;60;61 17:37:39 INFO - PROCESS | 1515 | __end_tp_report 17:37:39 INFO - PROCESS | 1515 | __start_cc_report 17:37:39 INFO - PROCESS | 1515 | _x_x_mozilla_cycle_collect,1589 17:37:39 INFO - PROCESS | 1515 | __end_cc_report 17:37:39 INFO - PROCESS | 1515 | __startTimestamp1482370659529__endTimestamp 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | ------- Summary: start ------- 17:37:39 INFO - PROCESS | 1515 | Number of tests: 14 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:37:39 INFO - PROCESS | 1515 | Values: 112.0 112.0 111.0 112.0 112.0 112.0 112.0 112.0 111.0 112.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:129.00 stddev:12.58 (9.7%) stddev-sans-first:11.78 17:37:39 INFO - PROCESS | 1515 | Values: 146.0 119.0 124.0 153.0 134.0 121.0 119.0 119.0 137.0 120.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 17:37:39 INFO - PROCESS | 1515 | Values: 161.0 161.0 161.0 161.0 160.0 161.0 159.0 161.0 161.0 162.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:11.78 (15.5%) stddev-sans-first:12.21 17:37:39 INFO - PROCESS | 1515 | Values: 74.0 75.0 75.0 109.0 76.0 75.0 77.0 77.0 96.0 76.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:83.50 stddev:11.97 (14.3%) stddev-sans-first:12.41 17:37:39 INFO - PROCESS | 1515 | Values: 83.0 84.0 113.0 84.0 83.0 83.0 94.0 83.0 84.0 111.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 17:37:39 INFO - PROCESS | 1515 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 116.0 117.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 17:37:39 INFO - PROCESS | 1515 | Values: 146.0 146.0 146.0 147.0 147.0 147.0 147.0 147.0 147.0 147.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 17:37:39 INFO - PROCESS | 1515 | Values: 96.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:1.45 (2.8%) stddev-sans-first:1.54 17:37:39 INFO - PROCESS | 1515 | Values: 52.0 55.0 50.0 51.0 52.0 51.0 54.0 52.0 52.0 52.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 17:37:39 INFO - PROCESS | 1515 | Values: 65.0 66.0 65.0 66.0 66.0 65.0 65.0 65.0 66.0 65.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.00 stddev:0.74 (0.9%) stddev-sans-first:0.78 17:37:39 INFO - PROCESS | 1515 | Values: 81.0 82.0 81.0 81.0 82.0 81.0 82.0 81.0 80.0 80.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#11] stanford-crypto-ccm Cycles:10 Average:132.30 Median:127.50 stddev:15.20 (11.9%) stddev-sans-first:15.95 17:37:39 INFO - PROCESS | 1515 | Values: 126.0 123.0 139.0 127.0 127.0 134.0 128.0 124.0 173.0 122.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:169.50 stddev:4.99 (2.9%) stddev-sans-first:5.21 17:37:39 INFO - PROCESS | 1515 | Values: 167.0 170.0 168.0 166.0 171.0 182.0 171.0 169.0 163.0 169.0 17:37:39 INFO - PROCESS | 1515 | 17:37:39 INFO - PROCESS | 1515 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.45 (2.3%) stddev-sans-first:1.50 17:37:39 INFO - PROCESS | 1515 | Values: 61.0 63.0 61.0 61.0 62.0 62.0 65.0 63.0 60.0 61.0 17:37:39 INFO - PROCESS | 1515 | -------- Summary: end -------- 17:37:39 INFO - PROCESS | 1515 | 17:37:40 INFO - TEST-INFO | 1515: exit 0 17:37:40 INFO - TEST-OK | kraken | took 61583ms 17:37:40 INFO - SUITE-END | took 1178s 17:37:40 INFO - Completed test suite (00:19:38) 17:37:40 INFO - javascript benchmark 17:37:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3107.0, 3113.7353324967376, 3132.937341253174, 3165.256164801273, 3167.334859679467, 5117.053345131153, 5182.818601348954, 5264.8157314494, 5271.894562108756, 5291.973540132303, 5224.686518808874, 5243.68537887727, 5244.370675518943, 5247.580193584514, 5279.0, 4718.97640511797, 4808.110499557593, 4843.7335946522835, 4851.490593487686, 4877.561019508244, 4732.0, 4801.591864691505, 4841.443234028079, 4841.636877234221, 4880.682755620891, 4856.150173719602, 4867.829625963092, 4908.852734417973, 4911.582515486188, 4955.033768415161, 4710.976445117787, 4741.028089241731, 4751.928721069186, 4847.175980083394, 4849.709017458964, 4729.810807567693, 4732.645051621143, 4767.499412561666, 4809.927851082237, 4827.203511420611, 5214.817481388133, 5228.320318358627, 5251.816186433494, 5260.395054568736, 5295.4704529547125, 5635.042042852725, 5638.267025286727, 5639.379668236491, 5642.605017648766, 5682.460166284196, 5585.329760428734, 5609.270794796691, 5618.859528511779, 5642.407547207526, 5701.486866182022, 5289.1008528549855, 5317.096093664087, 5321.600879933943, 5362.651427657229, 5368.141097424433, 5797.072468405035, 5797.275340582427, 5807.0998995155815, 5807.274090738658, 5821.27234095738, 5436.429174936654, 5476.479734425223, 5527.143292789624, 5580.581456390787, 5588.161775733673, 5563.193336966198, 5595.664260144323, 5601.63589366698, 5605.383407825136, 5694.886102277931, 5590.636608620427, 5599.132134519156, 5603.495685388335, 5604.0, 5605.579581531401, 5538.363088244822, 5613.522850557667, 5613.8596535086135, 5617.522510586646, 5659.5755318351285, 4178.206140833232, 4192.727472714325, 4197.601227883346, 4257.3613957905945, 4264.829406823755, 3780.0738818989525, 3780.5652349979555, 3782.0733920189728, 3811.0662887592716, 3821.732478726518, 5387.595930305243, 5389.892202155935, 5391.353037635509, 5396.892062158735, 5427.891442171134, 5534.501894829485, 5572.0, 5577.581681373913, 5654.886902261932, 5691.3170419549915, 5273.789048438096, 5344.225087362311, 5359.785608575691, 5359.919601205985, 5361.651492652965, 4655.0, 4661.021185551004, 4661.650376221797, 4666.65000124992, 4667.6265898728025, 5878.088896221093, 5882.999890018707, 5890.970545147246, 5891.027980383189, 5894.705264736832, 5601.719914004202, 5644.955683198621, 5646.9152962704775, 5652.180433837153, 5654.519365853784, 5888.381719919346, 5907.232059832194, 5915.112733090071, 5915.733791979285, 5915.852103697442, 5499.7250137492165, 5500.147477141049, 5513.724313784375, 5557.0, 5562.694051827189, 5661.179129026185, 5661.631993920382, 5667.546596272371, 5670.149477578396, 5671.914921276267, 5364.973175134255, 5405.135178371442, 5523.499972665769, 5524.447555244444, 5527.806526771544, 5461.644993075437, 5464.863378415413, 5487.067198576331, 5545.140503222007, 5588.3573389060775, 5519.337679478488, 5524.779008839601, 5531.197976293334, 5531.474509921551, 5532.004239236976, 5424.674519528841, 5459.727013649381, 5567.10926251814, 5582.7766889323975, 5590.105583106683, 5379.408265090915, 5389.110796718497, 5410.188471729115, 5421.430749771375, 5421.484958928895], "value": 5196.129660809654, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.7026135625406, 558.3021223470662, 571.6141604417019, 573.9626924249924, 578.0895090232884, 32303.838480807593, 32896.34207315855, 43444.13111737767, 45253.0, 45558.0, 44063.33904991428, 44266.557334426645, 44356.3346549802, 44373.7781311093, 44390.0, 8149.307308878722, 8692.869606955917, 8874.600642971081, 8876.0, 8892.599833007498, 8137.633806478722, 10247.897521024768, 10469.11012563933, 10487.528061237263, 10802.72993175173, 10568.841467377995, 10635.946820265888, 10636.468176591177, 10638.946805265963, 10642.521086551085, 10484.895151048468, 10527.157827373792, 10547.05076543111, 10563.207759418074, 10599.152067834555, 10357.48212589375, 10386.065254127165, 10405.47972601367, 10423.322484038588, 10429.113525350354, 40942.59057409447, 41085.58914410877, 41228.1754364914, 41241.38137927934, 41271.587284127076, 3506.7019303359243, 3523.841427135785, 3560.0743806610208, 3685.7419980601367, 3691.741578089535, 31757.682423175702, 31816.204594885083, 32140.357192856245, 32781.508277375855, 33564.328713425835, 4263.5736426357425, 4302.763348015857, 4332.761698106603, 4339.826406943718, 4350.782460876943, 316324.0, 316890.0, 317814.0, 317935.9999999977, 318042.0, 54175.45824541704, 54593.0, 54774.178387324224, 54816.858881107124, 54829.1775623366, 56799.740019497876, 56847.14729279064, 56906.43093569094, 56916.14625780617, 57033.42966570364, 13898.305084745722, 14262.786058209134, 14262.786058209134, 14270.785938210935, 14285.64285892861, 10096.0, 10369.066783989478, 11328.093752499944, 11645.475953582109, 11742.88257117418, 14890.627734306729, 15422.845771542141, 15471.381144754083, 15526.689466210839, 15603.687926241411, 5953.374895635982, 5956.1065840123465, 5998.520118390518, 6040.214772079602, 6109.327973922865, 888.9555522223862, 954.1126752120456, 995.915347195482, 1020.642775028734, 1042.9739256518496, 30838.53742193869, 30990.535141972887, 31105.377892442477, 31278.257090188017, 31348.37303253922, 1031.437866362834, 1045.5660900726114, 1061.946902654864, 1075.5697720911699, 1080.8054550181043, 16441.506754797374, 16495.752563711554, 16540.609669587535, 16636.50090497287, 16651.6669666606, 449664.0, 453844.0, 454244.0, 456253.0, 458885.0, 57620.71189644109, 57621.0, 57676.134857977166, 57682.13476797851, 57802.421975779704, 1248.6004478566772, 1310.9541166059141, 1330.8469526004633, 1354.8848347890344, 1387.66695992963, 28150.718492815628, 32285.35429291448, 32534.67465325411, 32543.0, 33011.504827427125, 48851.755741221066, 49491.75254123707, 49508.75245623749, 49594.0, 49680.25479617736, 289795.0, 290484.0, 290619.0, 291232.0, 291291.0, 24908.62637060409, 25155.371115722253, 25623.23130306019, 26102.779444111635, 38983.415248770725, 40485.39271910983, 40742.0, 40808.0, 40945.385819213334, 41168.79415602903, 35383.0, 35568.886222755034, 35598.0, 35867.461988070725, 36290.27419451649, 35427.645723543465, 35463.0, 35790.28419431649, 35922.64077359193, 36361.45457818082, 35196.47205291872, 35493.1126721834, 36276.27447451089, 36660.0, 36932.44601330929, 30771.230719232197, 32073.87741429039, 32511.67488325181, 32660.34679306353, 32663.836680816443, 25250.242492725247, 25297.0, 25357.239282821545, 25382.365440864127, 25521.284325141813], "value": 19010.032655578383, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.4183370360202, 397.2967846910966, 402.9456023436836, 406.4654978703005, 418.8764314527214, 13358.465661373546, 15227.238638068095, 18346.633067338662, 18479.722804157915, 18975.430737077877, 17562.912185439087, 17640.735388969177, 17949.820501794977, 18153.90923045386, 18172.18225179867, 5570.108782594785, 5772.4227577242145, 5777.133429985493, 5779.393163717812, 5781.682007489587, 5044.419891712463, 6387.2654644715985, 6424.614523128627, 6522.728068026737, 6543.541952063358, 6255.937440625581, 6392.23293204816, 6484.0598113273545, 6491.902621460682, 6492.772752953648, 6387.361263873622, 6409.198850143732, 6412.230532336102, 6416.422521973045, 6417.165768450095, 5558.249636299089, 5704.743286552115, 5722.62802917809, 5741.224934633813, 5744.798932037359, 21963.890180549155, 22278.220262290903, 22305.665415018786, 22307.888460557595, 22766.65850012251, 2277.749447560767, 2320.7447180810095, 2354.4702441950594, 2638.300850274679, 2665.906978138954, 13905.0, 14181.78727319091, 14323.283835808272, 14395.496157634432, 14604.488842890449, 1949.7855235924037, 1957.5204075001716, 1978.5251539630528, 2016.1028342387644, 2034.4608678700156, 73945.0, 75098.0, 78350.21649783543, 78365.0, 79056.60471697604, 10475.42385168823, 10616.0, 10682.412467314372, 10940.671779846618, 10995.450227488594, 14517.346719397652, 14773.113613183243, 14780.039297445632, 14898.1806000671, 15011.324490397958, 7584.54492730438, 7793.0, 7856.371490280878, 7918.168592297841, 7939.20607939213, 7405.0, 7501.0, 7541.849163016709, 7619.08570971487, 7710.11333696632, 4481.5294394088805, 4489.910201795946, 4552.590266875997, 4558.111168322212, 4569.977150114228, 1842.1986435900426, 1854.7125195594704, 1907.2943011085988, 1934.8839069655867, 1940.0687669918518, 868.8140687961014, 917.2341095185461, 976.6337623391229, 1003.2124782046204, 1015.1067060986433, 17471.650566988843, 18631.347902823607, 18780.90609546971, 18907.243710251434, 19150.138243779063, 968.1577027985697, 977.9266555008403, 982.5332966840837, 1009.6869970309227, 1062.7130674717937, 10201.265784816598, 10434.269601127848, 10631.255812093234, 10658.73353166177, 10679.359238445562, 199074.0, 199663.0, 201265.0, 343750.0, 396783.0, 64509.0, 88896.555517222, 89562.55218723995, 92913.53543232376, 93187.0, 904.9638014479478, 930.5858892792778, 936.864154697565, 939.7039932421266, 949.7530642032983, 15301.15843628611, 16172.838271617133, 17396.130193490528, 17425.738613921054, 17461.563460913578, 37916.0, 40720.592794072865, 41187.1762564753, 41281.0, 41794.79102604467, 56031.43968560262, 56138.43861561332, 56547.434525654215, 57058.0, 57708.71145644245, 8211.507309561446, 8441.0, 8458.111898250841, 8458.957705211435, 8630.654773808978, 12163.148579599343, 13137.671558211121, 13470.730585388434, 13471.528496502575, 13473.0, 11425.657230283105, 11462.14033947474, 11610.593629223275, 11798.289248058813, 11936.58221962227, 11033.779324413626, 11069.5018724156, 11087.889121108685, 11421.1434142438, 11547.769044618892, 10868.51091700891, 11502.0, 11780.352080635646, 11949.283043017447, 12288.50845966187, 8474.703385381483, 8494.1081186477, 8507.914920850711, 8577.8713319299, 8595.957020214859, 11375.601853935405, 11586.88413115858, 11661.53353865836, 11850.52597896109, 11905.880941190477], "value": 9031.115573870718, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.1664434627914, 1446.5371081253995, 1611.2588209423664, 1750.4748575427368, 1762.5505496098501, 8189.959050204756, 8693.95653021734, 8701.608427620757, 8707.608157632905, 8708.12918708129, 7599.696012159521, 7842.333401660851, 8170.468919520227, 8433.156684331563, 8544.829103417927, 2316.8494047886916, 2357.163207061494, 2357.316378250314, 2357.905683772647, 2365.2431222008963, 2328.2549584133085, 2339.789418952294, 2368.6210206366986, 2376.7979721723673, 2378.95242095158, 2335.0, 2346.2609278077434, 2364.408897775556, 2375.6080246759266, 2384.988075059631, 2226.799588037068, 2292.64464008079, 2308.215206829686, 2308.4228942764307, 2317.756635553268, 2235.284708893155, 2255.458689914425, 2268.829837762158, 2272.3864556569797, 2374.750651181618, 8230.753077407688, 8296.170382961716, 8299.543525106117, 8307.08622051574, 8316.209960053786, 4956.0, 5104.64267501275, 5111.386633603991, 5146.459621739701, 5165.948340516622, 3206.1022913584, 3251.2387414874474, 3430.3482338355634, 3432.8798492052906, 3441.8107004114763, 1109.7003808971613, 1109.8085189527176, 1114.3258328711095, 1126.1328776841867, 1151.756103408317, 11732.589359372381, 11820.349880756641, 11848.88151118495, 11857.110716696283, 11862.347570883598, 4345.695801293879, 4548.704334218265, 4608.101420223024, 4621.468531118897, 4636.466806317249, 9848.753781155528, 9882.505874706236, 9948.154406875497, 9950.452725100044, 9958.751031224278, 5138.743062846843, 5167.819126330561, 5187.221916712523, 5215.348081489814, 5269.314989051399, 4210.863066971961, 4260.467441569804, 4674.205385084544, 4731.621470282369, 4818.927716084261, 3861.710371722132, 3929.5088113985753, 3950.3679411294056, 3998.260321840469, 4047.291723948297, 1285.6721536008445, 1300.9639913535286, 1302.5636411801963, 1307.7907534794388, 1315.2437348524636, 846.6105591428012, 869.7347309070794, 993.6720882108887, 998.6854140945579, 1007.0131271354114, 5429.864253393618, 5510.752016159283, 5602.915956260659, 5603.887922241533, 5940.732667029994, 956.6986399284342, 959.64972784933, 965.0, 981.215027977615, 996.0637001218831, 4154.335306350969, 4260.360945858146, 4303.591158839905, 4439.889002774957, 4466.9106617867465, 10004.799904002024, 10284.485775711184, 10293.125084367763, 10298.536565854554, 10312.0, 9921.355111917703, 10073.445960472245, 10202.693919182437, 10213.0, 10227.87493375728, 7425.1832298447125, 7654.502457340255, 7695.923040769632, 7873.094594121635, 7896.84206315882, 5830.154627578892, 5832.912506312324, 5874.412558744091, 5997.82006539787, 6104.969475152596, 9379.671711490339, 9535.38020028696, 9568.952155239458, 9622.037796220602, 9632.373895696757, 8895.24390426795, 9258.95370523143, 9265.0, 9342.813143737223, 9455.101765332283, 6761.391474767294, 6950.339717726808, 7301.780946571611, 7579.090509138939, 7758.806029849299, 8058.3553315733425, 8264.669413223404, 8274.627641756257, 8278.254957053894, 8283.08886022549, 6923.307669233036, 6967.407770339578, 7154.713811447484, 7163.498555101093, 7169.569825810259, 6978.162620485574, 7528.962355188189, 7560.0, 7628.46600737943, 7682.846343072995, 6573.3426657333885, 6742.157230346207, 6744.865102698017, 6895.827604309732, 6898.689558969959, 4907.190313598217, 5094.465081166572, 5209.270702101633, 5380.919286210788, 5685.090385538294, 5201.323827902348, 5315.388730295911, 5357.357117145967, 5380.246765452918, 5396.7571459283645], "value": 4537.655499017911, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.8011970332665, 388.98444062237513, 389.3653345047572, 396.8253968253967, 399.23944884994097, 5032.370953630796, 5065.075271396864, 5077.669951453153, 5117.846464606063, 5133.511281728299, 5025.698458092517, 5043.360108699996, 5046.44489106198, 5047.9399326141465, 5048.29323894655, 3847.3267178243836, 3976.940345894814, 4009.5789942056103, 4091.4067460218175, 4120.732152410099, 3984.1234928315725, 4015.4980627421573, 4017.6183262590007, 4031.1736094100784, 4036.1927614477077, 3980.6417422431973, 3995.840166393341, 3997.8400863965408, 3998.5601583825755, 3998.700097492685, 3922.5096862892137, 4015.959840401588, 4029.738067025634, 4030.4961879765174, 4030.919381612351, 3513.789172649649, 3525.2773181497732, 3545.2732189901008, 3545.3618348697223, 3551.6093229744965, 4894.360389269599, 4908.01839632075, 4957.776900039471, 4959.132151873408, 4971.751412429364, 4575.977120114411, 4583.472900616405, 4595.908081838378, 4599.402077729907, 4619.74591397473, 5117.5394214520875, 5239.318888544503, 5266.394364648077, 5293.0, 5307.787688492455, 3874.2638898609457, 3879.5538513071074, 3885.494885664874, 3899.395593682984, 3911.7116486855225, 5302.859885124678, 5433.402325744165, 5458.4814442628285, 5548.447714051541, 5548.944510554924, 3941.2511622791576, 3962.8018599069933, 3969.4244334571904, 3973.6026397360033, 3981.9402708959387, 4028.2144981728284, 4037.1723796621623, 4045.838166473367, 4048.4129801178665, 4051.1897620476025, 4391.978040109779, 4504.189245935698, 4556.498785133633, 4597.747123908217, 4625.583697467244, 5255.527002569787, 5269.973650131725, 5284.841454756363, 5304.048483879535, 5304.522592966651, 1430.763923952536, 1433.1544388810444, 1433.6917562723856, 1433.8852891768634, 1435.2967046147312, 955.7371722776293, 997.2321312589265, 1024.293237666023, 1040.8542804007445, 1056.9207309451822, 4966.726830024383, 4986.650934434555, 4995.100881841304, 5007.39911210657, 5025.246213067996, 5264.0, 5270.947290527122, 5271.789128434896, 5273.736313184326, 5274.208868669731, 4806.134895718735, 4956.8512944611, 4976.676516026447, 4984.501549844987, 4999.625028122905, 2761.8895244190408, 2762.682291536499, 2774.639296891391, 2784.902528411496, 2798.6921438641734, 5451.209574611659, 5465.617406781566, 5467.398586155519, 5490.752916118784, 5494.697791621499, 5296.232046353257, 5368.973155134199, 5535.474129957647, 5553.527950124285, 5557.194206839986, 5656.208130861683, 5699.2590963174525, 5708.372079071299, 5711.771529138788, 5712.457316554921, 5247.1866860636665, 5483.177523371526, 5484.91772623403, 5517.834464966058, 5545.5599536203945, 5387.326584176978, 5413.70224637633, 5427.99582077317, 5438.102713052302, 5483.670979741229, 5503.284573005483, 5536.944630553643, 5638.577106716931, 5685.744141513559, 5686.488216060575, 3977.42327362525, 3998.1808277234372, 4023.879283621496, 4024.7182697212083, 4045.8786236412957, 5306.575473961997, 5517.310336207974, 5530.5022547970875, 5532.3637781655625, 5543.805966791143, 5278.551323137576, 5279.498447647467, 5305.840824775263, 5306.893862122659, 5325.4940780625775, 5286.550643195218, 5291.391489978701, 5302.920456193083, 5324.148136298329, 5340.6528575644015, 5092.668976516515, 5332.466753324792, 5344.25180474726, 5357.1785592898605, 5358.732063396893, 5377.623566350318, 5385.46145385474, 5392.245085688085, 5393.757280922447, 5395.298611180521, 5250.264962905273, 5259.763310650953, 5270.156774915995, 5275.973620131875, 5282.418933917189], "value": 4107.971543278239, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.8636802191595, 744.0898080586517, 747.8653842308382, 763.027140395995, 810.6878851642116, 14328.641783955394, 15725.528234152966, 15761.448349307777, 15777.447789327362, 15906.602334941617, 14513.0, 14649.121052736817, 14679.63300917475, 14697.706045879077, 14702.485413010547, 661.9768308109217, 693.2201273567237, 781.452982911961, 786.268770043859, 812.8455593437228, 814.0841553252591, 824.8597738384489, 827.0158511371449, 827.7847759582522, 828.3166387730116, 882.5984177199359, 884.8672699095155, 885.7475619448459, 887.0287035695902, 887.9112088791134, 701.5194591704699, 738.4793720427086, 805.6535689653447, 873.5151990645189, 879.0901417033372, 620.9844753881189, 623.5510432488601, 625.1154616218046, 625.1841347042108, 625.4652272307203, 14430.927845360811, 14768.926155369261, 14770.187639679812, 14772.926135369255, 14816.0, 13126.803097953538, 13452.1256118352, 13622.182669039888, 13655.088287639739, 13733.587992360246, 13027.478900843955, 13059.0, 13064.804027939588, 13399.799003014963, 13401.396937137852, 4360.0, 4388.253996820548, 4464.6428285737065, 4467.128909862578, 4500.48244451889, 82121.58939205266, 82961.02233865987, 83079.0, 83615.0, 87938.56030719806, 10671.25301228922, 10679.519421626046, 10775.407352595526, 10800.891991080145, 11026.44867756609, 21978.340649780534, 22227.66658500124, 22304.330870073925, 22306.330810075724, 22317.553648927256, 7604.961975190199, 7616.276453736886, 7710.383169346439, 7781.572013539197, 7964.561949092856, 4216.2199993001395, 4231.703780735319, 4244.978775106105, 4246.808893599796, 4356.738595684269, 3410.4031794435873, 3509.6139424663265, 3526.3828829954655, 3538.5930617978747, 3540.451230059345, 568.2045136808418, 578.9015867302485, 588.2881713127164, 592.7865968251426, 594.5006194796306, 593.2850914647839, 695.7337645485269, 707.6497133918743, 718.2817182817183, 727.6325455644866, 9117.270618350516, 9241.630334786667, 9390.483523406278, 9437.19783818383, 9899.0, 677.9966100169468, 682.7337338438014, 692.5083190934392, 695.3394275438253, 708.0971761004657, 4418.425604671372, 4616.584507394284, 4712.53911287502, 4751.52484751529, 4766.761661916959, 76984.0, 78312.60843695745, 78905.21094789094, 79590.0, 79707.0, 69816.30183698099, 70808.2919170812, 71208.2879171212, 72449.56628252902, 74292.25707742962, 65145.0, 66357.33642663609, 67411.66294168498, 68113.31886681169, 69430.0, 8665.220130188314, 8759.649614015369, 9013.504257265913, 9080.545972701206, 9125.133112354315, 44675.0, 45243.0, 45768.0, 46209.53790462052, 46590.0, 56179.0, 60579.091313631216, 60693.69653151706, 61773.69113154405, 63923.0, 11639.6508104757, 11662.70843228926, 11680.766384672428, 11917.940410297893, 11919.702007449883, 12713.0, 12742.171758835646, 13003.08978371505, 13006.219626822422, 13412.396442160321, 11862.347570883685, 12376.381180940736, 12513.624591262276, 12639.87360126387, 12722.427490762753, 11538.48076836561, 11730.413479326171, 11751.882481175078, 12220.083493738182, 12556.937215313865, 11640.27511196409, 11909.436068122364, 12104.216030313253, 12147.734635896642, 12168.939155304166, 9340.392874463138, 9440.905590944003, 9745.891775561859, 9899.455529945923, 10040.949795250977, 8984.326175536991, 9520.0, 10176.643817466353, 10291.433971131659, 10446.843297350393], "value": 7072.083527628062, "unit": "score"}], "name": "dromaeo_css", "value": 6999.643801464462, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0, 111.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 119.0, 124.0, 153.0, 134.0, 121.0, 119.0, 119.0, 137.0, 120.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 160.0, 161.0, 159.0, 161.0, 161.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 109.0, 76.0, 75.0, 77.0, 77.0, 96.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 113.0, 84.0, 83.0, 83.0, 94.0, 83.0, 84.0, 111.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 50.0, 51.0, 52.0, 51.0, 54.0, 52.0, 52.0, 52.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 81.0, 81.0, 82.0, 81.0, 82.0, 81.0, 80.0, 80.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 123.0, 139.0, 127.0, 127.0, 134.0, 128.0, 124.0, 173.0, 122.0], "value": 132.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 168.0, 166.0, 171.0, 182.0, 171.0, 169.0, 163.0, 169.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 61.0, 62.0, 62.0, 65.0, 63.0, 60.0, 61.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1494.1, "alertThreshold": 2.0}]} 17:37:40 INFO - Return code: 0 17:37:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:37:40 INFO - # TBPL SUCCESS # 17:37:40 INFO - Running post-action listener: _resource_record_post_action 17:37:40 INFO - [mozharness: 2016-12-22 01:37:40.820248Z] Finished run-tests step (success) 17:37:40 INFO - Running post-run listener: _resource_record_post_run 17:37:40 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 401379328; Read time: 0; Write time: 795136 17:37:40 INFO - TinderboxPrint: CPU usage
13.1% 17:37:40 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 17:37:40 INFO - TinderboxPrint: I/O write bytes / time
401,379,328 / 795,136 17:37:40 INFO - TinderboxPrint: CPU idle
8,294.0 (86.9%) 17:37:40 INFO - TinderboxPrint: CPU user
1,196.8 (12.5%) 17:37:40 INFO - TinderboxPrint: Swap in / out
0 / 0 17:37:40 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 105639936; Read time: 0; Write time: 195800 17:37:40 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 295739392; Read time: 0; Write time: 599336 17:37:41 INFO - Running post-run listener: _upload_blobber_files 17:37:41 INFO - Blob upload gear active. 17:37:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:37:41 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 17:37:41 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'] 17:37:41 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 17:37:41 INFO - (blobuploader) - INFO - Open directory for files ... 17:37:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:37:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:37:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:42 INFO - (blobuploader) - INFO - Done attempting. 17:37:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:37:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:37:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:46 INFO - (blobuploader) - INFO - Done attempting. 17:37:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:37:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:37:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:46 INFO - (blobuploader) - INFO - Done attempting. 17:37:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:37:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:37:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:47 INFO - (blobuploader) - INFO - Done attempting. 17:37:47 INFO - (blobuploader) - INFO - Iteration through files over. 17:37:47 INFO - Return code: 0 17:37:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:37:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:37:47 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/33f8fd4f629caf74f56900d841b5e89a6a04eed7771f84e2f42940512e95e7ec2ecbf51991c33dae0c74ad2e7f7841a65fe3eae9040d136886265c9baf95287e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eecad05c8c3ee98b6203c7755b3ea59e961e3a96e483176fdd5c523b04faeba018ab94019d389ec245d9706b2e580a814355c32cd8d375cb3dfd8e78c44ea508", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/08a1cc5a869aad04211f06b1cb08ed8e69b3e5d6cc5fdfd7b99b6d87831a9fcc3a5abe3cdaf5e8ed1b1a2533ecd46e26f3fd7536d76c57be7efede4fcdea5564", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ff4bf147d7f8c5bd073f341cbb8e866b4b8af7cd31b1bcd21e066e26a1e18806ba89da84a233d66db9c04cff4fabec87576281e1a21b0f8890a5fd2ef9aa1542"} 17:37:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:37:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:37:47 INFO - Contents: 17:37:47 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/33f8fd4f629caf74f56900d841b5e89a6a04eed7771f84e2f42940512e95e7ec2ecbf51991c33dae0c74ad2e7f7841a65fe3eae9040d136886265c9baf95287e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eecad05c8c3ee98b6203c7755b3ea59e961e3a96e483176fdd5c523b04faeba018ab94019d389ec245d9706b2e580a814355c32cd8d375cb3dfd8e78c44ea508", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/08a1cc5a869aad04211f06b1cb08ed8e69b3e5d6cc5fdfd7b99b6d87831a9fcc3a5abe3cdaf5e8ed1b1a2533ecd46e26f3fd7536d76c57be7efede4fcdea5564", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ff4bf147d7f8c5bd073f341cbb8e866b4b8af7cd31b1bcd21e066e26a1e18806ba89da84a233d66db9c04cff4fabec87576281e1a21b0f8890a5fd2ef9aa1542"} 17:37:47 INFO - Running post-run listener: copy_logs_to_upload_dir 17:37:47 INFO - Copying logs to upload dir... 17:37:47 INFO - mkdir: /builds/slave/test/build/upload/logs 17:37:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.875915 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-21 17:37:46.885367) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 17:37:46.888463) ========= 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=df17445c3590ff7897617a0e00000225-1482369270.228303-794034786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/33f8fd4f629caf74f56900d841b5e89a6a04eed7771f84e2f42940512e95e7ec2ecbf51991c33dae0c74ad2e7f7841a65fe3eae9040d136886265c9baf95287e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eecad05c8c3ee98b6203c7755b3ea59e961e3a96e483176fdd5c523b04faeba018ab94019d389ec245d9706b2e580a814355c32cd8d375cb3dfd8e78c44ea508", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/08a1cc5a869aad04211f06b1cb08ed8e69b3e5d6cc5fdfd7b99b6d87831a9fcc3a5abe3cdaf5e8ed1b1a2533ecd46e26f3fd7536d76c57be7efede4fcdea5564", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ff4bf147d7f8c5bd073f341cbb8e866b4b8af7cd31b1bcd21e066e26a1e18806ba89da84a233d66db9c04cff4fabec87576281e1a21b0f8890a5fd2ef9aa1542"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011431 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482363302/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/33f8fd4f629caf74f56900d841b5e89a6a04eed7771f84e2f42940512e95e7ec2ecbf51991c33dae0c74ad2e7f7841a65fe3eae9040d136886265c9baf95287e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eecad05c8c3ee98b6203c7755b3ea59e961e3a96e483176fdd5c523b04faeba018ab94019d389ec245d9706b2e580a814355c32cd8d375cb3dfd8e78c44ea508", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/08a1cc5a869aad04211f06b1cb08ed8e69b3e5d6cc5fdfd7b99b6d87831a9fcc3a5abe3cdaf5e8ed1b1a2533ecd46e26f3fd7536d76c57be7efede4fcdea5564", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ff4bf147d7f8c5bd073f341cbb8e866b4b8af7cd31b1bcd21e066e26a1e18806ba89da84a233d66db9c04cff4fabec87576281e1a21b0f8890a5fd2ef9aa1542"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 17:37:46.914628) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 17:37:46.914992) ========= 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=df17445c3590ff7897617a0e00000225-1482369270.228303-794034786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 17:37:46.998917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 17:37:46.999245) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 17:37:46.999641) ========= ========= Total master_lag: 0.17 =========