builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-028 starttime: 1481202424.42 results: success (0) buildid: 20161208050637 builduid: ade73b0a3beb447fac1f2321bdd3b18d revision: 387b89eeed54d07238a2e0e878e44ca7cdbd26cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:04.419323) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:04.419768) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:04.420051) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:04.434943) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:04.435276) ========= 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=836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-08 05:07:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 27.2M=0s 2016-12-08 05:07:04 (27.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080335 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:04.529617) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:04.529899) ========= 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=836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021752 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:04.580637) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:04.580944) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 387b89eeed54d07238a2e0e878e44ca7cdbd26cb --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 387b89eeed54d07238a2e0e878e44ca7cdbd26cb --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=836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-08 05:07:05,106 truncating revision to first 12 chars 2016-12-08 05:07:05,106 Setting DEBUG logging. 2016-12-08 05:07:05,106 attempt 1/10 2016-12-08 05:07:05,106 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/387b89eeed54?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-08 05:07:05,505 unpacking tar archive at: mozilla-inbound-387b89eeed54/testing/mozharness/ program finished with exit code 0 elapsedTime=0.691503 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:05.288805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:05.289309) ========= script_repo_revision: 387b89eeed54d07238a2e0e878e44ca7cdbd26cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:05.289927) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:05.290243) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 05:07:05.302335) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-12-08 05:07:05.302730) ========= /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=836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:07:05 INFO - MultiFileLogger online at 20161208 05:07:05 in /builds/slave/test 05:07:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 05:07:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:07:05 INFO - {'append_to_log': False, 05:07:05 INFO - 'base_work_dir': '/builds/slave/test', 05:07:05 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 05:07:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:07:05 INFO - 'branch': 'Mozilla-Inbound', 05:07:05 INFO - 'buildbot_json_path': 'buildprops.json', 05:07:05 INFO - 'config_files': ('talos/linux_config.py',), 05:07:05 INFO - 'default_actions': ('clobber', 05:07:05 INFO - 'read-buildbot-config', 05:07:05 INFO - 'download-and-extract', 05:07:05 INFO - 'populate-webroot', 05:07:05 INFO - 'create-virtualenv', 05:07:05 INFO - 'install', 05:07:05 INFO - 'run-tests'), 05:07:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:07:05 INFO - 'download_minidump_stackwalk': True, 05:07:05 INFO - 'download_symbols': 'ondemand', 05:07:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:07:05 INFO - 'tooltool.py': '/tools/tooltool.py', 05:07:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:07:05 INFO - '/tools/misc-python/virtualenv.py')}, 05:07:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:07:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:07:05 INFO - 'installer_path': 'installer.exe', 05:07:05 INFO - 'log_level': 'info', 05:07:05 INFO - 'log_name': 'talos', 05:07:05 INFO - 'log_to_console': True, 05:07:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:07:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:07:05 INFO - 'opt_config_files': (), 05:07:05 INFO - 'pip_index': False, 05:07:05 INFO - 'sps_profile': False, 05:07:05 INFO - 'sps_profile_interval': 0, 05:07:05 INFO - 'suite': 'dromaeojs', 05:07:05 INFO - 'system_bits': '32', 05:07:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:07:05 INFO - 'title': 'talos-linux64-ix-028', 05:07:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:07:05 INFO - 'use_talos_json': True, 05:07:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:07:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:07:05 INFO - 'work_dir': 'build'} 05:07:05 INFO - [mozharness: 2016-12-08 13:07:05.930564Z] Running clobber step. 05:07:05 INFO - Running pre-action listener: _resource_record_pre_action 05:07:05 INFO - Running main action method: clobber 05:07:05 INFO - rmtree: /builds/slave/test/build 05:07:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:07:06 INFO - Running post-action listener: _resource_record_post_action 05:07:06 INFO - [mozharness: 2016-12-08 13:07:06.382541Z] Finished clobber step (success) 05:07:06 INFO - [mozharness: 2016-12-08 13:07:06.382640Z] Running read-buildbot-config step. 05:07:06 INFO - Running pre-action listener: _resource_record_pre_action 05:07:06 INFO - Running main action method: read_buildbot_config 05:07:06 INFO - Using buildbot properties: 05:07:06 INFO - { 05:07:06 INFO - "project": "", 05:07:06 INFO - "product": "firefox", 05:07:06 INFO - "script_repo_revision": "production", 05:07:06 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 05:07:06 INFO - "repository": "", 05:07:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 05:07:06 INFO - "buildid": "20161208050637", 05:07:06 INFO - "stage_platform": "linux64-pgo", 05:07:06 INFO - "basedir": "/builds/slave/test", 05:07:06 INFO - "buildnumber": 2252, 05:07:06 INFO - "platform": "ubuntu64_hw", 05:07:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:07:06 INFO - "slavebuilddir": "test", 05:07:06 INFO - "branch": "mozilla-inbound", 05:07:06 INFO - "revision": "387b89eeed54d07238a2e0e878e44ca7cdbd26cb", 05:07:06 INFO - "slavename": "talos-linux64-ix-028", 05:07:06 INFO - "builduid": "ade73b0a3beb447fac1f2321bdd3b18d", 05:07:06 INFO - "repo_path": "integration/mozilla-inbound" 05:07:06 INFO - } 05:07:06 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 05:07:06 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 05:07:06 INFO - Running post-action listener: _resource_record_post_action 05:07:06 INFO - [mozharness: 2016-12-08 13:07:06.385035Z] Finished read-buildbot-config step (success) 05:07:06 INFO - [mozharness: 2016-12-08 13:07:06.385122Z] Running download-and-extract step. 05:07:06 INFO - Running pre-action listener: _resource_record_pre_action 05:07:06 INFO - Running main action method: download_and_extract 05:07:06 INFO - mkdir: /builds/slave/test/build/tests 05:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:06 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:07:06 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:07:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:07:06 INFO - Downloaded 1361 bytes. 05:07:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:07:06 INFO - Using the following test package requirements: 05:07:06 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:07:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:07:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:07:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:07:06 INFO - u'jsshell-linux-x86_64.zip'], 05:07:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:07:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:07:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:07:06 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:07:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:07:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:07:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:07:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:07:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:07:06 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:07:06 INFO - Expected file size: 21781583 05:07:06 INFO - Obtained file size: 21781583 05:07:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:07:10 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/1481196603/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:07:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:07:10 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:07:11 INFO - Expected file size: 21781583 05:07:11 INFO - Obtained file size: 21781583 05:07:15 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/1481196603/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 05:07:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:07:15 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:07:15 INFO - Expected file size: 14409734 05:07:15 INFO - Obtained file size: 14409734 05:07: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')]} 05:07:15 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:07:15 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:07:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:07:20 INFO - Downloaded 59832278 bytes. 05:07:20 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:07:20 INFO - mkdir: /builds/slave/test/properties 05:07:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:07:20 INFO - Writing to file /builds/slave/test/properties/build_url 05:07:20 INFO - Contents: 05:07:20 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:07:20 INFO - Running post-action listener: _resource_record_post_action 05:07:20 INFO - Running post-action listener: set_extra_try_arguments 05:07:20 INFO - [mozharness: 2016-12-08 13:07:20.930291Z] Finished download-and-extract step (success) 05:07:20 INFO - [mozharness: 2016-12-08 13:07:20.930518Z] Running populate-webroot step. 05:07:20 INFO - Running pre-action listener: _resource_record_pre_action 05:07:20 INFO - Running main action method: populate_webroot 05:07:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:07:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:07:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:07:20 INFO - '--tppagecycles', 05:07:20 INFO - '7'], 05:07:20 INFO - 'tests': ['tsvgm']}}, 05:07:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:07:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:07:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:07:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:07:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:07:20 INFO - 'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:07:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:07:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:07:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:07:20 INFO - 'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['damp', 'tps']}, 05:07:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:07:20 INFO - 'tests': ['damp', 'tps']}, 05:07:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['dromaeo_dom']}, 05:07:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:07:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['basic_compositor_video']}, 05:07:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:07:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['a11yr', 05:07:20 INFO - 'ts_paint', 05:07:20 INFO - 'tpaint', 05:07:20 INFO - 'sessionrestore', 05:07:20 INFO - 'sessionrestore_no_auto_restore', 05:07:20 INFO - 'tabpaint']}, 05:07:20 INFO - 'other-e10s': {'tests': ['a11yr', 05:07:20 INFO - 'ts_paint', 05:07:20 INFO - 'tpaint', 05:07:20 INFO - 'sessionrestore', 05:07:20 INFO - 'sessionrestore_no_auto_restore', 05:07:20 INFO - 'tabpaint']}, 05:07:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:07:20 INFO - 'ts_paint', 05:07:20 INFO - 'tpaint', 05:07:20 INFO - 'sessionrestore', 05:07:20 INFO - 'sessionrestore_no_auto_restore', 05:07:20 INFO - 'tabpaint']}, 05:07:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:07:20 INFO - 'ts_paint', 05:07:20 INFO - 'tpaint', 05:07:20 INFO - 'sessionrestore', 05:07:20 INFO - 'sessionrestore_no_auto_restore', 05:07:20 INFO - 'tabpaint']}, 05:07:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['a11yr', 05:07:20 INFO - 'ts_paint', 05:07:20 INFO - 'tpaint', 05:07:20 INFO - 'sessionrestore', 05:07:20 INFO - 'sessionrestore_no_auto_restore', 05:07:20 INFO - 'tabpaint']}, 05:07:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['a11yr', 05:07:20 INFO - 'ts_paint', 05:07:20 INFO - 'tpaint', 05:07:20 INFO - 'sessionrestore', 05:07:20 INFO - 'sessionrestore_no_auto_restore', 05:07:20 INFO - 'tabpaint']}, 05:07:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['tsvgx', 05:07:20 INFO - 'tsvgr_opacity', 05:07:20 INFO - 'tart', 05:07:20 INFO - 'tscrollx', 05:07:20 INFO - 'cart', 05:07:20 INFO - 'tsvg_static']}, 05:07:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:07:20 INFO - 'tsvgr_opacity', 05:07:20 INFO - 'tart', 05:07:20 INFO - 'tscrollx', 05:07:20 INFO - 'cart', 05:07:20 INFO - 'tsvg_static']}, 05:07:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:07:20 INFO - 'talos_options': ['--disable-e10s'], 05:07:20 INFO - 'tests': ['tp5o']}, 05:07:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:07:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:07:20 INFO - 'talos_options': ['--disable-e10s', 05:07:20 INFO - '--xperf_path', 05:07:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:07:20 INFO - 'tests': ['tp5n']}, 05:07:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:07:20 INFO - 'talos_options': ['--xperf_path', 05:07:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:07:20 INFO - 'tests': ['tp5n']}}, 05:07:20 INFO - 'talos.zip': {'path': '', 05:07:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:07:20 INFO - Running post-action listener: _resource_record_post_action 05:07:20 INFO - [mozharness: 2016-12-08 13:07:20.949290Z] Finished populate-webroot step (success) 05:07:20 INFO - [mozharness: 2016-12-08 13:07:20.949377Z] Running create-virtualenv step. 05:07:20 INFO - Running pre-action listener: _resource_record_pre_action 05:07:20 INFO - Running main action method: create_virtualenv 05:07:20 INFO - Creating virtualenv /builds/slave/test/build/venv 05:07:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:07:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:07:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:07:21 INFO - Using real prefix '/usr' 05:07:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:07:21 INFO - Installing distribute.............................................................................................................................................................................................done. 05:07:23 INFO - Installing pip.................done. 05:07:23 INFO - Return code: 0 05:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:07:23 INFO - Reading from file tmpfile_stdout 05:07:23 INFO - Output received: 05:07: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) 05:07:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:07: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')]} 05:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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')]} 05:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07: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 05:07: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 05:07:23 INFO - Using env: {'DISPLAY': ':0', 05:07:23 INFO - 'HOME': '/home/cltbld', 05:07:23 INFO - 'LANG': 'en_US.UTF-8', 05:07:23 INFO - 'LANGUAGE': 'en_US:en', 05:07:23 INFO - 'LOGNAME': 'cltbld', 05:07:23 INFO - 'MAIL': '/var/mail/cltbld', 05:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:23 INFO - 'MOZ_NO_REMOTE': '1', 05:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:23 INFO - 'NO_EM_RESTART': '1', 05:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:23 INFO - 'PWD': '/builds/slave/test', 05:07:23 INFO - 'SHELL': '/bin/bash', 05:07:23 INFO - 'SHLVL': '1', 05:07:23 INFO - 'TERM': 'linux', 05:07:23 INFO - 'TMOUT': '86400', 05:07:23 INFO - 'USER': 'cltbld', 05:07:23 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:23 INFO - '_': '/tools/buildbot/bin/python'} 05:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:07:23 INFO - Cleaning up... 05:07:23 INFO - Return code: 0 05:07:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:07: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')]} 05:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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')]} 05:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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', '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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:07: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 psutil>=3.1.1 05:07:23 INFO - Using env: {'DISPLAY': ':0', 05:07:23 INFO - 'HOME': '/home/cltbld', 05:07:23 INFO - 'LANG': 'en_US.UTF-8', 05:07:23 INFO - 'LANGUAGE': 'en_US:en', 05:07:23 INFO - 'LOGNAME': 'cltbld', 05:07:23 INFO - 'MAIL': '/var/mail/cltbld', 05:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:23 INFO - 'MOZ_NO_REMOTE': '1', 05:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:23 INFO - 'NO_EM_RESTART': '1', 05:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:23 INFO - 'PWD': '/builds/slave/test', 05:07:23 INFO - 'SHELL': '/bin/bash', 05:07:23 INFO - 'SHLVL': '1', 05:07:23 INFO - 'TERM': 'linux', 05:07:23 INFO - 'TMOUT': '86400', 05:07:23 INFO - 'USER': 'cltbld', 05:07:23 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:23 INFO - '_': '/tools/buildbot/bin/python'} 05:07:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:24 INFO - Downloading/unpacking psutil>=3.1.1 05:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:07:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:07:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:07:26 INFO - Installing collected packages: psutil 05:07:26 INFO - Running setup.py install for psutil 05:07:26 INFO - building 'psutil._psutil_linux' extension 05:07:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:07:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:07:26 INFO - building 'psutil._psutil_posix' extension 05:07:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:07: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 05:07:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:07:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:07:26 INFO - Successfully installed psutil 05:07:26 INFO - Cleaning up... 05:07:26 INFO - Return code: 0 05:07:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:26 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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:07:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:07:26 INFO - Using env: {'DISPLAY': ':0', 05:07:26 INFO - 'HOME': '/home/cltbld', 05:07:26 INFO - 'LANG': 'en_US.UTF-8', 05:07:26 INFO - 'LANGUAGE': 'en_US:en', 05:07:26 INFO - 'LOGNAME': 'cltbld', 05:07:26 INFO - 'MAIL': '/var/mail/cltbld', 05:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:26 INFO - 'MOZ_NO_REMOTE': '1', 05:07:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:26 INFO - 'NO_EM_RESTART': '1', 05:07:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:26 INFO - 'PWD': '/builds/slave/test', 05:07:26 INFO - 'SHELL': '/bin/bash', 05:07:26 INFO - 'SHLVL': '1', 05:07:26 INFO - 'TERM': 'linux', 05:07:26 INFO - 'TMOUT': '86400', 05:07:26 INFO - 'USER': 'cltbld', 05:07:26 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:26 INFO - '_': '/tools/buildbot/bin/python'} 05:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:07:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:07:29 INFO - Installing collected packages: mozsystemmonitor 05:07:29 INFO - Running setup.py install for mozsystemmonitor 05:07:29 INFO - Successfully installed mozsystemmonitor 05:07:29 INFO - Cleaning up... 05:07:29 INFO - Return code: 0 05:07:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:07:29 INFO - Using env: {'DISPLAY': ':0', 05:07:29 INFO - 'HOME': '/home/cltbld', 05:07:29 INFO - 'LANG': 'en_US.UTF-8', 05:07:29 INFO - 'LANGUAGE': 'en_US:en', 05:07:29 INFO - 'LOGNAME': 'cltbld', 05:07:29 INFO - 'MAIL': '/var/mail/cltbld', 05:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:29 INFO - 'MOZ_NO_REMOTE': '1', 05:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:29 INFO - 'NO_EM_RESTART': '1', 05:07:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:29 INFO - 'PWD': '/builds/slave/test', 05:07:29 INFO - 'SHELL': '/bin/bash', 05:07:29 INFO - 'SHLVL': '1', 05:07:29 INFO - 'TERM': 'linux', 05:07:29 INFO - 'TMOUT': '86400', 05:07:29 INFO - 'USER': 'cltbld', 05:07:29 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:29 INFO - '_': '/tools/buildbot/bin/python'} 05:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:29 INFO - Downloading/unpacking jsonschema==2.5.1 05:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:07:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:31 INFO - Downloading functools32-3.2.3-2.tar.gz 05:07:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:07:31 INFO - warning: no files found matching '*.txt' 05:07:31 INFO - no previously-included directories found matching 'build' 05:07:31 INFO - no previously-included directories found matching 'dist' 05:07:31 INFO - no previously-included directories found matching '.git*' 05:07:31 INFO - Installing collected packages: jsonschema, functools32 05:07:31 INFO - Running setup.py install for functools32 05:07:31 INFO - warning: no files found matching '*.txt' 05:07:31 INFO - no previously-included directories found matching 'build' 05:07:31 INFO - no previously-included directories found matching 'dist' 05:07:31 INFO - no previously-included directories found matching '.git*' 05:07:31 INFO - Successfully installed jsonschema functools32 05:07:31 INFO - Cleaning up... 05:07:31 INFO - Return code: 0 05:07:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:31 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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:07:31 INFO - Using env: {'DISPLAY': ':0', 05:07:31 INFO - 'HOME': '/home/cltbld', 05:07:31 INFO - 'LANG': 'en_US.UTF-8', 05:07:31 INFO - 'LANGUAGE': 'en_US:en', 05:07:31 INFO - 'LOGNAME': 'cltbld', 05:07:31 INFO - 'MAIL': '/var/mail/cltbld', 05:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:31 INFO - 'MOZ_NO_REMOTE': '1', 05:07:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:31 INFO - 'NO_EM_RESTART': '1', 05:07:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:31 INFO - 'PWD': '/builds/slave/test', 05:07:31 INFO - 'SHELL': '/bin/bash', 05:07:31 INFO - 'SHLVL': '1', 05:07:31 INFO - 'TERM': 'linux', 05:07:31 INFO - 'TMOUT': '86400', 05:07:31 INFO - 'USER': 'cltbld', 05:07:31 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:31 INFO - '_': '/tools/buildbot/bin/python'} 05:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:07:32 INFO - Cleaning up... 05:07:32 INFO - Return code: 0 05:07:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:07:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:07:32 INFO - Using env: {'DISPLAY': ':0', 05:07:32 INFO - 'HOME': '/home/cltbld', 05:07:32 INFO - 'LANG': 'en_US.UTF-8', 05:07:32 INFO - 'LANGUAGE': 'en_US:en', 05:07:32 INFO - 'LOGNAME': 'cltbld', 05:07:32 INFO - 'MAIL': '/var/mail/cltbld', 05:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:32 INFO - 'MOZ_NO_REMOTE': '1', 05:07:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:32 INFO - 'NO_EM_RESTART': '1', 05:07:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:32 INFO - 'PWD': '/builds/slave/test', 05:07:32 INFO - 'SHELL': '/bin/bash', 05:07:32 INFO - 'SHLVL': '1', 05:07:32 INFO - 'TERM': 'linux', 05:07:32 INFO - 'TMOUT': '86400', 05:07:32 INFO - 'USER': 'cltbld', 05:07:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:32 INFO - '_': '/tools/buildbot/bin/python'} 05:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:32 INFO - Downloading/unpacking blobuploader==1.2.4 05:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:34 INFO - Downloading blobuploader-1.2.4.tar.gz 05:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:07:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:07:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:35 INFO - Downloading docopt-0.6.1.tar.gz 05:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:07:35 INFO - Installing collected packages: blobuploader, requests, docopt 05:07:35 INFO - Running setup.py install for blobuploader 05:07:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:07:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:07:35 INFO - Running setup.py install for requests 05:07:35 INFO - Running setup.py install for docopt 05:07:35 INFO - Successfully installed blobuploader requests docopt 05:07:35 INFO - Cleaning up... 05:07:35 INFO - Return code: 0 05:07:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07: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 05:07: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 05:07:35 INFO - Using env: {'DISPLAY': ':0', 05:07:35 INFO - 'HOME': '/home/cltbld', 05:07:35 INFO - 'LANG': 'en_US.UTF-8', 05:07:35 INFO - 'LANGUAGE': 'en_US:en', 05:07:35 INFO - 'LOGNAME': 'cltbld', 05:07:35 INFO - 'MAIL': '/var/mail/cltbld', 05:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:35 INFO - 'MOZ_NO_REMOTE': '1', 05:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:35 INFO - 'NO_EM_RESTART': '1', 05:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:35 INFO - 'PWD': '/builds/slave/test', 05:07:35 INFO - 'SHELL': '/bin/bash', 05:07:35 INFO - 'SHLVL': '1', 05:07:35 INFO - 'TERM': 'linux', 05:07:35 INFO - 'TMOUT': '86400', 05:07:35 INFO - 'USER': 'cltbld', 05:07:35 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:35 INFO - '_': '/tools/buildbot/bin/python'} 05:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:35 INFO - Running setup.py (path:/tmp/pip-VZtgnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:35 INFO - Running setup.py (path:/tmp/pip-6JeaEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:36 INFO - Running setup.py (path:/tmp/pip-X2aQNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:36 INFO - Running setup.py (path:/tmp/pip-DdyeSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:36 INFO - Running setup.py (path:/tmp/pip-KWMv8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:36 INFO - Running setup.py (path:/tmp/pip-yCY0kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:36 INFO - Running setup.py (path:/tmp/pip-0jPmOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:36 INFO - Running setup.py (path:/tmp/pip-F0QO7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:36 INFO - Running setup.py (path:/tmp/pip-kWQ4N3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:36 INFO - Running setup.py (path:/tmp/pip-3mGxfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:36 INFO - Running setup.py (path:/tmp/pip-b4Iy1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:36 INFO - Running setup.py (path:/tmp/pip-FcMz5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:36 INFO - Running setup.py (path:/tmp/pip-TwbK0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:36 INFO - Running setup.py (path:/tmp/pip-RUaIzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:36 INFO - Running setup.py (path:/tmp/pip-f6M8PL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:37 INFO - Running setup.py (path:/tmp/pip-jkwdkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:37 INFO - Running setup.py (path:/tmp/pip-uYXwbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:07:37 INFO - Running setup.py install for manifestparser 05:07:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:07:37 INFO - Running setup.py install for mozcrash 05:07:37 INFO - Running setup.py install for mozdebug 05:07:37 INFO - Running setup.py install for mozdevice 05:07:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:07:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:07:37 INFO - Running setup.py install for mozfile 05:07:37 INFO - Running setup.py install for mozhttpd 05:07:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:07:37 INFO - Running setup.py install for mozinfo 05:07:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:07:37 INFO - Running setup.py install for mozInstall 05:07:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:07:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:07:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:07:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:07:37 INFO - Running setup.py install for mozleak 05:07:38 INFO - Running setup.py install for mozlog 05:07:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:07:38 INFO - Running setup.py install for moznetwork 05:07:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:07:38 INFO - Running setup.py install for mozprocess 05:07:38 INFO - Running setup.py install for mozprofile 05:07:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:07:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:07:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:07:38 INFO - Running setup.py install for mozrunner 05:07:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:07:38 INFO - Running setup.py install for mozscreenshot 05:07:38 INFO - Running setup.py install for moztest 05:07:38 INFO - Running setup.py install for mozversion 05:07:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:07:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:07:38 INFO - Cleaning up... 05:07:38 INFO - Return code: 0 05:07:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:38 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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:07:38 INFO - Using env: {'DISPLAY': ':0', 05:07:38 INFO - 'HOME': '/home/cltbld', 05:07:38 INFO - 'LANG': 'en_US.UTF-8', 05:07:38 INFO - 'LANGUAGE': 'en_US:en', 05:07:38 INFO - 'LOGNAME': 'cltbld', 05:07:38 INFO - 'MAIL': '/var/mail/cltbld', 05:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:38 INFO - 'MOZ_NO_REMOTE': '1', 05:07:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:38 INFO - 'NO_EM_RESTART': '1', 05:07:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:38 INFO - 'PWD': '/builds/slave/test', 05:07:38 INFO - 'SHELL': '/bin/bash', 05:07:38 INFO - 'SHLVL': '1', 05:07:38 INFO - 'TERM': 'linux', 05:07:38 INFO - 'TMOUT': '86400', 05:07:38 INFO - 'USER': 'cltbld', 05:07:38 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:38 INFO - '_': '/tools/buildbot/bin/python'} 05:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:39 INFO - Running setup.py (path:/tmp/pip-TbotMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:39 INFO - Running setup.py (path:/tmp/pip-1Hidoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:39 INFO - Running setup.py (path:/tmp/pip-9ubsIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:39 INFO - Running setup.py (path:/tmp/pip-IW4pdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07: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)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:39 INFO - Running setup.py (path:/tmp/pip-hG1AO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07: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)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:39 INFO - Running setup.py (path:/tmp/pip-SkN8po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07: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)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:39 INFO - Running setup.py (path:/tmp/pip-Iu3hed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07: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)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:39 INFO - Running setup.py (path:/tmp/pip-2JkqvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07: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)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:39 INFO - Running setup.py (path:/tmp/pip-XwKTp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:39 INFO - Running setup.py (path:/tmp/pip-POiefh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:39 INFO - Running setup.py (path:/tmp/pip-5KGMkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:39 INFO - Running setup.py (path:/tmp/pip-i2Ew90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:40 INFO - Running setup.py (path:/tmp/pip-SUhifS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:40 INFO - Running setup.py (path:/tmp/pip-uvCyOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:40 INFO - Running setup.py (path:/tmp/pip-3BReyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:40 INFO - Running setup.py (path:/tmp/pip-TRK_XA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:40 INFO - Running setup.py (path:/tmp/pip-QDxlov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:07:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:42 INFO - Downloading blessings-1.6.tar.gz 05:07:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:07:42 INFO - Installing collected packages: blessings 05:07:42 INFO - Running setup.py install for blessings 05:07:42 INFO - Successfully installed blessings 05:07:42 INFO - Cleaning up... 05:07:42 INFO - Return code: 0 05:07:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:07:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:07:42 INFO - Reading from file tmpfile_stdout 05:07:42 INFO - Current package versions: 05:07:42 INFO - argparse == 1.2.1 05:07:42 INFO - blessings == 1.6 05:07:42 INFO - blobuploader == 1.2.4 05:07:42 INFO - docopt == 0.6.1 05:07:42 INFO - functools32 == 3.2.3-2 05:07:42 INFO - jsonschema == 2.5.1 05:07:42 INFO - manifestparser == 1.1 05:07:42 INFO - mozInstall == 1.12 05:07:42 INFO - mozcrash == 1.0 05:07:42 INFO - mozdebug == 0.1 05:07:42 INFO - mozdevice == 0.49 05:07:42 INFO - mozfile == 1.2 05:07:42 INFO - mozhttpd == 0.7 05:07:42 INFO - mozinfo == 0.9 05:07:42 INFO - mozleak == 0.1 05:07:42 INFO - mozlog == 3.4 05:07:42 INFO - moznetwork == 0.27 05:07:42 INFO - mozprocess == 0.23 05:07:42 INFO - mozprofile == 0.28 05:07:42 INFO - mozrunner == 6.13 05:07:42 INFO - mozscreenshot == 0.1 05:07:42 INFO - mozsystemmonitor == 0.3 05:07:42 INFO - moztest == 0.7 05:07:42 INFO - mozversion == 1.4 05:07:42 INFO - psutil == 3.1.1 05:07:42 INFO - requests == 1.2.3 05:07:42 INFO - wsgiref == 0.1.2 05:07:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:42 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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:07:42 INFO - Using env: {'DISPLAY': ':0', 05:07:42 INFO - 'HOME': '/home/cltbld', 05:07:42 INFO - 'LANG': 'en_US.UTF-8', 05:07:42 INFO - 'LANGUAGE': 'en_US:en', 05:07:42 INFO - 'LOGNAME': 'cltbld', 05:07:42 INFO - 'MAIL': '/var/mail/cltbld', 05:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:42 INFO - 'MOZ_NO_REMOTE': '1', 05:07:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:42 INFO - 'NO_EM_RESTART': '1', 05:07:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:42 INFO - 'PWD': '/builds/slave/test', 05:07:42 INFO - 'SHELL': '/bin/bash', 05:07:42 INFO - 'SHLVL': '1', 05:07:42 INFO - 'TERM': 'linux', 05:07:42 INFO - 'TMOUT': '86400', 05:07:42 INFO - 'USER': 'cltbld', 05:07:42 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:42 INFO - '_': '/tools/buildbot/bin/python'} 05:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:07:43 INFO - Cleaning up... 05:07:43 INFO - Return code: 0 05:07:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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 0x7f6c7095fe30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd2fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xecd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, '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': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:07:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:07:43 INFO - Using env: {'DISPLAY': ':0', 05:07:43 INFO - 'HOME': '/home/cltbld', 05:07:43 INFO - 'LANG': 'en_US.UTF-8', 05:07:43 INFO - 'LANGUAGE': 'en_US:en', 05:07:43 INFO - 'LOGNAME': 'cltbld', 05:07:43 INFO - 'MAIL': '/var/mail/cltbld', 05:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:43 INFO - 'MOZ_NO_REMOTE': '1', 05:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:43 INFO - 'NO_EM_RESTART': '1', 05:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:43 INFO - 'PWD': '/builds/slave/test', 05:07:43 INFO - 'SHELL': '/bin/bash', 05:07:43 INFO - 'SHLVL': '1', 05:07:43 INFO - 'TERM': 'linux', 05:07:43 INFO - 'TMOUT': '86400', 05:07:43 INFO - 'USER': 'cltbld', 05:07:43 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:43 INFO - '_': '/tools/buildbot/bin/python'} 05:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:07:43 INFO - Cleaning up... 05:07:43 INFO - Return code: 0 05:07:43 INFO - Running post-action listener: _resource_record_post_action 05:07:43 INFO - Running post-action listener: _start_resource_monitoring 05:07:43 INFO - Starting resource monitoring. 05:07:43 INFO - [mozharness: 2016-12-08 13:07:43.360483Z] Finished create-virtualenv step (success) 05:07:43 INFO - [mozharness: 2016-12-08 13:07:43.360953Z] Running install step. 05:07:43 INFO - Running pre-action listener: _resource_record_pre_action 05:07:43 INFO - Running main action method: install 05:07:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:07:43 INFO - Reading from file tmpfile_stdout 05:07:43 INFO - Detecting whether we're running mozinstall >=1.0... 05:07:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:07:43 INFO - Reading from file tmpfile_stdout 05:07:43 INFO - Output received: 05:07:43 INFO - Usage: mozinstall [options] installer 05:07:43 INFO - Options: 05:07:43 INFO - -h, --help show this help message and exit 05:07:43 INFO - -d DEST, --destination=DEST 05:07:43 INFO - Directory to install application into. [default: 05:07:43 INFO - "/builds/slave/test"] 05:07:43 INFO - --app=APP Application being installed. [default: firefox] 05:07:43 INFO - mkdir: /builds/slave/test/build/application 05:07:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:08:00 INFO - Reading from file tmpfile_stdout 05:08:00 INFO - Output received: 05:08:00 INFO - /builds/slave/test/build/application/firefox/firefox 05:08:00 INFO - Running post-action listener: _resource_record_post_action 05:08:00 INFO - [mozharness: 2016-12-08 13:08:00.623756Z] Finished install step (success) 05:08:00 INFO - [mozharness: 2016-12-08 13:08:00.624031Z] Running run-tests step. 05:08:00 INFO - Running pre-action listener: _resource_record_pre_action 05:08:00 INFO - Running main action method: run_tests 05:08:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:08:00 INFO - Python 2.7.3 05:08:00 INFO - Return code: 0 05:08:00 INFO - grabbing minidump binary from tooltool 05:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:00 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 0xf5df20>, '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 0xecac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecaf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:08:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:08:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:08:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:08:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:08:00 INFO - Return code: 0 05:08:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:08:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:08:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:08:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:08:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:08:00 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/1481196603/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:08:00 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/1481196603/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:08:00 INFO - Using env: {'DISPLAY': ':0', 05:08:00 INFO - 'HOME': '/home/cltbld', 05:08:00 INFO - 'LANG': 'en_US.UTF-8', 05:08:00 INFO - 'LANGUAGE': 'en_US:en', 05:08:00 INFO - 'LOGNAME': 'cltbld', 05:08:00 INFO - 'MAIL': '/var/mail/cltbld', 05:08:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:08:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:00 INFO - 'MOZ_NO_REMOTE': '1', 05:08:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:08:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:00 INFO - 'NO_EM_RESTART': '1', 05:08:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:00 INFO - 'PWD': '/builds/slave/test', 05:08:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:08:00 INFO - 'SHELL': '/bin/bash', 05:08:00 INFO - 'SHLVL': '1', 05:08:00 INFO - 'TERM': 'linux', 05:08:00 INFO - 'TMOUT': '86400', 05:08:00 INFO - 'USER': 'cltbld', 05:08:00 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860', 05:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:00 INFO - '_': '/tools/buildbot/bin/python'} 05:08:00 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/1481196603/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:08:00 INFO - mozversion application_buildid: 20161208033003 05:08:00 INFO - mozversion application_changeset: 387b89eeed54d07238a2e0e878e44ca7cdbd26cb 05:08:00 INFO - mozversion application_display_name: Nightly 05:08:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:08:00 INFO - mozversion application_name: Firefox 05:08:00 INFO - mozversion application_remotingname: firefox 05:08:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:08:00 INFO - mozversion application_vendor: Mozilla 05:08:00 INFO - mozversion application_version: 53.0a1 05:08:00 INFO - mozversion platform_buildid: 20161208033003 05:08:00 INFO - mozversion platform_changeset: 387b89eeed54d07238a2e0e878e44ca7cdbd26cb 05:08:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:08:00 INFO - mozversion platform_version: 53.0a1 05:08:00 INFO - using testdate: 1481202480 05:08:00 INFO - actual date: 1481202480 05:08:00 INFO - starting webserver on 'localhost:39603' 05:08:00 INFO - SUITE-START | Running 2 tests 05:08:00 INFO - TEST-START | dromaeo_css 05:08:00 INFO - operating with platform_type : linux_ 05:08:00 INFO - Initialising browser for dromaeo_css test... 05:08:00 INFO - TEST-INFO | started process 25087 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKNKMOA/profile http://localhost:39603/getInfo.html) 05:08:04 INFO - PROCESS | 25087 | __metrics Screen width/height:1600/1200 05:08:04 INFO - PROCESS | 25087 | colorDepth:24 05:08:04 INFO - PROCESS | 25087 | Browser inner width/height: 1024/697 05:08:04 INFO - PROCESS | 25087 | __metrics 05:08:05 INFO - PROCESS | 25087 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:08:05 INFO - PROCESS | 25087 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:08:06 INFO - PROCESS | 25087 | console.error: PushService: 05:08:06 INFO - PROCESS | 25087 | stateChangeProcessEnqueue: Error transitioning state 05:08:06 INFO - PROCESS | 25087 | UnknownError 05:08:07 INFO - TEST-INFO | 25087: exit 0 05:08:07 INFO - Browser initialized. 05:08:07 INFO - Running cycle 1/1 for dromaeo_css test... 05:08:07 INFO - TEST-INFO | started process 25171 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKNKMOA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:08:19 INFO - PROCESS | 25171 | RSS: Main: 172343296 05:08:19 INFO - PROCESS | 25171 | 05:09:09 INFO - PROCESS | 25171 | 1481202549988 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:11:21 INFO - PROCESS | 25171 | Cycle 1(1): loaded http://localhost:39603/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39603/tests/dromaeo/cssquery-ext.html) 05:11:23 INFO - PROCESS | 25171 | RSS: Main: 274927616 05:11:23 INFO - PROCESS | 25171 | 05:14:25 INFO - PROCESS | 25171 | Cycle 1(1): loaded http://localhost:39603/tests/dromaeo/cssquery-ext.html (next: http://localhost:39603/tests/dromaeo/cssquery-jquery.html) 05:14:26 INFO - PROCESS | 25171 | RSS: Main: 283262976 05:14:26 INFO - PROCESS | 25171 | 05:17:28 INFO - PROCESS | 25171 | Cycle 1(1): loaded http://localhost:39603/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39603/tests/dromaeo/cssquery-mootools.html) 05:17:28 INFO - PROCESS | 25171 | RSS: Main: 217534464 05:17:28 INFO - PROCESS | 25171 | 05:20:31 INFO - PROCESS | 25171 | Cycle 1(1): loaded http://localhost:39603/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39603/tests/dromaeo/cssquery-prototype.html) 05:20:31 INFO - PROCESS | 25171 | RSS: Main: 250761216 05:20:31 INFO - PROCESS | 25171 | 05:23:34 INFO - PROCESS | 25171 | Cycle 1(1): loaded http://localhost:39603/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39603/tests/dromaeo/cssquery-yui.html) 05:23:34 INFO - PROCESS | 25171 | RSS: Main: 217714688 05:23:34 INFO - PROCESS | 25171 | 05:26:37 INFO - PROCESS | 25171 | Cycle 1(1): loaded http://localhost:39603/tests/dromaeo/cssquery-yui.html (next: http://localhost:39603/tests/dromaeo/cssquery-dojo.html) 05:26:38 INFO - PROCESS | 25171 | RSS: Main: 311078912 05:26:38 INFO - PROCESS | 25171 | 05:26:38 INFO - PROCESS | 25171 | __start_tp_report 05:26:38 INFO - PROCESS | 25171 | _x_x_mozilla_page_load 05:26:38 INFO - PROCESS | 25171 | _x_x_mozilla_page_load_details 05:26:38 INFO - PROCESS | 25171 | |i|pagename|runs| 05:26:38 INFO - PROCESS | 25171 | |0;dojo.html;3244.545763593097;3287.2603664175563;3301.158204657812;3321.651226621205;3326.5841769778776;5166.638335316529;5253.8686532836655;5256.26412302278;5262;5312.2828418163535;5261.684298942067;5293.15309550472;5341.813036543722;5346.545543628797;5444.673319600817;4826.686265392738;4855.393075865517;4874.8537543873745;4905.926411103818;4937.851864444073;4846.854594362175;4886.267059941002;4941.975290123562;4966.9751651241695;5006.849794506171;4866.099771542241;4881.267809828519;4987.576056035223;5003.574696150831;5026.472220416858;4773.093112308685;4791.065742180242;4796.328514008042;4802.447718512381;4810.5670489656095;4802.135615589193;4805.951940480585;4809.014152098812;4839.467658557556;4870.293807397908;5239.187925871496;5263.02703616592;5284.9735751320995;5300.761465734013;5319.654222475527;5499.587530935156;5537.58468114889;5662.348829884575;5712.82861514151;5738.225339579145;5731.512821410185;5771.884562308773;5782.855428614318;5794.855128621776;5826.300843898759;5349.705766182819;5370.925814837048;5379.65032272901;5387.838364849061;5389.083855744533;5624.409437009137;5662.292213473316;5849.970750146222;5851.941480585225;5852.970735146382;5476.315460567429;5544.16837474374;5545.279113715191;5546.251256080377;5546.611737178439;5564.360098588714;5592.720363981784;5623.718814059363;5625.943740562624;5630.690312032795;5542.445755424425;5630.577706672016;5641.407652196543;5654.038813401731;5661.547076233891;5624.943750562524;5632.155176723524;5634.6337488063145;5635.520980716603;5667.291588551431;4327.76197309151;4352.1513304905875;4387.495438024668;4389.100234451929;4432.445944256903;3949.842006319715;3961.4652021976663;3986.80065996699;3997.960020399759;4047.251258517184;5414.0254754144635;5415.404305526389;5419.756110975016;5423.620346575702;5450.427705090988;5696.145578163226;5728.112142617901;5728.140778883201;5728.226689396962;5773.653580785082;5191.558717508979;5215.895682086337;5262.079136151158;5269.051570717308;5464.28964234647;4787.305840653085;4814.15752243356;4817.084753896748;4835.274708793639;4852.708837469763;5861.41385861419;5861.9413805861395;5869.284113092055;5869.6771677558145;5917.852053698778;5584.804531841366;5616;5633.8309850704545;5653.095504719226;5663.518600918968;5816.621919575214;5910.024845900378;5910.645361278337;5932;5946.881062378641;5536;5573.303337082864;5575.972120139373;5579.581531385244;5580.972095139498;5523.530499907552;5559.8888022240135;5627.971860140673;5679.630823996427;5682.119271512922;5504.532114770129;5507.862303442285;5547.140193270049;5568;5616.269884914935;5547.750351234285;5553.777848886161;5556.611037227355;5559.33288005442;5573.3869274380595;5353.705546194997;5511.311086114236;5511.917321240265;5512.889742205213;5527.723613819373;5493.176023596492;5567.638103523258;5582.162675598693;5614.831555053355;5694;5453.945460545344;5481.177823326373;5493.176023596492;5502.614816962774;5547.389787123494 05:26:38 INFO - PROCESS | 25171 | |1;ext.html;552.4066827282392;571.702714588414;598.8173607049849;602.475846013968;607.2682417686688;31731.206719832015;41448.37827432587;42364.36453453197;42388;42427;42155.57844421555;42462.7876860616;42576.14847703044;42587.14825703484;42597.36103958443;7902.723404680837;8586.91413085869;8739.344549158806;8742.781430464258;8744.650213991432;7751.961240193791;10178.898211017906;10390.896091039069;10393.584256629763;10663.466826658674;10342.06921377076;10399.480025998708;10402.115820155295;10407.219458540601;10412.791744165112;10433.634822781205;10492.21308401873;10494.79010419795;10498.842517362207;10500.842487362695;10217.795644087151;10270.075693187575;10284.537195826206;10285.074343309063;10292.639757608522;38885.22229555393;39118;39182.804085979675;39219.21561568753;39262.80368598167;3544.3442963051916;3648.945265821015;3804.8097595120134;3993.1414745829497;4022.155347377054;29598.852005739835;29740.40519189604;29970.850145749133;30053.549196762066;30727.231819204477;3980.383040628707;4069.776162311072;4102.118044620391;4107.404426358191;4126.938095928564;219237;220946;223117;223551;223779;54288.72855635697;54609;54683.88348155525;54721.452785471636;54731.72634136804;58609;58982.70508647515;59035;59046.40953590495;59193.70403147957;16276.67446651084;16502.75245871313;16514.83485165133;16518.917405412896;16526;9920.851187232198;10319.896801031893;12038.291384438606;12056.698582535331;12387.07096967731;16024.679506409806;16698.749518757228;16710.58223544421;16732.498025059267;16740.330386784637;5858.443447872445;5993;6030.939690603125;6113.449789518964;6178.814635560941;930.1953809954341;973.2360097323567;978.5498670611456;1005.969820905374;1032.736652153696;30162.547561786592;30241.69758302433;30528.389432211232;30668.38663226723;30708.232294192378;1027.41950797799;1048.5334026358198;1075.8493810866485;1091.956321747137;1150.8446359741495;15426.768598471032;15982.36070557188;16019.679606407806;16058.219280644316;16241.2691428886;434711;437759;439834;440105;442452;56115.158272625944;56176.71911640416;56282;56377;56477.71761141168;1235.2032938754453;1255.4852510470664;1265.9050571207013;1335.4524644895546;1366.1120271823395;29305.120846374644;30438.84780576083;30630.846845765627;31090;31640.208994775316;48521.51478485311;48780.26829597488;49111.75444122757;49124.61380020574;49239.75380123076;343017;343311;345759.61043311603;347191;470764;27706.168814935583;27706.861465692542;27905.72094279031;28036.299092522033;28092.297692557848;45687.54312456833;46668;47371.52628473671;47575.5242447585;48240.5175948236;39049.41425878671;39485.80257098811;39944.80027599844;40345.39481907833;41410.58589414188;39662;39716.602833971294;40084.1983160341;40647.796761016005;41178;38755.80622096984;39186;40248;40313;40389.394159088224;32443.351132976735;35754.64245357513;36282.637173627925;36370.63629363672;36799.63200367962;26344;26912.596311054956;26919;26994.460110798067;27003.1899043029 05:26:38 INFO - PROCESS | 25171 | |2;jquery.html;352.40619556048046;382.2813111350661;384.0494775430808;395.30229145558076;398.80458575298115;13913.721725565496;17429.65140697187;18100.90949545254;19231.32690355838;19397.127129279183;16381.508554743377;16878.83121168788;17159.227834747435;17358.264173582636;18364;5071.213961835912;5187.507186817246;5201.40183878855;5223.294855194557;5229.8169564065265;3768.8492460301554;5186.0665080285535;5265.842024739264;5267.86740850717;5269.578433725293;3912.921741565167;6065.514758819306;6137.232845894263;6199.0391489319;6199.938000619981;6115.357887421801;6182.505399568024;6201.627902325852;6233.252009758835;6252.937470625281;5626.887462250732;5636.887262254773;5637.323521177444;5641.097424412074;5645.943540564583;20755.377338679864;21782.346529604136;21859.344219673436;21873.343799685877;22075.558488830295;2257.7855103765282;2314.317276403448;2368.0882860098814;2618.882150303222;2920.6349206349205;9626.566804493814;10003.89996100037;10192.674952256233;10474.58101675932;10793.67618971432;1876.47458711561;1907.647085289226;1918.539550507882;1925.0952052535285;1928.4021953194554;69222.65388673024;70320.6483967582;70390.64804675944;80556.59721701413;83461.58269208614;10875.347479151276;10945.398003109905;11024.409263822996;11101.38942358178;11349.829752553718;15459.690806183813;15469.458568950033;15486.148261845481;15491.84508154904;15551.766723499157;7689.19263477338;7750.806229844186;7776.922230777733;7908.881366779503;7941.920580794118;6957.512974091866;7049.29507049291;7181.174164970991;7199.964000180071;7219.783406497813;4790.856274311776;4792.281157826354;4802.51974802517;4865.781039853215;4951.331570238044;1942.4658218989607;2000.469875482998;2005.258054519837;2012.1347820437352;2087.4155236533493;894.7986702991905;915.3729695158837;949.767307009787;970.1171933540444;990.5938565188383;17113.48659540216;17996.37012704549;18250.178741956643;18506.259749609864;18595.721064183777;955.1546881010356;985.9161971232526;991.9702408927744;1011.9848202276971;1030.3457304611625;9865.457399842933;10019.749506262402;10217.489125543692;10274.589016439408;10310.32982856127;395149;399598;401085;401462;405032;85660;86742;86924.56537717271;88942;90013;986.8618393424927;1033.7053939627322;1075.2097208551709;1076.1713480619878;1076.5424694504804;15391.076535407912;15415.460458883885;15705.293261803476;15908.363665453253;16104.114273715059;38981.6101838978;39169.60830391774;39236.2152756949;40044.79977600093;40283.19433611253;52105;52106.47893521168;52669.20996184984;53649.4909256306;57824;8170.5896760457;8211.3430925527;8316.750497485085;8418.410711250153;8462.32301415873;8750.781230469289;8784.253338466046;8978.326625503245;8987.056359082202;9256.25949923991;8317.916820831715;8368.707095251637;8550.289942011497;8602.440841345293;8632.913670863461;8322.625481853453;8416.36877234222;8439.409241353047;8462.576871156542;8730.214280714767;8208.425410221227;8399.370047246359;8457.492550446992;8508.489490630582;8844.071372506052;11724.58963936258;12124.09069319787;12187.329696866404;12492.875071249171;12548.937255313665;11345.602903898323;11412.82880756806;11734.765304694029;11848.703782405164;12097.395130243629 05:26:38 INFO - PROCESS | 25171 | |3;mootools.html;1314.1589382795007;1385.0789225165265;1401.1172961034547;1528.6178455386153;1696.0163105398863;6656.534042617019;7406.407487401008;7672.309492145706;7701.3453856422275;7861.68553257869;7697.576633285181;7758.612069396536;7760.3791696664275;7763.650635721392;7767.650455729492;1913.8277555020045;1975.150685205361;2181.454636340911;2213.6236839737285;2215;2083.6978638097544;2151.9031643576;2160.3950893749698;2167.468970102327;2174.402039439151;2212.4468882779306;2217.2793842001333;2223.1663126327626;2223.2774348336775;2287.4281429642588;2230.609643312414;2232.765559616233;2236.709227800392;2238.373255488466;2252.1779550464125;2156.762756096828;2268.138107519149;2269.7389800173028;2274.056266649355;2274.3177046885867;7678.884816727754;7731.652075656609;7740.342070923978;7744.728934487266;7754.7673569793;4685.109829132488;4806.327114204014;4812.759362031885;4814.927776083362;4820.517948205186;3186.988997183038;3222.9425098082033;3266.644342597819;3360.31113621707;3381.8140002299865;1050.6900464363032;1086.6142519405576;1106.916981226403;1109.1902910875026;1112.6828853776635;9642.75893102671;9927.702168934871;9944.154746846527;10184.338018028879;10235.334703244265;4069.8168582413864;4181.247375472444;4242.44848169736;4257.297545904953;4257.531671516131;8213.876791848126;8263.256306932404;8273.379496537784;8276.503409795432;8279.793005174919;4742.146413645577;4783.712977221308;4785.401824771903;4786.042791441726;4787.42550893895;3894.7078969076865;4025.1547175092974;4419.337099435111;4465.040016396425;4465.464144302705;3451;3513.0865974846724;3529.699975502111;3565.750397472152;3576.445650924111;1114.1198453222025;1118.669992352258;1118.7706520163347;1119.0655802405083;1138.5332013874272;920.7606022434081;921.4665931083799;924.3390975452584;951.2437612098398;976.5166242709762;5138;5422.864428389243;5587.078132108157;5613.158026296007;5672.914906276409;903.20969151992;904.9321300902459;926.8146370725882;940.3652534539159;974.2011550528499;3645.5078564394;3649.9635003650155;3662.1577037281572;3698.130939229279;3802.4029907438944;9127.771805704777;9441.103095205945;9445.574949127305;9478.668246611336;9495.430274183433;9358.344915855962;9414.10565996243;9434;9463.337566370288;9470.289728270407;7266.128064632172;7412.925870741331;7418.925810741824;7421.18366979621;7423.888641670379;5289.365276166731;5290.44450332725;5394.03328169513;5410.431904649955;5411.431799661136;8488.702895398632;8549.700760473603;8551.914480855112;8557.871631925651;8648.82702345937;8474.322054235523;8892.555372231493;8940.329475289509;8945.37382383227;8971.372003959661;6661.600303981777;6874;6933.965330173317;7006.894896576658;7155.141383034069;7097.680604372919;7151.392131668867;7301.269873012656;7302.452316076422;7315.341619254293;6445.742170313322;6525.445337146396;6531.150950376421;6557.704903279268;6577.440917522064;6260.874782504416;6359.459445947146;6416.13382193398;6476.4495017924;6557.901631475436;6008.128821320796;6136.417040381157;6143.754249829957;6225.750969961332;6235.906461402992;4655.6973796703105;4705.55297246761;4778.617710583076;4849.684770489906;4859.829905953275;4736.100140973204;4804.495527969513;4852.199387101089;4902.975485122694;4935.728534930613 05:26:38 INFO - PROCESS | 25171 | |4;prototype.html;330.6676789826226;331.37370370000696;337.83446111405414;338.04839377153314;345.67506543848793;5135;5202.843914682561;5217.773823151556;5314.92027619586;5320.7871685132595;5098.668586541872;5157.71632560209;5221;5224.503672151149;5225.216217567367;4120.25835349637;4143.129942712019;4147.3778933159965;4150.916981660365;4158.189153115151;4045.939310910339;4143.461350024492;4145.274576949037;4157.355609880473;4170.415242207957;4139.6895232857505;4143.233501802176;4143.316352801784;4144.5855414458465;4161.146964872209;4088.5298190708154;4108.958910410887;4112.876613701594;4142.565030671782;4143.274926887782;3673.989652845462;3695.8194665147425;3698.815059247027;3700.50043244164;3730.9253814923823;4953;5149.459306772772;5162.277281180638;5164.380274367062;5210.915633746494;4676.321933319684;4706.552877476634;4708.78810453527;4720.244760838249;4725.976370118162;5512.228288039678;5528.889422211533;5541.944580554183;5585.441455854423;5616.85957851057;3933.9213215735526;3973.4834471518807;3997.960020399788;4000;4006.579309172524;5688.431156884279;5724.370319264877;5732.856678583027;5751.367349591542;5773.393793651648;4071.531773845986;4077.429159917614;4086.6730661547003;4108.4453598764385;4116;3994.1612261425425;4111.794410279534;4113.341865301538;4122.752634841919;4169.833206671759;4679.719216847;4681;4681.765911704401;4691.929621055687;4695.7886895089805;5571.888562228733;5579.238277052804;5598.2442370280305;5599.692016939108;5603.915941260884;1293.2563775828935;1314.572763851752;1377.669359353768;1401.453433161068;1413.547664747271;933.477252738455;949.8100379924043;960.3805545423163;967.4920666649924;973.956171972263;5066.504112162596;5090.949090509048;5103.2855400244;5119.180931051014;5124.308218390544;5432.886258317036;5435.565154787608;5470.097433923438;5471.781128754885;5485.780568777284;4868.41579010521;5070.442251352349;5121.795128194905;5129.179331306973;5172.637915345965;2889.6821349651523;2891.3928075104463;2895.739383455499;2907.691538807545;2913.1843083936533;5613.719314034363;5642.887142257132;5648.322201335866;5676.4323567642905;5758.942410575924;5259.263703081571;5413.539849112791;5476.2333273341765;5478.972605137029;5526.640768350045;5612.186232996192;5623.3533143689;5624.887502250014;5627.859303517363;5642.407547207484;5275.920861187008;5408.323959505062;5427.538659214011;5471.890562188654;5480.369757477941;5359.812406565751;5373.468561459965;5375.220593014069;5375.543078838343;5390.703511306916;5602.151290074347;5628.634138780966;5642.605017648725;5661.603687741983;5752.367239603556;3802.467654528313;3928.7833827893173;3948.9407658885716;3955.081785334168;4008.5390180129657;5333.7066461345;5360.65983504124;5375.704336261543;5452.209429632601;5497.862553436196;5360.946390536044;5392.406835248198;5507.889842203053;5520.806771762969;5524.585656075891;5494.066008778597;5503.807366742145;5510.421405752339;5544.972275138599;5558.72206389687;5196.714180720097;5376.301080859464;5431.972840135774;5432.560371501476;5434.701091439851;5515.420880807618;5521.779128834802;5523.917141242965;5537.2524709163745;5548.889022219614;5391.16436952273;5429.5656347492895;5430.728463576726;5434.429384914527;5461.890762184814 05:26:38 INFO - PROCESS | 25171 | |5;yui.html;724.6521669598593;733.4719002318333;789.5144486141025;795.1055063054064;797.812514059196;15082.36058689729;15185.240737963113;15218.467353642625;15229.086254824719;15269.312880920372;14214.431422743079;14308.427662893499;14318.355673994669;14339.569812905629;14342.13947163171;665.5108495255982;688.9242183359852;763.1109757132962;777.1296148313883;797.0395673213764;789.7433334166419;800.2117913854848;802.2619190344867;802.5987006496752;802.6348011654683;844.771911583872;847.809242920341;848.3637272045966;848.9151084891492;849.2823564088367;684.5379368925956;718.7951433841382;810.8540462716709;846.4963346808639;848.5841937450666;618.761776715963;623.5230049012509;624.2540164503421;624.2883113250898;624.3943374926314;15605;15625.374985000586;15643.92178039114;15647.452339168189;15649.139297338641;13618.843683083473;14135.575932722035;14160.645983850383;14164.733642617235;14348.354324055443;12359.326561736836;12399.566015189424;12415.93792031043;12745.681357966032;12787.61637150887;4153.584641535853;4231.132617813341;4254.276772948606;4313.180495705806;4430.8449204277695;88637.55681221616;89678.5516072422;91053;91788;91967.54016229942;11178;11331.433428328715;11346.773064538664;11355.375454349925;11390.886091139148;22012.908773666477;22042;22069.337919862108;22121.115155393927;22253.22113726012;8079.636416361278;8089.191080891981;8124.55314957683;8211.302039326605;8550.828983420297;4228.746275223496;4331.891702707395;4410.625096866802;4413.381420221365;4606.101810146989;3352.865885364607;3391.508231306496;3401.693847553732;3432.6910578047596;3459.1697992482127;576.9538436925036;590.9172715819789;595.8033848829875;599.2688919518181;607.3471018654964;544.923710680505;554.5092593055175;611.4466407900857;642.9839254018686;655.9508036897156;8634.913650863537;8836.955815220883;8992.505412202261;9558.90441095594;9922.7023189303;617.9691015449209;620.959637623553;667.8263651450658;690.6408667492875;754.1854796819422;4298.312270036779;4542.205114105084;4695.154872123051;4728.739919304471;4755.690880092783;66038.33961660322;68480.6575967127;76395;81835.18164818276;81867.5906620463;67376.6631166841;69980.3001969984;70384.64807675929;70568;70872.2912770876;63720.362796371446;63736;64095;64617.03074453887;64630.67684661641;9367.531623418665;9933.106020458194;10029.899701002896;10145.89854101479;10206.897931020594;41220.79389603033;42798.35802463028;43060.138797223255;43503.56496434995;44713.32930005987;50802;52014;54125;55360.16959745688;55504;10994.670159895217;11148.219624626197;11316.9434152832;12223.20549164337;13083.934580327037;12936.41786119608;13879.306034698428;14350.641233969234;14624.707505850036;14937.178455185069;13044;13838.723225535634;13883.514077007256;14065.789013165015;14367.928160359132;13720.862791372358;13937.651558711113;14399.640008999859;14552.345144468312;14563.199024053778;13334.266615335862;13755.518556850462;13822.170669759847;14123.223222722849;14558.4904528341;10281.177505799667;10389.84415233757;10459.84310235332;10688.57245710194;10821.783564328827;9370;10740.30188037775;10918.29031113007;11270.94364528172;11748.353840538852 05:26:38 INFO - PROCESS | 25171 | __end_tp_report 05:26:38 INFO - PROCESS | 25171 | __start_cc_report 05:26:38 INFO - PROCESS | 25171 | _x_x_mozilla_cycle_collect,1433 05:26:38 INFO - PROCESS | 25171 | __end_cc_report 05:26:38 INFO - PROCESS | 25171 | __startTimestamp1481203598129__endTimestamp 05:26:38 INFO - PROCESS | 25171 | 05:26:38 INFO - PROCESS | 25171 | ------- Summary: start ------- 05:26:38 INFO - PROCESS | 25171 | Number of tests: 6 05:26:38 INFO - PROCESS | 25171 | 05:26:38 INFO - PROCESS | 25171 | [#0] dojo.html Cycles:165 Average:5284.90 Median:5502.61 stddev:557.57 (10.1%) stddev-sans-first:535.81 05:26:38 INFO - PROCESS | 25171 | Values: 3244.5 3287.3 3301.2 3321.7 3326.6 5166.6 5253.9 5256.3 5262.0 5312.3 5261.7 5293.2 5341.8 5346.5 5444.7 4826.7 4855.4 4874.9 4905.9 4937.9 4846.9 4886.3 4942.0 4967.0 5006.8 4866.1 4881.3 4987.6 5003.6 5026.5 4773.1 4791.1 4796.3 4802.4 4810.6 4802.1 4806.0 4809.0 4839.5 4870.3 5239.2 5263.0 5285.0 5300.8 5319.7 5499.6 5537.6 5662.3 5712.8 5738.2 5731.5 5771.9 5782.9 5794.9 5826.3 5349.7 5370.9 5379.7 5387.8 5389.1 5624.4 5662.3 5850.0 5851.9 5853.0 5476.3 5544.2 5545.3 5546.3 5546.6 5564.4 5592.7 5623.7 5625.9 5630.7 5542.4 5630.6 5641.4 5654.0 5661.5 5624.9 5632.2 5634.6 5635.5 5667.3 4327.8 4352.2 4387.5 4389.1 4432.4 3949.8 3961.5 3986.8 3998.0 4047.3 5414.0 5415.4 5419.8 5423.6 5450.4 5696.1 5728.1 5728.1 5728.2 5773.7 5191.6 5215.9 5262.1 5269.1 5464.3 4787.3 4814.2 4817.1 4835.3 4852.7 5861.4 5861.9 5869.3 5869.7 5917.9 5584.8 5616.0 5633.8 5653.1 5663.5 5816.6 5910.0 5910.6 5932.0 5946.9 5536.0 5573.3 5576.0 5579.6 5581.0 5523.5 5559.9 5628.0 5679.6 5682.1 5504.5 5507.9 5547.1 5568.0 5616.3 5547.8 5553.8 5556.6 5559.3 5573.4 5353.7 5511.3 5511.9 5512.9 5527.7 5493.2 5567.6 5582.2 5614.8 5694.0 5453.9 5481.2 5493.2 5502.6 5547.4 05:26:38 INFO - PROCESS | 25171 | 05:26:38 INFO - PROCESS | 25171 | [#1] ext.html Cycles:180 Average:51331.06 Median:20224.29 stddev:94200.07 (465.8%) stddev-sans-first:94387.17 05:26:38 INFO - PROCESS | 25171 | Values: 552.4 571.7 598.8 602.5 607.3 31731.2 41448.4 42364.4 42388.0 42427.0 42155.6 42462.8 42576.1 42587.1 42597.4 7902.7 8586.9 8739.3 8742.8 8744.7 7752.0 10178.9 10390.9 10393.6 10663.5 10342.1 10399.5 10402.1 10407.2 10412.8 10433.6 10492.2 10494.8 10498.8 10500.8 10217.8 10270.1 10284.5 10285.1 10292.6 38885.2 39118.0 39182.8 39219.2 39262.8 3544.3 3648.9 3804.8 3993.1 4022.2 29598.9 29740.4 29970.9 30053.5 30727.2 3980.4 4069.8 4102.1 4107.4 4126.9 219237.0 220946.0 223117.0 223551.0 223779.0 54288.7 54609.0 54683.9 54721.5 54731.7 58609.0 58982.7 59035.0 59046.4 59193.7 16276.7 16502.8 16514.8 16518.9 16526.0 9920.9 10319.9 12038.3 12056.7 12387.1 16024.7 16698.7 16710.6 16732.5 16740.3 5858.4 5993.0 6030.9 6113.4 6178.8 930.2 973.2 978.5 1006.0 1032.7 30162.5 30241.7 30528.4 30668.4 30708.2 1027.4 1048.5 1075.8 1092.0 1150.8 15426.8 15982.4 16019.7 16058.2 16241.3 434711.0 437759.0 439834.0 440105.0 442452.0 56115.2 56176.7 56282.0 56377.0 56477.7 1235.2 1255.5 1265.9 1335.5 1366.1 29305.1 30438.8 30630.8 31090.0 31640.2 48521.5 48780.3 49111.8 49124.6 49239.8 343017.0 343311.0 345759.6 347191.0 470764.0 27706.2 27706.9 27905.7 28036.3 28092.3 45687.5 46668.0 47371.5 47575.5 48240.5 39049.4 39485.8 39944.8 40345.4 41410.6 39662.0 39716.6 40084.2 40647.8 41178.0 38755.8 39186.0 40248.0 40313.0 40389.4 32443.4 35754.6 36282.6 36370.6 36799.6 26344.0 26912.6 26919.0 26994.5 27003.2 05:26:38 INFO - PROCESS | 25171 | 05:26:38 INFO - PROCESS | 25171 | [#2] jquery.html Cycles:180 Average:25477.80 Median:204911.43 stddev:66425.74 (32.4%) stddev-sans-first:66585.30 05:26:38 INFO - PROCESS | 25171 | Values: 352.4 382.3 384.0 395.3 398.8 13913.7 17429.7 18100.9 19231.3 19397.1 16381.5 16878.8 17159.2 17358.3 18364.0 5071.2 5187.5 5201.4 5223.3 5229.8 3768.8 5186.1 5265.8 5267.9 5269.6 3912.9 6065.5 6137.2 6199.0 6199.9 6115.4 6182.5 6201.6 6233.3 6252.9 5626.9 5636.9 5637.3 5641.1 5645.9 20755.4 21782.3 21859.3 21873.3 22075.6 2257.8 2314.3 2368.1 2618.9 2920.6 9626.6 10003.9 10192.7 10474.6 10793.7 1876.5 1907.6 1918.5 1925.1 1928.4 69222.7 70320.6 70390.6 80556.6 83461.6 10875.3 10945.4 11024.4 11101.4 11349.8 15459.7 15469.5 15486.1 15491.8 15551.8 7689.2 7750.8 7776.9 7908.9 7941.9 6957.5 7049.3 7181.2 7200.0 7219.8 4790.9 4792.3 4802.5 4865.8 4951.3 1942.5 2000.5 2005.3 2012.1 2087.4 894.8 915.4 949.8 970.1 990.6 17113.5 17996.4 18250.2 18506.3 18595.7 955.2 985.9 992.0 1012.0 1030.3 9865.5 10019.7 10217.5 10274.6 10310.3 395149.0 399598.0 401085.0 401462.0 405032.0 85660.0 86742.0 86924.6 88942.0 90013.0 986.9 1033.7 1075.2 1076.2 1076.5 15391.1 15415.5 15705.3 15908.4 16104.1 38981.6 39169.6 39236.2 40044.8 40283.2 52105.0 52106.5 52669.2 53649.5 57824.0 8170.6 8211.3 8316.8 8418.4 8462.3 8750.8 8784.3 8978.3 8987.1 9256.3 8317.9 8368.7 8550.3 8602.4 8632.9 8322.6 8416.4 8439.4 8462.6 8730.2 8208.4 8399.4 8457.5 8508.5 8844.1 11724.6 12124.1 12187.3 12492.9 12548.9 11345.6 11412.8 11734.8 11848.7 12097.4 05:26:38 INFO - PROCESS | 25171 | 05:26:38 INFO - PROCESS | 25171 | [#3] mootools.html Cycles:180 Average:5098.85 Median:5402.23 stddev:2731.01 (50.6%) stddev-sans-first:2723.86 05:26:38 INFO - PROCESS | 25171 | Values: 1314.2 1385.1 1401.1 1528.6 1696.0 6656.5 7406.4 7672.3 7701.3 7861.7 7697.6 7758.6 7760.4 7763.7 7767.7 1913.8 1975.2 2181.5 2213.6 2215.0 2083.7 2151.9 2160.4 2167.5 2174.4 2212.4 2217.3 2223.2 2223.3 2287.4 2230.6 2232.8 2236.7 2238.4 2252.2 2156.8 2268.1 2269.7 2274.1 2274.3 7678.9 7731.7 7740.3 7744.7 7754.8 4685.1 4806.3 4812.8 4814.9 4820.5 3187.0 3222.9 3266.6 3360.3 3381.8 1050.7 1086.6 1106.9 1109.2 1112.7 9642.8 9927.7 9944.2 10184.3 10235.3 4069.8 4181.2 4242.4 4257.3 4257.5 8213.9 8263.3 8273.4 8276.5 8279.8 4742.1 4783.7 4785.4 4786.0 4787.4 3894.7 4025.2 4419.3 4465.0 4465.5 3451.0 3513.1 3529.7 3565.8 3576.4 1114.1 1118.7 1118.8 1119.1 1138.5 920.8 921.5 924.3 951.2 976.5 5138.0 5422.9 5587.1 5613.2 5672.9 903.2 904.9 926.8 940.4 974.2 3645.5 3650.0 3662.2 3698.1 3802.4 9127.8 9441.1 9445.6 9478.7 9495.4 9358.3 9414.1 9434.0 9463.3 9470.3 7266.1 7412.9 7418.9 7421.2 7423.9 5289.4 5290.4 5394.0 5410.4 5411.4 8488.7 8549.7 8551.9 8557.9 8648.8 8474.3 8892.6 8940.3 8945.4 8971.4 6661.6 6874.0 6934.0 7006.9 7155.1 7097.7 7151.4 7301.3 7302.5 7315.3 6445.7 6525.4 6531.2 6557.7 6577.4 6260.9 6359.5 6416.1 6476.4 6557.9 6008.1 6136.4 6143.8 6225.8 6235.9 4655.7 4705.6 4778.6 4849.7 4859.8 4736.1 4804.5 4852.2 4903.0 4935.7 05:26:38 INFO - PROCESS | 25171 | 05:26:38 INFO - PROCESS | 25171 | [#4] prototype.html Cycles:180 Average:4590.34 Median:5219.39 stddev:1326.82 (25.4%) stddev-sans-first:1291.45 05:26:38 INFO - PROCESS | 25171 | Values: 330.7 331.4 337.8 338.0 345.7 5135.0 5202.8 5217.8 5314.9 5320.8 5098.7 5157.7 5221.0 5224.5 5225.2 4120.3 4143.1 4147.4 4150.9 4158.2 4045.9 4143.5 4145.3 4157.4 4170.4 4139.7 4143.2 4143.3 4144.6 4161.1 4088.5 4109.0 4112.9 4142.6 4143.3 3674.0 3695.8 3698.8 3700.5 3730.9 4953.0 5149.5 5162.3 5164.4 5210.9 4676.3 4706.6 4708.8 4720.2 4726.0 5512.2 5528.9 5541.9 5585.4 5616.9 3933.9 3973.5 3998.0 4000.0 4006.6 5688.4 5724.4 5732.9 5751.4 5773.4 4071.5 4077.4 4086.7 4108.4 4116.0 3994.2 4111.8 4113.3 4122.8 4169.8 4679.7 4681.0 4681.8 4691.9 4695.8 5571.9 5579.2 5598.2 5599.7 5603.9 1293.3 1314.6 1377.7 1401.5 1413.5 933.5 949.8 960.4 967.5 974.0 5066.5 5090.9 5103.3 5119.2 5124.3 5432.9 5435.6 5470.1 5471.8 5485.8 4868.4 5070.4 5121.8 5129.2 5172.6 2889.7 2891.4 2895.7 2907.7 2913.2 5613.7 5642.9 5648.3 5676.4 5758.9 5259.3 5413.5 5476.2 5479.0 5526.6 5612.2 5623.4 5624.9 5627.9 5642.4 5275.9 5408.3 5427.5 5471.9 5480.4 5359.8 5373.5 5375.2 5375.5 5390.7 5602.2 5628.6 5642.6 5661.6 5752.4 3802.5 3928.8 3948.9 3955.1 4008.5 5333.7 5360.7 5375.7 5452.2 5497.9 5360.9 5392.4 5507.9 5520.8 5524.6 5494.1 5503.8 5510.4 5545.0 5558.7 5196.7 5376.3 5432.0 5432.6 5434.7 5515.4 5521.8 5523.9 5537.3 5548.9 5391.2 5429.6 5430.7 5434.4 5461.9 05:26:38 INFO - PROCESS | 25171 | 05:26:38 INFO - PROCESS | 25171 | [#5] yui.html Cycles:180 Average:17806.29 Median:24572.09 stddev:23227.68 (94.5%) stddev-sans-first:23257.42 05:26:38 INFO - PROCESS | 25171 | Values: 724.7 733.5 789.5 795.1 797.8 15082.4 15185.2 15218.5 15229.1 15269.3 14214.4 14308.4 14318.4 14339.6 14342.1 665.5 688.9 763.1 777.1 797.0 789.7 800.2 802.3 802.6 802.6 844.8 847.8 848.4 848.9 849.3 684.5 718.8 810.9 846.5 848.6 618.8 623.5 624.3 624.3 624.4 15605.0 15625.4 15643.9 15647.5 15649.1 13618.8 14135.6 14160.6 14164.7 14348.4 12359.3 12399.6 12415.9 12745.7 12787.6 4153.6 4231.1 4254.3 4313.2 4430.8 88637.6 89678.6 91053.0 91788.0 91967.5 11178.0 11331.4 11346.8 11355.4 11390.9 22012.9 22042.0 22069.3 22121.1 22253.2 8079.6 8089.2 8124.6 8211.3 8550.8 4228.7 4331.9 4410.6 4413.4 4606.1 3352.9 3391.5 3401.7 3432.7 3459.2 577.0 590.9 595.8 599.3 607.3 544.9 554.5 611.4 643.0 656.0 8634.9 8837.0 8992.5 9558.9 9922.7 618.0 621.0 667.8 690.6 754.2 4298.3 4542.2 4695.2 4728.7 4755.7 66038.3 68480.7 76395.0 81835.2 81867.6 67376.7 69980.3 70384.6 70568.0 70872.3 63720.4 63736.0 64095.0 64617.0 64630.7 9367.5 9933.1 10029.9 10145.9 10206.9 41220.8 42798.4 43060.1 43503.6 44713.3 50802.0 52014.0 54125.0 55360.2 55504.0 10994.7 11148.2 11316.9 12223.2 13083.9 12936.4 13879.3 14350.6 14624.7 14937.2 13044.0 13838.7 13883.5 14065.8 14367.9 13720.9 13937.7 14399.6 14552.3 14563.2 13334.3 13755.5 13822.2 14123.2 14558.5 10281.2 10389.8 10459.8 10688.6 10821.8 9370.0 10740.3 10918.3 11270.9 11748.4 05:26:38 INFO - PROCESS | 25171 | -------- Summary: end -------- 05:26:38 INFO - PROCESS | 25171 | 05:26:39 INFO - TEST-INFO | 25171: exit 0 05:26:39 INFO - TEST-OK | dromaeo_css | took 1118135ms 05:26:39 INFO - TEST-START | kraken 05:26:39 INFO - operating with platform_type : linux_ 05:26:39 INFO - Initialising browser for kraken test... 05:26:39 INFO - TEST-INFO | started process 25480 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUmskd0/profile http://localhost:39603/getInfo.html) 05:26:43 INFO - PROCESS | 25480 | __metrics Screen width/height:1600/1200 05:26:43 INFO - PROCESS | 25480 | colorDepth:24 05:26:43 INFO - PROCESS | 25480 | Browser inner width/height: 1024/697 05:26:43 INFO - PROCESS | 25480 | __metrics 05:26:43 INFO - PROCESS | 25480 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:26:43 INFO - PROCESS | 25480 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:26:44 INFO - PROCESS | 25480 | console.error: 05:26:44 INFO - PROCESS | 25480 | Message: Error: Connection closed before committing the transaction. 05:26:44 INFO - PROCESS | 25480 | Stack: 05:26:44 INFO - PROCESS | 25480 | executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 05:26:44 INFO - PROCESS | 25480 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 05:26:44 INFO - PROCESS | 25480 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 05:26:44 INFO - PROCESS | 25480 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 05:26:44 INFO - PROCESS | 25480 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 05:26:44 INFO - PROCESS | 25480 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 05:26:44 INFO - PROCESS | 25480 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 05:26:44 INFO - PROCESS | 25480 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 05:26:44 INFO - PROCESS | 25480 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 05:26:44 INFO - PROCESS | 25480 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 05:26:44 INFO - PROCESS | 25480 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 05:26:44 INFO - PROCESS | 25480 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 05:26:44 INFO - PROCESS | 25480 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 05:26:44 INFO - PROCESS | 25480 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 05:26:44 INFO - PROCESS | 25480 | 05:26:44 INFO - PROCESS | 25480 | console.error: PushService: 05:26:44 INFO - PROCESS | 25480 | stateChangeProcessEnqueue: Error transitioning state 05:26:44 INFO - PROCESS | 25480 | UnknownError 05:26:45 INFO - TEST-INFO | 25480: exit 0 05:26:45 INFO - Browser initialized. 05:26:45 INFO - Running cycle 1/1 for kraken test... 05:26:45 INFO - TEST-INFO | started process 25563 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUmskd0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:57 INFO - PROCESS | 25563 | RSS: Main: 172105728 05:26:57 INFO - PROCESS | 25563 | 05:26:59 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39603/tests/kraken/driver.html?testName=audio-beat-detection) 05:27:00 INFO - PROCESS | 25563 | RSS: Main: 234102784 05:27:00 INFO - PROCESS | 25563 | 05:27:03 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39603/tests/kraken/driver.html?testName=audio-dft) 05:27:04 INFO - PROCESS | 25563 | RSS: Main: 217075712 05:27:04 INFO - PROCESS | 25563 | 05:27:06 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39603/tests/kraken/driver.html?testName=audio-fft) 05:27:07 INFO - PROCESS | 25563 | RSS: Main: 298651648 05:27:07 INFO - PROCESS | 25563 | 05:27:09 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39603/tests/kraken/driver.html?testName=audio-oscillator) 05:27:10 INFO - PROCESS | 25563 | RSS: Main: 208486400 05:27:10 INFO - PROCESS | 25563 | 05:27:12 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39603/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:27:13 INFO - PROCESS | 25563 | RSS: Main: 213610496 05:27:13 INFO - PROCESS | 25563 | 05:27:16 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39603/tests/kraken/driver.html?testName=imaging-darkroom) 05:27:16 INFO - PROCESS | 25563 | RSS: Main: 252862464 05:27:16 INFO - PROCESS | 25563 | 05:27:19 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39603/tests/kraken/driver.html?testName=imaging-desaturate) 05:27:20 INFO - PROCESS | 25563 | RSS: Main: 252817408 05:27:20 INFO - PROCESS | 25563 | 05:27:23 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39603/tests/kraken/driver.html?testName=json-parse-financial) 05:27:23 INFO - PROCESS | 25563 | RSS: Main: 251273216 05:27:23 INFO - PROCESS | 25563 | 05:27:25 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39603/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:27:26 INFO - PROCESS | 25563 | RSS: Main: 213078016 05:27:26 INFO - PROCESS | 25563 | 05:27:27 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39603/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:27:28 INFO - PROCESS | 25563 | RSS: Main: 220295168 05:27:28 INFO - PROCESS | 25563 | 05:27:30 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39603/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:27:31 INFO - PROCESS | 25563 | RSS: Main: 229539840 05:27:31 INFO - PROCESS | 25563 | 05:27:33 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39603/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:27:34 INFO - PROCESS | 25563 | RSS: Main: 239259648 05:27:34 INFO - PROCESS | 25563 | 05:27:37 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39603/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:27:37 INFO - PROCESS | 25563 | RSS: Main: 235646976 05:27:37 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:39603/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39603/tests/kraken/driver.html?testName=ai-astar) 05:27:39 INFO - PROCESS | 25563 | RSS: Main: 287055872 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | __start_tp_report 05:27:39 INFO - PROCESS | 25563 | _x_x_mozilla_page_load 05:27:39 INFO - PROCESS | 25563 | _x_x_mozilla_page_load_details 05:27:39 INFO - PROCESS | 25563 | |i|pagename|runs| 05:27:39 INFO - PROCESS | 25563 | |0;ai-astar;106;112;114;112;112;114;112;112;112;112 05:27:39 INFO - PROCESS | 25563 | |1;audio-beat-detection;147;130;130;158;125;127;130;127;143;123 05:27:39 INFO - PROCESS | 25563 | |2;audio-dft;161;161;160;160;160;161;161;163;163;161 05:27:39 INFO - PROCESS | 25563 | |3;audio-fft;74;75;76;120;77;77;77;75;90;74 05:27:39 INFO - PROCESS | 25563 | |4;audio-oscillator;83;83;110;84;83;84;101;83;83;109 05:27:39 INFO - PROCESS | 25563 | |5;imaging-gaussian-blur;117;116;117;117;116;116;117;117;117;118 05:27:39 INFO - PROCESS | 25563 | |6;imaging-darkroom;145;145;145;145;147;145;146;146;146;147 05:27:39 INFO - PROCESS | 25563 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 05:27:39 INFO - PROCESS | 25563 | |8;json-parse-financial;52;52;63;51;52;53;52;53;53;53 05:27:39 INFO - PROCESS | 25563 | |9;json-stringify-tinderbox;61;62;61;62;61;61;62;63;62;66 05:27:39 INFO - PROCESS | 25563 | |10;stanford-crypto-aes;82;88;82;82;80;81;81;90;80;87 05:27:39 INFO - PROCESS | 25563 | |11;stanford-crypto-ccm;132;132;137;140;138;142;135;137;174;136 05:27:39 INFO - PROCESS | 25563 | |12;stanford-crypto-pbkdf2;171;163;163;166;167;162;163;163;163;164 05:27:39 INFO - PROCESS | 25563 | |13;stanford-crypto-sha256-iterative;64;64;65;66;70;65;63;63;63;63 05:27:39 INFO - PROCESS | 25563 | __end_tp_report 05:27:39 INFO - PROCESS | 25563 | __start_cc_report 05:27:39 INFO - PROCESS | 25563 | _x_x_mozilla_cycle_collect,1667 05:27:39 INFO - PROCESS | 25563 | __end_cc_report 05:27:39 INFO - PROCESS | 25563 | __startTimestamp1481203659848__endTimestamp 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | ------- Summary: start ------- 05:27:39 INFO - PROCESS | 25563 | Number of tests: 14 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:2.20 (2.0%) stddev-sans-first:0.88 05:27:39 INFO - PROCESS | 25563 | Values: 106.0 112.0 114.0 112.0 112.0 114.0 112.0 112.0 112.0 112.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:130.00 stddev:11.42 (8.8%) stddev-sans-first:11.10 05:27:39 INFO - PROCESS | 25563 | Values: 147.0 130.0 130.0 158.0 125.0 127.0 130.0 127.0 143.0 123.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 05:27:39 INFO - PROCESS | 25563 | Values: 161.0 161.0 160.0 160.0 160.0 161.0 161.0 163.0 163.0 161.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.50 stddev:14.31 (18.7%) stddev-sans-first:14.92 05:27:39 INFO - PROCESS | 25563 | Values: 74.0 75.0 76.0 120.0 77.0 77.0 77.0 75.0 90.0 74.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:83.00 stddev:11.54 (13.9%) stddev-sans-first:11.93 05:27:39 INFO - PROCESS | 25563 | Values: 83.0 83.0 110.0 84.0 83.0 84.0 101.0 83.0 83.0 109.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 05:27:39 INFO - PROCESS | 25563 | Values: 117.0 116.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 118.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 05:27:39 INFO - PROCESS | 25563 | Values: 145.0 145.0 145.0 145.0 147.0 145.0 146.0 146.0 146.0 147.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 05:27:39 INFO - PROCESS | 25563 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.00 stddev:3.44 (6.5%) stddev-sans-first:3.61 05:27:39 INFO - PROCESS | 25563 | Values: 52.0 52.0 63.0 51.0 52.0 53.0 52.0 53.0 53.0 53.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:1.52 (2.5%) stddev-sans-first:1.56 05:27:39 INFO - PROCESS | 25563 | Values: 61.0 62.0 61.0 62.0 61.0 61.0 62.0 63.0 62.0 66.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:82.00 stddev:3.62 (4.4%) stddev-sans-first:3.81 05:27:39 INFO - PROCESS | 25563 | Values: 82.0 88.0 82.0 82.0 80.0 81.0 81.0 90.0 80.0 87.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:137.50 stddev:12.25 (8.9%) stddev-sans-first:12.62 05:27:39 INFO - PROCESS | 25563 | Values: 132.0 132.0 137.0 140.0 138.0 142.0 135.0 137.0 174.0 136.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:163.50 stddev:2.76 (1.7%) stddev-sans-first:1.64 05:27:39 INFO - PROCESS | 25563 | Values: 171.0 163.0 163.0 166.0 167.0 162.0 163.0 163.0 163.0 164.0 05:27:39 INFO - PROCESS | 25563 | 05:27:39 INFO - PROCESS | 25563 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:64.50 stddev:2.17 (3.4%) stddev-sans-first:2.29 05:27:39 INFO - PROCESS | 25563 | Values: 64.0 64.0 65.0 66.0 70.0 65.0 63.0 63.0 63.0 63.0 05:27:39 INFO - PROCESS | 25563 | -------- Summary: end -------- 05:27:39 INFO - PROCESS | 25563 | 05:27:40 INFO - TEST-INFO | 25563: exit 0 05:27:40 INFO - TEST-OK | kraken | took 61924ms 05:27:40 INFO - SUITE-END | took 1180s 05:27:41 INFO - Completed test suite (00:19:40) 05:27:41 INFO - javascript benchmark 05:27:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3244.545763593097, 3287.2603664175563, 3301.158204657812, 3321.651226621205, 3326.5841769778776, 5166.638335316529, 5253.8686532836655, 5256.26412302278, 5262.0, 5312.2828418163535, 5261.684298942067, 5293.15309550472, 5341.813036543722, 5346.545543628797, 5444.673319600817, 4826.686265392738, 4855.393075865517, 4874.8537543873745, 4905.926411103818, 4937.851864444073, 4846.854594362175, 4886.267059941002, 4941.975290123562, 4966.9751651241695, 5006.849794506171, 4866.099771542241, 4881.267809828519, 4987.576056035223, 5003.574696150831, 5026.472220416858, 4773.093112308685, 4791.065742180242, 4796.328514008042, 4802.447718512381, 4810.5670489656095, 4802.135615589193, 4805.951940480585, 4809.014152098812, 4839.467658557556, 4870.293807397908, 5239.187925871496, 5263.02703616592, 5284.9735751320995, 5300.761465734013, 5319.654222475527, 5499.587530935156, 5537.58468114889, 5662.348829884575, 5712.82861514151, 5738.225339579145, 5731.512821410185, 5771.884562308773, 5782.855428614318, 5794.855128621776, 5826.300843898759, 5349.705766182819, 5370.925814837048, 5379.65032272901, 5387.838364849061, 5389.083855744533, 5624.409437009137, 5662.292213473316, 5849.970750146222, 5851.941480585225, 5852.970735146382, 5476.315460567429, 5544.16837474374, 5545.279113715191, 5546.251256080377, 5546.611737178439, 5564.360098588714, 5592.720363981784, 5623.718814059363, 5625.943740562624, 5630.690312032795, 5542.445755424425, 5630.577706672016, 5641.407652196543, 5654.038813401731, 5661.547076233891, 5624.943750562524, 5632.155176723524, 5634.6337488063145, 5635.520980716603, 5667.291588551431, 4327.76197309151, 4352.1513304905875, 4387.495438024668, 4389.100234451929, 4432.445944256903, 3949.842006319715, 3961.4652021976663, 3986.80065996699, 3997.960020399759, 4047.251258517184, 5414.0254754144635, 5415.404305526389, 5419.756110975016, 5423.620346575702, 5450.427705090988, 5696.145578163226, 5728.112142617901, 5728.140778883201, 5728.226689396962, 5773.653580785082, 5191.558717508979, 5215.895682086337, 5262.079136151158, 5269.051570717308, 5464.28964234647, 4787.305840653085, 4814.15752243356, 4817.084753896748, 4835.274708793639, 4852.708837469763, 5861.41385861419, 5861.9413805861395, 5869.284113092055, 5869.6771677558145, 5917.852053698778, 5584.804531841366, 5616.0, 5633.8309850704545, 5653.095504719226, 5663.518600918968, 5816.621919575214, 5910.024845900378, 5910.645361278337, 5932.0, 5946.881062378641, 5536.0, 5573.303337082864, 5575.972120139373, 5579.581531385244, 5580.972095139498, 5523.530499907552, 5559.8888022240135, 5627.971860140673, 5679.630823996427, 5682.119271512922, 5504.532114770129, 5507.862303442285, 5547.140193270049, 5568.0, 5616.269884914935, 5547.750351234285, 5553.777848886161, 5556.611037227355, 5559.33288005442, 5573.3869274380595, 5353.705546194997, 5511.311086114236, 5511.917321240265, 5512.889742205213, 5527.723613819373, 5493.176023596492, 5567.638103523258, 5582.162675598693, 5614.831555053355, 5694.0, 5453.945460545344, 5481.177823326373, 5493.176023596492, 5502.614816962774, 5547.389787123494], "value": 5250.918022718759, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.4066827282392, 571.702714588414, 598.8173607049849, 602.475846013968, 607.2682417686688, 31731.206719832015, 41448.37827432587, 42364.36453453197, 42388.0, 42427.0, 42155.57844421555, 42462.7876860616, 42576.14847703044, 42587.14825703484, 42597.36103958443, 7902.723404680837, 8586.91413085869, 8739.344549158806, 8742.781430464258, 8744.650213991432, 7751.961240193791, 10178.898211017906, 10390.896091039069, 10393.584256629763, 10663.466826658674, 10342.06921377076, 10399.480025998708, 10402.115820155295, 10407.219458540601, 10412.791744165112, 10433.634822781205, 10492.21308401873, 10494.79010419795, 10498.842517362207, 10500.842487362695, 10217.795644087151, 10270.075693187575, 10284.537195826206, 10285.074343309063, 10292.639757608522, 38885.22229555393, 39118.0, 39182.804085979675, 39219.21561568753, 39262.80368598167, 3544.3442963051916, 3648.945265821015, 3804.8097595120134, 3993.1414745829497, 4022.155347377054, 29598.852005739835, 29740.40519189604, 29970.850145749133, 30053.549196762066, 30727.231819204477, 3980.383040628707, 4069.776162311072, 4102.118044620391, 4107.404426358191, 4126.938095928564, 219237.0, 220946.0, 223117.0, 223551.0, 223779.0, 54288.72855635697, 54609.0, 54683.88348155525, 54721.452785471636, 54731.72634136804, 58609.0, 58982.70508647515, 59035.0, 59046.40953590495, 59193.70403147957, 16276.67446651084, 16502.75245871313, 16514.83485165133, 16518.917405412896, 16526.0, 9920.851187232198, 10319.896801031893, 12038.291384438606, 12056.698582535331, 12387.07096967731, 16024.679506409806, 16698.749518757228, 16710.58223544421, 16732.498025059267, 16740.330386784637, 5858.443447872445, 5993.0, 6030.939690603125, 6113.449789518964, 6178.814635560941, 930.1953809954341, 973.2360097323567, 978.5498670611456, 1005.969820905374, 1032.736652153696, 30162.547561786592, 30241.69758302433, 30528.389432211232, 30668.38663226723, 30708.232294192378, 1027.41950797799, 1048.5334026358198, 1075.8493810866485, 1091.956321747137, 1150.8446359741495, 15426.768598471032, 15982.36070557188, 16019.679606407806, 16058.219280644316, 16241.2691428886, 434711.0, 437759.0, 439834.0, 440105.0, 442452.0, 56115.158272625944, 56176.71911640416, 56282.0, 56377.0, 56477.71761141168, 1235.2032938754453, 1255.4852510470664, 1265.9050571207013, 1335.4524644895546, 1366.1120271823395, 29305.120846374644, 30438.84780576083, 30630.846845765627, 31090.0, 31640.208994775316, 48521.51478485311, 48780.26829597488, 49111.75444122757, 49124.61380020574, 49239.75380123076, 343017.0, 343311.0, 345759.61043311603, 347191.0, 470764.0, 27706.168814935583, 27706.861465692542, 27905.72094279031, 28036.299092522033, 28092.297692557848, 45687.54312456833, 46668.0, 47371.52628473671, 47575.5242447585, 48240.5175948236, 39049.41425878671, 39485.80257098811, 39944.80027599844, 40345.39481907833, 41410.58589414188, 39662.0, 39716.602833971294, 40084.1983160341, 40647.796761016005, 41178.0, 38755.80622096984, 39186.0, 40248.0, 40313.0, 40389.394159088224, 32443.351132976735, 35754.64245357513, 36282.637173627925, 36370.63629363672, 36799.63200367962, 26344.0, 26912.596311054956, 26919.0, 26994.460110798067, 27003.1899043029], "value": 19236.094117656736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.40619556048046, 382.2813111350661, 384.0494775430808, 395.30229145558076, 398.80458575298115, 13913.721725565496, 17429.65140697187, 18100.90949545254, 19231.32690355838, 19397.127129279183, 16381.508554743377, 16878.83121168788, 17159.227834747435, 17358.264173582636, 18364.0, 5071.213961835912, 5187.507186817246, 5201.40183878855, 5223.294855194557, 5229.8169564065265, 3768.8492460301554, 5186.0665080285535, 5265.842024739264, 5267.86740850717, 5269.578433725293, 3912.921741565167, 6065.514758819306, 6137.232845894263, 6199.0391489319, 6199.938000619981, 6115.357887421801, 6182.505399568024, 6201.627902325852, 6233.252009758835, 6252.937470625281, 5626.887462250732, 5636.887262254773, 5637.323521177444, 5641.097424412074, 5645.943540564583, 20755.377338679864, 21782.346529604136, 21859.344219673436, 21873.343799685877, 22075.558488830295, 2257.7855103765282, 2314.317276403448, 2368.0882860098814, 2618.882150303222, 2920.6349206349205, 9626.566804493814, 10003.89996100037, 10192.674952256233, 10474.58101675932, 10793.67618971432, 1876.47458711561, 1907.647085289226, 1918.539550507882, 1925.0952052535285, 1928.4021953194554, 69222.65388673024, 70320.6483967582, 70390.64804675944, 80556.59721701413, 83461.58269208614, 10875.347479151276, 10945.398003109905, 11024.409263822996, 11101.38942358178, 11349.829752553718, 15459.690806183813, 15469.458568950033, 15486.148261845481, 15491.84508154904, 15551.766723499157, 7689.19263477338, 7750.806229844186, 7776.922230777733, 7908.881366779503, 7941.920580794118, 6957.512974091866, 7049.29507049291, 7181.174164970991, 7199.964000180071, 7219.783406497813, 4790.856274311776, 4792.281157826354, 4802.51974802517, 4865.781039853215, 4951.331570238044, 1942.4658218989607, 2000.469875482998, 2005.258054519837, 2012.1347820437352, 2087.4155236533493, 894.7986702991905, 915.3729695158837, 949.767307009787, 970.1171933540444, 990.5938565188383, 17113.48659540216, 17996.37012704549, 18250.178741956643, 18506.259749609864, 18595.721064183777, 955.1546881010356, 985.9161971232526, 991.9702408927744, 1011.9848202276971, 1030.3457304611625, 9865.457399842933, 10019.749506262402, 10217.489125543692, 10274.589016439408, 10310.32982856127, 395149.0, 399598.0, 401085.0, 401462.0, 405032.0, 85660.0, 86742.0, 86924.56537717271, 88942.0, 90013.0, 986.8618393424927, 1033.7053939627322, 1075.2097208551709, 1076.1713480619878, 1076.5424694504804, 15391.076535407912, 15415.460458883885, 15705.293261803476, 15908.363665453253, 16104.114273715059, 38981.6101838978, 39169.60830391774, 39236.2152756949, 40044.79977600093, 40283.19433611253, 52105.0, 52106.47893521168, 52669.20996184984, 53649.4909256306, 57824.0, 8170.5896760457, 8211.3430925527, 8316.750497485085, 8418.410711250153, 8462.32301415873, 8750.781230469289, 8784.253338466046, 8978.326625503245, 8987.056359082202, 9256.25949923991, 8317.916820831715, 8368.707095251637, 8550.289942011497, 8602.440841345293, 8632.913670863461, 8322.625481853453, 8416.36877234222, 8439.409241353047, 8462.576871156542, 8730.214280714767, 8208.425410221227, 8399.370047246359, 8457.492550446992, 8508.489490630582, 8844.071372506052, 11724.58963936258, 12124.09069319787, 12187.329696866404, 12492.875071249171, 12548.937255313665, 11345.602903898323, 11412.82880756806, 11734.765304694029, 11848.703782405164, 12097.395130243629], "value": 8675.549412254415, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.1589382795007, 1385.0789225165265, 1401.1172961034547, 1528.6178455386153, 1696.0163105398863, 6656.534042617019, 7406.407487401008, 7672.309492145706, 7701.3453856422275, 7861.68553257869, 7697.576633285181, 7758.612069396536, 7760.3791696664275, 7763.650635721392, 7767.650455729492, 1913.8277555020045, 1975.150685205361, 2181.454636340911, 2213.6236839737285, 2215.0, 2083.6978638097544, 2151.9031643576, 2160.3950893749698, 2167.468970102327, 2174.402039439151, 2212.4468882779306, 2217.2793842001333, 2223.1663126327626, 2223.2774348336775, 2287.4281429642588, 2230.609643312414, 2232.765559616233, 2236.709227800392, 2238.373255488466, 2252.1779550464125, 2156.762756096828, 2268.138107519149, 2269.7389800173028, 2274.056266649355, 2274.3177046885867, 7678.884816727754, 7731.652075656609, 7740.342070923978, 7744.728934487266, 7754.7673569793, 4685.109829132488, 4806.327114204014, 4812.759362031885, 4814.927776083362, 4820.517948205186, 3186.988997183038, 3222.9425098082033, 3266.644342597819, 3360.31113621707, 3381.8140002299865, 1050.6900464363032, 1086.6142519405576, 1106.916981226403, 1109.1902910875026, 1112.6828853776635, 9642.75893102671, 9927.702168934871, 9944.154746846527, 10184.338018028879, 10235.334703244265, 4069.8168582413864, 4181.247375472444, 4242.44848169736, 4257.297545904953, 4257.531671516131, 8213.876791848126, 8263.256306932404, 8273.379496537784, 8276.503409795432, 8279.793005174919, 4742.146413645577, 4783.712977221308, 4785.401824771903, 4786.042791441726, 4787.42550893895, 3894.7078969076865, 4025.1547175092974, 4419.337099435111, 4465.040016396425, 4465.464144302705, 3451.0, 3513.0865974846724, 3529.699975502111, 3565.750397472152, 3576.445650924111, 1114.1198453222025, 1118.669992352258, 1118.7706520163347, 1119.0655802405083, 1138.5332013874272, 920.7606022434081, 921.4665931083799, 924.3390975452584, 951.2437612098398, 976.5166242709762, 5138.0, 5422.864428389243, 5587.078132108157, 5613.158026296007, 5672.914906276409, 903.20969151992, 904.9321300902459, 926.8146370725882, 940.3652534539159, 974.2011550528499, 3645.5078564394, 3649.9635003650155, 3662.1577037281572, 3698.130939229279, 3802.4029907438944, 9127.771805704777, 9441.103095205945, 9445.574949127305, 9478.668246611336, 9495.430274183433, 9358.344915855962, 9414.10565996243, 9434.0, 9463.337566370288, 9470.289728270407, 7266.128064632172, 7412.925870741331, 7418.925810741824, 7421.18366979621, 7423.888641670379, 5289.365276166731, 5290.44450332725, 5394.03328169513, 5410.431904649955, 5411.431799661136, 8488.702895398632, 8549.700760473603, 8551.914480855112, 8557.871631925651, 8648.82702345937, 8474.322054235523, 8892.555372231493, 8940.329475289509, 8945.37382383227, 8971.372003959661, 6661.600303981777, 6874.0, 6933.965330173317, 7006.894896576658, 7155.141383034069, 7097.680604372919, 7151.392131668867, 7301.269873012656, 7302.452316076422, 7315.341619254293, 6445.742170313322, 6525.445337146396, 6531.150950376421, 6557.704903279268, 6577.440917522064, 6260.874782504416, 6359.459445947146, 6416.13382193398, 6476.4495017924, 6557.901631475436, 6008.128821320796, 6136.417040381157, 6143.754249829957, 6225.750969961332, 6235.906461402992, 4655.6973796703105, 4705.55297246761, 4778.617710583076, 4849.684770489906, 4859.829905953275, 4736.100140973204, 4804.495527969513, 4852.199387101089, 4902.975485122694, 4935.728534930613], "value": 4176.36743579026, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.6676789826226, 331.37370370000696, 337.83446111405414, 338.04839377153314, 345.67506543848793, 5135.0, 5202.843914682561, 5217.773823151556, 5314.92027619586, 5320.7871685132595, 5098.668586541872, 5157.71632560209, 5221.0, 5224.503672151149, 5225.216217567367, 4120.25835349637, 4143.129942712019, 4147.3778933159965, 4150.916981660365, 4158.189153115151, 4045.939310910339, 4143.461350024492, 4145.274576949037, 4157.355609880473, 4170.415242207957, 4139.6895232857505, 4143.233501802176, 4143.316352801784, 4144.5855414458465, 4161.146964872209, 4088.5298190708154, 4108.958910410887, 4112.876613701594, 4142.565030671782, 4143.274926887782, 3673.989652845462, 3695.8194665147425, 3698.815059247027, 3700.50043244164, 3730.9253814923823, 4953.0, 5149.459306772772, 5162.277281180638, 5164.380274367062, 5210.915633746494, 4676.321933319684, 4706.552877476634, 4708.78810453527, 4720.244760838249, 4725.976370118162, 5512.228288039678, 5528.889422211533, 5541.944580554183, 5585.441455854423, 5616.85957851057, 3933.9213215735526, 3973.4834471518807, 3997.960020399788, 4000.0, 4006.579309172524, 5688.431156884279, 5724.370319264877, 5732.856678583027, 5751.367349591542, 5773.393793651648, 4071.531773845986, 4077.429159917614, 4086.6730661547003, 4108.4453598764385, 4116.0, 3994.1612261425425, 4111.794410279534, 4113.341865301538, 4122.752634841919, 4169.833206671759, 4679.719216847, 4681.0, 4681.765911704401, 4691.929621055687, 4695.7886895089805, 5571.888562228733, 5579.238277052804, 5598.2442370280305, 5599.692016939108, 5603.915941260884, 1293.2563775828935, 1314.572763851752, 1377.669359353768, 1401.453433161068, 1413.547664747271, 933.477252738455, 949.8100379924043, 960.3805545423163, 967.4920666649924, 973.956171972263, 5066.504112162596, 5090.949090509048, 5103.2855400244, 5119.180931051014, 5124.308218390544, 5432.886258317036, 5435.565154787608, 5470.097433923438, 5471.781128754885, 5485.780568777284, 4868.41579010521, 5070.442251352349, 5121.795128194905, 5129.179331306973, 5172.637915345965, 2889.6821349651523, 2891.3928075104463, 2895.739383455499, 2907.691538807545, 2913.1843083936533, 5613.719314034363, 5642.887142257132, 5648.322201335866, 5676.4323567642905, 5758.942410575924, 5259.263703081571, 5413.539849112791, 5476.2333273341765, 5478.972605137029, 5526.640768350045, 5612.186232996192, 5623.3533143689, 5624.887502250014, 5627.859303517363, 5642.407547207484, 5275.920861187008, 5408.323959505062, 5427.538659214011, 5471.890562188654, 5480.369757477941, 5359.812406565751, 5373.468561459965, 5375.220593014069, 5375.543078838343, 5390.703511306916, 5602.151290074347, 5628.634138780966, 5642.605017648725, 5661.603687741983, 5752.367239603556, 3802.467654528313, 3928.7833827893173, 3948.9407658885716, 3955.081785334168, 4008.5390180129657, 5333.7066461345, 5360.65983504124, 5375.704336261543, 5452.209429632601, 5497.862553436196, 5360.946390536044, 5392.406835248198, 5507.889842203053, 5520.806771762969, 5524.585656075891, 5494.066008778597, 5503.807366742145, 5510.421405752339, 5544.972275138599, 5558.72206389687, 5196.714180720097, 5376.301080859464, 5431.972840135774, 5432.560371501476, 5434.701091439851, 5515.420880807618, 5521.779128834802, 5523.917141242965, 5537.2524709163745, 5548.889022219614, 5391.16436952273, 5429.5656347492895, 5430.728463576726, 5434.429384914527, 5461.890762184814], "value": 4169.28810513607, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.6521669598593, 733.4719002318333, 789.5144486141025, 795.1055063054064, 797.812514059196, 15082.36058689729, 15185.240737963113, 15218.467353642625, 15229.086254824719, 15269.312880920372, 14214.431422743079, 14308.427662893499, 14318.355673994669, 14339.569812905629, 14342.13947163171, 665.5108495255982, 688.9242183359852, 763.1109757132962, 777.1296148313883, 797.0395673213764, 789.7433334166419, 800.2117913854848, 802.2619190344867, 802.5987006496752, 802.6348011654683, 844.771911583872, 847.809242920341, 848.3637272045966, 848.9151084891492, 849.2823564088367, 684.5379368925956, 718.7951433841382, 810.8540462716709, 846.4963346808639, 848.5841937450666, 618.761776715963, 623.5230049012509, 624.2540164503421, 624.2883113250898, 624.3943374926314, 15605.0, 15625.374985000586, 15643.92178039114, 15647.452339168189, 15649.139297338641, 13618.843683083473, 14135.575932722035, 14160.645983850383, 14164.733642617235, 14348.354324055443, 12359.326561736836, 12399.566015189424, 12415.93792031043, 12745.681357966032, 12787.61637150887, 4153.584641535853, 4231.132617813341, 4254.276772948606, 4313.180495705806, 4430.8449204277695, 88637.55681221616, 89678.5516072422, 91053.0, 91788.0, 91967.54016229942, 11178.0, 11331.433428328715, 11346.773064538664, 11355.375454349925, 11390.886091139148, 22012.908773666477, 22042.0, 22069.337919862108, 22121.115155393927, 22253.22113726012, 8079.636416361278, 8089.191080891981, 8124.55314957683, 8211.302039326605, 8550.828983420297, 4228.746275223496, 4331.891702707395, 4410.625096866802, 4413.381420221365, 4606.101810146989, 3352.865885364607, 3391.508231306496, 3401.693847553732, 3432.6910578047596, 3459.1697992482127, 576.9538436925036, 590.9172715819789, 595.8033848829875, 599.2688919518181, 607.3471018654964, 544.923710680505, 554.5092593055175, 611.4466407900857, 642.9839254018686, 655.9508036897156, 8634.913650863537, 8836.955815220883, 8992.505412202261, 9558.90441095594, 9922.7023189303, 617.9691015449209, 620.959637623553, 667.8263651450658, 690.6408667492875, 754.1854796819422, 4298.312270036779, 4542.205114105084, 4695.154872123051, 4728.739919304471, 4755.690880092783, 66038.33961660322, 68480.6575967127, 76395.0, 81835.18164818276, 81867.5906620463, 67376.6631166841, 69980.3001969984, 70384.64807675929, 70568.0, 70872.2912770876, 63720.362796371446, 63736.0, 64095.0, 64617.03074453887, 64630.67684661641, 9367.531623418665, 9933.106020458194, 10029.899701002896, 10145.89854101479, 10206.897931020594, 41220.79389603033, 42798.35802463028, 43060.138797223255, 43503.56496434995, 44713.32930005987, 50802.0, 52014.0, 54125.0, 55360.16959745688, 55504.0, 10994.670159895217, 11148.219624626197, 11316.9434152832, 12223.20549164337, 13083.934580327037, 12936.41786119608, 13879.306034698428, 14350.641233969234, 14624.707505850036, 14937.178455185069, 13044.0, 13838.723225535634, 13883.514077007256, 14065.789013165015, 14367.928160359132, 13720.862791372358, 13937.651558711113, 14399.640008999859, 14552.345144468312, 14563.199024053778, 13334.266615335862, 13755.518556850462, 13822.170669759847, 14123.223222722849, 14558.4904528341, 10281.177505799667, 10389.84415233757, 10459.84310235332, 10688.57245710194, 10821.783564328827, 9370.0, 10740.30188037775, 10918.29031113007, 11270.94364528172, 11748.353840538852], "value": 7142.804961435594, "unit": "score"}], "name": "dromaeo_css", "value": 6911.478544806354, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 112.0, 114.0, 112.0, 112.0, 114.0, 112.0, 112.0, 112.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 130.0, 130.0, 158.0, 125.0, 127.0, 130.0, 127.0, 143.0, 123.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 160.0, 161.0, 161.0, 163.0, 163.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 120.0, 77.0, 77.0, 77.0, 75.0, 90.0, 74.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 110.0, 84.0, 83.0, 84.0, 101.0, 83.0, 83.0, 109.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 118.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 147.0, 145.0, 146.0, 146.0, 146.0, 147.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 63.0, 51.0, 52.0, 53.0, 52.0, 53.0, 53.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 62.0, 61.0, 61.0, 62.0, 63.0, 62.0, 66.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 82.0, 82.0, 80.0, 81.0, 81.0, 90.0, 80.0, 87.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 137.0, 140.0, 138.0, 142.0, 135.0, 137.0, 174.0, 136.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 163.0, 163.0, 166.0, 167.0, 162.0, 163.0, 163.0, 163.0, 164.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 66.0, 70.0, 65.0, 63.0, 63.0, 63.0, 63.0], "value": 64.6, "unit": "score"}], "name": "kraken", "value": 1504.3999999999996, "alertThreshold": 2.0}]} 05:27:41 INFO - Return code: 0 05:27:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:27:41 INFO - # TBPL SUCCESS # 05:27:41 INFO - Running post-action listener: _resource_record_post_action 05:27:41 INFO - [mozharness: 2016-12-08 13:27:41.172327Z] Finished run-tests step (success) 05:27:41 INFO - Running post-run listener: _resource_record_post_run 05:27:41 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392318976; Read time: 16; Write time: 294620 05:27:41 INFO - TinderboxPrint: CPU usage
13.1% 05:27:41 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 05:27:41 INFO - TinderboxPrint: I/O write bytes / time
392,318,976 / 294,620 05:27:41 INFO - TinderboxPrint: CPU idle
8,295.7 (86.9%) 05:27:41 INFO - TinderboxPrint: CPU user
1,203.2 (12.6%) 05:27:41 INFO - TinderboxPrint: Swap in / out
0 / 0 05:27:41 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103809024; Read time: 0; Write time: 65312 05:27:41 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 288509952; Read time: 0; Write time: 229308 05:27:41 INFO - Running post-run listener: _upload_blobber_files 05:27:41 INFO - Blob upload gear active. 05:27:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:27: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 05:27: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'] 05:27: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 05:27:42 INFO - (blobuploader) - INFO - Open directory for files ... 05:27:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:27:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:27:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:43 INFO - (blobuploader) - INFO - Done attempting. 05:27:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:27:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:27:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:46 INFO - (blobuploader) - INFO - Done attempting. 05:27:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:27:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:27:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:47 INFO - (blobuploader) - INFO - Done attempting. 05:27:47 INFO - (blobuploader) - INFO - Iteration through files over. 05:27:47 INFO - Return code: 0 05:27:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:27:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:27:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/627bf33d30df12a98a4856af29181cd5b4efe54dd0123d4110239455cc96e14409c13d667d6613d43c415aeecb818889f71441993a7a0f53d1e9772883130070", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/18b63308dc732387f1a341b9061344a15e9bd3e8dfe5cbb9abae07468fb46005766839ad90f4743a90adec070febcd50505eaee297611a9ce499dc58e5003a95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/58ff6ecd3983147aaa387e93ef2bf1956aff4c9d22ddf5680596629c99e1a6ad4da59a5d511d69672d7a9a42bbd7ecd000891ca14bf7cb0ce617397a0f0b0354"} 05:27:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:27:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:27:47 INFO - Contents: 05:27:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/627bf33d30df12a98a4856af29181cd5b4efe54dd0123d4110239455cc96e14409c13d667d6613d43c415aeecb818889f71441993a7a0f53d1e9772883130070", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/18b63308dc732387f1a341b9061344a15e9bd3e8dfe5cbb9abae07468fb46005766839ad90f4743a90adec070febcd50505eaee297611a9ce499dc58e5003a95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/58ff6ecd3983147aaa387e93ef2bf1956aff4c9d22ddf5680596629c99e1a6ad4da59a5d511d69672d7a9a42bbd7ecd000891ca14bf7cb0ce617397a0f0b0354"} 05:27:47 INFO - Running post-run listener: copy_logs_to_upload_dir 05:27:47 INFO - Copying logs to upload dir... 05:27:47 INFO - mkdir: /builds/slave/test/build/upload/logs 05:27:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.328969 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-12-08 05:27:46.651024) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 05:27:46.657400) ========= 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=836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/627bf33d30df12a98a4856af29181cd5b4efe54dd0123d4110239455cc96e14409c13d667d6613d43c415aeecb818889f71441993a7a0f53d1e9772883130070", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/18b63308dc732387f1a341b9061344a15e9bd3e8dfe5cbb9abae07468fb46005766839ad90f4743a90adec070febcd50505eaee297611a9ce499dc58e5003a95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/58ff6ecd3983147aaa387e93ef2bf1956aff4c9d22ddf5680596629c99e1a6ad4da59a5d511d69672d7a9a42bbd7ecd000891ca14bf7cb0ce617397a0f0b0354"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011418 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481196603/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/627bf33d30df12a98a4856af29181cd5b4efe54dd0123d4110239455cc96e14409c13d667d6613d43c415aeecb818889f71441993a7a0f53d1e9772883130070", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/18b63308dc732387f1a341b9061344a15e9bd3e8dfe5cbb9abae07468fb46005766839ad90f4743a90adec070febcd50505eaee297611a9ce499dc58e5003a95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/58ff6ecd3983147aaa387e93ef2bf1956aff4c9d22ddf5680596629c99e1a6ad4da59a5d511d69672d7a9a42bbd7ecd000891ca14bf7cb0ce617397a0f0b0354"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 05:27:46.681944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 05:27:46.683877) ========= 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=836cfdc75598d54cb747368e000001a9-1481195218.807267-416221860 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 05:27:46.764774) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 05:27:46.765095) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 05:27:46.765386) ========= ========= Total master_lag: 0.17 =========