builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-032 starttime: 1505429426.09 results: success (0) revision: b778b6cb49c8ed1dab244c00dc4dc3cf88b25ffe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:26.091812) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:26.092475) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:26.092736) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:26.230656) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:26.230938) ========= 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=745a4373a2a16df560f216e000000264-1505427955.148683-1209847522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-14 15:50:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-09-14 15:50:26 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.166267 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:26.424340) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:26.427652) ========= 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=745a4373a2a16df560f216e000000264-1505427955.148683-1209847522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024483 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:26.507001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-14 15:50:26.507289) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b778b6cb49c8ed1dab244c00dc4dc3cf88b25ffe --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 b778b6cb49c8ed1dab244c00dc4dc3cf88b25ffe --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=745a4373a2a16df560f216e000000264-1505427955.148683-1209847522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-14 15:50:26,700 truncating revision to first 12 chars 2017-09-14 15:50:26,700 Setting DEBUG logging. 2017-09-14 15:50:26,700 attempt 1/10 2017-09-14 15:50:26,700 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b778b6cb49c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-14 15:50:27,744 unpacking tar archive at: mozilla-central-b778b6cb49c8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.369457 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-14 15:50:27.896017) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:27.896338) ========= script_repo_revision: b778b6cb49c8ed1dab244c00dc4dc3cf88b25ffe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:27.899761) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:27.900027) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 15:50:27.914388) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-14 15:50:27.914807) ========= /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=745a4373a2a16df560f216e000000264-1505427955.148683-1209847522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:50:28 INFO - MultiFileLogger online at 20170914 15:50:28 in /builds/slave/test 15:50:28 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 15:50:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:28 INFO - {'append_to_log': False, 15:50:28 INFO - 'base_work_dir': '/builds/slave/test', 15:50:28 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:50:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:28 INFO - 'branch': 'Firefox-Non-PGO', 15:50:28 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:28 INFO - 'code_coverage': False, 15:50:28 INFO - 'config_files': ('talos/linux_config.py',), 15:50:28 INFO - 'default_actions': ('clobber', 15:50:28 INFO - 'read-buildbot-config', 15:50:28 INFO - 'download-and-extract', 15:50:28 INFO - 'populate-webroot', 15:50:28 INFO - 'create-virtualenv', 15:50:28 INFO - 'install', 15:50:28 INFO - 'setup-mitmproxy', 15:50:28 INFO - 'run-tests'), 15:50:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:28 INFO - 'disable_ccov_upload': False, 15:50:28 INFO - 'disable_stylo': False, 15:50:28 INFO - 'download_minidump_stackwalk': True, 15:50:28 INFO - 'download_symbols': 'ondemand', 15:50:28 INFO - 'enable_stylo': False, 15:50:28 INFO - 'enable_webrender': False, 15:50:28 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:50:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:28 INFO - 'gecko_profile': False, 15:50:28 INFO - 'gecko_profile_interval': 0, 15:50:28 INFO - 'installer_path': 'installer.exe', 15:50:28 INFO - 'log_level': 'info', 15:50:28 INFO - 'log_name': 'talos', 15:50:28 INFO - 'log_to_console': True, 15:50:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:50:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:50:28 INFO - 'opt_config_files': (), 15:50:28 INFO - 'pip_index': False, 15:50:28 INFO - 'suite': 'dromaeojs-e10s', 15:50:28 INFO - 'system_bits': '32', 15:50:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:28 INFO - 'title': 'talos-linux64-ix-032', 15:50:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:28 INFO - 'use_talos_json': True, 15:50:28 INFO - 'verify': 'False', 15:50:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:28 INFO - 'work_dir': 'build'} 15:50:28 INFO - [mozharness: 2017-09-14 22:50:28.241561Z] Running clobber step. 15:50:28 INFO - Running pre-action listener: _resource_record_pre_action 15:50:28 INFO - Running main action method: clobber 15:50:28 INFO - rmtree: /builds/slave/test/build 15:50:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:29 INFO - Running post-action listener: _resource_record_post_action 15:50:29 INFO - [mozharness: 2017-09-14 22:50:29.244496Z] Finished clobber step (success) 15:50:29 INFO - [mozharness: 2017-09-14 22:50:29.244594Z] Running read-buildbot-config step. 15:50:29 INFO - Running pre-action listener: _resource_record_pre_action 15:50:29 INFO - Running main action method: read_buildbot_config 15:50:29 INFO - Using buildbot properties: 15:50:29 INFO - { 15:50:29 INFO - "project": "", 15:50:29 INFO - "product": "firefox", 15:50:29 INFO - "who": "archaeopteryx@coole-files.de", 15:50:29 INFO - "installer_path": "public/build/target.tar.bz2", 15:50:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:50:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:50:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:50:29 INFO - "stage_platform": "linux64", 15:50:29 INFO - "basedir": "/builds/slave/test", 15:50:29 INFO - "buildnumber": 827, 15:50:29 INFO - "platform": "ubuntu64_hw", 15:50:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:50:29 INFO - "slavebuilddir": "test", 15:50:29 INFO - "taskId": "ZNT0vqP2TYau4PHblCnAkg", 15:50:29 INFO - "branch": "mozilla-central", 15:50:29 INFO - "script_repo_revision": "production", 15:50:29 INFO - "revision": "b778b6cb49c8ed1dab244c00dc4dc3cf88b25ffe", 15:50:29 INFO - "slavename": "talos-linux64-ix-032", 15:50:29 INFO - "repo_path": "mozilla-central" 15:50:29 INFO - } 15:50:29 INFO - Finding installer, test and symbols from parent task. 15:50:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZNT0vqP2TYau4PHblCnAkg'}, attempt #1 15:50:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZNT0vqP2TYau4PHblCnAkg'}, attempt #1 15:50:30 INFO - Task dependencies: Nu1bmwwJTLu1t2PmdT-yrg 15:50:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg'}, attempt #1 15:50:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.tar.bz2 15:50:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.test_packages.json 15:50:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.crashreporter-symbols.zip 15:50:30 INFO - Running post-action listener: _resource_record_post_action 15:50:30 INFO - [mozharness: 2017-09-14 22:50:30.598413Z] Finished read-buildbot-config step (success) 15:50:30 INFO - [mozharness: 2017-09-14 22:50:30.598630Z] Running download-and-extract step. 15:50:30 INFO - Running pre-action listener: _resource_record_pre_action 15:50:30 INFO - Running main action method: download_and_extract 15:50:30 INFO - mkdir: /builds/slave/test/build/tests 15:50: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')]} 15:50:30 INFO - trying https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.test_packages.json 15:50:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:50:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:50:31 INFO - Downloaded 989 bytes. 15:50:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:50:31 INFO - Using the following test package requirements: 15:50:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:50:31 INFO - u'common': [u'target.common.tests.zip'], 15:50:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:50:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:50:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:50:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:50:31 INFO - u'mozbase': [u'target.common.tests.zip'], 15:50:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:50:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:50:31 INFO - u'web-platform': [u'target.common.tests.zip', 15:50:31 INFO - u'target.web-platform.tests.tar.gz'], 15:50:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:50:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:50:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.common.tests.zip 15:50:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:50:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.common.tests.zip into memory 15:50:33 INFO - Content-Length response header: 54593789 15:50:33 INFO - Bytes received: 54593789 15:50:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.talos.tests.zip 15:50:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:50:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.talos.tests.zip into memory 15:50:44 INFO - Content-Length response header: 14500356 15:50:44 INFO - Bytes received: 14500356 15:50:45 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')]} 15:50:45 INFO - trying https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.tar.bz2 15:50:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:50:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:50:48 INFO - Downloaded 61400593 bytes. 15:50:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.tar.bz2 15:50:48 INFO - mkdir: /builds/slave/test/properties 15:50:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:50:48 INFO - Writing to file /builds/slave/test/properties/build_url 15:50:48 INFO - Contents: 15:50:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.tar.bz2 15:50:48 INFO - Running post-action listener: _resource_record_post_action 15:50:48 INFO - Running post-action listener: find_tests_for_verification 15:50:48 INFO - Running post-action listener: set_extra_try_arguments 15:50:48 INFO - [mozharness: 2017-09-14 22:50:48.493052Z] Finished download-and-extract step (success) 15:50:48 INFO - [mozharness: 2017-09-14 22:50:48.493279Z] Running populate-webroot step. 15:50:48 INFO - Running pre-action listener: _resource_record_pre_action 15:50:48 INFO - Running main action method: populate_webroot 15:50:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:50:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['tresize', 15:50:48 INFO - 'tcanvasmark']}, 15:50:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:50:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['dromaeo_css', 15:50:48 INFO - 'kraken']}, 15:50:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:50:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:48 INFO - 'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:50:48 INFO - 'tests': ['damp', 'tps']}, 15:50:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:48 INFO - 'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['damp', 'tps']}, 15:50:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:50:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['dromaeo_dom']}, 15:50:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:50:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['basic_compositor_video', 15:50:48 INFO - 'glvideo']}, 15:50:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:50:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:50:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:48 INFO - 'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['ts_paint_webext', 15:50:48 INFO - 'tp5o_webext']}, 15:50:48 INFO - 'other-e10s': {'tests': ['a11yr', 15:50:48 INFO - 'ts_paint', 15:50:48 INFO - 'tpaint', 15:50:48 INFO - 'sessionrestore', 15:50:48 INFO - 'sessionrestore_many_windows', 15:50:48 INFO - 'sessionrestore_no_auto_restore', 15:50:48 INFO - 'tabpaint']}, 15:50:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['a11yr', 15:50:48 INFO - 'ts_paint', 15:50:48 INFO - 'tpaint', 15:50:48 INFO - 'sessionrestore', 15:50:48 INFO - 'sessionrestore_many_windows', 15:50:48 INFO - 'sessionrestore_no_auto_restore', 15:50:48 INFO - 'tabpaint']}, 15:50:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:50:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:50:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['bloom_basic_singleton']}, 15:50:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['bloom_basic']}, 15:50:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:50:48 INFO - 'tsvgr_opacity', 15:50:48 INFO - 'tart', 15:50:48 INFO - 'tscrollx', 15:50:48 INFO - 'tsvg_static']}, 15:50:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['tsvgx', 15:50:48 INFO - 'tsvgr_opacity', 15:50:48 INFO - 'tart', 15:50:48 INFO - 'tscrollx', 15:50:48 INFO - 'tsvg_static']}, 15:50:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:50:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:48 INFO - 'talos_options': ['--disable-stylo'], 15:50:48 INFO - 'tests': ['tp5o']}, 15:50:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:48 INFO - 'talos_options': ['--mitmproxy', 15:50:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:48 INFO - '--firstNonBlankPaint'], 15:50:48 INFO - 'tests': ['tp6_google', 15:50:48 INFO - 'tp6_youtube', 15:50:48 INFO - 'tp6_amazon', 15:50:48 INFO - 'tp6_facebook']}, 15:50:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:48 INFO - 'talos_options': ['--disable-stylo', 15:50:48 INFO - '--mitmproxy', 15:50:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:48 INFO - '--firstNonBlankPaint'], 15:50:48 INFO - 'tests': ['tp6_google', 15:50:48 INFO - 'tp6_youtube', 15:50:48 INFO - 'tp6_amazon', 15:50:48 INFO - 'tp6_facebook']}, 15:50:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:48 INFO - 'talos_options': ['--stylo-threads=1', 15:50:48 INFO - '--mitmproxy', 15:50:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:48 INFO - '--firstNonBlankPaint'], 15:50:48 INFO - 'tests': ['tp6_google', 15:50:48 INFO - 'tp6_youtube', 15:50:48 INFO - 'tp6_amazon', 15:50:48 INFO - 'tp6_facebook']}, 15:50:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:50:48 INFO - 'talos_options': ['--xperf_path', 15:50:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:48 INFO - 'tests': ['tp5n']}, 15:50:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:48 INFO - 'talos_options': ['--disable-stylo', 15:50:48 INFO - '--xperf_path', 15:50:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:48 INFO - 'tests': ['tp5n']}}} 15:50:48 INFO - Running post-action listener: _resource_record_post_action 15:50:48 INFO - [mozharness: 2017-09-14 22:50:48.516871Z] Finished populate-webroot step (success) 15:50:48 INFO - [mozharness: 2017-09-14 22:50:48.516961Z] Running create-virtualenv step. 15:50:48 INFO - Running pre-action listener: _resource_record_pre_action 15:50:48 INFO - Running main action method: create_virtualenv 15:50:48 INFO - Creating virtualenv /builds/slave/test/build/venv 15:50:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:50:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:50:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:50:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:50:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:50:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:50:49 INFO - Installing pip...............done. 15:50:49 INFO - Return code: 0 15:50:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:50:49 INFO - Reading from file tmpfile_stdout 15:50:49 INFO - Output received: 15:50:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:50:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:50:49 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')]} 15:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:49 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')]} 15:50:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:49 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 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:49 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 15:50:49 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 15:50:49 INFO - Using env: {'DISPLAY': ':0', 15:50:49 INFO - 'HOME': '/home/cltbld', 15:50:49 INFO - 'LANG': 'en_US.UTF-8', 15:50:49 INFO - 'LANGUAGE': 'en_US:en', 15:50:49 INFO - 'LOGNAME': 'cltbld', 15:50:49 INFO - 'MAIL': '/var/mail/cltbld', 15:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:49 INFO - 'MOZ_NO_REMOTE': '1', 15:50:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:49 INFO - 'NO_EM_RESTART': '1', 15:50:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:49 INFO - 'PWD': '/builds/slave/test', 15:50:49 INFO - 'SHELL': '/bin/bash', 15:50:49 INFO - 'SHLVL': '1', 15:50:49 INFO - 'TERM': 'linux', 15:50:49 INFO - 'TMOUT': '86400', 15:50:49 INFO - 'USER': 'cltbld', 15:50:49 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:49 INFO - '_': '/tools/buildbot/bin/python'} 15:50:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:50:50 INFO - Downloading/unpacking pip>=1.5 15:50:50 INFO - Running setup.py egg_info for package pip 15:50:50 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:50 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:50 INFO - Installing collected packages: pip 15:50:50 INFO - Found existing installation: pip 1.1 15:50:50 INFO - Uninstalling pip: 15:50:50 INFO - Successfully uninstalled pip 15:50:50 INFO - Running setup.py install for pip 15:50:50 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:50 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:50:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:50:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:50:50 INFO - Successfully installed pip 15:50:50 INFO - Cleaning up... 15:50:50 INFO - Return code: 0 15:50:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:50:50 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')]} 15:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:50 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')]} 15:50:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:50 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 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:50 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 15:50:50 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 15:50:50 INFO - Using env: {'DISPLAY': ':0', 15:50:50 INFO - 'HOME': '/home/cltbld', 15:50:50 INFO - 'LANG': 'en_US.UTF-8', 15:50:50 INFO - 'LANGUAGE': 'en_US:en', 15:50:50 INFO - 'LOGNAME': 'cltbld', 15:50:50 INFO - 'MAIL': '/var/mail/cltbld', 15:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:50 INFO - 'MOZ_NO_REMOTE': '1', 15:50:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:50 INFO - 'NO_EM_RESTART': '1', 15:50:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:50 INFO - 'PWD': '/builds/slave/test', 15:50:50 INFO - 'SHELL': '/bin/bash', 15:50:50 INFO - 'SHLVL': '1', 15:50:50 INFO - 'TERM': 'linux', 15:50:50 INFO - 'TMOUT': '86400', 15:50:50 INFO - 'USER': 'cltbld', 15:50:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:50:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:50 INFO - '_': '/tools/buildbot/bin/python'} 15:50:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:50 INFO - Downloading/unpacking psutil>=3.1.1 15:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:50:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:52 INFO - Installing collected packages: psutil 15:50:52 INFO - Running setup.py install for psutil 15:50:52 INFO - building 'psutil._psutil_linux' extension 15:50:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:50:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:50:52 INFO - building 'psutil._psutil_posix' extension 15:50:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:50:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:50:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:52 INFO - Successfully installed psutil 15:50:52 INFO - Cleaning up... 15:50:52 INFO - Return code: 0 15:50:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:50:52 INFO - Using env: {'DISPLAY': ':0', 15:50:52 INFO - 'HOME': '/home/cltbld', 15:50:52 INFO - 'LANG': 'en_US.UTF-8', 15:50:52 INFO - 'LANGUAGE': 'en_US:en', 15:50:52 INFO - 'LOGNAME': 'cltbld', 15:50:52 INFO - 'MAIL': '/var/mail/cltbld', 15:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:52 INFO - 'MOZ_NO_REMOTE': '1', 15:50:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:52 INFO - 'NO_EM_RESTART': '1', 15:50:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:52 INFO - 'PWD': '/builds/slave/test', 15:50:52 INFO - 'SHELL': '/bin/bash', 15:50:52 INFO - 'SHLVL': '1', 15:50:52 INFO - 'TERM': 'linux', 15:50:52 INFO - 'TMOUT': '86400', 15:50:52 INFO - 'USER': 'cltbld', 15:50:52 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:52 INFO - '_': '/tools/buildbot/bin/python'} 15:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:50:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:50:54 INFO - Installing collected packages: mozsystemmonitor 15:50:54 INFO - Running setup.py install for mozsystemmonitor 15:50:54 INFO - Successfully installed mozsystemmonitor 15:50:54 INFO - Cleaning up... 15:50:55 INFO - Return code: 0 15:50:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:50:55 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')]} 15:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:55 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')]} 15:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:55 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 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:55 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 15:50:55 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 15:50:55 INFO - Using env: {'DISPLAY': ':0', 15:50:55 INFO - 'HOME': '/home/cltbld', 15:50:55 INFO - 'LANG': 'en_US.UTF-8', 15:50:55 INFO - 'LANGUAGE': 'en_US:en', 15:50:55 INFO - 'LOGNAME': 'cltbld', 15:50:55 INFO - 'MAIL': '/var/mail/cltbld', 15:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:55 INFO - 'MOZ_NO_REMOTE': '1', 15:50:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:55 INFO - 'NO_EM_RESTART': '1', 15:50:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:55 INFO - 'PWD': '/builds/slave/test', 15:50:55 INFO - 'SHELL': '/bin/bash', 15:50:55 INFO - 'SHLVL': '1', 15:50:55 INFO - 'TERM': 'linux', 15:50:55 INFO - 'TMOUT': '86400', 15:50:55 INFO - 'USER': 'cltbld', 15:50:55 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:55 INFO - '_': '/tools/buildbot/bin/python'} 15:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:55 INFO - Downloading/unpacking jsonschema==2.5.1 15:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:50:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:57 INFO - Downloading functools32-3.2.3-2.tar.gz 15:50:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:50:57 INFO - warning: no files found matching '*.txt' 15:50:57 INFO - no previously-included directories found matching 'build' 15:50:57 INFO - no previously-included directories found matching 'dist' 15:50:57 INFO - no previously-included directories found matching '.git*' 15:50:57 INFO - Installing collected packages: jsonschema, functools32 15:50:57 INFO - Running setup.py install for functools32 15:50:57 INFO - warning: no files found matching '*.txt' 15:50:57 INFO - no previously-included directories found matching 'build' 15:50:57 INFO - no previously-included directories found matching 'dist' 15:50:57 INFO - no previously-included directories found matching '.git*' 15:50:57 INFO - Successfully installed jsonschema functools32 15:50:57 INFO - Cleaning up... 15:50:57 INFO - Return code: 0 15:50:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:50:57 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')]} 15:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:57 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')]} 15:50:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:57 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 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:57 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 15:50:57 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 15:50:57 INFO - Using env: {'DISPLAY': ':0', 15:50:57 INFO - 'HOME': '/home/cltbld', 15:50:57 INFO - 'LANG': 'en_US.UTF-8', 15:50:57 INFO - 'LANGUAGE': 'en_US:en', 15:50:57 INFO - 'LOGNAME': 'cltbld', 15:50:57 INFO - 'MAIL': '/var/mail/cltbld', 15:50:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:57 INFO - 'MOZ_NO_REMOTE': '1', 15:50:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:57 INFO - 'NO_EM_RESTART': '1', 15:50:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:57 INFO - 'PWD': '/builds/slave/test', 15:50:57 INFO - 'SHELL': '/bin/bash', 15:50:57 INFO - 'SHLVL': '1', 15:50:57 INFO - 'TERM': 'linux', 15:50:57 INFO - 'TMOUT': '86400', 15:50:57 INFO - 'USER': 'cltbld', 15:50:57 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:50:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:57 INFO - '_': '/tools/buildbot/bin/python'} 15:50:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:50:58 INFO - Cleaning up... 15:50:58 INFO - Return code: 0 15:50:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:50:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:50:58 INFO - Using env: {'DISPLAY': ':0', 15:50:58 INFO - 'HOME': '/home/cltbld', 15:50:58 INFO - 'LANG': 'en_US.UTF-8', 15:50:58 INFO - 'LANGUAGE': 'en_US:en', 15:50:58 INFO - 'LOGNAME': 'cltbld', 15:50:58 INFO - 'MAIL': '/var/mail/cltbld', 15:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:58 INFO - 'MOZ_NO_REMOTE': '1', 15:50:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:58 INFO - 'NO_EM_RESTART': '1', 15:50:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:58 INFO - 'PWD': '/builds/slave/test', 15:50:58 INFO - 'SHELL': '/bin/bash', 15:50:58 INFO - 'SHLVL': '1', 15:50:58 INFO - 'TERM': 'linux', 15:50:58 INFO - 'TMOUT': '86400', 15:50:58 INFO - 'USER': 'cltbld', 15:50:58 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:58 INFO - '_': '/tools/buildbot/bin/python'} 15:50:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:58 INFO - Downloading/unpacking blobuploader==1.2.4 15:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:00 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:51:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:00 INFO - Downloading docopt-0.6.1.tar.gz 15:51:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:00 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:00 INFO - Running setup.py install for blobuploader 15:51:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:51:00 INFO - Running setup.py install for requests 15:51:01 INFO - Running setup.py install for docopt 15:51:01 INFO - Successfully installed blobuploader requests docopt 15:51:01 INFO - Cleaning up... 15:51:01 INFO - Return code: 0 15:51:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:01 INFO - Using env: {'DISPLAY': ':0', 15:51:01 INFO - 'HOME': '/home/cltbld', 15:51:01 INFO - 'LANG': 'en_US.UTF-8', 15:51:01 INFO - 'LANGUAGE': 'en_US:en', 15:51:01 INFO - 'LOGNAME': 'cltbld', 15:51:01 INFO - 'MAIL': '/var/mail/cltbld', 15:51:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:01 INFO - 'MOZ_NO_REMOTE': '1', 15:51:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:01 INFO - 'NO_EM_RESTART': '1', 15:51:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:01 INFO - 'PWD': '/builds/slave/test', 15:51:01 INFO - 'SHELL': '/bin/bash', 15:51:01 INFO - 'SHLVL': '1', 15:51:01 INFO - 'TERM': 'linux', 15:51:01 INFO - 'TMOUT': '86400', 15:51:01 INFO - 'USER': 'cltbld', 15:51:01 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:51:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:01 INFO - '_': '/tools/buildbot/bin/python'} 15:51:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:01 INFO - Running setup.py (path:/tmp/pip-4U9olL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:01 INFO - Running setup.py (path:/tmp/pip-hrNIrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:01 INFO - Running setup.py (path:/tmp/pip-wq4tVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:01 INFO - Running setup.py (path:/tmp/pip-L19gv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:01 INFO - Running setup.py (path:/tmp/pip-Xq1JeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:02 INFO - Running setup.py (path:/tmp/pip-5DAR7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:02 INFO - Running setup.py (path:/tmp/pip-mqSBnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:02 INFO - Running setup.py (path:/tmp/pip-BUKwUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:02 INFO - Running setup.py (path:/tmp/pip-LfaUw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:02 INFO - Running setup.py (path:/tmp/pip-vviIs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:02 INFO - Running setup.py (path:/tmp/pip-ptdIk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:02 INFO - Running setup.py (path:/tmp/pip-J7xqpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:02 INFO - Running setup.py (path:/tmp/pip-ap_3Ux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:02 INFO - Running setup.py (path:/tmp/pip-B4zjw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:02 INFO - Running setup.py (path:/tmp/pip-0Ar7HI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:02 INFO - Running setup.py (path:/tmp/pip-lZ8GE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:02 INFO - Running setup.py (path:/tmp/pip-xK_RDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:02 INFO - Running setup.py install for manifestparser 15:51:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Running setup.py install for mozcrash 15:51:03 INFO - Running setup.py install for mozdebug 15:51:03 INFO - Running setup.py install for mozdevice 15:51:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Running setup.py install for mozfile 15:51:03 INFO - Running setup.py install for mozhttpd 15:51:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Running setup.py install for mozinfo 15:51:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Running setup.py install for mozInstall 15:51:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Running setup.py install for mozleak 15:51:03 INFO - Running setup.py install for mozlog 15:51:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Running setup.py install for moznetwork 15:51:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Running setup.py install for mozprocess 15:51:04 INFO - Running setup.py install for mozprofile 15:51:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:04 INFO - Running setup.py install for mozrunner 15:51:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:04 INFO - Running setup.py install for mozscreenshot 15:51:04 INFO - Running setup.py install for moztest 15:51:04 INFO - Running setup.py install for mozversion 15:51:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:04 INFO - Cleaning up... 15:51:04 INFO - Return code: 0 15:51:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:04 INFO - Using env: {'DISPLAY': ':0', 15:51:04 INFO - 'HOME': '/home/cltbld', 15:51:04 INFO - 'LANG': 'en_US.UTF-8', 15:51:04 INFO - 'LANGUAGE': 'en_US:en', 15:51:04 INFO - 'LOGNAME': 'cltbld', 15:51:04 INFO - 'MAIL': '/var/mail/cltbld', 15:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:04 INFO - 'MOZ_NO_REMOTE': '1', 15:51:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:04 INFO - 'NO_EM_RESTART': '1', 15:51:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:04 INFO - 'PWD': '/builds/slave/test', 15:51:04 INFO - 'SHELL': '/bin/bash', 15:51:04 INFO - 'SHLVL': '1', 15:51:04 INFO - 'TERM': 'linux', 15:51:04 INFO - 'TMOUT': '86400', 15:51:04 INFO - 'USER': 'cltbld', 15:51:04 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:04 INFO - '_': '/tools/buildbot/bin/python'} 15:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:04 INFO - Running setup.py (path:/tmp/pip-rg_PTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:04 INFO - Running setup.py (path:/tmp/pip-UJcSaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:04 INFO - Running setup.py (path:/tmp/pip-Uqe9QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:05 INFO - Running setup.py (path:/tmp/pip-Owa7j7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:05 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)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:05 INFO - Running setup.py (path:/tmp/pip-avJ_l9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:05 INFO - Running setup.py (path:/tmp/pip-fhBqSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:05 INFO - Running setup.py (path:/tmp/pip-5kvzMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:05 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)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:05 INFO - Running setup.py (path:/tmp/pip-9f0es6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:05 INFO - Running setup.py (path:/tmp/pip-sTsItK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:05 INFO - Running setup.py (path:/tmp/pip-VtVzeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:05 INFO - Running setup.py (path:/tmp/pip-xrD_em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:05 INFO - Running setup.py (path:/tmp/pip-QTOxTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:05 INFO - Running setup.py (path:/tmp/pip-JniiBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:05 INFO - Running setup.py (path:/tmp/pip-DemCyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:05 INFO - Running setup.py (path:/tmp/pip-H5FXtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:05 INFO - Running setup.py (path:/tmp/pip-bU6CsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:05 INFO - Running setup.py (path:/tmp/pip-ghiMzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:06 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)) 15:51:06 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)) 15:51:06 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)) 15:51:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:07 INFO - Downloading blessings-1.6.tar.gz 15:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:07 INFO - Installing collected packages: blessings 15:51:07 INFO - Running setup.py install for blessings 15:51:07 INFO - Successfully installed blessings 15:51:07 INFO - Cleaning up... 15:51:07 INFO - Return code: 0 15:51:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:07 INFO - Reading from file tmpfile_stdout 15:51:07 INFO - Current package versions: 15:51:07 INFO - argparse == 1.2.1 15:51:07 INFO - blessings == 1.6 15:51:07 INFO - blobuploader == 1.2.4 15:51:07 INFO - docopt == 0.6.1 15:51:07 INFO - functools32 == 3.2.3-2 15:51:07 INFO - jsonschema == 2.5.1 15:51:07 INFO - manifestparser == 1.1 15:51:07 INFO - mozInstall == 1.13 15:51:07 INFO - mozcrash == 1.0 15:51:07 INFO - mozdebug == 0.1 15:51:07 INFO - mozdevice == 0.51 15:51:07 INFO - mozfile == 1.2 15:51:07 INFO - mozhttpd == 0.7 15:51:07 INFO - mozinfo == 0.10 15:51:07 INFO - mozleak == 0.1 15:51:07 INFO - mozlog == 3.5 15:51:07 INFO - moznetwork == 0.27 15:51:07 INFO - mozprocess == 0.25 15:51:07 INFO - mozprofile == 0.28 15:51:07 INFO - mozrunner == 6.13 15:51:07 INFO - mozscreenshot == 0.1 15:51:07 INFO - mozsystemmonitor == 0.3 15:51:07 INFO - moztest == 0.8 15:51:07 INFO - mozversion == 1.4 15:51:07 INFO - psutil == 3.1.1 15:51:07 INFO - requests == 1.2.3 15:51:07 INFO - wsgiref == 0.1.2 15:51:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:07 INFO - Using env: {'DISPLAY': ':0', 15:51:07 INFO - 'HOME': '/home/cltbld', 15:51:07 INFO - 'LANG': 'en_US.UTF-8', 15:51:07 INFO - 'LANGUAGE': 'en_US:en', 15:51:07 INFO - 'LOGNAME': 'cltbld', 15:51:07 INFO - 'MAIL': '/var/mail/cltbld', 15:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:07 INFO - 'MOZ_NO_REMOTE': '1', 15:51:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:07 INFO - 'NO_EM_RESTART': '1', 15:51:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:07 INFO - 'PWD': '/builds/slave/test', 15:51:07 INFO - 'SHELL': '/bin/bash', 15:51:07 INFO - 'SHLVL': '1', 15:51:07 INFO - 'TERM': 'linux', 15:51:07 INFO - 'TMOUT': '86400', 15:51:07 INFO - 'USER': 'cltbld', 15:51:07 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:07 INFO - '_': '/tools/buildbot/bin/python'} 15:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:51:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:51:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:51:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:51:08 INFO - Installing collected packages: simplejson 15:51:08 INFO - Running setup.py install for simplejson 15:51:09 INFO - building 'simplejson._speedups' extension 15:51:09 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 15:51:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:51:09 INFO - Successfully installed simplejson 15:51:09 INFO - Cleaning up... 15:51:10 INFO - Return code: 0 15:51:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51:10 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')]} 15:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:10 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')]} 15:51:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:10 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 0x1a7d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, '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': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:10 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 15:51:10 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 15:51:10 INFO - Using env: {'DISPLAY': ':0', 15:51:10 INFO - 'HOME': '/home/cltbld', 15:51:10 INFO - 'LANG': 'en_US.UTF-8', 15:51:10 INFO - 'LANGUAGE': 'en_US:en', 15:51:10 INFO - 'LOGNAME': 'cltbld', 15:51:10 INFO - 'MAIL': '/var/mail/cltbld', 15:51:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:10 INFO - 'MOZ_NO_REMOTE': '1', 15:51:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:10 INFO - 'NO_EM_RESTART': '1', 15:51:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:10 INFO - 'PWD': '/builds/slave/test', 15:51:10 INFO - 'SHELL': '/bin/bash', 15:51:10 INFO - 'SHLVL': '1', 15:51:10 INFO - 'TERM': 'linux', 15:51:10 INFO - 'TMOUT': '86400', 15:51:10 INFO - 'USER': 'cltbld', 15:51:10 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:51:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:10 INFO - '_': '/tools/buildbot/bin/python'} 15:51:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:51:10 INFO - Cleaning up... 15:51:10 INFO - Return code: 0 15:51:10 INFO - Running post-action listener: _resource_record_post_action 15:51:10 INFO - Running post-action listener: _start_resource_monitoring 15:51:10 INFO - Starting resource monitoring. 15:51:10 INFO - [mozharness: 2017-09-14 22:51:10.277102Z] Finished create-virtualenv step (success) 15:51:10 INFO - [mozharness: 2017-09-14 22:51:10.277624Z] Running install step. 15:51:10 INFO - Running pre-action listener: _resource_record_pre_action 15:51:10 INFO - Running main action method: install 15:51:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:10 INFO - Reading from file tmpfile_stdout 15:51:10 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:10 INFO - Reading from file tmpfile_stdout 15:51:10 INFO - Output received: 15:51:10 INFO - Usage: mozinstall [options] installer 15:51:10 INFO - Options: 15:51:10 INFO - -h, --help show this help message and exit 15:51:10 INFO - -d DEST, --destination=DEST 15:51:10 INFO - Directory to install application into. [default: 15:51:10 INFO - "/builds/slave/test"] 15:51:10 INFO - --app=APP Application being installed. [default: firefox] 15:51:10 INFO - mkdir: /builds/slave/test/build/application 15:51:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:51:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:51:28 INFO - Reading from file tmpfile_stdout 15:51:28 INFO - Output received: 15:51:28 INFO - /builds/slave/test/build/application/firefox/firefox 15:51:28 INFO - Running post-action listener: _resource_record_post_action 15:51:28 INFO - [mozharness: 2017-09-14 22:51:28.396155Z] Finished install step (success) 15:51:28 INFO - [mozharness: 2017-09-14 22:51:28.396404Z] Running setup-mitmproxy step. 15:51:28 INFO - Running pre-action listener: _resource_record_pre_action 15:51:28 INFO - Running main action method: setup_mitmproxy 15:51:28 INFO - Skipping: mitmproxy is not required 15:51:28 INFO - Running post-action listener: _resource_record_post_action 15:51:28 INFO - [mozharness: 2017-09-14 22:51:28.397529Z] Finished setup-mitmproxy step (success) 15:51:28 INFO - [mozharness: 2017-09-14 22:51:28.397749Z] Running run-tests step. 15:51:28 INFO - Running pre-action listener: _resource_record_pre_action 15:51:28 INFO - Running pre-action listener: _set_gcov_prefix 15:51:28 INFO - Running main action method: run_tests 15:51:28 WARNING - Try message not found. 15:51:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:51:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:51:28 INFO - Python 2.7.3 15:51:28 INFO - Return code: 0 15:51:28 INFO - grabbing minidump binary from tooltool 15:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ded090>, '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 0x1dedd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:51:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:51:28 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:51:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:51:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:51:28 INFO - Return code: 0 15:51:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:51:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:51:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:51:28 INFO - ENV: RUST_BACKTRACE is now full 15:51:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:51:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:51:28 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/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 15:51:28 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/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 15:51:28 INFO - Using env: {'DISPLAY': ':0', 15:51:28 INFO - 'HOME': '/home/cltbld', 15:51:28 INFO - 'LANG': 'en_US.UTF-8', 15:51:28 INFO - 'LANGUAGE': 'en_US:en', 15:51:28 INFO - 'LOGNAME': 'cltbld', 15:51:28 INFO - 'MAIL': '/var/mail/cltbld', 15:51:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:28 INFO - 'MOZ_NO_REMOTE': '1', 15:51:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:28 INFO - 'NO_EM_RESTART': '1', 15:51:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:28 INFO - 'PWD': '/builds/slave/test', 15:51:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:28 INFO - 'RUST_BACKTRACE': 'full', 15:51:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:28 INFO - 'SHELL': '/bin/bash', 15:51:28 INFO - 'SHLVL': '1', 15:51:28 INFO - 'TERM': 'linux', 15:51:28 INFO - 'TMOUT': '86400', 15:51:28 INFO - 'USER': 'cltbld', 15:51:28 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:28 INFO - '_': '/tools/buildbot/bin/python'} 15:51:28 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/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 15:51:28 INFO - mozversion application_buildid: 20170914215707 15:51:28 INFO - mozversion application_changeset: b778b6cb49c8ed1dab244c00dc4dc3cf88b25ffe 15:51:28 INFO - mozversion application_display_name: Nightly 15:51:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:51:28 INFO - mozversion application_name: Firefox 15:51:28 INFO - mozversion application_remotingname: firefox 15:51:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:51:28 INFO - mozversion application_vendor: Mozilla 15:51:28 INFO - mozversion application_version: 57.0a1 15:51:28 INFO - mozversion platform_buildid: 20170914215707 15:51:28 INFO - mozversion platform_changeset: b778b6cb49c8ed1dab244c00dc4dc3cf88b25ffe 15:51:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:51:28 INFO - mozversion platform_version: 57.0a1 15:51:28 INFO - using testdate: 1505429488 15:51:28 INFO - actual date: 1505429488 15:51:28 INFO - starting webserver on 'localhost:50532' 15:51:28 INFO - SUITE-START | Running 2 tests 15:51:28 INFO - TEST-START | dromaeo_css 15:51:28 INFO - Initialising browser for dromaeo_css test... 15:51:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50532/getInfo.html -profile /tmp/tmp2niGRE/profile 15:51:28 INFO - TEST-INFO | started process 28407 (/builds/slave/test/build/application/firefox/firefox http://localhost:50532/getInfo.html) 15:51:34 INFO - TEST-INFO | 28407: exit 0 15:51:34 INFO - Browser initialized. 15:51:34 INFO - Running cycle 1/1 for dromaeo_css test... 15:51:34 INFO - Using env: {'DISPLAY': ':0', 15:51:34 INFO - 'HOME': '/home/cltbld', 15:51:34 INFO - 'JSGC_DISABLE_POISONING': '1', 15:51:34 INFO - 'LANG': 'en_US.UTF-8', 15:51:34 INFO - 'LANGUAGE': 'en_US:en', 15:51:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:51:34 INFO - 'LOGNAME': 'cltbld', 15:51:34 INFO - 'MAIL': '/var/mail/cltbld', 15:51:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:34 INFO - 'MOZ_CRASHREPORTER': '1', 15:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:51:34 INFO - 'MOZ_NO_REMOTE': '1', 15:51:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:34 INFO - 'NO_EM_RESTART': '1', 15:51:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:34 INFO - 'PWD': '/builds/slave/test', 15:51:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:34 INFO - 'RUST_BACKTRACE': 'full', 15:51:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:34 INFO - 'SHELL': '/bin/bash', 15:51:34 INFO - 'SHLVL': '1', 15:51:34 INFO - 'TERM': 'linux', 15:51:34 INFO - 'TMOUT': '86400', 15:51:34 INFO - 'USER': 'cltbld', 15:51:34 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 15:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:34 INFO - '_': '/tools/buildbot/bin/python'} 15:51:34 INFO - TEST-INFO | started process 28637 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2niGRE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:35 INFO - PID 28637 | 15:51:35 INFO - PID 28637 | (/builds/slave/test/build/application/firefox/firefox:28686): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:51:35 INFO - PID 28637 | 15:51:45 INFO - PID 28637 | RSS: Main: 174227456 15:51:45 INFO - PID 28637 | 15:54:42 INFO - PID 28637 | Cycle 1(1): loaded http://localhost:50532/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50532/tests/dromaeo/cssquery-ext.html) 15:54:43 INFO - PID 28637 | RSS: Main: 176340992 15:54:43 INFO - PID 28637 | 15:57:45 INFO - PID 28637 | Cycle 1(1): loaded http://localhost:50532/tests/dromaeo/cssquery-ext.html (next: http://localhost:50532/tests/dromaeo/cssquery-jquery.html) 15:57:46 INFO - PID 28637 | RSS: Main: 171737088 15:57:46 INFO - PID 28637 | 16:00:47 INFO - PID 28637 | Cycle 1(1): loaded http://localhost:50532/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50532/tests/dromaeo/cssquery-mootools.html) 16:00:48 INFO - PID 28637 | RSS: Main: 174714880 16:00:48 INFO - PID 28637 | 16:03:50 INFO - PID 28637 | Cycle 1(1): loaded http://localhost:50532/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50532/tests/dromaeo/cssquery-prototype.html) 16:03:50 INFO - PID 28637 | RSS: Main: 171139072 16:03:50 INFO - PID 28637 | 16:06:53 INFO - PID 28637 | Cycle 1(1): loaded http://localhost:50532/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50532/tests/dromaeo/cssquery-yui.html) 16:06:54 INFO - PID 28637 | RSS: Main: 172474368 16:06:54 INFO - PID 28637 | 16:09:56 INFO - PID 28637 | Cycle 1(1): loaded http://localhost:50532/tests/dromaeo/cssquery-yui.html (next: http://localhost:50532/tests/dromaeo/cssquery-dojo.html) 16:09:56 INFO - PID 28637 | RSS: Main: 178204672 16:09:56 INFO - PID 28637 | 16:09:56 INFO - PID 28637 | __start_tp_report 16:09:56 INFO - PID 28637 | _x_x_mozilla_page_load 16:09:56 INFO - PID 28637 | _x_x_mozilla_page_load_details 16:09:56 INFO - PID 28637 | |i|pagename|runs| 16:09:56 INFO - PID 28637 | |0;dojo.html;3131.295458521833;3143.2927591291964;3156.147840083176;3183.4110689522427;3197.2326641606005;6319.747210111595;6323.620582765032;6327.7785277515295;6356.427921487065;6375.489960803142;6157.076438534222;6282.591631543946;6284.842878928029;6292.590981586193;6317.305096439388;5781.103928891029;5781.248437703103;5823;5840.240768700084;5932.6143800653035;5850.590458667895;5892.852678683024;5892.88214235715;5895.823125306226;5917.349091599921;5610.943890561124;5750.166225897243;5813.9418605814035;5885.529157667398;5893.911591326134;5736.741846616912;5739.253896993406;5822.359540450547;5858.736356863951;5890.322612899528;5793.768249270024;5797.855053623651;5832.766689332421;5834.154047663107;5840.094785308284;6117.602355846856;6221.346758590374;6231.940570103048;6296;6367.808965731036;6742.595444273359;6764.898526522106;6788.287229840845;6797.762078327284;6823.795286141423;6834.2482326944;6919.827004324882;6926.445884329292;6932.306769323078;6933.445324374088;6294.716737746812;6324.146240257553;6369.872602547923;6377.23473183221;6405.071264666643;6772.2211945625895;6886.8622627547165;6905.274946130685;6909.067275917787;6920.30796920304;6455.806325810233;6544.3782840630065;6546.70539825709;6564.277929427759;6567.934320656732;6630.6353150576215;6633.668316584151;6634.104395906491;6654.23476300222;6663.167104111986;6600.339966003457;6605.504587155982;6613.272540020594;6689.832754181184;6791.320867913268;6798.932010679929;6841.965790171017;6843.931560684429;6858.794236172923;6858.862822743517;5315.282436870973;5316.521513063843;5378.354597448253;5382.381026182039;5391.137418013099;4551.52209018055;4617.561331673485;4620.676552641283;4624.398828152319;4648.721076735406;5371.409144994048;5789.739461724233;5817.650940943557;5832.650040997553;5839.357670656224;6730.427913627299;6756.391924726799;6863.691133898987;6864.759733409307;6905.861882762317;5882.147088672119;5948.10778383246;5949.762009519571;5951.672658003765;5962.5826192167;5834.5624078194305;5855.736491857877;5894.587378883522;5902.586818922634;5916.940830591725;6420.711068001951;6509.8698026040165;6565.212174539086;6657.267700552935;6662.833429164213;6732.865342693119;6747.595144291358;6751.797446076429;6759.966200168968;6766;6459.289478157493;6480.740770369321;6500.219973603009;6504.642244676588;6505.967470162808;6687.665616719242;6758.831029224309;6776.085228494288;6782.93217067823;6790.422814060663;6810.897836532356;6822.624755638289;6837.487188460786;6910.170779506491;6921.930780692129;6369.49044076482;6503.479721622164;6584.407403333723;6591.670416479253;6657.367550082734;6622.569532980341;6637.435817955527;6637.834054148685;6668.66656667155;6681.432078273401;6533.575317604341;6600.8349791255605;6603.405693487609;6608.966955165193;6613.338666133541;6650.4347130492515;6653.367930046637;6662.966685166543;6666.100076489805;6749.662516874038;6262.060690896402;6383.042543618494;6390.840228994312;6490.350964903472;6492.993585994179 16:09:56 INFO - PID 28637 | |1;ext.html;795.8806179073134;849.6219182463805;873.9781505462363;878.4904755241961;878.7627340618031;39748.40377394338;41485.58514414855;41778.58221417785;41781.58218417819;41936.58063419365;38011.80994095033;38279.80860095696;38311.80844095782;38348.61651383485;38368.232635347274;6821.283765204656;6996.965015174917;7036.226015138331;7049.295070492935;7052.753153639624;6981.6509174541325;7074.893876591856;7079.433645308363;7081.61051142187;7082.150141982969;7048.823779405505;7077.25688802676;7084.256153103927;7084.752033678797;7085.04351912493;7613.80965475862;7827.843443131105;7829.41279404047;7834.725784597512;7857.803554911145;7663.233676632348;7692.884606730903;7735.071791385014;7747.186545412706;7749.186335434754;38264.61735382638;38279;38522.422163667565;38969.805150974345;38986;3505.2113274513285;3556.448750443685;3556.87550935716;3558.430651095812;3559.76861504004;32205.677943220504;32245.51631725526;32268.19329516781;32307.51538726921;32317.515237271225;5181.559567436772;5192.7403629818355;5226.137687281594;5229.372475302988;5242.790288388498;184556;186275;186294;186330;187195;46765.53234467612;46985.76507117443;47039;47141.76429117901;47426;49135;49192.75403622959;49242.50757492451;49314.75342623336;49441.50558494369;15667.843321566867;16074.598135046483;16080.195990200444;16092.597685057966;16140.75788863168;13225.338733063309;13228.074034817662;13232.73534529304;13272.070955033056;13291.401886915111;13236.867631323756;13263.602091937257;13288.933555332354;13424.664383390298;13646.86353136456;5453.072977593819;5524.14375771756;5545.473180047889;5659.462351076641;5660.490555849993;1000.5747557288123;1001.2590682894605;1007.7027276953316;1008.0977525114981;1022.5551884930079;22567.210147644753;22610.208642697427;22648.773512264666;23009.769902301097;23074.192403265806;1107.7286064913988;1110.3116068037868;1112.6383925224334;1122.5004872831676;1123.5337335005881;12232.877671223352;12393.876061239453;12424.192427492184;12429.378531073591;12487.562935297403;224499;225793;226608;226803;226814;44834.77582612066;45465.54534454613;45740.771296143306;51960;52864.20703689448;1438.4030627289767;1446.5515690136092;1448.529228000904;1452.4117732318427;1458;26745.33136671599;26791.196264112106;26805.329866753487;27085.729142708322;27275.59086613742;37518.24963500769;37525.62474375221;37563.06092347713;37571.24857502889;37591.43612845755;202091;202640;202905;203984;204389;33594.832025839714;33835.15412114717;33850.49224261589;33856.322873542886;33859.3228135431;41650.375244371964;43466.34800477932;43911.34132988071;43947.78026109849;44045.77977110094;31165;31402.21494462657;31781.523277150398;31834;32132.35735285328;31214.531782022834;31273.37453250968;31405.842970785;31419;32712.509312359856;35637.64362356447;35911.82044089763;36140.4578931311;36225.637743622225;36363.27273454463;30511.542326865558;30584.388312233186;30952.84523577368;30999.380012400077;30999.535006975366;22482.887585561966;22653.320400388016;22795.65806512937;22981.655275170553;23049.65425518652 16:09:56 INFO - PID 28637 | |2;jquery.html;556.3101753825256;565.9830205093843;573.4781348972435;573.9282589676291;600.7837178615699;22168.88915555422;22760.54478910421;22914.427139321506;22946.541069178606;22954.311370658885;23329.76670233297;24449.511009779795;24468.75531244687;24512.264632061066;24782.75217247827;6625.204975402947;6751.966240168792;6795.490338224628;6796.660166991655;6821.317868213164;6173.845653858667;6615.470762339025;6668.733250669968;6730.293319201487;6745.898811517831;6450.161479007746;6472.158619379474;6475.676216189196;6476.384743449365;6478.481721462272;6427.8393040173905;6462.676866156697;6468.838279043014;6469.83825404364;6470.288268290485;5950.761969521214;6744.5616034957575;7003.2296447390745;7004;7051.717931282743;29471.70528294711;30077;30080.548791768142;30102.84948575265;30142.24644383886;2069.1619893942975;2100.6218880601486;2100.884451355175;2102.2326850699537;2106.420734298065;19677.80322196774;20023.399298021082;20346.18615255388;20382.490437739027;20468.795312046837;2442.218490083174;2474.7648973347505;2533.505966336565;2560.3215147985798;2625.4355313607475;117361;122296;128320.3583982074;133157.67369305217;138117.30941345228;19009.524761880788;20750.273740419012;20785.792142078688;21186.19917307046;21600.675989860163;40343.39484907729;40356.39465408021;40808.79595602003;40868.591314087076;40920.38619420711;11323.43382830855;11391.715207119889;11429.314241145557;11569.768604628029;11677.124215683858;9471.479068651153;9502.904970950201;9583.329166958247;9631.759206019906;9685.17675997548;10815.78368432627;10909.83635245472;10914.56341746337;11168.553257869595;11559.653210403701;3526.20660351424;3563.803990780532;4040.878773636796;4168.624823765815;4296.849610263688;962.1148543340004;979.4417182206074;987.916027137687;989.4904124375952;1000.7648202672366;19119;19172.328968486036;19183.616327673368;19599.412017639494;19702.901485492483;1010.1867996262932;1029.8867124616286;1057.7672911959357;1061.135174832509;1073.538378497238;10500.474976251156;10634.20243481742;10822;11057;11137.554497819996;468493;485081;491406;493575;499123;113224.43387783009;113457;114263;115894;118822;1174.8120300751839;1206.4182649130867;1213.9210951288137;1218.7867123253395;1218.823270625772;17453.82546174522;17537.824621753618;17566.824331756517;17580.38468653591;17659.823401765818;52318;56601.433985661264;57007.42992570021;57224.71387643035;58169;70761.64619176871;70862.29137708763;71083.64458177677;71797.28202717906;72455;18767.906160469658;18894.52763680919;19788.20847166097;19791.60416791685;20084.297049603192;22218.44453888666;22263.44341391478;22366.552668946206;22392;22504;17746.37887673925;17915.552111196805;17934.91032544829;18124.546886327946;18159.636807263516;16220.837791622405;16583;16720.498385048468;16761.5809604756;16987;18034.63930721352;18583.72124418162;18890.7166392507;18961;19046.333378331692;18405.355812547034;19513.02434878222;19562.119704613044;19603.901980490005;19617.332526735387;17957.191926363084;17985.34194631371;18051.277948881896;18057.638847223243;18080.276788928822 16:09:56 INFO - PID 28637 | |3;mootools.html;1293.0948336164686;1374.5120482228815;1387.8272859433775;1397.5877116250708;1402.291842619477;14817.259137043158;15479.303431345588;15502.767458488104;15507.224638768059;15517.301721422535;15206.391744330214;15214.163221022867;15278;15330.386784528606;15333.846661533382;2666.1468330134367;2697.1908427471776;2700.8649567521643;2704.2157774245447;2737.7262273772662;2673.17131689177;2719.5648696208514;2722.3738540135682;2730.931726706838;2735.0153944579934;2275.180934863448;2358.882055897207;2548.503041906829;2655.6414883990706;2719.8504082275467;2698.473797609467;2700.648915640964;2722.91831245062;2725.74105459981;2727.1954773341813;2653.6550248467574;2655.150351887397;2656.242970753326;2667.9333016674736;2669.2259244819174;14656.633584160374;15068.773968390373;15081.396744130221;15086.54740357791;15104.697906041818;7937.404694647925;8292.212239837207;8295.336373090138;8300.460470069487;8302.916970830274;5245.711485868275;5392.487713667196;5465.808696695596;5693.060644993571;5700.686462244575;1748.1434097292235;1758.1297257857443;1759.7624320716668;1801.2164708351777;1827.5979284557377;26747.86626066877;26750.73249267502;26768;26779.33051673704;26782.598261025905;7131.393831524275;7155.141383033965;7186.389156921616;7318.926810731931;7358.448116391292;20307.898460507604;20314.18743250283;20319.8984005082;20346.593068138554;20382.69425958612;7999.200079991954;8187.795305117301;8204.220599043081;8215.383846211558;8222.506649601042;7109.253528379446;7537.886931696029;7555.357794587411;7557.130929943127;7594.772156835304;5424.349078110573;5696.572757043238;5704.22992895962;5706.857328566736;5719.056355701263;1751.816059313779;1762.4007837335369;1771.2029586686042;1783.7948635907037;1796.6614871920503;791.6556298010383;901.2204443156753;923.0861447167256;930.6603089872283;950.6054987180381;8317.251447369645;8539.231469167804;8578.227959483676;8932.508712020901;8962.507062111516;975.2100798353357;1001.9699409017741;1003.6888564544869;1005.5475036233724;1008.702432782331;5437.184422336602;5574;5629.943700562942;5660.122680984454;5685.289338832646;21012.47468813292;22080;22098;22130.557388852132;22149.114035438724;20875;21605.243816466347;21761.782382175974;21776.782232177477;21851;11038.44807759625;11235.213535052468;11474.770504589862;11526.193166478266;11530.76938461243;8512.659493620433;8533.957330213309;8563.700270490503;8676.78308042304;8685.8697119542;17892.373766918095;18172.45482635523;18248.0875956199;18343.35798247055;18866.05669716536;19432.8056719431;19545.315913942944;19563.119659615066;19752.90123549373;19757.90121049443;11495.195336326376;11730.472128754398;11792.528298868292;11860.110491713329;11874.584389546324;13702.45190192381;13898.930505347409;13902.860971390157;13931.512397066053;14247.21640309793;11018;11120.833187502356;11325.830112548483;11386.487608057492;11511.42442877836;11350.318980861175;11496;11506.30962142274;11576.594819181288;11693.824592630946;10117.342372745748;10176.389416635026;10188.337758045704;10261.84607230907;10315.27793054479;7477.289657482531;7513.4740568159705;7522.962385188258;7546.207648196861;7584.696612135453;7738.264864838056;7790.571518566533;7946.7615971520945;7948.483348582324;7951.204879512003 16:09:56 INFO - PID 28637 | |4;prototype.html;500.1722149842013;507.79688124750106;521.1921521641455;534.1133718028074;534.3187436019075;6164.938350616492;6252.187215661957;6280.246370435542;6291.213598300213;6310.589811662238;6117.6329420234815;6158.84602884927;6161.199044124258;6205.286392064914;6217.5025997920175;5141.922871156936;5145.948540514584;5172.40517340506;5189.8183563575285;5192;4828.7585620719;4932.358793356859;5024.899502009957;5105.897882042375;5180.896382072357;4876.829310974116;4882.169186555042;4902.632302577304;4925.507449255064;4963.329950456697;4878.634102442314;4889.853304400874;4910.232316366105;4928.137575924235;4947.777350019258;4308.9784551077355;4384.342348647697;4409.801558929856;4416.3375493675885;4418.270985287424;5922.674252916088;5969.761209551612;6009.399060094;6013.639181649115;6031.577789554733;5612.494875461187;5634.690092044936;5656.943430565683;5664.801731939404;5678.716064196774;6414.967925160391;6435.324290949429;6453.677316134174;6468.191476065492;6508.284088750233;4530.986893715708;4806.855794326141;4835.6131509479155;4857.417109946794;4911.189653707134;6631.3700198481065;6670.83322916931;6672.733090676367;6747.595144291358;6820.522563420563;4930;4930.605551555938;4931.569844744984;4931.704097754075;4968.627352948543;4929.383827021622;4940.802367905316;5020.7740651670765;5025.698458092526;5052.823151189691;5614.641850422832;5617.8033768817895;5648.4633959773755;5650.180723795027;5652.3217213934595;6541.514224594624;6582.76960306387;6586.769463068769;6598.670066496752;6610.3720146586;1706.7610534525177;1723.5608267096864;1727.421313859846;1752.404182577904;1755.3066538717135;1150.4190383856267;1169.2283093158474;1185.040117504824;1205.9698507537382;1213.350857291345;5614;5636.661800291996;5662.017424666267;5674.2339784129435;5696.971515142398;6533.803985880431;6635.9336406636285;6674.132362792806;6696.330366963265;6706.429953453913;5618.269624948813;5625.54995600351;5626.493615574618;5653.406392328829;5654.067078932012;3458.0317511096932;3462.6149540183624;3555.8755443559867;3555.9644403555635;3558.6797188252663;6345.524085693592;6399.048290271418;6400.39196276353;6441.420272175433;6448;6767.308172956761;6810.387065164159;6826.624535650587;6844.178698556205;6885.242623311432;6223.222097237845;6375.458086062737;6392.137061496732;6428.71070801815;6502.902456463157;6101.542384321282;6531.150950376421;6673.399394054559;6679.666016699242;6724.663766811737;6522.315156908457;6531.706073226621;6545.770898018737;6571.769988050395;6580.802575922538;6319.241690996926;6322.3561873912195;6354.4281014708895;6355.904661430175;6444.677766111582;4827.662063655511;4873.122837889072;4941.431735350337;4967.82612608557;4970.801167953241;6269.592476489013;6439.871202576016;6484.416402523795;6707.463402927851;6763.086983257197;6195.845103872439;6300.369963003846;6395;6414.198225221848;6521.152250207443;6369.0764839099;6468.191476065492;6502.4719190989135;6548.607083575001;6559.540172435337;6399.424051835357;6401.295857455583;6472.126262954441;6493.155889734304;6497.480201583768;6488.286288508355;6510.967445162744;6565.737370505318;6608.43828274602;6614.801555953329;6291.842703932438;6318.178636777373;6332.620042797447;6396.9040464394;6402.967985160044 16:09:56 INFO - PID 28637 | |5;yui.html;903.1690844423126;919.3886065766266;926.249737712453;928.5264515097298;942.7643089227695;19100.522486937814;20160.588758786907;20181.29365472209;20213.898930505344;20223.191072357087;18670.159842807072;18815.247390104414;18874.33939812107;18878.15048322825;18909.810901890978;941.0777438110639;962.4706411473696;966.8791401074866;968.375397868378;968.4479846487505;978.3640633588153;978.5303054533828;979.7697541077841;979.7991411760572;980.1766516126453;976.8241716491028;997.5959736306808;1010.5604062232952;1012.9493525323741;1015.0458568945205;993.0665174735725;999.4552968632113;1000.1548691355792;1002.7543251903257;1004.8944860789621;720.4200618502103;734.9522281051715;736.1350413264446;741.2254194366845;744.2483092077041;19493.902530487398;19611;19667.311644092388;19679.803201968083;19734.506637334038;21376.679349809765;21380.786192138192;21998.450038749;22035;22077.448063798372;13795.10331828428;13832.723345533033;14062.718745625132;14068.155910645393;14144.151350918977;3830.2339532093692;3854.5181852268465;3863.38185890259;3863.5943225961432;3870.3807390817333;111167.44416277867;133963;142174.2891285537;142548;143144.28427857897;17277.56806079833;17410.738838917172;17592;17691.823081769275;17827.197776100107;31615.52576711351;32617.51073733896;32663.183420414203;32680.83659581687;32714.509282360785;8624.223819856243;8884.8667269991;8945.507997060224;8955.910440895637;9014.368994170345;4480.7535585542455;4603.838865639686;4605.539446055368;4614.284785858197;4629.1436084324505;5373.462653734595;5410.998965191374;5459.454054594509;5463.754131064112;5490.258815059996;1123.4270522033821;1130.3669944830922;1132.9660110196708;1139.5214010115772;1144.6279959013355;683.565935630868;698.1238545625208;700.3311837195466;715.8532500837316;726.9491135620456;12253.264804111783;12543.623691289276;12651.86133248012;13452.798208026887;13612.251326176956;679.5311235247664;680.6562685843618;683.8119517132828;693.8993845892318;707.2679776431369;5597.888042239132;5674.792285236707;5736;5819.5053420459735;5836.795712150054;122410.3879480597;122538;125586;127443;130253.34873325762;109452.4527377358;110872;111061;111107.44446277717;111395.44302278598;96113;97092;99212;101352.99999999853;101916.49041754744;10235.437050962268;10396.064354208158;10588;10626.149908007186;10643.893561064291;43401.56598434102;43924;44102.33846492369;44279.55720442755;45084.54915450804;57641.13538296845;58562.7071864638;58916.41083589109;59203.40796591979;59882.40117598768;15746.527604171893;16022.27899744491;16072.196390180678;16299.755503667691;16305.184740762677;17582.20880060375;17610.559736006704;17661.470155895342;17682.91158544199;17869.821301786815;14859.257037147883;14917.328720207399;14999.7000059996;15140.848591513944;15155.166465844484;16577.41979030728;16728.91635541863;16837.2423240952;17084.145792710562;17095.91452042732;16248.67502649964;16321;16404.671906562042;16520;16572.41996530164;12330.568430104904;12358.814617780561;12513.061520385825;12590.685232869251;12984.026198035373;12665.87334126647;12778.744425111632;12885.742285154432;12931.353432328158;13145.079844410799 16:09:56 INFO - PID 28637 | __end_tp_report 16:09:56 INFO - PID 28637 | __start_cc_report 16:09:56 INFO - PID 28637 | _x_x_mozilla_cycle_collect,394 16:09:56 INFO - PID 28637 | __end_cc_report 16:09:56 INFO - PID 28637 | __startTimestamp1505430596377__endTimestamp 16:09:56 INFO - PID 28637 | 16:09:56 INFO - PID 28637 | ------- Summary: start ------- 16:09:56 INFO - PID 28637 | Number of tests: 6 16:09:56 INFO - PID 28637 | 16:09:56 INFO - PID 28637 | [#0] dojo.html Cycles:165 Average:6224.09 Median:6480.74 stddev:747.55 (11.5%) stddev-sans-first:709.38 16:09:56 INFO - PID 28637 | Values: 3131.3 3143.3 3156.1 3183.4 3197.2 6319.7 6323.6 6327.8 6356.4 6375.5 6157.1 6282.6 6284.8 6292.6 6317.3 5781.1 5781.2 5823.0 5840.2 5932.6 5850.6 5892.9 5892.9 5895.8 5917.3 5610.9 5750.2 5813.9 5885.5 5893.9 5736.7 5739.3 5822.4 5858.7 5890.3 5793.8 5797.9 5832.8 5834.2 5840.1 6117.6 6221.3 6231.9 6296.0 6367.8 6742.6 6764.9 6788.3 6797.8 6823.8 6834.2 6919.8 6926.4 6932.3 6933.4 6294.7 6324.1 6369.9 6377.2 6405.1 6772.2 6886.9 6905.3 6909.1 6920.3 6455.8 6544.4 6546.7 6564.3 6567.9 6630.6 6633.7 6634.1 6654.2 6663.2 6600.3 6605.5 6613.3 6689.8 6791.3 6798.9 6842.0 6843.9 6858.8 6858.9 5315.3 5316.5 5378.4 5382.4 5391.1 4551.5 4617.6 4620.7 4624.4 4648.7 5371.4 5789.7 5817.7 5832.7 5839.4 6730.4 6756.4 6863.7 6864.8 6905.9 5882.1 5948.1 5949.8 5951.7 5962.6 5834.6 5855.7 5894.6 5902.6 5916.9 6420.7 6509.9 6565.2 6657.3 6662.8 6732.9 6747.6 6751.8 6760.0 6766.0 6459.3 6480.7 6500.2 6504.6 6506.0 6687.7 6758.8 6776.1 6782.9 6790.4 6810.9 6822.6 6837.5 6910.2 6921.9 6369.5 6503.5 6584.4 6591.7 6657.4 6622.6 6637.4 6637.8 6668.7 6681.4 6533.6 6600.8 6603.4 6609.0 6613.3 6650.4 6653.4 6663.0 6666.1 6749.7 6262.1 6383.0 6390.8 6490.4 6493.0 16:09:56 INFO - PID 28637 | 16:09:56 INFO - PID 28637 | [#1] ext.html Cycles:180 Average:37173.81 Median:3530.83 stddev:53261.34 (1508.5%) stddev-sans-first:53340.71 16:09:56 INFO - PID 28637 | Values: 795.9 849.6 874.0 878.5 878.8 39748.4 41485.6 41778.6 41781.6 41936.6 38011.8 38279.8 38311.8 38348.6 38368.2 6821.3 6997.0 7036.2 7049.3 7052.8 6981.7 7074.9 7079.4 7081.6 7082.2 7048.8 7077.3 7084.3 7084.8 7085.0 7613.8 7827.8 7829.4 7834.7 7857.8 7663.2 7692.9 7735.1 7747.2 7749.2 38264.6 38279.0 38522.4 38969.8 38986.0 3505.2 3556.4 3556.9 3558.4 3559.8 32205.7 32245.5 32268.2 32307.5 32317.5 5181.6 5192.7 5226.1 5229.4 5242.8 184556.0 186275.0 186294.0 186330.0 187195.0 46765.5 46985.8 47039.0 47141.8 47426.0 49135.0 49192.8 49242.5 49314.8 49441.5 15667.8 16074.6 16080.2 16092.6 16140.8 13225.3 13228.1 13232.7 13272.1 13291.4 13236.9 13263.6 13288.9 13424.7 13646.9 5453.1 5524.1 5545.5 5659.5 5660.5 1000.6 1001.3 1007.7 1008.1 1022.6 22567.2 22610.2 22648.8 23009.8 23074.2 1107.7 1110.3 1112.6 1122.5 1123.5 12232.9 12393.9 12424.2 12429.4 12487.6 224499.0 225793.0 226608.0 226803.0 226814.0 44834.8 45465.5 45740.8 51960.0 52864.2 1438.4 1446.6 1448.5 1452.4 1458.0 26745.3 26791.2 26805.3 27085.7 27275.6 37518.2 37525.6 37563.1 37571.2 37591.4 202091.0 202640.0 202905.0 203984.0 204389.0 33594.8 33835.2 33850.5 33856.3 33859.3 41650.4 43466.3 43911.3 43947.8 44045.8 31165.0 31402.2 31781.5 31834.0 32132.4 31214.5 31273.4 31405.8 31419.0 32712.5 35637.6 35911.8 36140.5 36225.6 36363.3 30511.5 30584.4 30952.8 30999.4 30999.5 22482.9 22653.3 22795.7 22981.7 23049.7 16:09:56 INFO - PID 28637 | 16:09:56 INFO - PID 28637 | [#2] jquery.html Cycles:180 Average:35402.21 Median:21884.78 stddev:81711.64 (373.4%) stddev-sans-first:81898.98 16:09:56 INFO - PID 28637 | Values: 556.3 566.0 573.5 573.9 600.8 22168.9 22760.5 22914.4 22946.5 22954.3 23329.8 24449.5 24468.8 24512.3 24782.8 6625.2 6752.0 6795.5 6796.7 6821.3 6173.8 6615.5 6668.7 6730.3 6745.9 6450.2 6472.2 6475.7 6476.4 6478.5 6427.8 6462.7 6468.8 6469.8 6470.3 5950.8 6744.6 7003.2 7004.0 7051.7 29471.7 30077.0 30080.5 30102.8 30142.2 2069.2 2100.6 2100.9 2102.2 2106.4 19677.8 20023.4 20346.2 20382.5 20468.8 2442.2 2474.8 2533.5 2560.3 2625.4 117361.0 122296.0 128320.4 133157.7 138117.3 19009.5 20750.3 20785.8 21186.2 21600.7 40343.4 40356.4 40808.8 40868.6 40920.4 11323.4 11391.7 11429.3 11569.8 11677.1 9471.5 9502.9 9583.3 9631.8 9685.2 10815.8 10909.8 10914.6 11168.6 11559.7 3526.2 3563.8 4040.9 4168.6 4296.8 962.1 979.4 987.9 989.5 1000.8 19119.0 19172.3 19183.6 19599.4 19702.9 1010.2 1029.9 1057.8 1061.1 1073.5 10500.5 10634.2 10822.0 11057.0 11137.6 468493.0 485081.0 491406.0 493575.0 499123.0 113224.4 113457.0 114263.0 115894.0 118822.0 1174.8 1206.4 1213.9 1218.8 1218.8 17453.8 17537.8 17566.8 17580.4 17659.8 52318.0 56601.4 57007.4 57224.7 58169.0 70761.6 70862.3 71083.6 71797.3 72455.0 18767.9 18894.5 19788.2 19791.6 20084.3 22218.4 22263.4 22366.6 22392.0 22504.0 17746.4 17915.6 17934.9 18124.5 18159.6 16220.8 16583.0 16720.5 16761.6 16987.0 18034.6 18583.7 18890.7 18961.0 19046.3 18405.4 19513.0 19562.1 19603.9 19617.3 17957.2 17985.3 18051.3 18057.6 18080.3 16:09:56 INFO - PID 28637 | 16:09:56 INFO - PID 28637 | [#3] mootools.html Cycles:180 Average:9577.98 Median:2453.69 stddev:6812.62 (277.6%) stddev-sans-first:6803.29 16:09:56 INFO - PID 28637 | Values: 1293.1 1374.5 1387.8 1397.6 1402.3 14817.3 15479.3 15502.8 15507.2 15517.3 15206.4 15214.2 15278.0 15330.4 15333.8 2666.1 2697.2 2700.9 2704.2 2737.7 2673.2 2719.6 2722.4 2730.9 2735.0 2275.2 2358.9 2548.5 2655.6 2719.9 2698.5 2700.6 2722.9 2725.7 2727.2 2653.7 2655.2 2656.2 2667.9 2669.2 14656.6 15068.8 15081.4 15086.5 15104.7 7937.4 8292.2 8295.3 8300.5 8302.9 5245.7 5392.5 5465.8 5693.1 5700.7 1748.1 1758.1 1759.8 1801.2 1827.6 26747.9 26750.7 26768.0 26779.3 26782.6 7131.4 7155.1 7186.4 7318.9 7358.4 20307.9 20314.2 20319.9 20346.6 20382.7 7999.2 8187.8 8204.2 8215.4 8222.5 7109.3 7537.9 7555.4 7557.1 7594.8 5424.3 5696.6 5704.2 5706.9 5719.1 1751.8 1762.4 1771.2 1783.8 1796.7 791.7 901.2 923.1 930.7 950.6 8317.3 8539.2 8578.2 8932.5 8962.5 975.2 1002.0 1003.7 1005.5 1008.7 5437.2 5574.0 5629.9 5660.1 5685.3 21012.5 22080.0 22098.0 22130.6 22149.1 20875.0 21605.2 21761.8 21776.8 21851.0 11038.4 11235.2 11474.8 11526.2 11530.8 8512.7 8534.0 8563.7 8676.8 8685.9 17892.4 18172.5 18248.1 18343.4 18866.1 19432.8 19545.3 19563.1 19752.9 19757.9 11495.2 11730.5 11792.5 11860.1 11874.6 13702.5 13898.9 13902.9 13931.5 14247.2 11018.0 11120.8 11325.8 11386.5 11511.4 11350.3 11496.0 11506.3 11576.6 11693.8 10117.3 10176.4 10188.3 10261.8 10315.3 7477.3 7513.5 7523.0 7546.2 7584.7 7738.3 7790.6 7946.8 7948.5 7951.2 16:09:56 INFO - PID 28637 | 16:09:56 INFO - PID 28637 | [#4] prototype.html Cycles:180 Average:5449.96 Median:6109.59 stddev:1526.24 (25.0%) stddev-sans-first:1484.62 16:09:56 INFO - PID 28637 | Values: 500.2 507.8 521.2 534.1 534.3 6164.9 6252.2 6280.2 6291.2 6310.6 6117.6 6158.8 6161.2 6205.3 6217.5 5141.9 5145.9 5172.4 5189.8 5192.0 4828.8 4932.4 5024.9 5105.9 5180.9 4876.8 4882.2 4902.6 4925.5 4963.3 4878.6 4889.9 4910.2 4928.1 4947.8 4309.0 4384.3 4409.8 4416.3 4418.3 5922.7 5969.8 6009.4 6013.6 6031.6 5612.5 5634.7 5656.9 5664.8 5678.7 6415.0 6435.3 6453.7 6468.2 6508.3 4531.0 4806.9 4835.6 4857.4 4911.2 6631.4 6670.8 6672.7 6747.6 6820.5 4930.0 4930.6 4931.6 4931.7 4968.6 4929.4 4940.8 5020.8 5025.7 5052.8 5614.6 5617.8 5648.5 5650.2 5652.3 6541.5 6582.8 6586.8 6598.7 6610.4 1706.8 1723.6 1727.4 1752.4 1755.3 1150.4 1169.2 1185.0 1206.0 1213.4 5614.0 5636.7 5662.0 5674.2 5697.0 6533.8 6635.9 6674.1 6696.3 6706.4 5618.3 5625.5 5626.5 5653.4 5654.1 3458.0 3462.6 3555.9 3556.0 3558.7 6345.5 6399.0 6400.4 6441.4 6448.0 6767.3 6810.4 6826.6 6844.2 6885.2 6223.2 6375.5 6392.1 6428.7 6502.9 6101.5 6531.2 6673.4 6679.7 6724.7 6522.3 6531.7 6545.8 6571.8 6580.8 6319.2 6322.4 6354.4 6355.9 6444.7 4827.7 4873.1 4941.4 4967.8 4970.8 6269.6 6439.9 6484.4 6707.5 6763.1 6195.8 6300.4 6395.0 6414.2 6521.2 6369.1 6468.2 6502.5 6548.6 6559.5 6399.4 6401.3 6472.1 6493.2 6497.5 6488.3 6511.0 6565.7 6608.4 6614.8 6291.8 6318.2 6332.6 6396.9 6403.0 16:09:56 INFO - PID 28637 | 16:09:56 INFO - PID 28637 | [#5] yui.html Cycles:180 Average:24580.77 Median:19639.16 stddev:35507.50 (180.8%) stddev-sans-first:35562.60 16:09:56 INFO - PID 28637 | Values: 903.2 919.4 926.2 928.5 942.8 19100.5 20160.6 20181.3 20213.9 20223.2 18670.2 18815.2 18874.3 18878.2 18909.8 941.1 962.5 966.9 968.4 968.4 978.4 978.5 979.8 979.8 980.2 976.8 997.6 1010.6 1012.9 1015.0 993.1 999.5 1000.2 1002.8 1004.9 720.4 735.0 736.1 741.2 744.2 19493.9 19611.0 19667.3 19679.8 19734.5 21376.7 21380.8 21998.5 22035.0 22077.4 13795.1 13832.7 14062.7 14068.2 14144.2 3830.2 3854.5 3863.4 3863.6 3870.4 111167.4 133963.0 142174.3 142548.0 143144.3 17277.6 17410.7 17592.0 17691.8 17827.2 31615.5 32617.5 32663.2 32680.8 32714.5 8624.2 8884.9 8945.5 8955.9 9014.4 4480.8 4603.8 4605.5 4614.3 4629.1 5373.5 5411.0 5459.5 5463.8 5490.3 1123.4 1130.4 1133.0 1139.5 1144.6 683.6 698.1 700.3 715.9 726.9 12253.3 12543.6 12651.9 13452.8 13612.3 679.5 680.7 683.8 693.9 707.3 5597.9 5674.8 5736.0 5819.5 5836.8 122410.4 122538.0 125586.0 127443.0 130253.3 109452.5 110872.0 111061.0 111107.4 111395.4 96113.0 97092.0 99212.0 101353.0 101916.5 10235.4 10396.1 10588.0 10626.1 10643.9 43401.6 43924.0 44102.3 44279.6 45084.5 57641.1 58562.7 58916.4 59203.4 59882.4 15746.5 16022.3 16072.2 16299.8 16305.2 17582.2 17610.6 17661.5 17682.9 17869.8 14859.3 14917.3 14999.7 15140.8 15155.2 16577.4 16728.9 16837.2 17084.1 17095.9 16248.7 16321.0 16404.7 16520.0 16572.4 12330.6 12358.8 12513.1 12590.7 12984.0 12665.9 12778.7 12885.7 12931.4 13145.1 16:09:56 INFO - PID 28637 | -------- Summary: end -------- 16:09:56 INFO - PID 28637 | 16:09:57 INFO - TEST-INFO | 28637: exit 0 16:09:57 INFO - TEST-OK | dromaeo_css | took 1108309ms 16:09:57 INFO - TEST-START | kraken 16:09:57 INFO - Initialising browser for kraken test... 16:09:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50532/getInfo.html -profile /tmp/tmp4vvyOy/profile 16:09:57 INFO - TEST-INFO | started process 29008 (/builds/slave/test/build/application/firefox/firefox http://localhost:50532/getInfo.html) 16:10:02 INFO - TEST-INFO | 29008: exit 0 16:10:02 INFO - Browser initialized. 16:10:02 INFO - Running cycle 1/1 for kraken test... 16:10:02 INFO - Using env: {'DISPLAY': ':0', 16:10:02 INFO - 'HOME': '/home/cltbld', 16:10:02 INFO - 'JSGC_DISABLE_POISONING': '1', 16:10:02 INFO - 'LANG': 'en_US.UTF-8', 16:10:02 INFO - 'LANGUAGE': 'en_US:en', 16:10:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:10:02 INFO - 'LOGNAME': 'cltbld', 16:10:02 INFO - 'MAIL': '/var/mail/cltbld', 16:10:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:10:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:10:02 INFO - 'MOZ_CRASHREPORTER': '1', 16:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:10:02 INFO - 'MOZ_NO_REMOTE': '1', 16:10:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:10:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:02 INFO - 'NO_EM_RESTART': '1', 16:10:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:02 INFO - 'PWD': '/builds/slave/test', 16:10:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:10:02 INFO - 'RUST_BACKTRACE': 'full', 16:10:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:10:02 INFO - 'SHELL': '/bin/bash', 16:10:02 INFO - 'SHLVL': '1', 16:10:02 INFO - 'TERM': 'linux', 16:10:02 INFO - 'TMOUT': '86400', 16:10:02 INFO - 'USER': 'cltbld', 16:10:02 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1505427955.148683-1209847522', 16:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:02 INFO - '_': '/tools/buildbot/bin/python'} 16:10:02 INFO - TEST-INFO | started process 29235 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4vvyOy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:10:03 INFO - PID 29235 | 16:10:03 INFO - PID 29235 | (/builds/slave/test/build/application/firefox/firefox:29284): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:10:03 INFO - PID 29235 | 16:10:13 INFO - PID 29235 | RSS: Main: 173674496 16:10:13 INFO - PID 29235 | 16:10:16 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50532/tests/kraken/driver.html?testName=audio-beat-detection) 16:10:17 INFO - PID 29235 | RSS: Main: 177778688 16:10:17 INFO - PID 29235 | 16:10:20 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50532/tests/kraken/driver.html?testName=audio-dft) 16:10:20 INFO - PID 29235 | RSS: Main: 178249728 16:10:20 INFO - PID 29235 | 16:10:23 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50532/tests/kraken/driver.html?testName=audio-fft) 16:10:24 INFO - PID 29235 | RSS: Main: 174104576 16:10:24 INFO - PID 29235 | 16:10:26 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50532/tests/kraken/driver.html?testName=audio-oscillator) 16:10:26 INFO - PID 29235 | RSS: Main: 174907392 16:10:26 INFO - PID 29235 | 16:10:29 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50532/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:10:29 INFO - PID 29235 | RSS: Main: 174948352 16:10:29 INFO - PID 29235 | 16:10:32 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50532/tests/kraken/driver.html?testName=imaging-darkroom) 16:10:33 INFO - PID 29235 | RSS: Main: 177504256 16:10:33 INFO - PID 29235 | 16:10:37 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50532/tests/kraken/driver.html?testName=imaging-desaturate) 16:10:37 INFO - PID 29235 | RSS: Main: 177647616 16:10:37 INFO - PID 29235 | 16:10:40 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50532/tests/kraken/driver.html?testName=json-parse-financial) 16:10:41 INFO - PID 29235 | RSS: Main: 177836032 16:10:41 INFO - PID 29235 | 16:10:43 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50532/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:10:43 INFO - PID 29235 | RSS: Main: 178073600 16:10:43 INFO - PID 29235 | 16:10:45 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50532/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:10:45 INFO - PID 29235 | RSS: Main: 178249728 16:10:45 INFO - PID 29235 | 16:10:48 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50532/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:10:48 INFO - PID 29235 | RSS: Main: 175468544 16:10:48 INFO - PID 29235 | 16:10:51 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50532/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:52 INFO - PID 29235 | RSS: Main: 175480832 16:10:52 INFO - PID 29235 | 16:10:54 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50532/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:55 INFO - PID 29235 | RSS: Main: 175501312 16:10:55 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | Cycle 1(1): loaded http://localhost:50532/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50532/tests/kraken/driver.html?testName=ai-astar) 16:10:57 INFO - PID 29235 | RSS: Main: 176959488 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | __start_tp_report 16:10:57 INFO - PID 29235 | _x_x_mozilla_page_load 16:10:57 INFO - PID 29235 | _x_x_mozilla_page_load_details 16:10:57 INFO - PID 29235 | |i|pagename|runs| 16:10:57 INFO - PID 29235 | |0;ai-astar;108;108;107;110;108;96;109;107;107;107 16:10:57 INFO - PID 29235 | |1;audio-beat-detection;120;117;118;122;121;143;116;119;118;120 16:10:57 INFO - PID 29235 | |2;audio-dft;163;161;162;163;163;162;172;167;162;165 16:10:57 INFO - PID 29235 | |3;audio-fft;74;73;74;74;74;74;74;74;77;73 16:10:57 INFO - PID 29235 | |4;audio-oscillator;79;78;79;79;79;78;80;80;79;78 16:10:57 INFO - PID 29235 | |5;imaging-gaussian-blur;112;114;113;113;112;114;114;113;114;116 16:10:57 INFO - PID 29235 | |6;imaging-darkroom;148;147;148;147;148;149;149;149;149;148 16:10:57 INFO - PID 29235 | |7;imaging-desaturate;102;94;93;99;94;94;95;99;95;98 16:10:57 INFO - PID 29235 | |8;json-parse-financial;54;54;54;54;54;56;54;53;51;51 16:10:57 INFO - PID 29235 | |9;json-stringify-tinderbox;37;37;38;39;37;38;38;37;38;37 16:10:57 INFO - PID 29235 | |10;stanford-crypto-aes;80;73;78;78;75;77;79;80;73;76 16:10:57 INFO - PID 29235 | |11;stanford-crypto-ccm;113;111;111;121;119;111;117;110;111;111 16:10:57 INFO - PID 29235 | |12;stanford-crypto-pbkdf2;168;174;164;167;169;168;166;167;166;167 16:10:57 INFO - PID 29235 | |13;stanford-crypto-sha256-iterative;70;74;76;78;66;65;67;69;67;70 16:10:57 INFO - PID 29235 | __end_tp_report 16:10:57 INFO - PID 29235 | __start_cc_report 16:10:57 INFO - PID 29235 | _x_x_mozilla_cycle_collect,915 16:10:57 INFO - PID 29235 | __end_cc_report 16:10:57 INFO - PID 29235 | __startTimestamp1505430657643__endTimestamp 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | ------- Summary: start ------- 16:10:57 INFO - PID 29235 | Number of tests: 14 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#0] ai-astar Cycles:10 Average:106.70 Median:108.00 stddev:3.89 (3.6%) stddev-sans-first:4.10 16:10:57 INFO - PID 29235 | Values: 108.0 108.0 107.0 110.0 108.0 96.0 109.0 107.0 107.0 107.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#1] audio-beat-detection Cycles:10 Average:121.40 Median:120.00 stddev:7.81 (6.5%) stddev-sans-first:8.26 16:10:57 INFO - PID 29235 | Values: 120.0 117.0 118.0 122.0 121.0 143.0 116.0 119.0 118.0 120.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#2] audio-dft Cycles:10 Average:164.00 Median:163.00 stddev:3.30 (2.0%) stddev-sans-first:3.48 16:10:57 INFO - PID 29235 | Values: 163.0 161.0 162.0 163.0 163.0 162.0 172.0 167.0 162.0 165.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#3] audio-fft Cycles:10 Average:74.10 Median:74.00 stddev:1.10 (1.5%) stddev-sans-first:1.17 16:10:57 INFO - PID 29235 | Values: 74.0 73.0 74.0 74.0 74.0 74.0 74.0 74.0 77.0 73.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#4] audio-oscillator Cycles:10 Average:78.90 Median:79.00 stddev:0.74 (0.9%) stddev-sans-first:0.78 16:10:57 INFO - PID 29235 | Values: 79.0 78.0 79.0 79.0 79.0 78.0 80.0 80.0 79.0 78.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:1.18 (1.0%) stddev-sans-first:1.12 16:10:57 INFO - PID 29235 | Values: 112.0 114.0 113.0 113.0 112.0 114.0 114.0 113.0 114.0 116.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 16:10:57 INFO - PID 29235 | Values: 148.0 147.0 148.0 147.0 148.0 149.0 149.0 149.0 149.0 148.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:95.00 stddev:2.98 (3.1%) stddev-sans-first:2.35 16:10:57 INFO - PID 29235 | Values: 102.0 94.0 93.0 99.0 94.0 94.0 95.0 99.0 95.0 98.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.51 (2.8%) stddev-sans-first:1.59 16:10:57 INFO - PID 29235 | Values: 54.0 54.0 54.0 54.0 54.0 56.0 54.0 53.0 51.0 51.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#9] json-stringify-tinderbox Cycles:10 Average:37.60 Median:38.00 stddev:0.70 (1.8%) stddev-sans-first:0.71 16:10:57 INFO - PID 29235 | Values: 37.0 37.0 38.0 39.0 37.0 38.0 38.0 37.0 38.0 37.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:78.00 stddev:2.60 (3.3%) stddev-sans-first:2.51 16:10:57 INFO - PID 29235 | Values: 80.0 73.0 78.0 78.0 75.0 77.0 79.0 80.0 73.0 76.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#11] stanford-crypto-ccm Cycles:10 Average:113.50 Median:112.00 stddev:3.98 (3.6%) stddev-sans-first:4.22 16:10:57 INFO - PID 29235 | Values: 113.0 111.0 111.0 121.0 119.0 111.0 117.0 110.0 111.0 111.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:167.50 stddev:2.63 (1.6%) stddev-sans-first:2.79 16:10:57 INFO - PID 29235 | Values: 168.0 174.0 164.0 167.0 169.0 168.0 166.0 167.0 166.0 167.0 16:10:57 INFO - PID 29235 | 16:10:57 INFO - PID 29235 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.00 stddev:4.42 (6.3%) stddev-sans-first:4.68 16:10:57 INFO - PID 29235 | Values: 70.0 74.0 76.0 78.0 66.0 65.0 67.0 69.0 67.0 70.0 16:10:57 INFO - PID 29235 | -------- Summary: end -------- 16:10:57 INFO - PID 29235 | 16:10:58 INFO - PID 29235 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:10:58 INFO - TEST-INFO | 29235: exit 0 16:10:58 INFO - TEST-OK | kraken | took 61328ms 16:10:58 INFO - SUITE-END | took 1169s 16:10:58 INFO - Completed test suite (00:19:30) 16:10:58 INFO - javascript benchmark 16:10:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8624.333862467593, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3131.295458521833, 3143.2927591291964, 3156.147840083176, 3183.4110689522427, 3197.2326641606005, 6319.747210111595, 6323.620582765032, 6327.7785277515295, 6356.427921487065, 6375.489960803142, 6157.076438534222, 6282.591631543946, 6284.842878928029, 6292.590981586193, 6317.305096439388, 5781.103928891029, 5781.248437703103, 5823.0, 5840.240768700084, 5932.6143800653035, 5850.590458667895, 5892.852678683024, 5892.88214235715, 5895.823125306226, 5917.349091599921, 5610.943890561124, 5750.166225897243, 5813.9418605814035, 5885.529157667398, 5893.911591326134, 5736.741846616912, 5739.253896993406, 5822.359540450547, 5858.736356863951, 5890.322612899528, 5793.768249270024, 5797.855053623651, 5832.766689332421, 5834.154047663107, 5840.094785308284, 6117.602355846856, 6221.346758590374, 6231.940570103048, 6296.0, 6367.808965731036, 6742.595444273359, 6764.898526522106, 6788.287229840845, 6797.762078327284, 6823.795286141423, 6834.2482326944, 6919.827004324882, 6926.445884329292, 6932.306769323078, 6933.445324374088, 6294.716737746812, 6324.146240257553, 6369.872602547923, 6377.23473183221, 6405.071264666643, 6772.2211945625895, 6886.8622627547165, 6905.274946130685, 6909.067275917787, 6920.30796920304, 6455.806325810233, 6544.3782840630065, 6546.70539825709, 6564.277929427759, 6567.934320656732, 6630.6353150576215, 6633.668316584151, 6634.104395906491, 6654.23476300222, 6663.167104111986, 6600.339966003457, 6605.504587155982, 6613.272540020594, 6689.832754181184, 6791.320867913268, 6798.932010679929, 6841.965790171017, 6843.931560684429, 6858.794236172923, 6858.862822743517, 5315.282436870973, 5316.521513063843, 5378.354597448253, 5382.381026182039, 5391.137418013099, 4551.52209018055, 4617.561331673485, 4620.676552641283, 4624.398828152319, 4648.721076735406, 5371.409144994048, 5789.739461724233, 5817.650940943557, 5832.650040997553, 5839.357670656224, 6730.427913627299, 6756.391924726799, 6863.691133898987, 6864.759733409307, 6905.861882762317, 5882.147088672119, 5948.10778383246, 5949.762009519571, 5951.672658003765, 5962.5826192167, 5834.5624078194305, 5855.736491857877, 5894.587378883522, 5902.586818922634, 5916.940830591725, 6420.711068001951, 6509.8698026040165, 6565.212174539086, 6657.267700552935, 6662.833429164213, 6732.865342693119, 6747.595144291358, 6751.797446076429, 6759.966200168968, 6766.0, 6459.289478157493, 6480.740770369321, 6500.219973603009, 6504.642244676588, 6505.967470162808, 6687.665616719242, 6758.831029224309, 6776.085228494288, 6782.93217067823, 6790.422814060663, 6810.897836532356, 6822.624755638289, 6837.487188460786, 6910.170779506491, 6921.930780692129, 6369.49044076482, 6503.479721622164, 6584.407403333723, 6591.670416479253, 6657.367550082734, 6622.569532980341, 6637.435817955527, 6637.834054148685, 6668.66656667155, 6681.432078273401, 6533.575317604341, 6600.8349791255605, 6603.405693487609, 6608.966955165193, 6613.338666133541, 6650.4347130492515, 6653.367930046637, 6662.966685166543, 6666.100076489805, 6749.662516874038, 6262.060690896402, 6383.042543618494, 6390.840228994312, 6490.350964903472, 6492.993585994179], "value": 6166.798711360256, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.8806179073134, 849.6219182463805, 873.9781505462363, 878.4904755241961, 878.7627340618031, 39748.40377394338, 41485.58514414855, 41778.58221417785, 41781.58218417819, 41936.58063419365, 38011.80994095033, 38279.80860095696, 38311.80844095782, 38348.61651383485, 38368.232635347274, 6821.283765204656, 6996.965015174917, 7036.226015138331, 7049.295070492935, 7052.753153639624, 6981.6509174541325, 7074.893876591856, 7079.433645308363, 7081.61051142187, 7082.150141982969, 7048.823779405505, 7077.25688802676, 7084.256153103927, 7084.752033678797, 7085.04351912493, 7613.80965475862, 7827.843443131105, 7829.41279404047, 7834.725784597512, 7857.803554911145, 7663.233676632348, 7692.884606730903, 7735.071791385014, 7747.186545412706, 7749.186335434754, 38264.61735382638, 38279.0, 38522.422163667565, 38969.805150974345, 38986.0, 3505.2113274513285, 3556.448750443685, 3556.87550935716, 3558.430651095812, 3559.76861504004, 32205.677943220504, 32245.51631725526, 32268.19329516781, 32307.51538726921, 32317.515237271225, 5181.559567436772, 5192.7403629818355, 5226.137687281594, 5229.372475302988, 5242.790288388498, 184556.0, 186275.0, 186294.0, 186330.0, 187195.0, 46765.53234467612, 46985.76507117443, 47039.0, 47141.76429117901, 47426.0, 49135.0, 49192.75403622959, 49242.50757492451, 49314.75342623336, 49441.50558494369, 15667.843321566867, 16074.598135046483, 16080.195990200444, 16092.597685057966, 16140.75788863168, 13225.338733063309, 13228.074034817662, 13232.73534529304, 13272.070955033056, 13291.401886915111, 13236.867631323756, 13263.602091937257, 13288.933555332354, 13424.664383390298, 13646.86353136456, 5453.072977593819, 5524.14375771756, 5545.473180047889, 5659.462351076641, 5660.490555849993, 1000.5747557288123, 1001.2590682894605, 1007.7027276953316, 1008.0977525114981, 1022.5551884930079, 22567.210147644753, 22610.208642697427, 22648.773512264666, 23009.769902301097, 23074.192403265806, 1107.7286064913988, 1110.3116068037868, 1112.6383925224334, 1122.5004872831676, 1123.5337335005881, 12232.877671223352, 12393.876061239453, 12424.192427492184, 12429.378531073591, 12487.562935297403, 224499.0, 225793.0, 226608.0, 226803.0, 226814.0, 44834.77582612066, 45465.54534454613, 45740.771296143306, 51960.0, 52864.20703689448, 1438.4030627289767, 1446.5515690136092, 1448.529228000904, 1452.4117732318427, 1458.0, 26745.33136671599, 26791.196264112106, 26805.329866753487, 27085.729142708322, 27275.59086613742, 37518.24963500769, 37525.62474375221, 37563.06092347713, 37571.24857502889, 37591.43612845755, 202091.0, 202640.0, 202905.0, 203984.0, 204389.0, 33594.832025839714, 33835.15412114717, 33850.49224261589, 33856.322873542886, 33859.3228135431, 41650.375244371964, 43466.34800477932, 43911.34132988071, 43947.78026109849, 44045.77977110094, 31165.0, 31402.21494462657, 31781.523277150398, 31834.0, 32132.35735285328, 31214.531782022834, 31273.37453250968, 31405.842970785, 31419.0, 32712.509312359856, 35637.64362356447, 35911.82044089763, 36140.4578931311, 36225.637743622225, 36363.27273454463, 30511.542326865558, 30584.388312233186, 30952.84523577368, 30999.380012400077, 30999.535006975366, 22482.887585561966, 22653.320400388016, 22795.65806512937, 22981.655275170553, 23049.65425518652], "value": 16933.123174623997, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.3101753825256, 565.9830205093843, 573.4781348972435, 573.9282589676291, 600.7837178615699, 22168.88915555422, 22760.54478910421, 22914.427139321506, 22946.541069178606, 22954.311370658885, 23329.76670233297, 24449.511009779795, 24468.75531244687, 24512.264632061066, 24782.75217247827, 6625.204975402947, 6751.966240168792, 6795.490338224628, 6796.660166991655, 6821.317868213164, 6173.845653858667, 6615.470762339025, 6668.733250669968, 6730.293319201487, 6745.898811517831, 6450.161479007746, 6472.158619379474, 6475.676216189196, 6476.384743449365, 6478.481721462272, 6427.8393040173905, 6462.676866156697, 6468.838279043014, 6469.83825404364, 6470.288268290485, 5950.761969521214, 6744.5616034957575, 7003.2296447390745, 7004.0, 7051.717931282743, 29471.70528294711, 30077.0, 30080.548791768142, 30102.84948575265, 30142.24644383886, 2069.1619893942975, 2100.6218880601486, 2100.884451355175, 2102.2326850699537, 2106.420734298065, 19677.80322196774, 20023.399298021082, 20346.18615255388, 20382.490437739027, 20468.795312046837, 2442.218490083174, 2474.7648973347505, 2533.505966336565, 2560.3215147985798, 2625.4355313607475, 117361.0, 122296.0, 128320.3583982074, 133157.67369305217, 138117.30941345228, 19009.524761880788, 20750.273740419012, 20785.792142078688, 21186.19917307046, 21600.675989860163, 40343.39484907729, 40356.39465408021, 40808.79595602003, 40868.591314087076, 40920.38619420711, 11323.43382830855, 11391.715207119889, 11429.314241145557, 11569.768604628029, 11677.124215683858, 9471.479068651153, 9502.904970950201, 9583.329166958247, 9631.759206019906, 9685.17675997548, 10815.78368432627, 10909.83635245472, 10914.56341746337, 11168.553257869595, 11559.653210403701, 3526.20660351424, 3563.803990780532, 4040.878773636796, 4168.624823765815, 4296.849610263688, 962.1148543340004, 979.4417182206074, 987.916027137687, 989.4904124375952, 1000.7648202672366, 19119.0, 19172.328968486036, 19183.616327673368, 19599.412017639494, 19702.901485492483, 1010.1867996262932, 1029.8867124616286, 1057.7672911959357, 1061.135174832509, 1073.538378497238, 10500.474976251156, 10634.20243481742, 10822.0, 11057.0, 11137.554497819996, 468493.0, 485081.0, 491406.0, 493575.0, 499123.0, 113224.43387783009, 113457.0, 114263.0, 115894.0, 118822.0, 1174.8120300751839, 1206.4182649130867, 1213.9210951288137, 1218.7867123253395, 1218.823270625772, 17453.82546174522, 17537.824621753618, 17566.824331756517, 17580.38468653591, 17659.823401765818, 52318.0, 56601.433985661264, 57007.42992570021, 57224.71387643035, 58169.0, 70761.64619176871, 70862.29137708763, 71083.64458177677, 71797.28202717906, 72455.0, 18767.906160469658, 18894.52763680919, 19788.20847166097, 19791.60416791685, 20084.297049603192, 22218.44453888666, 22263.44341391478, 22366.552668946206, 22392.0, 22504.0, 17746.37887673925, 17915.552111196805, 17934.91032544829, 18124.546886327946, 18159.636807263516, 16220.837791622405, 16583.0, 16720.498385048468, 16761.5809604756, 16987.0, 18034.63930721352, 18583.72124418162, 18890.7166392507, 18961.0, 19046.333378331692, 18405.355812547034, 19513.02434878222, 19562.119704613044, 19603.901980490005, 19617.332526735387, 17957.191926363084, 17985.34194631371, 18051.277948881896, 18057.638847223243, 18080.276788928822], "value": 12719.125258534596, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.0948336164686, 1374.5120482228815, 1387.8272859433775, 1397.5877116250708, 1402.291842619477, 14817.259137043158, 15479.303431345588, 15502.767458488104, 15507.224638768059, 15517.301721422535, 15206.391744330214, 15214.163221022867, 15278.0, 15330.386784528606, 15333.846661533382, 2666.1468330134367, 2697.1908427471776, 2700.8649567521643, 2704.2157774245447, 2737.7262273772662, 2673.17131689177, 2719.5648696208514, 2722.3738540135682, 2730.931726706838, 2735.0153944579934, 2275.180934863448, 2358.882055897207, 2548.503041906829, 2655.6414883990706, 2719.8504082275467, 2698.473797609467, 2700.648915640964, 2722.91831245062, 2725.74105459981, 2727.1954773341813, 2653.6550248467574, 2655.150351887397, 2656.242970753326, 2667.9333016674736, 2669.2259244819174, 14656.633584160374, 15068.773968390373, 15081.396744130221, 15086.54740357791, 15104.697906041818, 7937.404694647925, 8292.212239837207, 8295.336373090138, 8300.460470069487, 8302.916970830274, 5245.711485868275, 5392.487713667196, 5465.808696695596, 5693.060644993571, 5700.686462244575, 1748.1434097292235, 1758.1297257857443, 1759.7624320716668, 1801.2164708351777, 1827.5979284557377, 26747.86626066877, 26750.73249267502, 26768.0, 26779.33051673704, 26782.598261025905, 7131.393831524275, 7155.141383033965, 7186.389156921616, 7318.926810731931, 7358.448116391292, 20307.898460507604, 20314.18743250283, 20319.8984005082, 20346.593068138554, 20382.69425958612, 7999.200079991954, 8187.795305117301, 8204.220599043081, 8215.383846211558, 8222.506649601042, 7109.253528379446, 7537.886931696029, 7555.357794587411, 7557.130929943127, 7594.772156835304, 5424.349078110573, 5696.572757043238, 5704.22992895962, 5706.857328566736, 5719.056355701263, 1751.816059313779, 1762.4007837335369, 1771.2029586686042, 1783.7948635907037, 1796.6614871920503, 791.6556298010383, 901.2204443156753, 923.0861447167256, 930.6603089872283, 950.6054987180381, 8317.251447369645, 8539.231469167804, 8578.227959483676, 8932.508712020901, 8962.507062111516, 975.2100798353357, 1001.9699409017741, 1003.6888564544869, 1005.5475036233724, 1008.702432782331, 5437.184422336602, 5574.0, 5629.943700562942, 5660.122680984454, 5685.289338832646, 21012.47468813292, 22080.0, 22098.0, 22130.557388852132, 22149.114035438724, 20875.0, 21605.243816466347, 21761.782382175974, 21776.782232177477, 21851.0, 11038.44807759625, 11235.213535052468, 11474.770504589862, 11526.193166478266, 11530.76938461243, 8512.659493620433, 8533.957330213309, 8563.700270490503, 8676.78308042304, 8685.8697119542, 17892.373766918095, 18172.45482635523, 18248.0875956199, 18343.35798247055, 18866.05669716536, 19432.8056719431, 19545.315913942944, 19563.119659615066, 19752.90123549373, 19757.90121049443, 11495.195336326376, 11730.472128754398, 11792.528298868292, 11860.110491713329, 11874.584389546324, 13702.45190192381, 13898.930505347409, 13902.860971390157, 13931.512397066053, 14247.21640309793, 11018.0, 11120.833187502356, 11325.830112548483, 11386.487608057492, 11511.42442877836, 11350.318980861175, 11496.0, 11506.30962142274, 11576.594819181288, 11693.824592630946, 10117.342372745748, 10176.389416635026, 10188.337758045704, 10261.84607230907, 10315.27793054479, 7477.289657482531, 7513.4740568159705, 7522.962385188258, 7546.207648196861, 7584.696612135453, 7738.264864838056, 7790.571518566533, 7946.7615971520945, 7948.483348582324, 7951.204879512003], "value": 6840.94619625242, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.1722149842013, 507.79688124750106, 521.1921521641455, 534.1133718028074, 534.3187436019075, 6164.938350616492, 6252.187215661957, 6280.246370435542, 6291.213598300213, 6310.589811662238, 6117.6329420234815, 6158.84602884927, 6161.199044124258, 6205.286392064914, 6217.5025997920175, 5141.922871156936, 5145.948540514584, 5172.40517340506, 5189.8183563575285, 5192.0, 4828.7585620719, 4932.358793356859, 5024.899502009957, 5105.897882042375, 5180.896382072357, 4876.829310974116, 4882.169186555042, 4902.632302577304, 4925.507449255064, 4963.329950456697, 4878.634102442314, 4889.853304400874, 4910.232316366105, 4928.137575924235, 4947.777350019258, 4308.9784551077355, 4384.342348647697, 4409.801558929856, 4416.3375493675885, 4418.270985287424, 5922.674252916088, 5969.761209551612, 6009.399060094, 6013.639181649115, 6031.577789554733, 5612.494875461187, 5634.690092044936, 5656.943430565683, 5664.801731939404, 5678.716064196774, 6414.967925160391, 6435.324290949429, 6453.677316134174, 6468.191476065492, 6508.284088750233, 4530.986893715708, 4806.855794326141, 4835.6131509479155, 4857.417109946794, 4911.189653707134, 6631.3700198481065, 6670.83322916931, 6672.733090676367, 6747.595144291358, 6820.522563420563, 4930.0, 4930.605551555938, 4931.569844744984, 4931.704097754075, 4968.627352948543, 4929.383827021622, 4940.802367905316, 5020.7740651670765, 5025.698458092526, 5052.823151189691, 5614.641850422832, 5617.8033768817895, 5648.4633959773755, 5650.180723795027, 5652.3217213934595, 6541.514224594624, 6582.76960306387, 6586.769463068769, 6598.670066496752, 6610.3720146586, 1706.7610534525177, 1723.5608267096864, 1727.421313859846, 1752.404182577904, 1755.3066538717135, 1150.4190383856267, 1169.2283093158474, 1185.040117504824, 1205.9698507537382, 1213.350857291345, 5614.0, 5636.661800291996, 5662.017424666267, 5674.2339784129435, 5696.971515142398, 6533.803985880431, 6635.9336406636285, 6674.132362792806, 6696.330366963265, 6706.429953453913, 5618.269624948813, 5625.54995600351, 5626.493615574618, 5653.406392328829, 5654.067078932012, 3458.0317511096932, 3462.6149540183624, 3555.8755443559867, 3555.9644403555635, 3558.6797188252663, 6345.524085693592, 6399.048290271418, 6400.39196276353, 6441.420272175433, 6448.0, 6767.308172956761, 6810.387065164159, 6826.624535650587, 6844.178698556205, 6885.242623311432, 6223.222097237845, 6375.458086062737, 6392.137061496732, 6428.71070801815, 6502.902456463157, 6101.542384321282, 6531.150950376421, 6673.399394054559, 6679.666016699242, 6724.663766811737, 6522.315156908457, 6531.706073226621, 6545.770898018737, 6571.769988050395, 6580.802575922538, 6319.241690996926, 6322.3561873912195, 6354.4281014708895, 6355.904661430175, 6444.677766111582, 4827.662063655511, 4873.122837889072, 4941.431735350337, 4967.82612608557, 4970.801167953241, 6269.592476489013, 6439.871202576016, 6484.416402523795, 6707.463402927851, 6763.086983257197, 6195.845103872439, 6300.369963003846, 6395.0, 6414.198225221848, 6521.152250207443, 6369.0764839099, 6468.191476065492, 6502.4719190989135, 6548.607083575001, 6559.540172435337, 6399.424051835357, 6401.295857455583, 6472.126262954441, 6493.155889734304, 6497.480201583768, 6488.286288508355, 6510.967445162744, 6565.737370505318, 6608.43828274602, 6614.801555953329, 6291.842703932438, 6318.178636777373, 6332.620042797447, 6396.9040464394, 6402.967985160044], "value": 5001.50594908166, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [903.1690844423126, 919.3886065766266, 926.249737712453, 928.5264515097298, 942.7643089227695, 19100.522486937814, 20160.588758786907, 20181.29365472209, 20213.898930505344, 20223.191072357087, 18670.159842807072, 18815.247390104414, 18874.33939812107, 18878.15048322825, 18909.810901890978, 941.0777438110639, 962.4706411473696, 966.8791401074866, 968.375397868378, 968.4479846487505, 978.3640633588153, 978.5303054533828, 979.7697541077841, 979.7991411760572, 980.1766516126453, 976.8241716491028, 997.5959736306808, 1010.5604062232952, 1012.9493525323741, 1015.0458568945205, 993.0665174735725, 999.4552968632113, 1000.1548691355792, 1002.7543251903257, 1004.8944860789621, 720.4200618502103, 734.9522281051715, 736.1350413264446, 741.2254194366845, 744.2483092077041, 19493.902530487398, 19611.0, 19667.311644092388, 19679.803201968083, 19734.506637334038, 21376.679349809765, 21380.786192138192, 21998.450038749, 22035.0, 22077.448063798372, 13795.10331828428, 13832.723345533033, 14062.718745625132, 14068.155910645393, 14144.151350918977, 3830.2339532093692, 3854.5181852268465, 3863.38185890259, 3863.5943225961432, 3870.3807390817333, 111167.44416277867, 133963.0, 142174.2891285537, 142548.0, 143144.28427857897, 17277.56806079833, 17410.738838917172, 17592.0, 17691.823081769275, 17827.197776100107, 31615.52576711351, 32617.51073733896, 32663.183420414203, 32680.83659581687, 32714.509282360785, 8624.223819856243, 8884.8667269991, 8945.507997060224, 8955.910440895637, 9014.368994170345, 4480.7535585542455, 4603.838865639686, 4605.539446055368, 4614.284785858197, 4629.1436084324505, 5373.462653734595, 5410.998965191374, 5459.454054594509, 5463.754131064112, 5490.258815059996, 1123.4270522033821, 1130.3669944830922, 1132.9660110196708, 1139.5214010115772, 1144.6279959013355, 683.565935630868, 698.1238545625208, 700.3311837195466, 715.8532500837316, 726.9491135620456, 12253.264804111783, 12543.623691289276, 12651.86133248012, 13452.798208026887, 13612.251326176956, 679.5311235247664, 680.6562685843618, 683.8119517132828, 693.8993845892318, 707.2679776431369, 5597.888042239132, 5674.792285236707, 5736.0, 5819.5053420459735, 5836.795712150054, 122410.3879480597, 122538.0, 125586.0, 127443.0, 130253.34873325762, 109452.4527377358, 110872.0, 111061.0, 111107.44446277717, 111395.44302278598, 96113.0, 97092.0, 99212.0, 101352.99999999853, 101916.49041754744, 10235.437050962268, 10396.064354208158, 10588.0, 10626.149908007186, 10643.893561064291, 43401.56598434102, 43924.0, 44102.33846492369, 44279.55720442755, 45084.54915450804, 57641.13538296845, 58562.7071864638, 58916.41083589109, 59203.40796591979, 59882.40117598768, 15746.527604171893, 16022.27899744491, 16072.196390180678, 16299.755503667691, 16305.184740762677, 17582.20880060375, 17610.559736006704, 17661.470155895342, 17682.91158544199, 17869.821301786815, 14859.257037147883, 14917.328720207399, 14999.7000059996, 15140.848591513944, 15155.166465844484, 16577.41979030728, 16728.91635541863, 16837.2423240952, 17084.145792710562, 17095.91452042732, 16248.67502649964, 16321.0, 16404.671906562042, 16520.0, 16572.41996530164, 12330.568430104904, 12358.814617780561, 12513.061520385825, 12590.685232869251, 12984.026198035373, 12665.87334126647, 12778.744425111632, 12885.742285154432, 12931.353432328158, 13145.079844410799], "value": 9054.322533385723, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1422.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 107.0, 110.0, 108.0, 96.0, 109.0, 107.0, 107.0, 107.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 118.0, 122.0, 121.0, 143.0, 116.0, 119.0, 118.0, 120.0], "value": 121.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 163.0, 163.0, 162.0, 172.0, 167.0, 162.0, 165.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 74.0, 74.0, 74.0, 74.0, 74.0, 77.0, 73.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 79.0, 79.0, 79.0, 78.0, 80.0, 80.0, 79.0, 78.0], "value": 78.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 113.0, 113.0, 112.0, 114.0, 114.0, 113.0, 114.0, 116.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 147.0, 148.0, 149.0, 149.0, 149.0, 149.0, 148.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 94.0, 93.0, 99.0, 94.0, 94.0, 95.0, 99.0, 95.0, 98.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 54.0, 56.0, 54.0, 53.0, 51.0, 51.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 38.0, 39.0, 37.0, 38.0, 38.0, 37.0, 38.0, 37.0], "value": 37.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 78.0, 78.0, 75.0, 77.0, 79.0, 80.0, 73.0, 76.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 111.0, 121.0, 119.0, 111.0, 117.0, 110.0, 111.0, 111.0], "value": 113.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 174.0, 164.0, 167.0, 169.0, 168.0, 166.0, 167.0, 166.0, 167.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 76.0, 78.0, 66.0, 65.0, 67.0, 69.0, 67.0, 70.0], "value": 70.2, "unit": "score"}]}]} 16:10:58 INFO - Return code: 0 16:10:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:10:58 INFO - # TBPL SUCCESS # 16:10:58 INFO - Running post-action listener: _package_coverage_data 16:10:58 INFO - Running post-action listener: _resource_record_post_action 16:10:58 INFO - [mozharness: 2017-09-14 23:10:58.939905Z] Finished run-tests step (success) 16:10:58 INFO - Running post-run listener: _resource_record_post_run 16:10:59 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 336850944; Read time: 12; Write time: 311408 16:10:59 INFO - TinderboxPrint: CPU usage
13.1% 16:10:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:10:59 INFO - TinderboxPrint: I/O write bytes / time
336,850,944 / 311,408 16:10:59 INFO - TinderboxPrint: CPU idle
8,229.2 (86.9%) 16:10:59 INFO - TinderboxPrint: CPU user
1,192.3 (12.6%) 16:10:59 INFO - TinderboxPrint: Swap in / out
0 / 0 16:10:59 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10571776; Read time: 0; Write time: 896 16:10:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:10:59 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 0; Write bytes: 326279168; Read time: 0; Write time: 310512 16:10:59 INFO - Running post-run listener: _upload_blobber_files 16:10:59 INFO - Blob upload gear active. 16:10:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:10:59 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 16:10:59 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'] 16:10:59 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 16:10:59 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:10:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:11:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:01 INFO - (blobuploader) - INFO - Done attempting. 16:11:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:11:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:11:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:07 INFO - (blobuploader) - INFO - Done attempting. 16:11:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:11:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:11:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:08 INFO - (blobuploader) - INFO - Done attempting. 16:11:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:11:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:11:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:10 INFO - (blobuploader) - INFO - Done attempting. 16:11:10 INFO - (blobuploader) - INFO - Iteration through files over. 16:11:10 INFO - Return code: 0 16:11:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:11:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:11:10 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec643a5714b197c4d605a84acdbf8a7f0fe828502ea63f1b22e320a3a819bec5a877dafa3582290a332ead824ab96249b5a0a39e53f70ff17dfc93f309d1eb30", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7623e20d15f27f25172b10c0a74027d28c200f7ff449c681ed054124273be06d191723fb148e6bf102193625d6841690eed72409493af5fc3cb15703a63b3bb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af8fd7d5dd23ea71be434e5161b1780efa973672d34d7836034478ac6f4b675e6120f9bb47d7cd04d3ca31f1eba81d8bab7ead99de0e64717e4787f8c94c79aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cbb2a65e93e7288e57c797440dfceeaf6469aa6174b701e01fa08472089c4b058f16d901b0fae41979e6f12927ce62bfbe511544b42937e074573042d22f75e"} 16:11:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:11:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:11:10 INFO - Contents: 16:11:10 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec643a5714b197c4d605a84acdbf8a7f0fe828502ea63f1b22e320a3a819bec5a877dafa3582290a332ead824ab96249b5a0a39e53f70ff17dfc93f309d1eb30", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7623e20d15f27f25172b10c0a74027d28c200f7ff449c681ed054124273be06d191723fb148e6bf102193625d6841690eed72409493af5fc3cb15703a63b3bb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af8fd7d5dd23ea71be434e5161b1780efa973672d34d7836034478ac6f4b675e6120f9bb47d7cd04d3ca31f1eba81d8bab7ead99de0e64717e4787f8c94c79aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cbb2a65e93e7288e57c797440dfceeaf6469aa6174b701e01fa08472089c4b058f16d901b0fae41979e6f12927ce62bfbe511544b42937e074573042d22f75e"} 16:11:10 INFO - Running post-run listener: copy_logs_to_upload_dir 16:11:10 INFO - Copying logs to upload dir... 16:11:10 INFO - mkdir: /builds/slave/test/build/upload/logs 16:11:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.497042 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-14 16:11:10.432214) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 16:11:10.440053) ========= 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=745a4373a2a16df560f216e000000264-1505427955.148683-1209847522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec643a5714b197c4d605a84acdbf8a7f0fe828502ea63f1b22e320a3a819bec5a877dafa3582290a332ead824ab96249b5a0a39e53f70ff17dfc93f309d1eb30", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7623e20d15f27f25172b10c0a74027d28c200f7ff449c681ed054124273be06d191723fb148e6bf102193625d6841690eed72409493af5fc3cb15703a63b3bb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af8fd7d5dd23ea71be434e5161b1780efa973672d34d7836034478ac6f4b675e6120f9bb47d7cd04d3ca31f1eba81d8bab7ead99de0e64717e4787f8c94c79aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cbb2a65e93e7288e57c797440dfceeaf6469aa6174b701e01fa08472089c4b058f16d901b0fae41979e6f12927ce62bfbe511544b42937e074573042d22f75e"} build_url:https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011405 build_url: 'https://queue.taskcluster.net/v1/task/Nu1bmwwJTLu1t2PmdT-yrg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec643a5714b197c4d605a84acdbf8a7f0fe828502ea63f1b22e320a3a819bec5a877dafa3582290a332ead824ab96249b5a0a39e53f70ff17dfc93f309d1eb30", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7623e20d15f27f25172b10c0a74027d28c200f7ff449c681ed054124273be06d191723fb148e6bf102193625d6841690eed72409493af5fc3cb15703a63b3bb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af8fd7d5dd23ea71be434e5161b1780efa973672d34d7836034478ac6f4b675e6120f9bb47d7cd04d3ca31f1eba81d8bab7ead99de0e64717e4787f8c94c79aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cbb2a65e93e7288e57c797440dfceeaf6469aa6174b701e01fa08472089c4b058f16d901b0fae41979e6f12927ce62bfbe511544b42937e074573042d22f75e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 16:11:10.469348) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 16:11:10.469694) ========= 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=745a4373a2a16df560f216e000000264-1505427955.148683-1209847522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004601 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 16:11:10.550534) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 16:11:10.553858) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 16:11:10.554186) ========= ========= Total master_lag: 0.22 =========