builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-013 starttime: 1492034860.35 results: success (0) revision: cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:40.350774) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:40.351246) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:40.351514) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:40.365985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:40.366253) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-12 15:07:40-- 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% 50.4M=0s 2017-04-12 15:07:40 (50.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124671 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:40.505211) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:40.505566) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014441 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:40.555010) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 15:07:40.555377) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 --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=9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-12 15:07:40,912 truncating revision to first 12 chars 2017-04-12 15:07:40,912 Setting DEBUG logging. 2017-04-12 15:07:40,912 attempt 1/10 2017-04-12 15:07:40,912 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cef7b5c139ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 15:07:42,087 unpacking tar archive at: mozilla-aurora-cef7b5c139ef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.571762 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 15:07:42.140640) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:42.140957) ========= script_repo_revision: cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:42.141383) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:42.141644) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 15:07:42.150962) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-12 15:07:42.151334) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 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-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] 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=9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:07:42 INFO - MultiFileLogger online at 20170412 15:07:42 in /builds/slave/test 15:07:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 15:07:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:07:42 INFO - {'append_to_log': False, 15:07:42 INFO - 'base_work_dir': '/builds/slave/test', 15:07:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 15:07:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:07:42 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 15:07:42 INFO - 'buildbot_json_path': 'buildprops.json', 15:07:42 INFO - 'config_files': ('talos/linux_config.py',), 15:07:42 INFO - 'default_actions': ('clobber', 15:07:42 INFO - 'read-buildbot-config', 15:07:42 INFO - 'download-and-extract', 15:07:42 INFO - 'populate-webroot', 15:07:42 INFO - 'create-virtualenv', 15:07:42 INFO - 'install', 15:07:42 INFO - 'run-tests'), 15:07:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:07:42 INFO - 'download_minidump_stackwalk': True, 15:07:42 INFO - 'download_symbols': 'ondemand', 15:07:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:07:42 INFO - 'tooltool.py': '/tools/tooltool.py', 15:07:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:07:42 INFO - '/tools/misc-python/virtualenv.py')}, 15:07:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:07:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:07:42 INFO - 'gecko_profile': False, 15:07:42 INFO - 'gecko_profile_interval': 0, 15:07:42 INFO - 'installer_path': 'installer.exe', 15:07:42 INFO - 'log_level': 'info', 15:07:42 INFO - 'log_name': 'talos', 15:07:42 INFO - 'log_to_console': True, 15:07:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:07:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:07:42 INFO - 'opt_config_files': (), 15:07:42 INFO - 'pip_index': False, 15:07:42 INFO - 'suite': 'dromaeojs', 15:07:42 INFO - 'system_bits': '32', 15:07:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:07:42 INFO - 'title': 'talos-linux64-ix-013', 15:07:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:07:42 INFO - 'use_talos_json': True, 15:07:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:07:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:07:42 INFO - 'work_dir': 'build'} 15:07:42 INFO - [mozharness: 2017-04-12 22:07:42.604841Z] Running clobber step. 15:07:42 INFO - Running pre-action listener: _resource_record_pre_action 15:07:42 INFO - Running main action method: clobber 15:07:42 INFO - rmtree: /builds/slave/test/build 15:07:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:07:43 INFO - Running post-action listener: _resource_record_post_action 15:07:43 INFO - [mozharness: 2017-04-12 22:07:43.578669Z] Finished clobber step (success) 15:07:43 INFO - [mozharness: 2017-04-12 22:07:43.578768Z] Running read-buildbot-config step. 15:07:43 INFO - Running pre-action listener: _resource_record_pre_action 15:07:43 INFO - Running main action method: read_buildbot_config 15:07:43 INFO - Using buildbot properties: 15:07:43 INFO - { 15:07:43 INFO - "project": "", 15:07:43 INFO - "product": "firefox", 15:07:43 INFO - "who": "ryanvm@gmail.com", 15:07:43 INFO - "installer_path": "public/build/target.tar.bz2", 15:07:43 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:07:43 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 15:07:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 15:07:43 INFO - "stage_platform": "linux64", 15:07:43 INFO - "basedir": "/builds/slave/test", 15:07:43 INFO - "buildnumber": 123, 15:07:43 INFO - "platform": "ubuntu64_hw", 15:07:43 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:07:43 INFO - "slavebuilddir": "test", 15:07:43 INFO - "taskId": "M0M_6lJTQ12jWahSwjcjew", 15:07:43 INFO - "branch": "mozilla-aurora", 15:07:43 INFO - "script_repo_revision": "production", 15:07:43 INFO - "revision": "cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2", 15:07:43 INFO - "slavename": "talos-linux64-ix-013", 15:07:43 INFO - "repo_path": "releases/mozilla-aurora" 15:07:43 INFO - } 15:07:43 INFO - Finding installer, test and symbols from parent task. 15:07:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M0M_6lJTQ12jWahSwjcjew'}, attempt #1 15:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M0M_6lJTQ12jWahSwjcjew'}, attempt #1 15:07:44 INFO - Task dependencies: fbfM5napRMuXpZ_93eEa1w 15:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w'}, attempt #1 15:07:44 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.tar.bz2 15:07:44 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/test_packages.json 15:07:44 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.crashreporter-symbols.zip 15:07:44 INFO - Running post-action listener: _resource_record_post_action 15:07:44 INFO - [mozharness: 2017-04-12 22:07:44.986123Z] Finished read-buildbot-config step (success) 15:07:44 INFO - [mozharness: 2017-04-12 22:07:44.986340Z] Running download-and-extract step. 15:07:44 INFO - Running pre-action listener: _resource_record_pre_action 15:07:44 INFO - Running main action method: download_and_extract 15:07:44 INFO - mkdir: /builds/slave/test/build/tests 15:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:44 INFO - trying https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.test_packages.json 15:07:44 INFO - Downloading https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:07:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:07:45 INFO - Downloaded 896 bytes. 15:07:45 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:07:45 INFO - Using the following test package requirements: 15:07:45 INFO - {u'common': [u'target.common.tests.zip'], 15:07:45 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:07:45 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:07:45 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:07:45 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:07:45 INFO - u'mozbase': [u'target.common.tests.zip'], 15:07:45 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:07:45 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:07:45 INFO - u'web-platform': [u'target.common.tests.zip', 15:07:45 INFO - u'target.web-platform.tests.zip'], 15:07:45 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:07:45 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:07:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.common.tests.zip 15:07:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:07:45 INFO - Fetch https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.common.tests.zip into memory 15:07:46 INFO - Expected file size: 59541711 15:07:46 INFO - Obtained file size: 59541711 15:07:56 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:07:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.common.tests.zip 15:07:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:07:56 INFO - Fetch https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.common.tests.zip into memory 15:07:57 INFO - Expected file size: 59541711 15:07:57 INFO - Obtained file size: 59541711 15:08:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.talos.tests.zip 15:08:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:08:08 INFO - Fetch https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.talos.tests.zip into memory 15:08:09 INFO - Expected file size: 14404424 15:08:09 INFO - Obtained file size: 14404424 15:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:09 INFO - trying https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.tar.bz2 15:08:09 INFO - Downloading https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:08:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:08:10 INFO - Downloaded 66785189 bytes. 15:08:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.tar.bz2 15:08:10 INFO - mkdir: /builds/slave/test/properties 15:08:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:08:10 INFO - Writing to file /builds/slave/test/properties/build_url 15:08:10 INFO - Contents: 15:08:10 INFO - build_url:https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.tar.bz2 15:08:10 INFO - Running post-action listener: _resource_record_post_action 15:08:10 INFO - Running post-action listener: set_extra_try_arguments 15:08:10 INFO - [mozharness: 2017-04-12 22:08:10.475718Z] Finished download-and-extract step (success) 15:08:10 INFO - [mozharness: 2017-04-12 22:08:10.475814Z] Running populate-webroot step. 15:08:10 INFO - Running pre-action listener: _resource_record_pre_action 15:08:10 INFO - Running main action method: populate_webroot 15:08:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:08:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:08:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:08:10 INFO - '--tppagecycles', 15:08:10 INFO - '7'], 15:08:10 INFO - 'tests': ['tsvgm']}}, 15:08:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:08:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:08:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:08:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:08:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:08:10 INFO - 'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:08:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:08:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:08:10 INFO - 'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['damp', 'tps']}, 15:08:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:08:10 INFO - 'tests': ['damp', 'tps']}, 15:08:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['dromaeo_dom']}, 15:08:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:08:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['basic_compositor_video']}, 15:08:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:08:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['a11yr', 15:08:10 INFO - 'ts_paint', 15:08:10 INFO - 'tpaint', 15:08:10 INFO - 'sessionrestore', 15:08:10 INFO - 'sessionrestore_no_auto_restore', 15:08:10 INFO - 'tabpaint']}, 15:08:10 INFO - 'other-e10s': {'tests': ['a11yr', 15:08:10 INFO - 'ts_paint', 15:08:10 INFO - 'tpaint', 15:08:10 INFO - 'sessionrestore', 15:08:10 INFO - 'sessionrestore_no_auto_restore', 15:08:10 INFO - 'tabpaint']}, 15:08:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:08:10 INFO - 'ts_paint', 15:08:10 INFO - 'tpaint', 15:08:10 INFO - 'sessionrestore', 15:08:10 INFO - 'sessionrestore_no_auto_restore', 15:08:10 INFO - 'tabpaint']}, 15:08:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:08:10 INFO - 'ts_paint', 15:08:10 INFO - 'tpaint', 15:08:10 INFO - 'sessionrestore', 15:08:10 INFO - 'sessionrestore_no_auto_restore', 15:08:10 INFO - 'tabpaint']}, 15:08:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['a11yr', 15:08:10 INFO - 'ts_paint', 15:08:10 INFO - 'tpaint', 15:08:10 INFO - 'sessionrestore', 15:08:10 INFO - 'sessionrestore_no_auto_restore', 15:08:10 INFO - 'tabpaint']}, 15:08:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['a11yr', 15:08:10 INFO - 'ts_paint', 15:08:10 INFO - 'tpaint', 15:08:10 INFO - 'sessionrestore', 15:08:10 INFO - 'sessionrestore_no_auto_restore', 15:08:10 INFO - 'tabpaint']}, 15:08:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['tsvgx', 15:08:10 INFO - 'tsvgr_opacity', 15:08:10 INFO - 'tart', 15:08:10 INFO - 'tscrollx', 15:08:10 INFO - 'cart', 15:08:10 INFO - 'tsvg_static']}, 15:08:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:08:10 INFO - 'tsvgr_opacity', 15:08:10 INFO - 'tart', 15:08:10 INFO - 'tscrollx', 15:08:10 INFO - 'cart', 15:08:10 INFO - 'tsvg_static']}, 15:08:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:08:10 INFO - 'talos_options': ['--disable-e10s'], 15:08:10 INFO - 'tests': ['tp5o']}, 15:08:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:08:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:08:10 INFO - 'talos_options': ['--disable-e10s', 15:08:10 INFO - '--xperf_path', 15:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:08:10 INFO - 'tests': ['tp5n']}, 15:08:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:08:10 INFO - 'talos_options': ['--xperf_path', 15:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:08:10 INFO - 'tests': ['tp5n']}}, 15:08:10 INFO - 'talos.zip': {'path': '', 15:08:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:08:10 INFO - Running post-action listener: _resource_record_post_action 15:08:10 INFO - [mozharness: 2017-04-12 22:08:10.488865Z] Finished populate-webroot step (success) 15:08:10 INFO - [mozharness: 2017-04-12 22:08:10.488955Z] Running create-virtualenv step. 15:08:10 INFO - Running pre-action listener: _resource_record_pre_action 15:08:10 INFO - Running main action method: create_virtualenv 15:08:10 INFO - Creating virtualenv /builds/slave/test/build/venv 15:08:10 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 15:08:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:08:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:08:10 INFO - Using real prefix '/usr' 15:08:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:08:11 INFO - Installing distribute.............................................................................................................................................................................................done. 15:08:13 INFO - Installing pip.................done. 15:08:13 INFO - Return code: 0 15:08:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:08:13 INFO - Reading from file tmpfile_stdout 15:08:13 INFO - Output received: 15:08:13 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) 15:08:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:08:13 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')]} 15:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:13 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')]} 15:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:13 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:13 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 15:08:13 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 15:08:13 INFO - Using env: {'DISPLAY': ':0', 15:08:13 INFO - 'HOME': '/home/cltbld', 15:08:13 INFO - 'LANG': 'en_US.UTF-8', 15:08:13 INFO - 'LANGUAGE': 'en_US:en', 15:08:13 INFO - 'LOGNAME': 'cltbld', 15:08:13 INFO - 'MAIL': '/var/mail/cltbld', 15:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:13 INFO - 'MOZ_NO_REMOTE': '1', 15:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:13 INFO - 'NO_EM_RESTART': '1', 15:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:13 INFO - 'PWD': '/builds/slave/test', 15:08:13 INFO - 'SHELL': '/bin/bash', 15:08:13 INFO - 'SHLVL': '1', 15:08:13 INFO - 'TERM': 'linux', 15:08:13 INFO - 'TMOUT': '86400', 15:08:13 INFO - 'USER': 'cltbld', 15:08:13 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:13 INFO - '_': '/tools/buildbot/bin/python'} 15:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:13 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 15:08:13 INFO - Cleaning up... 15:08:13 INFO - Return code: 0 15:08:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:08:13 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')]} 15:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:13 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')]} 15:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:13 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:13 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 15:08:13 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 15:08:13 INFO - Using env: {'DISPLAY': ':0', 15:08:13 INFO - 'HOME': '/home/cltbld', 15:08:13 INFO - 'LANG': 'en_US.UTF-8', 15:08:13 INFO - 'LANGUAGE': 'en_US:en', 15:08:13 INFO - 'LOGNAME': 'cltbld', 15:08:13 INFO - 'MAIL': '/var/mail/cltbld', 15:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:13 INFO - 'MOZ_NO_REMOTE': '1', 15:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:13 INFO - 'NO_EM_RESTART': '1', 15:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:13 INFO - 'PWD': '/builds/slave/test', 15:08:13 INFO - 'SHELL': '/bin/bash', 15:08:13 INFO - 'SHLVL': '1', 15:08:13 INFO - 'TERM': 'linux', 15:08:13 INFO - 'TMOUT': '86400', 15:08:13 INFO - 'USER': 'cltbld', 15:08:13 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:13 INFO - '_': '/tools/buildbot/bin/python'} 15:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:13 INFO - Downloading/unpacking psutil>=3.1.1 15:08:13 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 15:08:13 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 15:08:13 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 15:08:13 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 15:08:13 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 15:08:13 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 15:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:08:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:08:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:08:15 INFO - Installing collected packages: psutil 15:08:15 INFO - Running setup.py install for psutil 15:08:15 INFO - building 'psutil._psutil_linux' extension 15:08:15 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 15:08:16 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 15:08:16 INFO - building 'psutil._psutil_posix' extension 15:08:16 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 15:08:16 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 15:08:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:08:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:08:16 INFO - Successfully installed psutil 15:08:16 INFO - Cleaning up... 15:08:16 INFO - Return code: 0 15:08:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:08:16 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')]} 15:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:16 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')]} 15:08:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:16 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:16 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 15:08:16 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 15:08:16 INFO - Using env: {'DISPLAY': ':0', 15:08:16 INFO - 'HOME': '/home/cltbld', 15:08:16 INFO - 'LANG': 'en_US.UTF-8', 15:08:16 INFO - 'LANGUAGE': 'en_US:en', 15:08:16 INFO - 'LOGNAME': 'cltbld', 15:08:16 INFO - 'MAIL': '/var/mail/cltbld', 15:08:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:16 INFO - 'MOZ_NO_REMOTE': '1', 15:08:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:16 INFO - 'NO_EM_RESTART': '1', 15:08:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:16 INFO - 'PWD': '/builds/slave/test', 15:08:16 INFO - 'SHELL': '/bin/bash', 15:08:16 INFO - 'SHLVL': '1', 15:08:16 INFO - 'TERM': 'linux', 15:08:16 INFO - 'TMOUT': '86400', 15:08:16 INFO - 'USER': 'cltbld', 15:08:16 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:16 INFO - '_': '/tools/buildbot/bin/python'} 15:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:08:16 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 15:08:16 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 15:08:16 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 15:08:16 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 15:08:16 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 15:08:16 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 15:08:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:08:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:08:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:08:18 INFO - Installing collected packages: mozsystemmonitor 15:08:18 INFO - Running setup.py install for mozsystemmonitor 15:08:18 INFO - Successfully installed mozsystemmonitor 15:08:18 INFO - Cleaning up... 15:08:18 INFO - Return code: 0 15:08:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:08:18 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')]} 15:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:18 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')]} 15:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:18 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:18 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 15:08:18 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 15:08:18 INFO - Using env: {'DISPLAY': ':0', 15:08:18 INFO - 'HOME': '/home/cltbld', 15:08:18 INFO - 'LANG': 'en_US.UTF-8', 15:08:18 INFO - 'LANGUAGE': 'en_US:en', 15:08:18 INFO - 'LOGNAME': 'cltbld', 15:08:18 INFO - 'MAIL': '/var/mail/cltbld', 15:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:18 INFO - 'MOZ_NO_REMOTE': '1', 15:08:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:18 INFO - 'NO_EM_RESTART': '1', 15:08:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:18 INFO - 'PWD': '/builds/slave/test', 15:08:18 INFO - 'SHELL': '/bin/bash', 15:08:18 INFO - 'SHLVL': '1', 15:08:18 INFO - 'TERM': 'linux', 15:08:18 INFO - 'TMOUT': '86400', 15:08:18 INFO - 'USER': 'cltbld', 15:08:18 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:18 INFO - '_': '/tools/buildbot/bin/python'} 15:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:19 INFO - Downloading/unpacking jsonschema==2.5.1 15:08:19 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 15:08:19 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 15:08:19 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 15:08:19 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 15:08:19 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 15:08:19 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 15:08:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:08:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:08:21 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 15:08:21 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 15:08:21 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 15:08:21 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 15:08:21 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 15:08:21 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 15:08:21 INFO - Downloading functools32-3.2.3-2.tar.gz 15:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:08:21 INFO - warning: no files found matching '*.txt' 15:08:21 INFO - no previously-included directories found matching 'build' 15:08:21 INFO - no previously-included directories found matching 'dist' 15:08:21 INFO - no previously-included directories found matching '.git*' 15:08:21 INFO - Installing collected packages: jsonschema, functools32 15:08:21 INFO - Running setup.py install for functools32 15:08:21 INFO - warning: no files found matching '*.txt' 15:08:21 INFO - no previously-included directories found matching 'build' 15:08:21 INFO - no previously-included directories found matching 'dist' 15:08:21 INFO - no previously-included directories found matching '.git*' 15:08:21 INFO - Successfully installed jsonschema functools32 15:08:21 INFO - Cleaning up... 15:08:21 INFO - Return code: 0 15:08:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:08:21 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')]} 15:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:21 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')]} 15:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:21 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:21 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 15:08:21 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 15:08:21 INFO - Using env: {'DISPLAY': ':0', 15:08:21 INFO - 'HOME': '/home/cltbld', 15:08:21 INFO - 'LANG': 'en_US.UTF-8', 15:08:21 INFO - 'LANGUAGE': 'en_US:en', 15:08:21 INFO - 'LOGNAME': 'cltbld', 15:08:21 INFO - 'MAIL': '/var/mail/cltbld', 15:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:21 INFO - 'MOZ_NO_REMOTE': '1', 15:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:21 INFO - 'NO_EM_RESTART': '1', 15:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:21 INFO - 'PWD': '/builds/slave/test', 15:08:21 INFO - 'SHELL': '/bin/bash', 15:08:21 INFO - 'SHLVL': '1', 15:08:21 INFO - 'TERM': 'linux', 15:08:21 INFO - 'TMOUT': '86400', 15:08:21 INFO - 'USER': 'cltbld', 15:08:21 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:21 INFO - '_': '/tools/buildbot/bin/python'} 15:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:08:21 INFO - Cleaning up... 15:08:21 INFO - Return code: 0 15:08:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:08:21 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')]} 15:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:21 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')]} 15:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:21 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:21 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 15:08:21 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 15:08:21 INFO - Using env: {'DISPLAY': ':0', 15:08:21 INFO - 'HOME': '/home/cltbld', 15:08:21 INFO - 'LANG': 'en_US.UTF-8', 15:08:21 INFO - 'LANGUAGE': 'en_US:en', 15:08:21 INFO - 'LOGNAME': 'cltbld', 15:08:21 INFO - 'MAIL': '/var/mail/cltbld', 15:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:21 INFO - 'MOZ_NO_REMOTE': '1', 15:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:21 INFO - 'NO_EM_RESTART': '1', 15:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:21 INFO - 'PWD': '/builds/slave/test', 15:08:21 INFO - 'SHELL': '/bin/bash', 15:08:21 INFO - 'SHLVL': '1', 15:08:21 INFO - 'TERM': 'linux', 15:08:21 INFO - 'TMOUT': '86400', 15:08:21 INFO - 'USER': 'cltbld', 15:08:21 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:21 INFO - '_': '/tools/buildbot/bin/python'} 15:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:22 INFO - Downloading/unpacking blobuploader==1.2.4 15:08:22 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 15:08:22 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 15:08:22 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 15:08:22 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 15:08:22 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 15:08:22 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 15:08:24 INFO - Downloading blobuploader-1.2.4.tar.gz 15:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:08:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:08:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:08:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:08:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:08:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:08:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:08:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:08:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:08:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:08:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:08:25 INFO - Downloading docopt-0.6.1.tar.gz 15:08:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:08:25 INFO - Installing collected packages: blobuploader, requests, docopt 15:08:25 INFO - Running setup.py install for blobuploader 15:08:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:08:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:08:25 INFO - Running setup.py install for requests 15:08:25 INFO - Running setup.py install for docopt 15:08:25 INFO - Successfully installed blobuploader requests docopt 15:08:25 INFO - Cleaning up... 15:08:25 INFO - Return code: 0 15:08:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:08:25 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')]} 15:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:25 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')]} 15:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:25 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:25 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 15:08:25 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 15:08:25 INFO - Using env: {'DISPLAY': ':0', 15:08:25 INFO - 'HOME': '/home/cltbld', 15:08:25 INFO - 'LANG': 'en_US.UTF-8', 15:08:25 INFO - 'LANGUAGE': 'en_US:en', 15:08:25 INFO - 'LOGNAME': 'cltbld', 15:08:25 INFO - 'MAIL': '/var/mail/cltbld', 15:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:25 INFO - 'MOZ_NO_REMOTE': '1', 15:08:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:25 INFO - 'NO_EM_RESTART': '1', 15:08:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:25 INFO - 'PWD': '/builds/slave/test', 15:08:25 INFO - 'SHELL': '/bin/bash', 15:08:25 INFO - 'SHLVL': '1', 15:08:25 INFO - 'TERM': 'linux', 15:08:25 INFO - 'TMOUT': '86400', 15:08:25 INFO - 'USER': 'cltbld', 15:08:25 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:25 INFO - '_': '/tools/buildbot/bin/python'} 15:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:08:25 INFO - Running setup.py (path:/tmp/pip-utGaBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:08:26 INFO - Running setup.py (path:/tmp/pip-BwEoSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:08:26 INFO - Running setup.py (path:/tmp/pip-IJbvWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:08:26 INFO - Running setup.py (path:/tmp/pip-0VJU2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:08:26 INFO - Running setup.py (path:/tmp/pip-XQtHGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:08:26 INFO - Running setup.py (path:/tmp/pip-_ySyzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:08:26 INFO - Running setup.py (path:/tmp/pip-bX4ivq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:08:26 INFO - Running setup.py (path:/tmp/pip-pSIlaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:08:26 INFO - Running setup.py (path:/tmp/pip-9kaWu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:08:26 INFO - Running setup.py (path:/tmp/pip-2dZX33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:08:26 INFO - Running setup.py (path:/tmp/pip-68oozG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:08:26 INFO - Running setup.py (path:/tmp/pip-fWp8dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:08:26 INFO - Running setup.py (path:/tmp/pip-6HDdnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:08:26 INFO - Running setup.py (path:/tmp/pip-a6RLvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:08:26 INFO - Running setup.py (path:/tmp/pip-H48u_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:08:27 INFO - Running setup.py (path:/tmp/pip-LXoaBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:08:27 INFO - Running setup.py (path:/tmp/pip-yVRhZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:08:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:08:27 INFO - Running setup.py install for manifestparser 15:08:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:08:27 INFO - Running setup.py install for mozcrash 15:08:27 INFO - Running setup.py install for mozdebug 15:08:27 INFO - Running setup.py install for mozdevice 15:08:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:08:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:08:27 INFO - Running setup.py install for mozfile 15:08:27 INFO - Running setup.py install for mozhttpd 15:08:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:08:27 INFO - Running setup.py install for mozinfo 15:08:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:08:27 INFO - Running setup.py install for mozInstall 15:08:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:08:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:08:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:08:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:08:27 INFO - Running setup.py install for mozleak 15:08:28 INFO - Running setup.py install for mozlog 15:08:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:08:28 INFO - Running setup.py install for moznetwork 15:08:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:08:28 INFO - Running setup.py install for mozprocess 15:08:28 INFO - Running setup.py install for mozprofile 15:08:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:08:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:08:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:08:28 INFO - Running setup.py install for mozrunner 15:08:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:08:28 INFO - Running setup.py install for mozscreenshot 15:08:28 INFO - Running setup.py install for moztest 15:08:28 INFO - Running setup.py install for mozversion 15:08:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:08:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:08:28 INFO - Cleaning up... 15:08:28 INFO - Return code: 0 15:08:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:08: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')]} 15:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08: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')]} 15:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:29 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:29 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 15:08:29 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 15:08:29 INFO - Using env: {'DISPLAY': ':0', 15:08:29 INFO - 'HOME': '/home/cltbld', 15:08:29 INFO - 'LANG': 'en_US.UTF-8', 15:08:29 INFO - 'LANGUAGE': 'en_US:en', 15:08:29 INFO - 'LOGNAME': 'cltbld', 15:08:29 INFO - 'MAIL': '/var/mail/cltbld', 15:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:29 INFO - 'MOZ_NO_REMOTE': '1', 15:08:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:29 INFO - 'NO_EM_RESTART': '1', 15:08:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:29 INFO - 'PWD': '/builds/slave/test', 15:08:29 INFO - 'SHELL': '/bin/bash', 15:08:29 INFO - 'SHLVL': '1', 15:08:29 INFO - 'TERM': 'linux', 15:08:29 INFO - 'TMOUT': '86400', 15:08:29 INFO - 'USER': 'cltbld', 15:08:29 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:29 INFO - '_': '/tools/buildbot/bin/python'} 15:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:08:29 INFO - Running setup.py (path:/tmp/pip-jso39w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:08:29 INFO - Running setup.py (path:/tmp/pip-M4MQUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:08:29 INFO - Running setup.py (path:/tmp/pip-b_MEeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:08:29 INFO - Running setup.py (path:/tmp/pip-s9kzo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:08:29 INFO - Running setup.py (path:/tmp/pip-r5pwaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:08:29 INFO - Running setup.py (path:/tmp/pip-8obxtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:08:29 INFO - Running setup.py (path:/tmp/pip-AGzJde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:08:29 INFO - Running setup.py (path:/tmp/pip-X_NFGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:08:29 INFO - Running setup.py (path:/tmp/pip-I3cTiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:08:29 INFO - Running setup.py (path:/tmp/pip-qsdy2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:08:29 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)) 15:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:08:29 INFO - Running setup.py (path:/tmp/pip-mfLHsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:08:30 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)) 15:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:08:30 INFO - Running setup.py (path:/tmp/pip-Panmpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 15:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:08:30 INFO - Running setup.py (path:/tmp/pip-DncURj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:08:30 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)) 15:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:08:30 INFO - Running setup.py (path:/tmp/pip-UlNaYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:08:30 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)) 15:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:08:30 INFO - Running setup.py (path:/tmp/pip-Mvphfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:08:30 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)) 15:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:08:30 INFO - Running setup.py (path:/tmp/pip-QZ43Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:08:30 INFO - Running setup.py (path:/tmp/pip-ismujk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:08:30 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)) 15:08:30 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)) 15:08:30 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)) 15:08:30 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)) 15:08:30 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)) 15:08:30 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)) 15:08:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:08:30 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 15:08:30 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 15:08:30 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 15:08:30 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 15:08:30 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 15:08:30 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 15:08:32 INFO - Downloading blessings-1.6.tar.gz 15:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:08:32 INFO - Installing collected packages: blessings 15:08:32 INFO - Running setup.py install for blessings 15:08:32 INFO - Successfully installed blessings 15:08:32 INFO - Cleaning up... 15:08:32 INFO - Return code: 0 15:08:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:08:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:08:32 INFO - Reading from file tmpfile_stdout 15:08:32 INFO - Current package versions: 15:08:32 INFO - argparse == 1.2.1 15:08:32 INFO - blessings == 1.6 15:08:32 INFO - blobuploader == 1.2.4 15:08:32 INFO - docopt == 0.6.1 15:08:32 INFO - functools32 == 3.2.3-2 15:08:32 INFO - jsonschema == 2.5.1 15:08:32 INFO - manifestparser == 1.1 15:08:32 INFO - mozInstall == 1.12 15:08:32 INFO - mozcrash == 1.0 15:08:32 INFO - mozdebug == 0.1 15:08:32 INFO - mozdevice == 0.49 15:08:32 INFO - mozfile == 1.2 15:08:32 INFO - mozhttpd == 0.7 15:08:32 INFO - mozinfo == 0.9 15:08:32 INFO - mozleak == 0.1 15:08:32 INFO - mozlog == 3.4 15:08:32 INFO - moznetwork == 0.27 15:08:32 INFO - mozprocess == 0.25 15:08:32 INFO - mozprofile == 0.28 15:08:32 INFO - mozrunner == 6.13 15:08:32 INFO - mozscreenshot == 0.1 15:08:32 INFO - mozsystemmonitor == 0.3 15:08:32 INFO - moztest == 0.8 15:08:32 INFO - mozversion == 1.4 15:08:32 INFO - psutil == 3.1.1 15:08:32 INFO - requests == 1.2.3 15:08:32 INFO - wsgiref == 0.1.2 15:08:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:32 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:32 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 15:08:32 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 15:08:32 INFO - Using env: {'DISPLAY': ':0', 15:08:32 INFO - 'HOME': '/home/cltbld', 15:08:32 INFO - 'LANG': 'en_US.UTF-8', 15:08:32 INFO - 'LANGUAGE': 'en_US:en', 15:08:32 INFO - 'LOGNAME': 'cltbld', 15:08:32 INFO - 'MAIL': '/var/mail/cltbld', 15:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:32 INFO - 'MOZ_NO_REMOTE': '1', 15:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:32 INFO - 'NO_EM_RESTART': '1', 15:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:32 INFO - 'PWD': '/builds/slave/test', 15:08:32 INFO - 'SHELL': '/bin/bash', 15:08:32 INFO - 'SHLVL': '1', 15:08:32 INFO - 'TERM': 'linux', 15:08:32 INFO - 'TMOUT': '86400', 15:08:32 INFO - 'USER': 'cltbld', 15:08:32 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:32 INFO - '_': '/tools/buildbot/bin/python'} 15:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:33 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)) 15:08:33 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)) 15:08:33 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)) 15:08:33 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)) 15:08:33 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)) 15:08:33 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)) 15:08:33 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)) 15:08:33 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)) 15:08:33 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)) 15:08:33 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)) 15:08:33 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)) 15:08:33 INFO - Cleaning up... 15:08:33 INFO - Return code: 0 15:08:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:08:33 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')]} 15:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:33 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')]} 15:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:33 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 0x16d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1717a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1977310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', '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 15:08:33 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 15:08:33 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 15:08:33 INFO - Using env: {'DISPLAY': ':0', 15:08:33 INFO - 'HOME': '/home/cltbld', 15:08:33 INFO - 'LANG': 'en_US.UTF-8', 15:08:33 INFO - 'LANGUAGE': 'en_US:en', 15:08:33 INFO - 'LOGNAME': 'cltbld', 15:08:33 INFO - 'MAIL': '/var/mail/cltbld', 15:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:33 INFO - 'MOZ_NO_REMOTE': '1', 15:08:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:33 INFO - 'NO_EM_RESTART': '1', 15:08:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:33 INFO - 'PWD': '/builds/slave/test', 15:08:33 INFO - 'SHELL': '/bin/bash', 15:08:33 INFO - 'SHLVL': '1', 15:08:33 INFO - 'TERM': 'linux', 15:08:33 INFO - 'TMOUT': '86400', 15:08:33 INFO - 'USER': 'cltbld', 15:08:33 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:33 INFO - '_': '/tools/buildbot/bin/python'} 15:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:08:33 INFO - Cleaning up... 15:08:33 INFO - Return code: 0 15:08:33 INFO - Running post-action listener: _resource_record_post_action 15:08:33 INFO - Running post-action listener: _start_resource_monitoring 15:08:33 INFO - Starting resource monitoring. 15:08:33 INFO - [mozharness: 2017-04-12 22:08:33.414848Z] Finished create-virtualenv step (success) 15:08:33 INFO - [mozharness: 2017-04-12 22:08:33.415362Z] Running install step. 15:08:33 INFO - Running pre-action listener: _resource_record_pre_action 15:08:33 INFO - Running main action method: install 15:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:08:33 INFO - Reading from file tmpfile_stdout 15:08:33 INFO - Detecting whether we're running mozinstall >=1.0... 15:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:08:33 INFO - Reading from file tmpfile_stdout 15:08:33 INFO - Output received: 15:08:33 INFO - Usage: mozinstall [options] installer 15:08:33 INFO - Options: 15:08:33 INFO - -h, --help show this help message and exit 15:08:33 INFO - -d DEST, --destination=DEST 15:08:33 INFO - Directory to install application into. [default: 15:08:33 INFO - "/builds/slave/test"] 15:08:33 INFO - --app=APP Application being installed. [default: firefox] 15:08:33 INFO - mkdir: /builds/slave/test/build/application 15:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:08:50 INFO - Reading from file tmpfile_stdout 15:08:50 INFO - Output received: 15:08:50 INFO - /builds/slave/test/build/application/firefox/firefox 15:08:50 INFO - Running post-action listener: _resource_record_post_action 15:08:50 INFO - [mozharness: 2017-04-12 22:08:50.296364Z] Finished install step (success) 15:08:50 INFO - [mozharness: 2017-04-12 22:08:50.296639Z] Running run-tests step. 15:08:50 INFO - Running pre-action listener: _resource_record_pre_action 15:08:50 INFO - Running main action method: run_tests 15:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:08:50 INFO - Python 2.7.3 15:08:50 INFO - Return code: 0 15:08:50 INFO - grabbing minidump binary from tooltool 15:08:50 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')]} 15:08:50 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 0x1974400>, '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 0x19753b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1975840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:08:50 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 15:08:50 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 15:08:50 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 15:08:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:08:50 INFO - Return code: 0 15:08:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:08:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:08:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:08:50 INFO - ENV: RUST_BACKTRACE is now 1 15:08:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:08:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 15:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 15:08:50 INFO - Using env: {'DISPLAY': ':0', 15:08:50 INFO - 'HOME': '/home/cltbld', 15:08:50 INFO - 'LANG': 'en_US.UTF-8', 15:08:50 INFO - 'LANGUAGE': 'en_US:en', 15:08:50 INFO - 'LOGNAME': 'cltbld', 15:08:50 INFO - 'MAIL': '/var/mail/cltbld', 15:08:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:08:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:50 INFO - 'MOZ_NO_REMOTE': '1', 15:08:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:50 INFO - 'NO_EM_RESTART': '1', 15:08:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:50 INFO - 'PWD': '/builds/slave/test', 15:08:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:08:50 INFO - 'RUST_BACKTRACE': '1', 15:08:50 INFO - 'SHELL': '/bin/bash', 15:08:50 INFO - 'SHLVL': '1', 15:08:50 INFO - 'TERM': 'linux', 15:08:50 INFO - 'TMOUT': '86400', 15:08:50 INFO - 'USER': 'cltbld', 15:08:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118', 15:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:50 INFO - '_': '/tools/buildbot/bin/python'} 15:08:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 15:08:50 INFO - mozversion application_buildid: 20170412205010 15:08:50 INFO - mozversion application_changeset: cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 15:08:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:08:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:08:50 INFO - mozversion application_name: Firefox 15:08:50 INFO - mozversion application_remotingname: firefox-dev 15:08:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:08:50 INFO - mozversion application_vendor: Mozilla 15:08:50 INFO - mozversion application_version: 54.0a2 15:08:50 INFO - mozversion platform_buildid: 20170412205010 15:08:50 INFO - mozversion platform_changeset: cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 15:08:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:08:50 INFO - mozversion platform_version: 54.0a2 15:08:50 INFO - using testdate: 1492034930 15:08:50 INFO - actual date: 1492034930 15:08:50 INFO - starting webserver on 'localhost:57421' 15:08:50 INFO - SUITE-START | Running 2 tests 15:08:50 INFO - TEST-START | dromaeo_css 15:08:50 INFO - operating with platform_type : linux_ 15:08:50 INFO - Initialising browser for dromaeo_css test... 15:08:50 INFO - TEST-INFO | started process 30138 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ0A6fT/profile http://localhost:57421/getInfo.html) 15:08:54 INFO - PROCESS | 30138 | __metrics Screen width/height:1600/1200 15:08:54 INFO - PROCESS | 30138 | colorDepth:24 15:08:54 INFO - PROCESS | 30138 | Browser inner width/height: 1024/697 15:08:54 INFO - PROCESS | 30138 | __metrics 15:08:55 INFO - PROCESS | 30138 | 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" 15:08:55 INFO - PROCESS | 30138 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:08:55 INFO - PROCESS | 30138 | console.error: PushService: 15:08:55 INFO - PROCESS | 30138 | stateChangeProcessEnqueue: Error transitioning state 15:08:55 INFO - PROCESS | 30138 | UnknownError 15:08:56 INFO - TEST-INFO | 30138: exit 0 15:08:56 INFO - Browser initialized. 15:08:56 INFO - Running cycle 1/1 for dromaeo_css test... 15:08:56 INFO - TEST-INFO | started process 30226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ0A6fT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:09:08 INFO - PROCESS | 30226 | RSS: Main: 139599872 15:09:08 INFO - PROCESS | 30226 | 15:12:09 INFO - PROCESS | 30226 | Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57421/tests/dromaeo/cssquery-ext.html) 15:12:11 INFO - PROCESS | 30226 | RSS: Main: 276836352 15:12:11 INFO - PROCESS | 30226 | 15:13:58 INFO - PROCESS | 30226 | ************************* 15:13:58 INFO - PROCESS | 30226 | A coding exception was thrown and uncaught in a Task. 15:13:58 INFO - PROCESS | 30226 | 15:13:58 ERROR - PROCESS | 30226 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:13:58 INFO - PROCESS | 30226 | Full stack: 15:13:58 INFO - PROCESS | 30226 | ************************* 15:13:58 INFO - PROCESS | 30226 | ************************* 15:13:58 INFO - PROCESS | 30226 | A coding exception was thrown and uncaught in a Task. 15:13:58 INFO - PROCESS | 30226 | 15:13:58 ERROR - PROCESS | 30226 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:13:58 INFO - PROCESS | 30226 | Full stack: 15:13:58 INFO - PROCESS | 30226 | ************************* 15:13:58 INFO - PROCESS | 30226 | ************************* 15:13:58 INFO - PROCESS | 30226 | A coding exception was thrown and uncaught in a Task. 15:13:58 INFO - PROCESS | 30226 | 15:13:58 ERROR - PROCESS | 30226 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:13:58 INFO - PROCESS | 30226 | Full stack: 15:13:58 INFO - PROCESS | 30226 | ************************* 15:13:58 INFO - PROCESS | 30226 | ************************* 15:13:58 INFO - PROCESS | 30226 | A coding exception was thrown and uncaught in a Task. 15:13:58 INFO - PROCESS | 30226 | 15:13:58 ERROR - PROCESS | 30226 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:13:58 INFO - PROCESS | 30226 | Full stack: 15:13:58 INFO - PROCESS | 30226 | ************************* 15:15:13 INFO - PROCESS | 30226 | Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-ext.html (next: http://localhost:57421/tests/dromaeo/cssquery-jquery.html) 15:15:13 INFO - PROCESS | 30226 | RSS: Main: 216252416 15:15:13 INFO - PROCESS | 30226 | 15:18:15 INFO - PROCESS | 30226 | Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57421/tests/dromaeo/cssquery-mootools.html) 15:18:16 INFO - PROCESS | 30226 | RSS: Main: 169922560 15:18:16 INFO - PROCESS | 30226 | 15:21:18 INFO - PROCESS | 30226 | Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57421/tests/dromaeo/cssquery-prototype.html) 15:21:18 INFO - PROCESS | 30226 | RSS: Main: 171036672 15:21:18 INFO - PROCESS | 30226 | 15:24:21 INFO - PROCESS | 30226 | Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57421/tests/dromaeo/cssquery-yui.html) 15:24:22 INFO - PROCESS | 30226 | RSS: Main: 173359104 15:24:22 INFO - PROCESS | 30226 | 15:27:24 INFO - PROCESS | 30226 | Cycle 1(1): loaded http://localhost:57421/tests/dromaeo/cssquery-yui.html (next: http://localhost:57421/tests/dromaeo/cssquery-dojo.html) 15:27:24 INFO - PROCESS | 30226 | RSS: Main: 263610368 15:27:24 INFO - PROCESS | 30226 | 15:27:24 INFO - PROCESS | 30226 | __start_tp_report 15:27:24 INFO - PROCESS | 30226 | _x_x_mozilla_page_load 15:27:24 INFO - PROCESS | 30226 | _x_x_mozilla_page_load_details 15:27:24 INFO - PROCESS | 30226 | |i|pagename|runs| 15:27:24 INFO - PROCESS | 30226 | |0;dojo.html;3380.5605271314735;3386.2888793353395;3400.2349471368943;3410.403179443597;3437.9656203437976;5677.545796336293;5728.513076388512;5730.025895597748;5736.741846616902;5749.310082790061;5746.339170995337;5806.216160818289;5808.709564521768;5810.273715785527;5815.360310365856;5115.155999260118;5147.433782283946;5148.407933087687;5175.068487672217;5221.921671174935;5010.3737032870895;5021.899562008758;5229.084910140729;5240.633155679104;5274.92087618686;5075.187969924813;5129.871753206163;5193.195054766498;5193.896122077556;5194.168932970725;5223.164293712987;5223.2165175223645;5224.08578498765;5225.372955245357;5252.395974462947;4918.114739346916;5010.123228435009;5120.052790233819;5124.487551244845;5187.662801917863;5622.072358060915;5633.126865335879;5678.801241956553;5701.657900525982;5703.429657034305;6139.907901381483;6175.475084617813;6187.443130118311;6194.5044396448175;6202.007678771375;6002.8499287517725;6279.183706118221;6305.306416294204;6313.210848643919;6318.4313411793155;5702.65784052954;5721.799737009226;5750.338711048241;5823.94176058234;5843.9773039717875;6251.843703907439;6283.05754136874;6284.685765711696;6286.937130628727;6314.747410103636;6027.9095813562835;6082.513398928075;6091.02543593023;6093.878122437526;6100;5880.117982302603;5923.17075609415;6097.054956481752;6104.877902441926;6139.26328840542;6132.122542660378;6175.3515880832765;6176.81469555914;6181.258249010058;6183.5671502994355;5954.49386802118;5997.100434934795;5997.640141591519;6074.51403887688;6190.350013248634;4632.791524381411;4758.5241475852135;4764.4282686077895;4767.141914455431;4769.30845027476;4090.9181816363507;4105.507339119325;4198.265303571863;4212.4523811904255;4220.15596880619;5963.761449541969;5996.220491336098;6041.909371359346;6043.335233124353;6055.697215139314;6261.9373806262265;6288.402601752826;6290.025046117859;6297.527685423612;6306.716197771111;5779.450952159538;5990.4908082813445;6004.729787159588;6008.969955150284;6035.456808887134;5197.506236907488;5203.869903252449;5244.921326180111;5262.473752624707;5304.681719096867;6395.42441180296;6398.776042838572;6400.807975760735;6400.935990640127;6407.839804004937;6120.265568131853;6124.142620033288;6172.598781079395;6174.25908890936;6184.845378865384;6120.204373431426;6162.876742465036;6244.750209991731;6336.081268216175;6399.232092148972;6114.844865410725;6128.350350989301;6133.701927635063;6139.124180917401;6140.28953073628;6247;6249.093881387264;6260.436560709558;6263.718132684131;6265.718042687999;5844.532437405082;5904.350521442552;5963.194968679173;6053.727582258721;6110.877782444415;6014.0678194879865;6038.456538911519;6064.545159112996;6067.878642427215;6094.878102437838;5970.134330522137;6025.578209525291;6025.849353766191;6026.306974697791;6052.636841789506;5878.911816322673;5911.349751527415;5914.526837852875;6000.369961154016;6086.269647642312;5986.970065149646;5987.760489580368;5999.130126131774;6001.639901605918;6015.909761353496 15:27:24 INFO - PROCESS | 30226 | |1;ext.html;768.3392282636935;838.9958050209748;861.3927181337156;923.1553128887065;940.9764755881106;31762.68237317626;43793;44997.999999999956;45158.774206128925;45232.3215151773;41322.79338603303;41472;41574.7921260394;45167;45259.32111018337;7833.882491762628;9025.413348132366;9170.082991700843;9170.358074934757;9170.954145229265;7843.294103530681;10528.368297902112;10722.142228621731;10731.731706707316;10756.623518176866;10789.676309710721;10809.243352965295;10812.189085818594;10822.404767737771;10826.1880358973;10746.785064298709;10757.515911783988;10774.245802793808;10787.892121078767;10793.784124317548;10552.314099583502;10591.152707783358;10598.364098154136;10605;10609.946950265277;41717.58282417167;41911.37132943008;41936.790316048224;42021.1595768086;42039;4146.523149837777;4164.4586203793315;4196.811143498519;4246.363045543162;4276.572342765729;30150.24624384386;30441.23896902595;30463;30784.692153078406;30790.846045769853;4272.188284226018;4360.6293465055505;4373.890652733675;4398.208322501949;4469;223579;223623;223676;224235;224408;51529.22706159411;51752;51780.22329665058;51876;51944.74027629838;56036;56171.71914140403;56362.71818640963;56393;56422.71788641113;16644.50066498007;16973.660526789394;17001;17016;17022.82977170237;13125.278109703868;13303.600891973256;14970.70058598822;14971.550853474413;14971.625709357135;13558.728825423435;13892.791608125886;14241.715165696629;14256.35846386915;14327.856721432861;5605.439456054443;5658.377578466366;5670.149477578314;5780.826575202751;5814.273215848019;1011.0698157694939;1027.0397178637998;1029.5161274201114;1030.4384110659707;1038.159091136182;28018.85990570034;28106.859465702542;28520.429591408472;28530;28764.568531472043;1092.1644941619668;1096.5021581156086;1097.433341639671;1102.3330884814573;1114.1755101225197;14143.787843182361;15077.869159813057;15303;15303.234838258266;15443.84556154424;293522;294027;295090;295274;295803;54250.18624720632;54504;54517.454825452034;54537.181942270894;54545.72727136418;1515.772634104893;1525.435588832117;1552.697224041301;1558.9688206235812;1559.4619856149611;26007.739922600533;27212.863935680194;27416.86291568609;27618.309542261602;27702.584461233502;40756.79621601873;40953.1809363817;41067.383989239584;41175.58824411718;41288.38067429051;257836;259387;259806;260333;261008;29542.261443464085;31485.370292594478;35334.82332588424;35347.46978795371;35731.285374292886;42976.119402985074;44618.48310517757;44793;45457;45779.084418310784;34022;34151;34339.31321373609;34427.483587746705;34553.30893382068;33296.33407331888;33737.15657108494;33751;33789.15527111842;33853.49219761755;35674.46488302626;36410.63589364073;38971.41542876802;39414.2117157661;39704;29370.853145734134;29649.703502964694;29655.85172074126;30071.548926766554;30456.695433045385;24931;25152.49695006126;25237.369065773502;25349.873250633627;25671.358216044748 15:27:24 INFO - PROCESS | 30226 | |2;jquery.html;447.3513405561932;462.6622565527164;463.4508107892148;466.49829148714895;484.70917449530276;19505;21013.159473621057;21176.894115529438;21446.14215431381;21831.781682183173;23961.76038239617;24284.392890177754;24429.26712198637;25465.87267063667;25760.35599110023;6927.688254028556;7065.858682826341;7232.168300645414;7262.709491620329;7270.963645181767;6958.756443524452;7125.1806042305025;7162.713491460361;7261.636918154098;7263.527870688415;7163;7164.677589508458;7167.0682811234465;7170.713171473135;7171.462140339469;6929.1684997800085;7253.347198752112;7280.3083707047745;7293.015442915218;7299.963500182465;6889.862202755967;7077.610731409771;7113.359797618239;7147.106611673541;7153.928460715379;28633.427331453255;29124.126276211748;29343.2664183395;29505;29720;3245.2211469247445;3341.5655964724674;3352.7317814574776;3418;3419.4870769384543;17302;18158.364457244064;18301.08494575279;18473.2610695572;18738.906305468387;2309.1802410144323;2378.060666036923;2402.627592723113;2432.027189124347;2569.961450578243;106551.46724266406;111313.44343278313;111456;112097.43951280272;115361;21140;21522.246721364678;21580.784192157877;21865.344039678836;22012.339629811133;40857.59142408597;41171.58828411737;41203.381949270784;41335.58664413378;41396.1720765583;11693.707657308636;11782.587609433629;11787.823182652268;11836.289822610672;12186.75626487483;9692.806143877084;9768.36505627132;9815.950920245354;9960;9971.900280997243;10651.242544979992;11117.332960022424;11412.486438110305;11424.771504570028;11502.59740909081;3699.8150092495266;3879.153294435324;3975.5626881043063;4200.705950583491;4311.568843115726;961.2598299309632;972;973.7273563402259;977.5307852230969;992.7617371830709;20902.477438063866;22212;22575.404533960012;22863.657045144337;22914;1089.760252744395;1095.649392194506;1098.4672433869566;1113.3932007056173;1119.8376235445814;11315.321080735182;11372.715682108013;11526.020288275591;11631.76736465283;11709.355985420712;666792;685098;697175;715571;717844;72358.63820680862;119837;119979;121756;124103.379483102;1276.7765641012786;1407.7466056109793;1455.657920388708;1457.4097490516358;1470.867621914033;18770.436886893414;18983.810161898204;19031.42905712831;19073;19096.90451547789;51677.34339140709;52077.7396113017;52367.73816131047;52605;53029.7348513255;69067;69534.30465695278;69830;70246.29753702602;70249.64875175795;9543.618255269712;9754.414735115915;12401.13192076546;12431.94328482089;12619.30593817349;13446.46214151423;13882.791758123434;13999.370028348545;14002.229877356436;14082.225477598831;11631.302121872377;12330.938345308216;12451.688707782378;12451.75096498083;12553.874461255271;10667.626633068116;10670.359778413009;10866.565337386732;10916.50875710579;11110.16673749456;12474.18917770342;12482.313472758722;12508.687282818002;12557.686057848627;12640.43118059708;12397.75204495923;12513.749725005631;12555.183913045988;12644.557440489538;12854.293013884326;11931.224470409396;12074.215176013531;12126.575569855013;12135.696607584881;12453.128281020605 15:27:24 INFO - PROCESS | 30226 | |3;mootools.html;1578.0925967568646;1578.6605879735855;1600.2638786158366;1613.411104946694;1618.66817302453;11192.10463162947;11921.284722916631;11935.164538482299;11935.761284774298;11939.880601194007;11033.282836615614;11144.777104457906;11209.047230985376;11320.094392448606;11357.091432685385;2454.165583701541;2487.3657217409536;2491.0409492345398;2492.8753562321904;2498.3129639349236;2404.4950560382254;2420.6490058941445;2460.6432067350224;2465.0509553821817;2466.2724496273554;2446.070493212577;2461.913833015845;2470.468849197422;2510.497900419914;2513.472170844125;2324.883755812211;2334;2344.6600242964846;2353.3528279723128;2434.780869721716;2353.8940747666397;2365.5860224460653;2371.3834403055157;2376.7742064503846;2383.9523209535887;11641.243319184226;11852.644420667393;11854.3480108594;11858.407079645984;11860.169788114748;5875.059990401515;6036.758529658809;6039.3960603939695;6039.818805435844;6047.21386219793;3405.9489107663403;3528.6647768461953;3538.5222994895616;3539.009077458316;3581.033121057326;1253.216739537789;1301.134745394321;1319.4656164253493;1370.7806750919904;1392.1368751374112;15208.08751474915;15610.843891561053;16424.260908259155;17221.397251096278;17415.042172680496;4826.831060912851;4973.358791598764;5000.349954505891;5002.224655178453;5008.6744361616375;13639.590812275648;13694;13704.794428083587;13717;13725.656858578615;4921.876953076202;5089.618278629117;5921.644701317849;6090.177825993434;6112.938870611237;5325.547328477045;5447.319085114361;5530.695811730313;5666.34836993751;5669.886602267931;4265;4268.573142685768;4388.144311859157;4430.401895744033;4448.110377924428;1159.6173262823247;1193.6538403862783;1209.1958847366402;1210.9152359334762;1213.1023042948332;1094.6523218099846;1122.4892673833385;1132.2470557079614;1133.620237220524;1168.053876360151;7649.158592554814;7801.53190808542;7860.724874629361;7870.724524641724;7994;1075.5536452372335;1101;1108.5953626926114;1109.678193323943;1134.9886501135047;4801.159797035505;4882.902341953141;4993.975030124899;5019.372578427697;5072.655217965684;14999.250037498081;15241.23793810305;15302.464413745465;15448.922755386377;15485.458008969632;14724.631884202981;15069.17119558413;15267.236638168048;15484.690306194037;15495.535133946;11014.118870490342;11034.834477482844;11111.499982500807;11147.609833655946;11222.831657525143;7667.501612395176;7688.692452302069;7766.223377662414;7815.25755053269;7914.920850791418;11329.660110196708;13396.866031339561;13458.93270533641;13481.460741570227;13547.93226033903;12946.546870859474;13482.93258533701;13641.727165456834;13682.179069255877;13819.309034548434;10143.7464063399;10292.536835842555;10341.914099019494;10366.792664146826;10368.42973636427;11150.219484635998;11277.548898044315;11344.489497972776;11366.261193022758;11460.426978650867;9436.292278079309;9436.858447123435;9508.429494230368;9616.95191524038;9752.414855108716;9531.189848862925;9602.471864047266;9679.467629280458;9856.063673950963;9869.407835529892;8382.706605268786;8394.11861754507;8432.367572432215;8474.491530508189;8491.363147764067;6374.426301632875;6430.582012169194;6525.412712855866;6534.346565343618;6632.535722499571;6837.965810171117;6858;6933.930660693329;7064.32888875556;7129.643517823984 15:27:24 INFO - PROCESS | 30226 | |4;prototype.html;410.67761806981525;430.43107871807246;432.8593207207657;433.60325302348343;436.9563043695631;5134.743262836857;5200.557952574031;5249.973750131248;5263.236815666746;5280.551153151977;5018.774155163017;5131.307273518074;5145.176771716526;5249.4041581516685;5287.788488460457;4311.72804022814;4329.805158767847;4330.935035974463;4335.804888780012;4344.326629372453;4332.805023773938;4333.1766964276685;4346.78266086696;4351.891202719926;4367.563243675639;4136.27615167346;4384.408104905829;4396;4402.911941761163;4416.646668266547;4305.526392096866;4320.56794320567;4337.97831010846;4349.891252718676;4355.651547876162;4074.3888416737727;4097.8565750198895;4101.282275601788;4106.897327566835;4111.835526578933;5094.414142373637;5099.566536844335;5110.693358398471;5119.38567371914;5135.948640513584;4904.141775189328;5053.84838454847;5088.338515992898;5091.5489085610425;5156.484351564814;5479.150731636563;5487.368952570466;5499.7250137492965;5548.335499350179;5568.470995255485;4398.186335527937;4635.513271106553;4657.930131048037;4661.440627124767;4667.22990706533;5512.889742205173;5540.556755459594;5563.885723425113;5744.712764361765;5758.510526605243;4581.427321584802;4641.303804429363;4648.418947631546;4652;4660.650451216173;4751.8574442766085;4753.619710423227;4756.429228492534;4756.976215118972;4757.881052973703;4889.462159162489;4993.7503124843615;5004.099262132779;5011.824586139468;5176.922346164811;5433.945660543423;5455.645383050089;5479.397266300703;5490.615656903979;5497.9828731684765;1650.315119225532;1659.4109091272667;1666.3834381279005;1671.7993840739189;1676.8764927498605;1218.074263559701;1274.3118715893313;1286.2668279080833;1287.3177216075494;1293.9870601293867;5347.545458635982;5357.357117145967;5372.274742909735;5383.865403364947;5416.539594134464;5620.4660557247;5630.943690563124;5633.183188437653;5648.124540696199;5663.943360566424;5117.401842938806;5327.573794096463;5367.758450869642;5409.404965453797;5417.404085550586;2949.705029497033;2957.3050333171686;2964.6659003448535;2966.487091583308;2966.569847372119;5488.506034456838;5661.433856614306;5782.45066718661;5822.806324703426;5826.242588463558;5675.148727690879;5716.828495145069;5722.310514058469;5736.368999410061;5748.798792042258;5757.65454072757;5767.740451679769;5768.769249229984;5780;5801.82594522165;5348.1442969124755;5572.582056345709;5607.41122182165;5609.663420194638;5664.235328230636;5305.65513241638;5484.243293344072;5547.916781248204;5561.332640083216;5579.972100139636;5288.338957630296;5603.495685388335;5611.232461774691;5612.719364031864;5631;4588.810776510293;4650.674452788272;4652.185867473111;4671.925457144807;4694.201985662514;5480.424555421623;5615.971920140537;5650.796189486022;5662.292213473316;5676.489115979582;5449.945500545103;5484.2047903053035;5488.9725551371985;5494.791145947885;5518.6755178756;5468.316460442445;5469.917951230815;5484.204790305464;5527.281453411031;5538.56608902483;5263.131583288868;5302.072137375867;5419.430959749169;5434.891302173855;5455.781768729206;5431.619786614899;5461.863453413696;5467.402983582766;5469.9179512306555;5504.7155430495895;5123.539791159502;5378.247045413567;5397.8110766123;5402.540784033401;5443.319585051869 15:27:24 INFO - PROCESS | 30226 | |5;yui.html;856.5246288309985;893.9284857211424;911.7629416351749;918.228687902162;960.5485421851728;16031.4388996385;17417.303307867685;17520.64958700825;17657;17662.558436039104;15260.465883694073;15344.539663810076;15375.615609609737;15383.307751151196;15437.92281038596;683.9726410943581;707.3492387003969;845.2350622686479;852.3777642321124;882.6734108379908;879.7668617816283;886.5168483176652;887.2502735188788;888.0497867282011;889.6752685269862;934.4766930518897;936.2884208001933;936.8313703533395;938.8920274168489;938.9154976052154;707.5153519838901;726.564061563058;726.6584705188579;727.4980263618087;929.7675581104724;645.2676212498801;670.6747227594612;674.0765151742096;674.5480528046221;675.2707076357522;16620.919640223347;17071.829281707272;17140.31438742449;17160.57098572533;17539.298428062863;14590;14806.70386592273;15186.620334491616;15369.692606147815;15546.689066218725;14910.0129015547;15036.248187590576;15400.460983865623;15467.92266038674;15837.732981361463;4848.660593758438;4874.122657921573;4878.121938051149;4914.754262286871;4980.726060066695;81532.59233703853;83906;84509.57745211296;85283;86120;12510.249385036926;12549.937250313873;12588.811167832308;12656.303903285223;13265.734685306239;22751.886240569023;22852.542949140923;22923.31230063101;23045.308640740805;23215;8101.432899696964;8291.917080829235;8354.8329033419;8393.328533717287;8521.10528394522;4518.909621807546;4595.793189306489;4614.907701845944;4642.582167604932;4662.557057079572;3605.0446631642635;3671;3687.1888184599347;3694.353488139565;3712.2575484903127;587.9441453061952;589.593180705312;594.8423667728056;599.8872092268866;602.2592211579781;770.6300975531664;788.0779487999055;803.216863558038;821.37575442664;823.0452674897091;10090.899091008996;10570.947145264225;10692.358458492516;10960.342379457257;11174.38540880243;612.5834432585884;619.7613918641265;631.6967855429419;632.5983000794529;635.5773410681845;5098.796048158107;5231.293775340357;5232.189010703347;5283.339582552181;5306.363236411656;59420;71514.64242678753;76966.2136542343;77780;79969;69178;69635;71580.28419715737;71906.9213961791;72751.3624318782;62342.376576234565;63406.68296658487;63476;63708;63825.680871596276;10029.548670309707;10088.092071713581;10169.491525423824;10218.131458826083;10226.386416815016;43940.560594393646;44340.11319773522;44617.33074003957;45154.322685160405;45231.54768452273;41028.1794364117;51448.48551514437;52834.73582632062;53227.733861331995;53290;11166.553337866395;11233.157513186381;11359.375234361858;11360.943195283971;11512.30926144434;12800.16798908068;12881.613551593467;12883.613491595268;12926.159799612995;13050.608481745563;11762.82355764647;11786.646400607995;11789.823152652889;11887.524499020288;11949;12152.392380381123;12283;12559.686007849878;12633.418390142517;12636.873631263938;11509.539618415505;11729;11922;12218.877811221773;12228.449719762453;8231.547264900248;8306.62620182081;8538.573071346533;8684.305255579664;8776.034636189886;9791.46146961924;10335.896641033494;10356.430396328275;10422.583096676351;10424.68725938223 15:27:24 INFO - PROCESS | 30226 | __end_tp_report 15:27:24 INFO - PROCESS | 30226 | __start_cc_report 15:27:24 INFO - PROCESS | 30226 | _x_x_mozilla_cycle_collect,1365 15:27:24 INFO - PROCESS | 30226 | __end_cc_report 15:27:24 INFO - PROCESS | 30226 | __startTimestamp1492036044910__endTimestamp 15:27:24 INFO - PROCESS | 30226 | 15:27:24 INFO - PROCESS | 30226 | ------- Summary: start ------- 15:27:24 INFO - PROCESS | 30226 | Number of tests: 6 15:27:24 INFO - PROCESS | 30226 | 15:27:24 INFO - PROCESS | 30226 | [#0] dojo.html Cycles:165 Average:5720.92 Median:5997.64 stddev:660.57 (11.0%) stddev-sans-first:636.57 15:27:24 INFO - PROCESS | 30226 | Values: 3380.6 3386.3 3400.2 3410.4 3438.0 5677.5 5728.5 5730.0 5736.7 5749.3 5746.3 5806.2 5808.7 5810.3 5815.4 5115.2 5147.4 5148.4 5175.1 5221.9 5010.4 5021.9 5229.1 5240.6 5274.9 5075.2 5129.9 5193.2 5193.9 5194.2 5223.2 5223.2 5224.1 5225.4 5252.4 4918.1 5010.1 5120.1 5124.5 5187.7 5622.1 5633.1 5678.8 5701.7 5703.4 6139.9 6175.5 6187.4 6194.5 6202.0 6002.8 6279.2 6305.3 6313.2 6318.4 5702.7 5721.8 5750.3 5823.9 5844.0 6251.8 6283.1 6284.7 6286.9 6314.7 6027.9 6082.5 6091.0 6093.9 6100.0 5880.1 5923.2 6097.1 6104.9 6139.3 6132.1 6175.4 6176.8 6181.3 6183.6 5954.5 5997.1 5997.6 6074.5 6190.4 4632.8 4758.5 4764.4 4767.1 4769.3 4090.9 4105.5 4198.3 4212.5 4220.2 5963.8 5996.2 6041.9 6043.3 6055.7 6261.9 6288.4 6290.0 6297.5 6306.7 5779.5 5990.5 6004.7 6009.0 6035.5 5197.5 5203.9 5244.9 5262.5 5304.7 6395.4 6398.8 6400.8 6400.9 6407.8 6120.3 6124.1 6172.6 6174.3 6184.8 6120.2 6162.9 6244.8 6336.1 6399.2 6114.8 6128.4 6133.7 6139.1 6140.3 6247.0 6249.1 6260.4 6263.7 6265.7 5844.5 5904.4 5963.2 6053.7 6110.9 6014.1 6038.5 6064.5 6067.9 6094.9 5970.1 6025.6 6025.8 6026.3 6052.6 5878.9 5911.3 5914.5 6000.4 6086.3 5987.0 5987.8 5999.1 6001.6 6015.9 15:27:24 INFO - PROCESS | 30226 | 15:27:24 INFO - PROCESS | 30226 | [#1] ext.html Cycles:180 Average:43464.28 Median:30448.97 stddev:67839.48 (222.8%) stddev-sans-first:67954.04 15:27:24 INFO - PROCESS | 30226 | Values: 768.3 839.0 861.4 923.2 941.0 31762.7 43793.0 44998.0 45158.8 45232.3 41322.8 41472.0 41574.8 45167.0 45259.3 7833.9 9025.4 9170.1 9170.4 9171.0 7843.3 10528.4 10722.1 10731.7 10756.6 10789.7 10809.2 10812.2 10822.4 10826.2 10746.8 10757.5 10774.2 10787.9 10793.8 10552.3 10591.2 10598.4 10605.0 10609.9 41717.6 41911.4 41936.8 42021.2 42039.0 4146.5 4164.5 4196.8 4246.4 4276.6 30150.2 30441.2 30463.0 30784.7 30790.8 4272.2 4360.6 4373.9 4398.2 4469.0 223579.0 223623.0 223676.0 224235.0 224408.0 51529.2 51752.0 51780.2 51876.0 51944.7 56036.0 56171.7 56362.7 56393.0 56422.7 16644.5 16973.7 17001.0 17016.0 17022.8 13125.3 13303.6 14970.7 14971.6 14971.6 13558.7 13892.8 14241.7 14256.4 14327.9 5605.4 5658.4 5670.1 5780.8 5814.3 1011.1 1027.0 1029.5 1030.4 1038.2 28018.9 28106.9 28520.4 28530.0 28764.6 1092.2 1096.5 1097.4 1102.3 1114.2 14143.8 15077.9 15303.0 15303.2 15443.8 293522.0 294027.0 295090.0 295274.0 295803.0 54250.2 54504.0 54517.5 54537.2 54545.7 1515.8 1525.4 1552.7 1559.0 1559.5 26007.7 27212.9 27416.9 27618.3 27702.6 40756.8 40953.2 41067.4 41175.6 41288.4 257836.0 259387.0 259806.0 260333.0 261008.0 29542.3 31485.4 35334.8 35347.5 35731.3 42976.1 44618.5 44793.0 45457.0 45779.1 34022.0 34151.0 34339.3 34427.5 34553.3 33296.3 33737.2 33751.0 33789.2 33853.5 35674.5 36410.6 38971.4 39414.2 39704.0 29370.9 29649.7 29655.9 30071.5 30456.7 24931.0 25152.5 25237.4 25349.9 25671.4 15:27:24 INFO - PROCESS | 30226 | 15:27:24 INFO - PROCESS | 30226 | [#2] jquery.html Cycles:180 Average:39402.85 Median:22888.83 stddev:114525.30 (500.4%) stddev-sans-first:114809.22 15:27:24 INFO - PROCESS | 30226 | Values: 447.4 462.7 463.5 466.5 484.7 19505.0 21013.2 21176.9 21446.1 21831.8 23961.8 24284.4 24429.3 25465.9 25760.4 6927.7 7065.9 7232.2 7262.7 7271.0 6958.8 7125.2 7162.7 7261.6 7263.5 7163.0 7164.7 7167.1 7170.7 7171.5 6929.2 7253.3 7280.3 7293.0 7300.0 6889.9 7077.6 7113.4 7147.1 7153.9 28633.4 29124.1 29343.3 29505.0 29720.0 3245.2 3341.6 3352.7 3418.0 3419.5 17302.0 18158.4 18301.1 18473.3 18738.9 2309.2 2378.1 2402.6 2432.0 2570.0 106551.5 111313.4 111456.0 112097.4 115361.0 21140.0 21522.2 21580.8 21865.3 22012.3 40857.6 41171.6 41203.4 41335.6 41396.2 11693.7 11782.6 11787.8 11836.3 12186.8 9692.8 9768.4 9816.0 9960.0 9971.9 10651.2 11117.3 11412.5 11424.8 11502.6 3699.8 3879.2 3975.6 4200.7 4311.6 961.3 972.0 973.7 977.5 992.8 20902.5 22212.0 22575.4 22863.7 22914.0 1089.8 1095.6 1098.5 1113.4 1119.8 11315.3 11372.7 11526.0 11631.8 11709.4 666792.0 685098.0 697175.0 715571.0 717844.0 72358.6 119837.0 119979.0 121756.0 124103.4 1276.8 1407.7 1455.7 1457.4 1470.9 18770.4 18983.8 19031.4 19073.0 19096.9 51677.3 52077.7 52367.7 52605.0 53029.7 69067.0 69534.3 69830.0 70246.3 70249.6 9543.6 9754.4 12401.1 12431.9 12619.3 13446.5 13882.8 13999.4 14002.2 14082.2 11631.3 12330.9 12451.7 12451.8 12553.9 10667.6 10670.4 10866.6 10916.5 11110.2 12474.2 12482.3 12508.7 12557.7 12640.4 12397.8 12513.7 12555.2 12644.6 12854.3 11931.2 12074.2 12126.6 12135.7 12453.1 15:27:24 INFO - PROCESS | 30226 | 15:27:24 INFO - PROCESS | 30226 | [#3] mootools.html Cycles:180 Average:7358.05 Median:2368.48 stddev:4651.01 (196.4%) stddev-sans-first:4643.78 15:27:24 INFO - PROCESS | 30226 | Values: 1578.1 1578.7 1600.3 1613.4 1618.7 11192.1 11921.3 11935.2 11935.8 11939.9 11033.3 11144.8 11209.0 11320.1 11357.1 2454.2 2487.4 2491.0 2492.9 2498.3 2404.5 2420.6 2460.6 2465.1 2466.3 2446.1 2461.9 2470.5 2510.5 2513.5 2324.9 2334.0 2344.7 2353.4 2434.8 2353.9 2365.6 2371.4 2376.8 2384.0 11641.2 11852.6 11854.3 11858.4 11860.2 5875.1 6036.8 6039.4 6039.8 6047.2 3405.9 3528.7 3538.5 3539.0 3581.0 1253.2 1301.1 1319.5 1370.8 1392.1 15208.1 15610.8 16424.3 17221.4 17415.0 4826.8 4973.4 5000.3 5002.2 5008.7 13639.6 13694.0 13704.8 13717.0 13725.7 4921.9 5089.6 5921.6 6090.2 6112.9 5325.5 5447.3 5530.7 5666.3 5669.9 4265.0 4268.6 4388.1 4430.4 4448.1 1159.6 1193.7 1209.2 1210.9 1213.1 1094.7 1122.5 1132.2 1133.6 1168.1 7649.2 7801.5 7860.7 7870.7 7994.0 1075.6 1101.0 1108.6 1109.7 1135.0 4801.2 4882.9 4994.0 5019.4 5072.7 14999.3 15241.2 15302.5 15448.9 15485.5 14724.6 15069.2 15267.2 15484.7 15495.5 11014.1 11034.8 11111.5 11147.6 11222.8 7667.5 7688.7 7766.2 7815.3 7914.9 11329.7 13396.9 13458.9 13481.5 13547.9 12946.5 13482.9 13641.7 13682.2 13819.3 10143.7 10292.5 10341.9 10366.8 10368.4 11150.2 11277.5 11344.5 11366.3 11460.4 9436.3 9436.9 9508.4 9617.0 9752.4 9531.2 9602.5 9679.5 9856.1 9869.4 8382.7 8394.1 8432.4 8474.5 8491.4 6374.4 6430.6 6525.4 6534.3 6632.5 6838.0 6858.0 6933.9 7064.3 7129.6 15:27:24 INFO - PROCESS | 30226 | 15:27:24 INFO - PROCESS | 30226 | [#4] prototype.html Cycles:180 Average:4765.97 Median:5271.89 stddev:1259.19 (23.9%) stddev-sans-first:1219.55 15:27:24 INFO - PROCESS | 30226 | Values: 410.7 430.4 432.9 433.6 437.0 5134.7 5200.6 5250.0 5263.2 5280.6 5018.8 5131.3 5145.2 5249.4 5287.8 4311.7 4329.8 4330.9 4335.8 4344.3 4332.8 4333.2 4346.8 4351.9 4367.6 4136.3 4384.4 4396.0 4402.9 4416.6 4305.5 4320.6 4338.0 4349.9 4355.7 4074.4 4097.9 4101.3 4106.9 4111.8 5094.4 5099.6 5110.7 5119.4 5135.9 4904.1 5053.8 5088.3 5091.5 5156.5 5479.2 5487.4 5499.7 5548.3 5568.5 4398.2 4635.5 4657.9 4661.4 4667.2 5512.9 5540.6 5563.9 5744.7 5758.5 4581.4 4641.3 4648.4 4652.0 4660.7 4751.9 4753.6 4756.4 4757.0 4757.9 4889.5 4993.8 5004.1 5011.8 5176.9 5433.9 5455.6 5479.4 5490.6 5498.0 1650.3 1659.4 1666.4 1671.8 1676.9 1218.1 1274.3 1286.3 1287.3 1294.0 5347.5 5357.4 5372.3 5383.9 5416.5 5620.5 5630.9 5633.2 5648.1 5663.9 5117.4 5327.6 5367.8 5409.4 5417.4 2949.7 2957.3 2964.7 2966.5 2966.6 5488.5 5661.4 5782.5 5822.8 5826.2 5675.1 5716.8 5722.3 5736.4 5748.8 5757.7 5767.7 5768.8 5780.0 5801.8 5348.1 5572.6 5607.4 5609.7 5664.2 5305.7 5484.2 5547.9 5561.3 5580.0 5288.3 5603.5 5611.2 5612.7 5631.0 4588.8 4650.7 4652.2 4671.9 4694.2 5480.4 5616.0 5650.8 5662.3 5676.5 5449.9 5484.2 5489.0 5494.8 5518.7 5468.3 5469.9 5484.2 5527.3 5538.6 5263.1 5302.1 5419.4 5434.9 5455.8 5431.6 5461.9 5467.4 5469.9 5504.7 5123.5 5378.2 5397.8 5402.5 5443.3 15:27:24 INFO - PROCESS | 30226 | 15:27:24 INFO - PROCESS | 30226 | [#5] yui.html Cycles:180 Average:17677.71 Median:4861.39 stddev:22507.52 (463.0%) stddev-sans-first:22535.21 15:27:24 INFO - PROCESS | 30226 | Values: 856.5 893.9 911.8 918.2 960.5 16031.4 17417.3 17520.6 17657.0 17662.6 15260.5 15344.5 15375.6 15383.3 15437.9 684.0 707.3 845.2 852.4 882.7 879.8 886.5 887.3 888.0 889.7 934.5 936.3 936.8 938.9 938.9 707.5 726.6 726.7 727.5 929.8 645.3 670.7 674.1 674.5 675.3 16620.9 17071.8 17140.3 17160.6 17539.3 14590.0 14806.7 15186.6 15369.7 15546.7 14910.0 15036.2 15400.5 15467.9 15837.7 4848.7 4874.1 4878.1 4914.8 4980.7 81532.6 83906.0 84509.6 85283.0 86120.0 12510.2 12549.9 12588.8 12656.3 13265.7 22751.9 22852.5 22923.3 23045.3 23215.0 8101.4 8291.9 8354.8 8393.3 8521.1 4518.9 4595.8 4614.9 4642.6 4662.6 3605.0 3671.0 3687.2 3694.4 3712.3 587.9 589.6 594.8 599.9 602.3 770.6 788.1 803.2 821.4 823.0 10090.9 10570.9 10692.4 10960.3 11174.4 612.6 619.8 631.7 632.6 635.6 5098.8 5231.3 5232.2 5283.3 5306.4 59420.0 71514.6 76966.2 77780.0 79969.0 69178.0 69635.0 71580.3 71906.9 72751.4 62342.4 63406.7 63476.0 63708.0 63825.7 10029.5 10088.1 10169.5 10218.1 10226.4 43940.6 44340.1 44617.3 45154.3 45231.5 41028.2 51448.5 52834.7 53227.7 53290.0 11166.6 11233.2 11359.4 11360.9 11512.3 12800.2 12881.6 12883.6 12926.2 13050.6 11762.8 11786.6 11789.8 11887.5 11949.0 12152.4 12283.0 12559.7 12633.4 12636.9 11509.5 11729.0 11922.0 12218.9 12228.4 8231.5 8306.6 8538.6 8684.3 8776.0 9791.5 10335.9 10356.4 10422.6 10424.7 15:27:24 INFO - PROCESS | 30226 | -------- Summary: end -------- 15:27:24 INFO - PROCESS | 30226 | 15:27:25 INFO - TEST-INFO | 30226: exit 0 15:27:25 INFO - TEST-OK | dromaeo_css | took 1115092ms 15:27:25 INFO - TEST-START | kraken 15:27:25 INFO - operating with platform_type : linux_ 15:27:25 INFO - Initialising browser for kraken test... 15:27:25 INFO - TEST-INFO | started process 30553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpig3iLg/profile http://localhost:57421/getInfo.html) 15:27:29 INFO - PROCESS | 30553 | __metrics Screen width/height:1600/1200 15:27:29 INFO - PROCESS | 30553 | colorDepth:24 15:27:29 INFO - PROCESS | 30553 | Browser inner width/height: 1024/697 15:27:29 INFO - PROCESS | 30553 | __metrics 15:27:30 INFO - PROCESS | 30553 | 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" 15:27:30 INFO - PROCESS | 30553 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:27:31 INFO - TEST-INFO | 30553: exit 0 15:27:31 INFO - Browser initialized. 15:27:31 INFO - Running cycle 1/1 for kraken test... 15:27:31 INFO - TEST-INFO | started process 30642 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpig3iLg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:27:43 INFO - PROCESS | 30642 | RSS: Main: 140091392 15:27:43 INFO - PROCESS | 30642 | 15:27:45 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57421/tests/kraken/driver.html?testName=audio-beat-detection) 15:27:46 INFO - PROCESS | 30642 | RSS: Main: 182829056 15:27:46 INFO - PROCESS | 30642 | 15:27:49 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57421/tests/kraken/driver.html?testName=audio-dft) 15:27:49 INFO - PROCESS | 30642 | RSS: Main: 173948928 15:27:49 INFO - PROCESS | 30642 | 15:27:52 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57421/tests/kraken/driver.html?testName=audio-fft) 15:27:53 INFO - PROCESS | 30642 | RSS: Main: 266354688 15:27:53 INFO - PROCESS | 30642 | 15:27:55 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57421/tests/kraken/driver.html?testName=audio-oscillator) 15:27:56 INFO - PROCESS | 30642 | RSS: Main: 171917312 15:27:56 INFO - PROCESS | 30642 | 15:27:58 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57421/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:27:58 INFO - PROCESS | 30642 | RSS: Main: 175251456 15:27:58 INFO - PROCESS | 30642 | 15:28:01 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57421/tests/kraken/driver.html?testName=imaging-darkroom) 15:28:02 INFO - PROCESS | 30642 | RSS: Main: 213639168 15:28:02 INFO - PROCESS | 30642 | 15:28:05 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57421/tests/kraken/driver.html?testName=imaging-desaturate) 15:28:06 INFO - PROCESS | 30642 | RSS: Main: 212430848 15:28:06 INFO - PROCESS | 30642 | 15:28:08 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57421/tests/kraken/driver.html?testName=json-parse-financial) 15:28:09 INFO - PROCESS | 30642 | RSS: Main: 210907136 15:28:09 INFO - PROCESS | 30642 | 15:28:11 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57421/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:28:11 INFO - PROCESS | 30642 | RSS: Main: 173555712 15:28:11 INFO - PROCESS | 30642 | 15:28:13 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:28:13 INFO - PROCESS | 30642 | RSS: Main: 179642368 15:28:13 INFO - PROCESS | 30642 | 15:28:15 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:28:16 INFO - PROCESS | 30642 | RSS: Main: 190017536 15:28:16 INFO - PROCESS | 30642 | 15:28:19 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:28:19 INFO - PROCESS | 30642 | RSS: Main: 198856704 15:28:19 INFO - PROCESS | 30642 | 15:28:22 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:28:23 INFO - PROCESS | 30642 | RSS: Main: 196059136 15:28:23 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | Cycle 1(1): loaded http://localhost:57421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57421/tests/kraken/driver.html?testName=ai-astar) 15:28:24 INFO - PROCESS | 30642 | RSS: Main: 246534144 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | __start_tp_report 15:28:24 INFO - PROCESS | 30642 | _x_x_mozilla_page_load 15:28:24 INFO - PROCESS | 30642 | _x_x_mozilla_page_load_details 15:28:24 INFO - PROCESS | 30642 | |i|pagename|runs| 15:28:24 INFO - PROCESS | 30642 | |0;ai-astar;107;112;113;113;112;113;111;112;113;110 15:28:24 INFO - PROCESS | 30642 | |1;audio-beat-detection;151;115;120;155;115;114;117;114;133;115 15:28:24 INFO - PROCESS | 30642 | |2;audio-dft;164;160;162;160;160;162;162;161;163;162 15:28:24 INFO - PROCESS | 30642 | |3;audio-fft;75;75;75;109;75;75;76;77;89;75 15:28:24 INFO - PROCESS | 30642 | |4;audio-oscillator;81;81;106;81;82;81;95;81;82;101 15:28:24 INFO - PROCESS | 30642 | |5;imaging-gaussian-blur;113;112;113;113;114;113;113;113;112;113 15:28:24 INFO - PROCESS | 30642 | |6;imaging-darkroom;145;147;146;146;146;146;145;146;145;146 15:28:24 INFO - PROCESS | 30642 | |7;imaging-desaturate;94;94;94;94;94;94;94;95;94;94 15:28:24 INFO - PROCESS | 30642 | |8;json-parse-financial;54;54;54;54;54;56;54;54;55;54 15:28:24 INFO - PROCESS | 30642 | |9;json-stringify-tinderbox;54;55;54;52;57;53;53;53;53;53 15:28:24 INFO - PROCESS | 30642 | |10;stanford-crypto-aes;85;82;79;75;79;79;76;75;76;75 15:28:24 INFO - PROCESS | 30642 | |11;stanford-crypto-ccm;124;132;136;125;122;127;129;120;157;139 15:28:24 INFO - PROCESS | 30642 | |12;stanford-crypto-pbkdf2;163;163;159;162;163;160;160;158;158;164 15:28:24 INFO - PROCESS | 30642 | |13;stanford-crypto-sha256-iterative;63;62;60;63;61;65;60;60;62;58 15:28:24 INFO - PROCESS | 30642 | __end_tp_report 15:28:24 INFO - PROCESS | 30642 | __start_cc_report 15:28:24 INFO - PROCESS | 30642 | _x_x_mozilla_cycle_collect,1629 15:28:24 INFO - PROCESS | 30642 | __end_cc_report 15:28:24 INFO - PROCESS | 30642 | __startTimestamp1492036104982__endTimestamp 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | ------- Summary: start ------- 15:28:24 INFO - PROCESS | 30642 | Number of tests: 14 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:1.90 (1.7%) stddev-sans-first:1.05 15:28:24 INFO - PROCESS | 30642 | Values: 107.0 112.0 113.0 113.0 112.0 113.0 111.0 112.0 113.0 110.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:118.50 stddev:15.88 (13.4%) stddev-sans-first:13.76 15:28:24 INFO - PROCESS | 30642 | Values: 151.0 115.0 120.0 155.0 115.0 114.0 117.0 114.0 133.0 115.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.35 (0.8%) stddev-sans-first:1.12 15:28:24 INFO - PROCESS | 30642 | Values: 164.0 160.0 162.0 160.0 160.0 162.0 162.0 161.0 163.0 162.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#3] audio-fft Cycles:10 Average:80.10 Median:75.00 stddev:11.04 (14.7%) stddev-sans-first:11.55 15:28:24 INFO - PROCESS | 30642 | Values: 75.0 75.0 75.0 109.0 75.0 75.0 76.0 77.0 89.0 75.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:81.00 stddev:9.72 (12.0%) stddev-sans-first:10.06 15:28:24 INFO - PROCESS | 30642 | Values: 81.0 81.0 106.0 81.0 82.0 81.0 95.0 81.0 82.0 101.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:28:24 INFO - PROCESS | 30642 | Values: 113.0 112.0 113.0 113.0 114.0 113.0 113.0 113.0 112.0 113.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 15:28:24 INFO - PROCESS | 30642 | Values: 145.0 147.0 146.0 146.0 146.0 146.0 145.0 146.0 145.0 146.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:28:24 INFO - PROCESS | 30642 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 15:28:24 INFO - PROCESS | 30642 | Values: 54.0 54.0 54.0 54.0 54.0 56.0 54.0 54.0 55.0 54.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#9] json-stringify-tinderbox Cycles:10 Average:53.70 Median:53.50 stddev:1.42 (2.7%) stddev-sans-first:1.50 15:28:24 INFO - PROCESS | 30642 | Values: 54.0 55.0 54.0 52.0 57.0 53.0 53.0 53.0 53.0 53.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:79.00 stddev:3.38 (4.3%) stddev-sans-first:2.50 15:28:24 INFO - PROCESS | 30642 | Values: 85.0 82.0 79.0 75.0 79.0 79.0 76.0 75.0 76.0 75.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#11] stanford-crypto-ccm Cycles:10 Average:131.10 Median:130.50 stddev:10.92 (8.4%) stddev-sans-first:11.27 15:28:24 INFO - PROCESS | 30642 | Values: 124.0 132.0 136.0 125.0 122.0 127.0 129.0 120.0 157.0 139.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.00 Median:162.50 stddev:2.26 (1.4%) stddev-sans-first:2.28 15:28:24 INFO - PROCESS | 30642 | Values: 163.0 163.0 159.0 162.0 163.0 160.0 160.0 158.0 158.0 164.0 15:28:24 INFO - PROCESS | 30642 | 15:28:24 INFO - PROCESS | 30642 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:62.00 stddev:2.01 (3.2%) stddev-sans-first:2.05 15:28:24 INFO - PROCESS | 30642 | Values: 63.0 62.0 60.0 63.0 61.0 65.0 60.0 60.0 62.0 58.0 15:28:24 INFO - PROCESS | 30642 | -------- Summary: end -------- 15:28:24 INFO - PROCESS | 30642 | 15:28:25 INFO - TEST-INFO | 30642: exit 0 15:28:26 INFO - TEST-OK | kraken | took 60332ms 15:28:26 INFO - SUITE-END | took 1175s 15:28:26 INFO - Completed test suite (00:19:35) 15:28:26 INFO - javascript benchmark 15:28:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7849.613624388724, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3380.5605271314735, 3386.2888793353395, 3400.2349471368943, 3410.403179443597, 3437.9656203437976, 5677.545796336293, 5728.513076388512, 5730.025895597748, 5736.741846616902, 5749.310082790061, 5746.339170995337, 5806.216160818289, 5808.709564521768, 5810.273715785527, 5815.360310365856, 5115.155999260118, 5147.433782283946, 5148.407933087687, 5175.068487672217, 5221.921671174935, 5010.3737032870895, 5021.899562008758, 5229.084910140729, 5240.633155679104, 5274.92087618686, 5075.187969924813, 5129.871753206163, 5193.195054766498, 5193.896122077556, 5194.168932970725, 5223.164293712987, 5223.2165175223645, 5224.08578498765, 5225.372955245357, 5252.395974462947, 4918.114739346916, 5010.123228435009, 5120.052790233819, 5124.487551244845, 5187.662801917863, 5622.072358060915, 5633.126865335879, 5678.801241956553, 5701.657900525982, 5703.429657034305, 6139.907901381483, 6175.475084617813, 6187.443130118311, 6194.5044396448175, 6202.007678771375, 6002.8499287517725, 6279.183706118221, 6305.306416294204, 6313.210848643919, 6318.4313411793155, 5702.65784052954, 5721.799737009226, 5750.338711048241, 5823.94176058234, 5843.9773039717875, 6251.843703907439, 6283.05754136874, 6284.685765711696, 6286.937130628727, 6314.747410103636, 6027.9095813562835, 6082.513398928075, 6091.02543593023, 6093.878122437526, 6100.0, 5880.117982302603, 5923.17075609415, 6097.054956481752, 6104.877902441926, 6139.26328840542, 6132.122542660378, 6175.3515880832765, 6176.81469555914, 6181.258249010058, 6183.5671502994355, 5954.49386802118, 5997.100434934795, 5997.640141591519, 6074.51403887688, 6190.350013248634, 4632.791524381411, 4758.5241475852135, 4764.4282686077895, 4767.141914455431, 4769.30845027476, 4090.9181816363507, 4105.507339119325, 4198.265303571863, 4212.4523811904255, 4220.15596880619, 5963.761449541969, 5996.220491336098, 6041.909371359346, 6043.335233124353, 6055.697215139314, 6261.9373806262265, 6288.402601752826, 6290.025046117859, 6297.527685423612, 6306.716197771111, 5779.450952159538, 5990.4908082813445, 6004.729787159588, 6008.969955150284, 6035.456808887134, 5197.506236907488, 5203.869903252449, 5244.921326180111, 5262.473752624707, 5304.681719096867, 6395.42441180296, 6398.776042838572, 6400.807975760735, 6400.935990640127, 6407.839804004937, 6120.265568131853, 6124.142620033288, 6172.598781079395, 6174.25908890936, 6184.845378865384, 6120.204373431426, 6162.876742465036, 6244.750209991731, 6336.081268216175, 6399.232092148972, 6114.844865410725, 6128.350350989301, 6133.701927635063, 6139.124180917401, 6140.28953073628, 6247.0, 6249.093881387264, 6260.436560709558, 6263.718132684131, 6265.718042687999, 5844.532437405082, 5904.350521442552, 5963.194968679173, 6053.727582258721, 6110.877782444415, 6014.0678194879865, 6038.456538911519, 6064.545159112996, 6067.878642427215, 6094.878102437838, 5970.134330522137, 6025.578209525291, 6025.849353766191, 6026.306974697791, 6052.636841789506, 5878.911816322673, 5911.349751527415, 5914.526837852875, 6000.369961154016, 6086.269647642312, 5986.970065149646, 5987.760489580368, 5999.130126131774, 6001.639901605918, 6015.909761353496], "value": 5676.263291086524, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.3392282636935, 838.9958050209748, 861.3927181337156, 923.1553128887065, 940.9764755881106, 31762.68237317626, 43793.0, 44997.999999999956, 45158.774206128925, 45232.3215151773, 41322.79338603303, 41472.0, 41574.7921260394, 45167.0, 45259.32111018337, 7833.882491762628, 9025.413348132366, 9170.082991700843, 9170.358074934757, 9170.954145229265, 7843.294103530681, 10528.368297902112, 10722.142228621731, 10731.731706707316, 10756.623518176866, 10789.676309710721, 10809.243352965295, 10812.189085818594, 10822.404767737771, 10826.1880358973, 10746.785064298709, 10757.515911783988, 10774.245802793808, 10787.892121078767, 10793.784124317548, 10552.314099583502, 10591.152707783358, 10598.364098154136, 10605.0, 10609.946950265277, 41717.58282417167, 41911.37132943008, 41936.790316048224, 42021.1595768086, 42039.0, 4146.523149837777, 4164.4586203793315, 4196.811143498519, 4246.363045543162, 4276.572342765729, 30150.24624384386, 30441.23896902595, 30463.0, 30784.692153078406, 30790.846045769853, 4272.188284226018, 4360.6293465055505, 4373.890652733675, 4398.208322501949, 4469.0, 223579.0, 223623.0, 223676.0, 224235.0, 224408.0, 51529.22706159411, 51752.0, 51780.22329665058, 51876.0, 51944.74027629838, 56036.0, 56171.71914140403, 56362.71818640963, 56393.0, 56422.71788641113, 16644.50066498007, 16973.660526789394, 17001.0, 17016.0, 17022.82977170237, 13125.278109703868, 13303.600891973256, 14970.70058598822, 14971.550853474413, 14971.625709357135, 13558.728825423435, 13892.791608125886, 14241.715165696629, 14256.35846386915, 14327.856721432861, 5605.439456054443, 5658.377578466366, 5670.149477578314, 5780.826575202751, 5814.273215848019, 1011.0698157694939, 1027.0397178637998, 1029.5161274201114, 1030.4384110659707, 1038.159091136182, 28018.85990570034, 28106.859465702542, 28520.429591408472, 28530.0, 28764.568531472043, 1092.1644941619668, 1096.5021581156086, 1097.433341639671, 1102.3330884814573, 1114.1755101225197, 14143.787843182361, 15077.869159813057, 15303.0, 15303.234838258266, 15443.84556154424, 293522.0, 294027.0, 295090.0, 295274.0, 295803.0, 54250.18624720632, 54504.0, 54517.454825452034, 54537.181942270894, 54545.72727136418, 1515.772634104893, 1525.435588832117, 1552.697224041301, 1558.9688206235812, 1559.4619856149611, 26007.739922600533, 27212.863935680194, 27416.86291568609, 27618.309542261602, 27702.584461233502, 40756.79621601873, 40953.1809363817, 41067.383989239584, 41175.58824411718, 41288.38067429051, 257836.0, 259387.0, 259806.0, 260333.0, 261008.0, 29542.261443464085, 31485.370292594478, 35334.82332588424, 35347.46978795371, 35731.285374292886, 42976.119402985074, 44618.48310517757, 44793.0, 45457.0, 45779.084418310784, 34022.0, 34151.0, 34339.31321373609, 34427.483587746705, 34553.30893382068, 33296.33407331888, 33737.15657108494, 33751.0, 33789.15527111842, 33853.49219761755, 35674.46488302626, 36410.63589364073, 38971.41542876802, 39414.2117157661, 39704.0, 29370.853145734134, 29649.703502964694, 29655.85172074126, 30071.548926766554, 30456.695433045385, 24931.0, 25152.49695006126, 25237.369065773502, 25349.873250633627, 25671.358216044748], "value": 18898.63402060761, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.3513405561932, 462.6622565527164, 463.4508107892148, 466.49829148714895, 484.70917449530276, 19505.0, 21013.159473621057, 21176.894115529438, 21446.14215431381, 21831.781682183173, 23961.76038239617, 24284.392890177754, 24429.26712198637, 25465.87267063667, 25760.35599110023, 6927.688254028556, 7065.858682826341, 7232.168300645414, 7262.709491620329, 7270.963645181767, 6958.756443524452, 7125.1806042305025, 7162.713491460361, 7261.636918154098, 7263.527870688415, 7163.0, 7164.677589508458, 7167.0682811234465, 7170.713171473135, 7171.462140339469, 6929.1684997800085, 7253.347198752112, 7280.3083707047745, 7293.015442915218, 7299.963500182465, 6889.862202755967, 7077.610731409771, 7113.359797618239, 7147.106611673541, 7153.928460715379, 28633.427331453255, 29124.126276211748, 29343.2664183395, 29505.0, 29720.0, 3245.2211469247445, 3341.5655964724674, 3352.7317814574776, 3418.0, 3419.4870769384543, 17302.0, 18158.364457244064, 18301.08494575279, 18473.2610695572, 18738.906305468387, 2309.1802410144323, 2378.060666036923, 2402.627592723113, 2432.027189124347, 2569.961450578243, 106551.46724266406, 111313.44343278313, 111456.0, 112097.43951280272, 115361.0, 21140.0, 21522.246721364678, 21580.784192157877, 21865.344039678836, 22012.339629811133, 40857.59142408597, 41171.58828411737, 41203.381949270784, 41335.58664413378, 41396.1720765583, 11693.707657308636, 11782.587609433629, 11787.823182652268, 11836.289822610672, 12186.75626487483, 9692.806143877084, 9768.36505627132, 9815.950920245354, 9960.0, 9971.900280997243, 10651.242544979992, 11117.332960022424, 11412.486438110305, 11424.771504570028, 11502.59740909081, 3699.8150092495266, 3879.153294435324, 3975.5626881043063, 4200.705950583491, 4311.568843115726, 961.2598299309632, 972.0, 973.7273563402259, 977.5307852230969, 992.7617371830709, 20902.477438063866, 22212.0, 22575.404533960012, 22863.657045144337, 22914.0, 1089.760252744395, 1095.649392194506, 1098.4672433869566, 1113.3932007056173, 1119.8376235445814, 11315.321080735182, 11372.715682108013, 11526.020288275591, 11631.76736465283, 11709.355985420712, 666792.0, 685098.0, 697175.0, 715571.0, 717844.0, 72358.63820680862, 119837.0, 119979.0, 121756.0, 124103.379483102, 1276.7765641012786, 1407.7466056109793, 1455.657920388708, 1457.4097490516358, 1470.867621914033, 18770.436886893414, 18983.810161898204, 19031.42905712831, 19073.0, 19096.90451547789, 51677.34339140709, 52077.7396113017, 52367.73816131047, 52605.0, 53029.7348513255, 69067.0, 69534.30465695278, 69830.0, 70246.29753702602, 70249.64875175795, 9543.618255269712, 9754.414735115915, 12401.13192076546, 12431.94328482089, 12619.30593817349, 13446.46214151423, 13882.791758123434, 13999.370028348545, 14002.229877356436, 14082.225477598831, 11631.302121872377, 12330.938345308216, 12451.688707782378, 12451.75096498083, 12553.874461255271, 10667.626633068116, 10670.359778413009, 10866.565337386732, 10916.50875710579, 11110.16673749456, 12474.18917770342, 12482.313472758722, 12508.687282818002, 12557.686057848627, 12640.43118059708, 12397.75204495923, 12513.749725005631, 12555.183913045988, 12644.557440489538, 12854.293013884326, 11931.224470409396, 12074.215176013531, 12126.575569855013, 12135.696607584881, 12453.128281020605], "value": 12067.797243944708, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1578.0925967568646, 1578.6605879735855, 1600.2638786158366, 1613.411104946694, 1618.66817302453, 11192.10463162947, 11921.284722916631, 11935.164538482299, 11935.761284774298, 11939.880601194007, 11033.282836615614, 11144.777104457906, 11209.047230985376, 11320.094392448606, 11357.091432685385, 2454.165583701541, 2487.3657217409536, 2491.0409492345398, 2492.8753562321904, 2498.3129639349236, 2404.4950560382254, 2420.6490058941445, 2460.6432067350224, 2465.0509553821817, 2466.2724496273554, 2446.070493212577, 2461.913833015845, 2470.468849197422, 2510.497900419914, 2513.472170844125, 2324.883755812211, 2334.0, 2344.6600242964846, 2353.3528279723128, 2434.780869721716, 2353.8940747666397, 2365.5860224460653, 2371.3834403055157, 2376.7742064503846, 2383.9523209535887, 11641.243319184226, 11852.644420667393, 11854.3480108594, 11858.407079645984, 11860.169788114748, 5875.059990401515, 6036.758529658809, 6039.3960603939695, 6039.818805435844, 6047.21386219793, 3405.9489107663403, 3528.6647768461953, 3538.5222994895616, 3539.009077458316, 3581.033121057326, 1253.216739537789, 1301.134745394321, 1319.4656164253493, 1370.7806750919904, 1392.1368751374112, 15208.08751474915, 15610.843891561053, 16424.260908259155, 17221.397251096278, 17415.042172680496, 4826.831060912851, 4973.358791598764, 5000.349954505891, 5002.224655178453, 5008.6744361616375, 13639.590812275648, 13694.0, 13704.794428083587, 13717.0, 13725.656858578615, 4921.876953076202, 5089.618278629117, 5921.644701317849, 6090.177825993434, 6112.938870611237, 5325.547328477045, 5447.319085114361, 5530.695811730313, 5666.34836993751, 5669.886602267931, 4265.0, 4268.573142685768, 4388.144311859157, 4430.401895744033, 4448.110377924428, 1159.6173262823247, 1193.6538403862783, 1209.1958847366402, 1210.9152359334762, 1213.1023042948332, 1094.6523218099846, 1122.4892673833385, 1132.2470557079614, 1133.620237220524, 1168.053876360151, 7649.158592554814, 7801.53190808542, 7860.724874629361, 7870.724524641724, 7994.0, 1075.5536452372335, 1101.0, 1108.5953626926114, 1109.678193323943, 1134.9886501135047, 4801.159797035505, 4882.902341953141, 4993.975030124899, 5019.372578427697, 5072.655217965684, 14999.250037498081, 15241.23793810305, 15302.464413745465, 15448.922755386377, 15485.458008969632, 14724.631884202981, 15069.17119558413, 15267.236638168048, 15484.690306194037, 15495.535133946, 11014.118870490342, 11034.834477482844, 11111.499982500807, 11147.609833655946, 11222.831657525143, 7667.501612395176, 7688.692452302069, 7766.223377662414, 7815.25755053269, 7914.920850791418, 11329.660110196708, 13396.866031339561, 13458.93270533641, 13481.460741570227, 13547.93226033903, 12946.546870859474, 13482.93258533701, 13641.727165456834, 13682.179069255877, 13819.309034548434, 10143.7464063399, 10292.536835842555, 10341.914099019494, 10366.792664146826, 10368.42973636427, 11150.219484635998, 11277.548898044315, 11344.489497972776, 11366.261193022758, 11460.426978650867, 9436.292278079309, 9436.858447123435, 9508.429494230368, 9616.95191524038, 9752.414855108716, 9531.189848862925, 9602.471864047266, 9679.467629280458, 9856.063673950963, 9869.407835529892, 8382.706605268786, 8394.11861754507, 8432.367572432215, 8474.491530508189, 8491.363147764067, 6374.426301632875, 6430.582012169194, 6525.412712855866, 6534.346565343618, 6632.535722499571, 6837.965810171117, 6858.0, 6933.930660693329, 7064.32888875556, 7129.643517823984], "value": 5580.082071793332, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.67761806981525, 430.43107871807246, 432.8593207207657, 433.60325302348343, 436.9563043695631, 5134.743262836857, 5200.557952574031, 5249.973750131248, 5263.236815666746, 5280.551153151977, 5018.774155163017, 5131.307273518074, 5145.176771716526, 5249.4041581516685, 5287.788488460457, 4311.72804022814, 4329.805158767847, 4330.935035974463, 4335.804888780012, 4344.326629372453, 4332.805023773938, 4333.1766964276685, 4346.78266086696, 4351.891202719926, 4367.563243675639, 4136.27615167346, 4384.408104905829, 4396.0, 4402.911941761163, 4416.646668266547, 4305.526392096866, 4320.56794320567, 4337.97831010846, 4349.891252718676, 4355.651547876162, 4074.3888416737727, 4097.8565750198895, 4101.282275601788, 4106.897327566835, 4111.835526578933, 5094.414142373637, 5099.566536844335, 5110.693358398471, 5119.38567371914, 5135.948640513584, 4904.141775189328, 5053.84838454847, 5088.338515992898, 5091.5489085610425, 5156.484351564814, 5479.150731636563, 5487.368952570466, 5499.7250137492965, 5548.335499350179, 5568.470995255485, 4398.186335527937, 4635.513271106553, 4657.930131048037, 4661.440627124767, 4667.22990706533, 5512.889742205173, 5540.556755459594, 5563.885723425113, 5744.712764361765, 5758.510526605243, 4581.427321584802, 4641.303804429363, 4648.418947631546, 4652.0, 4660.650451216173, 4751.8574442766085, 4753.619710423227, 4756.429228492534, 4756.976215118972, 4757.881052973703, 4889.462159162489, 4993.7503124843615, 5004.099262132779, 5011.824586139468, 5176.922346164811, 5433.945660543423, 5455.645383050089, 5479.397266300703, 5490.615656903979, 5497.9828731684765, 1650.315119225532, 1659.4109091272667, 1666.3834381279005, 1671.7993840739189, 1676.8764927498605, 1218.074263559701, 1274.3118715893313, 1286.2668279080833, 1287.3177216075494, 1293.9870601293867, 5347.545458635982, 5357.357117145967, 5372.274742909735, 5383.865403364947, 5416.539594134464, 5620.4660557247, 5630.943690563124, 5633.183188437653, 5648.124540696199, 5663.943360566424, 5117.401842938806, 5327.573794096463, 5367.758450869642, 5409.404965453797, 5417.404085550586, 2949.705029497033, 2957.3050333171686, 2964.6659003448535, 2966.487091583308, 2966.569847372119, 5488.506034456838, 5661.433856614306, 5782.45066718661, 5822.806324703426, 5826.242588463558, 5675.148727690879, 5716.828495145069, 5722.310514058469, 5736.368999410061, 5748.798792042258, 5757.65454072757, 5767.740451679769, 5768.769249229984, 5780.0, 5801.82594522165, 5348.1442969124755, 5572.582056345709, 5607.41122182165, 5609.663420194638, 5664.235328230636, 5305.65513241638, 5484.243293344072, 5547.916781248204, 5561.332640083216, 5579.972100139636, 5288.338957630296, 5603.495685388335, 5611.232461774691, 5612.719364031864, 5631.0, 4588.810776510293, 4650.674452788272, 4652.185867473111, 4671.925457144807, 4694.201985662514, 5480.424555421623, 5615.971920140537, 5650.796189486022, 5662.292213473316, 5676.489115979582, 5449.945500545103, 5484.2047903053035, 5488.9725551371985, 5494.791145947885, 5518.6755178756, 5468.316460442445, 5469.917951230815, 5484.204790305464, 5527.281453411031, 5538.56608902483, 5263.131583288868, 5302.072137375867, 5419.430959749169, 5434.891302173855, 5455.781768729206, 5431.619786614899, 5461.863453413696, 5467.402983582766, 5469.9179512306555, 5504.7155430495895, 5123.539791159502, 5378.247045413567, 5397.8110766123, 5402.540784033401, 5443.319585051869], "value": 4404.53014705248, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [856.5246288309985, 893.9284857211424, 911.7629416351749, 918.228687902162, 960.5485421851728, 16031.4388996385, 17417.303307867685, 17520.64958700825, 17657.0, 17662.558436039104, 15260.465883694073, 15344.539663810076, 15375.615609609737, 15383.307751151196, 15437.92281038596, 683.9726410943581, 707.3492387003969, 845.2350622686479, 852.3777642321124, 882.6734108379908, 879.7668617816283, 886.5168483176652, 887.2502735188788, 888.0497867282011, 889.6752685269862, 934.4766930518897, 936.2884208001933, 936.8313703533395, 938.8920274168489, 938.9154976052154, 707.5153519838901, 726.564061563058, 726.6584705188579, 727.4980263618087, 929.7675581104724, 645.2676212498801, 670.6747227594612, 674.0765151742096, 674.5480528046221, 675.2707076357522, 16620.919640223347, 17071.829281707272, 17140.31438742449, 17160.57098572533, 17539.298428062863, 14590.0, 14806.70386592273, 15186.620334491616, 15369.692606147815, 15546.689066218725, 14910.0129015547, 15036.248187590576, 15400.460983865623, 15467.92266038674, 15837.732981361463, 4848.660593758438, 4874.122657921573, 4878.121938051149, 4914.754262286871, 4980.726060066695, 81532.59233703853, 83906.0, 84509.57745211296, 85283.0, 86120.0, 12510.249385036926, 12549.937250313873, 12588.811167832308, 12656.303903285223, 13265.734685306239, 22751.886240569023, 22852.542949140923, 22923.31230063101, 23045.308640740805, 23215.0, 8101.432899696964, 8291.917080829235, 8354.8329033419, 8393.328533717287, 8521.10528394522, 4518.909621807546, 4595.793189306489, 4614.907701845944, 4642.582167604932, 4662.557057079572, 3605.0446631642635, 3671.0, 3687.1888184599347, 3694.353488139565, 3712.2575484903127, 587.9441453061952, 589.593180705312, 594.8423667728056, 599.8872092268866, 602.2592211579781, 770.6300975531664, 788.0779487999055, 803.216863558038, 821.37575442664, 823.0452674897091, 10090.899091008996, 10570.947145264225, 10692.358458492516, 10960.342379457257, 11174.38540880243, 612.5834432585884, 619.7613918641265, 631.6967855429419, 632.5983000794529, 635.5773410681845, 5098.796048158107, 5231.293775340357, 5232.189010703347, 5283.339582552181, 5306.363236411656, 59420.0, 71514.64242678753, 76966.2136542343, 77780.0, 79969.0, 69178.0, 69635.0, 71580.28419715737, 71906.9213961791, 72751.3624318782, 62342.376576234565, 63406.68296658487, 63476.0, 63708.0, 63825.680871596276, 10029.548670309707, 10088.092071713581, 10169.491525423824, 10218.131458826083, 10226.386416815016, 43940.560594393646, 44340.11319773522, 44617.33074003957, 45154.322685160405, 45231.54768452273, 41028.1794364117, 51448.48551514437, 52834.73582632062, 53227.733861331995, 53290.0, 11166.553337866395, 11233.157513186381, 11359.375234361858, 11360.943195283971, 11512.30926144434, 12800.16798908068, 12881.613551593467, 12883.613491595268, 12926.159799612995, 13050.608481745563, 11762.82355764647, 11786.646400607995, 11789.823152652889, 11887.524499020288, 11949.0, 12152.392380381123, 12283.0, 12559.686007849878, 12633.418390142517, 12636.873631263938, 11509.539618415505, 11729.0, 11922.0, 12218.877811221773, 12228.449719762453, 8231.547264900248, 8306.62620182081, 8538.573071346533, 8684.305255579664, 8776.034636189886, 9791.46146961924, 10335.896641033494, 10356.430396328275, 10422.583096676351, 10424.68725938223], "value": 7351.729926004953, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1457.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 112.0, 113.0, 113.0, 112.0, 113.0, 111.0, 112.0, 113.0, 110.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 120.0, 155.0, 115.0, 114.0, 117.0, 114.0, 133.0, 115.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 162.0, 160.0, 160.0, 162.0, 162.0, 161.0, 163.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 109.0, 75.0, 75.0, 76.0, 77.0, 89.0, 75.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 106.0, 81.0, 82.0, 81.0, 95.0, 81.0, 82.0, 101.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 54.0, 56.0, 54.0, 54.0, 55.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 52.0, 57.0, 53.0, 53.0, 53.0, 53.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 79.0, 75.0, 79.0, 79.0, 76.0, 75.0, 76.0, 75.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 132.0, 136.0, 125.0, 122.0, 127.0, 129.0, 120.0, 157.0, 139.0], "value": 131.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 159.0, 162.0, 163.0, 160.0, 160.0, 158.0, 158.0, 164.0], "value": 161.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 60.0, 63.0, 61.0, 65.0, 60.0, 60.0, 62.0, 58.0], "value": 61.4, "unit": "score"}]}]} 15:28:26 INFO - Return code: 0 15:28:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:28:26 ERROR - # TBPL SUCCESS # 15:28:26 INFO - Running post-action listener: _resource_record_post_action 15:28:26 INFO - [mozharness: 2017-04-12 22:28:26.290771Z] Finished run-tests step (success) 15:28:26 INFO - Running post-run listener: _resource_record_post_run 15:28:26 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 371261440; Read time: 24; Write time: 270040 15:28:26 INFO - TinderboxPrint: CPU usage
13.2% 15:28:26 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 15:28:26 INFO - TinderboxPrint: I/O write bytes / time
371,261,440 / 270,040 15:28:26 INFO - TinderboxPrint: CPU idle
8,260.0 (86.8%) 15:28:26 INFO - TinderboxPrint: CPU user
1,203.8 (12.7%) 15:28:26 INFO - TinderboxPrint: Swap in / out
0 / 0 15:28:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 117784576; Read time: 0; Write time: 127880 15:28:26 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 266240; Write bytes: 253476864; Read time: 24; Write time: 142160 15:28:26 INFO - Running post-run listener: _upload_blobber_files 15:28:26 INFO - Blob upload gear active. 15:28:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:28:26 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 15:28:26 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-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:28:26 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-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:28:27 INFO - (blobuploader) - INFO - Open directory for files ... 15:28:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:28:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:28:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:28 INFO - (blobuploader) - INFO - Done attempting. 15:28:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:28:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:28:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:31 INFO - (blobuploader) - INFO - Done attempting. 15:28:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:28:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:28:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:32 INFO - (blobuploader) - INFO - Done attempting. 15:28:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:28:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:28:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:33 INFO - (blobuploader) - INFO - Done attempting. 15:28:33 INFO - (blobuploader) - INFO - Iteration through files over. 15:28:33 INFO - Return code: 0 15:28:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:28:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:28:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/b47847503242242af685a42ebc8eb190b1b34f459704e54ff30e7c4e0ac742d46f21d406050d4e0c2dc283a5638debd30112e7fccfd19dcb388fbb85e18f9469", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f7a31dfc8b4437af64aebc36be1c6d8329dcda09b0ef7d7c61fd5449da7c7e498d30f8fed3932ba1d15587fbe1f83bc53cc29d63608f961e5793bc1021cf0a99", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/414ea5a263eb181650b3a63d935ce399b517f5608a45f9f1620ee23dd733d791b181bcb34392aec84d5a3b6cc265912cb2f6245594f6b0e1e133f07931ebe915", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8a7b8167fe4f79dd7d9a2037f6119b8a459b04fbc5c9cd15521364a9e05651084a9c44f9583a39fe4614db63756882f7fa0e73161308fbb216af8231248f3033"} 15:28:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:28:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:28:33 INFO - Contents: 15:28:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/b47847503242242af685a42ebc8eb190b1b34f459704e54ff30e7c4e0ac742d46f21d406050d4e0c2dc283a5638debd30112e7fccfd19dcb388fbb85e18f9469", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f7a31dfc8b4437af64aebc36be1c6d8329dcda09b0ef7d7c61fd5449da7c7e498d30f8fed3932ba1d15587fbe1f83bc53cc29d63608f961e5793bc1021cf0a99", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/414ea5a263eb181650b3a63d935ce399b517f5608a45f9f1620ee23dd733d791b181bcb34392aec84d5a3b6cc265912cb2f6245594f6b0e1e133f07931ebe915", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8a7b8167fe4f79dd7d9a2037f6119b8a459b04fbc5c9cd15521364a9e05651084a9c44f9583a39fe4614db63756882f7fa0e73161308fbb216af8231248f3033"} 15:28:33 INFO - Running post-run listener: copy_logs_to_upload_dir 15:28:33 INFO - Copying logs to upload dir... 15:28:33 INFO - mkdir: /builds/slave/test/build/upload/logs 15:28:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.794413 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-12 15:28:32.961239) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 15:28:32.964606) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118 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-Aurora-Non-PGO/sha512/b47847503242242af685a42ebc8eb190b1b34f459704e54ff30e7c4e0ac742d46f21d406050d4e0c2dc283a5638debd30112e7fccfd19dcb388fbb85e18f9469", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f7a31dfc8b4437af64aebc36be1c6d8329dcda09b0ef7d7c61fd5449da7c7e498d30f8fed3932ba1d15587fbe1f83bc53cc29d63608f961e5793bc1021cf0a99", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/414ea5a263eb181650b3a63d935ce399b517f5608a45f9f1620ee23dd733d791b181bcb34392aec84d5a3b6cc265912cb2f6245594f6b0e1e133f07931ebe915", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8a7b8167fe4f79dd7d9a2037f6119b8a459b04fbc5c9cd15521364a9e05651084a9c44f9583a39fe4614db63756882f7fa0e73161308fbb216af8231248f3033"} build_url:https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011434 build_url: 'https://queue.taskcluster.net/v1/task/fbfM5napRMuXpZ_93eEa1w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/b47847503242242af685a42ebc8eb190b1b34f459704e54ff30e7c4e0ac742d46f21d406050d4e0c2dc283a5638debd30112e7fccfd19dcb388fbb85e18f9469", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f7a31dfc8b4437af64aebc36be1c6d8329dcda09b0ef7d7c61fd5449da7c7e498d30f8fed3932ba1d15587fbe1f83bc53cc29d63608f961e5793bc1021cf0a99", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/414ea5a263eb181650b3a63d935ce399b517f5608a45f9f1620ee23dd733d791b181bcb34392aec84d5a3b6cc265912cb2f6245594f6b0e1e133f07931ebe915", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8a7b8167fe4f79dd7d9a2037f6119b8a459b04fbc5c9cd15521364a9e05651084a9c44f9583a39fe4614db63756882f7fa0e73161308fbb216af8231248f3033"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 15:28:33.002984) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:28:33.003311) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1492034860.76766-1631305118 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:28:33.124279) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 15:28:33.124581) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 15:28:33.124842) ========= ========= Total master_lag: 0.22 =========