builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-054 starttime: 1505340873.22 results: success (0) revision: b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:33.216092) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:33.216612) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:33.216934) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:33.248551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:33.248997) ========= 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=cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 15:14:33-- 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% 228M=0s 2017-09-13 15:14:33 (228 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.201622 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:33.472280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:33.472934) ========= 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=cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015228 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:33.527279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-13 15:14:33.530705) ========= 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=cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 15:14:33,593 truncating revision to first 12 chars 2017-09-13 15:14:33,593 Setting DEBUG logging. 2017-09-13 15:14:33,593 attempt 1/10 2017-09-13 15:14:33,593 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:34,631 unpacking tar archive at: mozilla-central-b9ffb07cf8e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.317752 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-13 15:14:34.864990) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:34.865323) ========= script_repo_revision: b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:34.865711) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:34.865971) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 15:14:34.882966) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-13 15:14:34.883353) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:14:35 INFO - MultiFileLogger online at 20170913 15:14:35 in /builds/slave/test 15:14:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:14:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:14:35 INFO - {'append_to_log': False, 15:14:35 INFO - 'base_work_dir': '/builds/slave/test', 15:14:35 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:14:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:14:35 INFO - 'branch': 'Firefox-Non-PGO', 15:14:35 INFO - 'buildbot_json_path': 'buildprops.json', 15:14:35 INFO - 'code_coverage': False, 15:14:35 INFO - 'config_files': ('talos/linux_config.py',), 15:14:35 INFO - 'default_actions': ('clobber', 15:14:35 INFO - 'read-buildbot-config', 15:14:35 INFO - 'download-and-extract', 15:14:35 INFO - 'populate-webroot', 15:14:35 INFO - 'create-virtualenv', 15:14:35 INFO - 'install', 15:14:35 INFO - 'setup-mitmproxy', 15:14:35 INFO - 'run-tests'), 15:14:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:14:35 INFO - 'disable_ccov_upload': False, 15:14:35 INFO - 'disable_stylo': False, 15:14:35 INFO - 'download_minidump_stackwalk': True, 15:14:35 INFO - 'download_symbols': 'ondemand', 15:14:35 INFO - 'enable_stylo': False, 15:14:35 INFO - 'enable_webrender': False, 15:14:35 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:14:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:14:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:14:35 INFO - 'gecko_profile': False, 15:14:35 INFO - 'gecko_profile_interval': 0, 15:14:35 INFO - 'installer_path': 'installer.exe', 15:14:35 INFO - 'log_level': 'info', 15:14:35 INFO - 'log_name': 'talos', 15:14:35 INFO - 'log_to_console': True, 15:14:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:14:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:14:35 INFO - 'opt_config_files': (), 15:14:35 INFO - 'pip_index': False, 15:14:35 INFO - 'suite': 'dromaeojs-e10s', 15:14:35 INFO - 'system_bits': '32', 15:14:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:14:35 INFO - 'title': 'talos-linux64-ix-054', 15:14:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:14:35 INFO - 'use_talos_json': True, 15:14:35 INFO - 'verify': 'False', 15:14:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:14:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:14:35 INFO - 'work_dir': 'build'} 15:14:35 INFO - [mozharness: 2017-09-13 22:14:35.202197Z] Running clobber step. 15:14:35 INFO - Running pre-action listener: _resource_record_pre_action 15:14:35 INFO - Running main action method: clobber 15:14:35 INFO - rmtree: /builds/slave/test/build 15:14:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:14:36 INFO - Running post-action listener: _resource_record_post_action 15:14:36 INFO - [mozharness: 2017-09-13 22:14:36.176470Z] Finished clobber step (success) 15:14:36 INFO - [mozharness: 2017-09-13 22:14:36.176567Z] Running read-buildbot-config step. 15:14:36 INFO - Running pre-action listener: _resource_record_pre_action 15:14:36 INFO - Running main action method: read_buildbot_config 15:14:36 INFO - Using buildbot properties: 15:14:36 INFO - { 15:14:36 INFO - "project": "", 15:14:36 INFO - "product": "firefox", 15:14:36 INFO - "who": "kwierso@gmail.com", 15:14:36 INFO - "installer_path": "public/build/target.tar.bz2", 15:14:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:14:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:14:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:14:36 INFO - "stage_platform": "linux64", 15:14:36 INFO - "basedir": "/builds/slave/test", 15:14:36 INFO - "buildnumber": 817, 15:14:36 INFO - "platform": "ubuntu64_hw", 15:14:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:14:36 INFO - "slavebuilddir": "test", 15:14:36 INFO - "taskId": "dDfCapPBS_uZzwv6PwDNWQ", 15:14:36 INFO - "branch": "mozilla-central", 15:14:36 INFO - "script_repo_revision": "production", 15:14:36 INFO - "revision": "b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d", 15:14:36 INFO - "slavename": "talos-linux64-ix-054", 15:14:36 INFO - "repo_path": "mozilla-central" 15:14:36 INFO - } 15:14:36 INFO - Finding installer, test and symbols from parent task. 15:14:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dDfCapPBS_uZzwv6PwDNWQ'}, attempt #1 15:14:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dDfCapPBS_uZzwv6PwDNWQ'}, attempt #1 15:14:37 INFO - Task dependencies: QBzmMKkgReqgjM-CLAYvGQ 15:14:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ'}, attempt #1 15:14:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 15:14:37 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.test_packages.json 15:14:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.crashreporter-symbols.zip 15:14:37 INFO - Running post-action listener: _resource_record_post_action 15:14:37 INFO - [mozharness: 2017-09-13 22:14:37.626331Z] Finished read-buildbot-config step (success) 15:14:37 INFO - [mozharness: 2017-09-13 22:14:37.626548Z] Running download-and-extract step. 15:14:37 INFO - Running pre-action listener: _resource_record_pre_action 15:14:37 INFO - Running main action method: download_and_extract 15:14:37 INFO - mkdir: /builds/slave/test/build/tests 15:14:37 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:37 INFO - trying https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.test_packages.json 15:14:37 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:37 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:38 INFO - Downloaded 989 bytes. 15:14:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:14:38 INFO - Using the following test package requirements: 15:14:38 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:14:38 INFO - u'common': [u'target.common.tests.zip'], 15:14:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:14:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:14:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:14:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:14:38 INFO - u'mozbase': [u'target.common.tests.zip'], 15:14:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:14:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:14:38 INFO - u'web-platform': [u'target.common.tests.zip', 15:14:38 INFO - u'target.web-platform.tests.tar.gz'], 15:14:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:14:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:14:38 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:38 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:38 INFO - Fetch https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.common.tests.zip into memory 15:14:39 INFO - Content-Length response header: 54570111 15:14:39 INFO - Bytes received: 54570111 15:14:50 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:50 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:50 INFO - Fetch https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.talos.tests.zip into memory 15:14:51 INFO - Content-Length response header: 14499226 15:14:51 INFO - Bytes received: 14499226 15:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:51 INFO - trying https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 15:14:51 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:51 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:52 INFO - Downloaded 61426431 bytes. 15:14:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 15:14:52 INFO - mkdir: /builds/slave/test/properties 15:14:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:14:52 INFO - Writing to file /builds/slave/test/properties/build_url 15:14:52 INFO - Contents: 15:14:52 INFO - build_url:https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 15:14:52 INFO - Running post-action listener: _resource_record_post_action 15:14:52 INFO - Running post-action listener: find_tests_for_verification 15:14:52 INFO - Running post-action listener: set_extra_try_arguments 15:14:52 INFO - [mozharness: 2017-09-13 22:14:52.498792Z] Finished download-and-extract step (success) 15:14:52 INFO - [mozharness: 2017-09-13 22:14:52.498951Z] Running populate-webroot step. 15:14:52 INFO - Running pre-action listener: _resource_record_pre_action 15:14:52 INFO - Running main action method: populate_webroot 15:14:52 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:14:52 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['tresize', 15:14:52 INFO - 'tcanvasmark']}, 15:14:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:14:52 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['dromaeo_css', 15:14:52 INFO - 'kraken']}, 15:14:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:14:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:52 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:52 INFO - 'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:14:52 INFO - 'tests': ['damp', 'tps']}, 15:14:52 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:52 INFO - 'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['damp', 'tps']}, 15:14:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:14:52 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['dromaeo_dom']}, 15:14:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:14:52 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['basic_compositor_video', 15:14:52 INFO - 'glvideo']}, 15:14:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:14:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:14:52 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:52 INFO - 'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['ts_paint_webext', 15:14:52 INFO - 'tp5o_webext']}, 15:14:52 INFO - 'other-e10s': {'tests': ['a11yr', 15:14:52 INFO - 'ts_paint', 15:14:52 INFO - 'tpaint', 15:14:52 INFO - 'sessionrestore', 15:14:52 INFO - 'sessionrestore_many_windows', 15:14:52 INFO - 'sessionrestore_no_auto_restore', 15:14:52 INFO - 'tabpaint']}, 15:14:52 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['a11yr', 15:14:52 INFO - 'ts_paint', 15:14:52 INFO - 'tpaint', 15:14:52 INFO - 'sessionrestore', 15:14:52 INFO - 'sessionrestore_many_windows', 15:14:52 INFO - 'sessionrestore_no_auto_restore', 15:14:52 INFO - 'tabpaint']}, 15:14:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:14:52 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:14:52 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['bloom_basic_singleton']}, 15:14:52 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['bloom_basic']}, 15:14:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:14:52 INFO - 'tsvgr_opacity', 15:14:52 INFO - 'tart', 15:14:52 INFO - 'tscrollx', 15:14:52 INFO - 'tsvg_static']}, 15:14:52 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['tsvgx', 15:14:52 INFO - 'tsvgr_opacity', 15:14:52 INFO - 'tart', 15:14:52 INFO - 'tscrollx', 15:14:52 INFO - 'tsvg_static']}, 15:14:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:14:52 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:52 INFO - 'talos_options': ['--disable-stylo'], 15:14:52 INFO - 'tests': ['tp5o']}, 15:14:52 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:14:52 INFO - 'talos_options': ['--mitmproxy', 15:14:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:14:52 INFO - '--firstNonBlankPaint'], 15:14:52 INFO - 'tests': ['tp6_google', 15:14:52 INFO - 'tp6_youtube', 15:14:52 INFO - 'tp6_amazon', 15:14:52 INFO - 'tp6_facebook']}, 15:14:52 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:14:52 INFO - 'talos_options': ['--disable-stylo', 15:14:52 INFO - '--mitmproxy', 15:14:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:14:52 INFO - '--firstNonBlankPaint'], 15:14:52 INFO - 'tests': ['tp6_google', 15:14:52 INFO - 'tp6_youtube', 15:14:52 INFO - 'tp6_amazon', 15:14:52 INFO - 'tp6_facebook']}, 15:14:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:14:52 INFO - 'talos_options': ['--stylo-threads=1', 15:14:52 INFO - '--mitmproxy', 15:14:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:14:52 INFO - '--firstNonBlankPaint'], 15:14:52 INFO - 'tests': ['tp6_google', 15:14:52 INFO - 'tp6_youtube', 15:14:52 INFO - 'tp6_amazon', 15:14:52 INFO - 'tp6_facebook']}, 15:14:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:14:52 INFO - 'talos_options': ['--xperf_path', 15:14:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:52 INFO - 'tests': ['tp5n']}, 15:14:52 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:14:52 INFO - 'talos_options': ['--disable-stylo', 15:14:52 INFO - '--xperf_path', 15:14:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:52 INFO - 'tests': ['tp5n']}}} 15:14:52 INFO - Running post-action listener: _resource_record_post_action 15:14:52 INFO - [mozharness: 2017-09-13 22:14:52.514028Z] Finished populate-webroot step (success) 15:14:52 INFO - [mozharness: 2017-09-13 22:14:52.514117Z] Running create-virtualenv step. 15:14:52 INFO - Running pre-action listener: _resource_record_pre_action 15:14:52 INFO - Running main action method: create_virtualenv 15:14:52 INFO - Creating virtualenv /builds/slave/test/build/venv 15:14:52 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:14:52 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:14:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:14:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:14:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:14:55 INFO - Installing distribute.............................................................................................................................................................................................done. 15:14:56 INFO - Installing pip...............done. 15:14:56 INFO - Return code: 0 15:14:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:14:56 INFO - Reading from file tmpfile_stdout 15:14:56 INFO - Output received: 15:14:56 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:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:14:56 INFO - Using env: {'DISPLAY': ':0', 15:14:56 INFO - 'HOME': '/home/cltbld', 15:14:56 INFO - 'LANG': 'en_US.UTF-8', 15:14:56 INFO - 'LANGUAGE': 'en_US:en', 15:14:56 INFO - 'LOGNAME': 'cltbld', 15:14:56 INFO - 'MAIL': '/var/mail/cltbld', 15:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:56 INFO - 'MOZ_NO_REMOTE': '1', 15:14:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:56 INFO - 'NO_EM_RESTART': '1', 15:14:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:56 INFO - 'PWD': '/builds/slave/test', 15:14:56 INFO - 'SHELL': '/bin/bash', 15:14:56 INFO - 'SHLVL': '1', 15:14:56 INFO - 'TERM': 'linux', 15:14:56 INFO - 'TMOUT': '86400', 15:14:56 INFO - 'USER': 'cltbld', 15:14:56 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:56 INFO - '_': '/tools/buildbot/bin/python'} 15:14:57 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:14:57 INFO - Downloading/unpacking pip>=1.5 15:14:57 INFO - Running setup.py egg_info for package pip 15:14:57 INFO - warning: no files found matching 'pip/cacert.pem' 15:14:57 INFO - warning: no files found matching '*.html' under directory 'docs' 15:14:57 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:14:57 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:14:57 INFO - Installing collected packages: pip 15:14:57 INFO - Found existing installation: pip 1.1 15:14:57 INFO - Uninstalling pip: 15:14:57 INFO - Successfully uninstalled pip 15:14:57 INFO - Running setup.py install for pip 15:14:57 INFO - warning: no files found matching 'pip/cacert.pem' 15:14:57 INFO - warning: no files found matching '*.html' under directory 'docs' 15:14:57 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:14:57 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:14:57 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:14:57 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:14:57 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:14:57 INFO - Successfully installed pip 15:14:57 INFO - Cleaning up... 15:14:57 INFO - Return code: 0 15:14:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:14:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:14:57 INFO - Using env: {'DISPLAY': ':0', 15:14:57 INFO - 'HOME': '/home/cltbld', 15:14:57 INFO - 'LANG': 'en_US.UTF-8', 15:14:57 INFO - 'LANGUAGE': 'en_US:en', 15:14:57 INFO - 'LOGNAME': 'cltbld', 15:14:57 INFO - 'MAIL': '/var/mail/cltbld', 15:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:57 INFO - 'MOZ_NO_REMOTE': '1', 15:14:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:57 INFO - 'NO_EM_RESTART': '1', 15:14:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:57 INFO - 'PWD': '/builds/slave/test', 15:14:57 INFO - 'SHELL': '/bin/bash', 15:14:57 INFO - 'SHLVL': '1', 15:14:57 INFO - 'TERM': 'linux', 15:14:57 INFO - 'TMOUT': '86400', 15:14:57 INFO - 'USER': 'cltbld', 15:14:57 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:57 INFO - '_': '/tools/buildbot/bin/python'} 15:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:57 INFO - Downloading/unpacking psutil>=3.1.1 15:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:14:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:14:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:14:59 INFO - Installing collected packages: psutil 15:14:59 INFO - Running setup.py install for psutil 15:14:59 INFO - building 'psutil._psutil_linux' extension 15:14:59 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:15:00 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:15:00 INFO - building 'psutil._psutil_posix' extension 15:15:00 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:15:00 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:15:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:15:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:15:00 INFO - Successfully installed psutil 15:15:00 INFO - Cleaning up... 15:15:00 INFO - Return code: 0 15:15:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:15:00 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:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:00 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:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:00 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 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:15:00 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:15:00 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:15:00 INFO - Using env: {'DISPLAY': ':0', 15:15:00 INFO - 'HOME': '/home/cltbld', 15:15:00 INFO - 'LANG': 'en_US.UTF-8', 15:15:00 INFO - 'LANGUAGE': 'en_US:en', 15:15:00 INFO - 'LOGNAME': 'cltbld', 15:15:00 INFO - 'MAIL': '/var/mail/cltbld', 15:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:00 INFO - 'MOZ_NO_REMOTE': '1', 15:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:00 INFO - 'NO_EM_RESTART': '1', 15:15:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:00 INFO - 'PWD': '/builds/slave/test', 15:15:00 INFO - 'SHELL': '/bin/bash', 15:15:00 INFO - 'SHLVL': '1', 15:15:00 INFO - 'TERM': 'linux', 15:15:00 INFO - 'TMOUT': '86400', 15:15:00 INFO - 'USER': 'cltbld', 15:15:00 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:00 INFO - '_': '/tools/buildbot/bin/python'} 15:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:15:01 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:15:01 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:15:01 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:15:01 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:15:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:15:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:15:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:15:02 INFO - Installing collected packages: mozsystemmonitor 15:15:02 INFO - Running setup.py install for mozsystemmonitor 15:15:02 INFO - Successfully installed mozsystemmonitor 15:15:02 INFO - Cleaning up... 15:15:02 INFO - Return code: 0 15:15:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:02 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 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:15:02 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:15:02 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:15:02 INFO - Using env: {'DISPLAY': ':0', 15:15:02 INFO - 'HOME': '/home/cltbld', 15:15:02 INFO - 'LANG': 'en_US.UTF-8', 15:15:02 INFO - 'LANGUAGE': 'en_US:en', 15:15:02 INFO - 'LOGNAME': 'cltbld', 15:15:02 INFO - 'MAIL': '/var/mail/cltbld', 15:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:02 INFO - 'MOZ_NO_REMOTE': '1', 15:15:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:02 INFO - 'NO_EM_RESTART': '1', 15:15:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:02 INFO - 'PWD': '/builds/slave/test', 15:15:02 INFO - 'SHELL': '/bin/bash', 15:15:02 INFO - 'SHLVL': '1', 15:15:02 INFO - 'TERM': 'linux', 15:15:02 INFO - 'TMOUT': '86400', 15:15:02 INFO - 'USER': 'cltbld', 15:15:02 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:02 INFO - '_': '/tools/buildbot/bin/python'} 15:15:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:02 INFO - Downloading/unpacking jsonschema==2.5.1 15:15:02 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:15:02 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:15:02 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:15:02 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:15:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:15:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:15:03 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:15:03 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:15:03 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:15:03 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:15:03 INFO - Downloading functools32-3.2.3-2.tar.gz 15:15:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:15:03 INFO - warning: no files found matching '*.txt' 15:15:03 INFO - no previously-included directories found matching 'build' 15:15:03 INFO - no previously-included directories found matching 'dist' 15:15:03 INFO - no previously-included directories found matching '.git*' 15:15:03 INFO - Installing collected packages: jsonschema, functools32 15:15:04 INFO - Running setup.py install for functools32 15:15:04 INFO - warning: no files found matching '*.txt' 15:15:04 INFO - no previously-included directories found matching 'build' 15:15:04 INFO - no previously-included directories found matching 'dist' 15:15:04 INFO - no previously-included directories found matching '.git*' 15:15:04 INFO - Successfully installed jsonschema functools32 15:15:04 INFO - Cleaning up... 15:15:04 INFO - Return code: 0 15:15:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:04 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 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:15:04 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:15:04 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:15:04 INFO - Using env: {'DISPLAY': ':0', 15:15:04 INFO - 'HOME': '/home/cltbld', 15:15:04 INFO - 'LANG': 'en_US.UTF-8', 15:15:04 INFO - 'LANGUAGE': 'en_US:en', 15:15:04 INFO - 'LOGNAME': 'cltbld', 15:15:04 INFO - 'MAIL': '/var/mail/cltbld', 15:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:04 INFO - 'MOZ_NO_REMOTE': '1', 15:15:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:04 INFO - 'NO_EM_RESTART': '1', 15:15:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:04 INFO - 'PWD': '/builds/slave/test', 15:15:04 INFO - 'SHELL': '/bin/bash', 15:15:04 INFO - 'SHLVL': '1', 15:15:04 INFO - 'TERM': 'linux', 15:15:04 INFO - 'TMOUT': '86400', 15:15:04 INFO - 'USER': 'cltbld', 15:15:04 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:04 INFO - '_': '/tools/buildbot/bin/python'} 15:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:15:04 INFO - Cleaning up... 15:15:04 INFO - Return code: 0 15:15:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:04 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 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:15:04 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:15:04 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:15:04 INFO - Using env: {'DISPLAY': ':0', 15:15:04 INFO - 'HOME': '/home/cltbld', 15:15:04 INFO - 'LANG': 'en_US.UTF-8', 15:15:04 INFO - 'LANGUAGE': 'en_US:en', 15:15:04 INFO - 'LOGNAME': 'cltbld', 15:15:04 INFO - 'MAIL': '/var/mail/cltbld', 15:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:04 INFO - 'MOZ_NO_REMOTE': '1', 15:15:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:04 INFO - 'NO_EM_RESTART': '1', 15:15:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:04 INFO - 'PWD': '/builds/slave/test', 15:15:04 INFO - 'SHELL': '/bin/bash', 15:15:04 INFO - 'SHLVL': '1', 15:15:04 INFO - 'TERM': 'linux', 15:15:04 INFO - 'TMOUT': '86400', 15:15:04 INFO - 'USER': 'cltbld', 15:15:04 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:04 INFO - '_': '/tools/buildbot/bin/python'} 15:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:04 INFO - Downloading/unpacking blobuploader==1.2.4 15:15:04 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:15:04 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:15:04 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:15:04 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:15:05 INFO - Downloading blobuploader-1.2.4.tar.gz 15:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:15:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:15:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:06 INFO - Downloading docopt-0.6.1.tar.gz 15:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:15:06 INFO - Installing collected packages: blobuploader, requests, docopt 15:15:06 INFO - Running setup.py install for blobuploader 15:15:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:15:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:15:07 INFO - Running setup.py install for requests 15:15:07 INFO - Running setup.py install for docopt 15:15:07 INFO - Successfully installed blobuploader requests docopt 15:15:07 INFO - Cleaning up... 15:15:07 INFO - Return code: 0 15:15:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:07 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 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:15:07 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:15:07 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:15:07 INFO - Using env: {'DISPLAY': ':0', 15:15:07 INFO - 'HOME': '/home/cltbld', 15:15:07 INFO - 'LANG': 'en_US.UTF-8', 15:15:07 INFO - 'LANGUAGE': 'en_US:en', 15:15:07 INFO - 'LOGNAME': 'cltbld', 15:15:07 INFO - 'MAIL': '/var/mail/cltbld', 15:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:07 INFO - 'MOZ_NO_REMOTE': '1', 15:15:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:07 INFO - 'NO_EM_RESTART': '1', 15:15:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:07 INFO - 'PWD': '/builds/slave/test', 15:15:07 INFO - 'SHELL': '/bin/bash', 15:15:07 INFO - 'SHLVL': '1', 15:15:07 INFO - 'TERM': 'linux', 15:15:07 INFO - 'TMOUT': '86400', 15:15:07 INFO - 'USER': 'cltbld', 15:15:07 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:07 INFO - '_': '/tools/buildbot/bin/python'} 15:15:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:15:07 INFO - Running setup.py (path:/tmp/pip-jCcmni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:15:08 INFO - Running setup.py (path:/tmp/pip-3g4MMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:15:08 INFO - Running setup.py (path:/tmp/pip-ouih7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:15:08 INFO - Running setup.py (path:/tmp/pip-DhdvrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:15:08 INFO - Running setup.py (path:/tmp/pip-MbVAsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:15:08 INFO - Running setup.py (path:/tmp/pip-aO922o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:15:08 INFO - Running setup.py (path:/tmp/pip-SXzdwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:15:08 INFO - Running setup.py (path:/tmp/pip-PyL7dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:15:08 INFO - Running setup.py (path:/tmp/pip-W6pdSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:15:08 INFO - Running setup.py (path:/tmp/pip-7Ztvjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:15:08 INFO - Running setup.py (path:/tmp/pip-fTCA2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:15:08 INFO - Running setup.py (path:/tmp/pip-qohY4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:15:08 INFO - Running setup.py (path:/tmp/pip-7RZdW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:15:08 INFO - Running setup.py (path:/tmp/pip-njIBou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:15:08 INFO - Running setup.py (path:/tmp/pip-G_5Qcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:15:08 INFO - Running setup.py (path:/tmp/pip-dbHhDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:15:09 INFO - Running setup.py (path:/tmp/pip-n9m3yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:15:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:15:09 INFO - Running setup.py install for manifestparser 15:15:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:15:09 INFO - Running setup.py install for mozcrash 15:15:09 INFO - Running setup.py install for mozdebug 15:15:09 INFO - Running setup.py install for mozdevice 15:15:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:15:09 INFO - Running setup.py install for mozfile 15:15:09 INFO - Running setup.py install for mozhttpd 15:15:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:15:09 INFO - Running setup.py install for mozinfo 15:15:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:15:09 INFO - Running setup.py install for mozInstall 15:15:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:15:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:15:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:15:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:15:09 INFO - Running setup.py install for mozleak 15:15:09 INFO - Running setup.py install for mozlog 15:15:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:15:10 INFO - Running setup.py install for moznetwork 15:15:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:15:10 INFO - Running setup.py install for mozprocess 15:15:10 INFO - Running setup.py install for mozprofile 15:15:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:15:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:15:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:15:10 INFO - Running setup.py install for mozrunner 15:15:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:15:10 INFO - Running setup.py install for mozscreenshot 15:15:10 INFO - Running setup.py install for moztest 15:15:10 INFO - Running setup.py install for mozversion 15:15:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:15:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:15:10 INFO - Cleaning up... 15:15:10 INFO - Return code: 0 15:15:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:10 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 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:15:10 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:15:10 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: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 - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:10 INFO - 'MOZ_NO_REMOTE': '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': '/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 - '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:10 INFO - '_': '/tools/buildbot/bin/python'} 15:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:15:10 INFO - Running setup.py (path:/tmp/pip-OuVSHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:15:11 INFO - Running setup.py (path:/tmp/pip-OyEZKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:15:11 INFO - Running setup.py (path:/tmp/pip-HMe1Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:15:11 INFO - Running setup.py (path:/tmp/pip-FIzsvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:15:11 INFO - Running setup.py (path:/tmp/pip-mqk7bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:15:11 INFO - Running setup.py (path:/tmp/pip-6rs_XT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:15:11 INFO - Running setup.py (path:/tmp/pip-39GWrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:15:11 INFO - Running setup.py (path:/tmp/pip-lmfwD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:15:11 INFO - Running setup.py (path:/tmp/pip-DvN2fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:15:11 INFO - Running setup.py (path:/tmp/pip-eXTbB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:15:11 INFO - Running setup.py (path:/tmp/pip-XOPHFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:15:11 INFO - Running setup.py (path:/tmp/pip-PdbbRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:15:11 INFO - Running setup.py (path:/tmp/pip-EAtrnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:15:11 INFO - Running setup.py (path:/tmp/pip-yyLa9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:15:11 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:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:15:11 INFO - Running setup.py (path:/tmp/pip-VBzXMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:15:12 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:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:15:12 INFO - Running setup.py (path:/tmp/pip-iUTd9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:15:12 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:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:15:12 INFO - Running setup.py (path:/tmp/pip-qxXuXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:15:12 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:15:12 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:15:12 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:15:12 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:15:12 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:15:12 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:15:12 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:15:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:15:12 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:15:12 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:15:12 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:15:12 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:15:13 INFO - Downloading blessings-1.6.tar.gz 15:15:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:15:13 INFO - Installing collected packages: blessings 15:15:13 INFO - Running setup.py install for blessings 15:15:13 INFO - Successfully installed blessings 15:15:13 INFO - Cleaning up... 15:15:13 INFO - Return code: 0 15:15:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:15:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:15:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:15:13 INFO - Reading from file tmpfile_stdout 15:15:13 INFO - Current package versions: 15:15:13 INFO - argparse == 1.2.1 15:15:13 INFO - blessings == 1.6 15:15:13 INFO - blobuploader == 1.2.4 15:15:13 INFO - docopt == 0.6.1 15:15:13 INFO - functools32 == 3.2.3-2 15:15:13 INFO - jsonschema == 2.5.1 15:15:13 INFO - manifestparser == 1.1 15:15:13 INFO - mozInstall == 1.13 15:15:13 INFO - mozcrash == 1.0 15:15:13 INFO - mozdebug == 0.1 15:15:13 INFO - mozdevice == 0.51 15:15:13 INFO - mozfile == 1.2 15:15:13 INFO - mozhttpd == 0.7 15:15:13 INFO - mozinfo == 0.10 15:15:13 INFO - mozleak == 0.1 15:15:13 INFO - mozlog == 3.5 15:15:13 INFO - moznetwork == 0.27 15:15:13 INFO - mozprocess == 0.25 15:15:13 INFO - mozprofile == 0.28 15:15:13 INFO - mozrunner == 6.13 15:15:13 INFO - mozscreenshot == 0.1 15:15:13 INFO - mozsystemmonitor == 0.3 15:15:13 INFO - moztest == 0.8 15:15:13 INFO - mozversion == 1.4 15:15:13 INFO - psutil == 3.1.1 15:15:13 INFO - requests == 1.2.3 15:15:13 INFO - wsgiref == 0.1.2 15:15:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:13 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 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:15:13 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:15:13 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:15:13 INFO - Using env: {'DISPLAY': ':0', 15:15:13 INFO - 'HOME': '/home/cltbld', 15:15:13 INFO - 'LANG': 'en_US.UTF-8', 15:15:13 INFO - 'LANGUAGE': 'en_US:en', 15:15:13 INFO - 'LOGNAME': 'cltbld', 15:15:13 INFO - 'MAIL': '/var/mail/cltbld', 15:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:13 INFO - 'MOZ_NO_REMOTE': '1', 15:15:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:13 INFO - 'NO_EM_RESTART': '1', 15:15:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:13 INFO - 'PWD': '/builds/slave/test', 15:15:13 INFO - 'SHELL': '/bin/bash', 15:15:13 INFO - 'SHLVL': '1', 15:15:13 INFO - 'TERM': 'linux', 15:15:13 INFO - 'TMOUT': '86400', 15:15:13 INFO - 'USER': 'cltbld', 15:15:13 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:13 INFO - '_': '/tools/buildbot/bin/python'} 15:15:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:14 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:15:14 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:15:14 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:15:14 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:15:14 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:15:14 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:15:14 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:15:14 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:15:14 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:15:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:15:14 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:15:14 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:15:14 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:15:14 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:15:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:15:15 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:15:15 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:15:15 INFO - Installing collected packages: simplejson 15:15:15 INFO - Running setup.py install for simplejson 15:15:15 INFO - building 'simplejson._speedups' extension 15:15:15 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:15:16 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:15:16 INFO - Successfully installed simplejson 15:15:16 INFO - Cleaning up... 15:15:16 INFO - Return code: 0 15:15:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:15:16 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:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:16 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:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:16 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 0xacd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc96a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe39a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, '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': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', '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:15:16 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:15:16 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:15:16 INFO - Using env: {'DISPLAY': ':0', 15:15:16 INFO - 'HOME': '/home/cltbld', 15:15:16 INFO - 'LANG': 'en_US.UTF-8', 15:15:16 INFO - 'LANGUAGE': 'en_US:en', 15:15:16 INFO - 'LOGNAME': 'cltbld', 15:15:16 INFO - 'MAIL': '/var/mail/cltbld', 15:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:16 INFO - 'MOZ_NO_REMOTE': '1', 15:15:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:16 INFO - 'NO_EM_RESTART': '1', 15:15:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:16 INFO - 'PWD': '/builds/slave/test', 15:15:16 INFO - 'SHELL': '/bin/bash', 15:15:16 INFO - 'SHLVL': '1', 15:15:16 INFO - 'TERM': 'linux', 15:15:16 INFO - 'TMOUT': '86400', 15:15:16 INFO - 'USER': 'cltbld', 15:15:16 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:16 INFO - '_': '/tools/buildbot/bin/python'} 15:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:15:16 INFO - Cleaning up... 15:15:16 INFO - Return code: 0 15:15:16 INFO - Running post-action listener: _resource_record_post_action 15:15:16 INFO - Running post-action listener: _start_resource_monitoring 15:15:16 INFO - Starting resource monitoring. 15:15:16 INFO - [mozharness: 2017-09-13 22:15:16.561904Z] Finished create-virtualenv step (success) 15:15:16 INFO - [mozharness: 2017-09-13 22:15:16.562982Z] Running install step. 15:15:16 INFO - Running pre-action listener: _resource_record_pre_action 15:15:16 INFO - Running main action method: install 15:15:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:15:16 INFO - Reading from file tmpfile_stdout 15:15:16 INFO - Detecting whether we're running mozinstall >=1.0... 15:15:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:15:16 INFO - Reading from file tmpfile_stdout 15:15:16 INFO - Output received: 15:15:16 INFO - Usage: mozinstall [options] installer 15:15:16 INFO - Options: 15:15:16 INFO - -h, --help show this help message and exit 15:15:16 INFO - -d DEST, --destination=DEST 15:15:16 INFO - Directory to install application into. [default: 15:15:16 INFO - "/builds/slave/test"] 15:15:16 INFO - --app=APP Application being installed. [default: firefox] 15:15:16 INFO - mkdir: /builds/slave/test/build/application 15:15:16 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:15:16 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:34 INFO - Reading from file tmpfile_stdout 15:15:34 INFO - Output received: 15:15:34 INFO - /builds/slave/test/build/application/firefox/firefox 15:15:34 INFO - Running post-action listener: _resource_record_post_action 15:15:34 INFO - [mozharness: 2017-09-13 22:15:34.667400Z] Finished install step (success) 15:15:34 INFO - [mozharness: 2017-09-13 22:15:34.667551Z] Running setup-mitmproxy step. 15:15:34 INFO - Running pre-action listener: _resource_record_pre_action 15:15:34 INFO - Running main action method: setup_mitmproxy 15:15:34 INFO - Skipping: mitmproxy is not required 15:15:34 INFO - Running post-action listener: _resource_record_post_action 15:15:34 INFO - [mozharness: 2017-09-13 22:15:34.668262Z] Finished setup-mitmproxy step (success) 15:15:34 INFO - [mozharness: 2017-09-13 22:15:34.668443Z] Running run-tests step. 15:15:34 INFO - Running pre-action listener: _resource_record_pre_action 15:15:34 INFO - Running pre-action listener: _set_gcov_prefix 15:15:34 INFO - Running main action method: run_tests 15:15:34 WARNING - Try message not found. 15:15:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:15:34 INFO - Python 2.7.3 15:15:34 INFO - Return code: 0 15:15:34 INFO - grabbing minidump binary from tooltool 15:15: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:15:34 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 0xe3d090>, '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 0xe3dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe37f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:15:34 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:34 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:34 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:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:15:34 INFO - Return code: 0 15:15:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:15:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:15:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:15:34 INFO - ENV: RUST_BACKTRACE is now full 15:15:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:15:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:15:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-054 --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:34 INFO - Using env: {'DISPLAY': ':0', 15:15:34 INFO - 'HOME': '/home/cltbld', 15:15:34 INFO - 'LANG': 'en_US.UTF-8', 15:15:34 INFO - 'LANGUAGE': 'en_US:en', 15:15:34 INFO - 'LOGNAME': 'cltbld', 15:15:34 INFO - 'MAIL': '/var/mail/cltbld', 15:15:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:15:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:34 INFO - 'MOZ_NO_REMOTE': '1', 15:15:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:15:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:34 INFO - 'NO_EM_RESTART': '1', 15:15:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:34 INFO - 'PWD': '/builds/slave/test', 15:15:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:15:34 INFO - 'RUST_BACKTRACE': 'full', 15:15:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:15:34 INFO - 'SHELL': '/bin/bash', 15:15:34 INFO - 'SHLVL': '1', 15:15:34 INFO - 'TERM': 'linux', 15:15:34 INFO - 'TMOUT': '86400', 15:15:34 INFO - 'USER': 'cltbld', 15:15:34 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:34 INFO - '_': '/tools/buildbot/bin/python'} 15:15:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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:35 INFO - mozversion application_buildid: 20170913212101 15:15:35 INFO - mozversion application_changeset: b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d 15:15:35 INFO - mozversion application_display_name: Nightly 15:15:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:15:35 INFO - mozversion application_name: Firefox 15:15:35 INFO - mozversion application_remotingname: firefox 15:15:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:15:35 INFO - mozversion application_vendor: Mozilla 15:15:35 INFO - mozversion application_version: 57.0a1 15:15:35 INFO - mozversion platform_buildid: 20170913212101 15:15:35 INFO - mozversion platform_changeset: b9ffb07cf8e71b1f4285ec7741f6b2f8256dc79d 15:15:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:15:35 INFO - mozversion platform_version: 57.0a1 15:15:35 INFO - using testdate: 1505340935 15:15:35 INFO - actual date: 1505340935 15:15:35 INFO - starting webserver on 'localhost:37975' 15:15:35 INFO - SUITE-START | Running 2 tests 15:15:35 INFO - TEST-START | dromaeo_css 15:15:35 INFO - Initialising browser for dromaeo_css test... 15:15:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37975/getInfo.html -profile /tmp/tmpXGMiUq/profile 15:15:35 INFO - TEST-INFO | started process 3577 (/builds/slave/test/build/application/firefox/firefox http://localhost:37975/getInfo.html) 15:15:43 INFO - TEST-INFO | 3577: exit 0 15:15:43 INFO - Browser initialized. 15:15:43 INFO - Running cycle 1/1 for dromaeo_css test... 15:15:43 INFO - Using env: {'DISPLAY': ':0', 15:15:43 INFO - 'HOME': '/home/cltbld', 15:15:43 INFO - 'JSGC_DISABLE_POISONING': '1', 15:15:43 INFO - 'LANG': 'en_US.UTF-8', 15:15:43 INFO - 'LANGUAGE': 'en_US:en', 15:15:43 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:15:43 INFO - 'LOGNAME': 'cltbld', 15:15:43 INFO - 'MAIL': '/var/mail/cltbld', 15:15:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:15:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:15:43 INFO - 'MOZ_CRASHREPORTER': '1', 15:15:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:43 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:15:43 INFO - 'MOZ_NO_REMOTE': '1', 15:15:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:15:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:43 INFO - 'NO_EM_RESTART': '1', 15:15:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:43 INFO - 'PWD': '/builds/slave/test', 15:15:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:15:43 INFO - 'RUST_BACKTRACE': 'full', 15:15:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:15:43 INFO - 'SHELL': '/bin/bash', 15:15:43 INFO - 'SHLVL': '1', 15:15:43 INFO - 'TERM': 'linux', 15:15:43 INFO - 'TMOUT': '86400', 15:15:43 INFO - 'USER': 'cltbld', 15:15:43 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:15:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:43 INFO - '_': '/tools/buildbot/bin/python'} 15:15:43 INFO - TEST-INFO | started process 3815 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXGMiUq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:15:44 INFO - PID 3815 | 15:15:44 INFO - PID 3815 | (/builds/slave/test/build/application/firefox/firefox:3864): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:15:44 INFO - PID 3815 | 15:15:54 INFO - PID 3815 | RSS: Main: 174678016 15:15:54 INFO - PID 3815 | 15:18:52 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:37975/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37975/tests/dromaeo/cssquery-ext.html) 15:18:53 INFO - PID 3815 | RSS: Main: 177659904 15:18:53 INFO - PID 3815 | 15:21:55 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:37975/tests/dromaeo/cssquery-ext.html (next: http://localhost:37975/tests/dromaeo/cssquery-jquery.html) 15:21:55 INFO - PID 3815 | RSS: Main: 174022656 15:21:55 INFO - PID 3815 | 15:24:57 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:37975/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37975/tests/dromaeo/cssquery-mootools.html) 15:24:58 INFO - PID 3815 | RSS: Main: 176418816 15:24:58 INFO - PID 3815 | 15:28:00 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:37975/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37975/tests/dromaeo/cssquery-prototype.html) 15:28:00 INFO - PID 3815 | RSS: Main: 173445120 15:28:00 INFO - PID 3815 | 15:31:04 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:37975/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37975/tests/dromaeo/cssquery-yui.html) 15:31:04 INFO - PID 3815 | RSS: Main: 176435200 15:31:04 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:37975/tests/dromaeo/cssquery-yui.html (next: http://localhost:37975/tests/dromaeo/cssquery-dojo.html) 15:34:06 INFO - PID 3815 | RSS: Main: 179421184 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | __start_tp_report 15:34:06 INFO - PID 3815 | _x_x_mozilla_page_load 15:34:06 INFO - PID 3815 | _x_x_mozilla_page_load_details 15:34:06 INFO - PID 3815 | |i|pagename|runs| 15:34:06 INFO - PID 3815 | |0;dojo.html;3120.3447276072025;3144.88992885249;3166.0343595203467;3191.0267368452614;3194.6006749156354;6239.532035097363;6303.936960630393;6384.201974753156;6418.454431373333;6477.870442591151;6242.032484964838;6266.46735027522;6267.247930248365;6308.905366419496;6343.953247714133;5797.913031304523;5839.649621022752;5889.411058894119;5896.88206235875;5900.1149827526;5914.822555323347;5942.227510423639;5998.730057147417;5999.340072592011;6012.6392416455155;1178.914421199311;5780.132980052984;5784.132380142991;5793.507551858097;5898;5839.240898683186;5847.152162936351;5916.674582897939;5930.614510056876;5931.495822855062;5820.3888591698105;5825;5875.676837773921;5883.941160588425;5890.263717035371;6287.591306565058;6343.270523889765;6355.428011478943;6442.48460123189;6449.451796597248;6492.805215843485;6791.694373753193;6818.079559259486;6898.241193468714;6902.758403455855;6777.830554236134;6852.691628876662;6852.965735171292;6858.6570671466225;6933.4106600938985;6364.7135878885565;6398.9040164397575;6443.033544968245;6446.806595802086;6447.83880402989;7062.15254169503;7078.221395646475;7089.113860767404;7103.609301488468;7167.104111986002;6735.326467353323;6750.493712971547;6817.352351526597;6817.556858804261;6828.52200345981;6643.40209381158;6747.05541224229;6780.355866192704;6820.556663816836;6852.143482064742;6779.491538134561;6859.622720750406;6865.828354291083;6874.931250687529;6882.449404047664;6847.1783385994;6853.657317134223;6938.27148149437;6939.271376505495;6950.30496950311;5232.764525596282;5298.231756395378;5328.280682107866;5404.054290499147;5415.83752487426;4563.452385713736;4566;4575.039241759201;4579.931301030487;4590.862274131781;5688.288963879515;5691.943080569141;5693.629914055656;5731.083026715705;5775.711214439261;6801.1158549388265;6855.691493882788;6883.690233939485;6891.27641597635;6891.931080689129;5803.216565763568;5813.82558523245;5878.2064421302575;5885.146653735184;5891.882162356729;5737.397573254748;5829.242198514166;5836.387179346192;5859.2382990211;5935;6677.699503522354;6703.095082163943;6706.094677218513;6709.127813384229;6709.966450167718;6855.383015528527;6876.243613202644;6898.758543450955;6908.516403851682;6962.025316455599;6559.639219842955;6650.600963942179;6665.433438157811;6732;6734.225564060196;6477.902831457626;6605.966970165118;6618.404343609098;6670.433013193933;6729.730810767514;6909.827254318682;6944.305569443015;6949.444044476329;6957.339052789976;6973.5118541701595;6656.83357916056;6658.167729033871;6749.831254218684;6754.662266886734;6758.121444212221;6651.401373876374;6659.733610655519;6662.56693314933;6743.35938085881;6751.763688270887;6435.388638079375;6518.054882041983;6531.052997315458;6621.933780662324;6622.4702023838945;6702.061711360316;6772.288909664611;6790.7623233186605;6822.692978815866;6835.7949261522235;6251.124842521959;6400.647964361819;6472.579282346633;6495.253045899782;6595.604263744191 15:34:06 INFO - PID 3815 | |1;ext.html;832.5753865528578;836.6486075848143;862.4911302331623;886.5965985476607;890.4657205676599;41966.37050444242;41999.79000105003;42224.36663450043;42396.99999999996;42840.78579607102;38606.42090368647;38929.805350973205;39132.80433597835;39232;39246.60753392465;7102.183248926388;7188.388986936091;7225.710971561158;7228.638568071576;7232.566046037255;7132.3580877721;7225.277472252759;7247.130344358658;7248.166460857016;7251.202367739545;7198.820029499252;7226.6025368604705;7227.494075414723;7835.490693104958;8038.799030024267;7921.920780792205;7987.08148562914;7988.800279992988;7990.760277191694;7992.3606111510935;7776.027996500437;7862.2923936845955;7864.5281283123195;7869.055713314382;7877.409194310392;39773;40208.39687404691;40227;40339.394909076385;40357.394639080434;3542.291541691646;3590.461430785377;3594.11944073701;3596.658317459863;3599.2981368633127;33301.16747081342;33309.83345083283;33467.16332091717;33538.993830185136;33570.832145839115;5186.818461353834;5194.896102077975;5212.374515058217;5217.5565076968505;5265.394479634852;189628;190574;190950;191012;191481;48399.75800120977;48414.27378589324;48479.75760121247;48616.51383486191;49019.26471102936;50789.74605126951;50992.490075099515;51027.23459148116;51066.744666276434;51281;16370.345186192419;16622.916885415736;16711.331546737994;16716.414925477795;16732.24704888283;13708.040437169502;13712;13716.931415342859;13722.725545489235;13723;14345.64135896611;14471.49349772753;14514.854851451562;14621.122732636077;14639.414423423157;5866.296044474605;5885.323187833368;5913.49735272498;5935.139404786281;6071.392860713893;981.190517822799;994.5922171909482;1005.7887843552936;1017.0592202212923;1027.0397178637998;22820.65769013466;22896;22897.198598048988;22929.426764330692;23008.19471318496;1090;1096.6819622309602;1106.1261603333276;1123.1239633086207;1126.6169502369235;12049;12055;12074.939625301817;12189.939050304693;12249.265044097383;223844;224198;224299;225398;225688;52965.7351713239;54224;57436;57611.13583296254;57628.42371576231;1404.676924307415;1440.4382290906556;1442.2716528153217;1447.4355001549404;1456.3155317000992;26817.195484135507;27150;27340.863295683394;27466.45067098687;27642.58536122;36539.26921461609;36901.26197476089;37069.62930370662;37243;37279.81360093182;199838;200184;200212;200462;200616;33737.66262337345;33766.49350259699;33970.83014584911;34021.659783402836;34103.4884476738;43106.353404698326;43333.56666433421;43547.12905741804;43705.34441983309;43795.12409751723;31471.84264078665;31555.36889262248;31589.684103158674;31644.841775790974;31919.840400797846;31058;31505.527417088302;31550.684493154775;31751;31824.681753182173;35104.12239694028;35153.296934060665;35561.64438355583;35596.644033559336;35790.821045895646;30633.54049689301;31140.844295778377;31157;31166.688333116377;31324.843375782973;23109.88445057764;23174.420639483473;23185.652215217124;23278.534429311658;23411.648825267974 15:34:06 INFO - PID 3815 | |2;jquery.html;564.7373971103436;565.2143520506494;567.6026781253124;568.7071158353448;582.8018473718936;21929;22575.54848903023;22626.773732262653;22655.09379624813;22699.638021718667;23359.532809343804;24199.39501512463;24597.631035534483;24598.139065132724;25011.24966251011;6322.683865806725;6502.837429064264;6505.674716264191;6507.4143327100555;6517.511186660996;5942.881142377149;6334.429901308881;6397.200349956255;6429.807105786834;6445.387688169616;6423.743050278006;6456.257530383996;6456.838579035538;6457.4511166550665;6461.1600491936215;6456.322086180954;6477;6477.514186436013;6478.093066970628;6485.351464853524;5774.307083150007;5990.2212712347555;6051.757929682852;6061.484773794233;6065.848353791146;29450.558241626393;29946.850265748748;29983;30031.549526757117;30066.24834379136;2068.13138481838;2098.3495116513777;2103.463616777727;2107.8840663763485;2114.8836813975227;18246.178921948413;18547.07264636763;18776.906115469334;20180.596388072303;20356.18575256988;2439.512097580491;2465.1372019793107;2485.3413845331;2488.029668429314;2490.402303447178;110754;114964;132561.3371933134;137330.3133484336;137738;14856.480023199352;15020.549383518512;15034.248287585795;15106.697866042618;15145.939784215;22944.541109177724;22954.311370658907;22967;22972.7702722974;23001.769982299964;9655.613775449045;9726.221902247804;9880.160186384237;9942.652007179713;9943.30396872212;8809.163129502687;8820.162084601952;8963.507007114671;8988.460692358349;9005.14451127142;9012.188902998761;9144.131307525908;9474.289428292768;9477.668281610111;9561.61753529865;2622.265765585639;2690.6098615701007;2693;2739.849308288063;2772;966.6568368229177;975.8194733974237;979.1530326267728;991.4943378876825;992.8758905136858;19371.3220037298;19372.2251109954;19538.413847584594;19618;19927.20291188365;1030.3714734012246;1038.5689938675366;1041.3387498938077;1044.6552637629563;1046.9267151299337;10751.354918704903;10757.784844303227;10870.18473614482;10898.237123401286;11167.72080697989;459708.83248122025;466688;469604;474919;482850;100116;102239.48880255551;102767;104945;111051.44474277577;1036.0571879589538;1132.5413207650915;1141.7145713571608;1184.2775906696909;1192.976140477203;20377.083031263926;20581;20690.58618827585;20860.58278834445;21011.949402530117;54711;54903;55370.16944745913;56049.71975140098;56992.14511782244;65097.34902651102;66140;66267;66496.66751666211;66601;12748.171368860994;13124.40940157676;13301;13363.3986470611;13375.53185638498;21270.78729212688;21737.130514779863;22726.431839204153;23353.182638607566;23372;18254.81745182531;18346.082695864887;18468.076596170406;18491.167897444917;18670.346537871108;15910.12494312824;16718.58203544921;16880.324787008874;16924.66150677004;17128.914355428144;17847.553811154306;18816.6236675263;18986.43040708781;19114.808851911304;19137.808621913602;13232.669183270495;13252.801207981694;13376.130551514121;13384.063115582205;13438.529651462151;18202.180901859712;18223;18338.449846504627;18375.632487350445;18580.44258672242 15:34:06 INFO - PID 3815 | |3;mootools.html;1315.250307324825;1349.4602159136339;1351.4796803230754;1356.3421740455876;1369.6438925879268;12375.44310506027;12968.610941671754;12977.935110324446;15254;15748;15484.845151548481;15756.527304180865;15770.84229157708;15779.21103944801;15782.447614333501;2562.1032638576535;2613.4903693779675;2657.7209393013745;2661.7072122066556;2662.9467410651773;2675.1172113202692;2692.9596056059177;2698.581719833429;2701.1086341507353;2701.2031450722084;2613.5687611544167;2638.907638232662;2644.338915271182;2715.945681086377;2782.596523504091;2633.6971248306404;2640.0231914191872;2642.4582960493053;2642.748938850806;2657.1231493607065;2651.7348265173523;2660.507806055886;2664.5736682130764;2671.479061582992;2671.519126557219;14698.70602587953;14983.400663973427;14992.775108373275;14999.550013499613;15021.849781502155;7958.801029974239;8110.797230069295;8182.590870456454;8184.427090103695;8188.918110818935;4908.288298196741;5007.349044624212;5083.38999320084;5085.364329458817;5099;2276;2357.905683772647;2442.206282958046;2445.400876785199;2457.1154384421593;25975.610365844528;26337.20988370352;26377.3405664858;26417.603735943976;26487.205383838515;6980;7031.29687031303;7101.609411482418;7161.1406631203545;7186.7125314986815;20134.093965771575;20282.492937676678;20288.695669564968;20354.28759993393;20371.592568148553;7597.886031709529;7694;7708.537487750753;7791.610419479003;7796.883046754303;6904.171499420102;6941.375276225164;6987.510874238857;7009.684564194624;7009.719611215494;5527.474889885454;5637.56242158247;5695.601307908407;5764.337101233328;5941.524678025748;1788.4634609617062;1804.5939663575853;1831.9175637096362;1858.3681548273653;1878.1078987480837;887.7070566712969;920.3925409229876;923.316745608245;927.9025702301296;929.8837645294338;7800.102988156321;7822.413319000984;7903.881441778378;7915.525068495908;7917;716.4662326566742;723.7611588175889;727.8253219227347;736.8710475666737;739.4934469888144;4830.420349558076;4989.426215985208;5183.144781111075;5388.865278368072;5586.24585680936;19410.417687469115;20436;20453.590928181355;20487.69268460974;20517.38447846567;19967.900160499394;21501.354959351243;21621.67567486489;21722.456938576663;21725.45686357822;10574.68275951738;11311.773764524663;11622.244554103956;11653.88346116528;11666.941665291619;8673;8847.778805529655;8963;8990.235829954527;8991.23574496149;18344.266229350676;18616;18746.437606871816;18863;18960.43118706441;19197.520061998563;19323.710144348126;19542.60914781725;19708.014599270267;19974.600507989468;12298;12599.496020159457;12606.747865042464;12608.432620532281;12612.684682883002;13569.1858488491;13771;13964.511242106884;13975.371108300355;13979.860201397856;11323.263987840764;11336.433178341214;11487.36819474937;11679.532818687157;11779.116566257393;11100.61147859821;11165.665030049111;11199.384033878216;11359.204855660024;11515.596954106566;9786.168175705145;9943;9947.950260248941;9948.701538953843;9951.552180151766;6861.485388595976;6911.308869113049;6911.827204319932;6940.236573976757;6974.4769142315545;7488.850222995618;7572.545647261182;7591.430642701709;7666.310032097138;7706.922930770621 15:34:06 INFO - PID 3815 | |4;prototype.html;494.00276795325254;497.8599891769567;510.45126489024307;517.1983425690179;520.45092427489;6192.164057852188;6239.594426362283;6266.054954171544;6267.122602835607;6323.968380158104;6024.728887200075;6069.544784141185;6117.480014198798;6188.907166392496;6199.69001549923;4832.806687732486;4919.581835543983;4971.527704868032;5044.974775126119;5113.693178409298;4918.557329840313;4966.751662416883;4980.626453016021;4990.777259571392;4996.5503104720565;4857.587105096071;4874.926876096843;4875.9760450305475;4890.310329318861;4925.901481970376;4907.1412502812045;4912.164931961557;4947.406311242638;4984.850454486371;5001.624878134118;4441.600255976945;4442.8222871085445;4461.308497182942;4472.46330440346;4473.329000649896;5995.160677505153;6047.183630209909;6085.4523092921845;6161.815145545596;6208.782692605782;5561.972190139064;5586.86032849182;5589.245451864028;5599.384067752544;5669.518090962273;6602.7028783704845;6606.471482281454;6627.138471998657;6633.635150066792;6647.401733843929;4809.975950120227;4831.178699621073;4831.782569784333;4844.442889067767;4853.417589889201;6781.93218067813;6791.366618858936;6808.795736127924;6856.965715171392;6909.481788865804;4900.828471003498;4928.359313289249;4935.161022626162;4955.2567114933045;4959.04117873438;5002.399712034507;5022.924656130161;5039.521245481674;5052.520010598987;5056.292119103255;5557.666540007613;5568.021108706823;5632.746526406321;5651.547876169897;5657.886842263131;6207.6275423474735;6343.5278128919;6393.264774550986;6411.4229719325485;6649.271189490671;1870.289290069792;1874.3158747057225;1885.792562818089;1887.3865993552151;1912.5792325688326;1363.6454521824396;1400.3698335748954;1403.1440821099122;1409.8096756937682;1411.9011669183265;5521.861953451196;5587.580931430159;5644.57665675076;5718.199452076713;5724.856878578069;6656.36764507371;6665.300143484961;6669.066330713704;6693.799186024427;6710.966445167743;5610.5231055360755;5656.9817432861655;5657.321121465368;5695.5443564514735;5754.338251101093;3384.323135372886;3541.6635419635095;3555.466679998021;3574.8748793792092;3592.3533763922746;6386.680665966776;6453.676996215764;6505.349465053457;6509.9023514647315;6575.928582496498;6774.42417394527;6830.760923367758;6918.619475928872;6922.134733158356;6946.37482626566;6288.245410550763;6485.70814313357;6513.706883190173;6614.404703576702;6648.634325112069;6660.800175994728;6695.631740254333;6735.4274886635185;6765.391114799495;6803.863922721617;6702.597844129368;6715.56348837324;6726.663666816542;6747.932520674927;6775.695093720893;6462.773802916875;6488.837779055561;6557.409833115043;6601.867962640624;6606.8017959461295;4915.311856340187;4930.91519865629;4939.753012349296;4990.775415106256;5017.197248440232;6427.614343139427;6452.870942581216;6604;6763.782519146601;6809.761658341934;6394.23269207698;6423.293437721753;6468.838279043061;6519.543631945908;6581.8279967609615;6476.514261430318;6512.674366281762;6530.967345163243;6534.444572211415;6595.604263744191;6422.197225346832;6432.774852880127;6494.383033611799;6519.761245363366;6525;6504.252011018793;6550.639714815731;6605.451420806546;6624.271330153776;6639.170103737033;6353.5870168438905;6416.7754128605275;6441.355864413708;6441.874181519994;6473.967630161819 15:34:06 INFO - PID 3815 | |5;yui.html;874.2656168818191;906.8367693815113;907.2288554728482;908.7818923458372;937.756183392318;18123.27506899724;19020.90489547552;19160.904195479005;19167.329143479998;19198.904005479988;17785.19966601506;18129.90935045326;18286.451406457792;18380.264789408408;18518.90740546299;958.0898146760588;978.8580655804903;980.745006298364;981.8330883749779;982.017982017982;950.4772375193616;979.3732011512637;982.6609819612222;982.9262805289596;983.7835676151235;1057.4924036462503;1066.2536224642781;1066.402814423925;1067.647676266834;1069.1820757120809;980.8283550378726;1055.7941201465717;1061.4745700878077;1061.5859814672283;1061.851340812287;738.6565247160096;748.7304570354668;749.2844333661341;750.0511852506584;750.6734570461866;17762.555936101573;18043.18805653749;18084.638307233912;18086.366977155736;18087.095645217818;19219;19270.71093933592;20380.5923881523;20532.589348213103;20536.79463205364;17506.12469376526;17595.12024398775;17727.11364431773;17805.821941780545;17892.55268618282;3795.35478968576;3814.8664796731982;3819.7135214858718;3823.847046118152;3824.082220267144;123087.3845630775;133921;134876.32561837227;134943;136631;16681.165941702868;16707.415240466526;16983;16988.49034528966;17084.572885677957;31058.844705776777;31259.687403126132;31323.843380783404;31396.843015784776;31959.360812783612;8554.828903421896;8693.261072808882;8710.782230444163;8715.825683486295;8729.869051964226;4118.155778065489;4184.267753143188;4185.351270553069;4194.979025104916;4195.265828480065;5327.147656374961;5376.704281264568;5403.9459605403445;5509.641873278224;5512.2558454608115;933.3140141995627;939.7321763297564;944.2210176604328;950.1306304731231;951.048951048951;701.929807019294;713.3437237741186;718.1633397092329;718.8382613911828;731.3673672273445;10994.505247263893;11296.322220666787;11439.828402573969;12251.32617706017;12456.439460224312;693.6219760230687;705.6648092156184;720.0711082703359;720.9639518024078;723.9565626062348;5468.179773034077;5513.862153446196;5556.555475561945;5629.155626655953;5637.041702910515;119126;122770;125545;126789;128769.35615321863;106770;106888.46555767172;107412;110759.44620277008;112366.43816781028;96066.55900161504;96900.51549742348;99807.50096249473;101816;102026.48986755019;10242.334248273839;10331.586736530455;10393.792124157324;10472.842907356244;10508;44180.11639767251;45465;45801.312980304654;45983.77008115072;46557.76721116373;55120.72439637776;55588;57236.14145787733;57324.14013789713;57537.13694294672;15948.963317384332;15987.520374388787;16203.270852811416;16256.349746010028;16406.507804765875;17543.912280438515;17555.56111097233;17789.911050444665;17803.821961780217;17890.194941227935;15025.173615451255;15168.08991460516;15178.696426071638;15259.465918693237;15273;13068.607941761762;13132.62107478701;13147.868521314664;13215.273159976294;13321.13412628176;14911.478098266509;14972.1016738996;15144.091354518765;15194.772078419053;15238.923805380902;11167.609133680282;11198.328100314007;11392.601258956229;11447.427628618703;11478.483468243781;11452.541898324307;11534.711632209262;11710.94144529306;11924.165308428326;12112.636620901387 15:34:06 INFO - PID 3815 | __end_tp_report 15:34:06 INFO - PID 3815 | __start_cc_report 15:34:06 INFO - PID 3815 | _x_x_mozilla_cycle_collect,384 15:34:06 INFO - PID 3815 | __end_cc_report 15:34:06 INFO - PID 3815 | __startTimestamp1505342046545__endTimestamp 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | ------- Summary: start ------- 15:34:06 INFO - PID 3815 | Number of tests: 6 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | [#0] dojo.html Cycles:165 Average:6252.89 Median:6518.05 stddev:874.72 (13.4%) stddev-sans-first:842.17 15:34:06 INFO - PID 3815 | Values: 3120.3 3144.9 3166.0 3191.0 3194.6 6239.5 6303.9 6384.2 6418.5 6477.9 6242.0 6266.5 6267.2 6308.9 6344.0 5797.9 5839.6 5889.4 5896.9 5900.1 5914.8 5942.2 5998.7 5999.3 6012.6 1178.9 5780.1 5784.1 5793.5 5898.0 5839.2 5847.2 5916.7 5930.6 5931.5 5820.4 5825.0 5875.7 5883.9 5890.3 6287.6 6343.3 6355.4 6442.5 6449.5 6492.8 6791.7 6818.1 6898.2 6902.8 6777.8 6852.7 6853.0 6858.7 6933.4 6364.7 6398.9 6443.0 6446.8 6447.8 7062.2 7078.2 7089.1 7103.6 7167.1 6735.3 6750.5 6817.4 6817.6 6828.5 6643.4 6747.1 6780.4 6820.6 6852.1 6779.5 6859.6 6865.8 6874.9 6882.4 6847.2 6853.7 6938.3 6939.3 6950.3 5232.8 5298.2 5328.3 5404.1 5415.8 4563.5 4566.0 4575.0 4579.9 4590.9 5688.3 5691.9 5693.6 5731.1 5775.7 6801.1 6855.7 6883.7 6891.3 6891.9 5803.2 5813.8 5878.2 5885.1 5891.9 5737.4 5829.2 5836.4 5859.2 5935.0 6677.7 6703.1 6706.1 6709.1 6710.0 6855.4 6876.2 6898.8 6908.5 6962.0 6559.6 6650.6 6665.4 6732.0 6734.2 6477.9 6606.0 6618.4 6670.4 6729.7 6909.8 6944.3 6949.4 6957.3 6973.5 6656.8 6658.2 6749.8 6754.7 6758.1 6651.4 6659.7 6662.6 6743.4 6751.8 6435.4 6518.1 6531.1 6621.9 6622.5 6702.1 6772.3 6790.8 6822.7 6835.8 6251.1 6400.6 6472.6 6495.3 6595.6 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | [#1] ext.html Cycles:180 Average:37712.12 Median:19551.97 stddev:53257.47 (272.4%) stddev-sans-first:53334.87 15:34:06 INFO - PID 3815 | Values: 832.6 836.6 862.5 886.6 890.5 41966.4 41999.8 42224.4 42397.0 42840.8 38606.4 38929.8 39132.8 39232.0 39246.6 7102.2 7188.4 7225.7 7228.6 7232.6 7132.4 7225.3 7247.1 7248.2 7251.2 7198.8 7226.6 7227.5 7835.5 8038.8 7921.9 7987.1 7988.8 7990.8 7992.4 7776.0 7862.3 7864.5 7869.1 7877.4 39773.0 40208.4 40227.0 40339.4 40357.4 3542.3 3590.5 3594.1 3596.7 3599.3 33301.2 33309.8 33467.2 33539.0 33570.8 5186.8 5194.9 5212.4 5217.6 5265.4 189628.0 190574.0 190950.0 191012.0 191481.0 48399.8 48414.3 48479.8 48616.5 49019.3 50789.7 50992.5 51027.2 51066.7 51281.0 16370.3 16622.9 16711.3 16716.4 16732.2 13708.0 13712.0 13716.9 13722.7 13723.0 14345.6 14471.5 14514.9 14621.1 14639.4 5866.3 5885.3 5913.5 5935.1 6071.4 981.2 994.6 1005.8 1017.1 1027.0 22820.7 22896.0 22897.2 22929.4 23008.2 1090.0 1096.7 1106.1 1123.1 1126.6 12049.0 12055.0 12074.9 12189.9 12249.3 223844.0 224198.0 224299.0 225398.0 225688.0 52965.7 54224.0 57436.0 57611.1 57628.4 1404.7 1440.4 1442.3 1447.4 1456.3 26817.2 27150.0 27340.9 27466.5 27642.6 36539.3 36901.3 37069.6 37243.0 37279.8 199838.0 200184.0 200212.0 200462.0 200616.0 33737.7 33766.5 33970.8 34021.7 34103.5 43106.4 43333.6 43547.1 43705.3 43795.1 31471.8 31555.4 31589.7 31644.8 31919.8 31058.0 31505.5 31550.7 31751.0 31824.7 35104.1 35153.3 35561.6 35596.6 35790.8 30633.5 31140.8 31157.0 31166.7 31324.8 23109.9 23174.4 23185.7 23278.5 23411.6 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | [#2] jquery.html Cycles:180 Average:33349.33 Median:22969.89 stddev:78898.01 (343.5%) stddev-sans-first:79080.94 15:34:06 INFO - PID 3815 | Values: 564.7 565.2 567.6 568.7 582.8 21929.0 22575.5 22626.8 22655.1 22699.6 23359.5 24199.4 24597.6 24598.1 25011.2 6322.7 6502.8 6505.7 6507.4 6517.5 5942.9 6334.4 6397.2 6429.8 6445.4 6423.7 6456.3 6456.8 6457.5 6461.2 6456.3 6477.0 6477.5 6478.1 6485.4 5774.3 5990.2 6051.8 6061.5 6065.8 29450.6 29946.9 29983.0 30031.5 30066.2 2068.1 2098.3 2103.5 2107.9 2114.9 18246.2 18547.1 18776.9 20180.6 20356.2 2439.5 2465.1 2485.3 2488.0 2490.4 110754.0 114964.0 132561.3 137330.3 137738.0 14856.5 15020.5 15034.2 15106.7 15145.9 22944.5 22954.3 22967.0 22972.8 23001.8 9655.6 9726.2 9880.2 9942.7 9943.3 8809.2 8820.2 8963.5 8988.5 9005.1 9012.2 9144.1 9474.3 9477.7 9561.6 2622.3 2690.6 2693.0 2739.8 2772.0 966.7 975.8 979.2 991.5 992.9 19371.3 19372.2 19538.4 19618.0 19927.2 1030.4 1038.6 1041.3 1044.7 1046.9 10751.4 10757.8 10870.2 10898.2 11167.7 459708.8 466688.0 469604.0 474919.0 482850.0 100116.0 102239.5 102767.0 104945.0 111051.4 1036.1 1132.5 1141.7 1184.3 1193.0 20377.1 20581.0 20690.6 20860.6 21011.9 54711.0 54903.0 55370.2 56049.7 56992.1 65097.3 66140.0 66267.0 66496.7 66601.0 12748.2 13124.4 13301.0 13363.4 13375.5 21270.8 21737.1 22726.4 23353.2 23372.0 18254.8 18346.1 18468.1 18491.2 18670.3 15910.1 16718.6 16880.3 16924.7 17128.9 17847.6 18816.6 18986.4 19114.8 19137.8 13232.7 13252.8 13376.1 13384.1 13438.5 18202.2 18223.0 18338.4 18375.6 18580.4 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | [#3] mootools.html Cycles:180 Average:9419.21 Median:2642.60 stddev:6711.92 (254.0%) stddev-sans-first:6703.13 15:34:06 INFO - PID 3815 | Values: 1315.3 1349.5 1351.5 1356.3 1369.6 12375.4 12968.6 12977.9 15254.0 15748.0 15484.8 15756.5 15770.8 15779.2 15782.4 2562.1 2613.5 2657.7 2661.7 2662.9 2675.1 2693.0 2698.6 2701.1 2701.2 2613.6 2638.9 2644.3 2715.9 2782.6 2633.7 2640.0 2642.5 2642.7 2657.1 2651.7 2660.5 2664.6 2671.5 2671.5 14698.7 14983.4 14992.8 14999.6 15021.8 7958.8 8110.8 8182.6 8184.4 8188.9 4908.3 5007.3 5083.4 5085.4 5099.0 2276.0 2357.9 2442.2 2445.4 2457.1 25975.6 26337.2 26377.3 26417.6 26487.2 6980.0 7031.3 7101.6 7161.1 7186.7 20134.1 20282.5 20288.7 20354.3 20371.6 7597.9 7694.0 7708.5 7791.6 7796.9 6904.2 6941.4 6987.5 7009.7 7009.7 5527.5 5637.6 5695.6 5764.3 5941.5 1788.5 1804.6 1831.9 1858.4 1878.1 887.7 920.4 923.3 927.9 929.9 7800.1 7822.4 7903.9 7915.5 7917.0 716.5 723.8 727.8 736.9 739.5 4830.4 4989.4 5183.1 5388.9 5586.2 19410.4 20436.0 20453.6 20487.7 20517.4 19967.9 21501.4 21621.7 21722.5 21725.5 10574.7 11311.8 11622.2 11653.9 11666.9 8673.0 8847.8 8963.0 8990.2 8991.2 18344.3 18616.0 18746.4 18863.0 18960.4 19197.5 19323.7 19542.6 19708.0 19974.6 12298.0 12599.5 12606.7 12608.4 12612.7 13569.2 13771.0 13964.5 13975.4 13979.9 11323.3 11336.4 11487.4 11679.5 11779.1 11100.6 11165.7 11199.4 11359.2 11515.6 9786.2 9943.0 9948.0 9948.7 9951.6 6861.5 6911.3 6911.8 6940.2 6974.5 7488.9 7572.5 7591.4 7666.3 7706.9 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | [#4] prototype.html Cycles:180 Average:5496.08 Median:6139.65 stddev:1525.45 (24.8%) stddev-sans-first:1482.81 15:34:06 INFO - PID 3815 | Values: 494.0 497.9 510.5 517.2 520.5 6192.2 6239.6 6266.1 6267.1 6324.0 6024.7 6069.5 6117.5 6188.9 6199.7 4832.8 4919.6 4971.5 5045.0 5113.7 4918.6 4966.8 4980.6 4990.8 4996.6 4857.6 4874.9 4876.0 4890.3 4925.9 4907.1 4912.2 4947.4 4984.9 5001.6 4441.6 4442.8 4461.3 4472.5 4473.3 5995.2 6047.2 6085.5 6161.8 6208.8 5562.0 5586.9 5589.2 5599.4 5669.5 6602.7 6606.5 6627.1 6633.6 6647.4 4810.0 4831.2 4831.8 4844.4 4853.4 6781.9 6791.4 6808.8 6857.0 6909.5 4900.8 4928.4 4935.2 4955.3 4959.0 5002.4 5022.9 5039.5 5052.5 5056.3 5557.7 5568.0 5632.7 5651.5 5657.9 6207.6 6343.5 6393.3 6411.4 6649.3 1870.3 1874.3 1885.8 1887.4 1912.6 1363.6 1400.4 1403.1 1409.8 1411.9 5521.9 5587.6 5644.6 5718.2 5724.9 6656.4 6665.3 6669.1 6693.8 6711.0 5610.5 5657.0 5657.3 5695.5 5754.3 3384.3 3541.7 3555.5 3574.9 3592.4 6386.7 6453.7 6505.3 6509.9 6575.9 6774.4 6830.8 6918.6 6922.1 6946.4 6288.2 6485.7 6513.7 6614.4 6648.6 6660.8 6695.6 6735.4 6765.4 6803.9 6702.6 6715.6 6726.7 6747.9 6775.7 6462.8 6488.8 6557.4 6601.9 6606.8 4915.3 4930.9 4939.8 4990.8 5017.2 6427.6 6452.9 6604.0 6763.8 6809.8 6394.2 6423.3 6468.8 6519.5 6581.8 6476.5 6512.7 6531.0 6534.4 6595.6 6422.2 6432.8 6494.4 6519.8 6525.0 6504.3 6550.6 6605.5 6624.3 6639.2 6353.6 6416.8 6441.4 6441.9 6474.0 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | [#5] yui.html Cycles:180 Average:24083.32 Median:18449.59 stddev:35130.63 (190.4%) stddev-sans-first:35185.95 15:34:06 INFO - PID 3815 | Values: 874.3 906.8 907.2 908.8 937.8 18123.3 19020.9 19160.9 19167.3 19198.9 17785.2 18129.9 18286.5 18380.3 18518.9 958.1 978.9 980.7 981.8 982.0 950.5 979.4 982.7 982.9 983.8 1057.5 1066.3 1066.4 1067.6 1069.2 980.8 1055.8 1061.5 1061.6 1061.9 738.7 748.7 749.3 750.1 750.7 17762.6 18043.2 18084.6 18086.4 18087.1 19219.0 19270.7 20380.6 20532.6 20536.8 17506.1 17595.1 17727.1 17805.8 17892.6 3795.4 3814.9 3819.7 3823.8 3824.1 123087.4 133921.0 134876.3 134943.0 136631.0 16681.2 16707.4 16983.0 16988.5 17084.6 31058.8 31259.7 31323.8 31396.8 31959.4 8554.8 8693.3 8710.8 8715.8 8729.9 4118.2 4184.3 4185.4 4195.0 4195.3 5327.1 5376.7 5403.9 5509.6 5512.3 933.3 939.7 944.2 950.1 951.0 701.9 713.3 718.2 718.8 731.4 10994.5 11296.3 11439.8 12251.3 12456.4 693.6 705.7 720.1 721.0 724.0 5468.2 5513.9 5556.6 5629.2 5637.0 119126.0 122770.0 125545.0 126789.0 128769.4 106770.0 106888.5 107412.0 110759.4 112366.4 96066.6 96900.5 99807.5 101816.0 102026.5 10242.3 10331.6 10393.8 10472.8 10508.0 44180.1 45465.0 45801.3 45983.8 46557.8 55120.7 55588.0 57236.1 57324.1 57537.1 15949.0 15987.5 16203.3 16256.3 16406.5 17543.9 17555.6 17789.9 17803.8 17890.2 15025.2 15168.1 15178.7 15259.5 15273.0 13068.6 13132.6 13147.9 13215.3 13321.1 14911.5 14972.1 15144.1 15194.8 15238.9 11167.6 11198.3 11392.6 11447.4 11478.5 11452.5 11534.7 11710.9 11924.2 12112.6 15:34:06 INFO - PID 3815 | -------- Summary: end -------- 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | 15:34:06 INFO - PID 3815 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 15:34:06 INFO - PID 3815 | 15:34:07 INFO - TEST-INFO | 3815: exit 0 15:34:07 INFO - TEST-OK | dromaeo_css | took 1111888ms 15:34:07 INFO - TEST-START | kraken 15:34:07 INFO - Initialising browser for kraken test... 15:34:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37975/getInfo.html -profile /tmp/tmpxBkgKg/profile 15:34:07 INFO - TEST-INFO | started process 4190 (/builds/slave/test/build/application/firefox/firefox http://localhost:37975/getInfo.html) 15:34:13 INFO - TEST-INFO | 4190: exit 0 15:34:13 INFO - Browser initialized. 15:34:13 INFO - Running cycle 1/1 for kraken test... 15:34:13 INFO - Using env: {'DISPLAY': ':0', 15:34:13 INFO - 'HOME': '/home/cltbld', 15:34:13 INFO - 'JSGC_DISABLE_POISONING': '1', 15:34:13 INFO - 'LANG': 'en_US.UTF-8', 15:34:13 INFO - 'LANGUAGE': 'en_US:en', 15:34:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:34:13 INFO - 'LOGNAME': 'cltbld', 15:34:13 INFO - 'MAIL': '/var/mail/cltbld', 15:34:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:34:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:34:13 INFO - 'MOZ_CRASHREPORTER': '1', 15:34:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:34:13 INFO - 'MOZ_NO_REMOTE': '1', 15:34:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:34:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:13 INFO - 'NO_EM_RESTART': '1', 15:34:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:13 INFO - 'PWD': '/builds/slave/test', 15:34:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:34:13 INFO - 'RUST_BACKTRACE': 'full', 15:34:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:34:13 INFO - 'SHELL': '/bin/bash', 15:34:13 INFO - 'SHLVL': '1', 15:34:13 INFO - 'TERM': 'linux', 15:34:13 INFO - 'TMOUT': '86400', 15:34:13 INFO - 'USER': 'cltbld', 15:34:13 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193', 15:34:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:13 INFO - '_': '/tools/buildbot/bin/python'} 15:34:13 INFO - TEST-INFO | started process 4426 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxBkgKg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:34:14 INFO - PID 4426 | 15:34:14 INFO - PID 4426 | (/builds/slave/test/build/application/firefox/firefox:4475): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:34:14 INFO - PID 4426 | 15:34:24 INFO - PID 4426 | RSS: Main: 174084096 15:34:24 INFO - PID 4426 | 15:34:27 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37975/tests/kraken/driver.html?testName=audio-beat-detection) 15:34:28 INFO - PID 4426 | RSS: Main: 179273728 15:34:28 INFO - PID 4426 | 15:34:31 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37975/tests/kraken/driver.html?testName=audio-dft) 15:34:31 INFO - PID 4426 | RSS: Main: 179429376 15:34:31 INFO - PID 4426 | 15:34:34 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37975/tests/kraken/driver.html?testName=audio-fft) 15:34:35 INFO - PID 4426 | RSS: Main: 175013888 15:34:35 INFO - PID 4426 | 15:34:37 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37975/tests/kraken/driver.html?testName=audio-oscillator) 15:34:38 INFO - PID 4426 | RSS: Main: 175824896 15:34:38 INFO - PID 4426 | 15:34:40 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37975/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:34:40 INFO - PID 4426 | RSS: Main: 175955968 15:34:40 INFO - PID 4426 | 15:34:44 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37975/tests/kraken/driver.html?testName=imaging-darkroom) 15:34:44 INFO - PID 4426 | RSS: Main: 177954816 15:34:44 INFO - PID 4426 | 15:34:48 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37975/tests/kraken/driver.html?testName=imaging-desaturate) 15:34:48 INFO - PID 4426 | RSS: Main: 178278400 15:34:48 INFO - PID 4426 | 15:34:52 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37975/tests/kraken/driver.html?testName=json-parse-financial) 15:34:52 INFO - PID 4426 | RSS: Main: 178995200 15:34:52 INFO - PID 4426 | 15:34:54 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37975/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:34:54 INFO - PID 4426 | RSS: Main: 178548736 15:34:54 INFO - PID 4426 | 15:34:56 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37975/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:34:57 INFO - PID 4426 | RSS: Main: 176140288 15:34:57 INFO - PID 4426 | 15:34:59 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37975/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:34:59 INFO - PID 4426 | RSS: Main: 176091136 15:34:59 INFO - PID 4426 | 15:35:02 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37975/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:35:03 INFO - PID 4426 | RSS: Main: 176521216 15:35:03 INFO - PID 4426 | 15:35:06 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37975/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:35:06 INFO - PID 4426 | RSS: Main: 176246784 15:35:06 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | Cycle 1(1): loaded http://localhost:37975/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37975/tests/kraken/driver.html?testName=ai-astar) 15:35:08 INFO - PID 4426 | RSS: Main: 177786880 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | __start_tp_report 15:35:08 INFO - PID 4426 | _x_x_mozilla_page_load 15:35:08 INFO - PID 4426 | _x_x_mozilla_page_load_details 15:35:08 INFO - PID 4426 | |i|pagename|runs| 15:35:08 INFO - PID 4426 | |0;ai-astar;108;115;107;107;108;110;109;109;106;108 15:35:08 INFO - PID 4426 | |1;audio-beat-detection;117;116;115;118;145;118;117;117;117;115 15:35:08 INFO - PID 4426 | |2;audio-dft;161;161;163;163;162;162;170;166;165;167 15:35:08 INFO - PID 4426 | |3;audio-fft;74;74;73;73;73;75;83;81;75;73 15:35:08 INFO - PID 4426 | |4;audio-oscillator;80;79;78;80;78;79;79;78;78;79 15:35:08 INFO - PID 4426 | |5;imaging-gaussian-blur;114;114;113;115;114;114;114;114;114;113 15:35:08 INFO - PID 4426 | |6;imaging-darkroom;147;148;148;147;148;149;149;149;147;148 15:35:08 INFO - PID 4426 | |7;imaging-desaturate;93;95;94;96;94;95;94;95;93;95 15:35:08 INFO - PID 4426 | |8;json-parse-financial;52;53;53;55;54;53;53;52;53;52 15:35:08 INFO - PID 4426 | |9;json-stringify-tinderbox;39;39;39;39;39;38;38;38;38;38 15:35:08 INFO - PID 4426 | |10;stanford-crypto-aes;77;75;74;74;75;75;75;74;83;81 15:35:08 INFO - PID 4426 | |11;stanford-crypto-ccm;114;117;117;114;117;113;113;111;117;111 15:35:08 INFO - PID 4426 | |12;stanford-crypto-pbkdf2;171;177;167;169;176;164;174;171;167;170 15:35:08 INFO - PID 4426 | |13;stanford-crypto-sha256-iterative;67;64;72;63;64;65;69;68;85;70 15:35:08 INFO - PID 4426 | __end_tp_report 15:35:08 INFO - PID 4426 | __start_cc_report 15:35:08 INFO - PID 4426 | _x_x_mozilla_cycle_collect,913 15:35:08 INFO - PID 4426 | __end_cc_report 15:35:08 INFO - PID 4426 | __startTimestamp1505342108838__endTimestamp 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | ------- Summary: start ------- 15:35:08 INFO - PID 4426 | Number of tests: 14 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#0] ai-astar Cycles:10 Average:108.70 Median:108.50 stddev:2.50 (2.3%) stddev-sans-first:2.64 15:35:08 INFO - PID 4426 | Values: 108.0 115.0 107.0 107.0 108.0 110.0 109.0 109.0 106.0 108.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#1] audio-beat-detection Cycles:10 Average:119.50 Median:117.00 stddev:9.02 (7.7%) stddev-sans-first:9.52 15:35:08 INFO - PID 4426 | Values: 117.0 116.0 115.0 118.0 145.0 118.0 117.0 117.0 117.0 115.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.00 stddev:2.94 (1.8%) stddev-sans-first:2.92 15:35:08 INFO - PID 4426 | Values: 161.0 161.0 163.0 163.0 162.0 162.0 170.0 166.0 165.0 167.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#3] audio-fft Cycles:10 Average:75.40 Median:74.50 stddev:3.60 (4.8%) stddev-sans-first:3.78 15:35:08 INFO - PID 4426 | Values: 74.0 74.0 73.0 73.0 73.0 75.0 83.0 81.0 75.0 73.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:79.00 stddev:0.79 (1.0%) stddev-sans-first:0.71 15:35:08 INFO - PID 4426 | Values: 80.0 79.0 78.0 80.0 78.0 79.0 79.0 78.0 78.0 79.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:35:08 INFO - PID 4426 | Values: 114.0 114.0 113.0 115.0 114.0 114.0 114.0 114.0 114.0 113.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 15:35:08 INFO - PID 4426 | Values: 147.0 148.0 148.0 147.0 148.0 149.0 149.0 149.0 147.0 148.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:95.00 stddev:0.97 (1.0%) stddev-sans-first:0.88 15:35:08 INFO - PID 4426 | Values: 93.0 95.0 94.0 96.0 94.0 95.0 94.0 95.0 93.0 95.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.94 (1.8%) stddev-sans-first:0.93 15:35:08 INFO - PID 4426 | Values: 52.0 53.0 53.0 55.0 54.0 53.0 53.0 52.0 53.0 52.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:39.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 15:35:08 INFO - PID 4426 | Values: 39.0 39.0 39.0 39.0 39.0 38.0 38.0 38.0 38.0 38.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:75.00 stddev:3.16 (4.2%) stddev-sans-first:3.35 15:35:08 INFO - PID 4426 | Values: 77.0 75.0 74.0 74.0 75.0 75.0 75.0 74.0 83.0 81.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#11] stanford-crypto-ccm Cycles:10 Average:114.40 Median:115.50 stddev:2.46 (2.1%) stddev-sans-first:2.60 15:35:08 INFO - PID 4426 | Values: 114.0 117.0 117.0 114.0 117.0 113.0 113.0 111.0 117.0 111.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:171.00 stddev:4.14 (2.4%) stddev-sans-first:4.39 15:35:08 INFO - PID 4426 | Values: 171.0 177.0 167.0 169.0 176.0 164.0 174.0 171.0 167.0 170.0 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.50 stddev:6.43 (9.4%) stddev-sans-first:6.79 15:35:08 INFO - PID 4426 | Values: 67.0 64.0 72.0 63.0 64.0 65.0 69.0 68.0 85.0 70.0 15:35:08 INFO - PID 4426 | -------- Summary: end -------- 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | 15:35:08 INFO - PID 4426 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 15:35:08 INFO - PID 4426 | 15:35:09 INFO - PID 4426 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:35:09 INFO - TEST-INFO | 4426: exit 0 15:35:09 INFO - TEST-OK | kraken | took 62329ms 15:35:09 INFO - SUITE-END | took 1174s 15:35:09 INFO - Completed test suite (00:19:34) 15:35:09 INFO - javascript benchmark 15:35:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8502.653106939062, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3120.3447276072025, 3144.88992885249, 3166.0343595203467, 3191.0267368452614, 3194.6006749156354, 6239.532035097363, 6303.936960630393, 6384.201974753156, 6418.454431373333, 6477.870442591151, 6242.032484964838, 6266.46735027522, 6267.247930248365, 6308.905366419496, 6343.953247714133, 5797.913031304523, 5839.649621022752, 5889.411058894119, 5896.88206235875, 5900.1149827526, 5914.822555323347, 5942.227510423639, 5998.730057147417, 5999.340072592011, 6012.6392416455155, 1178.914421199311, 5780.132980052984, 5784.132380142991, 5793.507551858097, 5898.0, 5839.240898683186, 5847.152162936351, 5916.674582897939, 5930.614510056876, 5931.495822855062, 5820.3888591698105, 5825.0, 5875.676837773921, 5883.941160588425, 5890.263717035371, 6287.591306565058, 6343.270523889765, 6355.428011478943, 6442.48460123189, 6449.451796597248, 6492.805215843485, 6791.694373753193, 6818.079559259486, 6898.241193468714, 6902.758403455855, 6777.830554236134, 6852.691628876662, 6852.965735171292, 6858.6570671466225, 6933.4106600938985, 6364.7135878885565, 6398.9040164397575, 6443.033544968245, 6446.806595802086, 6447.83880402989, 7062.15254169503, 7078.221395646475, 7089.113860767404, 7103.609301488468, 7167.104111986002, 6735.326467353323, 6750.493712971547, 6817.352351526597, 6817.556858804261, 6828.52200345981, 6643.40209381158, 6747.05541224229, 6780.355866192704, 6820.556663816836, 6852.143482064742, 6779.491538134561, 6859.622720750406, 6865.828354291083, 6874.931250687529, 6882.449404047664, 6847.1783385994, 6853.657317134223, 6938.27148149437, 6939.271376505495, 6950.30496950311, 5232.764525596282, 5298.231756395378, 5328.280682107866, 5404.054290499147, 5415.83752487426, 4563.452385713736, 4566.0, 4575.039241759201, 4579.931301030487, 4590.862274131781, 5688.288963879515, 5691.943080569141, 5693.629914055656, 5731.083026715705, 5775.711214439261, 6801.1158549388265, 6855.691493882788, 6883.690233939485, 6891.27641597635, 6891.931080689129, 5803.216565763568, 5813.82558523245, 5878.2064421302575, 5885.146653735184, 5891.882162356729, 5737.397573254748, 5829.242198514166, 5836.387179346192, 5859.2382990211, 5935.0, 6677.699503522354, 6703.095082163943, 6706.094677218513, 6709.127813384229, 6709.966450167718, 6855.383015528527, 6876.243613202644, 6898.758543450955, 6908.516403851682, 6962.025316455599, 6559.639219842955, 6650.600963942179, 6665.433438157811, 6732.0, 6734.225564060196, 6477.902831457626, 6605.966970165118, 6618.404343609098, 6670.433013193933, 6729.730810767514, 6909.827254318682, 6944.305569443015, 6949.444044476329, 6957.339052789976, 6973.5118541701595, 6656.83357916056, 6658.167729033871, 6749.831254218684, 6754.662266886734, 6758.121444212221, 6651.401373876374, 6659.733610655519, 6662.56693314933, 6743.35938085881, 6751.763688270887, 6435.388638079375, 6518.054882041983, 6531.052997315458, 6621.933780662324, 6622.4702023838945, 6702.061711360316, 6772.288909664611, 6790.7623233186605, 6822.692978815866, 6835.7949261522235, 6251.124842521959, 6400.647964361819, 6472.579282346633, 6495.253045899782, 6595.604263744191], "value": 6186.762894674843, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [832.5753865528578, 836.6486075848143, 862.4911302331623, 886.5965985476607, 890.4657205676599, 41966.37050444242, 41999.79000105003, 42224.36663450043, 42396.99999999996, 42840.78579607102, 38606.42090368647, 38929.805350973205, 39132.80433597835, 39232.0, 39246.60753392465, 7102.183248926388, 7188.388986936091, 7225.710971561158, 7228.638568071576, 7232.566046037255, 7132.3580877721, 7225.277472252759, 7247.130344358658, 7248.166460857016, 7251.202367739545, 7198.820029499252, 7226.6025368604705, 7227.494075414723, 7835.490693104958, 8038.799030024267, 7921.920780792205, 7987.08148562914, 7988.800279992988, 7990.760277191694, 7992.3606111510935, 7776.027996500437, 7862.2923936845955, 7864.5281283123195, 7869.055713314382, 7877.409194310392, 39773.0, 40208.39687404691, 40227.0, 40339.394909076385, 40357.394639080434, 3542.291541691646, 3590.461430785377, 3594.11944073701, 3596.658317459863, 3599.2981368633127, 33301.16747081342, 33309.83345083283, 33467.16332091717, 33538.993830185136, 33570.832145839115, 5186.818461353834, 5194.896102077975, 5212.374515058217, 5217.5565076968505, 5265.394479634852, 189628.0, 190574.0, 190950.0, 191012.0, 191481.0, 48399.75800120977, 48414.27378589324, 48479.75760121247, 48616.51383486191, 49019.26471102936, 50789.74605126951, 50992.490075099515, 51027.23459148116, 51066.744666276434, 51281.0, 16370.345186192419, 16622.916885415736, 16711.331546737994, 16716.414925477795, 16732.24704888283, 13708.040437169502, 13712.0, 13716.931415342859, 13722.725545489235, 13723.0, 14345.64135896611, 14471.49349772753, 14514.854851451562, 14621.122732636077, 14639.414423423157, 5866.296044474605, 5885.323187833368, 5913.49735272498, 5935.139404786281, 6071.392860713893, 981.190517822799, 994.5922171909482, 1005.7887843552936, 1017.0592202212923, 1027.0397178637998, 22820.65769013466, 22896.0, 22897.198598048988, 22929.426764330692, 23008.19471318496, 1090.0, 1096.6819622309602, 1106.1261603333276, 1123.1239633086207, 1126.6169502369235, 12049.0, 12055.0, 12074.939625301817, 12189.939050304693, 12249.265044097383, 223844.0, 224198.0, 224299.0, 225398.0, 225688.0, 52965.7351713239, 54224.0, 57436.0, 57611.13583296254, 57628.42371576231, 1404.676924307415, 1440.4382290906556, 1442.2716528153217, 1447.4355001549404, 1456.3155317000992, 26817.195484135507, 27150.0, 27340.863295683394, 27466.45067098687, 27642.58536122, 36539.26921461609, 36901.26197476089, 37069.62930370662, 37243.0, 37279.81360093182, 199838.0, 200184.0, 200212.0, 200462.0, 200616.0, 33737.66262337345, 33766.49350259699, 33970.83014584911, 34021.659783402836, 34103.4884476738, 43106.353404698326, 43333.56666433421, 43547.12905741804, 43705.34441983309, 43795.12409751723, 31471.84264078665, 31555.36889262248, 31589.684103158674, 31644.841775790974, 31919.840400797846, 31058.0, 31505.527417088302, 31550.684493154775, 31751.0, 31824.681753182173, 35104.12239694028, 35153.296934060665, 35561.64438355583, 35596.644033559336, 35790.821045895646, 30633.54049689301, 31140.844295778377, 31157.0, 31166.688333116377, 31324.843375782973, 23109.88445057764, 23174.420639483473, 23185.652215217124, 23278.534429311658, 23411.648825267974], "value": 17267.59243896761, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.7373971103436, 565.2143520506494, 567.6026781253124, 568.7071158353448, 582.8018473718936, 21929.0, 22575.54848903023, 22626.773732262653, 22655.09379624813, 22699.638021718667, 23359.532809343804, 24199.39501512463, 24597.631035534483, 24598.139065132724, 25011.24966251011, 6322.683865806725, 6502.837429064264, 6505.674716264191, 6507.4143327100555, 6517.511186660996, 5942.881142377149, 6334.429901308881, 6397.200349956255, 6429.807105786834, 6445.387688169616, 6423.743050278006, 6456.257530383996, 6456.838579035538, 6457.4511166550665, 6461.1600491936215, 6456.322086180954, 6477.0, 6477.514186436013, 6478.093066970628, 6485.351464853524, 5774.307083150007, 5990.2212712347555, 6051.757929682852, 6061.484773794233, 6065.848353791146, 29450.558241626393, 29946.850265748748, 29983.0, 30031.549526757117, 30066.24834379136, 2068.13138481838, 2098.3495116513777, 2103.463616777727, 2107.8840663763485, 2114.8836813975227, 18246.178921948413, 18547.07264636763, 18776.906115469334, 20180.596388072303, 20356.18575256988, 2439.512097580491, 2465.1372019793107, 2485.3413845331, 2488.029668429314, 2490.402303447178, 110754.0, 114964.0, 132561.3371933134, 137330.3133484336, 137738.0, 14856.480023199352, 15020.549383518512, 15034.248287585795, 15106.697866042618, 15145.939784215, 22944.541109177724, 22954.311370658907, 22967.0, 22972.7702722974, 23001.769982299964, 9655.613775449045, 9726.221902247804, 9880.160186384237, 9942.652007179713, 9943.30396872212, 8809.163129502687, 8820.162084601952, 8963.507007114671, 8988.460692358349, 9005.14451127142, 9012.188902998761, 9144.131307525908, 9474.289428292768, 9477.668281610111, 9561.61753529865, 2622.265765585639, 2690.6098615701007, 2693.0, 2739.849308288063, 2772.0, 966.6568368229177, 975.8194733974237, 979.1530326267728, 991.4943378876825, 992.8758905136858, 19371.3220037298, 19372.2251109954, 19538.413847584594, 19618.0, 19927.20291188365, 1030.3714734012246, 1038.5689938675366, 1041.3387498938077, 1044.6552637629563, 1046.9267151299337, 10751.354918704903, 10757.784844303227, 10870.18473614482, 10898.237123401286, 11167.72080697989, 459708.83248122025, 466688.0, 469604.0, 474919.0, 482850.0, 100116.0, 102239.48880255551, 102767.0, 104945.0, 111051.44474277577, 1036.0571879589538, 1132.5413207650915, 1141.7145713571608, 1184.2775906696909, 1192.976140477203, 20377.083031263926, 20581.0, 20690.58618827585, 20860.58278834445, 21011.949402530117, 54711.0, 54903.0, 55370.16944745913, 56049.71975140098, 56992.14511782244, 65097.34902651102, 66140.0, 66267.0, 66496.66751666211, 66601.0, 12748.171368860994, 13124.40940157676, 13301.0, 13363.3986470611, 13375.53185638498, 21270.78729212688, 21737.130514779863, 22726.431839204153, 23353.182638607566, 23372.0, 18254.81745182531, 18346.082695864887, 18468.076596170406, 18491.167897444917, 18670.346537871108, 15910.12494312824, 16718.58203544921, 16880.324787008874, 16924.66150677004, 17128.914355428144, 17847.553811154306, 18816.6236675263, 18986.43040708781, 19114.808851911304, 19137.808621913602, 13232.669183270495, 13252.801207981694, 13376.130551514121, 13384.063115582205, 13438.529651462151, 18202.180901859712, 18223.0, 18338.449846504627, 18375.632487350445, 18580.44258672242], "value": 11775.212526856545, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.250307324825, 1349.4602159136339, 1351.4796803230754, 1356.3421740455876, 1369.6438925879268, 12375.44310506027, 12968.610941671754, 12977.935110324446, 15254.0, 15748.0, 15484.845151548481, 15756.527304180865, 15770.84229157708, 15779.21103944801, 15782.447614333501, 2562.1032638576535, 2613.4903693779675, 2657.7209393013745, 2661.7072122066556, 2662.9467410651773, 2675.1172113202692, 2692.9596056059177, 2698.581719833429, 2701.1086341507353, 2701.2031450722084, 2613.5687611544167, 2638.907638232662, 2644.338915271182, 2715.945681086377, 2782.596523504091, 2633.6971248306404, 2640.0231914191872, 2642.4582960493053, 2642.748938850806, 2657.1231493607065, 2651.7348265173523, 2660.507806055886, 2664.5736682130764, 2671.479061582992, 2671.519126557219, 14698.70602587953, 14983.400663973427, 14992.775108373275, 14999.550013499613, 15021.849781502155, 7958.801029974239, 8110.797230069295, 8182.590870456454, 8184.427090103695, 8188.918110818935, 4908.288298196741, 5007.349044624212, 5083.38999320084, 5085.364329458817, 5099.0, 2276.0, 2357.905683772647, 2442.206282958046, 2445.400876785199, 2457.1154384421593, 25975.610365844528, 26337.20988370352, 26377.3405664858, 26417.603735943976, 26487.205383838515, 6980.0, 7031.29687031303, 7101.609411482418, 7161.1406631203545, 7186.7125314986815, 20134.093965771575, 20282.492937676678, 20288.695669564968, 20354.28759993393, 20371.592568148553, 7597.886031709529, 7694.0, 7708.537487750753, 7791.610419479003, 7796.883046754303, 6904.171499420102, 6941.375276225164, 6987.510874238857, 7009.684564194624, 7009.719611215494, 5527.474889885454, 5637.56242158247, 5695.601307908407, 5764.337101233328, 5941.524678025748, 1788.4634609617062, 1804.5939663575853, 1831.9175637096362, 1858.3681548273653, 1878.1078987480837, 887.7070566712969, 920.3925409229876, 923.316745608245, 927.9025702301296, 929.8837645294338, 7800.102988156321, 7822.413319000984, 7903.881441778378, 7915.525068495908, 7917.0, 716.4662326566742, 723.7611588175889, 727.8253219227347, 736.8710475666737, 739.4934469888144, 4830.420349558076, 4989.426215985208, 5183.144781111075, 5388.865278368072, 5586.24585680936, 19410.417687469115, 20436.0, 20453.590928181355, 20487.69268460974, 20517.38447846567, 19967.900160499394, 21501.354959351243, 21621.67567486489, 21722.456938576663, 21725.45686357822, 10574.68275951738, 11311.773764524663, 11622.244554103956, 11653.88346116528, 11666.941665291619, 8673.0, 8847.778805529655, 8963.0, 8990.235829954527, 8991.23574496149, 18344.266229350676, 18616.0, 18746.437606871816, 18863.0, 18960.43118706441, 19197.520061998563, 19323.710144348126, 19542.60914781725, 19708.014599270267, 19974.600507989468, 12298.0, 12599.496020159457, 12606.747865042464, 12608.432620532281, 12612.684682883002, 13569.1858488491, 13771.0, 13964.511242106884, 13975.371108300355, 13979.860201397856, 11323.263987840764, 11336.433178341214, 11487.36819474937, 11679.532818687157, 11779.116566257393, 11100.61147859821, 11165.665030049111, 11199.384033878216, 11359.204855660024, 11515.596954106566, 9786.168175705145, 9943.0, 9947.950260248941, 9948.701538953843, 9951.552180151766, 6861.485388595976, 6911.308869113049, 6911.827204319932, 6940.236573976757, 6974.4769142315545, 7488.850222995618, 7572.545647261182, 7591.430642701709, 7666.310032097138, 7706.922930770621], "value": 6726.883720911329, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.00276795325254, 497.8599891769567, 510.45126489024307, 517.1983425690179, 520.45092427489, 6192.164057852188, 6239.594426362283, 6266.054954171544, 6267.122602835607, 6323.968380158104, 6024.728887200075, 6069.544784141185, 6117.480014198798, 6188.907166392496, 6199.69001549923, 4832.806687732486, 4919.581835543983, 4971.527704868032, 5044.974775126119, 5113.693178409298, 4918.557329840313, 4966.751662416883, 4980.626453016021, 4990.777259571392, 4996.5503104720565, 4857.587105096071, 4874.926876096843, 4875.9760450305475, 4890.310329318861, 4925.901481970376, 4907.1412502812045, 4912.164931961557, 4947.406311242638, 4984.850454486371, 5001.624878134118, 4441.600255976945, 4442.8222871085445, 4461.308497182942, 4472.46330440346, 4473.329000649896, 5995.160677505153, 6047.183630209909, 6085.4523092921845, 6161.815145545596, 6208.782692605782, 5561.972190139064, 5586.86032849182, 5589.245451864028, 5599.384067752544, 5669.518090962273, 6602.7028783704845, 6606.471482281454, 6627.138471998657, 6633.635150066792, 6647.401733843929, 4809.975950120227, 4831.178699621073, 4831.782569784333, 4844.442889067767, 4853.417589889201, 6781.93218067813, 6791.366618858936, 6808.795736127924, 6856.965715171392, 6909.481788865804, 4900.828471003498, 4928.359313289249, 4935.161022626162, 4955.2567114933045, 4959.04117873438, 5002.399712034507, 5022.924656130161, 5039.521245481674, 5052.520010598987, 5056.292119103255, 5557.666540007613, 5568.021108706823, 5632.746526406321, 5651.547876169897, 5657.886842263131, 6207.6275423474735, 6343.5278128919, 6393.264774550986, 6411.4229719325485, 6649.271189490671, 1870.289290069792, 1874.3158747057225, 1885.792562818089, 1887.3865993552151, 1912.5792325688326, 1363.6454521824396, 1400.3698335748954, 1403.1440821099122, 1409.8096756937682, 1411.9011669183265, 5521.861953451196, 5587.580931430159, 5644.57665675076, 5718.199452076713, 5724.856878578069, 6656.36764507371, 6665.300143484961, 6669.066330713704, 6693.799186024427, 6710.966445167743, 5610.5231055360755, 5656.9817432861655, 5657.321121465368, 5695.5443564514735, 5754.338251101093, 3384.323135372886, 3541.6635419635095, 3555.466679998021, 3574.8748793792092, 3592.3533763922746, 6386.680665966776, 6453.676996215764, 6505.349465053457, 6509.9023514647315, 6575.928582496498, 6774.42417394527, 6830.760923367758, 6918.619475928872, 6922.134733158356, 6946.37482626566, 6288.245410550763, 6485.70814313357, 6513.706883190173, 6614.404703576702, 6648.634325112069, 6660.800175994728, 6695.631740254333, 6735.4274886635185, 6765.391114799495, 6803.863922721617, 6702.597844129368, 6715.56348837324, 6726.663666816542, 6747.932520674927, 6775.695093720893, 6462.773802916875, 6488.837779055561, 6557.409833115043, 6601.867962640624, 6606.8017959461295, 4915.311856340187, 4930.91519865629, 4939.753012349296, 4990.775415106256, 5017.197248440232, 6427.614343139427, 6452.870942581216, 6604.0, 6763.782519146601, 6809.761658341934, 6394.23269207698, 6423.293437721753, 6468.838279043061, 6519.543631945908, 6581.8279967609615, 6476.514261430318, 6512.674366281762, 6530.967345163243, 6534.444572211415, 6595.604263744191, 6422.197225346832, 6432.774852880127, 6494.383033611799, 6519.761245363366, 6525.0, 6504.252011018793, 6550.639714815731, 6605.451420806546, 6624.271330153776, 6639.170103737033, 6353.5870168438905, 6416.7754128605275, 6441.355864413708, 6441.874181519994, 6473.967630161819], "value": 5061.483770382436, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [874.2656168818191, 906.8367693815113, 907.2288554728482, 908.7818923458372, 937.756183392318, 18123.27506899724, 19020.90489547552, 19160.904195479005, 19167.329143479998, 19198.904005479988, 17785.19966601506, 18129.90935045326, 18286.451406457792, 18380.264789408408, 18518.90740546299, 958.0898146760588, 978.8580655804903, 980.745006298364, 981.8330883749779, 982.017982017982, 950.4772375193616, 979.3732011512637, 982.6609819612222, 982.9262805289596, 983.7835676151235, 1057.4924036462503, 1066.2536224642781, 1066.402814423925, 1067.647676266834, 1069.1820757120809, 980.8283550378726, 1055.7941201465717, 1061.4745700878077, 1061.5859814672283, 1061.851340812287, 738.6565247160096, 748.7304570354668, 749.2844333661341, 750.0511852506584, 750.6734570461866, 17762.555936101573, 18043.18805653749, 18084.638307233912, 18086.366977155736, 18087.095645217818, 19219.0, 19270.71093933592, 20380.5923881523, 20532.589348213103, 20536.79463205364, 17506.12469376526, 17595.12024398775, 17727.11364431773, 17805.821941780545, 17892.55268618282, 3795.35478968576, 3814.8664796731982, 3819.7135214858718, 3823.847046118152, 3824.082220267144, 123087.3845630775, 133921.0, 134876.32561837227, 134943.0, 136631.0, 16681.165941702868, 16707.415240466526, 16983.0, 16988.49034528966, 17084.572885677957, 31058.844705776777, 31259.687403126132, 31323.843380783404, 31396.843015784776, 31959.360812783612, 8554.828903421896, 8693.261072808882, 8710.782230444163, 8715.825683486295, 8729.869051964226, 4118.155778065489, 4184.267753143188, 4185.351270553069, 4194.979025104916, 4195.265828480065, 5327.147656374961, 5376.704281264568, 5403.9459605403445, 5509.641873278224, 5512.2558454608115, 933.3140141995627, 939.7321763297564, 944.2210176604328, 950.1306304731231, 951.048951048951, 701.929807019294, 713.3437237741186, 718.1633397092329, 718.8382613911828, 731.3673672273445, 10994.505247263893, 11296.322220666787, 11439.828402573969, 12251.32617706017, 12456.439460224312, 693.6219760230687, 705.6648092156184, 720.0711082703359, 720.9639518024078, 723.9565626062348, 5468.179773034077, 5513.862153446196, 5556.555475561945, 5629.155626655953, 5637.041702910515, 119126.0, 122770.0, 125545.0, 126789.0, 128769.35615321863, 106770.0, 106888.46555767172, 107412.0, 110759.44620277008, 112366.43816781028, 96066.55900161504, 96900.51549742348, 99807.50096249473, 101816.0, 102026.48986755019, 10242.334248273839, 10331.586736530455, 10393.792124157324, 10472.842907356244, 10508.0, 44180.11639767251, 45465.0, 45801.312980304654, 45983.77008115072, 46557.76721116373, 55120.72439637776, 55588.0, 57236.14145787733, 57324.14013789713, 57537.13694294672, 15948.963317384332, 15987.520374388787, 16203.270852811416, 16256.349746010028, 16406.507804765875, 17543.912280438515, 17555.56111097233, 17789.911050444665, 17803.821961780217, 17890.194941227935, 15025.173615451255, 15168.08991460516, 15178.696426071638, 15259.465918693237, 15273.0, 13068.607941761762, 13132.62107478701, 13147.868521314664, 13215.273159976294, 13321.13412628176, 14911.478098266509, 14972.1016738996, 15144.091354518765, 15194.772078419053, 15238.923805380902, 11167.609133680282, 11198.328100314007, 11392.601258956229, 11447.427628618703, 11478.483468243781, 11452.541898324307, 11534.711632209262, 11710.94144529306, 11924.165308428326, 12112.636620901387], "value": 8821.564740041003, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1424.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 107.0, 107.0, 108.0, 110.0, 109.0, 109.0, 106.0, 108.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 115.0, 118.0, 145.0, 118.0, 117.0, 117.0, 117.0, 115.0], "value": 119.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 163.0, 162.0, 162.0, 170.0, 166.0, 165.0, 167.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 73.0, 73.0, 75.0, 83.0, 81.0, 75.0, 73.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 78.0, 80.0, 78.0, 79.0, 79.0, 78.0, 78.0, 79.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 148.0, 149.0, 149.0, 149.0, 147.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 96.0, 94.0, 95.0, 94.0, 95.0, 93.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 55.0, 54.0, 53.0, 53.0, 52.0, 53.0, 52.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 39.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 74.0, 74.0, 75.0, 75.0, 75.0, 74.0, 83.0, 81.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 117.0, 114.0, 117.0, 113.0, 113.0, 111.0, 117.0, 111.0], "value": 114.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 177.0, 167.0, 169.0, 176.0, 164.0, 174.0, 171.0, 167.0, 170.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 72.0, 63.0, 64.0, 65.0, 69.0, 68.0, 85.0, 70.0], "value": 68.7, "unit": "score"}]}]} 15:35:10 INFO - Return code: 0 15:35:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:35:10 INFO - # TBPL SUCCESS # 15:35:10 INFO - Running post-action listener: _package_coverage_data 15:35:10 INFO - Running post-action listener: _resource_record_post_action 15:35:10 INFO - [mozharness: 2017-09-13 22:35:10.092118Z] Finished run-tests step (success) 15:35:10 INFO - Running post-run listener: _resource_record_post_run 15:35:10 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 9744384; Write bytes: 479989760; Read time: 3664; Write time: 398196 15:35:10 INFO - TinderboxPrint: CPU usage
13.1% 15:35:10 INFO - TinderboxPrint: I/O read bytes / time
9,744,384 / 3,664 15:35:10 INFO - TinderboxPrint: I/O write bytes / time
479,989,760 / 398,196 15:35:10 INFO - TinderboxPrint: CPU idle
8,261.0 (86.8%) 15:35:10 INFO - TinderboxPrint: CPU user
1,194.9 (12.6%) 15:35:10 INFO - TinderboxPrint: Swap in / out
0 / 0 15:35:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 104943616; Read time: 0; Write time: 60844 15:35:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:35:10 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 7512064; Write bytes: 302170112; Read time: 3088; Write time: 218492 15:35:10 INFO - Running post-run listener: _upload_blobber_files 15:35:10 INFO - Blob upload gear active. 15:35:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:35:10 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:35:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:35:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:35:11 INFO - (blobuploader) - INFO - Open directory for files ... 15:35:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:35:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:35:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:35:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:35:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:35:18 INFO - (blobuploader) - INFO - Done attempting. 15:35:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:35:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:35:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:35:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:35:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:35:19 INFO - (blobuploader) - INFO - Done attempting. 15:35:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:35:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:35:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:35:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:35:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:35:20 INFO - (blobuploader) - INFO - Done attempting. 15:35:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:35:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:35:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:35:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:35:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:35:21 INFO - (blobuploader) - INFO - Done attempting. 15:35:21 INFO - (blobuploader) - INFO - Iteration through files over. 15:35:21 INFO - Return code: 0 15:35:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:35:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:35:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92d1c18b373c3f2878029059ff13cb857b35d3cb400c33390d79a0f9fa48cde406fd199359074dfb6a83029e3c830ead1e8effa67432226d26293b71a7a2dd1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9d0f6aa2beaeed4bf473a0dfaa4a44757204ae70d2317f9a19852de26ceae95c320573cd81330eb9958dd4f9c8ec5b121f2ac4c76235b6fde99f52daacc80ef", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f272a32b1be8cdc88279c4f949e30d6f64447725e8f74c071ac2acab9ff8b5178f14772cc5919e5382d54a22f444d4807d787463fddeb5c658478333a81a5708", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2891a975704da3f9ec938bfc99b8e03cc68416a5d1b76c606c71613c998cf9cf66b8e4bdee32cd452ada14d8aa7f514f20a666b60b4460ab165e16d070a216a"} 15:35:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:35:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:35:21 INFO - Contents: 15:35:21 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92d1c18b373c3f2878029059ff13cb857b35d3cb400c33390d79a0f9fa48cde406fd199359074dfb6a83029e3c830ead1e8effa67432226d26293b71a7a2dd1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9d0f6aa2beaeed4bf473a0dfaa4a44757204ae70d2317f9a19852de26ceae95c320573cd81330eb9958dd4f9c8ec5b121f2ac4c76235b6fde99f52daacc80ef", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f272a32b1be8cdc88279c4f949e30d6f64447725e8f74c071ac2acab9ff8b5178f14772cc5919e5382d54a22f444d4807d787463fddeb5c658478333a81a5708", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2891a975704da3f9ec938bfc99b8e03cc68416a5d1b76c606c71613c998cf9cf66b8e4bdee32cd452ada14d8aa7f514f20a666b60b4460ab165e16d070a216a"} 15:35:21 INFO - Running post-run listener: copy_logs_to_upload_dir 15:35:21 INFO - Copying logs to upload dir... 15:35:21 INFO - mkdir: /builds/slave/test/build/upload/logs 15:35:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.804378 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-13 15:35:21.720152) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 15:35:21.724874) ========= 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=cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92d1c18b373c3f2878029059ff13cb857b35d3cb400c33390d79a0f9fa48cde406fd199359074dfb6a83029e3c830ead1e8effa67432226d26293b71a7a2dd1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9d0f6aa2beaeed4bf473a0dfaa4a44757204ae70d2317f9a19852de26ceae95c320573cd81330eb9958dd4f9c8ec5b121f2ac4c76235b6fde99f52daacc80ef", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f272a32b1be8cdc88279c4f949e30d6f64447725e8f74c071ac2acab9ff8b5178f14772cc5919e5382d54a22f444d4807d787463fddeb5c658478333a81a5708", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2891a975704da3f9ec938bfc99b8e03cc68416a5d1b76c606c71613c998cf9cf66b8e4bdee32cd452ada14d8aa7f514f20a666b60b4460ab165e16d070a216a"} build_url:https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/QBzmMKkgReqgjM-CLAYvGQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92d1c18b373c3f2878029059ff13cb857b35d3cb400c33390d79a0f9fa48cde406fd199359074dfb6a83029e3c830ead1e8effa67432226d26293b71a7a2dd1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9d0f6aa2beaeed4bf473a0dfaa4a44757204ae70d2317f9a19852de26ceae95c320573cd81330eb9958dd4f9c8ec5b121f2ac4c76235b6fde99f52daacc80ef", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f272a32b1be8cdc88279c4f949e30d6f64447725e8f74c071ac2acab9ff8b5178f14772cc5919e5382d54a22f444d4807d787463fddeb5c658478333a81a5708", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2891a975704da3f9ec938bfc99b8e03cc68416a5d1b76c606c71613c998cf9cf66b8e4bdee32cd452ada14d8aa7f514f20a666b60b4460ab165e16d070a216a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 15:35:21.755691) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:35:21.756073) ========= 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=cbe409c6de289db6ac707de3000001d4-1505340871.351454-1288719193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:35:21.836248) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 15:35:21.839562) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 15:35:21.839865) ========= ========= Total master_lag: 0.20 =========