builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-030 starttime: 1505957276.22 results: success (0) revision: 61340c7debf6dccec3b863d1bc00426feac42fe7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:56.217286) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:56.217747) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:56.218061) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:56.236341) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:56.236727) ========= 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=3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 18:27:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 198K=0.06s 2017-09-20 18:27:56 (198 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.238816 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:56.493019) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:56.493377) ========= 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=3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015647 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:56.544328) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:56.544672) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 61340c7debf6dccec3b863d1bc00426feac42fe7 --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 61340c7debf6dccec3b863d1bc00426feac42fe7 --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=3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 18:27:56,596 truncating revision to first 12 chars 2017-09-20 18:27:56,596 Setting DEBUG logging. 2017-09-20 18:27:56,596 attempt 1/10 2017-09-20 18:27:56,596 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/61340c7debf6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 18:27:56,991 unpacking tar archive at: mozilla-central-61340c7debf6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.723112 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:57.284841) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:57.285159) ========= script_repo_revision: 61340c7debf6dccec3b863d1bc00426feac42fe7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:57.285552) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:57.285852) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:27:57.302419) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-20 18:27:57.302840) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:27:57 INFO - MultiFileLogger online at 20170920 18:27:57 in /builds/slave/test 18:27:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:27:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:27:57 INFO - {'append_to_log': False, 18:27:57 INFO - 'base_work_dir': '/builds/slave/test', 18:27:57 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:27:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:27:57 INFO - 'branch': 'Firefox-Non-PGO', 18:27:57 INFO - 'buildbot_json_path': 'buildprops.json', 18:27:57 INFO - 'code_coverage': False, 18:27:57 INFO - 'config_files': ('talos/linux_config.py',), 18:27:57 INFO - 'default_actions': ('clobber', 18:27:57 INFO - 'read-buildbot-config', 18:27:57 INFO - 'download-and-extract', 18:27:57 INFO - 'populate-webroot', 18:27:57 INFO - 'create-virtualenv', 18:27:57 INFO - 'install', 18:27:57 INFO - 'setup-mitmproxy', 18:27:57 INFO - 'run-tests'), 18:27:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:27:57 INFO - 'disable_ccov_upload': False, 18:27:57 INFO - 'disable_stylo': False, 18:27:57 INFO - 'download_minidump_stackwalk': True, 18:27:57 INFO - 'download_symbols': 'ondemand', 18:27:57 INFO - 'enable_stylo': False, 18:27:57 INFO - 'enable_webrender': False, 18:27:57 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:27:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:27:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:27:57 INFO - 'gecko_profile': False, 18:27:57 INFO - 'gecko_profile_interval': 0, 18:27:57 INFO - 'installer_path': 'installer.exe', 18:27:57 INFO - 'log_level': 'info', 18:27:57 INFO - 'log_name': 'talos', 18:27:57 INFO - 'log_to_console': True, 18:27:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:27:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:27:57 INFO - 'opt_config_files': (), 18:27:57 INFO - 'pip_index': False, 18:27:57 INFO - 'suite': 'dromaeojs-e10s', 18:27:57 INFO - 'system_bits': '32', 18:27:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:27:57 INFO - 'title': 'talos-linux64-ix-030', 18:27:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:27:57 INFO - 'use_talos_json': True, 18:27:57 INFO - 'verify': 'False', 18:27:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:27:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:27:57 INFO - 'work_dir': 'build'} 18:27:57 INFO - [mozharness: 2017-09-21 01:27:57.493510Z] Running clobber step. 18:27:57 INFO - Running pre-action listener: _resource_record_pre_action 18:27:57 INFO - Running main action method: clobber 18:27:57 INFO - rmtree: /builds/slave/test/build 18:27:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:27:58 INFO - Running post-action listener: _resource_record_post_action 18:27:58 INFO - [mozharness: 2017-09-21 01:27:58.576975Z] Finished clobber step (success) 18:27:58 INFO - [mozharness: 2017-09-21 01:27:58.577072Z] Running read-buildbot-config step. 18:27:58 INFO - Running pre-action listener: _resource_record_pre_action 18:27:58 INFO - Running main action method: read_buildbot_config 18:27:58 INFO - Using buildbot properties: 18:27:58 INFO - { 18:27:58 INFO - "project": "", 18:27:58 INFO - "product": "firefox", 18:27:58 INFO - "who": "kwierso@gmail.com", 18:27:58 INFO - "installer_path": "public/build/target.tar.bz2", 18:27:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:27:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:27:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:27:58 INFO - "stage_platform": "linux64", 18:27:58 INFO - "basedir": "/builds/slave/test", 18:27:58 INFO - "buildnumber": 1167, 18:27:58 INFO - "platform": "ubuntu64_hw", 18:27:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:27:58 INFO - "slavebuilddir": "test", 18:27:58 INFO - "taskId": "OCx0XrDZTc-jp0Xi0Xq1Cg", 18:27:58 INFO - "branch": "mozilla-central", 18:27:58 INFO - "script_repo_revision": "production", 18:27:58 INFO - "revision": "61340c7debf6dccec3b863d1bc00426feac42fe7", 18:27:58 INFO - "slavename": "talos-linux64-ix-030", 18:27:58 INFO - "repo_path": "mozilla-central" 18:27:58 INFO - } 18:27:58 INFO - Finding installer, test and symbols from parent task. 18:27:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OCx0XrDZTc-jp0Xi0Xq1Cg'}, attempt #1 18:27:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OCx0XrDZTc-jp0Xi0Xq1Cg'}, attempt #1 18:27:59 INFO - Task dependencies: YpEoj-OJRvCSQ0IhvHvM7g 18:27:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g'}, attempt #1 18:27:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 18:27:59 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.test_packages.json 18:27:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.crashreporter-symbols.zip 18:27:59 INFO - Running post-action listener: _resource_record_post_action 18:27:59 INFO - [mozharness: 2017-09-21 01:27:59.884273Z] Finished read-buildbot-config step (success) 18:27:59 INFO - [mozharness: 2017-09-21 01:27:59.884489Z] Running download-and-extract step. 18:27:59 INFO - Running pre-action listener: _resource_record_pre_action 18:27:59 INFO - Running main action method: download_and_extract 18:27:59 INFO - mkdir: /builds/slave/test/build/tests 18:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:59 INFO - trying https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.test_packages.json 18:27:59 INFO - Downloading https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:27:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:28:00 INFO - Downloaded 989 bytes. 18:28:00 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:28:00 INFO - Using the following test package requirements: 18:28:00 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:28:00 INFO - u'common': [u'target.common.tests.zip'], 18:28:00 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:28:00 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:28:00 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:28:00 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:28:00 INFO - u'mozbase': [u'target.common.tests.zip'], 18:28:00 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:28:00 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:28:00 INFO - u'web-platform': [u'target.common.tests.zip', 18:28:00 INFO - u'target.web-platform.tests.tar.gz'], 18:28:00 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:28:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:28:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.common.tests.zip 18:28:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:28:00 INFO - Fetch https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.common.tests.zip into memory 18:28:01 INFO - Content-Length response header: 54733806 18:28:01 INFO - Bytes received: 54733806 18:28:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.talos.tests.zip 18:28:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:28:12 INFO - Fetch https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.talos.tests.zip into memory 18:28:12 INFO - Content-Length response header: 14512509 18:28:12 INFO - Bytes received: 14512509 18:28:13 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')]} 18:28:13 INFO - trying https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 18:28:13 INFO - Downloading https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:28:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:28:14 INFO - Downloaded 61663650 bytes. 18:28:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 18:28:14 INFO - mkdir: /builds/slave/test/properties 18:28:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:28:14 INFO - Writing to file /builds/slave/test/properties/build_url 18:28:14 INFO - Contents: 18:28:14 INFO - build_url:https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 18:28:14 INFO - Running post-action listener: _resource_record_post_action 18:28:14 INFO - Running post-action listener: find_tests_for_verification 18:28:14 INFO - Running post-action listener: set_extra_try_arguments 18:28:14 INFO - [mozharness: 2017-09-21 01:28:14.385264Z] Finished download-and-extract step (success) 18:28:14 INFO - [mozharness: 2017-09-21 01:28:14.385359Z] Running populate-webroot step. 18:28:14 INFO - Running pre-action listener: _resource_record_pre_action 18:28:14 INFO - Running main action method: populate_webroot 18:28:14 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:28:14 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['tresize', 18:28:14 INFO - 'tcanvasmark']}, 18:28:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:28:14 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['dromaeo_css', 18:28:14 INFO - 'kraken']}, 18:28:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:28:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:28:14 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:28:14 INFO - 'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:28:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:28:14 INFO - 'tests': ['damp', 'tps']}, 18:28:14 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:28:14 INFO - 'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['damp', 'tps']}, 18:28:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:28:14 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['dromaeo_dom']}, 18:28:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:28:14 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['basic_compositor_video', 18:28:14 INFO - 'glvideo']}, 18:28:14 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:28:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:28:14 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:28:14 INFO - 'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['ts_paint_webext', 18:28:14 INFO - 'tp5o_webext']}, 18:28:14 INFO - 'other-e10s': {'tests': ['a11yr', 18:28:14 INFO - 'ts_paint', 18:28:14 INFO - 'tpaint', 18:28:14 INFO - 'sessionrestore', 18:28:14 INFO - 'sessionrestore_many_windows', 18:28:14 INFO - 'sessionrestore_no_auto_restore', 18:28:14 INFO - 'tabpaint']}, 18:28:14 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['a11yr', 18:28:14 INFO - 'ts_paint', 18:28:14 INFO - 'tpaint', 18:28:14 INFO - 'sessionrestore', 18:28:14 INFO - 'sessionrestore_many_windows', 18:28:14 INFO - 'sessionrestore_no_auto_restore', 18:28:14 INFO - 'tabpaint']}, 18:28:14 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:28:14 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:28:14 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['perf_reftest_singletons']}, 18:28:14 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['bloom_basic']}, 18:28:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:28:14 INFO - 'tsvgr_opacity', 18:28:14 INFO - 'tart', 18:28:14 INFO - 'tscrollx', 18:28:14 INFO - 'tsvg_static']}, 18:28:14 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['tsvgx', 18:28:14 INFO - 'tsvgr_opacity', 18:28:14 INFO - 'tart', 18:28:14 INFO - 'tscrollx', 18:28:14 INFO - 'tsvg_static']}, 18:28:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:28:14 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:28:14 INFO - 'talos_options': ['--disable-stylo'], 18:28:14 INFO - 'tests': ['tp5o']}, 18:28:14 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:28:14 INFO - 'talos_options': ['--mitmproxy', 18:28:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:28:14 INFO - '--firstNonBlankPaint'], 18:28:14 INFO - 'tests': ['tp6_google', 18:28:14 INFO - 'tp6_youtube', 18:28:14 INFO - 'tp6_amazon', 18:28:14 INFO - 'tp6_facebook']}, 18:28:14 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:28:14 INFO - 'talos_options': ['--disable-stylo', 18:28:14 INFO - '--mitmproxy', 18:28:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:28:14 INFO - '--firstNonBlankPaint'], 18:28:14 INFO - 'tests': ['tp6_google', 18:28:14 INFO - 'tp6_youtube', 18:28:14 INFO - 'tp6_amazon', 18:28:14 INFO - 'tp6_facebook']}, 18:28:14 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:28:14 INFO - 'talos_options': ['--stylo-threads=1', 18:28:14 INFO - '--mitmproxy', 18:28:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:28:14 INFO - '--firstNonBlankPaint'], 18:28:14 INFO - 'tests': ['tp6_google', 18:28:14 INFO - 'tp6_youtube', 18:28:14 INFO - 'tp6_amazon', 18:28:14 INFO - 'tp6_facebook']}, 18:28:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:28:14 INFO - 'talos_options': ['--xperf_path', 18:28:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:28:14 INFO - 'tests': ['tp5n']}, 18:28:14 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:28:14 INFO - 'talos_options': ['--disable-stylo', 18:28:14 INFO - '--xperf_path', 18:28:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:28:14 INFO - 'tests': ['tp5n']}}} 18:28:14 INFO - Running post-action listener: _resource_record_post_action 18:28:14 INFO - [mozharness: 2017-09-21 01:28:14.400212Z] Finished populate-webroot step (success) 18:28:14 INFO - [mozharness: 2017-09-21 01:28:14.400301Z] Running create-virtualenv step. 18:28:14 INFO - Running pre-action listener: _resource_record_pre_action 18:28:14 INFO - Running main action method: create_virtualenv 18:28:14 INFO - Creating virtualenv /builds/slave/test/build/venv 18:28:14 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:28:14 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:28:14 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:28:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:28:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:28:15 INFO - Installing distribute.............................................................................................................................................................................................done. 18:28:15 INFO - Installing pip...............done. 18:28:15 INFO - Return code: 0 18:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:28:15 INFO - Reading from file tmpfile_stdout 18:28:15 INFO - Output received: 18:28:15 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:28:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:15 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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:15 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 18:28:15 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 18:28:15 INFO - Using env: {'DISPLAY': ':0', 18:28:15 INFO - 'HOME': '/home/cltbld', 18:28:15 INFO - 'LANG': 'en_US.UTF-8', 18:28:15 INFO - 'LANGUAGE': 'en_US:en', 18:28:15 INFO - 'LOGNAME': 'cltbld', 18:28:15 INFO - 'MAIL': '/var/mail/cltbld', 18:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:15 INFO - 'MOZ_NO_REMOTE': '1', 18:28:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:15 INFO - 'NO_EM_RESTART': '1', 18:28:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:15 INFO - 'PWD': '/builds/slave/test', 18:28:15 INFO - 'SHELL': '/bin/bash', 18:28:15 INFO - 'SHLVL': '1', 18:28:15 INFO - 'TERM': 'linux', 18:28:15 INFO - 'TMOUT': '86400', 18:28:15 INFO - 'USER': 'cltbld', 18:28:15 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:15 INFO - '_': '/tools/buildbot/bin/python'} 18:28:17 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:28:17 INFO - Downloading/unpacking pip>=1.5 18:28:17 INFO - Running setup.py egg_info for package pip 18:28:17 INFO - warning: no files found matching 'pip/cacert.pem' 18:28:17 INFO - warning: no files found matching '*.html' under directory 'docs' 18:28:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:28:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:28:17 INFO - Installing collected packages: pip 18:28:17 INFO - Found existing installation: pip 1.1 18:28:17 INFO - Uninstalling pip: 18:28:17 INFO - Successfully uninstalled pip 18:28:17 INFO - Running setup.py install for pip 18:28:17 INFO - warning: no files found matching 'pip/cacert.pem' 18:28:17 INFO - warning: no files found matching '*.html' under directory 'docs' 18:28:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:28:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:28:17 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Successfully installed pip 18:28:17 INFO - Cleaning up... 18:28:17 INFO - Return code: 0 18:28:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:28:17 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')]} 18:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:17 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')]} 18:28:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:17 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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:17 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 18:28:17 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 18:28:17 INFO - Using env: {'DISPLAY': ':0', 18:28:17 INFO - 'HOME': '/home/cltbld', 18:28:17 INFO - 'LANG': 'en_US.UTF-8', 18:28:17 INFO - 'LANGUAGE': 'en_US:en', 18:28:17 INFO - 'LOGNAME': 'cltbld', 18:28:17 INFO - 'MAIL': '/var/mail/cltbld', 18:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:17 INFO - 'MOZ_NO_REMOTE': '1', 18:28:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:17 INFO - 'NO_EM_RESTART': '1', 18:28:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:17 INFO - 'PWD': '/builds/slave/test', 18:28:17 INFO - 'SHELL': '/bin/bash', 18:28:17 INFO - 'SHLVL': '1', 18:28:17 INFO - 'TERM': 'linux', 18:28:17 INFO - 'TMOUT': '86400', 18:28:17 INFO - 'USER': 'cltbld', 18:28:17 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:17 INFO - '_': '/tools/buildbot/bin/python'} 18:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:17 INFO - Downloading/unpacking psutil>=3.1.1 18:28:17 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 18:28:17 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 18:28:17 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 18:28:17 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 18:28:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:28:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:28:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:28:19 INFO - Installing collected packages: psutil 18:28:19 INFO - Running setup.py install for psutil 18:28:19 INFO - building 'psutil._psutil_linux' extension 18:28:19 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 18:28:19 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 18:28:19 INFO - building 'psutil._psutil_posix' extension 18:28:19 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 18:28:19 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 18:28:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:28:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:28:19 INFO - Successfully installed psutil 18:28:19 INFO - Cleaning up... 18:28:19 INFO - Return code: 0 18:28:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:28:19 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')]} 18:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:19 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')]} 18:28:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:19 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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:19 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 18:28:19 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 18:28:19 INFO - Using env: {'DISPLAY': ':0', 18:28:19 INFO - 'HOME': '/home/cltbld', 18:28:19 INFO - 'LANG': 'en_US.UTF-8', 18:28:19 INFO - 'LANGUAGE': 'en_US:en', 18:28:19 INFO - 'LOGNAME': 'cltbld', 18:28:19 INFO - 'MAIL': '/var/mail/cltbld', 18:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:19 INFO - 'MOZ_NO_REMOTE': '1', 18:28:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:19 INFO - 'NO_EM_RESTART': '1', 18:28:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:19 INFO - 'PWD': '/builds/slave/test', 18:28:19 INFO - 'SHELL': '/bin/bash', 18:28:19 INFO - 'SHLVL': '1', 18:28:19 INFO - 'TERM': 'linux', 18:28:19 INFO - 'TMOUT': '86400', 18:28:19 INFO - 'USER': 'cltbld', 18:28:19 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:19 INFO - '_': '/tools/buildbot/bin/python'} 18:28:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:28:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:28:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:28:21 INFO - Installing collected packages: mozsystemmonitor 18:28:21 INFO - Running setup.py install for mozsystemmonitor 18:28:21 INFO - Successfully installed mozsystemmonitor 18:28:21 INFO - Cleaning up... 18:28:21 INFO - Return code: 0 18:28:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:28:21 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')]} 18:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:21 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')]} 18:28:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:21 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 18:28:21 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 18:28:21 INFO - Using env: {'DISPLAY': ':0', 18:28:21 INFO - 'HOME': '/home/cltbld', 18:28:21 INFO - 'LANG': 'en_US.UTF-8', 18:28:21 INFO - 'LANGUAGE': 'en_US:en', 18:28:21 INFO - 'LOGNAME': 'cltbld', 18:28:21 INFO - 'MAIL': '/var/mail/cltbld', 18:28:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:21 INFO - 'MOZ_NO_REMOTE': '1', 18:28:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:21 INFO - 'NO_EM_RESTART': '1', 18:28:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:21 INFO - 'PWD': '/builds/slave/test', 18:28:21 INFO - 'SHELL': '/bin/bash', 18:28:21 INFO - 'SHLVL': '1', 18:28:21 INFO - 'TERM': 'linux', 18:28:21 INFO - 'TMOUT': '86400', 18:28:21 INFO - 'USER': 'cltbld', 18:28:21 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:21 INFO - '_': '/tools/buildbot/bin/python'} 18:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:22 INFO - Downloading/unpacking jsonschema==2.5.1 18:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:28:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:23 INFO - Downloading functools32-3.2.3-2.tar.gz 18:28:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:28:24 INFO - warning: no files found matching '*.txt' 18:28:24 INFO - no previously-included directories found matching 'build' 18:28:24 INFO - no previously-included directories found matching 'dist' 18:28:24 INFO - no previously-included directories found matching '.git*' 18:28:24 INFO - Installing collected packages: jsonschema, functools32 18:28:24 INFO - Running setup.py install for functools32 18:28:24 INFO - warning: no files found matching '*.txt' 18:28:24 INFO - no previously-included directories found matching 'build' 18:28:24 INFO - no previously-included directories found matching 'dist' 18:28:24 INFO - no previously-included directories found matching '.git*' 18:28:24 INFO - Successfully installed jsonschema functools32 18:28:24 INFO - Cleaning up... 18:28:24 INFO - Return code: 0 18:28:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:28:24 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')]} 18:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:24 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')]} 18:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:24 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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:24 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 18:28:24 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 18:28:24 INFO - Using env: {'DISPLAY': ':0', 18:28:24 INFO - 'HOME': '/home/cltbld', 18:28:24 INFO - 'LANG': 'en_US.UTF-8', 18:28:24 INFO - 'LANGUAGE': 'en_US:en', 18:28:24 INFO - 'LOGNAME': 'cltbld', 18:28:24 INFO - 'MAIL': '/var/mail/cltbld', 18:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:24 INFO - 'MOZ_NO_REMOTE': '1', 18:28:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:24 INFO - 'NO_EM_RESTART': '1', 18:28:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:24 INFO - 'PWD': '/builds/slave/test', 18:28:24 INFO - 'SHELL': '/bin/bash', 18:28:24 INFO - 'SHLVL': '1', 18:28:24 INFO - 'TERM': 'linux', 18:28:24 INFO - 'TMOUT': '86400', 18:28:24 INFO - 'USER': 'cltbld', 18:28:24 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:24 INFO - '_': '/tools/buildbot/bin/python'} 18:28:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:28:24 INFO - Cleaning up... 18:28:24 INFO - Return code: 0 18:28:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:28:24 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')]} 18:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:24 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')]} 18:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:24 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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:24 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 18:28:24 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 18:28:24 INFO - Using env: {'DISPLAY': ':0', 18:28:24 INFO - 'HOME': '/home/cltbld', 18:28:24 INFO - 'LANG': 'en_US.UTF-8', 18:28:24 INFO - 'LANGUAGE': 'en_US:en', 18:28:24 INFO - 'LOGNAME': 'cltbld', 18:28:24 INFO - 'MAIL': '/var/mail/cltbld', 18:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:24 INFO - 'MOZ_NO_REMOTE': '1', 18:28:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:24 INFO - 'NO_EM_RESTART': '1', 18:28:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:24 INFO - 'PWD': '/builds/slave/test', 18:28:24 INFO - 'SHELL': '/bin/bash', 18:28:24 INFO - 'SHLVL': '1', 18:28:24 INFO - 'TERM': 'linux', 18:28:24 INFO - 'TMOUT': '86400', 18:28:24 INFO - 'USER': 'cltbld', 18:28:24 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:24 INFO - '_': '/tools/buildbot/bin/python'} 18:28:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:24 INFO - Downloading/unpacking blobuploader==1.2.4 18:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:25 INFO - Downloading blobuploader-1.2.4.tar.gz 18:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:28:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:28:25 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 18:28:25 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 18:28:25 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 18:28:25 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 18:28:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:28:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:26 INFO - Downloading docopt-0.6.1.tar.gz 18:28:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:28:26 INFO - Installing collected packages: blobuploader, requests, docopt 18:28:26 INFO - Running setup.py install for blobuploader 18:28:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:28:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:28:26 INFO - Running setup.py install for requests 18:28:26 INFO - Running setup.py install for docopt 18:28:26 INFO - Successfully installed blobuploader requests docopt 18:28:26 INFO - Cleaning up... 18:28:26 INFO - Return code: 0 18:28:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:26 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')]} 18:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:26 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')]} 18:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:26 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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:26 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 18:28:26 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 18:28:26 INFO - Using env: {'DISPLAY': ':0', 18:28:26 INFO - 'HOME': '/home/cltbld', 18:28:26 INFO - 'LANG': 'en_US.UTF-8', 18:28:26 INFO - 'LANGUAGE': 'en_US:en', 18:28:26 INFO - 'LOGNAME': 'cltbld', 18:28:26 INFO - 'MAIL': '/var/mail/cltbld', 18:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:26 INFO - 'MOZ_NO_REMOTE': '1', 18:28:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:26 INFO - 'NO_EM_RESTART': '1', 18:28:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:26 INFO - 'PWD': '/builds/slave/test', 18:28:26 INFO - 'SHELL': '/bin/bash', 18:28:26 INFO - 'SHLVL': '1', 18:28:26 INFO - 'TERM': 'linux', 18:28:26 INFO - 'TMOUT': '86400', 18:28:26 INFO - 'USER': 'cltbld', 18:28:26 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:26 INFO - '_': '/tools/buildbot/bin/python'} 18:28:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:28:27 INFO - Running setup.py (path:/tmp/pip-eaGiJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:28:27 INFO - Running setup.py (path:/tmp/pip-viL_jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:28:27 INFO - Running setup.py (path:/tmp/pip-xaENYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:28:27 INFO - Running setup.py (path:/tmp/pip-xQqCPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:28:27 INFO - Running setup.py (path:/tmp/pip-Cw4L6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:28:27 INFO - Running setup.py (path:/tmp/pip-3xBfq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:28:27 INFO - Running setup.py (path:/tmp/pip-0hYgtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:28:27 INFO - Running setup.py (path:/tmp/pip-PZWvdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:28:27 INFO - Running setup.py (path:/tmp/pip-bMT8co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:28:27 INFO - Running setup.py (path:/tmp/pip-CWn9ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:28:27 INFO - Running setup.py (path:/tmp/pip-_RJvqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:28:27 INFO - Running setup.py (path:/tmp/pip-KOzQUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:28:28 INFO - Running setup.py (path:/tmp/pip-8I00zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:28:28 INFO - Running setup.py (path:/tmp/pip-mZ2Afe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:28 INFO - Running setup.py (path:/tmp/pip-k_AkcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:28:28 INFO - Running setup.py (path:/tmp/pip-wakXbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:28:28 INFO - Running setup.py (path:/tmp/pip-SolpwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:28:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:28:28 INFO - Running setup.py install for manifestparser 18:28:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:28:28 INFO - Running setup.py install for mozcrash 18:28:28 INFO - Running setup.py install for mozdebug 18:28:28 INFO - Running setup.py install for mozdevice 18:28:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:28:28 INFO - Running setup.py install for mozfile 18:28:28 INFO - Running setup.py install for mozhttpd 18:28:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:28:28 INFO - Running setup.py install for mozinfo 18:28:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Running setup.py install for mozInstall 18:28:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Running setup.py install for mozleak 18:28:29 INFO - Running setup.py install for mozlog 18:28:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Running setup.py install for moznetwork 18:28:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Running setup.py install for mozprocess 18:28:29 INFO - Running setup.py install for mozprofile 18:28:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Running setup.py install for mozrunner 18:28:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:28:29 INFO - Running setup.py install for mozscreenshot 18:28:29 INFO - Running setup.py install for moztest 18:28:30 INFO - Running setup.py install for mozversion 18:28:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:28:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:28:30 INFO - Cleaning up... 18:28:30 INFO - Return code: 0 18:28:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:30 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')]} 18:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:30 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')]} 18:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:30 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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:30 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 18:28:30 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 18:28:30 INFO - Using env: {'DISPLAY': ':0', 18:28:30 INFO - 'HOME': '/home/cltbld', 18:28:30 INFO - 'LANG': 'en_US.UTF-8', 18:28:30 INFO - 'LANGUAGE': 'en_US:en', 18:28:30 INFO - 'LOGNAME': 'cltbld', 18:28:30 INFO - 'MAIL': '/var/mail/cltbld', 18:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:30 INFO - 'MOZ_NO_REMOTE': '1', 18:28:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:30 INFO - 'NO_EM_RESTART': '1', 18:28:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:30 INFO - 'PWD': '/builds/slave/test', 18:28:30 INFO - 'SHELL': '/bin/bash', 18:28:30 INFO - 'SHLVL': '1', 18:28:30 INFO - 'TERM': 'linux', 18:28:30 INFO - 'TMOUT': '86400', 18:28:30 INFO - 'USER': 'cltbld', 18:28:30 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:30 INFO - '_': '/tools/buildbot/bin/python'} 18:28:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:28:30 INFO - Running setup.py (path:/tmp/pip-Sjzlq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:28:30 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)) 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:28:30 INFO - Running setup.py (path:/tmp/pip-bDD5Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:28:30 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)) 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:28:30 INFO - Running setup.py (path:/tmp/pip-uGUVRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:28:30 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)) 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:28:30 INFO - Running setup.py (path:/tmp/pip-cXGKmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:28:30 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)) 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:28:30 INFO - Running setup.py (path:/tmp/pip-909gxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:28:30 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)) 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:28:30 INFO - Running setup.py (path:/tmp/pip-5iNqxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:28:30 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)) 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:28:30 INFO - Running setup.py (path:/tmp/pip-4hdohV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:28:30 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)) 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:28:30 INFO - Running setup.py (path:/tmp/pip-GQfPTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:28:30 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)) 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:28:30 INFO - Running setup.py (path:/tmp/pip-d75iwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:28:30 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)) 18:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:28:31 INFO - Running setup.py (path:/tmp/pip-5gbsLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:28:31 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)) 18:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:28:31 INFO - Running setup.py (path:/tmp/pip-S90piF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:28:31 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)) 18:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:28:31 INFO - Running setup.py (path:/tmp/pip-Czckp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:28:31 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)) 18:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:28:31 INFO - Running setup.py (path:/tmp/pip-tkrLqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:28:31 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)) 18:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:28:31 INFO - Running setup.py (path:/tmp/pip-wdKQ5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:28:31 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)) 18:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:31 INFO - Running setup.py (path:/tmp/pip-zTRsLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:31 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)) 18:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:28:31 INFO - Running setup.py (path:/tmp/pip-2zDjHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:28:31 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)) 18:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:28:31 INFO - Running setup.py (path:/tmp/pip-FLL8_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:28:31 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)) 18:28:31 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)) 18:28:31 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)) 18:28:31 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)) 18:28:31 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)) 18:28:31 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)) 18:28:31 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)) 18:28:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:32 INFO - Downloading blessings-1.6.tar.gz 18:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:28:32 INFO - Installing collected packages: blessings 18:28:32 INFO - Running setup.py install for blessings 18:28:32 INFO - Successfully installed blessings 18:28:32 INFO - Cleaning up... 18:28:32 INFO - Return code: 0 18:28:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:28:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:33 INFO - Reading from file tmpfile_stdout 18:28:33 INFO - Current package versions: 18:28:33 INFO - argparse == 1.2.1 18:28:33 INFO - blessings == 1.6 18:28:33 INFO - blobuploader == 1.2.4 18:28:33 INFO - docopt == 0.6.1 18:28:33 INFO - functools32 == 3.2.3-2 18:28:33 INFO - jsonschema == 2.5.1 18:28:33 INFO - manifestparser == 1.1 18:28:33 INFO - mozInstall == 1.13 18:28:33 INFO - mozcrash == 1.0 18:28:33 INFO - mozdebug == 0.1 18:28:33 INFO - mozdevice == 0.51 18:28:33 INFO - mozfile == 1.2 18:28:33 INFO - mozhttpd == 0.7 18:28:33 INFO - mozinfo == 0.10 18:28:33 INFO - mozleak == 0.1 18:28:33 INFO - mozlog == 3.5 18:28:33 INFO - moznetwork == 0.27 18:28:33 INFO - mozprocess == 0.25 18:28:33 INFO - mozprofile == 0.28 18:28:33 INFO - mozrunner == 6.13 18:28:33 INFO - mozscreenshot == 0.1 18:28:33 INFO - mozsystemmonitor == 0.3 18:28:33 INFO - moztest == 0.8 18:28:33 INFO - mozversion == 1.4 18:28:33 INFO - psutil == 3.1.1 18:28:33 INFO - requests == 1.2.3 18:28:33 INFO - wsgiref == 0.1.2 18:28:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:33 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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:33 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 18:28:33 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 18:28:33 INFO - Using env: {'DISPLAY': ':0', 18:28:33 INFO - 'HOME': '/home/cltbld', 18:28:33 INFO - 'LANG': 'en_US.UTF-8', 18:28:33 INFO - 'LANGUAGE': 'en_US:en', 18:28:33 INFO - 'LOGNAME': 'cltbld', 18:28:33 INFO - 'MAIL': '/var/mail/cltbld', 18:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:33 INFO - 'MOZ_NO_REMOTE': '1', 18:28:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:33 INFO - 'NO_EM_RESTART': '1', 18:28:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:33 INFO - 'PWD': '/builds/slave/test', 18:28:33 INFO - 'SHELL': '/bin/bash', 18:28:33 INFO - 'SHLVL': '1', 18:28:33 INFO - 'TERM': 'linux', 18:28:33 INFO - 'TMOUT': '86400', 18:28:33 INFO - 'USER': 'cltbld', 18:28:33 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:33 INFO - '_': '/tools/buildbot/bin/python'} 18:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:28:33 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:28:33 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 18:28:33 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 18:28:33 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 18:28:33 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 18:28:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:28:34 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)) 18:28:34 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)) 18:28:34 INFO - Installing collected packages: simplejson 18:28:34 INFO - Running setup.py install for simplejson 18:28:34 INFO - building 'simplejson._speedups' extension 18:28:34 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 18:28:35 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 18:28:35 INFO - Successfully installed simplejson 18:28:35 INFO - Cleaning up... 18:28:35 INFO - Return code: 0 18:28:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:28: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')]} 18:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28: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')]} 18:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:35 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 0xfb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, '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': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', '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 18:28:35 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 18:28:35 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 18:28:35 INFO - Using env: {'DISPLAY': ':0', 18:28:35 INFO - 'HOME': '/home/cltbld', 18:28:35 INFO - 'LANG': 'en_US.UTF-8', 18:28:35 INFO - 'LANGUAGE': 'en_US:en', 18:28:35 INFO - 'LOGNAME': 'cltbld', 18:28:35 INFO - 'MAIL': '/var/mail/cltbld', 18:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:35 INFO - 'MOZ_NO_REMOTE': '1', 18:28:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:35 INFO - 'NO_EM_RESTART': '1', 18:28:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:35 INFO - 'PWD': '/builds/slave/test', 18:28:35 INFO - 'SHELL': '/bin/bash', 18:28:35 INFO - 'SHLVL': '1', 18:28:35 INFO - 'TERM': 'linux', 18:28:35 INFO - 'TMOUT': '86400', 18:28:35 INFO - 'USER': 'cltbld', 18:28:35 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:35 INFO - '_': '/tools/buildbot/bin/python'} 18:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:28:35 INFO - Cleaning up... 18:28:35 INFO - Return code: 0 18:28:35 INFO - Running post-action listener: _resource_record_post_action 18:28:35 INFO - Running post-action listener: _start_resource_monitoring 18:28:35 INFO - Starting resource monitoring. 18:28:35 INFO - [mozharness: 2017-09-21 01:28:35.899433Z] Finished create-virtualenv step (success) 18:28:35 INFO - [mozharness: 2017-09-21 01:28:35.899945Z] Running install step. 18:28:35 INFO - Running pre-action listener: _resource_record_pre_action 18:28:35 INFO - Running main action method: install 18:28:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:36 INFO - Reading from file tmpfile_stdout 18:28:36 INFO - Detecting whether we're running mozinstall >=1.0... 18:28:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:28:36 INFO - Reading from file tmpfile_stdout 18:28:36 INFO - Output received: 18:28:36 INFO - Usage: mozinstall [options] installer 18:28:36 INFO - Options: 18:28:36 INFO - -h, --help show this help message and exit 18:28:36 INFO - -d DEST, --destination=DEST 18:28:36 INFO - Directory to install application into. [default: 18:28:36 INFO - "/builds/slave/test"] 18:28:36 INFO - --app=APP Application being installed. [default: firefox] 18:28:36 INFO - mkdir: /builds/slave/test/build/application 18:28:36 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'] 18:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:28:54 INFO - Reading from file tmpfile_stdout 18:28:54 INFO - Output received: 18:28:54 INFO - /builds/slave/test/build/application/firefox/firefox 18:28:54 INFO - Running post-action listener: _resource_record_post_action 18:28:54 INFO - [mozharness: 2017-09-21 01:28:54.045828Z] Finished install step (success) 18:28:54 INFO - [mozharness: 2017-09-21 01:28:54.046077Z] Running setup-mitmproxy step. 18:28:54 INFO - Running pre-action listener: _resource_record_pre_action 18:28:54 INFO - Running main action method: setup_mitmproxy 18:28:54 INFO - Skipping: mitmproxy is not required 18:28:54 INFO - Running post-action listener: _resource_record_post_action 18:28:54 INFO - [mozharness: 2017-09-21 01:28:54.047273Z] Finished setup-mitmproxy step (success) 18:28:54 INFO - [mozharness: 2017-09-21 01:28:54.047493Z] Running run-tests step. 18:28:54 INFO - Running pre-action listener: _resource_record_pre_action 18:28:54 INFO - Running pre-action listener: _set_gcov_prefix 18:28:54 INFO - Running main action method: run_tests 18:28:54 WARNING - Try message not found. 18:28:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:28:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:28:54 INFO - Python 2.7.3 18:28:54 INFO - Return code: 0 18:28:54 INFO - grabbing minidump binary from tooltool 18:28: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')]} 18:28:54 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 0x1323090>, '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 0x1323d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:28:54 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 18:28:54 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 18:28:54 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 18:28:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:28:54 INFO - Return code: 0 18:28:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:28:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:28:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:28:54 INFO - ENV: RUST_BACKTRACE is now full 18:28:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:28:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:28:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 18:28:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 18:28:54 INFO - Using env: {'DISPLAY': ':0', 18:28:54 INFO - 'HOME': '/home/cltbld', 18:28:54 INFO - 'LANG': 'en_US.UTF-8', 18:28:54 INFO - 'LANGUAGE': 'en_US:en', 18:28:54 INFO - 'LOGNAME': 'cltbld', 18:28:54 INFO - 'MAIL': '/var/mail/cltbld', 18:28:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:28:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:28:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:54 INFO - 'MOZ_NO_REMOTE': '1', 18:28:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:28:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:54 INFO - 'NO_EM_RESTART': '1', 18:28:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:54 INFO - 'PWD': '/builds/slave/test', 18:28:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:28:54 INFO - 'RUST_BACKTRACE': 'full', 18:28:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:28:54 INFO - 'SHELL': '/bin/bash', 18:28:54 INFO - 'SHLVL': '1', 18:28:54 INFO - 'TERM': 'linux', 18:28:54 INFO - 'TMOUT': '86400', 18:28:54 INFO - 'USER': 'cltbld', 18:28:54 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:28:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:54 INFO - '_': '/tools/buildbot/bin/python'} 18:28:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 18:28:54 INFO - mozversion application_buildid: 20170921001837 18:28:54 INFO - mozversion application_changeset: 61340c7debf6dccec3b863d1bc00426feac42fe7 18:28:54 INFO - mozversion application_display_name: Nightly 18:28:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:28:54 INFO - mozversion application_name: Firefox 18:28:54 INFO - mozversion application_remotingname: firefox 18:28:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:28:54 INFO - mozversion application_vendor: Mozilla 18:28:54 INFO - mozversion application_version: 57.0a1 18:28:54 INFO - mozversion platform_buildid: 20170921001837 18:28:54 INFO - mozversion platform_changeset: 61340c7debf6dccec3b863d1bc00426feac42fe7 18:28:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:28:54 INFO - mozversion platform_version: 57.0a1 18:28:54 INFO - using testdate: 1505957334 18:28:54 INFO - actual date: 1505957334 18:28:54 INFO - starting webserver on 'localhost:44412' 18:28:54 INFO - SUITE-START | Running 2 tests 18:28:54 INFO - TEST-START | dromaeo_css 18:28:54 INFO - Initialising browser for dromaeo_css test... 18:28:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44412/getInfo.html -profile /tmp/tmpUfX5G2/profile 18:28:54 INFO - TEST-INFO | started process 5285 (/builds/slave/test/build/application/firefox/firefox http://localhost:44412/getInfo.html) 18:29:00 INFO - TEST-INFO | 5285: exit 0 18:29:00 INFO - Browser initialized. 18:29:00 INFO - Running cycle 1/1 for dromaeo_css test... 18:29:00 INFO - Using env: {'DISPLAY': ':0', 18:29:00 INFO - 'HOME': '/home/cltbld', 18:29:00 INFO - 'JSGC_DISABLE_POISONING': '1', 18:29:00 INFO - 'LANG': 'en_US.UTF-8', 18:29:00 INFO - 'LANGUAGE': 'en_US:en', 18:29:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:29:00 INFO - 'LOGNAME': 'cltbld', 18:29:00 INFO - 'MAIL': '/var/mail/cltbld', 18:29:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:29:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:29:00 INFO - 'MOZ_CRASHREPORTER': '1', 18:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:29:00 INFO - 'MOZ_NO_REMOTE': '1', 18:29:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:29:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:00 INFO - 'NO_EM_RESTART': '1', 18:29:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:00 INFO - 'PWD': '/builds/slave/test', 18:29:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:29:00 INFO - 'RUST_BACKTRACE': 'full', 18:29:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:29:00 INFO - 'SHELL': '/bin/bash', 18:29:00 INFO - 'SHLVL': '1', 18:29:00 INFO - 'TERM': 'linux', 18:29:00 INFO - 'TMOUT': '86400', 18:29:00 INFO - 'USER': 'cltbld', 18:29:00 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:00 INFO - '_': '/tools/buildbot/bin/python'} 18:29:00 INFO - TEST-INFO | started process 5521 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUfX5G2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:29:01 INFO - PID 5521 | 18:29:01 INFO - PID 5521 | (/builds/slave/test/build/application/firefox/firefox:5570): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:29:01 INFO - PID 5521 | 18:29:11 INFO - PID 5521 | RSS: Main: 153907200 18:29:11 INFO - PID 5521 | 18:32:07 INFO - PID 5521 | Cycle 1(1): loaded http://localhost:44412/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44412/tests/dromaeo/cssquery-ext.html) 18:32:09 INFO - PID 5521 | RSS: Main: 155308032 18:32:09 INFO - PID 5521 | 18:35:11 INFO - PID 5521 | Cycle 1(1): loaded http://localhost:44412/tests/dromaeo/cssquery-ext.html (next: http://localhost:44412/tests/dromaeo/cssquery-jquery.html) 18:35:11 INFO - PID 5521 | RSS: Main: 153473024 18:35:11 INFO - PID 5521 | 18:38:13 INFO - PID 5521 | Cycle 1(1): loaded http://localhost:44412/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44412/tests/dromaeo/cssquery-mootools.html) 18:38:14 INFO - PID 5521 | RSS: Main: 157655040 18:38:14 INFO - PID 5521 | 18:41:16 INFO - PID 5521 | Cycle 1(1): loaded http://localhost:44412/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44412/tests/dromaeo/cssquery-prototype.html) 18:41:16 INFO - PID 5521 | RSS: Main: 155033600 18:41:16 INFO - PID 5521 | 18:44:19 INFO - PID 5521 | Cycle 1(1): loaded http://localhost:44412/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44412/tests/dromaeo/cssquery-yui.html) 18:44:20 INFO - PID 5521 | RSS: Main: 157302784 18:44:20 INFO - PID 5521 | 18:47:22 INFO - PID 5521 | Cycle 1(1): loaded http://localhost:44412/tests/dromaeo/cssquery-yui.html (next: http://localhost:44412/tests/dromaeo/cssquery-dojo.html) 18:47:22 INFO - PID 5521 | RSS: Main: 158892032 18:47:22 INFO - PID 5521 | 18:47:22 INFO - PID 5521 | __start_tp_report 18:47:22 INFO - PID 5521 | _x_x_mozilla_page_load 18:47:22 INFO - PID 5521 | _x_x_mozilla_page_load_details 18:47:22 INFO - PID 5521 | |i|pagename|runs| 18:47:22 INFO - PID 5521 | |0;dojo.html;3102.8758849646015;3114.968850311496;3134.9811311323824;3149.9842500787495;3158.384115097557;6186.938130618692;6271.310155882855;6310.873782524347;6312.305646378901;6435.452986496153;6155.784547540837;6273.435390814826;6280;6309.716062777175;6311.77908773193;5770.2210201622665;5786.623869448494;5803.912941305884;5808.796692115777;5882.264716910387;5861.443162899539;5877.735501902424;5877.7648894044405;5913.083472061816;5931.584789064767;5832.224230746312;5833.941660583403;5903.822885313426;5908.67502287374;5920.5559583031445;753.0236524650876;5244.344456942882;5650.773969041234;5860.00379935412;5878.265216847894;5734.684592347419;5738.684372359518;5759.568032397587;5818.301803783531;5819.24349834523;6142.447179753799;6272.56092073555;6302.4958003359625;6339.36606339367;6360.268569114564;6657.76697815579;6704.497162712767;6709.228438729511;6768.627725475097;6796.048553202556;6798;6798.966005169992;6829.214640316328;6871.931280687179;6889.517733758639;6078.787242446493;6221.3156552779155;6247.187865577446;6315.652639104847;6317.241930968267;6899;6971.5468494547695;6976.19773726028;7004.3696067354185;7016.684249208798;6593.3736295051895;6596.835079122964;6668.233153187445;6681.265060843303;6691.498137639696;6578.769743059066;6720.361565651353;6747.493937954673;6791.083203767428;6837.589744615339;6616.4376028037195;6698.966505167443;6753.932460675429;6757.932420675828;6764.086848275519;6744.190697116377;6791.524593278423;6794.898076528856;6803.965980170067;6807.2512023677355;5282.7622756976025;5350.892982140335;5358.303420555303;5364.034473794676;5370.221317908881;4521.525239849834;4595.494495605481;4605.539446055368;4605.723656580549;4617.607503362185;5680.573956953245;5736.024875771129;5803.129530570363;5811.128330750336;5812.912806307909;6776.593404395752;6865.176178858469;6881.243063263037;6890.517663763489;6891.241963384024;5754.827355179351;5767.625104368202;5782.797602083991;5840.678762668094;5846.503047240947;5681.857953551194;5735.913961290584;5764.221830052973;5826.563007774434;5906;6383.170187875547;6397.96801015992;6464.191976003;6535.0850880876715;6556.901646475307;6816.147981502312;6816.7614133505085;6874.072000279998;6900.413464855544;6908.861822763617;6532.41208291256;6539.640319782267;6625.768098116543;6628.469722422291;6635.767748128792;6431.228252609717;6549.377809108127;6551.050097735897;6555.4100130986535;6675.198976122896;6754.527183097136;6774.187097548326;6807.965960170168;6872.45020398357;6885.827854303682;6564.179477565304;6608.074869518176;6623.735050597922;6665.600063996176;6665.800025999228;6574.572652777745;6579.736810527525;6650.467962562887;6668.699908504032;6670.132882725215;6496.090547323473;6511.641859697762;6571.901421478778;6584.440322572635;6590.406863382319;6475.190601174853;6518.282988871126;6581.769638062645;6582.144321238208;6615.669216539058;6426.100345951477;6458.095866578777;6473.838154046187;6475.870482590416;6479.125318081999 18:47:22 INFO - PID 5521 | |1;ext.html;864.6368525219408;890.2343984173609;894.4141587260347;897.2059727141478;897.4077109107988;38848.41727374092;40117.398239026435;43004.784976075076;43176.568234317645;43383.34924976128;39018.41472377917;39242.41136382957;39279.41080883782;39411.40882886752;39571.802140989326;7265.854682906352;7410;7966.800829979239;8136.674533018672;8173.346132309401;8056.718014869481;8164.387670924675;8164.959175204116;8207.466514676556;8212.425130240856;8129.715459958903;8189.140140285273;8189.467684600511;8190.385721070914;8217.671293148267;8094.797630059236;8158;8168.550729709864;8178.386621003418;8182.509049457051;8063.1130575636635;8108.270255677017;8113.8782918256275;8119.106898241189;8126.796830079295;38364.808175959224;38472;38511.229775404616;38621.42067868984;38648.61351386478;3533.9293214135832;3564.108972756811;3581.4448760442174;3583.7670551414335;3583.9104022399647;31448;31510.369792604248;31617.84191079053;31698.207544811565;31865.044048678577;5088.847334579968;5243.4232234454175;5292.788288468457;5317.9202311965355;5348.973255133738;185960;187003;187786;188298.05850970795;188596;43559.78220108942;44619.553804462186;50241;50459.495405045476;50621.49378506168;52055;52653;52695;52752;52771.47228527666;16692.499225023268;16696.081715505763;16698.4155554555;16700.83299166993;16835.494935151964;13740.312984350743;13764.17414955106;13767.518136865361;13772.449102035805;13782.241976691177;13956;14126.929365353108;14189.574312770634;14190.290485475685;14358.138511689333;5593.468620481048;5613.382527921925;5658.490735833712;5667.291588551431;5824.563157763185;981.2003217377815;984.9950750246203;1002.1231422505308;1028;1033.4316126130598;22856.885715571316;23021.539569208522;23131.768682312962;23132.76867231306;23439.765602344098;1102.9613963511238;1106.0653747583278;1107.7950579142885;1109.1016276815806;1111.4442778610694;11966.940165299291;12015;12026.759464810655;12127.696807579881;12157.69605759863;224003;225196;226273;226491;226772;54744;55157;55333.72333138309;55412.16881746777;55435.445645543834;1434.4047220403625;1466.6877795811693;1468.6034770611875;1481.185348058585;1489.5382431446287;22180.334589962327;22207.44481387978;22363.552728945655;22398.440038999157;22519.211827585958;37989.430158548195;38152.618473816015;38162.80918595389;38174.236515270095;38219.808900955315;188573;191078;191287;191466;191531;31617.8419107903;31976.761626685908;32171.35657286888;34365.65634343625;34687.82656086703;44497.55502445064;44522.10955780801;44613.55386446094;44844.55155448404;45560.77219613881;32519.837400812845;32532.83733581317;32673.18317042093;32917.50623740694;33245.33509329848;31813.8409307952;31970.36059278848;32099.197520062186;32256.677433225366;32338.676613234507;35848.283034338645;35924.82037589795;36087.63912360843;36180.27639447144;36250.274994499436;31047.84476077605;31080.533791992686;31549.053528394186;31570;32118;23332;23371.766282337638;23388.18141365044;23572;23604.09508659472 18:47:22 INFO - PID 5521 | |2;jquery.html;579.2411940358131;589.6579983609507;596.126674421972;598.4255115089513;622.0637939900449;21090.89454552727;22338.776612233894;22339.218127365544;22401.55196896061;22412;18589.349372771954;23551.882240588817;23820.404489887762;23928.641070383914;23967.161149359777;6401.551891367594;6573.112629794989;6585.80242592723;6588.703508342112;6590;6364.2681091674585;6396.968015159918;6475.676216189196;6487.448566871822;6668.133142691443;6625;6634.701438435258;6640.037194606779;6643.6346000969925;6644.667766611674;6250.874982500324;6594.637294948776;6606.9008964865325;6607.471402287805;6615.867682646344;6051.515878729691;6076.149339092531;6077.726502307362;6078.878422431527;6088.178095957033;27424.45151096987;27679.30801729953;27750.444991100067;27807.026754063714;27897.86051069752;2015.7379540659767;2031.5022819409191;2045.222815330165;2047.1095073643014;2049.7027930950076;18338.541536461562;18900.243990240375;19257.614847702967;19315.51711207231;19422.22311107554;2460.9753902460925;2463.2733343663663;2505.436276837715;2512.0705339024494;2524.962125568118;115749.42125289404;121309;126466.36766816198;129131;135819;14466.991328668459;14724.26378681083;14769.852301477062;14952.375725340826;15310.617234569003;22964.770352296262;23049.19327823518;23139.88430057839;23171.189008384627;23186.188483403337;9779.26655500826;9785.217182625373;9817.754556136153;9825.361351512129;9934.304598678023;8879.600417981206;8945.19493245611;8994.86507702385;8998.235150012191;9057.547122643973;8899.644014239488;9044.457332560067;9061.95469022664;9107.316951228684;9112.271018318519;2571.781398581104;2603.3621762668267;2667.7599016088643;2680.490706765708;2694.3129501977264;912.9315301352425;943.2312665177858;946.9952650236705;954.7565370830393;967.4340510801119;18864.81135188658;19043.047847607566;19141.425757227305;19234.422967311002;19321.130549125322;1005.5173516711873;1006.2955930848289;1015.167562598662;1024.784795193003;1031.4120951057976;10468.371897686011;10720.785584288271;10749.462526873625;10848.349099054081;10952.39761813092;491314;511081;513205;519567;527425;113279.4336028331;114358;115673;115981.42009289899;116946;1090.0407641275785;1131.5982826096617;1134.0304040045808;1141.3323205924462;1153.6135394642888;16903.915480422518;17159.227834747217;17479.82520174782;17529.298828046736;17658.735118973484;52586.737066314425;54127.188092179436;54529.182062269894;54673.72663136818;55919.72040139773;66127.33872661405;67059.3294067053;67268.32731672621;67835.3216467829;69255.65372173107;14264.857351426353;14544.345504452536;14568.125912445286;14577.052491588012;14578.635534111309;22444.5511089774;22677.546449071255;22700.772992269867;22718;22750.544989100457;17811.287548497916;18147.54631134232;18153.81846181521;18177.09114544241;18181.363652272106;15168.544943651708;15733.763993539877;16026.198690065683;16463.588410289838;16613.916930415755;17633.20650570753;17909.373171938918;18154.364597239033;18172.000539970428;18453.72319415183;14295;14327.426902923766;14472.27638618086;14472.855271447572;14476.348564314841;17953.102344882966;17985.550361241072;18050.638987220445;18268.26926922908;18269.634607307515 18:47:22 INFO - PID 5521 | |3;mootools.html;1326.8938484921207;1355.315565639352;1363.8499765025852;1365.6722386627212;1368.5689007962492;15191;15818.920905395471;15836.287367068495;15859.444919427822;15879.52361429156;15570.14364209968;15629.296681649324;15661.530154095366;15681.215939203052;15687.137207453585;2752.9587056194173;2877.6834548199786;2883.9711602884017;2893.56596510523;2893.6961619030017;2808.031229225924;2866.0398766413277;2876.3384421582946;2877.0505733108025;2888.7688984881265;2768.5155097857796;2769.3492029373083;2854.4861924853517;2856.742893139617;2857.27139579408;2820.6756223034204;2825.6185414969023;2848.3448806774454;2848.829070255791;2850.800443968923;2357.905683772647;2428.3200703802963;2804.9018284360154;2810.9718902810914;2814.605955166258;15169.924150379287;15358.078515289008;15375.538733838002;15402;15405.845941540665;8295.253427191521;8599.82800343996;8617.181367770052;8626.051134375213;8635.17965793255;5833.329167145747;5837.737301821428;5931.673757943311;6093.756249750005;6148.754049838001;1911.9713204301947;1921.8462522998127;1924.788273289937;1945.5233467800479;1971.3494546799523;27576.586351204747;27789.444211115868;27891.721082789114;27896.860515697495;27928;6958.47811414146;7059.82350441243;7094.964525177341;7207.351338379571;7211.675474603656;17185.31258749636;17192.656146876994;17225.052622105653;17238.482845514656;17261.741073883903;7891.723789667449;8163.183681631789;8164.959175204086;8166.836663266701;8167.428280020342;7295.671694773748;7494.775156745306;7568.053993250844;7628.0083589133055;7640.083190017122;5330.173823057432;5558.110702287696;5559.221708960748;5583.246261754693;5596.97201513998;1612.7338989066907;1637.2305016642158;1641.5813967438467;1645.9670806583802;1647.0529445568845;885.198895000026;892.638481415028;908.4957848394205;911.40758506971;918.0406475233397;7335.303146201102;7570.242975702496;7633.618319084024;7653.502522336142;7656.885146722803;715.6707914359349;721.9422446204396;730.6858051038105;731.3125661877817;739.1389031777918;5042.168042273057;5079.288899554065;5161.74191290434;5190.844274671766;5224.190250511177;21558;21856.344309670418;22337.218197363338;22723.88638056832;22786.772132278464;22211.888940555193;22299;22344.776552234594;22562.210322638628;22579.548409032057;10933.726656833484;11505.539778408938;11520.596779112691;11559.364234967157;11589.609246890426;8721.825563488821;8748.78128046804;8832.779180520538;8857.734267971713;8941.597628106621;18362.540936476697;18826.435206943814;18867;19013.42959711211;19187;19124.80875191286;19213.519662008563;19251.13369898386;19839.60320793605;19938.800611994273;12634.494620215088;12749.426275817425;12820;12841.678958026125;12850.614481565568;14101.153930764187;14276.71446571042;14277.357518911882;14368.425262989782;14435.494757683431;11629.651110466699;11685.649430517098;11853.288802671867;12046.638600841989;12051.517939282681;11062.612808551663;11178.161637877029;11383.146264030396;11544.365059921785;11816.231944923553;9983.301168918106;10024.298299118991;10060.899391005996;10153.390796551936;10179.7455063624;7043.506954513135;7135.250798666273;7143.571385716874;7197.208307086112;7328.267173282629;7257.346838784535;7321.670524826289;7364.963175184304;7382.5570465772225;7405.407567394488 18:47:22 INFO - PID 5521 | |4;prototype.html;492.48104091712634;522.8144008876851;532.9083347859653;535.520708965476;536.6216817143912;6295.653739044351;6313;6344.619322840627;6357.71390287437;6387.616742995424;6168.969155154229;6186.2885768136675;6269.717862696179;6271.891910798423;6294.43350098491;5167.9224811627855;5184.766685499152;5187.766550505217;5202.297689811865;5210.973945130288;4906.165951788204;5148.227765835118;5176.741162941857;5250.343707036621;5269.209618557228;4963.528464795839;4988.32657591226;5064.215046667754;5071.833478299994;5082.923756143661;4913.164761990471;4955.950440495585;4966.5778408835295;5040.319556859814;5048.924266136011;4412.205802955466;4453.732776033416;4488.079943611585;4518.5314772699;4557.179707652621;6020.247469066367;6060.272767267912;6065.909011364833;6088.985951953767;6234.968825155891;5729.856753581152;5740.799072032501;5747.942520574783;5749.195112684228;5805.593608447411;6467.83830404239;6525.249596296392;6535.2484464286745;6553.508486863504;6594.516233847394;4921.114199444099;4958.20668693011;4960.553550180465;4968.360441054443;4990.925136122961;6521.673916304213;6731.461483081342;6744.527883048188;6803.931960680379;6813.7955861324235;5010.248462730619;5055.570276526463;5061.164907790173;5068;5090.9236361454605;5159.948400515947;5163.4062082860955;5167.914737905082;5168.560672342818;5172.5344718975475;5797.6375551745305;5807.5063619591965;5809.9419005809405;5817.680027598438;5831.183634291203;6573.1783527059115;6615.206175259;6624.337566243434;6638.966805165944;6724.58783655428;1775.245520653717;1783.7324401339642;1788.1685016467145;1789.185920406212;1792.3995461520276;1213.5115206484907;1244.160191870484;1244.6141696074246;1257.1639859493516;1258.8670196822932;5661.264035675335;5679.636887147138;5704.429557044263;5708.400617935057;5730.799422020293;6595.241547222035;6638.236602790645;6683.665816709243;6708.551378746723;6723.260441351447;5619.26949496571;5636.520895723909;5640.32316122068;5663.66018038919;5697.857553561112;3409.991679344594;3518.7185025197923;3582.570091589026;3590.856365745341;3598.226381328027;6373.840653983688;6376.840578985562;6427.935720642734;6451.41937225652;6475.546711730133;6788.911045474298;6814.114165158498;6826.829329266808;6845.877777888871;6952.687129079102;6386.680665966776;6412.236634925433;6425.646589437626;6484.902726459107;6543.887539118361;6543.738250470118;6572.009735570208;6613.338666133348;6736.663166841737;6738.865222695421;6568.507361947969;6599.241087274832;6704.66476676174;6715.597064176165;6759.343960729652;6245;6359.682015899094;6392.232932048184;6402.775902843378;6446.742130314735;5019.824306149268;5081.898362032664;5085.711429856653;5089.422279093493;5104.1067813133295;6439.291677915332;6538.771143009972;6716.429103526255;6852;6857.759978400732;6402.583832050902;6531.860051551526;6558.1802274715665;6564.409203171737;6579.342065793382;6414.454771344488;6496.512761542808;6538.542302038812;6561;6629.602223866584;6414.679266036586;6519.576227545194;6553.377429144223;6554.606723596599;6555.80332590023;6645.800625981228;6653;6677.362441835545;6691.063251144941;6694.564853284521;6281.371862813682;6331.556791024584;6333.746650133943;6421.8715625688155;6449.5166111793615 18:47:22 INFO - PID 5521 | |5;yui.html;726.7456390263409;775.6005657086598;784.6508303804806;907.9455232686038;935.6538080910065;19328.90335548324;19608.607827843436;19744.70382944255;19766.01169941501;19884.801151988493;17735.55661108473;17793;17891.552711182227;17918.28326866927;17990;951.3958636265969;961.3270710502676;964.9228061755043;965.6813251626946;966.8356379415482;967.5162418790604;969.4910172159603;971.7424882406133;973.7176218896511;974.5224839828469;989.2975987049203;1022.4018948914857;1023.3655133817044;1029.5058371981454;1041.828098363769;1028.686250693538;1029.0686928329874;1029.1046789293325;1030.1295405382439;1032.2155162076838;724.6811402982723;757.7310054930475;759.8594260061906;760.4182800157884;762.20349734527;19313.42059738196;19344.226230950746;19354.32259870898;19430.417087487396;19507.317243896538;21322.253721119687;21337;22190.667139992915;22249.33252002426;22262.220822271305;17685.469435916944;17822.10889455535;17903.820961790476;17978.460646180505;18146.274149034023;3782;3811.27585758705;3811.80940952954;3815.04623844039;3815.389537673959;104237;133756.33121834425;134788.32605837006;136252;137161.3141934294;17690.734638980426;18026.188821503063;18351.724724129148;18380.724289135673;18449;31446.528302075487;32717.345653087283;32893.50659740106;32932;32954.34091318208;9015.188633023059;9328.860067098998;9358.15776580111;9377.812443751223;9402.623895044122;4223.978880105641;4316.69783115179;4346.6088052075465;4347.260965635849;4348.717333373384;5653.208550802891;5749.195112684228;5752.597318187687;5757.481826635623;5771.336296325892;1128.3004537186996;1128.8927551882557;1132.0773569540797;1134.5575225661999;1138.5502726423017;685.7736946807541;696.9581825090511;703.9190493093257;708.387245033053;713.3187805645699;11383.943080284545;11426.657200284004;11457.770844583061;12138.514459421702;12229.3273869938;676.5331920974512;679.4224908827457;689.6586189836063;689.9689513971883;690.7133539581017;5315.627906046617;5332.226827110125;5385.569154467633;5459.945400546023;5461.535769459561;114000;115461;118571;120438.39780801215;120784.39607801905;104212;104821.47589262004;107219;107678;110151;89573.55213224022;90149;92193.53903230575;93426;94143;9845.753856153653;9942.850857237292;10002.399856008662;10007.09936105754;10066.748331291776;41535.79232103941;41834.790826045675;42151;42565.787171063945;43316.35025474557;55567;56698.43301566932;57530.712346438;57774.13338800005;57785.71107144438;15343.846561534241;15370.846291536942;15443.305051272944;15454.690906182039;15618.45335413255;17156.914215428842;17174.742378864576;17203.569910752332;17307.740383894503;17426.73859892128;15114.395424182909;15330.233488325315;15361.38554457854;15363.23183840826;15455.922720386327;17019.14904254807;17124.48626541206;17178.65642687114;17358.39245626397;17368;15426.92286538605;15499.070055796688;15525.767113493532;15730.685386292438;15732.370705171665;11150;11357.48891299877;11478;11494.080473561928;11525.596604118815;11615.709607259887;11699.766004680028;11715.707107322045;11990.280583165038;12039.097067720131 18:47:22 INFO - PID 5521 | __end_tp_report 18:47:22 INFO - PID 5521 | __start_cc_report 18:47:22 INFO - PID 5521 | _x_x_mozilla_cycle_collect,282 18:47:22 INFO - PID 5521 | __end_cc_report 18:47:22 INFO - PID 5521 | __startTimestamp1505958442393__endTimestamp 18:47:22 INFO - PID 5521 | 18:47:22 INFO - PID 5521 | ------- Summary: start ------- 18:47:22 INFO - PID 5521 | Number of tests: 6 18:47:22 INFO - PID 5521 | 18:47:22 INFO - PID 5521 | [#0] dojo.html Cycles:165 Average:6180.12 Median:6475.87 stddev:869.21 (13.4%) stddev-sans-first:837.68 18:47:22 INFO - PID 5521 | Values: 3102.9 3115.0 3135.0 3150.0 3158.4 6186.9 6271.3 6310.9 6312.3 6435.5 6155.8 6273.4 6280.0 6309.7 6311.8 5770.2 5786.6 5803.9 5808.8 5882.3 5861.4 5877.7 5877.8 5913.1 5931.6 5832.2 5833.9 5903.8 5908.7 5920.6 753.0 5244.3 5650.8 5860.0 5878.3 5734.7 5738.7 5759.6 5818.3 5819.2 6142.4 6272.6 6302.5 6339.4 6360.3 6657.8 6704.5 6709.2 6768.6 6796.0 6798.0 6799.0 6829.2 6871.9 6889.5 6078.8 6221.3 6247.2 6315.7 6317.2 6899.0 6971.5 6976.2 7004.4 7016.7 6593.4 6596.8 6668.2 6681.3 6691.5 6578.8 6720.4 6747.5 6791.1 6837.6 6616.4 6699.0 6753.9 6757.9 6764.1 6744.2 6791.5 6794.9 6804.0 6807.3 5282.8 5350.9 5358.3 5364.0 5370.2 4521.5 4595.5 4605.5 4605.7 4617.6 5680.6 5736.0 5803.1 5811.1 5812.9 6776.6 6865.2 6881.2 6890.5 6891.2 5754.8 5767.6 5782.8 5840.7 5846.5 5681.9 5735.9 5764.2 5826.6 5906.0 6383.2 6398.0 6464.2 6535.1 6556.9 6816.1 6816.8 6874.1 6900.4 6908.9 6532.4 6539.6 6625.8 6628.5 6635.8 6431.2 6549.4 6551.1 6555.4 6675.2 6754.5 6774.2 6808.0 6872.5 6885.8 6564.2 6608.1 6623.7 6665.6 6665.8 6574.6 6579.7 6650.5 6668.7 6670.1 6496.1 6511.6 6571.9 6584.4 6590.4 6475.2 6518.3 6581.8 6582.1 6615.7 6426.1 6458.1 6473.8 6475.9 6479.1 18:47:22 INFO - PID 5521 | 18:47:22 INFO - PID 5521 | [#1] ext.html Cycles:180 Average:37339.65 Median:3582.61 stddev:52314.74 (1460.2%) stddev-sans-first:52389.80 18:47:22 INFO - PID 5521 | Values: 864.6 890.2 894.4 897.2 897.4 38848.4 40117.4 43004.8 43176.6 43383.3 39018.4 39242.4 39279.4 39411.4 39571.8 7265.9 7410.0 7966.8 8136.7 8173.3 8056.7 8164.4 8165.0 8207.5 8212.4 8129.7 8189.1 8189.5 8190.4 8217.7 8094.8 8158.0 8168.6 8178.4 8182.5 8063.1 8108.3 8113.9 8119.1 8126.8 38364.8 38472.0 38511.2 38621.4 38648.6 3533.9 3564.1 3581.4 3583.8 3583.9 31448.0 31510.4 31617.8 31698.2 31865.0 5088.8 5243.4 5292.8 5317.9 5349.0 185960.0 187003.0 187786.0 188298.1 188596.0 43559.8 44619.6 50241.0 50459.5 50621.5 52055.0 52653.0 52695.0 52752.0 52771.5 16692.5 16696.1 16698.4 16700.8 16835.5 13740.3 13764.2 13767.5 13772.4 13782.2 13956.0 14126.9 14189.6 14190.3 14358.1 5593.5 5613.4 5658.5 5667.3 5824.6 981.2 985.0 1002.1 1028.0 1033.4 22856.9 23021.5 23131.8 23132.8 23439.8 1103.0 1106.1 1107.8 1109.1 1111.4 11966.9 12015.0 12026.8 12127.7 12157.7 224003.0 225196.0 226273.0 226491.0 226772.0 54744.0 55157.0 55333.7 55412.2 55435.4 1434.4 1466.7 1468.6 1481.2 1489.5 22180.3 22207.4 22363.6 22398.4 22519.2 37989.4 38152.6 38162.8 38174.2 38219.8 188573.0 191078.0 191287.0 191466.0 191531.0 31617.8 31976.8 32171.4 34365.7 34687.8 44497.6 44522.1 44613.6 44844.6 45560.8 32519.8 32532.8 32673.2 32917.5 33245.3 31813.8 31970.4 32099.2 32256.7 32338.7 35848.3 35924.8 36087.6 36180.3 36250.3 31047.8 31080.5 31549.1 31570.0 32118.0 23332.0 23371.8 23388.2 23572.0 23604.1 18:47:22 INFO - PID 5521 | 18:47:22 INFO - PID 5521 | [#2] jquery.html Cycles:180 Average:34582.53 Median:22857.66 stddev:85669.91 (374.8%) stddev-sans-first:85872.19 18:47:22 INFO - PID 5521 | Values: 579.2 589.7 596.1 598.4 622.1 21090.9 22338.8 22339.2 22401.6 22412.0 18589.3 23551.9 23820.4 23928.6 23967.2 6401.6 6573.1 6585.8 6588.7 6590.0 6364.3 6397.0 6475.7 6487.4 6668.1 6625.0 6634.7 6640.0 6643.6 6644.7 6250.9 6594.6 6606.9 6607.5 6615.9 6051.5 6076.1 6077.7 6078.9 6088.2 27424.5 27679.3 27750.4 27807.0 27897.9 2015.7 2031.5 2045.2 2047.1 2049.7 18338.5 18900.2 19257.6 19315.5 19422.2 2461.0 2463.3 2505.4 2512.1 2525.0 115749.4 121309.0 126466.4 129131.0 135819.0 14467.0 14724.3 14769.9 14952.4 15310.6 22964.8 23049.2 23139.9 23171.2 23186.2 9779.3 9785.2 9817.8 9825.4 9934.3 8879.6 8945.2 8994.9 8998.2 9057.5 8899.6 9044.5 9062.0 9107.3 9112.3 2571.8 2603.4 2667.8 2680.5 2694.3 912.9 943.2 947.0 954.8 967.4 18864.8 19043.0 19141.4 19234.4 19321.1 1005.5 1006.3 1015.2 1024.8 1031.4 10468.4 10720.8 10749.5 10848.3 10952.4 491314.0 511081.0 513205.0 519567.0 527425.0 113279.4 114358.0 115673.0 115981.4 116946.0 1090.0 1131.6 1134.0 1141.3 1153.6 16903.9 17159.2 17479.8 17529.3 17658.7 52586.7 54127.2 54529.2 54673.7 55919.7 66127.3 67059.3 67268.3 67835.3 69255.7 14264.9 14544.3 14568.1 14577.1 14578.6 22444.6 22677.5 22700.8 22718.0 22750.5 17811.3 18147.5 18153.8 18177.1 18181.4 15168.5 15733.8 16026.2 16463.6 16613.9 17633.2 17909.4 18154.4 18172.0 18453.7 14295.0 14327.4 14472.3 14472.9 14476.3 17953.1 17985.6 18050.6 18268.3 18269.6 18:47:22 INFO - PID 5521 | 18:47:22 INFO - PID 5521 | [#3] mootools.html Cycles:180 Average:9610.18 Median:27840.58 stddev:6897.66 (24.8%) stddev-sans-first:6888.93 18:47:22 INFO - PID 5521 | Values: 1326.9 1355.3 1363.8 1365.7 1368.6 15191.0 15818.9 15836.3 15859.4 15879.5 15570.1 15629.3 15661.5 15681.2 15687.1 2753.0 2877.7 2884.0 2893.6 2893.7 2808.0 2866.0 2876.3 2877.1 2888.8 2768.5 2769.3 2854.5 2856.7 2857.3 2820.7 2825.6 2848.3 2848.8 2850.8 2357.9 2428.3 2804.9 2811.0 2814.6 15169.9 15358.1 15375.5 15402.0 15405.8 8295.3 8599.8 8617.2 8626.1 8635.2 5833.3 5837.7 5931.7 6093.8 6148.8 1912.0 1921.8 1924.8 1945.5 1971.3 27576.6 27789.4 27891.7 27896.9 27928.0 6958.5 7059.8 7095.0 7207.4 7211.7 17185.3 17192.7 17225.1 17238.5 17261.7 7891.7 8163.2 8165.0 8166.8 8167.4 7295.7 7494.8 7568.1 7628.0 7640.1 5330.2 5558.1 5559.2 5583.2 5597.0 1612.7 1637.2 1641.6 1646.0 1647.1 885.2 892.6 908.5 911.4 918.0 7335.3 7570.2 7633.6 7653.5 7656.9 715.7 721.9 730.7 731.3 739.1 5042.2 5079.3 5161.7 5190.8 5224.2 21558.0 21856.3 22337.2 22723.9 22786.8 22211.9 22299.0 22344.8 22562.2 22579.5 10933.7 11505.5 11520.6 11559.4 11589.6 8721.8 8748.8 8832.8 8857.7 8941.6 18362.5 18826.4 18867.0 19013.4 19187.0 19124.8 19213.5 19251.1 19839.6 19938.8 12634.5 12749.4 12820.0 12841.7 12850.6 14101.2 14276.7 14277.4 14368.4 14435.5 11629.7 11685.6 11853.3 12046.6 12051.5 11062.6 11178.2 11383.1 11544.4 11816.2 9983.3 10024.3 10060.9 10153.4 10179.7 7043.5 7135.3 7143.6 7197.2 7328.3 7257.3 7321.7 7365.0 7382.6 7405.4 18:47:22 INFO - PID 5521 | 18:47:22 INFO - PID 5521 | [#4] prototype.html Cycles:180 Average:5528.87 Median:6210.63 stddev:1530.22 (24.6%) stddev-sans-first:1487.08 18:47:22 INFO - PID 5521 | Values: 492.5 522.8 532.9 535.5 536.6 6295.7 6313.0 6344.6 6357.7 6387.6 6169.0 6186.3 6269.7 6271.9 6294.4 5167.9 5184.8 5187.8 5202.3 5211.0 4906.2 5148.2 5176.7 5250.3 5269.2 4963.5 4988.3 5064.2 5071.8 5082.9 4913.2 4956.0 4966.6 5040.3 5048.9 4412.2 4453.7 4488.1 4518.5 4557.2 6020.2 6060.3 6065.9 6089.0 6235.0 5729.9 5740.8 5747.9 5749.2 5805.6 6467.8 6525.2 6535.2 6553.5 6594.5 4921.1 4958.2 4960.6 4968.4 4990.9 6521.7 6731.5 6744.5 6803.9 6813.8 5010.2 5055.6 5061.2 5068.0 5090.9 5159.9 5163.4 5167.9 5168.6 5172.5 5797.6 5807.5 5809.9 5817.7 5831.2 6573.2 6615.2 6624.3 6639.0 6724.6 1775.2 1783.7 1788.2 1789.2 1792.4 1213.5 1244.2 1244.6 1257.2 1258.9 5661.3 5679.6 5704.4 5708.4 5730.8 6595.2 6638.2 6683.7 6708.6 6723.3 5619.3 5636.5 5640.3 5663.7 5697.9 3410.0 3518.7 3582.6 3590.9 3598.2 6373.8 6376.8 6427.9 6451.4 6475.5 6788.9 6814.1 6826.8 6845.9 6952.7 6386.7 6412.2 6425.6 6484.9 6543.9 6543.7 6572.0 6613.3 6736.7 6738.9 6568.5 6599.2 6704.7 6715.6 6759.3 6245.0 6359.7 6392.2 6402.8 6446.7 5019.8 5081.9 5085.7 5089.4 5104.1 6439.3 6538.8 6716.4 6852.0 6857.8 6402.6 6531.9 6558.2 6564.4 6579.3 6414.5 6496.5 6538.5 6561.0 6629.6 6414.7 6519.6 6553.4 6554.6 6555.8 6645.8 6653.0 6677.4 6691.1 6694.6 6281.4 6331.6 6333.7 6421.9 6449.5 18:47:22 INFO - PID 5521 | 18:47:22 INFO - PID 5521 | [#5] yui.html Cycles:180 Average:23749.66 Median:19392.37 stddev:33692.40 (173.7%) stddev-sans-first:33742.57 18:47:22 INFO - PID 5521 | Values: 726.7 775.6 784.7 907.9 935.7 19328.9 19608.6 19744.7 19766.0 19884.8 17735.6 17793.0 17891.6 17918.3 17990.0 951.4 961.3 964.9 965.7 966.8 967.5 969.5 971.7 973.7 974.5 989.3 1022.4 1023.4 1029.5 1041.8 1028.7 1029.1 1029.1 1030.1 1032.2 724.7 757.7 759.9 760.4 762.2 19313.4 19344.2 19354.3 19430.4 19507.3 21322.3 21337.0 22190.7 22249.3 22262.2 17685.5 17822.1 17903.8 17978.5 18146.3 3782.0 3811.3 3811.8 3815.0 3815.4 104237.0 133756.3 134788.3 136252.0 137161.3 17690.7 18026.2 18351.7 18380.7 18449.0 31446.5 32717.3 32893.5 32932.0 32954.3 9015.2 9328.9 9358.2 9377.8 9402.6 4224.0 4316.7 4346.6 4347.3 4348.7 5653.2 5749.2 5752.6 5757.5 5771.3 1128.3 1128.9 1132.1 1134.6 1138.6 685.8 697.0 703.9 708.4 713.3 11383.9 11426.7 11457.8 12138.5 12229.3 676.5 679.4 689.7 690.0 690.7 5315.6 5332.2 5385.6 5459.9 5461.5 114000.0 115461.0 118571.0 120438.4 120784.4 104212.0 104821.5 107219.0 107678.0 110151.0 89573.6 90149.0 92193.5 93426.0 94143.0 9845.8 9942.9 10002.4 10007.1 10066.7 41535.8 41834.8 42151.0 42565.8 43316.4 55567.0 56698.4 57530.7 57774.1 57785.7 15343.8 15370.8 15443.3 15454.7 15618.5 17156.9 17174.7 17203.6 17307.7 17426.7 15114.4 15330.2 15361.4 15363.2 15455.9 17019.1 17124.5 17178.7 17358.4 17368.0 15426.9 15499.1 15525.8 15730.7 15732.4 11150.0 11357.5 11478.0 11494.1 11525.6 11615.7 11699.8 11715.7 11990.3 12039.1 18:47:22 INFO - PID 5521 | -------- Summary: end -------- 18:47:22 INFO - PID 5521 | 18:47:23 INFO - TEST-INFO | 5521: exit 0 18:47:23 INFO - TEST-OK | dromaeo_css | took 1108759ms 18:47:23 INFO - TEST-START | kraken 18:47:23 INFO - Initialising browser for kraken test... 18:47:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44412/getInfo.html -profile /tmp/tmpI0c6YT/profile 18:47:23 INFO - TEST-INFO | started process 5901 (/builds/slave/test/build/application/firefox/firefox http://localhost:44412/getInfo.html) 18:47:28 INFO - TEST-INFO | 5901: exit 0 18:47:28 INFO - Browser initialized. 18:47:28 INFO - Running cycle 1/1 for kraken test... 18:47:28 INFO - Using env: {'DISPLAY': ':0', 18:47:28 INFO - 'HOME': '/home/cltbld', 18:47:28 INFO - 'JSGC_DISABLE_POISONING': '1', 18:47:28 INFO - 'LANG': 'en_US.UTF-8', 18:47:28 INFO - 'LANGUAGE': 'en_US:en', 18:47:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:47:28 INFO - 'LOGNAME': 'cltbld', 18:47:28 INFO - 'MAIL': '/var/mail/cltbld', 18:47:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:47:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:47:28 INFO - 'MOZ_CRASHREPORTER': '1', 18:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:47:28 INFO - 'MOZ_NO_REMOTE': '1', 18:47:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:47:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:28 INFO - 'NO_EM_RESTART': '1', 18:47:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:28 INFO - 'PWD': '/builds/slave/test', 18:47:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:47:28 INFO - 'RUST_BACKTRACE': 'full', 18:47:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:47:28 INFO - 'SHELL': '/bin/bash', 18:47:28 INFO - 'SHLVL': '1', 18:47:28 INFO - 'TERM': 'linux', 18:47:28 INFO - 'TMOUT': '86400', 18:47:28 INFO - 'USER': 'cltbld', 18:47:28 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747', 18:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:28 INFO - '_': '/tools/buildbot/bin/python'} 18:47:28 INFO - TEST-INFO | started process 6134 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI0c6YT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:29 INFO - PID 6134 | 18:47:29 INFO - PID 6134 | (/builds/slave/test/build/application/firefox/firefox:6183): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:47:29 INFO - PID 6134 | 18:47:39 INFO - PID 6134 | RSS: Main: 156188672 18:47:39 INFO - PID 6134 | 18:47:42 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44412/tests/kraken/driver.html?testName=audio-beat-detection) 18:47:43 INFO - PID 6134 | RSS: Main: 162648064 18:47:43 INFO - PID 6134 | 18:47:45 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44412/tests/kraken/driver.html?testName=audio-dft) 18:47:46 INFO - PID 6134 | RSS: Main: 163024896 18:47:46 INFO - PID 6134 | 18:47:49 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44412/tests/kraken/driver.html?testName=audio-fft) 18:47:49 INFO - PID 6134 | RSS: Main: 158400512 18:47:49 INFO - PID 6134 | 18:47:52 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44412/tests/kraken/driver.html?testName=audio-oscillator) 18:47:52 INFO - PID 6134 | RSS: Main: 158920704 18:47:52 INFO - PID 6134 | 18:47:54 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44412/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:47:55 INFO - PID 6134 | RSS: Main: 159043584 18:47:55 INFO - PID 6134 | 18:47:58 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44412/tests/kraken/driver.html?testName=imaging-darkroom) 18:47:59 INFO - PID 6134 | RSS: Main: 161144832 18:47:59 INFO - PID 6134 | 18:48:02 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44412/tests/kraken/driver.html?testName=imaging-desaturate) 18:48:03 INFO - PID 6134 | RSS: Main: 161247232 18:48:03 INFO - PID 6134 | 18:48:06 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44412/tests/kraken/driver.html?testName=json-parse-financial) 18:48:07 INFO - PID 6134 | RSS: Main: 161509376 18:48:07 INFO - PID 6134 | 18:48:08 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44412/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:48:09 INFO - PID 6134 | RSS: Main: 161566720 18:48:09 INFO - PID 6134 | 18:48:10 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44412/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:48:11 INFO - PID 6134 | RSS: Main: 161525760 18:48:11 INFO - PID 6134 | 18:48:13 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44412/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:48:14 INFO - PID 6134 | RSS: Main: 158728192 18:48:14 INFO - PID 6134 | 18:48:16 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44412/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:48:17 INFO - PID 6134 | RSS: Main: 158978048 18:48:17 INFO - PID 6134 | 18:48:20 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44412/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:48:21 INFO - PID 6134 | RSS: Main: 158900224 18:48:21 INFO - PID 6134 | 18:48:22 INFO - PID 6134 | Cycle 1(1): loaded http://localhost:44412/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44412/tests/kraken/driver.html?testName=ai-astar) 18:48:23 INFO - PID 6134 | RSS: Main: 159813632 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | __start_tp_report 18:48:23 INFO - PID 6134 | _x_x_mozilla_page_load 18:48:23 INFO - PID 6134 | _x_x_mozilla_page_load_details 18:48:23 INFO - PID 6134 | |i|pagename|runs| 18:48:23 INFO - PID 6134 | |0;ai-astar;108;107;107;106;105;106;107;108;107;106 18:48:23 INFO - PID 6134 | |1;audio-beat-detection;118;115;121;123;144;114;119;116;114;118 18:48:23 INFO - PID 6134 | |2;audio-dft;163;160;164;162;160;163;162;168;168;164 18:48:23 INFO - PID 6134 | |3;audio-fft;73;75;73;73;74;74;73;73;75;73 18:48:23 INFO - PID 6134 | |4;audio-oscillator;79;78;78;80;79;80;78;78;78;79 18:48:23 INFO - PID 6134 | |5;imaging-gaussian-blur;113;114;109;113;113;114;113;115;113;114 18:48:23 INFO - PID 6134 | |6;imaging-darkroom;154;148;148;148;147;147;148;147;147;147 18:48:23 INFO - PID 6134 | |7;imaging-desaturate;93;94;93;94;93;94;93;95;99;93 18:48:23 INFO - PID 6134 | |8;json-parse-financial;52;53;53;52;52;51;54;53;54;52 18:48:23 INFO - PID 6134 | |9;json-stringify-tinderbox;38;38;38;38;38;38;41;43;39;38 18:48:23 INFO - PID 6134 | |10;stanford-crypto-aes;78;72;74;73;73;74;73;75;71;79 18:48:23 INFO - PID 6134 | |11;stanford-crypto-ccm;110;114;110;121;115;115;116;114;114;113 18:48:23 INFO - PID 6134 | |12;stanford-crypto-pbkdf2;168;167;167;172;166;174;166;171;172;175 18:48:23 INFO - PID 6134 | |13;stanford-crypto-sha256-iterative;69;69;68;69;67;65;67;70;66;65 18:48:23 INFO - PID 6134 | __end_tp_report 18:48:23 INFO - PID 6134 | __start_cc_report 18:48:23 INFO - PID 6134 | _x_x_mozilla_cycle_collect,762 18:48:23 INFO - PID 6134 | __end_cc_report 18:48:23 INFO - PID 6134 | __startTimestamp1505958503059__endTimestamp 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | ------- Summary: start ------- 18:48:23 INFO - PID 6134 | Number of tests: 14 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#0] ai-astar Cycles:10 Average:106.70 Median:107.00 stddev:0.95 (0.9%) stddev-sans-first:0.88 18:48:23 INFO - PID 6134 | Values: 108.0 107.0 107.0 106.0 105.0 106.0 107.0 108.0 107.0 106.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#1] audio-beat-detection Cycles:10 Average:120.20 Median:118.50 stddev:8.87 (7.5%) stddev-sans-first:9.37 18:48:23 INFO - PID 6134 | Values: 118.0 115.0 121.0 123.0 144.0 114.0 119.0 116.0 114.0 118.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.50 stddev:2.80 (1.7%) stddev-sans-first:2.96 18:48:23 INFO - PID 6134 | Values: 163.0 160.0 164.0 162.0 160.0 163.0 162.0 168.0 168.0 164.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#3] audio-fft Cycles:10 Average:73.60 Median:73.50 stddev:0.84 (1.1%) stddev-sans-first:0.87 18:48:23 INFO - PID 6134 | Values: 73.0 75.0 73.0 73.0 74.0 74.0 73.0 73.0 75.0 73.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:79.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 18:48:23 INFO - PID 6134 | Values: 79.0 78.0 78.0 80.0 79.0 80.0 78.0 78.0 78.0 79.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.50 stddev:1.60 (1.4%) stddev-sans-first:1.69 18:48:23 INFO - PID 6134 | Values: 113.0 114.0 109.0 113.0 113.0 114.0 113.0 115.0 113.0 114.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:2.13 (1.4%) stddev-sans-first:0.53 18:48:23 INFO - PID 6134 | Values: 154.0 148.0 148.0 148.0 147.0 147.0 148.0 147.0 147.0 147.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:1.85 (2.0%) stddev-sans-first:1.92 18:48:23 INFO - PID 6134 | Values: 93.0 94.0 93.0 94.0 93.0 94.0 93.0 95.0 99.0 93.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:53.00 stddev:0.97 (1.8%) stddev-sans-first:1.00 18:48:23 INFO - PID 6134 | Values: 52.0 53.0 53.0 52.0 52.0 51.0 54.0 53.0 54.0 52.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#9] json-stringify-tinderbox Cycles:10 Average:38.90 Median:38.00 stddev:1.73 (4.5%) stddev-sans-first:1.80 18:48:23 INFO - PID 6134 | Values: 38.0 38.0 38.0 38.0 38.0 38.0 41.0 43.0 39.0 38.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:74.00 stddev:2.53 (3.4%) stddev-sans-first:2.28 18:48:23 INFO - PID 6134 | Values: 78.0 72.0 74.0 73.0 73.0 74.0 73.0 75.0 71.0 79.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#11] stanford-crypto-ccm Cycles:10 Average:114.20 Median:114.50 stddev:3.12 (2.7%) stddev-sans-first:2.92 18:48:23 INFO - PID 6134 | Values: 110.0 114.0 110.0 121.0 115.0 115.0 116.0 114.0 114.0 113.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:171.50 stddev:3.39 (2.0%) stddev-sans-first:3.54 18:48:23 INFO - PID 6134 | Values: 168.0 167.0 167.0 172.0 166.0 174.0 166.0 171.0 172.0 175.0 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.50 stddev:1.78 (2.6%) stddev-sans-first:1.80 18:48:23 INFO - PID 6134 | Values: 69.0 69.0 68.0 69.0 67.0 65.0 67.0 70.0 66.0 65.0 18:48:23 INFO - PID 6134 | -------- Summary: end -------- 18:48:23 INFO - PID 6134 | 18:48:23 INFO - PID 6134 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:48:23 INFO - TEST-INFO | 6134: exit 0 18:48:23 INFO - TEST-OK | kraken | took 60528ms 18:48:23 INFO - SUITE-END | took 1169s 18:48:23 INFO - Completed test suite (00:19:29) 18:48:23 INFO - javascript benchmark 18:48:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8522.720338458086, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3102.8758849646015, 3114.968850311496, 3134.9811311323824, 3149.9842500787495, 3158.384115097557, 6186.938130618692, 6271.310155882855, 6310.873782524347, 6312.305646378901, 6435.452986496153, 6155.784547540837, 6273.435390814826, 6280.0, 6309.716062777175, 6311.77908773193, 5770.2210201622665, 5786.623869448494, 5803.912941305884, 5808.796692115777, 5882.264716910387, 5861.443162899539, 5877.735501902424, 5877.7648894044405, 5913.083472061816, 5931.584789064767, 5832.224230746312, 5833.941660583403, 5903.822885313426, 5908.67502287374, 5920.5559583031445, 753.0236524650876, 5244.344456942882, 5650.773969041234, 5860.00379935412, 5878.265216847894, 5734.684592347419, 5738.684372359518, 5759.568032397587, 5818.301803783531, 5819.24349834523, 6142.447179753799, 6272.56092073555, 6302.4958003359625, 6339.36606339367, 6360.268569114564, 6657.76697815579, 6704.497162712767, 6709.228438729511, 6768.627725475097, 6796.048553202556, 6798.0, 6798.966005169992, 6829.214640316328, 6871.931280687179, 6889.517733758639, 6078.787242446493, 6221.3156552779155, 6247.187865577446, 6315.652639104847, 6317.241930968267, 6899.0, 6971.5468494547695, 6976.19773726028, 7004.3696067354185, 7016.684249208798, 6593.3736295051895, 6596.835079122964, 6668.233153187445, 6681.265060843303, 6691.498137639696, 6578.769743059066, 6720.361565651353, 6747.493937954673, 6791.083203767428, 6837.589744615339, 6616.4376028037195, 6698.966505167443, 6753.932460675429, 6757.932420675828, 6764.086848275519, 6744.190697116377, 6791.524593278423, 6794.898076528856, 6803.965980170067, 6807.2512023677355, 5282.7622756976025, 5350.892982140335, 5358.303420555303, 5364.034473794676, 5370.221317908881, 4521.525239849834, 4595.494495605481, 4605.539446055368, 4605.723656580549, 4617.607503362185, 5680.573956953245, 5736.024875771129, 5803.129530570363, 5811.128330750336, 5812.912806307909, 6776.593404395752, 6865.176178858469, 6881.243063263037, 6890.517663763489, 6891.241963384024, 5754.827355179351, 5767.625104368202, 5782.797602083991, 5840.678762668094, 5846.503047240947, 5681.857953551194, 5735.913961290584, 5764.221830052973, 5826.563007774434, 5906.0, 6383.170187875547, 6397.96801015992, 6464.191976003, 6535.0850880876715, 6556.901646475307, 6816.147981502312, 6816.7614133505085, 6874.072000279998, 6900.413464855544, 6908.861822763617, 6532.41208291256, 6539.640319782267, 6625.768098116543, 6628.469722422291, 6635.767748128792, 6431.228252609717, 6549.377809108127, 6551.050097735897, 6555.4100130986535, 6675.198976122896, 6754.527183097136, 6774.187097548326, 6807.965960170168, 6872.45020398357, 6885.827854303682, 6564.179477565304, 6608.074869518176, 6623.735050597922, 6665.600063996176, 6665.800025999228, 6574.572652777745, 6579.736810527525, 6650.467962562887, 6668.699908504032, 6670.132882725215, 6496.090547323473, 6511.641859697762, 6571.901421478778, 6584.440322572635, 6590.406863382319, 6475.190601174853, 6518.282988871126, 6581.769638062645, 6582.144321238208, 6615.669216539058, 6426.100345951477, 6458.095866578777, 6473.838154046187, 6475.870482590416, 6479.125318081999], "value": 6115.388959690057, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [864.6368525219408, 890.2343984173609, 894.4141587260347, 897.2059727141478, 897.4077109107988, 38848.41727374092, 40117.398239026435, 43004.784976075076, 43176.568234317645, 43383.34924976128, 39018.41472377917, 39242.41136382957, 39279.41080883782, 39411.40882886752, 39571.802140989326, 7265.854682906352, 7410.0, 7966.800829979239, 8136.674533018672, 8173.346132309401, 8056.718014869481, 8164.387670924675, 8164.959175204116, 8207.466514676556, 8212.425130240856, 8129.715459958903, 8189.140140285273, 8189.467684600511, 8190.385721070914, 8217.671293148267, 8094.797630059236, 8158.0, 8168.550729709864, 8178.386621003418, 8182.509049457051, 8063.1130575636635, 8108.270255677017, 8113.8782918256275, 8119.106898241189, 8126.796830079295, 38364.808175959224, 38472.0, 38511.229775404616, 38621.42067868984, 38648.61351386478, 3533.9293214135832, 3564.108972756811, 3581.4448760442174, 3583.7670551414335, 3583.9104022399647, 31448.0, 31510.369792604248, 31617.84191079053, 31698.207544811565, 31865.044048678577, 5088.847334579968, 5243.4232234454175, 5292.788288468457, 5317.9202311965355, 5348.973255133738, 185960.0, 187003.0, 187786.0, 188298.05850970795, 188596.0, 43559.78220108942, 44619.553804462186, 50241.0, 50459.495405045476, 50621.49378506168, 52055.0, 52653.0, 52695.0, 52752.0, 52771.47228527666, 16692.499225023268, 16696.081715505763, 16698.4155554555, 16700.83299166993, 16835.494935151964, 13740.312984350743, 13764.17414955106, 13767.518136865361, 13772.449102035805, 13782.241976691177, 13956.0, 14126.929365353108, 14189.574312770634, 14190.290485475685, 14358.138511689333, 5593.468620481048, 5613.382527921925, 5658.490735833712, 5667.291588551431, 5824.563157763185, 981.2003217377815, 984.9950750246203, 1002.1231422505308, 1028.0, 1033.4316126130598, 22856.885715571316, 23021.539569208522, 23131.768682312962, 23132.76867231306, 23439.765602344098, 1102.9613963511238, 1106.0653747583278, 1107.7950579142885, 1109.1016276815806, 1111.4442778610694, 11966.940165299291, 12015.0, 12026.759464810655, 12127.696807579881, 12157.69605759863, 224003.0, 225196.0, 226273.0, 226491.0, 226772.0, 54744.0, 55157.0, 55333.72333138309, 55412.16881746777, 55435.445645543834, 1434.4047220403625, 1466.6877795811693, 1468.6034770611875, 1481.185348058585, 1489.5382431446287, 22180.334589962327, 22207.44481387978, 22363.552728945655, 22398.440038999157, 22519.211827585958, 37989.430158548195, 38152.618473816015, 38162.80918595389, 38174.236515270095, 38219.808900955315, 188573.0, 191078.0, 191287.0, 191466.0, 191531.0, 31617.8419107903, 31976.761626685908, 32171.35657286888, 34365.65634343625, 34687.82656086703, 44497.55502445064, 44522.10955780801, 44613.55386446094, 44844.55155448404, 45560.77219613881, 32519.837400812845, 32532.83733581317, 32673.18317042093, 32917.50623740694, 33245.33509329848, 31813.8409307952, 31970.36059278848, 32099.197520062186, 32256.677433225366, 32338.676613234507, 35848.283034338645, 35924.82037589795, 36087.63912360843, 36180.27639447144, 36250.274994499436, 31047.84476077605, 31080.533791992686, 31549.053528394186, 31570.0, 32118.0, 23332.0, 23371.766282337638, 23388.18141365044, 23572.0, 23604.09508659472], "value": 17316.091063763382, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.2411940358131, 589.6579983609507, 596.126674421972, 598.4255115089513, 622.0637939900449, 21090.89454552727, 22338.776612233894, 22339.218127365544, 22401.55196896061, 22412.0, 18589.349372771954, 23551.882240588817, 23820.404489887762, 23928.641070383914, 23967.161149359777, 6401.551891367594, 6573.112629794989, 6585.80242592723, 6588.703508342112, 6590.0, 6364.2681091674585, 6396.968015159918, 6475.676216189196, 6487.448566871822, 6668.133142691443, 6625.0, 6634.701438435258, 6640.037194606779, 6643.6346000969925, 6644.667766611674, 6250.874982500324, 6594.637294948776, 6606.9008964865325, 6607.471402287805, 6615.867682646344, 6051.515878729691, 6076.149339092531, 6077.726502307362, 6078.878422431527, 6088.178095957033, 27424.45151096987, 27679.30801729953, 27750.444991100067, 27807.026754063714, 27897.86051069752, 2015.7379540659767, 2031.5022819409191, 2045.222815330165, 2047.1095073643014, 2049.7027930950076, 18338.541536461562, 18900.243990240375, 19257.614847702967, 19315.51711207231, 19422.22311107554, 2460.9753902460925, 2463.2733343663663, 2505.436276837715, 2512.0705339024494, 2524.962125568118, 115749.42125289404, 121309.0, 126466.36766816198, 129131.0, 135819.0, 14466.991328668459, 14724.26378681083, 14769.852301477062, 14952.375725340826, 15310.617234569003, 22964.770352296262, 23049.19327823518, 23139.88430057839, 23171.189008384627, 23186.188483403337, 9779.26655500826, 9785.217182625373, 9817.754556136153, 9825.361351512129, 9934.304598678023, 8879.600417981206, 8945.19493245611, 8994.86507702385, 8998.235150012191, 9057.547122643973, 8899.644014239488, 9044.457332560067, 9061.95469022664, 9107.316951228684, 9112.271018318519, 2571.781398581104, 2603.3621762668267, 2667.7599016088643, 2680.490706765708, 2694.3129501977264, 912.9315301352425, 943.2312665177858, 946.9952650236705, 954.7565370830393, 967.4340510801119, 18864.81135188658, 19043.047847607566, 19141.425757227305, 19234.422967311002, 19321.130549125322, 1005.5173516711873, 1006.2955930848289, 1015.167562598662, 1024.784795193003, 1031.4120951057976, 10468.371897686011, 10720.785584288271, 10749.462526873625, 10848.349099054081, 10952.39761813092, 491314.0, 511081.0, 513205.0, 519567.0, 527425.0, 113279.4336028331, 114358.0, 115673.0, 115981.42009289899, 116946.0, 1090.0407641275785, 1131.5982826096617, 1134.0304040045808, 1141.3323205924462, 1153.6135394642888, 16903.915480422518, 17159.227834747217, 17479.82520174782, 17529.298828046736, 17658.735118973484, 52586.737066314425, 54127.188092179436, 54529.182062269894, 54673.72663136818, 55919.72040139773, 66127.33872661405, 67059.3294067053, 67268.32731672621, 67835.3216467829, 69255.65372173107, 14264.857351426353, 14544.345504452536, 14568.125912445286, 14577.052491588012, 14578.635534111309, 22444.5511089774, 22677.546449071255, 22700.772992269867, 22718.0, 22750.544989100457, 17811.287548497916, 18147.54631134232, 18153.81846181521, 18177.09114544241, 18181.363652272106, 15168.544943651708, 15733.763993539877, 16026.198690065683, 16463.588410289838, 16613.916930415755, 17633.20650570753, 17909.373171938918, 18154.364597239033, 18172.000539970428, 18453.72319415183, 14295.0, 14327.426902923766, 14472.27638618086, 14472.855271447572, 14476.348564314841, 17953.102344882966, 17985.550361241072, 18050.638987220445, 18268.26926922908, 18269.634607307515], "value": 11727.433025443333, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1326.8938484921207, 1355.315565639352, 1363.8499765025852, 1365.6722386627212, 1368.5689007962492, 15191.0, 15818.920905395471, 15836.287367068495, 15859.444919427822, 15879.52361429156, 15570.14364209968, 15629.296681649324, 15661.530154095366, 15681.215939203052, 15687.137207453585, 2752.9587056194173, 2877.6834548199786, 2883.9711602884017, 2893.56596510523, 2893.6961619030017, 2808.031229225924, 2866.0398766413277, 2876.3384421582946, 2877.0505733108025, 2888.7688984881265, 2768.5155097857796, 2769.3492029373083, 2854.4861924853517, 2856.742893139617, 2857.27139579408, 2820.6756223034204, 2825.6185414969023, 2848.3448806774454, 2848.829070255791, 2850.800443968923, 2357.905683772647, 2428.3200703802963, 2804.9018284360154, 2810.9718902810914, 2814.605955166258, 15169.924150379287, 15358.078515289008, 15375.538733838002, 15402.0, 15405.845941540665, 8295.253427191521, 8599.82800343996, 8617.181367770052, 8626.051134375213, 8635.17965793255, 5833.329167145747, 5837.737301821428, 5931.673757943311, 6093.756249750005, 6148.754049838001, 1911.9713204301947, 1921.8462522998127, 1924.788273289937, 1945.5233467800479, 1971.3494546799523, 27576.586351204747, 27789.444211115868, 27891.721082789114, 27896.860515697495, 27928.0, 6958.47811414146, 7059.82350441243, 7094.964525177341, 7207.351338379571, 7211.675474603656, 17185.31258749636, 17192.656146876994, 17225.052622105653, 17238.482845514656, 17261.741073883903, 7891.723789667449, 8163.183681631789, 8164.959175204086, 8166.836663266701, 8167.428280020342, 7295.671694773748, 7494.775156745306, 7568.053993250844, 7628.0083589133055, 7640.083190017122, 5330.173823057432, 5558.110702287696, 5559.221708960748, 5583.246261754693, 5596.97201513998, 1612.7338989066907, 1637.2305016642158, 1641.5813967438467, 1645.9670806583802, 1647.0529445568845, 885.198895000026, 892.638481415028, 908.4957848394205, 911.40758506971, 918.0406475233397, 7335.303146201102, 7570.242975702496, 7633.618319084024, 7653.502522336142, 7656.885146722803, 715.6707914359349, 721.9422446204396, 730.6858051038105, 731.3125661877817, 739.1389031777918, 5042.168042273057, 5079.288899554065, 5161.74191290434, 5190.844274671766, 5224.190250511177, 21558.0, 21856.344309670418, 22337.218197363338, 22723.88638056832, 22786.772132278464, 22211.888940555193, 22299.0, 22344.776552234594, 22562.210322638628, 22579.548409032057, 10933.726656833484, 11505.539778408938, 11520.596779112691, 11559.364234967157, 11589.609246890426, 8721.825563488821, 8748.78128046804, 8832.779180520538, 8857.734267971713, 8941.597628106621, 18362.540936476697, 18826.435206943814, 18867.0, 19013.42959711211, 19187.0, 19124.80875191286, 19213.519662008563, 19251.13369898386, 19839.60320793605, 19938.800611994273, 12634.494620215088, 12749.426275817425, 12820.0, 12841.678958026125, 12850.614481565568, 14101.153930764187, 14276.71446571042, 14277.357518911882, 14368.425262989782, 14435.494757683431, 11629.651110466699, 11685.649430517098, 11853.288802671867, 12046.638600841989, 12051.517939282681, 11062.612808551663, 11178.161637877029, 11383.146264030396, 11544.365059921785, 11816.231944923553, 9983.301168918106, 10024.298299118991, 10060.899391005996, 10153.390796551936, 10179.7455063624, 7043.506954513135, 7135.250798666273, 7143.571385716874, 7197.208307086112, 7328.267173282629, 7257.346838784535, 7321.670524826289, 7364.963175184304, 7382.5570465772225, 7405.407567394488], "value": 6816.806536964531, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.48104091712634, 522.8144008876851, 532.9083347859653, 535.520708965476, 536.6216817143912, 6295.653739044351, 6313.0, 6344.619322840627, 6357.71390287437, 6387.616742995424, 6168.969155154229, 6186.2885768136675, 6269.717862696179, 6271.891910798423, 6294.43350098491, 5167.9224811627855, 5184.766685499152, 5187.766550505217, 5202.297689811865, 5210.973945130288, 4906.165951788204, 5148.227765835118, 5176.741162941857, 5250.343707036621, 5269.209618557228, 4963.528464795839, 4988.32657591226, 5064.215046667754, 5071.833478299994, 5082.923756143661, 4913.164761990471, 4955.950440495585, 4966.5778408835295, 5040.319556859814, 5048.924266136011, 4412.205802955466, 4453.732776033416, 4488.079943611585, 4518.5314772699, 4557.179707652621, 6020.247469066367, 6060.272767267912, 6065.909011364833, 6088.985951953767, 6234.968825155891, 5729.856753581152, 5740.799072032501, 5747.942520574783, 5749.195112684228, 5805.593608447411, 6467.83830404239, 6525.249596296392, 6535.2484464286745, 6553.508486863504, 6594.516233847394, 4921.114199444099, 4958.20668693011, 4960.553550180465, 4968.360441054443, 4990.925136122961, 6521.673916304213, 6731.461483081342, 6744.527883048188, 6803.931960680379, 6813.7955861324235, 5010.248462730619, 5055.570276526463, 5061.164907790173, 5068.0, 5090.9236361454605, 5159.948400515947, 5163.4062082860955, 5167.914737905082, 5168.560672342818, 5172.5344718975475, 5797.6375551745305, 5807.5063619591965, 5809.9419005809405, 5817.680027598438, 5831.183634291203, 6573.1783527059115, 6615.206175259, 6624.337566243434, 6638.966805165944, 6724.58783655428, 1775.245520653717, 1783.7324401339642, 1788.1685016467145, 1789.185920406212, 1792.3995461520276, 1213.5115206484907, 1244.160191870484, 1244.6141696074246, 1257.1639859493516, 1258.8670196822932, 5661.264035675335, 5679.636887147138, 5704.429557044263, 5708.400617935057, 5730.799422020293, 6595.241547222035, 6638.236602790645, 6683.665816709243, 6708.551378746723, 6723.260441351447, 5619.26949496571, 5636.520895723909, 5640.32316122068, 5663.66018038919, 5697.857553561112, 3409.991679344594, 3518.7185025197923, 3582.570091589026, 3590.856365745341, 3598.226381328027, 6373.840653983688, 6376.840578985562, 6427.935720642734, 6451.41937225652, 6475.546711730133, 6788.911045474298, 6814.114165158498, 6826.829329266808, 6845.877777888871, 6952.687129079102, 6386.680665966776, 6412.236634925433, 6425.646589437626, 6484.902726459107, 6543.887539118361, 6543.738250470118, 6572.009735570208, 6613.338666133348, 6736.663166841737, 6738.865222695421, 6568.507361947969, 6599.241087274832, 6704.66476676174, 6715.597064176165, 6759.343960729652, 6245.0, 6359.682015899094, 6392.232932048184, 6402.775902843378, 6446.742130314735, 5019.824306149268, 5081.898362032664, 5085.711429856653, 5089.422279093493, 5104.1067813133295, 6439.291677915332, 6538.771143009972, 6716.429103526255, 6852.0, 6857.759978400732, 6402.583832050902, 6531.860051551526, 6558.1802274715665, 6564.409203171737, 6579.342065793382, 6414.454771344488, 6496.512761542808, 6538.542302038812, 6561.0, 6629.602223866584, 6414.679266036586, 6519.576227545194, 6553.377429144223, 6554.606723596599, 6555.80332590023, 6645.800625981228, 6653.0, 6677.362441835545, 6691.063251144941, 6694.564853284521, 6281.371862813682, 6331.556791024584, 6333.746650133943, 6421.8715625688155, 6449.5166111793615], "value": 5081.265657821803, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.7456390263409, 775.6005657086598, 784.6508303804806, 907.9455232686038, 935.6538080910065, 19328.90335548324, 19608.607827843436, 19744.70382944255, 19766.01169941501, 19884.801151988493, 17735.55661108473, 17793.0, 17891.552711182227, 17918.28326866927, 17990.0, 951.3958636265969, 961.3270710502676, 964.9228061755043, 965.6813251626946, 966.8356379415482, 967.5162418790604, 969.4910172159603, 971.7424882406133, 973.7176218896511, 974.5224839828469, 989.2975987049203, 1022.4018948914857, 1023.3655133817044, 1029.5058371981454, 1041.828098363769, 1028.686250693538, 1029.0686928329874, 1029.1046789293325, 1030.1295405382439, 1032.2155162076838, 724.6811402982723, 757.7310054930475, 759.8594260061906, 760.4182800157884, 762.20349734527, 19313.42059738196, 19344.226230950746, 19354.32259870898, 19430.417087487396, 19507.317243896538, 21322.253721119687, 21337.0, 22190.667139992915, 22249.33252002426, 22262.220822271305, 17685.469435916944, 17822.10889455535, 17903.820961790476, 17978.460646180505, 18146.274149034023, 3782.0, 3811.27585758705, 3811.80940952954, 3815.04623844039, 3815.389537673959, 104237.0, 133756.33121834425, 134788.32605837006, 136252.0, 137161.3141934294, 17690.734638980426, 18026.188821503063, 18351.724724129148, 18380.724289135673, 18449.0, 31446.528302075487, 32717.345653087283, 32893.50659740106, 32932.0, 32954.34091318208, 9015.188633023059, 9328.860067098998, 9358.15776580111, 9377.812443751223, 9402.623895044122, 4223.978880105641, 4316.69783115179, 4346.6088052075465, 4347.260965635849, 4348.717333373384, 5653.208550802891, 5749.195112684228, 5752.597318187687, 5757.481826635623, 5771.336296325892, 1128.3004537186996, 1128.8927551882557, 1132.0773569540797, 1134.5575225661999, 1138.5502726423017, 685.7736946807541, 696.9581825090511, 703.9190493093257, 708.387245033053, 713.3187805645699, 11383.943080284545, 11426.657200284004, 11457.770844583061, 12138.514459421702, 12229.3273869938, 676.5331920974512, 679.4224908827457, 689.6586189836063, 689.9689513971883, 690.7133539581017, 5315.627906046617, 5332.226827110125, 5385.569154467633, 5459.945400546023, 5461.535769459561, 114000.0, 115461.0, 118571.0, 120438.39780801215, 120784.39607801905, 104212.0, 104821.47589262004, 107219.0, 107678.0, 110151.0, 89573.55213224022, 90149.0, 92193.53903230575, 93426.0, 94143.0, 9845.753856153653, 9942.850857237292, 10002.399856008662, 10007.09936105754, 10066.748331291776, 41535.79232103941, 41834.790826045675, 42151.0, 42565.787171063945, 43316.35025474557, 55567.0, 56698.43301566932, 57530.712346438, 57774.13338800005, 57785.71107144438, 15343.846561534241, 15370.846291536942, 15443.305051272944, 15454.690906182039, 15618.45335413255, 17156.914215428842, 17174.742378864576, 17203.569910752332, 17307.740383894503, 17426.73859892128, 15114.395424182909, 15330.233488325315, 15361.38554457854, 15363.23183840826, 15455.922720386327, 17019.14904254807, 17124.48626541206, 17178.65642687114, 17358.39245626397, 17368.0, 15426.92286538605, 15499.070055796688, 15525.767113493532, 15730.685386292438, 15732.370705171665, 11150.0, 11357.48891299877, 11478.0, 11494.080473561928, 11525.596604118815, 11615.709607259887, 11699.766004680028, 11715.707107322045, 11990.280583165038, 12039.097067720131], "value": 8908.700148545626, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1415.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 107.0, 106.0, 105.0, 106.0, 107.0, 108.0, 107.0, 106.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 121.0, 123.0, 144.0, 114.0, 119.0, 116.0, 114.0, 118.0], "value": 120.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 164.0, 162.0, 160.0, 163.0, 162.0, 168.0, 168.0, 164.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 73.0, 73.0, 74.0, 74.0, 73.0, 73.0, 75.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 80.0, 79.0, 80.0, 78.0, 78.0, 78.0, 79.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 109.0, 113.0, 113.0, 114.0, 113.0, 115.0, 113.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 148.0, 148.0, 148.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 93.0, 95.0, 99.0, 93.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 52.0, 52.0, 51.0, 54.0, 53.0, 54.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 41.0, 43.0, 39.0, 38.0], "value": 38.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 74.0, 73.0, 73.0, 74.0, 73.0, 75.0, 71.0, 79.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 110.0, 121.0, 115.0, 115.0, 116.0, 114.0, 114.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 167.0, 172.0, 166.0, 174.0, 166.0, 171.0, 172.0, 175.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 69.0, 67.0, 65.0, 67.0, 70.0, 66.0, 65.0], "value": 67.5, "unit": "score"}]}]} 18:48:23 INFO - Return code: 0 18:48:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:48:23 INFO - # TBPL SUCCESS # 18:48:23 INFO - Running post-action listener: _package_coverage_data 18:48:23 INFO - Running post-action listener: _resource_record_post_action 18:48:23 INFO - [mozharness: 2017-09-21 01:48:23.877578Z] Finished run-tests step (success) 18:48:23 INFO - Running post-run listener: _resource_record_post_run 18:48:23 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 342589440; Read time: 116; Write time: 224580 18:48:23 INFO - TinderboxPrint: CPU usage
13.1% 18:48:23 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 116 18:48:23 INFO - TinderboxPrint: I/O write bytes / time
342,589,440 / 224,580 18:48:23 INFO - TinderboxPrint: CPU idle
8,223.3 (86.9%) 18:48:23 INFO - TinderboxPrint: CPU user
1,191.7 (12.6%) 18:48:23 INFO - TinderboxPrint: Swap in / out
0 / 0 18:48:23 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10133504; Read time: 0; Write time: 556 18:48:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:48:23 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 4096; Write bytes: 332455936; Read time: 88; Write time: 224024 18:48:24 INFO - Running post-run listener: _upload_blobber_files 18:48:24 INFO - Blob upload gear active. 18:48:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:48:24 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 18:48:24 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:48:24 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:48:24 INFO - (blobuploader) - INFO - Open directory for files ... 18:48:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:48:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:48:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:31 INFO - (blobuploader) - INFO - Done attempting. 18:48:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:48:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:48:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:32 INFO - (blobuploader) - INFO - Done attempting. 18:48:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:48:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:48:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:33 INFO - (blobuploader) - INFO - Done attempting. 18:48:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:48:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:48:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:34 INFO - (blobuploader) - INFO - Done attempting. 18:48:34 INFO - (blobuploader) - INFO - Iteration through files over. 18:48:34 INFO - Return code: 0 18:48:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:48:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:48:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a984c9f5c39a2cda8ed59e1094895f421e9302c06d0ac8235249eb695ce494b727c274b52c6556776d4fac3c2f1bf92baf4a37e2a5af4225aea0a85ccaca8171", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac1a9d9417b678af4cf6aa8878127f5fdd4d3fbf7461186349c613279fe61e5b153e8ded5d4b25bcdfe68e921f2f92c86adacee3b83f25a40ce2ce622d03c165", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7d901d0b77a4909e54acc5e42a2e0b306981aafb3c4055c5ba060caf24f208bb3e3d17149a6d93c8d5f278d98e9dda3d64cad9976f366bb289511dfc4f34ab8d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62b17ec4cc212e96b6a2f3635f80d92cab794a1deff5db00433825713c20d79eca768a72b99aaf461b03159ef9d6ddb16c0affbef9865925c2643fa9328eb6d0"} 18:48:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:48:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:48:34 INFO - Contents: 18:48:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a984c9f5c39a2cda8ed59e1094895f421e9302c06d0ac8235249eb695ce494b727c274b52c6556776d4fac3c2f1bf92baf4a37e2a5af4225aea0a85ccaca8171", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac1a9d9417b678af4cf6aa8878127f5fdd4d3fbf7461186349c613279fe61e5b153e8ded5d4b25bcdfe68e921f2f92c86adacee3b83f25a40ce2ce622d03c165", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7d901d0b77a4909e54acc5e42a2e0b306981aafb3c4055c5ba060caf24f208bb3e3d17149a6d93c8d5f278d98e9dda3d64cad9976f366bb289511dfc4f34ab8d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62b17ec4cc212e96b6a2f3635f80d92cab794a1deff5db00433825713c20d79eca768a72b99aaf461b03159ef9d6ddb16c0affbef9865925c2643fa9328eb6d0"} 18:48:34 INFO - Running post-run listener: copy_logs_to_upload_dir 18:48:34 INFO - Copying logs to upload dir... 18:48:34 INFO - mkdir: /builds/slave/test/build/upload/logs 18:48:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.618335 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-20 18:48:34.963963) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:34.967400) ========= 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=3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a984c9f5c39a2cda8ed59e1094895f421e9302c06d0ac8235249eb695ce494b727c274b52c6556776d4fac3c2f1bf92baf4a37e2a5af4225aea0a85ccaca8171", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac1a9d9417b678af4cf6aa8878127f5fdd4d3fbf7461186349c613279fe61e5b153e8ded5d4b25bcdfe68e921f2f92c86adacee3b83f25a40ce2ce622d03c165", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7d901d0b77a4909e54acc5e42a2e0b306981aafb3c4055c5ba060caf24f208bb3e3d17149a6d93c8d5f278d98e9dda3d64cad9976f366bb289511dfc4f34ab8d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62b17ec4cc212e96b6a2f3635f80d92cab794a1deff5db00433825713c20d79eca768a72b99aaf461b03159ef9d6ddb16c0affbef9865925c2643fa9328eb6d0"} build_url:https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011436 build_url: 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a984c9f5c39a2cda8ed59e1094895f421e9302c06d0ac8235249eb695ce494b727c274b52c6556776d4fac3c2f1bf92baf4a37e2a5af4225aea0a85ccaca8171", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac1a9d9417b678af4cf6aa8878127f5fdd4d3fbf7461186349c613279fe61e5b153e8ded5d4b25bcdfe68e921f2f92c86adacee3b83f25a40ce2ce622d03c165", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7d901d0b77a4909e54acc5e42a2e0b306981aafb3c4055c5ba060caf24f208bb3e3d17149a6d93c8d5f278d98e9dda3d64cad9976f366bb289511dfc4f34ab8d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62b17ec4cc212e96b6a2f3635f80d92cab794a1deff5db00433825713c20d79eca768a72b99aaf461b03159ef9d6ddb16c0affbef9865925c2643fa9328eb6d0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:34.998075) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:34.998466) ========= 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=3836458cccd887f2d2de3cf1000001de-1505957275.113246-691147747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:48:35.084128) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 18:48:35.084455) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 18:48:35.084781) ========= ========= Total master_lag: 0.21 =========