builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1481006223.89 results: success (0) buildid: 20161205223639 builduid: 122d118a78a24bb5bed01739bd3018ef revision: 93d785448a69cd60c7105bfe722cfd94b6d59b37 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:03.889572) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:03.890267) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:03.890643) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:03.914291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:03.914612) ========= 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=03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-05 22:37: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% 224M=0s 2016-12-05 22:37:04 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129147 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:04.060287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:04.060648) ========= 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=03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017141 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:04.117927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:04.118251) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 93d785448a69cd60c7105bfe722cfd94b6d59b37 --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 93d785448a69cd60c7105bfe722cfd94b6d59b37 --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=03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-05 22:37:05,088 truncating revision to first 12 chars 2016-12-05 22:37:05,088 Setting DEBUG logging. 2016-12-05 22:37:05,088 attempt 1/10 2016-12-05 22:37:05,088 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/93d785448a69?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-05 22:37:05,581 unpacking tar archive at: mozilla-inbound-93d785448a69/testing/mozharness/ program finished with exit code 0 elapsedTime=0.799753 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:04.943247) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:04.943546) ========= script_repo_revision: 93d785448a69cd60c7105bfe722cfd94b6d59b37 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:04.943962) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:04.944241) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 22:37:04.953432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-12-05 22:37:04.953702) ========= /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=03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:37:05 INFO - MultiFileLogger online at 20161205 22:37:05 in /builds/slave/test 22:37: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 22:37:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:37:06 INFO - {'append_to_log': False, 22:37:06 INFO - 'base_work_dir': '/builds/slave/test', 22:37:06 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:37:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:37:06 INFO - 'branch': 'Mozilla-Inbound', 22:37:06 INFO - 'buildbot_json_path': 'buildprops.json', 22:37:06 INFO - 'config_files': ('talos/linux_config.py',), 22:37:06 INFO - 'default_actions': ('clobber', 22:37:06 INFO - 'read-buildbot-config', 22:37:06 INFO - 'download-and-extract', 22:37:06 INFO - 'populate-webroot', 22:37:06 INFO - 'create-virtualenv', 22:37:06 INFO - 'install', 22:37:06 INFO - 'run-tests'), 22:37:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:37:06 INFO - 'download_minidump_stackwalk': True, 22:37:06 INFO - 'download_symbols': 'ondemand', 22:37:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:37:06 INFO - 'tooltool.py': '/tools/tooltool.py', 22:37:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:37:06 INFO - '/tools/misc-python/virtualenv.py')}, 22:37:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:37:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:37:06 INFO - 'installer_path': 'installer.exe', 22:37:06 INFO - 'log_level': 'info', 22:37:06 INFO - 'log_name': 'talos', 22:37:06 INFO - 'log_to_console': True, 22:37:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:37:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:37:06 INFO - 'opt_config_files': (), 22:37:06 INFO - 'pip_index': False, 22:37:06 INFO - 'sps_profile': False, 22:37:06 INFO - 'sps_profile_interval': 0, 22:37:06 INFO - 'suite': 'dromaeojs', 22:37:06 INFO - 'system_bits': '32', 22:37:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:37:06 INFO - 'title': 'talos-linux64-ix-047', 22:37:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:37:06 INFO - 'use_talos_json': True, 22:37:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:37:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:37:06 INFO - 'work_dir': 'build'} 22:37:06 INFO - [mozharness: 2016-12-06 06:37:06.005617Z] Running clobber step. 22:37:06 INFO - Running pre-action listener: _resource_record_pre_action 22:37:06 INFO - Running main action method: clobber 22:37:06 INFO - rmtree: /builds/slave/test/build 22:37:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:37:06 INFO - Running post-action listener: _resource_record_post_action 22:37:06 INFO - [mozharness: 2016-12-06 06:37:06.471377Z] Finished clobber step (success) 22:37:06 INFO - [mozharness: 2016-12-06 06:37:06.471476Z] Running read-buildbot-config step. 22:37:06 INFO - Running pre-action listener: _resource_record_pre_action 22:37:06 INFO - Running main action method: read_buildbot_config 22:37:06 INFO - Using buildbot properties: 22:37:06 INFO - { 22:37:06 INFO - "project": "", 22:37:06 INFO - "product": "firefox", 22:37:06 INFO - "script_repo_revision": "production", 22:37:06 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:37:06 INFO - "repository": "", 22:37:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:37:06 INFO - "buildid": "20161205223639", 22:37:06 INFO - "stage_platform": "linux64-pgo", 22:37:06 INFO - "basedir": "/builds/slave/test", 22:37:06 INFO - "buildnumber": 2993, 22:37:06 INFO - "platform": "ubuntu64_hw", 22:37:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:37:06 INFO - "slavebuilddir": "test", 22:37:06 INFO - "branch": "mozilla-inbound", 22:37:06 INFO - "revision": "93d785448a69cd60c7105bfe722cfd94b6d59b37", 22:37:06 INFO - "slavename": "talos-linux64-ix-047", 22:37:06 INFO - "builduid": "122d118a78a24bb5bed01739bd3018ef", 22:37:06 INFO - "repo_path": "integration/mozilla-inbound" 22:37:06 INFO - } 22:37:06 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 22:37:06 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 22:37:06 INFO - Running post-action listener: _resource_record_post_action 22:37:06 INFO - [mozharness: 2016-12-06 06:37:06.473833Z] Finished read-buildbot-config step (success) 22:37:06 INFO - [mozharness: 2016-12-06 06:37:06.473920Z] Running download-and-extract step. 22:37:06 INFO - Running pre-action listener: _resource_record_pre_action 22:37:06 INFO - Running main action method: download_and_extract 22:37:06 INFO - mkdir: /builds/slave/test/build/tests 22:37: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')]} 22:37:06 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:37:06 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/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 22:37:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/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 22:37:06 INFO - Downloaded 1361 bytes. 22:37:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:37:06 INFO - Using the following test package requirements: 22:37:06 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 22:37:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:37:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:37:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:37:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:37:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:37:06 INFO - u'jsshell-linux-x86_64.zip'], 22:37:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:37:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:37:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 22:37:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:37:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:37:06 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:37:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:37:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:37:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:37:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:37:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:37:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:37: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/1480999449/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 22:37: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/1480999449/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:37:06 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:37:06 INFO - Expected file size: 21781865 22:37:06 INFO - Obtained file size: 21781865 22:37: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 22:37: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/1480999449/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 22:37: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/1480999449/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:37:10 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:37:11 INFO - Expected file size: 21781865 22:37:11 INFO - Obtained file size: 21781865 22:37: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/1480999449/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 22:37: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/1480999449/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:37:15 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 22:37:15 INFO - Expected file size: 14409734 22:37:15 INFO - Obtained file size: 14409734 22:37: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')]} 22:37:15 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:37:15 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/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 22:37:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/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 22:37:20 INFO - Downloaded 59834378 bytes. 22:37:20 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:37:20 INFO - mkdir: /builds/slave/test/properties 22:37:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:37:20 INFO - Writing to file /builds/slave/test/properties/build_url 22:37:20 INFO - Contents: 22:37:20 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:37:20 INFO - Running post-action listener: _resource_record_post_action 22:37:20 INFO - Running post-action listener: set_extra_try_arguments 22:37:20 INFO - [mozharness: 2016-12-06 06:37:20.623435Z] Finished download-and-extract step (success) 22:37:20 INFO - [mozharness: 2016-12-06 06:37:20.623662Z] Running populate-webroot step. 22:37:20 INFO - Running pre-action listener: _resource_record_pre_action 22:37:20 INFO - Running main action method: populate_webroot 22:37:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:37:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:37:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:37:20 INFO - '--tppagecycles', 22:37:20 INFO - '7'], 22:37:20 INFO - 'tests': ['tsvgm']}}, 22:37:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:37:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:37:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:37:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:37:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:37:20 INFO - 'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:37:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:37:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:37:20 INFO - 'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['damp', 'tps']}, 22:37:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:37:20 INFO - 'tests': ['damp', 'tps']}, 22:37:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['dromaeo_dom']}, 22:37:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:37:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['basic_compositor_video']}, 22:37:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:37:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['a11yr', 22:37:20 INFO - 'ts_paint', 22:37:20 INFO - 'tpaint', 22:37:20 INFO - 'sessionrestore', 22:37:20 INFO - 'sessionrestore_no_auto_restore', 22:37:20 INFO - 'tabpaint']}, 22:37:20 INFO - 'other-e10s': {'tests': ['a11yr', 22:37:20 INFO - 'ts_paint', 22:37:20 INFO - 'tpaint', 22:37:20 INFO - 'sessionrestore', 22:37:20 INFO - 'sessionrestore_no_auto_restore', 22:37:20 INFO - 'tabpaint']}, 22:37:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:37:20 INFO - 'ts_paint', 22:37:20 INFO - 'tpaint', 22:37:20 INFO - 'sessionrestore', 22:37:20 INFO - 'sessionrestore_no_auto_restore', 22:37:20 INFO - 'tabpaint']}, 22:37:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:37:20 INFO - 'ts_paint', 22:37:20 INFO - 'tpaint', 22:37:20 INFO - 'sessionrestore', 22:37:20 INFO - 'sessionrestore_no_auto_restore', 22:37:20 INFO - 'tabpaint']}, 22:37:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['a11yr', 22:37:20 INFO - 'ts_paint', 22:37:20 INFO - 'tpaint', 22:37:20 INFO - 'sessionrestore', 22:37:20 INFO - 'sessionrestore_no_auto_restore', 22:37:20 INFO - 'tabpaint']}, 22:37:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['a11yr', 22:37:20 INFO - 'ts_paint', 22:37:20 INFO - 'tpaint', 22:37:20 INFO - 'sessionrestore', 22:37:20 INFO - 'sessionrestore_no_auto_restore', 22:37:20 INFO - 'tabpaint']}, 22:37:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['tsvgx', 22:37:20 INFO - 'tsvgr_opacity', 22:37:20 INFO - 'tart', 22:37:20 INFO - 'tscrollx', 22:37:20 INFO - 'cart', 22:37:20 INFO - 'tsvg_static']}, 22:37:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:37:20 INFO - 'tsvgr_opacity', 22:37:20 INFO - 'tart', 22:37:20 INFO - 'tscrollx', 22:37:20 INFO - 'cart', 22:37:20 INFO - 'tsvg_static']}, 22:37:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:37:20 INFO - 'talos_options': ['--disable-e10s'], 22:37:20 INFO - 'tests': ['tp5o']}, 22:37:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:37:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:37:20 INFO - 'talos_options': ['--disable-e10s', 22:37:20 INFO - '--xperf_path', 22:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:37:20 INFO - 'tests': ['tp5n']}, 22:37:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:37:20 INFO - 'talos_options': ['--xperf_path', 22:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:37:20 INFO - 'tests': ['tp5n']}}, 22:37:20 INFO - 'talos.zip': {'path': '', 22:37:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:37:20 INFO - Running post-action listener: _resource_record_post_action 22:37:20 INFO - [mozharness: 2016-12-06 06:37:20.640204Z] Finished populate-webroot step (success) 22:37:20 INFO - [mozharness: 2016-12-06 06:37:20.640292Z] Running create-virtualenv step. 22:37:20 INFO - Running pre-action listener: _resource_record_pre_action 22:37:20 INFO - Running main action method: create_virtualenv 22:37:20 INFO - Creating virtualenv /builds/slave/test/build/venv 22:37: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 22:37:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:37:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:37:20 INFO - Using real prefix '/usr' 22:37:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:37:21 INFO - Installing distribute.............................................................................................................................................................................................done. 22:37:23 INFO - Installing pip.................done. 22:37:23 INFO - Return code: 0 22:37:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:37:23 INFO - Reading from file tmpfile_stdout 22:37:23 INFO - Output received: 22:37: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) 22:37:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37: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 22:37: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 22:37:23 INFO - Using env: {'DISPLAY': ':0', 22:37:23 INFO - 'HOME': '/home/cltbld', 22:37:23 INFO - 'LANG': 'en_US.UTF-8', 22:37:23 INFO - 'LANGUAGE': 'en_US:en', 22:37:23 INFO - 'LOGNAME': 'cltbld', 22:37:23 INFO - 'MAIL': '/var/mail/cltbld', 22:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:23 INFO - 'MOZ_NO_REMOTE': '1', 22:37:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:23 INFO - 'NO_EM_RESTART': '1', 22:37:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:23 INFO - 'PWD': '/builds/slave/test', 22:37:23 INFO - 'SHELL': '/bin/bash', 22:37:23 INFO - 'SHLVL': '1', 22:37:23 INFO - 'TERM': 'linux', 22:37:23 INFO - 'TMOUT': '86400', 22:37:23 INFO - 'USER': 'cltbld', 22:37:23 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:23 INFO - '_': '/tools/buildbot/bin/python'} 22:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37: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 22:37:23 INFO - Cleaning up... 22:37:23 INFO - Return code: 0 22:37:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37: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 22:37: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 22:37:23 INFO - Using env: {'DISPLAY': ':0', 22:37:23 INFO - 'HOME': '/home/cltbld', 22:37:23 INFO - 'LANG': 'en_US.UTF-8', 22:37:23 INFO - 'LANGUAGE': 'en_US:en', 22:37:23 INFO - 'LOGNAME': 'cltbld', 22:37:23 INFO - 'MAIL': '/var/mail/cltbld', 22:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:23 INFO - 'MOZ_NO_REMOTE': '1', 22:37:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:23 INFO - 'NO_EM_RESTART': '1', 22:37:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:23 INFO - 'PWD': '/builds/slave/test', 22:37:23 INFO - 'SHELL': '/bin/bash', 22:37:23 INFO - 'SHLVL': '1', 22:37:23 INFO - 'TERM': 'linux', 22:37:23 INFO - 'TMOUT': '86400', 22:37:23 INFO - 'USER': 'cltbld', 22:37:23 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:23 INFO - '_': '/tools/buildbot/bin/python'} 22:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:23 INFO - Downloading/unpacking psutil>=3.1.1 22:37:23 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 22:37:23 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 22:37:23 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 22:37:23 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 22:37:23 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 22:37:23 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 22:37:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:37:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:37:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:37:25 INFO - Installing collected packages: psutil 22:37:25 INFO - Running setup.py install for psutil 22:37:26 INFO - building 'psutil._psutil_linux' extension 22:37: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 22:37: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 22:37:26 INFO - building 'psutil._psutil_posix' extension 22:37: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 22:37: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 22:37:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:37:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:37:26 INFO - Successfully installed psutil 22:37:26 INFO - Cleaning up... 22:37:26 INFO - Return code: 0 22:37:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37: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 22:37: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 22:37:26 INFO - Using env: {'DISPLAY': ':0', 22:37:26 INFO - 'HOME': '/home/cltbld', 22:37:26 INFO - 'LANG': 'en_US.UTF-8', 22:37:26 INFO - 'LANGUAGE': 'en_US:en', 22:37:26 INFO - 'LOGNAME': 'cltbld', 22:37:26 INFO - 'MAIL': '/var/mail/cltbld', 22:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:26 INFO - 'MOZ_NO_REMOTE': '1', 22:37:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:26 INFO - 'NO_EM_RESTART': '1', 22:37:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:26 INFO - 'PWD': '/builds/slave/test', 22:37:26 INFO - 'SHELL': '/bin/bash', 22:37:26 INFO - 'SHLVL': '1', 22:37:26 INFO - 'TERM': 'linux', 22:37:26 INFO - 'TMOUT': '86400', 22:37:26 INFO - 'USER': 'cltbld', 22:37:26 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:26 INFO - '_': '/tools/buildbot/bin/python'} 22:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:37:26 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 22:37:26 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 22:37:26 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 22:37:26 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 22:37:26 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 22:37:26 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 22:37:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:37:28 INFO - Installing collected packages: mozsystemmonitor 22:37:28 INFO - Running setup.py install for mozsystemmonitor 22:37:28 INFO - Successfully installed mozsystemmonitor 22:37:28 INFO - Cleaning up... 22:37:28 INFO - Return code: 0 22:37:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:37:28 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')]} 22:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:28 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')]} 22:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:28 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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37:28 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 22:37:28 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 22:37:28 INFO - Using env: {'DISPLAY': ':0', 22:37:28 INFO - 'HOME': '/home/cltbld', 22:37:28 INFO - 'LANG': 'en_US.UTF-8', 22:37:28 INFO - 'LANGUAGE': 'en_US:en', 22:37:28 INFO - 'LOGNAME': 'cltbld', 22:37:28 INFO - 'MAIL': '/var/mail/cltbld', 22:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:28 INFO - 'MOZ_NO_REMOTE': '1', 22:37:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:28 INFO - 'NO_EM_RESTART': '1', 22:37:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:28 INFO - 'PWD': '/builds/slave/test', 22:37:28 INFO - 'SHELL': '/bin/bash', 22:37:28 INFO - 'SHLVL': '1', 22:37:28 INFO - 'TERM': 'linux', 22:37:28 INFO - 'TMOUT': '86400', 22:37:28 INFO - 'USER': 'cltbld', 22:37:28 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:28 INFO - '_': '/tools/buildbot/bin/python'} 22:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:29 INFO - Downloading/unpacking jsonschema==2.5.1 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:37:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:31 INFO - Downloading functools32-3.2.3-2.tar.gz 22:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:37:31 INFO - warning: no files found matching '*.txt' 22:37:31 INFO - no previously-included directories found matching 'build' 22:37:31 INFO - no previously-included directories found matching 'dist' 22:37:31 INFO - no previously-included directories found matching '.git*' 22:37:31 INFO - Installing collected packages: jsonschema, functools32 22:37:31 INFO - Running setup.py install for functools32 22:37:31 INFO - warning: no files found matching '*.txt' 22:37:31 INFO - no previously-included directories found matching 'build' 22:37:31 INFO - no previously-included directories found matching 'dist' 22:37:31 INFO - no previously-included directories found matching '.git*' 22:37:31 INFO - Successfully installed jsonschema functools32 22:37:31 INFO - Cleaning up... 22:37:31 INFO - Return code: 0 22:37:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37: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 22:37: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 22:37:31 INFO - Using env: {'DISPLAY': ':0', 22:37:31 INFO - 'HOME': '/home/cltbld', 22:37:31 INFO - 'LANG': 'en_US.UTF-8', 22:37:31 INFO - 'LANGUAGE': 'en_US:en', 22:37:31 INFO - 'LOGNAME': 'cltbld', 22:37:31 INFO - 'MAIL': '/var/mail/cltbld', 22:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:31 INFO - 'MOZ_NO_REMOTE': '1', 22:37:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:31 INFO - 'NO_EM_RESTART': '1', 22:37:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:31 INFO - 'PWD': '/builds/slave/test', 22:37:31 INFO - 'SHELL': '/bin/bash', 22:37:31 INFO - 'SHLVL': '1', 22:37:31 INFO - 'TERM': 'linux', 22:37:31 INFO - 'TMOUT': '86400', 22:37:31 INFO - 'USER': 'cltbld', 22:37:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:31 INFO - '_': '/tools/buildbot/bin/python'} 22:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:37:31 INFO - Cleaning up... 22:37:31 INFO - Return code: 0 22:37:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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', '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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:37: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 blobuploader==1.2.4 22:37:31 INFO - Using env: {'DISPLAY': ':0', 22:37:31 INFO - 'HOME': '/home/cltbld', 22:37:31 INFO - 'LANG': 'en_US.UTF-8', 22:37:31 INFO - 'LANGUAGE': 'en_US:en', 22:37:31 INFO - 'LOGNAME': 'cltbld', 22:37:31 INFO - 'MAIL': '/var/mail/cltbld', 22:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:31 INFO - 'MOZ_NO_REMOTE': '1', 22:37:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:31 INFO - 'NO_EM_RESTART': '1', 22:37:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:31 INFO - 'PWD': '/builds/slave/test', 22:37:31 INFO - 'SHELL': '/bin/bash', 22:37:31 INFO - 'SHLVL': '1', 22:37:31 INFO - 'TERM': 'linux', 22:37:31 INFO - 'TMOUT': '86400', 22:37:31 INFO - 'USER': 'cltbld', 22:37:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:31 INFO - '_': '/tools/buildbot/bin/python'} 22:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:32 INFO - Downloading/unpacking blobuploader==1.2.4 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:34 INFO - Downloading blobuploader-1.2.4.tar.gz 22:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:37:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:37:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:34 INFO - Downloading docopt-0.6.1.tar.gz 22:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:37:35 INFO - Installing collected packages: blobuploader, requests, docopt 22:37:35 INFO - Running setup.py install for blobuploader 22:37:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:37:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:37:35 INFO - Running setup.py install for requests 22:37:35 INFO - Running setup.py install for docopt 22:37:35 INFO - Successfully installed blobuploader requests docopt 22:37:35 INFO - Cleaning up... 22:37:35 INFO - Return code: 0 22:37:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37: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 22:37: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 22:37:35 INFO - Using env: {'DISPLAY': ':0', 22:37:35 INFO - 'HOME': '/home/cltbld', 22:37:35 INFO - 'LANG': 'en_US.UTF-8', 22:37:35 INFO - 'LANGUAGE': 'en_US:en', 22:37:35 INFO - 'LOGNAME': 'cltbld', 22:37:35 INFO - 'MAIL': '/var/mail/cltbld', 22:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:35 INFO - 'MOZ_NO_REMOTE': '1', 22:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:35 INFO - 'NO_EM_RESTART': '1', 22:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:35 INFO - 'PWD': '/builds/slave/test', 22:37:35 INFO - 'SHELL': '/bin/bash', 22:37:35 INFO - 'SHLVL': '1', 22:37:35 INFO - 'TERM': 'linux', 22:37:35 INFO - 'TMOUT': '86400', 22:37:35 INFO - 'USER': 'cltbld', 22:37:35 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:35 INFO - '_': '/tools/buildbot/bin/python'} 22:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:37:35 INFO - Running setup.py (path:/tmp/pip-pimRqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:37:35 INFO - Running setup.py (path:/tmp/pip-X8VkeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:37:36 INFO - Running setup.py (path:/tmp/pip-VNW0mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:37:36 INFO - Running setup.py (path:/tmp/pip-KVabCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:37:36 INFO - Running setup.py (path:/tmp/pip-18Igjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:37:36 INFO - Running setup.py (path:/tmp/pip-LK5PQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:37:36 INFO - Running setup.py (path:/tmp/pip-wnXJRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:37:36 INFO - Running setup.py (path:/tmp/pip-bGew7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:37:36 INFO - Running setup.py (path:/tmp/pip-i6RCH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:37:36 INFO - Running setup.py (path:/tmp/pip-FuqgPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:37:36 INFO - Running setup.py (path:/tmp/pip-paCg1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:37:36 INFO - Running setup.py (path:/tmp/pip-786zll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:37:36 INFO - Running setup.py (path:/tmp/pip-b5Uq_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:37:36 INFO - Running setup.py (path:/tmp/pip-aXv9ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:37:36 INFO - Running setup.py (path:/tmp/pip-dQK7Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:37:36 INFO - Running setup.py (path:/tmp/pip-qC1G8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:37:37 INFO - Running setup.py (path:/tmp/pip-HFmNow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:37:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:37:37 INFO - Running setup.py install for manifestparser 22:37:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:37:37 INFO - Running setup.py install for mozcrash 22:37:37 INFO - Running setup.py install for mozdebug 22:37:37 INFO - Running setup.py install for mozdevice 22:37:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:37:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:37:37 INFO - Running setup.py install for mozfile 22:37:37 INFO - Running setup.py install for mozhttpd 22:37:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:37:37 INFO - Running setup.py install for mozinfo 22:37:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:37:37 INFO - Running setup.py install for mozInstall 22:37:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:37:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:37:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:37:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:37:37 INFO - Running setup.py install for mozleak 22:37:38 INFO - Running setup.py install for mozlog 22:37:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:37:38 INFO - Running setup.py install for moznetwork 22:37:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:37:38 INFO - Running setup.py install for mozprocess 22:37:38 INFO - Running setup.py install for mozprofile 22:37:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:37:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:37:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:37:38 INFO - Running setup.py install for mozrunner 22:37:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:37:38 INFO - Running setup.py install for mozscreenshot 22:37:38 INFO - Running setup.py install for moztest 22:37:38 INFO - Running setup.py install for mozversion 22:37:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:37:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:37:38 INFO - Cleaning up... 22:37:38 INFO - Return code: 0 22:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37: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 22:37: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 22:37:38 INFO - Using env: {'DISPLAY': ':0', 22:37:38 INFO - 'HOME': '/home/cltbld', 22:37:38 INFO - 'LANG': 'en_US.UTF-8', 22:37:38 INFO - 'LANGUAGE': 'en_US:en', 22:37:38 INFO - 'LOGNAME': 'cltbld', 22:37:38 INFO - 'MAIL': '/var/mail/cltbld', 22:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:38 INFO - 'MOZ_NO_REMOTE': '1', 22:37:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:38 INFO - 'NO_EM_RESTART': '1', 22:37:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:38 INFO - 'PWD': '/builds/slave/test', 22:37:38 INFO - 'SHELL': '/bin/bash', 22:37:38 INFO - 'SHLVL': '1', 22:37:38 INFO - 'TERM': 'linux', 22:37:38 INFO - 'TMOUT': '86400', 22:37:38 INFO - 'USER': 'cltbld', 22:37:38 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:38 INFO - '_': '/tools/buildbot/bin/python'} 22:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:37:39 INFO - Running setup.py (path:/tmp/pip-yjZXXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:37:39 INFO - Running setup.py (path:/tmp/pip-bINrwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:37:39 INFO - Running setup.py (path:/tmp/pip-EsOjOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:37:39 INFO - Running setup.py (path:/tmp/pip-zYAIMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:37:39 INFO - Running setup.py (path:/tmp/pip-6vOhm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:37:39 INFO - Running setup.py (path:/tmp/pip-Ri7PLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:37:39 INFO - Running setup.py (path:/tmp/pip-FrhVAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:37:39 INFO - Running setup.py (path:/tmp/pip-1vQrVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:37:39 INFO - Running setup.py (path:/tmp/pip-RitGeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:37:39 INFO - Running setup.py (path:/tmp/pip-8IFaAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:37:39 INFO - Running setup.py (path:/tmp/pip-s60qiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:37: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)) 22:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:37:39 INFO - Running setup.py (path:/tmp/pip-UyjQfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:37: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)) 22:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:37:40 INFO - Running setup.py (path:/tmp/pip-5Yci87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:37: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)) 22:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:37:40 INFO - Running setup.py (path:/tmp/pip-FSfeVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:37: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)) 22:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:37:40 INFO - Running setup.py (path:/tmp/pip-lPkWSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:37: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)) 22:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:37:40 INFO - Running setup.py (path:/tmp/pip-8foC44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:37: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)) 22:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:37:40 INFO - Running setup.py (path:/tmp/pip-wpBbMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:42 INFO - Downloading blessings-1.6.tar.gz 22:37:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:37:42 INFO - Installing collected packages: blessings 22:37:42 INFO - Running setup.py install for blessings 22:37:42 INFO - Successfully installed blessings 22:37:42 INFO - Cleaning up... 22:37:42 INFO - Return code: 0 22:37:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:37:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:37:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:37:42 INFO - Reading from file tmpfile_stdout 22:37:42 INFO - Current package versions: 22:37:42 INFO - argparse == 1.2.1 22:37:42 INFO - blessings == 1.6 22:37:42 INFO - blobuploader == 1.2.4 22:37:42 INFO - docopt == 0.6.1 22:37:42 INFO - functools32 == 3.2.3-2 22:37:42 INFO - jsonschema == 2.5.1 22:37:42 INFO - manifestparser == 1.1 22:37:42 INFO - mozInstall == 1.12 22:37:42 INFO - mozcrash == 1.0 22:37:42 INFO - mozdebug == 0.1 22:37:42 INFO - mozdevice == 0.49 22:37:42 INFO - mozfile == 1.2 22:37:42 INFO - mozhttpd == 0.7 22:37:42 INFO - mozinfo == 0.9 22:37:42 INFO - mozleak == 0.1 22:37:42 INFO - mozlog == 3.4 22:37:42 INFO - moznetwork == 0.27 22:37:42 INFO - mozprocess == 0.23 22:37:42 INFO - mozprofile == 0.28 22:37:42 INFO - mozrunner == 6.13 22:37:42 INFO - mozscreenshot == 0.1 22:37:42 INFO - mozsystemmonitor == 0.3 22:37:42 INFO - moztest == 0.7 22:37:42 INFO - mozversion == 1.4 22:37:42 INFO - psutil == 3.1.1 22:37:42 INFO - requests == 1.2.3 22:37:42 INFO - wsgiref == 0.1.2 22:37:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37: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 22:37: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 22:37:42 INFO - Using env: {'DISPLAY': ':0', 22:37:42 INFO - 'HOME': '/home/cltbld', 22:37:42 INFO - 'LANG': 'en_US.UTF-8', 22:37:42 INFO - 'LANGUAGE': 'en_US:en', 22:37:42 INFO - 'LOGNAME': 'cltbld', 22:37:42 INFO - 'MAIL': '/var/mail/cltbld', 22:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:42 INFO - 'MOZ_NO_REMOTE': '1', 22:37:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:42 INFO - 'NO_EM_RESTART': '1', 22:37:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:42 INFO - 'PWD': '/builds/slave/test', 22:37:42 INFO - 'SHELL': '/bin/bash', 22:37:42 INFO - 'SHLVL': '1', 22:37:42 INFO - 'TERM': 'linux', 22:37:42 INFO - 'TMOUT': '86400', 22:37:42 INFO - 'USER': 'cltbld', 22:37:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:42 INFO - '_': '/tools/buildbot/bin/python'} 22:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37:43 INFO - Cleaning up... 22:37:43 INFO - Return code: 0 22:37:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x7fb85f209e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1798a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1936120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', '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 22:37: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 22:37: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 22:37:43 INFO - Using env: {'DISPLAY': ':0', 22:37:43 INFO - 'HOME': '/home/cltbld', 22:37:43 INFO - 'LANG': 'en_US.UTF-8', 22:37:43 INFO - 'LANGUAGE': 'en_US:en', 22:37:43 INFO - 'LOGNAME': 'cltbld', 22:37:43 INFO - 'MAIL': '/var/mail/cltbld', 22:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:43 INFO - 'MOZ_NO_REMOTE': '1', 22:37:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:37:43 INFO - 'NO_EM_RESTART': '1', 22:37:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:43 INFO - 'PWD': '/builds/slave/test', 22:37:43 INFO - 'SHELL': '/bin/bash', 22:37:43 INFO - 'SHLVL': '1', 22:37:43 INFO - 'TERM': 'linux', 22:37:43 INFO - 'TMOUT': '86400', 22:37:43 INFO - 'USER': 'cltbld', 22:37:43 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:43 INFO - '_': '/tools/buildbot/bin/python'} 22:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:37:43 INFO - Cleaning up... 22:37:43 INFO - Return code: 0 22:37:43 INFO - Running post-action listener: _resource_record_post_action 22:37:43 INFO - Running post-action listener: _start_resource_monitoring 22:37:43 INFO - Starting resource monitoring. 22:37:43 INFO - [mozharness: 2016-12-06 06:37:43.341653Z] Finished create-virtualenv step (success) 22:37:43 INFO - [mozharness: 2016-12-06 06:37:43.342134Z] Running install step. 22:37:43 INFO - Running pre-action listener: _resource_record_pre_action 22:37:43 INFO - Running main action method: install 22:37:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:37:43 INFO - Reading from file tmpfile_stdout 22:37:43 INFO - Detecting whether we're running mozinstall >=1.0... 22:37:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:37:43 INFO - Reading from file tmpfile_stdout 22:37:43 INFO - Output received: 22:37:43 INFO - Usage: mozinstall [options] installer 22:37:43 INFO - Options: 22:37:43 INFO - -h, --help show this help message and exit 22:37:43 INFO - -d DEST, --destination=DEST 22:37:43 INFO - Directory to install application into. [default: 22:37:43 INFO - "/builds/slave/test"] 22:37:43 INFO - --app=APP Application being installed. [default: firefox] 22:37:43 INFO - mkdir: /builds/slave/test/build/application 22:37: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'] 22:37: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 22:38:00 INFO - Reading from file tmpfile_stdout 22:38:00 INFO - Output received: 22:38:00 INFO - /builds/slave/test/build/application/firefox/firefox 22:38:00 INFO - Running post-action listener: _resource_record_post_action 22:38:00 INFO - [mozharness: 2016-12-06 06:38:00.611844Z] Finished install step (success) 22:38:00 INFO - [mozharness: 2016-12-06 06:38:00.612117Z] Running run-tests step. 22:38:00 INFO - Running pre-action listener: _resource_record_pre_action 22:38:00 INFO - Running main action method: run_tests 22:38:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:38:00 INFO - Python 2.7.3 22:38:00 INFO - Return code: 0 22:38:00 INFO - grabbing minidump binary from tooltool 22:38: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')]} 22:38: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 0x19c6f20>, '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 0x1933c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1933f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:38: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 22:38: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 22:38: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 22:38:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:38:00 INFO - Return code: 0 22:38:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:38:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:38:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:38:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:38:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:38: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/1480999449/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 22:38: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/1480999449/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 22:38:00 INFO - Using env: {'DISPLAY': ':0', 22:38:00 INFO - 'HOME': '/home/cltbld', 22:38:00 INFO - 'LANG': 'en_US.UTF-8', 22:38:00 INFO - 'LANGUAGE': 'en_US:en', 22:38:00 INFO - 'LOGNAME': 'cltbld', 22:38:00 INFO - 'MAIL': '/var/mail/cltbld', 22:38:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:38:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:00 INFO - 'MOZ_NO_REMOTE': '1', 22:38:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:38:00 INFO - 'NO_EM_RESTART': '1', 22:38:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:00 INFO - 'PWD': '/builds/slave/test', 22:38:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:38:00 INFO - 'SHELL': '/bin/bash', 22:38:00 INFO - 'SHLVL': '1', 22:38:00 INFO - 'TERM': 'linux', 22:38:00 INFO - 'TMOUT': '86400', 22:38:00 INFO - 'USER': 'cltbld', 22:38:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019', 22:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:00 INFO - '_': '/tools/buildbot/bin/python'} 22:38: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/1480999449/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 22:38:00 INFO - mozversion application_buildid: 20161205204409 22:38:00 INFO - mozversion application_changeset: 93d785448a69cd60c7105bfe722cfd94b6d59b37 22:38:00 INFO - mozversion application_display_name: Nightly 22:38:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:38:00 INFO - mozversion application_name: Firefox 22:38:00 INFO - mozversion application_remotingname: firefox 22:38:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:38:00 INFO - mozversion application_vendor: Mozilla 22:38:00 INFO - mozversion application_version: 53.0a1 22:38:00 INFO - mozversion platform_buildid: 20161205204409 22:38:00 INFO - mozversion platform_changeset: 93d785448a69cd60c7105bfe722cfd94b6d59b37 22:38:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:38:00 INFO - mozversion platform_version: 53.0a1 22:38:00 INFO - using testdate: 1481006280 22:38:00 INFO - actual date: 1481006280 22:38:00 INFO - starting webserver on 'localhost:41162' 22:38:00 INFO - SUITE-START | Running 2 tests 22:38:00 INFO - TEST-START | dromaeo_css 22:38:00 INFO - operating with platform_type : linux_ 22:38:00 INFO - Initialising browser for dromaeo_css test... 22:38:00 INFO - TEST-INFO | started process 28328 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUkpkT7/profile http://localhost:41162/getInfo.html) 22:38:05 INFO - PROCESS | 28328 | __metrics Screen width/height:1600/1200 22:38:05 INFO - PROCESS | 28328 | colorDepth:24 22:38:05 INFO - PROCESS | 28328 | Browser inner width/height: 1024/697 22:38:05 INFO - PROCESS | 28328 | __metrics 22:38:05 INFO - PROCESS | 28328 | 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" 22:38:05 INFO - PROCESS | 28328 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:38:06 INFO - PROCESS | 28328 | console.error: PushService: 22:38:06 INFO - PROCESS | 28328 | stateChangeProcessEnqueue: Error transitioning state 22:38:06 INFO - PROCESS | 28328 | UnknownError 22:38:07 INFO - TEST-INFO | 28328: exit 0 22:38:07 INFO - Browser initialized. 22:38:07 INFO - Running cycle 1/1 for dromaeo_css test... 22:38:07 INFO - TEST-INFO | started process 28414 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUkpkT7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:38:19 INFO - PROCESS | 28414 | RSS: Main: 174227456 22:38:19 INFO - PROCESS | 28414 | 22:39:10 INFO - PROCESS | 28414 | 1481006350731 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] 22:41:20 INFO - PROCESS | 28414 | Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41162/tests/dromaeo/cssquery-ext.html) 22:41:23 INFO - PROCESS | 28414 | RSS: Main: 426881024 22:41:23 INFO - PROCESS | 28414 | 22:44:26 INFO - PROCESS | 28414 | Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-ext.html (next: http://localhost:41162/tests/dromaeo/cssquery-jquery.html) 22:44:26 INFO - PROCESS | 28414 | RSS: Main: 279601152 22:44:26 INFO - PROCESS | 28414 | 22:47:28 INFO - PROCESS | 28414 | Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41162/tests/dromaeo/cssquery-mootools.html) 22:47:29 INFO - PROCESS | 28414 | RSS: Main: 221839360 22:47:29 INFO - PROCESS | 28414 | 22:50:31 INFO - PROCESS | 28414 | Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41162/tests/dromaeo/cssquery-prototype.html) 22:50:32 INFO - PROCESS | 28414 | RSS: Main: 265170944 22:50:32 INFO - PROCESS | 28414 | 22:53:35 INFO - PROCESS | 28414 | Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41162/tests/dromaeo/cssquery-yui.html) 22:53:35 INFO - PROCESS | 28414 | RSS: Main: 221954048 22:53:35 INFO - PROCESS | 28414 | 22:56:38 INFO - PROCESS | 28414 | Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-yui.html (next: http://localhost:41162/tests/dromaeo/cssquery-dojo.html) 22:56:39 INFO - PROCESS | 28414 | RSS: Main: 321314816 22:56:39 INFO - PROCESS | 28414 | 22:56:39 INFO - PROCESS | 28414 | __start_tp_report 22:56:39 INFO - PROCESS | 28414 | _x_x_mozilla_page_load 22:56:39 INFO - PROCESS | 28414 | _x_x_mozilla_page_load_details 22:56:39 INFO - PROCESS | 28414 | |i|pagename|runs| 22:56:39 INFO - PROCESS | 28414 | |0;dojo.html;3219.951700724489;3255.4465740824044;3261.6575259597744;3279.934401311973;3289.2105894585306;5283.2867562879;5300.734963251832;5321.707306098163;5345.278387417698;5352.705601191943;5291.047611429941;5333.040052790496;5352.2239275305055;5376.1129413646795;5410.8917821643645;4825.083234185511;4866.635002374818;4899.093667671475;4904.166291730397;4936.777844996984;4913.31213630092;4976.701397916118;5030.82392116276;5040.722760248185;5088.18589025756;4976.2038073908;4982.451930287665;5001.8249361272365;5052.8484145475695;5078.695278283315;4841.612670986313;4855;4879.194932836078;4901.387326584177;4908.361912951329;4826;4840.903181936377;4846.491118432585;4919.606431485508;4926.753662316905;5351.7056561889085;5370.838874833761;5380.596455265871;5391.78432862685;5424.321959755031;5655.802046928379;5684.147377893308;5753.913691294634;5757.021306377926;5757.136429535562;5612.635178713371;5728;5746.310442746897;5798.333191682926;5808.273965754281;5381.946180538144;5383.919241211424;5388.110961691277;5388.784448622089;5396.487333703292;5764.74058667361;5815.04051831443;5823.272090988627;5832.533397328203;5900.085486749561;5581.972090139605;5636.436356364413;5636.887262254814;5652.236948012048;5659.434056594308;5784.88430231393;5786.218860453785;5820.505257053198;5844.678542680108;5859.736311865976;5747.913781293284;5749.913751293734;5756.194132821408;5761.9135712964335;5820.185174075633;5646.2942132233475;5715.771369145271;5727.284089488814;5775.711214439261;5785.797497087582;4456.777161141995;4482.327650852399;4489.663275254369;4493.59557639814;4557.293619488984;4023.879283621496;4058.228936502055;4060.045889216032;4060.4315395844606;4074.2869997750277;5514.80698175562;5543.417941116206;5554.50009499147;5556.16657501378;5584.357798853184;5632.408597097327;5639.492445679908;5655.943440565624;5702.48677619008;5727.026405511073;5437.075697131497;5442.374126975448;5480;5481.506664400223;5484.8354549363585;5008.974955125201;5010.348654674942;5011.8496445105975;5014.398272207359;5055.9241611375855;5831.620944638585;5905.793297234576;5969.253843269591;5970.9701451492465;5973.402659733992;5690.544756419559;5709.22925405065;5755.366909639853;5758.654480731169;5759.107338362561;5983.072623743326;5987.820365389045;5989.431004054608;5989.760409583568;5990.610610310316;5680.943190568207;5681.659100453986;5681.943180568307;5683.2895888014;5685.431456854447;5766.654000759968;5771.94228057714;5775.6823374714795;5778.017736984639;5780.161876527795;5709.9714501427225;5729.914051289151;5732.34078081026;5735.11105778605;5736.483716465538;5665.348484924287;5714.457126572969;5714.885702286014;5717.914231286451;5719.799807006735;5616.831495055155;5658.9151162733415;5692.1746346778655;5692.373838877637;5697.6296540724725;5755.3381361144;5779.2198053263955;5782.942170578409;5787.102999035156;5789.478946894799;5424.2406063151975;5458.645188062763;5461;5506.669599824023;5508.531774799187 22:56:39 INFO - PROCESS | 28414 | |1;ext.html;588.9957622254057;615.0773839241139;642.1459458919635;655.1221363373079;662.1888186970962;38963.61036389639;40282.59717402829;40403.59596404035;43340;43585.78207108968;40488.79755601218;41213.58786412135;41245.58754412455;41285;41467.37798933018;7728.343090837286;7931.167227441122;8331.708390206344;8597.355198360117;8604.096569860169;9409.717708468757;9659.178969787576;9677.661281855137;9912.058354456316;9916.801663966717;10216.13162881156;10281.640142594973;10286.639967601135;10289.691309260734;10290.897091029068;10188.23588230882;10238.488075596264;10261.948690256575;10266.435346055963;10268.178545716324;9962.352447090916;10015.09864112226;10035.949820250851;10039.799204015879;10051.497425128788;38972.15417687341;39326;39342.409863852066;39343.40984885229;39353.60646393528;3083.953740693891;3187.42626327261;3248.691374319436;3504.281622267429;3930.1746633207063;27489.31276718078;29098.563521547192;29251.41497170066;30126.397472050438;30188.69811301903;4028.254772867029;4046.5366670654316;4140.974881547341;4141.834326626931;4281.165172791307;325961;326095;326769;326896;327002;54665.72667136639;54746.72626636921;55027;55336.84973626994;55341.616459588025;59473.405265946785;59477.70261148667;59482;59554.40445595575;59570;13986.440542378394;14416.13503189791;14429.062110962965;14430.711385772225;14441.133531988115;9911.10800028001;10205.438700871375;10577.259591828652;10782.299150555345;10863.782724345469;18109.818901810813;18150.183241754152;18162.909185453987;18196.272149113887;18234.179461924246;6795.660216989131;6908.171019477695;6987.336203060701;7060.964695176492;7101.857962840714;907.5598334807613;964.5707660091332;1018.8217062014118;1040.3757745352727;1041.5833666533447;29824.254393639898;30203.697963020528;30266.54600180999;30297.091087267418;30380.240493987385;1038.2887721910515;1106.280917403694;1112.5213183675044;1127.695522208999;1196.6170825335982;15459.458918937784;16011.199440027953;16082;16141.919290403473;16250.512484625244;458997;460811;460980;461062;461834;54621.453785461636;55125;55135.00542421326;55199;55411.445885541434;1181.3620644851856;1188.4711303469874;1230.5754514692417;1282.858885522573;1332.2672530108402;27746.722532774413;30874;30917;30972.380552389277;31104.533431998992;46544.301835471815;47055.52944470511;47105.2934205994;47184.29223561718;47290.290645641035;352053;353430;354951;357197;360770.1191374868;26649;26964.32589185286;27176.456470870868;27616.723832761414;28711.425771484035;43709.78145109254;43838.56161438345;43849.34225986549;44155;44285.33571996358;37514;37740.43389349217;38072.80963595164;38855.41716874193;39055.2188956225;36963;37276.6272337284;37504;38425;38828;37425.4386184202;38082;38118;38778;39037.804810975766;29262;30691;31156.844215778776;31364.84317578489;31819.840900796273;26086.34784130412;26208.4758304829;26418.207453776417;26481.205563833115;26868.328291792863 22:56:39 INFO - PROCESS | 28414 | |2;jquery.html;377.80543020344544;385.10271068410606;399.9240144372568;402.665787396461;421.72638631333376;13184.406701698423;15097.924510377446;18269.36057237997;18508.907455462704;18821.62356752864;17097.57256068599;17206.13969301536;17303.13484325782;17303.13484325785;17469.126543672828;5375.973120134394;5621.241132447129;5626.071698169797;5630.7184640768;5631.352394474627;5246.134387826004;5482.150266708666;5590.413006634306;5620.718964051781;5683.175939488771;5643.548516118721;5660.377358490563;5675.035244008528;5683.175939488792;5685;5661.2923384576925;6056.212692349988;6068.696565171724;6105.206323178003;6112.694365281763;4972.477889821589;5463.562914966793;5489.835304940858;5492.3522943116905;5503.779848806083;13042.152260103063;13764.17414955106;13773.242471664054;13779.379927903268;15430.5370838875;2223.4107961390246;2327.6624889391114;2342.437814924423;2432.2338463383976;2744.6294750208667;10004.399736015863;10010.299279050396;10211.234157438223;10274.948625256826;10372.63695770652;1812.1437422552665;1859.4700510354485;1911.44568075258;1927.7012063130237;1948.8733232339853;75926;78428.21571784266;79765;81456;82391.17608823895;10688;10845.620403285848;10850.457477126112;11025.669229923114;11215.271007384494;15357.385704571916;15451.613709657124;15479.380824766884;15484.5354639361;15490.535283941499;6589.538732288694;6655.334466553403;6801.625910574867;6874.175098988154;7388.113426388761;6133;6167.907481387782;6446.194225721785;6690.163729533809;6700.631465269457;4627.329037289642;4677.859664210079;4744.319459505437;4770.856874293777;4870.488598697157;1731.757553942449;1894.6169296413696;1940.5827747034455;2016.5059560407794;2020.3433883987764;815.2133191470259;816.9918300817035;849.933333666245;947.554649314821;996.8953259907751;17549.385771497935;18675.346362877233;18802.435926921942;18921.526961826065;19234.422967311002;936.2650319499161;957.3394357893031;998.2712619787595;1006.9194464442827;1014.4116412480744;10045.095941365165;10275.845862312071;10379.584816607403;10440.947795260976;10534.736631584272;418910;421892;424835;429194;432349;80632;81830.18169818226;85757.57121214354;85802.57098714466;88481;963.937344072633;967.3567077893263;991.7272749993809;1031.4739482863793;1033.4343469643345;15900.284487197872;16132.838671613603;17190.914045429694;17263.309467621155;17411.129443528025;39391;40475.797621011705;40523.797381012904;41393.79303103465;41668.374974374805;55053.44946550483;56132.43867561436;56866.71566642306;57334;57926.7103664479;8107.391945603986;8114.634841432032;8206.876896846672;8458.365622578454;8664.436811607226;10926.6721998337;11689;11759.764804703687;11989.100817438553;12225.877741222474;10155.339902906288;10464.47677616101;10677.412742298937;10754.89245107539;11351.262167959056;9987.201023918215;10790.406527641055;10830.945845270724;10984.340939543654;11096.11231101526;10568.313059651406;10582.8412573813;10782.838257425989;11056.115510758958;11130.499127539118;8227.218414250636;8300.169983001651;8432.53621050848;8511.702090426805;8824.735257942271;8941.508217048126;9674.274429417681;10222.591096356062;10599.470026498799;10818.945905270422 22:56:39 INFO - PROCESS | 28414 | |3;mootools.html;1398.8601139886014;1439.5177615498803;1558.7038462692087;1719.6990526657848;1738.2525514028962;7239.457040721941;7469.663865126069;7974.441789074767;8383.58082095895;8404.243618074372;7973.800654983628;8303;8355.331573474123;8355.582220888962;8553.272971797389;2243.618584840577;2289.496310811619;2291.0606651272983;2297.069686776858;2302.8848557572137;2014.315132854829;2063.4222417723063;2139.229877244191;2171.109844963565;2320.3387034695115;2194.890255487227;2262.5248697773486;2291.1522736587403;2299.5171014086977;2304.3547806614174;2258.2999270226283;2264.116994372188;2282.3495303838326;2288.1762565476415;2288.267754318627;2334.0897050150456;2357.5167090746527;2372.7034120734907;2376.382140643445;2377.358113309414;8313.210245026712;8398.580070996426;8447.535385553854;8448.366372522023;8449;5096.464871188509;5241.842744717665;5241.842744717665;5242.580593552507;5243.449437809013;3264.2329052672603;3317.502374643823;3391.321735652855;3405.216800135983;3414.36834185674;1143.3025854228913;1162.5930924176555;1173.254983085738;1193.9044876409866;2019.6768517037203;11168.497417616227;11757.823632645517;11764.94117529424;11781.587644432404;11800.822987655192;4632.837850675211;4638.791254393493;4675.485696573374;4686.507916668769;4687.742174180385;10130;10135.341202821794;10150.340227885164;10158.39049657023;10159;5314.415414304423;5318.095923692904;5325.920111198335;5327.280817089721;5327.307450031549;4698.671093023524;4758.07217592573;4796.496367881392;4813.879653008703;4885.09625719236;3784.375578029594;3803;3807.9619203807606;3861.189150278473;3908.2965066288343;1183.1363104933446;1210.8970737487236;1243.111175509515;1248.975020499603;1263.4946021591254;878.7363790862844;883.9195404016996;885.1060428966662;892.3575025981283;906.4878343735768;5114.693118412907;5161.81933632321;5355.30381050461;5371.033214021513;5379.65032272901;846.3229416466802;891.5809569502323;913.6893456224915;923.9168474837297;942.5146049784366;3523.841427135785;3630.5824830144343;3665.193657395369;3682.650148235913;3748.5314335708035;10173.593056277814;10412.167026637851;10513.421761803174;10526.947365263279;10528.526216320284;10387.065164135112;10392.584296628202;10403.323783954018;10416.895831041744;10465.895341046644;7946.602669866599;8016.038075430985;8024.398170137263;8027.117017127995;8029.197080291924;5317.654352467077;5370.113931201464;5457.126859702428;5535.612507124623;5589.636673616202;9165.266778657824;9499.477528735986;9500.667476638284;9640.951795240979;9755.365901216399;9539.284553658643;9676.467794271382;9734.9026509734;9767.072128147815;9870.062344077302;7285.927140728737;7314.524555903849;7322.0847394075745;7338.046054012944;7341.70633174667;7982.560959147303;8046.476978996347;8104.473209241381;8106.878396823934;8107.594620269081;6831.795046148623;6867.450603951772;7017.052697885924;7063.046488723957;7207.56754594705;6744.494162937701;6880.174379074543;6889.483288753263;6925.29208901651;7157.57054576727;6169.383061693795;6232.158658581215;6281.309056003927;6286.65423401699;6288.968555157195;4566.634669226521;4599.149157405891;4683.601893838925;4734.644901632322;4814.927776083291;4795.712257264575;4805.087033463631;4868.829590964299;4876.878078048077;4898 22:56:39 INFO - PROCESS | 28414 | |4;prototype.html;332.9546015906733;336.3055290824447;347.53951014905255;351.80826449584976;354.28702224743364;5084.8050708083665;5163.483651634835;5240.711760853152;5247.8950420991605;5257.7108259045735;4964.950350496494;4970.70175789452;5041.6218783591285;5064.797408103672;5066.544011039005;4038;4043.0498832774274;4054.756714597119;4073.6944729145357;4079.7348172368847;3833.7699738015667;3919.666828319582;4042.43405923171;4043.5147782266167;4077.143799802045;4016.6786657067364;4030.858919937803;4039.2123535910364;4041.575634558373;4056.127932494513;3986.1629057897585;4028.47629808126;4052.8581499647516;4062.7765472898977;4062.796860156995;3599.9100022499647;3603.513525674027;3609.512715783388;3610.6028336882923;3619.6199399062984;4815.566599006072;4941.407031156248;4946.703197808143;4948.554630083309;4962.404511458648;4566.497685254652;4569.9314510282375;4571.22289210835;4575.267957126877;4579.9084018319445;5293.814716484943;5300.2314664373835;5333.386660534011;5348.705821179834;5354.785808567692;3706.9073273168115;3834.7699138051807;3864.8840534784003;3892.4161375793574;3902.107477873229;5482.396936337;5501.312335958005;5510.283663123809;5525.778968841241;5529.723513824292;4003.279409706281;4007.6192761687594;4009.939850902239;4011.8194681239415;4012.6789856811383;3963.722539422213;3973.920521589552;3999.6200360965663;4035.435039094529;4070.8371665133654;4538.659600529973;4547.453865685683;4551.908961820811;4552.430946131733;4582.9541704582525;5343.332083489564;5347.679139251657;5352.357717073976;5367.463253674679;5377.354717433855;1244.9190802597802;1255.6798016505732;1336.9799453008213;1343.079990206692;1355.979660305096;937.1846493550654;946.8306641297992;949.928755343352;962.4754508792724;964.5965120749433;5004.124278251292;5027.3464449621315;5052.898942021139;5077.619178561623;5116;5007.397632757556;5229.529342359206;5247.580193584504;5248.6325957182635;5293.841184764463;5013.493253373314;5018.197088465901;5030.622703297267;5055.16589762685;5058.367704036996;2793.385455199853;2793.4413117376607;2808.5787131930374;2823.9294017649313;2824.728872007605;5228.503292187236;5375.220593014069;5424.969255841377;5445.945540544544;5455.8090466834265;5342.584215182979;5359.847632758975;5366.812161574326;5389.892202155935;5393.4336894626285;5405.432429594836;5416.539594134542;5433.483819037185;5437;5494.862628434321;5498.780048798003;5512.173174023768;5530.364008139116;5533.225348451139;5566.972165139148;5497.587680923867;5514.862128446661;5530.253415788817;5545.500904918576;5572.4706152915405;5418.972905135449;5671.8865622688145;5718.485336319751;5826.883462330645;5847.152162936435;3978.5026871641044;3991.660708839665;4010.2981978153125;4016.357382818735;4021.9396709049975;5579.19101730255;5594.916076258778;5595.300587426572;5595.804146854841;5596.313769390634;5187.58499320061;5350.652207606493;5369.6509726867625;5387.81142660005;5481.808136715355;5120.820771272987;5242.580593552583;5253.553447956967;5346.705931173823;5357.303550538405;5231.163013917755;5285.445028271976;5301.045811753921;5311.814086506954;5342.75957581902;5380.43505431924;5383.16560933056;5399.2980912481125;5411.810586629449;5412.7293635318865;5177.456367081402;5226.712530810842;5258.815941442184;5280.392754833089;5281.973590132025 22:56:39 INFO - PROCESS | 28414 | |5;yui.html;694.8436601764602;722.1695050691704;740.0970815604963;751.8195633048067;782.3584660578329;13979.580612581625;14473.421063157488;14576.781348279785;14581.781273280896;14679.633009174762;13312.933435332834;13358.93320533396;13362.532311369105;13380.130291531044;13386.397612107454;634.8381162803479;661.3353579652443;741.292066076898;765.7817522006216;773.5822655765879;771.1863983497408;780.7189411811743;782.792559971608;782.8473447677706;783.6081959020489;826.1408135539032;830.0205757206494;832.6253186066264;833.0919297965216;833.4582521361134;671.8589096289785;694.437505620444;793.996030019852;825.2119226139052;826.5701835045772;599.9820005399845;600.1388008208235;600.6306121735145;606.2724730323586;606.3754333036935;15304.923475382664;15352.385904563804;15362.078275303518;15374.308156133;15433.151176685278;14155.363008664635;14201.928990355085;14289.571312860631;14360.928195358956;14484.130952142905;12586.937065314707;12607.11750177488;12621.242725436412;12866.806997895039;12896.613101606967;4299.763513006784;4306.935395969063;4372.846950356722;4373.365861949999;4375.803088861009;84182;85943.14056859414;86323.56838215832;88036;90355.54822225912;11494.367809770376;11619.535218591162;11629.302241865345;11760;12392.504299828086;22100.226492073023;22137;22181.334559963227;22206.333809985725;22768.658470122962;7491.812704682317;7505.699772009168;7639.885401718979;7653.234676532302;7757.379409647215;4183.937240941388;4223.1975924574235;4264.061906380592;4304.655627549789;4309.116631090619;3316.5688460499982;3394.9151271217925;3416.265502916885;3429.9195753337617;3432.485127230935;589.1810383566846;593.8248216776061;610.1975901689265;610.6824451285307;621.59906860075;549.5493695170002;615.806021103351;625.5081630311739;650.5608714118046;677.2584020497547;9001.909980900238;9147.496887671243;9157.358984871129;9239.538023098818;9402.529873506297;633.6419922743635;634.8666779976164;639.9392057754505;640.6924676155471;640.8622146238581;4285.442892423965;4329.301677367963;4517.482517482517;4622.46841613212;4717.150912835654;67108;67267.32732672709;77473;85290.57354713186;87855;74831;74836;75030.24969750342;75296;75962.62018689871;66011;66766.33233667602;67964.32035679679;69456;70035;8510.31917446615;8649.8702519461;8684.34867384936;8729.170728780755;8812.250958668585;38629.55760087626;39428.80285598554;41905;46765.53234467612;48562.27156592724;53436.46563534315;58348.416515836;58935.41064589299;59343.40656593379;62305;12979.740405192033;12984.675383115498;13079.542216022393;13100.659331870313;13439.126456780277;14338.928305358824;14411.78382324287;14724.55826325212;15074.170920599472;15604.609884752972;13759.656008599864;14085.929570352082;14149.36327865228;14390.496332628307;14621.122732636077;13167.867654016;13355.131916425402;13528.729425411633;13649.249291288677;13686.247256400993;13770;13873.236971966639;14691.632709182357;15068.849311507183;15151.621209469851;11642.825357619811;11802.94098529502;11845.46695398692;12003.759924801281;12367.381630918599;11525.827112593486;11989.940050299692;12068.698282543006;12306.753864922472;12356.38218089074 22:56:39 INFO - PROCESS | 28414 | __end_tp_report 22:56:39 INFO - PROCESS | 28414 | __start_cc_report 22:56:39 INFO - PROCESS | 28414 | _x_x_mozilla_cycle_collect,2754 22:56:39 INFO - PROCESS | 28414 | __end_cc_report 22:56:39 INFO - PROCESS | 28414 | __startTimestamp1481007399003__endTimestamp 22:56:39 INFO - PROCESS | 28414 | 22:56:39 INFO - PROCESS | 28414 | ------- Summary: start ------- 22:56:39 INFO - PROCESS | 28414 | Number of tests: 6 22:56:39 INFO - PROCESS | 28414 | 22:56:39 INFO - PROCESS | 28414 | [#0] dojo.html Cycles:165 Average:5373.76 Median:5616.83 stddev:574.87 (10.2%) stddev-sans-first:551.24 22:56:39 INFO - PROCESS | 28414 | Values: 3220.0 3255.4 3261.7 3279.9 3289.2 5283.3 5300.7 5321.7 5345.3 5352.7 5291.0 5333.0 5352.2 5376.1 5410.9 4825.1 4866.6 4899.1 4904.2 4936.8 4913.3 4976.7 5030.8 5040.7 5088.2 4976.2 4982.5 5001.8 5052.8 5078.7 4841.6 4855.0 4879.2 4901.4 4908.4 4826.0 4840.9 4846.5 4919.6 4926.8 5351.7 5370.8 5380.6 5391.8 5424.3 5655.8 5684.1 5753.9 5757.0 5757.1 5612.6 5728.0 5746.3 5798.3 5808.3 5381.9 5383.9 5388.1 5388.8 5396.5 5764.7 5815.0 5823.3 5832.5 5900.1 5582.0 5636.4 5636.9 5652.2 5659.4 5784.9 5786.2 5820.5 5844.7 5859.7 5747.9 5749.9 5756.2 5761.9 5820.2 5646.3 5715.8 5727.3 5775.7 5785.8 4456.8 4482.3 4489.7 4493.6 4557.3 4023.9 4058.2 4060.0 4060.4 4074.3 5514.8 5543.4 5554.5 5556.2 5584.4 5632.4 5639.5 5655.9 5702.5 5727.0 5437.1 5442.4 5480.0 5481.5 5484.8 5009.0 5010.3 5011.8 5014.4 5055.9 5831.6 5905.8 5969.3 5971.0 5973.4 5690.5 5709.2 5755.4 5758.7 5759.1 5983.1 5987.8 5989.4 5989.8 5990.6 5680.9 5681.7 5681.9 5683.3 5685.4 5766.7 5771.9 5775.7 5778.0 5780.2 5710.0 5729.9 5732.3 5735.1 5736.5 5665.3 5714.5 5714.9 5717.9 5719.8 5616.8 5658.9 5692.2 5692.4 5697.6 5755.3 5779.2 5782.9 5787.1 5789.5 5424.2 5458.6 5461.0 5506.7 5508.5 22:56:39 INFO - PROCESS | 28414 | 22:56:39 INFO - PROCESS | 28414 | [#1] ext.html Cycles:180 Average:53889.90 Median:37119.81 stddev:101728.42 (274.1%) stddev-sans-first:101935.08 22:56:39 INFO - PROCESS | 28414 | Values: 589.0 615.1 642.1 655.1 662.2 38963.6 40282.6 40403.6 43340.0 43585.8 40488.8 41213.6 41245.6 41285.0 41467.4 7728.3 7931.2 8331.7 8597.4 8604.1 9409.7 9659.2 9677.7 9912.1 9916.8 10216.1 10281.6 10286.6 10289.7 10290.9 10188.2 10238.5 10261.9 10266.4 10268.2 9962.4 10015.1 10035.9 10039.8 10051.5 38972.2 39326.0 39342.4 39343.4 39353.6 3084.0 3187.4 3248.7 3504.3 3930.2 27489.3 29098.6 29251.4 30126.4 30188.7 4028.3 4046.5 4141.0 4141.8 4281.2 325961.0 326095.0 326769.0 326896.0 327002.0 54665.7 54746.7 55027.0 55336.8 55341.6 59473.4 59477.7 59482.0 59554.4 59570.0 13986.4 14416.1 14429.1 14430.7 14441.1 9911.1 10205.4 10577.3 10782.3 10863.8 18109.8 18150.2 18162.9 18196.3 18234.2 6795.7 6908.2 6987.3 7061.0 7101.9 907.6 964.6 1018.8 1040.4 1041.6 29824.3 30203.7 30266.5 30297.1 30380.2 1038.3 1106.3 1112.5 1127.7 1196.6 15459.5 16011.2 16082.0 16141.9 16250.5 458997.0 460811.0 460980.0 461062.0 461834.0 54621.5 55125.0 55135.0 55199.0 55411.4 1181.4 1188.5 1230.6 1282.9 1332.3 27746.7 30874.0 30917.0 30972.4 31104.5 46544.3 47055.5 47105.3 47184.3 47290.3 352053.0 353430.0 354951.0 357197.0 360770.1 26649.0 26964.3 27176.5 27616.7 28711.4 43709.8 43838.6 43849.3 44155.0 44285.3 37514.0 37740.4 38072.8 38855.4 39055.2 36963.0 37276.6 37504.0 38425.0 38828.0 37425.4 38082.0 38118.0 38778.0 39037.8 29262.0 30691.0 31156.8 31364.8 31819.8 26086.3 26208.5 26418.2 26481.2 26868.3 22:56:39 INFO - PROCESS | 28414 | 22:56:39 INFO - PROCESS | 28414 | [#2] jquery.html Cycles:180 Average:26212.57 Median:20439.23 stddev:70439.91 (344.6%) stddev-sans-first:70610.80 22:56:39 INFO - PROCESS | 28414 | Values: 377.8 385.1 399.9 402.7 421.7 13184.4 15097.9 18269.4 18508.9 18821.6 17097.6 17206.1 17303.1 17303.1 17469.1 5376.0 5621.2 5626.1 5630.7 5631.4 5246.1 5482.2 5590.4 5620.7 5683.2 5643.5 5660.4 5675.0 5683.2 5685.0 5661.3 6056.2 6068.7 6105.2 6112.7 4972.5 5463.6 5489.8 5492.4 5503.8 13042.2 13764.2 13773.2 13779.4 15430.5 2223.4 2327.7 2342.4 2432.2 2744.6 10004.4 10010.3 10211.2 10274.9 10372.6 1812.1 1859.5 1911.4 1927.7 1948.9 75926.0 78428.2 79765.0 81456.0 82391.2 10688.0 10845.6 10850.5 11025.7 11215.3 15357.4 15451.6 15479.4 15484.5 15490.5 6589.5 6655.3 6801.6 6874.2 7388.1 6133.0 6167.9 6446.2 6690.2 6700.6 4627.3 4677.9 4744.3 4770.9 4870.5 1731.8 1894.6 1940.6 2016.5 2020.3 815.2 817.0 849.9 947.6 996.9 17549.4 18675.3 18802.4 18921.5 19234.4 936.3 957.3 998.3 1006.9 1014.4 10045.1 10275.8 10379.6 10440.9 10534.7 418910.0 421892.0 424835.0 429194.0 432349.0 80632.0 81830.2 85757.6 85802.6 88481.0 963.9 967.4 991.7 1031.5 1033.4 15900.3 16132.8 17190.9 17263.3 17411.1 39391.0 40475.8 40523.8 41393.8 41668.4 55053.4 56132.4 56866.7 57334.0 57926.7 8107.4 8114.6 8206.9 8458.4 8664.4 10926.7 11689.0 11759.8 11989.1 12225.9 10155.3 10464.5 10677.4 10754.9 11351.3 9987.2 10790.4 10830.9 10984.3 11096.1 10568.3 10582.8 10782.8 11056.1 11130.5 8227.2 8300.2 8432.5 8511.7 8824.7 8941.5 9674.3 10222.6 10599.5 10818.9 22:56:39 INFO - PROCESS | 28414 | 22:56:39 INFO - PROCESS | 28414 | [#3] mootools.html Cycles:180 Average:5511.58 Median:4841.88 stddev:3101.94 (64.1%) stddev-sans-first:3095.25 22:56:39 INFO - PROCESS | 28414 | Values: 1398.9 1439.5 1558.7 1719.7 1738.3 7239.5 7469.7 7974.4 8383.6 8404.2 7973.8 8303.0 8355.3 8355.6 8553.3 2243.6 2289.5 2291.1 2297.1 2302.9 2014.3 2063.4 2139.2 2171.1 2320.3 2194.9 2262.5 2291.2 2299.5 2304.4 2258.3 2264.1 2282.3 2288.2 2288.3 2334.1 2357.5 2372.7 2376.4 2377.4 8313.2 8398.6 8447.5 8448.4 8449.0 5096.5 5241.8 5241.8 5242.6 5243.4 3264.2 3317.5 3391.3 3405.2 3414.4 1143.3 1162.6 1173.3 1193.9 2019.7 11168.5 11757.8 11764.9 11781.6 11800.8 4632.8 4638.8 4675.5 4686.5 4687.7 10130.0 10135.3 10150.3 10158.4 10159.0 5314.4 5318.1 5325.9 5327.3 5327.3 4698.7 4758.1 4796.5 4813.9 4885.1 3784.4 3803.0 3808.0 3861.2 3908.3 1183.1 1210.9 1243.1 1249.0 1263.5 878.7 883.9 885.1 892.4 906.5 5114.7 5161.8 5355.3 5371.0 5379.7 846.3 891.6 913.7 923.9 942.5 3523.8 3630.6 3665.2 3682.7 3748.5 10173.6 10412.2 10513.4 10526.9 10528.5 10387.1 10392.6 10403.3 10416.9 10465.9 7946.6 8016.0 8024.4 8027.1 8029.2 5317.7 5370.1 5457.1 5535.6 5589.6 9165.3 9499.5 9500.7 9641.0 9755.4 9539.3 9676.5 9734.9 9767.1 9870.1 7285.9 7314.5 7322.1 7338.0 7341.7 7982.6 8046.5 8104.5 8106.9 8107.6 6831.8 6867.5 7017.1 7063.0 7207.6 6744.5 6880.2 6889.5 6925.3 7157.6 6169.4 6232.2 6281.3 6286.7 6289.0 4566.6 4599.1 4683.6 4734.6 4814.9 4795.7 4805.1 4868.8 4876.9 4898.0 22:56:39 INFO - PROCESS | 28414 | 22:56:39 INFO - PROCESS | 28414 | [#4] prototype.html Cycles:180 Average:4496.13 Median:5100.40 stddev:1303.26 (25.6%) stddev-sans-first:1268.90 22:56:39 INFO - PROCESS | 28414 | Values: 333.0 336.3 347.5 351.8 354.3 5084.8 5163.5 5240.7 5247.9 5257.7 4965.0 4970.7 5041.6 5064.8 5066.5 4038.0 4043.0 4054.8 4073.7 4079.7 3833.8 3919.7 4042.4 4043.5 4077.1 4016.7 4030.9 4039.2 4041.6 4056.1 3986.2 4028.5 4052.9 4062.8 4062.8 3599.9 3603.5 3609.5 3610.6 3619.6 4815.6 4941.4 4946.7 4948.6 4962.4 4566.5 4569.9 4571.2 4575.3 4579.9 5293.8 5300.2 5333.4 5348.7 5354.8 3706.9 3834.8 3864.9 3892.4 3902.1 5482.4 5501.3 5510.3 5525.8 5529.7 4003.3 4007.6 4009.9 4011.8 4012.7 3963.7 3973.9 3999.6 4035.4 4070.8 4538.7 4547.5 4551.9 4552.4 4583.0 5343.3 5347.7 5352.4 5367.5 5377.4 1244.9 1255.7 1337.0 1343.1 1356.0 937.2 946.8 949.9 962.5 964.6 5004.1 5027.3 5052.9 5077.6 5116.0 5007.4 5229.5 5247.6 5248.6 5293.8 5013.5 5018.2 5030.6 5055.2 5058.4 2793.4 2793.4 2808.6 2823.9 2824.7 5228.5 5375.2 5425.0 5445.9 5455.8 5342.6 5359.8 5366.8 5389.9 5393.4 5405.4 5416.5 5433.5 5437.0 5494.9 5498.8 5512.2 5530.4 5533.2 5567.0 5497.6 5514.9 5530.3 5545.5 5572.5 5419.0 5671.9 5718.5 5826.9 5847.2 3978.5 3991.7 4010.3 4016.4 4021.9 5579.2 5594.9 5595.3 5595.8 5596.3 5187.6 5350.7 5369.7 5387.8 5481.8 5120.8 5242.6 5253.6 5346.7 5357.3 5231.2 5285.4 5301.0 5311.8 5342.8 5380.4 5383.2 5399.3 5411.8 5412.7 5177.5 5226.7 5258.8 5280.4 5282.0 22:56:39 INFO - PROCESS | 28414 | 22:56:39 INFO - PROCESS | 28414 | [#5] yui.html Cycles:180 Average:18205.01 Median:4569.98 stddev:23880.10 (522.5%) stddev-sans-first:23910.89 22:56:39 INFO - PROCESS | 28414 | Values: 694.8 722.2 740.1 751.8 782.4 13979.6 14473.4 14576.8 14581.8 14679.6 13312.9 13358.9 13362.5 13380.1 13386.4 634.8 661.3 741.3 765.8 773.6 771.2 780.7 782.8 782.8 783.6 826.1 830.0 832.6 833.1 833.5 671.9 694.4 794.0 825.2 826.6 600.0 600.1 600.6 606.3 606.4 15304.9 15352.4 15362.1 15374.3 15433.2 14155.4 14201.9 14289.6 14360.9 14484.1 12586.9 12607.1 12621.2 12866.8 12896.6 4299.8 4306.9 4372.8 4373.4 4375.8 84182.0 85943.1 86323.6 88036.0 90355.5 11494.4 11619.5 11629.3 11760.0 12392.5 22100.2 22137.0 22181.3 22206.3 22768.7 7491.8 7505.7 7639.9 7653.2 7757.4 4183.9 4223.2 4264.1 4304.7 4309.1 3316.6 3394.9 3416.3 3429.9 3432.5 589.2 593.8 610.2 610.7 621.6 549.5 615.8 625.5 650.6 677.3 9001.9 9147.5 9157.4 9239.5 9402.5 633.6 634.9 639.9 640.7 640.9 4285.4 4329.3 4517.5 4622.5 4717.2 67108.0 67267.3 77473.0 85290.6 87855.0 74831.0 74836.0 75030.2 75296.0 75962.6 66011.0 66766.3 67964.3 69456.0 70035.0 8510.3 8649.9 8684.3 8729.2 8812.3 38629.6 39428.8 41905.0 46765.5 48562.3 53436.5 58348.4 58935.4 59343.4 62305.0 12979.7 12984.7 13079.5 13100.7 13439.1 14338.9 14411.8 14724.6 15074.2 15604.6 13759.7 14085.9 14149.4 14390.5 14621.1 13167.9 13355.1 13528.7 13649.2 13686.2 13770.0 13873.2 14691.6 15068.8 15151.6 11642.8 11802.9 11845.5 12003.8 12367.4 11525.8 11989.9 12068.7 12306.8 12356.4 22:56:39 INFO - PROCESS | 28414 | -------- Summary: end -------- 22:56:39 INFO - PROCESS | 28414 | 22:56:39 INFO - TEST-INFO | 28414: exit 0 22:56:39 INFO - TEST-OK | dromaeo_css | took 1119078ms 22:56:39 INFO - TEST-START | kraken 22:56:39 INFO - operating with platform_type : linux_ 22:56:39 INFO - Initialising browser for kraken test... 22:56:39 INFO - TEST-INFO | started process 28721 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpktgnKQ/profile http://localhost:41162/getInfo.html) 22:56:43 INFO - PROCESS | 28721 | __metrics Screen width/height:1600/1200 22:56:43 INFO - PROCESS | 28721 | colorDepth:24 22:56:43 INFO - PROCESS | 28721 | Browser inner width/height: 1024/697 22:56:43 INFO - PROCESS | 28721 | __metrics 22:56:44 INFO - PROCESS | 28721 | 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" 22:56:44 INFO - PROCESS | 28721 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:56:45 INFO - PROCESS | 28721 | console.error: PushService: 22:56:45 INFO - PROCESS | 28721 | stateChangeProcessEnqueue: Error transitioning state 22:56:45 INFO - PROCESS | 28721 | UnknownError 22:56:45 INFO - TEST-INFO | 28721: exit 0 22:56:45 INFO - Browser initialized. 22:56:45 INFO - Running cycle 1/1 for kraken test... 22:56:45 INFO - TEST-INFO | started process 28806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpktgnKQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:56:57 INFO - PROCESS | 28806 | RSS: Main: 172580864 22:56:57 INFO - PROCESS | 28806 | 22:57:00 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41162/tests/kraken/driver.html?testName=audio-beat-detection) 22:57:01 INFO - PROCESS | 28806 | RSS: Main: 238747648 22:57:01 INFO - PROCESS | 28806 | 22:57:04 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41162/tests/kraken/driver.html?testName=audio-dft) 22:57:04 INFO - PROCESS | 28806 | RSS: Main: 216985600 22:57:04 INFO - PROCESS | 28806 | 22:57:07 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41162/tests/kraken/driver.html?testName=audio-fft) 22:57:08 INFO - PROCESS | 28806 | RSS: Main: 298491904 22:57:08 INFO - PROCESS | 28806 | 22:57:10 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41162/tests/kraken/driver.html?testName=audio-oscillator) 22:57:10 INFO - PROCESS | 28806 | RSS: Main: 208969728 22:57:10 INFO - PROCESS | 28806 | 22:57:13 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41162/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:57:13 INFO - PROCESS | 28806 | RSS: Main: 214556672 22:57:13 INFO - PROCESS | 28806 | 22:57:16 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41162/tests/kraken/driver.html?testName=imaging-darkroom) 22:57:17 INFO - PROCESS | 28806 | RSS: Main: 252973056 22:57:17 INFO - PROCESS | 28806 | 22:57:20 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41162/tests/kraken/driver.html?testName=imaging-desaturate) 22:57:21 INFO - PROCESS | 28806 | RSS: Main: 252559360 22:57:21 INFO - PROCESS | 28806 | 22:57:23 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41162/tests/kraken/driver.html?testName=json-parse-financial) 22:57:24 INFO - PROCESS | 28806 | RSS: Main: 251674624 22:57:24 INFO - PROCESS | 28806 | 22:57:26 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41162/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:57:26 INFO - PROCESS | 28806 | RSS: Main: 213778432 22:57:26 INFO - PROCESS | 28806 | 22:57:28 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:57:29 INFO - PROCESS | 28806 | RSS: Main: 219975680 22:57:29 INFO - PROCESS | 28806 | 22:57:31 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:57:31 INFO - PROCESS | 28806 | RSS: Main: 229302272 22:57:31 INFO - PROCESS | 28806 | 22:57:34 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:57:35 INFO - PROCESS | 28806 | RSS: Main: 239390720 22:57:35 INFO - PROCESS | 28806 | 22:57:37 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:57:38 INFO - PROCESS | 28806 | RSS: Main: 235982848 22:57:38 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41162/tests/kraken/driver.html?testName=ai-astar) 22:57:40 INFO - PROCESS | 28806 | RSS: Main: 286543872 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | __start_tp_report 22:57:40 INFO - PROCESS | 28806 | _x_x_mozilla_page_load 22:57:40 INFO - PROCESS | 28806 | _x_x_mozilla_page_load_details 22:57:40 INFO - PROCESS | 28806 | |i|pagename|runs| 22:57:40 INFO - PROCESS | 28806 | |0;ai-astar;105;113;115;114;113;111;113;113;112;111 22:57:40 INFO - PROCESS | 28806 | |1;audio-beat-detection;156;124;124;160;134;122;124;127;142;126 22:57:40 INFO - PROCESS | 28806 | |2;audio-dft;160;162;162;159;160;161;162;160;159;160 22:57:40 INFO - PROCESS | 28806 | |3;audio-fft;74;74;75;101;77;77;77;75;90;75 22:57:40 INFO - PROCESS | 28806 | |4;audio-oscillator;92;81;113;82;82;82;96;83;82;113 22:57:40 INFO - PROCESS | 28806 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;117;117;116 22:57:40 INFO - PROCESS | 28806 | |6;imaging-darkroom;146;146;145;145;146;145;146;145;146;148 22:57:40 INFO - PROCESS | 28806 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;95 22:57:40 INFO - PROCESS | 28806 | |8;json-parse-financial;53;53;52;53;53;54;54;55;55;55 22:57:40 INFO - PROCESS | 28806 | |9;json-stringify-tinderbox;63;64;63;62;63;64;64;64;63;64 22:57:40 INFO - PROCESS | 28806 | |10;stanford-crypto-aes;82;81;81;81;81;81;79;81;81;80 22:57:40 INFO - PROCESS | 28806 | |11;stanford-crypto-ccm;140;141;130;130;142;130;136;130;175;144 22:57:40 INFO - PROCESS | 28806 | |12;stanford-crypto-pbkdf2;168;169;171;165;168;166;163;168;163;167 22:57:40 INFO - PROCESS | 28806 | |13;stanford-crypto-sha256-iterative;65;64;65;65;65;64;68;65;64;64 22:57:40 INFO - PROCESS | 28806 | __end_tp_report 22:57:40 INFO - PROCESS | 28806 | __start_cc_report 22:57:40 INFO - PROCESS | 28806 | _x_x_mozilla_cycle_collect,1680 22:57:40 INFO - PROCESS | 28806 | __end_cc_report 22:57:40 INFO - PROCESS | 28806 | __startTimestamp1481007460355__endTimestamp 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | ------- Summary: start ------- 22:57:40 INFO - PROCESS | 28806 | Number of tests: 14 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.75 (2.4%) stddev-sans-first:1.30 22:57:40 INFO - PROCESS | 28806 | Values: 105.0 113.0 115.0 114.0 113.0 111.0 113.0 113.0 112.0 111.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:130.50 stddev:14.07 (10.8%) stddev-sans-first:12.44 22:57:40 INFO - PROCESS | 28806 | Values: 156.0 124.0 124.0 160.0 134.0 122.0 124.0 127.0 142.0 126.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:1.18 (0.7%) stddev-sans-first:1.24 22:57:40 INFO - PROCESS | 28806 | Values: 160.0 162.0 162.0 159.0 160.0 161.0 162.0 160.0 159.0 160.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#3] audio-fft Cycles:10 Average:79.50 Median:76.00 stddev:8.90 (11.7%) stddev-sans-first:9.21 22:57:40 INFO - PROCESS | 28806 | Values: 74.0 74.0 75.0 101.0 77.0 77.0 77.0 75.0 90.0 75.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:82.00 stddev:12.83 (15.6%) stddev-sans-first:13.59 22:57:40 INFO - PROCESS | 28806 | Values: 92.0 81.0 113.0 82.0 82.0 82.0 96.0 83.0 82.0 113.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:57:40 INFO - PROCESS | 28806 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 22:57:40 INFO - PROCESS | 28806 | Values: 146.0 146.0 145.0 145.0 146.0 145.0 146.0 145.0 146.0 148.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:57:40 INFO - PROCESS | 28806 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.06 (2.0%) stddev-sans-first:1.09 22:57:40 INFO - PROCESS | 28806 | Values: 53.0 53.0 52.0 53.0 53.0 54.0 54.0 55.0 55.0 55.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:64.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 22:57:40 INFO - PROCESS | 28806 | Values: 63.0 64.0 63.0 62.0 63.0 64.0 64.0 64.0 63.0 64.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:81.00 stddev:0.79 (1.0%) stddev-sans-first:0.71 22:57:40 INFO - PROCESS | 28806 | Values: 82.0 81.0 81.0 81.0 81.0 81.0 79.0 81.0 81.0 80.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#11] stanford-crypto-ccm Cycles:10 Average:139.80 Median:140.50 stddev:13.59 (9.7%) stddev-sans-first:14.41 22:57:40 INFO - PROCESS | 28806 | Values: 140.0 141.0 130.0 130.0 142.0 130.0 136.0 130.0 175.0 144.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:168.00 stddev:2.57 (1.5%) stddev-sans-first:2.69 22:57:40 INFO - PROCESS | 28806 | Values: 168.0 169.0 171.0 165.0 168.0 166.0 163.0 168.0 163.0 167.0 22:57:40 INFO - PROCESS | 28806 | 22:57:40 INFO - PROCESS | 28806 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:1.20 (1.8%) stddev-sans-first:1.27 22:57:40 INFO - PROCESS | 28806 | Values: 65.0 64.0 65.0 65.0 65.0 64.0 68.0 65.0 64.0 64.0 22:57:40 INFO - PROCESS | 28806 | -------- Summary: end -------- 22:57:40 INFO - PROCESS | 28806 | 22:57:41 INFO - TEST-INFO | 28806: exit 0 22:57:41 INFO - TEST-OK | kraken | took 61612ms 22:57:41 INFO - SUITE-END | took 1180s 22:57:42 INFO - Completed test suite (00:19:41) 22:57:42 INFO - javascript benchmark 22:57:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3219.951700724489, 3255.4465740824044, 3261.6575259597744, 3279.934401311973, 3289.2105894585306, 5283.2867562879, 5300.734963251832, 5321.707306098163, 5345.278387417698, 5352.705601191943, 5291.047611429941, 5333.040052790496, 5352.2239275305055, 5376.1129413646795, 5410.8917821643645, 4825.083234185511, 4866.635002374818, 4899.093667671475, 4904.166291730397, 4936.777844996984, 4913.31213630092, 4976.701397916118, 5030.82392116276, 5040.722760248185, 5088.18589025756, 4976.2038073908, 4982.451930287665, 5001.8249361272365, 5052.8484145475695, 5078.695278283315, 4841.612670986313, 4855.0, 4879.194932836078, 4901.387326584177, 4908.361912951329, 4826.0, 4840.903181936377, 4846.491118432585, 4919.606431485508, 4926.753662316905, 5351.7056561889085, 5370.838874833761, 5380.596455265871, 5391.78432862685, 5424.321959755031, 5655.802046928379, 5684.147377893308, 5753.913691294634, 5757.021306377926, 5757.136429535562, 5612.635178713371, 5728.0, 5746.310442746897, 5798.333191682926, 5808.273965754281, 5381.946180538144, 5383.919241211424, 5388.110961691277, 5388.784448622089, 5396.487333703292, 5764.74058667361, 5815.04051831443, 5823.272090988627, 5832.533397328203, 5900.085486749561, 5581.972090139605, 5636.436356364413, 5636.887262254814, 5652.236948012048, 5659.434056594308, 5784.88430231393, 5786.218860453785, 5820.505257053198, 5844.678542680108, 5859.736311865976, 5747.913781293284, 5749.913751293734, 5756.194132821408, 5761.9135712964335, 5820.185174075633, 5646.2942132233475, 5715.771369145271, 5727.284089488814, 5775.711214439261, 5785.797497087582, 4456.777161141995, 4482.327650852399, 4489.663275254369, 4493.59557639814, 4557.293619488984, 4023.879283621496, 4058.228936502055, 4060.045889216032, 4060.4315395844606, 4074.2869997750277, 5514.80698175562, 5543.417941116206, 5554.50009499147, 5556.16657501378, 5584.357798853184, 5632.408597097327, 5639.492445679908, 5655.943440565624, 5702.48677619008, 5727.026405511073, 5437.075697131497, 5442.374126975448, 5480.0, 5481.506664400223, 5484.8354549363585, 5008.974955125201, 5010.348654674942, 5011.8496445105975, 5014.398272207359, 5055.9241611375855, 5831.620944638585, 5905.793297234576, 5969.253843269591, 5970.9701451492465, 5973.402659733992, 5690.544756419559, 5709.22925405065, 5755.366909639853, 5758.654480731169, 5759.107338362561, 5983.072623743326, 5987.820365389045, 5989.431004054608, 5989.760409583568, 5990.610610310316, 5680.943190568207, 5681.659100453986, 5681.943180568307, 5683.2895888014, 5685.431456854447, 5766.654000759968, 5771.94228057714, 5775.6823374714795, 5778.017736984639, 5780.161876527795, 5709.9714501427225, 5729.914051289151, 5732.34078081026, 5735.11105778605, 5736.483716465538, 5665.348484924287, 5714.457126572969, 5714.885702286014, 5717.914231286451, 5719.799807006735, 5616.831495055155, 5658.9151162733415, 5692.1746346778655, 5692.373838877637, 5697.6296540724725, 5755.3381361144, 5779.2198053263955, 5782.942170578409, 5787.102999035156, 5789.478946894799, 5424.2406063151975, 5458.645188062763, 5461.0, 5506.669599824023, 5508.531774799187], "value": 5337.63589070869, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.9957622254057, 615.0773839241139, 642.1459458919635, 655.1221363373079, 662.1888186970962, 38963.61036389639, 40282.59717402829, 40403.59596404035, 43340.0, 43585.78207108968, 40488.79755601218, 41213.58786412135, 41245.58754412455, 41285.0, 41467.37798933018, 7728.343090837286, 7931.167227441122, 8331.708390206344, 8597.355198360117, 8604.096569860169, 9409.717708468757, 9659.178969787576, 9677.661281855137, 9912.058354456316, 9916.801663966717, 10216.13162881156, 10281.640142594973, 10286.639967601135, 10289.691309260734, 10290.897091029068, 10188.23588230882, 10238.488075596264, 10261.948690256575, 10266.435346055963, 10268.178545716324, 9962.352447090916, 10015.09864112226, 10035.949820250851, 10039.799204015879, 10051.497425128788, 38972.15417687341, 39326.0, 39342.409863852066, 39343.40984885229, 39353.60646393528, 3083.953740693891, 3187.42626327261, 3248.691374319436, 3504.281622267429, 3930.1746633207063, 27489.31276718078, 29098.563521547192, 29251.41497170066, 30126.397472050438, 30188.69811301903, 4028.254772867029, 4046.5366670654316, 4140.974881547341, 4141.834326626931, 4281.165172791307, 325961.0, 326095.0, 326769.0, 326896.0, 327002.0, 54665.72667136639, 54746.72626636921, 55027.0, 55336.84973626994, 55341.616459588025, 59473.405265946785, 59477.70261148667, 59482.0, 59554.40445595575, 59570.0, 13986.440542378394, 14416.13503189791, 14429.062110962965, 14430.711385772225, 14441.133531988115, 9911.10800028001, 10205.438700871375, 10577.259591828652, 10782.299150555345, 10863.782724345469, 18109.818901810813, 18150.183241754152, 18162.909185453987, 18196.272149113887, 18234.179461924246, 6795.660216989131, 6908.171019477695, 6987.336203060701, 7060.964695176492, 7101.857962840714, 907.5598334807613, 964.5707660091332, 1018.8217062014118, 1040.3757745352727, 1041.5833666533447, 29824.254393639898, 30203.697963020528, 30266.54600180999, 30297.091087267418, 30380.240493987385, 1038.2887721910515, 1106.280917403694, 1112.5213183675044, 1127.695522208999, 1196.6170825335982, 15459.458918937784, 16011.199440027953, 16082.0, 16141.919290403473, 16250.512484625244, 458997.0, 460811.0, 460980.0, 461062.0, 461834.0, 54621.453785461636, 55125.0, 55135.00542421326, 55199.0, 55411.445885541434, 1181.3620644851856, 1188.4711303469874, 1230.5754514692417, 1282.858885522573, 1332.2672530108402, 27746.722532774413, 30874.0, 30917.0, 30972.380552389277, 31104.533431998992, 46544.301835471815, 47055.52944470511, 47105.2934205994, 47184.29223561718, 47290.290645641035, 352053.0, 353430.0, 354951.0, 357197.0, 360770.1191374868, 26649.0, 26964.32589185286, 27176.456470870868, 27616.723832761414, 28711.425771484035, 43709.78145109254, 43838.56161438345, 43849.34225986549, 44155.0, 44285.33571996358, 37514.0, 37740.43389349217, 38072.80963595164, 38855.41716874193, 39055.2188956225, 36963.0, 37276.6272337284, 37504.0, 38425.0, 38828.0, 37425.4386184202, 38082.0, 38118.0, 38778.0, 39037.804810975766, 29262.0, 30691.0, 31156.844215778776, 31364.84317578489, 31819.840900796273, 26086.34784130412, 26208.4758304829, 26418.207453776417, 26481.205563833115, 26868.328291792863], "value": 19131.63070103715, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.80543020344544, 385.10271068410606, 399.9240144372568, 402.665787396461, 421.72638631333376, 13184.406701698423, 15097.924510377446, 18269.36057237997, 18508.907455462704, 18821.62356752864, 17097.57256068599, 17206.13969301536, 17303.13484325782, 17303.13484325785, 17469.126543672828, 5375.973120134394, 5621.241132447129, 5626.071698169797, 5630.7184640768, 5631.352394474627, 5246.134387826004, 5482.150266708666, 5590.413006634306, 5620.718964051781, 5683.175939488771, 5643.548516118721, 5660.377358490563, 5675.035244008528, 5683.175939488792, 5685.0, 5661.2923384576925, 6056.212692349988, 6068.696565171724, 6105.206323178003, 6112.694365281763, 4972.477889821589, 5463.562914966793, 5489.835304940858, 5492.3522943116905, 5503.779848806083, 13042.152260103063, 13764.17414955106, 13773.242471664054, 13779.379927903268, 15430.5370838875, 2223.4107961390246, 2327.6624889391114, 2342.437814924423, 2432.2338463383976, 2744.6294750208667, 10004.399736015863, 10010.299279050396, 10211.234157438223, 10274.948625256826, 10372.63695770652, 1812.1437422552665, 1859.4700510354485, 1911.44568075258, 1927.7012063130237, 1948.8733232339853, 75926.0, 78428.21571784266, 79765.0, 81456.0, 82391.17608823895, 10688.0, 10845.620403285848, 10850.457477126112, 11025.669229923114, 11215.271007384494, 15357.385704571916, 15451.613709657124, 15479.380824766884, 15484.5354639361, 15490.535283941499, 6589.538732288694, 6655.334466553403, 6801.625910574867, 6874.175098988154, 7388.113426388761, 6133.0, 6167.907481387782, 6446.194225721785, 6690.163729533809, 6700.631465269457, 4627.329037289642, 4677.859664210079, 4744.319459505437, 4770.856874293777, 4870.488598697157, 1731.757553942449, 1894.6169296413696, 1940.5827747034455, 2016.5059560407794, 2020.3433883987764, 815.2133191470259, 816.9918300817035, 849.933333666245, 947.554649314821, 996.8953259907751, 17549.385771497935, 18675.346362877233, 18802.435926921942, 18921.526961826065, 19234.422967311002, 936.2650319499161, 957.3394357893031, 998.2712619787595, 1006.9194464442827, 1014.4116412480744, 10045.095941365165, 10275.845862312071, 10379.584816607403, 10440.947795260976, 10534.736631584272, 418910.0, 421892.0, 424835.0, 429194.0, 432349.0, 80632.0, 81830.18169818226, 85757.57121214354, 85802.57098714466, 88481.0, 963.937344072633, 967.3567077893263, 991.7272749993809, 1031.4739482863793, 1033.4343469643345, 15900.284487197872, 16132.838671613603, 17190.914045429694, 17263.309467621155, 17411.129443528025, 39391.0, 40475.797621011705, 40523.797381012904, 41393.79303103465, 41668.374974374805, 55053.44946550483, 56132.43867561436, 56866.71566642306, 57334.0, 57926.7103664479, 8107.391945603986, 8114.634841432032, 8206.876896846672, 8458.365622578454, 8664.436811607226, 10926.6721998337, 11689.0, 11759.764804703687, 11989.100817438553, 12225.877741222474, 10155.339902906288, 10464.47677616101, 10677.412742298937, 10754.89245107539, 11351.262167959056, 9987.201023918215, 10790.406527641055, 10830.945845270724, 10984.340939543654, 11096.11231101526, 10568.313059651406, 10582.8412573813, 10782.838257425989, 11056.115510758958, 11130.499127539118, 8227.218414250636, 8300.169983001651, 8432.53621050848, 8511.702090426805, 8824.735257942271, 8941.508217048126, 9674.274429417681, 10222.591096356062, 10599.470026498799, 10818.945905270422], "value": 8652.847208455918, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.8601139886014, 1439.5177615498803, 1558.7038462692087, 1719.6990526657848, 1738.2525514028962, 7239.457040721941, 7469.663865126069, 7974.441789074767, 8383.58082095895, 8404.243618074372, 7973.800654983628, 8303.0, 8355.331573474123, 8355.582220888962, 8553.272971797389, 2243.618584840577, 2289.496310811619, 2291.0606651272983, 2297.069686776858, 2302.8848557572137, 2014.315132854829, 2063.4222417723063, 2139.229877244191, 2171.109844963565, 2320.3387034695115, 2194.890255487227, 2262.5248697773486, 2291.1522736587403, 2299.5171014086977, 2304.3547806614174, 2258.2999270226283, 2264.116994372188, 2282.3495303838326, 2288.1762565476415, 2288.267754318627, 2334.0897050150456, 2357.5167090746527, 2372.7034120734907, 2376.382140643445, 2377.358113309414, 8313.210245026712, 8398.580070996426, 8447.535385553854, 8448.366372522023, 8449.0, 5096.464871188509, 5241.842744717665, 5241.842744717665, 5242.580593552507, 5243.449437809013, 3264.2329052672603, 3317.502374643823, 3391.321735652855, 3405.216800135983, 3414.36834185674, 1143.3025854228913, 1162.5930924176555, 1173.254983085738, 1193.9044876409866, 2019.6768517037203, 11168.497417616227, 11757.823632645517, 11764.94117529424, 11781.587644432404, 11800.822987655192, 4632.837850675211, 4638.791254393493, 4675.485696573374, 4686.507916668769, 4687.742174180385, 10130.0, 10135.341202821794, 10150.340227885164, 10158.39049657023, 10159.0, 5314.415414304423, 5318.095923692904, 5325.920111198335, 5327.280817089721, 5327.307450031549, 4698.671093023524, 4758.07217592573, 4796.496367881392, 4813.879653008703, 4885.09625719236, 3784.375578029594, 3803.0, 3807.9619203807606, 3861.189150278473, 3908.2965066288343, 1183.1363104933446, 1210.8970737487236, 1243.111175509515, 1248.975020499603, 1263.4946021591254, 878.7363790862844, 883.9195404016996, 885.1060428966662, 892.3575025981283, 906.4878343735768, 5114.693118412907, 5161.81933632321, 5355.30381050461, 5371.033214021513, 5379.65032272901, 846.3229416466802, 891.5809569502323, 913.6893456224915, 923.9168474837297, 942.5146049784366, 3523.841427135785, 3630.5824830144343, 3665.193657395369, 3682.650148235913, 3748.5314335708035, 10173.593056277814, 10412.167026637851, 10513.421761803174, 10526.947365263279, 10528.526216320284, 10387.065164135112, 10392.584296628202, 10403.323783954018, 10416.895831041744, 10465.895341046644, 7946.602669866599, 8016.038075430985, 8024.398170137263, 8027.117017127995, 8029.197080291924, 5317.654352467077, 5370.113931201464, 5457.126859702428, 5535.612507124623, 5589.636673616202, 9165.266778657824, 9499.477528735986, 9500.667476638284, 9640.951795240979, 9755.365901216399, 9539.284553658643, 9676.467794271382, 9734.9026509734, 9767.072128147815, 9870.062344077302, 7285.927140728737, 7314.524555903849, 7322.0847394075745, 7338.046054012944, 7341.70633174667, 7982.560959147303, 8046.476978996347, 8104.473209241381, 8106.878396823934, 8107.594620269081, 6831.795046148623, 6867.450603951772, 7017.052697885924, 7063.046488723957, 7207.56754594705, 6744.494162937701, 6880.174379074543, 6889.483288753263, 6925.29208901651, 7157.57054576727, 6169.383061693795, 6232.158658581215, 6281.309056003927, 6286.65423401699, 6288.968555157195, 4566.634669226521, 4599.149157405891, 4683.601893838925, 4734.644901632322, 4814.927776083291, 4795.712257264575, 4805.087033463631, 4868.829590964299, 4876.878078048077, 4898.0], "value": 4451.712732117531, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.9546015906733, 336.3055290824447, 347.53951014905255, 351.80826449584976, 354.28702224743364, 5084.8050708083665, 5163.483651634835, 5240.711760853152, 5247.8950420991605, 5257.7108259045735, 4964.950350496494, 4970.70175789452, 5041.6218783591285, 5064.797408103672, 5066.544011039005, 4038.0, 4043.0498832774274, 4054.756714597119, 4073.6944729145357, 4079.7348172368847, 3833.7699738015667, 3919.666828319582, 4042.43405923171, 4043.5147782266167, 4077.143799802045, 4016.6786657067364, 4030.858919937803, 4039.2123535910364, 4041.575634558373, 4056.127932494513, 3986.1629057897585, 4028.47629808126, 4052.8581499647516, 4062.7765472898977, 4062.796860156995, 3599.9100022499647, 3603.513525674027, 3609.512715783388, 3610.6028336882923, 3619.6199399062984, 4815.566599006072, 4941.407031156248, 4946.703197808143, 4948.554630083309, 4962.404511458648, 4566.497685254652, 4569.9314510282375, 4571.22289210835, 4575.267957126877, 4579.9084018319445, 5293.814716484943, 5300.2314664373835, 5333.386660534011, 5348.705821179834, 5354.785808567692, 3706.9073273168115, 3834.7699138051807, 3864.8840534784003, 3892.4161375793574, 3902.107477873229, 5482.396936337, 5501.312335958005, 5510.283663123809, 5525.778968841241, 5529.723513824292, 4003.279409706281, 4007.6192761687594, 4009.939850902239, 4011.8194681239415, 4012.6789856811383, 3963.722539422213, 3973.920521589552, 3999.6200360965663, 4035.435039094529, 4070.8371665133654, 4538.659600529973, 4547.453865685683, 4551.908961820811, 4552.430946131733, 4582.9541704582525, 5343.332083489564, 5347.679139251657, 5352.357717073976, 5367.463253674679, 5377.354717433855, 1244.9190802597802, 1255.6798016505732, 1336.9799453008213, 1343.079990206692, 1355.979660305096, 937.1846493550654, 946.8306641297992, 949.928755343352, 962.4754508792724, 964.5965120749433, 5004.124278251292, 5027.3464449621315, 5052.898942021139, 5077.619178561623, 5116.0, 5007.397632757556, 5229.529342359206, 5247.580193584504, 5248.6325957182635, 5293.841184764463, 5013.493253373314, 5018.197088465901, 5030.622703297267, 5055.16589762685, 5058.367704036996, 2793.385455199853, 2793.4413117376607, 2808.5787131930374, 2823.9294017649313, 2824.728872007605, 5228.503292187236, 5375.220593014069, 5424.969255841377, 5445.945540544544, 5455.8090466834265, 5342.584215182979, 5359.847632758975, 5366.812161574326, 5389.892202155935, 5393.4336894626285, 5405.432429594836, 5416.539594134542, 5433.483819037185, 5437.0, 5494.862628434321, 5498.780048798003, 5512.173174023768, 5530.364008139116, 5533.225348451139, 5566.972165139148, 5497.587680923867, 5514.862128446661, 5530.253415788817, 5545.500904918576, 5572.4706152915405, 5418.972905135449, 5671.8865622688145, 5718.485336319751, 5826.883462330645, 5847.152162936435, 3978.5026871641044, 3991.660708839665, 4010.2981978153125, 4016.357382818735, 4021.9396709049975, 5579.19101730255, 5594.916076258778, 5595.300587426572, 5595.804146854841, 5596.313769390634, 5187.58499320061, 5350.652207606493, 5369.6509726867625, 5387.81142660005, 5481.808136715355, 5120.820771272987, 5242.580593552583, 5253.553447956967, 5346.705931173823, 5357.303550538405, 5231.163013917755, 5285.445028271976, 5301.045811753921, 5311.814086506954, 5342.75957581902, 5380.43505431924, 5383.16560933056, 5399.2980912481125, 5411.810586629449, 5412.7293635318865, 5177.456367081402, 5226.712530810842, 5258.815941442184, 5280.392754833089, 5281.973590132025], "value": 4087.0841981347003, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.8436601764602, 722.1695050691704, 740.0970815604963, 751.8195633048067, 782.3584660578329, 13979.580612581625, 14473.421063157488, 14576.781348279785, 14581.781273280896, 14679.633009174762, 13312.933435332834, 13358.93320533396, 13362.532311369105, 13380.130291531044, 13386.397612107454, 634.8381162803479, 661.3353579652443, 741.292066076898, 765.7817522006216, 773.5822655765879, 771.1863983497408, 780.7189411811743, 782.792559971608, 782.8473447677706, 783.6081959020489, 826.1408135539032, 830.0205757206494, 832.6253186066264, 833.0919297965216, 833.4582521361134, 671.8589096289785, 694.437505620444, 793.996030019852, 825.2119226139052, 826.5701835045772, 599.9820005399845, 600.1388008208235, 600.6306121735145, 606.2724730323586, 606.3754333036935, 15304.923475382664, 15352.385904563804, 15362.078275303518, 15374.308156133, 15433.151176685278, 14155.363008664635, 14201.928990355085, 14289.571312860631, 14360.928195358956, 14484.130952142905, 12586.937065314707, 12607.11750177488, 12621.242725436412, 12866.806997895039, 12896.613101606967, 4299.763513006784, 4306.935395969063, 4372.846950356722, 4373.365861949999, 4375.803088861009, 84182.0, 85943.14056859414, 86323.56838215832, 88036.0, 90355.54822225912, 11494.367809770376, 11619.535218591162, 11629.302241865345, 11760.0, 12392.504299828086, 22100.226492073023, 22137.0, 22181.334559963227, 22206.333809985725, 22768.658470122962, 7491.812704682317, 7505.699772009168, 7639.885401718979, 7653.234676532302, 7757.379409647215, 4183.937240941388, 4223.1975924574235, 4264.061906380592, 4304.655627549789, 4309.116631090619, 3316.5688460499982, 3394.9151271217925, 3416.265502916885, 3429.9195753337617, 3432.485127230935, 589.1810383566846, 593.8248216776061, 610.1975901689265, 610.6824451285307, 621.59906860075, 549.5493695170002, 615.806021103351, 625.5081630311739, 650.5608714118046, 677.2584020497547, 9001.909980900238, 9147.496887671243, 9157.358984871129, 9239.538023098818, 9402.529873506297, 633.6419922743635, 634.8666779976164, 639.9392057754505, 640.6924676155471, 640.8622146238581, 4285.442892423965, 4329.301677367963, 4517.482517482517, 4622.46841613212, 4717.150912835654, 67108.0, 67267.32732672709, 77473.0, 85290.57354713186, 87855.0, 74831.0, 74836.0, 75030.24969750342, 75296.0, 75962.62018689871, 66011.0, 66766.33233667602, 67964.32035679679, 69456.0, 70035.0, 8510.31917446615, 8649.8702519461, 8684.34867384936, 8729.170728780755, 8812.250958668585, 38629.55760087626, 39428.80285598554, 41905.0, 46765.53234467612, 48562.27156592724, 53436.46563534315, 58348.416515836, 58935.41064589299, 59343.40656593379, 62305.0, 12979.740405192033, 12984.675383115498, 13079.542216022393, 13100.659331870313, 13439.126456780277, 14338.928305358824, 14411.78382324287, 14724.55826325212, 15074.170920599472, 15604.609884752972, 13759.656008599864, 14085.929570352082, 14149.36327865228, 14390.496332628307, 14621.122732636077, 13167.867654016, 13355.131916425402, 13528.729425411633, 13649.249291288677, 13686.247256400993, 13770.0, 13873.236971966639, 14691.632709182357, 15068.849311507183, 15151.621209469851, 11642.825357619811, 11802.94098529502, 11845.46695398692, 12003.759924801281, 12367.381630918599, 11525.827112593486, 11989.940050299692, 12068.698282543006, 12306.753864922472, 12356.38218089074], "value": 7168.045835316548, "unit": "score"}], "name": "dromaeo_css", "value": 6976.02073006176, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 115.0, 114.0, 113.0, 111.0, 113.0, 113.0, 112.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 124.0, 160.0, 134.0, 122.0, 124.0, 127.0, 142.0, 126.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 159.0, 160.0, 161.0, 162.0, 160.0, 159.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 101.0, 77.0, 77.0, 77.0, 75.0, 90.0, 75.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 81.0, 113.0, 82.0, 82.0, 82.0, 96.0, 83.0, 82.0, 113.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 148.0], "value": 145.8, "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, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 53.0, 53.0, 54.0, 54.0, 55.0, 55.0, 55.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 62.0, 63.0, 64.0, 64.0, 64.0, 63.0, 64.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 81.0, 81.0, 81.0, 79.0, 81.0, 81.0, 80.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 130.0, 130.0, 142.0, 130.0, 136.0, 130.0, 175.0, 144.0], "value": 139.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 171.0, 165.0, 168.0, 166.0, 163.0, 168.0, 163.0, 167.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 65.0, 64.0, 68.0, 65.0, 64.0, 64.0], "value": 64.9, "unit": "score"}], "name": "kraken", "value": 1503.5, "alertThreshold": 2.0}]} 22:57:42 INFO - Return code: 0 22:57:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:57:42 INFO - # TBPL SUCCESS # 22:57:42 INFO - Running post-action listener: _resource_record_post_action 22:57:42 INFO - [mozharness: 2016-12-06 06:57:42.161474Z] Finished run-tests step (success) 22:57:42 INFO - Running post-run listener: _resource_record_post_run 22:57:42 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392409088; Read time: 12; Write time: 314956 22:57:42 INFO - TinderboxPrint: CPU usage
13.1% 22:57:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 22:57:42 INFO - TinderboxPrint: I/O write bytes / time
392,409,088 / 314,956 22:57:42 INFO - TinderboxPrint: CPU idle
8,305.7 (86.9%) 22:57:42 INFO - TinderboxPrint: CPU user
1,203.2 (12.6%) 22:57:42 INFO - TinderboxPrint: Swap in / out
0 / 0 22:57:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103829504; Read time: 0; Write time: 74276 22:57:42 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 288579584; Read time: 0; Write time: 240680 22:57:42 INFO - Running post-run listener: _upload_blobber_files 22:57:42 INFO - Blob upload gear active. 22:57:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:57:42 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 22:57:42 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'] 22:57:42 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 22:57:43 INFO - (blobuploader) - INFO - Open directory for files ... 22:57:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:57:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:57:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:57:46 INFO - (blobuploader) - INFO - Done attempting. 22:57:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:57:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:57:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:57:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:57:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:57:47 INFO - (blobuploader) - INFO - Done attempting. 22:57:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:57:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:57:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:57:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:57:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:57:48 INFO - (blobuploader) - INFO - Done attempting. 22:57:48 INFO - (blobuploader) - INFO - Iteration through files over. 22:57:48 INFO - Return code: 0 22:57:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:57:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:57:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cfb8c89152f96af9c7399cdb012216221aeab43a45b43e7631774437380297d24f43ca4bd4e671d0e61e4e868e7dab57c3453fc8df86d5f80e12ed15266eeaf4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/23e406f8e004528d7902b1bbe25404b070b61b8e41570d42c64b92c8b1ba628c2ead5f292621c4df4f5a54ee47976ad7f5658ac675b9d4424a61995bf6d6ed5a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad3b285af836bc9e215b455b037606b4aedd954b5e16c602601b91f09ad3821f6ccc4af877b3f6a247a0a502c612d4a8c9cde190a0da7049e2355aa6cf505ad9"} 22:57:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:57:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:57:48 INFO - Contents: 22:57:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cfb8c89152f96af9c7399cdb012216221aeab43a45b43e7631774437380297d24f43ca4bd4e671d0e61e4e868e7dab57c3453fc8df86d5f80e12ed15266eeaf4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/23e406f8e004528d7902b1bbe25404b070b61b8e41570d42c64b92c8b1ba628c2ead5f292621c4df4f5a54ee47976ad7f5658ac675b9d4424a61995bf6d6ed5a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad3b285af836bc9e215b455b037606b4aedd954b5e16c602601b91f09ad3821f6ccc4af877b3f6a247a0a502c612d4a8c9cde190a0da7049e2355aa6cf505ad9"} 22:57:48 INFO - Running post-run listener: copy_logs_to_upload_dir 22:57:48 INFO - Copying logs to upload dir... 22:57:48 INFO - mkdir: /builds/slave/test/build/upload/logs 22:57:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.517346 ========= master_lag: 0.73 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-12-05 22:57:48.200321) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-12-05 22:57:48.205306) ========= 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=03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019 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/cfb8c89152f96af9c7399cdb012216221aeab43a45b43e7631774437380297d24f43ca4bd4e671d0e61e4e868e7dab57c3453fc8df86d5f80e12ed15266eeaf4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/23e406f8e004528d7902b1bbe25404b070b61b8e41570d42c64b92c8b1ba628c2ead5f292621c4df4f5a54ee47976ad7f5658ac675b9d4424a61995bf6d6ed5a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad3b285af836bc9e215b455b037606b4aedd954b5e16c602601b91f09ad3821f6ccc4af877b3f6a247a0a502c612d4a8c9cde190a0da7049e2355aa6cf505ad9"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480999449/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/cfb8c89152f96af9c7399cdb012216221aeab43a45b43e7631774437380297d24f43ca4bd4e671d0e61e4e868e7dab57c3453fc8df86d5f80e12ed15266eeaf4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/23e406f8e004528d7902b1bbe25404b070b61b8e41570d42c64b92c8b1ba628c2ead5f292621c4df4f5a54ee47976ad7f5658ac675b9d4424a61995bf6d6ed5a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad3b285af836bc9e215b455b037606b4aedd954b5e16c602601b91f09ad3821f6ccc4af877b3f6a247a0a502c612d4a8c9cde190a0da7049e2355aa6cf505ad9"}' ========= master_lag: 1.91 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-12-05 22:57:50.121862) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 22:57:50.122324) ========= 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=03627c79f6bb3d1578c4a402000001ba-1480998819.766060-381833019 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004661 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 22:57:50.143369) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 22:57:50.143713) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 22:57:50.147163) ========= ========= Total master_lag: 2.73 =========