builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-010 starttime: 1506558035.44 results: success (0) revision: 69e3f89816455e567f1a20b694fd6afd549c82c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:35.436938) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:35.437361) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:35.437641) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:35.453350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:35.453655) ========= 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=eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 17:20:35-- 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% 225M=0s 2017-09-27 17:20:35 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174402 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:35.647452) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:35.647759) ========= 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=eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017002 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:35.696988) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 17:20:35.697299) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 69e3f89816455e567f1a20b694fd6afd549c82c7 --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 69e3f89816455e567f1a20b694fd6afd549c82c7 --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=eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 17:20:35,735 truncating revision to first 12 chars 2017-09-27 17:20:35,735 Setting DEBUG logging. 2017-09-27 17:20:35,735 attempt 1/10 2017-09-27 17:20:35,735 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/69e3f8981645?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 17:20:36,868 unpacking tar archive at: mozilla-central-69e3f8981645/testing/mozharness/ program finished with exit code 0 elapsedTime=1.426507 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 17:20:37.142678) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:37.142986) ========= script_repo_revision: 69e3f89816455e567f1a20b694fd6afd549c82c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:37.143375) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:37.146651) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 17:20:37.163187) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-27 17:20:37.163468) ========= /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=eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:20:37 INFO - MultiFileLogger online at 20170927 17:20:37 in /builds/slave/test 17:20:37 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 17:20:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:20:37 INFO - {'append_to_log': False, 17:20:37 INFO - 'base_work_dir': '/builds/slave/test', 17:20:37 INFO - 'blob_upload_branch': 'Firefox', 17:20:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:20:37 INFO - 'branch': 'Firefox', 17:20:37 INFO - 'buildbot_json_path': 'buildprops.json', 17:20:37 INFO - 'code_coverage': False, 17:20:37 INFO - 'config_files': ('talos/linux_config.py',), 17:20:37 INFO - 'default_actions': ('clobber', 17:20:37 INFO - 'read-buildbot-config', 17:20:37 INFO - 'download-and-extract', 17:20:37 INFO - 'populate-webroot', 17:20:37 INFO - 'create-virtualenv', 17:20:37 INFO - 'install', 17:20:37 INFO - 'setup-mitmproxy', 17:20:37 INFO - 'run-tests'), 17:20:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:20:37 INFO - 'disable_ccov_upload': False, 17:20:37 INFO - 'disable_stylo': False, 17:20:37 INFO - 'download_minidump_stackwalk': True, 17:20:37 INFO - 'download_symbols': 'ondemand', 17:20:37 INFO - 'enable_stylo': False, 17:20:37 INFO - 'enable_webrender': False, 17:20:37 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:20:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:20:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:20:37 INFO - 'gecko_profile': False, 17:20:37 INFO - 'gecko_profile_interval': 0, 17:20:37 INFO - 'installer_path': 'installer.exe', 17:20:37 INFO - 'log_level': 'info', 17:20:37 INFO - 'log_name': 'talos', 17:20:37 INFO - 'log_to_console': True, 17:20:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:20:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:20:37 INFO - 'opt_config_files': (), 17:20:37 INFO - 'pip_index': False, 17:20:37 INFO - 'suite': 'dromaeojs-e10s', 17:20:37 INFO - 'system_bits': '32', 17:20:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:20:37 INFO - 'title': 'talos-linux64-ix-010', 17:20:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:20:37 INFO - 'use_talos_json': True, 17:20:37 INFO - 'verify': 'False', 17:20:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:20:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:20:37 INFO - 'work_dir': 'build'} 17:20:37 INFO - [mozharness: 2017-09-28 00:20:37.326907Z] Running clobber step. 17:20:37 INFO - Running pre-action listener: _resource_record_pre_action 17:20:37 INFO - Running main action method: clobber 17:20:37 INFO - rmtree: /builds/slave/test/build 17:20:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:20:38 INFO - Running post-action listener: _resource_record_post_action 17:20:38 INFO - [mozharness: 2017-09-28 00:20:38.310991Z] Finished clobber step (success) 17:20:38 INFO - [mozharness: 2017-09-28 00:20:38.311089Z] Running read-buildbot-config step. 17:20:38 INFO - Running pre-action listener: _resource_record_pre_action 17:20:38 INFO - Running main action method: read_buildbot_config 17:20:38 INFO - Using buildbot properties: 17:20:38 INFO - { 17:20:38 INFO - "project": "", 17:20:38 INFO - "product": "firefox", 17:20:38 INFO - "who": "archaeopteryx@coole-files.de", 17:20:38 INFO - "installer_path": "public/build/target.tar.bz2", 17:20:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:20:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:20:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 17:20:38 INFO - "stage_platform": "linux64-pgo", 17:20:38 INFO - "basedir": "/builds/slave/test", 17:20:38 INFO - "buildnumber": 213, 17:20:38 INFO - "platform": "ubuntu64_hw", 17:20:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:20:38 INFO - "slavebuilddir": "test", 17:20:38 INFO - "taskId": "GC9oeqdLT1CzhUvM1FaosA", 17:20:38 INFO - "branch": "mozilla-central", 17:20:38 INFO - "script_repo_revision": "production", 17:20:38 INFO - "revision": "69e3f89816455e567f1a20b694fd6afd549c82c7", 17:20:38 INFO - "slavename": "talos-linux64-ix-010", 17:20:38 INFO - "repo_path": "mozilla-central" 17:20:38 INFO - } 17:20:38 INFO - Finding installer, test and symbols from parent task. 17:20:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GC9oeqdLT1CzhUvM1FaosA'}, attempt #1 17:20:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GC9oeqdLT1CzhUvM1FaosA'}, attempt #1 17:20:39 INFO - Task dependencies: IgMi7h1TRIKpKFonaQA_1Q 17:20:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q'}, attempt #1 17:20:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.tar.bz2 17:20:39 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.test_packages.json 17:20:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.crashreporter-symbols.zip 17:20:39 INFO - Running post-action listener: _resource_record_post_action 17:20:39 INFO - [mozharness: 2017-09-28 00:20:39.674508Z] Finished read-buildbot-config step (success) 17:20:39 INFO - [mozharness: 2017-09-28 00:20:39.674726Z] Running download-and-extract step. 17:20:39 INFO - Running pre-action listener: _resource_record_pre_action 17:20:39 INFO - Running main action method: download_and_extract 17:20:39 INFO - mkdir: /builds/slave/test/build/tests 17:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:39 INFO - trying https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.test_packages.json 17:20:39 INFO - Downloading https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:20:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:20:40 INFO - Downloaded 989 bytes. 17:20:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:20:40 INFO - Using the following test package requirements: 17:20:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:20:40 INFO - u'common': [u'target.common.tests.zip'], 17:20:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:20:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:20:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:20:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:20:40 INFO - u'mozbase': [u'target.common.tests.zip'], 17:20:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:20:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:20:40 INFO - u'web-platform': [u'target.common.tests.zip', 17:20:40 INFO - u'target.web-platform.tests.tar.gz'], 17:20:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:20:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:20:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.common.tests.zip 17:20:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:20:40 INFO - Fetch https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.common.tests.zip into memory 17:20:41 INFO - Content-Length response header: 55350054 17:20:41 INFO - Bytes received: 55350054 17:20:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.talos.tests.zip 17:20:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:20:52 INFO - Fetch https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.talos.tests.zip into memory 17:20:53 INFO - Content-Length response header: 14513779 17:20:53 INFO - Bytes received: 14513779 17:20:53 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')]} 17:20:53 INFO - trying https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.tar.bz2 17:20:53 INFO - Downloading https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:20:54 INFO - Downloaded 62383395 bytes. 17:20:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.tar.bz2 17:20:54 INFO - mkdir: /builds/slave/test/properties 17:20:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:20:54 INFO - Writing to file /builds/slave/test/properties/build_url 17:20:54 INFO - Contents: 17:20:54 INFO - build_url:https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.tar.bz2 17:20:54 INFO - Running post-action listener: _resource_record_post_action 17:20:54 INFO - Running post-action listener: find_tests_for_verification 17:20:54 INFO - Running post-action listener: set_extra_try_arguments 17:20:54 INFO - [mozharness: 2017-09-28 00:20:54.439535Z] Finished download-and-extract step (success) 17:20:54 INFO - [mozharness: 2017-09-28 00:20:54.439635Z] Running populate-webroot step. 17:20:54 INFO - Running pre-action listener: _resource_record_pre_action 17:20:54 INFO - Running main action method: populate_webroot 17:20:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:20:54 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['tresize', 17:20:54 INFO - 'tcanvasmark']}, 17:20:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:20:54 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['dromaeo_css', 17:20:54 INFO - 'kraken']}, 17:20:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:20:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:54 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:20:54 INFO - 'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:20:54 INFO - 'tests': ['damp', 'tps']}, 17:20:54 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:20:54 INFO - 'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['damp', 'tps']}, 17:20:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:20:54 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['dromaeo_dom']}, 17:20:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:20:54 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['basic_compositor_video', 17:20:54 INFO - 'glvideo']}, 17:20:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:20:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:20:54 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:20:54 INFO - 'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['ts_paint_webext', 17:20:54 INFO - 'tp5o_webext']}, 17:20:54 INFO - 'other-e10s': {'tests': ['a11yr', 17:20:54 INFO - 'ts_paint', 17:20:54 INFO - 'tpaint', 17:20:54 INFO - 'sessionrestore', 17:20:54 INFO - 'sessionrestore_many_windows', 17:20:54 INFO - 'sessionrestore_no_auto_restore', 17:20:54 INFO - 'tabpaint']}, 17:20:54 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['a11yr', 17:20:54 INFO - 'ts_paint', 17:20:54 INFO - 'tpaint', 17:20:54 INFO - 'sessionrestore', 17:20:54 INFO - 'sessionrestore_many_windows', 17:20:54 INFO - 'sessionrestore_no_auto_restore', 17:20:54 INFO - 'tabpaint']}, 17:20:54 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 17:20:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 17:20:54 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['perf_reftest_singletons']}, 17:20:54 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['perf_reftest']}, 17:20:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:20:54 INFO - 'tsvgr_opacity', 17:20:54 INFO - 'tart', 17:20:54 INFO - 'tscrollx', 17:20:54 INFO - 'tsvg_static']}, 17:20:54 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['tsvgx', 17:20:54 INFO - 'tsvgr_opacity', 17:20:54 INFO - 'tart', 17:20:54 INFO - 'tscrollx', 17:20:54 INFO - 'tsvg_static']}, 17:20:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:20:54 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:20:54 INFO - 'talos_options': ['--disable-stylo'], 17:20:54 INFO - 'tests': ['tp5o']}, 17:20:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:20:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:20:54 INFO - 'talos_options': ['--mitmproxy', 17:20:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:20:54 INFO - '--firstNonBlankPaint'], 17:20:54 INFO - 'tests': ['tp6_google', 17:20:54 INFO - 'tp6_youtube', 17:20:54 INFO - 'tp6_amazon', 17:20:54 INFO - 'tp6_facebook']}, 17:20:54 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:20:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:20:54 INFO - 'talos_options': ['--disable-stylo', 17:20:54 INFO - '--mitmproxy', 17:20:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:20:54 INFO - '--firstNonBlankPaint'], 17:20:54 INFO - 'tests': ['tp6_google', 17:20:54 INFO - 'tp6_youtube', 17:20:54 INFO - 'tp6_amazon', 17:20:54 INFO - 'tp6_facebook']}, 17:20:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:20:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:20:54 INFO - 'talos_options': ['--stylo-threads=1', 17:20:54 INFO - '--mitmproxy', 17:20:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:20:54 INFO - '--firstNonBlankPaint'], 17:20:54 INFO - 'tests': ['tp6_google', 17:20:54 INFO - 'tp6_youtube', 17:20:54 INFO - 'tp6_amazon', 17:20:54 INFO - 'tp6_facebook']}, 17:20:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:20:54 INFO - 'talos_options': ['--xperf_path', 17:20:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:54 INFO - 'tests': ['tp5n']}, 17:20:54 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:20:54 INFO - 'talos_options': ['--disable-stylo', 17:20:54 INFO - '--xperf_path', 17:20:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:54 INFO - 'tests': ['tp5n']}}} 17:20:54 INFO - Running post-action listener: _resource_record_post_action 17:20:54 INFO - [mozharness: 2017-09-28 00:20:54.455097Z] Finished populate-webroot step (success) 17:20:54 INFO - [mozharness: 2017-09-28 00:20:54.455186Z] Running create-virtualenv step. 17:20:54 INFO - Running pre-action listener: _resource_record_pre_action 17:20:54 INFO - Running main action method: create_virtualenv 17:20:54 INFO - Creating virtualenv /builds/slave/test/build/venv 17:20:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:20:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:20:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:20:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:20:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:20:56 INFO - Installing distribute.............................................................................................................................................................................................done. 17:20:56 INFO - Installing pip...............done. 17:20:56 INFO - Return code: 0 17:20:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:20:56 INFO - Reading from file tmpfile_stdout 17:20:56 INFO - Output received: 17:20:56 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:20:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:20: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')]} 17:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20: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')]} 17:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:20:56 INFO - Using env: {'DISPLAY': ':0', 17:20:56 INFO - 'HOME': '/home/cltbld', 17:20:56 INFO - 'LANG': 'en_US.UTF-8', 17:20:56 INFO - 'LANGUAGE': 'en_US:en', 17:20:56 INFO - 'LOGNAME': 'cltbld', 17:20:56 INFO - 'MAIL': '/var/mail/cltbld', 17:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:56 INFO - 'MOZ_NO_REMOTE': '1', 17:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:56 INFO - 'NO_EM_RESTART': '1', 17:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:56 INFO - 'PWD': '/builds/slave/test', 17:20:56 INFO - 'SHELL': '/bin/bash', 17:20:56 INFO - 'SHLVL': '1', 17:20:56 INFO - 'TERM': 'linux', 17:20:56 INFO - 'TMOUT': '86400', 17:20:56 INFO - 'USER': 'cltbld', 17:20:56 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:56 INFO - '_': '/tools/buildbot/bin/python'} 17:20:58 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:20:58 INFO - Downloading/unpacking pip>=1.5 17:20:58 INFO - Running setup.py egg_info for package pip 17:20:58 INFO - warning: no files found matching 'pip/cacert.pem' 17:20:58 INFO - warning: no files found matching '*.html' under directory 'docs' 17:20:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:20:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:20:58 INFO - Installing collected packages: pip 17:20:58 INFO - Found existing installation: pip 1.1 17:20:58 INFO - Uninstalling pip: 17:20:58 INFO - Successfully uninstalled pip 17:20:58 INFO - Running setup.py install for pip 17:20:58 INFO - warning: no files found matching 'pip/cacert.pem' 17:20:58 INFO - warning: no files found matching '*.html' under directory 'docs' 17:20:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:20:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:20:58 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:20:58 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:20:58 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:20:58 INFO - Successfully installed pip 17:20:58 INFO - Cleaning up... 17:20:58 INFO - Return code: 0 17:20:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:20:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:20:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:20:58 INFO - Using env: {'DISPLAY': ':0', 17:20:58 INFO - 'HOME': '/home/cltbld', 17:20:58 INFO - 'LANG': 'en_US.UTF-8', 17:20:58 INFO - 'LANGUAGE': 'en_US:en', 17:20:58 INFO - 'LOGNAME': 'cltbld', 17:20:58 INFO - 'MAIL': '/var/mail/cltbld', 17:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:58 INFO - 'MOZ_NO_REMOTE': '1', 17:20:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:58 INFO - 'NO_EM_RESTART': '1', 17:20:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:58 INFO - 'PWD': '/builds/slave/test', 17:20:58 INFO - 'SHELL': '/bin/bash', 17:20:58 INFO - 'SHLVL': '1', 17:20:58 INFO - 'TERM': 'linux', 17:20:58 INFO - 'TMOUT': '86400', 17:20:58 INFO - 'USER': 'cltbld', 17:20:58 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:58 INFO - '_': '/tools/buildbot/bin/python'} 17:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:58 INFO - Downloading/unpacking psutil>=3.1.1 17:20: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 17:20: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 17:20: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 17:20: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 17:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:20:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:59 INFO - Installing collected packages: psutil 17:20:59 INFO - Running setup.py install for psutil 17:20:59 INFO - building 'psutil._psutil_linux' extension 17:20:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:20:59 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 17:20:59 INFO - building 'psutil._psutil_posix' extension 17:20:59 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 17:20:59 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 17:20:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:59 INFO - Successfully installed psutil 17:20:59 INFO - Cleaning up... 17:20:59 INFO - Return code: 0 17:20:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:20: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')]} 17:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20: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')]} 17:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:21:00 INFO - Using env: {'DISPLAY': ':0', 17:21:00 INFO - 'HOME': '/home/cltbld', 17:21:00 INFO - 'LANG': 'en_US.UTF-8', 17:21:00 INFO - 'LANGUAGE': 'en_US:en', 17:21:00 INFO - 'LOGNAME': 'cltbld', 17:21:00 INFO - 'MAIL': '/var/mail/cltbld', 17:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:00 INFO - 'MOZ_NO_REMOTE': '1', 17:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:00 INFO - 'NO_EM_RESTART': '1', 17:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:00 INFO - 'PWD': '/builds/slave/test', 17:21:00 INFO - 'SHELL': '/bin/bash', 17:21:00 INFO - 'SHLVL': '1', 17:21:00 INFO - 'TERM': 'linux', 17:21:00 INFO - 'TMOUT': '86400', 17:21:00 INFO - 'USER': 'cltbld', 17:21:00 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:00 INFO - '_': '/tools/buildbot/bin/python'} 17:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:21:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:21:01 INFO - Installing collected packages: mozsystemmonitor 17:21:01 INFO - Running setup.py install for mozsystemmonitor 17:21:01 INFO - Successfully installed mozsystemmonitor 17:21:01 INFO - Cleaning up... 17:21:02 INFO - Return code: 0 17:21:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:21:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:21:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:21:02 INFO - Using env: {'DISPLAY': ':0', 17:21:02 INFO - 'HOME': '/home/cltbld', 17:21:02 INFO - 'LANG': 'en_US.UTF-8', 17:21:02 INFO - 'LANGUAGE': 'en_US:en', 17:21:02 INFO - 'LOGNAME': 'cltbld', 17:21:02 INFO - 'MAIL': '/var/mail/cltbld', 17:21:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:02 INFO - 'MOZ_NO_REMOTE': '1', 17:21:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:02 INFO - 'NO_EM_RESTART': '1', 17:21:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:02 INFO - 'PWD': '/builds/slave/test', 17:21:02 INFO - 'SHELL': '/bin/bash', 17:21:02 INFO - 'SHLVL': '1', 17:21:02 INFO - 'TERM': 'linux', 17:21:02 INFO - 'TMOUT': '86400', 17:21:02 INFO - 'USER': 'cltbld', 17:21:02 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:02 INFO - '_': '/tools/buildbot/bin/python'} 17:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:02 INFO - Downloading/unpacking jsonschema==2.5.1 17:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:21:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:04 INFO - Downloading functools32-3.2.3-2.tar.gz 17:21:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:21:04 INFO - warning: no files found matching '*.txt' 17:21:04 INFO - no previously-included directories found matching 'build' 17:21:04 INFO - no previously-included directories found matching 'dist' 17:21:04 INFO - no previously-included directories found matching '.git*' 17:21:04 INFO - Installing collected packages: jsonschema, functools32 17:21:04 INFO - Running setup.py install for functools32 17:21:04 INFO - warning: no files found matching '*.txt' 17:21:04 INFO - no previously-included directories found matching 'build' 17:21:04 INFO - no previously-included directories found matching 'dist' 17:21:04 INFO - no previously-included directories found matching '.git*' 17:21:04 INFO - Successfully installed jsonschema functools32 17:21:04 INFO - Cleaning up... 17:21:04 INFO - Return code: 0 17:21:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:21:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:21:04 INFO - Using env: {'DISPLAY': ':0', 17:21:04 INFO - 'HOME': '/home/cltbld', 17:21:04 INFO - 'LANG': 'en_US.UTF-8', 17:21:04 INFO - 'LANGUAGE': 'en_US:en', 17:21:04 INFO - 'LOGNAME': 'cltbld', 17:21:04 INFO - 'MAIL': '/var/mail/cltbld', 17:21:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:04 INFO - 'MOZ_NO_REMOTE': '1', 17:21:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:04 INFO - 'NO_EM_RESTART': '1', 17:21:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:04 INFO - 'PWD': '/builds/slave/test', 17:21:04 INFO - 'SHELL': '/bin/bash', 17:21:04 INFO - 'SHLVL': '1', 17:21:04 INFO - 'TERM': 'linux', 17:21:04 INFO - 'TMOUT': '86400', 17:21:04 INFO - 'USER': 'cltbld', 17:21:04 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:04 INFO - '_': '/tools/buildbot/bin/python'} 17:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:21:04 INFO - Cleaning up... 17:21:04 INFO - Return code: 0 17:21:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:21:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:21:04 INFO - Using env: {'DISPLAY': ':0', 17:21:04 INFO - 'HOME': '/home/cltbld', 17:21:04 INFO - 'LANG': 'en_US.UTF-8', 17:21:04 INFO - 'LANGUAGE': 'en_US:en', 17:21:04 INFO - 'LOGNAME': 'cltbld', 17:21:04 INFO - 'MAIL': '/var/mail/cltbld', 17:21:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:04 INFO - 'MOZ_NO_REMOTE': '1', 17:21:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:04 INFO - 'NO_EM_RESTART': '1', 17:21:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:04 INFO - 'PWD': '/builds/slave/test', 17:21:04 INFO - 'SHELL': '/bin/bash', 17:21:04 INFO - 'SHLVL': '1', 17:21:04 INFO - 'TERM': 'linux', 17:21:04 INFO - 'TMOUT': '86400', 17:21:04 INFO - 'USER': 'cltbld', 17:21:04 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:04 INFO - '_': '/tools/buildbot/bin/python'} 17:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:04 INFO - Downloading/unpacking blobuploader==1.2.4 17:21: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 17:21: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 17:21: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 17:21: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 17:21:05 INFO - Downloading blobuploader-1.2.4.tar.gz 17:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:21:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:21:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:21: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 17:21: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 17:21: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 17:21: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 17:21:06 INFO - Downloading docopt-0.6.1.tar.gz 17:21:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:21:06 INFO - Installing collected packages: blobuploader, requests, docopt 17:21:06 INFO - Running setup.py install for blobuploader 17:21:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:21:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:21:06 INFO - Running setup.py install for requests 17:21:06 INFO - Running setup.py install for docopt 17:21:06 INFO - Successfully installed blobuploader requests docopt 17:21:06 INFO - Cleaning up... 17:21:07 INFO - Return code: 0 17:21:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:21:07 INFO - Using env: {'DISPLAY': ':0', 17:21:07 INFO - 'HOME': '/home/cltbld', 17:21:07 INFO - 'LANG': 'en_US.UTF-8', 17:21:07 INFO - 'LANGUAGE': 'en_US:en', 17:21:07 INFO - 'LOGNAME': 'cltbld', 17:21:07 INFO - 'MAIL': '/var/mail/cltbld', 17:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:07 INFO - 'MOZ_NO_REMOTE': '1', 17:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:07 INFO - 'NO_EM_RESTART': '1', 17:21:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:07 INFO - 'PWD': '/builds/slave/test', 17:21:07 INFO - 'SHELL': '/bin/bash', 17:21:07 INFO - 'SHLVL': '1', 17:21:07 INFO - 'TERM': 'linux', 17:21:07 INFO - 'TMOUT': '86400', 17:21:07 INFO - 'USER': 'cltbld', 17:21:07 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:07 INFO - '_': '/tools/buildbot/bin/python'} 17:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:07 INFO - Running setup.py (path:/tmp/pip-CAsv5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:07 INFO - Running setup.py (path:/tmp/pip-Wqr3VP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:07 INFO - Running setup.py (path:/tmp/pip-aFd07u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:07 INFO - Running setup.py (path:/tmp/pip-Ne_3Kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:07 INFO - Running setup.py (path:/tmp/pip-JYwqrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:07 INFO - Running setup.py (path:/tmp/pip-bOhwiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:07 INFO - Running setup.py (path:/tmp/pip-AaU4Vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:07 INFO - Running setup.py (path:/tmp/pip-NsVw8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:07 INFO - Running setup.py (path:/tmp/pip-MKn9ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:07 INFO - Running setup.py (path:/tmp/pip-JYLjpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:07 INFO - Running setup.py (path:/tmp/pip-ZV3XFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:08 INFO - Running setup.py (path:/tmp/pip-sUW8sU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:08 INFO - Running setup.py (path:/tmp/pip-sPN10f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:08 INFO - Running setup.py (path:/tmp/pip-C0INO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:08 INFO - Running setup.py (path:/tmp/pip-bEr1PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:08 INFO - Running setup.py (path:/tmp/pip-7vEPTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:08 INFO - Running setup.py (path:/tmp/pip-sctV77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:21:08 INFO - Running setup.py install for manifestparser 17:21:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:21:08 INFO - Running setup.py install for mozcrash 17:21:08 INFO - Running setup.py install for mozdebug 17:21:08 INFO - Running setup.py install for mozdevice 17:21:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:21:08 INFO - Running setup.py install for mozfile 17:21:08 INFO - Running setup.py install for mozhttpd 17:21:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Running setup.py install for mozinfo 17:21:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Running setup.py install for mozInstall 17:21:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Running setup.py install for mozleak 17:21:09 INFO - Running setup.py install for mozlog 17:21:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Running setup.py install for moznetwork 17:21:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Running setup.py install for mozprocess 17:21:09 INFO - Running setup.py install for mozprofile 17:21:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Running setup.py install for mozrunner 17:21:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:21:09 INFO - Running setup.py install for mozscreenshot 17:21:09 INFO - Running setup.py install for moztest 17:21:10 INFO - Running setup.py install for mozversion 17:21:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:21:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:21:10 INFO - Cleaning up... 17:21:10 INFO - Return code: 0 17:21:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:21:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:21:10 INFO - Using env: {'DISPLAY': ':0', 17:21:10 INFO - 'HOME': '/home/cltbld', 17:21:10 INFO - 'LANG': 'en_US.UTF-8', 17:21:10 INFO - 'LANGUAGE': 'en_US:en', 17:21:10 INFO - 'LOGNAME': 'cltbld', 17:21:10 INFO - 'MAIL': '/var/mail/cltbld', 17:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:10 INFO - 'MOZ_NO_REMOTE': '1', 17:21:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:10 INFO - 'NO_EM_RESTART': '1', 17:21:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:10 INFO - 'PWD': '/builds/slave/test', 17:21:10 INFO - 'SHELL': '/bin/bash', 17:21:10 INFO - 'SHLVL': '1', 17:21:10 INFO - 'TERM': 'linux', 17:21:10 INFO - 'TMOUT': '86400', 17:21:10 INFO - 'USER': 'cltbld', 17:21:10 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:10 INFO - '_': '/tools/buildbot/bin/python'} 17:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:10 INFO - Running setup.py (path:/tmp/pip-rMLlOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:10 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)) 17:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:10 INFO - Running setup.py (path:/tmp/pip-UxTd71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:10 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)) 17:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:10 INFO - Running setup.py (path:/tmp/pip-Bc842k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:10 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)) 17:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:10 INFO - Running setup.py (path:/tmp/pip-1hzvz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:10 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)) 17:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:10 INFO - Running setup.py (path:/tmp/pip-hpjNej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:10 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)) 17:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:10 INFO - Running setup.py (path:/tmp/pip-TrFYJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:10 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)) 17:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:10 INFO - Running setup.py (path:/tmp/pip-8ohvj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:10 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)) 17:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:10 INFO - Running setup.py (path:/tmp/pip-hVk0sR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:11 INFO - Running setup.py (path:/tmp/pip-9b5EBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:11 INFO - Running setup.py (path:/tmp/pip-Ere131-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:11 INFO - Running setup.py (path:/tmp/pip-5fgHDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:11 INFO - Running setup.py (path:/tmp/pip-1iH9g4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:11 INFO - Running setup.py (path:/tmp/pip-lcjmi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:11 INFO - Running setup.py (path:/tmp/pip-PRA60d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:11 INFO - Running setup.py (path:/tmp/pip-O3IhVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:11 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)) 17:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:11 INFO - Running setup.py (path:/tmp/pip-S7ASbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:11 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)) 17:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:11 INFO - Running setup.py (path:/tmp/pip-e93llX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:11 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)) 17:21:11 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)) 17:21:11 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)) 17:21:11 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)) 17:21:11 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)) 17:21:11 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)) 17:21:11 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)) 17:21:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:12 INFO - Downloading blessings-1.6.tar.gz 17:21:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:21:12 INFO - Installing collected packages: blessings 17:21:12 INFO - Running setup.py install for blessings 17:21:12 INFO - Successfully installed blessings 17:21:12 INFO - Cleaning up... 17:21:13 INFO - Return code: 0 17:21:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:21:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:13 INFO - Reading from file tmpfile_stdout 17:21:13 INFO - Current package versions: 17:21:13 INFO - argparse == 1.2.1 17:21:13 INFO - blessings == 1.6 17:21:13 INFO - blobuploader == 1.2.4 17:21:13 INFO - docopt == 0.6.1 17:21:13 INFO - functools32 == 3.2.3-2 17:21:13 INFO - jsonschema == 2.5.1 17:21:13 INFO - manifestparser == 1.1 17:21:13 INFO - mozInstall == 1.13 17:21:13 INFO - mozcrash == 1.0 17:21:13 INFO - mozdebug == 0.1 17:21:13 INFO - mozdevice == 0.51 17:21:13 INFO - mozfile == 1.2 17:21:13 INFO - mozhttpd == 0.7 17:21:13 INFO - mozinfo == 0.10 17:21:13 INFO - mozleak == 0.1 17:21:13 INFO - mozlog == 3.5 17:21:13 INFO - moznetwork == 0.27 17:21:13 INFO - mozprocess == 0.25 17:21:13 INFO - mozprofile == 0.28 17:21:13 INFO - mozrunner == 6.13 17:21:13 INFO - mozscreenshot == 0.1 17:21:13 INFO - mozsystemmonitor == 0.3 17:21:13 INFO - moztest == 0.8 17:21:13 INFO - mozversion == 1.4 17:21:13 INFO - psutil == 3.1.1 17:21:13 INFO - requests == 1.2.3 17:21:13 INFO - wsgiref == 0.1.2 17:21:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:21:13 INFO - Using env: {'DISPLAY': ':0', 17:21:13 INFO - 'HOME': '/home/cltbld', 17:21:13 INFO - 'LANG': 'en_US.UTF-8', 17:21:13 INFO - 'LANGUAGE': 'en_US:en', 17:21:13 INFO - 'LOGNAME': 'cltbld', 17:21:13 INFO - 'MAIL': '/var/mail/cltbld', 17:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:13 INFO - 'MOZ_NO_REMOTE': '1', 17:21:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:13 INFO - 'NO_EM_RESTART': '1', 17:21:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:13 INFO - 'PWD': '/builds/slave/test', 17:21:13 INFO - 'SHELL': '/bin/bash', 17:21:13 INFO - 'SHLVL': '1', 17:21:13 INFO - 'TERM': 'linux', 17:21:13 INFO - 'TMOUT': '86400', 17:21:13 INFO - 'USER': 'cltbld', 17:21:13 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:13 INFO - '_': '/tools/buildbot/bin/python'} 17:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:13 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)) 17:21:13 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)) 17:21:13 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)) 17:21:13 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)) 17:21:13 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)) 17:21:13 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)) 17:21:13 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)) 17:21:13 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)) 17:21:13 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)) 17:21:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:21:13 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 17:21:13 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 17:21:13 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 17:21:13 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 17:21:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:21:14 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)) 17:21:14 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)) 17:21:14 INFO - Installing collected packages: simplejson 17:21:14 INFO - Running setup.py install for simplejson 17:21:14 INFO - building 'simplejson._speedups' extension 17:21:14 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 17:21:15 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 17:21:15 INFO - Successfully installed simplejson 17:21:15 INFO - Cleaning up... 17:21:15 INFO - Return code: 0 17:21:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:21:15 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')]} 17:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:15 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')]} 17:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:15 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, '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': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', '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 17:21:15 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 17:21:15 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 17:21:15 INFO - Using env: {'DISPLAY': ':0', 17:21:15 INFO - 'HOME': '/home/cltbld', 17:21:15 INFO - 'LANG': 'en_US.UTF-8', 17:21:15 INFO - 'LANGUAGE': 'en_US:en', 17:21:15 INFO - 'LOGNAME': 'cltbld', 17:21:15 INFO - 'MAIL': '/var/mail/cltbld', 17:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:15 INFO - 'MOZ_NO_REMOTE': '1', 17:21:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:15 INFO - 'NO_EM_RESTART': '1', 17:21:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:15 INFO - 'PWD': '/builds/slave/test', 17:21:15 INFO - 'SHELL': '/bin/bash', 17:21:15 INFO - 'SHLVL': '1', 17:21:15 INFO - 'TERM': 'linux', 17:21:15 INFO - 'TMOUT': '86400', 17:21:15 INFO - 'USER': 'cltbld', 17:21:15 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:15 INFO - '_': '/tools/buildbot/bin/python'} 17:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:21:15 INFO - Cleaning up... 17:21:15 INFO - Return code: 0 17:21:15 INFO - Running post-action listener: _resource_record_post_action 17:21:15 INFO - Running post-action listener: _start_resource_monitoring 17:21:15 INFO - Starting resource monitoring. 17:21:15 INFO - [mozharness: 2017-09-28 00:21:15.939069Z] Finished create-virtualenv step (success) 17:21:15 INFO - [mozharness: 2017-09-28 00:21:15.939533Z] Running install step. 17:21:15 INFO - Running pre-action listener: _resource_record_pre_action 17:21:15 INFO - Running main action method: install 17:21:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:16 INFO - Reading from file tmpfile_stdout 17:21:16 INFO - Detecting whether we're running mozinstall >=1.0... 17:21:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:21:16 INFO - Reading from file tmpfile_stdout 17:21:16 INFO - Output received: 17:21:16 INFO - Usage: mozinstall [options] installer 17:21:16 INFO - Options: 17:21:16 INFO - -h, --help show this help message and exit 17:21:16 INFO - -d DEST, --destination=DEST 17:21:16 INFO - Directory to install application into. [default: 17:21:16 INFO - "/builds/slave/test"] 17:21:16 INFO - --app=APP Application being installed. [default: firefox] 17:21:16 INFO - mkdir: /builds/slave/test/build/application 17:21:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:21:33 INFO - Reading from file tmpfile_stdout 17:21:33 INFO - Output received: 17:21:33 INFO - /builds/slave/test/build/application/firefox/firefox 17:21:33 INFO - Running post-action listener: _resource_record_post_action 17:21:33 INFO - [mozharness: 2017-09-28 00:21:33.922573Z] Finished install step (success) 17:21:33 INFO - [mozharness: 2017-09-28 00:21:33.922825Z] Running setup-mitmproxy step. 17:21:33 INFO - Running pre-action listener: _resource_record_pre_action 17:21:33 INFO - Running main action method: setup_mitmproxy 17:21:33 INFO - Skipping: mitmproxy is not required 17:21:33 INFO - Running post-action listener: _resource_record_post_action 17:21:33 INFO - [mozharness: 2017-09-28 00:21:33.923956Z] Finished setup-mitmproxy step (success) 17:21:33 INFO - [mozharness: 2017-09-28 00:21:33.924177Z] Running run-tests step. 17:21:33 INFO - Running pre-action listener: _resource_record_pre_action 17:21:33 INFO - Running pre-action listener: _set_gcov_prefix 17:21:33 INFO - Running main action method: run_tests 17:21:33 WARNING - Try message not found. 17:21:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:21:33 INFO - Python 2.7.3 17:21:33 INFO - Return code: 0 17:21:33 INFO - grabbing minidump binary from tooltool 17:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:33 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 0x218fd60>, '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 0x2190a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2190ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:21:33 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 17:21:33 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 17:21:33 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 17:21:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:21:34 INFO - Return code: 0 17:21:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:21:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:21:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:21:34 INFO - ENV: RUST_BACKTRACE is now full 17:21:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:21:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:21:34 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/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 17:21:34 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/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 17:21:34 INFO - Using env: {'DISPLAY': ':0', 17:21:34 INFO - 'HOME': '/home/cltbld', 17:21:34 INFO - 'LANG': 'en_US.UTF-8', 17:21:34 INFO - 'LANGUAGE': 'en_US:en', 17:21:34 INFO - 'LOGNAME': 'cltbld', 17:21:34 INFO - 'MAIL': '/var/mail/cltbld', 17:21:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:21:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:34 INFO - 'MOZ_NO_REMOTE': '1', 17:21:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:21:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:34 INFO - 'NO_EM_RESTART': '1', 17:21:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:34 INFO - 'PWD': '/builds/slave/test', 17:21:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:21:34 INFO - 'RUST_BACKTRACE': 'full', 17:21:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:21:34 INFO - 'SHELL': '/bin/bash', 17:21:34 INFO - 'SHLVL': '1', 17:21:34 INFO - 'TERM': 'linux', 17:21:34 INFO - 'TMOUT': '86400', 17:21:34 INFO - 'USER': 'cltbld', 17:21:34 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:34 INFO - '_': '/tools/buildbot/bin/python'} 17:21:34 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/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 17:21:34 INFO - mozversion application_buildid: 20170927215532 17:21:34 INFO - mozversion application_changeset: 69e3f89816455e567f1a20b694fd6afd549c82c7 17:21:34 INFO - mozversion application_display_name: Nightly 17:21:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:21:34 INFO - mozversion application_name: Firefox 17:21:34 INFO - mozversion application_remotingname: firefox 17:21:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:21:34 INFO - mozversion application_vendor: Mozilla 17:21:34 INFO - mozversion application_version: 58.0a1 17:21:34 INFO - mozversion platform_buildid: 20170927215532 17:21:34 INFO - mozversion platform_changeset: 69e3f89816455e567f1a20b694fd6afd549c82c7 17:21:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:21:34 INFO - mozversion platform_version: 58.0a1 17:21:34 INFO - using testdate: 1506558094 17:21:34 INFO - actual date: 1506558094 17:21:34 INFO - starting webserver on 'localhost:47680' 17:21:34 INFO - SUITE-START | Running 2 tests 17:21:34 INFO - TEST-START | dromaeo_css 17:21:34 INFO - Initialising browser for dromaeo_css test... 17:21:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47680/getInfo.html -profile /tmp/tmpbKUQ_d/profile 17:21:34 INFO - TEST-INFO | started process 13978 (/builds/slave/test/build/application/firefox/firefox http://localhost:47680/getInfo.html) 17:21:40 INFO - TEST-INFO | 13978: exit 0 17:21:40 INFO - Browser initialized. 17:21:40 INFO - Running cycle 1/1 for dromaeo_css test... 17:21:40 INFO - Using env: {'DISPLAY': ':0', 17:21:40 INFO - 'HOME': '/home/cltbld', 17:21:40 INFO - 'JSGC_DISABLE_POISONING': '1', 17:21:40 INFO - 'LANG': 'en_US.UTF-8', 17:21:40 INFO - 'LANGUAGE': 'en_US:en', 17:21:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:21:40 INFO - 'LOGNAME': 'cltbld', 17:21:40 INFO - 'MAIL': '/var/mail/cltbld', 17:21:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:21:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:21:40 INFO - 'MOZ_CRASHREPORTER': '1', 17:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:21:40 INFO - 'MOZ_NO_REMOTE': '1', 17:21:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:21:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:40 INFO - 'NO_EM_RESTART': '1', 17:21:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:40 INFO - 'PWD': '/builds/slave/test', 17:21:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:21:40 INFO - 'RUST_BACKTRACE': 'full', 17:21:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:21:40 INFO - 'SHELL': '/bin/bash', 17:21:40 INFO - 'SHLVL': '1', 17:21:40 INFO - 'TERM': 'linux', 17:21:40 INFO - 'TMOUT': '86400', 17:21:40 INFO - 'USER': 'cltbld', 17:21:40 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:40 INFO - '_': '/tools/buildbot/bin/python'} 17:21:40 INFO - TEST-INFO | started process 14215 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbKUQ_d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:41 INFO - PID 14215 | 17:21:41 INFO - PID 14215 | (/builds/slave/test/build/application/firefox/firefox:14265): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:21:41 INFO - PID 14215 | 17:21:52 INFO - PID 14215 | RSS: Main: 141197312 17:21:52 INFO - PID 14215 | 17:24:49 INFO - PID 14215 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47680/tests/dromaeo/cssquery-ext.html) 17:24:50 INFO - PID 14215 | RSS: Main: 142475264 17:24:50 INFO - PID 14215 | 17:27:52 INFO - PID 14215 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-ext.html (next: http://localhost:47680/tests/dromaeo/cssquery-jquery.html) 17:27:53 INFO - PID 14215 | RSS: Main: 143290368 17:27:53 INFO - PID 14215 | 17:30:54 INFO - PID 14215 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47680/tests/dromaeo/cssquery-mootools.html) 17:30:55 INFO - PID 14215 | RSS: Main: 144347136 17:30:55 INFO - PID 14215 | 17:33:56 INFO - PID 14215 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47680/tests/dromaeo/cssquery-prototype.html) 17:33:57 INFO - PID 14215 | RSS: Main: 144502784 17:33:57 INFO - PID 14215 | 17:37:00 INFO - PID 14215 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47680/tests/dromaeo/cssquery-yui.html) 17:37:00 INFO - PID 14215 | RSS: Main: 147255296 17:37:00 INFO - PID 14215 | 17:40:02 INFO - PID 14215 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-yui.html (next: http://localhost:47680/tests/dromaeo/cssquery-dojo.html) 17:40:02 INFO - PID 14215 | RSS: Main: 148889600 17:40:02 INFO - PID 14215 | 17:40:02 INFO - PID 14215 | __start_tp_report 17:40:02 INFO - PID 14215 | _x_x_mozilla_page_load 17:40:02 INFO - PID 14215 | _x_x_mozilla_page_load_details 17:40:02 INFO - PID 14215 | |i|pagename|runs| 17:40:02 INFO - PID 14215 | |0;dojo.html;3953.2093581283734;3953.644172024517;3965.345717956537;3973.9403908941354;4034.293998550255;6286.654234017127;6356.713947872346;6413.390727880856;6423.582467139633;6506.837329066776;6380.585261957969;6429.582077164992;6503.674816259192;6505.0242463630475;6514.250861150969;5670.6030577859565;5699.088145896658;5764.971175144118;5766.769329226847;5793.159991801186;5694.2028116063575;5758.769649214027;5786.855328616797;5831.679257640828;5853.5317174625925;5614.466625670555;5666.405027472098;5673.177389278552;5697.515711164556;5752.942470575283;5718.857028574319;5777.075667893159;5796.188533605298;5796.652200867961;5803.506701930341;5719.170720245583;5732.656040637534;5760.481556659879;5787.855303617401;5821.388754180793;6317.778877739303;6350.33321501245;6374.681265936684;6395.584287021329;6476.060971159202;6936.167659880846;6969.965150174267;6976.895346569806;6986.790396288068;7055.329743674342;7040.683169257396;7119.964400177966;7130.6078165700865;7139.393151582121;7141.785746427564;6369.267534233576;6434.581752186093;6472.741090356333;6475.967620161963;6515.185601799775;7187.245339239409;7192.244814294424;7199.388052015637;7201;7214.855702886018;6729.629870357079;6853.109095817512;6862.553933994274;6865.485088618374;6874.106366172366;6980.267071957473;6993.930060699429;6996.230414654486;6998.16022077354;6999.12510936133;6978.232394436608;7025.718971241093;7037.542559733601;7039.225685174627;7042.6126563039525;6935.861282774316;6955.6869940852785;6980.755673551503;7057.682404291819;7078.893816592756;4965.726885021359;4994.051130285234;5035.269885866529;5050.217216331474;5059.62052846038;4579.725216486955;4580.198465268565;4645.372874661945;4649.69776964503;4661.487236403993;6348.492120630339;6361.745530178833;6430.196225471816;6431.678416079178;6433.74265029403;6893.517453778288;6901.413379862768;6986.196587392515;6990.405815505637;7024.719011239493;6312.747490100436;6360.491160707133;6378.744850206032;6386;6389.424951754271;5535.667859928417;5544.63959842609;5598.916016259759;5602.691851948182;5638.295213098363;7173.7130514779865;7246.855062898712;7355.963220183865;7361.227071157558;7369.8157546061775;6876.69054892541;6912.585244885323;6961.095057642474;6979.755708550278;6982.266861979622;7111.964440177766;7252.601106939169;7254.96372518134;7263.382612477999;7372.299631534996;6784.3554862288;6854.417374523221;6858.485613578901;6863.07348507965;6886.724531018703;6898.827529311808;6945.305469453015;6956.478264130109;6980.755673551402;6995.230524642184;6736.73053077891;6807.0810440589885;6820.931790682228;6828.180618325832;6831;6738.797836064926;6739.629320387426;6741.59550426976;6831.829204269933;6842.315768423118;6534.281229064894;6537.771178008747;6637.103990961158;6653.866922661617;6699.129113215251;6831.897521537082;6858.725650974105;6867.2102708189195;6906.930930690629;6911.930880691129;6536.967315163393;6543.476521878333;6633.436157926631;6637.070810086689;6674.599524028574 17:40:02 INFO - PID 14215 | |1;ext.html;948.2508818033754;953.3707752883098;977.8239916814974;1005.7033175213311;1006.4665727164605;41556.376654350206;42038.36942445866;42174.367384489255;42238.366424503656;42317.365239521394;40449.39325910107;40613.18773624526;43605;44033.77983110088;44210.55789442104;8328.708495202654;8441.113683063282;8476.576171191447;8482.915170848306;8490.957545212264;8311.127331630183;8444.366672499556;8445.746627601182;8463.873041904377;8472;8454.323654107688;8490.830183396329;8492.15078492152;8525.31797456202;8526.70156544521;8429.536375499376;8431.494110353398;8433.70482033129;8436.072032076467;8439.493630382196;8228.547429891354;8266.462679925786;8310.459820111733;8316.043654979694;8367.58162091899;39789;40106;40191.7990410049;40262.39606405906;40268;3725.2735716535285;3758.3798673218885;3771.698264138862;3773.4528493368584;3782.224643947984;32236;32396.514052289236;32449.188770280696;32495.51256731151;32575.51136732951;5382.461753824626;5398.109311963522;5410.8917821643345;5420.159875219348;5430.185472179165;217029;220081;221605;223001;224304;53200.46799532032;54039;54197;54202;54255.186172207446;57363.71318143383;57369.426305737245;57424.71287643535;57490.13764793531;57500.42499575034;16474.505764827074;16585.25366358517;16586.834131658772;16607;16623.41818036387;13365.799513007312;13367.665808354674;13372.465101395836;13373.598792036255;13377.197368157942;14574.562763117123;14730.263486825615;14781;14812.333444995002;14844.92577537127;5523.834284971457;5640.943590564124;5676.318841739017;5694.629849059881;5722.341930677942;1049.8897615750236;1088.1403691084113;1088.8094583447864;1096.9670909872716;1101.658485869383;25055.498890022096;25108.874455627603;25187.748122518908;25364.492710145692;25629.871850641;1166.1720178673238;1184.798584240681;1194.4863708605383;1196.1208511743862;1200.351810022578;13380.732385352238;13455;13508.392122354519;13576.524821631394;13736;287293;287372;288520;289596;290309;56676.71661641748;56726.7163664179;57190.42809571934;57270;57319;1480.970380592382;1523.6190952261934;1525.794017807604;1527.2974431761293;1541.899776514523;24318.148864789648;24411.51176976486;24429;24476.14333498319;24589.401688890164;39129.41305880471;39288.21423571456;39900;39919.20161596694;39925.40111898382;237541;237808;239119;239772;240150;35418.46872296866;35808.820955895055;36120;36130.8193459031;36342.454863177605;45142;45661.31508027316;45714;45865.770671146434;46318.30522542097;31474.213144671565;31501.684983150793;31600.525992110597;31897.36205275928;32655.673443266212;33124.1718957028;33508.83245583757;33542.49686254757;33973.49039764455;34465.82767086149;38603;39822.800885995384;39929.80035099922;39949.40075898806;40136.59863401445;32333.35333293274;32539.674603253665;32838.507422388204;33027.174320642174;33197.66802331946;25253.747462525873;25379.74620253774;25515.489690206465;25984.740152598988;26098.086566970065 17:40:02 INFO - PID 14215 | |2;jquery.html;606.6412823860561;607.3926073926074;609.4575827513517;614.4531367083296;615.4583966109823;22813.201537946174;23383.415414614643;23593.646095308584;23620.29139125827;23643.881780591095;23434;25967.87016064917;26212.082577109806;26232.868835655794;26234.868825655845;6386.042093685938;6421.743130274806;6471.996840489732;6472.482201423898;6550.246721627002;6385.584936979105;6435;6495.090687303781;6695.49783766217;6717.09319241901;6651.2018557773135;6656.301088385722;6665.600063996176;6695.196576410837;6697.363750443725;6225.470834979033;6492.9350706492805;6690.096836927001;6696.430803381719;6696.799096027152;6082.178905847698;6091.390860913917;6092;6093.939060609381;6116.694165291718;29776;29916.40167196644;30055.549166762517;30139.69860301391;30237.395252094833;2197.8791166485835;2218.711567496215;2226.198568515333;2226.543558570489;2227.0201111510883;19797.70303445435;20421.489462763402;20650.48373790652;20970.580588388148;21003.684944725843;2504.9854808802465;2517.546841568517;2553.9361515962064;2559.36015996001;2572.5755250383663;135585;142533;150681.24659376632;154478.99999999886;155451;15517.612059698598;15764.211789410483;16410;16433.753493697604;16625;25650.615240771403;25653.61519577208;25668.229953101436;25668.871655641975;25674.74325256761;10356.792864142675;10735.731606709895;10777.892221077846;10816.837747433794;10867.347959122479;9704.126628603459;9720.270979676552;9746.561404736662;9784.657536986313;9800.36297640651;9581.233501319877;9638.409662405624;9728.173105285989;9876.555555000043;9879.654212102541;2816.352238985045;2846.217290245199;2862.670792858806;2920.8685609147637;3027.591275177823;1026.1534234256767;1054.9103326217205;1067.5089458849016;1077.3051381858656;1088.580896354909;21143.577128457346;21180.576388472145;21252.14991400327;21263.149474020865;21320.253791117546;1122.3209957975307;1122.960696375623;1125.6172901213627;1129.0685184722636;1136.1422126294594;11619.302841829518;11621.24461909973;11724.296542207494;11730.530778768923;11841.407929603485;569397;574271;578686;605451;612567;126990;128532;128761;130682;132805;1228.2814553486132;1239.6652903715947;1269.0862578943147;1270.7267937393506;1281.1672412931669;18336.991465469528;18585;18635.90682046581;18739;18787.530311742317;60897.69551152216;63974.3602563987;64933.35066649273;64969;65052.67473662602;76016.61991690006;76162.61918690371;76644.61677691576;76732;77295;15218.771718424012;15317.540473785804;15453.304601292744;15462.613434664223;15486.457973971308;22592.66111008369;22866.771332286466;23030.884845575663;23326.416839579146;23339.883300583388;18696.813031869508;18903.43289701311;19090.427287181406;19451;19496.707549386487;16880.409185678443;17192.39826606113;17400.73898891543;17577.209025593624;17884.91057544704;19161.90419047896;19196.71204931899;19298.8070119297;19436.4169074928;19450;14950.700985980002;14955.551333460013;15036.624084397978;15062.096274223582;15088.54734357971;18462;18504.259829606664;18706.625867482846;18833.24667013304;18976.14607342645 17:40:02 INFO - PID 14215 | |3;mootools.html;1389.9027068105233;1463.8316593591733;1503.6165777726685;1510.4788847847497;1513.962150946227;16769.580760480978;17070.317187312507;17109.230084646206;17141.14294285284;17201.483955481355;16793.748093778602;16804.83195168048;16874;16885.15574221287;16890.746638800396;2876.3528206153605;2992.955105673406;2995.9101226963226;3002.939941201175;3012.1565961530805;2828.448452551743;2893.2043687985865;2900.1444573850763;2900.8114472559323;2901.3036871150985;2772.265349682326;2960.5115155999233;2972.301509145349;2978.19588711049;2979.3147576057518;2833.5607980763016;2857.7285157909964;2859.37093839355;2871.5405535114387;2877.6115224444748;2700.1629494856656;2720.40151166743;2735.2341344423594;2742.7394397532203;2743.7942154338307;16406;16639.16804159787;16667;16674.082925439096;16728.41450549225;8455.239028487465;8835.690750823755;8839.160279773476;8843.46939183651;8844.38089333747;5808.535317174616;5820.738066787005;5979.730912108966;6018.789342373039;6047.455728984412;2124.798144176301;2131.7015617813518;2161.6757486377014;2190.726159230096;2193.144673577306;30777.538336924965;30981.690183097882;31004.379912401626;31010.224744381572;31029.8448507756;7201.17186523546;7329.303716146957;7349;7473.476856619985;7517.285857843496;19347.226110955686;19444.70832937507;19493.122809473607;19619.90190049069;19664;8209.876851847228;8505.74482765518;8508.149185081442;8518.318534517224;8533.189347012023;7664.34853037498;7675.654595543214;7730.652120654584;7880.448368614257;7895.210478952174;5340.893182136336;5551.583631227674;5555.388937208245;5616.9327827712605;5630.211770352154;1502.0462006625871;1530.6326481644326;1672.4982505248377;1674.9330026799034;1680.1515234806345;912.2473958983865;921.6037104045191;925.8379783537854;936.2509992006504;942.6229508196639;8965.148310910454;9014.098590140933;9018.458892466473;9073.954630226806;9144.177024067865;1021.6526381030337;1022.1260821997234;1041.463646222196;1050.873895132589;1053.6733612580124;4638.837640682627;4779.545943135397;5064.366954130734;5651;5853.443922827325;24581.38546536351;25194.622080668803;25294.367640809127;25331.493370132495;25470.108546200794;23931.401714956915;24642.50714985726;24833.379165520644;25025;25279.494410112064;12711.682207944876;12722.809157862639;12886.742265154644;12927.28899910495;12942.288174150512;9528.857067144138;9546.522673866419;9557.569909353957;9597.712068637951;9656.082672146134;20366.89816550908;20699.4825129373;21219.36341909745;21484;21513.6772948409;22253.55492890101;22556.66165007559;22702.205422810785;22709.886450567643;23024.884875575513;12322.815157772462;12532.060095492692;12536.059795515555;12545.31000794965;12587.433565489759;15634.45279415215;15643.687126257639;15774.605634859221;15863.127527986071;15885.205739712737;12797.872021279669;12878.484860605471;13103.60689179326;13187.274699891597;13225;11795.351255680676;11843.111766617365;11864.881351186377;11992;12179.208351457128;9819.9018009819;9972.650957216463;9990.450525221182;10046.296759226783;10081.848772268275;8634.136586341316;8658.047614762245;8708.738737837875;8709.08554601783;8740.257078148428;8146.103928567972;8225.218604232588;8237.629306681094;8281.503109813431;8357.874631880648 17:40:02 INFO - PID 14215 | |4;prototype.html;553.9418361072088;560.9046462101445;567.4183961439526;574.3595890582002;583.8978178818705;6182.752689892404;6228.501719862412;6262.530310226734;6263.812085637427;6279.24649042115;6192.133101365813;6225.937740622592;6235.345288744673;6237.220347456568;6269.059641053845;5061.848144555669;5073.847784566469;5087.414947281073;5129;5129.769160387773;4863.951360486403;4993.625478089158;5007.824726134586;5021.146405111122;5138.486151384869;4959.504049595048;4963.329950456697;4984.277279794428;5000.449950505442;5004.374453193351;4943.332650092228;4960.801567937278;4966.453690094087;4968.70187788732;4972.801087956477;4453.39677716022;4504.707194032378;4551.7724113794175;4557.954420455786;4567.063751930847;6059.272887253558;6111.358307377705;6132.7240274187325;6177.907331390033;6183.907241391383;5900.704964751788;5916.556258280646;5954.940450595525;5957.731902064417;5983.311919129269;6718.66406679664;6723.630200338979;6758.121444212221;6762.289959554225;6770.627615481147;5014.498550144993;5055.51972562606;5074.934263804568;5084.211947148198;5131.640785145041;6677.766278180289;6822.522423430361;6908.549204667076;6926.168859736864;6978.79063628092;5127.948720512747;5209.47905209476;5235.633505654568;5237.738113094406;5262.052830490472;5248.396434410015;5290.153575427913;5298.231756395301;5331.653442526223;5342.8664283392445;5792.333881603585;5861.150133230658;5871;5878.617889837147;5906.793262235801;6671.498188179433;6701.832454188684;6728.360805723449;6730.360615741497;6770.932290677129;1832.3769918227867;1835;1837.2559113558764;1839.9724004139948;1840.3834715370233;1253.1828848170007;1261.6689392690616;1294.5210272199158;1301.73965206959;1305.3342795174528;6181.165542651775;6211.565190436717;6231.781887633911;6252.155958945576;6277.309495955441;6447.742090316428;6803.727850886008;6816.829579260559;6824.897626535606;6825.965870170618;6110.114033465123;6113.786017489367;6159.599626024384;6208.534359923024;6263.935131027736;3507.7544571879725;3521.33094712009;3525.982370088133;3543.2204914918675;3558.6619271169197;6872.862542749117;6967.512274140863;7046.189688185822;7071.186813516511;7083.433325334064;6861.622610756456;6906.274841141708;6944.7222111116;6951.930480695129;6976.825579360455;7002.40479559233;7048.506604537633;7063.551971845759;7078.1506219254015;7139.357457828716;6650.467962562887;6731.720973015307;6742.69657865407;6798.558093723892;6804.55770374924;6675.265720770614;6696.799096027127;6761.695723692347;6767.93232067673;6807.489438292247;7086.681099350645;7115.217326094229;7121.323474269936;7137.928620713934;7151;5087.065546419966;5204.115300398866;5229.320188375486;5242.5281724644965;5267.657602255841;6569.948808190667;6714.865702686016;6730.730770769114;6750.797476075725;6781.559198652072;6394.840128996812;6452.193475815523;6583.44040756541;6592.340765923369;6610.570312929644;6497.772577959749;6552.034389682981;6584.934150658623;6594.439472644879;6607.471402287902;6376.266729326187;6428.132202152649;6440.323766004502;6441.806745797634;6471.094046833353;6575.145231119924;6633.9336606633315;6664.666766661744;6664.866702666017;6672.966635166793;6294.402031806971;6381.7128229228865;6398.296187419287;6408.935910640834;6412.23053233615 17:40:02 INFO - PID 14215 | |5;yui.html;856.2850020233109;866.4931015356017;877.8288233794412;987.4420952162028;1008.5915204342242;22445.3266402008;23840.40398990024;23848.5230295394;23874.880625596892;23928.88035559822;22582.322530324116;23276.76723232767;23360.64959025616;23444.64833027506;23492.942817573206;1039.1738567838586;1053.2311412668757;1067.1889364083274;1077.2782235901964;1081.3836113415357;1065.5524679634534;1076.9461526923621;1077.6820837852852;1078.8921107889228;1080.168270431767;1121.6130434999911;1123.5561953028548;1128.6839684888243;1129.0797999630315;1132.0321125437756;1112.0325316974204;1123.7696272264166;1123.9887601123967;1124.943752812356;1124.966251012467;816.9877451838167;824.1017291152613;826.5412695953743;828.0891019878146;833.3333333333309;21158.576828463345;21384.46538836526;21661;21779.891100544395;22599.435014124614;23086.65370019451;23260.185893493646;23499.53000939989;23793.286201413986;23842.880785596135;18330.725039124423;18657.906710466497;18713.906430467898;18765;18775.24899004038;3892.8442862285474;3899.3566061599804;3908.3746600543777;3909.1986142840933;3910.491636087319;158157;161358;163359;164821.17589412097;165198;17940.282388704567;18177.63644727098;18364.908175459306;18448.907755461405;18480.353187638368;33845.492317614764;34830.47754283688;34938.30123397518;34963.82518087393;35142.64857351445;8530.914690853137;8686.39195256326;8725.91274087251;8779.20987111163;8847.601857916408;4493.6854420190275;4613.1465678848845;4637.3275874997935;4640.605548528345;4653.511381304982;6027.21646185993;6176.227971503562;6177.320494745574;6199.380061993765;6206.472449841724;1161.1117495116243;1191.4578866615668;1192.7793358228755;1193.7791508570942;1197.6107764976243;608.1182285685774;628.7107930351998;633.0946746153045;636.9585976911486;640.8237734623016;13200.669983250496;13374;13429.597112086458;14747.262636868329;14791.408343666348;749.606456610284;753.0398741604389;759.3545486336569;759.9506032107895;770.9267619576132;6242.375762423812;6270.874582508324;6279.780207692709;6378.330275321117;6429.324920883333;143278;148573.25713371363;152058;152157;156606;133150;133592;133773.33113334372;135805;142308;115308.42345788385;115435.42282288703;115834;117297.41351293189;118212;11569.942150289195;11868.287902725864;12004.459799308877;12196.878031219574;12203.08476864221;54549;54926.72536637291;55165.44834551603;55416.445835541126;55873;70294.64852675704;70675.64662176862;71116.28883711097;71639.64180179065;74129.25870741224;16930.153492325582;17399.652006960045;17407.303707851544;17491.912540437217;17506.299748009937;19567;19577;19609.411717648494;19721.309754158534;19839.30562430308;18001.1899464527;18325.633487330444;18391.425200471676;18483.445496635122;18538.351157709414;17367.47897563075;17368.82631173672;17500.562485937953;17591.208395622485;17621.911890440464;16707.331706732082;17109.914450428165;17134.22895969709;17175.227114780115;17209.397671081453;12965.805512917124;13115.475380984653;13132.40904159296;13260.005499587376;13410.530631427853;13493.392797323948;13529.797053044016;13662.863371366158;13677.179369237878;13728.107673001223 17:40:02 INFO - PID 14215 | __end_tp_report 17:40:02 INFO - PID 14215 | __start_cc_report 17:40:02 INFO - PID 14215 | _x_x_mozilla_cycle_collect,283 17:40:02 INFO - PID 14215 | __end_cc_report 17:40:02 INFO - PID 14215 | __startTimestamp1506559202622__endTimestamp 17:40:02 INFO - PID 14215 | 17:40:02 INFO - PID 14215 | ------- Summary: start ------- 17:40:02 INFO - PID 14215 | Number of tests: 6 17:40:02 INFO - PID 14215 | 17:40:02 INFO - PID 14215 | [#0] dojo.html Cycles:165 Average:6409.40 Median:6653.87 stddev:778.15 (11.7%) stddev-sans-first:756.31 17:40:02 INFO - PID 14215 | Values: 3953.2 3953.6 3965.3 3973.9 4034.3 6286.7 6356.7 6413.4 6423.6 6506.8 6380.6 6429.6 6503.7 6505.0 6514.3 5670.6 5699.1 5765.0 5766.8 5793.2 5694.2 5758.8 5786.9 5831.7 5853.5 5614.5 5666.4 5673.2 5697.5 5752.9 5718.9 5777.1 5796.2 5796.7 5803.5 5719.2 5732.7 5760.5 5787.9 5821.4 6317.8 6350.3 6374.7 6395.6 6476.1 6936.2 6970.0 6976.9 6986.8 7055.3 7040.7 7120.0 7130.6 7139.4 7141.8 6369.3 6434.6 6472.7 6476.0 6515.2 7187.2 7192.2 7199.4 7201.0 7214.9 6729.6 6853.1 6862.6 6865.5 6874.1 6980.3 6993.9 6996.2 6998.2 6999.1 6978.2 7025.7 7037.5 7039.2 7042.6 6935.9 6955.7 6980.8 7057.7 7078.9 4965.7 4994.1 5035.3 5050.2 5059.6 4579.7 4580.2 4645.4 4649.7 4661.5 6348.5 6361.7 6430.2 6431.7 6433.7 6893.5 6901.4 6986.2 6990.4 7024.7 6312.7 6360.5 6378.7 6386.0 6389.4 5535.7 5544.6 5598.9 5602.7 5638.3 7173.7 7246.9 7356.0 7361.2 7369.8 6876.7 6912.6 6961.1 6979.8 6982.3 7112.0 7252.6 7255.0 7263.4 7372.3 6784.4 6854.4 6858.5 6863.1 6886.7 6898.8 6945.3 6956.5 6980.8 6995.2 6736.7 6807.1 6820.9 6828.2 6831.0 6738.8 6739.6 6741.6 6831.8 6842.3 6534.3 6537.8 6637.1 6653.9 6699.1 6831.9 6858.7 6867.2 6906.9 6911.9 6537.0 6543.5 6633.4 6637.1 6674.6 17:40:02 INFO - PID 14215 | 17:40:02 INFO - PID 14215 | [#1] ext.html Cycles:180 Average:42471.94 Median:36236.64 stddev:65377.77 (180.4%) stddev-sans-first:65486.82 17:40:02 INFO - PID 14215 | Values: 948.3 953.4 977.8 1005.7 1006.5 41556.4 42038.4 42174.4 42238.4 42317.4 40449.4 40613.2 43605.0 44033.8 44210.6 8328.7 8441.1 8476.6 8482.9 8491.0 8311.1 8444.4 8445.7 8463.9 8472.0 8454.3 8490.8 8492.2 8525.3 8526.7 8429.5 8431.5 8433.7 8436.1 8439.5 8228.5 8266.5 8310.5 8316.0 8367.6 39789.0 40106.0 40191.8 40262.4 40268.0 3725.3 3758.4 3771.7 3773.5 3782.2 32236.0 32396.5 32449.2 32495.5 32575.5 5382.5 5398.1 5410.9 5420.2 5430.2 217029.0 220081.0 221605.0 223001.0 224304.0 53200.5 54039.0 54197.0 54202.0 54255.2 57363.7 57369.4 57424.7 57490.1 57500.4 16474.5 16585.3 16586.8 16607.0 16623.4 13365.8 13367.7 13372.5 13373.6 13377.2 14574.6 14730.3 14781.0 14812.3 14844.9 5523.8 5640.9 5676.3 5694.6 5722.3 1049.9 1088.1 1088.8 1097.0 1101.7 25055.5 25108.9 25187.7 25364.5 25629.9 1166.2 1184.8 1194.5 1196.1 1200.4 13380.7 13455.0 13508.4 13576.5 13736.0 287293.0 287372.0 288520.0 289596.0 290309.0 56676.7 56726.7 57190.4 57270.0 57319.0 1481.0 1523.6 1525.8 1527.3 1541.9 24318.1 24411.5 24429.0 24476.1 24589.4 39129.4 39288.2 39900.0 39919.2 39925.4 237541.0 237808.0 239119.0 239772.0 240150.0 35418.5 35808.8 36120.0 36130.8 36342.5 45142.0 45661.3 45714.0 45865.8 46318.3 31474.2 31501.7 31600.5 31897.4 32655.7 33124.2 33508.8 33542.5 33973.5 34465.8 38603.0 39822.8 39929.8 39949.4 40136.6 32333.4 32539.7 32838.5 33027.2 33197.7 25253.7 25379.7 25515.5 25984.7 26098.1 17:40:02 INFO - PID 14215 | 17:40:02 INFO - PID 14215 | [#2] jquery.html Cycles:180 Average:38919.73 Median:22702.93 stddev:98528.98 (434.0%) stddev-sans-first:98763.39 17:40:02 INFO - PID 14215 | Values: 606.6 607.4 609.5 614.5 615.5 22813.2 23383.4 23593.6 23620.3 23643.9 23434.0 25967.9 26212.1 26232.9 26234.9 6386.0 6421.7 6472.0 6472.5 6550.2 6385.6 6435.0 6495.1 6695.5 6717.1 6651.2 6656.3 6665.6 6695.2 6697.4 6225.5 6492.9 6690.1 6696.4 6696.8 6082.2 6091.4 6092.0 6093.9 6116.7 29776.0 29916.4 30055.5 30139.7 30237.4 2197.9 2218.7 2226.2 2226.5 2227.0 19797.7 20421.5 20650.5 20970.6 21003.7 2505.0 2517.5 2553.9 2559.4 2572.6 135585.0 142533.0 150681.2 154479.0 155451.0 15517.6 15764.2 16410.0 16433.8 16625.0 25650.6 25653.6 25668.2 25668.9 25674.7 10356.8 10735.7 10777.9 10816.8 10867.3 9704.1 9720.3 9746.6 9784.7 9800.4 9581.2 9638.4 9728.2 9876.6 9879.7 2816.4 2846.2 2862.7 2920.9 3027.6 1026.2 1054.9 1067.5 1077.3 1088.6 21143.6 21180.6 21252.1 21263.1 21320.3 1122.3 1123.0 1125.6 1129.1 1136.1 11619.3 11621.2 11724.3 11730.5 11841.4 569397.0 574271.0 578686.0 605451.0 612567.0 126990.0 128532.0 128761.0 130682.0 132805.0 1228.3 1239.7 1269.1 1270.7 1281.2 18337.0 18585.0 18635.9 18739.0 18787.5 60897.7 63974.4 64933.4 64969.0 65052.7 76016.6 76162.6 76644.6 76732.0 77295.0 15218.8 15317.5 15453.3 15462.6 15486.5 22592.7 22866.8 23030.9 23326.4 23339.9 18696.8 18903.4 19090.4 19451.0 19496.7 16880.4 17192.4 17400.7 17577.2 17884.9 19161.9 19196.7 19298.8 19436.4 19450.0 14950.7 14955.6 15036.6 15062.1 15088.5 18462.0 18504.3 18706.6 18833.2 18976.1 17:40:02 INFO - PID 14215 | 17:40:02 INFO - PID 14215 | [#3] mootools.html Cycles:180 Average:10468.81 Median:2743.27 stddev:7760.32 (282.9%) stddev-sans-first:7752.11 17:40:02 INFO - PID 14215 | Values: 1389.9 1463.8 1503.6 1510.5 1514.0 16769.6 17070.3 17109.2 17141.1 17201.5 16793.7 16804.8 16874.0 16885.2 16890.7 2876.4 2993.0 2995.9 3002.9 3012.2 2828.4 2893.2 2900.1 2900.8 2901.3 2772.3 2960.5 2972.3 2978.2 2979.3 2833.6 2857.7 2859.4 2871.5 2877.6 2700.2 2720.4 2735.2 2742.7 2743.8 16406.0 16639.2 16667.0 16674.1 16728.4 8455.2 8835.7 8839.2 8843.5 8844.4 5808.5 5820.7 5979.7 6018.8 6047.5 2124.8 2131.7 2161.7 2190.7 2193.1 30777.5 30981.7 31004.4 31010.2 31029.8 7201.2 7329.3 7349.0 7473.5 7517.3 19347.2 19444.7 19493.1 19619.9 19664.0 8209.9 8505.7 8508.1 8518.3 8533.2 7664.3 7675.7 7730.7 7880.4 7895.2 5340.9 5551.6 5555.4 5616.9 5630.2 1502.0 1530.6 1672.5 1674.9 1680.2 912.2 921.6 925.8 936.3 942.6 8965.1 9014.1 9018.5 9074.0 9144.2 1021.7 1022.1 1041.5 1050.9 1053.7 4638.8 4779.5 5064.4 5651.0 5853.4 24581.4 25194.6 25294.4 25331.5 25470.1 23931.4 24642.5 24833.4 25025.0 25279.5 12711.7 12722.8 12886.7 12927.3 12942.3 9528.9 9546.5 9557.6 9597.7 9656.1 20366.9 20699.5 21219.4 21484.0 21513.7 22253.6 22556.7 22702.2 22709.9 23024.9 12322.8 12532.1 12536.1 12545.3 12587.4 15634.5 15643.7 15774.6 15863.1 15885.2 12797.9 12878.5 13103.6 13187.3 13225.0 11795.4 11843.1 11864.9 11992.0 12179.2 9819.9 9972.7 9990.5 10046.3 10081.8 8634.1 8658.0 8708.7 8709.1 8740.3 8146.1 8225.2 8237.6 8281.5 8357.9 17:40:02 INFO - PID 14215 | 17:40:02 INFO - PID 14215 | [#4] prototype.html Cycles:180 Average:5648.51 Median:6233.56 stddev:1579.81 (25.3%) stddev-sans-first:1537.27 17:40:02 INFO - PID 14215 | Values: 553.9 560.9 567.4 574.4 583.9 6182.8 6228.5 6262.5 6263.8 6279.2 6192.1 6225.9 6235.3 6237.2 6269.1 5061.8 5073.8 5087.4 5129.0 5129.8 4864.0 4993.6 5007.8 5021.1 5138.5 4959.5 4963.3 4984.3 5000.4 5004.4 4943.3 4960.8 4966.5 4968.7 4972.8 4453.4 4504.7 4551.8 4558.0 4567.1 6059.3 6111.4 6132.7 6177.9 6183.9 5900.7 5916.6 5954.9 5957.7 5983.3 6718.7 6723.6 6758.1 6762.3 6770.6 5014.5 5055.5 5074.9 5084.2 5131.6 6677.8 6822.5 6908.5 6926.2 6978.8 5127.9 5209.5 5235.6 5237.7 5262.1 5248.4 5290.2 5298.2 5331.7 5342.9 5792.3 5861.2 5871.0 5878.6 5906.8 6671.5 6701.8 6728.4 6730.4 6770.9 1832.4 1835.0 1837.3 1840.0 1840.4 1253.2 1261.7 1294.5 1301.7 1305.3 6181.2 6211.6 6231.8 6252.2 6277.3 6447.7 6803.7 6816.8 6824.9 6826.0 6110.1 6113.8 6159.6 6208.5 6263.9 3507.8 3521.3 3526.0 3543.2 3558.7 6872.9 6967.5 7046.2 7071.2 7083.4 6861.6 6906.3 6944.7 6951.9 6976.8 7002.4 7048.5 7063.6 7078.2 7139.4 6650.5 6731.7 6742.7 6798.6 6804.6 6675.3 6696.8 6761.7 6767.9 6807.5 7086.7 7115.2 7121.3 7137.9 7151.0 5087.1 5204.1 5229.3 5242.5 5267.7 6569.9 6714.9 6730.7 6750.8 6781.6 6394.8 6452.2 6583.4 6592.3 6610.6 6497.8 6552.0 6584.9 6594.4 6607.5 6376.3 6428.1 6440.3 6441.8 6471.1 6575.1 6633.9 6664.7 6664.9 6673.0 6294.4 6381.7 6398.3 6408.9 6412.2 17:40:02 INFO - PID 14215 | 17:40:02 INFO - PID 14215 | [#5] yui.html Cycles:180 Average:28797.79 Median:18510.90 stddev:42919.92 (231.9%) stddev-sans-first:42989.04 17:40:02 INFO - PID 14215 | Values: 856.3 866.5 877.8 987.4 1008.6 22445.3 23840.4 23848.5 23874.9 23928.9 22582.3 23276.8 23360.6 23444.6 23492.9 1039.2 1053.2 1067.2 1077.3 1081.4 1065.6 1076.9 1077.7 1078.9 1080.2 1121.6 1123.6 1128.7 1129.1 1132.0 1112.0 1123.8 1124.0 1124.9 1125.0 817.0 824.1 826.5 828.1 833.3 21158.6 21384.5 21661.0 21779.9 22599.4 23086.7 23260.2 23499.5 23793.3 23842.9 18330.7 18657.9 18713.9 18765.0 18775.2 3892.8 3899.4 3908.4 3909.2 3910.5 158157.0 161358.0 163359.0 164821.2 165198.0 17940.3 18177.6 18364.9 18448.9 18480.4 33845.5 34830.5 34938.3 34963.8 35142.6 8530.9 8686.4 8725.9 8779.2 8847.6 4493.7 4613.1 4637.3 4640.6 4653.5 6027.2 6176.2 6177.3 6199.4 6206.5 1161.1 1191.5 1192.8 1193.8 1197.6 608.1 628.7 633.1 637.0 640.8 13200.7 13374.0 13429.6 14747.3 14791.4 749.6 753.0 759.4 760.0 770.9 6242.4 6270.9 6279.8 6378.3 6429.3 143278.0 148573.3 152058.0 152157.0 156606.0 133150.0 133592.0 133773.3 135805.0 142308.0 115308.4 115435.4 115834.0 117297.4 118212.0 11569.9 11868.3 12004.5 12196.9 12203.1 54549.0 54926.7 55165.4 55416.4 55873.0 70294.6 70675.6 71116.3 71639.6 74129.3 16930.2 17399.7 17407.3 17491.9 17506.3 19567.0 19577.0 19609.4 19721.3 19839.3 18001.2 18325.6 18391.4 18483.4 18538.4 17367.5 17368.8 17500.6 17591.2 17621.9 16707.3 17109.9 17134.2 17175.2 17209.4 12965.8 13115.5 13132.4 13260.0 13410.5 13493.4 13529.8 13662.9 13677.2 13728.1 17:40:02 INFO - PID 14215 | -------- Summary: end -------- 17:40:02 INFO - PID 14215 | 17:40:03 INFO - TEST-INFO | 14215: exit 0 17:40:03 INFO - TEST-OK | dromaeo_css | took 1108802ms 17:40:03 INFO - TEST-START | kraken 17:40:03 INFO - Initialising browser for kraken test... 17:40:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47680/getInfo.html -profile /tmp/tmp09TUXs/profile 17:40:03 INFO - TEST-INFO | started process 14584 (/builds/slave/test/build/application/firefox/firefox http://localhost:47680/getInfo.html) 17:40:08 INFO - TEST-INFO | 14584: exit 0 17:40:09 INFO - Browser initialized. 17:40:09 INFO - Running cycle 1/1 for kraken test... 17:40:09 INFO - Using env: {'DISPLAY': ':0', 17:40:09 INFO - 'HOME': '/home/cltbld', 17:40:09 INFO - 'JSGC_DISABLE_POISONING': '1', 17:40:09 INFO - 'LANG': 'en_US.UTF-8', 17:40:09 INFO - 'LANGUAGE': 'en_US:en', 17:40:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:40:09 INFO - 'LOGNAME': 'cltbld', 17:40:09 INFO - 'MAIL': '/var/mail/cltbld', 17:40:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:40:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:40:09 INFO - 'MOZ_CRASHREPORTER': '1', 17:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:40:09 INFO - 'MOZ_NO_REMOTE': '1', 17:40:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:40:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:40:09 INFO - 'NO_EM_RESTART': '1', 17:40:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:09 INFO - 'PWD': '/builds/slave/test', 17:40:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:40:09 INFO - 'RUST_BACKTRACE': 'full', 17:40:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:40:09 INFO - 'SHELL': '/bin/bash', 17:40:09 INFO - 'SHLVL': '1', 17:40:09 INFO - 'TERM': 'linux', 17:40:09 INFO - 'TMOUT': '86400', 17:40:09 INFO - 'USER': 'cltbld', 17:40:09 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139', 17:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:09 INFO - '_': '/tools/buildbot/bin/python'} 17:40:09 INFO - TEST-INFO | started process 14820 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp09TUXs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:40:09 INFO - PID 14820 | 17:40:09 INFO - PID 14820 | (/builds/slave/test/build/application/firefox/firefox:14870): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:40:09 INFO - PID 14820 | 17:40:20 INFO - PID 14820 | RSS: Main: 144470016 17:40:20 INFO - PID 14820 | 17:40:22 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47680/tests/kraken/driver.html?testName=audio-beat-detection) 17:40:23 INFO - PID 14820 | RSS: Main: 149991424 17:40:23 INFO - PID 14820 | 17:40:26 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47680/tests/kraken/driver.html?testName=audio-dft) 17:40:26 INFO - PID 14820 | RSS: Main: 150458368 17:40:26 INFO - PID 14820 | 17:40:29 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47680/tests/kraken/driver.html?testName=audio-fft) 17:40:30 INFO - PID 14820 | RSS: Main: 147316736 17:40:30 INFO - PID 14820 | 17:40:32 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47680/tests/kraken/driver.html?testName=audio-oscillator) 17:40:32 INFO - PID 14820 | RSS: Main: 147984384 17:40:32 INFO - PID 14820 | 17:40:35 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47680/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:40:35 INFO - PID 14820 | RSS: Main: 147886080 17:40:35 INFO - PID 14820 | 17:40:38 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47680/tests/kraken/driver.html?testName=imaging-darkroom) 17:40:39 INFO - PID 14820 | RSS: Main: 147861504 17:40:39 INFO - PID 14820 | 17:40:42 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47680/tests/kraken/driver.html?testName=imaging-desaturate) 17:40:43 INFO - PID 14820 | RSS: Main: 149286912 17:40:43 INFO - PID 14820 | 17:40:46 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47680/tests/kraken/driver.html?testName=json-parse-financial) 17:40:47 INFO - PID 14820 | RSS: Main: 150671360 17:40:47 INFO - PID 14820 | 17:40:48 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47680/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:40:49 INFO - PID 14820 | RSS: Main: 150654976 17:40:49 INFO - PID 14820 | 17:40:50 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:40:51 INFO - PID 14820 | RSS: Main: 150790144 17:40:51 INFO - PID 14820 | 17:40:53 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:40:53 INFO - PID 14820 | RSS: Main: 147750912 17:40:53 INFO - PID 14820 | 17:40:56 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:40:57 INFO - PID 14820 | RSS: Main: 147918848 17:40:57 INFO - PID 14820 | 17:40:59 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:41:00 INFO - PID 14820 | RSS: Main: 148054016 17:41:00 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47680/tests/kraken/driver.html?testName=ai-astar) 17:41:02 INFO - PID 14820 | RSS: Main: 149508096 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | __start_tp_report 17:41:02 INFO - PID 14820 | _x_x_mozilla_page_load 17:41:02 INFO - PID 14820 | _x_x_mozilla_page_load_details 17:41:02 INFO - PID 14820 | |i|pagename|runs| 17:41:02 INFO - PID 14820 | |0;ai-astar;104;105;105;106;104;108;106;105;104;104 17:41:02 INFO - PID 14820 | |1;audio-beat-detection;118;116;112;119;114;112;113;112;143;118 17:41:02 INFO - PID 14820 | |2;audio-dft;161;163;164;162;163;161;163;162;164;165 17:41:02 INFO - PID 14820 | |3;audio-fft;74;82;72;73;74;76;74;73;73;74 17:41:02 INFO - PID 14820 | |4;audio-oscillator;78;79;82;80;88;77;77;78;79;78 17:41:02 INFO - PID 14820 | |5;imaging-gaussian-blur;114;114;113;113;114;113;118;114;113;114 17:41:02 INFO - PID 14820 | |6;imaging-darkroom;149;150;149;150;150;150;151;150;150;152 17:41:02 INFO - PID 14820 | |7;imaging-desaturate;97;95;95;95;94;94;95;94;94;94 17:41:02 INFO - PID 14820 | |8;json-parse-financial;44;44;44;44;45;45;44;45;43;45 17:41:02 INFO - PID 14820 | |9;json-stringify-tinderbox;37;37;37;38;37;36;37;37;37;37 17:41:02 INFO - PID 14820 | |10;stanford-crypto-aes;72;71;74;75;75;70;70;71;72;71 17:41:02 INFO - PID 14820 | |11;stanford-crypto-ccm;104;104;106;113;106;105;109;102;114;109 17:41:02 INFO - PID 14820 | |12;stanford-crypto-pbkdf2;153;167;155;156;153;156;163;155;157;159 17:41:02 INFO - PID 14820 | |13;stanford-crypto-sha256-iterative;63;63;60;62;59;59;63;63;59;65 17:41:02 INFO - PID 14820 | __end_tp_report 17:41:02 INFO - PID 14820 | __start_cc_report 17:41:02 INFO - PID 14820 | _x_x_mozilla_cycle_collect,709 17:41:02 INFO - PID 14820 | __end_cc_report 17:41:02 INFO - PID 14820 | __startTimestamp1506559262340__endTimestamp 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | ------- Summary: start ------- 17:41:02 INFO - PID 14820 | Number of tests: 14 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#0] ai-astar Cycles:10 Average:105.10 Median:105.00 stddev:1.29 (1.2%) stddev-sans-first:1.30 17:41:02 INFO - PID 14820 | Values: 104.0 105.0 105.0 106.0 104.0 108.0 106.0 105.0 104.0 104.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#1] audio-beat-detection Cycles:10 Average:117.70 Median:117.00 stddev:9.30 (7.9%) stddev-sans-first:9.86 17:41:02 INFO - PID 14820 | Values: 118.0 116.0 112.0 119.0 114.0 112.0 113.0 112.0 143.0 118.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:1.32 (0.8%) stddev-sans-first:1.22 17:41:02 INFO - PID 14820 | Values: 161.0 163.0 164.0 162.0 163.0 161.0 163.0 162.0 164.0 165.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#3] audio-fft Cycles:10 Average:74.50 Median:74.00 stddev:2.84 (3.8%) stddev-sans-first:3.00 17:41:02 INFO - PID 14820 | Values: 74.0 82.0 72.0 73.0 74.0 76.0 74.0 73.0 73.0 74.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:79.00 stddev:3.31 (4.2%) stddev-sans-first:3.46 17:41:02 INFO - PID 14820 | Values: 78.0 79.0 82.0 80.0 88.0 77.0 77.0 78.0 79.0 78.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:1.49 (1.3%) stddev-sans-first:1.58 17:41:02 INFO - PID 14820 | Values: 114.0 114.0 113.0 113.0 114.0 113.0 118.0 114.0 113.0 114.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:150.00 stddev:0.88 (0.6%) stddev-sans-first:0.83 17:41:02 INFO - PID 14820 | Values: 149.0 150.0 149.0 150.0 150.0 150.0 151.0 150.0 150.0 152.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.95 (1.0%) stddev-sans-first:0.53 17:41:02 INFO - PID 14820 | Values: 97.0 95.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 94.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#8] json-parse-financial Cycles:10 Average:44.30 Median:44.50 stddev:0.67 (1.5%) stddev-sans-first:0.71 17:41:02 INFO - PID 14820 | Values: 44.0 44.0 44.0 44.0 45.0 45.0 44.0 45.0 43.0 45.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:0.47 (1.3%) stddev-sans-first:0.50 17:41:02 INFO - PID 14820 | Values: 37.0 37.0 37.0 38.0 37.0 36.0 37.0 37.0 37.0 37.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#10] stanford-crypto-aes Cycles:10 Average:72.10 Median:72.00 stddev:1.91 (2.7%) stddev-sans-first:2.03 17:41:02 INFO - PID 14820 | Values: 72.0 71.0 74.0 75.0 75.0 70.0 70.0 71.0 72.0 71.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#11] stanford-crypto-ccm Cycles:10 Average:107.20 Median:107.50 stddev:3.97 (3.7%) stddev-sans-first:4.03 17:41:02 INFO - PID 14820 | Values: 104.0 104.0 106.0 113.0 106.0 105.0 109.0 102.0 114.0 109.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.40 Median:156.50 stddev:4.48 (2.9%) stddev-sans-first:4.46 17:41:02 INFO - PID 14820 | Values: 153.0 167.0 155.0 156.0 153.0 156.0 163.0 155.0 157.0 159.0 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:63.00 stddev:2.17 (3.4%) stddev-sans-first:2.24 17:41:02 INFO - PID 14820 | Values: 63.0 63.0 60.0 62.0 59.0 59.0 63.0 63.0 59.0 65.0 17:41:02 INFO - PID 14820 | -------- Summary: end -------- 17:41:02 INFO - PID 14820 | 17:41:02 INFO - PID 14820 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:41:03 INFO - TEST-INFO | 14820: exit 0 17:41:03 INFO - TEST-OK | kraken | took 60117ms 17:41:03 INFO - SUITE-END | took 1168s 17:41:03 INFO - Completed test suite (00:19:29) 17:41:03 INFO - javascript benchmark 17:41:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9094.087128027124, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3953.2093581283734, 3953.644172024517, 3965.345717956537, 3973.9403908941354, 4034.293998550255, 6286.654234017127, 6356.713947872346, 6413.390727880856, 6423.582467139633, 6506.837329066776, 6380.585261957969, 6429.582077164992, 6503.674816259192, 6505.0242463630475, 6514.250861150969, 5670.6030577859565, 5699.088145896658, 5764.971175144118, 5766.769329226847, 5793.159991801186, 5694.2028116063575, 5758.769649214027, 5786.855328616797, 5831.679257640828, 5853.5317174625925, 5614.466625670555, 5666.405027472098, 5673.177389278552, 5697.515711164556, 5752.942470575283, 5718.857028574319, 5777.075667893159, 5796.188533605298, 5796.652200867961, 5803.506701930341, 5719.170720245583, 5732.656040637534, 5760.481556659879, 5787.855303617401, 5821.388754180793, 6317.778877739303, 6350.33321501245, 6374.681265936684, 6395.584287021329, 6476.060971159202, 6936.167659880846, 6969.965150174267, 6976.895346569806, 6986.790396288068, 7055.329743674342, 7040.683169257396, 7119.964400177966, 7130.6078165700865, 7139.393151582121, 7141.785746427564, 6369.267534233576, 6434.581752186093, 6472.741090356333, 6475.967620161963, 6515.185601799775, 7187.245339239409, 7192.244814294424, 7199.388052015637, 7201.0, 7214.855702886018, 6729.629870357079, 6853.109095817512, 6862.553933994274, 6865.485088618374, 6874.106366172366, 6980.267071957473, 6993.930060699429, 6996.230414654486, 6998.16022077354, 6999.12510936133, 6978.232394436608, 7025.718971241093, 7037.542559733601, 7039.225685174627, 7042.6126563039525, 6935.861282774316, 6955.6869940852785, 6980.755673551503, 7057.682404291819, 7078.893816592756, 4965.726885021359, 4994.051130285234, 5035.269885866529, 5050.217216331474, 5059.62052846038, 4579.725216486955, 4580.198465268565, 4645.372874661945, 4649.69776964503, 4661.487236403993, 6348.492120630339, 6361.745530178833, 6430.196225471816, 6431.678416079178, 6433.74265029403, 6893.517453778288, 6901.413379862768, 6986.196587392515, 6990.405815505637, 7024.719011239493, 6312.747490100436, 6360.491160707133, 6378.744850206032, 6386.0, 6389.424951754271, 5535.667859928417, 5544.63959842609, 5598.916016259759, 5602.691851948182, 5638.295213098363, 7173.7130514779865, 7246.855062898712, 7355.963220183865, 7361.227071157558, 7369.8157546061775, 6876.69054892541, 6912.585244885323, 6961.095057642474, 6979.755708550278, 6982.266861979622, 7111.964440177766, 7252.601106939169, 7254.96372518134, 7263.382612477999, 7372.299631534996, 6784.3554862288, 6854.417374523221, 6858.485613578901, 6863.07348507965, 6886.724531018703, 6898.827529311808, 6945.305469453015, 6956.478264130109, 6980.755673551402, 6995.230524642184, 6736.73053077891, 6807.0810440589885, 6820.931790682228, 6828.180618325832, 6831.0, 6738.797836064926, 6739.629320387426, 6741.59550426976, 6831.829204269933, 6842.315768423118, 6534.281229064894, 6537.771178008747, 6637.103990961158, 6653.866922661617, 6699.129113215251, 6831.897521537082, 6858.725650974105, 6867.2102708189195, 6906.930930690629, 6911.930880691129, 6536.967315163393, 6543.476521878333, 6633.436157926631, 6637.070810086689, 6674.599524028574], "value": 6355.618777644987, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [948.2508818033754, 953.3707752883098, 977.8239916814974, 1005.7033175213311, 1006.4665727164605, 41556.376654350206, 42038.36942445866, 42174.367384489255, 42238.366424503656, 42317.365239521394, 40449.39325910107, 40613.18773624526, 43605.0, 44033.77983110088, 44210.55789442104, 8328.708495202654, 8441.113683063282, 8476.576171191447, 8482.915170848306, 8490.957545212264, 8311.127331630183, 8444.366672499556, 8445.746627601182, 8463.873041904377, 8472.0, 8454.323654107688, 8490.830183396329, 8492.15078492152, 8525.31797456202, 8526.70156544521, 8429.536375499376, 8431.494110353398, 8433.70482033129, 8436.072032076467, 8439.493630382196, 8228.547429891354, 8266.462679925786, 8310.459820111733, 8316.043654979694, 8367.58162091899, 39789.0, 40106.0, 40191.7990410049, 40262.39606405906, 40268.0, 3725.2735716535285, 3758.3798673218885, 3771.698264138862, 3773.4528493368584, 3782.224643947984, 32236.0, 32396.514052289236, 32449.188770280696, 32495.51256731151, 32575.51136732951, 5382.461753824626, 5398.109311963522, 5410.8917821643345, 5420.159875219348, 5430.185472179165, 217029.0, 220081.0, 221605.0, 223001.0, 224304.0, 53200.46799532032, 54039.0, 54197.0, 54202.0, 54255.186172207446, 57363.71318143383, 57369.426305737245, 57424.71287643535, 57490.13764793531, 57500.42499575034, 16474.505764827074, 16585.25366358517, 16586.834131658772, 16607.0, 16623.41818036387, 13365.799513007312, 13367.665808354674, 13372.465101395836, 13373.598792036255, 13377.197368157942, 14574.562763117123, 14730.263486825615, 14781.0, 14812.333444995002, 14844.92577537127, 5523.834284971457, 5640.943590564124, 5676.318841739017, 5694.629849059881, 5722.341930677942, 1049.8897615750236, 1088.1403691084113, 1088.8094583447864, 1096.9670909872716, 1101.658485869383, 25055.498890022096, 25108.874455627603, 25187.748122518908, 25364.492710145692, 25629.871850641, 1166.1720178673238, 1184.798584240681, 1194.4863708605383, 1196.1208511743862, 1200.351810022578, 13380.732385352238, 13455.0, 13508.392122354519, 13576.524821631394, 13736.0, 287293.0, 287372.0, 288520.0, 289596.0, 290309.0, 56676.71661641748, 56726.7163664179, 57190.42809571934, 57270.0, 57319.0, 1480.970380592382, 1523.6190952261934, 1525.794017807604, 1527.2974431761293, 1541.899776514523, 24318.148864789648, 24411.51176976486, 24429.0, 24476.14333498319, 24589.401688890164, 39129.41305880471, 39288.21423571456, 39900.0, 39919.20161596694, 39925.40111898382, 237541.0, 237808.0, 239119.0, 239772.0, 240150.0, 35418.46872296866, 35808.820955895055, 36120.0, 36130.8193459031, 36342.454863177605, 45142.0, 45661.31508027316, 45714.0, 45865.770671146434, 46318.30522542097, 31474.213144671565, 31501.684983150793, 31600.525992110597, 31897.36205275928, 32655.673443266212, 33124.1718957028, 33508.83245583757, 33542.49686254757, 33973.49039764455, 34465.82767086149, 38603.0, 39822.800885995384, 39929.80035099922, 39949.40075898806, 40136.59863401445, 32333.35333293274, 32539.674603253665, 32838.507422388204, 33027.174320642174, 33197.66802331946, 25253.747462525873, 25379.74620253774, 25515.489690206465, 25984.740152598988, 26098.086566970065], "value": 18448.51181807389, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.6412823860561, 607.3926073926074, 609.4575827513517, 614.4531367083296, 615.4583966109823, 22813.201537946174, 23383.415414614643, 23593.646095308584, 23620.29139125827, 23643.881780591095, 23434.0, 25967.87016064917, 26212.082577109806, 26232.868835655794, 26234.868825655845, 6386.042093685938, 6421.743130274806, 6471.996840489732, 6472.482201423898, 6550.246721627002, 6385.584936979105, 6435.0, 6495.090687303781, 6695.49783766217, 6717.09319241901, 6651.2018557773135, 6656.301088385722, 6665.600063996176, 6695.196576410837, 6697.363750443725, 6225.470834979033, 6492.9350706492805, 6690.096836927001, 6696.430803381719, 6696.799096027152, 6082.178905847698, 6091.390860913917, 6092.0, 6093.939060609381, 6116.694165291718, 29776.0, 29916.40167196644, 30055.549166762517, 30139.69860301391, 30237.395252094833, 2197.8791166485835, 2218.711567496215, 2226.198568515333, 2226.543558570489, 2227.0201111510883, 19797.70303445435, 20421.489462763402, 20650.48373790652, 20970.580588388148, 21003.684944725843, 2504.9854808802465, 2517.546841568517, 2553.9361515962064, 2559.36015996001, 2572.5755250383663, 135585.0, 142533.0, 150681.24659376632, 154478.99999999886, 155451.0, 15517.612059698598, 15764.211789410483, 16410.0, 16433.753493697604, 16625.0, 25650.615240771403, 25653.61519577208, 25668.229953101436, 25668.871655641975, 25674.74325256761, 10356.792864142675, 10735.731606709895, 10777.892221077846, 10816.837747433794, 10867.347959122479, 9704.126628603459, 9720.270979676552, 9746.561404736662, 9784.657536986313, 9800.36297640651, 9581.233501319877, 9638.409662405624, 9728.173105285989, 9876.555555000043, 9879.654212102541, 2816.352238985045, 2846.217290245199, 2862.670792858806, 2920.8685609147637, 3027.591275177823, 1026.1534234256767, 1054.9103326217205, 1067.5089458849016, 1077.3051381858656, 1088.580896354909, 21143.577128457346, 21180.576388472145, 21252.14991400327, 21263.149474020865, 21320.253791117546, 1122.3209957975307, 1122.960696375623, 1125.6172901213627, 1129.0685184722636, 1136.1422126294594, 11619.302841829518, 11621.24461909973, 11724.296542207494, 11730.530778768923, 11841.407929603485, 569397.0, 574271.0, 578686.0, 605451.0, 612567.0, 126990.0, 128532.0, 128761.0, 130682.0, 132805.0, 1228.2814553486132, 1239.6652903715947, 1269.0862578943147, 1270.7267937393506, 1281.1672412931669, 18336.991465469528, 18585.0, 18635.90682046581, 18739.0, 18787.530311742317, 60897.69551152216, 63974.3602563987, 64933.35066649273, 64969.0, 65052.67473662602, 76016.61991690006, 76162.61918690371, 76644.61677691576, 76732.0, 77295.0, 15218.771718424012, 15317.540473785804, 15453.304601292744, 15462.613434664223, 15486.457973971308, 22592.66111008369, 22866.771332286466, 23030.884845575663, 23326.416839579146, 23339.883300583388, 18696.813031869508, 18903.43289701311, 19090.427287181406, 19451.0, 19496.707549386487, 16880.409185678443, 17192.39826606113, 17400.73898891543, 17577.209025593624, 17884.91057544704, 19161.90419047896, 19196.71204931899, 19298.8070119297, 19436.4169074928, 19450.0, 14950.700985980002, 14955.551333460013, 15036.624084397978, 15062.096274223582, 15088.54734357971, 18462.0, 18504.259829606664, 18706.625867482846, 18833.24667013304, 18976.14607342645], "value": 12619.232471961972, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.9027068105233, 1463.8316593591733, 1503.6165777726685, 1510.4788847847497, 1513.962150946227, 16769.580760480978, 17070.317187312507, 17109.230084646206, 17141.14294285284, 17201.483955481355, 16793.748093778602, 16804.83195168048, 16874.0, 16885.15574221287, 16890.746638800396, 2876.3528206153605, 2992.955105673406, 2995.9101226963226, 3002.939941201175, 3012.1565961530805, 2828.448452551743, 2893.2043687985865, 2900.1444573850763, 2900.8114472559323, 2901.3036871150985, 2772.265349682326, 2960.5115155999233, 2972.301509145349, 2978.19588711049, 2979.3147576057518, 2833.5607980763016, 2857.7285157909964, 2859.37093839355, 2871.5405535114387, 2877.6115224444748, 2700.1629494856656, 2720.40151166743, 2735.2341344423594, 2742.7394397532203, 2743.7942154338307, 16406.0, 16639.16804159787, 16667.0, 16674.082925439096, 16728.41450549225, 8455.239028487465, 8835.690750823755, 8839.160279773476, 8843.46939183651, 8844.38089333747, 5808.535317174616, 5820.738066787005, 5979.730912108966, 6018.789342373039, 6047.455728984412, 2124.798144176301, 2131.7015617813518, 2161.6757486377014, 2190.726159230096, 2193.144673577306, 30777.538336924965, 30981.690183097882, 31004.379912401626, 31010.224744381572, 31029.8448507756, 7201.17186523546, 7329.303716146957, 7349.0, 7473.476856619985, 7517.285857843496, 19347.226110955686, 19444.70832937507, 19493.122809473607, 19619.90190049069, 19664.0, 8209.876851847228, 8505.74482765518, 8508.149185081442, 8518.318534517224, 8533.189347012023, 7664.34853037498, 7675.654595543214, 7730.652120654584, 7880.448368614257, 7895.210478952174, 5340.893182136336, 5551.583631227674, 5555.388937208245, 5616.9327827712605, 5630.211770352154, 1502.0462006625871, 1530.6326481644326, 1672.4982505248377, 1674.9330026799034, 1680.1515234806345, 912.2473958983865, 921.6037104045191, 925.8379783537854, 936.2509992006504, 942.6229508196639, 8965.148310910454, 9014.098590140933, 9018.458892466473, 9073.954630226806, 9144.177024067865, 1021.6526381030337, 1022.1260821997234, 1041.463646222196, 1050.873895132589, 1053.6733612580124, 4638.837640682627, 4779.545943135397, 5064.366954130734, 5651.0, 5853.443922827325, 24581.38546536351, 25194.622080668803, 25294.367640809127, 25331.493370132495, 25470.108546200794, 23931.401714956915, 24642.50714985726, 24833.379165520644, 25025.0, 25279.494410112064, 12711.682207944876, 12722.809157862639, 12886.742265154644, 12927.28899910495, 12942.288174150512, 9528.857067144138, 9546.522673866419, 9557.569909353957, 9597.712068637951, 9656.082672146134, 20366.89816550908, 20699.4825129373, 21219.36341909745, 21484.0, 21513.6772948409, 22253.55492890101, 22556.66165007559, 22702.205422810785, 22709.886450567643, 23024.884875575513, 12322.815157772462, 12532.060095492692, 12536.059795515555, 12545.31000794965, 12587.433565489759, 15634.45279415215, 15643.687126257639, 15774.605634859221, 15863.127527986071, 15885.205739712737, 12797.872021279669, 12878.484860605471, 13103.60689179326, 13187.274699891597, 13225.0, 11795.351255680676, 11843.111766617365, 11864.881351186377, 11992.0, 12179.208351457128, 9819.9018009819, 9972.650957216463, 9990.450525221182, 10046.296759226783, 10081.848772268275, 8634.136586341316, 8658.047614762245, 8708.738737837875, 8709.08554601783, 8740.257078148428, 8146.103928567972, 8225.218604232588, 8237.629306681094, 8281.503109813431, 8357.874631880648], "value": 7342.555062798852, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.9418361072088, 560.9046462101445, 567.4183961439526, 574.3595890582002, 583.8978178818705, 6182.752689892404, 6228.501719862412, 6262.530310226734, 6263.812085637427, 6279.24649042115, 6192.133101365813, 6225.937740622592, 6235.345288744673, 6237.220347456568, 6269.059641053845, 5061.848144555669, 5073.847784566469, 5087.414947281073, 5129.0, 5129.769160387773, 4863.951360486403, 4993.625478089158, 5007.824726134586, 5021.146405111122, 5138.486151384869, 4959.504049595048, 4963.329950456697, 4984.277279794428, 5000.449950505442, 5004.374453193351, 4943.332650092228, 4960.801567937278, 4966.453690094087, 4968.70187788732, 4972.801087956477, 4453.39677716022, 4504.707194032378, 4551.7724113794175, 4557.954420455786, 4567.063751930847, 6059.272887253558, 6111.358307377705, 6132.7240274187325, 6177.907331390033, 6183.907241391383, 5900.704964751788, 5916.556258280646, 5954.940450595525, 5957.731902064417, 5983.311919129269, 6718.66406679664, 6723.630200338979, 6758.121444212221, 6762.289959554225, 6770.627615481147, 5014.498550144993, 5055.51972562606, 5074.934263804568, 5084.211947148198, 5131.640785145041, 6677.766278180289, 6822.522423430361, 6908.549204667076, 6926.168859736864, 6978.79063628092, 5127.948720512747, 5209.47905209476, 5235.633505654568, 5237.738113094406, 5262.052830490472, 5248.396434410015, 5290.153575427913, 5298.231756395301, 5331.653442526223, 5342.8664283392445, 5792.333881603585, 5861.150133230658, 5871.0, 5878.617889837147, 5906.793262235801, 6671.498188179433, 6701.832454188684, 6728.360805723449, 6730.360615741497, 6770.932290677129, 1832.3769918227867, 1835.0, 1837.2559113558764, 1839.9724004139948, 1840.3834715370233, 1253.1828848170007, 1261.6689392690616, 1294.5210272199158, 1301.73965206959, 1305.3342795174528, 6181.165542651775, 6211.565190436717, 6231.781887633911, 6252.155958945576, 6277.309495955441, 6447.742090316428, 6803.727850886008, 6816.829579260559, 6824.897626535606, 6825.965870170618, 6110.114033465123, 6113.786017489367, 6159.599626024384, 6208.534359923024, 6263.935131027736, 3507.7544571879725, 3521.33094712009, 3525.982370088133, 3543.2204914918675, 3558.6619271169197, 6872.862542749117, 6967.512274140863, 7046.189688185822, 7071.186813516511, 7083.433325334064, 6861.622610756456, 6906.274841141708, 6944.7222111116, 6951.930480695129, 6976.825579360455, 7002.40479559233, 7048.506604537633, 7063.551971845759, 7078.1506219254015, 7139.357457828716, 6650.467962562887, 6731.720973015307, 6742.69657865407, 6798.558093723892, 6804.55770374924, 6675.265720770614, 6696.799096027127, 6761.695723692347, 6767.93232067673, 6807.489438292247, 7086.681099350645, 7115.217326094229, 7121.323474269936, 7137.928620713934, 7151.0, 5087.065546419966, 5204.115300398866, 5229.320188375486, 5242.5281724644965, 5267.657602255841, 6569.948808190667, 6714.865702686016, 6730.730770769114, 6750.797476075725, 6781.559198652072, 6394.840128996812, 6452.193475815523, 6583.44040756541, 6592.340765923369, 6610.570312929644, 6497.772577959749, 6552.034389682981, 6584.934150658623, 6594.439472644879, 6607.471402287902, 6376.266729326187, 6428.132202152649, 6440.323766004502, 6441.806745797634, 6471.094046833353, 6575.145231119924, 6633.9336606633315, 6664.666766661744, 6664.866702666017, 6672.966635166793, 6294.402031806971, 6381.7128229228865, 6398.296187419287, 6408.935910640834, 6412.23053233615], "value": 5195.021358163067, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [856.2850020233109, 866.4931015356017, 877.8288233794412, 987.4420952162028, 1008.5915204342242, 22445.3266402008, 23840.40398990024, 23848.5230295394, 23874.880625596892, 23928.88035559822, 22582.322530324116, 23276.76723232767, 23360.64959025616, 23444.64833027506, 23492.942817573206, 1039.1738567838586, 1053.2311412668757, 1067.1889364083274, 1077.2782235901964, 1081.3836113415357, 1065.5524679634534, 1076.9461526923621, 1077.6820837852852, 1078.8921107889228, 1080.168270431767, 1121.6130434999911, 1123.5561953028548, 1128.6839684888243, 1129.0797999630315, 1132.0321125437756, 1112.0325316974204, 1123.7696272264166, 1123.9887601123967, 1124.943752812356, 1124.966251012467, 816.9877451838167, 824.1017291152613, 826.5412695953743, 828.0891019878146, 833.3333333333309, 21158.576828463345, 21384.46538836526, 21661.0, 21779.891100544395, 22599.435014124614, 23086.65370019451, 23260.185893493646, 23499.53000939989, 23793.286201413986, 23842.880785596135, 18330.725039124423, 18657.906710466497, 18713.906430467898, 18765.0, 18775.24899004038, 3892.8442862285474, 3899.3566061599804, 3908.3746600543777, 3909.1986142840933, 3910.491636087319, 158157.0, 161358.0, 163359.0, 164821.17589412097, 165198.0, 17940.282388704567, 18177.63644727098, 18364.908175459306, 18448.907755461405, 18480.353187638368, 33845.492317614764, 34830.47754283688, 34938.30123397518, 34963.82518087393, 35142.64857351445, 8530.914690853137, 8686.39195256326, 8725.91274087251, 8779.20987111163, 8847.601857916408, 4493.6854420190275, 4613.1465678848845, 4637.3275874997935, 4640.605548528345, 4653.511381304982, 6027.21646185993, 6176.227971503562, 6177.320494745574, 6199.380061993765, 6206.472449841724, 1161.1117495116243, 1191.4578866615668, 1192.7793358228755, 1193.7791508570942, 1197.6107764976243, 608.1182285685774, 628.7107930351998, 633.0946746153045, 636.9585976911486, 640.8237734623016, 13200.669983250496, 13374.0, 13429.597112086458, 14747.262636868329, 14791.408343666348, 749.606456610284, 753.0398741604389, 759.3545486336569, 759.9506032107895, 770.9267619576132, 6242.375762423812, 6270.874582508324, 6279.780207692709, 6378.330275321117, 6429.324920883333, 143278.0, 148573.25713371363, 152058.0, 152157.0, 156606.0, 133150.0, 133592.0, 133773.33113334372, 135805.0, 142308.0, 115308.42345788385, 115435.42282288703, 115834.0, 117297.41351293189, 118212.0, 11569.942150289195, 11868.287902725864, 12004.459799308877, 12196.878031219574, 12203.08476864221, 54549.0, 54926.72536637291, 55165.44834551603, 55416.445835541126, 55873.0, 70294.64852675704, 70675.64662176862, 71116.28883711097, 71639.64180179065, 74129.25870741224, 16930.153492325582, 17399.652006960045, 17407.303707851544, 17491.912540437217, 17506.299748009937, 19567.0, 19577.0, 19609.411717648494, 19721.309754158534, 19839.30562430308, 18001.1899464527, 18325.633487330444, 18391.425200471676, 18483.445496635122, 18538.351157709414, 17367.47897563075, 17368.82631173672, 17500.562485937953, 17591.208395622485, 17621.911890440464, 16707.331706732082, 17109.914450428165, 17134.22895969709, 17175.227114780115, 17209.397671081453, 12965.805512917124, 13115.475380984653, 13132.40904159296, 13260.005499587376, 13410.530631427853, 13493.392797323948, 13529.797053044016, 13662.863371366158, 13677.179369237878, 13728.107673001223], "value": 10021.717725866152, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1378.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 105.0, 105.0, 106.0, 104.0, 108.0, 106.0, 105.0, 104.0, 104.0], "value": 105.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 112.0, 119.0, 114.0, 112.0, 113.0, 112.0, 143.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 164.0, 162.0, 163.0, 161.0, 163.0, 162.0, 164.0, 165.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 72.0, 73.0, 74.0, 76.0, 74.0, 73.0, 73.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 82.0, 80.0, 88.0, 77.0, 77.0, 78.0, 79.0, 78.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 114.0, 113.0, 118.0, 114.0, 113.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 150.0, 150.0, 150.0, 151.0, 150.0, 150.0, 152.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 44.0, 44.0, 44.0, 45.0, 45.0, 44.0, 45.0, 43.0, 45.0], "value": 44.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 38.0, 37.0, 36.0, 37.0, 37.0, 37.0, 37.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 74.0, 75.0, 75.0, 70.0, 70.0, 71.0, 72.0, 71.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 104.0, 106.0, 113.0, 106.0, 105.0, 109.0, 102.0, 114.0, 109.0], "value": 107.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 167.0, 155.0, 156.0, 153.0, 156.0, 163.0, 155.0, 157.0, 159.0], "value": 157.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 60.0, 62.0, 59.0, 59.0, 63.0, 63.0, 59.0, 65.0], "value": 61.6, "unit": "score"}]}]} 17:41:03 INFO - Return code: 0 17:41:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:41:03 INFO - # TBPL SUCCESS # 17:41:03 INFO - Running post-action listener: _package_coverage_data 17:41:03 INFO - Running post-action listener: _resource_record_post_action 17:41:03 INFO - [mozharness: 2017-09-28 00:41:03.606270Z] Finished run-tests step (success) 17:41:03 INFO - Running post-run listener: _resource_record_post_run 17:41:03 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 151552; Write bytes: 396828672; Read time: 68; Write time: 344204 17:41:03 INFO - TinderboxPrint: CPU usage
13.1% 17:41:03 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 68 17:41:03 INFO - TinderboxPrint: I/O write bytes / time
396,828,672 / 344,204 17:41:03 INFO - TinderboxPrint: CPU idle
8,225.1 (86.9%) 17:41:03 INFO - TinderboxPrint: CPU user
1,187.3 (12.5%) 17:41:03 INFO - TinderboxPrint: Swap in / out
0 / 0 17:41:03 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 75636736; Read time: 0; Write time: 52364 17:41:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:41:03 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 151552; Write bytes: 321191936; Read time: 68; Write time: 291840 17:41:04 INFO - Running post-run listener: _upload_blobber_files 17:41:04 INFO - Blob upload gear active. 17:41:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:41:04 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 17:41:04 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'] 17:41:04 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 17:41:04 INFO - (blobuploader) - INFO - Open directory for files ... 17:41:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:41:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:41:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:11 INFO - (blobuploader) - INFO - Done attempting. 17:41:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:41:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:41:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:12 INFO - (blobuploader) - INFO - Done attempting. 17:41:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:41:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:41:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:13 INFO - (blobuploader) - INFO - Done attempting. 17:41:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:41:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:41:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:14 INFO - (blobuploader) - INFO - Done attempting. 17:41:14 INFO - (blobuploader) - INFO - Iteration through files over. 17:41:14 INFO - Return code: 0 17:41:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:41:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:41:14 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0b02ea109d3e0e559d17dd0e0e0aeb6ab1eed1291857e20313457125e6f36628da48e5d0a859803b2101be9342b6f6d9806323cbe57c98f0d9d9d7ddc65ab1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66c31ef836a5c8091fc0b61084ad157db6d1d590566b8347a8ccb56684213415a131ff93404043fe9a1e172b9d1012c1af7c2ea524729a600d06994b9e14629c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0647352f23bebc0add1c55e6c5d0895baa7d239a63a5df6b83182a7cf8da5627967f32d2f5f76f71ad092911e5eca740b3210dd1152076cd6f9a970a33e7bf99", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fee5ac07ba6c3737437d882e434f649aa91221b1585df6ed763839c722d1ebb66550216daeb1806f70efb2d4d92d24d8e8c76be1da5bd605d69843dedbfb9286"} 17:41:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:41:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:41:14 INFO - Contents: 17:41:14 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0b02ea109d3e0e559d17dd0e0e0aeb6ab1eed1291857e20313457125e6f36628da48e5d0a859803b2101be9342b6f6d9806323cbe57c98f0d9d9d7ddc65ab1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66c31ef836a5c8091fc0b61084ad157db6d1d590566b8347a8ccb56684213415a131ff93404043fe9a1e172b9d1012c1af7c2ea524729a600d06994b9e14629c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0647352f23bebc0add1c55e6c5d0895baa7d239a63a5df6b83182a7cf8da5627967f32d2f5f76f71ad092911e5eca740b3210dd1152076cd6f9a970a33e7bf99", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fee5ac07ba6c3737437d882e434f649aa91221b1585df6ed763839c722d1ebb66550216daeb1806f70efb2d4d92d24d8e8c76be1da5bd605d69843dedbfb9286"} 17:41:14 INFO - Running post-run listener: copy_logs_to_upload_dir 17:41:14 INFO - Copying logs to upload dir... 17:41:14 INFO - mkdir: /builds/slave/test/build/upload/logs 17:41:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.636682 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-27 17:41:14.852853) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 17:41:14.857902) ========= 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=eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0b02ea109d3e0e559d17dd0e0e0aeb6ab1eed1291857e20313457125e6f36628da48e5d0a859803b2101be9342b6f6d9806323cbe57c98f0d9d9d7ddc65ab1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66c31ef836a5c8091fc0b61084ad157db6d1d590566b8347a8ccb56684213415a131ff93404043fe9a1e172b9d1012c1af7c2ea524729a600d06994b9e14629c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0647352f23bebc0add1c55e6c5d0895baa7d239a63a5df6b83182a7cf8da5627967f32d2f5f76f71ad092911e5eca740b3210dd1152076cd6f9a970a33e7bf99", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fee5ac07ba6c3737437d882e434f649aa91221b1585df6ed763839c722d1ebb66550216daeb1806f70efb2d4d92d24d8e8c76be1da5bd605d69843dedbfb9286"} build_url:https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/IgMi7h1TRIKpKFonaQA_1Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0b02ea109d3e0e559d17dd0e0e0aeb6ab1eed1291857e20313457125e6f36628da48e5d0a859803b2101be9342b6f6d9806323cbe57c98f0d9d9d7ddc65ab1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66c31ef836a5c8091fc0b61084ad157db6d1d590566b8347a8ccb56684213415a131ff93404043fe9a1e172b9d1012c1af7c2ea524729a600d06994b9e14629c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0647352f23bebc0add1c55e6c5d0895baa7d239a63a5df6b83182a7cf8da5627967f32d2f5f76f71ad092911e5eca740b3210dd1152076cd6f9a970a33e7bf99", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fee5ac07ba6c3737437d882e434f649aa91221b1585df6ed763839c722d1ebb66550216daeb1806f70efb2d4d92d24d8e8c76be1da5bd605d69843dedbfb9286"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 17:41:14.889364) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:41:14.889739) ========= 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=eca2ab57546de7b41f256b19000001ef-1506558032.895687-2125860139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:41:14.971411) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 17:41:14.974766) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 17:41:14.975130) ========= ========= Total master_lag: 0.22 =========