builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1505340852.82 results: success (0) revision: b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:12.825253) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:12.825775) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:12.826053) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:12.840912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:12.841347) ========= 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=03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 15:14:12-- 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% 103M=0s 2017-09-13 15:14:13 (103 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.221756 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:13.080125) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:13.080445) ========= 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=03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020924 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:13.131223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:13.131566) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d --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 b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d --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=03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 15:14:13,186 truncating revision to first 12 chars 2017-09-13 15:14:13,187 Setting DEBUG logging. 2017-09-13 15:14:13,187 attempt 1/10 2017-09-13 15:14:13,187 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b9ffb07cf8e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 15:14:13,735 unpacking tar archive at: mozilla-central-b9ffb07cf8e7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.823416 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:13.971860) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:13.972191) ========= script_repo_revision: b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:13.972570) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:13.972847) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:13.985608) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-13 15:14:13.986031) ========= /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=03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:14:14 INFO - MultiFileLogger online at 20170913 15:14:14 in /builds/slave/test 15:14:14 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 15:14:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:14:14 INFO - {'append_to_log': False, 15:14:14 INFO - 'base_work_dir': '/builds/slave/test', 15:14:14 INFO - 'blob_upload_branch': 'Firefox', 15:14:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:14:14 INFO - 'branch': 'Firefox', 15:14:14 INFO - 'buildbot_json_path': 'buildprops.json', 15:14:14 INFO - 'code_coverage': False, 15:14:14 INFO - 'config_files': ('talos/linux_config.py',), 15:14:14 INFO - 'default_actions': ('clobber', 15:14:14 INFO - 'read-buildbot-config', 15:14:14 INFO - 'download-and-extract', 15:14:14 INFO - 'populate-webroot', 15:14:14 INFO - 'create-virtualenv', 15:14:14 INFO - 'install', 15:14:14 INFO - 'setup-mitmproxy', 15:14:14 INFO - 'run-tests'), 15:14:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:14:14 INFO - 'disable_ccov_upload': False, 15:14:14 INFO - 'disable_stylo': False, 15:14:14 INFO - 'download_minidump_stackwalk': True, 15:14:14 INFO - 'download_symbols': 'ondemand', 15:14:14 INFO - 'enable_stylo': False, 15:14:14 INFO - 'enable_webrender': False, 15:14:14 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:14:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:14:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:14:14 INFO - 'gecko_profile': False, 15:14:14 INFO - 'gecko_profile_interval': 0, 15:14:14 INFO - 'installer_path': 'installer.exe', 15:14:14 INFO - 'log_level': 'info', 15:14:14 INFO - 'log_name': 'talos', 15:14:14 INFO - 'log_to_console': True, 15:14:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:14:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:14:14 INFO - 'opt_config_files': (), 15:14:14 INFO - 'pip_index': False, 15:14:14 INFO - 'suite': 'dromaeojs-e10s', 15:14:14 INFO - 'system_bits': '32', 15:14:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:14:14 INFO - 'title': 'talos-linux64-ix-047', 15:14:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:14:14 INFO - 'use_talos_json': True, 15:14:14 INFO - 'verify': 'False', 15:14:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:14:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:14:14 INFO - 'work_dir': 'build'} 15:14:14 INFO - [mozharness: 2017-09-13 22:14:14.175568Z] Running clobber step. 15:14:14 INFO - Running pre-action listener: _resource_record_pre_action 15:14:14 INFO - Running main action method: clobber 15:14:14 INFO - rmtree: /builds/slave/test/build 15:14:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:14:15 INFO - Running post-action listener: _resource_record_post_action 15:14:15 INFO - [mozharness: 2017-09-13 22:14:15.769855Z] Finished clobber step (success) 15:14:15 INFO - [mozharness: 2017-09-13 22:14:15.769953Z] Running read-buildbot-config step. 15:14:15 INFO - Running pre-action listener: _resource_record_pre_action 15:14:15 INFO - Running main action method: read_buildbot_config 15:14:15 INFO - Using buildbot properties: 15:14:15 INFO - { 15:14:15 INFO - "project": "", 15:14:15 INFO - "product": "firefox", 15:14:15 INFO - "who": "kwierso@gmail.com", 15:14:15 INFO - "installer_path": "public/build/target.tar.bz2", 15:14:15 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 15:14:15 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:14:15 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 15:14:15 INFO - "stage_platform": "linux64-qr", 15:14:15 INFO - "basedir": "/builds/slave/test", 15:14:15 INFO - "buildnumber": 51, 15:14:15 INFO - "platform": "ubuntu64_hw_qr", 15:14:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:14:15 INFO - "slavebuilddir": "test", 15:14:15 INFO - "taskId": "GhDvxgPeS6CRgx3qqPnLWg", 15:14:15 INFO - "branch": "mozilla-central", 15:14:15 INFO - "script_repo_revision": "production", 15:14:15 INFO - "revision": "b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d", 15:14:15 INFO - "slavename": "talos-linux64-ix-047", 15:14:15 INFO - "repo_path": "mozilla-central" 15:14:15 INFO - } 15:14:15 INFO - Finding installer, test and symbols from parent task. 15:14:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GhDvxgPeS6CRgx3qqPnLWg'}, attempt #1 15:14:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GhDvxgPeS6CRgx3qqPnLWg'}, attempt #1 15:14:16 INFO - Task dependencies: QBzmMKkgReqgjM-CLAYvGQ 15:14:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ'}, attempt #1 15:14:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 15:14:17 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.test_packages.json 15:14:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.crashreporter-symbols.zip 15:14:17 INFO - Running post-action listener: _resource_record_post_action 15:14:17 INFO - [mozharness: 2017-09-13 22:14:17.136225Z] Finished read-buildbot-config step (success) 15:14:17 INFO - [mozharness: 2017-09-13 22:14:17.136442Z] Running download-and-extract step. 15:14:17 INFO - Running pre-action listener: _resource_record_pre_action 15:14:17 INFO - Running main action method: download_and_extract 15:14:17 INFO - mkdir: /builds/slave/test/build/tests 15:14: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')]} 15:14:17 INFO - trying https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.test_packages.json 15:14:17 INFO - Downloading https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:14:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:14:17 INFO - Downloaded 989 bytes. 15:14:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:14:17 INFO - Using the following test package requirements: 15:14:17 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:14:17 INFO - u'common': [u'target.common.tests.zip'], 15:14:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:14:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:14:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:14:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:14:17 INFO - u'mozbase': [u'target.common.tests.zip'], 15:14:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:14:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:14:17 INFO - u'web-platform': [u'target.common.tests.zip', 15:14:17 INFO - u'target.web-platform.tests.tar.gz'], 15:14:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:14:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:14:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.common.tests.zip 15:14:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:14:17 INFO - Fetch https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.common.tests.zip into memory 15:14:18 INFO - Content-Length response header: 54570111 15:14:18 INFO - Bytes received: 54570111 15:14:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.talos.tests.zip 15:14:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:14:29 INFO - Fetch https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.talos.tests.zip into memory 15:14:30 INFO - Content-Length response header: 14499226 15:14:30 INFO - Bytes received: 14499226 15:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:30 INFO - trying https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 15:14:30 INFO - Downloading https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:14:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:14:32 INFO - Downloaded 61426431 bytes. 15:14:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 15:14:32 INFO - mkdir: /builds/slave/test/properties 15:14:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:14:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:14:32 INFO - Contents: 15:14:32 INFO - build_url:https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 15:14:32 INFO - Running post-action listener: _resource_record_post_action 15:14:32 INFO - Running post-action listener: find_tests_for_verification 15:14:32 INFO - Running post-action listener: set_extra_try_arguments 15:14:32 INFO - [mozharness: 2017-09-13 22:14:32.022064Z] Finished download-and-extract step (success) 15:14:32 INFO - [mozharness: 2017-09-13 22:14:32.022160Z] Running populate-webroot step. 15:14:32 INFO - Running pre-action listener: _resource_record_pre_action 15:14:32 INFO - Running main action method: populate_webroot 15:14:32 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:14:32 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['tresize', 15:14:32 INFO - 'tcanvasmark']}, 15:14:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:14:32 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['dromaeo_css', 15:14:32 INFO - 'kraken']}, 15:14:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:14:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:32 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:32 INFO - 'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:14:32 INFO - 'tests': ['damp', 'tps']}, 15:14:32 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:32 INFO - 'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['damp', 'tps']}, 15:14:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:14:32 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['dromaeo_dom']}, 15:14:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:14:32 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['basic_compositor_video', 15:14:32 INFO - 'glvideo']}, 15:14:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:14:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:14:32 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:32 INFO - 'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['ts_paint_webext', 15:14:32 INFO - 'tp5o_webext']}, 15:14:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:14:32 INFO - 'ts_paint', 15:14:32 INFO - 'tpaint', 15:14:32 INFO - 'sessionrestore', 15:14:32 INFO - 'sessionrestore_many_windows', 15:14:32 INFO - 'sessionrestore_no_auto_restore', 15:14:32 INFO - 'tabpaint']}, 15:14:32 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['a11yr', 15:14:32 INFO - 'ts_paint', 15:14:32 INFO - 'tpaint', 15:14:32 INFO - 'sessionrestore', 15:14:32 INFO - 'sessionrestore_many_windows', 15:14:32 INFO - 'sessionrestore_no_auto_restore', 15:14:32 INFO - 'tabpaint']}, 15:14:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:14:32 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:14:32 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['bloom_basic_singleton']}, 15:14:32 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['bloom_basic']}, 15:14:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:14:32 INFO - 'tsvgr_opacity', 15:14:32 INFO - 'tart', 15:14:32 INFO - 'tscrollx', 15:14:32 INFO - 'tsvg_static']}, 15:14:32 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['tsvgx', 15:14:32 INFO - 'tsvgr_opacity', 15:14:32 INFO - 'tart', 15:14:32 INFO - 'tscrollx', 15:14:32 INFO - 'tsvg_static']}, 15:14:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:14:32 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:32 INFO - 'talos_options': ['--disable-stylo'], 15:14:32 INFO - 'tests': ['tp5o']}, 15:14:32 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:14:32 INFO - 'talos_options': ['--mitmproxy', 15:14:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:14:32 INFO - '--firstNonBlankPaint'], 15:14:32 INFO - 'tests': ['tp6_google', 15:14:32 INFO - 'tp6_youtube', 15:14:32 INFO - 'tp6_amazon', 15:14:32 INFO - 'tp6_facebook']}, 15:14:32 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:14:32 INFO - 'talos_options': ['--disable-stylo', 15:14:32 INFO - '--mitmproxy', 15:14:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:14:32 INFO - '--firstNonBlankPaint'], 15:14:32 INFO - 'tests': ['tp6_google', 15:14:32 INFO - 'tp6_youtube', 15:14:32 INFO - 'tp6_amazon', 15:14:32 INFO - 'tp6_facebook']}, 15:14:32 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:14:32 INFO - 'talos_options': ['--stylo-threads=1', 15:14:32 INFO - '--mitmproxy', 15:14:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:14:32 INFO - '--firstNonBlankPaint'], 15:14:32 INFO - 'tests': ['tp6_google', 15:14:32 INFO - 'tp6_youtube', 15:14:32 INFO - 'tp6_amazon', 15:14:32 INFO - 'tp6_facebook']}, 15:14:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:14:32 INFO - 'talos_options': ['--xperf_path', 15:14:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:32 INFO - 'tests': ['tp5n']}, 15:14:32 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:32 INFO - 'talos_options': ['--disable-stylo', 15:14:32 INFO - '--xperf_path', 15:14:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:32 INFO - 'tests': ['tp5n']}}} 15:14:32 INFO - Running post-action listener: _resource_record_post_action 15:14:32 INFO - [mozharness: 2017-09-13 22:14:32.037047Z] Finished populate-webroot step (success) 15:14:32 INFO - [mozharness: 2017-09-13 22:14:32.037136Z] Running create-virtualenv step. 15:14:32 INFO - Running pre-action listener: _resource_record_pre_action 15:14:32 INFO - Running main action method: create_virtualenv 15:14:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:14:32 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:14:32 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:14:32 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:14:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:14:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:14:32 INFO - Installing distribute.............................................................................................................................................................................................done. 15:14:33 INFO - Installing pip...............done. 15:14:33 INFO - Return code: 0 15:14:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:14:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:14:33 INFO - Reading from file tmpfile_stdout 15:14:33 INFO - Output received: 15:14:33 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:14:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:14:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:33 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 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:14:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:14:33 INFO - Using env: {'DISPLAY': ':0', 15:14:33 INFO - 'HOME': '/home/cltbld', 15:14:33 INFO - 'LANG': 'en_US.UTF-8', 15:14:33 INFO - 'LANGUAGE': 'en_US:en', 15:14:33 INFO - 'LOGNAME': 'cltbld', 15:14:33 INFO - 'MAIL': '/var/mail/cltbld', 15:14:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:33 INFO - 'MOZ_NO_REMOTE': '1', 15:14:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:33 INFO - 'NO_EM_RESTART': '1', 15:14:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:33 INFO - 'PWD': '/builds/slave/test', 15:14:33 INFO - 'SHELL': '/bin/bash', 15:14:33 INFO - 'SHLVL': '1', 15:14:33 INFO - 'TERM': 'linux', 15:14:33 INFO - 'TMOUT': '86400', 15:14:33 INFO - 'USER': 'cltbld', 15:14:33 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:33 INFO - '_': '/tools/buildbot/bin/python'} 15:14:34 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:14:34 INFO - Downloading/unpacking pip>=1.5 15:14:34 INFO - Running setup.py egg_info for package pip 15:14:34 INFO - warning: no files found matching 'pip/cacert.pem' 15:14:34 INFO - warning: no files found matching '*.html' under directory 'docs' 15:14:34 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:14:34 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:14:34 INFO - Installing collected packages: pip 15:14:34 INFO - Found existing installation: pip 1.1 15:14:34 INFO - Uninstalling pip: 15:14:34 INFO - Successfully uninstalled pip 15:14:34 INFO - Running setup.py install for pip 15:14:34 INFO - warning: no files found matching 'pip/cacert.pem' 15:14:34 INFO - warning: no files found matching '*.html' under directory 'docs' 15:14:34 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:14:34 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:14:34 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Successfully installed pip 15:14:34 INFO - Cleaning up... 15:14:34 INFO - Return code: 0 15:14:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:34 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 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:14:34 INFO - Using env: {'DISPLAY': ':0', 15:14:34 INFO - 'HOME': '/home/cltbld', 15:14:34 INFO - 'LANG': 'en_US.UTF-8', 15:14:34 INFO - 'LANGUAGE': 'en_US:en', 15:14:34 INFO - 'LOGNAME': 'cltbld', 15:14:34 INFO - 'MAIL': '/var/mail/cltbld', 15:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:34 INFO - 'MOZ_NO_REMOTE': '1', 15:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:34 INFO - 'NO_EM_RESTART': '1', 15:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:34 INFO - 'PWD': '/builds/slave/test', 15:14:34 INFO - 'SHELL': '/bin/bash', 15:14:34 INFO - 'SHLVL': '1', 15:14:34 INFO - 'TERM': 'linux', 15:14:34 INFO - 'TMOUT': '86400', 15:14:34 INFO - 'USER': 'cltbld', 15:14:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:34 INFO - '_': '/tools/buildbot/bin/python'} 15:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:34 INFO - Downloading/unpacking psutil>=3.1.1 15:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:14:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:14:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:14:35 INFO - Installing collected packages: psutil 15:14:35 INFO - Running setup.py install for psutil 15:14:35 INFO - building 'psutil._psutil_linux' extension 15:14:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:14:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:14:35 INFO - building 'psutil._psutil_posix' extension 15:14:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:14:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:14:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:14:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:14:36 INFO - Successfully installed psutil 15:14:36 INFO - Cleaning up... 15:14:36 INFO - Return code: 0 15:14:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:14: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')]} 15:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14: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')]} 15:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:36 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 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:14:36 INFO - Using env: {'DISPLAY': ':0', 15:14:36 INFO - 'HOME': '/home/cltbld', 15:14:36 INFO - 'LANG': 'en_US.UTF-8', 15:14:36 INFO - 'LANGUAGE': 'en_US:en', 15:14:36 INFO - 'LOGNAME': 'cltbld', 15:14:36 INFO - 'MAIL': '/var/mail/cltbld', 15:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:36 INFO - 'MOZ_NO_REMOTE': '1', 15:14:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:36 INFO - 'NO_EM_RESTART': '1', 15:14:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:36 INFO - 'PWD': '/builds/slave/test', 15:14:36 INFO - 'SHELL': '/bin/bash', 15:14:36 INFO - 'SHLVL': '1', 15:14:36 INFO - 'TERM': 'linux', 15:14:36 INFO - 'TMOUT': '86400', 15:14:36 INFO - 'USER': 'cltbld', 15:14:36 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:36 INFO - '_': '/tools/buildbot/bin/python'} 15:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:14:37 INFO - Installing collected packages: mozsystemmonitor 15:14:37 INFO - Running setup.py install for mozsystemmonitor 15:14:37 INFO - Successfully installed mozsystemmonitor 15:14:37 INFO - Cleaning up... 15:14:38 INFO - Return code: 0 15:14:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:38 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 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:14:38 INFO - Using env: {'DISPLAY': ':0', 15:14:38 INFO - 'HOME': '/home/cltbld', 15:14:38 INFO - 'LANG': 'en_US.UTF-8', 15:14:38 INFO - 'LANGUAGE': 'en_US:en', 15:14:38 INFO - 'LOGNAME': 'cltbld', 15:14:38 INFO - 'MAIL': '/var/mail/cltbld', 15:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:38 INFO - 'MOZ_NO_REMOTE': '1', 15:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:38 INFO - 'NO_EM_RESTART': '1', 15:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:38 INFO - 'PWD': '/builds/slave/test', 15:14:38 INFO - 'SHELL': '/bin/bash', 15:14:38 INFO - 'SHLVL': '1', 15:14:38 INFO - 'TERM': 'linux', 15:14:38 INFO - 'TMOUT': '86400', 15:14:38 INFO - 'USER': 'cltbld', 15:14:38 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:38 INFO - '_': '/tools/buildbot/bin/python'} 15:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:38 INFO - Downloading/unpacking jsonschema==2.5.1 15:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:14:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:40 INFO - Downloading functools32-3.2.3-2.tar.gz 15:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:14:40 INFO - warning: no files found matching '*.txt' 15:14:40 INFO - no previously-included directories found matching 'build' 15:14:40 INFO - no previously-included directories found matching 'dist' 15:14:40 INFO - no previously-included directories found matching '.git*' 15:14:40 INFO - Installing collected packages: jsonschema, functools32 15:14:40 INFO - Running setup.py install for functools32 15:14:40 INFO - warning: no files found matching '*.txt' 15:14:40 INFO - no previously-included directories found matching 'build' 15:14:40 INFO - no previously-included directories found matching 'dist' 15:14:40 INFO - no previously-included directories found matching '.git*' 15:14:40 INFO - Successfully installed jsonschema functools32 15:14:40 INFO - Cleaning up... 15:14:40 INFO - Return code: 0 15:14:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:14:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:40 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 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:14:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:14:40 INFO - Using env: {'DISPLAY': ':0', 15:14:40 INFO - 'HOME': '/home/cltbld', 15:14:40 INFO - 'LANG': 'en_US.UTF-8', 15:14:40 INFO - 'LANGUAGE': 'en_US:en', 15:14:40 INFO - 'LOGNAME': 'cltbld', 15:14:40 INFO - 'MAIL': '/var/mail/cltbld', 15:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:40 INFO - 'MOZ_NO_REMOTE': '1', 15:14:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:40 INFO - 'NO_EM_RESTART': '1', 15:14:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:40 INFO - 'PWD': '/builds/slave/test', 15:14:40 INFO - 'SHELL': '/bin/bash', 15:14:40 INFO - 'SHLVL': '1', 15:14:40 INFO - 'TERM': 'linux', 15:14:40 INFO - 'TMOUT': '86400', 15:14:40 INFO - 'USER': 'cltbld', 15:14:40 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:40 INFO - '_': '/tools/buildbot/bin/python'} 15:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:14:40 INFO - Cleaning up... 15:14:41 INFO - Return code: 0 15:14:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:14: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')]} 15:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14: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')]} 15:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14: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', '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 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:14: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 blobuploader==1.2.4 15:14:41 INFO - Using env: {'DISPLAY': ':0', 15:14:41 INFO - 'HOME': '/home/cltbld', 15:14:41 INFO - 'LANG': 'en_US.UTF-8', 15:14:41 INFO - 'LANGUAGE': 'en_US:en', 15:14:41 INFO - 'LOGNAME': 'cltbld', 15:14:41 INFO - 'MAIL': '/var/mail/cltbld', 15:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:41 INFO - 'MOZ_NO_REMOTE': '1', 15:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:41 INFO - 'NO_EM_RESTART': '1', 15:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:41 INFO - 'PWD': '/builds/slave/test', 15:14:41 INFO - 'SHELL': '/bin/bash', 15:14:41 INFO - 'SHLVL': '1', 15:14:41 INFO - 'TERM': 'linux', 15:14:41 INFO - 'TMOUT': '86400', 15:14:41 INFO - 'USER': 'cltbld', 15:14:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:41 INFO - '_': '/tools/buildbot/bin/python'} 15:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:41 INFO - Downloading/unpacking blobuploader==1.2.4 15:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:42 INFO - Downloading blobuploader-1.2.4.tar.gz 15:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:14:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:14:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:42 INFO - Downloading docopt-0.6.1.tar.gz 15:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:14:42 INFO - Installing collected packages: blobuploader, requests, docopt 15:14:42 INFO - Running setup.py install for blobuploader 15:14:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:14:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:14:43 INFO - Running setup.py install for requests 15:14:43 INFO - Running setup.py install for docopt 15:14:43 INFO - Successfully installed blobuploader requests docopt 15:14:43 INFO - Cleaning up... 15:14:43 INFO - Return code: 0 15:14:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:43 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 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:14:43 INFO - Using env: {'DISPLAY': ':0', 15:14:43 INFO - 'HOME': '/home/cltbld', 15:14:43 INFO - 'LANG': 'en_US.UTF-8', 15:14:43 INFO - 'LANGUAGE': 'en_US:en', 15:14:43 INFO - 'LOGNAME': 'cltbld', 15:14:43 INFO - 'MAIL': '/var/mail/cltbld', 15:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:43 INFO - 'MOZ_NO_REMOTE': '1', 15:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:43 INFO - 'NO_EM_RESTART': '1', 15:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:43 INFO - 'PWD': '/builds/slave/test', 15:14:43 INFO - 'SHELL': '/bin/bash', 15:14:43 INFO - 'SHLVL': '1', 15:14:43 INFO - 'TERM': 'linux', 15:14:43 INFO - 'TMOUT': '86400', 15:14:43 INFO - 'USER': 'cltbld', 15:14:43 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:43 INFO - '_': '/tools/buildbot/bin/python'} 15:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:43 INFO - Running setup.py (path:/tmp/pip-nYErSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:43 INFO - Running setup.py (path:/tmp/pip-ME5kfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:43 INFO - Running setup.py (path:/tmp/pip-oms87I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:43 INFO - Running setup.py (path:/tmp/pip-PxnXgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:43 INFO - Running setup.py (path:/tmp/pip-uNGY0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:44 INFO - Running setup.py (path:/tmp/pip-u_4GxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:44 INFO - Running setup.py (path:/tmp/pip-LEHr9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:44 INFO - Running setup.py (path:/tmp/pip-bQQLVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:44 INFO - Running setup.py (path:/tmp/pip-6FDHGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:44 INFO - Running setup.py (path:/tmp/pip-uqlRGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:44 INFO - Running setup.py (path:/tmp/pip-rHrxfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:44 INFO - Running setup.py (path:/tmp/pip-ENuobh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:44 INFO - Running setup.py (path:/tmp/pip-wbcu_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:44 INFO - Running setup.py (path:/tmp/pip-A3nUDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:44 INFO - Running setup.py (path:/tmp/pip-uK3S1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:44 INFO - Running setup.py (path:/tmp/pip-9bB_BR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:44 INFO - Running setup.py (path:/tmp/pip-170Jlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:14:44 INFO - Running setup.py install for manifestparser 15:14:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for mozcrash 15:14:45 INFO - Running setup.py install for mozdebug 15:14:45 INFO - Running setup.py install for mozdevice 15:14:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for mozfile 15:14:45 INFO - Running setup.py install for mozhttpd 15:14:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for mozinfo 15:14:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for mozInstall 15:14:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for mozleak 15:14:45 INFO - Running setup.py install for mozlog 15:14:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for moznetwork 15:14:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Running setup.py install for mozprocess 15:14:46 INFO - Running setup.py install for mozprofile 15:14:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Running setup.py install for mozrunner 15:14:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Running setup.py install for mozscreenshot 15:14:46 INFO - Running setup.py install for moztest 15:14:46 INFO - Running setup.py install for mozversion 15:14:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:14:46 INFO - Cleaning up... 15:14:46 INFO - Return code: 0 15:14:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:46 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 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:14:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:14:46 INFO - Using env: {'DISPLAY': ':0', 15:14:46 INFO - 'HOME': '/home/cltbld', 15:14:46 INFO - 'LANG': 'en_US.UTF-8', 15:14:46 INFO - 'LANGUAGE': 'en_US:en', 15:14:46 INFO - 'LOGNAME': 'cltbld', 15:14:46 INFO - 'MAIL': '/var/mail/cltbld', 15:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:46 INFO - 'MOZ_NO_REMOTE': '1', 15:14:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:46 INFO - 'NO_EM_RESTART': '1', 15:14:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:46 INFO - 'PWD': '/builds/slave/test', 15:14:46 INFO - 'SHELL': '/bin/bash', 15:14:46 INFO - 'SHLVL': '1', 15:14:46 INFO - 'TERM': 'linux', 15:14:46 INFO - 'TMOUT': '86400', 15:14:46 INFO - 'USER': 'cltbld', 15:14:46 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:46 INFO - '_': '/tools/buildbot/bin/python'} 15:14:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:46 INFO - Running setup.py (path:/tmp/pip-zgUkeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:46 INFO - Running setup.py (path:/tmp/pip-9MISWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:47 INFO - Running setup.py (path:/tmp/pip-q14r_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:47 INFO - Running setup.py (path:/tmp/pip-lKw9zA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:47 INFO - Running setup.py (path:/tmp/pip-yd4y22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:47 INFO - Running setup.py (path:/tmp/pip-VLvsPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:47 INFO - Running setup.py (path:/tmp/pip-8jQI11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:47 INFO - Running setup.py (path:/tmp/pip-jq5EzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:47 INFO - Running setup.py (path:/tmp/pip-Xyn1Lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:47 INFO - Running setup.py (path:/tmp/pip-mxUnCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:47 INFO - Running setup.py (path:/tmp/pip-wSzhh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:47 INFO - Running setup.py (path:/tmp/pip-Zq7Nip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:47 INFO - Running setup.py (path:/tmp/pip-5bZyhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:47 INFO - Running setup.py (path:/tmp/pip-mQ7e8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:47 INFO - Running setup.py (path:/tmp/pip-je6iu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:47 INFO - Running setup.py (path:/tmp/pip-gsq5a2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:48 INFO - Running setup.py (path:/tmp/pip-cqd1dv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:14:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:49 INFO - Downloading blessings-1.6.tar.gz 15:14:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:14:49 INFO - Installing collected packages: blessings 15:14:49 INFO - Running setup.py install for blessings 15:14:49 INFO - Successfully installed blessings 15:14:49 INFO - Cleaning up... 15:14:49 INFO - Return code: 0 15:14:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:14:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:49 INFO - Reading from file tmpfile_stdout 15:14:49 INFO - Current package versions: 15:14:49 INFO - argparse == 1.2.1 15:14:49 INFO - blessings == 1.6 15:14:49 INFO - blobuploader == 1.2.4 15:14:49 INFO - docopt == 0.6.1 15:14:49 INFO - functools32 == 3.2.3-2 15:14:49 INFO - jsonschema == 2.5.1 15:14:49 INFO - manifestparser == 1.1 15:14:49 INFO - mozInstall == 1.13 15:14:49 INFO - mozcrash == 1.0 15:14:49 INFO - mozdebug == 0.1 15:14:49 INFO - mozdevice == 0.51 15:14:49 INFO - mozfile == 1.2 15:14:49 INFO - mozhttpd == 0.7 15:14:49 INFO - mozinfo == 0.10 15:14:49 INFO - mozleak == 0.1 15:14:49 INFO - mozlog == 3.5 15:14:49 INFO - moznetwork == 0.27 15:14:49 INFO - mozprocess == 0.25 15:14:49 INFO - mozprofile == 0.28 15:14:49 INFO - mozrunner == 6.13 15:14:49 INFO - mozscreenshot == 0.1 15:14:49 INFO - mozsystemmonitor == 0.3 15:14:49 INFO - moztest == 0.8 15:14:49 INFO - mozversion == 1.4 15:14:49 INFO - psutil == 3.1.1 15:14:49 INFO - requests == 1.2.3 15:14:49 INFO - wsgiref == 0.1.2 15:14:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:49 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 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:14:49 INFO - Using env: {'DISPLAY': ':0', 15:14:49 INFO - 'HOME': '/home/cltbld', 15:14:49 INFO - 'LANG': 'en_US.UTF-8', 15:14:49 INFO - 'LANGUAGE': 'en_US:en', 15:14:49 INFO - 'LOGNAME': 'cltbld', 15:14:49 INFO - 'MAIL': '/var/mail/cltbld', 15:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:49 INFO - 'MOZ_NO_REMOTE': '1', 15:14:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:49 INFO - 'NO_EM_RESTART': '1', 15:14:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:49 INFO - 'PWD': '/builds/slave/test', 15:14:49 INFO - 'SHELL': '/bin/bash', 15:14:49 INFO - 'SHLVL': '1', 15:14:49 INFO - 'TERM': 'linux', 15:14:49 INFO - 'TMOUT': '86400', 15:14:49 INFO - 'USER': 'cltbld', 15:14:49 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:49 INFO - '_': '/tools/buildbot/bin/python'} 15:14:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:14:49 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:14:51 INFO - Installing collected packages: simplejson 15:14:51 INFO - Running setup.py install for simplejson 15:14:51 INFO - building 'simplejson._speedups' extension 15:14:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:14:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:14:52 INFO - Successfully installed simplejson 15:14:52 INFO - Cleaning up... 15:14:52 INFO - Return code: 0 15:14:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1965a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:14:52 INFO - Using env: {'DISPLAY': ':0', 15:14:52 INFO - 'HOME': '/home/cltbld', 15:14:52 INFO - 'LANG': 'en_US.UTF-8', 15:14:52 INFO - 'LANGUAGE': 'en_US:en', 15:14:52 INFO - 'LOGNAME': 'cltbld', 15:14:52 INFO - 'MAIL': '/var/mail/cltbld', 15:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:52 INFO - 'MOZ_NO_REMOTE': '1', 15:14:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:52 INFO - 'NO_EM_RESTART': '1', 15:14:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:52 INFO - 'PWD': '/builds/slave/test', 15:14:52 INFO - 'SHELL': '/bin/bash', 15:14:52 INFO - 'SHLVL': '1', 15:14:52 INFO - 'TERM': 'linux', 15:14:52 INFO - 'TMOUT': '86400', 15:14:52 INFO - 'USER': 'cltbld', 15:14:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:52 INFO - '_': '/tools/buildbot/bin/python'} 15:14:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:14:52 INFO - Cleaning up... 15:14:52 INFO - Return code: 0 15:14:52 INFO - Running post-action listener: _resource_record_post_action 15:14:52 INFO - Running post-action listener: _start_resource_monitoring 15:14:52 INFO - Starting resource monitoring. 15:14:52 INFO - [mozharness: 2017-09-13 22:14:52.359099Z] Finished create-virtualenv step (success) 15:14:52 INFO - [mozharness: 2017-09-13 22:14:52.359569Z] Running install step. 15:14:52 INFO - Running pre-action listener: _resource_record_pre_action 15:14:52 INFO - Running main action method: install 15:14:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:52 INFO - Reading from file tmpfile_stdout 15:14:52 INFO - Detecting whether we're running mozinstall >=1.0... 15:14:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:14:52 INFO - Reading from file tmpfile_stdout 15:14:52 INFO - Output received: 15:14:52 INFO - Usage: mozinstall [options] installer 15:14:52 INFO - Options: 15:14:52 INFO - -h, --help show this help message and exit 15:14:52 INFO - -d DEST, --destination=DEST 15:14:52 INFO - Directory to install application into. [default: 15:14:52 INFO - "/builds/slave/test"] 15:14:52 INFO - --app=APP Application being installed. [default: firefox] 15:14:52 INFO - mkdir: /builds/slave/test/build/application 15:14:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:15:10 INFO - Reading from file tmpfile_stdout 15:15:10 INFO - Output received: 15:15:10 INFO - /builds/slave/test/build/application/firefox/firefox 15:15:10 INFO - Running post-action listener: _resource_record_post_action 15:15:10 INFO - [mozharness: 2017-09-13 22:15:10.474341Z] Finished install step (success) 15:15:10 INFO - [mozharness: 2017-09-13 22:15:10.474593Z] Running setup-mitmproxy step. 15:15:10 INFO - Running pre-action listener: _resource_record_pre_action 15:15:10 INFO - Running main action method: setup_mitmproxy 15:15:10 INFO - Skipping: mitmproxy is not required 15:15:10 INFO - Running post-action listener: _resource_record_post_action 15:15:10 INFO - [mozharness: 2017-09-13 22:15:10.475713Z] Finished setup-mitmproxy step (success) 15:15:10 INFO - [mozharness: 2017-09-13 22:15:10.475933Z] Running run-tests step. 15:15:10 INFO - Running pre-action listener: _resource_record_pre_action 15:15:10 INFO - Running pre-action listener: _set_gcov_prefix 15:15:10 INFO - Running main action method: run_tests 15:15:10 WARNING - Try message not found. 15:15:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:15:10 INFO - Python 2.7.3 15:15:10 INFO - Return code: 0 15:15:10 INFO - grabbing minidump binary from tooltool 15:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:10 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 0x1969090>, '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 0x1969d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1963f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:15:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:15:10 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:15:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:15:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:15:10 INFO - Return code: 0 15:15:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:15:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:15:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:15:10 INFO - ENV: RUST_BACKTRACE is now full 15:15:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:15:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:15:10 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/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:15:10 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/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:15:10 INFO - Using env: {'DISPLAY': ':0', 15:15:10 INFO - 'HOME': '/home/cltbld', 15:15:10 INFO - 'LANG': 'en_US.UTF-8', 15:15:10 INFO - 'LANGUAGE': 'en_US:en', 15:15:10 INFO - 'LOGNAME': 'cltbld', 15:15:10 INFO - 'MAIL': '/var/mail/cltbld', 15:15:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:15:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:15:10 INFO - 'MOZ_ACCELERATED': '1', 15:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:10 INFO - 'MOZ_NO_REMOTE': '1', 15:15:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:15:10 INFO - 'MOZ_WEBRENDER': '1', 15:15:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:10 INFO - 'NO_EM_RESTART': '1', 15:15:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:10 INFO - 'PWD': '/builds/slave/test', 15:15:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:15:10 INFO - 'RUST_BACKTRACE': 'full', 15:15:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:15:10 INFO - 'SHELL': '/bin/bash', 15:15:10 INFO - 'SHLVL': '1', 15:15:10 INFO - 'TERM': 'linux', 15:15:10 INFO - 'TMOUT': '86400', 15:15:10 INFO - 'USER': 'cltbld', 15:15:10 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:10 INFO - '_': '/tools/buildbot/bin/python'} 15:15:10 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/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:15:10 INFO - mozversion application_buildid: 20170913212101 15:15:10 INFO - mozversion application_changeset: b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d 15:15:10 INFO - mozversion application_display_name: Nightly 15:15:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:15:10 INFO - mozversion application_name: Firefox 15:15:10 INFO - mozversion application_remotingname: firefox 15:15:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:15:10 INFO - mozversion application_vendor: Mozilla 15:15:10 INFO - mozversion application_version: 57.0a1 15:15:10 INFO - mozversion platform_buildid: 20170913212101 15:15:10 INFO - mozversion platform_changeset: b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d 15:15:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:15:10 INFO - mozversion platform_version: 57.0a1 15:15:10 INFO - using testdate: 1505340910 15:15:10 INFO - actual date: 1505340910 15:15:10 INFO - starting webserver on 'localhost:34470' 15:15:10 INFO - SUITE-START | Running 2 tests 15:15:10 INFO - TEST-START | dromaeo_css 15:15:10 INFO - Initialising browser for dromaeo_css test... 15:15:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34470/getInfo.html -profile /tmp/tmpfI1UDV/profile 15:15:10 INFO - TEST-INFO | started process 7308 (/builds/slave/test/build/application/firefox/firefox http://localhost:34470/getInfo.html) 15:15:17 INFO - TEST-INFO | 7308: exit 0 15:15:17 INFO - Browser initialized. 15:15:17 INFO - Running cycle 1/1 for dromaeo_css test... 15:15:17 INFO - Using env: {'DISPLAY': ':0', 15:15:17 INFO - 'HOME': '/home/cltbld', 15:15:17 INFO - 'JSGC_DISABLE_POISONING': '1', 15:15:17 INFO - 'LANG': 'en_US.UTF-8', 15:15:17 INFO - 'LANGUAGE': 'en_US:en', 15:15:17 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:15:17 INFO - 'LOGNAME': 'cltbld', 15:15:17 INFO - 'MAIL': '/var/mail/cltbld', 15:15:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:15:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:15:17 INFO - 'MOZ_ACCELERATED': '1', 15:15:17 INFO - 'MOZ_CRASHREPORTER': '1', 15:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:17 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:15:17 INFO - 'MOZ_NO_REMOTE': '1', 15:15:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:15:17 INFO - 'MOZ_WEBRENDER': '1', 15:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:17 INFO - 'NO_EM_RESTART': '1', 15:15:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:17 INFO - 'PWD': '/builds/slave/test', 15:15:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:15:17 INFO - 'RUST_BACKTRACE': 'full', 15:15:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:15:17 INFO - 'SHELL': '/bin/bash', 15:15:17 INFO - 'SHLVL': '1', 15:15:17 INFO - 'TERM': 'linux', 15:15:17 INFO - 'TMOUT': '86400', 15:15:17 INFO - 'USER': 'cltbld', 15:15:17 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:17 INFO - '_': '/tools/buildbot/bin/python'} 15:15:17 INFO - TEST-INFO | started process 7547 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfI1UDV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:15:18 INFO - PID 7547 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:15:18 INFO - PID 7547 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:15:19 INFO - PID 7547 | 15:15:19 INFO - PID 7547 | (/builds/slave/test/build/application/firefox/firefox:7606): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:15:19 INFO - PID 7547 | 15:15:29 INFO - PID 7547 | RSS: Main: 219410432 15:15:29 INFO - PID 7547 | 15:18:27 INFO - PID 7547 | Cycle 1(1): loaded http://localhost:34470/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34470/tests/dromaeo/cssquery-ext.html) 15:18:28 INFO - PID 7547 | RSS: Main: 228335616 15:18:28 INFO - PID 7547 | 15:21:30 INFO - PID 7547 | Cycle 1(1): loaded http://localhost:34470/tests/dromaeo/cssquery-ext.html (next: http://localhost:34470/tests/dromaeo/cssquery-jquery.html) 15:21:30 INFO - PID 7547 | RSS: Main: 225783808 15:21:30 INFO - PID 7547 | 15:24:33 INFO - PID 7547 | Cycle 1(1): loaded http://localhost:34470/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34470/tests/dromaeo/cssquery-mootools.html) 15:24:33 INFO - PID 7547 | RSS: Main: 227467264 15:24:33 INFO - PID 7547 | 15:27:35 INFO - PID 7547 | Cycle 1(1): loaded http://localhost:34470/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34470/tests/dromaeo/cssquery-prototype.html) 15:27:36 INFO - PID 7547 | RSS: Main: 225841152 15:27:36 INFO - PID 7547 | 15:30:39 INFO - PID 7547 | Cycle 1(1): loaded http://localhost:34470/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34470/tests/dromaeo/cssquery-yui.html) 15:30:40 INFO - PID 7547 | RSS: Main: 226574336 15:30:40 INFO - PID 7547 | 15:33:42 INFO - PID 7547 | Cycle 1(1): loaded http://localhost:34470/tests/dromaeo/cssquery-yui.html (next: http://localhost:34470/tests/dromaeo/cssquery-dojo.html) 15:33:42 INFO - PID 7547 | RSS: Main: 232640512 15:33:42 INFO - PID 7547 | 15:33:42 INFO - PID 7547 | __start_tp_report 15:33:42 INFO - PID 7547 | _x_x_mozilla_page_load 15:33:42 INFO - PID 7547 | _x_x_mozilla_page_load_details 15:33:42 INFO - PID 7547 | |i|pagename|runs| 15:33:42 INFO - PID 7547 | |0;dojo.html;3100.364425292815;3114.034649258729;3138.623365196176;3145.8112513249193;3156.3687262547496;6128.203333566641;6256.84357891053;6273.811785646432;6303.810885673425;6334.429901308881;6207.068939659053;6267.592606480587;6372.553921225516;6393.296737358887;6423.518236132285;5576.302962129734;5770.538356931446;5792.913106303409;5825.708714564276;5867.4719275265215;5751.252337196159;5791.420857914217;5837.036888913324;5841.3282472515775;5873.500752436048;5635.718214089279;5654.74553645087;5716.028275193206;5767.163761254616;5866.823995280149;5696.572757043238;5749.137629355589;5755.712214389306;5784.739686714066;5801.622894511843;5609;5670.801521946753;5706.572007099484;5714.200011998323;5751.884962300773;6167.845803854894;6196.163517925068;6239.407256310688;6262.561620686508;6309.495240380805;6466.515011374118;6607.702653380609;6622.238442579117;6746.730130794763;6788.966055169742;6821.761238356683;6844.452443804484;6888.621125838077;6912.481563882729;6922.2731613180395;6083.878322433571;6089.451949324538;6179.81460556184;6286.308506064329;6364.872702545923;6872.278410766898;6970.546914450544;7004.15950085993;7009.894851577231;7014.158301003912;6625.933740662628;6639.170103737033;6656.334366563402;6668.766593169215;6707.094542236833;6438.034294855809;6534.183227096613;6549.672516374162;6699.598024118569;6766.69549870257;6545.803625891231;6556.541042127101;6580.8354791130605;6600.240972288152;6635.5023373247195;6648.601083934979;6657;6667.433268172163;6671.699773510107;6679.298673639295;5102.311187989574;5303.840884773463;5311.49540793624;5314.73426328682;5317.468253174651;4516.887077823015;4598.057398233354;4606.101810147056;4613.1004454131125;4644;5635.830925072254;5651.237082993825;5709.8287051388525;5731.484166425042;5741;6546.443552298013;6657.267700552935;6668.566543174775;6749.628770417576;6769.086173366631;5724;5761.366249712528;5784.537237021028;5789.363170051291;5793.333766616809;5776.942230577641;5787.189793428924;5866;5868.354481007086;5870.236869207061;6221.346758590283;6498.155239818793;6507.3167317431935;6605.86788264232;6610.0745895574655;6641.86716265672;6655.767048153291;6822.283660215804;6823.215330237086;6824.283450237852;6480.513961453004;6495.382938620824;6502.902456463252;6510.3489651034515;6570.638614876228;6584.901226481511;6629.801105966829;6662.433693136138;6675.265720770614;6683.030960510796;6583.901241481477;6736.4947628929;6767.25560188369;6801.693923773343;6873.243943166348;6442.871142577216;6463.7737679181;6468.8706225874275;6474.708638111202;6477.643729594917;6478.2550006749825;6481.254655714653;6512.6092434454085;6566.737330506918;6623.933760662332;6461.483081353575;6489.123968264224;6533.41199292047;6543.770968016097;6553.639549824806;6566.277709451861;6570.5729127606555;6595.769148079794;6626.701798419179;6718;6240.781572644935;6324.114623952558;6353.3011368748475;6433.967830160819;6458.773942911976 15:33:42 INFO - PID 7547 | |1;ext.html;852.0882655558555;852.9701460448886;868.079835374503;900.113388312512;903.8734577159199;38640.80679596605;40362.39456408153;40976.38535421964;41421;41581.376279355834;41024.38463423051;41525.16949661013;41601;41691.16617667645;41816.16367672645;7756.612169391522;7898.170692077307;7898.565578893159;7905.209479052078;7924.603769811515;7790.143084260727;7930.246626570467;7934.761957141295;7941;7950.522968621901;7883.921160788404;7917.406194535404;7921;7924.445288829785;7925.9603701981405;7799.454038217299;7828.943092682472;7829.921700783005;7840.568768717718;7852.371810255166;7642.235776422369;7746.302832745024;7752.302292793675;7760.9223907761325;7764.262395072459;37202.06994825124;37489.43765843515;37648.247035059416;37706.24587508262;37787.4331885022;3542.079059444537;3584.534010578634;3587.8744243951337;3593.0298819318637;3595.0652830264057;30960.225994350098;30986;31371.529427058613;31466.528002079987;31501.52747708786;4884.7069175849565;5108.6168537359845;5119.846404607868;5123.7438128093445;5235.502627250406;171302;173233.1338343313;173515;173629;174065;45474.31788523175;45679.77160114178;45774.77112614416;45776;45921.31118033232;47722.5227747725;47776.28335574969;54843;56289.71855140698;56654;16133.354665813471;16277.267522961494;16313.10277934725;16354.446327598858;16379.83620163783;13352.265625390502;13469.326533673473;13478.730425391437;13505.932470337586;13517.932410337886;15203.771943420857;15243.39944305854;15393.615159621098;15486.767698484531;15557.533274001798;5970.283565972024;6019.187409699634;6074.878502429927;6092.765527958115;6207.099970504251;992;1011.630754774502;1018.7096677446892;1018.9133923616575;1028.4960369418932;23228.187013454448;23297.883510582677;23366.532669346518;23438.531229375658;23888.761112388653;1059.4014381874217;1115.358668765463;1117.5306371324064;1119.5577746789972;1123.8482804821244;12191.817122743167;12313.815292770616;12328;12366.876331236752;12413.627591172279;222925;234371;235120;236114;236518;45714;52175.73912130415;52976;53086.20370694443;53349;1438.6043837944649;1443.595793177912;1452.8765054970233;1459.241194578813;1471.727730369885;26948.191554253404;27206.591901121104;27326.04358847431;27928;28042.85978570094;34853.477197841545;34856;34960.475592865616;35038.64961350354;35048.64951350454;198352;199851;200151;200232;200278;32581.18547036343;33423.49864752079;33628.831855840566;33795.4930676045;34000.10999065107;42803.35794963016;42862.35706464343;42893;43182.784086080625;43378.56621433745;30843;31274;31333.216669583442;31528.531861494757;31775.68224317727;30576.694233058275;30666.540001900437;30927.0721878344;30992.380152397276;31858.36283274275;35560.82219588885;35569.46645800367;35815.64184358123;35901.461478078374;35940.64059359478;30080.398392032475;30081.548776768803;30201.848990754905;30569.847150764104;31099.378012440076;22838.77161228433;23289.76710232876;23573.17493887706;23574.88212558926;23601.763982359957 15:33:42 INFO - PID 7547 | |2;jquery.html;567.2993852592045;567.424064574457;573.180352096502;575.9769609215632;577.535084257173;21957.121715131394;22674.206402775886;22681.886590567065;22688;22752.65871011936;22039.889800551016;22239;22528.43678908024;22557.323280301574;25103.623445648285;6546.116274302955;6590.242122155965;6626.105475760783;6648.301928297532;6672.599644021374;6554.213494380681;6632.403083722464;6647.567908085983;6655.733770649168;6664.733410663592;6195.411435913581;6436.420722135007;6479.416852483276;6492.188476440445;6495.902561461582;6395.20059992501;6398.07227951949;6413.583117097373;6423.197100362455;6438.517111216677;5842.444967728102;5977.701114944279;5986.790462333797;6001.579889407743;6027.065804800263;28178.57732133999;28876.133715988555;28887.855560722062;28941.565876511657;28952.565711514344;1975.7530308711412;1978.8317992970615;1993.481694759373;1995.4412764425983;1997.6004799040104;17866.106694665214;17930.19314130867;17996.730049049274;18352.724709129372;18382;2408.1932552594903;2436.8424998125925;2462.150558057467;2464.2853572463964;2466.4203912080825;115499;120526;121489.39255303756;126804;132689.99999999904;14371.925157226504;14582.781258281135;14691.55925322242;14751.262436878113;14891.404343826125;21907.904604770018;22594.774052259596;22692.546149076927;22719.09123635069;22741.658875116886;9684.370515916444;9691.466969316756;9753.878303995132;9788.168005719594;9946.950265248628;8850.380473366931;8918;8922.420042697333;8957.552122393854;9061.637534498546;8819.839139410886;8846.77883052916;8929.821403571892;9038;9228.723138305862;2533.40464990727;2600.4929038837245;2602.8568428736603;2630.9607704956816;2684.2886635041727;932.2588542109039;937.437537477508;974.171953839231;976.6581696406201;977.9364341317792;18898.90550547266;19000.619987600447;19266.422007339803;19295.614087718448;19629.21483140687;1037.180627304421;1037.4812593703148;1040.0379648824808;1050.5167622893555;1051.0960573906434;10536.420496872746;10695.786084278427;10710.732231694114;10749.516271767789;10963.83554246687;429298;447238;448354;457320;475156;103692;104316;105463;107407.46296268469;108765.45617271862;1022.5245260953692;1124.5614210457927;1145.8166693329026;1203.6629743671785;1205.168433780671;19744.901275493532;19850;20199.293024744064;20231.393058208276;20275.493112671713;50520.49479505158;51170.23244651408;51456.74271628618;52511;53533.73233133809;63738.68130659317;64580.677096614214;65159.67420162869;65811.67094164499;66829.33170668426;13898.096623719426;14135.222562759147;14145.929270353583;14214.857851421353;14303.141811491343;20197.09113089885;22314.553708925407;22405.32784016482;22447.77552224522;22686.432839179153;17493;17675;17795.46613601594;17920.193591288164;18068.186931587847;14199;14457.638559036108;14667.706645867236;15421.151836649093;15913.204339782733;17520.737188942432;18557.721634175767;18597.81402185961;18810.24759009679;18835.71746423832;14013.159210447406;14044.719105618035;14044.78932816029;14078.084924479876;14092.506762263272;17008.74486882673;17635.55911102233;17887;17925.37261195852;18019 15:33:42 INFO - PID 7547 | |3;mootools.html;1309.423853504458;1361.2649169448496;1367.699106196637;1382.6958069224768;1387.2370196391983;14809.555713328604;15257.61855953599;15423.151726655029;15448.227588620566;15463.458778942739;15018.324175412105;15070;15228.314725837336;15350.309236084375;15375.692486150298;2633.5259653262406;2726.8500232487204;2733;2735.658042744657;2736.6852811926583;2620.4759048190344;2731.412746259554;2740.6707746742813;2756.4349308391734;2758.2001219646277;2515.76100270474;2587.5601147804823;2658.9468210635873;2667.653205083346;2680.437108207279;2726.005008172013;2748.2167582239067;2763.3644261819695;2765.764909982654;2770.3074231442138;2647.3249321423104;2666.733326667337;2672.9575465568446;2677.0764086390163;2687.784977201819;14613.853861461355;14903.850961490354;14905.552833415015;14923.179225142612;14943.775843362359;7679.731209407644;8000.799980000488;8038.356931445471;8038.8794168087525;8043.798905027421;4862.610991120702;4978.925316120261;5067.062593420229;5076.289319495274;5085.898282034375;1810.9185086671132;1833.688272993581;1873.9156737946826;1881.0782716468968;1904.0860387014172;25630.23109306724;25861;25884;25926.351841203934;26219.868900655376;6583.967080164569;6746.730130794811;6757.290484499155;6884.552504087218;6929.688164032631;16814.15929203535;16834.831651683573;16894.831051689325;16930;16942.98342099478;7834.216578342121;7969.760907172794;7988.440809143421;7993.360531157494;8022.598870056474;7142.964285178541;7247;7323.487355885037;7396;7434.033575635132;5221.347331583552;5234.581233501311;5248.895022099613;5292.655977361459;5481.616286859881;1445.4001589340517;1486.048928685642;1513.6669932614807;1516.7952326436011;1522.9771553426708;883.1036497954581;885.5041176940805;900.0234745301291;903.0382642485731;914.5244472874201;8305.709300174465;8367.916320836714;8375.623096960651;8375.664973401117;8431.451955622864;938.4603852784676;987.3384832162468;997.3616885193481;999.0708640963828;1011.8077565262577;4572.497025327211;4638.281066434708;4679.789409476582;4708.011317623337;5188.740562971836;20617;21459.67810482844;21614.567708646053;21685.132594696035;21817.67273490899;20510.692339614918;20654.793452065587;21084.789152108588;21214.893925530272;21410.89294553517;11136.554537818558;11303.717407064889;11339.659810205707;11420.37187954654;11577.479013444416;8286.4199506034;8408.201220884006;8456.704015359433;8475.872861907199;8549.358798090292;17780.55548611295;17927.462176134737;18151.90924045371;18239.270429182685;18463.446096617125;17606.093789022765;18658.253669853053;18686.626267474847;19375;19437.31969381065;10808.837867432152;10966.451677416257;10976.451177440937;10987.505562249558;11008.284461509977;12945.870541294467;13656.317184140951;13676.726465470436;13776.10455320401;13797.310134493035;11259.380734059705;11293.43532823372;11314.321140731583;11369.147313951322;11426.314421134759;10891.782164356828;10920.18098642589;11072.446377681244;11078.667639970814;11199.21605487608;9678.661246856327;9843.064908833649;9861;9864.112229899343;9870.457124858201;7269.636518174176;7319.121705395387;7327.780166595011;7346;7362.889556656762;7368.889466658112;7388.6675099621725;7618.428617853572;7678.61606919663;7686.654100565376 15:33:42 INFO - PID 7547 | |4;prototype.html;481.76875099952;499.47305592599815;504.722402678527;518.7535920437792;520.2066848056716;6185.505159587234;6197.481617003835;6270.561060725751;6287.591306565081;6306.779262725806;6099.542534309922;6149.938500614992;6159.5380346474085;6160.630362178261;6188.721507532161;5089.974550127254;5095.363079615048;5128.153854613985;5147.897042059157;5160.993606246783;5153.845384638467;5178.482151784829;5197.180986654678;5200.557952574036;5202.635815492917;4817.735024573647;4892.339534162896;4942.110420124376;4985.326980857574;5095.9745201273945;4906.730129842857;4943.209086546171;4958.429780575226;4991.925121123186;4994.850154495371;4283.721558098745;4416.514183439819;4433.497536945809;4464.55354464551;4490.136593313771;5966.373530779249;5997.010493268605;6041.5468839837185;6054.4550990410635;6076.240469941257;5515.145152501329;5608.831735047955;5643.322801263875;5682.744276507526;5691.971540142315;6351.872962540723;6495.7076931538195;6519.467925037612;6533.627938132998;6549.2468366137555;4644.907101857978;4744;4793.832215872462;4834.58905992991;4854.17479028566;6681.432078273353;6719.563228390139;6775.62734049627;6789.18529776425;6800.183977922582;4889.706617602955;4944.431390390079;4963.280324353021;4975.278584605285;4980.800767969313;4999.775010124553;5013;5013.423456302499;5016.2224855147515;5047.697138171721;5576.693281869536;5592.384837667853;5644.322681278273;5648.322201335866;5669.971650141723;6504.284528701839;6512.674366281762;6554.770583029666;6563.310852360529;6631.966840165768;1742.0941110622405;1752.7546143539914;1753.9123043847756;1755.043501291799;1773.8226177382157;1126.1441304608556;1189.8691143974156;1197.6107764976418;1204.2052245517916;1227.098082909061;5385.434529374359;5504.587155963239;5558.44415558441;5559.694216818114;5603.971980140154;6534.803955881332;6582.539222254396;6586.209654841449;6587.604743715392;6686.732530698815;5567.777288908398;5569.637973531708;5588.497035266764;5590.748416321194;5616.438356164433;3442.7590068695454;3461.532693086401;3479.5476588043393;3519.9648003519637;3525.202146905097;6291.653959032297;6291.779787707408;6360.236771587347;6413.819301437534;6438.645874476949;6673.4661227101715;6739;6749.426298764562;6761.8647627047185;6810.727570897207;6294.1188233648245;6322.304546499881;6342.968285158545;6401.647909364937;6497.545171838021;6044.697765111639;6557.540972132096;6559.311272316338;6611.504137189634;6670.266270710121;6555.80332590023;6607.834804129935;6614.636195009129;6627.4698024158915;6636.800895973129;6261.499080073492;6278.663136334079;6455.935440645721;6462.773802916875;6469.800267908933;4861.149298872754;4878.414590249193;4913.852584422473;4927.334809800775;4937.308776771327;6420.967895160495;6500.902486462613;6514.1531600891585;6527.31206868118;6600.735970561123;6343.9382200784;6367.172267605181;6392.776252831129;6475.899097153401;6562.639054852029;6341.524385671001;6418.807435776934;6442.677866106769;6462.386073323027;6490.766754316854;6354.968225158845;6439.746088868858;6450.129232553545;6475.449586784987;6481.384268494486;6447.871042579029;6526.379993900572;6531.216254049545;6532.289100023437;6569.24453687832;6248;6269.247690277013;6308.905366419415;6322.683865806599;6364.904526432015 15:33:42 INFO - PID 7547 | |5;yui.html;916.8166366726653;918.2103391083668;921.8755468011816;933.2067742418941;947.8246524392986;19301.420957371283;20040.899795501;20136.49658758532;20167.798322016795;20294.89852550737;17784.46646600604;17857.46427607174;18024.54938626535;18077.548061298476;18078.638427231446;960.4189465373453;961.6441916490908;962.4658314635375;965.2374624047029;969.4571040217465;957.2342126298968;966.111177716499;967.52591230297;968.389914353956;969.8933117357085;947.209080417852;975.6146322202726;994.3834822410116;1013.8732658417698;1016.8881423043497;973.7808992976594;978.1587834462357;982.0670363154995;988.2538683294067;988.4859872866141;715.0989752911369;738.04791818554;744.0513345484496;744.4379493482386;745.1133151549639;19369.12838922252;19638;19657.705134422995;19672.311469098655;19697.21211151552;19882.20471181151;19949.50126246841;20043.699344509845;20523.692144617842;20568.897155514278;15353.462628807938;15639.92180039104;15657.765133523006;15709.371625135094;15942.28259728315;3699.2416554606243;3772.6038765929457;3781.5651200112065;3781.9054523636855;3788.7916164610933;103239;130424;142906;145405;147644;16199;16586.83413165853;16721.665566688596;16741.6651666966;16813.747793783103;30454;31062.68937310643;31434.21414464657;31561.684383155876;31691.524627130613;8788.8681669775;9041.63833446668;9059.456432614064;9099.68151114721;9113.635454581743;4479.70881892676;4551.749653769009;4588.587027167571;4592.288195329729;4601.562851529099;5432.972835135799;5566.860828479321;5568;5574.860628484239;5600.551955843523;1048.5596049659162;1065.9147268218524;1066.7919755647574;1076.9192310576739;1077.6766969908997;682.8566001139816;690.9516333856683;705.4814711187273;720.1646090534954;722.8807246804323;10881.401522916158;11296.71758206035;11688.240264382957;12101.213421127775;12546.372681365896;673.276228054843;678.6911955060435;695.1935754524727;700.9964950175216;705.0869124483805;5337.039332920034;5457.945420545744;5473.17902314656;5521;5527.751251193706;126358.73641263654;126389.36805315915;130358.74103147308;131900;132194;111668;112483;113203.43398282956;113260.43369783099;118736;98389.01610983942;98394;100298.498507507;101282.9290965528;104186.47906760417;10042.397456152363;10132.138768204784;10146.086852183338;10221.488925553842;10409.11522520564;44266.77866610646;45812.31281530713;45987.54012459832;46511.76744116393;46519.767401162775;56101.15848262361;58059.709701451226;58273.417265828495;58458;58970;15811.446599368966;15845.683086338438;15906.9204653976;15984.840151598335;16205.75691364654;17205.63991971418;17594.472165835046;17674.469765907044;17806.287748489915;17814.821851781315;15311.61720956985;15315.693686126438;15393.230338482806;15399.461018864286;15551.76672349893;16427.34290628409;16522.83477165261;16709.582260443585;16728.330866765195;16731.247093881047;15232.923835380752;15623.14072726011;15718.921405392899;15780.60548486297;15962.361505539648;11746.236494627821;11863.169578129447;11882.940585297018;11969.64091077269;12253.938730306649;11175.273607215506;11293.491792869505;11383.373914434454;11594.536218551164;11854.822177667515 15:33:42 INFO - PID 7547 | __end_tp_report 15:33:42 INFO - PID 7547 | __start_cc_report 15:33:42 INFO - PID 7547 | _x_x_mozilla_cycle_collect,385 15:33:42 INFO - PID 7547 | __end_cc_report 15:33:42 INFO - PID 7547 | __startTimestamp1505342022852__endTimestamp 15:33:42 INFO - PID 7547 | 15:33:42 INFO - PID 7547 | ------- Summary: start ------- 15:33:42 INFO - PID 7547 | Number of tests: 6 15:33:42 INFO - PID 7547 | 15:33:42 INFO - PID 7547 | [#0] dojo.html Cycles:165 Average:6164.02 Median:6458.77 stddev:746.32 (11.6%) stddev-sans-first:708.85 15:33:42 INFO - PID 7547 | Values: 3100.4 3114.0 3138.6 3145.8 3156.4 6128.2 6256.8 6273.8 6303.8 6334.4 6207.1 6267.6 6372.6 6393.3 6423.5 5576.3 5770.5 5792.9 5825.7 5867.5 5751.3 5791.4 5837.0 5841.3 5873.5 5635.7 5654.7 5716.0 5767.2 5866.8 5696.6 5749.1 5755.7 5784.7 5801.6 5609.0 5670.8 5706.6 5714.2 5751.9 6167.8 6196.2 6239.4 6262.6 6309.5 6466.5 6607.7 6622.2 6746.7 6789.0 6821.8 6844.5 6888.6 6912.5 6922.3 6083.9 6089.5 6179.8 6286.3 6364.9 6872.3 6970.5 7004.2 7009.9 7014.2 6625.9 6639.2 6656.3 6668.8 6707.1 6438.0 6534.2 6549.7 6699.6 6766.7 6545.8 6556.5 6580.8 6600.2 6635.5 6648.6 6657.0 6667.4 6671.7 6679.3 5102.3 5303.8 5311.5 5314.7 5317.5 4516.9 4598.1 4606.1 4613.1 4644.0 5635.8 5651.2 5709.8 5731.5 5741.0 6546.4 6657.3 6668.6 6749.6 6769.1 5724.0 5761.4 5784.5 5789.4 5793.3 5776.9 5787.2 5866.0 5868.4 5870.2 6221.3 6498.2 6507.3 6605.9 6610.1 6641.9 6655.8 6822.3 6823.2 6824.3 6480.5 6495.4 6502.9 6510.3 6570.6 6584.9 6629.8 6662.4 6675.3 6683.0 6583.9 6736.5 6767.3 6801.7 6873.2 6442.9 6463.8 6468.9 6474.7 6477.6 6478.3 6481.3 6512.6 6566.7 6623.9 6461.5 6489.1 6533.4 6543.8 6553.6 6566.3 6570.6 6595.8 6626.7 6718.0 6240.8 6324.1 6353.3 6434.0 6458.8 15:33:42 INFO - PID 7547 | 15:33:42 INFO - PID 7547 | [#1] ext.html Cycles:180 Average:37137.05 Median:34908.24 stddev:52660.57 (150.9%) stddev-sans-first:52737.82 15:33:42 INFO - PID 7547 | Values: 852.1 853.0 868.1 900.1 903.9 38640.8 40362.4 40976.4 41421.0 41581.4 41024.4 41525.2 41601.0 41691.2 41816.2 7756.6 7898.2 7898.6 7905.2 7924.6 7790.1 7930.2 7934.8 7941.0 7950.5 7883.9 7917.4 7921.0 7924.4 7926.0 7799.5 7828.9 7829.9 7840.6 7852.4 7642.2 7746.3 7752.3 7760.9 7764.3 37202.1 37489.4 37648.2 37706.2 37787.4 3542.1 3584.5 3587.9 3593.0 3595.1 30960.2 30986.0 31371.5 31466.5 31501.5 4884.7 5108.6 5119.8 5123.7 5235.5 171302.0 173233.1 173515.0 173629.0 174065.0 45474.3 45679.8 45774.8 45776.0 45921.3 47722.5 47776.3 54843.0 56289.7 56654.0 16133.4 16277.3 16313.1 16354.4 16379.8 13352.3 13469.3 13478.7 13505.9 13517.9 15203.8 15243.4 15393.6 15486.8 15557.5 5970.3 6019.2 6074.9 6092.8 6207.1 992.0 1011.6 1018.7 1018.9 1028.5 23228.2 23297.9 23366.5 23438.5 23888.8 1059.4 1115.4 1117.5 1119.6 1123.8 12191.8 12313.8 12328.0 12366.9 12413.6 222925.0 234371.0 235120.0 236114.0 236518.0 45714.0 52175.7 52976.0 53086.2 53349.0 1438.6 1443.6 1452.9 1459.2 1471.7 26948.2 27206.6 27326.0 27928.0 28042.9 34853.5 34856.0 34960.5 35038.6 35048.6 198352.0 199851.0 200151.0 200232.0 200278.0 32581.2 33423.5 33628.8 33795.5 34000.1 42803.4 42862.4 42893.0 43182.8 43378.6 30843.0 31274.0 31333.2 31528.5 31775.7 30576.7 30666.5 30927.1 30992.4 31858.4 35560.8 35569.5 35815.6 35901.5 35940.6 30080.4 30081.5 30201.8 30569.8 31099.4 22838.8 23289.8 23573.2 23574.9 23601.8 15:33:42 INFO - PID 7547 | 15:33:42 INFO - PID 7547 | [#2] jquery.html Cycles:180 Average:32415.72 Median:22634.49 stddev:75858.57 (335.1%) stddev-sans-first:76033.68 15:33:42 INFO - PID 7547 | Values: 567.3 567.4 573.2 576.0 577.5 21957.1 22674.2 22681.9 22688.0 22752.7 22039.9 22239.0 22528.4 22557.3 25103.6 6546.1 6590.2 6626.1 6648.3 6672.6 6554.2 6632.4 6647.6 6655.7 6664.7 6195.4 6436.4 6479.4 6492.2 6495.9 6395.2 6398.1 6413.6 6423.2 6438.5 5842.4 5977.7 5986.8 6001.6 6027.1 28178.6 28876.1 28887.9 28941.6 28952.6 1975.8 1978.8 1993.5 1995.4 1997.6 17866.1 17930.2 17996.7 18352.7 18382.0 2408.2 2436.8 2462.2 2464.3 2466.4 115499.0 120526.0 121489.4 126804.0 132690.0 14371.9 14582.8 14691.6 14751.3 14891.4 21907.9 22594.8 22692.5 22719.1 22741.7 9684.4 9691.5 9753.9 9788.2 9947.0 8850.4 8918.0 8922.4 8957.6 9061.6 8819.8 8846.8 8929.8 9038.0 9228.7 2533.4 2600.5 2602.9 2631.0 2684.3 932.3 937.4 974.2 976.7 977.9 18898.9 19000.6 19266.4 19295.6 19629.2 1037.2 1037.5 1040.0 1050.5 1051.1 10536.4 10695.8 10710.7 10749.5 10963.8 429298.0 447238.0 448354.0 457320.0 475156.0 103692.0 104316.0 105463.0 107407.5 108765.5 1022.5 1124.6 1145.8 1203.7 1205.2 19744.9 19850.0 20199.3 20231.4 20275.5 50520.5 51170.2 51456.7 52511.0 53533.7 63738.7 64580.7 65159.7 65811.7 66829.3 13898.1 14135.2 14145.9 14214.9 14303.1 20197.1 22314.6 22405.3 22447.8 22686.4 17493.0 17675.0 17795.5 17920.2 18068.2 14199.0 14457.6 14667.7 15421.2 15913.2 17520.7 18557.7 18597.8 18810.2 18835.7 14013.2 14044.7 14044.8 14078.1 14092.5 17008.7 17635.6 17887.0 17925.4 18019.0 15:33:42 INFO - PID 7547 | 15:33:42 INFO - PID 7547 | [#3] mootools.html Cycles:180 Average:9231.15 Median:2653.14 stddev:6545.14 (246.7%) stddev-sans-first:6536.44 15:33:42 INFO - PID 7547 | Values: 1309.4 1361.3 1367.7 1382.7 1387.2 14809.6 15257.6 15423.2 15448.2 15463.5 15018.3 15070.0 15228.3 15350.3 15375.7 2633.5 2726.9 2733.0 2735.7 2736.7 2620.5 2731.4 2740.7 2756.4 2758.2 2515.8 2587.6 2658.9 2667.7 2680.4 2726.0 2748.2 2763.4 2765.8 2770.3 2647.3 2666.7 2673.0 2677.1 2687.8 14613.9 14903.9 14905.6 14923.2 14943.8 7679.7 8000.8 8038.4 8038.9 8043.8 4862.6 4978.9 5067.1 5076.3 5085.9 1810.9 1833.7 1873.9 1881.1 1904.1 25630.2 25861.0 25884.0 25926.4 26219.9 6584.0 6746.7 6757.3 6884.6 6929.7 16814.2 16834.8 16894.8 16930.0 16943.0 7834.2 7969.8 7988.4 7993.4 8022.6 7143.0 7247.0 7323.5 7396.0 7434.0 5221.3 5234.6 5248.9 5292.7 5481.6 1445.4 1486.0 1513.7 1516.8 1523.0 883.1 885.5 900.0 903.0 914.5 8305.7 8367.9 8375.6 8375.7 8431.5 938.5 987.3 997.4 999.1 1011.8 4572.5 4638.3 4679.8 4708.0 5188.7 20617.0 21459.7 21614.6 21685.1 21817.7 20510.7 20654.8 21084.8 21214.9 21410.9 11136.6 11303.7 11339.7 11420.4 11577.5 8286.4 8408.2 8456.7 8475.9 8549.4 17780.6 17927.5 18151.9 18239.3 18463.4 17606.1 18658.3 18686.6 19375.0 19437.3 10808.8 10966.5 10976.5 10987.5 11008.3 12945.9 13656.3 13676.7 13776.1 13797.3 11259.4 11293.4 11314.3 11369.1 11426.3 10891.8 10920.2 11072.4 11078.7 11199.2 9678.7 9843.1 9861.0 9864.1 9870.5 7269.6 7319.1 7327.8 7346.0 7362.9 7368.9 7388.7 7618.4 7678.6 7686.7 15:33:42 INFO - PID 7547 | 15:33:42 INFO - PID 7547 | [#4] prototype.html Cycles:180 Average:5444.40 Median:6087.89 stddev:1519.03 (25.0%) stddev-sans-first:1476.92 15:33:42 INFO - PID 7547 | Values: 481.8 499.5 504.7 518.8 520.2 6185.5 6197.5 6270.6 6287.6 6306.8 6099.5 6149.9 6159.5 6160.6 6188.7 5090.0 5095.4 5128.2 5147.9 5161.0 5153.8 5178.5 5197.2 5200.6 5202.6 4817.7 4892.3 4942.1 4985.3 5096.0 4906.7 4943.2 4958.4 4991.9 4994.9 4283.7 4416.5 4433.5 4464.6 4490.1 5966.4 5997.0 6041.5 6054.5 6076.2 5515.1 5608.8 5643.3 5682.7 5692.0 6351.9 6495.7 6519.5 6533.6 6549.2 4644.9 4744.0 4793.8 4834.6 4854.2 6681.4 6719.6 6775.6 6789.2 6800.2 4889.7 4944.4 4963.3 4975.3 4980.8 4999.8 5013.0 5013.4 5016.2 5047.7 5576.7 5592.4 5644.3 5648.3 5670.0 6504.3 6512.7 6554.8 6563.3 6632.0 1742.1 1752.8 1753.9 1755.0 1773.8 1126.1 1189.9 1197.6 1204.2 1227.1 5385.4 5504.6 5558.4 5559.7 5604.0 6534.8 6582.5 6586.2 6587.6 6686.7 5567.8 5569.6 5588.5 5590.7 5616.4 3442.8 3461.5 3479.5 3520.0 3525.2 6291.7 6291.8 6360.2 6413.8 6438.6 6673.5 6739.0 6749.4 6761.9 6810.7 6294.1 6322.3 6343.0 6401.6 6497.5 6044.7 6557.5 6559.3 6611.5 6670.3 6555.8 6607.8 6614.6 6627.5 6636.8 6261.5 6278.7 6455.9 6462.8 6469.8 4861.1 4878.4 4913.9 4927.3 4937.3 6421.0 6500.9 6514.2 6527.3 6600.7 6343.9 6367.2 6392.8 6475.9 6562.6 6341.5 6418.8 6442.7 6462.4 6490.8 6355.0 6439.7 6450.1 6475.4 6481.4 6447.9 6526.4 6531.2 6532.3 6569.2 6248.0 6269.2 6308.9 6322.7 6364.9 15:33:42 INFO - PID 7547 | 15:33:42 INFO - PID 7547 | [#5] yui.html Cycles:180 Average:24603.91 Median:19789.71 stddev:36137.59 (182.6%) stddev-sans-first:36195.20 15:33:42 INFO - PID 7547 | Values: 916.8 918.2 921.9 933.2 947.8 19301.4 20040.9 20136.5 20167.8 20294.9 17784.5 17857.5 18024.5 18077.5 18078.6 960.4 961.6 962.5 965.2 969.5 957.2 966.1 967.5 968.4 969.9 947.2 975.6 994.4 1013.9 1016.9 973.8 978.2 982.1 988.3 988.5 715.1 738.0 744.1 744.4 745.1 19369.1 19638.0 19657.7 19672.3 19697.2 19882.2 19949.5 20043.7 20523.7 20568.9 15353.5 15639.9 15657.8 15709.4 15942.3 3699.2 3772.6 3781.6 3781.9 3788.8 103239.0 130424.0 142906.0 145405.0 147644.0 16199.0 16586.8 16721.7 16741.7 16813.7 30454.0 31062.7 31434.2 31561.7 31691.5 8788.9 9041.6 9059.5 9099.7 9113.6 4479.7 4551.7 4588.6 4592.3 4601.6 5433.0 5566.9 5568.0 5574.9 5600.6 1048.6 1065.9 1066.8 1076.9 1077.7 682.9 691.0 705.5 720.2 722.9 10881.4 11296.7 11688.2 12101.2 12546.4 673.3 678.7 695.2 701.0 705.1 5337.0 5457.9 5473.2 5521.0 5527.8 126358.7 126389.4 130358.7 131900.0 132194.0 111668.0 112483.0 113203.4 113260.4 118736.0 98389.0 98394.0 100298.5 101282.9 104186.5 10042.4 10132.1 10146.1 10221.5 10409.1 44266.8 45812.3 45987.5 46511.8 46519.8 56101.2 58059.7 58273.4 58458.0 58970.0 15811.4 15845.7 15906.9 15984.8 16205.8 17205.6 17594.5 17674.5 17806.3 17814.8 15311.6 15315.7 15393.2 15399.5 15551.8 16427.3 16522.8 16709.6 16728.3 16731.2 15232.9 15623.1 15718.9 15780.6 15962.4 11746.2 11863.2 11882.9 11969.6 12253.9 11175.3 11293.5 11383.4 11594.5 11854.8 15:33:42 INFO - PID 7547 | -------- Summary: end -------- 15:33:42 INFO - PID 7547 | 15:33:43 INFO - PID 7547 | 15:33:43 INFO - PID 7547 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 15:33:43 INFO - PID 7547 | 15:33:43 INFO - TEST-INFO | 7547: exit 0 15:33:43 INFO - TEST-OK | dromaeo_css | took 1112931ms 15:33:43 INFO - TEST-START | kraken 15:33:43 INFO - Initialising browser for kraken test... 15:33:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34470/getInfo.html -profile /tmp/tmpwULSNz/profile 15:33:43 INFO - TEST-INFO | started process 7930 (/builds/slave/test/build/application/firefox/firefox http://localhost:34470/getInfo.html) 15:33:49 INFO - TEST-INFO | 7930: exit 0 15:33:49 INFO - Browser initialized. 15:33:49 INFO - Running cycle 1/1 for kraken test... 15:33:49 INFO - Using env: {'DISPLAY': ':0', 15:33:49 INFO - 'HOME': '/home/cltbld', 15:33:49 INFO - 'JSGC_DISABLE_POISONING': '1', 15:33:49 INFO - 'LANG': 'en_US.UTF-8', 15:33:49 INFO - 'LANGUAGE': 'en_US:en', 15:33:49 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:33:49 INFO - 'LOGNAME': 'cltbld', 15:33:49 INFO - 'MAIL': '/var/mail/cltbld', 15:33:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:33:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:33:49 INFO - 'MOZ_ACCELERATED': '1', 15:33:49 INFO - 'MOZ_CRASHREPORTER': '1', 15:33:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:49 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:33:49 INFO - 'MOZ_NO_REMOTE': '1', 15:33:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:33:49 INFO - 'MOZ_WEBRENDER': '1', 15:33:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:49 INFO - 'NO_EM_RESTART': '1', 15:33:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:49 INFO - 'PWD': '/builds/slave/test', 15:33:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:33:49 INFO - 'RUST_BACKTRACE': 'full', 15:33:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:33:49 INFO - 'SHELL': '/bin/bash', 15:33:49 INFO - 'SHLVL': '1', 15:33:49 INFO - 'TERM': 'linux', 15:33:49 INFO - 'TMOUT': '86400', 15:33:49 INFO - 'USER': 'cltbld', 15:33:49 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737', 15:33:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:49 INFO - '_': '/tools/buildbot/bin/python'} 15:33:49 INFO - TEST-INFO | started process 8170 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwULSNz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:33:50 INFO - PID 8170 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:33:50 INFO - PID 8170 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:33:51 INFO - PID 8170 | 15:33:51 INFO - PID 8170 | (/builds/slave/test/build/application/firefox/firefox:8230): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:33:51 INFO - PID 8170 | 15:34:01 INFO - PID 8170 | RSS: Main: 218730496 15:34:01 INFO - PID 8170 | 15:34:04 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34470/tests/kraken/driver.html?testName=audio-beat-detection) 15:34:05 INFO - PID 8170 | RSS: Main: 224587776 15:34:05 INFO - PID 8170 | 15:34:07 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34470/tests/kraken/driver.html?testName=audio-dft) 15:34:08 INFO - PID 8170 | RSS: Main: 224841728 15:34:08 INFO - PID 8170 | 15:34:11 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34470/tests/kraken/driver.html?testName=audio-fft) 15:34:12 INFO - PID 8170 | RSS: Main: 220725248 15:34:12 INFO - PID 8170 | 15:34:14 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34470/tests/kraken/driver.html?testName=audio-oscillator) 15:34:14 INFO - PID 8170 | RSS: Main: 221360128 15:34:14 INFO - PID 8170 | 15:34:17 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34470/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:34:17 INFO - PID 8170 | RSS: Main: 220696576 15:34:17 INFO - PID 8170 | 15:34:20 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34470/tests/kraken/driver.html?testName=imaging-darkroom) 15:34:21 INFO - PID 8170 | RSS: Main: 223457280 15:34:21 INFO - PID 8170 | 15:34:25 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34470/tests/kraken/driver.html?testName=imaging-desaturate) 15:34:25 INFO - PID 8170 | RSS: Main: 221933568 15:34:25 INFO - PID 8170 | 15:34:29 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34470/tests/kraken/driver.html?testName=json-parse-financial) 15:34:29 INFO - PID 8170 | RSS: Main: 223993856 15:34:29 INFO - PID 8170 | 15:34:31 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34470/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:34:31 INFO - PID 8170 | RSS: Main: 224800768 15:34:31 INFO - PID 8170 | 15:34:33 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34470/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:34:34 INFO - PID 8170 | RSS: Main: 221548544 15:34:34 INFO - PID 8170 | 15:34:36 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34470/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:34:37 INFO - PID 8170 | RSS: Main: 221642752 15:34:37 INFO - PID 8170 | 15:34:39 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34470/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:34:40 INFO - PID 8170 | RSS: Main: 222900224 15:34:40 INFO - PID 8170 | 15:34:43 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34470/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:34:44 INFO - PID 8170 | RSS: Main: 222433280 15:34:44 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | Cycle 1(1): loaded http://localhost:34470/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34470/tests/kraken/driver.html?testName=ai-astar) 15:34:46 INFO - PID 8170 | RSS: Main: 223735808 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | __start_tp_report 15:34:46 INFO - PID 8170 | _x_x_mozilla_page_load 15:34:46 INFO - PID 8170 | _x_x_mozilla_page_load_details 15:34:46 INFO - PID 8170 | |i|pagename|runs| 15:34:46 INFO - PID 8170 | |0;ai-astar;114;113;108;111;109;107;110;107;118;116 15:34:46 INFO - PID 8170 | |1;audio-beat-detection;121;121;125;126;124;118;118;126;122;117 15:34:46 INFO - PID 8170 | |2;audio-dft;163;162;169;172;171;167;165;163;175;164 15:34:46 INFO - PID 8170 | |3;audio-fft;77;79;78;77;75;76;79;78;74;78 15:34:46 INFO - PID 8170 | |4;audio-oscillator;87;82;84;79;80;79;88;87;86;87 15:34:46 INFO - PID 8170 | |5;imaging-gaussian-blur;110;119;113;116;115;115;115;115;116;114 15:34:46 INFO - PID 8170 | |6;imaging-darkroom;150;149;150;154;149;154;149;151;148;153 15:34:46 INFO - PID 8170 | |7;imaging-desaturate;98;95;94;103;102;96;95;103;97;96 15:34:46 INFO - PID 8170 | |8;json-parse-financial;54;53;53;54;55;53;54;55;53;53 15:34:46 INFO - PID 8170 | |9;json-stringify-tinderbox;41;45;39;45;39;39;38;42;39;40 15:34:46 INFO - PID 8170 | |10;stanford-crypto-aes;77;76;80;86;80;84;76;80;83;79 15:34:46 INFO - PID 8170 | |11;stanford-crypto-ccm;120;121;116;119;127;129;120;121;124;116 15:34:46 INFO - PID 8170 | |12;stanford-crypto-pbkdf2;183;172;173;184;170;173;171;178;173;173 15:34:46 INFO - PID 8170 | |13;stanford-crypto-sha256-iterative;84;68;66;65;70;68;66;67;68;67 15:34:46 INFO - PID 8170 | __end_tp_report 15:34:46 INFO - PID 8170 | __start_cc_report 15:34:46 INFO - PID 8170 | _x_x_mozilla_cycle_collect,967 15:34:46 INFO - PID 8170 | __end_cc_report 15:34:46 INFO - PID 8170 | __startTimestamp1505342086302__endTimestamp 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | ------- Summary: start ------- 15:34:46 INFO - PID 8170 | Number of tests: 14 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:3.83 (3.4%) stddev-sans-first:3.94 15:34:46 INFO - PID 8170 | Values: 114.0 113.0 108.0 111.0 109.0 107.0 110.0 107.0 118.0 116.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:123.00 stddev:3.39 (2.8%) stddev-sans-first:3.59 15:34:46 INFO - PID 8170 | Values: 121.0 121.0 125.0 126.0 124.0 118.0 118.0 126.0 122.0 117.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#2] audio-dft Cycles:10 Average:167.10 Median:168.00 stddev:4.46 (2.7%) stddev-sans-first:4.48 15:34:46 INFO - PID 8170 | Values: 163.0 162.0 169.0 172.0 171.0 167.0 165.0 163.0 175.0 164.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#3] audio-fft Cycles:10 Average:77.10 Median:78.00 stddev:1.66 (2.1%) stddev-sans-first:1.76 15:34:46 INFO - PID 8170 | Values: 77.0 79.0 78.0 77.0 75.0 76.0 79.0 78.0 74.0 78.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#4] audio-oscillator Cycles:10 Average:83.90 Median:86.50 stddev:3.60 (4.2%) stddev-sans-first:3.64 15:34:46 INFO - PID 8170 | Values: 87.0 82.0 84.0 79.0 80.0 79.0 88.0 87.0 86.0 87.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#5] imaging-gaussian-blur Cycles:10 Average:114.80 Median:115.00 stddev:2.30 (2.0%) stddev-sans-first:1.66 15:34:46 INFO - PID 8170 | Values: 110.0 119.0 113.0 116.0 115.0 115.0 115.0 115.0 116.0 114.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:150.50 stddev:2.21 (1.5%) stddev-sans-first:2.33 15:34:46 INFO - PID 8170 | Values: 150.0 149.0 150.0 154.0 149.0 154.0 149.0 151.0 148.0 153.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#7] imaging-desaturate Cycles:10 Average:97.90 Median:95.50 stddev:3.48 (3.6%) stddev-sans-first:3.69 15:34:46 INFO - PID 8170 | Values: 98.0 95.0 94.0 103.0 102.0 96.0 95.0 103.0 97.0 96.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 15:34:46 INFO - PID 8170 | Values: 54.0 53.0 53.0 54.0 55.0 53.0 54.0 55.0 53.0 53.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#9] json-stringify-tinderbox Cycles:10 Average:40.70 Median:40.50 stddev:2.54 (6.3%) stddev-sans-first:2.69 15:34:46 INFO - PID 8170 | Values: 41.0 45.0 39.0 45.0 39.0 39.0 38.0 42.0 39.0 40.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:80.00 stddev:3.38 (4.2%) stddev-sans-first:3.40 15:34:46 INFO - PID 8170 | Values: 77.0 76.0 80.0 86.0 80.0 84.0 76.0 80.0 83.0 79.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#11] stanford-crypto-ccm Cycles:10 Average:121.30 Median:121.00 stddev:4.27 (3.5%) stddev-sans-first:4.50 15:34:46 INFO - PID 8170 | Values: 120.0 121.0 116.0 119.0 127.0 129.0 120.0 121.0 124.0 116.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:173.00 stddev:4.94 (2.9%) stddev-sans-first:4.31 15:34:46 INFO - PID 8170 | Values: 183.0 172.0 173.0 184.0 170.0 173.0 171.0 178.0 173.0 173.0 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:68.00 stddev:5.49 (8.1%) stddev-sans-first:1.48 15:34:46 INFO - PID 8170 | Values: 84.0 68.0 66.0 65.0 70.0 68.0 66.0 67.0 68.0 67.0 15:34:46 INFO - PID 8170 | -------- Summary: end -------- 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 15:34:46 INFO - PID 8170 | 15:34:46 INFO - PID 8170 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:34:47 INFO - TEST-INFO | 8170: exit 0 15:34:47 INFO - TEST-OK | kraken | took 63529ms 15:34:47 INFO - SUITE-END | took 1176s 15:34:47 INFO - Completed test suite (00:19:36) 15:34:47 INFO - javascript benchmark 15:34:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8432.727134046561, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3100.364425292815, 3114.034649258729, 3138.623365196176, 3145.8112513249193, 3156.3687262547496, 6128.203333566641, 6256.84357891053, 6273.811785646432, 6303.810885673425, 6334.429901308881, 6207.068939659053, 6267.592606480587, 6372.553921225516, 6393.296737358887, 6423.518236132285, 5576.302962129734, 5770.538356931446, 5792.913106303409, 5825.708714564276, 5867.4719275265215, 5751.252337196159, 5791.420857914217, 5837.036888913324, 5841.3282472515775, 5873.500752436048, 5635.718214089279, 5654.74553645087, 5716.028275193206, 5767.163761254616, 5866.823995280149, 5696.572757043238, 5749.137629355589, 5755.712214389306, 5784.739686714066, 5801.622894511843, 5609.0, 5670.801521946753, 5706.572007099484, 5714.200011998323, 5751.884962300773, 6167.845803854894, 6196.163517925068, 6239.407256310688, 6262.561620686508, 6309.495240380805, 6466.515011374118, 6607.702653380609, 6622.238442579117, 6746.730130794763, 6788.966055169742, 6821.761238356683, 6844.452443804484, 6888.621125838077, 6912.481563882729, 6922.2731613180395, 6083.878322433571, 6089.451949324538, 6179.81460556184, 6286.308506064329, 6364.872702545923, 6872.278410766898, 6970.546914450544, 7004.15950085993, 7009.894851577231, 7014.158301003912, 6625.933740662628, 6639.170103737033, 6656.334366563402, 6668.766593169215, 6707.094542236833, 6438.034294855809, 6534.183227096613, 6549.672516374162, 6699.598024118569, 6766.69549870257, 6545.803625891231, 6556.541042127101, 6580.8354791130605, 6600.240972288152, 6635.5023373247195, 6648.601083934979, 6657.0, 6667.433268172163, 6671.699773510107, 6679.298673639295, 5102.311187989574, 5303.840884773463, 5311.49540793624, 5314.73426328682, 5317.468253174651, 4516.887077823015, 4598.057398233354, 4606.101810147056, 4613.1004454131125, 4644.0, 5635.830925072254, 5651.237082993825, 5709.8287051388525, 5731.484166425042, 5741.0, 6546.443552298013, 6657.267700552935, 6668.566543174775, 6749.628770417576, 6769.086173366631, 5724.0, 5761.366249712528, 5784.537237021028, 5789.363170051291, 5793.333766616809, 5776.942230577641, 5787.189793428924, 5866.0, 5868.354481007086, 5870.236869207061, 6221.346758590283, 6498.155239818793, 6507.3167317431935, 6605.86788264232, 6610.0745895574655, 6641.86716265672, 6655.767048153291, 6822.283660215804, 6823.215330237086, 6824.283450237852, 6480.513961453004, 6495.382938620824, 6502.902456463252, 6510.3489651034515, 6570.638614876228, 6584.901226481511, 6629.801105966829, 6662.433693136138, 6675.265720770614, 6683.030960510796, 6583.901241481477, 6736.4947628929, 6767.25560188369, 6801.693923773343, 6873.243943166348, 6442.871142577216, 6463.7737679181, 6468.8706225874275, 6474.708638111202, 6477.643729594917, 6478.2550006749825, 6481.254655714653, 6512.6092434454085, 6566.737330506918, 6623.933760662332, 6461.483081353575, 6489.123968264224, 6533.41199292047, 6543.770968016097, 6553.639549824806, 6566.277709451861, 6570.5729127606555, 6595.769148079794, 6626.701798419179, 6718.0, 6240.781572644935, 6324.114623952558, 6353.3011368748475, 6433.967830160819, 6458.773942911976], "value": 6106.7172863218975, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [852.0882655558555, 852.9701460448886, 868.079835374503, 900.113388312512, 903.8734577159199, 38640.80679596605, 40362.39456408153, 40976.38535421964, 41421.0, 41581.376279355834, 41024.38463423051, 41525.16949661013, 41601.0, 41691.16617667645, 41816.16367672645, 7756.612169391522, 7898.170692077307, 7898.565578893159, 7905.209479052078, 7924.603769811515, 7790.143084260727, 7930.246626570467, 7934.761957141295, 7941.0, 7950.522968621901, 7883.921160788404, 7917.406194535404, 7921.0, 7924.445288829785, 7925.9603701981405, 7799.454038217299, 7828.943092682472, 7829.921700783005, 7840.568768717718, 7852.371810255166, 7642.235776422369, 7746.302832745024, 7752.302292793675, 7760.9223907761325, 7764.262395072459, 37202.06994825124, 37489.43765843515, 37648.247035059416, 37706.24587508262, 37787.4331885022, 3542.079059444537, 3584.534010578634, 3587.8744243951337, 3593.0298819318637, 3595.0652830264057, 30960.225994350098, 30986.0, 31371.529427058613, 31466.528002079987, 31501.52747708786, 4884.7069175849565, 5108.6168537359845, 5119.846404607868, 5123.7438128093445, 5235.502627250406, 171302.0, 173233.1338343313, 173515.0, 173629.0, 174065.0, 45474.31788523175, 45679.77160114178, 45774.77112614416, 45776.0, 45921.31118033232, 47722.5227747725, 47776.28335574969, 54843.0, 56289.71855140698, 56654.0, 16133.354665813471, 16277.267522961494, 16313.10277934725, 16354.446327598858, 16379.83620163783, 13352.265625390502, 13469.326533673473, 13478.730425391437, 13505.932470337586, 13517.932410337886, 15203.771943420857, 15243.39944305854, 15393.615159621098, 15486.767698484531, 15557.533274001798, 5970.283565972024, 6019.187409699634, 6074.878502429927, 6092.765527958115, 6207.099970504251, 992.0, 1011.630754774502, 1018.7096677446892, 1018.9133923616575, 1028.4960369418932, 23228.187013454448, 23297.883510582677, 23366.532669346518, 23438.531229375658, 23888.761112388653, 1059.4014381874217, 1115.358668765463, 1117.5306371324064, 1119.5577746789972, 1123.8482804821244, 12191.817122743167, 12313.815292770616, 12328.0, 12366.876331236752, 12413.627591172279, 222925.0, 234371.0, 235120.0, 236114.0, 236518.0, 45714.0, 52175.73912130415, 52976.0, 53086.20370694443, 53349.0, 1438.6043837944649, 1443.595793177912, 1452.8765054970233, 1459.241194578813, 1471.727730369885, 26948.191554253404, 27206.591901121104, 27326.04358847431, 27928.0, 28042.85978570094, 34853.477197841545, 34856.0, 34960.475592865616, 35038.64961350354, 35048.64951350454, 198352.0, 199851.0, 200151.0, 200232.0, 200278.0, 32581.18547036343, 33423.49864752079, 33628.831855840566, 33795.4930676045, 34000.10999065107, 42803.35794963016, 42862.35706464343, 42893.0, 43182.784086080625, 43378.56621433745, 30843.0, 31274.0, 31333.216669583442, 31528.531861494757, 31775.68224317727, 30576.694233058275, 30666.540001900437, 30927.0721878344, 30992.380152397276, 31858.36283274275, 35560.82219588885, 35569.46645800367, 35815.64184358123, 35901.461478078374, 35940.64059359478, 30080.398392032475, 30081.548776768803, 30201.848990754905, 30569.847150764104, 31099.378012440076, 22838.77161228433, 23289.76710232876, 23573.17493887706, 23574.88212558926, 23601.763982359957], "value": 17217.19949642456, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.2993852592045, 567.424064574457, 573.180352096502, 575.9769609215632, 577.535084257173, 21957.121715131394, 22674.206402775886, 22681.886590567065, 22688.0, 22752.65871011936, 22039.889800551016, 22239.0, 22528.43678908024, 22557.323280301574, 25103.623445648285, 6546.116274302955, 6590.242122155965, 6626.105475760783, 6648.301928297532, 6672.599644021374, 6554.213494380681, 6632.403083722464, 6647.567908085983, 6655.733770649168, 6664.733410663592, 6195.411435913581, 6436.420722135007, 6479.416852483276, 6492.188476440445, 6495.902561461582, 6395.20059992501, 6398.07227951949, 6413.583117097373, 6423.197100362455, 6438.517111216677, 5842.444967728102, 5977.701114944279, 5986.790462333797, 6001.579889407743, 6027.065804800263, 28178.57732133999, 28876.133715988555, 28887.855560722062, 28941.565876511657, 28952.565711514344, 1975.7530308711412, 1978.8317992970615, 1993.481694759373, 1995.4412764425983, 1997.6004799040104, 17866.106694665214, 17930.19314130867, 17996.730049049274, 18352.724709129372, 18382.0, 2408.1932552594903, 2436.8424998125925, 2462.150558057467, 2464.2853572463964, 2466.4203912080825, 115499.0, 120526.0, 121489.39255303756, 126804.0, 132689.99999999904, 14371.925157226504, 14582.781258281135, 14691.55925322242, 14751.262436878113, 14891.404343826125, 21907.904604770018, 22594.774052259596, 22692.546149076927, 22719.09123635069, 22741.658875116886, 9684.370515916444, 9691.466969316756, 9753.878303995132, 9788.168005719594, 9946.950265248628, 8850.380473366931, 8918.0, 8922.420042697333, 8957.552122393854, 9061.637534498546, 8819.839139410886, 8846.77883052916, 8929.821403571892, 9038.0, 9228.723138305862, 2533.40464990727, 2600.4929038837245, 2602.8568428736603, 2630.9607704956816, 2684.2886635041727, 932.2588542109039, 937.437537477508, 974.171953839231, 976.6581696406201, 977.9364341317792, 18898.90550547266, 19000.619987600447, 19266.422007339803, 19295.614087718448, 19629.21483140687, 1037.180627304421, 1037.4812593703148, 1040.0379648824808, 1050.5167622893555, 1051.0960573906434, 10536.420496872746, 10695.786084278427, 10710.732231694114, 10749.516271767789, 10963.83554246687, 429298.0, 447238.0, 448354.0, 457320.0, 475156.0, 103692.0, 104316.0, 105463.0, 107407.46296268469, 108765.45617271862, 1022.5245260953692, 1124.5614210457927, 1145.8166693329026, 1203.6629743671785, 1205.168433780671, 19744.901275493532, 19850.0, 20199.293024744064, 20231.393058208276, 20275.493112671713, 50520.49479505158, 51170.23244651408, 51456.74271628618, 52511.0, 53533.73233133809, 63738.68130659317, 64580.677096614214, 65159.67420162869, 65811.67094164499, 66829.33170668426, 13898.096623719426, 14135.222562759147, 14145.929270353583, 14214.857851421353, 14303.141811491343, 20197.09113089885, 22314.553708925407, 22405.32784016482, 22447.77552224522, 22686.432839179153, 17493.0, 17675.0, 17795.46613601594, 17920.193591288164, 18068.186931587847, 14199.0, 14457.638559036108, 14667.706645867236, 15421.151836649093, 15913.204339782733, 17520.737188942432, 18557.721634175767, 18597.81402185961, 18810.24759009679, 18835.71746423832, 14013.159210447406, 14044.719105618035, 14044.78932816029, 14078.084924479876, 14092.506762263272, 17008.74486882673, 17635.55911102233, 17887.0, 17925.37261195852, 18019.0], "value": 11579.41833020762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.423853504458, 1361.2649169448496, 1367.699106196637, 1382.6958069224768, 1387.2370196391983, 14809.555713328604, 15257.61855953599, 15423.151726655029, 15448.227588620566, 15463.458778942739, 15018.324175412105, 15070.0, 15228.314725837336, 15350.309236084375, 15375.692486150298, 2633.5259653262406, 2726.8500232487204, 2733.0, 2735.658042744657, 2736.6852811926583, 2620.4759048190344, 2731.412746259554, 2740.6707746742813, 2756.4349308391734, 2758.2001219646277, 2515.76100270474, 2587.5601147804823, 2658.9468210635873, 2667.653205083346, 2680.437108207279, 2726.005008172013, 2748.2167582239067, 2763.3644261819695, 2765.764909982654, 2770.3074231442138, 2647.3249321423104, 2666.733326667337, 2672.9575465568446, 2677.0764086390163, 2687.784977201819, 14613.853861461355, 14903.850961490354, 14905.552833415015, 14923.179225142612, 14943.775843362359, 7679.731209407644, 8000.799980000488, 8038.356931445471, 8038.8794168087525, 8043.798905027421, 4862.610991120702, 4978.925316120261, 5067.062593420229, 5076.289319495274, 5085.898282034375, 1810.9185086671132, 1833.688272993581, 1873.9156737946826, 1881.0782716468968, 1904.0860387014172, 25630.23109306724, 25861.0, 25884.0, 25926.351841203934, 26219.868900655376, 6583.967080164569, 6746.730130794811, 6757.290484499155, 6884.552504087218, 6929.688164032631, 16814.15929203535, 16834.831651683573, 16894.831051689325, 16930.0, 16942.98342099478, 7834.216578342121, 7969.760907172794, 7988.440809143421, 7993.360531157494, 8022.598870056474, 7142.964285178541, 7247.0, 7323.487355885037, 7396.0, 7434.033575635132, 5221.347331583552, 5234.581233501311, 5248.895022099613, 5292.655977361459, 5481.616286859881, 1445.4001589340517, 1486.048928685642, 1513.6669932614807, 1516.7952326436011, 1522.9771553426708, 883.1036497954581, 885.5041176940805, 900.0234745301291, 903.0382642485731, 914.5244472874201, 8305.709300174465, 8367.916320836714, 8375.623096960651, 8375.664973401117, 8431.451955622864, 938.4603852784676, 987.3384832162468, 997.3616885193481, 999.0708640963828, 1011.8077565262577, 4572.497025327211, 4638.281066434708, 4679.789409476582, 4708.011317623337, 5188.740562971836, 20617.0, 21459.67810482844, 21614.567708646053, 21685.132594696035, 21817.67273490899, 20510.692339614918, 20654.793452065587, 21084.789152108588, 21214.893925530272, 21410.89294553517, 11136.554537818558, 11303.717407064889, 11339.659810205707, 11420.37187954654, 11577.479013444416, 8286.4199506034, 8408.201220884006, 8456.704015359433, 8475.872861907199, 8549.358798090292, 17780.55548611295, 17927.462176134737, 18151.90924045371, 18239.270429182685, 18463.446096617125, 17606.093789022765, 18658.253669853053, 18686.626267474847, 19375.0, 19437.31969381065, 10808.837867432152, 10966.451677416257, 10976.451177440937, 10987.505562249558, 11008.284461509977, 12945.870541294467, 13656.317184140951, 13676.726465470436, 13776.10455320401, 13797.310134493035, 11259.380734059705, 11293.43532823372, 11314.321140731583, 11369.147313951322, 11426.314421134759, 10891.782164356828, 10920.18098642589, 11072.446377681244, 11078.667639970814, 11199.21605487608, 9678.661246856327, 9843.064908833649, 9861.0, 9864.112229899343, 9870.457124858201, 7269.636518174176, 7319.121705395387, 7327.780166595011, 7346.0, 7362.889556656762, 7368.889466658112, 7388.6675099621725, 7618.428617853572, 7678.61606919663, 7686.654100565376], "value": 6619.863277968938, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.76875099952, 499.47305592599815, 504.722402678527, 518.7535920437792, 520.2066848056716, 6185.505159587234, 6197.481617003835, 6270.561060725751, 6287.591306565081, 6306.779262725806, 6099.542534309922, 6149.938500614992, 6159.5380346474085, 6160.630362178261, 6188.721507532161, 5089.974550127254, 5095.363079615048, 5128.153854613985, 5147.897042059157, 5160.993606246783, 5153.845384638467, 5178.482151784829, 5197.180986654678, 5200.557952574036, 5202.635815492917, 4817.735024573647, 4892.339534162896, 4942.110420124376, 4985.326980857574, 5095.9745201273945, 4906.730129842857, 4943.209086546171, 4958.429780575226, 4991.925121123186, 4994.850154495371, 4283.721558098745, 4416.514183439819, 4433.497536945809, 4464.55354464551, 4490.136593313771, 5966.373530779249, 5997.010493268605, 6041.5468839837185, 6054.4550990410635, 6076.240469941257, 5515.145152501329, 5608.831735047955, 5643.322801263875, 5682.744276507526, 5691.971540142315, 6351.872962540723, 6495.7076931538195, 6519.467925037612, 6533.627938132998, 6549.2468366137555, 4644.907101857978, 4744.0, 4793.832215872462, 4834.58905992991, 4854.17479028566, 6681.432078273353, 6719.563228390139, 6775.62734049627, 6789.18529776425, 6800.183977922582, 4889.706617602955, 4944.431390390079, 4963.280324353021, 4975.278584605285, 4980.800767969313, 4999.775010124553, 5013.0, 5013.423456302499, 5016.2224855147515, 5047.697138171721, 5576.693281869536, 5592.384837667853, 5644.322681278273, 5648.322201335866, 5669.971650141723, 6504.284528701839, 6512.674366281762, 6554.770583029666, 6563.310852360529, 6631.966840165768, 1742.0941110622405, 1752.7546143539914, 1753.9123043847756, 1755.043501291799, 1773.8226177382157, 1126.1441304608556, 1189.8691143974156, 1197.6107764976418, 1204.2052245517916, 1227.098082909061, 5385.434529374359, 5504.587155963239, 5558.44415558441, 5559.694216818114, 5603.971980140154, 6534.803955881332, 6582.539222254396, 6586.209654841449, 6587.604743715392, 6686.732530698815, 5567.777288908398, 5569.637973531708, 5588.497035266764, 5590.748416321194, 5616.438356164433, 3442.7590068695454, 3461.532693086401, 3479.5476588043393, 3519.9648003519637, 3525.202146905097, 6291.653959032297, 6291.779787707408, 6360.236771587347, 6413.819301437534, 6438.645874476949, 6673.4661227101715, 6739.0, 6749.426298764562, 6761.8647627047185, 6810.727570897207, 6294.1188233648245, 6322.304546499881, 6342.968285158545, 6401.647909364937, 6497.545171838021, 6044.697765111639, 6557.540972132096, 6559.311272316338, 6611.504137189634, 6670.266270710121, 6555.80332590023, 6607.834804129935, 6614.636195009129, 6627.4698024158915, 6636.800895973129, 6261.499080073492, 6278.663136334079, 6455.935440645721, 6462.773802916875, 6469.800267908933, 4861.149298872754, 4878.414590249193, 4913.852584422473, 4927.334809800775, 4937.308776771327, 6420.967895160495, 6500.902486462613, 6514.1531600891585, 6527.31206868118, 6600.735970561123, 6343.9382200784, 6367.172267605181, 6392.776252831129, 6475.899097153401, 6562.639054852029, 6341.524385671001, 6418.807435776934, 6442.677866106769, 6462.386073323027, 6490.766754316854, 6354.968225158845, 6439.746088868858, 6450.129232553545, 6475.449586784987, 6481.384268494486, 6447.871042579029, 6526.379993900572, 6531.216254049545, 6532.289100023437, 6569.24453687832, 6248.0, 6269.247690277013, 6308.905366419415, 6322.683865806599, 6364.904526432015], "value": 4995.736155894841, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [916.8166366726653, 918.2103391083668, 921.8755468011816, 933.2067742418941, 947.8246524392986, 19301.420957371283, 20040.899795501, 20136.49658758532, 20167.798322016795, 20294.89852550737, 17784.46646600604, 17857.46427607174, 18024.54938626535, 18077.548061298476, 18078.638427231446, 960.4189465373453, 961.6441916490908, 962.4658314635375, 965.2374624047029, 969.4571040217465, 957.2342126298968, 966.111177716499, 967.52591230297, 968.389914353956, 969.8933117357085, 947.209080417852, 975.6146322202726, 994.3834822410116, 1013.8732658417698, 1016.8881423043497, 973.7808992976594, 978.1587834462357, 982.0670363154995, 988.2538683294067, 988.4859872866141, 715.0989752911369, 738.04791818554, 744.0513345484496, 744.4379493482386, 745.1133151549639, 19369.12838922252, 19638.0, 19657.705134422995, 19672.311469098655, 19697.21211151552, 19882.20471181151, 19949.50126246841, 20043.699344509845, 20523.692144617842, 20568.897155514278, 15353.462628807938, 15639.92180039104, 15657.765133523006, 15709.371625135094, 15942.28259728315, 3699.2416554606243, 3772.6038765929457, 3781.5651200112065, 3781.9054523636855, 3788.7916164610933, 103239.0, 130424.0, 142906.0, 145405.0, 147644.0, 16199.0, 16586.83413165853, 16721.665566688596, 16741.6651666966, 16813.747793783103, 30454.0, 31062.68937310643, 31434.21414464657, 31561.684383155876, 31691.524627130613, 8788.8681669775, 9041.63833446668, 9059.456432614064, 9099.68151114721, 9113.635454581743, 4479.70881892676, 4551.749653769009, 4588.587027167571, 4592.288195329729, 4601.562851529099, 5432.972835135799, 5566.860828479321, 5568.0, 5574.860628484239, 5600.551955843523, 1048.5596049659162, 1065.9147268218524, 1066.7919755647574, 1076.9192310576739, 1077.6766969908997, 682.8566001139816, 690.9516333856683, 705.4814711187273, 720.1646090534954, 722.8807246804323, 10881.401522916158, 11296.71758206035, 11688.240264382957, 12101.213421127775, 12546.372681365896, 673.276228054843, 678.6911955060435, 695.1935754524727, 700.9964950175216, 705.0869124483805, 5337.039332920034, 5457.945420545744, 5473.17902314656, 5521.0, 5527.751251193706, 126358.73641263654, 126389.36805315915, 130358.74103147308, 131900.0, 132194.0, 111668.0, 112483.0, 113203.43398282956, 113260.43369783099, 118736.0, 98389.01610983942, 98394.0, 100298.498507507, 101282.9290965528, 104186.47906760417, 10042.397456152363, 10132.138768204784, 10146.086852183338, 10221.488925553842, 10409.11522520564, 44266.77866610646, 45812.31281530713, 45987.54012459832, 46511.76744116393, 46519.767401162775, 56101.15848262361, 58059.709701451226, 58273.417265828495, 58458.0, 58970.0, 15811.446599368966, 15845.683086338438, 15906.9204653976, 15984.840151598335, 16205.75691364654, 17205.63991971418, 17594.472165835046, 17674.469765907044, 17806.287748489915, 17814.821851781315, 15311.61720956985, 15315.693686126438, 15393.230338482806, 15399.461018864286, 15551.76672349893, 16427.34290628409, 16522.83477165261, 16709.582260443585, 16728.330866765195, 16731.247093881047, 15232.923835380752, 15623.14072726011, 15718.921405392899, 15780.60548486297, 15962.361505539648, 11746.236494627821, 11863.169578129447, 11882.940585297018, 11969.64091077269, 12253.938730306649, 11175.273607215506, 11293.491792869505, 11383.373914434454, 11594.536218551164, 11854.822177667515], "value": 8930.519006626082, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1464.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 108.0, 111.0, 109.0, 107.0, 110.0, 107.0, 118.0, 116.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 125.0, 126.0, 124.0, 118.0, 118.0, 126.0, 122.0, 117.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 169.0, 172.0, 171.0, 167.0, 165.0, 163.0, 175.0, 164.0], "value": 167.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 78.0, 77.0, 75.0, 76.0, 79.0, 78.0, 74.0, 78.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 84.0, 79.0, 80.0, 79.0, 88.0, 87.0, 86.0, 87.0], "value": 83.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 119.0, 113.0, 116.0, 115.0, 115.0, 115.0, 115.0, 116.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 154.0, 149.0, 154.0, 149.0, 151.0, 148.0, 153.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 95.0, 94.0, 103.0, 102.0, 96.0, 95.0, 103.0, 97.0, 96.0], "value": 97.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 55.0, 53.0, 54.0, 55.0, 53.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [41.0, 45.0, 39.0, 45.0, 39.0, 39.0, 38.0, 42.0, 39.0, 40.0], "value": 40.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 80.0, 86.0, 80.0, 84.0, 76.0, 80.0, 83.0, 79.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 116.0, 119.0, 127.0, 129.0, 120.0, 121.0, 124.0, 116.0], "value": 121.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 172.0, 173.0, 184.0, 170.0, 173.0, 171.0, 178.0, 173.0, 173.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 66.0, 65.0, 70.0, 68.0, 66.0, 67.0, 68.0, 67.0], "value": 68.9, "unit": "score"}]}]} 15:34:47 INFO - Return code: 0 15:34:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:34:47 INFO - # TBPL SUCCESS # 15:34:47 INFO - Running post-action listener: _package_coverage_data 15:34:47 INFO - Running post-action listener: _resource_record_post_action 15:34:47 INFO - [mozharness: 2017-09-13 22:34:47.554997Z] Finished run-tests step (success) 15:34:47 INFO - Running post-run listener: _resource_record_post_run 15:34:47 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 6270976; Write bytes: 336687104; Read time: 3072; Write time: 250876 15:34:47 INFO - TinderboxPrint: CPU usage
13.3% 15:34:47 INFO - TinderboxPrint: I/O read bytes / time
6,270,976 / 3,072 15:34:47 INFO - TinderboxPrint: I/O write bytes / time
336,687,104 / 250,876 15:34:47 INFO - TinderboxPrint: CPU idle
8,258.1 (86.7%) 15:34:47 INFO - TinderboxPrint: CPU user
1,201.9 (12.6%) 15:34:47 INFO - TinderboxPrint: Swap in / out
0 / 0 15:34:47 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9838592; Read time: 0; Write time: 1156 15:34:47 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:34:47 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 6266880; Write bytes: 326848512; Read time: 3056; Write time: 249720 15:34:48 INFO - Running post-run listener: _upload_blobber_files 15:34:48 INFO - Blob upload gear active. 15:34:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:34:48 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 15:34:48 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'] 15:34:48 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 15:34:48 INFO - (blobuploader) - INFO - Open directory for files ... 15:34:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:34:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:34:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:55 INFO - (blobuploader) - INFO - Done attempting. 15:34:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:34:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:34:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:56 INFO - (blobuploader) - INFO - Done attempting. 15:34:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:34:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:34:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:57 INFO - (blobuploader) - INFO - Done attempting. 15:34:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:34:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:34:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:59 INFO - (blobuploader) - INFO - Done attempting. 15:34:59 INFO - (blobuploader) - INFO - Iteration through files over. 15:34:59 INFO - Return code: 0 15:34:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:34:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:34:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b9a93f88cc2e7158c847b740f59b7aaa8dda38ea67ef636e89c6a4e5cddec25fa599164368d43f7c59244c70e161092e097ac3aa9afc5975add05e7d2992d13", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/381b23e1ea6314b15f79c081615baec0589c1494efe0babb44d49c10b7018bf6677a7319bf85c950de71e61dbbc170f4269f20cc1495ddf24e9aaa9a11ece58b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d287fc73178d9f5ebeab4e1d63b10dbaeea2f2064a01cc31b63c050dd1c39dfae434a014b88bb945aa6e9c124581361effa4787b0153ebb5762fcafb4738ee4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402c6b09700b02a9af020e8c255f1556736a8c7a6e445a662dd763eba55c236dc85c0572d4b7f158091c0f57da75574dfd08bdbab105ead6f7fab0b5ccd26f99"} 15:34:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:34:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:34:59 INFO - Contents: 15:34:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b9a93f88cc2e7158c847b740f59b7aaa8dda38ea67ef636e89c6a4e5cddec25fa599164368d43f7c59244c70e161092e097ac3aa9afc5975add05e7d2992d13", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/381b23e1ea6314b15f79c081615baec0589c1494efe0babb44d49c10b7018bf6677a7319bf85c950de71e61dbbc170f4269f20cc1495ddf24e9aaa9a11ece58b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d287fc73178d9f5ebeab4e1d63b10dbaeea2f2064a01cc31b63c050dd1c39dfae434a014b88bb945aa6e9c124581361effa4787b0153ebb5762fcafb4738ee4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402c6b09700b02a9af020e8c255f1556736a8c7a6e445a662dd763eba55c236dc85c0572d4b7f158091c0f57da75574dfd08bdbab105ead6f7fab0b5ccd26f99"} 15:34:59 INFO - Running post-run listener: copy_logs_to_upload_dir 15:34:59 INFO - Copying logs to upload dir... 15:34:59 INFO - mkdir: /builds/slave/test/build/upload/logs 15:34:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.212880 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-13 15:34:59.229841) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 15:34:59.233659) ========= 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=03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737 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/4b9a93f88cc2e7158c847b740f59b7aaa8dda38ea67ef636e89c6a4e5cddec25fa599164368d43f7c59244c70e161092e097ac3aa9afc5975add05e7d2992d13", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/381b23e1ea6314b15f79c081615baec0589c1494efe0babb44d49c10b7018bf6677a7319bf85c950de71e61dbbc170f4269f20cc1495ddf24e9aaa9a11ece58b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d287fc73178d9f5ebeab4e1d63b10dbaeea2f2064a01cc31b63c050dd1c39dfae434a014b88bb945aa6e9c124581361effa4787b0153ebb5762fcafb4738ee4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402c6b09700b02a9af020e8c255f1556736a8c7a6e445a662dd763eba55c236dc85c0572d4b7f158091c0f57da75574dfd08bdbab105ead6f7fab0b5ccd26f99"} build_url:https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b9a93f88cc2e7158c847b740f59b7aaa8dda38ea67ef636e89c6a4e5cddec25fa599164368d43f7c59244c70e161092e097ac3aa9afc5975add05e7d2992d13", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/381b23e1ea6314b15f79c081615baec0589c1494efe0babb44d49c10b7018bf6677a7319bf85c950de71e61dbbc170f4269f20cc1495ddf24e9aaa9a11ece58b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d287fc73178d9f5ebeab4e1d63b10dbaeea2f2064a01cc31b63c050dd1c39dfae434a014b88bb945aa6e9c124581361effa4787b0153ebb5762fcafb4738ee4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402c6b09700b02a9af020e8c255f1556736a8c7a6e445a662dd763eba55c236dc85c0572d4b7f158091c0f57da75574dfd08bdbab105ead6f7fab0b5ccd26f99"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 15:34:59.265983) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:34:59.266688) ========= 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=03627c79f6bb3d1578c4a402000001ba-1505340852.30587-1548349737 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004567 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:34:59.347229) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 15:34:59.347539) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 15:34:59.347844) ========= ========= Total master_lag: 0.19 =========