builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-063 starttime: 1496156971.11 results: success (0) revision: 3524778fe631486d5a9cd4f5392177f297b337dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:31.109124) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:31.109545) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:31.109859) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:31.127268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:31.127534) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-30 08:09:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-05-30 08:09:31 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076810 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:31.219276) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:31.219930) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017301 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:31.278742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:31.279041) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3524778fe631486d5a9cd4f5392177f297b337dc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3524778fe631486d5a9cd4f5392177f297b337dc --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=769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-30 08:09:31,817 truncating revision to first 12 chars 2017-05-30 08:09:31,817 Setting DEBUG logging. 2017-05-30 08:09:31,817 attempt 1/10 2017-05-30 08:09:31,817 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3524778fe631?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 08:09:32,349 unpacking tar archive at: mozilla-beta-3524778fe631/testing/mozharness/ program finished with exit code 0 elapsedTime=0.829528 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:32.125395) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:32.125987) ========= script_repo_revision: 3524778fe631486d5a9cd4f5392177f297b337dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:32.126352) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:32.126621) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 08:09:32.136275) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-30 08:09:32.136722) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-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-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-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=769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:09:32 INFO - MultiFileLogger online at 20170530 08:09:32 in /builds/slave/test 08:09:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 08:09:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:09:32 INFO - {'append_to_log': False, 08:09:32 INFO - 'base_work_dir': '/builds/slave/test', 08:09:32 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 08:09:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:09:32 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 08:09:32 INFO - 'buildbot_json_path': 'buildprops.json', 08:09:32 INFO - 'config_files': ('talos/linux_config.py',), 08:09:32 INFO - 'default_actions': ('clobber', 08:09:32 INFO - 'read-buildbot-config', 08:09:32 INFO - 'download-and-extract', 08:09:32 INFO - 'populate-webroot', 08:09:32 INFO - 'create-virtualenv', 08:09:32 INFO - 'install', 08:09:32 INFO - 'run-tests'), 08:09:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:09:32 INFO - 'download_minidump_stackwalk': True, 08:09:32 INFO - 'download_symbols': 'ondemand', 08:09:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:09:32 INFO - 'tooltool.py': '/tools/tooltool.py', 08:09:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:09:32 INFO - '/tools/misc-python/virtualenv.py')}, 08:09:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:09:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:09:32 INFO - 'gecko_profile': False, 08:09:32 INFO - 'gecko_profile_interval': 0, 08:09:32 INFO - 'installer_path': 'installer.exe', 08:09:32 INFO - 'log_level': 'info', 08:09:32 INFO - 'log_name': 'talos', 08:09:32 INFO - 'log_to_console': True, 08:09:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:09:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:09:32 INFO - 'opt_config_files': (), 08:09:32 INFO - 'pip_index': False, 08:09:32 INFO - 'suite': 'dromaeojs', 08:09:32 INFO - 'system_bits': '32', 08:09:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:09:32 INFO - 'title': 'talos-linux64-ix-063', 08:09:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:09:32 INFO - 'use_talos_json': True, 08:09:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:09:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:09:32 INFO - 'work_dir': 'build'} 08:09:32 INFO - [mozharness: 2017-05-30 15:09:32.780383Z] Running clobber step. 08:09:32 INFO - Running pre-action listener: _resource_record_pre_action 08:09:32 INFO - Running main action method: clobber 08:09:32 INFO - rmtree: /builds/slave/test/build 08:09:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:09:34 INFO - Running post-action listener: _resource_record_post_action 08:09:34 INFO - [mozharness: 2017-05-30 15:09:34.323515Z] Finished clobber step (success) 08:09:34 INFO - [mozharness: 2017-05-30 15:09:34.323613Z] Running read-buildbot-config step. 08:09:34 INFO - Running pre-action listener: _resource_record_pre_action 08:09:34 INFO - Running main action method: read_buildbot_config 08:09:34 INFO - Using buildbot properties: 08:09:34 INFO - { 08:09:34 INFO - "project": "", 08:09:34 INFO - "product": "firefox", 08:09:34 INFO - "who": "ffxbld@noreply.mozilla.org", 08:09:34 INFO - "installer_path": "public/build/target.tar.bz2", 08:09:34 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:09:34 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 08:09:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 08:09:34 INFO - "stage_platform": "linux64", 08:09:34 INFO - "basedir": "/builds/slave/test", 08:09:34 INFO - "buildnumber": 64, 08:09:34 INFO - "platform": "ubuntu64_hw", 08:09:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:09:34 INFO - "slavebuilddir": "test", 08:09:34 INFO - "taskId": "LGRk9r4ZSD6kRl9IKJNjIA", 08:09:34 INFO - "branch": "mozilla-beta", 08:09:34 INFO - "script_repo_revision": "production", 08:09:34 INFO - "revision": "3524778fe631486d5a9cd4f5392177f297b337dc", 08:09:34 INFO - "slavename": "talos-linux64-ix-063", 08:09:34 INFO - "repo_path": "releases/mozilla-beta" 08:09:34 INFO - } 08:09:34 INFO - Finding installer, test and symbols from parent task. 08:09:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LGRk9r4ZSD6kRl9IKJNjIA'}, attempt #1 08:09:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LGRk9r4ZSD6kRl9IKJNjIA'}, attempt #1 08:09:35 INFO - Task dependencies: cWAXvGM4R22iMRAkSG3X6A 08:09:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A'}, attempt #1 08:09:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.tar.bz2 08:09:35 INFO - Set test_url: https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/test_packages.json 08:09:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.crashreporter-symbols.zip 08:09:35 INFO - Running post-action listener: _resource_record_post_action 08:09:35 INFO - [mozharness: 2017-05-30 15:09:35.683371Z] Finished read-buildbot-config step (success) 08:09:35 INFO - [mozharness: 2017-05-30 15:09:35.683591Z] Running download-and-extract step. 08:09:35 INFO - Running pre-action listener: _resource_record_pre_action 08:09:35 INFO - Running main action method: download_and_extract 08:09:35 INFO - mkdir: /builds/slave/test/build/tests 08:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:09:35 INFO - trying https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.test_packages.json 08:09:35 INFO - Downloading https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:09:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:09:36 INFO - Downloaded 986 bytes. 08:09:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:09:36 INFO - Using the following test package requirements: 08:09:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:09:36 INFO - u'common': [u'target.common.tests.zip'], 08:09:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:09:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:09:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:09:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:09:36 INFO - u'mozbase': [u'target.common.tests.zip'], 08:09:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:09:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:09:36 INFO - u'web-platform': [u'target.common.tests.zip', 08:09:36 INFO - u'target.web-platform.tests.zip'], 08:09:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:09:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:09:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.common.tests.zip 08:09:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:09:36 INFO - Fetch https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.common.tests.zip into memory 08:09:37 INFO - Expected file size: 59711189 08:09:37 INFO - Obtained file size: 59711189 08:09:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:09:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.common.tests.zip 08:09:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:09:47 INFO - Fetch https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.common.tests.zip into memory 08:09:48 INFO - Expected file size: 59711189 08:09:48 INFO - Obtained file size: 59711189 08:09:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.talos.tests.zip 08:09:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:09:58 INFO - Fetch https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.talos.tests.zip into memory 08:09:59 INFO - Expected file size: 14404461 08:09:59 INFO - Obtained file size: 14404461 08:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:09:59 INFO - trying https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.tar.bz2 08:09:59 INFO - Downloading https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:09:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:10:00 INFO - Downloaded 59810679 bytes. 08:10:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.tar.bz2 08:10:00 INFO - mkdir: /builds/slave/test/properties 08:10:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:10:00 INFO - Writing to file /builds/slave/test/properties/build_url 08:10:00 INFO - Contents: 08:10:00 INFO - build_url:https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.tar.bz2 08:10:00 INFO - Running post-action listener: _resource_record_post_action 08:10:00 INFO - Running post-action listener: set_extra_try_arguments 08:10:00 INFO - [mozharness: 2017-05-30 15:10:00.940244Z] Finished download-and-extract step (success) 08:10:00 INFO - [mozharness: 2017-05-30 15:10:00.940342Z] Running populate-webroot step. 08:10:00 INFO - Running pre-action listener: _resource_record_pre_action 08:10:00 INFO - Running main action method: populate_webroot 08:10:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:10:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:10:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:10:00 INFO - '--tppagecycles', 08:10:00 INFO - '7'], 08:10:00 INFO - 'tests': ['tsvgm']}}, 08:10:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:10:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:10:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:10:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:10:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:10:00 INFO - 'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:10:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:10:00 INFO - 'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['damp', 'tps']}, 08:10:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:10:00 INFO - 'tests': ['damp', 'tps']}, 08:10:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['dromaeo_dom']}, 08:10:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:10:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['basic_compositor_video']}, 08:10:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:10:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['a11yr', 08:10:00 INFO - 'ts_paint', 08:10:00 INFO - 'tpaint', 08:10:00 INFO - 'sessionrestore', 08:10:00 INFO - 'sessionrestore_no_auto_restore', 08:10:00 INFO - 'tabpaint']}, 08:10:00 INFO - 'other-e10s': {'tests': ['a11yr', 08:10:00 INFO - 'ts_paint', 08:10:00 INFO - 'tpaint', 08:10:00 INFO - 'sessionrestore', 08:10:00 INFO - 'sessionrestore_no_auto_restore', 08:10:00 INFO - 'tabpaint']}, 08:10:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:10:00 INFO - 'ts_paint', 08:10:00 INFO - 'tpaint', 08:10:00 INFO - 'sessionrestore', 08:10:00 INFO - 'sessionrestore_no_auto_restore', 08:10:00 INFO - 'tabpaint']}, 08:10:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:10:00 INFO - 'ts_paint', 08:10:00 INFO - 'tpaint', 08:10:00 INFO - 'sessionrestore', 08:10:00 INFO - 'sessionrestore_no_auto_restore', 08:10:00 INFO - 'tabpaint']}, 08:10:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['a11yr', 08:10:00 INFO - 'ts_paint', 08:10:00 INFO - 'tpaint', 08:10:00 INFO - 'sessionrestore', 08:10:00 INFO - 'sessionrestore_no_auto_restore', 08:10:00 INFO - 'tabpaint']}, 08:10:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['a11yr', 08:10:00 INFO - 'ts_paint', 08:10:00 INFO - 'tpaint', 08:10:00 INFO - 'sessionrestore', 08:10:00 INFO - 'sessionrestore_no_auto_restore', 08:10:00 INFO - 'tabpaint']}, 08:10:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['tsvgx', 08:10:00 INFO - 'tsvgr_opacity', 08:10:00 INFO - 'tart', 08:10:00 INFO - 'tscrollx', 08:10:00 INFO - 'cart', 08:10:00 INFO - 'tsvg_static']}, 08:10:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:10:00 INFO - 'tsvgr_opacity', 08:10:00 INFO - 'tart', 08:10:00 INFO - 'tscrollx', 08:10:00 INFO - 'cart', 08:10:00 INFO - 'tsvg_static']}, 08:10:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:10:00 INFO - 'talos_options': ['--disable-e10s'], 08:10:00 INFO - 'tests': ['tp5o']}, 08:10:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:10:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:10:00 INFO - 'talos_options': ['--disable-e10s', 08:10:00 INFO - '--xperf_path', 08:10:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:00 INFO - 'tests': ['tp5n']}, 08:10:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:10:00 INFO - 'talos_options': ['--xperf_path', 08:10:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:00 INFO - 'tests': ['tp5n']}}, 08:10:00 INFO - 'talos.zip': {'path': '', 08:10:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:10:00 INFO - Running post-action listener: _resource_record_post_action 08:10:00 INFO - [mozharness: 2017-05-30 15:10:00.953668Z] Finished populate-webroot step (success) 08:10:00 INFO - [mozharness: 2017-05-30 15:10:00.953773Z] Running create-virtualenv step. 08:10:00 INFO - Running pre-action listener: _resource_record_pre_action 08:10:00 INFO - Running main action method: create_virtualenv 08:10:00 INFO - Creating virtualenv /builds/slave/test/build/venv 08:10:00 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 08:10:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:10:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:10:01 INFO - Using real prefix '/usr' 08:10:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:10:01 INFO - Installing distribute.............................................................................................................................................................................................done. 08:10:03 INFO - Installing pip.................done. 08:10:03 INFO - Return code: 0 08:10:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:10:03 INFO - Reading from file tmpfile_stdout 08:10:03 INFO - Output received: 08:10:03 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) 08:10:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:03 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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 08:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:10:03 INFO - Using env: {'DISPLAY': ':0', 08:10:03 INFO - 'HOME': '/home/cltbld', 08:10:03 INFO - 'LANG': 'en_US.UTF-8', 08:10:03 INFO - 'LANGUAGE': 'en_US:en', 08:10:03 INFO - 'LOGNAME': 'cltbld', 08:10:03 INFO - 'MAIL': '/var/mail/cltbld', 08:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:03 INFO - 'MOZ_NO_REMOTE': '1', 08:10:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:03 INFO - 'NO_EM_RESTART': '1', 08:10:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:03 INFO - 'PWD': '/builds/slave/test', 08:10:03 INFO - 'SHELL': '/bin/bash', 08:10:03 INFO - 'SHLVL': '1', 08:10:03 INFO - 'TERM': 'linux', 08:10:03 INFO - 'TMOUT': '86400', 08:10:03 INFO - 'USER': 'cltbld', 08:10:03 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:03 INFO - '_': '/tools/buildbot/bin/python'} 08:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:03 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 08:10:03 INFO - Cleaning up... 08:10:03 INFO - Return code: 0 08:10:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:03 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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 08:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:10:03 INFO - Using env: {'DISPLAY': ':0', 08:10:03 INFO - 'HOME': '/home/cltbld', 08:10:03 INFO - 'LANG': 'en_US.UTF-8', 08:10:03 INFO - 'LANGUAGE': 'en_US:en', 08:10:03 INFO - 'LOGNAME': 'cltbld', 08:10:03 INFO - 'MAIL': '/var/mail/cltbld', 08:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:03 INFO - 'MOZ_NO_REMOTE': '1', 08:10:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:03 INFO - 'NO_EM_RESTART': '1', 08:10:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:03 INFO - 'PWD': '/builds/slave/test', 08:10:03 INFO - 'SHELL': '/bin/bash', 08:10:03 INFO - 'SHLVL': '1', 08:10:03 INFO - 'TERM': 'linux', 08:10:03 INFO - 'TMOUT': '86400', 08:10:03 INFO - 'USER': 'cltbld', 08:10:03 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:03 INFO - '_': '/tools/buildbot/bin/python'} 08:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:04 INFO - Downloading/unpacking psutil>=3.1.1 08:10:04 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 08:10:04 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 08:10:04 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 08:10:04 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 08:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:10:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:05 INFO - Installing collected packages: psutil 08:10:05 INFO - Running setup.py install for psutil 08:10:05 INFO - building 'psutil._psutil_linux' extension 08:10:05 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 08:10:05 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 08:10:05 INFO - building 'psutil._psutil_posix' extension 08:10:05 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 08:10:05 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 08:10:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:05 INFO - Successfully installed psutil 08:10:05 INFO - Cleaning up... 08:10:05 INFO - Return code: 0 08:10:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:05 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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 08:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:10:05 INFO - Using env: {'DISPLAY': ':0', 08:10:05 INFO - 'HOME': '/home/cltbld', 08:10:05 INFO - 'LANG': 'en_US.UTF-8', 08:10:05 INFO - 'LANGUAGE': 'en_US:en', 08:10:05 INFO - 'LOGNAME': 'cltbld', 08:10:05 INFO - 'MAIL': '/var/mail/cltbld', 08:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:05 INFO - 'MOZ_NO_REMOTE': '1', 08:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:05 INFO - 'NO_EM_RESTART': '1', 08:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:05 INFO - 'PWD': '/builds/slave/test', 08:10:05 INFO - 'SHELL': '/bin/bash', 08:10:05 INFO - 'SHLVL': '1', 08:10:05 INFO - 'TERM': 'linux', 08:10:05 INFO - 'TMOUT': '86400', 08:10:05 INFO - 'USER': 'cltbld', 08:10:05 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:05 INFO - '_': '/tools/buildbot/bin/python'} 08:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:10:06 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 08:10:06 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 08:10:06 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 08:10:06 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 08:10:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:10:07 INFO - Installing collected packages: mozsystemmonitor 08:10:07 INFO - Running setup.py install for mozsystemmonitor 08:10:07 INFO - Successfully installed mozsystemmonitor 08:10:07 INFO - Cleaning up... 08:10:07 INFO - Return code: 0 08:10:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:07 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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 08:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:10:07 INFO - Using env: {'DISPLAY': ':0', 08:10:07 INFO - 'HOME': '/home/cltbld', 08:10:07 INFO - 'LANG': 'en_US.UTF-8', 08:10:07 INFO - 'LANGUAGE': 'en_US:en', 08:10:07 INFO - 'LOGNAME': 'cltbld', 08:10:07 INFO - 'MAIL': '/var/mail/cltbld', 08:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:07 INFO - 'MOZ_NO_REMOTE': '1', 08:10:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:07 INFO - 'NO_EM_RESTART': '1', 08:10:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:07 INFO - 'PWD': '/builds/slave/test', 08:10:07 INFO - 'SHELL': '/bin/bash', 08:10:07 INFO - 'SHLVL': '1', 08:10:07 INFO - 'TERM': 'linux', 08:10:07 INFO - 'TMOUT': '86400', 08:10:07 INFO - 'USER': 'cltbld', 08:10:07 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:07 INFO - '_': '/tools/buildbot/bin/python'} 08:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:07 INFO - Downloading/unpacking jsonschema==2.5.1 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:10:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:10:08 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 08:10:08 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 08:10:08 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 08:10:08 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 08:10:08 INFO - Downloading functools32-3.2.3-2.tar.gz 08:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:10:08 INFO - warning: no files found matching '*.txt' 08:10:08 INFO - no previously-included directories found matching 'build' 08:10:08 INFO - no previously-included directories found matching 'dist' 08:10:08 INFO - no previously-included directories found matching '.git*' 08:10:08 INFO - Installing collected packages: jsonschema, functools32 08:10:08 INFO - Running setup.py install for functools32 08:10:08 INFO - warning: no files found matching '*.txt' 08:10:08 INFO - no previously-included directories found matching 'build' 08:10:08 INFO - no previously-included directories found matching 'dist' 08:10:08 INFO - no previously-included directories found matching '.git*' 08:10:09 INFO - Successfully installed jsonschema functools32 08:10:09 INFO - Cleaning up... 08:10:09 INFO - Return code: 0 08:10:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:09 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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 08:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:10:09 INFO - Using env: {'DISPLAY': ':0', 08:10:09 INFO - 'HOME': '/home/cltbld', 08:10:09 INFO - 'LANG': 'en_US.UTF-8', 08:10:09 INFO - 'LANGUAGE': 'en_US:en', 08:10:09 INFO - 'LOGNAME': 'cltbld', 08:10:09 INFO - 'MAIL': '/var/mail/cltbld', 08:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:09 INFO - 'MOZ_NO_REMOTE': '1', 08:10:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:09 INFO - 'NO_EM_RESTART': '1', 08:10:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:09 INFO - 'PWD': '/builds/slave/test', 08:10:09 INFO - 'SHELL': '/bin/bash', 08:10:09 INFO - 'SHLVL': '1', 08:10:09 INFO - 'TERM': 'linux', 08:10:09 INFO - 'TMOUT': '86400', 08:10:09 INFO - 'USER': 'cltbld', 08:10:09 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:09 INFO - '_': '/tools/buildbot/bin/python'} 08:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:10:09 INFO - Cleaning up... 08:10:09 INFO - Return code: 0 08:10:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:09 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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 08:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:10:09 INFO - Using env: {'DISPLAY': ':0', 08:10:09 INFO - 'HOME': '/home/cltbld', 08:10:09 INFO - 'LANG': 'en_US.UTF-8', 08:10:09 INFO - 'LANGUAGE': 'en_US:en', 08:10:09 INFO - 'LOGNAME': 'cltbld', 08:10:09 INFO - 'MAIL': '/var/mail/cltbld', 08:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:09 INFO - 'MOZ_NO_REMOTE': '1', 08:10:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:09 INFO - 'NO_EM_RESTART': '1', 08:10:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:09 INFO - 'PWD': '/builds/slave/test', 08:10:09 INFO - 'SHELL': '/bin/bash', 08:10:09 INFO - 'SHLVL': '1', 08:10:09 INFO - 'TERM': 'linux', 08:10:09 INFO - 'TMOUT': '86400', 08:10:09 INFO - 'USER': 'cltbld', 08:10:09 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:09 INFO - '_': '/tools/buildbot/bin/python'} 08:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:09 INFO - Downloading/unpacking blobuploader==1.2.4 08:10:09 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 08:10:09 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 08:10:09 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 08:10:09 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 08:10:10 INFO - Downloading blobuploader-1.2.4.tar.gz 08:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:10:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:10:10 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 08:10:10 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 08:10:10 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 08:10:10 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 08:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:10:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:10:10 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 08:10:10 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 08:10:10 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 08:10:10 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 08:10:11 INFO - Downloading docopt-0.6.1.tar.gz 08:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:10:11 INFO - Installing collected packages: blobuploader, requests, docopt 08:10:11 INFO - Running setup.py install for blobuploader 08:10:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:10:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:10:11 INFO - Running setup.py install for requests 08:10:11 INFO - Running setup.py install for docopt 08:10:11 INFO - Successfully installed blobuploader requests docopt 08:10:11 INFO - Cleaning up... 08:10:11 INFO - Return code: 0 08:10:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:11 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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:11 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:10:11 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:10:11 INFO - Using env: {'DISPLAY': ':0', 08:10:11 INFO - 'HOME': '/home/cltbld', 08:10:11 INFO - 'LANG': 'en_US.UTF-8', 08:10:11 INFO - 'LANGUAGE': 'en_US:en', 08:10:11 INFO - 'LOGNAME': 'cltbld', 08:10:11 INFO - 'MAIL': '/var/mail/cltbld', 08:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:11 INFO - 'MOZ_NO_REMOTE': '1', 08:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:11 INFO - 'NO_EM_RESTART': '1', 08:10:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:11 INFO - 'PWD': '/builds/slave/test', 08:10:11 INFO - 'SHELL': '/bin/bash', 08:10:11 INFO - 'SHLVL': '1', 08:10:11 INFO - 'TERM': 'linux', 08:10:11 INFO - 'TMOUT': '86400', 08:10:11 INFO - 'USER': 'cltbld', 08:10:11 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:11 INFO - '_': '/tools/buildbot/bin/python'} 08:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:12 INFO - Running setup.py (path:/tmp/pip-DjCxfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:12 INFO - Running setup.py (path:/tmp/pip-gv8MtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:12 INFO - Running setup.py (path:/tmp/pip-7_QQt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:12 INFO - Running setup.py (path:/tmp/pip-X8Xqyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:12 INFO - Running setup.py (path:/tmp/pip-BGiAiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:12 INFO - Running setup.py (path:/tmp/pip-V5FU8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:12 INFO - Running setup.py (path:/tmp/pip-9INuaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:12 INFO - Running setup.py (path:/tmp/pip-ucRglL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:12 INFO - Running setup.py (path:/tmp/pip-wRrM4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:12 INFO - Running setup.py (path:/tmp/pip-2sZIp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:12 INFO - Running setup.py (path:/tmp/pip-fLguSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:12 INFO - Running setup.py (path:/tmp/pip-PbQcvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:13 INFO - Running setup.py (path:/tmp/pip-nSrbq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:13 INFO - Running setup.py (path:/tmp/pip-rfA99y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:13 INFO - Running setup.py (path:/tmp/pip-euf6sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:13 INFO - Running setup.py (path:/tmp/pip-fVdGQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:13 INFO - Running setup.py (path:/tmp/pip-HWX3rC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:10:13 INFO - Running setup.py install for manifestparser 08:10:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:10:13 INFO - Running setup.py install for mozcrash 08:10:13 INFO - Running setup.py install for mozdebug 08:10:13 INFO - Running setup.py install for mozdevice 08:10:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:10:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:10:13 INFO - Running setup.py install for mozfile 08:10:13 INFO - Running setup.py install for mozhttpd 08:10:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:10:13 INFO - Running setup.py install for mozinfo 08:10:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Running setup.py install for mozInstall 08:10:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Running setup.py install for mozleak 08:10:14 INFO - Running setup.py install for mozlog 08:10:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Running setup.py install for moznetwork 08:10:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Running setup.py install for mozprocess 08:10:14 INFO - Running setup.py install for mozprofile 08:10:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Running setup.py install for mozrunner 08:10:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:10:14 INFO - Running setup.py install for mozscreenshot 08:10:14 INFO - Running setup.py install for moztest 08:10:15 INFO - Running setup.py install for mozversion 08:10:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:10:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:10:15 INFO - Cleaning up... 08:10:15 INFO - Return code: 0 08:10:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:15 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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:15 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:10:15 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:10:15 INFO - Using env: {'DISPLAY': ':0', 08:10:15 INFO - 'HOME': '/home/cltbld', 08:10:15 INFO - 'LANG': 'en_US.UTF-8', 08:10:15 INFO - 'LANGUAGE': 'en_US:en', 08:10:15 INFO - 'LOGNAME': 'cltbld', 08:10:15 INFO - 'MAIL': '/var/mail/cltbld', 08:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:15 INFO - 'MOZ_NO_REMOTE': '1', 08:10:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:15 INFO - 'NO_EM_RESTART': '1', 08:10:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:15 INFO - 'PWD': '/builds/slave/test', 08:10:15 INFO - 'SHELL': '/bin/bash', 08:10:15 INFO - 'SHLVL': '1', 08:10:15 INFO - 'TERM': 'linux', 08:10:15 INFO - 'TMOUT': '86400', 08:10:15 INFO - 'USER': 'cltbld', 08:10:15 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:15 INFO - '_': '/tools/buildbot/bin/python'} 08:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:15 INFO - Running setup.py (path:/tmp/pip-RPzfOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:15 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)) 08:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:15 INFO - Running setup.py (path:/tmp/pip-pc1z12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:15 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)) 08:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:15 INFO - Running setup.py (path:/tmp/pip-mbQ8CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:15 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)) 08:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:15 INFO - Running setup.py (path:/tmp/pip-nbaMx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:15 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)) 08:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:15 INFO - Running setup.py (path:/tmp/pip-eBvpQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:15 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)) 08:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:15 INFO - Running setup.py (path:/tmp/pip-X7AjMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:15 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)) 08:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:15 INFO - Running setup.py (path:/tmp/pip-xQ8CEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:15 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)) 08:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:15 INFO - Running setup.py (path:/tmp/pip-vP8MWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:15 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)) 08:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:15 INFO - Running setup.py (path:/tmp/pip-X7VzO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:16 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)) 08:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:16 INFO - Running setup.py (path:/tmp/pip-OqxM4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:16 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)) 08:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:16 INFO - Running setup.py (path:/tmp/pip-FAZ6pn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:16 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)) 08:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:16 INFO - Running setup.py (path:/tmp/pip-ghxncZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:16 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)) 08:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:16 INFO - Running setup.py (path:/tmp/pip-sPDRIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:16 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)) 08:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:16 INFO - Running setup.py (path:/tmp/pip-IqjmDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:16 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)) 08:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:16 INFO - Running setup.py (path:/tmp/pip-x8th6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:16 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)) 08:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:16 INFO - Running setup.py (path:/tmp/pip-DAfN3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:16 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)) 08:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:16 INFO - Running setup.py (path:/tmp/pip-VVakOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:16 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)) 08:10:16 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)) 08:10:16 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)) 08:10:16 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)) 08:10:16 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)) 08:10:16 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)) 08:10:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:10: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 08:10: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 08:10: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 08:10: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 08:10:17 INFO - Downloading blessings-1.6.tar.gz 08:10:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:10:17 INFO - Installing collected packages: blessings 08:10:17 INFO - Running setup.py install for blessings 08:10:17 INFO - Successfully installed blessings 08:10:17 INFO - Cleaning up... 08:10:17 INFO - Return code: 0 08:10:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:10:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:18 INFO - Reading from file tmpfile_stdout 08:10:18 INFO - Current package versions: 08:10:18 INFO - argparse == 1.2.1 08:10:18 INFO - blessings == 1.6 08:10:18 INFO - blobuploader == 1.2.4 08:10:18 INFO - docopt == 0.6.1 08:10:18 INFO - functools32 == 3.2.3-2 08:10:18 INFO - jsonschema == 2.5.1 08:10:18 INFO - manifestparser == 1.1 08:10:18 INFO - mozInstall == 1.12 08:10:18 INFO - mozcrash == 1.0 08:10:18 INFO - mozdebug == 0.1 08:10:18 INFO - mozdevice == 0.49 08:10:18 INFO - mozfile == 1.2 08:10:18 INFO - mozhttpd == 0.7 08:10:18 INFO - mozinfo == 0.9 08:10:18 INFO - mozleak == 0.1 08:10:18 INFO - mozlog == 3.4 08:10:18 INFO - moznetwork == 0.27 08:10:18 INFO - mozprocess == 0.25 08:10:18 INFO - mozprofile == 0.28 08:10:18 INFO - mozrunner == 6.13 08:10:18 INFO - mozscreenshot == 0.1 08:10:18 INFO - mozsystemmonitor == 0.3 08:10:18 INFO - moztest == 0.8 08:10:18 INFO - mozversion == 1.4 08:10:18 INFO - psutil == 3.1.1 08:10:18 INFO - requests == 1.2.3 08:10:18 INFO - wsgiref == 0.1.2 08:10:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:18 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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:18 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:10:18 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:10:18 INFO - Using env: {'DISPLAY': ':0', 08:10:18 INFO - 'HOME': '/home/cltbld', 08:10:18 INFO - 'LANG': 'en_US.UTF-8', 08:10:18 INFO - 'LANGUAGE': 'en_US:en', 08:10:18 INFO - 'LOGNAME': 'cltbld', 08:10:18 INFO - 'MAIL': '/var/mail/cltbld', 08:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:18 INFO - 'MOZ_NO_REMOTE': '1', 08:10:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:18 INFO - 'NO_EM_RESTART': '1', 08:10:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:18 INFO - 'PWD': '/builds/slave/test', 08:10:18 INFO - 'SHELL': '/bin/bash', 08:10:18 INFO - 'SHLVL': '1', 08:10:18 INFO - 'TERM': 'linux', 08:10:18 INFO - 'TMOUT': '86400', 08:10:18 INFO - 'USER': 'cltbld', 08:10:18 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:18 INFO - '_': '/tools/buildbot/bin/python'} 08:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:18 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)) 08:10:18 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)) 08:10:18 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)) 08:10:18 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)) 08:10:18 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)) 08:10:18 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)) 08:10:18 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)) 08:10:18 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)) 08:10:18 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)) 08:10:18 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)) 08:10:18 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)) 08:10:18 INFO - Cleaning up... 08:10:18 INFO - Return code: 0 08:10:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10: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/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 0x166d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16aba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190b310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', '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 08:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:10:18 INFO - Using env: {'DISPLAY': ':0', 08:10:18 INFO - 'HOME': '/home/cltbld', 08:10:18 INFO - 'LANG': 'en_US.UTF-8', 08:10:18 INFO - 'LANGUAGE': 'en_US:en', 08:10:18 INFO - 'LOGNAME': 'cltbld', 08:10:18 INFO - 'MAIL': '/var/mail/cltbld', 08:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:18 INFO - 'MOZ_NO_REMOTE': '1', 08:10:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:18 INFO - 'NO_EM_RESTART': '1', 08:10:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:18 INFO - 'PWD': '/builds/slave/test', 08:10:18 INFO - 'SHELL': '/bin/bash', 08:10:18 INFO - 'SHLVL': '1', 08:10:18 INFO - 'TERM': 'linux', 08:10:18 INFO - 'TMOUT': '86400', 08:10:18 INFO - 'USER': 'cltbld', 08:10:18 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:18 INFO - '_': '/tools/buildbot/bin/python'} 08:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:10:18 INFO - Cleaning up... 08:10:18 INFO - Return code: 0 08:10:18 INFO - Running post-action listener: _resource_record_post_action 08:10:18 INFO - Running post-action listener: _start_resource_monitoring 08:10:18 INFO - Starting resource monitoring. 08:10:18 INFO - [mozharness: 2017-05-30 15:10:18.645924Z] Finished create-virtualenv step (success) 08:10:18 INFO - [mozharness: 2017-05-30 15:10:18.646435Z] Running install step. 08:10:18 INFO - Running pre-action listener: _resource_record_pre_action 08:10:18 INFO - Running main action method: install 08:10:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:18 INFO - Reading from file tmpfile_stdout 08:10:18 INFO - Detecting whether we're running mozinstall >=1.0... 08:10:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:10:18 INFO - Reading from file tmpfile_stdout 08:10:18 INFO - Output received: 08:10:18 INFO - Usage: mozinstall [options] installer 08:10:18 INFO - Options: 08:10:18 INFO - -h, --help show this help message and exit 08:10:18 INFO - -d DEST, --destination=DEST 08:10:18 INFO - Directory to install application into. [default: 08:10:18 INFO - "/builds/slave/test"] 08:10:18 INFO - --app=APP Application being installed. [default: firefox] 08:10:18 INFO - mkdir: /builds/slave/test/build/application 08:10:18 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'] 08:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:10:33 INFO - Reading from file tmpfile_stdout 08:10:33 INFO - Output received: 08:10:33 INFO - /builds/slave/test/build/application/firefox/firefox 08:10:33 INFO - Running post-action listener: _resource_record_post_action 08:10:33 INFO - [mozharness: 2017-05-30 15:10:33.017296Z] Finished install step (success) 08:10:33 INFO - [mozharness: 2017-05-30 15:10:33.017539Z] Running run-tests step. 08:10:33 INFO - Running pre-action listener: _resource_record_pre_action 08:10:33 INFO - Running main action method: run_tests 08:10:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:33 INFO - Python 2.7.3 08:10:33 INFO - Return code: 0 08:10:33 INFO - grabbing minidump binary from tooltool 08:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 08:10:33 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 0x1908400>, '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 0x19093b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1909840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:33 INFO - Return code: 0 08:10:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:10:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:33 INFO - ENV: RUST_BACKTRACE is now 1 08:10:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:10:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 08:10:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-063 --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 08:10:33 INFO - Using env: {'DISPLAY': ':0', 08:10:33 INFO - 'HOME': '/home/cltbld', 08:10:33 INFO - 'LANG': 'en_US.UTF-8', 08:10:33 INFO - 'LANGUAGE': 'en_US:en', 08:10:33 INFO - 'LOGNAME': 'cltbld', 08:10:33 INFO - 'MAIL': '/var/mail/cltbld', 08:10:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:10:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:33 INFO - 'MOZ_NO_REMOTE': '1', 08:10:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:33 INFO - 'NO_EM_RESTART': '1', 08:10:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:33 INFO - 'PWD': '/builds/slave/test', 08:10:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:33 INFO - 'RUST_BACKTRACE': '1', 08:10:33 INFO - 'SHELL': '/bin/bash', 08:10:33 INFO - 'SHLVL': '1', 08:10:33 INFO - 'TERM': 'linux', 08:10:33 INFO - 'TMOUT': '86400', 08:10:33 INFO - 'USER': 'cltbld', 08:10:33 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231', 08:10:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:33 INFO - '_': '/tools/buildbot/bin/python'} 08:10:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 08:10:33 INFO - mozversion application_buildid: 20170530141011 08:10:33 INFO - mozversion application_changeset: 3524778fe631486d5a9cd4f5392177f297b337dc 08:10:33 INFO - mozversion application_display_name: Firefox 08:10:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:33 INFO - mozversion application_name: Firefox 08:10:33 INFO - mozversion application_remotingname: firefox 08:10:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:10:33 INFO - mozversion application_vendor: Mozilla 08:10:33 INFO - mozversion application_version: 54.0 08:10:33 INFO - mozversion platform_buildid: 20170530141011 08:10:33 INFO - mozversion platform_changeset: 3524778fe631486d5a9cd4f5392177f297b337dc 08:10:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:10:33 INFO - mozversion platform_version: 54.0 08:10:33 INFO - using testdate: 1496157033 08:10:33 INFO - actual date: 1496157033 08:10:33 INFO - starting webserver on 'localhost:44726' 08:10:33 INFO - SUITE-START | Running 2 tests 08:10:33 INFO - TEST-START | dromaeo_css 08:10:33 INFO - operating with platform_type : linux_ 08:10:33 INFO - Initialising browser for dromaeo_css test... 08:10:33 INFO - TEST-INFO | started process 13763 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5LWOFj/profile http://localhost:44726/getInfo.html) 08:10:37 INFO - PROCESS | 13763 | __metrics Screen width/height:1600/1200 08:10:37 INFO - PROCESS | 13763 | colorDepth:24 08:10:37 INFO - PROCESS | 13763 | Browser inner width/height: 1024/697 08:10:37 INFO - PROCESS | 13763 | __metrics 08:10:38 INFO - PROCESS | 13763 | 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" 08:10:38 INFO - PROCESS | 13763 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:10:39 INFO - TEST-INFO | 13763: exit 0 08:10:39 INFO - Browser initialized. 08:10:39 INFO - Running cycle 1/1 for dromaeo_css test... 08:10:39 INFO - TEST-INFO | started process 13855 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5LWOFj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:51 INFO - PROCESS | 13855 | RSS: Main: 141475840 08:10:51 INFO - PROCESS | 13855 | 08:13:52 INFO - PROCESS | 13855 | Cycle 1(1): loaded http://localhost:44726/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44726/tests/dromaeo/cssquery-ext.html) 08:13:53 INFO - PROCESS | 13855 | RSS: Main: 256032768 08:13:53 INFO - PROCESS | 13855 | 08:16:55 INFO - PROCESS | 13855 | Cycle 1(1): loaded http://localhost:44726/tests/dromaeo/cssquery-ext.html (next: http://localhost:44726/tests/dromaeo/cssquery-jquery.html) 08:16:56 INFO - PROCESS | 13855 | RSS: Main: 228683776 08:16:56 INFO - PROCESS | 13855 | 08:19:58 INFO - PROCESS | 13855 | Cycle 1(1): loaded http://localhost:44726/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44726/tests/dromaeo/cssquery-mootools.html) 08:19:58 INFO - PROCESS | 13855 | RSS: Main: 173895680 08:19:58 INFO - PROCESS | 13855 | 08:23:00 INFO - PROCESS | 13855 | Cycle 1(1): loaded http://localhost:44726/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44726/tests/dromaeo/cssquery-prototype.html) 08:23:01 INFO - PROCESS | 13855 | RSS: Main: 171368448 08:23:01 INFO - PROCESS | 13855 | 08:26:04 INFO - PROCESS | 13855 | Cycle 1(1): loaded http://localhost:44726/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44726/tests/dromaeo/cssquery-yui.html) 08:26:04 INFO - PROCESS | 13855 | RSS: Main: 174710784 08:26:04 INFO - PROCESS | 13855 | 08:29:07 INFO - PROCESS | 13855 | Cycle 1(1): loaded http://localhost:44726/tests/dromaeo/cssquery-yui.html (next: http://localhost:44726/tests/dromaeo/cssquery-dojo.html) 08:29:07 INFO - PROCESS | 13855 | RSS: Main: 258338816 08:29:07 INFO - PROCESS | 13855 | 08:29:07 INFO - PROCESS | 13855 | __start_tp_report 08:29:07 INFO - PROCESS | 13855 | _x_x_mozilla_page_load 08:29:07 INFO - PROCESS | 13855 | _x_x_mozilla_page_load_details 08:29:07 INFO - PROCESS | 13855 | |i|pagename|runs| 08:29:07 INFO - PROCESS | 13855 | |0;dojo.html;3318.9336213275733;3355.094124586361;3365.326934613078;3366.6296707362203;3402.965970340296;5642.548596112311;5649.717514124298;5653.067243904761;5660.915086273704;5712.42875712429;5682.26130603021;5772.278465191851;5781.103928891029;5786.913196302059;5789.4500022497905;5228.843134705946;5238.816641417551;5243.895122097565;5269.341332333458;5299.708516031616;5078.568321692661;5104.3619547556555;5317.734113294339;5325.600579956499;5359.919601205985;5212.817551385702;5228.973855130719;5235.345581802275;5248.895022099556;5260.605454590902;5320.600954928376;5328.49379308965;5329.040772660919;5329.360476742777;5358.839234822923;5048.974755126237;5177.689338639693;5243.370795504526;5291.576673866081;5304.151335786294;5583.022970980103;5597.860053498654;5601.719914004324;5608.383077861432;5610.607257491977;5965.791197308116;6000.039993601046;6002.669853158074;6017;6019.127226552169;6061.696915154224;6082.513398928119;6084.695765211722;6086.543509236781;6120.908186377208;5805.303363596396;5807.651540907516;5839.708014599253;5840.795572154997;5847.649141051551;6250.124982502453;6269.592476489105;6273.0904018917;6275.6862156891975;6279.340669229665;5798.478136967693;5836.970815145982;6066.393360663987;6072.028475443908;6076.969615151896;6102.511799056065;6113.932424433873;6125.368194935222;6135.661410523194;6139;6146;6148.508119350441;6149.723262453111;6163.876722465526;6165.907511387333;5875.500582450454;5897.852553686193;5898.8820223595285;5905;6060.727267272983;4734.360861283752;4746.905061898743;4751.691140075884;4760.285957106462;4762.404699412574;4177.686673499439;4189.099343641132;4191;4213;4218.767967761742;5584.4136365681015;5878.059510478389;5889.057750759944;5890.175375447441;5894.823155305347;5959.22530071088;6061.151438798572;6094.786682466092;6105.877882442415;6106.786262480792;5801.361850196475;5842.357340692521;5848.181254624356;5849.473547380756;5856.092305692625;5180.145276029413;5225;5230.3985041719925;5230.555402790806;5278.129108697099;6131.346082861964;6149.200603921461;6306.905396419057;6309.495240380758;6364.363563643598;6222;6224.533160013108;6228.564000520101;6229.937700622936;6237.033259844731;6473.838154046187;6478.708458119301;6480.740770369132;6482.189726284215;6485.870282594227;5839.649621022752;5938.673372964528;6058.515318774399;6111.266648002268;6152.477039451696;6210.968945155245;6264.498840092872;6265;6266.937330626635;6268.498520118471;6074.696265186812;6081.635101893724;6081.817545473643;6082.5438092142385;6086.847828804315;5814.970925145347;5860.648361098348;5986.760529578768;6047.395260473917;6100.847478813065;6044.848878778066;6074.240719910012;6075.544334175043;6097.969510152421;6108.694565271808;6020.969895150672;6071.301800293023;6076.210092687922;6080.0575910733905;6109.20580324555;5675.659460432387;5709.286339207599;5710.086386178192;5801.216835727123;5827.679477628772 08:29:07 INFO - PROCESS | 13855 | |1;ext.html;755.4522970846137;812.6343145584488;830.0661755525034;874.921257086862;885.2784980241099;31066.223344416372;43484.565154348486;43750.781246093764;43810;43954.34068488975;45820.541794582045;45947;45948;46018.769906150425;46035;7545.50954187976;9006.45961242326;9142.680006199784;9148.771280718001;9150.222231110363;7347.265273472637;10566.471676416148;10703.571857125706;10764.946175269113;10785.892141078606;10840.512176952017;10912.290701104403;10928.672139835779;10940.56237750489;10948.835767463494;10742.3017503862;10810.837837432446;10840.837387439155;10847.783044339109;10888.510017049251;10370.792584148276;10371.896281037169;10404.79190416195;10409.479526023668;10428.374297542172;42019.57980420187;42233.366499502226;42253.788731056455;42346.57653423457;42359.36460953088;4026.939595906064;4044.1507283470214;4060.2691515527194;4144.171165766858;4196.685248606367;30187;30510.54234186489;30819.845900770575;30912.690873091207;31178.064658060066;4330.5669433056755;4435.268180750172;4438.911221775546;4462.219111655448;4507;225079;225654;225824;226801;226803;52380;52541;52672;52694;52735.20897186545;56661.433385665616;56725.71637141788;56732;56737.43262567322;56764.71617641968;16431.1784410779;16912.154392280336;16914.407995720092;16920.492385228463;16929.238184281738;12096.637100886988;12246;15020.17389043613;15030.849691502945;15036.097834129987;13188.208707477583;13509.662258443617;13611.387487563083;13759.518416855362;13874.722505549833;5567.749451274703;5581.358143813432;5672.404397538282;5688.288963879515;5741.85645358861;1038.4651904269308;1042.5136050519566;1043.3844032020993;1245.5578269714301;1323.3317174826843;28460.146195614165;29390.265243369086;29635.259118522208;29714.851425742734;29762.25594360115;1158.472894832849;1184.1934614962422;1190.5594929876002;1220.3837062283667;1227.9017678585692;14471.565853024425;14934.850651493345;15132.697346053237;15366.923165384325;15561.610959725871;314735;357404;359821;472243;473079;48273;48465;48600.7569962155;48685;48688;1473.3811799044427;1505.3978408636633;1518.2105305241212;1523.748581484065;1529.4264650755968;27863.44273114486;28203.15390538287;28671.624765254255;29411.264718382212;29513.704862951097;41152.58847411488;41186.1762764749;41230.79384603058;41268;41313.1737365245;279822;279866;279987;280591;281015;24612.507749844546;26547.867260663574;29862.85068574643;30384.696153039073;30544.694553054185;38177.42733858939;38249.23501529898;38452;38846;39415.21169576535;29989.700102999563;30272.84863575668;30409;30585;30842;30430;31547.52678709775;32729;32790;34523;36383.27233455369;36953.44569831401;36971.8151409252;37091;37435;30337.544936825525;30899.69100308968;30906.536401953537;31297;31542.842285788425;25609.23172304834;25665.743342566337;25795.742042579335;25824.48351032931;25967.480650387264 08:29:07 INFO - PROCESS | 13855 | |2;jquery.html;448.93490443885617;455.4762023672776;468.10591769719844;475.3962467666064;478.8467690339092;15729.842701572981;16145.757813632805;19883.60232795343;21335.82652954089;22540.661890071642;21387;21582.0576148147;21637.3508794736;22001.449963750874;22128.22551210708;6708.765193218238;6991.440684745208;7107.147142342926;7116.252793456664;7134.357907788298;6298.551333193368;6854.10896583446;6954.40887524561;7005.089338386003;7048.1542214934025;6824.522283440161;7052.259512751164;7059.929400706004;7062.905249686307;7066.222715501291;6889.276625954278;6999;7016.333448322402;7043.260457651975;7075.8584828303665;6702.798916032478;6848.589084654936;6861.965690171517;6890.93109068913;6924.23833378328;26339;26698.332541686417;27044.459110817872;27051.323716907038;27301.180964571096;3210.502372132323;3272.132884785534;3309.172706823294;3386.5766779152605;3391.491276308549;16413.097279649613;17151.571210719707;17265.482035538953;17493.475195744148;17589.120543972876;2348.624220124772;2459.6802415686025;2498.362917456055;2566.550853600631;2741.4105967217142;116360;120020.39989800082;120239.3988030063;121123;124308;21590.676139857915;21619.243326483498;21659.67510487344;22533.661995070088;22554;43319.783401082794;43434.34848477275;43541.34687979683;43633.34549981753;43663;9524.904750952403;9752.46361450127;9765.755856103513;9885.703428897144;9933.15568176713;7616.428767842434;7670.194629563927;7776.84446311082;7851.882221766564;7912.525248484994;10864.565417383374;10966.835497467544;11446.427678616203;11506.367149806674;11633.76732465346;4055.411965265079;4233.216854881856;4394.560543945579;4438.822447102079;4568.17772800899;963.3352986439335;966.4684423567151;976.2482888176065;980.8310205218459;983.7147227304145;21054.157833686786;21899.67150492744;22927.777750008692;23165.884170579375;23355.299341019796;1093.5516438260274;1105.380986647464;1107.6012635451232;1116.7431490757172;1123.4831977290537;10891.618793342195;11472.598459053894;11518.251313664585;11577.247478913843;11669;680065;686469;702953;717533;754347;114724.42637786758;121267;121280;122714;123269;1327.1970457872849;1335.8730920562532;1376.8898488120926;1379.2620947793084;1382.7787553991313;18165.36421225251;18204;18680.813191868452;18698.43904682862;19006.619867602847;50883.491165089355;50955.74522127365;52012;52113.218301724744;53303;63372;69404.30595693979;69697.30302696908;71291.28708712847;72320.63839680768;11590.94204528972;12393.070519710878;12706.30115343628;12713.55502557406;12774.169678970837;14100.153990760587;14118.152910824972;14170.574882753534;14176.574702758933;14243.359048843035;11856.762864742484;11880.524779009089;11932.701682458008;12302.138850280395;12576;11367.54529818798;11526.654200374001;11553.826692599785;11631.941840291083;11671.533138674358;12191.634250972485;12551.121421500407;12677.492900283885;12691.175073620185;12739.363031848185;12386.380680966096;12440.440180191732;12570.560030398892;12578.119531633063;12584.622461326175;9585.616575336908;11859.644210673694;11868.465919033491;12486.937565312115;12528.812067818808 08:29:07 INFO - PROCESS | 13855 | |3;mootools.html;1401.222321611505;1579.7551379536176;1593.8645215156703;1597.2971892367357;1612.5887898585859;9426.34015618907;11275.548978040879;11458.48436820343;11543.53825846965;11543.595974140897;11847.822282665767;11866.050715942725;11893.048556115511;11913.404329783498;11954.282743035425;1964.3419454482694;2310.90756369745;2345.835791494596;2346.6245400735884;2347.3310106619615;1985.9801401986012;2009.889456079915;2010.2059686423927;2280.4982903761147;2364.3498038039506;2309.1917828759883;2313.2250696016854;2314.826388020905;2325.6278995360744;2326.488172602025;2165.1988764157204;2174.1629472652985;2188.1794327127327;2191.6493361062235;2191.934241972743;2229.3534874886263;2245.2141750387395;2249.6737972993824;2251.470904337479;2254.109626697462;11678.656954450262;11753.059755219561;11756.706082347924;11767.41162941858;11769.176157668966;5734.168545560912;5851.444112809277;5879.8530036749;5881.617694849864;5888.352281249059;3320.7177389921644;3373.713234375081;3422.6748458896363;3491.3715531204375;3535.1515636247113;1311;1312.0880987713447;1314.7370525894764;1365.4060483689627;1367.5692156970522;16775.2451139699;16775.580610484714;16817.495475135765;16839.32642694291;16846.747298790528;4768.690035147704;4949.76255936016;4983.302337672729;5010.448850626428;5014.323066385991;13520.729585408237;13597.728045439037;13613.11514751538;13622.659433514242;13626.727465450636;6029.457348838626;6048.395160483917;6055.182550355734;6055.939440605625;6058.848528786727;5365.946340536622;5368.973155134199;5373.785048598091;5455.699936503531;5458.9454105458435;3516.613172551017;3588.6411358863907;3961.8217180226957;4121;4245.80893859777;1107.684309971654;1110.5890820396344;1120.1374941295157;1130.4799792095564;1141.851559297286;1027.547878933267;1037.2479952034714;1046.0951277145214;1058.8517607534798;1064.4518073192312;6965.860682786316;7227.819304517429;7295;7429.145648250413;7467.767818310027;1108.1356541897333;1127.2165844737829;1142.194752699345;1151.890570395811;1172.6599286207074;4739.691920025256;4782.856514304576;4846.39420072491;4854.732989685601;4971.825986090469;14452.494162704465;14937.103773773608;14939.70120597582;14944.925275373553;15044.699106018037;14668.633284167769;15193.772093418607;15286.617834554003;15302.693946121015;15308.158051307062;10760.085392741705;10949.343039417661;10990.560377584807;10995.955384238645;11055.668329950115;7136.3220494052985;7269.127704675261;7335.77992660221;7502.924970750222;7628.199039100815;13269.668258293234;13271.800922986356;13307.334633268492;13390.062695611214;13406.463741450232;13511.662208444868;13538;13637.795433068313;13658.590242292747;13663.726725465634;10025.699229023141;10110;10178.083972442515;10225.233107516817;10300.587976480856;9358.906410935804;9578.185854202193;10366.326188797704;11078.501467433824;11280.379579122899;9443.480608566595;9467.905320946702;9481.478518681262;9649.614015439303;9814;9001.684941027033;9107.362484626012;9320.6271749132;9324.813503730024;9379.859302110337;8380.16198380157;8524.957375213333;8534.06125326226;8538.402311838112;8543.530105844238;6540.803775886731;6568.343165683394;6609.570377925419;6640.369164929324;6733.663316834237;6341.55609107358;6396.968015159894;6449.355064493513;6538.411542961157;6560.40956313934 08:29:07 INFO - PROCESS | 13855 | |4;prototype.html;416.10329739411577;438.11500768447735;443.5761206527048;445.1275500019961;447.3290064902646;5218.017153481676;5331.973340133299;5341.626086173965;5342.759575819087;5421.484958928905;5085.288059671649;5306.681599104057;5312.123499622558;5312.707801070939;5335.866603334918;4409.228385032622;4410.514843367227;4418.895276180955;4424.823007079713;4457.1977044132045;4493.606981835642;4503.437070366204;4505.887352816173;4558.817647294104;4566.246569316058;4425.424694789673;4517.232070547998;4545.9545404546025;4550.817967281305;4558.81764729412;4461.06317673289;4468.017036252019;4469.418975533193;4472.74763066342;4492.932606010912;4203.957960420387;4206.149294310524;4213.620774130312;4217.599328063829;4218.6625069994325;5195.220716892496;5204.5427280361555;5231.476852314776;5237.55967109048;5265.3253615817885;5114.514121158484;5158.561522270612;5193.480651934814;5193.870153246199;5202.583793296527;5600.747966341483;5610.24261724666;5613;5641.407652196502;5650.180723795027;4357.433533640607;4547.408836851221;4573.199690054227;4605.055963527502;4739.857804265877;5477.260569823063;5568.66588004717;5569.192467092289;5734.33704225774;5766.192733017381;4704.506026867198;4718.669693121517;4721.6458765592715;4788.664793464493;4799.688020278671;4830.903381932412;4831.347768051268;4846.376463884568;4846.733429661332;4862.319275301461;4819.518048195152;4849.903001940012;5023.372078490189;5108.540231379194;5167.715775632301;5480.972595136999;5489.780408783604;5495.845872366848;5500.28496295479;5552.333719953632;1630.2582325042076;1654.3134599141463;1669.4657709532833;1675.4638515674867;1678.8332109184244;1258.6664533898524;1293.353323338331;1298.817985783892;1304.1588175626669;1319.4062671797728;5242.086638377041;5387.326584176978;5407.918881216785;5428.4028756836715;5431.375391829911;5667.951428985651;5679.261695979496;5695.40198279183;5704.857378565569;5717.456841600041;5296.655717378358;5299.655522391032;5347.219445888117;5407.756650950717;5440.483154100354;2888.8555572221726;2987.581738556604;3042.39152169567;3043.239190202449;3065.647450543216;5902.439268269508;5931.56805709657;5933.199018132583;5952.404759524013;5976.970115149484;5566.749496272678;5798;5830;5868.031774757203;5879.676617786063;5913.881722365529;5920.4967577755515;5921.407859214044;5921.615095018896;5923.792667256797;5669.461401166883;5705.46415599591;5719.0832675336715;5726.885462290647;5746.195532625519;5359.785608575613;5467.261919640957;5542.9168562472405;5586.692731899622;5650.660960342392;5691.402402747652;5801.477866991822;5936.495397891227;5939.970300148644;5954.4938680210935;4767.880802979953;4785.473597904297;4798.592119669867;4803.711777293373;4817.927731084106;5335.253064571041;5726.713664316684;5728.598998070095;5851.795187168429;5853.278225625105;5532.336119665666;5542.75057622416;5545.473180047889;5560.888782224252;5569.972150139223;5561.304836895388;5570.576799092408;5604.85987850307;5623.847111342083;5638.308507447831;5350.678959262456;5470.905818836328;5493.862653433696;5513.558915286848;5518.503334699896;5498.697571633438;5516;5531.308586426697;5569.832905012856;5659.518940890071;5298;5389.892202156013;5452.59105567092;5479;5490.862728431821 08:29:07 INFO - PROCESS | 13855 | |5;yui.html;827.888235088263;847.1316900177318;890.4123278636099;892.2282225874617;920.5857364186118;15522.22388880557;16410.83589164108;16411.261493232803;16428.753568696466;16487.258073386696;16243.83756162438;16682.749758753627;16812.075335856523;17097.145142742876;17106.230219640114;872.5331947408138;900.5317235037793;904.4754042655277;926.5598840550751;930.3766476460754;906.8141031088644;925.3383830561147;925.9120533373293;926.106307413346;926.3561824531953;971.2666936462961;973.2165606686615;974.907383798538;975.2441857560412;975.3075316525241;963.0706368354502;965.1844191658038;974.8927617962019;976.2336565795852;976.4336684722844;706.8798304288283;713.8893471511924;716.0905649824693;716.4232792601954;717.0105254748414;16540.421085262078;16559;16642.58393540159;17344.913275433668;17372.565685857953;15043.548693539211;15285.694286114327;15321.846781532153;15432;15661.530154095395;13063.412146453433;13601.659958501017;13692.246926419042;13759.312034398241;13967.79048314276;4098.610631989956;4103.281925662997;4122.093139509303;4188.225178342016;4230.407742915994;50893.74553127248;55179.44820551783;69772;95169.52415237948;98754;11460.828087578693;11970.940145299217;12086;12107.75784484323;12517.687057823627;20339.288124915558;21036.789632103482;21157;21195.046222920006;21470.141194352364;8909.376343656011;8912;8921.598528066253;8939.597718102701;9061.13919177677;4928.704277743347;4996.200607902791;5072.289879416885;5085;5088.058709138747;3756.962430375716;3851.4607954886337;3861.362875125629;3862.246861861966;3866.4586957825927;637.6461064109371;637.6620391192675;644.365300179323;661.798151563768;666.963317017559;771.2904128202067;785.4030936488195;786.563457281203;808.7129069180473;813.1177672225701;11058.612948546923;11263.099669611698;11345.09239260857;11557.942210289062;11706.76586468283;729.099562040879;789.3172405868883;805.923437273458;815.592203898051;844.6072576252072;4911;5392.865178370494;5578.944210557843;5640.464155905182;5715.914261286084;81909.59045204736;83501;83599.5820020896;84848;85252.57373713092;60642.393576064555;61220.69389653023;74312;75925;77763.61118194372;61767.382326176164;63366.83165841689;63537.68231158907;64586.677066614364;64830;11515.654530363767;11776.76446471083;11826.231294965799;11826.408679565815;12087.51649934028;36495.45256821203;37532.81233593814;37697.633150883725;42626.36060459157;45464.09071818479;56354;57925;58218.7089064552;59692.70153649204;60943;14048;14231.217283049531;14417.207053612152;14435.494757683431;14561.490347837775;16586.58533536671;16709.16454177262;16800.24398902028;17054.488365349058;17319.30722771075;14791.778123327944;14881.627959301104;15244.618884527976;15355.232238387813;15804.36782528686;14152;14227.928860355632;14257.714845703234;14418.42326306936;14474.203918784568;13587.932060339635;13672.711339487516;13743.381547830171;13797.10318829273;13852.861471385157;10509.789804204025;11034.448277586249;11187;11321.716957075809;11442;11358.829617555908;11653.41732913368;11909.464074116859;11942.820857687315;12102.394880256128 08:29:07 INFO - PROCESS | 13855 | __end_tp_report 08:29:07 INFO - PROCESS | 13855 | __start_cc_report 08:29:07 INFO - PROCESS | 13855 | _x_x_mozilla_cycle_collect,1151 08:29:07 INFO - PROCESS | 13855 | __end_cc_report 08:29:07 INFO - PROCESS | 13855 | __startTimestamp1496158147513__endTimestamp 08:29:07 INFO - PROCESS | 13855 | 08:29:07 INFO - PROCESS | 13855 | ------- Summary: start ------- 08:29:07 INFO - PROCESS | 13855 | Number of tests: 6 08:29:07 INFO - PROCESS | 13855 | 08:29:07 INFO - PROCESS | 13855 | [#0] dojo.html Cycles:165 Average:5700.42 Median:5878.06 stddev:637.49 (10.8%) stddev-sans-first:611.46 08:29:07 INFO - PROCESS | 13855 | Values: 3318.9 3355.1 3365.3 3366.6 3403.0 5642.5 5649.7 5653.1 5660.9 5712.4 5682.3 5772.3 5781.1 5786.9 5789.5 5228.8 5238.8 5243.9 5269.3 5299.7 5078.6 5104.4 5317.7 5325.6 5359.9 5212.8 5229.0 5235.3 5248.9 5260.6 5320.6 5328.5 5329.0 5329.4 5358.8 5049.0 5177.7 5243.4 5291.6 5304.2 5583.0 5597.9 5601.7 5608.4 5610.6 5965.8 6000.0 6002.7 6017.0 6019.1 6061.7 6082.5 6084.7 6086.5 6120.9 5805.3 5807.7 5839.7 5840.8 5847.6 6250.1 6269.6 6273.1 6275.7 6279.3 5798.5 5837.0 6066.4 6072.0 6077.0 6102.5 6113.9 6125.4 6135.7 6139.0 6146.0 6148.5 6149.7 6163.9 6165.9 5875.5 5897.9 5898.9 5905.0 6060.7 4734.4 4746.9 4751.7 4760.3 4762.4 4177.7 4189.1 4191.0 4213.0 4218.8 5584.4 5878.1 5889.1 5890.2 5894.8 5959.2 6061.2 6094.8 6105.9 6106.8 5801.4 5842.4 5848.2 5849.5 5856.1 5180.1 5225.0 5230.4 5230.6 5278.1 6131.3 6149.2 6306.9 6309.5 6364.4 6222.0 6224.5 6228.6 6229.9 6237.0 6473.8 6478.7 6480.7 6482.2 6485.9 5839.6 5938.7 6058.5 6111.3 6152.5 6211.0 6264.5 6265.0 6266.9 6268.5 6074.7 6081.6 6081.8 6082.5 6086.8 5815.0 5860.6 5986.8 6047.4 6100.8 6044.8 6074.2 6075.5 6098.0 6108.7 6021.0 6071.3 6076.2 6080.1 6109.2 5675.7 5709.3 5710.1 5801.2 5827.7 08:29:07 INFO - PROCESS | 13855 | 08:29:07 INFO - PROCESS | 13855 | [#1] ext.html Cycles:180 Average:46441.56 Median:30564.85 stddev:81820.14 (267.7%) stddev-sans-first:81977.77 08:29:07 INFO - PROCESS | 13855 | Values: 755.5 812.6 830.1 874.9 885.3 31066.2 43484.6 43750.8 43810.0 43954.3 45820.5 45947.0 45948.0 46018.8 46035.0 7545.5 9006.5 9142.7 9148.8 9150.2 7347.3 10566.5 10703.6 10764.9 10785.9 10840.5 10912.3 10928.7 10940.6 10948.8 10742.3 10810.8 10840.8 10847.8 10888.5 10370.8 10371.9 10404.8 10409.5 10428.4 42019.6 42233.4 42253.8 42346.6 42359.4 4026.9 4044.2 4060.3 4144.2 4196.7 30187.0 30510.5 30819.8 30912.7 31178.1 4330.6 4435.3 4438.9 4462.2 4507.0 225079.0 225654.0 225824.0 226801.0 226803.0 52380.0 52541.0 52672.0 52694.0 52735.2 56661.4 56725.7 56732.0 56737.4 56764.7 16431.2 16912.2 16914.4 16920.5 16929.2 12096.6 12246.0 15020.2 15030.8 15036.1 13188.2 13509.7 13611.4 13759.5 13874.7 5567.7 5581.4 5672.4 5688.3 5741.9 1038.5 1042.5 1043.4 1245.6 1323.3 28460.1 29390.3 29635.3 29714.9 29762.3 1158.5 1184.2 1190.6 1220.4 1227.9 14471.6 14934.9 15132.7 15366.9 15561.6 314735.0 357404.0 359821.0 472243.0 473079.0 48273.0 48465.0 48600.8 48685.0 48688.0 1473.4 1505.4 1518.2 1523.7 1529.4 27863.4 28203.2 28671.6 29411.3 29513.7 41152.6 41186.2 41230.8 41268.0 41313.2 279822.0 279866.0 279987.0 280591.0 281015.0 24612.5 26547.9 29862.9 30384.7 30544.7 38177.4 38249.2 38452.0 38846.0 39415.2 29989.7 30272.8 30409.0 30585.0 30842.0 30430.0 31547.5 32729.0 32790.0 34523.0 36383.3 36953.4 36971.8 37091.0 37435.0 30337.5 30899.7 30906.5 31297.0 31542.8 25609.2 25665.7 25795.7 25824.5 25967.5 08:29:07 INFO - PROCESS | 13855 | 08:29:07 INFO - PROCESS | 13855 | [#2] jquery.html Cycles:180 Average:39942.62 Median:22740.89 stddev:116795.55 (513.6%) stddev-sans-first:117085.55 08:29:07 INFO - PROCESS | 13855 | Values: 448.9 455.5 468.1 475.4 478.8 15729.8 16145.8 19883.6 21335.8 22540.7 21387.0 21582.1 21637.4 22001.4 22128.2 6708.8 6991.4 7107.1 7116.3 7134.4 6298.6 6854.1 6954.4 7005.1 7048.2 6824.5 7052.3 7059.9 7062.9 7066.2 6889.3 6999.0 7016.3 7043.3 7075.9 6702.8 6848.6 6862.0 6890.9 6924.2 26339.0 26698.3 27044.5 27051.3 27301.2 3210.5 3272.1 3309.2 3386.6 3391.5 16413.1 17151.6 17265.5 17493.5 17589.1 2348.6 2459.7 2498.4 2566.6 2741.4 116360.0 120020.4 120239.4 121123.0 124308.0 21590.7 21619.2 21659.7 22533.7 22554.0 43319.8 43434.3 43541.3 43633.3 43663.0 9524.9 9752.5 9765.8 9885.7 9933.2 7616.4 7670.2 7776.8 7851.9 7912.5 10864.6 10966.8 11446.4 11506.4 11633.8 4055.4 4233.2 4394.6 4438.8 4568.2 963.3 966.5 976.2 980.8 983.7 21054.2 21899.7 22927.8 23165.9 23355.3 1093.6 1105.4 1107.6 1116.7 1123.5 10891.6 11472.6 11518.3 11577.2 11669.0 680065.0 686469.0 702953.0 717533.0 754347.0 114724.4 121267.0 121280.0 122714.0 123269.0 1327.2 1335.9 1376.9 1379.3 1382.8 18165.4 18204.0 18680.8 18698.4 19006.6 50883.5 50955.7 52012.0 52113.2 53303.0 63372.0 69404.3 69697.3 71291.3 72320.6 11590.9 12393.1 12706.3 12713.6 12774.2 14100.2 14118.2 14170.6 14176.6 14243.4 11856.8 11880.5 11932.7 12302.1 12576.0 11367.5 11526.7 11553.8 11631.9 11671.5 12191.6 12551.1 12677.5 12691.2 12739.4 12386.4 12440.4 12570.6 12578.1 12584.6 9585.6 11859.6 11868.5 12486.9 12528.8 08:29:07 INFO - PROCESS | 13855 | 08:29:07 INFO - PROCESS | 13855 | [#3] mootools.html Cycles:180 Average:7229.58 Median:2210.64 stddev:4672.66 (211.4%) stddev-sans-first:4665.25 08:29:07 INFO - PROCESS | 13855 | Values: 1401.2 1579.8 1593.9 1597.3 1612.6 9426.3 11275.5 11458.5 11543.5 11543.6 11847.8 11866.1 11893.0 11913.4 11954.3 1964.3 2310.9 2345.8 2346.6 2347.3 1986.0 2009.9 2010.2 2280.5 2364.3 2309.2 2313.2 2314.8 2325.6 2326.5 2165.2 2174.2 2188.2 2191.6 2191.9 2229.4 2245.2 2249.7 2251.5 2254.1 11678.7 11753.1 11756.7 11767.4 11769.2 5734.2 5851.4 5879.9 5881.6 5888.4 3320.7 3373.7 3422.7 3491.4 3535.2 1311.0 1312.1 1314.7 1365.4 1367.6 16775.2 16775.6 16817.5 16839.3 16846.7 4768.7 4949.8 4983.3 5010.4 5014.3 13520.7 13597.7 13613.1 13622.7 13626.7 6029.5 6048.4 6055.2 6055.9 6058.8 5365.9 5369.0 5373.8 5455.7 5458.9 3516.6 3588.6 3961.8 4121.0 4245.8 1107.7 1110.6 1120.1 1130.5 1141.9 1027.5 1037.2 1046.1 1058.9 1064.5 6965.9 7227.8 7295.0 7429.1 7467.8 1108.1 1127.2 1142.2 1151.9 1172.7 4739.7 4782.9 4846.4 4854.7 4971.8 14452.5 14937.1 14939.7 14944.9 15044.7 14668.6 15193.8 15286.6 15302.7 15308.2 10760.1 10949.3 10990.6 10996.0 11055.7 7136.3 7269.1 7335.8 7502.9 7628.2 13269.7 13271.8 13307.3 13390.1 13406.5 13511.7 13538.0 13637.8 13658.6 13663.7 10025.7 10110.0 10178.1 10225.2 10300.6 9358.9 9578.2 10366.3 11078.5 11280.4 9443.5 9467.9 9481.5 9649.6 9814.0 9001.7 9107.4 9320.6 9324.8 9379.9 8380.2 8525.0 8534.1 8538.4 8543.5 6540.8 6568.3 6609.6 6640.4 6733.7 6341.6 6397.0 6449.4 6538.4 6560.4 08:29:07 INFO - PROCESS | 13855 | 08:29:07 INFO - PROCESS | 13855 | [#4] prototype.html Cycles:180 Average:4853.42 Median:5335.56 stddev:1282.08 (24.0%) stddev-sans-first:1241.66 08:29:07 INFO - PROCESS | 13855 | Values: 416.1 438.1 443.6 445.1 447.3 5218.0 5332.0 5341.6 5342.8 5421.5 5085.3 5306.7 5312.1 5312.7 5335.9 4409.2 4410.5 4418.9 4424.8 4457.2 4493.6 4503.4 4505.9 4558.8 4566.2 4425.4 4517.2 4546.0 4550.8 4558.8 4461.1 4468.0 4469.4 4472.7 4492.9 4204.0 4206.1 4213.6 4217.6 4218.7 5195.2 5204.5 5231.5 5237.6 5265.3 5114.5 5158.6 5193.5 5193.9 5202.6 5600.7 5610.2 5613.0 5641.4 5650.2 4357.4 4547.4 4573.2 4605.1 4739.9 5477.3 5568.7 5569.2 5734.3 5766.2 4704.5 4718.7 4721.6 4788.7 4799.7 4830.9 4831.3 4846.4 4846.7 4862.3 4819.5 4849.9 5023.4 5108.5 5167.7 5481.0 5489.8 5495.8 5500.3 5552.3 1630.3 1654.3 1669.5 1675.5 1678.8 1258.7 1293.4 1298.8 1304.2 1319.4 5242.1 5387.3 5407.9 5428.4 5431.4 5668.0 5679.3 5695.4 5704.9 5717.5 5296.7 5299.7 5347.2 5407.8 5440.5 2888.9 2987.6 3042.4 3043.2 3065.6 5902.4 5931.6 5933.2 5952.4 5977.0 5566.7 5798.0 5830.0 5868.0 5879.7 5913.9 5920.5 5921.4 5921.6 5923.8 5669.5 5705.5 5719.1 5726.9 5746.2 5359.8 5467.3 5542.9 5586.7 5650.7 5691.4 5801.5 5936.5 5940.0 5954.5 4767.9 4785.5 4798.6 4803.7 4817.9 5335.3 5726.7 5728.6 5851.8 5853.3 5532.3 5542.8 5545.5 5560.9 5570.0 5561.3 5570.6 5604.9 5623.8 5638.3 5350.7 5470.9 5493.9 5513.6 5518.5 5498.7 5516.0 5531.3 5569.8 5659.5 5298.0 5389.9 5452.6 5479.0 5490.9 08:29:07 INFO - PROCESS | 13855 | 08:29:07 INFO - PROCESS | 13855 | [#5] yui.html Cycles:180 Average:18264.31 Median:4155.16 stddev:22788.60 (548.4%) stddev-sans-first:22814.92 08:29:07 INFO - PROCESS | 13855 | Values: 827.9 847.1 890.4 892.2 920.6 15522.2 16410.8 16411.3 16428.8 16487.3 16243.8 16682.7 16812.1 17097.1 17106.2 872.5 900.5 904.5 926.6 930.4 906.8 925.3 925.9 926.1 926.4 971.3 973.2 974.9 975.2 975.3 963.1 965.2 974.9 976.2 976.4 706.9 713.9 716.1 716.4 717.0 16540.4 16559.0 16642.6 17344.9 17372.6 15043.5 15285.7 15321.8 15432.0 15661.5 13063.4 13601.7 13692.2 13759.3 13967.8 4098.6 4103.3 4122.1 4188.2 4230.4 50893.7 55179.4 69772.0 95169.5 98754.0 11460.8 11970.9 12086.0 12107.8 12517.7 20339.3 21036.8 21157.0 21195.0 21470.1 8909.4 8912.0 8921.6 8939.6 9061.1 4928.7 4996.2 5072.3 5085.0 5088.1 3757.0 3851.5 3861.4 3862.2 3866.5 637.6 637.7 644.4 661.8 667.0 771.3 785.4 786.6 808.7 813.1 11058.6 11263.1 11345.1 11557.9 11706.8 729.1 789.3 805.9 815.6 844.6 4911.0 5392.9 5578.9 5640.5 5715.9 81909.6 83501.0 83599.6 84848.0 85252.6 60642.4 61220.7 74312.0 75925.0 77763.6 61767.4 63366.8 63537.7 64586.7 64830.0 11515.7 11776.8 11826.2 11826.4 12087.5 36495.5 37532.8 37697.6 42626.4 45464.1 56354.0 57925.0 58218.7 59692.7 60943.0 14048.0 14231.2 14417.2 14435.5 14561.5 16586.6 16709.2 16800.2 17054.5 17319.3 14791.8 14881.6 15244.6 15355.2 15804.4 14152.0 14227.9 14257.7 14418.4 14474.2 13587.9 13672.7 13743.4 13797.1 13852.9 10509.8 11034.4 11187.0 11321.7 11442.0 11358.8 11653.4 11909.5 11942.8 12102.4 08:29:07 INFO - PROCESS | 13855 | -------- Summary: end -------- 08:29:07 INFO - PROCESS | 13855 | 08:29:08 INFO - TEST-INFO | 13855: exit 0 08:29:08 INFO - TEST-OK | dromaeo_css | took 1114976ms 08:29:08 INFO - TEST-START | kraken 08:29:08 INFO - operating with platform_type : linux_ 08:29:08 INFO - Initialising browser for kraken test... 08:29:08 INFO - TEST-INFO | started process 14164 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHoH8YV/profile http://localhost:44726/getInfo.html) 08:29:12 INFO - PROCESS | 14164 | __metrics Screen width/height:1600/1200 08:29:12 INFO - PROCESS | 14164 | colorDepth:24 08:29:12 INFO - PROCESS | 14164 | Browser inner width/height: 1024/697 08:29:12 INFO - PROCESS | 14164 | __metrics 08:29:13 INFO - PROCESS | 14164 | 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" 08:29:13 INFO - PROCESS | 14164 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:29:14 INFO - TEST-INFO | 14164: exit 0 08:29:14 INFO - Browser initialized. 08:29:14 INFO - Running cycle 1/1 for kraken test... 08:29:14 INFO - TEST-INFO | started process 14255 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHoH8YV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:25 INFO - PROCESS | 14255 | RSS: Main: 141152256 08:29:25 INFO - PROCESS | 14255 | 08:29:28 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44726/tests/kraken/driver.html?testName=audio-beat-detection) 08:29:29 INFO - PROCESS | 14255 | RSS: Main: 184082432 08:29:29 INFO - PROCESS | 14255 | 08:29:31 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44726/tests/kraken/driver.html?testName=audio-dft) 08:29:32 INFO - PROCESS | 14255 | RSS: Main: 173731840 08:29:32 INFO - PROCESS | 14255 | 08:29:35 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44726/tests/kraken/driver.html?testName=audio-fft) 08:29:35 INFO - PROCESS | 14255 | RSS: Main: 265203712 08:29:35 INFO - PROCESS | 14255 | 08:29:37 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44726/tests/kraken/driver.html?testName=audio-oscillator) 08:29:38 INFO - PROCESS | 14255 | RSS: Main: 171950080 08:29:38 INFO - PROCESS | 14255 | 08:29:40 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44726/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:29:40 INFO - PROCESS | 14255 | RSS: Main: 174399488 08:29:40 INFO - PROCESS | 14255 | 08:29:43 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44726/tests/kraken/driver.html?testName=imaging-darkroom) 08:29:44 INFO - PROCESS | 14255 | RSS: Main: 213311488 08:29:44 INFO - PROCESS | 14255 | 08:29:47 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44726/tests/kraken/driver.html?testName=imaging-desaturate) 08:29:48 INFO - PROCESS | 14255 | RSS: Main: 213008384 08:29:48 INFO - PROCESS | 14255 | 08:29:51 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44726/tests/kraken/driver.html?testName=json-parse-financial) 08:29:51 INFO - PROCESS | 14255 | RSS: Main: 211402752 08:29:51 INFO - PROCESS | 14255 | 08:29:53 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44726/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:29:54 INFO - PROCESS | 14255 | RSS: Main: 173281280 08:29:54 INFO - PROCESS | 14255 | 08:29:55 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44726/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:29:56 INFO - PROCESS | 14255 | RSS: Main: 180400128 08:29:56 INFO - PROCESS | 14255 | 08:29:58 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44726/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:29:59 INFO - PROCESS | 14255 | RSS: Main: 186097664 08:29:59 INFO - PROCESS | 14255 | 08:30:01 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44726/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:30:02 INFO - PROCESS | 14255 | RSS: Main: 183865344 08:30:02 INFO - PROCESS | 14255 | 08:30:04 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44726/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:30:05 INFO - PROCESS | 14255 | RSS: Main: 186351616 08:30:05 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | Cycle 1(1): loaded http://localhost:44726/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44726/tests/kraken/driver.html?testName=ai-astar) 08:30:07 INFO - PROCESS | 14255 | RSS: Main: 238993408 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | __start_tp_report 08:30:07 INFO - PROCESS | 14255 | _x_x_mozilla_page_load 08:30:07 INFO - PROCESS | 14255 | _x_x_mozilla_page_load_details 08:30:07 INFO - PROCESS | 14255 | |i|pagename|runs| 08:30:07 INFO - PROCESS | 14255 | |0;ai-astar;104;113;114;112;113;113;112;114;112;112 08:30:07 INFO - PROCESS | 14255 | |1;audio-beat-detection;145;115;123;151;122;121;116;117;133;120 08:30:07 INFO - PROCESS | 14255 | |2;audio-dft;162;162;161;161;162;161;162;161;160;162 08:30:07 INFO - PROCESS | 14255 | |3;audio-fft;75;77;75;109;74;77;78;76;92;75 08:30:07 INFO - PROCESS | 14255 | |4;audio-oscillator;82;81;104;82;83;82;94;82;81;99 08:30:07 INFO - PROCESS | 14255 | |5;imaging-gaussian-blur;113;112;113;113;113;113;113;112;113;113 08:30:07 INFO - PROCESS | 14255 | |6;imaging-darkroom;147;147;148;147;148;147;146;147;146;146 08:30:07 INFO - PROCESS | 14255 | |7;imaging-desaturate;93;93;94;93;93;93;94;93;93;93 08:30:07 INFO - PROCESS | 14255 | |8;json-parse-financial;54;53;54;54;52;53;54;54;53;54 08:30:07 INFO - PROCESS | 14255 | |9;json-stringify-tinderbox;53;54;53;54;54;54;54;54;54;53 08:30:07 INFO - PROCESS | 14255 | |10;stanford-crypto-aes;81;79;85;78;82;75;82;84;95;77 08:30:07 INFO - PROCESS | 14255 | |11;stanford-crypto-ccm;123;123;120;120;127;121;127;119;127;123 08:30:07 INFO - PROCESS | 14255 | |12;stanford-crypto-pbkdf2;157;157;154;155;154;156;156;157;156;158 08:30:07 INFO - PROCESS | 14255 | |13;stanford-crypto-sha256-iterative;63;62;65;60;59;59;63;59;59;58 08:30:07 INFO - PROCESS | 14255 | __end_tp_report 08:30:07 INFO - PROCESS | 14255 | __start_cc_report 08:30:07 INFO - PROCESS | 14255 | _x_x_mozilla_cycle_collect,1629 08:30:07 INFO - PROCESS | 14255 | __end_cc_report 08:30:07 INFO - PROCESS | 14255 | __startTimestamp1496158207274__endTimestamp 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | ------- Summary: start ------- 08:30:07 INFO - PROCESS | 14255 | Number of tests: 14 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:2.88 (2.6%) stddev-sans-first:0.83 08:30:07 INFO - PROCESS | 14255 | Values: 104.0 113.0 114.0 112.0 113.0 113.0 112.0 114.0 112.0 112.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:122.50 stddev:12.57 (10.3%) stddev-sans-first:11.37 08:30:07 INFO - PROCESS | 14255 | Values: 145.0 115.0 123.0 151.0 122.0 121.0 116.0 117.0 133.0 120.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 08:30:07 INFO - PROCESS | 14255 | Values: 162.0 162.0 161.0 161.0 162.0 161.0 162.0 161.0 160.0 162.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.50 stddev:11.19 (14.6%) stddev-sans-first:11.67 08:30:07 INFO - PROCESS | 14255 | Values: 75.0 77.0 75.0 109.0 74.0 77.0 78.0 76.0 92.0 75.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#4] audio-oscillator Cycles:10 Average:87.00 Median:82.00 stddev:8.63 (10.5%) stddev-sans-first:8.96 08:30:07 INFO - PROCESS | 14255 | Values: 82.0 81.0 104.0 82.0 83.0 82.0 94.0 82.0 81.0 99.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:30:07 INFO - PROCESS | 14255 | Values: 113.0 112.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 113.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 08:30:07 INFO - PROCESS | 14255 | Values: 147.0 147.0 148.0 147.0 148.0 147.0 146.0 147.0 146.0 146.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#7] imaging-desaturate Cycles:10 Average:93.20 Median:93.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 08:30:07 INFO - PROCESS | 14255 | Values: 93.0 93.0 94.0 93.0 93.0 93.0 94.0 93.0 93.0 93.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:0.71 (1.3%) stddev-sans-first:0.73 08:30:07 INFO - PROCESS | 14255 | Values: 54.0 53.0 54.0 54.0 52.0 53.0 54.0 54.0 53.0 54.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#9] json-stringify-tinderbox Cycles:10 Average:53.70 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.44 08:30:07 INFO - PROCESS | 14255 | Values: 53.0 54.0 53.0 54.0 54.0 54.0 54.0 54.0 54.0 53.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:5.59 (6.8%) stddev-sans-first:5.93 08:30:07 INFO - PROCESS | 14255 | Values: 81.0 79.0 85.0 78.0 82.0 75.0 82.0 84.0 95.0 77.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:123.00 stddev:3.09 (2.5%) stddev-sans-first:3.28 08:30:07 INFO - PROCESS | 14255 | Values: 123.0 123.0 120.0 120.0 127.0 121.0 127.0 119.0 127.0 123.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.00 Median:156.50 stddev:1.33 (0.9%) stddev-sans-first:1.36 08:30:07 INFO - PROCESS | 14255 | Values: 157.0 157.0 154.0 155.0 154.0 156.0 156.0 157.0 156.0 158.0 08:30:07 INFO - PROCESS | 14255 | 08:30:07 INFO - PROCESS | 14255 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:61.00 stddev:2.36 (3.9%) stddev-sans-first:2.35 08:30:07 INFO - PROCESS | 14255 | Values: 63.0 62.0 65.0 60.0 59.0 59.0 63.0 59.0 59.0 58.0 08:30:07 INFO - PROCESS | 14255 | -------- Summary: end -------- 08:30:07 INFO - PROCESS | 14255 | 08:30:08 INFO - TEST-INFO | 14255: exit 0 08:30:08 INFO - TEST-OK | kraken | took 60072ms 08:30:08 INFO - SUITE-END | took 1175s 08:30:08 INFO - Completed test suite (00:19:35) 08:30:08 INFO - javascript benchmark 08:30:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7896.925992550635, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.9336213275733, 3355.094124586361, 3365.326934613078, 3366.6296707362203, 3402.965970340296, 5642.548596112311, 5649.717514124298, 5653.067243904761, 5660.915086273704, 5712.42875712429, 5682.26130603021, 5772.278465191851, 5781.103928891029, 5786.913196302059, 5789.4500022497905, 5228.843134705946, 5238.816641417551, 5243.895122097565, 5269.341332333458, 5299.708516031616, 5078.568321692661, 5104.3619547556555, 5317.734113294339, 5325.600579956499, 5359.919601205985, 5212.817551385702, 5228.973855130719, 5235.345581802275, 5248.895022099556, 5260.605454590902, 5320.600954928376, 5328.49379308965, 5329.040772660919, 5329.360476742777, 5358.839234822923, 5048.974755126237, 5177.689338639693, 5243.370795504526, 5291.576673866081, 5304.151335786294, 5583.022970980103, 5597.860053498654, 5601.719914004324, 5608.383077861432, 5610.607257491977, 5965.791197308116, 6000.039993601046, 6002.669853158074, 6017.0, 6019.127226552169, 6061.696915154224, 6082.513398928119, 6084.695765211722, 6086.543509236781, 6120.908186377208, 5805.303363596396, 5807.651540907516, 5839.708014599253, 5840.795572154997, 5847.649141051551, 6250.124982502453, 6269.592476489105, 6273.0904018917, 6275.6862156891975, 6279.340669229665, 5798.478136967693, 5836.970815145982, 6066.393360663987, 6072.028475443908, 6076.969615151896, 6102.511799056065, 6113.932424433873, 6125.368194935222, 6135.661410523194, 6139.0, 6146.0, 6148.508119350441, 6149.723262453111, 6163.876722465526, 6165.907511387333, 5875.500582450454, 5897.852553686193, 5898.8820223595285, 5905.0, 6060.727267272983, 4734.360861283752, 4746.905061898743, 4751.691140075884, 4760.285957106462, 4762.404699412574, 4177.686673499439, 4189.099343641132, 4191.0, 4213.0, 4218.767967761742, 5584.4136365681015, 5878.059510478389, 5889.057750759944, 5890.175375447441, 5894.823155305347, 5959.22530071088, 6061.151438798572, 6094.786682466092, 6105.877882442415, 6106.786262480792, 5801.361850196475, 5842.357340692521, 5848.181254624356, 5849.473547380756, 5856.092305692625, 5180.145276029413, 5225.0, 5230.3985041719925, 5230.555402790806, 5278.129108697099, 6131.346082861964, 6149.200603921461, 6306.905396419057, 6309.495240380758, 6364.363563643598, 6222.0, 6224.533160013108, 6228.564000520101, 6229.937700622936, 6237.033259844731, 6473.838154046187, 6478.708458119301, 6480.740770369132, 6482.189726284215, 6485.870282594227, 5839.649621022752, 5938.673372964528, 6058.515318774399, 6111.266648002268, 6152.477039451696, 6210.968945155245, 6264.498840092872, 6265.0, 6266.937330626635, 6268.498520118471, 6074.696265186812, 6081.635101893724, 6081.817545473643, 6082.5438092142385, 6086.847828804315, 5814.970925145347, 5860.648361098348, 5986.760529578768, 6047.395260473917, 6100.847478813065, 6044.848878778066, 6074.240719910012, 6075.544334175043, 6097.969510152421, 6108.694565271808, 6020.969895150672, 6071.301800293023, 6076.210092687922, 6080.0575910733905, 6109.20580324555, 5675.659460432387, 5709.286339207599, 5710.086386178192, 5801.216835727123, 5827.679477628772], "value": 5658.3142712790595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.4522970846137, 812.6343145584488, 830.0661755525034, 874.921257086862, 885.2784980241099, 31066.223344416372, 43484.565154348486, 43750.781246093764, 43810.0, 43954.34068488975, 45820.541794582045, 45947.0, 45948.0, 46018.769906150425, 46035.0, 7545.50954187976, 9006.45961242326, 9142.680006199784, 9148.771280718001, 9150.222231110363, 7347.265273472637, 10566.471676416148, 10703.571857125706, 10764.946175269113, 10785.892141078606, 10840.512176952017, 10912.290701104403, 10928.672139835779, 10940.56237750489, 10948.835767463494, 10742.3017503862, 10810.837837432446, 10840.837387439155, 10847.783044339109, 10888.510017049251, 10370.792584148276, 10371.896281037169, 10404.79190416195, 10409.479526023668, 10428.374297542172, 42019.57980420187, 42233.366499502226, 42253.788731056455, 42346.57653423457, 42359.36460953088, 4026.939595906064, 4044.1507283470214, 4060.2691515527194, 4144.171165766858, 4196.685248606367, 30187.0, 30510.54234186489, 30819.845900770575, 30912.690873091207, 31178.064658060066, 4330.5669433056755, 4435.268180750172, 4438.911221775546, 4462.219111655448, 4507.0, 225079.0, 225654.0, 225824.0, 226801.0, 226803.0, 52380.0, 52541.0, 52672.0, 52694.0, 52735.20897186545, 56661.433385665616, 56725.71637141788, 56732.0, 56737.43262567322, 56764.71617641968, 16431.1784410779, 16912.154392280336, 16914.407995720092, 16920.492385228463, 16929.238184281738, 12096.637100886988, 12246.0, 15020.17389043613, 15030.849691502945, 15036.097834129987, 13188.208707477583, 13509.662258443617, 13611.387487563083, 13759.518416855362, 13874.722505549833, 5567.749451274703, 5581.358143813432, 5672.404397538282, 5688.288963879515, 5741.85645358861, 1038.4651904269308, 1042.5136050519566, 1043.3844032020993, 1245.5578269714301, 1323.3317174826843, 28460.146195614165, 29390.265243369086, 29635.259118522208, 29714.851425742734, 29762.25594360115, 1158.472894832849, 1184.1934614962422, 1190.5594929876002, 1220.3837062283667, 1227.9017678585692, 14471.565853024425, 14934.850651493345, 15132.697346053237, 15366.923165384325, 15561.610959725871, 314735.0, 357404.0, 359821.0, 472243.0, 473079.0, 48273.0, 48465.0, 48600.7569962155, 48685.0, 48688.0, 1473.3811799044427, 1505.3978408636633, 1518.2105305241212, 1523.748581484065, 1529.4264650755968, 27863.44273114486, 28203.15390538287, 28671.624765254255, 29411.264718382212, 29513.704862951097, 41152.58847411488, 41186.1762764749, 41230.79384603058, 41268.0, 41313.1737365245, 279822.0, 279866.0, 279987.0, 280591.0, 281015.0, 24612.507749844546, 26547.867260663574, 29862.85068574643, 30384.696153039073, 30544.694553054185, 38177.42733858939, 38249.23501529898, 38452.0, 38846.0, 39415.21169576535, 29989.700102999563, 30272.84863575668, 30409.0, 30585.0, 30842.0, 30430.0, 31547.52678709775, 32729.0, 32790.0, 34523.0, 36383.27233455369, 36953.44569831401, 36971.8151409252, 37091.0, 37435.0, 30337.544936825525, 30899.69100308968, 30906.536401953537, 31297.0, 31542.842285788425, 25609.23172304834, 25665.743342566337, 25795.742042579335, 25824.48351032931, 25967.480650387264], "value": 18906.19748478736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.93490443885617, 455.4762023672776, 468.10591769719844, 475.3962467666064, 478.8467690339092, 15729.842701572981, 16145.757813632805, 19883.60232795343, 21335.82652954089, 22540.661890071642, 21387.0, 21582.0576148147, 21637.3508794736, 22001.449963750874, 22128.22551210708, 6708.765193218238, 6991.440684745208, 7107.147142342926, 7116.252793456664, 7134.357907788298, 6298.551333193368, 6854.10896583446, 6954.40887524561, 7005.089338386003, 7048.1542214934025, 6824.522283440161, 7052.259512751164, 7059.929400706004, 7062.905249686307, 7066.222715501291, 6889.276625954278, 6999.0, 7016.333448322402, 7043.260457651975, 7075.8584828303665, 6702.798916032478, 6848.589084654936, 6861.965690171517, 6890.93109068913, 6924.23833378328, 26339.0, 26698.332541686417, 27044.459110817872, 27051.323716907038, 27301.180964571096, 3210.502372132323, 3272.132884785534, 3309.172706823294, 3386.5766779152605, 3391.491276308549, 16413.097279649613, 17151.571210719707, 17265.482035538953, 17493.475195744148, 17589.120543972876, 2348.624220124772, 2459.6802415686025, 2498.362917456055, 2566.550853600631, 2741.4105967217142, 116360.0, 120020.39989800082, 120239.3988030063, 121123.0, 124308.0, 21590.676139857915, 21619.243326483498, 21659.67510487344, 22533.661995070088, 22554.0, 43319.783401082794, 43434.34848477275, 43541.34687979683, 43633.34549981753, 43663.0, 9524.904750952403, 9752.46361450127, 9765.755856103513, 9885.703428897144, 9933.15568176713, 7616.428767842434, 7670.194629563927, 7776.84446311082, 7851.882221766564, 7912.525248484994, 10864.565417383374, 10966.835497467544, 11446.427678616203, 11506.367149806674, 11633.76732465346, 4055.411965265079, 4233.216854881856, 4394.560543945579, 4438.822447102079, 4568.17772800899, 963.3352986439335, 966.4684423567151, 976.2482888176065, 980.8310205218459, 983.7147227304145, 21054.157833686786, 21899.67150492744, 22927.777750008692, 23165.884170579375, 23355.299341019796, 1093.5516438260274, 1105.380986647464, 1107.6012635451232, 1116.7431490757172, 1123.4831977290537, 10891.618793342195, 11472.598459053894, 11518.251313664585, 11577.247478913843, 11669.0, 680065.0, 686469.0, 702953.0, 717533.0, 754347.0, 114724.42637786758, 121267.0, 121280.0, 122714.0, 123269.0, 1327.1970457872849, 1335.8730920562532, 1376.8898488120926, 1379.2620947793084, 1382.7787553991313, 18165.36421225251, 18204.0, 18680.813191868452, 18698.43904682862, 19006.619867602847, 50883.491165089355, 50955.74522127365, 52012.0, 52113.218301724744, 53303.0, 63372.0, 69404.30595693979, 69697.30302696908, 71291.28708712847, 72320.63839680768, 11590.94204528972, 12393.070519710878, 12706.30115343628, 12713.55502557406, 12774.169678970837, 14100.153990760587, 14118.152910824972, 14170.574882753534, 14176.574702758933, 14243.359048843035, 11856.762864742484, 11880.524779009089, 11932.701682458008, 12302.138850280395, 12576.0, 11367.54529818798, 11526.654200374001, 11553.826692599785, 11631.941840291083, 11671.533138674358, 12191.634250972485, 12551.121421500407, 12677.492900283885, 12691.175073620185, 12739.363031848185, 12386.380680966096, 12440.440180191732, 12570.560030398892, 12578.119531633063, 12584.622461326175, 9585.616575336908, 11859.644210673694, 11868.465919033491, 12486.937565312115, 12528.812067818808], "value": 11886.262484076728, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.222321611505, 1579.7551379536176, 1593.8645215156703, 1597.2971892367357, 1612.5887898585859, 9426.34015618907, 11275.548978040879, 11458.48436820343, 11543.53825846965, 11543.595974140897, 11847.822282665767, 11866.050715942725, 11893.048556115511, 11913.404329783498, 11954.282743035425, 1964.3419454482694, 2310.90756369745, 2345.835791494596, 2346.6245400735884, 2347.3310106619615, 1985.9801401986012, 2009.889456079915, 2010.2059686423927, 2280.4982903761147, 2364.3498038039506, 2309.1917828759883, 2313.2250696016854, 2314.826388020905, 2325.6278995360744, 2326.488172602025, 2165.1988764157204, 2174.1629472652985, 2188.1794327127327, 2191.6493361062235, 2191.934241972743, 2229.3534874886263, 2245.2141750387395, 2249.6737972993824, 2251.470904337479, 2254.109626697462, 11678.656954450262, 11753.059755219561, 11756.706082347924, 11767.41162941858, 11769.176157668966, 5734.168545560912, 5851.444112809277, 5879.8530036749, 5881.617694849864, 5888.352281249059, 3320.7177389921644, 3373.713234375081, 3422.6748458896363, 3491.3715531204375, 3535.1515636247113, 1311.0, 1312.0880987713447, 1314.7370525894764, 1365.4060483689627, 1367.5692156970522, 16775.2451139699, 16775.580610484714, 16817.495475135765, 16839.32642694291, 16846.747298790528, 4768.690035147704, 4949.76255936016, 4983.302337672729, 5010.448850626428, 5014.323066385991, 13520.729585408237, 13597.728045439037, 13613.11514751538, 13622.659433514242, 13626.727465450636, 6029.457348838626, 6048.395160483917, 6055.182550355734, 6055.939440605625, 6058.848528786727, 5365.946340536622, 5368.973155134199, 5373.785048598091, 5455.699936503531, 5458.9454105458435, 3516.613172551017, 3588.6411358863907, 3961.8217180226957, 4121.0, 4245.80893859777, 1107.684309971654, 1110.5890820396344, 1120.1374941295157, 1130.4799792095564, 1141.851559297286, 1027.547878933267, 1037.2479952034714, 1046.0951277145214, 1058.8517607534798, 1064.4518073192312, 6965.860682786316, 7227.819304517429, 7295.0, 7429.145648250413, 7467.767818310027, 1108.1356541897333, 1127.2165844737829, 1142.194752699345, 1151.890570395811, 1172.6599286207074, 4739.691920025256, 4782.856514304576, 4846.39420072491, 4854.732989685601, 4971.825986090469, 14452.494162704465, 14937.103773773608, 14939.70120597582, 14944.925275373553, 15044.699106018037, 14668.633284167769, 15193.772093418607, 15286.617834554003, 15302.693946121015, 15308.158051307062, 10760.085392741705, 10949.343039417661, 10990.560377584807, 10995.955384238645, 11055.668329950115, 7136.3220494052985, 7269.127704675261, 7335.77992660221, 7502.924970750222, 7628.199039100815, 13269.668258293234, 13271.800922986356, 13307.334633268492, 13390.062695611214, 13406.463741450232, 13511.662208444868, 13538.0, 13637.795433068313, 13658.590242292747, 13663.726725465634, 10025.699229023141, 10110.0, 10178.083972442515, 10225.233107516817, 10300.587976480856, 9358.906410935804, 9578.185854202193, 10366.326188797704, 11078.501467433824, 11280.379579122899, 9443.480608566595, 9467.905320946702, 9481.478518681262, 9649.614015439303, 9814.0, 9001.684941027033, 9107.362484626012, 9320.6271749132, 9324.813503730024, 9379.859302110337, 8380.16198380157, 8524.957375213333, 8534.06125326226, 8538.402311838112, 8543.530105844238, 6540.803775886731, 6568.343165683394, 6609.570377925419, 6640.369164929324, 6733.663316834237, 6341.55609107358, 6396.968015159894, 6449.355064493513, 6538.411542961157, 6560.40956313934], "value": 5420.3592879522985, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.10329739411577, 438.11500768447735, 443.5761206527048, 445.1275500019961, 447.3290064902646, 5218.017153481676, 5331.973340133299, 5341.626086173965, 5342.759575819087, 5421.484958928905, 5085.288059671649, 5306.681599104057, 5312.123499622558, 5312.707801070939, 5335.866603334918, 4409.228385032622, 4410.514843367227, 4418.895276180955, 4424.823007079713, 4457.1977044132045, 4493.606981835642, 4503.437070366204, 4505.887352816173, 4558.817647294104, 4566.246569316058, 4425.424694789673, 4517.232070547998, 4545.9545404546025, 4550.817967281305, 4558.81764729412, 4461.06317673289, 4468.017036252019, 4469.418975533193, 4472.74763066342, 4492.932606010912, 4203.957960420387, 4206.149294310524, 4213.620774130312, 4217.599328063829, 4218.6625069994325, 5195.220716892496, 5204.5427280361555, 5231.476852314776, 5237.55967109048, 5265.3253615817885, 5114.514121158484, 5158.561522270612, 5193.480651934814, 5193.870153246199, 5202.583793296527, 5600.747966341483, 5610.24261724666, 5613.0, 5641.407652196502, 5650.180723795027, 4357.433533640607, 4547.408836851221, 4573.199690054227, 4605.055963527502, 4739.857804265877, 5477.260569823063, 5568.66588004717, 5569.192467092289, 5734.33704225774, 5766.192733017381, 4704.506026867198, 4718.669693121517, 4721.6458765592715, 4788.664793464493, 4799.688020278671, 4830.903381932412, 4831.347768051268, 4846.376463884568, 4846.733429661332, 4862.319275301461, 4819.518048195152, 4849.903001940012, 5023.372078490189, 5108.540231379194, 5167.715775632301, 5480.972595136999, 5489.780408783604, 5495.845872366848, 5500.28496295479, 5552.333719953632, 1630.2582325042076, 1654.3134599141463, 1669.4657709532833, 1675.4638515674867, 1678.8332109184244, 1258.6664533898524, 1293.353323338331, 1298.817985783892, 1304.1588175626669, 1319.4062671797728, 5242.086638377041, 5387.326584176978, 5407.918881216785, 5428.4028756836715, 5431.375391829911, 5667.951428985651, 5679.261695979496, 5695.40198279183, 5704.857378565569, 5717.456841600041, 5296.655717378358, 5299.655522391032, 5347.219445888117, 5407.756650950717, 5440.483154100354, 2888.8555572221726, 2987.581738556604, 3042.39152169567, 3043.239190202449, 3065.647450543216, 5902.439268269508, 5931.56805709657, 5933.199018132583, 5952.404759524013, 5976.970115149484, 5566.749496272678, 5798.0, 5830.0, 5868.031774757203, 5879.676617786063, 5913.881722365529, 5920.4967577755515, 5921.407859214044, 5921.615095018896, 5923.792667256797, 5669.461401166883, 5705.46415599591, 5719.0832675336715, 5726.885462290647, 5746.195532625519, 5359.785608575613, 5467.261919640957, 5542.9168562472405, 5586.692731899622, 5650.660960342392, 5691.402402747652, 5801.477866991822, 5936.495397891227, 5939.970300148644, 5954.4938680210935, 4767.880802979953, 4785.473597904297, 4798.592119669867, 4803.711777293373, 4817.927731084106, 5335.253064571041, 5726.713664316684, 5728.598998070095, 5851.795187168429, 5853.278225625105, 5532.336119665666, 5542.75057622416, 5545.473180047889, 5560.888782224252, 5569.972150139223, 5561.304836895388, 5570.576799092408, 5604.85987850307, 5623.847111342083, 5638.308507447831, 5350.678959262456, 5470.905818836328, 5493.862653433696, 5513.558915286848, 5518.503334699896, 5498.697571633438, 5516.0, 5531.308586426697, 5569.832905012856, 5659.518940890071, 5298.0, 5389.892202156013, 5452.59105567092, 5479.0, 5490.862728431821], "value": 4484.6561340939725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.888235088263, 847.1316900177318, 890.4123278636099, 892.2282225874617, 920.5857364186118, 15522.22388880557, 16410.83589164108, 16411.261493232803, 16428.753568696466, 16487.258073386696, 16243.83756162438, 16682.749758753627, 16812.075335856523, 17097.145142742876, 17106.230219640114, 872.5331947408138, 900.5317235037793, 904.4754042655277, 926.5598840550751, 930.3766476460754, 906.8141031088644, 925.3383830561147, 925.9120533373293, 926.106307413346, 926.3561824531953, 971.2666936462961, 973.2165606686615, 974.907383798538, 975.2441857560412, 975.3075316525241, 963.0706368354502, 965.1844191658038, 974.8927617962019, 976.2336565795852, 976.4336684722844, 706.8798304288283, 713.8893471511924, 716.0905649824693, 716.4232792601954, 717.0105254748414, 16540.421085262078, 16559.0, 16642.58393540159, 17344.913275433668, 17372.565685857953, 15043.548693539211, 15285.694286114327, 15321.846781532153, 15432.0, 15661.530154095395, 13063.412146453433, 13601.659958501017, 13692.246926419042, 13759.312034398241, 13967.79048314276, 4098.610631989956, 4103.281925662997, 4122.093139509303, 4188.225178342016, 4230.407742915994, 50893.74553127248, 55179.44820551783, 69772.0, 95169.52415237948, 98754.0, 11460.828087578693, 11970.940145299217, 12086.0, 12107.75784484323, 12517.687057823627, 20339.288124915558, 21036.789632103482, 21157.0, 21195.046222920006, 21470.141194352364, 8909.376343656011, 8912.0, 8921.598528066253, 8939.597718102701, 9061.13919177677, 4928.704277743347, 4996.200607902791, 5072.289879416885, 5085.0, 5088.058709138747, 3756.962430375716, 3851.4607954886337, 3861.362875125629, 3862.246861861966, 3866.4586957825927, 637.6461064109371, 637.6620391192675, 644.365300179323, 661.798151563768, 666.963317017559, 771.2904128202067, 785.4030936488195, 786.563457281203, 808.7129069180473, 813.1177672225701, 11058.612948546923, 11263.099669611698, 11345.09239260857, 11557.942210289062, 11706.76586468283, 729.099562040879, 789.3172405868883, 805.923437273458, 815.592203898051, 844.6072576252072, 4911.0, 5392.865178370494, 5578.944210557843, 5640.464155905182, 5715.914261286084, 81909.59045204736, 83501.0, 83599.5820020896, 84848.0, 85252.57373713092, 60642.393576064555, 61220.69389653023, 74312.0, 75925.0, 77763.61118194372, 61767.382326176164, 63366.83165841689, 63537.68231158907, 64586.677066614364, 64830.0, 11515.654530363767, 11776.76446471083, 11826.231294965799, 11826.408679565815, 12087.51649934028, 36495.45256821203, 37532.81233593814, 37697.633150883725, 42626.36060459157, 45464.09071818479, 56354.0, 57925.0, 58218.7089064552, 59692.70153649204, 60943.0, 14048.0, 14231.217283049531, 14417.207053612152, 14435.494757683431, 14561.490347837775, 16586.58533536671, 16709.16454177262, 16800.24398902028, 17054.488365349058, 17319.30722771075, 14791.778123327944, 14881.627959301104, 15244.618884527976, 15355.232238387813, 15804.36782528686, 14152.0, 14227.928860355632, 14257.714845703234, 14418.42326306936, 14474.203918784568, 13587.932060339635, 13672.711339487516, 13743.381547830171, 13797.10318829273, 13852.861471385157, 10509.789804204025, 11034.448277586249, 11187.0, 11321.716957075809, 11442.0, 11358.829617555908, 11653.41732913368, 11909.464074116859, 11942.820857687315, 12102.394880256128], "value": 7845.416664294568, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1449.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 114.0, 112.0, 113.0, 113.0, 112.0, 114.0, 112.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 123.0, 151.0, 122.0, 121.0, 116.0, 117.0, 133.0, 120.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 161.0, 162.0, 161.0, 162.0, 161.0, 160.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 109.0, 74.0, 77.0, 78.0, 76.0, 92.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 104.0, 82.0, 83.0, 82.0, 94.0, 82.0, 81.0, 99.0], "value": 87.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 147.0, 148.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0], "value": 93.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 54.0, 52.0, 53.0, 54.0, 54.0, 53.0, 54.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 85.0, 78.0, 82.0, 75.0, 82.0, 84.0, 95.0, 77.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 123.0, 120.0, 120.0, 127.0, 121.0, 127.0, 119.0, 127.0, 123.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 157.0, 154.0, 155.0, 154.0, 156.0, 156.0, 157.0, 156.0, 158.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 65.0, 60.0, 59.0, 59.0, 63.0, 59.0, 59.0, 58.0], "value": 60.7, "unit": "score"}]}]} 08:30:08 INFO - Return code: 0 08:30:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:30:08 INFO - # TBPL SUCCESS # 08:30:08 INFO - Running post-action listener: _resource_record_post_action 08:30:08 INFO - [mozharness: 2017-05-30 15:30:08.571620Z] Finished run-tests step (success) 08:30:08 INFO - Running post-run listener: _resource_record_post_run 08:30:08 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 326799360; Read time: 100; Write time: 179496 08:30:08 INFO - TinderboxPrint: CPU usage
13.1% 08:30:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 100 08:30:08 INFO - TinderboxPrint: I/O write bytes / time
326,799,360 / 179,496 08:30:08 INFO - TinderboxPrint: CPU idle
8,244.8 (86.9%) 08:30:08 INFO - TinderboxPrint: CPU user
1,200.6 (12.6%) 08:30:08 INFO - TinderboxPrint: Swap in / out
0 / 0 08:30:08 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 98877440; Read time: 0; Write time: 83412 08:30:08 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 227336192; Read time: 100; Write time: 96060 08:30:09 INFO - Running post-run listener: _upload_blobber_files 08:30:09 INFO - Blob upload gear active. 08:30:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:30:09 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 08:30:09 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-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:30:09 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-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:30:09 INFO - (blobuploader) - INFO - Open directory for files ... 08:30:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:30:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:30:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:10 INFO - (blobuploader) - INFO - Done attempting. 08:30:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:30:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:30:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:11 INFO - (blobuploader) - INFO - Done attempting. 08:30:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:30:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:30:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:12 INFO - (blobuploader) - INFO - Done attempting. 08:30:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:30:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:30:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:17 INFO - (blobuploader) - INFO - Done attempting. 08:30:17 INFO - (blobuploader) - INFO - Iteration through files over. 08:30:17 INFO - Return code: 0 08:30:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:30:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:30:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/29a272a81d57605b0bd9245bc8ac9310c5f44ef087525cb32469d03d0d20e56e22a9b69bc2f97e40074ed373697c274fad69d9a40a4552e928311cb56ab84d64", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ba243f1ee7aee11e0beb4992ba2667a29ff8442a2085485e04741c084535f92ac1c52fcefac04a6747aaeaf65d25d19aa7e20a5bce4ccdfd55f9bd6af92d0893", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55f28311258bfdb1971bae387c8c5b70c44da5ef1cf2b1ba69e4d8036176199e59ec97c6d39eeb2ca67e0b94a47bdfb9a03d795b3e5c30dd30377c5b705bafd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b4a1a76fb5e0390598357008adaacb69393ac7276b626e554ccbd387556eddd43610e971ea231d18c6cb34ca4a604950ab0980197cca8e853779caa73a975f48"} 08:30:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:30:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:30:17 INFO - Contents: 08:30:17 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/29a272a81d57605b0bd9245bc8ac9310c5f44ef087525cb32469d03d0d20e56e22a9b69bc2f97e40074ed373697c274fad69d9a40a4552e928311cb56ab84d64", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ba243f1ee7aee11e0beb4992ba2667a29ff8442a2085485e04741c084535f92ac1c52fcefac04a6747aaeaf65d25d19aa7e20a5bce4ccdfd55f9bd6af92d0893", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55f28311258bfdb1971bae387c8c5b70c44da5ef1cf2b1ba69e4d8036176199e59ec97c6d39eeb2ca67e0b94a47bdfb9a03d795b3e5c30dd30377c5b705bafd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b4a1a76fb5e0390598357008adaacb69393ac7276b626e554ccbd387556eddd43610e971ea231d18c6cb34ca4a604950ab0980197cca8e853779caa73a975f48"} 08:30:17 INFO - Running post-run listener: copy_logs_to_upload_dir 08:30:17 INFO - Copying logs to upload dir... 08:30:17 INFO - mkdir: /builds/slave/test/build/upload/logs 08:30:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.558380 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-30 08:30:16.698825) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 08:30:16.702563) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/29a272a81d57605b0bd9245bc8ac9310c5f44ef087525cb32469d03d0d20e56e22a9b69bc2f97e40074ed373697c274fad69d9a40a4552e928311cb56ab84d64", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ba243f1ee7aee11e0beb4992ba2667a29ff8442a2085485e04741c084535f92ac1c52fcefac04a6747aaeaf65d25d19aa7e20a5bce4ccdfd55f9bd6af92d0893", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55f28311258bfdb1971bae387c8c5b70c44da5ef1cf2b1ba69e4d8036176199e59ec97c6d39eeb2ca67e0b94a47bdfb9a03d795b3e5c30dd30377c5b705bafd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b4a1a76fb5e0390598357008adaacb69393ac7276b626e554ccbd387556eddd43610e971ea231d18c6cb34ca4a604950ab0980197cca8e853779caa73a975f48"} build_url:https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011409 build_url: 'https://queue.taskcluster.net/v1/task/cWAXvGM4R22iMRAkSG3X6A/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/29a272a81d57605b0bd9245bc8ac9310c5f44ef087525cb32469d03d0d20e56e22a9b69bc2f97e40074ed373697c274fad69d9a40a4552e928311cb56ab84d64", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ba243f1ee7aee11e0beb4992ba2667a29ff8442a2085485e04741c084535f92ac1c52fcefac04a6747aaeaf65d25d19aa7e20a5bce4ccdfd55f9bd6af92d0893", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55f28311258bfdb1971bae387c8c5b70c44da5ef1cf2b1ba69e4d8036176199e59ec97c6d39eeb2ca67e0b94a47bdfb9a03d795b3e5c30dd30377c5b705bafd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b4a1a76fb5e0390598357008adaacb69393ac7276b626e554ccbd387556eddd43610e971ea231d18c6cb34ca4a604950ab0980197cca8e853779caa73a975f48"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 08:30:16.727065) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:30:16.727400) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1496154589.625893-1323489231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004562 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:30:16.812210) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 08:30:16.812525) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 08:30:16.812841) ========= ========= Total master_lag: 0.17 =========