builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-065 starttime: 1505559118.58 results: success (0) revision: 27e7b4261e4b5a016e09adb5332a181e43ab520e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:58.576912) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:58.577334) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:58.577606) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:58.609933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:58.610290) ========= 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=523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-16 03:51:58-- 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% 227M=0s 2017-09-16 03:51:58 (227 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.211821 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:58.865687) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:58.865997) ========= 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=523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020994 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:58.915626) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:58.915976) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 27e7b4261e4b5a016e09adb5332a181e43ab520e --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 27e7b4261e4b5a016e09adb5332a181e43ab520e --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=523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-16 03:51:58,976 truncating revision to first 12 chars 2017-09-16 03:51:58,976 Setting DEBUG logging. 2017-09-16 03:51:58,976 attempt 1/10 2017-09-16 03:51:58,976 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/27e7b4261e4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-16 03:51:59,385 unpacking tar archive at: mozilla-central-27e7b4261e4b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686022 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:59.618060) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:59.618373) ========= script_repo_revision: 27e7b4261e4b5a016e09adb5332a181e43ab520e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:59.618770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:59.619032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 03:51:59.632692) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-16 03:51:59.633030) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:51:59 INFO - MultiFileLogger online at 20170916 03:51:59 in /builds/slave/test 03:51:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:51:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:51:59 INFO - {'append_to_log': False, 03:51:59 INFO - 'base_work_dir': '/builds/slave/test', 03:51:59 INFO - 'blob_upload_branch': 'Firefox', 03:51:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:51:59 INFO - 'branch': 'Firefox', 03:51:59 INFO - 'buildbot_json_path': 'buildprops.json', 03:51:59 INFO - 'code_coverage': False, 03:51:59 INFO - 'config_files': ('talos/linux_config.py',), 03:51:59 INFO - 'default_actions': ('clobber', 03:51:59 INFO - 'read-buildbot-config', 03:51:59 INFO - 'download-and-extract', 03:51:59 INFO - 'populate-webroot', 03:51:59 INFO - 'create-virtualenv', 03:51:59 INFO - 'install', 03:51:59 INFO - 'setup-mitmproxy', 03:51:59 INFO - 'run-tests'), 03:51:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:51:59 INFO - 'disable_ccov_upload': False, 03:51:59 INFO - 'disable_stylo': False, 03:51:59 INFO - 'download_minidump_stackwalk': True, 03:51:59 INFO - 'download_symbols': 'ondemand', 03:51:59 INFO - 'enable_stylo': False, 03:51:59 INFO - 'enable_webrender': False, 03:51:59 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:51:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:51:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:51:59 INFO - 'gecko_profile': False, 03:51:59 INFO - 'gecko_profile_interval': 0, 03:51:59 INFO - 'installer_path': 'installer.exe', 03:51:59 INFO - 'log_level': 'info', 03:51:59 INFO - 'log_name': 'talos', 03:51:59 INFO - 'log_to_console': True, 03:51:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:51:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:51:59 INFO - 'opt_config_files': (), 03:51:59 INFO - 'pip_index': False, 03:51:59 INFO - 'suite': 'dromaeojs-e10s', 03:51:59 INFO - 'system_bits': '32', 03:51:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:51:59 INFO - 'title': 'talos-linux64-ix-065', 03:51:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:51:59 INFO - 'use_talos_json': True, 03:51:59 INFO - 'verify': 'False', 03:51:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:51:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:51:59 INFO - 'work_dir': 'build'} 03:51:59 INFO - [mozharness: 2017-09-16 10:51:59.936273Z] Running clobber step. 03:51:59 INFO - Running pre-action listener: _resource_record_pre_action 03:51:59 INFO - Running main action method: clobber 03:51:59 INFO - rmtree: /builds/slave/test/build 03:51:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:52:00 INFO - Running post-action listener: _resource_record_post_action 03:52:00 INFO - [mozharness: 2017-09-16 10:52:00.908891Z] Finished clobber step (success) 03:52:00 INFO - [mozharness: 2017-09-16 10:52:00.908987Z] Running read-buildbot-config step. 03:52:00 INFO - Running pre-action listener: _resource_record_pre_action 03:52:00 INFO - Running main action method: read_buildbot_config 03:52:00 INFO - Using buildbot properties: 03:52:00 INFO - { 03:52:00 INFO - "project": "", 03:52:00 INFO - "product": "firefox", 03:52:00 INFO - "who": "archaeopteryx@coole-files.de", 03:52:00 INFO - "installer_path": "public/build/target.tar.bz2", 03:52:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:52:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:52:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:52:00 INFO - "stage_platform": "linux64-pgo", 03:52:00 INFO - "basedir": "/builds/slave/test", 03:52:00 INFO - "buildnumber": 189, 03:52:00 INFO - "platform": "ubuntu64_hw", 03:52:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:52:00 INFO - "slavebuilddir": "test", 03:52:00 INFO - "taskId": "dE4NYpjITcC3ji7RZzoY5w", 03:52:00 INFO - "branch": "mozilla-central", 03:52:00 INFO - "script_repo_revision": "production", 03:52:00 INFO - "revision": "27e7b4261e4b5a016e09adb5332a181e43ab520e", 03:52:00 INFO - "slavename": "talos-linux64-ix-065", 03:52:00 INFO - "repo_path": "mozilla-central" 03:52:00 INFO - } 03:52:00 INFO - Finding installer, test and symbols from parent task. 03:52:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dE4NYpjITcC3ji7RZzoY5w'}, attempt #1 03:52:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dE4NYpjITcC3ji7RZzoY5w'}, attempt #1 03:52:02 INFO - Task dependencies: Uxal_AuHRK26pEqWpbUtvQ 03:52:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ'}, attempt #1 03:52:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.tar.bz2 03:52:02 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.test_packages.json 03:52:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.crashreporter-symbols.zip 03:52:02 INFO - Running post-action listener: _resource_record_post_action 03:52:02 INFO - [mozharness: 2017-09-16 10:52:02.579158Z] Finished read-buildbot-config step (success) 03:52:02 INFO - [mozharness: 2017-09-16 10:52:02.579378Z] Running download-and-extract step. 03:52:02 INFO - Running pre-action listener: _resource_record_pre_action 03:52:02 INFO - Running main action method: download_and_extract 03:52:02 INFO - mkdir: /builds/slave/test/build/tests 03:52:02 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')]} 03:52:02 INFO - trying https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.test_packages.json 03:52:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:52:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:52:03 INFO - Downloaded 989 bytes. 03:52:03 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:52:03 INFO - Using the following test package requirements: 03:52:03 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:52:03 INFO - u'common': [u'target.common.tests.zip'], 03:52:03 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:52:03 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:52:03 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:52:03 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:52:03 INFO - u'mozbase': [u'target.common.tests.zip'], 03:52:03 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:52:03 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:52:03 INFO - u'web-platform': [u'target.common.tests.zip', 03:52:03 INFO - u'target.web-platform.tests.tar.gz'], 03:52:03 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:52:03 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:52:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.common.tests.zip 03:52:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:52:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.common.tests.zip into memory 03:52:05 INFO - Content-Length response header: 55279463 03:52:05 INFO - Bytes received: 55279463 03:52:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.talos.tests.zip 03:52:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:52:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.talos.tests.zip into memory 03:52:17 INFO - Content-Length response header: 14508748 03:52:17 INFO - Bytes received: 14508748 03:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:52:17 INFO - trying https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.tar.bz2 03:52:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:52:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:52:19 INFO - Downloaded 62177886 bytes. 03:52:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.tar.bz2 03:52:19 INFO - mkdir: /builds/slave/test/properties 03:52:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:52:19 INFO - Writing to file /builds/slave/test/properties/build_url 03:52:19 INFO - Contents: 03:52:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.tar.bz2 03:52:19 INFO - Running post-action listener: _resource_record_post_action 03:52:19 INFO - Running post-action listener: find_tests_for_verification 03:52:19 INFO - Running post-action listener: set_extra_try_arguments 03:52:19 INFO - [mozharness: 2017-09-16 10:52:19.747400Z] Finished download-and-extract step (success) 03:52:19 INFO - [mozharness: 2017-09-16 10:52:19.747536Z] Running populate-webroot step. 03:52:19 INFO - Running pre-action listener: _resource_record_pre_action 03:52:19 INFO - Running main action method: populate_webroot 03:52:19 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:52:19 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['tresize', 03:52:19 INFO - 'tcanvasmark']}, 03:52:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:52:19 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['dromaeo_css', 03:52:19 INFO - 'kraken']}, 03:52:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:52:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:19 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:52:19 INFO - 'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:52:19 INFO - 'tests': ['damp', 'tps']}, 03:52:19 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:52:19 INFO - 'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['damp', 'tps']}, 03:52:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:52:19 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['dromaeo_dom']}, 03:52:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:52:19 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['basic_compositor_video', 03:52:19 INFO - 'glvideo']}, 03:52:19 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:52:19 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:52:19 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:52:19 INFO - 'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['ts_paint_webext', 03:52:19 INFO - 'tp5o_webext']}, 03:52:19 INFO - 'other-e10s': {'tests': ['a11yr', 03:52:19 INFO - 'ts_paint', 03:52:19 INFO - 'tpaint', 03:52:19 INFO - 'sessionrestore', 03:52:19 INFO - 'sessionrestore_many_windows', 03:52:19 INFO - 'sessionrestore_no_auto_restore', 03:52:19 INFO - 'tabpaint']}, 03:52:19 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['a11yr', 03:52:19 INFO - 'ts_paint', 03:52:19 INFO - 'tpaint', 03:52:19 INFO - 'sessionrestore', 03:52:19 INFO - 'sessionrestore_many_windows', 03:52:19 INFO - 'sessionrestore_no_auto_restore', 03:52:19 INFO - 'tabpaint']}, 03:52:19 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:52:19 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:52:19 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['perf_reftest_singletons']}, 03:52:19 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['bloom_basic']}, 03:52:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:52:19 INFO - 'tsvgr_opacity', 03:52:19 INFO - 'tart', 03:52:19 INFO - 'tscrollx', 03:52:19 INFO - 'tsvg_static']}, 03:52:19 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['tsvgx', 03:52:19 INFO - 'tsvgr_opacity', 03:52:19 INFO - 'tart', 03:52:19 INFO - 'tscrollx', 03:52:19 INFO - 'tsvg_static']}, 03:52:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:52:19 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:52:19 INFO - 'talos_options': ['--disable-stylo'], 03:52:19 INFO - 'tests': ['tp5o']}, 03:52:19 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:52:19 INFO - 'talos_options': ['--mitmproxy', 03:52:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:52:19 INFO - '--firstNonBlankPaint'], 03:52:19 INFO - 'tests': ['tp6_google', 03:52:19 INFO - 'tp6_youtube', 03:52:19 INFO - 'tp6_amazon', 03:52:19 INFO - 'tp6_facebook']}, 03:52:19 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:52:19 INFO - 'talos_options': ['--disable-stylo', 03:52:19 INFO - '--mitmproxy', 03:52:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:52:19 INFO - '--firstNonBlankPaint'], 03:52:19 INFO - 'tests': ['tp6_google', 03:52:19 INFO - 'tp6_youtube', 03:52:19 INFO - 'tp6_amazon', 03:52:19 INFO - 'tp6_facebook']}, 03:52:19 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:52:19 INFO - 'talos_options': ['--stylo-threads=1', 03:52:19 INFO - '--mitmproxy', 03:52:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:52:19 INFO - '--firstNonBlankPaint'], 03:52:19 INFO - 'tests': ['tp6_google', 03:52:19 INFO - 'tp6_youtube', 03:52:19 INFO - 'tp6_amazon', 03:52:19 INFO - 'tp6_facebook']}, 03:52:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:52:19 INFO - 'talos_options': ['--xperf_path', 03:52:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:19 INFO - 'tests': ['tp5n']}, 03:52:19 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:52:19 INFO - 'talos_options': ['--disable-stylo', 03:52:19 INFO - '--xperf_path', 03:52:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:19 INFO - 'tests': ['tp5n']}}} 03:52:19 INFO - Running post-action listener: _resource_record_post_action 03:52:19 INFO - [mozharness: 2017-09-16 10:52:19.763009Z] Finished populate-webroot step (success) 03:52:19 INFO - [mozharness: 2017-09-16 10:52:19.763098Z] Running create-virtualenv step. 03:52:19 INFO - Running pre-action listener: _resource_record_pre_action 03:52:19 INFO - Running main action method: create_virtualenv 03:52:19 INFO - Creating virtualenv /builds/slave/test/build/venv 03:52:19 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:52:19 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:52:19 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:52:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:52:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:52:22 INFO - Installing distribute.............................................................................................................................................................................................done. 03:52:22 INFO - Installing pip...............done. 03:52:22 INFO - Return code: 0 03:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:52:22 INFO - Reading from file tmpfile_stdout 03:52:22 INFO - Output received: 03:52:22 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:52:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:52:22 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')]} 03:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:22 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')]} 03:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:22 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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:22 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 03:52:22 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 03:52:22 INFO - Using env: {'DISPLAY': ':0', 03:52:22 INFO - 'HOME': '/home/cltbld', 03:52:22 INFO - 'LANG': 'en_US.UTF-8', 03:52:22 INFO - 'LANGUAGE': 'en_US:en', 03:52:22 INFO - 'LOGNAME': 'cltbld', 03:52:22 INFO - 'MAIL': '/var/mail/cltbld', 03:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:22 INFO - 'MOZ_NO_REMOTE': '1', 03:52:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:22 INFO - 'NO_EM_RESTART': '1', 03:52:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:22 INFO - 'PWD': '/builds/slave/test', 03:52:22 INFO - 'SHELL': '/bin/bash', 03:52:22 INFO - 'SHLVL': '1', 03:52:22 INFO - 'TERM': 'linux', 03:52:22 INFO - 'TMOUT': '86400', 03:52:22 INFO - 'USER': 'cltbld', 03:52:22 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:22 INFO - '_': '/tools/buildbot/bin/python'} 03:52:24 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:52:24 INFO - Downloading/unpacking pip>=1.5 03:52:24 INFO - Running setup.py egg_info for package pip 03:52:24 INFO - warning: no files found matching 'pip/cacert.pem' 03:52:24 INFO - warning: no files found matching '*.html' under directory 'docs' 03:52:24 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:52:24 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:52:24 INFO - Installing collected packages: pip 03:52:24 INFO - Found existing installation: pip 1.1 03:52:24 INFO - Uninstalling pip: 03:52:24 INFO - Successfully uninstalled pip 03:52:24 INFO - Running setup.py install for pip 03:52:24 INFO - warning: no files found matching 'pip/cacert.pem' 03:52:24 INFO - warning: no files found matching '*.html' under directory 'docs' 03:52:24 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:52:24 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:52:24 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:52:24 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:52:24 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:52:24 INFO - Successfully installed pip 03:52:24 INFO - Cleaning up... 03:52:24 INFO - Return code: 0 03:52:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:52:24 INFO - Using env: {'DISPLAY': ':0', 03:52:24 INFO - 'HOME': '/home/cltbld', 03:52:24 INFO - 'LANG': 'en_US.UTF-8', 03:52:24 INFO - 'LANGUAGE': 'en_US:en', 03:52:24 INFO - 'LOGNAME': 'cltbld', 03:52:24 INFO - 'MAIL': '/var/mail/cltbld', 03:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:24 INFO - 'MOZ_NO_REMOTE': '1', 03:52:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:24 INFO - 'NO_EM_RESTART': '1', 03:52:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:24 INFO - 'PWD': '/builds/slave/test', 03:52:24 INFO - 'SHELL': '/bin/bash', 03:52:24 INFO - 'SHLVL': '1', 03:52:24 INFO - 'TERM': 'linux', 03:52:24 INFO - 'TMOUT': '86400', 03:52:24 INFO - 'USER': 'cltbld', 03:52:24 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:24 INFO - '_': '/tools/buildbot/bin/python'} 03:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:24 INFO - Downloading/unpacking psutil>=3.1.1 03:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:52:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:25 INFO - Installing collected packages: psutil 03:52:25 INFO - Running setup.py install for psutil 03:52:25 INFO - building 'psutil._psutil_linux' extension 03:52:25 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 03:52:26 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 03:52:26 INFO - building 'psutil._psutil_posix' extension 03:52:26 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 03:52:26 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 03:52:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:27 INFO - Successfully installed psutil 03:52:27 INFO - Cleaning up... 03:52:27 INFO - Return code: 0 03:52:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:52:27 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')]} 03:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:27 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')]} 03:52:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:27 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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:27 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 03:52:27 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 03:52:27 INFO - Using env: {'DISPLAY': ':0', 03:52:27 INFO - 'HOME': '/home/cltbld', 03:52:27 INFO - 'LANG': 'en_US.UTF-8', 03:52:27 INFO - 'LANGUAGE': 'en_US:en', 03:52:27 INFO - 'LOGNAME': 'cltbld', 03:52:27 INFO - 'MAIL': '/var/mail/cltbld', 03:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:27 INFO - 'MOZ_NO_REMOTE': '1', 03:52:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:27 INFO - 'NO_EM_RESTART': '1', 03:52:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:27 INFO - 'PWD': '/builds/slave/test', 03:52:27 INFO - 'SHELL': '/bin/bash', 03:52:27 INFO - 'SHLVL': '1', 03:52:27 INFO - 'TERM': 'linux', 03:52:27 INFO - 'TMOUT': '86400', 03:52:27 INFO - 'USER': 'cltbld', 03:52:27 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:27 INFO - '_': '/tools/buildbot/bin/python'} 03:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:52:27 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 03:52:27 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 03:52:27 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 03:52:27 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 03:52:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:52:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:52:28 INFO - Installing collected packages: mozsystemmonitor 03:52:28 INFO - Running setup.py install for mozsystemmonitor 03:52:28 INFO - Successfully installed mozsystemmonitor 03:52:28 INFO - Cleaning up... 03:52:28 INFO - Return code: 0 03:52:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52: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')]} 03:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:28 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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:28 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 03:52:28 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 03:52:28 INFO - Using env: {'DISPLAY': ':0', 03:52:28 INFO - 'HOME': '/home/cltbld', 03:52:28 INFO - 'LANG': 'en_US.UTF-8', 03:52:28 INFO - 'LANGUAGE': 'en_US:en', 03:52:28 INFO - 'LOGNAME': 'cltbld', 03:52:28 INFO - 'MAIL': '/var/mail/cltbld', 03:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:28 INFO - 'MOZ_NO_REMOTE': '1', 03:52:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:28 INFO - 'NO_EM_RESTART': '1', 03:52:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:28 INFO - 'PWD': '/builds/slave/test', 03:52:28 INFO - 'SHELL': '/bin/bash', 03:52:28 INFO - 'SHLVL': '1', 03:52:28 INFO - 'TERM': 'linux', 03:52:28 INFO - 'TMOUT': '86400', 03:52:28 INFO - 'USER': 'cltbld', 03:52:28 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:28 INFO - '_': '/tools/buildbot/bin/python'} 03:52:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:28 INFO - Downloading/unpacking jsonschema==2.5.1 03:52:28 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 03:52:28 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 03:52:28 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 03:52:28 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 03:52:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:52:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:52:29 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 03:52:29 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 03:52:29 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 03:52:29 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 03:52:30 INFO - Downloading functools32-3.2.3-2.tar.gz 03:52:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:52:30 INFO - warning: no files found matching '*.txt' 03:52:30 INFO - no previously-included directories found matching 'build' 03:52:30 INFO - no previously-included directories found matching 'dist' 03:52:30 INFO - no previously-included directories found matching '.git*' 03:52:30 INFO - Installing collected packages: jsonschema, functools32 03:52:30 INFO - Running setup.py install for functools32 03:52:30 INFO - warning: no files found matching '*.txt' 03:52:30 INFO - no previously-included directories found matching 'build' 03:52:30 INFO - no previously-included directories found matching 'dist' 03:52:30 INFO - no previously-included directories found matching '.git*' 03:52:30 INFO - Successfully installed jsonschema functools32 03:52:30 INFO - Cleaning up... 03:52:30 INFO - Return code: 0 03:52:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52: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')]} 03:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:30 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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:30 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 03:52:30 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 03:52:30 INFO - Using env: {'DISPLAY': ':0', 03:52:30 INFO - 'HOME': '/home/cltbld', 03:52:30 INFO - 'LANG': 'en_US.UTF-8', 03:52:30 INFO - 'LANGUAGE': 'en_US:en', 03:52:30 INFO - 'LOGNAME': 'cltbld', 03:52:30 INFO - 'MAIL': '/var/mail/cltbld', 03:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:30 INFO - 'MOZ_NO_REMOTE': '1', 03:52:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:30 INFO - 'NO_EM_RESTART': '1', 03:52:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:30 INFO - 'PWD': '/builds/slave/test', 03:52:30 INFO - 'SHELL': '/bin/bash', 03:52:30 INFO - 'SHLVL': '1', 03:52:30 INFO - 'TERM': 'linux', 03:52:30 INFO - 'TMOUT': '86400', 03:52:30 INFO - 'USER': 'cltbld', 03:52:30 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:30 INFO - '_': '/tools/buildbot/bin/python'} 03:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:52:30 INFO - Cleaning up... 03:52:30 INFO - Return code: 0 03:52:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52: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')]} 03:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:30 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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:30 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 03:52:30 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 03:52:30 INFO - Using env: {'DISPLAY': ':0', 03:52:30 INFO - 'HOME': '/home/cltbld', 03:52:30 INFO - 'LANG': 'en_US.UTF-8', 03:52:30 INFO - 'LANGUAGE': 'en_US:en', 03:52:30 INFO - 'LOGNAME': 'cltbld', 03:52:30 INFO - 'MAIL': '/var/mail/cltbld', 03:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:30 INFO - 'MOZ_NO_REMOTE': '1', 03:52:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:30 INFO - 'NO_EM_RESTART': '1', 03:52:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:30 INFO - 'PWD': '/builds/slave/test', 03:52:30 INFO - 'SHELL': '/bin/bash', 03:52:30 INFO - 'SHLVL': '1', 03:52:30 INFO - 'TERM': 'linux', 03:52:30 INFO - 'TMOUT': '86400', 03:52:30 INFO - 'USER': 'cltbld', 03:52:30 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:30 INFO - '_': '/tools/buildbot/bin/python'} 03:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:30 INFO - Downloading/unpacking blobuploader==1.2.4 03:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:31 INFO - Downloading blobuploader-1.2.4.tar.gz 03:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:52:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:52:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:52:32 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 03:52:32 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 03:52:32 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 03:52:32 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 03:52:32 INFO - Downloading docopt-0.6.1.tar.gz 03:52:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:52:32 INFO - Installing collected packages: blobuploader, requests, docopt 03:52:32 INFO - Running setup.py install for blobuploader 03:52:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:52:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:52:32 INFO - Running setup.py install for requests 03:52:32 INFO - Running setup.py install for docopt 03:52:32 INFO - Successfully installed blobuploader requests docopt 03:52:32 INFO - Cleaning up... 03:52:32 INFO - Return code: 0 03:52:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52:32 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')]} 03:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:32 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')]} 03:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:33 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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:33 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 03:52:33 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 03:52:33 INFO - Using env: {'DISPLAY': ':0', 03:52:33 INFO - 'HOME': '/home/cltbld', 03:52:33 INFO - 'LANG': 'en_US.UTF-8', 03:52:33 INFO - 'LANGUAGE': 'en_US:en', 03:52:33 INFO - 'LOGNAME': 'cltbld', 03:52:33 INFO - 'MAIL': '/var/mail/cltbld', 03:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:33 INFO - 'MOZ_NO_REMOTE': '1', 03:52:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:33 INFO - 'NO_EM_RESTART': '1', 03:52:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:33 INFO - 'PWD': '/builds/slave/test', 03:52:33 INFO - 'SHELL': '/bin/bash', 03:52:33 INFO - 'SHLVL': '1', 03:52:33 INFO - 'TERM': 'linux', 03:52:33 INFO - 'TMOUT': '86400', 03:52:33 INFO - 'USER': 'cltbld', 03:52:33 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:33 INFO - '_': '/tools/buildbot/bin/python'} 03:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:52:33 INFO - Running setup.py (path:/tmp/pip-_Ij_mI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:52:33 INFO - Running setup.py (path:/tmp/pip-SN8nng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:52:33 INFO - Running setup.py (path:/tmp/pip-Q0u46l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:52:33 INFO - Running setup.py (path:/tmp/pip-p4BENS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:52:33 INFO - Running setup.py (path:/tmp/pip-gjgkmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:52:33 INFO - Running setup.py (path:/tmp/pip-FThLLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:52:33 INFO - Running setup.py (path:/tmp/pip-QvCJF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:52:33 INFO - Running setup.py (path:/tmp/pip-4Wnrgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:52:33 INFO - Running setup.py (path:/tmp/pip-sZCYbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:52:33 INFO - Running setup.py (path:/tmp/pip-qf_Boh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:52:33 INFO - Running setup.py (path:/tmp/pip-E_zH_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:52:33 INFO - Running setup.py (path:/tmp/pip-C8GZjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:52:34 INFO - Running setup.py (path:/tmp/pip-K5jR_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:52:34 INFO - Running setup.py (path:/tmp/pip-zy211S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:34 INFO - Running setup.py (path:/tmp/pip-Z3ztdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:52:34 INFO - Running setup.py (path:/tmp/pip-qiNwvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:52:34 INFO - Running setup.py (path:/tmp/pip-16Nlrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:52:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:52:34 INFO - Running setup.py install for manifestparser 03:52:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:52:34 INFO - Running setup.py install for mozcrash 03:52:34 INFO - Running setup.py install for mozdebug 03:52:34 INFO - Running setup.py install for mozdevice 03:52:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:52:34 INFO - Running setup.py install for mozfile 03:52:34 INFO - Running setup.py install for mozhttpd 03:52:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:52:34 INFO - Running setup.py install for mozinfo 03:52:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Running setup.py install for mozInstall 03:52:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Running setup.py install for mozleak 03:52:35 INFO - Running setup.py install for mozlog 03:52:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Running setup.py install for moznetwork 03:52:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Running setup.py install for mozprocess 03:52:35 INFO - Running setup.py install for mozprofile 03:52:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Running setup.py install for mozrunner 03:52:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:52:35 INFO - Running setup.py install for mozscreenshot 03:52:35 INFO - Running setup.py install for moztest 03:52:36 INFO - Running setup.py install for mozversion 03:52:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:52:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:52:36 INFO - Cleaning up... 03:52:36 INFO - Return code: 0 03:52:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52:36 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')]} 03:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:36 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')]} 03:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:36 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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:36 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 03:52:36 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 03:52:36 INFO - Using env: {'DISPLAY': ':0', 03:52:36 INFO - 'HOME': '/home/cltbld', 03:52:36 INFO - 'LANG': 'en_US.UTF-8', 03:52:36 INFO - 'LANGUAGE': 'en_US:en', 03:52:36 INFO - 'LOGNAME': 'cltbld', 03:52:36 INFO - 'MAIL': '/var/mail/cltbld', 03:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:36 INFO - 'MOZ_NO_REMOTE': '1', 03:52:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:36 INFO - 'NO_EM_RESTART': '1', 03:52:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:36 INFO - 'PWD': '/builds/slave/test', 03:52:36 INFO - 'SHELL': '/bin/bash', 03:52:36 INFO - 'SHLVL': '1', 03:52:36 INFO - 'TERM': 'linux', 03:52:36 INFO - 'TMOUT': '86400', 03:52:36 INFO - 'USER': 'cltbld', 03:52:36 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:36 INFO - '_': '/tools/buildbot/bin/python'} 03:52:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:52:36 INFO - Running setup.py (path:/tmp/pip-JIdEG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:52:36 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)) 03:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:52:36 INFO - Running setup.py (path:/tmp/pip-Ql3cER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:52:36 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)) 03:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:52:36 INFO - Running setup.py (path:/tmp/pip-q5pf92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:52:36 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)) 03:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:52:36 INFO - Running setup.py (path:/tmp/pip-iSNmM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:52:36 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)) 03:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:52:36 INFO - Running setup.py (path:/tmp/pip-mjOOiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:52:36 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)) 03:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:52:36 INFO - Running setup.py (path:/tmp/pip-FdplNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:52:36 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)) 03:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:52:36 INFO - Running setup.py (path:/tmp/pip-YD90j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:52:36 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)) 03:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:52:36 INFO - Running setup.py (path:/tmp/pip-cm6y9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:52:36 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)) 03:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:52:36 INFO - Running setup.py (path:/tmp/pip-LP3uOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:52:37 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)) 03:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:52:37 INFO - Running setup.py (path:/tmp/pip-Itqlyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:52:37 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)) 03:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:52:37 INFO - Running setup.py (path:/tmp/pip-i066qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:52:37 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)) 03:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:52:37 INFO - Running setup.py (path:/tmp/pip-h5PQqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:52:37 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)) 03:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:52:37 INFO - Running setup.py (path:/tmp/pip-to1VOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:52:37 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)) 03:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:52:37 INFO - Running setup.py (path:/tmp/pip-E6McLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:52:37 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)) 03:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:37 INFO - Running setup.py (path:/tmp/pip-407vII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:37 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)) 03:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:52:37 INFO - Running setup.py (path:/tmp/pip-eg4x8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:52:37 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)) 03:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:52:37 INFO - Running setup.py (path:/tmp/pip-7POe_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:52:37 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)) 03:52:37 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)) 03:52:37 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)) 03:52:37 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)) 03:52:37 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)) 03:52:37 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)) 03:52:37 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)) 03:52:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:52:37 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 03:52:37 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 03:52:37 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 03:52:37 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 03:52:38 INFO - Downloading blessings-1.6.tar.gz 03:52:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:52:38 INFO - Installing collected packages: blessings 03:52:38 INFO - Running setup.py install for blessings 03:52:38 INFO - Successfully installed blessings 03:52:38 INFO - Cleaning up... 03:52:38 INFO - Return code: 0 03:52:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:52:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:52:39 INFO - Reading from file tmpfile_stdout 03:52:39 INFO - Current package versions: 03:52:39 INFO - argparse == 1.2.1 03:52:39 INFO - blessings == 1.6 03:52:39 INFO - blobuploader == 1.2.4 03:52:39 INFO - docopt == 0.6.1 03:52:39 INFO - functools32 == 3.2.3-2 03:52:39 INFO - jsonschema == 2.5.1 03:52:39 INFO - manifestparser == 1.1 03:52:39 INFO - mozInstall == 1.13 03:52:39 INFO - mozcrash == 1.0 03:52:39 INFO - mozdebug == 0.1 03:52:39 INFO - mozdevice == 0.51 03:52:39 INFO - mozfile == 1.2 03:52:39 INFO - mozhttpd == 0.7 03:52:39 INFO - mozinfo == 0.10 03:52:39 INFO - mozleak == 0.1 03:52:39 INFO - mozlog == 3.5 03:52:39 INFO - moznetwork == 0.27 03:52:39 INFO - mozprocess == 0.25 03:52:39 INFO - mozprofile == 0.28 03:52:39 INFO - mozrunner == 6.13 03:52:39 INFO - mozscreenshot == 0.1 03:52:39 INFO - mozsystemmonitor == 0.3 03:52:39 INFO - moztest == 0.8 03:52:39 INFO - mozversion == 1.4 03:52:39 INFO - psutil == 3.1.1 03:52:39 INFO - requests == 1.2.3 03:52:39 INFO - wsgiref == 0.1.2 03:52:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52:39 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')]} 03:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:39 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')]} 03:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:39 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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:39 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 03:52:39 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 03:52:39 INFO - Using env: {'DISPLAY': ':0', 03:52:39 INFO - 'HOME': '/home/cltbld', 03:52:39 INFO - 'LANG': 'en_US.UTF-8', 03:52:39 INFO - 'LANGUAGE': 'en_US:en', 03:52:39 INFO - 'LOGNAME': 'cltbld', 03:52:39 INFO - 'MAIL': '/var/mail/cltbld', 03:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:39 INFO - 'MOZ_NO_REMOTE': '1', 03:52:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:39 INFO - 'NO_EM_RESTART': '1', 03:52:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:39 INFO - 'PWD': '/builds/slave/test', 03:52:39 INFO - 'SHELL': '/bin/bash', 03:52:39 INFO - 'SHLVL': '1', 03:52:39 INFO - 'TERM': 'linux', 03:52:39 INFO - 'TMOUT': '86400', 03:52:39 INFO - 'USER': 'cltbld', 03:52:39 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:39 INFO - '_': '/tools/buildbot/bin/python'} 03:52:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:39 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)) 03:52:39 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)) 03:52:39 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)) 03:52:39 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)) 03:52:39 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)) 03:52:39 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)) 03:52:39 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)) 03:52:39 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)) 03:52:39 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)) 03:52:39 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:52:39 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 03:52:39 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 03:52:39 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 03:52:39 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 03:52:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:52:40 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)) 03:52:40 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)) 03:52:40 INFO - Installing collected packages: simplejson 03:52:40 INFO - Running setup.py install for simplejson 03:52:40 INFO - building 'simplejson._speedups' extension 03:52:40 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 03:52:41 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 03:52:41 INFO - Successfully installed simplejson 03:52:41 INFO - Cleaning up... 03:52:41 INFO - Return code: 0 03:52:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:52:41 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')]} 03:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:41 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')]} 03:52:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:41 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 0xad91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xca2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe45a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, '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': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', '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 03:52:41 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 03:52:41 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 03:52:41 INFO - Using env: {'DISPLAY': ':0', 03:52:41 INFO - 'HOME': '/home/cltbld', 03:52:41 INFO - 'LANG': 'en_US.UTF-8', 03:52:41 INFO - 'LANGUAGE': 'en_US:en', 03:52:41 INFO - 'LOGNAME': 'cltbld', 03:52:41 INFO - 'MAIL': '/var/mail/cltbld', 03:52:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:41 INFO - 'MOZ_NO_REMOTE': '1', 03:52:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:41 INFO - 'NO_EM_RESTART': '1', 03:52:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:41 INFO - 'PWD': '/builds/slave/test', 03:52:41 INFO - 'SHELL': '/bin/bash', 03:52:41 INFO - 'SHLVL': '1', 03:52:41 INFO - 'TERM': 'linux', 03:52:41 INFO - 'TMOUT': '86400', 03:52:41 INFO - 'USER': 'cltbld', 03:52:41 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:41 INFO - '_': '/tools/buildbot/bin/python'} 03:52:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:52:41 INFO - Cleaning up... 03:52:41 INFO - Return code: 0 03:52:41 INFO - Running post-action listener: _resource_record_post_action 03:52:41 INFO - Running post-action listener: _start_resource_monitoring 03:52:41 INFO - Starting resource monitoring. 03:52:41 INFO - [mozharness: 2017-09-16 10:52:41.907456Z] Finished create-virtualenv step (success) 03:52:41 INFO - [mozharness: 2017-09-16 10:52:41.908448Z] Running install step. 03:52:41 INFO - Running pre-action listener: _resource_record_pre_action 03:52:41 INFO - Running main action method: install 03:52:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:52:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:52:42 INFO - Reading from file tmpfile_stdout 03:52:42 INFO - Detecting whether we're running mozinstall >=1.0... 03:52:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:52:42 INFO - Reading from file tmpfile_stdout 03:52:42 INFO - Output received: 03:52:42 INFO - Usage: mozinstall [options] installer 03:52:42 INFO - Options: 03:52:42 INFO - -h, --help show this help message and exit 03:52:42 INFO - -d DEST, --destination=DEST 03:52:42 INFO - Directory to install application into. [default: 03:52:42 INFO - "/builds/slave/test"] 03:52:42 INFO - --app=APP Application being installed. [default: firefox] 03:52:42 INFO - mkdir: /builds/slave/test/build/application 03:52:42 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'] 03:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:52:59 INFO - Reading from file tmpfile_stdout 03:52:59 INFO - Output received: 03:52:59 INFO - /builds/slave/test/build/application/firefox/firefox 03:52:59 INFO - Running post-action listener: _resource_record_post_action 03:52:59 INFO - [mozharness: 2017-09-16 10:52:59.816451Z] Finished install step (success) 03:52:59 INFO - [mozharness: 2017-09-16 10:52:59.816702Z] Running setup-mitmproxy step. 03:52:59 INFO - Running pre-action listener: _resource_record_pre_action 03:52:59 INFO - Running main action method: setup_mitmproxy 03:52:59 INFO - Skipping: mitmproxy is not required 03:52:59 INFO - Running post-action listener: _resource_record_post_action 03:52:59 INFO - [mozharness: 2017-09-16 10:52:59.817888Z] Finished setup-mitmproxy step (success) 03:52:59 INFO - [mozharness: 2017-09-16 10:52:59.818108Z] Running run-tests step. 03:52:59 INFO - Running pre-action listener: _resource_record_pre_action 03:52:59 INFO - Running pre-action listener: _set_gcov_prefix 03:52:59 INFO - Running main action method: run_tests 03:52:59 WARNING - Try message not found. 03:52:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:52:59 INFO - Python 2.7.3 03:52:59 INFO - Return code: 0 03:52:59 INFO - grabbing minidump binary from tooltool 03:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:52:59 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 0xe49090>, '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 0xe49d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe43f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:52:59 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 03:52:59 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 03:52:59 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 03:52:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:52:59 INFO - Return code: 0 03:52:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:52:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:52:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:52:59 INFO - ENV: RUST_BACKTRACE is now full 03:52:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:52:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:52:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 03:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 03:52:59 INFO - Using env: {'DISPLAY': ':0', 03:52:59 INFO - 'HOME': '/home/cltbld', 03:52:59 INFO - 'LANG': 'en_US.UTF-8', 03:52:59 INFO - 'LANGUAGE': 'en_US:en', 03:52:59 INFO - 'LOGNAME': 'cltbld', 03:52:59 INFO - 'MAIL': '/var/mail/cltbld', 03:52:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:52:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:59 INFO - 'MOZ_NO_REMOTE': '1', 03:52:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:52:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:59 INFO - 'NO_EM_RESTART': '1', 03:52:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:59 INFO - 'PWD': '/builds/slave/test', 03:52:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:52:59 INFO - 'RUST_BACKTRACE': 'full', 03:52:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:52:59 INFO - 'SHELL': '/bin/bash', 03:52:59 INFO - 'SHLVL': '1', 03:52:59 INFO - 'TERM': 'linux', 03:52:59 INFO - 'TMOUT': '86400', 03:52:59 INFO - 'USER': 'cltbld', 03:52:59 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:59 INFO - '_': '/tools/buildbot/bin/python'} 03:52:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 03:53:00 INFO - mozversion application_buildid: 20170916093526 03:53:00 INFO - mozversion application_changeset: 27e7b4261e4b5a016e09adb5332a181e43ab520e 03:53:00 INFO - mozversion application_display_name: Nightly 03:53:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:53:00 INFO - mozversion application_name: Firefox 03:53:00 INFO - mozversion application_remotingname: firefox 03:53:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:53:00 INFO - mozversion application_vendor: Mozilla 03:53:00 INFO - mozversion application_version: 57.0a1 03:53:00 INFO - mozversion platform_buildid: 20170916093526 03:53:00 INFO - mozversion platform_changeset: 27e7b4261e4b5a016e09adb5332a181e43ab520e 03:53:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:53:00 INFO - mozversion platform_version: 57.0a1 03:53:00 INFO - using testdate: 1505559180 03:53:00 INFO - actual date: 1505559180 03:53:00 INFO - starting webserver on 'localhost:58447' 03:53:00 INFO - SUITE-START | Running 2 tests 03:53:00 INFO - TEST-START | dromaeo_css 03:53:00 INFO - Initialising browser for dromaeo_css test... 03:53:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58447/getInfo.html -profile /tmp/tmpATDIhe/profile 03:53:00 INFO - TEST-INFO | started process 3560 (/builds/slave/test/build/application/firefox/firefox http://localhost:58447/getInfo.html) 03:53:06 INFO - TEST-INFO | 3560: exit 0 03:53:06 INFO - Browser initialized. 03:53:06 INFO - Running cycle 1/1 for dromaeo_css test... 03:53:06 INFO - Using env: {'DISPLAY': ':0', 03:53:06 INFO - 'HOME': '/home/cltbld', 03:53:06 INFO - 'JSGC_DISABLE_POISONING': '1', 03:53:06 INFO - 'LANG': 'en_US.UTF-8', 03:53:06 INFO - 'LANGUAGE': 'en_US:en', 03:53:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:53:06 INFO - 'LOGNAME': 'cltbld', 03:53:06 INFO - 'MAIL': '/var/mail/cltbld', 03:53:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:53:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:53:06 INFO - 'MOZ_CRASHREPORTER': '1', 03:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:53:06 INFO - 'MOZ_NO_REMOTE': '1', 03:53:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:53:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:06 INFO - 'NO_EM_RESTART': '1', 03:53:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:06 INFO - 'PWD': '/builds/slave/test', 03:53:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:53:06 INFO - 'RUST_BACKTRACE': 'full', 03:53:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:53:06 INFO - 'SHELL': '/bin/bash', 03:53:06 INFO - 'SHLVL': '1', 03:53:06 INFO - 'TERM': 'linux', 03:53:06 INFO - 'TMOUT': '86400', 03:53:06 INFO - 'USER': 'cltbld', 03:53:06 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 03:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:06 INFO - '_': '/tools/buildbot/bin/python'} 03:53:06 INFO - TEST-INFO | started process 3799 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpATDIhe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:53:07 INFO - PID 3799 | 03:53:07 INFO - PID 3799 | (/builds/slave/test/build/application/firefox/firefox:3848): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:53:07 INFO - PID 3799 | 03:53:17 INFO - PID 3799 | RSS: Main: 147701760 03:53:17 INFO - PID 3799 | 03:56:14 INFO - PID 3799 | Cycle 1(1): loaded http://localhost:58447/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58447/tests/dromaeo/cssquery-ext.html) 03:56:15 INFO - PID 3799 | RSS: Main: 148770816 03:56:15 INFO - PID 3799 | 03:59:17 INFO - PID 3799 | Cycle 1(1): loaded http://localhost:58447/tests/dromaeo/cssquery-ext.html (next: http://localhost:58447/tests/dromaeo/cssquery-jquery.html) 03:59:18 INFO - PID 3799 | RSS: Main: 148471808 03:59:18 INFO - PID 3799 | 04:02:19 INFO - PID 3799 | Cycle 1(1): loaded http://localhost:58447/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58447/tests/dromaeo/cssquery-mootools.html) 04:02:20 INFO - PID 3799 | RSS: Main: 150601728 04:02:20 INFO - PID 3799 | 04:05:21 INFO - PID 3799 | Cycle 1(1): loaded http://localhost:58447/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58447/tests/dromaeo/cssquery-prototype.html) 04:05:22 INFO - PID 3799 | RSS: Main: 149364736 04:05:22 INFO - PID 3799 | 04:08:25 INFO - PID 3799 | Cycle 1(1): loaded http://localhost:58447/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58447/tests/dromaeo/cssquery-yui.html) 04:08:26 INFO - PID 3799 | RSS: Main: 150753280 04:08:26 INFO - PID 3799 | 04:11:27 INFO - PID 3799 | Cycle 1(1): loaded http://localhost:58447/tests/dromaeo/cssquery-yui.html (next: http://localhost:58447/tests/dromaeo/cssquery-dojo.html) 04:11:27 INFO - PID 3799 | RSS: Main: 153137152 04:11:27 INFO - PID 3799 | 04:11:27 INFO - PID 3799 | __start_tp_report 04:11:27 INFO - PID 3799 | _x_x_mozilla_page_load 04:11:27 INFO - PID 3799 | _x_x_mozilla_page_load_details 04:11:27 INFO - PID 3799 | |i|pagename|runs| 04:11:27 INFO - PID 3799 | |0;dojo.html;3825.387937929931;3858.189780146171;3870.0324918770298;3886.1450480894227;3894.240623078499;6250.218722659668;6322.462590679792;6356.904646430302;6396.584222025576;6421.197350331208;6325.33583973683;6333.493320534358;6339.080833279172;6390.424861762441;6390.55266131371;5621.690807005613;5736.627119237257;5769.826905192851;5772.682502462363;5787.971060144693;166.14047336537524;5714.285714285715;5759.971200143993;5789.160571717099;5792.739326730286;5642.943570564304;5648.943510564904;5650.5479561635175;5682.715864206773;5704.714764261791;5616.241807356037;5636.126400407943;5668.659880407189;5718.942810571883;5758.309002919635;5695.117256825199;5705.914411283834;5772.711364431803;5783.016887129198;5786.652800831964;6249.0626406039;6280.277768056686;6291.370862913718;6302.054691796267;6369.20384951881;6686.598804071771;6804.76183333581;6805.795826125224;6827.48793840459;6869.210040845267;6826.658667066677;6888.931110688879;6892.069570608004;6898.758543450955;6900.2064762552445;6329.778457753957;6389.648569328685;6488.902666460007;6494.253160886511;6525.836854078639;7140.571565706075;7252.891206631904;7261.092363454568;7265.418766498773;7269.454790890704;6840.897386539206;6841.828954276083;6920.515563910478;6946.895796563055;6977.616231107237;6772.5936443813525;6874.690638921261;6883.724651014004;6989.580625162506;7101.396381307543;6823.829404264834;6838.111045564145;6841.281665425058;6851.760188393482;6875.346842049998;6850.38346548813;6930;6947.201071876698;6950.20072691647;6955;4949.925751113737;4955.430125535538;5000.62495312853;5009.799608015712;5030.748462576929;4432.15789000089;4485.0805584855025;4493.236149854532;4500.369948207253;4521.547845215518;6354.17395738551;6391.552591318656;6420.261669907927;6437.839054023687;6443.420092191725;6740.359665831738;6837.453003759687;6938.791836244921;6947.40947019509;6951.200611929592;6293.37066293367;6332.841678958063;6344.238691356974;6377.71300291488;6439.388258115471;5455.1271796512365;5505.862353441196;5506.504414602705;5507.449255074461;5508.20131080999;7035.085438893014;7048.083749112582;7049.753258635923;7060.6116663584;7088.362047415654;6766.221884483249;6800.761973330909;6893.827654308682;6992.0910281663055;6998.75504357345;7216.134063912364;7226.819329516804;7306.634668266459;7312.853742925218;7329.486935914435;6816.318368163144;6818.829529261808;6822.283660215606;6920.446364290945;6922.653867306513;6814.2163651180745;6841.076454678555;6871.896921546081;6890.724371025303;6940.271271516418;6607.372299631527;6679.0649309097735;6710.328967103251;6718.462522998051;6720.193576770819;6599.7360105595235;6614.4708423325055;6701.1623547056615;6709.698063587052;6709.798706038827;6495.642739649365;6522.608643481406;6534.379233972765;6586.967065164644;6618.172728408949;6639.568428052161;6748.527603067738;6748.7300507979135;6755.898661519983;6771.187457505131;6407.551471396958;6474;6491.707873145625;6496.512761542997;6601.53789234749 04:11:27 INFO - PID 3799 | |1;ext.html;957.1959553974662;986.9950650246748;987.1263931420694;987.2842189412677;988.9159421449172;45339.319910201375;45939.77030114845;46245;46418.767906160465;46470;43536.56463435365;43947.56052439474;44179.77910110453;44194.33708494375;44237.7788111059;7325.9267407325915;7466.216047315035;7467.925320746805;7498.325150736433;7514.549127052394;7057.541259818096;7086.751963681272;7089.610071446068;7090.50366474347;7104.893426598605;8048.436609437342;8222.876656850152;8251.793705157359;8252.628631711588;8256.133106023872;8119.147489513604;8204.794880127985;8210.753677389657;8220.63007164679;8235.1353107924;8028.799280017988;8093.838123237561;8096.878546821802;8139.145389734052;8139.38954578409;40428.59571404278;40446.59553404458;40775.592244077474;41030.79484602558;41071.1785764286;3402.931941361184;3435.3301106284284;3474.7915125092322;3485.5120283160377;3504.9299014019575;33702.83148584241;33858.83070584656;33945.15137121567;33966.83016584901;34028;5555.194496797982;5564.666120032771;5593.216949627055;5625.80309689163;5636;198597.00701496543;206887;208662;208950;209408;46065.7696711521;46386;53350;54062;54260.72869635706;57175.42824571701;57213;57362;57430.7128464355;57450.13824792631;15451.61370965735;15764.76352854708;15829.525114246591;15848.286827092808;15910.522684319258;12475.064370172273;12516.937415312865;12528.060395470377;12546.05904557162;12558.560450384193;15664;15674.9216253918;15677.216139192995;15744.92127539355;15763.211839407984;5846.1523079153285;6004.129401236796;6008.116104269975;6076.635401875901;6155.045967874987;1095.370162156747;1098.8351747237818;1105.2705214558353;1106.5739690219323;1109.2734259060328;25394.238172854475;25477.872610636827;25658.35854103625;25819.35451613687;25845.35386615312;1173.976520469586;1193.7373777768878;1203.8796120387892;1206.3365149168098;1216.6958260434892;13646.044776865525;13671.931640341934;13694.931525342508;13866.93066534681;13918.30408479572;283131;283690;284051;284252;284302;57331;57549;57560.71219643875;57832;57870;1467.2297044051745;1526.3284154971777;1534.70840540297;1537.446519253068;1551.0926108226813;24938.501229975664;25202.873985629954;25231.87384063068;25454;25483.61774573346;41548.79225603853;41549.37675934803;41612.58387416087;45881.77059114683;46518.302225465966;221494;240503;243675;246523;246551;36231;36349.636503634625;36472.452913205794;36482.08794780152;36558.4516232262;47204.52795472001;47365.28952065653;47471.76264118657;47956.280655789495;47964.520354796005;34502.13744656404;34616;34673.653263467044;34772.65227347694;35122.64877351194;33527;33992.83003585065;34028.48957265593;34259;34691.30617387689;40118.799406002785;40216.59783402128;40736.18527629371;40740.388894167205;40967.1806563873;32911;32955.17612059718;33213;33304.50043249305;33663.66336336605;25094.74905250924;25601.359966001;25624.743752562237;25855.741442585335;25876.870615647553 04:11:27 INFO - PID 3799 | |2;jquery.html;600.309643909504;604.4378727783161;621.6021746082511;631.6147150238354;632.5919781740776;21807.45481362967;22361.44096397589;23316.06735730571;23363;23453;24348.878255608743;24891.875540622317;24928.252152435412;24938.875305623493;24979.75020249797;6609.768658096968;6698.531102822804;6769.458443324547;6815.42068924142;6826.24911259761;6415.1981002374705;6696.196456425211;6712.22809376923;6720;6755.763548275811;6644.601323920556;6681.231658359302;6682.231543372526;6683.431908287801;6689.163854518185;6543.574667646588;6684.4986626003;6689.264180940093;6689.966550167267;6827.044213810071;6306.400891915261;6334.334894836022;6345;6345.17512723343;6345.714442850082;28610.570841437187;29083.41833163346;29164.562531562042;29190.854045729848;29231.853840730873;2304.5275718477674;2336.5443738471004;2338.029717667166;2340.0522788270778;2340.976590234093;19986.800131998785;20474.692879606817;20505.17979280827;20929.162833486644;20967.16131354744;2507.8244522883406;2516.421223118693;2565.5638541447997;2598.454324591838;2604.7785938195275;133142;139758;142563;145076;151045;15841.841581584267;16101.758473622906;16302.347906083862;16458.177091145393;16590.419335323204;24771.25686229416;24829.379265518146;24904.37739056538;24953.87523062373;24989.750102499107;10057.849132263022;10067.69796906094;10315.226358023028;10348.068673819393;10451.216158788122;9358.485283309483;9381.812363752686;9421.199198068103;9450.669226577036;9488.857667134998;9467.289953253534;9581.904180958241;9723.562439690231;9932.702018939299;10018.448985305733;2783.7772978161697;2852.686204517501;2866.8279903205876;2941.0735618280173;3016.065019843855;1034.8809886862957;1038.1539045677748;1043.968680939573;1046.215338496128;1049.186880167876;20680.48298792542;20687.689684654742;20878.164873404894;21190.258340958302;21453.67819482709;1109.378747901178;1114.1950239930547;1118.2004866520476;1120.4173829608721;1137.5790957345669;11384.715382115513;11627.186096973131;11761.705957351134;11832;11903.345316007702;593342;613093;614919;636194;636382;122894;124462.37768811098;125620.37189813993;126040;126412;1180.114913814639;1204.8192771084407;1223.4372188793254;1238.1456794811731;1242.9316387598587;18462;18734.625307494047;18866.151023204264;19006.049697515344;19018.619627607095;60860;61299.38700613115;61599.84400545959;62029.37970620416;64285.67857160684;77445;78232.60883695545;78264.60867695625;78562;79120.60439697765;14647.92676036613;14987.700245995236;15003.77494337564;15051.096934183985;15076.472323468626;22950.655740163576;23289.417764556023;23440.64839027382;23446.76553234446;23509.177178799346;19233.8076619232;19290.324838630582;19373.515662107995;19391.4182574523;19653.11560979788;16348.673026539165;16697.49907502777;16961.745573816155;17386.304547817945;17559.12204389801;19815.306464273683;19824.70262946028;19938.501537461678;19944.50138746543;20082.297119600746;14254.358553865308;14665.633359166106;14835.480758173411;14923.179225142287;14962.551123466314;18843.434696959113;18969.90515047416;19125.426237212905;19210.13554390028;19245.80754192496 04:11:27 INFO - PID 3799 | |3;mootools.html;1397.154721393557;1443.5236371997241;1466.8899832512557;1470.5808844479325;1475.350845627924;16130.919345403272;16822.074785886773;16868.662626747457;16920.23858926348;16928.492145235632;16450.917745411258;16521.256543455544;16580.336786528555;16581.751273730904;16598.917005414987;2573.0736934703536;2699.8650067496646;2708.3499960009553;2728.140635699748;2732.2076597786618;2548.197317844873;2689.623452716621;2701.2166471723176;2722.891084356623;2744.0258708158617;2606.6741657292837;2621.737826217382;2627.211836449067;2628.434886499402;2633.2363614551755;2644.973550264492;2675.772559332459;2676.625272461857;2681.839089654627;2693.8787754550995;2585.3665851866413;2620.5545057340296;2625.343664083979;2638.6569745933098;2640.524705553;16277.674446511004;16441.753373699405;16449.67100657992;16660.50018499447;16733.497995060046;7498.925010749932;7847.646855891441;7853.410994175402;7858.449908506407;7867.6459559319965;5304.946950530484;5404.135338345885;5426.131818908995;5506.229127922094;5633.380328163898;1866.1695545482266;1880.1069491991332;1893.0250920775811;1924.9326273580498;1930.4980705016796;29856.402871942657;30199.69800301991;30202.84898575515;30264.54603180954;30276.394472110434;6761.087253220752;6771.695273712695;6843.076184715;6940.548864323803;7053.118360204974;19199;19307.90346048261;19320.32378866733;19331.4200573983;19341.41975740702;7665.8850117248285;7820.061592608809;7840.843183136305;7852;7874.56689882062;7047.788566343018;7286.48994570375;7341.375983041395;7410.888836667455;7433.85132297351;5198.766055527435;5279.841604751787;5307.363116425976;5330.147176451826;5417.88933268679;1440.4454285100103;1514.8788096952217;1544.7991761071214;1547.3655801121486;1560.3680509393714;914.711865762296;924.902885197058;929.8279818233649;934.8878134623889;941.3504681769693;9267.44395336282;9368.344215904957;9395.671151509665;9405.717828465156;9552.235821134293;1056.1392465140887;1060.565168281001;1061.2359101446946;1080.4651697409834;1088.0642647323295;4976.129177393942;5169.198774190007;5401.918971215435;5698.686572238566;5858.091995740667;24582.385440364134;24709.629355559682;25068.749312507007;25583.872080639478;25726.6141007885;24669.5066098677;25175.37061573475;25245.747542524707;25306.367340816625;25321;12519;12520.999999999818;12593.685157871127;12691.746165076647;12712.809307860389;9142.451452912846;9219.539023048956;9236.72289831306;9244.583993720433;9481;20781.89609051945;20868.68696969577;21616.35150945474;21914.67127993113;22103.336899893027;22010.669839952094;22062.88968555147;22125.225617103326;22252.109915603196;22498.662520061884;12006.279623222634;12013.339266340436;12075.27548347102;12084.033277337616;12085.39573021363;14810.333534990736;14828.925855370655;15063.54809355721;15065.698686026437;15079.773803392738;12216.75566488683;12253.632391028283;12464.376781161087;12786.936065319986;12800.87199128034;11889.464974076014;12124.939375303067;12317.322547259628;12486.875131248935;12514.499420023098;11094.611688591183;11264.380459074502;11328.207025508136;11333.376664283214;11349.432528373383;7863.292303692695;7960.840783184188;8037.316828069445;8138.674453021813;8168.3465322772845;8292.751217463485;8359.456635318686;8372.372072094739;8429.325653947546;8432.662693492191 04:11:27 INFO - PID 3799 | |4;prototype.html;544.8174861421423;552.3978863039288;570.6576054367383;579.5363709032773;585.1778251556559;6275.968620156898;6302.873942521147;6311.274203466603;6327.715252813623;6329.430351268396;6154.138420621117;6182.969085154579;6222.222222222223;6224.502039836813;6237.251529816417;5149.356330458692;5156.278121063054;5157.896842063156;5160.974195129029;5164.457731938149;4830.420349558058;4998.875028124289;5138.152204886208;5170.121079416508;5175.58595312376;4962.975185124069;4989.226669866177;4992.151334273182;4999.100161970862;5000.19996800512;4955.702657840541;4963.751812409401;4974.37820272466;4987.47631498691;5007.098722229997;4425.380446737459;4489.595936365743;4490.528494508062;4491.169133710274;4508.211063063951;6136.079588061782;6153.507719382439;6165.969170154165;6171.722272497748;6202.503799696014;5866.882662346729;5869.4717475427415;5891.7054147292465;5896.204012458306;5907.616004959664;6706.2958389368905;6721.495887808385;6737.258901520829;6753.696083676197;6762.93237067628;5061.9876024795185;5066.290719299301;5076.44159142494;5085.338905942241;5093;6726.562773419712;6806.7957961261245;6894.345037221456;6917.377436030731;6936.306369363074;5096.566791822736;5123.333966584395;5126.846194614093;5175.560077393388;5186.507281808223;5175.53420192177;5193.195054766517;5218.191180367049;5228.2941802856885;5278.947210527922;5843.100992177474;5848.4151584841175;5851.590388672752;5857.853553661107;5889.793857214977;6703.93296067033;6720.193576770721;6726.3609957054005;6737.797866064026;6746.898796518056;1791.4177892184832;1809.8280663336961;1813.455963211058;1821.6265665538533;1830.5240637434097;1209.8291190622367;1256.4032084759665;1259.8614152443224;1275.79587266037;1287.575100216926;6202.472789812826;6216.564840461215;6262.655553944485;6265.279492858289;6266.24805023391;6551.901721474182;6559.409653131145;6620.271770105284;6665.933340666628;6715.5299129060495;5955;6132.448079672851;6173.59871608344;6174.012158054689;6189.659568723763;3488.0059183132685;3530.6645868642436;3533.2933413316923;3537.451694987281;3575.66031227033;6915.585064896021;6977.511574189758;7020.473464490184;7026.297370262932;7063.421851024339;6684.16447944007;6842.076319696876;6855.383015528626;6860.416864566568;6899.0341352210735;6942.687579058955;7029.92970070303;7049.682764275517;7066.929330706627;7090.858182836314;6619.834504137435;6675;6712.39588437043;6731.562448441032;6743.831404214934;6613.567701181033;6639;6649.534532582673;6685.933140668531;6717.1267735194115;7007.057036044772;7040.591881623798;7060.223375428596;7062.728708832254;7106.786796396116;5139.691618502902;5144.9742751286;5146.845594632167;5162.845114646417;5175.404828444777;6632.767853125118;6695.966520167368;6772.446593793043;6807.421369183477;6814.2163651180745;6307.779227727007;6428.774992875227;6505.316941721051;6520.576162549419;6628.171478565179;6493.870122597427;6518.217813862367;6544.411003009752;6546.705398257185;6573.737050517926;6310.245403984622;6478.319776423407;6486.286508484157;6489.772857949949;6521.250056243593;6427.871442571216;6572.507061970276;6584.7036883341325;6626.56927299724;6630.569013014138;6309.432151106423;6344.365563443619;6351.333110023381;6378.298387177314;6393.529488217643 04:11:27 INFO - PID 3799 | |5;yui.html;961.2886464016625;986.4574484033782;999.3254553176604;1005.4419797012655;1013.3818370793816;18642.813571864277;18967.810321896777;19072.90463547682;19077.236910523563;19105.61788764224;17741.201645925932;17758.733618995724;17902.28390864364;17924.82075179248;17929;1029.408090348051;1043.4730461117126;1054.5728979763207;1057.5981127171665;1060.178361769627;1042.2704107125005;1051.3796859852685;1054.8554818022417;1055.4617145255936;1060.787842431513;1060.2790102730141;1084.3656460970342;1099.0438318662734;1099.2140619457086;1112.9276597021208;1096.8299913513417;1100.4937728644834;1100.928439651424;1101.0585949013557;1103.7461383881753;788.1724189600897;799.5162926429542;801.054755388647;813.7518056992615;817.0889458254063;19302.517437064045;19310.420687379403;19503.707444388347;19557.119929603203;19712.40862774119;21758.12967481299;21832;22254;22358.776412235995;22456.101755929743;17096.914515427343;17435.65128697419;17744.3789467368;17777.46667599961;17917.37289194872;3894.883153505399;3920.274749171384;3920.705947053954;3934.0951581136214;3935.5080614923136;124509.37745311216;156678.21660891737;158035.99999999884;159577;163040.3695963037;16570.254338554794;16636.750448743278;16926.238319275417;17194.828051719323;17386.65226695459;34134.658653413644;34278.48582271268;34411.13972150716;34480.137996550286;35266.11834704153;9534.95232523833;9574.808503829885;9577.760555986017;9578.185854202331;9642.662506812369;4211.957880421218;4294.592013758688;4296.548862369469;4298.032942587955;4303.203907277164;5929.14027466015;5934.821955341346;5985.281766188085;5997.940020599738;6000.699965001732;1165.6677846813618;1195.8086706126978;1209.2865209526246;1211.2187638972814;1221.8533775946767;661.7551505942832;667.9599224046573;670.0719503487608;674.7065026713298;683.0880774166444;12802.231866088065;12916.741665166644;13246.470141194437;14242.643933901529;14288.785668214985;671.2079745899788;674.8346655069441;684.0867441964933;686.4405509509734;693.3898169610811;6293.811185664437;6347.650879201688;6407.935920640734;6410.55126141175;6420.486361091102;147955.26022370034;148857;149962;150349;152201;130178.34910825385;131946;132243;133378.33310833384;138100;114216.42891785488;114248.0013199534;114365;114879.42560287144;115694.4215278935;11825.40872956366;11973.820392694292;12047.75904481923;12136;12175.39123043862;55959;57037.42962570487;57235.141472877105;57462.7126864363;59619.10571341347;69788.30211697819;70230;71088.64455677688;72625.63687181531;73110;14327.57017289483;14794.6301342463;14826.851731482548;15041.6991660164;15051.623709407353;17107.401240956508;17246.82753172452;17281;17316.56708582245;17344.392946246822;15227.695446091238;15728.842711573196;15821.762673559675;15828;15846;16238;16527.50417487477;16580;16660.16699165061;16801.32794688199;16488.505344839676;16670.333186672397;16692.499225023268;16796.412125575545;16933.15334233259;12791.232526048467;13096.738065238833;13132.671683207996;13167.144135631153;13260.53588124411;13405.597832065054;13507.662308442368;13646.454141834216;13658.180509169482;13806.723865522834 04:11:27 INFO - PID 3799 | __end_tp_report 04:11:27 INFO - PID 3799 | __start_cc_report 04:11:27 INFO - PID 3799 | _x_x_mozilla_cycle_collect,268 04:11:27 INFO - PID 3799 | __end_cc_report 04:11:27 INFO - PID 3799 | __startTimestamp1505560287891__endTimestamp 04:11:27 INFO - PID 3799 | 04:11:27 INFO - PID 3799 | ------- Summary: start ------- 04:11:27 INFO - PID 3799 | Number of tests: 6 04:11:27 INFO - PID 3799 | 04:11:27 INFO - PID 3799 | [#0] dojo.html Cycles:165 Average:6303.79 Median:6586.97 stddev:910.76 (13.8%) stddev-sans-first:892.56 04:11:27 INFO - PID 3799 | Values: 3825.4 3858.2 3870.0 3886.1 3894.2 6250.2 6322.5 6356.9 6396.6 6421.2 6325.3 6333.5 6339.1 6390.4 6390.6 5621.7 5736.6 5769.8 5772.7 5788.0 166.1 5714.3 5760.0 5789.2 5792.7 5642.9 5648.9 5650.5 5682.7 5704.7 5616.2 5636.1 5668.7 5718.9 5758.3 5695.1 5705.9 5772.7 5783.0 5786.7 6249.1 6280.3 6291.4 6302.1 6369.2 6686.6 6804.8 6805.8 6827.5 6869.2 6826.7 6888.9 6892.1 6898.8 6900.2 6329.8 6389.6 6488.9 6494.3 6525.8 7140.6 7252.9 7261.1 7265.4 7269.5 6840.9 6841.8 6920.5 6946.9 6977.6 6772.6 6874.7 6883.7 6989.6 7101.4 6823.8 6838.1 6841.3 6851.8 6875.3 6850.4 6930.0 6947.2 6950.2 6955.0 4949.9 4955.4 5000.6 5009.8 5030.7 4432.2 4485.1 4493.2 4500.4 4521.5 6354.2 6391.6 6420.3 6437.8 6443.4 6740.4 6837.5 6938.8 6947.4 6951.2 6293.4 6332.8 6344.2 6377.7 6439.4 5455.1 5505.9 5506.5 5507.4 5508.2 7035.1 7048.1 7049.8 7060.6 7088.4 6766.2 6800.8 6893.8 6992.1 6998.8 7216.1 7226.8 7306.6 7312.9 7329.5 6816.3 6818.8 6822.3 6920.4 6922.7 6814.2 6841.1 6871.9 6890.7 6940.3 6607.4 6679.1 6710.3 6718.5 6720.2 6599.7 6614.5 6701.2 6709.7 6709.8 6495.6 6522.6 6534.4 6587.0 6618.2 6639.6 6748.5 6748.7 6755.9 6771.2 6407.6 6474.0 6491.7 6496.5 6601.5 04:11:27 INFO - PID 3799 | 04:11:27 INFO - PID 3799 | [#1] ext.html Cycles:180 Average:42330.30 Median:36290.32 stddev:63898.34 (176.1%) stddev-sans-first:64002.07 04:11:27 INFO - PID 3799 | Values: 957.2 987.0 987.1 987.3 988.9 45339.3 45939.8 46245.0 46418.8 46470.0 43536.6 43947.6 44179.8 44194.3 44237.8 7325.9 7466.2 7467.9 7498.3 7514.5 7057.5 7086.8 7089.6 7090.5 7104.9 8048.4 8222.9 8251.8 8252.6 8256.1 8119.1 8204.8 8210.8 8220.6 8235.1 8028.8 8093.8 8096.9 8139.1 8139.4 40428.6 40446.6 40775.6 41030.8 41071.2 3402.9 3435.3 3474.8 3485.5 3504.9 33702.8 33858.8 33945.2 33966.8 34028.0 5555.2 5564.7 5593.2 5625.8 5636.0 198597.0 206887.0 208662.0 208950.0 209408.0 46065.8 46386.0 53350.0 54062.0 54260.7 57175.4 57213.0 57362.0 57430.7 57450.1 15451.6 15764.8 15829.5 15848.3 15910.5 12475.1 12516.9 12528.1 12546.1 12558.6 15664.0 15674.9 15677.2 15744.9 15763.2 5846.2 6004.1 6008.1 6076.6 6155.0 1095.4 1098.8 1105.3 1106.6 1109.3 25394.2 25477.9 25658.4 25819.4 25845.4 1174.0 1193.7 1203.9 1206.3 1216.7 13646.0 13671.9 13694.9 13866.9 13918.3 283131.0 283690.0 284051.0 284252.0 284302.0 57331.0 57549.0 57560.7 57832.0 57870.0 1467.2 1526.3 1534.7 1537.4 1551.1 24938.5 25202.9 25231.9 25454.0 25483.6 41548.8 41549.4 41612.6 45881.8 46518.3 221494.0 240503.0 243675.0 246523.0 246551.0 36231.0 36349.6 36472.5 36482.1 36558.5 47204.5 47365.3 47471.8 47956.3 47964.5 34502.1 34616.0 34673.7 34772.7 35122.6 33527.0 33992.8 34028.5 34259.0 34691.3 40118.8 40216.6 40736.2 40740.4 40967.2 32911.0 32955.2 33213.0 33304.5 33663.7 25094.7 25601.4 25624.7 25855.7 25876.9 04:11:27 INFO - PID 3799 | 04:11:27 INFO - PID 3799 | [#2] jquery.html Cycles:180 Average:39421.26 Median:23302.74 stddev:103067.04 (442.3%) stddev-sans-first:103314.96 04:11:27 INFO - PID 3799 | Values: 600.3 604.4 621.6 631.6 632.6 21807.5 22361.4 23316.1 23363.0 23453.0 24348.9 24891.9 24928.3 24938.9 24979.8 6609.8 6698.5 6769.5 6815.4 6826.2 6415.2 6696.2 6712.2 6720.0 6755.8 6644.6 6681.2 6682.2 6683.4 6689.2 6543.6 6684.5 6689.3 6690.0 6827.0 6306.4 6334.3 6345.0 6345.2 6345.7 28610.6 29083.4 29164.6 29190.9 29231.9 2304.5 2336.5 2338.0 2340.1 2341.0 19986.8 20474.7 20505.2 20929.2 20967.2 2507.8 2516.4 2565.6 2598.5 2604.8 133142.0 139758.0 142563.0 145076.0 151045.0 15841.8 16101.8 16302.3 16458.2 16590.4 24771.3 24829.4 24904.4 24953.9 24989.8 10057.8 10067.7 10315.2 10348.1 10451.2 9358.5 9381.8 9421.2 9450.7 9488.9 9467.3 9581.9 9723.6 9932.7 10018.4 2783.8 2852.7 2866.8 2941.1 3016.1 1034.9 1038.2 1044.0 1046.2 1049.2 20680.5 20687.7 20878.2 21190.3 21453.7 1109.4 1114.2 1118.2 1120.4 1137.6 11384.7 11627.2 11761.7 11832.0 11903.3 593342.0 613093.0 614919.0 636194.0 636382.0 122894.0 124462.4 125620.4 126040.0 126412.0 1180.1 1204.8 1223.4 1238.1 1242.9 18462.0 18734.6 18866.2 19006.0 19018.6 60860.0 61299.4 61599.8 62029.4 64285.7 77445.0 78232.6 78264.6 78562.0 79120.6 14647.9 14987.7 15003.8 15051.1 15076.5 22950.7 23289.4 23440.6 23446.8 23509.2 19233.8 19290.3 19373.5 19391.4 19653.1 16348.7 16697.5 16961.7 17386.3 17559.1 19815.3 19824.7 19938.5 19944.5 20082.3 14254.4 14665.6 14835.5 14923.2 14962.6 18843.4 18969.9 19125.4 19210.1 19245.8 04:11:27 INFO - PID 3799 | 04:11:27 INFO - PID 3799 | [#3] mootools.html Cycles:180 Average:10278.97 Median:14149.84 stddev:7747.40 (54.8%) stddev-sans-first:7740.39 04:11:27 INFO - PID 3799 | Values: 1397.2 1443.5 1466.9 1470.6 1475.4 16130.9 16822.1 16868.7 16920.2 16928.5 16450.9 16521.3 16580.3 16581.8 16598.9 2573.1 2699.9 2708.3 2728.1 2732.2 2548.2 2689.6 2701.2 2722.9 2744.0 2606.7 2621.7 2627.2 2628.4 2633.2 2645.0 2675.8 2676.6 2681.8 2693.9 2585.4 2620.6 2625.3 2638.7 2640.5 16277.7 16441.8 16449.7 16660.5 16733.5 7498.9 7847.6 7853.4 7858.4 7867.6 5304.9 5404.1 5426.1 5506.2 5633.4 1866.2 1880.1 1893.0 1924.9 1930.5 29856.4 30199.7 30202.8 30264.5 30276.4 6761.1 6771.7 6843.1 6940.5 7053.1 19199.0 19307.9 19320.3 19331.4 19341.4 7665.9 7820.1 7840.8 7852.0 7874.6 7047.8 7286.5 7341.4 7410.9 7433.9 5198.8 5279.8 5307.4 5330.1 5417.9 1440.4 1514.9 1544.8 1547.4 1560.4 914.7 924.9 929.8 934.9 941.4 9267.4 9368.3 9395.7 9405.7 9552.2 1056.1 1060.6 1061.2 1080.5 1088.1 4976.1 5169.2 5401.9 5698.7 5858.1 24582.4 24709.6 25068.7 25583.9 25726.6 24669.5 25175.4 25245.7 25306.4 25321.0 12519.0 12521.0 12593.7 12691.7 12712.8 9142.5 9219.5 9236.7 9244.6 9481.0 20781.9 20868.7 21616.4 21914.7 22103.3 22010.7 22062.9 22125.2 22252.1 22498.7 12006.3 12013.3 12075.3 12084.0 12085.4 14810.3 14828.9 15063.5 15065.7 15079.8 12216.8 12253.6 12464.4 12786.9 12800.9 11889.5 12124.9 12317.3 12486.9 12514.5 11094.6 11264.4 11328.2 11333.4 11349.4 7863.3 7960.8 8037.3 8138.7 8168.3 8292.8 8359.5 8372.4 8429.3 8432.7 04:11:27 INFO - PID 3799 | 04:11:27 INFO - PID 3799 | [#4] prototype.html Cycles:180 Average:5627.24 Median:6230.88 stddev:1572.98 (25.2%) stddev-sans-first:1530.43 04:11:27 INFO - PID 3799 | Values: 544.8 552.4 570.7 579.5 585.2 6276.0 6302.9 6311.3 6327.7 6329.4 6154.1 6183.0 6222.2 6224.5 6237.3 5149.4 5156.3 5157.9 5161.0 5164.5 4830.4 4998.9 5138.2 5170.1 5175.6 4963.0 4989.2 4992.2 4999.1 5000.2 4955.7 4963.8 4974.4 4987.5 5007.1 4425.4 4489.6 4490.5 4491.2 4508.2 6136.1 6153.5 6166.0 6171.7 6202.5 5866.9 5869.5 5891.7 5896.2 5907.6 6706.3 6721.5 6737.3 6753.7 6762.9 5062.0 5066.3 5076.4 5085.3 5093.0 6726.6 6806.8 6894.3 6917.4 6936.3 5096.6 5123.3 5126.8 5175.6 5186.5 5175.5 5193.2 5218.2 5228.3 5278.9 5843.1 5848.4 5851.6 5857.9 5889.8 6703.9 6720.2 6726.4 6737.8 6746.9 1791.4 1809.8 1813.5 1821.6 1830.5 1209.8 1256.4 1259.9 1275.8 1287.6 6202.5 6216.6 6262.7 6265.3 6266.2 6551.9 6559.4 6620.3 6665.9 6715.5 5955.0 6132.4 6173.6 6174.0 6189.7 3488.0 3530.7 3533.3 3537.5 3575.7 6915.6 6977.5 7020.5 7026.3 7063.4 6684.2 6842.1 6855.4 6860.4 6899.0 6942.7 7029.9 7049.7 7066.9 7090.9 6619.8 6675.0 6712.4 6731.6 6743.8 6613.6 6639.0 6649.5 6685.9 6717.1 7007.1 7040.6 7060.2 7062.7 7106.8 5139.7 5145.0 5146.8 5162.8 5175.4 6632.8 6696.0 6772.4 6807.4 6814.2 6307.8 6428.8 6505.3 6520.6 6628.2 6493.9 6518.2 6544.4 6546.7 6573.7 6310.2 6478.3 6486.3 6489.8 6521.3 6427.9 6572.5 6584.7 6626.6 6630.6 6309.4 6344.4 6351.3 6378.3 6393.5 04:11:27 INFO - PID 3799 | 04:11:27 INFO - PID 3799 | [#5] yui.html Cycles:180 Average:27734.30 Median:17102.16 stddev:41957.90 (245.3%) stddev-sans-first:42027.44 04:11:27 INFO - PID 3799 | Values: 961.3 986.5 999.3 1005.4 1013.4 18642.8 18967.8 19072.9 19077.2 19105.6 17741.2 17758.7 17902.3 17924.8 17929.0 1029.4 1043.5 1054.6 1057.6 1060.2 1042.3 1051.4 1054.9 1055.5 1060.8 1060.3 1084.4 1099.0 1099.2 1112.9 1096.8 1100.5 1100.9 1101.1 1103.7 788.2 799.5 801.1 813.8 817.1 19302.5 19310.4 19503.7 19557.1 19712.4 21758.1 21832.0 22254.0 22358.8 22456.1 17096.9 17435.7 17744.4 17777.5 17917.4 3894.9 3920.3 3920.7 3934.1 3935.5 124509.4 156678.2 158036.0 159577.0 163040.4 16570.3 16636.8 16926.2 17194.8 17386.7 34134.7 34278.5 34411.1 34480.1 35266.1 9535.0 9574.8 9577.8 9578.2 9642.7 4212.0 4294.6 4296.5 4298.0 4303.2 5929.1 5934.8 5985.3 5997.9 6000.7 1165.7 1195.8 1209.3 1211.2 1221.9 661.8 668.0 670.1 674.7 683.1 12802.2 12916.7 13246.5 14242.6 14288.8 671.2 674.8 684.1 686.4 693.4 6293.8 6347.7 6407.9 6410.6 6420.5 147955.3 148857.0 149962.0 150349.0 152201.0 130178.3 131946.0 132243.0 133378.3 138100.0 114216.4 114248.0 114365.0 114879.4 115694.4 11825.4 11973.8 12047.8 12136.0 12175.4 55959.0 57037.4 57235.1 57462.7 59619.1 69788.3 70230.0 71088.6 72625.6 73110.0 14327.6 14794.6 14826.9 15041.7 15051.6 17107.4 17246.8 17281.0 17316.6 17344.4 15227.7 15728.8 15821.8 15828.0 15846.0 16238.0 16527.5 16580.0 16660.2 16801.3 16488.5 16670.3 16692.5 16796.4 16933.2 12791.2 13096.7 13132.7 13167.1 13260.5 13405.6 13507.7 13646.5 13658.2 13806.7 04:11:27 INFO - PID 3799 | -------- Summary: end -------- 04:11:27 INFO - PID 3799 | 04:11:28 INFO - TEST-INFO | 3799: exit 0 04:11:28 INFO - TEST-OK | dromaeo_css | took 1108358ms 04:11:28 INFO - TEST-START | kraken 04:11:28 INFO - Initialising browser for kraken test... 04:11:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58447/getInfo.html -profile /tmp/tmp0wWXrz/profile 04:11:28 INFO - TEST-INFO | started process 4178 (/builds/slave/test/build/application/firefox/firefox http://localhost:58447/getInfo.html) 04:11:33 INFO - TEST-INFO | 4178: exit 0 04:11:34 INFO - Browser initialized. 04:11:34 INFO - Running cycle 1/1 for kraken test... 04:11:34 INFO - Using env: {'DISPLAY': ':0', 04:11:34 INFO - 'HOME': '/home/cltbld', 04:11:34 INFO - 'JSGC_DISABLE_POISONING': '1', 04:11:34 INFO - 'LANG': 'en_US.UTF-8', 04:11:34 INFO - 'LANGUAGE': 'en_US:en', 04:11:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:11:34 INFO - 'LOGNAME': 'cltbld', 04:11:34 INFO - 'MAIL': '/var/mail/cltbld', 04:11:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:11:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:11:34 INFO - 'MOZ_CRASHREPORTER': '1', 04:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:11:34 INFO - 'MOZ_NO_REMOTE': '1', 04:11:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:34 INFO - 'NO_EM_RESTART': '1', 04:11:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:34 INFO - 'PWD': '/builds/slave/test', 04:11:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:11:34 INFO - 'RUST_BACKTRACE': 'full', 04:11:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:11:34 INFO - 'SHELL': '/bin/bash', 04:11:34 INFO - 'SHLVL': '1', 04:11:34 INFO - 'TERM': 'linux', 04:11:34 INFO - 'TMOUT': '86400', 04:11:34 INFO - 'USER': 'cltbld', 04:11:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084', 04:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:34 INFO - '_': '/tools/buildbot/bin/python'} 04:11:34 INFO - TEST-INFO | started process 4416 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0wWXrz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:11:34 INFO - PID 4416 | 04:11:34 INFO - PID 4416 | (/builds/slave/test/build/application/firefox/firefox:4466): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:11:34 INFO - PID 4416 | 04:11:45 INFO - PID 4416 | RSS: Main: 148303872 04:11:45 INFO - PID 4416 | 04:11:47 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58447/tests/kraken/driver.html?testName=audio-beat-detection) 04:11:48 INFO - PID 4416 | RSS: Main: 153440256 04:11:48 INFO - PID 4416 | 04:11:51 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58447/tests/kraken/driver.html?testName=audio-dft) 04:11:51 INFO - PID 4416 | RSS: Main: 153534464 04:11:51 INFO - PID 4416 | 04:11:54 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58447/tests/kraken/driver.html?testName=audio-fft) 04:11:55 INFO - PID 4416 | RSS: Main: 150573056 04:11:55 INFO - PID 4416 | 04:11:57 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58447/tests/kraken/driver.html?testName=audio-oscillator) 04:11:57 INFO - PID 4416 | RSS: Main: 150818816 04:11:57 INFO - PID 4416 | 04:11:59 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58447/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:12:00 INFO - PID 4416 | RSS: Main: 150757376 04:12:00 INFO - PID 4416 | 04:12:03 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58447/tests/kraken/driver.html?testName=imaging-darkroom) 04:12:04 INFO - PID 4416 | RSS: Main: 150929408 04:12:04 INFO - PID 4416 | 04:12:07 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58447/tests/kraken/driver.html?testName=imaging-desaturate) 04:12:08 INFO - PID 4416 | RSS: Main: 152129536 04:12:08 INFO - PID 4416 | 04:12:11 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58447/tests/kraken/driver.html?testName=json-parse-financial) 04:12:11 INFO - PID 4416 | RSS: Main: 153874432 04:12:11 INFO - PID 4416 | 04:12:13 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58447/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:12:13 INFO - PID 4416 | RSS: Main: 153751552 04:12:13 INFO - PID 4416 | 04:12:15 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58447/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:12:16 INFO - PID 4416 | RSS: Main: 153882624 04:12:16 INFO - PID 4416 | 04:12:18 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58447/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:12:18 INFO - PID 4416 | RSS: Main: 150249472 04:12:18 INFO - PID 4416 | 04:12:21 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:12:21 INFO - PID 4416 | RSS: Main: 150773760 04:12:21 INFO - PID 4416 | 04:12:24 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:12:25 INFO - PID 4416 | RSS: Main: 151203840 04:12:25 INFO - PID 4416 | 04:12:26 INFO - PID 4416 | Cycle 1(1): loaded http://localhost:58447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58447/tests/kraken/driver.html?testName=ai-astar) 04:12:27 INFO - PID 4416 | RSS: Main: 152494080 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | __start_tp_report 04:12:27 INFO - PID 4416 | _x_x_mozilla_page_load 04:12:27 INFO - PID 4416 | _x_x_mozilla_page_load_details 04:12:27 INFO - PID 4416 | |i|pagename|runs| 04:12:27 INFO - PID 4416 | |0;ai-astar;106;106;107;106;106;111;109;107;106;105 04:12:27 INFO - PID 4416 | |1;audio-beat-detection;112;113;114;117;115;113;116;115;113;115 04:12:27 INFO - PID 4416 | |2;audio-dft;163;163;162;162;160;161;162;162;162;168 04:12:27 INFO - PID 4416 | |3;audio-fft;74;75;73;75;74;74;74;73;76;73 04:12:27 INFO - PID 4416 | |4;audio-oscillator;78;79;79;78;77;78;77;77;78;81 04:12:27 INFO - PID 4416 | |5;imaging-gaussian-blur;114;116;116;114;115;113;111;114;114;114 04:12:27 INFO - PID 4416 | |6;imaging-darkroom;148;149;149;149;149;149;150;150;150;150 04:12:27 INFO - PID 4416 | |7;imaging-desaturate;94;94;94;95;94;99;95;93;94;95 04:12:27 INFO - PID 4416 | |8;json-parse-financial;44;44;48;43;45;43;44;43;43;43 04:12:27 INFO - PID 4416 | |9;json-stringify-tinderbox;37;38;38;38;38;37;37;49;37;37 04:12:27 INFO - PID 4416 | |10;stanford-crypto-aes;80;75;78;74;73;70;72;70;73;71 04:12:27 INFO - PID 4416 | |11;stanford-crypto-ccm;110;119;105;104;109;117;104;111;105;101 04:12:27 INFO - PID 4416 | |12;stanford-crypto-pbkdf2;156;154;163;153;153;150;151;149;155;167 04:12:27 INFO - PID 4416 | |13;stanford-crypto-sha256-iterative;65;60;58;61;65;59;59;59;60;61 04:12:27 INFO - PID 4416 | __end_tp_report 04:12:27 INFO - PID 4416 | __start_cc_report 04:12:27 INFO - PID 4416 | _x_x_mozilla_cycle_collect,704 04:12:27 INFO - PID 4416 | __end_cc_report 04:12:27 INFO - PID 4416 | __startTimestamp1505560347141__endTimestamp 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | ------- Summary: start ------- 04:12:27 INFO - PID 4416 | Number of tests: 14 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#0] ai-astar Cycles:10 Average:106.90 Median:106.50 stddev:1.79 (1.7%) stddev-sans-first:1.87 04:12:27 INFO - PID 4416 | Values: 106.0 106.0 107.0 106.0 106.0 111.0 109.0 107.0 106.0 105.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#1] audio-beat-detection Cycles:10 Average:114.30 Median:115.00 stddev:1.57 (1.4%) stddev-sans-first:1.42 04:12:27 INFO - PID 4416 | Values: 112.0 113.0 114.0 117.0 115.0 113.0 116.0 115.0 113.0 115.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.00 stddev:2.12 (1.3%) stddev-sans-first:2.24 04:12:27 INFO - PID 4416 | Values: 163.0 163.0 162.0 162.0 160.0 161.0 162.0 162.0 162.0 168.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#3] audio-fft Cycles:10 Average:74.10 Median:74.00 stddev:0.99 (1.3%) stddev-sans-first:1.05 04:12:27 INFO - PID 4416 | Values: 74.0 75.0 73.0 75.0 74.0 74.0 74.0 73.0 76.0 73.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:78.00 stddev:1.23 (1.6%) stddev-sans-first:1.30 04:12:27 INFO - PID 4416 | Values: 78.0 79.0 79.0 78.0 77.0 78.0 77.0 77.0 78.0 81.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:1.45 (1.3%) stddev-sans-first:1.54 04:12:27 INFO - PID 4416 | Values: 114.0 116.0 116.0 114.0 115.0 113.0 111.0 114.0 114.0 114.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.50 stddev:0.67 (0.5%) stddev-sans-first:0.53 04:12:27 INFO - PID 4416 | Values: 148.0 149.0 149.0 149.0 149.0 149.0 150.0 150.0 150.0 150.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.50 stddev:1.64 (1.7%) stddev-sans-first:1.72 04:12:27 INFO - PID 4416 | Values: 94.0 94.0 94.0 95.0 94.0 99.0 95.0 93.0 94.0 95.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#8] json-parse-financial Cycles:10 Average:44.00 Median:44.00 stddev:1.56 (3.6%) stddev-sans-first:1.66 04:12:27 INFO - PID 4416 | Values: 44.0 44.0 48.0 43.0 45.0 43.0 44.0 43.0 43.0 43.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:38.00 stddev:3.69 (9.7%) stddev-sans-first:3.87 04:12:27 INFO - PID 4416 | Values: 37.0 38.0 38.0 38.0 38.0 37.0 37.0 49.0 37.0 37.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:73.50 stddev:3.31 (4.5%) stddev-sans-first:2.57 04:12:27 INFO - PID 4416 | Values: 80.0 75.0 78.0 74.0 73.0 70.0 72.0 70.0 73.0 71.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#11] stanford-crypto-ccm Cycles:10 Average:108.50 Median:109.50 stddev:5.89 (5.4%) stddev-sans-first:6.22 04:12:27 INFO - PID 4416 | Values: 110.0 119.0 105.0 104.0 109.0 117.0 104.0 111.0 105.0 101.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.10 Median:154.50 stddev:5.72 (3.7%) stddev-sans-first:6.06 04:12:27 INFO - PID 4416 | Values: 156.0 154.0 163.0 153.0 153.0 150.0 151.0 149.0 155.0 167.0 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:60.50 stddev:2.45 (4.1%) stddev-sans-first:2.05 04:12:27 INFO - PID 4416 | Values: 65.0 60.0 58.0 61.0 65.0 59.0 59.0 59.0 60.0 61.0 04:12:27 INFO - PID 4416 | -------- Summary: end -------- 04:12:27 INFO - PID 4416 | 04:12:27 INFO - PID 4416 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:12:27 INFO - TEST-INFO | 4416: exit 0 04:12:27 INFO - TEST-OK | kraken | took 59167ms 04:12:27 INFO - SUITE-END | took 1167s 04:12:27 INFO - Completed test suite (00:19:27) 04:12:27 INFO - javascript benchmark 04:12:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8949.324023939982, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3825.387937929931, 3858.189780146171, 3870.0324918770298, 3886.1450480894227, 3894.240623078499, 6250.218722659668, 6322.462590679792, 6356.904646430302, 6396.584222025576, 6421.197350331208, 6325.33583973683, 6333.493320534358, 6339.080833279172, 6390.424861762441, 6390.55266131371, 5621.690807005613, 5736.627119237257, 5769.826905192851, 5772.682502462363, 5787.971060144693, 166.14047336537524, 5714.285714285715, 5759.971200143993, 5789.160571717099, 5792.739326730286, 5642.943570564304, 5648.943510564904, 5650.5479561635175, 5682.715864206773, 5704.714764261791, 5616.241807356037, 5636.126400407943, 5668.659880407189, 5718.942810571883, 5758.309002919635, 5695.117256825199, 5705.914411283834, 5772.711364431803, 5783.016887129198, 5786.652800831964, 6249.0626406039, 6280.277768056686, 6291.370862913718, 6302.054691796267, 6369.20384951881, 6686.598804071771, 6804.76183333581, 6805.795826125224, 6827.48793840459, 6869.210040845267, 6826.658667066677, 6888.931110688879, 6892.069570608004, 6898.758543450955, 6900.2064762552445, 6329.778457753957, 6389.648569328685, 6488.902666460007, 6494.253160886511, 6525.836854078639, 7140.571565706075, 7252.891206631904, 7261.092363454568, 7265.418766498773, 7269.454790890704, 6840.897386539206, 6841.828954276083, 6920.515563910478, 6946.895796563055, 6977.616231107237, 6772.5936443813525, 6874.690638921261, 6883.724651014004, 6989.580625162506, 7101.396381307543, 6823.829404264834, 6838.111045564145, 6841.281665425058, 6851.760188393482, 6875.346842049998, 6850.38346548813, 6930.0, 6947.201071876698, 6950.20072691647, 6955.0, 4949.925751113737, 4955.430125535538, 5000.62495312853, 5009.799608015712, 5030.748462576929, 4432.15789000089, 4485.0805584855025, 4493.236149854532, 4500.369948207253, 4521.547845215518, 6354.17395738551, 6391.552591318656, 6420.261669907927, 6437.839054023687, 6443.420092191725, 6740.359665831738, 6837.453003759687, 6938.791836244921, 6947.40947019509, 6951.200611929592, 6293.37066293367, 6332.841678958063, 6344.238691356974, 6377.71300291488, 6439.388258115471, 5455.1271796512365, 5505.862353441196, 5506.504414602705, 5507.449255074461, 5508.20131080999, 7035.085438893014, 7048.083749112582, 7049.753258635923, 7060.6116663584, 7088.362047415654, 6766.221884483249, 6800.761973330909, 6893.827654308682, 6992.0910281663055, 6998.75504357345, 7216.134063912364, 7226.819329516804, 7306.634668266459, 7312.853742925218, 7329.486935914435, 6816.318368163144, 6818.829529261808, 6822.283660215606, 6920.446364290945, 6922.653867306513, 6814.2163651180745, 6841.076454678555, 6871.896921546081, 6890.724371025303, 6940.271271516418, 6607.372299631527, 6679.0649309097735, 6710.328967103251, 6718.462522998051, 6720.193576770819, 6599.7360105595235, 6614.4708423325055, 6701.1623547056615, 6709.698063587052, 6709.798706038827, 6495.642739649365, 6522.608643481406, 6534.379233972765, 6586.967065164644, 6618.172728408949, 6639.568428052161, 6748.527603067738, 6748.7300507979135, 6755.898661519983, 6771.187457505131, 6407.551471396958, 6474.0, 6491.707873145625, 6496.512761542997, 6601.53789234749], "value": 6242.2645457225735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [957.1959553974662, 986.9950650246748, 987.1263931420694, 987.2842189412677, 988.9159421449172, 45339.319910201375, 45939.77030114845, 46245.0, 46418.767906160465, 46470.0, 43536.56463435365, 43947.56052439474, 44179.77910110453, 44194.33708494375, 44237.7788111059, 7325.9267407325915, 7466.216047315035, 7467.925320746805, 7498.325150736433, 7514.549127052394, 7057.541259818096, 7086.751963681272, 7089.610071446068, 7090.50366474347, 7104.893426598605, 8048.436609437342, 8222.876656850152, 8251.793705157359, 8252.628631711588, 8256.133106023872, 8119.147489513604, 8204.794880127985, 8210.753677389657, 8220.63007164679, 8235.1353107924, 8028.799280017988, 8093.838123237561, 8096.878546821802, 8139.145389734052, 8139.38954578409, 40428.59571404278, 40446.59553404458, 40775.592244077474, 41030.79484602558, 41071.1785764286, 3402.931941361184, 3435.3301106284284, 3474.7915125092322, 3485.5120283160377, 3504.9299014019575, 33702.83148584241, 33858.83070584656, 33945.15137121567, 33966.83016584901, 34028.0, 5555.194496797982, 5564.666120032771, 5593.216949627055, 5625.80309689163, 5636.0, 198597.00701496543, 206887.0, 208662.0, 208950.0, 209408.0, 46065.7696711521, 46386.0, 53350.0, 54062.0, 54260.72869635706, 57175.42824571701, 57213.0, 57362.0, 57430.7128464355, 57450.13824792631, 15451.61370965735, 15764.76352854708, 15829.525114246591, 15848.286827092808, 15910.522684319258, 12475.064370172273, 12516.937415312865, 12528.060395470377, 12546.05904557162, 12558.560450384193, 15664.0, 15674.9216253918, 15677.216139192995, 15744.92127539355, 15763.211839407984, 5846.1523079153285, 6004.129401236796, 6008.116104269975, 6076.635401875901, 6155.045967874987, 1095.370162156747, 1098.8351747237818, 1105.2705214558353, 1106.5739690219323, 1109.2734259060328, 25394.238172854475, 25477.872610636827, 25658.35854103625, 25819.35451613687, 25845.35386615312, 1173.976520469586, 1193.7373777768878, 1203.8796120387892, 1206.3365149168098, 1216.6958260434892, 13646.044776865525, 13671.931640341934, 13694.931525342508, 13866.93066534681, 13918.30408479572, 283131.0, 283690.0, 284051.0, 284252.0, 284302.0, 57331.0, 57549.0, 57560.71219643875, 57832.0, 57870.0, 1467.2297044051745, 1526.3284154971777, 1534.70840540297, 1537.446519253068, 1551.0926108226813, 24938.501229975664, 25202.873985629954, 25231.87384063068, 25454.0, 25483.61774573346, 41548.79225603853, 41549.37675934803, 41612.58387416087, 45881.77059114683, 46518.302225465966, 221494.0, 240503.0, 243675.0, 246523.0, 246551.0, 36231.0, 36349.636503634625, 36472.452913205794, 36482.08794780152, 36558.4516232262, 47204.52795472001, 47365.28952065653, 47471.76264118657, 47956.280655789495, 47964.520354796005, 34502.13744656404, 34616.0, 34673.653263467044, 34772.65227347694, 35122.64877351194, 33527.0, 33992.83003585065, 34028.48957265593, 34259.0, 34691.30617387689, 40118.799406002785, 40216.59783402128, 40736.18527629371, 40740.388894167205, 40967.1806563873, 32911.0, 32955.17612059718, 33213.0, 33304.50043249305, 33663.66336336605, 25094.74905250924, 25601.359966001, 25624.743752562237, 25855.741442585335, 25876.870615647553], "value": 18446.375505235377, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.309643909504, 604.4378727783161, 621.6021746082511, 631.6147150238354, 632.5919781740776, 21807.45481362967, 22361.44096397589, 23316.06735730571, 23363.0, 23453.0, 24348.878255608743, 24891.875540622317, 24928.252152435412, 24938.875305623493, 24979.75020249797, 6609.768658096968, 6698.531102822804, 6769.458443324547, 6815.42068924142, 6826.24911259761, 6415.1981002374705, 6696.196456425211, 6712.22809376923, 6720.0, 6755.763548275811, 6644.601323920556, 6681.231658359302, 6682.231543372526, 6683.431908287801, 6689.163854518185, 6543.574667646588, 6684.4986626003, 6689.264180940093, 6689.966550167267, 6827.044213810071, 6306.400891915261, 6334.334894836022, 6345.0, 6345.17512723343, 6345.714442850082, 28610.570841437187, 29083.41833163346, 29164.562531562042, 29190.854045729848, 29231.853840730873, 2304.5275718477674, 2336.5443738471004, 2338.029717667166, 2340.0522788270778, 2340.976590234093, 19986.800131998785, 20474.692879606817, 20505.17979280827, 20929.162833486644, 20967.16131354744, 2507.8244522883406, 2516.421223118693, 2565.5638541447997, 2598.454324591838, 2604.7785938195275, 133142.0, 139758.0, 142563.0, 145076.0, 151045.0, 15841.841581584267, 16101.758473622906, 16302.347906083862, 16458.177091145393, 16590.419335323204, 24771.25686229416, 24829.379265518146, 24904.37739056538, 24953.87523062373, 24989.750102499107, 10057.849132263022, 10067.69796906094, 10315.226358023028, 10348.068673819393, 10451.216158788122, 9358.485283309483, 9381.812363752686, 9421.199198068103, 9450.669226577036, 9488.857667134998, 9467.289953253534, 9581.904180958241, 9723.562439690231, 9932.702018939299, 10018.448985305733, 2783.7772978161697, 2852.686204517501, 2866.8279903205876, 2941.0735618280173, 3016.065019843855, 1034.8809886862957, 1038.1539045677748, 1043.968680939573, 1046.215338496128, 1049.186880167876, 20680.48298792542, 20687.689684654742, 20878.164873404894, 21190.258340958302, 21453.67819482709, 1109.378747901178, 1114.1950239930547, 1118.2004866520476, 1120.4173829608721, 1137.5790957345669, 11384.715382115513, 11627.186096973131, 11761.705957351134, 11832.0, 11903.345316007702, 593342.0, 613093.0, 614919.0, 636194.0, 636382.0, 122894.0, 124462.37768811098, 125620.37189813993, 126040.0, 126412.0, 1180.114913814639, 1204.8192771084407, 1223.4372188793254, 1238.1456794811731, 1242.9316387598587, 18462.0, 18734.625307494047, 18866.151023204264, 19006.049697515344, 19018.619627607095, 60860.0, 61299.38700613115, 61599.84400545959, 62029.37970620416, 64285.67857160684, 77445.0, 78232.60883695545, 78264.60867695625, 78562.0, 79120.60439697765, 14647.92676036613, 14987.700245995236, 15003.77494337564, 15051.096934183985, 15076.472323468626, 22950.655740163576, 23289.417764556023, 23440.64839027382, 23446.76553234446, 23509.177178799346, 19233.8076619232, 19290.324838630582, 19373.515662107995, 19391.4182574523, 19653.11560979788, 16348.673026539165, 16697.49907502777, 16961.745573816155, 17386.304547817945, 17559.12204389801, 19815.306464273683, 19824.70262946028, 19938.501537461678, 19944.50138746543, 20082.297119600746, 14254.358553865308, 14665.633359166106, 14835.480758173411, 14923.179225142287, 14962.551123466314, 18843.434696959113, 18969.90515047416, 19125.426237212905, 19210.13554390028, 19245.80754192496], "value": 12588.501780410686, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.154721393557, 1443.5236371997241, 1466.8899832512557, 1470.5808844479325, 1475.350845627924, 16130.919345403272, 16822.074785886773, 16868.662626747457, 16920.23858926348, 16928.492145235632, 16450.917745411258, 16521.256543455544, 16580.336786528555, 16581.751273730904, 16598.917005414987, 2573.0736934703536, 2699.8650067496646, 2708.3499960009553, 2728.140635699748, 2732.2076597786618, 2548.197317844873, 2689.623452716621, 2701.2166471723176, 2722.891084356623, 2744.0258708158617, 2606.6741657292837, 2621.737826217382, 2627.211836449067, 2628.434886499402, 2633.2363614551755, 2644.973550264492, 2675.772559332459, 2676.625272461857, 2681.839089654627, 2693.8787754550995, 2585.3665851866413, 2620.5545057340296, 2625.343664083979, 2638.6569745933098, 2640.524705553, 16277.674446511004, 16441.753373699405, 16449.67100657992, 16660.50018499447, 16733.497995060046, 7498.925010749932, 7847.646855891441, 7853.410994175402, 7858.449908506407, 7867.6459559319965, 5304.946950530484, 5404.135338345885, 5426.131818908995, 5506.229127922094, 5633.380328163898, 1866.1695545482266, 1880.1069491991332, 1893.0250920775811, 1924.9326273580498, 1930.4980705016796, 29856.402871942657, 30199.69800301991, 30202.84898575515, 30264.54603180954, 30276.394472110434, 6761.087253220752, 6771.695273712695, 6843.076184715, 6940.548864323803, 7053.118360204974, 19199.0, 19307.90346048261, 19320.32378866733, 19331.4200573983, 19341.41975740702, 7665.8850117248285, 7820.061592608809, 7840.843183136305, 7852.0, 7874.56689882062, 7047.788566343018, 7286.48994570375, 7341.375983041395, 7410.888836667455, 7433.85132297351, 5198.766055527435, 5279.841604751787, 5307.363116425976, 5330.147176451826, 5417.88933268679, 1440.4454285100103, 1514.8788096952217, 1544.7991761071214, 1547.3655801121486, 1560.3680509393714, 914.711865762296, 924.902885197058, 929.8279818233649, 934.8878134623889, 941.3504681769693, 9267.44395336282, 9368.344215904957, 9395.671151509665, 9405.717828465156, 9552.235821134293, 1056.1392465140887, 1060.565168281001, 1061.2359101446946, 1080.4651697409834, 1088.0642647323295, 4976.129177393942, 5169.198774190007, 5401.918971215435, 5698.686572238566, 5858.091995740667, 24582.385440364134, 24709.629355559682, 25068.749312507007, 25583.872080639478, 25726.6141007885, 24669.5066098677, 25175.37061573475, 25245.747542524707, 25306.367340816625, 25321.0, 12519.0, 12520.999999999818, 12593.685157871127, 12691.746165076647, 12712.809307860389, 9142.451452912846, 9219.539023048956, 9236.72289831306, 9244.583993720433, 9481.0, 20781.89609051945, 20868.68696969577, 21616.35150945474, 21914.67127993113, 22103.336899893027, 22010.669839952094, 22062.88968555147, 22125.225617103326, 22252.109915603196, 22498.662520061884, 12006.279623222634, 12013.339266340436, 12075.27548347102, 12084.033277337616, 12085.39573021363, 14810.333534990736, 14828.925855370655, 15063.54809355721, 15065.698686026437, 15079.773803392738, 12216.75566488683, 12253.632391028283, 12464.376781161087, 12786.936065319986, 12800.87199128034, 11889.464974076014, 12124.939375303067, 12317.322547259628, 12486.875131248935, 12514.499420023098, 11094.611688591183, 11264.380459074502, 11328.207025508136, 11333.376664283214, 11349.432528373383, 7863.292303692695, 7960.840783184188, 8037.316828069445, 8138.674453021813, 8168.3465322772845, 8292.751217463485, 8359.456635318686, 8372.372072094739, 8429.325653947546, 8432.662693492191], "value": 7120.409077433335, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.8174861421423, 552.3978863039288, 570.6576054367383, 579.5363709032773, 585.1778251556559, 6275.968620156898, 6302.873942521147, 6311.274203466603, 6327.715252813623, 6329.430351268396, 6154.138420621117, 6182.969085154579, 6222.222222222223, 6224.502039836813, 6237.251529816417, 5149.356330458692, 5156.278121063054, 5157.896842063156, 5160.974195129029, 5164.457731938149, 4830.420349558058, 4998.875028124289, 5138.152204886208, 5170.121079416508, 5175.58595312376, 4962.975185124069, 4989.226669866177, 4992.151334273182, 4999.100161970862, 5000.19996800512, 4955.702657840541, 4963.751812409401, 4974.37820272466, 4987.47631498691, 5007.098722229997, 4425.380446737459, 4489.595936365743, 4490.528494508062, 4491.169133710274, 4508.211063063951, 6136.079588061782, 6153.507719382439, 6165.969170154165, 6171.722272497748, 6202.503799696014, 5866.882662346729, 5869.4717475427415, 5891.7054147292465, 5896.204012458306, 5907.616004959664, 6706.2958389368905, 6721.495887808385, 6737.258901520829, 6753.696083676197, 6762.93237067628, 5061.9876024795185, 5066.290719299301, 5076.44159142494, 5085.338905942241, 5093.0, 6726.562773419712, 6806.7957961261245, 6894.345037221456, 6917.377436030731, 6936.306369363074, 5096.566791822736, 5123.333966584395, 5126.846194614093, 5175.560077393388, 5186.507281808223, 5175.53420192177, 5193.195054766517, 5218.191180367049, 5228.2941802856885, 5278.947210527922, 5843.100992177474, 5848.4151584841175, 5851.590388672752, 5857.853553661107, 5889.793857214977, 6703.93296067033, 6720.193576770721, 6726.3609957054005, 6737.797866064026, 6746.898796518056, 1791.4177892184832, 1809.8280663336961, 1813.455963211058, 1821.6265665538533, 1830.5240637434097, 1209.8291190622367, 1256.4032084759665, 1259.8614152443224, 1275.79587266037, 1287.575100216926, 6202.472789812826, 6216.564840461215, 6262.655553944485, 6265.279492858289, 6266.24805023391, 6551.901721474182, 6559.409653131145, 6620.271770105284, 6665.933340666628, 6715.5299129060495, 5955.0, 6132.448079672851, 6173.59871608344, 6174.012158054689, 6189.659568723763, 3488.0059183132685, 3530.6645868642436, 3533.2933413316923, 3537.451694987281, 3575.66031227033, 6915.585064896021, 6977.511574189758, 7020.473464490184, 7026.297370262932, 7063.421851024339, 6684.16447944007, 6842.076319696876, 6855.383015528626, 6860.416864566568, 6899.0341352210735, 6942.687579058955, 7029.92970070303, 7049.682764275517, 7066.929330706627, 7090.858182836314, 6619.834504137435, 6675.0, 6712.39588437043, 6731.562448441032, 6743.831404214934, 6613.567701181033, 6639.0, 6649.534532582673, 6685.933140668531, 6717.1267735194115, 7007.057036044772, 7040.591881623798, 7060.223375428596, 7062.728708832254, 7106.786796396116, 5139.691618502902, 5144.9742751286, 5146.845594632167, 5162.845114646417, 5175.404828444777, 6632.767853125118, 6695.966520167368, 6772.446593793043, 6807.421369183477, 6814.2163651180745, 6307.779227727007, 6428.774992875227, 6505.316941721051, 6520.576162549419, 6628.171478565179, 6493.870122597427, 6518.217813862367, 6544.411003009752, 6546.705398257185, 6573.737050517926, 6310.245403984622, 6478.319776423407, 6486.286508484157, 6489.772857949949, 6521.250056243593, 6427.871442571216, 6572.507061970276, 6584.7036883341325, 6626.56927299724, 6630.569013014138, 6309.432151106423, 6344.365563443619, 6351.333110023381, 6378.298387177314, 6393.529488217643], "value": 5173.757626811002, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [961.2886464016625, 986.4574484033782, 999.3254553176604, 1005.4419797012655, 1013.3818370793816, 18642.813571864277, 18967.810321896777, 19072.90463547682, 19077.236910523563, 19105.61788764224, 17741.201645925932, 17758.733618995724, 17902.28390864364, 17924.82075179248, 17929.0, 1029.408090348051, 1043.4730461117126, 1054.5728979763207, 1057.5981127171665, 1060.178361769627, 1042.2704107125005, 1051.3796859852685, 1054.8554818022417, 1055.4617145255936, 1060.787842431513, 1060.2790102730141, 1084.3656460970342, 1099.0438318662734, 1099.2140619457086, 1112.9276597021208, 1096.8299913513417, 1100.4937728644834, 1100.928439651424, 1101.0585949013557, 1103.7461383881753, 788.1724189600897, 799.5162926429542, 801.054755388647, 813.7518056992615, 817.0889458254063, 19302.517437064045, 19310.420687379403, 19503.707444388347, 19557.119929603203, 19712.40862774119, 21758.12967481299, 21832.0, 22254.0, 22358.776412235995, 22456.101755929743, 17096.914515427343, 17435.65128697419, 17744.3789467368, 17777.46667599961, 17917.37289194872, 3894.883153505399, 3920.274749171384, 3920.705947053954, 3934.0951581136214, 3935.5080614923136, 124509.37745311216, 156678.21660891737, 158035.99999999884, 159577.0, 163040.3695963037, 16570.254338554794, 16636.750448743278, 16926.238319275417, 17194.828051719323, 17386.65226695459, 34134.658653413644, 34278.48582271268, 34411.13972150716, 34480.137996550286, 35266.11834704153, 9534.95232523833, 9574.808503829885, 9577.760555986017, 9578.185854202331, 9642.662506812369, 4211.957880421218, 4294.592013758688, 4296.548862369469, 4298.032942587955, 4303.203907277164, 5929.14027466015, 5934.821955341346, 5985.281766188085, 5997.940020599738, 6000.699965001732, 1165.6677846813618, 1195.8086706126978, 1209.2865209526246, 1211.2187638972814, 1221.8533775946767, 661.7551505942832, 667.9599224046573, 670.0719503487608, 674.7065026713298, 683.0880774166444, 12802.231866088065, 12916.741665166644, 13246.470141194437, 14242.643933901529, 14288.785668214985, 671.2079745899788, 674.8346655069441, 684.0867441964933, 686.4405509509734, 693.3898169610811, 6293.811185664437, 6347.650879201688, 6407.935920640734, 6410.55126141175, 6420.486361091102, 147955.26022370034, 148857.0, 149962.0, 150349.0, 152201.0, 130178.34910825385, 131946.0, 132243.0, 133378.33310833384, 138100.0, 114216.42891785488, 114248.0013199534, 114365.0, 114879.42560287144, 115694.4215278935, 11825.40872956366, 11973.820392694292, 12047.75904481923, 12136.0, 12175.39123043862, 55959.0, 57037.42962570487, 57235.141472877105, 57462.7126864363, 59619.10571341347, 69788.30211697819, 70230.0, 71088.64455677688, 72625.63687181531, 73110.0, 14327.57017289483, 14794.6301342463, 14826.851731482548, 15041.6991660164, 15051.623709407353, 17107.401240956508, 17246.82753172452, 17281.0, 17316.56708582245, 17344.392946246822, 15227.695446091238, 15728.842711573196, 15821.762673559675, 15828.0, 15846.0, 16238.0, 16527.50417487477, 16580.0, 16660.16699165061, 16801.32794688199, 16488.505344839676, 16670.333186672397, 16692.499225023268, 16796.412125575545, 16933.15334233259, 12791.232526048467, 13096.738065238833, 13132.671683207996, 13167.144135631153, 13260.53588124411, 13405.597832065054, 13507.662308442368, 13646.454141834216, 13658.180509169482, 13806.723865522834], "value": 9620.033621736158, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1374.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 106.0, 107.0, 106.0, 106.0, 111.0, 109.0, 107.0, 106.0, 105.0], "value": 106.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 114.0, 117.0, 115.0, 113.0, 116.0, 115.0, 113.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 162.0, 160.0, 161.0, 162.0, 162.0, 162.0, 168.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 75.0, 74.0, 74.0, 74.0, 73.0, 76.0, 73.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 79.0, 78.0, 77.0, 78.0, 77.0, 77.0, 78.0, 81.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 116.0, 114.0, 115.0, 113.0, 111.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 149.0, 149.0, 149.0, 149.0, 150.0, 150.0, 150.0, 150.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 99.0, 95.0, 93.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 44.0, 48.0, 43.0, 45.0, 43.0, 44.0, 43.0, 43.0, 43.0], "value": 44.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 38.0, 38.0, 37.0, 37.0, 49.0, 37.0, 37.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 78.0, 74.0, 73.0, 70.0, 72.0, 70.0, 73.0, 71.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 119.0, 105.0, 104.0, 109.0, 117.0, 104.0, 111.0, 105.0, 101.0], "value": 108.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 163.0, 153.0, 153.0, 150.0, 151.0, 149.0, 155.0, 167.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 60.0, 58.0, 61.0, 65.0, 59.0, 59.0, 59.0, 60.0, 61.0], "value": 60.7, "unit": "score"}]}]} 04:12:27 INFO - Return code: 0 04:12:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:12:27 INFO - # TBPL SUCCESS # 04:12:27 INFO - Running post-action listener: _package_coverage_data 04:12:27 INFO - Running post-action listener: _resource_record_post_action 04:12:27 INFO - [mozharness: 2017-09-16 11:12:27.905756Z] Finished run-tests step (success) 04:12:27 INFO - Running post-run listener: _resource_record_post_run 04:12:27 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 8871936; Write bytes: 456404992; Read time: 2448; Write time: 338000 04:12:27 INFO - TinderboxPrint: CPU usage
13.1% 04:12:27 INFO - TinderboxPrint: I/O read bytes / time
8,871,936 / 2,448 04:12:27 INFO - TinderboxPrint: I/O write bytes / time
456,404,992 / 338,000 04:12:27 INFO - TinderboxPrint: CPU idle
8,212.6 (86.9%) 04:12:27 INFO - TinderboxPrint: CPU user
1,186.2 (12.6%) 04:12:27 INFO - TinderboxPrint: Swap in / out
0 / 0 04:12:27 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 132145152; Read time: 136; Write time: 141368 04:12:27 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:12:28 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 7122944; Write bytes: 324259840; Read time: 2276; Write time: 196632 04:12:28 INFO - Running post-run listener: _upload_blobber_files 04:12:28 INFO - Blob upload gear active. 04:12:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:12:28 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 04:12:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:12:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:12:28 INFO - (blobuploader) - INFO - Open directory for files ... 04:12:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:12:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:12:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:29 INFO - (blobuploader) - INFO - Done attempting. 04:12:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:12:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:12:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:30 INFO - (blobuploader) - INFO - Done attempting. 04:12:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:12:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:12:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:31 INFO - (blobuploader) - INFO - Done attempting. 04:12:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:12:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:12:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:38 INFO - (blobuploader) - INFO - Done attempting. 04:12:38 INFO - (blobuploader) - INFO - Iteration through files over. 04:12:38 INFO - Return code: 0 04:12:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:12:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:12:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1889a3897bc40c751f72f11e114516368e1f5430698959baeb9cadd1787fba134895cdb7ce7a7c88f795be8092e2f79c5c2a7f223bf579aa5cfdaf3a12e92773", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00b6d70b511bf0dcb92fb15ec7b6ec7a0353a7f9a9fcdb89c1070f7529dfbf992adc8b6d70f58d46502541ee85c14ff434666d0ab16529bf2804f0f8eea38012", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/585cf25fd6d76eb255e766506835771766bbada885650d9df103857ed4aa2c9ef799b808aac2233fafa75a6f9f2beb0594238beacf628b8985fb0fd55c59886c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0dcda2d4cb406a36b98df81a6549b998ea93fbb45c070897681ed0571105aeca6a27e4264ae3cdc11da7870b24ac8e11b426aadd30a7007ce1e81a097b67887"} 04:12:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:12:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:12:38 INFO - Contents: 04:12:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1889a3897bc40c751f72f11e114516368e1f5430698959baeb9cadd1787fba134895cdb7ce7a7c88f795be8092e2f79c5c2a7f223bf579aa5cfdaf3a12e92773", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00b6d70b511bf0dcb92fb15ec7b6ec7a0353a7f9a9fcdb89c1070f7529dfbf992adc8b6d70f58d46502541ee85c14ff434666d0ab16529bf2804f0f8eea38012", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/585cf25fd6d76eb255e766506835771766bbada885650d9df103857ed4aa2c9ef799b808aac2233fafa75a6f9f2beb0594238beacf628b8985fb0fd55c59886c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0dcda2d4cb406a36b98df81a6549b998ea93fbb45c070897681ed0571105aeca6a27e4264ae3cdc11da7870b24ac8e11b426aadd30a7007ce1e81a097b67887"} 04:12:38 INFO - Running post-run listener: copy_logs_to_upload_dir 04:12:38 INFO - Copying logs to upload dir... 04:12:38 INFO - mkdir: /builds/slave/test/build/upload/logs 04:12:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.920654 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-16 04:12:38.611826) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 04:12:38.619248) ========= 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=523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1889a3897bc40c751f72f11e114516368e1f5430698959baeb9cadd1787fba134895cdb7ce7a7c88f795be8092e2f79c5c2a7f223bf579aa5cfdaf3a12e92773", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00b6d70b511bf0dcb92fb15ec7b6ec7a0353a7f9a9fcdb89c1070f7529dfbf992adc8b6d70f58d46502541ee85c14ff434666d0ab16529bf2804f0f8eea38012", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/585cf25fd6d76eb255e766506835771766bbada885650d9df103857ed4aa2c9ef799b808aac2233fafa75a6f9f2beb0594238beacf628b8985fb0fd55c59886c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0dcda2d4cb406a36b98df81a6549b998ea93fbb45c070897681ed0571105aeca6a27e4264ae3cdc11da7870b24ac8e11b426aadd30a7007ce1e81a097b67887"} build_url:https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011147 build_url: 'https://queue.taskcluster.net/v1/task/Uxal_AuHRK26pEqWpbUtvQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1889a3897bc40c751f72f11e114516368e1f5430698959baeb9cadd1787fba134895cdb7ce7a7c88f795be8092e2f79c5c2a7f223bf579aa5cfdaf3a12e92773", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00b6d70b511bf0dcb92fb15ec7b6ec7a0353a7f9a9fcdb89c1070f7529dfbf992adc8b6d70f58d46502541ee85c14ff434666d0ab16529bf2804f0f8eea38012", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/585cf25fd6d76eb255e766506835771766bbada885650d9df103857ed4aa2c9ef799b808aac2233fafa75a6f9f2beb0594238beacf628b8985fb0fd55c59886c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0dcda2d4cb406a36b98df81a6549b998ea93fbb45c070897681ed0571105aeca6a27e4264ae3cdc11da7870b24ac8e11b426aadd30a7007ce1e81a097b67887"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 04:12:38.654438) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 04:12:38.654804) ========= 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=523ca41ca1e0623de333ad52000001bf-1505559086.206046-1387880084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004417 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 04:12:38.737592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 04:12:38.737930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 04:12:38.738307) ========= ========= Total master_lag: 0.25 =========