builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-057 starttime: 1506011130.1 results: success (0) revision: b14c75b83d0226333b1240466ea9f07cfb206ff3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:30.097399) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:30.098203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:30.098735) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:30.118349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:30.118966) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 09:25:30-- 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-09-21 09:25:30 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174589 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:30.315963) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:30.316293) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020762 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:30.367700) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-21 09:25:30.368087) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b14c75b83d0226333b1240466ea9f07cfb206ff3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev b14c75b83d0226333b1240466ea9f07cfb206ff3 --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=934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 09:25:30,418 truncating revision to first 12 chars 2017-09-21 09:25:30,418 Setting DEBUG logging. 2017-09-21 09:25:30,418 attempt 1/10 2017-09-21 09:25:30,418 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b14c75b83d02?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 09:25:31,662 unpacking tar archive at: mozilla-central-b14c75b83d02/testing/mozharness/ program finished with exit code 0 elapsedTime=1.554786 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-21 09:25:31.936771) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:31.937212) ========= script_repo_revision: b14c75b83d0226333b1240466ea9f07cfb206ff3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:31.937645) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:31.937927) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 09:25:31.951161) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-21 09:25:31.951655) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:25:32 INFO - MultiFileLogger online at 20170921 09:25:32 in /builds/slave/test 09:25:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:25:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:25:32 INFO - {'append_to_log': False, 09:25:32 INFO - 'base_work_dir': '/builds/slave/test', 09:25:32 INFO - 'blob_upload_branch': 'Firefox', 09:25:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:25:32 INFO - 'branch': 'Firefox', 09:25:32 INFO - 'buildbot_json_path': 'buildprops.json', 09:25:32 INFO - 'code_coverage': False, 09:25:32 INFO - 'config_files': ('talos/linux_config.py',), 09:25:32 INFO - 'default_actions': ('clobber', 09:25:32 INFO - 'read-buildbot-config', 09:25:32 INFO - 'download-and-extract', 09:25:32 INFO - 'populate-webroot', 09:25:32 INFO - 'create-virtualenv', 09:25:32 INFO - 'install', 09:25:32 INFO - 'setup-mitmproxy', 09:25:32 INFO - 'run-tests'), 09:25:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:25:32 INFO - 'disable_ccov_upload': False, 09:25:32 INFO - 'disable_stylo': False, 09:25:32 INFO - 'download_minidump_stackwalk': True, 09:25:32 INFO - 'download_symbols': 'ondemand', 09:25:32 INFO - 'enable_stylo': False, 09:25:32 INFO - 'enable_webrender': False, 09:25:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 09:25:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:25:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:25:32 INFO - 'gecko_profile': False, 09:25:32 INFO - 'gecko_profile_interval': 0, 09:25:32 INFO - 'installer_path': 'installer.exe', 09:25:32 INFO - 'log_level': 'info', 09:25:32 INFO - 'log_name': 'talos', 09:25:32 INFO - 'log_to_console': True, 09:25:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:25:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:25:32 INFO - 'opt_config_files': (), 09:25:32 INFO - 'pip_index': False, 09:25:32 INFO - 'suite': 'dromaeojs-e10s', 09:25:32 INFO - 'system_bits': '32', 09:25:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:25:32 INFO - 'title': 'talos-linux64-ix-057', 09:25:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:25:32 INFO - 'use_talos_json': True, 09:25:32 INFO - 'verify': 'False', 09:25:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:25:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:25:32 INFO - 'work_dir': 'build'} 09:25:32 INFO - [mozharness: 2017-09-21 16:25:32.146904Z] Running clobber step. 09:25:32 INFO - Running pre-action listener: _resource_record_pre_action 09:25:32 INFO - Running main action method: clobber 09:25:32 INFO - rmtree: /builds/slave/test/build 09:25:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:25:33 INFO - Running post-action listener: _resource_record_post_action 09:25:33 INFO - [mozharness: 2017-09-21 16:25:33.769010Z] Finished clobber step (success) 09:25:33 INFO - [mozharness: 2017-09-21 16:25:33.769107Z] Running read-buildbot-config step. 09:25:33 INFO - Running pre-action listener: _resource_record_pre_action 09:25:33 INFO - Running main action method: read_buildbot_config 09:25:33 INFO - Using buildbot properties: 09:25:33 INFO - { 09:25:33 INFO - "project": "", 09:25:33 INFO - "product": "firefox", 09:25:33 INFO - "who": "archaeopteryx@coole-files.de", 09:25:33 INFO - "installer_path": "public/build/target.tar.bz2", 09:25:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 09:25:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 09:25:33 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 09:25:33 INFO - "stage_platform": "linux64-qr", 09:25:33 INFO - "basedir": "/builds/slave/test", 09:25:33 INFO - "buildnumber": 69, 09:25:33 INFO - "platform": "ubuntu64_hw_qr", 09:25:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:25:33 INFO - "slavebuilddir": "test", 09:25:33 INFO - "taskId": "UVamwc7VSXmI2J49QC2Hew", 09:25:33 INFO - "branch": "mozilla-central", 09:25:33 INFO - "script_repo_revision": "production", 09:25:33 INFO - "revision": "b14c75b83d0226333b1240466ea9f07cfb206ff3", 09:25:33 INFO - "slavename": "talos-linux64-ix-057", 09:25:33 INFO - "repo_path": "mozilla-central" 09:25:33 INFO - } 09:25:33 INFO - Finding installer, test and symbols from parent task. 09:25:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVamwc7VSXmI2J49QC2Hew'}, attempt #1 09:25:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVamwc7VSXmI2J49QC2Hew'}, attempt #1 09:25:34 INFO - Task dependencies: HBJ4E0WUSRGZbfHgz26VAA 09:25:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA'}, attempt #1 09:25:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.tar.bz2 09:25:35 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.test_packages.json 09:25:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.crashreporter-symbols.zip 09:25:35 INFO - Running post-action listener: _resource_record_post_action 09:25:35 INFO - [mozharness: 2017-09-21 16:25:35.274807Z] Finished read-buildbot-config step (success) 09:25:35 INFO - [mozharness: 2017-09-21 16:25:35.275024Z] Running download-and-extract step. 09:25:35 INFO - Running pre-action listener: _resource_record_pre_action 09:25:35 INFO - Running main action method: download_and_extract 09:25:35 INFO - mkdir: /builds/slave/test/build/tests 09:25: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')]} 09:25:35 INFO - trying https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.test_packages.json 09:25:35 INFO - Downloading https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:25:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:25:35 INFO - Downloaded 989 bytes. 09:25:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:25:35 INFO - Using the following test package requirements: 09:25:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:25:35 INFO - u'common': [u'target.common.tests.zip'], 09:25:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:25:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:25:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:25:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:25:35 INFO - u'mozbase': [u'target.common.tests.zip'], 09:25:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:25:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:25:35 INFO - u'web-platform': [u'target.common.tests.zip', 09:25:35 INFO - u'target.web-platform.tests.tar.gz'], 09:25:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:25:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 09:25:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.common.tests.zip 09:25:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:25:35 INFO - Fetch https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.common.tests.zip into memory 09:25:36 INFO - Content-Length response header: 54741041 09:25:36 INFO - Bytes received: 54741041 09:25:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.talos.tests.zip 09:25:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:25:47 INFO - Fetch https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.talos.tests.zip into memory 09:25:48 INFO - Content-Length response header: 14509184 09:25:48 INFO - Bytes received: 14509184 09:25:48 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')]} 09:25:48 INFO - trying https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.tar.bz2 09:25:48 INFO - Downloading https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:25:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:25:49 INFO - Downloaded 61526732 bytes. 09:25:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.tar.bz2 09:25:49 INFO - mkdir: /builds/slave/test/properties 09:25:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:25:49 INFO - Writing to file /builds/slave/test/properties/build_url 09:25:49 INFO - Contents: 09:25:49 INFO - build_url:https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.tar.bz2 09:25:49 INFO - Running post-action listener: _resource_record_post_action 09:25:49 INFO - Running post-action listener: find_tests_for_verification 09:25:49 INFO - Running post-action listener: set_extra_try_arguments 09:25:49 INFO - [mozharness: 2017-09-21 16:25:49.814509Z] Finished download-and-extract step (success) 09:25:49 INFO - [mozharness: 2017-09-21 16:25:49.814604Z] Running populate-webroot step. 09:25:49 INFO - Running pre-action listener: _resource_record_pre_action 09:25:49 INFO - Running main action method: populate_webroot 09:25:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:25:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['tresize', 09:25:49 INFO - 'tcanvasmark']}, 09:25:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:25:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['dromaeo_css', 09:25:49 INFO - 'kraken']}, 09:25:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:25:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:25:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:25:49 INFO - 'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:25:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:25:49 INFO - 'tests': ['damp', 'tps']}, 09:25:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:25:49 INFO - 'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['damp', 'tps']}, 09:25:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:25:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['dromaeo_dom']}, 09:25:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:25:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['basic_compositor_video', 09:25:49 INFO - 'glvideo']}, 09:25:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:25:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:25:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:25:49 INFO - 'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['ts_paint_webext', 09:25:49 INFO - 'tp5o_webext']}, 09:25:49 INFO - 'other-e10s': {'tests': ['a11yr', 09:25:49 INFO - 'ts_paint', 09:25:49 INFO - 'tpaint', 09:25:49 INFO - 'sessionrestore', 09:25:49 INFO - 'sessionrestore_many_windows', 09:25:49 INFO - 'sessionrestore_no_auto_restore', 09:25:49 INFO - 'tabpaint']}, 09:25:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['a11yr', 09:25:49 INFO - 'ts_paint', 09:25:49 INFO - 'tpaint', 09:25:49 INFO - 'sessionrestore', 09:25:49 INFO - 'sessionrestore_many_windows', 09:25:49 INFO - 'sessionrestore_no_auto_restore', 09:25:49 INFO - 'tabpaint']}, 09:25:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:25:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 09:25:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['perf_reftest_singletons']}, 09:25:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['bloom_basic']}, 09:25:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:25:49 INFO - 'tsvgr_opacity', 09:25:49 INFO - 'tart', 09:25:49 INFO - 'tscrollx', 09:25:49 INFO - 'tsvg_static']}, 09:25:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['tsvgx', 09:25:49 INFO - 'tsvgr_opacity', 09:25:49 INFO - 'tart', 09:25:49 INFO - 'tscrollx', 09:25:49 INFO - 'tsvg_static']}, 09:25:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:25:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:25:49 INFO - 'talos_options': ['--disable-stylo'], 09:25:49 INFO - 'tests': ['tp5o']}, 09:25:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:25:49 INFO - 'talos_options': ['--mitmproxy', 09:25:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:25:49 INFO - '--firstNonBlankPaint'], 09:25:49 INFO - 'tests': ['tp6_google', 09:25:49 INFO - 'tp6_youtube', 09:25:49 INFO - 'tp6_amazon', 09:25:49 INFO - 'tp6_facebook']}, 09:25:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:25:49 INFO - 'talos_options': ['--disable-stylo', 09:25:49 INFO - '--mitmproxy', 09:25:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:25:49 INFO - '--firstNonBlankPaint'], 09:25:49 INFO - 'tests': ['tp6_google', 09:25:49 INFO - 'tp6_youtube', 09:25:49 INFO - 'tp6_amazon', 09:25:49 INFO - 'tp6_facebook']}, 09:25:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:25:49 INFO - 'talos_options': ['--stylo-threads=1', 09:25:49 INFO - '--mitmproxy', 09:25:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:25:49 INFO - '--firstNonBlankPaint'], 09:25:49 INFO - 'tests': ['tp6_google', 09:25:49 INFO - 'tp6_youtube', 09:25:49 INFO - 'tp6_amazon', 09:25:49 INFO - 'tp6_facebook']}, 09:25:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:25:49 INFO - 'talos_options': ['--xperf_path', 09:25:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:25:49 INFO - 'tests': ['tp5n']}, 09:25:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:25:49 INFO - 'talos_options': ['--disable-stylo', 09:25:49 INFO - '--xperf_path', 09:25:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:25:49 INFO - 'tests': ['tp5n']}}} 09:25:49 INFO - Running post-action listener: _resource_record_post_action 09:25:49 INFO - [mozharness: 2017-09-21 16:25:49.829442Z] Finished populate-webroot step (success) 09:25:49 INFO - [mozharness: 2017-09-21 16:25:49.829530Z] Running create-virtualenv step. 09:25:49 INFO - Running pre-action listener: _resource_record_pre_action 09:25:49 INFO - Running main action method: create_virtualenv 09:25:49 INFO - Creating virtualenv /builds/slave/test/build/venv 09:25:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:25:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 09:25:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:25:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:25:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:25:50 INFO - Installing distribute.............................................................................................................................................................................................done. 09:25:50 INFO - Installing pip...............done. 09:25:50 INFO - Return code: 0 09:25:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:25:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:25:51 INFO - Reading from file tmpfile_stdout 09:25:51 INFO - Output received: 09:25:51 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 09:25:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:25:51 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')]} 09:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:51 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')]} 09:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:51 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 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:25:51 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 09:25:51 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 09:25:51 INFO - Using env: {'DISPLAY': ':0', 09:25:51 INFO - 'HOME': '/home/cltbld', 09:25:51 INFO - 'LANG': 'en_US.UTF-8', 09:25:51 INFO - 'LANGUAGE': 'en_US:en', 09:25:51 INFO - 'LOGNAME': 'cltbld', 09:25:51 INFO - 'MAIL': '/var/mail/cltbld', 09:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:51 INFO - 'MOZ_NO_REMOTE': '1', 09:25:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:51 INFO - 'NO_EM_RESTART': '1', 09:25:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:51 INFO - 'PWD': '/builds/slave/test', 09:25:51 INFO - 'SHELL': '/bin/bash', 09:25:51 INFO - 'SHLVL': '1', 09:25:51 INFO - 'TERM': 'linux', 09:25:51 INFO - 'TMOUT': '86400', 09:25:51 INFO - 'USER': 'cltbld', 09:25:51 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:51 INFO - '_': '/tools/buildbot/bin/python'} 09:25:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 09:25:52 INFO - Downloading/unpacking pip>=1.5 09:25:52 INFO - Running setup.py egg_info for package pip 09:25:52 INFO - warning: no files found matching 'pip/cacert.pem' 09:25:52 INFO - warning: no files found matching '*.html' under directory 'docs' 09:25:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:25:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:25:52 INFO - Installing collected packages: pip 09:25:52 INFO - Found existing installation: pip 1.1 09:25:52 INFO - Uninstalling pip: 09:25:52 INFO - Successfully uninstalled pip 09:25:52 INFO - Running setup.py install for pip 09:25:52 INFO - warning: no files found matching 'pip/cacert.pem' 09:25:52 INFO - warning: no files found matching '*.html' under directory 'docs' 09:25:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:25:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:25:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 09:25:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 09:25:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 09:25:52 INFO - Successfully installed pip 09:25:52 INFO - Cleaning up... 09:25:52 INFO - Return code: 0 09:25:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:25:52 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')]} 09:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:52 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')]} 09:25:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:52 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 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:25:52 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 09:25:52 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 09:25:52 INFO - Using env: {'DISPLAY': ':0', 09:25:52 INFO - 'HOME': '/home/cltbld', 09:25:52 INFO - 'LANG': 'en_US.UTF-8', 09:25:52 INFO - 'LANGUAGE': 'en_US:en', 09:25:52 INFO - 'LOGNAME': 'cltbld', 09:25:52 INFO - 'MAIL': '/var/mail/cltbld', 09:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:52 INFO - 'MOZ_NO_REMOTE': '1', 09:25:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:52 INFO - 'NO_EM_RESTART': '1', 09:25:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:52 INFO - 'PWD': '/builds/slave/test', 09:25:52 INFO - 'SHELL': '/bin/bash', 09:25:52 INFO - 'SHLVL': '1', 09:25:52 INFO - 'TERM': 'linux', 09:25:52 INFO - 'TMOUT': '86400', 09:25:52 INFO - 'USER': 'cltbld', 09:25:52 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:52 INFO - '_': '/tools/buildbot/bin/python'} 09:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:52 INFO - Downloading/unpacking psutil>=3.1.1 09:25:52 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 09:25:52 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 09:25:52 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 09:25:52 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 09:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:25:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:25:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:25:53 INFO - Installing collected packages: psutil 09:25:53 INFO - Running setup.py install for psutil 09:25:53 INFO - building 'psutil._psutil_linux' extension 09:25:53 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 09:25:53 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 09:25:54 INFO - building 'psutil._psutil_posix' extension 09:25:54 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 09:25:54 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 09:25:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:25:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:25:54 INFO - Successfully installed psutil 09:25:54 INFO - Cleaning up... 09:25:54 INFO - Return code: 0 09:25:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:25:54 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')]} 09:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:54 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')]} 09:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:54 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 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:25:54 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 09:25:54 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 09:25:54 INFO - Using env: {'DISPLAY': ':0', 09:25:54 INFO - 'HOME': '/home/cltbld', 09:25:54 INFO - 'LANG': 'en_US.UTF-8', 09:25:54 INFO - 'LANGUAGE': 'en_US:en', 09:25:54 INFO - 'LOGNAME': 'cltbld', 09:25:54 INFO - 'MAIL': '/var/mail/cltbld', 09:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:54 INFO - 'MOZ_NO_REMOTE': '1', 09:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:54 INFO - 'NO_EM_RESTART': '1', 09:25:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:54 INFO - 'PWD': '/builds/slave/test', 09:25:54 INFO - 'SHELL': '/bin/bash', 09:25:54 INFO - 'SHLVL': '1', 09:25:54 INFO - 'TERM': 'linux', 09:25:54 INFO - 'TMOUT': '86400', 09:25:54 INFO - 'USER': 'cltbld', 09:25:54 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:54 INFO - '_': '/tools/buildbot/bin/python'} 09:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:25:54 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 09:25:54 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 09:25:54 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 09:25:54 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 09:25:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:25:55 INFO - Installing collected packages: mozsystemmonitor 09:25:55 INFO - Running setup.py install for mozsystemmonitor 09:25:55 INFO - Successfully installed mozsystemmonitor 09:25:55 INFO - Cleaning up... 09:25:56 INFO - Return code: 0 09:25:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:25:56 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')]} 09:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:56 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')]} 09:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:56 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 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:25:56 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 09:25:56 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 09:25:56 INFO - Using env: {'DISPLAY': ':0', 09:25:56 INFO - 'HOME': '/home/cltbld', 09:25:56 INFO - 'LANG': 'en_US.UTF-8', 09:25:56 INFO - 'LANGUAGE': 'en_US:en', 09:25:56 INFO - 'LOGNAME': 'cltbld', 09:25:56 INFO - 'MAIL': '/var/mail/cltbld', 09:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:56 INFO - 'MOZ_NO_REMOTE': '1', 09:25:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:56 INFO - 'NO_EM_RESTART': '1', 09:25:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:56 INFO - 'PWD': '/builds/slave/test', 09:25:56 INFO - 'SHELL': '/bin/bash', 09:25:56 INFO - 'SHLVL': '1', 09:25:56 INFO - 'TERM': 'linux', 09:25:56 INFO - 'TMOUT': '86400', 09:25:56 INFO - 'USER': 'cltbld', 09:25:56 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:56 INFO - '_': '/tools/buildbot/bin/python'} 09:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:56 INFO - Downloading/unpacking jsonschema==2.5.1 09:25:56 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 09:25:56 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 09:25:56 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 09:25:56 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 09:25:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:25:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:25:57 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 09:25:57 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 09:25:57 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 09:25:57 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 09:25:58 INFO - Downloading functools32-3.2.3-2.tar.gz 09:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:25:58 INFO - warning: no files found matching '*.txt' 09:25:58 INFO - no previously-included directories found matching 'build' 09:25:58 INFO - no previously-included directories found matching 'dist' 09:25:58 INFO - no previously-included directories found matching '.git*' 09:25:58 INFO - Installing collected packages: jsonschema, functools32 09:25:58 INFO - Running setup.py install for functools32 09:25:58 INFO - warning: no files found matching '*.txt' 09:25:58 INFO - no previously-included directories found matching 'build' 09:25:58 INFO - no previously-included directories found matching 'dist' 09:25:58 INFO - no previously-included directories found matching '.git*' 09:25:58 INFO - Successfully installed jsonschema functools32 09:25:58 INFO - Cleaning up... 09:25:58 INFO - Return code: 0 09:25:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:25:58 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')]} 09:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:58 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')]} 09:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:58 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 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:25:58 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 09:25:58 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 09:25:58 INFO - Using env: {'DISPLAY': ':0', 09:25:58 INFO - 'HOME': '/home/cltbld', 09:25:58 INFO - 'LANG': 'en_US.UTF-8', 09:25:58 INFO - 'LANGUAGE': 'en_US:en', 09:25:58 INFO - 'LOGNAME': 'cltbld', 09:25:58 INFO - 'MAIL': '/var/mail/cltbld', 09:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:58 INFO - 'MOZ_NO_REMOTE': '1', 09:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:58 INFO - 'NO_EM_RESTART': '1', 09:25:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:58 INFO - 'PWD': '/builds/slave/test', 09:25:58 INFO - 'SHELL': '/bin/bash', 09:25:58 INFO - 'SHLVL': '1', 09:25:58 INFO - 'TERM': 'linux', 09:25:58 INFO - 'TMOUT': '86400', 09:25:58 INFO - 'USER': 'cltbld', 09:25:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:58 INFO - '_': '/tools/buildbot/bin/python'} 09:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:25:58 INFO - Cleaning up... 09:25:58 INFO - Return code: 0 09:25:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:25:58 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')]} 09:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:58 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')]} 09:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:58 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 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:25:58 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 09:25:58 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 09:25:58 INFO - Using env: {'DISPLAY': ':0', 09:25:58 INFO - 'HOME': '/home/cltbld', 09:25:58 INFO - 'LANG': 'en_US.UTF-8', 09:25:58 INFO - 'LANGUAGE': 'en_US:en', 09:25:58 INFO - 'LOGNAME': 'cltbld', 09:25:58 INFO - 'MAIL': '/var/mail/cltbld', 09:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:58 INFO - 'MOZ_NO_REMOTE': '1', 09:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:58 INFO - 'NO_EM_RESTART': '1', 09:25:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:58 INFO - 'PWD': '/builds/slave/test', 09:25:58 INFO - 'SHELL': '/bin/bash', 09:25:58 INFO - 'SHLVL': '1', 09:25:58 INFO - 'TERM': 'linux', 09:25:58 INFO - 'TMOUT': '86400', 09:25:58 INFO - 'USER': 'cltbld', 09:25:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:58 INFO - '_': '/tools/buildbot/bin/python'} 09:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:58 INFO - Downloading/unpacking blobuploader==1.2.4 09:25:58 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 09:25:58 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 09:25:58 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 09:25:58 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 09:25:59 INFO - Downloading blobuploader-1.2.4.tar.gz 09:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:25:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:25:59 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 09:25:59 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 09:25:59 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 09:25:59 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 09:26:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:26:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:26:00 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 09:26:00 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 09:26:00 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 09:26:00 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 09:26:00 INFO - Downloading docopt-0.6.1.tar.gz 09:26:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:26:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:26:00 INFO - Running setup.py install for blobuploader 09:26:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:26:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:26:00 INFO - Running setup.py install for requests 09:26:00 INFO - Running setup.py install for docopt 09:26:00 INFO - Successfully installed blobuploader requests docopt 09:26:00 INFO - Cleaning up... 09:26:01 INFO - Return code: 0 09:26:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:01 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')]} 09:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:01 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')]} 09:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:01 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 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:26:01 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 09:26:01 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 09:26:01 INFO - Using env: {'DISPLAY': ':0', 09:26:01 INFO - 'HOME': '/home/cltbld', 09:26:01 INFO - 'LANG': 'en_US.UTF-8', 09:26:01 INFO - 'LANGUAGE': 'en_US:en', 09:26:01 INFO - 'LOGNAME': 'cltbld', 09:26:01 INFO - 'MAIL': '/var/mail/cltbld', 09:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:01 INFO - 'MOZ_NO_REMOTE': '1', 09:26:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:01 INFO - 'NO_EM_RESTART': '1', 09:26:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:01 INFO - 'PWD': '/builds/slave/test', 09:26:01 INFO - 'SHELL': '/bin/bash', 09:26:01 INFO - 'SHLVL': '1', 09:26:01 INFO - 'TERM': 'linux', 09:26:01 INFO - 'TMOUT': '86400', 09:26:01 INFO - 'USER': 'cltbld', 09:26:01 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:01 INFO - '_': '/tools/buildbot/bin/python'} 09:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:01 INFO - Running setup.py (path:/tmp/pip-rgQmi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:01 INFO - Running setup.py (path:/tmp/pip-786Ljw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:01 INFO - Running setup.py (path:/tmp/pip-81eifN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:01 INFO - Running setup.py (path:/tmp/pip-LT39JK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:01 INFO - Running setup.py (path:/tmp/pip-0OQN_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:01 INFO - Running setup.py (path:/tmp/pip-X1_uKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:01 INFO - Running setup.py (path:/tmp/pip-3xRt6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:01 INFO - Running setup.py (path:/tmp/pip-CrfTrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:01 INFO - Running setup.py (path:/tmp/pip-GCuEGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:01 INFO - Running setup.py (path:/tmp/pip-0TXvTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:01 INFO - Running setup.py (path:/tmp/pip-_yKcpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:02 INFO - Running setup.py (path:/tmp/pip-lZ6Eht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:02 INFO - Running setup.py (path:/tmp/pip-nqNuh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:02 INFO - Running setup.py (path:/tmp/pip-ZsXb8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:02 INFO - Running setup.py (path:/tmp/pip-pcB8v8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:02 INFO - Running setup.py (path:/tmp/pip-1unHNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:02 INFO - Running setup.py (path:/tmp/pip-PJQjTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:26:02 INFO - Running setup.py install for manifestparser 09:26:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:26:02 INFO - Running setup.py install for mozcrash 09:26:02 INFO - Running setup.py install for mozdebug 09:26:02 INFO - Running setup.py install for mozdevice 09:26:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:26:02 INFO - Running setup.py install for mozfile 09:26:02 INFO - Running setup.py install for mozhttpd 09:26:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Running setup.py install for mozinfo 09:26:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Running setup.py install for mozInstall 09:26:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Running setup.py install for mozleak 09:26:03 INFO - Running setup.py install for mozlog 09:26:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Running setup.py install for moznetwork 09:26:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Running setup.py install for mozprocess 09:26:03 INFO - Running setup.py install for mozprofile 09:26:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Running setup.py install for mozrunner 09:26:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:26:03 INFO - Running setup.py install for mozscreenshot 09:26:04 INFO - Running setup.py install for moztest 09:26:04 INFO - Running setup.py install for mozversion 09:26:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:26:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:26:04 INFO - Cleaning up... 09:26:04 INFO - Return code: 0 09:26:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:04 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')]} 09:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:04 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')]} 09:26:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:04 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 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:26:04 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 09:26:04 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 09:26:04 INFO - Using env: {'DISPLAY': ':0', 09:26:04 INFO - 'HOME': '/home/cltbld', 09:26:04 INFO - 'LANG': 'en_US.UTF-8', 09:26:04 INFO - 'LANGUAGE': 'en_US:en', 09:26:04 INFO - 'LOGNAME': 'cltbld', 09:26:04 INFO - 'MAIL': '/var/mail/cltbld', 09:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:04 INFO - 'MOZ_NO_REMOTE': '1', 09:26:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:04 INFO - 'NO_EM_RESTART': '1', 09:26:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:04 INFO - 'PWD': '/builds/slave/test', 09:26:04 INFO - 'SHELL': '/bin/bash', 09:26:04 INFO - 'SHLVL': '1', 09:26:04 INFO - 'TERM': 'linux', 09:26:04 INFO - 'TMOUT': '86400', 09:26:04 INFO - 'USER': 'cltbld', 09:26:04 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:04 INFO - '_': '/tools/buildbot/bin/python'} 09:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:04 INFO - Running setup.py (path:/tmp/pip-ACsWog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:04 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)) 09:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:04 INFO - Running setup.py (path:/tmp/pip-DMtjqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:04 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)) 09:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:04 INFO - Running setup.py (path:/tmp/pip-aeko0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:04 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)) 09:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:04 INFO - Running setup.py (path:/tmp/pip-ozn3sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 09:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:04 INFO - Running setup.py (path:/tmp/pip-KrhO47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:04 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)) 09:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:04 INFO - Running setup.py (path:/tmp/pip-MujxzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:04 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)) 09:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:04 INFO - Running setup.py (path:/tmp/pip-h5eOoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 09:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:04 INFO - Running setup.py (path:/tmp/pip-sfraP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:05 INFO - Running setup.py (path:/tmp/pip-hakeHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:05 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)) 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:05 INFO - Running setup.py (path:/tmp/pip-6TTcpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:05 INFO - Running setup.py (path:/tmp/pip-8rXh_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:05 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)) 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:05 INFO - Running setup.py (path:/tmp/pip-e2GU7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:05 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)) 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:05 INFO - Running setup.py (path:/tmp/pip-DklzMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:05 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)) 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:05 INFO - Running setup.py (path:/tmp/pip-iUgF_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:05 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)) 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:05 INFO - Running setup.py (path:/tmp/pip-epjaif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:05 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)) 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:05 INFO - Running setup.py (path:/tmp/pip-yzfp5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:05 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)) 09:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:05 INFO - Running setup.py (path:/tmp/pip-IpX3BU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:05 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)) 09:26:05 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)) 09:26:05 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)) 09:26:05 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)) 09:26:05 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:26:05 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:26:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:26:05 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 09:26:05 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 09:26:05 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 09:26:05 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 09:26:06 INFO - Downloading blessings-1.6.tar.gz 09:26:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:26:06 INFO - Installing collected packages: blessings 09:26:06 INFO - Running setup.py install for blessings 09:26:06 INFO - Successfully installed blessings 09:26:06 INFO - Cleaning up... 09:26:07 INFO - Return code: 0 09:26:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:26:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:07 INFO - Reading from file tmpfile_stdout 09:26:07 INFO - Current package versions: 09:26:07 INFO - argparse == 1.2.1 09:26:07 INFO - blessings == 1.6 09:26:07 INFO - blobuploader == 1.2.4 09:26:07 INFO - docopt == 0.6.1 09:26:07 INFO - functools32 == 3.2.3-2 09:26:07 INFO - jsonschema == 2.5.1 09:26:07 INFO - manifestparser == 1.1 09:26:07 INFO - mozInstall == 1.13 09:26:07 INFO - mozcrash == 1.0 09:26:07 INFO - mozdebug == 0.1 09:26:07 INFO - mozdevice == 0.51 09:26:07 INFO - mozfile == 1.2 09:26:07 INFO - mozhttpd == 0.7 09:26:07 INFO - mozinfo == 0.10 09:26:07 INFO - mozleak == 0.1 09:26:07 INFO - mozlog == 3.5 09:26:07 INFO - moznetwork == 0.27 09:26:07 INFO - mozprocess == 0.25 09:26:07 INFO - mozprofile == 0.28 09:26:07 INFO - mozrunner == 6.13 09:26:07 INFO - mozscreenshot == 0.1 09:26:07 INFO - mozsystemmonitor == 0.3 09:26:07 INFO - moztest == 0.8 09:26:07 INFO - mozversion == 1.4 09:26:07 INFO - psutil == 3.1.1 09:26:07 INFO - requests == 1.2.3 09:26:07 INFO - wsgiref == 0.1.2 09:26:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26: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')]} 09:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:07 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 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:26:07 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 09:26:07 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 09:26:07 INFO - Using env: {'DISPLAY': ':0', 09:26:07 INFO - 'HOME': '/home/cltbld', 09:26:07 INFO - 'LANG': 'en_US.UTF-8', 09:26:07 INFO - 'LANGUAGE': 'en_US:en', 09:26:07 INFO - 'LOGNAME': 'cltbld', 09:26:07 INFO - 'MAIL': '/var/mail/cltbld', 09:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:07 INFO - 'MOZ_NO_REMOTE': '1', 09:26:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:07 INFO - 'NO_EM_RESTART': '1', 09:26:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:07 INFO - 'PWD': '/builds/slave/test', 09:26:07 INFO - 'SHELL': '/bin/bash', 09:26:07 INFO - 'SHLVL': '1', 09:26:07 INFO - 'TERM': 'linux', 09:26:07 INFO - 'TMOUT': '86400', 09:26:07 INFO - 'USER': 'cltbld', 09:26:07 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:07 INFO - '_': '/tools/buildbot/bin/python'} 09:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:07 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)) 09:26:07 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)) 09:26:07 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)) 09:26:07 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)) 09:26:07 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)) 09:26:07 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)) 09:26:07 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)) 09:26:07 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)) 09:26:07 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)) 09:26:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:26: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 09:26: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 09:26: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 09:26: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 09:26:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:26:08 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)) 09:26:08 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)) 09:26:08 INFO - Installing collected packages: simplejson 09:26:08 INFO - Running setup.py install for simplejson 09:26:08 INFO - building 'simplejson._speedups' extension 09:26:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 09:26:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 09:26:09 INFO - Successfully installed simplejson 09:26:09 INFO - Cleaning up... 09:26:09 INFO - Return code: 0 09:26:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26: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')]} 09:26:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1539a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', '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 09:26: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', 'jsonschema'] in /builds/slave/test/build 09:26: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 jsonschema 09:26:09 INFO - Using env: {'DISPLAY': ':0', 09:26:09 INFO - 'HOME': '/home/cltbld', 09:26:09 INFO - 'LANG': 'en_US.UTF-8', 09:26:09 INFO - 'LANGUAGE': 'en_US:en', 09:26:09 INFO - 'LOGNAME': 'cltbld', 09:26:09 INFO - 'MAIL': '/var/mail/cltbld', 09:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:09 INFO - 'MOZ_NO_REMOTE': '1', 09:26:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:09 INFO - 'NO_EM_RESTART': '1', 09:26:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:09 INFO - 'PWD': '/builds/slave/test', 09:26:09 INFO - 'SHELL': '/bin/bash', 09:26:09 INFO - 'SHLVL': '1', 09:26:09 INFO - 'TERM': 'linux', 09:26:09 INFO - 'TMOUT': '86400', 09:26:09 INFO - 'USER': 'cltbld', 09:26:09 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:09 INFO - '_': '/tools/buildbot/bin/python'} 09:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:26:09 INFO - Cleaning up... 09:26:09 INFO - Return code: 0 09:26:09 INFO - Running post-action listener: _resource_record_post_action 09:26:09 INFO - Running post-action listener: _start_resource_monitoring 09:26:09 INFO - Starting resource monitoring. 09:26:09 INFO - [mozharness: 2017-09-21 16:26:09.959055Z] Finished create-virtualenv step (success) 09:26:09 INFO - [mozharness: 2017-09-21 16:26:09.959523Z] Running install step. 09:26:09 INFO - Running pre-action listener: _resource_record_pre_action 09:26:09 INFO - Running main action method: install 09:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:10 INFO - Reading from file tmpfile_stdout 09:26:10 INFO - Detecting whether we're running mozinstall >=1.0... 09:26:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:26:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:26:10 INFO - Reading from file tmpfile_stdout 09:26:10 INFO - Output received: 09:26:10 INFO - Usage: mozinstall [options] installer 09:26:10 INFO - Options: 09:26:10 INFO - -h, --help show this help message and exit 09:26:10 INFO - -d DEST, --destination=DEST 09:26:10 INFO - Directory to install application into. [default: 09:26:10 INFO - "/builds/slave/test"] 09:26:10 INFO - --app=APP Application being installed. [default: firefox] 09:26:10 INFO - mkdir: /builds/slave/test/build/application 09:26:10 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'] 09:26:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:26:28 INFO - Reading from file tmpfile_stdout 09:26:28 INFO - Output received: 09:26:28 INFO - /builds/slave/test/build/application/firefox/firefox 09:26:28 INFO - Running post-action listener: _resource_record_post_action 09:26:28 INFO - [mozharness: 2017-09-21 16:26:28.077041Z] Finished install step (success) 09:26:28 INFO - [mozharness: 2017-09-21 16:26:28.077377Z] Running setup-mitmproxy step. 09:26:28 INFO - Running pre-action listener: _resource_record_pre_action 09:26:28 INFO - Running main action method: setup_mitmproxy 09:26:28 INFO - Skipping: mitmproxy is not required 09:26:28 INFO - Running post-action listener: _resource_record_post_action 09:26:28 INFO - [mozharness: 2017-09-21 16:26:28.079031Z] Finished setup-mitmproxy step (success) 09:26:28 INFO - [mozharness: 2017-09-21 16:26:28.079342Z] Running run-tests step. 09:26:28 INFO - Running pre-action listener: _resource_record_pre_action 09:26:28 INFO - Running pre-action listener: _set_gcov_prefix 09:26:28 INFO - Running main action method: run_tests 09:26:28 WARNING - Try message not found. 09:26:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:26:28 INFO - Python 2.7.3 09:26:28 INFO - Return code: 0 09:26:28 INFO - grabbing minidump binary from tooltool 09:26:28 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')]} 09:26:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x16e0090>, '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 0x16e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16daf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:26:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 09:26:28 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:26:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 09:26:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:26:28 INFO - Return code: 0 09:26:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:26:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:26:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:26:28 INFO - ENV: RUST_BACKTRACE is now full 09:26:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:26:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:26:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:26:28 INFO - Using env: {'DISPLAY': ':0', 09:26:28 INFO - 'HOME': '/home/cltbld', 09:26:28 INFO - 'LANG': 'en_US.UTF-8', 09:26:28 INFO - 'LANGUAGE': 'en_US:en', 09:26:28 INFO - 'LOGNAME': 'cltbld', 09:26:28 INFO - 'MAIL': '/var/mail/cltbld', 09:26:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:26:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:26:28 INFO - 'MOZ_ACCELERATED': '1', 09:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:28 INFO - 'MOZ_NO_REMOTE': '1', 09:26:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:26:28 INFO - 'MOZ_WEBRENDER': '1', 09:26:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:28 INFO - 'NO_EM_RESTART': '1', 09:26:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:28 INFO - 'PWD': '/builds/slave/test', 09:26:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:26:28 INFO - 'RUST_BACKTRACE': 'full', 09:26:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:26:28 INFO - 'SHELL': '/bin/bash', 09:26:28 INFO - 'SHLVL': '1', 09:26:28 INFO - 'TERM': 'linux', 09:26:28 INFO - 'TMOUT': '86400', 09:26:28 INFO - 'USER': 'cltbld', 09:26:28 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:28 INFO - '_': '/tools/buildbot/bin/python'} 09:26:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:26:28 INFO - mozversion application_buildid: 20170921152748 09:26:28 INFO - mozversion application_changeset: b14c75b83d0226333b1240466ea9f07cfb206ff3 09:26:28 INFO - mozversion application_display_name: Nightly 09:26:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:26:28 INFO - mozversion application_name: Firefox 09:26:28 INFO - mozversion application_remotingname: firefox 09:26:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:26:28 INFO - mozversion application_vendor: Mozilla 09:26:28 INFO - mozversion application_version: 58.0a1 09:26:28 INFO - mozversion platform_buildid: 20170921152748 09:26:28 INFO - mozversion platform_changeset: b14c75b83d0226333b1240466ea9f07cfb206ff3 09:26:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:26:28 INFO - mozversion platform_version: 58.0a1 09:26:28 INFO - using testdate: 1506011188 09:26:28 INFO - actual date: 1506011188 09:26:28 INFO - starting webserver on 'localhost:46697' 09:26:28 INFO - SUITE-START | Running 2 tests 09:26:28 INFO - TEST-START | dromaeo_css 09:26:28 INFO - Initialising browser for dromaeo_css test... 09:26:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46697/getInfo.html -profile /tmp/tmpXzx7yk/profile 09:26:28 INFO - TEST-INFO | started process 18865 (/builds/slave/test/build/application/firefox/firefox http://localhost:46697/getInfo.html) 09:26:35 INFO - TEST-INFO | 18865: exit 0 09:26:35 INFO - Browser initialized. 09:26:35 INFO - Running cycle 1/1 for dromaeo_css test... 09:26:35 INFO - Using env: {'DISPLAY': ':0', 09:26:35 INFO - 'HOME': '/home/cltbld', 09:26:35 INFO - 'JSGC_DISABLE_POISONING': '1', 09:26:35 INFO - 'LANG': 'en_US.UTF-8', 09:26:35 INFO - 'LANGUAGE': 'en_US:en', 09:26:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:26:35 INFO - 'LOGNAME': 'cltbld', 09:26:35 INFO - 'MAIL': '/var/mail/cltbld', 09:26:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:26:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:26:35 INFO - 'MOZ_ACCELERATED': '1', 09:26:35 INFO - 'MOZ_CRASHREPORTER': '1', 09:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:26:35 INFO - 'MOZ_NO_REMOTE': '1', 09:26:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:26:35 INFO - 'MOZ_WEBRENDER': '1', 09:26:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:35 INFO - 'NO_EM_RESTART': '1', 09:26:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:35 INFO - 'PWD': '/builds/slave/test', 09:26:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:26:35 INFO - 'RUST_BACKTRACE': 'full', 09:26:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:26:35 INFO - 'SHELL': '/bin/bash', 09:26:35 INFO - 'SHLVL': '1', 09:26:35 INFO - 'TERM': 'linux', 09:26:35 INFO - 'TMOUT': '86400', 09:26:35 INFO - 'USER': 'cltbld', 09:26:35 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:35 INFO - '_': '/tools/buildbot/bin/python'} 09:26:35 INFO - TEST-INFO | started process 19110 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXzx7yk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:35 INFO - PID 19110 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:26:36 INFO - PID 19110 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:26:36 INFO - PID 19110 | 09:26:36 INFO - PID 19110 | (/builds/slave/test/build/application/firefox/firefox:19170): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:26:36 INFO - PID 19110 | 09:26:46 INFO - PID 19110 | RSS: Main: 184991744 09:26:46 INFO - PID 19110 | 09:29:44 INFO - PID 19110 | Cycle 1(1): loaded http://localhost:46697/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46697/tests/dromaeo/cssquery-ext.html) 09:29:46 INFO - PID 19110 | RSS: Main: 186454016 09:29:46 INFO - PID 19110 | 09:32:48 INFO - PID 19110 | Cycle 1(1): loaded http://localhost:46697/tests/dromaeo/cssquery-ext.html (next: http://localhost:46697/tests/dromaeo/cssquery-jquery.html) 09:32:49 INFO - PID 19110 | RSS: Main: 187719680 09:32:49 INFO - PID 19110 | 09:35:51 INFO - PID 19110 | Cycle 1(1): loaded http://localhost:46697/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46697/tests/dromaeo/cssquery-mootools.html) 09:35:51 INFO - PID 19110 | RSS: Main: 186634240 09:35:51 INFO - PID 19110 | 09:38:54 INFO - PID 19110 | Cycle 1(1): loaded http://localhost:46697/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46697/tests/dromaeo/cssquery-prototype.html) 09:38:54 INFO - PID 19110 | RSS: Main: 188628992 09:38:54 INFO - PID 19110 | 09:41:58 INFO - PID 19110 | Cycle 1(1): loaded http://localhost:46697/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46697/tests/dromaeo/cssquery-yui.html) 09:41:59 INFO - PID 19110 | RSS: Main: 192409600 09:41:59 INFO - PID 19110 | 09:45:01 INFO - PID 19110 | Cycle 1(1): loaded http://localhost:46697/tests/dromaeo/cssquery-yui.html (next: http://localhost:46697/tests/dromaeo/cssquery-dojo.html) 09:45:01 INFO - PID 19110 | RSS: Main: 194949120 09:45:01 INFO - PID 19110 | 09:45:01 INFO - PID 19110 | __start_tp_report 09:45:01 INFO - PID 19110 | _x_x_mozilla_page_load 09:45:01 INFO - PID 19110 | _x_x_mozilla_page_load_details 09:45:01 INFO - PID 19110 | |i|pagename|runs| 09:45:01 INFO - PID 19110 | |0;dojo.html;3074.2160749009;3148.1342630776535;3150.9916826615486;3184.299454120094;3185.6177258728926;6182.474489668372;6241.126242326072;6351.968240158804;6418.454431373333;6441.259255185655;6190.78332258371;6273.529485288599;6278.9372106278925;6291.4337709606125;6295.748170073203;5756.884862302752;5768.39431859655;5801.448862358069;5837.066069428893;5880.705964701748;5754.827355179351;5884.882302353951;5906.05503119501;5943.940560594404;5959.165716799652;5856.121581762748;5895.705214739267;5951.047832346825;5963.194968679216;5978.730957106941;5620.859478513029;5658.405867383906;5788.392218817005;5827.592068555203;5929.140274660193;5791.971040144815;5799.18811366409;5813.82558523245;5815.883682326372;5852.619579727304;6324.114623952651;6349.206349206349;6352.682365881734;6354.3010268870385;6395.328490508523;6812.557183783038;6816.931830681729;6880.759173428956;6902.654867256617;6910.136232970879;6637.137172167636;6668.333166683341;6803.863922721567;6862.691178896962;6884.311568843125;6262.655553944532;6263.217097862767;6294.213223347082;6297.842553936142;6383.074454204161;6985.965070174719;7032.578045317297;7056.682449289794;7068.469864760164;7142.464315176382;6675.432588229958;6697.162854643169;6732.225794033651;6774.187097548326;6796.4562834973085;6646.700898459581;6780.1524809398825;6792.286809884989;6871.622060786604;6921.930780692129;6692.59844409337;6706.329367063352;6753.15585551806;6759.324067593201;6808.455323574102;6723.798286051427;6773.424258937947;6831.180258369028;6866;6880.380765731108;5338.065838478251;5371.435999220025;5399.595030372738;5401.162819762943;5433.157860531624;4551.271796512542;4651.046535460223;4653.999390131138;4654.720716757005;4660.347551342815;5765.394633563499;5831.037878750044;5859.765609375662;5875.706214689249;5880.852978675567;6745.325467453215;6745.392914637706;6825.419839313615;6839.110915580943;6879.140107486564;5678.517326027251;5740.56945729072;5752.856178595569;5752.884942301131;5772.5670574707065;5847.122931560215;5898.262717160355;5909.113632955005;5910.793122240787;5917.911231331534;6627.569208001464;6641.767538136142;6677.332266773284;6681.997700344987;6692.531522793455;6755.86488270222;6764.391204791691;6844.1444819397575;6891.3797758202;6918.0314755933205;6359.07793369968;6578.8355291118105;6598.043283723737;6633;6666.300038495888;6629.602223866584;6637.402633762985;6645.468362531083;6678.165229346332;6679.131712877295;6786.287439818748;6947.79156625302;6950.79147625572;6956;6967.7212911482975;6596.208454985433;6690.330966903271;6694.665266736741;6697;6698.598084114774;6473.449756770728;6535.836604084936;6556;6565.835854103686;6630.635315057718;6376.298607153117;6467.676616169078;6480.41676249121;6484.643344615903;6619.834504137435;6544.770933017321;6581.506387020897;6622.1722284714415;6624.735010599522;6631.834204144935;6380.521460890359;6411.487081033413;6485.416312531895;6499.512536559682;6512.316206798408 09:45:01 INFO - PID 19110 | |1;ext.html;760.2359628573283;776.4215659333797;783.142459007387;785.5679376343013;785.7092875636016;40022.1995560089;40792;43618.56381436185;43839.34240986388;43917.7804110979;39111.41332880009;39373.80313098438;39468.80265598675;39678.40482392767;39772;7054.188768291648;7113.964430177842;7149.642517874112;7157.964210178968;7873.724419645314;7851.842963140733;7989.201079891994;7990.4007199460275;7992.160823113548;7996.960015199916;8018.639161237729;8075.959620201891;8079.9192008080045;8084.231997960213;8091.716789912354;7915.248051435105;8033.156518565584;8044.839103217902;8050.919490805134;8062.596870156469;7938.682452701943;7943.920560794376;7968.920310796876;7983.560904150269;8025.4783439076855;38482.80758596217;38772;38891.22217555633;38972.41541376882;39354.40968385476;3545.9113522161892;3560.2167523144867;3569.5002699622073;3575.6781889630056;3577.4454959481322;32400;32471.35057298841;32516.512252316235;32757.344853103044;32792.6720732792;5264.42091369949;5264.973675131638;5308.495692909168;5354.223637572531;5401.6218864679095;191865;191887;192369;192452;193369.9999999986;41830.581694183275;47967.520324797006;48074.557763267156;48171;48243.070277189225;43570.7821460897;51417;51425.228621570706;51449;51451.485485145415;14669;14798.48205312809;14837.258137093102;14843.183624900517;14871.405143794344;11762;11774.646760597025;11861.762764744657;11882.405879706153;11898.286102833858;13784.379702913393;13887;13976.301184940916;14109.929450352682;14155.929220353832;5368.538608806612;5396.325459317585;5417.539509141767;5423.674579525241;5539.667619942817;976.6630512473185;1000.6497725795913;1002.6390499420203;1006.2603986070233;1009.848522721583;22752.886235568716;22946.7705322948;23183.768162318498;23222.187223447097;23323.533529329317;1063.9414832184304;1069.540077793806;1085.9674209773718;1101.1136035491506;1119.3172164979355;11984.400779960966;12105.818412723816;12149.757004859854;12230.992107103679;12236.632901012983;227167;228409;229210;229309;230152;51306.23040654393;51342.22986655203;51498.485015150116;51679.22481162786;51874.48125518696;1420.4176287722191;1446.7251222267735;1447.1534152520894;1452.3391856705173;1465.3405967314538;21923;22537;22676.433089172904;22851.428714282276;22955.196568120034;36296.27407451889;36451.27097458089;36539.26921461609;36585.268294634494;36665;195752;197251;198077;198138;198427;30144;30333.544996825505;30652.386952261277;32829.507557387136;35012.64987350094;43138.13723725571;43446.78276608597;43628.345574815765;43808.78095609502;44318.335224972296;31951.36097278088;32077.518837217925;32114.51828222532;32309.51535726919;32413.513797292588;31313.68686313199;31349.843250784514;31613.367732644758;31693.69423378544;32258.677413225567;36099.63900360963;36222.63777362193;36233;36390.454143187344;36474.452883207305;30162.396752065273;30236.84881575578;30755.231119221302;31023.379532408773;31436.842815785774;22868.77131228733;23189.88405057964;23527.29418117459;23644.172453964027;24034.63948040746 09:45:01 INFO - PID 19110 | |2;jquery.html;578.5487319890486;587.917691523187;589.5578316262805;589.6196952965339;603.6498830678207;21320.680189797167;21957.341279761633;22164.335069947887;22200.555988880213;22215.888920555415;20881.791182088175;21886.89056554719;22240.888795556;22285.66571501429;22506.54986900261;6344.143540622026;6491.415772580467;6533.118029066087;6533.869322613545;6546.574472659262;6419.775307864225;6439.195100612424;6509.511786615999;6541.443977261938;6542.247641521215;6312.684365781715;6535.477161827066;6579.078928949951;6597.208334999783;6601.7359305627715;6561.311062338457;6565.54041217115;6566.540342176049;6575.3095924927675;6577.04632828242;5942.5840191186635;6092.177556029924;6111.052786818006;6147.877042459126;6149;28070.578941315896;28659;28676.426471470455;28688.569671454945;28692.13923582296;2017.1124705129844;2039.9188430132053;2040.3776848061336;2042.7855075217037;2045.4988527810633;18686;18919.24323027077;19254.32609858662;19424.12591433389;19427.708584371245;2308.9191878284178;2389.940251493709;2406.2300063979533;2406.4224586099385;2409.0725070847675;116935;122083.55832883292;122770;133847;140362.99999999898;14427.66529858818;14735.86329982168;14766.926165369105;14769.778453323208;14989.100653960579;22152.77847221507;22535.211267605882;22569.09723611037;22722.20472283462;22777.31668049961;9461.148496635336;9463.810723785486;9499.335046546814;9561.139497445263;9603.729139379568;8605.569721513899;8628.22345988864;8760.080191579793;8780.517071561126;8784.824303513893;8737.956310218407;8813.559322033872;8928.375013749104;8992.820143597091;9196.35625506208;2669.639598654157;2680.517506848747;2715.5919321251704;2741.696256011541;2894.8407837568716;942.9905700942903;965.1603105298435;966.4970970141497;969.9806003880024;979.3683074416961;18199;18371.357002504847;18551;18551.53621159482;18623.44129676112;1009.3086235928413;1022.7698767777191;1027.034587487774;1030.2427715629008;1032.566322144701;10218;10314.896851031544;10368.274220804622;10425.061744442884;10672.626458074086;439821;462182;464541;465658;488797;107216;107330;107545;110619;112220;1008.5461542305992;1055.4617145255975;1057.7197042783669;1121.3720316622555;1157.5311998640564;19295.4211373659;19377.70933436028;19564.21743130259;19655.508612284808;19811.20755169777;55301;55493;55844.72077639586;56303;56973.430265696814;68167;68558.3144168552;68579.65710171417;68687;69602;13747.312634368041;14063.929680351943;14082.014259001771;14104.576862694135;14105.506307279196;21713.565728685655;21904.671429928883;22101.33695989123;22213.333599992027;22399.664005039613;17545;17572.91213543924;17821.910890445466;17857.553561161076;18086.366977156264;16030.358785648443;16366.47340635023;16475.917620411823;16677.332906683598;16736.246868891172;18102.54743631367;18199.727004095213;18219.362322318655;18333.08334583292;18421;13801.447942082204;13937.024408291321;13951.302434878013;14026.29868506591;14047.367868446148;16863.494095177164;17789.822101779333;17988.910055450164;18025.188866500775;18058.63882722312 09:45:01 INFO - PID 19110 | |3;mootools.html;1329.7141114660349;1388.736140133375;1394.4103721757192;1394.7977543256234;1401.047287844266;14708.191049492287;15302.693946121071;15409.614759631015;15469;15481.922590387061;15065.548033559011;15280.770788438154;15285.235738213101;15307.081575105502;15318.157501337422;2729.836209827407;2809.19937817722;2819.900238906828;2825.9717402825913;2838.3329917469378;2736.3022429280504;2786.2755683522328;2788.9581656275172;2789.6373471448783;2796.622455968449;2726.495598314308;2794.4969905417015;2802.4395120975782;2816.830990140588;2818.3658676797763;2841.3038805492683;2856.9000084996924;2857.385662082652;2859.270885924097;2871.5118429866975;2321.8490798098237;2693.084351320541;2706.161090062087;2711.7423844734717;2732.153032559893;12530.937345313216;12640.431180596803;12678.492860285578;14732.116073035651;15222.923885380613;8121.796955076111;8314.916850831474;8395;8396.580170991427;8416.873746893802;5523.7238138092935;5607.920792079208;5714.571407144439;5765.827025189251;5844.649321040752;1977.6084015912636;1990.532224927141;1997.0913234478278;2042.3158241988951;2049.5183631846503;26379.736202638112;26492.867535662197;26521.46957060848;26558.078611352736;26601;6781.559198652072;6958.721651133898;7096.325849044332;7280.599567023864;7282.817929551804;17042.31830726782;17175.398861040052;17181.484555463358;17186.656266874594;17216.397426090276;8059.879101813477;8426.368022398345;8635.784105397415;8648.913510864937;8650.221480066699;7615.390768738488;7705.30652241301;7711.26742959418;7825.0218722659665;7886.763397098096;5414.431484694051;5512.2558454608115;5553.527950124204;5589.524890384363;5621.943780562224;1569.7723830044579;1597.5926138834523;1604.486564299413;1610.5973506623345;1628.7377282606076;878.5080355001091;883.7878909061908;897.4929165021872;903.1375036839802;912.9087091290951;7416.295451932057;7481.129717570608;7494.063242094739;7577.772666820004;7587;737.0933751485703;737.9963100184572;738.6233021159247;742.2132539508139;752.221450798421;4991.775370108354;5214.869628259324;5289.153735402394;5299;5435.864103397367;19846;21014.369568912956;21839.454013649785;21868.89065554662;22039;19159.137838797007;19690.916999564874;19943.501412464804;19995.700064499044;20026.09621604859;10883.401412922365;10917.072048875776;11074.335539867634;11133.777324453466;11304.321740695585;7846.646900889587;8268.9663792026;8314.376421768513;8440.535770532433;8458.06961234276;18295.90224586529;18876.05619719036;19202.8079719201;19219.32732354361;19225.807741922403;19432.319868804523;19581.608367832847;19673.606527869648;19966.90016549908;19979.900100499985;11141.610043648758;11233.887661123612;11271.774564508827;11363.54545818158;11367.488463019019;13974.650633734238;13983.510577129751;13998;14014;14125.152490850582;11566.710832229262;11824.76350473003;11948.223365481215;12011.579594714143;12153.513859445524;11354.432278385882;11508.482118304864;11516.251443656136;11516.654500365003;11611.477483513094;10199.490025498548;10309.74225644365;10318.122959548522;10402.895971040192;10403.84394234072;6937.618430986344;7082.150141982995;7139.9286007141345;7167.462440317101;7232.240614735588;6940;7055.541389809646;7058.894116588358;7167.283271672791;7173.856522869618 09:45:01 INFO - PID 19110 | |4;prototype.html;510.8952664703734;521.5749164430989;542.3871025740913;545.6780499505292;555.5056000159856;6134.601250918688;6209.627422354662;6257.811015906986;6282.994720844666;6319.304876463585;6080.969595152029;6121.112438696387;6131.693415329238;6185.6597887116195;6225.501959843213;5123.103456895047;5124.231365295208;5192.273081768555;5196.662216955905;5207;5007.84976450707;5184;5222.3472065991755;5224.373075230958;5235.214717792323;5083.898322033557;5096.949030509684;5112.821051263207;5123.820666276681;5142.820001299955;5091.1090559152;5091.949080509185;5092.79628814847;5132.358455193101;5137.7944882204665;4674.298855171717;4698.976505117453;4719.480857105716;4743.596794272491;4752.4297084349755;5972.522198224132;5999.460048595647;6027.427394397525;6051.152838602599;6065.969670151665;5877.324107727623;5878.119001919387;5902.439268269508;5902.734376953047;5939.316978547479;6337.524685648548;6357.396047375492;6438.903416448757;6446.129772480702;6476.4171224589545;4893.779779909913;5089.160288552384;5136.614753893473;5157.845264642029;5172.844814655567;6661.63361015144;6675.766348177839;6793.354631310018;6818.795436136924;6818.863622727567;5169.638125331191;5355.919661205085;5393.649412788156;5412.215228791803;5415.729213539307;5394.649347792381;5468.562514998791;5479.698616576126;5501.37884177293;5516.641418307797;5523.696196709139;5667.469783158606;5670.603057785998;5676.858078547987;5707.286589176353;6323.747050118036;6468.482521398277;6518.902216466757;6526.706298216592;6563.912967450169;1700.14142857856;1743.3810997095848;1764.3207365164503;1768.8142745011846;1782.8395444410066;1210.4915935307192;1226.2642414551199;1240.944157512914;1248.556762349371;1266.949322027127;5586.190002449622;5597.552195824324;5657.2928383952;5662.292213473316;5687.383803450459;6510.21877374718;6553.705083271264;6616.470682345401;6679.198496180392;6693.330666933268;5504.422035686275;5540.889182216414;5543.722813859291;5589.552835773128;5617.157426386075;3467.9769468007;3481.808500532495;3512.4204506256183;3537.3986422307753;3551.5205447264807;6358.586691865014;6471;6494.882456420944;6511.73953041873;6541.28045914949;6804.829879253059;6855.4172895304455;6906.516543841883;6951.409130223887;6969.372756451842;6033.276006879115;6436.7425302988295;6453.870922581522;6514.185726784152;6525.64108974011;6573.609640095076;6662.300458451987;6711.664416779044;6728.831779205559;6729.5625784323865;6308.653024083719;6441.581297215666;6538.967305163444;6587.275399705933;6610.314369835661;6239.812805615838;6412.102305677302;6428.164338635947;6437.5171862111465;6458.838529036624;4959.156943319572;4960.643694245349;4974.353334066548;4976.800927962986;5046.268291097844;6620;6681.064650948774;6718.462522998246;6824.658767061528;6825.488088393489;6423.775167869102;6443.67781610927;6461.321561236003;6489.5781774184525;6513.772017979348;6356.68216589178;6432.324605916311;6433.228012638513;6458;6489.5781774184525;6373.5857169283845;6449.355064493513;6458.7093580789915;6463.57986730861;6517.576357536745;6561.901571476336;6587.894381854101;6588.868222635616;6614.305497922841;6627.945336945562;6291.779787707408;6304.71628776697;6311.936880631319;6319.715612797343;6326.367363263636 09:45:01 INFO - PID 19110 | |5;yui.html;885.0574138542448;903.7198468474769;906.4651855405311;912.867634193042;916.9403988740734;17335.826641733594;17466;17485.21316540754;22388.55222895541;24523.509529809395;21586.56826863462;22045.559088818212;22376.552468950653;22395.32814015582;22420.66369004462;976.2239019979098;976.5605477535103;977.5845265762023;983.8081574093055;984.6061575369838;953.0707560128853;962.3840741925138;967.1585720423242;977.2426369563574;977.2963466304285;942.7548837302317;971.0726256425132;1040.2614143957762;1040.625374865048;1042.93220940639;1013.9758843567976;1017.4963393120379;1022.4989755020061;1025.5180065369302;1026.5996261458038;747.8130467383154;751.1587022534742;752.6575408189261;753.5026882257738;754.4530215593705;20186.798132018637;21051;21229.469263268387;21257.149714011422;21292.148314067417;21983.34049978503;22143.224987125534;22387.32838014862;22421;22434.102635894546;17772.377966771102;17793.110344482724;17803.287868485244;17824.93050416979;18002.729959050626;3751.662350388478;3788.3181027415053;3830.042489377656;3841.3277676406797;3854.5567259765207;141014.29492852572;141081;143310;144624;145057;16969.915150424167;17073.7438938416;17354.219060142324;17491.30034798619;17527.824721752873;31888.362232755215;32361.676383235867;32406.513902291485;32442;32593.348133037205;8464;8568.228859402683;8584.91415085841;8636.870446943301;8677.132286771272;3974.900627484336;4059.959400405958;4072.775997320176;4077.8776636700945;4078.7552746835286;5431.619786614899;5535.972320138373;5542.473465020817;5553.861153471195;5613;995.8207522645848;1008.8890222075565;1014.518103900656;1021.2391768132788;1026.9948650256702;617.5368473644767;632.6931438252444;650.067153634536;652.3802387731674;654.1430725749284;10919;11244.606438774603;11372.658820235407;11464.452298939537;11535.942320288512;674.7874419557824;681.1009467502848;682.6723172876948;689.7792706333925;694.0664805836142;5065.493450654905;5069.163588008011;5089.440161582223;5205.375354957354;5209;122112;123435;124808;129156.35421823019;129768;109932.4503377478;110831;112051;112095.43952280187;112109.87890120994;97249.51375243078;100689;100952.49523752334;101143;101474.49262753491;9507.286953478379;9601.32790704644;9643.469609171563;9775.413475191512;9869;36288;40754.38868416917;42798.93002674958;44245.778771105935;44615.10769784651;79142;79693;81227;81731;81829;16243.837561624232;16588.253528591486;16691;16762.910410823257;16982.065986370373;19408.320708775125;19568.315108971117;19578.216871325556;19609.509762256057;19946.20215191376;17890.194941227415;17952.192151353483;17997.370092046716;18176.636467270844;18372;18270.269189232815;18290.908545457187;18295.451136465395;18344.816551834843;18572.721409178605;17209.56976075608;17255.309787608356;17371.131443428032;17528.298868045134;17826.37607683725;13658.317084145952;13721.245331506863;13814.930925345308;14173.716525669635;14424.711505770036;13079;13368.264745439094;13413;13439.596812095653;13573.321333933462 09:45:01 INFO - PID 19110 | __end_tp_report 09:45:01 INFO - PID 19110 | __start_cc_report 09:45:01 INFO - PID 19110 | _x_x_mozilla_cycle_collect,292 09:45:01 INFO - PID 19110 | __end_cc_report 09:45:01 INFO - PID 19110 | __startTimestamp1506012301303__endTimestamp 09:45:01 INFO - PID 19110 | 09:45:01 INFO - PID 19110 | ------- Summary: start ------- 09:45:01 INFO - PID 19110 | Number of tests: 6 09:45:01 INFO - PID 19110 | 09:45:01 INFO - PID 19110 | [#0] dojo.html Cycles:165 Average:6245.96 Median:6484.64 stddev:760.00 (11.7%) stddev-sans-first:720.45 09:45:01 INFO - PID 19110 | Values: 3074.2 3148.1 3151.0 3184.3 3185.6 6182.5 6241.1 6352.0 6418.5 6441.3 6190.8 6273.5 6278.9 6291.4 6295.7 5756.9 5768.4 5801.4 5837.1 5880.7 5754.8 5884.9 5906.1 5943.9 5959.2 5856.1 5895.7 5951.0 5963.2 5978.7 5620.9 5658.4 5788.4 5827.6 5929.1 5792.0 5799.2 5813.8 5815.9 5852.6 6324.1 6349.2 6352.7 6354.3 6395.3 6812.6 6816.9 6880.8 6902.7 6910.1 6637.1 6668.3 6803.9 6862.7 6884.3 6262.7 6263.2 6294.2 6297.8 6383.1 6986.0 7032.6 7056.7 7068.5 7142.5 6675.4 6697.2 6732.2 6774.2 6796.5 6646.7 6780.2 6792.3 6871.6 6921.9 6692.6 6706.3 6753.2 6759.3 6808.5 6723.8 6773.4 6831.2 6866.0 6880.4 5338.1 5371.4 5399.6 5401.2 5433.2 4551.3 4651.0 4654.0 4654.7 4660.3 5765.4 5831.0 5859.8 5875.7 5880.9 6745.3 6745.4 6825.4 6839.1 6879.1 5678.5 5740.6 5752.9 5752.9 5772.6 5847.1 5898.3 5909.1 5910.8 5917.9 6627.6 6641.8 6677.3 6682.0 6692.5 6755.9 6764.4 6844.1 6891.4 6918.0 6359.1 6578.8 6598.0 6633.0 6666.3 6629.6 6637.4 6645.5 6678.2 6679.1 6786.3 6947.8 6950.8 6956.0 6967.7 6596.2 6690.3 6694.7 6697.0 6698.6 6473.4 6535.8 6556.0 6565.8 6630.6 6376.3 6467.7 6480.4 6484.6 6619.8 6544.8 6581.5 6622.2 6624.7 6631.8 6380.5 6411.5 6485.4 6499.5 6512.3 09:45:01 INFO - PID 19110 | 09:45:01 INFO - PID 19110 | [#1] ext.html Cycles:180 Average:37306.63 Median:3564.86 stddev:53593.07 (1503.4%) stddev-sans-first:53673.15 09:45:01 INFO - PID 19110 | Values: 760.2 776.4 783.1 785.6 785.7 40022.2 40792.0 43618.6 43839.3 43917.8 39111.4 39373.8 39468.8 39678.4 39772.0 7054.2 7114.0 7149.6 7158.0 7873.7 7851.8 7989.2 7990.4 7992.2 7997.0 8018.6 8076.0 8079.9 8084.2 8091.7 7915.2 8033.2 8044.8 8050.9 8062.6 7938.7 7943.9 7968.9 7983.6 8025.5 38482.8 38772.0 38891.2 38972.4 39354.4 3545.9 3560.2 3569.5 3575.7 3577.4 32400.0 32471.4 32516.5 32757.3 32792.7 5264.4 5265.0 5308.5 5354.2 5401.6 191865.0 191887.0 192369.0 192452.0 193370.0 41830.6 47967.5 48074.6 48171.0 48243.1 43570.8 51417.0 51425.2 51449.0 51451.5 14669.0 14798.5 14837.3 14843.2 14871.4 11762.0 11774.6 11861.8 11882.4 11898.3 13784.4 13887.0 13976.3 14109.9 14155.9 5368.5 5396.3 5417.5 5423.7 5539.7 976.7 1000.6 1002.6 1006.3 1009.8 22752.9 22946.8 23183.8 23222.2 23323.5 1063.9 1069.5 1086.0 1101.1 1119.3 11984.4 12105.8 12149.8 12231.0 12236.6 227167.0 228409.0 229210.0 229309.0 230152.0 51306.2 51342.2 51498.5 51679.2 51874.5 1420.4 1446.7 1447.2 1452.3 1465.3 21923.0 22537.0 22676.4 22851.4 22955.2 36296.3 36451.3 36539.3 36585.3 36665.0 195752.0 197251.0 198077.0 198138.0 198427.0 30144.0 30333.5 30652.4 32829.5 35012.6 43138.1 43446.8 43628.3 43808.8 44318.3 31951.4 32077.5 32114.5 32309.5 32413.5 31313.7 31349.8 31613.4 31693.7 32258.7 36099.6 36222.6 36233.0 36390.5 36474.5 30162.4 30236.8 30755.2 31023.4 31436.8 22868.8 23189.9 23527.3 23644.2 24034.6 09:45:01 INFO - PID 19110 | 09:45:01 INFO - PID 19110 | [#2] jquery.html Cycles:180 Average:33134.16 Median:22214.61 stddev:78121.06 (351.7%) stddev-sans-first:78301.97 09:45:01 INFO - PID 19110 | Values: 578.5 587.9 589.6 589.6 603.6 21320.7 21957.3 22164.3 22200.6 22215.9 20881.8 21886.9 22240.9 22285.7 22506.5 6344.1 6491.4 6533.1 6533.9 6546.6 6419.8 6439.2 6509.5 6541.4 6542.2 6312.7 6535.5 6579.1 6597.2 6601.7 6561.3 6565.5 6566.5 6575.3 6577.0 5942.6 6092.2 6111.1 6147.9 6149.0 28070.6 28659.0 28676.4 28688.6 28692.1 2017.1 2039.9 2040.4 2042.8 2045.5 18686.0 18919.2 19254.3 19424.1 19427.7 2308.9 2389.9 2406.2 2406.4 2409.1 116935.0 122083.6 122770.0 133847.0 140363.0 14427.7 14735.9 14766.9 14769.8 14989.1 22152.8 22535.2 22569.1 22722.2 22777.3 9461.1 9463.8 9499.3 9561.1 9603.7 8605.6 8628.2 8760.1 8780.5 8784.8 8738.0 8813.6 8928.4 8992.8 9196.4 2669.6 2680.5 2715.6 2741.7 2894.8 943.0 965.2 966.5 970.0 979.4 18199.0 18371.4 18551.0 18551.5 18623.4 1009.3 1022.8 1027.0 1030.2 1032.6 10218.0 10314.9 10368.3 10425.1 10672.6 439821.0 462182.0 464541.0 465658.0 488797.0 107216.0 107330.0 107545.0 110619.0 112220.0 1008.5 1055.5 1057.7 1121.4 1157.5 19295.4 19377.7 19564.2 19655.5 19811.2 55301.0 55493.0 55844.7 56303.0 56973.4 68167.0 68558.3 68579.7 68687.0 69602.0 13747.3 14063.9 14082.0 14104.6 14105.5 21713.6 21904.7 22101.3 22213.3 22399.7 17545.0 17572.9 17821.9 17857.6 18086.4 16030.4 16366.5 16475.9 16677.3 16736.2 18102.5 18199.7 18219.4 18333.1 18421.0 13801.4 13937.0 13951.3 14026.3 14047.4 16863.5 17789.8 17988.9 18025.2 18058.6 09:45:01 INFO - PID 19110 | 09:45:01 INFO - PID 19110 | [#3] mootools.html Cycles:180 Average:9339.00 Median:14647.04 stddev:6572.61 (44.9%) stddev-sans-first:6563.50 09:45:01 INFO - PID 19110 | Values: 1329.7 1388.7 1394.4 1394.8 1401.0 14708.2 15302.7 15409.6 15469.0 15481.9 15065.5 15280.8 15285.2 15307.1 15318.2 2729.8 2809.2 2819.9 2826.0 2838.3 2736.3 2786.3 2789.0 2789.6 2796.6 2726.5 2794.5 2802.4 2816.8 2818.4 2841.3 2856.9 2857.4 2859.3 2871.5 2321.8 2693.1 2706.2 2711.7 2732.2 12530.9 12640.4 12678.5 14732.1 15222.9 8121.8 8314.9 8395.0 8396.6 8416.9 5523.7 5607.9 5714.6 5765.8 5844.6 1977.6 1990.5 1997.1 2042.3 2049.5 26379.7 26492.9 26521.5 26558.1 26601.0 6781.6 6958.7 7096.3 7280.6 7282.8 17042.3 17175.4 17181.5 17186.7 17216.4 8059.9 8426.4 8635.8 8648.9 8650.2 7615.4 7705.3 7711.3 7825.0 7886.8 5414.4 5512.3 5553.5 5589.5 5621.9 1569.8 1597.6 1604.5 1610.6 1628.7 878.5 883.8 897.5 903.1 912.9 7416.3 7481.1 7494.1 7577.8 7587.0 737.1 738.0 738.6 742.2 752.2 4991.8 5214.9 5289.2 5299.0 5435.9 19846.0 21014.4 21839.5 21868.9 22039.0 19159.1 19690.9 19943.5 19995.7 20026.1 10883.4 10917.1 11074.3 11133.8 11304.3 7846.6 8269.0 8314.4 8440.5 8458.1 18295.9 18876.1 19202.8 19219.3 19225.8 19432.3 19581.6 19673.6 19966.9 19979.9 11141.6 11233.9 11271.8 11363.5 11367.5 13974.7 13983.5 13998.0 14014.0 14125.2 11566.7 11824.8 11948.2 12011.6 12153.5 11354.4 11508.5 11516.3 11516.7 11611.5 10199.5 10309.7 10318.1 10402.9 10403.8 6937.6 7082.2 7139.9 7167.5 7232.2 6940.0 7055.5 7058.9 7167.3 7173.9 09:45:01 INFO - PID 19110 | 09:45:01 INFO - PID 19110 | [#4] prototype.html Cycles:180 Average:5526.74 Median:6073.47 stddev:1509.36 (24.9%) stddev-sans-first:1465.89 09:45:01 INFO - PID 19110 | Values: 510.9 521.6 542.4 545.7 555.5 6134.6 6209.6 6257.8 6283.0 6319.3 6081.0 6121.1 6131.7 6185.7 6225.5 5123.1 5124.2 5192.3 5196.7 5207.0 5007.8 5184.0 5222.3 5224.4 5235.2 5083.9 5096.9 5112.8 5123.8 5142.8 5091.1 5091.9 5092.8 5132.4 5137.8 4674.3 4699.0 4719.5 4743.6 4752.4 5972.5 5999.5 6027.4 6051.2 6066.0 5877.3 5878.1 5902.4 5902.7 5939.3 6337.5 6357.4 6438.9 6446.1 6476.4 4893.8 5089.2 5136.6 5157.8 5172.8 6661.6 6675.8 6793.4 6818.8 6818.9 5169.6 5355.9 5393.6 5412.2 5415.7 5394.6 5468.6 5479.7 5501.4 5516.6 5523.7 5667.5 5670.6 5676.9 5707.3 6323.7 6468.5 6518.9 6526.7 6563.9 1700.1 1743.4 1764.3 1768.8 1782.8 1210.5 1226.3 1240.9 1248.6 1266.9 5586.2 5597.6 5657.3 5662.3 5687.4 6510.2 6553.7 6616.5 6679.2 6693.3 5504.4 5540.9 5543.7 5589.6 5617.2 3468.0 3481.8 3512.4 3537.4 3551.5 6358.6 6471.0 6494.9 6511.7 6541.3 6804.8 6855.4 6906.5 6951.4 6969.4 6033.3 6436.7 6453.9 6514.2 6525.6 6573.6 6662.3 6711.7 6728.8 6729.6 6308.7 6441.6 6539.0 6587.3 6610.3 6239.8 6412.1 6428.2 6437.5 6458.8 4959.2 4960.6 4974.4 4976.8 5046.3 6620.0 6681.1 6718.5 6824.7 6825.5 6423.8 6443.7 6461.3 6489.6 6513.8 6356.7 6432.3 6433.2 6458.0 6489.6 6373.6 6449.4 6458.7 6463.6 6517.6 6561.9 6587.9 6588.9 6614.3 6627.9 6291.8 6304.7 6311.9 6319.7 6326.4 09:45:01 INFO - PID 19110 | 09:45:01 INFO - PID 19110 | [#5] yui.html Cycles:180 Average:25815.01 Median:22014.45 stddev:36976.46 (168.0%) stddev-sans-first:37032.80 09:45:01 INFO - PID 19110 | Values: 885.1 903.7 906.5 912.9 916.9 17335.8 17466.0 17485.2 22388.6 24523.5 21586.6 22045.6 22376.6 22395.3 22420.7 976.2 976.6 977.6 983.8 984.6 953.1 962.4 967.2 977.2 977.3 942.8 971.1 1040.3 1040.6 1042.9 1014.0 1017.5 1022.5 1025.5 1026.6 747.8 751.2 752.7 753.5 754.5 20186.8 21051.0 21229.5 21257.1 21292.1 21983.3 22143.2 22387.3 22421.0 22434.1 17772.4 17793.1 17803.3 17824.9 18002.7 3751.7 3788.3 3830.0 3841.3 3854.6 141014.3 141081.0 143310.0 144624.0 145057.0 16969.9 17073.7 17354.2 17491.3 17527.8 31888.4 32361.7 32406.5 32442.0 32593.3 8464.0 8568.2 8584.9 8636.9 8677.1 3974.9 4060.0 4072.8 4077.9 4078.8 5431.6 5536.0 5542.5 5553.9 5613.0 995.8 1008.9 1014.5 1021.2 1027.0 617.5 632.7 650.1 652.4 654.1 10919.0 11244.6 11372.7 11464.5 11535.9 674.8 681.1 682.7 689.8 694.1 5065.5 5069.2 5089.4 5205.4 5209.0 122112.0 123435.0 124808.0 129156.4 129768.0 109932.5 110831.0 112051.0 112095.4 112109.9 97249.5 100689.0 100952.5 101143.0 101474.5 9507.3 9601.3 9643.5 9775.4 9869.0 36288.0 40754.4 42798.9 44245.8 44615.1 79142.0 79693.0 81227.0 81731.0 81829.0 16243.8 16588.3 16691.0 16762.9 16982.1 19408.3 19568.3 19578.2 19609.5 19946.2 17890.2 17952.2 17997.4 18176.6 18372.0 18270.3 18290.9 18295.5 18344.8 18572.7 17209.6 17255.3 17371.1 17528.3 17826.4 13658.3 13721.2 13814.9 14173.7 14424.7 13079.0 13368.3 13413.0 13439.6 13573.3 09:45:01 INFO - PID 19110 | -------- Summary: end -------- 09:45:01 INFO - PID 19110 | 09:45:02 INFO - TEST-INFO | 19110: exit 0 09:45:02 INFO - TEST-OK | dromaeo_css | took 1113609ms 09:45:02 INFO - TEST-START | kraken 09:45:02 INFO - Initialising browser for kraken test... 09:45:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46697/getInfo.html -profile /tmp/tmpdBCXuA/profile 09:45:02 INFO - TEST-INFO | started process 19496 (/builds/slave/test/build/application/firefox/firefox http://localhost:46697/getInfo.html) 09:45:07 INFO - TEST-INFO | 19496: exit 0 09:45:07 INFO - Browser initialized. 09:45:07 INFO - Running cycle 1/1 for kraken test... 09:45:07 INFO - Using env: {'DISPLAY': ':0', 09:45:07 INFO - 'HOME': '/home/cltbld', 09:45:07 INFO - 'JSGC_DISABLE_POISONING': '1', 09:45:07 INFO - 'LANG': 'en_US.UTF-8', 09:45:07 INFO - 'LANGUAGE': 'en_US:en', 09:45:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:45:07 INFO - 'LOGNAME': 'cltbld', 09:45:07 INFO - 'MAIL': '/var/mail/cltbld', 09:45:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:45:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:45:07 INFO - 'MOZ_ACCELERATED': '1', 09:45:07 INFO - 'MOZ_CRASHREPORTER': '1', 09:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:45:07 INFO - 'MOZ_NO_REMOTE': '1', 09:45:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:45:07 INFO - 'MOZ_WEBRENDER': '1', 09:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:45:07 INFO - 'NO_EM_RESTART': '1', 09:45:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:07 INFO - 'PWD': '/builds/slave/test', 09:45:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:45:07 INFO - 'RUST_BACKTRACE': 'full', 09:45:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:45:07 INFO - 'SHELL': '/bin/bash', 09:45:07 INFO - 'SHLVL': '1', 09:45:07 INFO - 'TERM': 'linux', 09:45:07 INFO - 'TMOUT': '86400', 09:45:07 INFO - 'USER': 'cltbld', 09:45:07 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881', 09:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:07 INFO - '_': '/tools/buildbot/bin/python'} 09:45:07 INFO - TEST-INFO | started process 19741 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdBCXuA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:08 INFO - PID 19741 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:45:08 INFO - PID 19741 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:45:08 INFO - PID 19741 | 09:45:08 INFO - PID 19741 | (/builds/slave/test/build/application/firefox/firefox:19800): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:45:08 INFO - PID 19741 | 09:45:19 INFO - PID 19741 | RSS: Main: 188821504 09:45:19 INFO - PID 19741 | 09:45:21 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46697/tests/kraken/driver.html?testName=audio-beat-detection) 09:45:22 INFO - PID 19741 | RSS: Main: 187228160 09:45:22 INFO - PID 19741 | 09:45:25 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46697/tests/kraken/driver.html?testName=audio-dft) 09:45:26 INFO - PID 19741 | RSS: Main: 186540032 09:45:26 INFO - PID 19741 | 09:45:29 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46697/tests/kraken/driver.html?testName=audio-fft) 09:45:29 INFO - PID 19741 | RSS: Main: 183939072 09:45:29 INFO - PID 19741 | 09:45:32 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46697/tests/kraken/driver.html?testName=audio-oscillator) 09:45:32 INFO - PID 19741 | RSS: Main: 184295424 09:45:32 INFO - PID 19741 | 09:45:34 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46697/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:45:35 INFO - PID 19741 | RSS: Main: 185257984 09:45:35 INFO - PID 19741 | 09:45:38 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46697/tests/kraken/driver.html?testName=imaging-darkroom) 09:45:39 INFO - PID 19741 | RSS: Main: 187195392 09:45:39 INFO - PID 19741 | 09:45:43 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46697/tests/kraken/driver.html?testName=imaging-desaturate) 09:45:43 INFO - PID 19741 | RSS: Main: 186667008 09:45:43 INFO - PID 19741 | 09:45:46 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46697/tests/kraken/driver.html?testName=json-parse-financial) 09:45:47 INFO - PID 19741 | RSS: Main: 187912192 09:45:47 INFO - PID 19741 | 09:45:48 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46697/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:45:49 INFO - PID 19741 | RSS: Main: 187555840 09:45:49 INFO - PID 19741 | 09:45:51 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46697/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:45:51 INFO - PID 19741 | RSS: Main: 184815616 09:45:51 INFO - PID 19741 | 09:45:54 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46697/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:45:54 INFO - PID 19741 | RSS: Main: 184279040 09:45:54 INFO - PID 19741 | 09:45:57 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46697/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:45:57 INFO - PID 19741 | RSS: Main: 184168448 09:45:57 INFO - PID 19741 | 09:46:00 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46697/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:46:01 INFO - PID 19741 | RSS: Main: 185040896 09:46:01 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | Cycle 1(1): loaded http://localhost:46697/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46697/tests/kraken/driver.html?testName=ai-astar) 09:46:03 INFO - PID 19741 | RSS: Main: 186064896 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | __start_tp_report 09:46:03 INFO - PID 19741 | _x_x_mozilla_page_load 09:46:03 INFO - PID 19741 | _x_x_mozilla_page_load_details 09:46:03 INFO - PID 19741 | |i|pagename|runs| 09:46:03 INFO - PID 19741 | |0;ai-astar;112;107;111;108;111;112;119;108;116;115 09:46:03 INFO - PID 19741 | |1;audio-beat-detection;123;119;118;116;117;117;120;121;150;117 09:46:03 INFO - PID 19741 | |2;audio-dft;162;170;170;164;172;164;167;162;174;168 09:46:03 INFO - PID 19741 | |3;audio-fft;81;76;76;84;80;75;76;75;82;77 09:46:03 INFO - PID 19741 | |4;audio-oscillator;84;85;87;85;80;83;89;80;80;83 09:46:03 INFO - PID 19741 | |5;imaging-gaussian-blur;114;113;122;115;114;116;115;115;124;112 09:46:03 INFO - PID 19741 | |6;imaging-darkroom;148;149;152;148;154;147;148;151;148;157 09:46:03 INFO - PID 19741 | |7;imaging-desaturate;100;94;94;94;101;98;96;96;99;95 09:46:03 INFO - PID 19741 | |8;json-parse-financial;58;61;53;58;57;54;54;57;56;51 09:46:03 INFO - PID 19741 | |9;json-stringify-tinderbox;38;40;39;45;38;38;51;49;39;39 09:46:03 INFO - PID 19741 | |10;stanford-crypto-aes;80;82;74;83;81;86;72;78;75;77 09:46:03 INFO - PID 19741 | |11;stanford-crypto-ccm;119;120;120;119;118;116;114;114;115;114 09:46:03 INFO - PID 19741 | |12;stanford-crypto-pbkdf2;175;170;169;175;173;168;177;171;174;176 09:46:03 INFO - PID 19741 | |13;stanford-crypto-sha256-iterative;80;65;68;66;67;69;66;68;64;66 09:46:03 INFO - PID 19741 | __end_tp_report 09:46:03 INFO - PID 19741 | __start_cc_report 09:46:03 INFO - PID 19741 | _x_x_mozilla_cycle_collect,764 09:46:03 INFO - PID 19741 | __end_cc_report 09:46:03 INFO - PID 19741 | __startTimestamp1506012363659__endTimestamp 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | ------- Summary: start ------- 09:46:03 INFO - PID 19741 | Number of tests: 14 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:3.84 (3.4%) stddev-sans-first:4.08 09:46:03 INFO - PID 19741 | Values: 112.0 107.0 111.0 108.0 111.0 112.0 119.0 108.0 116.0 115.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:119.50 stddev:10.14 (8.5%) stddev-sans-first:10.75 09:46:03 INFO - PID 19741 | Values: 123.0 119.0 118.0 116.0 117.0 117.0 120.0 121.0 150.0 117.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#2] audio-dft Cycles:10 Average:167.30 Median:169.00 stddev:4.22 (2.5%) stddev-sans-first:4.01 09:46:03 INFO - PID 19741 | Values: 162.0 170.0 170.0 164.0 172.0 164.0 167.0 162.0 174.0 168.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#3] audio-fft Cycles:10 Average:78.20 Median:78.50 stddev:3.26 (4.2%) stddev-sans-first:3.30 09:46:03 INFO - PID 19741 | Values: 81.0 76.0 76.0 84.0 80.0 75.0 76.0 75.0 82.0 77.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:84.50 stddev:3.06 (3.6%) stddev-sans-first:3.24 09:46:03 INFO - PID 19741 | Values: 84.0 85.0 87.0 85.0 80.0 83.0 89.0 80.0 80.0 83.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#5] imaging-gaussian-blur Cycles:10 Average:116.00 Median:115.00 stddev:3.89 (3.4%) stddev-sans-first:4.06 09:46:03 INFO - PID 19741 | Values: 114.0 113.0 122.0 115.0 114.0 116.0 115.0 115.0 124.0 112.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:3.26 (2.2%) stddev-sans-first:3.36 09:46:03 INFO - PID 19741 | Values: 148.0 149.0 152.0 148.0 154.0 147.0 148.0 151.0 148.0 157.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:95.50 stddev:2.63 (2.8%) stddev-sans-first:2.50 09:46:03 INFO - PID 19741 | Values: 100.0 94.0 94.0 94.0 101.0 98.0 96.0 96.0 99.0 95.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:57.00 stddev:2.92 (5.1%) stddev-sans-first:3.00 09:46:03 INFO - PID 19741 | Values: 58.0 61.0 53.0 58.0 57.0 54.0 54.0 57.0 56.0 51.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#9] json-stringify-tinderbox Cycles:10 Average:41.60 Median:39.50 stddev:4.90 (12.4%) stddev-sans-first:5.02 09:46:03 INFO - PID 19741 | Values: 38.0 40.0 39.0 45.0 38.0 38.0 51.0 49.0 39.0 39.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:80.50 stddev:4.39 (5.5%) stddev-sans-first:4.64 09:46:03 INFO - PID 19741 | Values: 80.0 82.0 74.0 83.0 81.0 86.0 72.0 78.0 75.0 77.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#11] stanford-crypto-ccm Cycles:10 Average:116.90 Median:118.50 stddev:2.56 (2.2%) stddev-sans-first:2.60 09:46:03 INFO - PID 19741 | Values: 119.0 120.0 120.0 119.0 118.0 116.0 114.0 114.0 115.0 114.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:174.50 stddev:3.12 (1.8%) stddev-sans-first:3.21 09:46:03 INFO - PID 19741 | Values: 175.0 170.0 169.0 175.0 173.0 168.0 177.0 171.0 174.0 176.0 09:46:03 INFO - PID 19741 | 09:46:03 INFO - PID 19741 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:67.50 stddev:4.51 (6.7%) stddev-sans-first:1.59 09:46:03 INFO - PID 19741 | Values: 80.0 65.0 68.0 66.0 67.0 69.0 66.0 68.0 64.0 66.0 09:46:03 INFO - PID 19741 | -------- Summary: end -------- 09:46:03 INFO - PID 19741 | 09:46:04 INFO - PID 19741 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:46:04 INFO - TEST-INFO | 19741: exit 0 09:46:04 INFO - TEST-OK | kraken | took 62527ms 09:46:04 INFO - SUITE-END | took 1176s 09:46:04 INFO - Completed test suite (00:19:36) 09:46:04 INFO - javascript benchmark 09:46:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8526.006780735057, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3074.2160749009, 3148.1342630776535, 3150.9916826615486, 3184.299454120094, 3185.6177258728926, 6182.474489668372, 6241.126242326072, 6351.968240158804, 6418.454431373333, 6441.259255185655, 6190.78332258371, 6273.529485288599, 6278.9372106278925, 6291.4337709606125, 6295.748170073203, 5756.884862302752, 5768.39431859655, 5801.448862358069, 5837.066069428893, 5880.705964701748, 5754.827355179351, 5884.882302353951, 5906.05503119501, 5943.940560594404, 5959.165716799652, 5856.121581762748, 5895.705214739267, 5951.047832346825, 5963.194968679216, 5978.730957106941, 5620.859478513029, 5658.405867383906, 5788.392218817005, 5827.592068555203, 5929.140274660193, 5791.971040144815, 5799.18811366409, 5813.82558523245, 5815.883682326372, 5852.619579727304, 6324.114623952651, 6349.206349206349, 6352.682365881734, 6354.3010268870385, 6395.328490508523, 6812.557183783038, 6816.931830681729, 6880.759173428956, 6902.654867256617, 6910.136232970879, 6637.137172167636, 6668.333166683341, 6803.863922721567, 6862.691178896962, 6884.311568843125, 6262.655553944532, 6263.217097862767, 6294.213223347082, 6297.842553936142, 6383.074454204161, 6985.965070174719, 7032.578045317297, 7056.682449289794, 7068.469864760164, 7142.464315176382, 6675.432588229958, 6697.162854643169, 6732.225794033651, 6774.187097548326, 6796.4562834973085, 6646.700898459581, 6780.1524809398825, 6792.286809884989, 6871.622060786604, 6921.930780692129, 6692.59844409337, 6706.329367063352, 6753.15585551806, 6759.324067593201, 6808.455323574102, 6723.798286051427, 6773.424258937947, 6831.180258369028, 6866.0, 6880.380765731108, 5338.065838478251, 5371.435999220025, 5399.595030372738, 5401.162819762943, 5433.157860531624, 4551.271796512542, 4651.046535460223, 4653.999390131138, 4654.720716757005, 4660.347551342815, 5765.394633563499, 5831.037878750044, 5859.765609375662, 5875.706214689249, 5880.852978675567, 6745.325467453215, 6745.392914637706, 6825.419839313615, 6839.110915580943, 6879.140107486564, 5678.517326027251, 5740.56945729072, 5752.856178595569, 5752.884942301131, 5772.5670574707065, 5847.122931560215, 5898.262717160355, 5909.113632955005, 5910.793122240787, 5917.911231331534, 6627.569208001464, 6641.767538136142, 6677.332266773284, 6681.997700344987, 6692.531522793455, 6755.86488270222, 6764.391204791691, 6844.1444819397575, 6891.3797758202, 6918.0314755933205, 6359.07793369968, 6578.8355291118105, 6598.043283723737, 6633.0, 6666.300038495888, 6629.602223866584, 6637.402633762985, 6645.468362531083, 6678.165229346332, 6679.131712877295, 6786.287439818748, 6947.79156625302, 6950.79147625572, 6956.0, 6967.7212911482975, 6596.208454985433, 6690.330966903271, 6694.665266736741, 6697.0, 6698.598084114774, 6473.449756770728, 6535.836604084936, 6556.0, 6565.835854103686, 6630.635315057718, 6376.298607153117, 6467.676616169078, 6480.41676249121, 6484.643344615903, 6619.834504137435, 6544.770933017321, 6581.506387020897, 6622.1722284714415, 6624.735010599522, 6631.834204144935, 6380.521460890359, 6411.487081033413, 6485.416312531895, 6499.512536559682, 6512.316206798408], "value": 6186.979034323551, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.2359628573283, 776.4215659333797, 783.142459007387, 785.5679376343013, 785.7092875636016, 40022.1995560089, 40792.0, 43618.56381436185, 43839.34240986388, 43917.7804110979, 39111.41332880009, 39373.80313098438, 39468.80265598675, 39678.40482392767, 39772.0, 7054.188768291648, 7113.964430177842, 7149.642517874112, 7157.964210178968, 7873.724419645314, 7851.842963140733, 7989.201079891994, 7990.4007199460275, 7992.160823113548, 7996.960015199916, 8018.639161237729, 8075.959620201891, 8079.9192008080045, 8084.231997960213, 8091.716789912354, 7915.248051435105, 8033.156518565584, 8044.839103217902, 8050.919490805134, 8062.596870156469, 7938.682452701943, 7943.920560794376, 7968.920310796876, 7983.560904150269, 8025.4783439076855, 38482.80758596217, 38772.0, 38891.22217555633, 38972.41541376882, 39354.40968385476, 3545.9113522161892, 3560.2167523144867, 3569.5002699622073, 3575.6781889630056, 3577.4454959481322, 32400.0, 32471.35057298841, 32516.512252316235, 32757.344853103044, 32792.6720732792, 5264.42091369949, 5264.973675131638, 5308.495692909168, 5354.223637572531, 5401.6218864679095, 191865.0, 191887.0, 192369.0, 192452.0, 193369.9999999986, 41830.581694183275, 47967.520324797006, 48074.557763267156, 48171.0, 48243.070277189225, 43570.7821460897, 51417.0, 51425.228621570706, 51449.0, 51451.485485145415, 14669.0, 14798.48205312809, 14837.258137093102, 14843.183624900517, 14871.405143794344, 11762.0, 11774.646760597025, 11861.762764744657, 11882.405879706153, 11898.286102833858, 13784.379702913393, 13887.0, 13976.301184940916, 14109.929450352682, 14155.929220353832, 5368.538608806612, 5396.325459317585, 5417.539509141767, 5423.674579525241, 5539.667619942817, 976.6630512473185, 1000.6497725795913, 1002.6390499420203, 1006.2603986070233, 1009.848522721583, 22752.886235568716, 22946.7705322948, 23183.768162318498, 23222.187223447097, 23323.533529329317, 1063.9414832184304, 1069.540077793806, 1085.9674209773718, 1101.1136035491506, 1119.3172164979355, 11984.400779960966, 12105.818412723816, 12149.757004859854, 12230.992107103679, 12236.632901012983, 227167.0, 228409.0, 229210.0, 229309.0, 230152.0, 51306.23040654393, 51342.22986655203, 51498.485015150116, 51679.22481162786, 51874.48125518696, 1420.4176287722191, 1446.7251222267735, 1447.1534152520894, 1452.3391856705173, 1465.3405967314538, 21923.0, 22537.0, 22676.433089172904, 22851.428714282276, 22955.196568120034, 36296.27407451889, 36451.27097458089, 36539.26921461609, 36585.268294634494, 36665.0, 195752.0, 197251.0, 198077.0, 198138.0, 198427.0, 30144.0, 30333.544996825505, 30652.386952261277, 32829.507557387136, 35012.64987350094, 43138.13723725571, 43446.78276608597, 43628.345574815765, 43808.78095609502, 44318.335224972296, 31951.36097278088, 32077.518837217925, 32114.51828222532, 32309.51535726919, 32413.513797292588, 31313.68686313199, 31349.843250784514, 31613.367732644758, 31693.69423378544, 32258.677413225567, 36099.63900360963, 36222.63777362193, 36233.0, 36390.454143187344, 36474.452883207305, 30162.396752065273, 30236.84881575578, 30755.231119221302, 31023.379532408773, 31436.842815785774, 22868.77131228733, 23189.88405057964, 23527.29418117459, 23644.172453964027, 24034.63948040746], "value": 16944.79244933435, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.5487319890486, 587.917691523187, 589.5578316262805, 589.6196952965339, 603.6498830678207, 21320.680189797167, 21957.341279761633, 22164.335069947887, 22200.555988880213, 22215.888920555415, 20881.791182088175, 21886.89056554719, 22240.888795556, 22285.66571501429, 22506.54986900261, 6344.143540622026, 6491.415772580467, 6533.118029066087, 6533.869322613545, 6546.574472659262, 6419.775307864225, 6439.195100612424, 6509.511786615999, 6541.443977261938, 6542.247641521215, 6312.684365781715, 6535.477161827066, 6579.078928949951, 6597.208334999783, 6601.7359305627715, 6561.311062338457, 6565.54041217115, 6566.540342176049, 6575.3095924927675, 6577.04632828242, 5942.5840191186635, 6092.177556029924, 6111.052786818006, 6147.877042459126, 6149.0, 28070.578941315896, 28659.0, 28676.426471470455, 28688.569671454945, 28692.13923582296, 2017.1124705129844, 2039.9188430132053, 2040.3776848061336, 2042.7855075217037, 2045.4988527810633, 18686.0, 18919.24323027077, 19254.32609858662, 19424.12591433389, 19427.708584371245, 2308.9191878284178, 2389.940251493709, 2406.2300063979533, 2406.4224586099385, 2409.0725070847675, 116935.0, 122083.55832883292, 122770.0, 133847.0, 140362.99999999898, 14427.66529858818, 14735.86329982168, 14766.926165369105, 14769.778453323208, 14989.100653960579, 22152.77847221507, 22535.211267605882, 22569.09723611037, 22722.20472283462, 22777.31668049961, 9461.148496635336, 9463.810723785486, 9499.335046546814, 9561.139497445263, 9603.729139379568, 8605.569721513899, 8628.22345988864, 8760.080191579793, 8780.517071561126, 8784.824303513893, 8737.956310218407, 8813.559322033872, 8928.375013749104, 8992.820143597091, 9196.35625506208, 2669.639598654157, 2680.517506848747, 2715.5919321251704, 2741.696256011541, 2894.8407837568716, 942.9905700942903, 965.1603105298435, 966.4970970141497, 969.9806003880024, 979.3683074416961, 18199.0, 18371.357002504847, 18551.0, 18551.53621159482, 18623.44129676112, 1009.3086235928413, 1022.7698767777191, 1027.034587487774, 1030.2427715629008, 1032.566322144701, 10218.0, 10314.896851031544, 10368.274220804622, 10425.061744442884, 10672.626458074086, 439821.0, 462182.0, 464541.0, 465658.0, 488797.0, 107216.0, 107330.0, 107545.0, 110619.0, 112220.0, 1008.5461542305992, 1055.4617145255975, 1057.7197042783669, 1121.3720316622555, 1157.5311998640564, 19295.4211373659, 19377.70933436028, 19564.21743130259, 19655.508612284808, 19811.20755169777, 55301.0, 55493.0, 55844.72077639586, 56303.0, 56973.430265696814, 68167.0, 68558.3144168552, 68579.65710171417, 68687.0, 69602.0, 13747.312634368041, 14063.929680351943, 14082.014259001771, 14104.576862694135, 14105.506307279196, 21713.565728685655, 21904.671429928883, 22101.33695989123, 22213.333599992027, 22399.664005039613, 17545.0, 17572.91213543924, 17821.910890445466, 17857.553561161076, 18086.366977156264, 16030.358785648443, 16366.47340635023, 16475.917620411823, 16677.332906683598, 16736.246868891172, 18102.54743631367, 18199.727004095213, 18219.362322318655, 18333.08334583292, 18421.0, 13801.447942082204, 13937.024408291321, 13951.302434878013, 14026.29868506591, 14047.367868446148, 16863.494095177164, 17789.822101779333, 17988.910055450164, 18025.188866500775, 18058.63882722312], "value": 11628.428422627525, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.7141114660349, 1388.736140133375, 1394.4103721757192, 1394.7977543256234, 1401.047287844266, 14708.191049492287, 15302.693946121071, 15409.614759631015, 15469.0, 15481.922590387061, 15065.548033559011, 15280.770788438154, 15285.235738213101, 15307.081575105502, 15318.157501337422, 2729.836209827407, 2809.19937817722, 2819.900238906828, 2825.9717402825913, 2838.3329917469378, 2736.3022429280504, 2786.2755683522328, 2788.9581656275172, 2789.6373471448783, 2796.622455968449, 2726.495598314308, 2794.4969905417015, 2802.4395120975782, 2816.830990140588, 2818.3658676797763, 2841.3038805492683, 2856.9000084996924, 2857.385662082652, 2859.270885924097, 2871.5118429866975, 2321.8490798098237, 2693.084351320541, 2706.161090062087, 2711.7423844734717, 2732.153032559893, 12530.937345313216, 12640.431180596803, 12678.492860285578, 14732.116073035651, 15222.923885380613, 8121.796955076111, 8314.916850831474, 8395.0, 8396.580170991427, 8416.873746893802, 5523.7238138092935, 5607.920792079208, 5714.571407144439, 5765.827025189251, 5844.649321040752, 1977.6084015912636, 1990.532224927141, 1997.0913234478278, 2042.3158241988951, 2049.5183631846503, 26379.736202638112, 26492.867535662197, 26521.46957060848, 26558.078611352736, 26601.0, 6781.559198652072, 6958.721651133898, 7096.325849044332, 7280.599567023864, 7282.817929551804, 17042.31830726782, 17175.398861040052, 17181.484555463358, 17186.656266874594, 17216.397426090276, 8059.879101813477, 8426.368022398345, 8635.784105397415, 8648.913510864937, 8650.221480066699, 7615.390768738488, 7705.30652241301, 7711.26742959418, 7825.0218722659665, 7886.763397098096, 5414.431484694051, 5512.2558454608115, 5553.527950124204, 5589.524890384363, 5621.943780562224, 1569.7723830044579, 1597.5926138834523, 1604.486564299413, 1610.5973506623345, 1628.7377282606076, 878.5080355001091, 883.7878909061908, 897.4929165021872, 903.1375036839802, 912.9087091290951, 7416.295451932057, 7481.129717570608, 7494.063242094739, 7577.772666820004, 7587.0, 737.0933751485703, 737.9963100184572, 738.6233021159247, 742.2132539508139, 752.221450798421, 4991.775370108354, 5214.869628259324, 5289.153735402394, 5299.0, 5435.864103397367, 19846.0, 21014.369568912956, 21839.454013649785, 21868.89065554662, 22039.0, 19159.137838797007, 19690.916999564874, 19943.501412464804, 19995.700064499044, 20026.09621604859, 10883.401412922365, 10917.072048875776, 11074.335539867634, 11133.777324453466, 11304.321740695585, 7846.646900889587, 8268.9663792026, 8314.376421768513, 8440.535770532433, 8458.06961234276, 18295.90224586529, 18876.05619719036, 19202.8079719201, 19219.32732354361, 19225.807741922403, 19432.319868804523, 19581.608367832847, 19673.606527869648, 19966.90016549908, 19979.900100499985, 11141.610043648758, 11233.887661123612, 11271.774564508827, 11363.54545818158, 11367.488463019019, 13974.650633734238, 13983.510577129751, 13998.0, 14014.0, 14125.152490850582, 11566.710832229262, 11824.76350473003, 11948.223365481215, 12011.579594714143, 12153.513859445524, 11354.432278385882, 11508.482118304864, 11516.251443656136, 11516.654500365003, 11611.477483513094, 10199.490025498548, 10309.74225644365, 10318.122959548522, 10402.895971040192, 10403.84394234072, 6937.618430986344, 7082.150141982995, 7139.9286007141345, 7167.462440317101, 7232.240614735588, 6940.0, 7055.541389809646, 7058.894116588358, 7167.283271672791, 7173.856522869618], "value": 6699.009297762945, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.8952664703734, 521.5749164430989, 542.3871025740913, 545.6780499505292, 555.5056000159856, 6134.601250918688, 6209.627422354662, 6257.811015906986, 6282.994720844666, 6319.304876463585, 6080.969595152029, 6121.112438696387, 6131.693415329238, 6185.6597887116195, 6225.501959843213, 5123.103456895047, 5124.231365295208, 5192.273081768555, 5196.662216955905, 5207.0, 5007.84976450707, 5184.0, 5222.3472065991755, 5224.373075230958, 5235.214717792323, 5083.898322033557, 5096.949030509684, 5112.821051263207, 5123.820666276681, 5142.820001299955, 5091.1090559152, 5091.949080509185, 5092.79628814847, 5132.358455193101, 5137.7944882204665, 4674.298855171717, 4698.976505117453, 4719.480857105716, 4743.596794272491, 4752.4297084349755, 5972.522198224132, 5999.460048595647, 6027.427394397525, 6051.152838602599, 6065.969670151665, 5877.324107727623, 5878.119001919387, 5902.439268269508, 5902.734376953047, 5939.316978547479, 6337.524685648548, 6357.396047375492, 6438.903416448757, 6446.129772480702, 6476.4171224589545, 4893.779779909913, 5089.160288552384, 5136.614753893473, 5157.845264642029, 5172.844814655567, 6661.63361015144, 6675.766348177839, 6793.354631310018, 6818.795436136924, 6818.863622727567, 5169.638125331191, 5355.919661205085, 5393.649412788156, 5412.215228791803, 5415.729213539307, 5394.649347792381, 5468.562514998791, 5479.698616576126, 5501.37884177293, 5516.641418307797, 5523.696196709139, 5667.469783158606, 5670.603057785998, 5676.858078547987, 5707.286589176353, 6323.747050118036, 6468.482521398277, 6518.902216466757, 6526.706298216592, 6563.912967450169, 1700.14142857856, 1743.3810997095848, 1764.3207365164503, 1768.8142745011846, 1782.8395444410066, 1210.4915935307192, 1226.2642414551199, 1240.944157512914, 1248.556762349371, 1266.949322027127, 5586.190002449622, 5597.552195824324, 5657.2928383952, 5662.292213473316, 5687.383803450459, 6510.21877374718, 6553.705083271264, 6616.470682345401, 6679.198496180392, 6693.330666933268, 5504.422035686275, 5540.889182216414, 5543.722813859291, 5589.552835773128, 5617.157426386075, 3467.9769468007, 3481.808500532495, 3512.4204506256183, 3537.3986422307753, 3551.5205447264807, 6358.586691865014, 6471.0, 6494.882456420944, 6511.73953041873, 6541.28045914949, 6804.829879253059, 6855.4172895304455, 6906.516543841883, 6951.409130223887, 6969.372756451842, 6033.276006879115, 6436.7425302988295, 6453.870922581522, 6514.185726784152, 6525.64108974011, 6573.609640095076, 6662.300458451987, 6711.664416779044, 6728.831779205559, 6729.5625784323865, 6308.653024083719, 6441.581297215666, 6538.967305163444, 6587.275399705933, 6610.314369835661, 6239.812805615838, 6412.102305677302, 6428.164338635947, 6437.5171862111465, 6458.838529036624, 4959.156943319572, 4960.643694245349, 4974.353334066548, 4976.800927962986, 5046.268291097844, 6620.0, 6681.064650948774, 6718.462522998246, 6824.658767061528, 6825.488088393489, 6423.775167869102, 6443.67781610927, 6461.321561236003, 6489.5781774184525, 6513.772017979348, 6356.68216589178, 6432.324605916311, 6433.228012638513, 6458.0, 6489.5781774184525, 6373.5857169283845, 6449.355064493513, 6458.7093580789915, 6463.57986730861, 6517.576357536745, 6561.901571476336, 6587.894381854101, 6588.868222635616, 6614.305497922841, 6627.945336945562, 6291.779787707408, 6304.71628776697, 6311.936880631319, 6319.715612797343, 6326.367363263636], "value": 5085.0430255974525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [885.0574138542448, 903.7198468474769, 906.4651855405311, 912.867634193042, 916.9403988740734, 17335.826641733594, 17466.0, 17485.21316540754, 22388.55222895541, 24523.509529809395, 21586.56826863462, 22045.559088818212, 22376.552468950653, 22395.32814015582, 22420.66369004462, 976.2239019979098, 976.5605477535103, 977.5845265762023, 983.8081574093055, 984.6061575369838, 953.0707560128853, 962.3840741925138, 967.1585720423242, 977.2426369563574, 977.2963466304285, 942.7548837302317, 971.0726256425132, 1040.2614143957762, 1040.625374865048, 1042.93220940639, 1013.9758843567976, 1017.4963393120379, 1022.4989755020061, 1025.5180065369302, 1026.5996261458038, 747.8130467383154, 751.1587022534742, 752.6575408189261, 753.5026882257738, 754.4530215593705, 20186.798132018637, 21051.0, 21229.469263268387, 21257.149714011422, 21292.148314067417, 21983.34049978503, 22143.224987125534, 22387.32838014862, 22421.0, 22434.102635894546, 17772.377966771102, 17793.110344482724, 17803.287868485244, 17824.93050416979, 18002.729959050626, 3751.662350388478, 3788.3181027415053, 3830.042489377656, 3841.3277676406797, 3854.5567259765207, 141014.29492852572, 141081.0, 143310.0, 144624.0, 145057.0, 16969.915150424167, 17073.7438938416, 17354.219060142324, 17491.30034798619, 17527.824721752873, 31888.362232755215, 32361.676383235867, 32406.513902291485, 32442.0, 32593.348133037205, 8464.0, 8568.228859402683, 8584.91415085841, 8636.870446943301, 8677.132286771272, 3974.900627484336, 4059.959400405958, 4072.775997320176, 4077.8776636700945, 4078.7552746835286, 5431.619786614899, 5535.972320138373, 5542.473465020817, 5553.861153471195, 5613.0, 995.8207522645848, 1008.8890222075565, 1014.518103900656, 1021.2391768132788, 1026.9948650256702, 617.5368473644767, 632.6931438252444, 650.067153634536, 652.3802387731674, 654.1430725749284, 10919.0, 11244.606438774603, 11372.658820235407, 11464.452298939537, 11535.942320288512, 674.7874419557824, 681.1009467502848, 682.6723172876948, 689.7792706333925, 694.0664805836142, 5065.493450654905, 5069.163588008011, 5089.440161582223, 5205.375354957354, 5209.0, 122112.0, 123435.0, 124808.0, 129156.35421823019, 129768.0, 109932.4503377478, 110831.0, 112051.0, 112095.43952280187, 112109.87890120994, 97249.51375243078, 100689.0, 100952.49523752334, 101143.0, 101474.49262753491, 9507.286953478379, 9601.32790704644, 9643.469609171563, 9775.413475191512, 9869.0, 36288.0, 40754.38868416917, 42798.93002674958, 44245.778771105935, 44615.10769784651, 79142.0, 79693.0, 81227.0, 81731.0, 81829.0, 16243.837561624232, 16588.253528591486, 16691.0, 16762.910410823257, 16982.065986370373, 19408.320708775125, 19568.315108971117, 19578.216871325556, 19609.509762256057, 19946.20215191376, 17890.194941227415, 17952.192151353483, 17997.370092046716, 18176.636467270844, 18372.0, 18270.269189232815, 18290.908545457187, 18295.451136465395, 18344.816551834843, 18572.721409178605, 17209.56976075608, 17255.309787608356, 17371.131443428032, 17528.298868045134, 17826.37607683725, 13658.317084145952, 13721.245331506863, 13814.930925345308, 14173.716525669635, 14424.711505770036, 13079.0, 13368.264745439094, 13413.0, 13439.596812095653, 13573.321333933462], "value": 9249.285316421629, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 111.0, 108.0, 111.0, 112.0, 119.0, 108.0, 116.0, 115.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 119.0, 118.0, 116.0, 117.0, 117.0, 120.0, 121.0, 150.0, 117.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 170.0, 170.0, 164.0, 172.0, 164.0, 167.0, 162.0, 174.0, 168.0], "value": 167.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 76.0, 84.0, 80.0, 75.0, 76.0, 75.0, 82.0, 77.0], "value": 78.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 87.0, 85.0, 80.0, 83.0, 89.0, 80.0, 80.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 122.0, 115.0, 114.0, 116.0, 115.0, 115.0, 124.0, 112.0], "value": 116.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 152.0, 148.0, 154.0, 147.0, 148.0, 151.0, 148.0, 157.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 94.0, 94.0, 94.0, 101.0, 98.0, 96.0, 96.0, 99.0, 95.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 53.0, 58.0, 57.0, 54.0, 54.0, 57.0, 56.0, 51.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 40.0, 39.0, 45.0, 38.0, 38.0, 51.0, 49.0, 39.0, 39.0], "value": 41.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 74.0, 83.0, 81.0, 86.0, 72.0, 78.0, 75.0, 77.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 120.0, 120.0, 119.0, 118.0, 116.0, 114.0, 114.0, 115.0, 114.0], "value": 116.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 170.0, 169.0, 175.0, 173.0, 168.0, 177.0, 171.0, 174.0, 176.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 65.0, 68.0, 66.0, 67.0, 69.0, 66.0, 68.0, 64.0, 66.0], "value": 67.9, "unit": "score"}]}]} 09:46:04 INFO - Return code: 0 09:46:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:46:04 INFO - # TBPL SUCCESS # 09:46:04 INFO - Running post-action listener: _package_coverage_data 09:46:04 INFO - Running post-action listener: _resource_record_post_action 09:46:04 INFO - [mozharness: 2017-09-21 16:46:04.923269Z] Finished run-tests step (success) 09:46:04 INFO - Running post-run listener: _resource_record_post_run 09:46:04 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 425984; Write bytes: 344424448; Read time: 284; Write time: 240896 09:46:04 INFO - TinderboxPrint: CPU usage
13.3% 09:46:04 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 284 09:46:04 INFO - TinderboxPrint: I/O write bytes / time
344,424,448 / 240,896 09:46:04 INFO - TinderboxPrint: CPU idle
8,249.8 (86.7%) 09:46:04 INFO - TinderboxPrint: CPU user
1,202.4 (12.6%) 09:46:04 INFO - TinderboxPrint: Swap in / out
0 / 0 09:46:04 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10121216; Read time: 0; Write time: 1584 09:46:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:46:05 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 421888; Write bytes: 334303232; Read time: 272; Write time: 239312 09:46:05 INFO - Running post-run listener: _upload_blobber_files 09:46:05 INFO - Blob upload gear active. 09:46:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:46:05 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 09:46:05 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:46:05 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:46:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:46:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:46:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:46:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:07 INFO - (blobuploader) - INFO - Done attempting. 09:46:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:46:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:46:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:10 INFO - (blobuploader) - INFO - Done attempting. 09:46:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:46:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:46:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:11 INFO - (blobuploader) - INFO - Done attempting. 09:46:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:46:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:46:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:12 INFO - (blobuploader) - INFO - Done attempting. 09:46:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:46:12 INFO - Return code: 0 09:46:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:46:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:46:12 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80b32415b991a2085b80ba0c5b5b2ee51ff795ec71fd324faa9c61c0e3d2390db7a2a8e5330be6ab5b006381ce7061fadaa882277ee09aaf3c1760a776b2ae70", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d03f25508474f0f04259e459fcdf254eb90bd926e1dd0b8c2618e83306f82b93fd6640f248486fc140da0812150c28c30bace2c1ee35a5ba78fc832143b03a0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7133461d67da40e50aa6bd7f03471d603eeb452b8b56eca1f1e61728248240e4dd75accc0d5c3ad0395eec8df932dd0b530cc7fa5f905002a9c17a5549f56c75", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9aa63140fbc21e4ec517c2c7891c300b48303a6006c4da6577a6bfd4a799ab1672cefdece4accc99d992e24d2c3f71e7e1f27708a65c444b94dd8cf366fcd3ab"} 09:46:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:46:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:46:12 INFO - Contents: 09:46:12 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80b32415b991a2085b80ba0c5b5b2ee51ff795ec71fd324faa9c61c0e3d2390db7a2a8e5330be6ab5b006381ce7061fadaa882277ee09aaf3c1760a776b2ae70", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d03f25508474f0f04259e459fcdf254eb90bd926e1dd0b8c2618e83306f82b93fd6640f248486fc140da0812150c28c30bace2c1ee35a5ba78fc832143b03a0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7133461d67da40e50aa6bd7f03471d603eeb452b8b56eca1f1e61728248240e4dd75accc0d5c3ad0395eec8df932dd0b530cc7fa5f905002a9c17a5549f56c75", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9aa63140fbc21e4ec517c2c7891c300b48303a6006c4da6577a6bfd4a799ab1672cefdece4accc99d992e24d2c3f71e7e1f27708a65c444b94dd8cf366fcd3ab"} 09:46:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:46:12 INFO - Copying logs to upload dir... 09:46:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:46:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.679721 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-21 09:46:12.664862) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 09:46:12.668907) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80b32415b991a2085b80ba0c5b5b2ee51ff795ec71fd324faa9c61c0e3d2390db7a2a8e5330be6ab5b006381ce7061fadaa882277ee09aaf3c1760a776b2ae70", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d03f25508474f0f04259e459fcdf254eb90bd926e1dd0b8c2618e83306f82b93fd6640f248486fc140da0812150c28c30bace2c1ee35a5ba78fc832143b03a0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7133461d67da40e50aa6bd7f03471d603eeb452b8b56eca1f1e61728248240e4dd75accc0d5c3ad0395eec8df932dd0b530cc7fa5f905002a9c17a5549f56c75", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9aa63140fbc21e4ec517c2c7891c300b48303a6006c4da6577a6bfd4a799ab1672cefdece4accc99d992e24d2c3f71e7e1f27708a65c444b94dd8cf366fcd3ab"} build_url:https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011419 build_url: 'https://queue.taskcluster.net/v1/task/HBJ4E0WUSRGZbfHgz26VAA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80b32415b991a2085b80ba0c5b5b2ee51ff795ec71fd324faa9c61c0e3d2390db7a2a8e5330be6ab5b006381ce7061fadaa882277ee09aaf3c1760a776b2ae70", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d03f25508474f0f04259e459fcdf254eb90bd926e1dd0b8c2618e83306f82b93fd6640f248486fc140da0812150c28c30bace2c1ee35a5ba78fc832143b03a0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7133461d67da40e50aa6bd7f03471d603eeb452b8b56eca1f1e61728248240e4dd75accc0d5c3ad0395eec8df932dd0b530cc7fa5f905002a9c17a5549f56c75", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9aa63140fbc21e4ec517c2c7891c300b48303a6006c4da6577a6bfd4a799ab1672cefdece4accc99d992e24d2c3f71e7e1f27708a65c444b94dd8cf366fcd3ab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 09:46:12.697556) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:46:12.698248) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1506011128.844748-1493962881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:46:12.782738) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 09:46:12.783087) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 09:46:12.783434) ========= ========= Total master_lag: 0.20 =========