builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-004 starttime: 1505504424.96 results: success (0) revision: 7aceaf8bcb9f582db0f93488b48ef7019e348dba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:24.963511) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:24.963899) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:24.964213) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.009340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.009642) ========= 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=c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 12:40:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.6M=0s 2017-09-15 12:40:25 (45.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122663 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.149505) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.150897) ========= 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=c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019926 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.204793) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.205178) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7aceaf8bcb9f582db0f93488b48ef7019e348dba --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 7aceaf8bcb9f582db0f93488b48ef7019e348dba --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=c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 12:40:25,275 truncating revision to first 12 chars 2017-09-15 12:40:25,275 Setting DEBUG logging. 2017-09-15 12:40:25,275 attempt 1/10 2017-09-15 12:40:25,275 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7aceaf8bcb9f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 12:40:25,659 unpacking tar archive at: mozilla-central-7aceaf8bcb9f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.672871 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.897184) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.897551) ========= script_repo_revision: 7aceaf8bcb9f582db0f93488b48ef7019e348dba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.898040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.898382) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 12:40:25.911715) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-15 12:40:25.912139) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:40:26 INFO - MultiFileLogger online at 20170915 12:40:26 in /builds/slave/test 12:40:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:40:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:40:26 INFO - {'append_to_log': False, 12:40:26 INFO - 'base_work_dir': '/builds/slave/test', 12:40:26 INFO - 'blob_upload_branch': 'Firefox', 12:40:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:40:26 INFO - 'branch': 'Firefox', 12:40:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:40:26 INFO - 'code_coverage': False, 12:40:26 INFO - 'config_files': ('talos/linux_config.py',), 12:40:26 INFO - 'default_actions': ('clobber', 12:40:26 INFO - 'read-buildbot-config', 12:40:26 INFO - 'download-and-extract', 12:40:26 INFO - 'populate-webroot', 12:40:26 INFO - 'create-virtualenv', 12:40:26 INFO - 'install', 12:40:26 INFO - 'setup-mitmproxy', 12:40:26 INFO - 'run-tests'), 12:40:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:40:26 INFO - 'disable_ccov_upload': False, 12:40:26 INFO - 'disable_stylo': False, 12:40:26 INFO - 'download_minidump_stackwalk': True, 12:40:26 INFO - 'download_symbols': 'ondemand', 12:40:26 INFO - 'enable_stylo': False, 12:40:26 INFO - 'enable_webrender': False, 12:40:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:40:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:40:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:40:26 INFO - 'gecko_profile': False, 12:40:26 INFO - 'gecko_profile_interval': 0, 12:40:26 INFO - 'installer_path': 'installer.exe', 12:40:26 INFO - 'log_level': 'info', 12:40:26 INFO - 'log_name': 'talos', 12:40:26 INFO - 'log_to_console': True, 12:40:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:40:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:40:26 INFO - 'opt_config_files': (), 12:40:26 INFO - 'pip_index': False, 12:40:26 INFO - 'suite': 'dromaeojs-e10s', 12:40:26 INFO - 'system_bits': '32', 12:40:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:40:26 INFO - 'title': 'talos-linux64-ix-004', 12:40:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:40:26 INFO - 'use_talos_json': True, 12:40:26 INFO - 'verify': 'False', 12:40:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:40:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:40:26 INFO - 'work_dir': 'build'} 12:40:26 INFO - [mozharness: 2017-09-15 19:40:26.136734Z] Running clobber step. 12:40:26 INFO - Running pre-action listener: _resource_record_pre_action 12:40:26 INFO - Running main action method: clobber 12:40:26 INFO - rmtree: /builds/slave/test/build 12:40:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:40:27 INFO - Running post-action listener: _resource_record_post_action 12:40:27 INFO - [mozharness: 2017-09-15 19:40:27.144285Z] Finished clobber step (success) 12:40:27 INFO - [mozharness: 2017-09-15 19:40:27.144382Z] Running read-buildbot-config step. 12:40:27 INFO - Running pre-action listener: _resource_record_pre_action 12:40:27 INFO - Running main action method: read_buildbot_config 12:40:27 INFO - Using buildbot properties: 12:40:27 INFO - { 12:40:27 INFO - "project": "", 12:40:27 INFO - "product": "firefox", 12:40:27 INFO - "who": "ryanvm@gmail.com", 12:40:27 INFO - "installer_path": "public/build/target.tar.bz2", 12:40:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:40:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:40:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:40:27 INFO - "stage_platform": "linux64-pgo", 12:40:27 INFO - "basedir": "/builds/slave/test", 12:40:27 INFO - "buildnumber": 165, 12:40:27 INFO - "platform": "ubuntu64_hw", 12:40:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:40:27 INFO - "slavebuilddir": "test", 12:40:27 INFO - "taskId": "GhHjTNLDSfaF30vn0uvfTg", 12:40:27 INFO - "branch": "mozilla-central", 12:40:27 INFO - "script_repo_revision": "production", 12:40:27 INFO - "revision": "7aceaf8bcb9f582db0f93488b48ef7019e348dba", 12:40:27 INFO - "slavename": "talos-linux64-ix-004", 12:40:27 INFO - "repo_path": "mozilla-central" 12:40:27 INFO - } 12:40:27 INFO - Finding installer, test and symbols from parent task. 12:40:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GhHjTNLDSfaF30vn0uvfTg'}, attempt #1 12:40:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GhHjTNLDSfaF30vn0uvfTg'}, attempt #1 12:40:28 INFO - Task dependencies: Yrrp_8EOT5an_C13Djd2RA 12:40:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA'}, attempt #1 12:40:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.tar.bz2 12:40:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.test_packages.json 12:40:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.crashreporter-symbols.zip 12:40:28 INFO - Running post-action listener: _resource_record_post_action 12:40:28 INFO - [mozharness: 2017-09-15 19:40:28.540151Z] Finished read-buildbot-config step (success) 12:40:28 INFO - [mozharness: 2017-09-15 19:40:28.540370Z] Running download-and-extract step. 12:40:28 INFO - Running pre-action listener: _resource_record_pre_action 12:40:28 INFO - Running main action method: download_and_extract 12:40:28 INFO - mkdir: /builds/slave/test/build/tests 12:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:28 INFO - trying https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.test_packages.json 12:40:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:40:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:40:29 INFO - Downloaded 989 bytes. 12:40:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:40:29 INFO - Using the following test package requirements: 12:40:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:40:29 INFO - u'common': [u'target.common.tests.zip'], 12:40:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:40:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:40:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:40:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:40:29 INFO - u'mozbase': [u'target.common.tests.zip'], 12:40:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:40:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:40:29 INFO - u'web-platform': [u'target.common.tests.zip', 12:40:29 INFO - u'target.web-platform.tests.tar.gz'], 12:40:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:40:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:40:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.common.tests.zip 12:40:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:40:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.common.tests.zip into memory 12:40:31 INFO - Content-Length response header: 55328870 12:40:31 INFO - Bytes received: 55328870 12:40:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.talos.tests.zip 12:40:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:40:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.talos.tests.zip into memory 12:40:44 INFO - Content-Length response header: 14508749 12:40:44 INFO - Bytes received: 14508749 12:40:44 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')]} 12:40:44 INFO - trying https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.tar.bz2 12:40:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:40:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:40:48 INFO - Downloaded 62238139 bytes. 12:40:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.tar.bz2 12:40:48 INFO - mkdir: /builds/slave/test/properties 12:40:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:40:48 INFO - Writing to file /builds/slave/test/properties/build_url 12:40:48 INFO - Contents: 12:40:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.tar.bz2 12:40:48 INFO - Running post-action listener: _resource_record_post_action 12:40:48 INFO - Running post-action listener: find_tests_for_verification 12:40:48 INFO - Running post-action listener: set_extra_try_arguments 12:40:48 INFO - [mozharness: 2017-09-15 19:40:48.380266Z] Finished download-and-extract step (success) 12:40:48 INFO - [mozharness: 2017-09-15 19:40:48.380395Z] Running populate-webroot step. 12:40:48 INFO - Running pre-action listener: _resource_record_pre_action 12:40:48 INFO - Running main action method: populate_webroot 12:40:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:40:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['tresize', 12:40:48 INFO - 'tcanvasmark']}, 12:40:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:40:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['dromaeo_css', 12:40:48 INFO - 'kraken']}, 12:40:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:40:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:40:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:40:48 INFO - 'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:40:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:40:48 INFO - 'tests': ['damp', 'tps']}, 12:40:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:40:48 INFO - 'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['damp', 'tps']}, 12:40:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:40:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['dromaeo_dom']}, 12:40:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:40:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['basic_compositor_video', 12:40:48 INFO - 'glvideo']}, 12:40:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:40:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:40:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:40:48 INFO - 'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['ts_paint_webext', 12:40:48 INFO - 'tp5o_webext']}, 12:40:48 INFO - 'other-e10s': {'tests': ['a11yr', 12:40:48 INFO - 'ts_paint', 12:40:48 INFO - 'tpaint', 12:40:48 INFO - 'sessionrestore', 12:40:48 INFO - 'sessionrestore_many_windows', 12:40:48 INFO - 'sessionrestore_no_auto_restore', 12:40:48 INFO - 'tabpaint']}, 12:40:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['a11yr', 12:40:48 INFO - 'ts_paint', 12:40:48 INFO - 'tpaint', 12:40:48 INFO - 'sessionrestore', 12:40:48 INFO - 'sessionrestore_many_windows', 12:40:48 INFO - 'sessionrestore_no_auto_restore', 12:40:48 INFO - 'tabpaint']}, 12:40:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:40:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:40:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['perf_reftest_singletons']}, 12:40:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['bloom_basic']}, 12:40:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:40:48 INFO - 'tsvgr_opacity', 12:40:48 INFO - 'tart', 12:40:48 INFO - 'tscrollx', 12:40:48 INFO - 'tsvg_static']}, 12:40:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['tsvgx', 12:40:48 INFO - 'tsvgr_opacity', 12:40:48 INFO - 'tart', 12:40:48 INFO - 'tscrollx', 12:40:48 INFO - 'tsvg_static']}, 12:40:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:40:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:40:48 INFO - 'talos_options': ['--disable-stylo'], 12:40:48 INFO - 'tests': ['tp5o']}, 12:40:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:40:48 INFO - 'talos_options': ['--mitmproxy', 12:40:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:40:48 INFO - '--firstNonBlankPaint'], 12:40:48 INFO - 'tests': ['tp6_google', 12:40:48 INFO - 'tp6_youtube', 12:40:48 INFO - 'tp6_amazon', 12:40:48 INFO - 'tp6_facebook']}, 12:40:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:40:48 INFO - 'talos_options': ['--disable-stylo', 12:40:48 INFO - '--mitmproxy', 12:40:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:40:48 INFO - '--firstNonBlankPaint'], 12:40:48 INFO - 'tests': ['tp6_google', 12:40:48 INFO - 'tp6_youtube', 12:40:48 INFO - 'tp6_amazon', 12:40:48 INFO - 'tp6_facebook']}, 12:40:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:40:48 INFO - 'talos_options': ['--stylo-threads=1', 12:40:48 INFO - '--mitmproxy', 12:40:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:40:48 INFO - '--firstNonBlankPaint'], 12:40:48 INFO - 'tests': ['tp6_google', 12:40:48 INFO - 'tp6_youtube', 12:40:48 INFO - 'tp6_amazon', 12:40:48 INFO - 'tp6_facebook']}, 12:40:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:40:48 INFO - 'talos_options': ['--xperf_path', 12:40:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:40:48 INFO - 'tests': ['tp5n']}, 12:40:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:40:48 INFO - 'talos_options': ['--disable-stylo', 12:40:48 INFO - '--xperf_path', 12:40:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:40:48 INFO - 'tests': ['tp5n']}}} 12:40:48 INFO - Running post-action listener: _resource_record_post_action 12:40:48 INFO - [mozharness: 2017-09-15 19:40:48.413912Z] Finished populate-webroot step (success) 12:40:48 INFO - [mozharness: 2017-09-15 19:40:48.414006Z] Running create-virtualenv step. 12:40:48 INFO - Running pre-action listener: _resource_record_pre_action 12:40:48 INFO - Running main action method: create_virtualenv 12:40:48 INFO - Creating virtualenv /builds/slave/test/build/venv 12:40:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:40:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:40:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:40:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:40:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:40:49 INFO - Installing distribute.............................................................................................................................................................................................done. 12:40:49 INFO - Installing pip...............done. 12:40:49 INFO - Return code: 0 12:40:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:40:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:40:49 INFO - Reading from file tmpfile_stdout 12:40:49 INFO - Output received: 12:40:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:40:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:40:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:40:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:40:49 INFO - Using env: {'DISPLAY': ':0', 12:40:49 INFO - 'HOME': '/home/cltbld', 12:40:49 INFO - 'LANG': 'en_US.UTF-8', 12:40:49 INFO - 'LANGUAGE': 'en_US:en', 12:40:49 INFO - 'LOGNAME': 'cltbld', 12:40:49 INFO - 'MAIL': '/var/mail/cltbld', 12:40:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:49 INFO - 'MOZ_NO_REMOTE': '1', 12:40:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:49 INFO - 'NO_EM_RESTART': '1', 12:40:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:49 INFO - 'PWD': '/builds/slave/test', 12:40:49 INFO - 'SHELL': '/bin/bash', 12:40:49 INFO - 'SHLVL': '1', 12:40:49 INFO - 'TERM': 'linux', 12:40:49 INFO - 'TMOUT': '86400', 12:40:49 INFO - 'USER': 'cltbld', 12:40:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:40:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:49 INFO - '_': '/tools/buildbot/bin/python'} 12:40:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:40:50 INFO - Downloading/unpacking pip>=1.5 12:40:50 INFO - Running setup.py egg_info for package pip 12:40:50 INFO - warning: no files found matching 'pip/cacert.pem' 12:40:50 INFO - warning: no files found matching '*.html' under directory 'docs' 12:40:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:40:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:40:50 INFO - Installing collected packages: pip 12:40:50 INFO - Found existing installation: pip 1.1 12:40:50 INFO - Uninstalling pip: 12:40:50 INFO - Successfully uninstalled pip 12:40:50 INFO - Running setup.py install for pip 12:40:50 INFO - warning: no files found matching 'pip/cacert.pem' 12:40:50 INFO - warning: no files found matching '*.html' under directory 'docs' 12:40:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:40:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:40:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:40:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:40:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:40:50 INFO - Successfully installed pip 12:40:50 INFO - Cleaning up... 12:40:50 INFO - Return code: 0 12:40:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:40:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:40:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:40:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:40:50 INFO - Using env: {'DISPLAY': ':0', 12:40:50 INFO - 'HOME': '/home/cltbld', 12:40:50 INFO - 'LANG': 'en_US.UTF-8', 12:40:50 INFO - 'LANGUAGE': 'en_US:en', 12:40:50 INFO - 'LOGNAME': 'cltbld', 12:40:50 INFO - 'MAIL': '/var/mail/cltbld', 12:40:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:50 INFO - 'MOZ_NO_REMOTE': '1', 12:40:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:50 INFO - 'NO_EM_RESTART': '1', 12:40:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:50 INFO - 'PWD': '/builds/slave/test', 12:40:50 INFO - 'SHELL': '/bin/bash', 12:40:50 INFO - 'SHLVL': '1', 12:40:50 INFO - 'TERM': 'linux', 12:40:50 INFO - 'TMOUT': '86400', 12:40:50 INFO - 'USER': 'cltbld', 12:40:50 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:40:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:50 INFO - '_': '/tools/buildbot/bin/python'} 12:40:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:50 INFO - Downloading/unpacking psutil>=3.1.1 12:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:40:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:52 INFO - Installing collected packages: psutil 12:40:52 INFO - Running setup.py install for psutil 12:40:52 INFO - building 'psutil._psutil_linux' extension 12:40:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:40:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:40:52 INFO - building 'psutil._psutil_posix' extension 12:40:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:40:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:40:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:52 INFO - Successfully installed psutil 12:40:52 INFO - Cleaning up... 12:40:52 INFO - Return code: 0 12:40:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:40:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:40:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:40:52 INFO - Using env: {'DISPLAY': ':0', 12:40:52 INFO - 'HOME': '/home/cltbld', 12:40:52 INFO - 'LANG': 'en_US.UTF-8', 12:40:52 INFO - 'LANGUAGE': 'en_US:en', 12:40:52 INFO - 'LOGNAME': 'cltbld', 12:40:52 INFO - 'MAIL': '/var/mail/cltbld', 12:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:52 INFO - 'MOZ_NO_REMOTE': '1', 12:40:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:52 INFO - 'NO_EM_RESTART': '1', 12:40:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:52 INFO - 'PWD': '/builds/slave/test', 12:40:52 INFO - 'SHELL': '/bin/bash', 12:40:52 INFO - 'SHLVL': '1', 12:40:52 INFO - 'TERM': 'linux', 12:40:52 INFO - 'TMOUT': '86400', 12:40:52 INFO - 'USER': 'cltbld', 12:40:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:52 INFO - '_': '/tools/buildbot/bin/python'} 12:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:40:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:40:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:40:54 INFO - Installing collected packages: mozsystemmonitor 12:40:54 INFO - Running setup.py install for mozsystemmonitor 12:40:54 INFO - Successfully installed mozsystemmonitor 12:40:54 INFO - Cleaning up... 12:40:54 INFO - Return code: 0 12:40:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:54 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 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:40:54 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 12:40:54 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 12:40:54 INFO - Using env: {'DISPLAY': ':0', 12:40:54 INFO - 'HOME': '/home/cltbld', 12:40:54 INFO - 'LANG': 'en_US.UTF-8', 12:40:54 INFO - 'LANGUAGE': 'en_US:en', 12:40:54 INFO - 'LOGNAME': 'cltbld', 12:40:54 INFO - 'MAIL': '/var/mail/cltbld', 12:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:54 INFO - 'MOZ_NO_REMOTE': '1', 12:40:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:54 INFO - 'NO_EM_RESTART': '1', 12:40:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:54 INFO - 'PWD': '/builds/slave/test', 12:40:54 INFO - 'SHELL': '/bin/bash', 12:40:54 INFO - 'SHLVL': '1', 12:40:54 INFO - 'TERM': 'linux', 12:40:54 INFO - 'TMOUT': '86400', 12:40:54 INFO - 'USER': 'cltbld', 12:40:54 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:54 INFO - '_': '/tools/buildbot/bin/python'} 12:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:55 INFO - Downloading/unpacking jsonschema==2.5.1 12:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:40:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:40:56 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 12:40:56 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 12:40:56 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 12:40:56 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 12:40:56 INFO - Downloading functools32-3.2.3-2.tar.gz 12:40:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:40:56 INFO - warning: no files found matching '*.txt' 12:40:56 INFO - no previously-included directories found matching 'build' 12:40:56 INFO - no previously-included directories found matching 'dist' 12:40:56 INFO - no previously-included directories found matching '.git*' 12:40:56 INFO - Installing collected packages: jsonschema, functools32 12:40:56 INFO - Running setup.py install for functools32 12:40:56 INFO - warning: no files found matching '*.txt' 12:40:56 INFO - no previously-included directories found matching 'build' 12:40:56 INFO - no previously-included directories found matching 'dist' 12:40:56 INFO - no previously-included directories found matching '.git*' 12:40:56 INFO - Successfully installed jsonschema functools32 12:40:56 INFO - Cleaning up... 12:40:56 INFO - Return code: 0 12:40:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40: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')]} 12:40:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40: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', '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 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:40: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:40: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 functools32==3.2.3-2 12:40:56 INFO - Using env: {'DISPLAY': ':0', 12:40:56 INFO - 'HOME': '/home/cltbld', 12:40:56 INFO - 'LANG': 'en_US.UTF-8', 12:40:56 INFO - 'LANGUAGE': 'en_US:en', 12:40:56 INFO - 'LOGNAME': 'cltbld', 12:40:56 INFO - 'MAIL': '/var/mail/cltbld', 12:40:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:56 INFO - 'MOZ_NO_REMOTE': '1', 12:40:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:56 INFO - 'NO_EM_RESTART': '1', 12:40:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:56 INFO - 'PWD': '/builds/slave/test', 12:40:56 INFO - 'SHELL': '/bin/bash', 12:40:56 INFO - 'SHLVL': '1', 12:40:56 INFO - 'TERM': 'linux', 12:40:56 INFO - 'TMOUT': '86400', 12:40:56 INFO - 'USER': 'cltbld', 12:40:56 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:40:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:56 INFO - '_': '/tools/buildbot/bin/python'} 12:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:40:57 INFO - Cleaning up... 12:40:57 INFO - Return code: 0 12:40:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40: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')]} 12:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40: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', '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 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:40: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:40: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 blobuploader==1.2.4 12:40:57 INFO - Using env: {'DISPLAY': ':0', 12:40:57 INFO - 'HOME': '/home/cltbld', 12:40:57 INFO - 'LANG': 'en_US.UTF-8', 12:40:57 INFO - 'LANGUAGE': 'en_US:en', 12:40:57 INFO - 'LOGNAME': 'cltbld', 12:40:57 INFO - 'MAIL': '/var/mail/cltbld', 12:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:57 INFO - 'MOZ_NO_REMOTE': '1', 12:40:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:57 INFO - 'NO_EM_RESTART': '1', 12:40:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:57 INFO - 'PWD': '/builds/slave/test', 12:40:57 INFO - 'SHELL': '/bin/bash', 12:40:57 INFO - 'SHLVL': '1', 12:40:57 INFO - 'TERM': 'linux', 12:40:57 INFO - 'TMOUT': '86400', 12:40:57 INFO - 'USER': 'cltbld', 12:40:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:57 INFO - '_': '/tools/buildbot/bin/python'} 12:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:57 INFO - Downloading/unpacking blobuploader==1.2.4 12:40: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 12:40: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 12:40: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 12:40: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 12:40:58 INFO - Downloading blobuploader-1.2.4.tar.gz 12:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:40:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:40:58 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 12:40:58 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 12:40:58 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 12:40:58 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 12:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:40:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:40:58 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 12:40:58 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 12:40:58 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 12:40:58 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 12:40:59 INFO - Downloading docopt-0.6.1.tar.gz 12:40:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:40:59 INFO - Installing collected packages: blobuploader, requests, docopt 12:40:59 INFO - Running setup.py install for blobuploader 12:40:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:40:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:40:59 INFO - Running setup.py install for requests 12:40:59 INFO - Running setup.py install for docopt 12:40:59 INFO - Successfully installed blobuploader requests docopt 12:40:59 INFO - Cleaning up... 12:40:59 INFO - Return code: 0 12:40:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:59 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 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:40:59 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 12:40:59 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 12:40:59 INFO - Using env: {'DISPLAY': ':0', 12:40:59 INFO - 'HOME': '/home/cltbld', 12:40:59 INFO - 'LANG': 'en_US.UTF-8', 12:40:59 INFO - 'LANGUAGE': 'en_US:en', 12:40:59 INFO - 'LOGNAME': 'cltbld', 12:40:59 INFO - 'MAIL': '/var/mail/cltbld', 12:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:59 INFO - 'MOZ_NO_REMOTE': '1', 12:40:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:59 INFO - 'NO_EM_RESTART': '1', 12:40:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:59 INFO - 'PWD': '/builds/slave/test', 12:40:59 INFO - 'SHELL': '/bin/bash', 12:40:59 INFO - 'SHLVL': '1', 12:40:59 INFO - 'TERM': 'linux', 12:40:59 INFO - 'TMOUT': '86400', 12:40:59 INFO - 'USER': 'cltbld', 12:40:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:59 INFO - '_': '/tools/buildbot/bin/python'} 12:40:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:41:00 INFO - Running setup.py (path:/tmp/pip-ASHC6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:41:00 INFO - Running setup.py (path:/tmp/pip-BUs9pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:41:00 INFO - Running setup.py (path:/tmp/pip-TxCcSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:41:00 INFO - Running setup.py (path:/tmp/pip-uxw0Tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:41:00 INFO - Running setup.py (path:/tmp/pip-dToAE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:41:00 INFO - Running setup.py (path:/tmp/pip-z1S92B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:41:00 INFO - Running setup.py (path:/tmp/pip-36jUAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:41:00 INFO - Running setup.py (path:/tmp/pip-3w0Rc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:41:00 INFO - Running setup.py (path:/tmp/pip-4EXvZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:41:00 INFO - Running setup.py (path:/tmp/pip-nS80mE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:41:00 INFO - Running setup.py (path:/tmp/pip-iQR447-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:41:00 INFO - Running setup.py (path:/tmp/pip-ayxf1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:41:00 INFO - Running setup.py (path:/tmp/pip-PtmwCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:41:00 INFO - Running setup.py (path:/tmp/pip-nQJ5nD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:41:01 INFO - Running setup.py (path:/tmp/pip-6hmDvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:41:01 INFO - Running setup.py (path:/tmp/pip-1hbRCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:41:01 INFO - Running setup.py (path:/tmp/pip-n8LoLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:41:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:41:01 INFO - Running setup.py install for manifestparser 12:41:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:41:01 INFO - Running setup.py install for mozcrash 12:41:01 INFO - Running setup.py install for mozdebug 12:41:01 INFO - Running setup.py install for mozdevice 12:41:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:41:01 INFO - Running setup.py install for mozfile 12:41:01 INFO - Running setup.py install for mozhttpd 12:41:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:41:01 INFO - Running setup.py install for mozinfo 12:41:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:41:01 INFO - Running setup.py install for mozInstall 12:41:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Running setup.py install for mozleak 12:41:02 INFO - Running setup.py install for mozlog 12:41:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Running setup.py install for moznetwork 12:41:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Running setup.py install for mozprocess 12:41:02 INFO - Running setup.py install for mozprofile 12:41:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Running setup.py install for mozrunner 12:41:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:41:02 INFO - Running setup.py install for mozscreenshot 12:41:02 INFO - Running setup.py install for moztest 12:41:02 INFO - Running setup.py install for mozversion 12:41:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:41:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:41:03 INFO - Cleaning up... 12:41:03 INFO - Return code: 0 12:41:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:41:03 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')]} 12:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:03 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')]} 12:41:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:03 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 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:41:03 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 12:41:03 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 12:41:03 INFO - Using env: {'DISPLAY': ':0', 12:41:03 INFO - 'HOME': '/home/cltbld', 12:41:03 INFO - 'LANG': 'en_US.UTF-8', 12:41:03 INFO - 'LANGUAGE': 'en_US:en', 12:41:03 INFO - 'LOGNAME': 'cltbld', 12:41:03 INFO - 'MAIL': '/var/mail/cltbld', 12:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:03 INFO - 'MOZ_NO_REMOTE': '1', 12:41:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:03 INFO - 'NO_EM_RESTART': '1', 12:41:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:03 INFO - 'PWD': '/builds/slave/test', 12:41:03 INFO - 'SHELL': '/bin/bash', 12:41:03 INFO - 'SHLVL': '1', 12:41:03 INFO - 'TERM': 'linux', 12:41:03 INFO - 'TMOUT': '86400', 12:41:03 INFO - 'USER': 'cltbld', 12:41:03 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:03 INFO - '_': '/tools/buildbot/bin/python'} 12:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:41:03 INFO - Running setup.py (path:/tmp/pip-Oi_Yg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:41:03 INFO - Running setup.py (path:/tmp/pip-glGj4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:41:03 INFO - Running setup.py (path:/tmp/pip-Ziilbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:41:03 INFO - Running setup.py (path:/tmp/pip-oBJSgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:41:03 INFO - Running setup.py (path:/tmp/pip-eXv6qB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:41:03 INFO - Running setup.py (path:/tmp/pip-fU1rdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:41:03 INFO - Running setup.py (path:/tmp/pip-nxfqFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:41:03 INFO - Running setup.py (path:/tmp/pip-yKpn_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:41:03 INFO - Running setup.py (path:/tmp/pip-nOEpbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:41:03 INFO - Running setup.py (path:/tmp/pip-DgHwPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:41:03 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)) 12:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:41:03 INFO - Running setup.py (path:/tmp/pip-wMJN8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:41:04 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)) 12:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:41:04 INFO - Running setup.py (path:/tmp/pip-FhI718-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:41:04 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)) 12:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:41:04 INFO - Running setup.py (path:/tmp/pip-XthIPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:41:04 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)) 12:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:41:04 INFO - Running setup.py (path:/tmp/pip-O92Uwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:41:04 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)) 12:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:41:04 INFO - Running setup.py (path:/tmp/pip-LcMSKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:41:04 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)) 12:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:41:04 INFO - Running setup.py (path:/tmp/pip-3KaRRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:41:04 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)) 12:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:41:04 INFO - Running setup.py (path:/tmp/pip-6zUphA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:41:04 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)) 12:41:04 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)) 12:41:04 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)) 12:41:04 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)) 12:41:04 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)) 12:41:04 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)) 12:41:04 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)) 12:41:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:41: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 12:41: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 12:41: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 12:41: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 12:41:05 INFO - Downloading blessings-1.6.tar.gz 12:41:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:41:05 INFO - Installing collected packages: blessings 12:41:05 INFO - Running setup.py install for blessings 12:41:05 INFO - Successfully installed blessings 12:41:05 INFO - Cleaning up... 12:41:05 INFO - Return code: 0 12:41:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:41:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:41:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:41:06 INFO - Reading from file tmpfile_stdout 12:41:06 INFO - Current package versions: 12:41:06 INFO - argparse == 1.2.1 12:41:06 INFO - blessings == 1.6 12:41:06 INFO - blobuploader == 1.2.4 12:41:06 INFO - docopt == 0.6.1 12:41:06 INFO - functools32 == 3.2.3-2 12:41:06 INFO - jsonschema == 2.5.1 12:41:06 INFO - manifestparser == 1.1 12:41:06 INFO - mozInstall == 1.13 12:41:06 INFO - mozcrash == 1.0 12:41:06 INFO - mozdebug == 0.1 12:41:06 INFO - mozdevice == 0.51 12:41:06 INFO - mozfile == 1.2 12:41:06 INFO - mozhttpd == 0.7 12:41:06 INFO - mozinfo == 0.10 12:41:06 INFO - mozleak == 0.1 12:41:06 INFO - mozlog == 3.5 12:41:06 INFO - moznetwork == 0.27 12:41:06 INFO - mozprocess == 0.25 12:41:06 INFO - mozprofile == 0.28 12:41:06 INFO - mozrunner == 6.13 12:41:06 INFO - mozscreenshot == 0.1 12:41:06 INFO - mozsystemmonitor == 0.3 12:41:06 INFO - moztest == 0.8 12:41:06 INFO - mozversion == 1.4 12:41:06 INFO - psutil == 3.1.1 12:41:06 INFO - requests == 1.2.3 12:41:06 INFO - wsgiref == 0.1.2 12:41:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:41:06 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')]} 12:41:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:06 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')]} 12:41:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:06 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 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:41:06 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 12:41:06 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 12:41:06 INFO - Using env: {'DISPLAY': ':0', 12:41:06 INFO - 'HOME': '/home/cltbld', 12:41:06 INFO - 'LANG': 'en_US.UTF-8', 12:41:06 INFO - 'LANGUAGE': 'en_US:en', 12:41:06 INFO - 'LOGNAME': 'cltbld', 12:41:06 INFO - 'MAIL': '/var/mail/cltbld', 12:41:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:06 INFO - 'MOZ_NO_REMOTE': '1', 12:41:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:06 INFO - 'NO_EM_RESTART': '1', 12:41:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:06 INFO - 'PWD': '/builds/slave/test', 12:41:06 INFO - 'SHELL': '/bin/bash', 12:41:06 INFO - 'SHLVL': '1', 12:41:06 INFO - 'TERM': 'linux', 12:41:06 INFO - 'TMOUT': '86400', 12:41:06 INFO - 'USER': 'cltbld', 12:41:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:41:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:06 INFO - '_': '/tools/buildbot/bin/python'} 12:41:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:06 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)) 12:41:06 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)) 12:41:06 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)) 12:41:06 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)) 12:41:06 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)) 12:41:06 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)) 12:41:06 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)) 12:41:06 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)) 12:41:06 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)) 12:41:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:41: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 12:41: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 12:41: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 12:41: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 12:41:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:41:07 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)) 12:41:07 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)) 12:41:07 INFO - Installing collected packages: simplejson 12:41:07 INFO - Running setup.py install for simplejson 12:41:07 INFO - building 'simplejson._speedups' extension 12:41:07 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 12:41:08 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 12:41:08 INFO - Successfully installed simplejson 12:41:08 INFO - Cleaning up... 12:41:08 INFO - Return code: 0 12:41:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:41:08 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')]} 12:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:08 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')]} 12:41:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:08 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 0x1ea51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2211a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', '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 12:41:08 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 12:41:08 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 12:41:08 INFO - Using env: {'DISPLAY': ':0', 12:41:08 INFO - 'HOME': '/home/cltbld', 12:41:08 INFO - 'LANG': 'en_US.UTF-8', 12:41:08 INFO - 'LANGUAGE': 'en_US:en', 12:41:08 INFO - 'LOGNAME': 'cltbld', 12:41:08 INFO - 'MAIL': '/var/mail/cltbld', 12:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:08 INFO - 'MOZ_NO_REMOTE': '1', 12:41:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:08 INFO - 'NO_EM_RESTART': '1', 12:41:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:08 INFO - 'PWD': '/builds/slave/test', 12:41:08 INFO - 'SHELL': '/bin/bash', 12:41:08 INFO - 'SHLVL': '1', 12:41:08 INFO - 'TERM': 'linux', 12:41:08 INFO - 'TMOUT': '86400', 12:41:08 INFO - 'USER': 'cltbld', 12:41:08 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:08 INFO - '_': '/tools/buildbot/bin/python'} 12:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:41:08 INFO - Cleaning up... 12:41:08 INFO - Return code: 0 12:41:08 INFO - Running post-action listener: _resource_record_post_action 12:41:08 INFO - Running post-action listener: _start_resource_monitoring 12:41:08 INFO - Starting resource monitoring. 12:41:08 INFO - [mozharness: 2017-09-15 19:41:08.728900Z] Finished create-virtualenv step (success) 12:41:08 INFO - [mozharness: 2017-09-15 19:41:08.729410Z] Running install step. 12:41:08 INFO - Running pre-action listener: _resource_record_pre_action 12:41:08 INFO - Running main action method: install 12:41:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:41:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:41:08 INFO - Reading from file tmpfile_stdout 12:41:08 INFO - Detecting whether we're running mozinstall >=1.0... 12:41:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:41:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:41:09 INFO - Reading from file tmpfile_stdout 12:41:09 INFO - Output received: 12:41:09 INFO - Usage: mozinstall [options] installer 12:41:09 INFO - Options: 12:41:09 INFO - -h, --help show this help message and exit 12:41:09 INFO - -d DEST, --destination=DEST 12:41:09 INFO - Directory to install application into. [default: 12:41:09 INFO - "/builds/slave/test"] 12:41:09 INFO - --app=APP Application being installed. [default: firefox] 12:41:09 INFO - mkdir: /builds/slave/test/build/application 12:41:09 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'] 12:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:41:26 INFO - Reading from file tmpfile_stdout 12:41:26 INFO - Output received: 12:41:26 INFO - /builds/slave/test/build/application/firefox/firefox 12:41:26 INFO - Running post-action listener: _resource_record_post_action 12:41:26 INFO - [mozharness: 2017-09-15 19:41:26.673111Z] Finished install step (success) 12:41:26 INFO - [mozharness: 2017-09-15 19:41:26.673363Z] Running setup-mitmproxy step. 12:41:26 INFO - Running pre-action listener: _resource_record_pre_action 12:41:26 INFO - Running main action method: setup_mitmproxy 12:41:26 INFO - Skipping: mitmproxy is not required 12:41:26 INFO - Running post-action listener: _resource_record_post_action 12:41:26 INFO - [mozharness: 2017-09-15 19:41:26.674592Z] Finished setup-mitmproxy step (success) 12:41:26 INFO - [mozharness: 2017-09-15 19:41:26.674812Z] Running run-tests step. 12:41:26 INFO - Running pre-action listener: _resource_record_pre_action 12:41:26 INFO - Running pre-action listener: _set_gcov_prefix 12:41:26 INFO - Running main action method: run_tests 12:41:26 WARNING - Try message not found. 12:41:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:41:26 INFO - Python 2.7.3 12:41:26 INFO - Return code: 0 12:41:26 INFO - grabbing minidump binary from tooltool 12:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:26 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 0x2215090>, '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 0x2215d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x220ff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:41:26 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 12:41:26 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 12:41:26 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 12:41:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:41:26 INFO - Return code: 0 12:41:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:41:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:41:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:41:26 INFO - ENV: RUST_BACKTRACE is now full 12:41:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:41:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:41:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 12:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 12:41:26 INFO - Using env: {'DISPLAY': ':0', 12:41:26 INFO - 'HOME': '/home/cltbld', 12:41:26 INFO - 'LANG': 'en_US.UTF-8', 12:41:26 INFO - 'LANGUAGE': 'en_US:en', 12:41:26 INFO - 'LOGNAME': 'cltbld', 12:41:26 INFO - 'MAIL': '/var/mail/cltbld', 12:41:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:41:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:26 INFO - 'MOZ_NO_REMOTE': '1', 12:41:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:41:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:26 INFO - 'NO_EM_RESTART': '1', 12:41:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:26 INFO - 'PWD': '/builds/slave/test', 12:41:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:41:26 INFO - 'RUST_BACKTRACE': 'full', 12:41:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:41:26 INFO - 'SHELL': '/bin/bash', 12:41:26 INFO - 'SHLVL': '1', 12:41:26 INFO - 'TERM': 'linux', 12:41:26 INFO - 'TMOUT': '86400', 12:41:26 INFO - 'USER': 'cltbld', 12:41:26 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:26 INFO - '_': '/tools/buildbot/bin/python'} 12:41:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 12:41:27 INFO - mozversion application_buildid: 20170915182012 12:41:27 INFO - mozversion application_changeset: 7aceaf8bcb9f582db0f93488b48ef7019e348dba 12:41:27 INFO - mozversion application_display_name: Nightly 12:41:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:41:27 INFO - mozversion application_name: Firefox 12:41:27 INFO - mozversion application_remotingname: firefox 12:41:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:41:27 INFO - mozversion application_vendor: Mozilla 12:41:27 INFO - mozversion application_version: 57.0a1 12:41:27 INFO - mozversion platform_buildid: 20170915182012 12:41:27 INFO - mozversion platform_changeset: 7aceaf8bcb9f582db0f93488b48ef7019e348dba 12:41:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:41:27 INFO - mozversion platform_version: 57.0a1 12:41:27 INFO - using testdate: 1505504487 12:41:27 INFO - actual date: 1505504487 12:41:27 INFO - starting webserver on 'localhost:60308' 12:41:27 INFO - SUITE-START | Running 2 tests 12:41:27 INFO - TEST-START | dromaeo_css 12:41:27 INFO - Initialising browser for dromaeo_css test... 12:41:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60308/getInfo.html -profile /tmp/tmp1NTnmo/profile 12:41:27 INFO - TEST-INFO | started process 827 (/builds/slave/test/build/application/firefox/firefox http://localhost:60308/getInfo.html) 12:41:32 INFO - TEST-INFO | 827: exit 0 12:41:32 INFO - Browser initialized. 12:41:32 INFO - Running cycle 1/1 for dromaeo_css test... 12:41:32 INFO - Using env: {'DISPLAY': ':0', 12:41:32 INFO - 'HOME': '/home/cltbld', 12:41:32 INFO - 'JSGC_DISABLE_POISONING': '1', 12:41:32 INFO - 'LANG': 'en_US.UTF-8', 12:41:32 INFO - 'LANGUAGE': 'en_US:en', 12:41:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:41:32 INFO - 'LOGNAME': 'cltbld', 12:41:32 INFO - 'MAIL': '/var/mail/cltbld', 12:41:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:41:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:41:32 INFO - 'MOZ_CRASHREPORTER': '1', 12:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:41:32 INFO - 'MOZ_NO_REMOTE': '1', 12:41:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:41:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:32 INFO - 'NO_EM_RESTART': '1', 12:41:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:32 INFO - 'PWD': '/builds/slave/test', 12:41:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:41:32 INFO - 'RUST_BACKTRACE': 'full', 12:41:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:41:32 INFO - 'SHELL': '/bin/bash', 12:41:32 INFO - 'SHLVL': '1', 12:41:32 INFO - 'TERM': 'linux', 12:41:32 INFO - 'TMOUT': '86400', 12:41:32 INFO - 'USER': 'cltbld', 12:41:32 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 12:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:32 INFO - '_': '/tools/buildbot/bin/python'} 12:41:32 INFO - TEST-INFO | started process 1063 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1NTnmo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:33 INFO - PID 1063 | 12:41:33 INFO - PID 1063 | (/builds/slave/test/build/application/firefox/firefox:1117): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:41:33 INFO - PID 1063 | 12:41:43 INFO - PID 1063 | RSS: Main: 167002112 12:41:43 INFO - PID 1063 | 12:44:41 INFO - PID 1063 | Cycle 1(1): loaded http://localhost:60308/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60308/tests/dromaeo/cssquery-ext.html) 12:44:43 INFO - PID 1063 | RSS: Main: 169943040 12:44:43 INFO - PID 1063 | 12:47:44 INFO - PID 1063 | Cycle 1(1): loaded http://localhost:60308/tests/dromaeo/cssquery-ext.html (next: http://localhost:60308/tests/dromaeo/cssquery-jquery.html) 12:47:45 INFO - PID 1063 | RSS: Main: 165675008 12:47:45 INFO - PID 1063 | 12:50:46 INFO - PID 1063 | Cycle 1(1): loaded http://localhost:60308/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60308/tests/dromaeo/cssquery-mootools.html) 12:50:47 INFO - PID 1063 | RSS: Main: 168005632 12:50:47 INFO - PID 1063 | 12:53:49 INFO - PID 1063 | Cycle 1(1): loaded http://localhost:60308/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60308/tests/dromaeo/cssquery-prototype.html) 12:53:49 INFO - PID 1063 | RSS: Main: 166576128 12:53:49 INFO - PID 1063 | 12:56:52 INFO - PID 1063 | Cycle 1(1): loaded http://localhost:60308/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60308/tests/dromaeo/cssquery-yui.html) 12:56:53 INFO - PID 1063 | RSS: Main: 168656896 12:56:53 INFO - PID 1063 | 12:59:55 INFO - PID 1063 | Cycle 1(1): loaded http://localhost:60308/tests/dromaeo/cssquery-yui.html (next: http://localhost:60308/tests/dromaeo/cssquery-dojo.html) 12:59:55 INFO - PID 1063 | RSS: Main: 171409408 12:59:55 INFO - PID 1063 | 12:59:55 INFO - PID 1063 | __start_tp_report 12:59:55 INFO - PID 1063 | _x_x_mozilla_page_load 12:59:55 INFO - PID 1063 | _x_x_mozilla_page_load_details 12:59:55 INFO - PID 1063 | |i|pagename|runs| 12:59:55 INFO - PID 1063 | |0;dojo.html;3843;3882.5340959084915;3885.028742814296;3899.5905429929876;3900.1029763154456;6201.441870231678;6207.813765587034;6218;6230.158928544651;6256.781012664557;6282.52881033922;6288.371162883709;6302.306746257908;6331.01869210272;6340.397662222082;5506.284183056217;5564.582656300783;5569.0254205514075;5575.916361254584;5587.189857470665;5530.806421775239;5630.042892708229;5661.2357331760095;5662.207290979266;5700.458456446651;5618.241537392461;5660.660360378391;5675.404082571333;5675.432456754333;5703.230063941377;5403.108487099624;5545.445455454463;5547.3343198816;5598.86002849932;5635.802746903839;5530.391656917735;5568.582356323292;5593.748281327309;5594.552435805166;5619.4942455178825;6160.229971253593;6240.594361366497;6246.906296405557;6265.934791085527;6319.052142178664;6759;6803.183617965827;6816.625085620289;6848.178218613748;6874.8625027499675;6826.965865170642;6898.965505172492;6904.792856214322;6922.376986071277;6928.826779330507;6292.874142517124;6312.305646378849;6375.553711240215;6395.904061439082;6424.132742079806;6885.173779146535;6945.37491625746;7001.684924178424;7007.894881576781;7009.719611215596;6684.732610695518;6687.6321802301345;6706.26231114577;6769.9845023245925;6771.28901465339;6800.115984921928;6848.657567121624;6862.656867156722;6911.619860907598;6944.201416837027;6780.593164410151;6785.626790526569;6846.246912839583;6863.210730765926;6876.209235937931;6701.497387695845;6743.190817101881;6778.932210677929;6795.320467953264;6845.247022827485;4941.209406494943;4957.752112394366;4975.129352363394;4976.576990955737;4989.451160372356;4454.443194600675;4489.6408287336935;4522.3668686383935;4529.569690879362;4568.132054909556;6171.9691401542705;6190;6270.435660790551;6272.6236425814595;6281.748730050838;6776.390124888685;6783.491238157158;6835.4189893859575;6850.143732033496;6858.6570671466225;6036.094585812163;6128.693565321716;6180.412860778219;6213.409726076015;6236.532260080512;5549.0289199389945;5554.389017208104;5578.86052848682;5585.916211256835;5588.189712491666;7040.190378106481;7053.92946070543;7150.034745309421;7155.499115062;7183.5330703504105;6598.769043083469;6746.089277947414;6757.594544327356;6845.075914751545;6849.931500685128;7052.788416347517;7055.082839230867;7064.611446370499;7066.540674856118;7067.222605513492;6610.6694665267505;6639.203295604365;6707.1616047994;6716.764913228014;6743.52795304324;6883.311668833277;6916.619585922823;6917.896231556631;6918.481113916376;6927.238003819475;6567.44176744982;6568.8357791055605;6646.534742567974;6650.434713049445;6654.567453115532;6507.349265073455;6539.967300163468;6553.901691474536;6636.900446493403;6642.833929151617;6486.189226346707;6519.152510173647;6521.9673901630185;6532.804015879531;6537.477001839747;6616.106825578485;6659.53383263167;6666.733330666719;6687.866242675216;6728.529002969745;6428.132202152649;6437.25971513282;6445.709943052668;6524.67376631176;6530.510211734044 12:59:55 INFO - PID 1063 | |1;ext.html;876.1028659038858;913.8629205619152;920.0431551186765;959.8896126945403;970.5001924009132;43891.56108438915;44408;44593.77703111488;44733.999999999956;44921.550784492145;41201.38197927026;45511;45527;45555.08889822201;45594.316085258666;8033.879491807642;8136.3084137848355;8159.592020398986;8166.387520935924;8175.059868115153;8041.839163216761;8056.154103819103;8123.187681231859;8161.510309381427;8170.305524030465;8147.26674599286;8156.673733050671;8157.347412207039;8212.794680132985;8219.71231006915;8164.6325915333955;8166.754997350059;8169.387295952827;8179.345652347798;8198.467099638534;7920.366370690389;7931.762047138595;7947.801304967422;7999.760007199793;8039.718609848627;41406.687465002724;41515.7924210377;41545.79227103845;41580;41662.79168604168;3754.511913451261;3797.867074652374;3799.3161230978412;3799.8480060797533;3801.4107813288706;32771;32927.6707232927;33137.50293745596;33145.668543314496;33220.83727069565;5324.281222035014;5339.332583427072;5349.025406372072;5361.839144825662;5423.755930983115;202738;205534;208242;213480;220980;54546.18180727292;54906.72546637241;55018.449815501335;55025.44974550283;55037;57131;57327.42672573304;57343;57408.71295643495;57446.7127664359;16603.41888033913;16765.329386824633;16793.076380798928;16798;16871.156442177842;13138.934305328603;13602.31988400576;13889.027768056341;13894.722105558034;13895.166290022631;14785.48250811238;14926.552203433914;14943.701125977419;14983;15102.320395582225;5510;5548.4174161712435;5590.273264475673;5605.92663639407;5629.493345598916;1070.9357438553711;1073.9570417183381;1094.9124070074374;1102.2339474065607;1105.5190991918541;25233.495330093294;25435.74564254371;25472.745272547407;25531.489370212865;25708.871455642602;1178.4696886401155;1184.3545267829054;1185.2947496239724;1193.6061099837034;1202.915795894279;13244.602661920158;13360.131591446525;13433.596992090253;13455.192688438725;13466.461341546425;276087;278047;278090;278292;279146;59950;59984.40015599788;59988.100178497356;60043.39956600466;60162.699186504666;1503.9849601503845;1508.8038554987781;1520.3538496139317;1548.705745908274;1556.533040087969;24657.424713396656;24761.761911904694;24772.788625933994;24869.129580464596;24912.12807551727;39549.80225098856;41297.79351103225;41437;41472.34110635541;41500;230987;232456;233356;233426;233912;34288;34548.654513454545;37259.627403725615;37417.812910935274;37942.051448714;46997.295040573736;47213.52786472091;47387.289190661475;47603.28595071007;47683.28475072946;33649;34189;34643;34693;35013.8249308762;33686.326273473904;33863.49204761881;33878.322433551686;34109.65890341065;34647.4802877952;39431.80284098676;40800.3879941807;40912;40969.59030409777;41218.1756364877;32983.34033319272;33396.49905251375;33557;33753.32493350168;34028;25177.87411062933;25266.747332527175;25458.618120728574;25797.51314445207;25825.483490329712 12:59:55 INFO - PID 1063 | |2;jquery.html;591.7218907113657;592.834006478186;593.3621357041181;611.4649681528662;635.6662752055171;23535.29394118175;24324.391890202754;24340.026398944043;24377.14679986201;24402.3899402515;24842.254732358015;27262.31844203896;27308.45383092337;27454.725452745468;27526.44947101062;6762.053312536249;6890.586564806103;6950.756723514678;6958.652067396635;6960.930390696079;6468.223813142406;6752.189737231539;6779.661016949133;6826.795196144099;6896.137982752156;6703.8324041899095;6704.094947182117;6707.631080290582;6708.899366509506;6712.798616041527;6479.870402591969;6691.899621505681;6734.292899245582;6742.089817874597;6746.224184218804;6211.34780848009;6230.314665386803;6234.064890266496;6238.719257633372;6254.7498100075945;30909.690903090905;31317.8434107828;31350.37299254025;31382;31426.685733142604;2315.131825565413;2320.1183550250967;2328.84862483938;2341.4731685370657;2343.6367363058753;21125.89437052805;21300.360989170196;21818.454538636503;21978.670319945213;22013.559728805496;2430.4774473488283;2450.816188785848;2511.033252197917;2549.4646124313917;2549.79601631869;136205;143283;143402;149455.25272373678;150620;15815.68368632644;15840.76238856418;16081.51755447315;16216.270267837976;16905.746413803558;25978.220653380427;26095.73904260971;26127;26153.215403537924;26167.869160654074;10494.108000820015;10500.844907060216;10566.31318964265;10645.627403040857;10682.94658526718;9610.135087842127;9633.710988670351;9646.324757266924;9717.076877696607;9742.464164471023;9735.269854760922;9861;10143.847842282372;10180.134688551556;10272.33229840073;2882.198625858838;2887.7978541501893;2956.512175491063;2964.1403992842265;2972.70272972701;1043.5304113149114;1048.6591857646295;1065.3767545985531;1075.5858994287255;1081.291753901196;21802.127914883225;21895;21923.232686855885;22382.55234895293;22599.09603615837;1117.3016864459712;1121.8653761548667;1132.28665940457;1149.9425028748528;1152.464104191555;11839.348835814113;11934;12080;12082.912537871634;12131.514739410324;560946;570093;576594;580183;582992;124612;126616.36691816481;126733;129849.35075324752;131732;1137.3460260350332;1237.8762123787549;1253.7680529101963;1269.3653173413293;1296.9481220751254;22781.99352421419;23098.307050788502;23148.6527702088;23288.184913527944;23570.410739731644;60441.69779151076;60557.69721151542;60691.39308607034;60828.08757868724;60957.39042609694;78555.60722196581;78906.21093788989;79187.20812791798;79216.60391698005;79849;15708.764368534252;16059.197040147712;16192.433264835674;16213.756793648341;16267.59331016734;25497.490050199263;25597.104101356363;25733.228003159937;25760.09839655678;25812.22563323103;19342.8065719341;19352.70970935409;20443.59112817765;20962.790372096082;21015.894920525912;16356.263968121226;16792.832071679128;17180.484585462455;17186.398476053277;17188.398406055727;19572.608547829248;19626.116824742636;19687.70468443003;19784.109715063143;19975.300864469675;15592.908496405144;15648.826338024466;15779.526614201592;15807.209639517749;15812.367505299659;20154.193832247132;20163.697544537135;20354.389368318974;20424.489387765425;20622.27822026282 12:59:55 INFO - PID 1063 | |3;mootools.html;1434.2470203143348;1481.0003247807726;1496.5659958612002;1507.321705232645;1507.917064561449;16240.35038598456;16770.66458670826;16878.578035549086;16887.324507019704;16891.662166756658;16585.83414165858;16692.499225023268;16762;16768.161591920416;16778.58053548662;2810.339570201001;2843.7724982001496;2854.557543580748;2855.5145625243654;2858.985705071472;2659.6276521287036;2755.738204870534;2760.7377299156547;2761.64098667173;2769.4322663853864;2734.220747087081;2820.421813528232;2823.6329277193936;2824.9717502825015;2826.081523504859;2810.985945070272;2843.2749648839517;2867.3261783480866;2871.0812539987214;2871.7559007484388;2798.1885253276523;2828.222238884303;2828.5191517442086;2829.698338764171;2838.957415638767;15956.601084972852;16008.279627416794;16120.435784747595;16296.674066518603;16358.918205409016;8424.620892059873;8809.823803523894;8814;8814.294856411472;8827.337949653738;5558.110702287655;5925.940740592625;5943.940560594425;5960.195373624549;6103.6032657877095;2026.8682535635137;2042.3770749921268;2042.7548694156649;2078.449210959082;2087.2590230468268;29890.850545747133;30154;30306.393872122655;30378.84810575955;30433.69566304331;7096.148462184571;7101.254368291255;7248.456365772483;7399.630018499161;7461.477696561188;19025.668203225643;19057.80942190588;19111.617767644846;19116;19185.424437266905;8599.269062129664;8830.779230519289;8856.601452934634;8857.11428857122;8865.512396818109;7781.610919454005;7810.0237470316215;7831.725889593837;7895.9210407896335;7910.7231246906085;5479.53423958968;5599.860003499945;5645.181448689917;5668.773249070074;5718.599698021098;1550.3411050303575;1551.3251735495096;1652.8760342974324;1655.6274838161316;1665.600255938566;966.7051549277538;971.1065819446127;981.1611072532882;1002.6340385759146;1002.674130907458;8474.237318641352;8549.27331176857;8574.528400938008;8591.398602097794;8631.309495240366;1053.599632139782;1065.1106326217541;1070.0476575847501;1071.3304184884448;1075.4246478134316;5144.61415393847;5144.922826157611;5197.350331208599;5218.165093585084;5267.420583735786;24003.399915002265;24911.62632560513;25077.247682569552;25079;25200.74799251984;23387.7661223389;23987.880060599586;24383.7561624385;24748.752512475006;24985.62521562178;11908.821367679491;11925.76148477043;12216.877831221751;12221.266723996589;12261.448234829453;9820.214382849212;9880.654177103766;9960.950195248977;9998.90012098654;10031.598736050477;21000.36998890036;21054;21234.575308493433;21348.89325553362;21776.564468710854;22011.11955521761;22256.888715556317;22384.664230036236;22648.433789154744;22731.431714207276;13090.672733181365;13287.468501259842;13477.797833032317;13497.86502134966;13501.932490337485;15462;15725.292361843516;15726.842731572538;15736.134512601917;15745.842541574895;11589.130815188726;11767.588134415255;11771.587994420153;11966.341851198267;12058.879411205775;11699;11788.410579471163;11815.232009919328;12199.268043917393;12202.81695774582;11010.623672040994;11088.944555277494;11123.499442524944;11166.44167791591;11175.49710263024;7840.882386764317;7868.488548244346;7927.920720792718;7960.522368657899;8092.393070519853;8033.919660803317;8097.595120243846;8174.959125204574;8212.17878212174;8225.670973161006 12:59:55 INFO - PID 1063 | |4;prototype.html;528.4715284715285;551.1677367175564;559.4685049203256;559.8096647139971;570.3982298674899;6198.915189841777;6239.251289845225;6259.53053520986;6273.7804176853815;6311.928805920179;6066.969665151679;6110.358412366704;6144.477719393857;6148.538859585527;6206.937930620692;5043.974780126095;5069.163588007993;5069.898602027957;5072.543471087602;5078.441371449156;4773.6897101688455;5072.112380333445;5074.517920797518;5090.185570308751;5096.847094587169;4845.370101886768;4861.951380486203;4862.756862156895;4876.951230487685;4887.902241955158;4833.734144622044;4842.12841688496;4860.1008813369635;4867.050925069613;4873.269009648546;4397.340398940152;4401.383806267125;4411.228035093878;4416.955830441687;4444.20004399208;6073.453389194994;6127.264728232596;6149.385061493859;6165.568410211287;6184.752609895599;5752.626079304831;5790.942090579082;5796.88406231873;5867.061270196748;5878.911816322759;6517.152770139852;6581;6631.502637302174;6637.93362066378;6657.633830139341;5012.498750124958;5015.949840501585;5019.272205530177;5043.167877300241;5053.722045287508;6701.363370479797;6797.592144471348;6906.792796216071;6922.896156557656;6930.20302665195;5096.719680417538;5110.4634013428795;5119.257707632447;5159.742012899415;5181.5854731621375;5148.845534633967;5152.948470515322;5178.870528236749;5185.292515851184;5197.24639927216;5764.365919748824;5768.817392434625;5774.82675519735;5779.248697669276;5807.593468457252;6487.967560162263;6518.28298887122;6551.737930482727;6629.734810607618;6659.834528957413;1771.8582513398896;1785.7321401789836;1792.4622613216243;1816.8819026763217;1818.1818181818235;1220.2177457529099;1248.7065539598186;1279.4050766393486;1290.7224946636331;1291.1994563370583;5954.166416701665;6018.398160183947;6028.608140470856;6088.391160883876;6091.969540152271;6630.701618427182;6651.16860392451;6663.36698013688;6696.263411024784;6714.227863795629;5899.46904778572;6030.868854834453;6078.240219972504;6117.755289788359;6191.854506916235;3492.406290930497;3504.6495350464747;3543.7165026797793;3571.321448924696;3581.28374325136;6989.685464154125;6994.790156295319;7003.404710599554;7022.262662420474;7029.402500787491;6755.49333799967;6865.5537390070485;6883.965580172067;6885;6888.380045795902;6981.441484681213;6988.755393561201;7005.92994070063;7035.226125126232;7086.114235720535;6636.13730215069;6672.633005184762;6693;6704.396604305636;6768.255491895986;6620.238672552526;6630.46956243509;6657.500687448558;6659.667016649051;6733.865322693421;6778.389944904982;6909.654517274015;6946.270641582763;6959.652017399211;7009.684564194521;5101.362329708786;5161.225816127611;5164.406093299169;5168.612354073534;5177.197534382177;6519.869602608016;6607.933920660924;6698.765543205965;6747.898781518183;6825.692843822139;6379.649119298483;6408.68621932512;6486.027095935648;6525.3034211104505;6565.113709649328;6411.166548348871;6443.484521238383;6475.740970361321;6488.221413430419;6515.609063456208;6446.8388290293115;6472.449841763315;6493.7986472502735;6523.67381630907;6546.574472659262;6584.242812076481;6594.8351291218105;6647.736929983288;6681.365270299314;6682.365175308338;6293.150424692608;6387.329330420239;6446.258680251831;6469.126667899774;6501.707423165874 12:59:55 INFO - PID 1063 | |5;yui.html;972.5817898303727;997.2470784557659;1008.354653022066;1009.6213919780082;1015.0661391519803;20836.479088022807;21246.468838279052;21308.680369794445;21358.466038349048;21383.465413364673;18786.436406907815;19158.13788379523;19947.700784488243;20081.196752129934;20135.093920773565;1058.3808472043845;1094.8467214589964;1096.8628921384827;1097.040089921319;1097.7091070866227;1043.801677681242;1076.569372251098;1077.450500244873;1090.8309212072102;1091.5142761471147;1135.5968631135952;1141.4007645985882;1141.5833220874363;1141.7488152606413;1143.982840257397;1093.5024563823442;1124.5276983666838;1129.8022846001916;1132.8527291452099;1134.3024040215291;793.8650429426954;794.6424109150905;795.312055072364;800.1718221640574;812.687115460546;19441.125149368312;19603;19632.70550941737;19659.80340196594;19676.114574844167;22694.773052269433;24165.154219602406;24319.878400607882;24410.145644902343;24420.511589768106;17543;18203.63592728138;18362.449126526226;18483.907580462146;18503.81496185048;3888.51393575803;3921.627445392683;3926.6466018058227;3929.607039296076;3946.881593552198;157109.214453927;157431.99999999886;158894;161837;162371.9999999988;17985.7302140468;17986.5503362417;18095;18252;18524.722129168073;33777.662223377454;34397.4521146549;34506.82746586301;34615.307693845985;35059.12352191165;8650.610722517502;8671.176238257356;8678.6528538859;8703.782405439788;8727.345449091343;4734.597559207437;4756.57190852818;4777.761111944389;4813.350197723333;4842.982973575588;6082.756689732361;6232.0963460298;6253.1245625612455;6263.404976527223;6285.622862628257;1068.1401471815109;1082.5290998415715;1107.2194103157256;1107.595727559451;1109.024058828226;753.7248904150056;755.4560716284376;756.2928661701217;758.9354904833041;772.1853444615872;12947.935260323638;13094.476220951055;13217.33913304331;13511.797323040162;13556.728865422636;720.3336913355125;725.600919494276;733.6258508160877;736.988945165823;748.5284270909292;5849.27446403312;5944.197533333031;5965.955957707384;6075.058365953284;6162.599431036968;142457;146994;147191;153486.23256883645;153975;127209;129427.35286323508;130670;130739;133319;111075;114396.42801785938;114472;115055;119887;11314.773704525698;11374.43127843621;11378.407023016618;11441.656750297505;11582.768344633228;52289.477105229984;53950;54473.18290225729;54536.72731636316;54577;69889.65055174692;71161;71239.64380178066;71680.28319716945;73216;18048.458546243633;18048.548786280448;18149.637007259516;18408.723869141704;18419.53951151232;18404;19072.33246836354;19103.904480477508;19353.709694354315;19540.413787586396;17442.30230790805;17642.38251661186;17643.823561764733;17664.675149364106;17735.556611084827;15186;15647.452339168074;15679.451219207273;15692.764608530653;15811;16160.838391616404;16168.67662646764;16218.189090545662;16374.590635234214;16403.507894763177;11630.127740419672;11669.35818529989;11738.471768770254;11803.822942655695;11947.522099115939;13140.540081097115;13251.734965300833;13288.202707837561;13328.200307981551;13522.661933451742 12:59:55 INFO - PID 1063 | __end_tp_report 12:59:55 INFO - PID 1063 | __start_cc_report 12:59:55 INFO - PID 1063 | _x_x_mozilla_cycle_collect,354 12:59:55 INFO - PID 1063 | __end_cc_report 12:59:55 INFO - PID 1063 | __startTimestamp1505505595269__endTimestamp 12:59:55 INFO - PID 1063 | 12:59:55 INFO - PID 1063 | ------- Summary: start ------- 12:59:55 INFO - PID 1063 | Number of tests: 6 12:59:55 INFO - PID 1063 | 12:59:55 INFO - PID 1063 | [#0] dojo.html Cycles:165 Average:6259.41 Median:6524.67 stddev:753.01 (11.5%) stddev-sans-first:731.07 12:59:55 INFO - PID 1063 | Values: 3843.0 3882.5 3885.0 3899.6 3900.1 6201.4 6207.8 6218.0 6230.2 6256.8 6282.5 6288.4 6302.3 6331.0 6340.4 5506.3 5564.6 5569.0 5575.9 5587.2 5530.8 5630.0 5661.2 5662.2 5700.5 5618.2 5660.7 5675.4 5675.4 5703.2 5403.1 5545.4 5547.3 5598.9 5635.8 5530.4 5568.6 5593.7 5594.6 5619.5 6160.2 6240.6 6246.9 6265.9 6319.1 6759.0 6803.2 6816.6 6848.2 6874.9 6827.0 6899.0 6904.8 6922.4 6928.8 6292.9 6312.3 6375.6 6395.9 6424.1 6885.2 6945.4 7001.7 7007.9 7009.7 6684.7 6687.6 6706.3 6770.0 6771.3 6800.1 6848.7 6862.7 6911.6 6944.2 6780.6 6785.6 6846.2 6863.2 6876.2 6701.5 6743.2 6778.9 6795.3 6845.2 4941.2 4957.8 4975.1 4976.6 4989.5 4454.4 4489.6 4522.4 4529.6 4568.1 6172.0 6190.0 6270.4 6272.6 6281.7 6776.4 6783.5 6835.4 6850.1 6858.7 6036.1 6128.7 6180.4 6213.4 6236.5 5549.0 5554.4 5578.9 5585.9 5588.2 7040.2 7053.9 7150.0 7155.5 7183.5 6598.8 6746.1 6757.6 6845.1 6849.9 7052.8 7055.1 7064.6 7066.5 7067.2 6610.7 6639.2 6707.2 6716.8 6743.5 6883.3 6916.6 6917.9 6918.5 6927.2 6567.4 6568.8 6646.5 6650.4 6654.6 6507.3 6540.0 6553.9 6636.9 6642.8 6486.2 6519.2 6522.0 6532.8 6537.5 6616.1 6659.5 6666.7 6687.9 6728.5 6428.1 6437.3 6445.7 6524.7 6530.5 12:59:55 INFO - PID 1063 | 12:59:55 INFO - PID 1063 | [#1] ext.html Cycles:180 Average:42194.12 Median:20869.96 stddev:62948.91 (301.6%) stddev-sans-first:63049.05 12:59:55 INFO - PID 1063 | Values: 876.1 913.9 920.0 959.9 970.5 43891.6 44408.0 44593.8 44734.0 44921.6 41201.4 45511.0 45527.0 45555.1 45594.3 8033.9 8136.3 8159.6 8166.4 8175.1 8041.8 8056.2 8123.2 8161.5 8170.3 8147.3 8156.7 8157.3 8212.8 8219.7 8164.6 8166.8 8169.4 8179.3 8198.5 7920.4 7931.8 7947.8 7999.8 8039.7 41406.7 41515.8 41545.8 41580.0 41662.8 3754.5 3797.9 3799.3 3799.8 3801.4 32771.0 32927.7 33137.5 33145.7 33220.8 5324.3 5339.3 5349.0 5361.8 5423.8 202738.0 205534.0 208242.0 213480.0 220980.0 54546.2 54906.7 55018.4 55025.4 55037.0 57131.0 57327.4 57343.0 57408.7 57446.7 16603.4 16765.3 16793.1 16798.0 16871.2 13138.9 13602.3 13889.0 13894.7 13895.2 14785.5 14926.6 14943.7 14983.0 15102.3 5510.0 5548.4 5590.3 5605.9 5629.5 1070.9 1074.0 1094.9 1102.2 1105.5 25233.5 25435.7 25472.7 25531.5 25708.9 1178.5 1184.4 1185.3 1193.6 1202.9 13244.6 13360.1 13433.6 13455.2 13466.5 276087.0 278047.0 278090.0 278292.0 279146.0 59950.0 59984.4 59988.1 60043.4 60162.7 1504.0 1508.8 1520.4 1548.7 1556.5 24657.4 24761.8 24772.8 24869.1 24912.1 39549.8 41297.8 41437.0 41472.3 41500.0 230987.0 232456.0 233356.0 233426.0 233912.0 34288.0 34548.7 37259.6 37417.8 37942.1 46997.3 47213.5 47387.3 47603.3 47683.3 33649.0 34189.0 34643.0 34693.0 35013.8 33686.3 33863.5 33878.3 34109.7 34647.5 39431.8 40800.4 40912.0 40969.6 41218.2 32983.3 33396.5 33557.0 33753.3 34028.0 25177.9 25266.7 25458.6 25797.5 25825.5 12:59:55 INFO - PID 1063 | 12:59:55 INFO - PID 1063 | [#2] jquery.html Cycles:180 Average:38893.92 Median:2342.55 stddev:96097.34 (4102.2%) stddev-sans-first:96323.89 12:59:55 INFO - PID 1063 | Values: 591.7 592.8 593.4 611.5 635.7 23535.3 24324.4 24340.0 24377.1 24402.4 24842.3 27262.3 27308.5 27454.7 27526.4 6762.1 6890.6 6950.8 6958.7 6960.9 6468.2 6752.2 6779.7 6826.8 6896.1 6703.8 6704.1 6707.6 6708.9 6712.8 6479.9 6691.9 6734.3 6742.1 6746.2 6211.3 6230.3 6234.1 6238.7 6254.7 30909.7 31317.8 31350.4 31382.0 31426.7 2315.1 2320.1 2328.8 2341.5 2343.6 21125.9 21300.4 21818.5 21978.7 22013.6 2430.5 2450.8 2511.0 2549.5 2549.8 136205.0 143283.0 143402.0 149455.3 150620.0 15815.7 15840.8 16081.5 16216.3 16905.7 25978.2 26095.7 26127.0 26153.2 26167.9 10494.1 10500.8 10566.3 10645.6 10682.9 9610.1 9633.7 9646.3 9717.1 9742.5 9735.3 9861.0 10143.8 10180.1 10272.3 2882.2 2887.8 2956.5 2964.1 2972.7 1043.5 1048.7 1065.4 1075.6 1081.3 21802.1 21895.0 21923.2 22382.6 22599.1 1117.3 1121.9 1132.3 1149.9 1152.5 11839.3 11934.0 12080.0 12082.9 12131.5 560946.0 570093.0 576594.0 580183.0 582992.0 124612.0 126616.4 126733.0 129849.4 131732.0 1137.3 1237.9 1253.8 1269.4 1296.9 22782.0 23098.3 23148.7 23288.2 23570.4 60441.7 60557.7 60691.4 60828.1 60957.4 78555.6 78906.2 79187.2 79216.6 79849.0 15708.8 16059.2 16192.4 16213.8 16267.6 25497.5 25597.1 25733.2 25760.1 25812.2 19342.8 19352.7 20443.6 20962.8 21015.9 16356.3 16792.8 17180.5 17186.4 17188.4 19572.6 19626.1 19687.7 19784.1 19975.3 15592.9 15648.8 15779.5 15807.2 15812.4 20154.2 20163.7 20354.4 20424.5 20622.3 12:59:55 INFO - PID 1063 | 12:59:55 INFO - PID 1063 | [#3] mootools.html Cycles:180 Average:10362.37 Median:25078.12 stddev:7638.29 (30.5%) stddev-sans-first:7630.26 12:59:55 INFO - PID 1063 | Values: 1434.2 1481.0 1496.6 1507.3 1507.9 16240.4 16770.7 16878.6 16887.3 16891.7 16585.8 16692.5 16762.0 16768.2 16778.6 2810.3 2843.8 2854.6 2855.5 2859.0 2659.6 2755.7 2760.7 2761.6 2769.4 2734.2 2820.4 2823.6 2825.0 2826.1 2811.0 2843.3 2867.3 2871.1 2871.8 2798.2 2828.2 2828.5 2829.7 2839.0 15956.6 16008.3 16120.4 16296.7 16358.9 8424.6 8809.8 8814.0 8814.3 8827.3 5558.1 5925.9 5943.9 5960.2 6103.6 2026.9 2042.4 2042.8 2078.4 2087.3 29890.9 30154.0 30306.4 30378.8 30433.7 7096.1 7101.3 7248.5 7399.6 7461.5 19025.7 19057.8 19111.6 19116.0 19185.4 8599.3 8830.8 8856.6 8857.1 8865.5 7781.6 7810.0 7831.7 7895.9 7910.7 5479.5 5599.9 5645.2 5668.8 5718.6 1550.3 1551.3 1652.9 1655.6 1665.6 966.7 971.1 981.2 1002.6 1002.7 8474.2 8549.3 8574.5 8591.4 8631.3 1053.6 1065.1 1070.0 1071.3 1075.4 5144.6 5144.9 5197.4 5218.2 5267.4 24003.4 24911.6 25077.2 25079.0 25200.7 23387.8 23987.9 24383.8 24748.8 24985.6 11908.8 11925.8 12216.9 12221.3 12261.4 9820.2 9880.7 9961.0 9998.9 10031.6 21000.4 21054.0 21234.6 21348.9 21776.6 22011.1 22256.9 22384.7 22648.4 22731.4 13090.7 13287.5 13477.8 13497.9 13501.9 15462.0 15725.3 15726.8 15736.1 15745.8 11589.1 11767.6 11771.6 11966.3 12058.9 11699.0 11788.4 11815.2 12199.3 12202.8 11010.6 11088.9 11123.5 11166.4 11175.5 7840.9 7868.5 7927.9 7960.5 8092.4 8033.9 8097.6 8175.0 8212.2 8225.7 12:59:55 INFO - PID 1063 | 12:59:55 INFO - PID 1063 | [#4] prototype.html Cycles:180 Average:5586.92 Median:6157.48 stddev:1568.93 (25.5%) stddev-sans-first:1526.70 12:59:55 INFO - PID 1063 | Values: 528.5 551.2 559.5 559.8 570.4 6198.9 6239.3 6259.5 6273.8 6311.9 6067.0 6110.4 6144.5 6148.5 6206.9 5044.0 5069.2 5069.9 5072.5 5078.4 4773.7 5072.1 5074.5 5090.2 5096.8 4845.4 4862.0 4862.8 4877.0 4887.9 4833.7 4842.1 4860.1 4867.1 4873.3 4397.3 4401.4 4411.2 4417.0 4444.2 6073.5 6127.3 6149.4 6165.6 6184.8 5752.6 5790.9 5796.9 5867.1 5878.9 6517.2 6581.0 6631.5 6637.9 6657.6 5012.5 5015.9 5019.3 5043.2 5053.7 6701.4 6797.6 6906.8 6922.9 6930.2 5096.7 5110.5 5119.3 5159.7 5181.6 5148.8 5152.9 5178.9 5185.3 5197.2 5764.4 5768.8 5774.8 5779.2 5807.6 6488.0 6518.3 6551.7 6629.7 6659.8 1771.9 1785.7 1792.5 1816.9 1818.2 1220.2 1248.7 1279.4 1290.7 1291.2 5954.2 6018.4 6028.6 6088.4 6092.0 6630.7 6651.2 6663.4 6696.3 6714.2 5899.5 6030.9 6078.2 6117.8 6191.9 3492.4 3504.6 3543.7 3571.3 3581.3 6989.7 6994.8 7003.4 7022.3 7029.4 6755.5 6865.6 6884.0 6885.0 6888.4 6981.4 6988.8 7005.9 7035.2 7086.1 6636.1 6672.6 6693.0 6704.4 6768.3 6620.2 6630.5 6657.5 6659.7 6733.9 6778.4 6909.7 6946.3 6959.7 7009.7 5101.4 5161.2 5164.4 5168.6 5177.2 6519.9 6607.9 6698.8 6747.9 6825.7 6379.6 6408.7 6486.0 6525.3 6565.1 6411.2 6443.5 6475.7 6488.2 6515.6 6446.8 6472.4 6493.8 6523.7 6546.6 6584.2 6594.8 6647.7 6681.4 6682.4 6293.2 6387.3 6446.3 6469.1 6501.7 12:59:55 INFO - PID 1063 | 12:59:55 INFO - PID 1063 | [#5] yui.html Cycles:180 Average:28036.10 Median:18227.82 stddev:42124.42 (231.1%) stddev-sans-first:42193.58 12:59:55 INFO - PID 1063 | Values: 972.6 997.2 1008.4 1009.6 1015.1 20836.5 21246.5 21308.7 21358.5 21383.5 18786.4 19158.1 19947.7 20081.2 20135.1 1058.4 1094.8 1096.9 1097.0 1097.7 1043.8 1076.6 1077.5 1090.8 1091.5 1135.6 1141.4 1141.6 1141.7 1144.0 1093.5 1124.5 1129.8 1132.9 1134.3 793.9 794.6 795.3 800.2 812.7 19441.1 19603.0 19632.7 19659.8 19676.1 22694.8 24165.2 24319.9 24410.1 24420.5 17543.0 18203.6 18362.4 18483.9 18503.8 3888.5 3921.6 3926.6 3929.6 3946.9 157109.2 157432.0 158894.0 161837.0 162372.0 17985.7 17986.6 18095.0 18252.0 18524.7 33777.7 34397.5 34506.8 34615.3 35059.1 8650.6 8671.2 8678.7 8703.8 8727.3 4734.6 4756.6 4777.8 4813.4 4843.0 6082.8 6232.1 6253.1 6263.4 6285.6 1068.1 1082.5 1107.2 1107.6 1109.0 753.7 755.5 756.3 758.9 772.2 12947.9 13094.5 13217.3 13511.8 13556.7 720.3 725.6 733.6 737.0 748.5 5849.3 5944.2 5966.0 6075.1 6162.6 142457.0 146994.0 147191.0 153486.2 153975.0 127209.0 129427.4 130670.0 130739.0 133319.0 111075.0 114396.4 114472.0 115055.0 119887.0 11314.8 11374.4 11378.4 11441.7 11582.8 52289.5 53950.0 54473.2 54536.7 54577.0 69889.7 71161.0 71239.6 71680.3 73216.0 18048.5 18048.5 18149.6 18408.7 18419.5 18404.0 19072.3 19103.9 19353.7 19540.4 17442.3 17642.4 17643.8 17664.7 17735.6 15186.0 15647.5 15679.5 15692.8 15811.0 16160.8 16168.7 16218.2 16374.6 16403.5 11630.1 11669.4 11738.5 11803.8 11947.5 13140.5 13251.7 13288.2 13328.2 13522.7 12:59:55 INFO - PID 1063 | -------- Summary: end -------- 12:59:55 INFO - PID 1063 | 12:59:55 INFO - TEST-INFO | 1063: exit 0 12:59:55 INFO - TEST-OK | dromaeo_css | took 1108959ms 12:59:55 INFO - TEST-START | kraken 12:59:55 INFO - Initialising browser for kraken test... 12:59:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60308/getInfo.html -profile /tmp/tmpIvRKXT/profile 12:59:56 INFO - TEST-INFO | started process 1515 (/builds/slave/test/build/application/firefox/firefox http://localhost:60308/getInfo.html) 13:00:01 INFO - TEST-INFO | 1515: exit 0 13:00:01 INFO - Browser initialized. 13:00:01 INFO - Running cycle 1/1 for kraken test... 13:00:01 INFO - Using env: {'DISPLAY': ':0', 13:00:01 INFO - 'HOME': '/home/cltbld', 13:00:01 INFO - 'JSGC_DISABLE_POISONING': '1', 13:00:01 INFO - 'LANG': 'en_US.UTF-8', 13:00:01 INFO - 'LANGUAGE': 'en_US:en', 13:00:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 13:00:01 INFO - 'LOGNAME': 'cltbld', 13:00:01 INFO - 'MAIL': '/var/mail/cltbld', 13:00:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:00:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:00:01 INFO - 'MOZ_CRASHREPORTER': '1', 13:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 13:00:01 INFO - 'MOZ_NO_REMOTE': '1', 13:00:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:01 INFO - 'NO_EM_RESTART': '1', 13:00:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:01 INFO - 'PWD': '/builds/slave/test', 13:00:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:00:01 INFO - 'RUST_BACKTRACE': 'full', 13:00:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:00:01 INFO - 'SHELL': '/bin/bash', 13:00:01 INFO - 'SHLVL': '1', 13:00:01 INFO - 'TERM': 'linux', 13:00:01 INFO - 'TMOUT': '86400', 13:00:01 INFO - 'USER': 'cltbld', 13:00:01 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688', 13:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:01 INFO - '_': '/tools/buildbot/bin/python'} 13:00:01 INFO - TEST-INFO | started process 1748 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIvRKXT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:00:02 INFO - PID 1748 | 13:00:02 INFO - PID 1748 | (/builds/slave/test/build/application/firefox/firefox:1798): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:00:02 INFO - PID 1748 | 13:00:12 INFO - PID 1748 | RSS: Main: 167038976 13:00:12 INFO - PID 1748 | 13:00:15 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60308/tests/kraken/driver.html?testName=audio-beat-detection) 13:00:16 INFO - PID 1748 | RSS: Main: 171843584 13:00:16 INFO - PID 1748 | 13:00:18 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60308/tests/kraken/driver.html?testName=audio-dft) 13:00:19 INFO - PID 1748 | RSS: Main: 172650496 13:00:19 INFO - PID 1748 | 13:00:22 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60308/tests/kraken/driver.html?testName=audio-fft) 13:00:22 INFO - PID 1748 | RSS: Main: 167354368 13:00:22 INFO - PID 1748 | 13:00:24 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60308/tests/kraken/driver.html?testName=audio-oscillator) 13:00:25 INFO - PID 1748 | RSS: Main: 168652800 13:00:25 INFO - PID 1748 | 13:00:27 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60308/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:00:28 INFO - PID 1748 | RSS: Main: 168742912 13:00:28 INFO - PID 1748 | 13:00:31 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60308/tests/kraken/driver.html?testName=imaging-darkroom) 13:00:31 INFO - PID 1748 | RSS: Main: 171929600 13:00:31 INFO - PID 1748 | 13:00:35 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60308/tests/kraken/driver.html?testName=imaging-desaturate) 13:00:36 INFO - PID 1748 | RSS: Main: 172199936 13:00:36 INFO - PID 1748 | 13:00:39 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60308/tests/kraken/driver.html?testName=json-parse-financial) 13:00:39 INFO - PID 1748 | RSS: Main: 171835392 13:00:39 INFO - PID 1748 | 13:00:41 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60308/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:00:41 INFO - PID 1748 | RSS: Main: 171667456 13:00:41 INFO - PID 1748 | 13:00:43 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60308/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:00:43 INFO - PID 1748 | RSS: Main: 171630592 13:00:43 INFO - PID 1748 | 13:00:46 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60308/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:00:46 INFO - PID 1748 | RSS: Main: 168742912 13:00:46 INFO - PID 1748 | 13:00:49 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60308/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:00:49 INFO - PID 1748 | RSS: Main: 168222720 13:00:49 INFO - PID 1748 | 13:00:52 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60308/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:00:53 INFO - PID 1748 | RSS: Main: 168943616 13:00:53 INFO - PID 1748 | 13:00:54 INFO - PID 1748 | Cycle 1(1): loaded http://localhost:60308/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60308/tests/kraken/driver.html?testName=ai-astar) 13:00:55 INFO - PID 1748 | RSS: Main: 170532864 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | __start_tp_report 13:00:55 INFO - PID 1748 | _x_x_mozilla_page_load 13:00:55 INFO - PID 1748 | _x_x_mozilla_page_load_details 13:00:55 INFO - PID 1748 | |i|pagename|runs| 13:00:55 INFO - PID 1748 | |0;ai-astar;107;104;105;108;105;107;107;106;106;105 13:00:55 INFO - PID 1748 | |1;audio-beat-detection;113;114;113;121;117;111;112;115;113;114 13:00:55 INFO - PID 1748 | |2;audio-dft;162;161;162;165;160;162;163;162;163;165 13:00:55 INFO - PID 1748 | |3;audio-fft;75;76;73;73;74;75;72;73;73;74 13:00:55 INFO - PID 1748 | |4;audio-oscillator;78;79;78;78;81;81;82;78;77;78 13:00:55 INFO - PID 1748 | |5;imaging-gaussian-blur;115;114;117;114;114;114;115;115;114;115 13:00:55 INFO - PID 1748 | |6;imaging-darkroom;150;149;149;150;150;149;150;149;150;151 13:00:55 INFO - PID 1748 | |7;imaging-desaturate;94;94;94;95;94;93;94;93;96;93 13:00:55 INFO - PID 1748 | |8;json-parse-financial;47;47;54;46;45;46;46;45;45;45 13:00:55 INFO - PID 1748 | |9;json-stringify-tinderbox;39;38;38;39;38;39;40;40;39;39 13:00:55 INFO - PID 1748 | |10;stanford-crypto-aes;75;74;76;71;73;71;72;77;72;71 13:00:55 INFO - PID 1748 | |11;stanford-crypto-ccm;109;108;109;107;111;107;108;114;111;104 13:00:55 INFO - PID 1748 | |12;stanford-crypto-pbkdf2;167;154;158;155;162;156;156;155;157;157 13:00:55 INFO - PID 1748 | |13;stanford-crypto-sha256-iterative;66;61;62;61;59;60;59;62;61;65 13:00:55 INFO - PID 1748 | __end_tp_report 13:00:55 INFO - PID 1748 | __start_cc_report 13:00:55 INFO - PID 1748 | _x_x_mozilla_cycle_collect,927 13:00:55 INFO - PID 1748 | __end_cc_report 13:00:55 INFO - PID 1748 | __startTimestamp1505505655177__endTimestamp 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | ------- Summary: start ------- 13:00:55 INFO - PID 1748 | Number of tests: 14 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#0] ai-astar Cycles:10 Average:106.00 Median:106.50 stddev:1.25 (1.2%) stddev-sans-first:1.27 13:00:55 INFO - PID 1748 | Values: 107.0 104.0 105.0 108.0 105.0 107.0 107.0 106.0 106.0 105.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#1] audio-beat-detection Cycles:10 Average:114.30 Median:114.00 stddev:2.87 (2.5%) stddev-sans-first:3.00 13:00:55 INFO - PID 1748 | Values: 113.0 114.0 113.0 121.0 117.0 111.0 112.0 115.0 113.0 114.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:1.58 (1.0%) stddev-sans-first:1.67 13:00:55 INFO - PID 1748 | Values: 162.0 161.0 162.0 165.0 160.0 162.0 163.0 162.0 163.0 165.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#3] audio-fft Cycles:10 Average:73.80 Median:74.00 stddev:1.23 (1.7%) stddev-sans-first:1.22 13:00:55 INFO - PID 1748 | Values: 75.0 76.0 73.0 73.0 74.0 75.0 72.0 73.0 73.0 74.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:78.50 stddev:1.70 (2.2%) stddev-sans-first:1.76 13:00:55 INFO - PID 1748 | Values: 78.0 79.0 78.0 78.0 81.0 81.0 82.0 78.0 77.0 78.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:115.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 13:00:55 INFO - PID 1748 | Values: 115.0 114.0 117.0 114.0 114.0 114.0 115.0 115.0 114.0 115.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 13:00:55 INFO - PID 1748 | Values: 150.0 149.0 149.0 150.0 150.0 149.0 150.0 149.0 150.0 151.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.94 (1.0%) stddev-sans-first:1.00 13:00:55 INFO - PID 1748 | Values: 94.0 94.0 94.0 95.0 94.0 93.0 94.0 93.0 96.0 93.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#8] json-parse-financial Cycles:10 Average:46.60 Median:46.00 stddev:2.72 (5.9%) stddev-sans-first:2.88 13:00:55 INFO - PID 1748 | Values: 47.0 47.0 54.0 46.0 45.0 46.0 46.0 45.0 45.0 45.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#9] json-stringify-tinderbox Cycles:10 Average:38.90 Median:39.00 stddev:0.74 (1.9%) stddev-sans-first:0.78 13:00:55 INFO - PID 1748 | Values: 39.0 38.0 38.0 39.0 38.0 39.0 40.0 40.0 39.0 39.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:73.50 stddev:2.20 (3.0%) stddev-sans-first:2.24 13:00:55 INFO - PID 1748 | Values: 75.0 74.0 76.0 71.0 73.0 71.0 72.0 77.0 72.0 71.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#11] stanford-crypto-ccm Cycles:10 Average:108.80 Median:109.00 stddev:2.74 (2.5%) stddev-sans-first:2.91 13:00:55 INFO - PID 1748 | Values: 109.0 108.0 109.0 107.0 111.0 107.0 108.0 114.0 111.0 104.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.70 Median:157.00 stddev:3.95 (2.5%) stddev-sans-first:2.35 13:00:55 INFO - PID 1748 | Values: 167.0 154.0 158.0 155.0 162.0 156.0 156.0 155.0 157.0 157.0 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.50 stddev:2.32 (3.8%) stddev-sans-first:1.83 13:00:55 INFO - PID 1748 | Values: 66.0 61.0 62.0 61.0 59.0 60.0 59.0 62.0 61.0 65.0 13:00:55 INFO - PID 1748 | -------- Summary: end -------- 13:00:55 INFO - PID 1748 | 13:00:55 INFO - PID 1748 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 13:00:55 INFO - TEST-INFO | 1748: exit 0 13:00:55 INFO - TEST-OK | kraken | took 59874ms 13:00:55 INFO - SUITE-END | took 1168s 13:00:56 INFO - Completed test suite (00:19:29) 13:00:56 INFO - javascript benchmark 13:00:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9042.07424905064, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3843.0, 3882.5340959084915, 3885.028742814296, 3899.5905429929876, 3900.1029763154456, 6201.441870231678, 6207.813765587034, 6218.0, 6230.158928544651, 6256.781012664557, 6282.52881033922, 6288.371162883709, 6302.306746257908, 6331.01869210272, 6340.397662222082, 5506.284183056217, 5564.582656300783, 5569.0254205514075, 5575.916361254584, 5587.189857470665, 5530.806421775239, 5630.042892708229, 5661.2357331760095, 5662.207290979266, 5700.458456446651, 5618.241537392461, 5660.660360378391, 5675.404082571333, 5675.432456754333, 5703.230063941377, 5403.108487099624, 5545.445455454463, 5547.3343198816, 5598.86002849932, 5635.802746903839, 5530.391656917735, 5568.582356323292, 5593.748281327309, 5594.552435805166, 5619.4942455178825, 6160.229971253593, 6240.594361366497, 6246.906296405557, 6265.934791085527, 6319.052142178664, 6759.0, 6803.183617965827, 6816.625085620289, 6848.178218613748, 6874.8625027499675, 6826.965865170642, 6898.965505172492, 6904.792856214322, 6922.376986071277, 6928.826779330507, 6292.874142517124, 6312.305646378849, 6375.553711240215, 6395.904061439082, 6424.132742079806, 6885.173779146535, 6945.37491625746, 7001.684924178424, 7007.894881576781, 7009.719611215596, 6684.732610695518, 6687.6321802301345, 6706.26231114577, 6769.9845023245925, 6771.28901465339, 6800.115984921928, 6848.657567121624, 6862.656867156722, 6911.619860907598, 6944.201416837027, 6780.593164410151, 6785.626790526569, 6846.246912839583, 6863.210730765926, 6876.209235937931, 6701.497387695845, 6743.190817101881, 6778.932210677929, 6795.320467953264, 6845.247022827485, 4941.209406494943, 4957.752112394366, 4975.129352363394, 4976.576990955737, 4989.451160372356, 4454.443194600675, 4489.6408287336935, 4522.3668686383935, 4529.569690879362, 4568.132054909556, 6171.9691401542705, 6190.0, 6270.435660790551, 6272.6236425814595, 6281.748730050838, 6776.390124888685, 6783.491238157158, 6835.4189893859575, 6850.143732033496, 6858.6570671466225, 6036.094585812163, 6128.693565321716, 6180.412860778219, 6213.409726076015, 6236.532260080512, 5549.0289199389945, 5554.389017208104, 5578.86052848682, 5585.916211256835, 5588.189712491666, 7040.190378106481, 7053.92946070543, 7150.034745309421, 7155.499115062, 7183.5330703504105, 6598.769043083469, 6746.089277947414, 6757.594544327356, 6845.075914751545, 6849.931500685128, 7052.788416347517, 7055.082839230867, 7064.611446370499, 7066.540674856118, 7067.222605513492, 6610.6694665267505, 6639.203295604365, 6707.1616047994, 6716.764913228014, 6743.52795304324, 6883.311668833277, 6916.619585922823, 6917.896231556631, 6918.481113916376, 6927.238003819475, 6567.44176744982, 6568.8357791055605, 6646.534742567974, 6650.434713049445, 6654.567453115532, 6507.349265073455, 6539.967300163468, 6553.901691474536, 6636.900446493403, 6642.833929151617, 6486.189226346707, 6519.152510173647, 6521.9673901630185, 6532.804015879531, 6537.477001839747, 6616.106825578485, 6659.53383263167, 6666.733330666719, 6687.866242675216, 6728.529002969745, 6428.132202152649, 6437.25971513282, 6445.709943052668, 6524.67376631176, 6530.510211734044], "value": 6207.79622675688, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [876.1028659038858, 913.8629205619152, 920.0431551186765, 959.8896126945403, 970.5001924009132, 43891.56108438915, 44408.0, 44593.77703111488, 44733.999999999956, 44921.550784492145, 41201.38197927026, 45511.0, 45527.0, 45555.08889822201, 45594.316085258666, 8033.879491807642, 8136.3084137848355, 8159.592020398986, 8166.387520935924, 8175.059868115153, 8041.839163216761, 8056.154103819103, 8123.187681231859, 8161.510309381427, 8170.305524030465, 8147.26674599286, 8156.673733050671, 8157.347412207039, 8212.794680132985, 8219.71231006915, 8164.6325915333955, 8166.754997350059, 8169.387295952827, 8179.345652347798, 8198.467099638534, 7920.366370690389, 7931.762047138595, 7947.801304967422, 7999.760007199793, 8039.718609848627, 41406.687465002724, 41515.7924210377, 41545.79227103845, 41580.0, 41662.79168604168, 3754.511913451261, 3797.867074652374, 3799.3161230978412, 3799.8480060797533, 3801.4107813288706, 32771.0, 32927.6707232927, 33137.50293745596, 33145.668543314496, 33220.83727069565, 5324.281222035014, 5339.332583427072, 5349.025406372072, 5361.839144825662, 5423.755930983115, 202738.0, 205534.0, 208242.0, 213480.0, 220980.0, 54546.18180727292, 54906.72546637241, 55018.449815501335, 55025.44974550283, 55037.0, 57131.0, 57327.42672573304, 57343.0, 57408.71295643495, 57446.7127664359, 16603.41888033913, 16765.329386824633, 16793.076380798928, 16798.0, 16871.156442177842, 13138.934305328603, 13602.31988400576, 13889.027768056341, 13894.722105558034, 13895.166290022631, 14785.48250811238, 14926.552203433914, 14943.701125977419, 14983.0, 15102.320395582225, 5510.0, 5548.4174161712435, 5590.273264475673, 5605.92663639407, 5629.493345598916, 1070.9357438553711, 1073.9570417183381, 1094.9124070074374, 1102.2339474065607, 1105.5190991918541, 25233.495330093294, 25435.74564254371, 25472.745272547407, 25531.489370212865, 25708.871455642602, 1178.4696886401155, 1184.3545267829054, 1185.2947496239724, 1193.6061099837034, 1202.915795894279, 13244.602661920158, 13360.131591446525, 13433.596992090253, 13455.192688438725, 13466.461341546425, 276087.0, 278047.0, 278090.0, 278292.0, 279146.0, 59950.0, 59984.40015599788, 59988.100178497356, 60043.39956600466, 60162.699186504666, 1503.9849601503845, 1508.8038554987781, 1520.3538496139317, 1548.705745908274, 1556.533040087969, 24657.424713396656, 24761.761911904694, 24772.788625933994, 24869.129580464596, 24912.12807551727, 39549.80225098856, 41297.79351103225, 41437.0, 41472.34110635541, 41500.0, 230987.0, 232456.0, 233356.0, 233426.0, 233912.0, 34288.0, 34548.654513454545, 37259.627403725615, 37417.812910935274, 37942.051448714, 46997.295040573736, 47213.52786472091, 47387.289190661475, 47603.28595071007, 47683.28475072946, 33649.0, 34189.0, 34643.0, 34693.0, 35013.8249308762, 33686.326273473904, 33863.49204761881, 33878.322433551686, 34109.65890341065, 34647.4802877952, 39431.80284098676, 40800.3879941807, 40912.0, 40969.59030409777, 41218.1756364877, 32983.34033319272, 33396.49905251375, 33557.0, 33753.32493350168, 34028.0, 25177.87411062933, 25266.747332527175, 25458.618120728574, 25797.51314445207, 25825.483490329712], "value": 18518.094018852204, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.7218907113657, 592.834006478186, 593.3621357041181, 611.4649681528662, 635.6662752055171, 23535.29394118175, 24324.391890202754, 24340.026398944043, 24377.14679986201, 24402.3899402515, 24842.254732358015, 27262.31844203896, 27308.45383092337, 27454.725452745468, 27526.44947101062, 6762.053312536249, 6890.586564806103, 6950.756723514678, 6958.652067396635, 6960.930390696079, 6468.223813142406, 6752.189737231539, 6779.661016949133, 6826.795196144099, 6896.137982752156, 6703.8324041899095, 6704.094947182117, 6707.631080290582, 6708.899366509506, 6712.798616041527, 6479.870402591969, 6691.899621505681, 6734.292899245582, 6742.089817874597, 6746.224184218804, 6211.34780848009, 6230.314665386803, 6234.064890266496, 6238.719257633372, 6254.7498100075945, 30909.690903090905, 31317.8434107828, 31350.37299254025, 31382.0, 31426.685733142604, 2315.131825565413, 2320.1183550250967, 2328.84862483938, 2341.4731685370657, 2343.6367363058753, 21125.89437052805, 21300.360989170196, 21818.454538636503, 21978.670319945213, 22013.559728805496, 2430.4774473488283, 2450.816188785848, 2511.033252197917, 2549.4646124313917, 2549.79601631869, 136205.0, 143283.0, 143402.0, 149455.25272373678, 150620.0, 15815.68368632644, 15840.76238856418, 16081.51755447315, 16216.270267837976, 16905.746413803558, 25978.220653380427, 26095.73904260971, 26127.0, 26153.215403537924, 26167.869160654074, 10494.108000820015, 10500.844907060216, 10566.31318964265, 10645.627403040857, 10682.94658526718, 9610.135087842127, 9633.710988670351, 9646.324757266924, 9717.076877696607, 9742.464164471023, 9735.269854760922, 9861.0, 10143.847842282372, 10180.134688551556, 10272.33229840073, 2882.198625858838, 2887.7978541501893, 2956.512175491063, 2964.1403992842265, 2972.70272972701, 1043.5304113149114, 1048.6591857646295, 1065.3767545985531, 1075.5858994287255, 1081.291753901196, 21802.127914883225, 21895.0, 21923.232686855885, 22382.55234895293, 22599.09603615837, 1117.3016864459712, 1121.8653761548667, 1132.28665940457, 1149.9425028748528, 1152.464104191555, 11839.348835814113, 11934.0, 12080.0, 12082.912537871634, 12131.514739410324, 560946.0, 570093.0, 576594.0, 580183.0, 582992.0, 124612.0, 126616.36691816481, 126733.0, 129849.35075324752, 131732.0, 1137.3460260350332, 1237.8762123787549, 1253.7680529101963, 1269.3653173413293, 1296.9481220751254, 22781.99352421419, 23098.307050788502, 23148.6527702088, 23288.184913527944, 23570.410739731644, 60441.69779151076, 60557.69721151542, 60691.39308607034, 60828.08757868724, 60957.39042609694, 78555.60722196581, 78906.21093788989, 79187.20812791798, 79216.60391698005, 79849.0, 15708.764368534252, 16059.197040147712, 16192.433264835674, 16213.756793648341, 16267.59331016734, 25497.490050199263, 25597.104101356363, 25733.228003159937, 25760.09839655678, 25812.22563323103, 19342.8065719341, 19352.70970935409, 20443.59112817765, 20962.790372096082, 21015.894920525912, 16356.263968121226, 16792.832071679128, 17180.484585462455, 17186.398476053277, 17188.398406055727, 19572.608547829248, 19626.116824742636, 19687.70468443003, 19784.109715063143, 19975.300864469675, 15592.908496405144, 15648.826338024466, 15779.526614201592, 15807.209639517749, 15812.367505299659, 20154.193832247132, 20163.697544537135, 20354.389368318974, 20424.489387765425, 20622.27822026282], "value": 12943.31687970291, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.2470203143348, 1481.0003247807726, 1496.5659958612002, 1507.321705232645, 1507.917064561449, 16240.35038598456, 16770.66458670826, 16878.578035549086, 16887.324507019704, 16891.662166756658, 16585.83414165858, 16692.499225023268, 16762.0, 16768.161591920416, 16778.58053548662, 2810.339570201001, 2843.7724982001496, 2854.557543580748, 2855.5145625243654, 2858.985705071472, 2659.6276521287036, 2755.738204870534, 2760.7377299156547, 2761.64098667173, 2769.4322663853864, 2734.220747087081, 2820.421813528232, 2823.6329277193936, 2824.9717502825015, 2826.081523504859, 2810.985945070272, 2843.2749648839517, 2867.3261783480866, 2871.0812539987214, 2871.7559007484388, 2798.1885253276523, 2828.222238884303, 2828.5191517442086, 2829.698338764171, 2838.957415638767, 15956.601084972852, 16008.279627416794, 16120.435784747595, 16296.674066518603, 16358.918205409016, 8424.620892059873, 8809.823803523894, 8814.0, 8814.294856411472, 8827.337949653738, 5558.110702287655, 5925.940740592625, 5943.940560594425, 5960.195373624549, 6103.6032657877095, 2026.8682535635137, 2042.3770749921268, 2042.7548694156649, 2078.449210959082, 2087.2590230468268, 29890.850545747133, 30154.0, 30306.393872122655, 30378.84810575955, 30433.69566304331, 7096.148462184571, 7101.254368291255, 7248.456365772483, 7399.630018499161, 7461.477696561188, 19025.668203225643, 19057.80942190588, 19111.617767644846, 19116.0, 19185.424437266905, 8599.269062129664, 8830.779230519289, 8856.601452934634, 8857.11428857122, 8865.512396818109, 7781.610919454005, 7810.0237470316215, 7831.725889593837, 7895.9210407896335, 7910.7231246906085, 5479.53423958968, 5599.860003499945, 5645.181448689917, 5668.773249070074, 5718.599698021098, 1550.3411050303575, 1551.3251735495096, 1652.8760342974324, 1655.6274838161316, 1665.600255938566, 966.7051549277538, 971.1065819446127, 981.1611072532882, 1002.6340385759146, 1002.674130907458, 8474.237318641352, 8549.27331176857, 8574.528400938008, 8591.398602097794, 8631.309495240366, 1053.599632139782, 1065.1106326217541, 1070.0476575847501, 1071.3304184884448, 1075.4246478134316, 5144.61415393847, 5144.922826157611, 5197.350331208599, 5218.165093585084, 5267.420583735786, 24003.399915002265, 24911.62632560513, 25077.247682569552, 25079.0, 25200.74799251984, 23387.7661223389, 23987.880060599586, 24383.7561624385, 24748.752512475006, 24985.62521562178, 11908.821367679491, 11925.76148477043, 12216.877831221751, 12221.266723996589, 12261.448234829453, 9820.214382849212, 9880.654177103766, 9960.950195248977, 9998.90012098654, 10031.598736050477, 21000.36998890036, 21054.0, 21234.575308493433, 21348.89325553362, 21776.564468710854, 22011.11955521761, 22256.888715556317, 22384.664230036236, 22648.433789154744, 22731.431714207276, 13090.672733181365, 13287.468501259842, 13477.797833032317, 13497.86502134966, 13501.932490337485, 15462.0, 15725.292361843516, 15726.842731572538, 15736.134512601917, 15745.842541574895, 11589.130815188726, 11767.588134415255, 11771.587994420153, 11966.341851198267, 12058.879411205775, 11699.0, 11788.410579471163, 11815.232009919328, 12199.268043917393, 12202.81695774582, 11010.623672040994, 11088.944555277494, 11123.499442524944, 11166.44167791591, 11175.49710263024, 7840.882386764317, 7868.488548244346, 7927.920720792718, 7960.522368657899, 8092.393070519853, 8033.919660803317, 8097.595120243846, 8174.959125204574, 8212.17878212174, 8225.670973161006], "value": 7289.461032894749, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.4715284715285, 551.1677367175564, 559.4685049203256, 559.8096647139971, 570.3982298674899, 6198.915189841777, 6239.251289845225, 6259.53053520986, 6273.7804176853815, 6311.928805920179, 6066.969665151679, 6110.358412366704, 6144.477719393857, 6148.538859585527, 6206.937930620692, 5043.974780126095, 5069.163588007993, 5069.898602027957, 5072.543471087602, 5078.441371449156, 4773.6897101688455, 5072.112380333445, 5074.517920797518, 5090.185570308751, 5096.847094587169, 4845.370101886768, 4861.951380486203, 4862.756862156895, 4876.951230487685, 4887.902241955158, 4833.734144622044, 4842.12841688496, 4860.1008813369635, 4867.050925069613, 4873.269009648546, 4397.340398940152, 4401.383806267125, 4411.228035093878, 4416.955830441687, 4444.20004399208, 6073.453389194994, 6127.264728232596, 6149.385061493859, 6165.568410211287, 6184.752609895599, 5752.626079304831, 5790.942090579082, 5796.88406231873, 5867.061270196748, 5878.911816322759, 6517.152770139852, 6581.0, 6631.502637302174, 6637.93362066378, 6657.633830139341, 5012.498750124958, 5015.949840501585, 5019.272205530177, 5043.167877300241, 5053.722045287508, 6701.363370479797, 6797.592144471348, 6906.792796216071, 6922.896156557656, 6930.20302665195, 5096.719680417538, 5110.4634013428795, 5119.257707632447, 5159.742012899415, 5181.5854731621375, 5148.845534633967, 5152.948470515322, 5178.870528236749, 5185.292515851184, 5197.24639927216, 5764.365919748824, 5768.817392434625, 5774.82675519735, 5779.248697669276, 5807.593468457252, 6487.967560162263, 6518.28298887122, 6551.737930482727, 6629.734810607618, 6659.834528957413, 1771.8582513398896, 1785.7321401789836, 1792.4622613216243, 1816.8819026763217, 1818.1818181818235, 1220.2177457529099, 1248.7065539598186, 1279.4050766393486, 1290.7224946636331, 1291.1994563370583, 5954.166416701665, 6018.398160183947, 6028.608140470856, 6088.391160883876, 6091.969540152271, 6630.701618427182, 6651.16860392451, 6663.36698013688, 6696.263411024784, 6714.227863795629, 5899.46904778572, 6030.868854834453, 6078.240219972504, 6117.755289788359, 6191.854506916235, 3492.406290930497, 3504.6495350464747, 3543.7165026797793, 3571.321448924696, 3581.28374325136, 6989.685464154125, 6994.790156295319, 7003.404710599554, 7022.262662420474, 7029.402500787491, 6755.49333799967, 6865.5537390070485, 6883.965580172067, 6885.0, 6888.380045795902, 6981.441484681213, 6988.755393561201, 7005.92994070063, 7035.226125126232, 7086.114235720535, 6636.13730215069, 6672.633005184762, 6693.0, 6704.396604305636, 6768.255491895986, 6620.238672552526, 6630.46956243509, 6657.500687448558, 6659.667016649051, 6733.865322693421, 6778.389944904982, 6909.654517274015, 6946.270641582763, 6959.652017399211, 7009.684564194521, 5101.362329708786, 5161.225816127611, 5164.406093299169, 5168.612354073534, 5177.197534382177, 6519.869602608016, 6607.933920660924, 6698.765543205965, 6747.898781518183, 6825.692843822139, 6379.649119298483, 6408.68621932512, 6486.027095935648, 6525.3034211104505, 6565.113709649328, 6411.166548348871, 6443.484521238383, 6475.740970361321, 6488.221413430419, 6515.609063456208, 6446.8388290293115, 6472.449841763315, 6493.7986472502735, 6523.67381630907, 6546.574472659262, 6584.242812076481, 6594.8351291218105, 6647.736929983288, 6681.365270299314, 6682.365175308338, 6293.150424692608, 6387.329330420239, 6446.258680251831, 6469.126667899774, 6501.707423165874], "value": 5134.427982729961, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [972.5817898303727, 997.2470784557659, 1008.354653022066, 1009.6213919780082, 1015.0661391519803, 20836.479088022807, 21246.468838279052, 21308.680369794445, 21358.466038349048, 21383.465413364673, 18786.436406907815, 19158.13788379523, 19947.700784488243, 20081.196752129934, 20135.093920773565, 1058.3808472043845, 1094.8467214589964, 1096.8628921384827, 1097.040089921319, 1097.7091070866227, 1043.801677681242, 1076.569372251098, 1077.450500244873, 1090.8309212072102, 1091.5142761471147, 1135.5968631135952, 1141.4007645985882, 1141.5833220874363, 1141.7488152606413, 1143.982840257397, 1093.5024563823442, 1124.5276983666838, 1129.8022846001916, 1132.8527291452099, 1134.3024040215291, 793.8650429426954, 794.6424109150905, 795.312055072364, 800.1718221640574, 812.687115460546, 19441.125149368312, 19603.0, 19632.70550941737, 19659.80340196594, 19676.114574844167, 22694.773052269433, 24165.154219602406, 24319.878400607882, 24410.145644902343, 24420.511589768106, 17543.0, 18203.63592728138, 18362.449126526226, 18483.907580462146, 18503.81496185048, 3888.51393575803, 3921.627445392683, 3926.6466018058227, 3929.607039296076, 3946.881593552198, 157109.214453927, 157431.99999999886, 158894.0, 161837.0, 162371.9999999988, 17985.7302140468, 17986.5503362417, 18095.0, 18252.0, 18524.722129168073, 33777.662223377454, 34397.4521146549, 34506.82746586301, 34615.307693845985, 35059.12352191165, 8650.610722517502, 8671.176238257356, 8678.6528538859, 8703.782405439788, 8727.345449091343, 4734.597559207437, 4756.57190852818, 4777.761111944389, 4813.350197723333, 4842.982973575588, 6082.756689732361, 6232.0963460298, 6253.1245625612455, 6263.404976527223, 6285.622862628257, 1068.1401471815109, 1082.5290998415715, 1107.2194103157256, 1107.595727559451, 1109.024058828226, 753.7248904150056, 755.4560716284376, 756.2928661701217, 758.9354904833041, 772.1853444615872, 12947.935260323638, 13094.476220951055, 13217.33913304331, 13511.797323040162, 13556.728865422636, 720.3336913355125, 725.600919494276, 733.6258508160877, 736.988945165823, 748.5284270909292, 5849.27446403312, 5944.197533333031, 5965.955957707384, 6075.058365953284, 6162.599431036968, 142457.0, 146994.0, 147191.0, 153486.23256883645, 153975.0, 127209.0, 129427.35286323508, 130670.0, 130739.0, 133319.0, 111075.0, 114396.42801785938, 114472.0, 115055.0, 119887.0, 11314.773704525698, 11374.43127843621, 11378.407023016618, 11441.656750297505, 11582.768344633228, 52289.477105229984, 53950.0, 54473.18290225729, 54536.72731636316, 54577.0, 69889.65055174692, 71161.0, 71239.64380178066, 71680.28319716945, 73216.0, 18048.458546243633, 18048.548786280448, 18149.637007259516, 18408.723869141704, 18419.53951151232, 18404.0, 19072.33246836354, 19103.904480477508, 19353.709694354315, 19540.413787586396, 17442.30230790805, 17642.38251661186, 17643.823561764733, 17664.675149364106, 17735.556611084827, 15186.0, 15647.452339168074, 15679.451219207273, 15692.764608530653, 15811.0, 16160.838391616404, 16168.67662646764, 16218.189090545662, 16374.590635234214, 16403.507894763177, 11630.127740419672, 11669.35818529989, 11738.471768770254, 11803.822942655695, 11947.522099115939, 13140.540081097115, 13251.734965300833, 13288.202707837561, 13328.200307981551, 13522.661933451742], "value": 9813.284984095162, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1380.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 104.0, 105.0, 108.0, 105.0, 107.0, 107.0, 106.0, 106.0, 105.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 121.0, 117.0, 111.0, 112.0, 115.0, 113.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 165.0, 160.0, 162.0, 163.0, 162.0, 163.0, 165.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 73.0, 73.0, 74.0, 75.0, 72.0, 73.0, 73.0, 74.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 78.0, 78.0, 81.0, 81.0, 82.0, 78.0, 77.0, 78.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 117.0, 114.0, 114.0, 114.0, 115.0, 115.0, 114.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 149.0, 150.0, 150.0, 149.0, 150.0, 149.0, 150.0, 151.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 93.0, 94.0, 93.0, 96.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 47.0, 54.0, 46.0, 45.0, 46.0, 46.0, 45.0, 45.0, 45.0], "value": 46.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 38.0, 39.0, 38.0, 39.0, 40.0, 40.0, 39.0, 39.0], "value": 38.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 71.0, 73.0, 71.0, 72.0, 77.0, 72.0, 71.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 108.0, 109.0, 107.0, 111.0, 107.0, 108.0, 114.0, 111.0, 104.0], "value": 108.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 154.0, 158.0, 155.0, 162.0, 156.0, 156.0, 155.0, 157.0, 157.0], "value": 157.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 61.0, 62.0, 61.0, 59.0, 60.0, 59.0, 62.0, 61.0, 65.0], "value": 61.6, "unit": "score"}]}]} 13:00:56 INFO - Return code: 0 13:00:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:00:56 INFO - # TBPL SUCCESS # 13:00:56 INFO - Running post-action listener: _package_coverage_data 13:00:56 INFO - Running post-action listener: _resource_record_post_action 13:00:56 INFO - [mozharness: 2017-09-15 20:00:56.456426Z] Finished run-tests step (success) 13:00:56 INFO - Running post-run listener: _resource_record_post_run 13:00:56 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 12288; Write bytes: 340127744; Read time: 224; Write time: 245264 13:00:56 INFO - TinderboxPrint: CPU usage
13.1% 13:00:56 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 224 13:00:56 INFO - TinderboxPrint: I/O write bytes / time
340,127,744 / 245,264 13:00:56 INFO - TinderboxPrint: CPU idle
8,227.8 (86.9%) 13:00:56 INFO - TinderboxPrint: CPU user
1,188.3 (12.6%) 13:00:56 INFO - TinderboxPrint: Swap in / out
0 / 0 13:00:56 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12574720; Read time: 0; Write time: 1600 13:00:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:00:56 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 12288; Write bytes: 326938624; Read time: 224; Write time: 243664 13:00:57 INFO - Running post-run listener: _upload_blobber_files 13:00:57 INFO - Blob upload gear active. 13:00:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:00:57 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 13:00:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:00:57 INFO - (blobuploader) - INFO - Open directory for files ... 13:00:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:00:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:00:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:00:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:00:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:00:58 INFO - (blobuploader) - INFO - Done attempting. 13:00:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:00:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:00:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:00:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:00:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:00:59 INFO - (blobuploader) - INFO - Done attempting. 13:00:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:00:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:00:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:01:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:01:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:01:00 INFO - (blobuploader) - INFO - Done attempting. 13:01:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:01:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:01:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:01:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:01:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:01:07 INFO - (blobuploader) - INFO - Done attempting. 13:01:07 INFO - (blobuploader) - INFO - Iteration through files over. 13:01:07 INFO - Return code: 0 13:01:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:01:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:01:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47eb5454696e90506d1f71228df93d0697a136f8e7360686f1bda9bcaba0e8d2c10a98680b91e1108e78e1db346c0ded1d157aabc025a7354362af32c509e393", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b012b7fe74f2487cde74634f69f27c8198b322dd52afed7dc3625892fd143e3d93cced8ea985ade5a838896fadcd024fdb0dc1fe3264d3629e9aea291411f8a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/759e94747a8d0a09c165e414e2cb6da4e180f39483c3db35157d2673f43aa659fa9aea006241a88ca3fa4da93a268cb29f304109eda8f60302cd9eb8572fba92", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5bed14b83476b31a4f3d06e583b36f0d065db5e884d9f908519929f1c062072840a58458aaac36f978ac015560037ff08ffe4ee08aa4bcc6ecbee31d8b041e5"} 13:01:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:01:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:01:07 INFO - Contents: 13:01:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47eb5454696e90506d1f71228df93d0697a136f8e7360686f1bda9bcaba0e8d2c10a98680b91e1108e78e1db346c0ded1d157aabc025a7354362af32c509e393", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b012b7fe74f2487cde74634f69f27c8198b322dd52afed7dc3625892fd143e3d93cced8ea985ade5a838896fadcd024fdb0dc1fe3264d3629e9aea291411f8a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/759e94747a8d0a09c165e414e2cb6da4e180f39483c3db35157d2673f43aa659fa9aea006241a88ca3fa4da93a268cb29f304109eda8f60302cd9eb8572fba92", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5bed14b83476b31a4f3d06e583b36f0d065db5e884d9f908519929f1c062072840a58458aaac36f978ac015560037ff08ffe4ee08aa4bcc6ecbee31d8b041e5"} 13:01:07 INFO - Running post-run listener: copy_logs_to_upload_dir 13:01:07 INFO - Copying logs to upload dir... 13:01:07 INFO - mkdir: /builds/slave/test/build/upload/logs 13:01:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.011562 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-15 13:01:08.042242) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 13:01:08.049643) ========= 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=c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47eb5454696e90506d1f71228df93d0697a136f8e7360686f1bda9bcaba0e8d2c10a98680b91e1108e78e1db346c0ded1d157aabc025a7354362af32c509e393", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b012b7fe74f2487cde74634f69f27c8198b322dd52afed7dc3625892fd143e3d93cced8ea985ade5a838896fadcd024fdb0dc1fe3264d3629e9aea291411f8a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/759e94747a8d0a09c165e414e2cb6da4e180f39483c3db35157d2673f43aa659fa9aea006241a88ca3fa4da93a268cb29f304109eda8f60302cd9eb8572fba92", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5bed14b83476b31a4f3d06e583b36f0d065db5e884d9f908519929f1c062072840a58458aaac36f978ac015560037ff08ffe4ee08aa4bcc6ecbee31d8b041e5"} build_url:https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011382 build_url: 'https://queue.taskcluster.net/v1/task/Yrrp_8EOT5an_C13Djd2RA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47eb5454696e90506d1f71228df93d0697a136f8e7360686f1bda9bcaba0e8d2c10a98680b91e1108e78e1db346c0ded1d157aabc025a7354362af32c509e393", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b012b7fe74f2487cde74634f69f27c8198b322dd52afed7dc3625892fd143e3d93cced8ea985ade5a838896fadcd024fdb0dc1fe3264d3629e9aea291411f8a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/759e94747a8d0a09c165e414e2cb6da4e180f39483c3db35157d2673f43aa659fa9aea006241a88ca3fa4da93a268cb29f304109eda8f60302cd9eb8572fba92", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5bed14b83476b31a4f3d06e583b36f0d065db5e884d9f908519929f1c062072840a58458aaac36f978ac015560037ff08ffe4ee08aa4bcc6ecbee31d8b041e5"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 13:01:08.101991) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:01:08.102654) ========= 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=c6927cf182745ad25b1d38b9000001fe-1505504224.633535-38678688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004562 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 13:01:08.182533) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 13:01:08.187203) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 13:01:08.188344) ========= ========= Total master_lag: 0.31 =========