builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1507170205.33 results: success (0) revision: 3d3e597d3b96eca3163c70ff0d2a263e2d7e9cf5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:25.331678) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:25.332039) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:25.332316) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:25.368238) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:25.368509) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-04 19:23:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2017-10-04 19:23:25 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103441 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:25.506891) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:25.507190) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015531 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:25.561069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:25.561368) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3d3e597d3b96eca3163c70ff0d2a263e2d7e9cf5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3d3e597d3b96eca3163c70ff0d2a263e2d7e9cf5 --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=56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-04 19:23:25,616 truncating revision to first 12 chars 2017-10-04 19:23:25,616 Setting DEBUG logging. 2017-10-04 19:23:25,616 attempt 1/10 2017-10-04 19:23:25,616 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3d3e597d3b96?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-04 19:23:25,983 unpacking tar archive at: mozilla-beta-3d3e597d3b96/testing/mozharness/ program finished with exit code 0 elapsedTime=0.623586 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:26.210638) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:26.210912) ========= script_repo_revision: 3d3e597d3b96eca3163c70ff0d2a263e2d7e9cf5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:26.211248) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:26.211520) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-04 19:23:26.226695) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2017-10-04 19:23:26.229525) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:23:26 INFO - MultiFileLogger online at 20171004 19:23:26 in /builds/slave/test 19:23:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 19:23:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:23:26 INFO - {'append_to_log': False, 19:23:26 INFO - 'base_work_dir': '/builds/slave/test', 19:23:26 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 19:23:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:23:26 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 19:23:26 INFO - 'buildbot_json_path': 'buildprops.json', 19:23:26 INFO - 'code_coverage': False, 19:23:26 INFO - 'config_files': ('talos/linux_config.py',), 19:23:26 INFO - 'default_actions': ('clobber', 19:23:26 INFO - 'read-buildbot-config', 19:23:26 INFO - 'download-and-extract', 19:23:26 INFO - 'populate-webroot', 19:23:26 INFO - 'create-virtualenv', 19:23:26 INFO - 'install', 19:23:26 INFO - 'setup-mitmproxy', 19:23:26 INFO - 'run-tests'), 19:23:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:23:26 INFO - 'disable_ccov_upload': False, 19:23:26 INFO - 'disable_stylo': False, 19:23:26 INFO - 'download_minidump_stackwalk': True, 19:23:26 INFO - 'download_symbols': 'ondemand', 19:23:26 INFO - 'enable_stylo': False, 19:23:26 INFO - 'enable_webrender': False, 19:23:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 19:23:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:23:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:23:26 INFO - 'gecko_profile': False, 19:23:26 INFO - 'gecko_profile_interval': 0, 19:23:26 INFO - 'installer_path': 'installer.exe', 19:23:26 INFO - 'log_level': 'info', 19:23:26 INFO - 'log_name': 'talos', 19:23:26 INFO - 'log_to_console': True, 19:23:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:23:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:23:26 INFO - 'opt_config_files': (), 19:23:26 INFO - 'pip_index': False, 19:23:26 INFO - 'suite': 'dromaeojs-e10s', 19:23:26 INFO - 'system_bits': '32', 19:23:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:23:26 INFO - 'title': 'talos-linux64-ix-037', 19:23:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:23:26 INFO - 'use_talos_json': True, 19:23:26 INFO - 'verify': 'False', 19:23:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:23:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:23:26 INFO - 'work_dir': 'build'} 19:23:26 INFO - [mozharness: 2017-10-05 02:23:26.419879Z] Running clobber step. 19:23:26 INFO - Running pre-action listener: _resource_record_pre_action 19:23:26 INFO - Running main action method: clobber 19:23:26 INFO - rmtree: /builds/slave/test/build 19:23:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:23:27 INFO - Running post-action listener: _resource_record_post_action 19:23:27 INFO - [mozharness: 2017-10-05 02:23:27.397169Z] Finished clobber step (success) 19:23:27 INFO - [mozharness: 2017-10-05 02:23:27.397275Z] Running read-buildbot-config step. 19:23:27 INFO - Running pre-action listener: _resource_record_pre_action 19:23:27 INFO - Running main action method: read_buildbot_config 19:23:27 INFO - Using buildbot properties: 19:23:27 INFO - { 19:23:27 INFO - "project": "", 19:23:27 INFO - "product": "firefox", 19:23:27 INFO - "who": "ryanvm@gmail.com", 19:23:27 INFO - "installer_path": "public/build/target.tar.bz2", 19:23:27 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 19:23:27 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 19:23:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 19:23:27 INFO - "stage_platform": "linux64", 19:23:27 INFO - "basedir": "/builds/slave/test", 19:23:27 INFO - "buildnumber": 157, 19:23:27 INFO - "platform": "ubuntu64_hw", 19:23:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:23:27 INFO - "slavebuilddir": "test", 19:23:27 INFO - "taskId": "NsvvsqM1RUKHavBmufWjNg", 19:23:27 INFO - "branch": "mozilla-beta", 19:23:27 INFO - "script_repo_revision": "production", 19:23:27 INFO - "revision": "3d3e597d3b96eca3163c70ff0d2a263e2d7e9cf5", 19:23:27 INFO - "slavename": "talos-linux64-ix-037", 19:23:27 INFO - "repo_path": "releases/mozilla-beta" 19:23:27 INFO - } 19:23:27 INFO - Finding installer, test and symbols from parent task. 19:23:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NsvvsqM1RUKHavBmufWjNg'}, attempt #1 19:23:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NsvvsqM1RUKHavBmufWjNg'}, attempt #1 19:23:28 INFO - Task dependencies: WYGk5NIrR0qAzKjoTlGHQQ 19:23:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ'}, attempt #1 19:23:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.tar.bz2 19:23:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.test_packages.json 19:23:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.crashreporter-symbols.zip 19:23:28 INFO - Running post-action listener: _resource_record_post_action 19:23:28 INFO - [mozharness: 2017-10-05 02:23:28.817838Z] Finished read-buildbot-config step (success) 19:23:28 INFO - [mozharness: 2017-10-05 02:23:28.818055Z] Running download-and-extract step. 19:23:28 INFO - Running pre-action listener: _resource_record_pre_action 19:23:28 INFO - Running main action method: download_and_extract 19:23:28 INFO - mkdir: /builds/slave/test/build/tests 19:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:28 INFO - trying https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.test_packages.json 19:23:28 INFO - Downloading https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:23:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:23:29 INFO - Downloaded 989 bytes. 19:23:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:23:29 INFO - Using the following test package requirements: 19:23:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:23:29 INFO - u'common': [u'target.common.tests.zip'], 19:23:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:23:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:23:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:23:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:23:29 INFO - u'mozbase': [u'target.common.tests.zip'], 19:23:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:23:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:23:29 INFO - u'web-platform': [u'target.common.tests.zip', 19:23:29 INFO - u'target.web-platform.tests.tar.gz'], 19:23:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:23:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 19:23:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.common.tests.zip 19:23:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:23:29 INFO - Fetch https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.common.tests.zip into memory 19:23:31 INFO - Content-Length response header: 55180909 19:23:31 INFO - Bytes received: 55180909 19:23:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.talos.tests.zip 19:23:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:23:42 INFO - Fetch https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.talos.tests.zip into memory 19:23:43 INFO - Content-Length response header: 14509224 19:23:43 INFO - Bytes received: 14509224 19:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:43 INFO - trying https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.tar.bz2 19:23:43 INFO - Downloading https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:23:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:23:46 INFO - Downloaded 55275142 bytes. 19:23:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.tar.bz2 19:23:46 INFO - mkdir: /builds/slave/test/properties 19:23:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:23:46 INFO - Writing to file /builds/slave/test/properties/build_url 19:23:46 INFO - Contents: 19:23:46 INFO - build_url:https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.tar.bz2 19:23:46 INFO - Running post-action listener: _resource_record_post_action 19:23:46 INFO - Running post-action listener: find_tests_for_verification 19:23:46 INFO - Running post-action listener: set_extra_try_arguments 19:23:46 INFO - [mozharness: 2017-10-05 02:23:46.457090Z] Finished download-and-extract step (success) 19:23:46 INFO - [mozharness: 2017-10-05 02:23:46.457307Z] Running populate-webroot step. 19:23:46 INFO - Running pre-action listener: _resource_record_pre_action 19:23:46 INFO - Running main action method: populate_webroot 19:23:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:23:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['tresize', 19:23:46 INFO - 'tcanvasmark']}, 19:23:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:23:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['dromaeo_css', 19:23:46 INFO - 'kraken']}, 19:23:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:23:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:23:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:23:46 INFO - 'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:23:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:23:46 INFO - 'tests': ['damp', 'tps']}, 19:23:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:23:46 INFO - 'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['damp', 'tps']}, 19:23:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:23:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['dromaeo_dom']}, 19:23:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:23:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['basic_compositor_video', 19:23:46 INFO - 'glvideo']}, 19:23:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:23:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:23:46 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:23:46 INFO - 'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['ts_paint_webext', 19:23:46 INFO - 'tp5o_webext']}, 19:23:46 INFO - 'other-e10s': {'tests': ['a11yr', 19:23:46 INFO - 'ts_paint', 19:23:46 INFO - 'tpaint', 19:23:46 INFO - 'sessionrestore', 19:23:46 INFO - 'sessionrestore_many_windows', 19:23:46 INFO - 'sessionrestore_no_auto_restore', 19:23:46 INFO - 'tabpaint']}, 19:23:46 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['a11yr', 19:23:46 INFO - 'ts_paint', 19:23:46 INFO - 'tpaint', 19:23:46 INFO - 'sessionrestore', 19:23:46 INFO - 'sessionrestore_many_windows', 19:23:46 INFO - 'sessionrestore_no_auto_restore', 19:23:46 INFO - 'tabpaint']}, 19:23:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 19:23:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 19:23:46 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['perf_reftest_singletons']}, 19:23:46 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['bloom_basic']}, 19:23:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:23:46 INFO - 'tsvgr_opacity', 19:23:46 INFO - 'tart', 19:23:46 INFO - 'tscrollx', 19:23:46 INFO - 'tsvg_static']}, 19:23:46 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['tsvgx', 19:23:46 INFO - 'tsvgr_opacity', 19:23:46 INFO - 'tart', 19:23:46 INFO - 'tscrollx', 19:23:46 INFO - 'tsvg_static']}, 19:23:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:23:46 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:23:46 INFO - 'talos_options': ['--disable-stylo'], 19:23:46 INFO - 'tests': ['tp5o']}, 19:23:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:23:46 INFO - 'talos_options': ['--mitmproxy', 19:23:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:23:46 INFO - '--firstNonBlankPaint'], 19:23:46 INFO - 'tests': ['tp6_google', 19:23:46 INFO - 'tp6_youtube', 19:23:46 INFO - 'tp6_amazon', 19:23:46 INFO - 'tp6_facebook']}, 19:23:46 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:23:46 INFO - 'talos_options': ['--disable-stylo', 19:23:46 INFO - '--mitmproxy', 19:23:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:23:46 INFO - '--firstNonBlankPaint'], 19:23:46 INFO - 'tests': ['tp6_google', 19:23:46 INFO - 'tp6_youtube', 19:23:46 INFO - 'tp6_amazon', 19:23:46 INFO - 'tp6_facebook']}, 19:23:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:23:46 INFO - 'talos_options': ['--stylo-threads=1', 19:23:46 INFO - '--mitmproxy', 19:23:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:23:46 INFO - '--firstNonBlankPaint'], 19:23:46 INFO - 'tests': ['tp6_google', 19:23:46 INFO - 'tp6_youtube', 19:23:46 INFO - 'tp6_amazon', 19:23:46 INFO - 'tp6_facebook']}, 19:23:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:23:46 INFO - 'talos_options': ['--xperf_path', 19:23:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:23:46 INFO - 'tests': ['tp5n']}, 19:23:46 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 19:23:46 INFO - 'talos_options': ['--disable-stylo', 19:23:46 INFO - '--xperf_path', 19:23:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:23:46 INFO - 'tests': ['tp5n']}}} 19:23:46 INFO - Running post-action listener: _resource_record_post_action 19:23:46 INFO - [mozharness: 2017-10-05 02:23:46.480596Z] Finished populate-webroot step (success) 19:23:46 INFO - [mozharness: 2017-10-05 02:23:46.480690Z] Running create-virtualenv step. 19:23:46 INFO - Running pre-action listener: _resource_record_pre_action 19:23:46 INFO - Running main action method: create_virtualenv 19:23:46 INFO - Creating virtualenv /builds/slave/test/build/venv 19:23:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:23:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 19:23:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:23:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:23:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:23:47 INFO - Installing distribute.............................................................................................................................................................................................done. 19:23:47 INFO - Installing pip...............done. 19:23:47 INFO - Return code: 0 19:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:23:47 INFO - Reading from file tmpfile_stdout 19:23:47 INFO - Output received: 19:23:47 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 19:23:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:23:47 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')]} 19:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:47 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')]} 19:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:47 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 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:23:47 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 19:23:47 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 19:23:47 INFO - Using env: {'DISPLAY': ':0', 19:23:47 INFO - 'HOME': '/home/cltbld', 19:23:47 INFO - 'LANG': 'en_US.UTF-8', 19:23:47 INFO - 'LANGUAGE': 'en_US:en', 19:23:47 INFO - 'LOGNAME': 'cltbld', 19:23:47 INFO - 'MAIL': '/var/mail/cltbld', 19:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:47 INFO - 'MOZ_NO_REMOTE': '1', 19:23:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:23:47 INFO - 'NO_EM_RESTART': '1', 19:23:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:47 INFO - 'PWD': '/builds/slave/test', 19:23:47 INFO - 'SHELL': '/bin/bash', 19:23:47 INFO - 'SHLVL': '1', 19:23:47 INFO - 'TERM': 'linux', 19:23:47 INFO - 'TMOUT': '86400', 19:23:47 INFO - 'USER': 'cltbld', 19:23:47 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:47 INFO - '_': '/tools/buildbot/bin/python'} 19:23:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 19:23:48 INFO - Downloading/unpacking pip>=1.5 19:23:48 INFO - Running setup.py egg_info for package pip 19:23:48 INFO - warning: no files found matching 'pip/cacert.pem' 19:23:48 INFO - warning: no files found matching '*.html' under directory 'docs' 19:23:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:23:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:23:48 INFO - Installing collected packages: pip 19:23:48 INFO - Found existing installation: pip 1.1 19:23:48 INFO - Uninstalling pip: 19:23:48 INFO - Successfully uninstalled pip 19:23:48 INFO - Running setup.py install for pip 19:23:48 INFO - warning: no files found matching 'pip/cacert.pem' 19:23:48 INFO - warning: no files found matching '*.html' under directory 'docs' 19:23:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:23:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:23:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 19:23:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 19:23:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 19:23:48 INFO - Successfully installed pip 19:23:48 INFO - Cleaning up... 19:23:48 INFO - Return code: 0 19:23:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:23:48 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')]} 19:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:48 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')]} 19:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:48 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 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:23:48 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 19:23:48 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 19:23:48 INFO - Using env: {'DISPLAY': ':0', 19:23:48 INFO - 'HOME': '/home/cltbld', 19:23:48 INFO - 'LANG': 'en_US.UTF-8', 19:23:48 INFO - 'LANGUAGE': 'en_US:en', 19:23:48 INFO - 'LOGNAME': 'cltbld', 19:23:48 INFO - 'MAIL': '/var/mail/cltbld', 19:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:48 INFO - 'MOZ_NO_REMOTE': '1', 19:23:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:23:48 INFO - 'NO_EM_RESTART': '1', 19:23:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:48 INFO - 'PWD': '/builds/slave/test', 19:23:48 INFO - 'SHELL': '/bin/bash', 19:23:48 INFO - 'SHLVL': '1', 19:23:48 INFO - 'TERM': 'linux', 19:23:48 INFO - 'TMOUT': '86400', 19:23:48 INFO - 'USER': 'cltbld', 19:23:48 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:48 INFO - '_': '/tools/buildbot/bin/python'} 19:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:48 INFO - Downloading/unpacking psutil>=3.1.1 19:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:23:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:23:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:23:50 INFO - Installing collected packages: psutil 19:23:50 INFO - Running setup.py install for psutil 19:23:50 INFO - building 'psutil._psutil_linux' extension 19:23:50 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 19:23:50 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 19:23:50 INFO - building 'psutil._psutil_posix' extension 19:23:50 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 19:23:50 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 19:23:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:23:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:23:50 INFO - Successfully installed psutil 19:23:50 INFO - Cleaning up... 19:23:50 INFO - Return code: 0 19:23:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:23:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:23:50 INFO - Using env: {'DISPLAY': ':0', 19:23:50 INFO - 'HOME': '/home/cltbld', 19:23:50 INFO - 'LANG': 'en_US.UTF-8', 19:23:50 INFO - 'LANGUAGE': 'en_US:en', 19:23:50 INFO - 'LOGNAME': 'cltbld', 19:23:50 INFO - 'MAIL': '/var/mail/cltbld', 19:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:50 INFO - 'MOZ_NO_REMOTE': '1', 19:23:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:23:50 INFO - 'NO_EM_RESTART': '1', 19:23:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:50 INFO - 'PWD': '/builds/slave/test', 19:23:50 INFO - 'SHELL': '/bin/bash', 19:23:50 INFO - 'SHLVL': '1', 19:23:50 INFO - 'TERM': 'linux', 19:23:50 INFO - 'TMOUT': '86400', 19:23:50 INFO - 'USER': 'cltbld', 19:23:50 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:50 INFO - '_': '/tools/buildbot/bin/python'} 19:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:23:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:23:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:23:52 INFO - Installing collected packages: mozsystemmonitor 19:23:52 INFO - Running setup.py install for mozsystemmonitor 19:23:52 INFO - Successfully installed mozsystemmonitor 19:23:52 INFO - Cleaning up... 19:23:52 INFO - Return code: 0 19:23:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:23:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:23:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:23:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:23:52 INFO - Using env: {'DISPLAY': ':0', 19:23:52 INFO - 'HOME': '/home/cltbld', 19:23:52 INFO - 'LANG': 'en_US.UTF-8', 19:23:52 INFO - 'LANGUAGE': 'en_US:en', 19:23:52 INFO - 'LOGNAME': 'cltbld', 19:23:52 INFO - 'MAIL': '/var/mail/cltbld', 19:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:52 INFO - 'MOZ_NO_REMOTE': '1', 19:23:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:23:52 INFO - 'NO_EM_RESTART': '1', 19:23:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:52 INFO - 'PWD': '/builds/slave/test', 19:23:52 INFO - 'SHELL': '/bin/bash', 19:23:52 INFO - 'SHLVL': '1', 19:23:52 INFO - 'TERM': 'linux', 19:23:52 INFO - 'TMOUT': '86400', 19:23:52 INFO - 'USER': 'cltbld', 19:23:52 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:52 INFO - '_': '/tools/buildbot/bin/python'} 19:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:52 INFO - Downloading/unpacking jsonschema==2.5.1 19:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:23:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:23:53 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 19:23:53 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 19:23:53 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 19:23:53 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 19:23:53 INFO - Downloading functools32-3.2.3-2.tar.gz 19:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:23:53 INFO - warning: no files found matching '*.txt' 19:23:53 INFO - no previously-included directories found matching 'build' 19:23:53 INFO - no previously-included directories found matching 'dist' 19:23:53 INFO - no previously-included directories found matching '.git*' 19:23:53 INFO - Installing collected packages: jsonschema, functools32 19:23:53 INFO - Running setup.py install for functools32 19:23:53 INFO - warning: no files found matching '*.txt' 19:23:53 INFO - no previously-included directories found matching 'build' 19:23:53 INFO - no previously-included directories found matching 'dist' 19:23:53 INFO - no previously-included directories found matching '.git*' 19:23:53 INFO - Successfully installed jsonschema functools32 19:23:53 INFO - Cleaning up... 19:23:53 INFO - Return code: 0 19:23:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:23: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')]} 19:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23: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')]} 19:23:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:53 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 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:23:53 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 19:23:53 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 19:23:53 INFO - Using env: {'DISPLAY': ':0', 19:23:53 INFO - 'HOME': '/home/cltbld', 19:23:53 INFO - 'LANG': 'en_US.UTF-8', 19:23:53 INFO - 'LANGUAGE': 'en_US:en', 19:23:53 INFO - 'LOGNAME': 'cltbld', 19:23:53 INFO - 'MAIL': '/var/mail/cltbld', 19:23:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:53 INFO - 'MOZ_NO_REMOTE': '1', 19:23:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:23:53 INFO - 'NO_EM_RESTART': '1', 19:23:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:23:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:53 INFO - 'PWD': '/builds/slave/test', 19:23:53 INFO - 'SHELL': '/bin/bash', 19:23:53 INFO - 'SHLVL': '1', 19:23:53 INFO - 'TERM': 'linux', 19:23:53 INFO - 'TMOUT': '86400', 19:23:53 INFO - 'USER': 'cltbld', 19:23:53 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:53 INFO - '_': '/tools/buildbot/bin/python'} 19:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:23:54 INFO - Cleaning up... 19:23:54 INFO - Return code: 0 19:23:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:23:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:23:54 INFO - Using env: {'DISPLAY': ':0', 19:23:54 INFO - 'HOME': '/home/cltbld', 19:23:54 INFO - 'LANG': 'en_US.UTF-8', 19:23:54 INFO - 'LANGUAGE': 'en_US:en', 19:23:54 INFO - 'LOGNAME': 'cltbld', 19:23:54 INFO - 'MAIL': '/var/mail/cltbld', 19:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:54 INFO - 'MOZ_NO_REMOTE': '1', 19:23:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:23:54 INFO - 'NO_EM_RESTART': '1', 19:23:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:54 INFO - 'PWD': '/builds/slave/test', 19:23:54 INFO - 'SHELL': '/bin/bash', 19:23:54 INFO - 'SHLVL': '1', 19:23:54 INFO - 'TERM': 'linux', 19:23:54 INFO - 'TMOUT': '86400', 19:23:54 INFO - 'USER': 'cltbld', 19:23:54 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:54 INFO - '_': '/tools/buildbot/bin/python'} 19:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:54 INFO - Downloading/unpacking blobuploader==1.2.4 19:23:54 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 19:23:54 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 19:23:54 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 19:23:54 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 19:23:55 INFO - Downloading blobuploader-1.2.4.tar.gz 19:23:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:23:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:23:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:55 INFO - Downloading docopt-0.6.1.tar.gz 19:23:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:23:55 INFO - Installing collected packages: blobuploader, requests, docopt 19:23:55 INFO - Running setup.py install for blobuploader 19:23:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:23:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:23:56 INFO - Running setup.py install for requests 19:23:56 INFO - Running setup.py install for docopt 19:23:56 INFO - Successfully installed blobuploader requests docopt 19:23:56 INFO - Cleaning up... 19:23:56 INFO - Return code: 0 19:23:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23: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')]} 19:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23: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')]} 19:23:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:56 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 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:23:56 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 19:23:56 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 19:23:56 INFO - Using env: {'DISPLAY': ':0', 19:23:56 INFO - 'HOME': '/home/cltbld', 19:23:56 INFO - 'LANG': 'en_US.UTF-8', 19:23:56 INFO - 'LANGUAGE': 'en_US:en', 19:23:56 INFO - 'LOGNAME': 'cltbld', 19:23:56 INFO - 'MAIL': '/var/mail/cltbld', 19:23:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:56 INFO - 'MOZ_NO_REMOTE': '1', 19:23:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:23:56 INFO - 'NO_EM_RESTART': '1', 19:23:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:23:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:56 INFO - 'PWD': '/builds/slave/test', 19:23:56 INFO - 'SHELL': '/bin/bash', 19:23:56 INFO - 'SHLVL': '1', 19:23:56 INFO - 'TERM': 'linux', 19:23:56 INFO - 'TMOUT': '86400', 19:23:56 INFO - 'USER': 'cltbld', 19:23:56 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:23:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:56 INFO - '_': '/tools/buildbot/bin/python'} 19:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:23:57 INFO - Running setup.py (path:/tmp/pip-O5VsBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:23:57 INFO - Running setup.py (path:/tmp/pip-WXFZ9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:23:57 INFO - Running setup.py (path:/tmp/pip-tlRUQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:23:57 INFO - Running setup.py (path:/tmp/pip-RWGadl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:23:57 INFO - Running setup.py (path:/tmp/pip-ZF7xmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:23:57 INFO - Running setup.py (path:/tmp/pip-YDMemY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:23:57 INFO - Running setup.py (path:/tmp/pip-lamE3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:23:57 INFO - Running setup.py (path:/tmp/pip-22EMNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:23:57 INFO - Running setup.py (path:/tmp/pip-xoNtiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:23:57 INFO - Running setup.py (path:/tmp/pip-4E2Tdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:23:57 INFO - Running setup.py (path:/tmp/pip-sGB17I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:23:57 INFO - Running setup.py (path:/tmp/pip-I2j4nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:23:58 INFO - Running setup.py (path:/tmp/pip-ifWvcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:23:58 INFO - Running setup.py (path:/tmp/pip-BnUCWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:58 INFO - Running setup.py (path:/tmp/pip-gBiKNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:23:58 INFO - Running setup.py (path:/tmp/pip-NblXsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:23:58 INFO - Running setup.py (path:/tmp/pip-L1Qdlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:23:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:23:58 INFO - Running setup.py install for manifestparser 19:23:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:23:58 INFO - Running setup.py install for mozcrash 19:23:58 INFO - Running setup.py install for mozdebug 19:23:58 INFO - Running setup.py install for mozdevice 19:23:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:23:58 INFO - Running setup.py install for mozfile 19:23:58 INFO - Running setup.py install for mozhttpd 19:23:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:23:58 INFO - Running setup.py install for mozinfo 19:23:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Running setup.py install for mozInstall 19:23:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Running setup.py install for mozleak 19:23:59 INFO - Running setup.py install for mozlog 19:23:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Running setup.py install for moznetwork 19:23:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Running setup.py install for mozprocess 19:23:59 INFO - Running setup.py install for mozprofile 19:23:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Running setup.py install for mozrunner 19:23:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:23:59 INFO - Running setup.py install for mozscreenshot 19:23:59 INFO - Running setup.py install for moztest 19:23:59 INFO - Running setup.py install for mozversion 19:24:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:24:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:24:00 INFO - Cleaning up... 19:24:00 INFO - Return code: 0 19:24:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:00 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 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:24:00 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 19:24:00 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 19:24:00 INFO - Using env: {'DISPLAY': ':0', 19:24:00 INFO - 'HOME': '/home/cltbld', 19:24:00 INFO - 'LANG': 'en_US.UTF-8', 19:24:00 INFO - 'LANGUAGE': 'en_US:en', 19:24:00 INFO - 'LOGNAME': 'cltbld', 19:24:00 INFO - 'MAIL': '/var/mail/cltbld', 19:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:00 INFO - 'MOZ_NO_REMOTE': '1', 19:24:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:00 INFO - 'NO_EM_RESTART': '1', 19:24:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:00 INFO - 'PWD': '/builds/slave/test', 19:24:00 INFO - 'SHELL': '/bin/bash', 19:24:00 INFO - 'SHLVL': '1', 19:24:00 INFO - 'TERM': 'linux', 19:24:00 INFO - 'TMOUT': '86400', 19:24:00 INFO - 'USER': 'cltbld', 19:24:00 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:00 INFO - '_': '/tools/buildbot/bin/python'} 19:24:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:24:00 INFO - Running setup.py (path:/tmp/pip-85Mbg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:24:00 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)) 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:24:00 INFO - Running setup.py (path:/tmp/pip-GWRHyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:24:00 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)) 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:24:00 INFO - Running setup.py (path:/tmp/pip-Nt6h8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:24:00 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)) 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:24:00 INFO - Running setup.py (path:/tmp/pip-tBWsNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:24:00 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)) 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:24:00 INFO - Running setup.py (path:/tmp/pip-feJupX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:24:00 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)) 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:24:00 INFO - Running setup.py (path:/tmp/pip-t0n7aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:24:00 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)) 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:24:00 INFO - Running setup.py (path:/tmp/pip-ESntWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:24:00 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)) 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:24:00 INFO - Running setup.py (path:/tmp/pip-mFaFDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:24:00 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)) 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:24:00 INFO - Running setup.py (path:/tmp/pip-qAcTdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:24:00 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)) 19:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:24:00 INFO - Running setup.py (path:/tmp/pip-8YpR3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:24:01 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)) 19:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:24:01 INFO - Running setup.py (path:/tmp/pip-aAiwZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:24:01 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)) 19:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:24:01 INFO - Running setup.py (path:/tmp/pip-3MLEYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:24:01 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)) 19:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:24:01 INFO - Running setup.py (path:/tmp/pip-xT5rUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:24:01 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)) 19:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:24:01 INFO - Running setup.py (path:/tmp/pip-AoBy5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:24:01 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)) 19:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:24:01 INFO - Running setup.py (path:/tmp/pip-ppyZku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:24:01 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)) 19:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:24:01 INFO - Running setup.py (path:/tmp/pip-74Eq0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:24:01 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)) 19:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:24:01 INFO - Running setup.py (path:/tmp/pip-n9DCB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:24:01 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)) 19:24:01 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)) 19:24:01 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)) 19:24:01 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)) 19:24:01 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)) 19:24:01 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)) 19:24:01 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)) 19:24:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:02 INFO - Downloading blessings-1.6.tar.gz 19:24:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:24:02 INFO - Installing collected packages: blessings 19:24:02 INFO - Running setup.py install for blessings 19:24:02 INFO - Successfully installed blessings 19:24:02 INFO - Cleaning up... 19:24:03 INFO - Return code: 0 19:24:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:24:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:24:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:24:03 INFO - Reading from file tmpfile_stdout 19:24:03 INFO - Current package versions: 19:24:03 INFO - argparse == 1.2.1 19:24:03 INFO - blessings == 1.6 19:24:03 INFO - blobuploader == 1.2.4 19:24:03 INFO - docopt == 0.6.1 19:24:03 INFO - functools32 == 3.2.3-2 19:24:03 INFO - jsonschema == 2.5.1 19:24:03 INFO - manifestparser == 1.1 19:24:03 INFO - mozInstall == 1.13 19:24:03 INFO - mozcrash == 1.0 19:24:03 INFO - mozdebug == 0.1 19:24:03 INFO - mozdevice == 0.51 19:24:03 INFO - mozfile == 1.2 19:24:03 INFO - mozhttpd == 0.7 19:24:03 INFO - mozinfo == 0.10 19:24:03 INFO - mozleak == 0.1 19:24:03 INFO - mozlog == 3.5 19:24:03 INFO - moznetwork == 0.27 19:24:03 INFO - mozprocess == 0.25 19:24:03 INFO - mozprofile == 0.28 19:24:03 INFO - mozrunner == 6.13 19:24:03 INFO - mozscreenshot == 0.1 19:24:03 INFO - mozsystemmonitor == 0.3 19:24:03 INFO - moztest == 0.8 19:24:03 INFO - mozversion == 1.4 19:24:03 INFO - psutil == 3.1.1 19:24:03 INFO - requests == 1.2.3 19:24:03 INFO - wsgiref == 0.1.2 19:24:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:24:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:03 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 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:24:03 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 19:24:03 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 19:24:03 INFO - Using env: {'DISPLAY': ':0', 19:24:03 INFO - 'HOME': '/home/cltbld', 19:24:03 INFO - 'LANG': 'en_US.UTF-8', 19:24:03 INFO - 'LANGUAGE': 'en_US:en', 19:24:03 INFO - 'LOGNAME': 'cltbld', 19:24:03 INFO - 'MAIL': '/var/mail/cltbld', 19:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:03 INFO - 'MOZ_NO_REMOTE': '1', 19:24:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:03 INFO - 'NO_EM_RESTART': '1', 19:24:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:03 INFO - 'PWD': '/builds/slave/test', 19:24:03 INFO - 'SHELL': '/bin/bash', 19:24:03 INFO - 'SHLVL': '1', 19:24:03 INFO - 'TERM': 'linux', 19:24:03 INFO - 'TMOUT': '86400', 19:24:03 INFO - 'USER': 'cltbld', 19:24:03 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:03 INFO - '_': '/tools/buildbot/bin/python'} 19:24:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:03 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)) 19:24:03 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)) 19:24:03 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)) 19:24:03 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)) 19:24:03 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)) 19:24:03 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)) 19:24:03 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)) 19:24:03 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)) 19:24:03 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)) 19:24:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:24: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 19:24: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 19:24: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 19:24: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 19:24:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 19:24:04 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)) 19:24:04 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)) 19:24:04 INFO - Installing collected packages: simplejson 19:24:04 INFO - Running setup.py install for simplejson 19:24:04 INFO - building 'simplejson._speedups' extension 19:24:04 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 19:24:05 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 19:24:05 INFO - Successfully installed simplejson 19:24:05 INFO - Cleaning up... 19:24:05 INFO - Return code: 0 19:24:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:24:05 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')]} 19:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:05 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')]} 19:24:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:05 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 0x23b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2579a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', '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 19:24:05 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 19:24:05 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 19:24:05 INFO - Using env: {'DISPLAY': ':0', 19:24:05 INFO - 'HOME': '/home/cltbld', 19:24:05 INFO - 'LANG': 'en_US.UTF-8', 19:24:05 INFO - 'LANGUAGE': 'en_US:en', 19:24:05 INFO - 'LOGNAME': 'cltbld', 19:24:05 INFO - 'MAIL': '/var/mail/cltbld', 19:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:05 INFO - 'MOZ_NO_REMOTE': '1', 19:24:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:05 INFO - 'NO_EM_RESTART': '1', 19:24:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:05 INFO - 'PWD': '/builds/slave/test', 19:24:05 INFO - 'SHELL': '/bin/bash', 19:24:05 INFO - 'SHLVL': '1', 19:24:05 INFO - 'TERM': 'linux', 19:24:05 INFO - 'TMOUT': '86400', 19:24:05 INFO - 'USER': 'cltbld', 19:24:05 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:05 INFO - '_': '/tools/buildbot/bin/python'} 19:24:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:24:05 INFO - Cleaning up... 19:24:05 INFO - Return code: 0 19:24:05 INFO - Running post-action listener: _resource_record_post_action 19:24:05 INFO - Running post-action listener: _start_resource_monitoring 19:24:06 INFO - Starting resource monitoring. 19:24:06 INFO - [mozharness: 2017-10-05 02:24:06.011306Z] Finished create-virtualenv step (success) 19:24:06 INFO - [mozharness: 2017-10-05 02:24:06.011765Z] Running install step. 19:24:06 INFO - Running pre-action listener: _resource_record_pre_action 19:24:06 INFO - Running main action method: install 19:24:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:24:06 INFO - Reading from file tmpfile_stdout 19:24:06 INFO - Detecting whether we're running mozinstall >=1.0... 19:24:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:24:06 INFO - Reading from file tmpfile_stdout 19:24:06 INFO - Output received: 19:24:06 INFO - Usage: mozinstall [options] installer 19:24:06 INFO - Options: 19:24:06 INFO - -h, --help show this help message and exit 19:24:06 INFO - -d DEST, --destination=DEST 19:24:06 INFO - Directory to install application into. [default: 19:24:06 INFO - "/builds/slave/test"] 19:24:06 INFO - --app=APP Application being installed. [default: firefox] 19:24:06 INFO - mkdir: /builds/slave/test/build/application 19:24:06 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'] 19:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:24:21 INFO - Reading from file tmpfile_stdout 19:24:21 INFO - Output received: 19:24:21 INFO - /builds/slave/test/build/application/firefox/firefox 19:24:21 INFO - Running post-action listener: _resource_record_post_action 19:24:21 INFO - [mozharness: 2017-10-05 02:24:21.226086Z] Finished install step (success) 19:24:21 INFO - [mozharness: 2017-10-05 02:24:21.226334Z] Running setup-mitmproxy step. 19:24:21 INFO - Running pre-action listener: _resource_record_pre_action 19:24:21 INFO - Running main action method: setup_mitmproxy 19:24:21 INFO - Skipping: mitmproxy is not required 19:24:21 INFO - Running post-action listener: _resource_record_post_action 19:24:21 INFO - [mozharness: 2017-10-05 02:24:21.227436Z] Finished setup-mitmproxy step (success) 19:24:21 INFO - [mozharness: 2017-10-05 02:24:21.227658Z] Running run-tests step. 19:24:21 INFO - Running pre-action listener: _resource_record_pre_action 19:24:21 INFO - Running pre-action listener: _set_gcov_prefix 19:24:21 INFO - Running main action method: run_tests 19:24:21 WARNING - Try message not found. 19:24:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:24:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:24:21 INFO - Python 2.7.3 19:24:21 INFO - Return code: 0 19:24:21 INFO - grabbing minidump binary from tooltool 19:24:21 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')]} 19:24:21 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 0x2720090>, '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 0x2720d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271af90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:24:21 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 19:24:21 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 19:24:21 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 19:24:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:24:21 INFO - Return code: 0 19:24:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:24:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:24:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:24:21 INFO - ENV: RUST_BACKTRACE is now full 19:24:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:24:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:24:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 19:24:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 19:24:21 INFO - Using env: {'DISPLAY': ':0', 19:24:21 INFO - 'HOME': '/home/cltbld', 19:24:21 INFO - 'LANG': 'en_US.UTF-8', 19:24:21 INFO - 'LANGUAGE': 'en_US:en', 19:24:21 INFO - 'LOGNAME': 'cltbld', 19:24:21 INFO - 'MAIL': '/var/mail/cltbld', 19:24:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:24:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:24:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:21 INFO - 'MOZ_NO_REMOTE': '1', 19:24:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:24:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:21 INFO - 'NO_EM_RESTART': '1', 19:24:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:21 INFO - 'PWD': '/builds/slave/test', 19:24:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:24:21 INFO - 'RUST_BACKTRACE': 'full', 19:24:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:24:21 INFO - 'SHELL': '/bin/bash', 19:24:21 INFO - 'SHLVL': '1', 19:24:21 INFO - 'TERM': 'linux', 19:24:21 INFO - 'TMOUT': '86400', 19:24:21 INFO - 'USER': 'cltbld', 19:24:21 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:24:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:21 INFO - '_': '/tools/buildbot/bin/python'} 19:24:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 19:24:21 INFO - mozversion application_buildid: 20171005010120 19:24:21 INFO - mozversion application_changeset: 3d3e597d3b96eca3163c70ff0d2a263e2d7e9cf5 19:24:21 INFO - mozversion application_display_name: Firefox 19:24:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:24:21 INFO - mozversion application_name: Firefox 19:24:21 INFO - mozversion application_remotingname: firefox 19:24:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:24:21 INFO - mozversion application_vendor: Mozilla 19:24:21 INFO - mozversion application_version: 57.0 19:24:21 INFO - mozversion platform_buildid: 20171005010120 19:24:21 INFO - mozversion platform_changeset: 3d3e597d3b96eca3163c70ff0d2a263e2d7e9cf5 19:24:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:24:21 INFO - mozversion platform_version: 57.0 19:24:21 INFO - using testdate: 1507170261 19:24:21 INFO - actual date: 1507170261 19:24:21 INFO - starting webserver on 'localhost:60452' 19:24:21 INFO - SUITE-START | Running 2 tests 19:24:21 INFO - TEST-START | dromaeo_css 19:24:21 INFO - Initialising browser for dromaeo_css test... 19:24:21 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60452/getInfo.html -profile /tmp/tmp_xmIXM/profile 19:24:21 INFO - TEST-INFO | started process 9487 (/builds/slave/test/build/application/firefox/firefox http://localhost:60452/getInfo.html) 19:24:26 INFO - TEST-INFO | 9487: exit 0 19:24:26 INFO - Browser initialized. 19:24:26 INFO - Running cycle 1/1 for dromaeo_css test... 19:24:26 INFO - Using env: {'DISPLAY': ':0', 19:24:26 INFO - 'HOME': '/home/cltbld', 19:24:26 INFO - 'JSGC_DISABLE_POISONING': '1', 19:24:26 INFO - 'LANG': 'en_US.UTF-8', 19:24:26 INFO - 'LANGUAGE': 'en_US:en', 19:24:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:24:26 INFO - 'LOGNAME': 'cltbld', 19:24:26 INFO - 'MAIL': '/var/mail/cltbld', 19:24:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:24:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:24:26 INFO - 'MOZ_CRASHREPORTER': '1', 19:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:24:26 INFO - 'MOZ_NO_REMOTE': '1', 19:24:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:24:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:26 INFO - 'NO_EM_RESTART': '1', 19:24:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:26 INFO - 'PWD': '/builds/slave/test', 19:24:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:24:26 INFO - 'RUST_BACKTRACE': 'full', 19:24:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:24:26 INFO - 'SHELL': '/bin/bash', 19:24:26 INFO - 'SHLVL': '1', 19:24:26 INFO - 'TERM': 'linux', 19:24:26 INFO - 'TMOUT': '86400', 19:24:26 INFO - 'USER': 'cltbld', 19:24:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:26 INFO - '_': '/tools/buildbot/bin/python'} 19:24:26 INFO - TEST-INFO | started process 9727 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_xmIXM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:24:27 INFO - PID 9727 | 19:24:27 INFO - PID 9727 | (/builds/slave/test/build/application/firefox/firefox:9776): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:24:27 INFO - PID 9727 | 19:24:37 INFO - PID 9727 | RSS: Main: 141213696 19:24:37 INFO - PID 9727 | 19:27:35 INFO - PID 9727 | Cycle 1(1): loaded http://localhost:60452/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60452/tests/dromaeo/cssquery-ext.html) 19:27:36 INFO - PID 9727 | RSS: Main: 145260544 19:27:36 INFO - PID 9727 | 19:30:38 INFO - PID 9727 | Cycle 1(1): loaded http://localhost:60452/tests/dromaeo/cssquery-ext.html (next: http://localhost:60452/tests/dromaeo/cssquery-jquery.html) 19:30:38 INFO - PID 9727 | RSS: Main: 143560704 19:30:38 INFO - PID 9727 | 19:33:40 INFO - PID 9727 | Cycle 1(1): loaded http://localhost:60452/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60452/tests/dromaeo/cssquery-mootools.html) 19:33:40 INFO - PID 9727 | RSS: Main: 145002496 19:33:40 INFO - PID 9727 | 19:36:42 INFO - PID 9727 | Cycle 1(1): loaded http://localhost:60452/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60452/tests/dromaeo/cssquery-prototype.html) 19:36:42 INFO - PID 9727 | RSS: Main: 145043456 19:36:42 INFO - PID 9727 | 19:39:45 INFO - PID 9727 | Cycle 1(1): loaded http://localhost:60452/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60452/tests/dromaeo/cssquery-yui.html) 19:39:46 INFO - PID 9727 | RSS: Main: 144408576 19:39:46 INFO - PID 9727 | 19:42:47 INFO - PID 9727 | Cycle 1(1): loaded http://localhost:60452/tests/dromaeo/cssquery-yui.html (next: http://localhost:60452/tests/dromaeo/cssquery-dojo.html) 19:42:48 INFO - PID 9727 | RSS: Main: 149159936 19:42:48 INFO - PID 9727 | 19:42:48 INFO - PID 9727 | __start_tp_report 19:42:48 INFO - PID 9727 | _x_x_mozilla_page_load 19:42:48 INFO - PID 9727 | _x_x_mozilla_page_load_details 19:42:48 INFO - PID 9727 | |i|pagename|runs| 19:42:48 INFO - PID 9727 | |0;dojo.html;3993.740406873553;3994.6604538614215;4010.1177740897006;4015.5783642717533;4028.7582745035293;6432.967835160817;6465.127207826942;6502.479801615871;6558.081868538408;6575.802725918218;6473.741050357992;6504.47964162867;6569.704363303663;6592.274849766533;6599;5762.82711518653;5823.767049318033;5883.676397798119;5884.117382392633;5900.262467191601;5894.233749612566;5900.911486327687;5900.940990590082;5924.466797988181;5946.70266486674;5910.46805787479;5934.792282270121;5953.077273022688;5974.641521508702;6006;5905.379935106795;5919.408059194089;5934.910976335358;5975.282966044081;5988.19159413478;5813.82558523245;5856.180134781134;5911.083782013752;5966.194563733884;6010.188624535675;6491.253505846841;6558.475321974231;6560.147180866456;6563.638999855006;6593.6703164841565;7049;7062.823429414254;7145.7141714331365;7150.678219480136;7157.677904494258;7070.29297070294;7124.857502849965;7160.319769621878;7212.098487689039;7227.277272272783;6664.633445160514;6691.866162676768;6703.028060931138;6704;6706.966465167643;7276.05411296528;7277.781666550012;7292.598907060056;7365.373943214887;7375.741849035258;6994.300569942965;7029.92970070303;7095.467839912028;7111.573305601577;7112.324329188718;7141.214466408691;7187.712491500386;7193.172785129777;7198.856022879618;7210.242924492853;7170.820729481805;7179.174394944648;7199.1001124859395;7265.818354541178;7280.162781280221;7217.097862767154;7227.27727227273;7313.268673132644;7313.780586582411;7314.049173607397;5277.419483856773;5326.121190003606;5385.299911011543;5385.407605163429;5468.0977638689965;4908.533689299511;4920.4833492483485;4984.526469985346;4998.600111991032;5021.347224860745;6604.174478190226;6676.165479315086;6764.966175169093;6774.119364482585;6779.49153813466;6956.339147780953;7063.046488723957;7179.964100179465;7200.099987501562;7293.489455738048;6653.733850646016;6724.159480064992;6729.663516824139;6747.898781518281;6748.055272261888;6086.908696369558;6150.169727086875;6153.938460615336;6172.69136543171;6179.196704428396;7235.6020418876415;7306.5981371024045;7358.484906056632;7364.484486085923;7386.113666359964;7102.289771022856;7107.431405487652;7108;7108.964455177691;7116.786496405116;7387.7783666490095;7393.667284972082;7405.62971851416;7471.439642026761;7499.212582678741;6992.3357281058215;6999.405050570758;7051.365377116084;7088.716451341888;7089.113860767404;7119.288071192839;7120.181179164462;7206.099237595301;7207.171175314906;7226.963865180641;6809.216940051958;6856.280090590416;6883.139607549057;6887.104676392037;6951.27011663768;6988.9301106990315;7010;7010.614416207158;7024.367806897404;7027.22700502934;6908.827279318058;6928.4803639726215;6985.161780586159;7015.0529678494795;7017.824554386181;6968.163820341591;7071.504994650325;7125.5368401053765;7161.104861892263;7161.176464706626;6727.798166055026;6760.93239067603;6784.21981472137;6800.251972282946;6863.588184708933 19:42:48 INFO - PID 9727 | |1;ext.html;970.4613939263705;993.1806259835637;996.5515518016892;1034.7051090439227;1039.4387031003264;46008.07983840321;46769.29846052312;46776.2983555247;46778.29832552506;46888.53111468893;43734.56265437345;43880.56119438804;43971.99999999992;44050.559494405046;44123.77938110305;8113.229243221899;8227.95886020569;8247.958760206191;8269.462484938489;8272.090070092285;8057.435979481439;8143.796405089861;8163.346932245436;8169.6732130715;8172.6322315495645;8777.42946708465;9063.50150741709;9064.138906803875;9065.04816994216;9097.454152750823;8960.775980600472;9008.549572521348;9019.684311049115;9026.593803278869;9035.277177825757;8788.956055219747;8934.955325223333;8951.910480895172;8954.19412252894;8964.68623598171;41249.1750164995;41450;41460.58539414628;41589.376159357635;41733.1653366931;3762.830672619739;3790.469334293201;3795.203007368456;3803.2773772983046;3820.3887378019385;34247.6575234247;34365.828170858986;34396.82801586001;34535.6546434535;34736.6526334736;5612.971935140339;5630.09918413056;5639.069553523664;5681.318241810968;5749.367569567345;228342;229343;230031;230305;230388;53646;54398.45601543934;54837.451625484035;55216.44783552193;55280.723596382566;58515.41484585185;58559.414405856245;58656.41343586509;58722.70638646865;58724;17613;17721.11394430299;17733.46799596014;17787.644247114986;17877.821221787875;14561.344739486749;14573.562793116223;14585.635359115895;14603.707925841423;14609.707805843824;15477.84522154764;15537.223138843012;15705;15755.684886302439;15854.286557104959;5977.445864075372;6007.3992600739575;6058.363871793486;6098.75604975796;6153.815385538441;1114.7714718482691;1118.4128332625437;1129.316763358173;1130.3556972525746;1135.5684839760838;27078.864605676845;27203.319917001838;27220;27444.45111097807;27498.862505687342;1231.4150778380376;1238.7708273969345;1239.0768877186554;1245.8131280307846;1260.602910083321;14113.650555763707;14272.429102835771;14289.499867504588;14357.497487587885;14625.853741462663;310436;311295;312135;313470;316579;53214.7339263309;53893;60639.39360606426;61722;62319;1554.9611259718597;1573.2684301799488;1588.4519840654955;1590.0936466214148;1612.1616759285107;31800.204994875312;31828;32582.83708581442;32749.345013100083;32789.508157378135;43201.78399107984;43323.783381082896;43355.566444335156;43358;43372.13255734931;250646;250948;253881;254743;256061;38373.808130959165;38380.616193837704;38418;38721.4191787129;38726.41910371403;48878.26682599693;49823.501764981884;50654.74672626761;50793.746031269606;50795.74602126966;36102.27795444129;36437.90686279416;36606.63393366032;36711;37096;36218.818905905304;36357.27285454223;36551;36729.63270367262;37149.814250928575;42021;42145.36781948335;42479.7876010618;42515.36226956536;42872.14255714806;34574;34877.302453951284;35017.474737878445;35225.47161792626;35252.82373588115;26865.597016045165;26903.327416814736;26958.865205673847;26989;27100.186994389413 19:42:48 INFO - PID 9727 | |2;jquery.html;616.5423984591404;621.1986537366796;634.3212762344293;635.980920572383;637.3021541412154;23939.162129325476;24463.510729785437;24567.63148552773;24632.753672463292;25987;25893.611595826078;26810.731892681066;26903.192904212858;26986.325341866464;27061.45877082457;6988.755393561227;7176.533525320863;7186.209516953131;7188;7191.460640451961;6694.631795251259;6954.478414118961;6964.895526567106;7002.614856182908;7104.857902841966;6936.098307220054;6959.30406959305;6961.477889158308;6962.756303529378;6963.721451141948;6920.75777347793;6947.4789390795895;6954.721811127549;6957.582545047263;6963.094797676295;6474.935250647527;6504.577202481823;6508.967455162741;6524.412802847766;6527.93472065278;31380.686193138004;31557.368852623047;32734;32903.17742056444;32907.50638740421;2262.3778460923377;2288.4622113803243;2293.8050265727434;2297.127091705158;2297.24191016964;19823.801761982482;20444.977751112387;20453.28413505535;20471.4882127948;20507.077181526718;2574.7039090504645;2604.8046396520335;2686.892524299026;2716.307341627872;2729.849858257795;138323.30838345844;144234;155746;161379.19310403374;161517;16668.333266669197;17656.646867062587;17736.645267094846;17872.106394680213;18042.548936276697;27863.16410507688;27999.160025199275;28112.578311325346;28195.57706634402;28359.858200708866;11432.827762725055;11466.763981023787;11497.885021149848;11807.645770626896;11840.703982400337;10629.46852657364;10641.255112142077;10677.199210059276;10799;10836.133109351233;10020;10059.597616095274;10243.282970192015;10354.53404596795;10723.410212438392;2926.1813333599857;3015;3017.260771111092;3022.863971121305;3142.261568531393;1042.3485321673954;1043.337480699761;1056.4559251985233;1060.4432672846663;1079.433297518793;21844.344669659935;22325.10699571999;22466.438339041655;22487.66268505974;22562.63092375277;1122.6968718445974;1129.9661010169707;1155.8555180602425;1158.1082566423806;1164.947577359021;12049.578264760692;12394.938025309815;12612;12652.87347126517;12735.617931462071;619263;634225;634271;646643;652887;124387;135546.32226839;138847;139981;141841.29079354744;1181.3679681370425;1237.1649136832602;1274.286399616218;1276.5276847566274;1325.1121748428577;23451.88274058619;24069.27792166238;24146.154884578955;24152;24296.635550466402;60166;61299;61410.38589614225;62218;63755;78601.60699196467;78611;78882;78922.6053869727;79575.20424795678;17125.74311385355;17318.30726770915;17363.739543907104;17406.216720247874;17417.129143543025;25379.873100634377;25624.103156389436;25807.870960645076;25928.8703556481;26553.203403897915;19861.702074469184;20019.799802002377;20046.09792559309;20158.29445969384;20205.292814751414;18852.24591016344;18888.33890813815;19135.71296430527;19213.903930480257;19418.126184322024;21250.681239781108;21304.254351097636;21553.461163471038;21609.243676471247;21920.123195072658;16563.83436165623;16744;16870.409535666193;16889.746653799957;16891.986480811192;20891.712123176167;20956.58086838285;20971.89514052481;20981.685274721196;21168.78831211668 19:42:48 INFO - PID 9727 | |3;mootools.html;1408.5844675820636;1478.6746915678548;1480.9555713328605;1490.403838464614;1495.8055452791136;17285.654286914254;17993.100344982762;18041.278348866046;18082.99999999998;18103.547411314725;17540.473785786446;17666.205020774065;17673.204705788237;17705.203265853037;17714.734278985794;2877.8273303601745;2913.5192693205595;2970.3168271297614;2974.271303530638;2976.9702302977016;2920.933859141409;2953.616029916108;2954.5125054366003;2958.4231074940394;2968.5398763191633;2848.672402673688;2947.3221159133413;2973.0189037617642;2974.643042834852;2974.732274095331;2940.882364705409;2996.745276651476;3009.608750862385;3009.8946536871103;3055.5569442430724;2947.056941778632;2953.2469220348676;2964.5256758918467;2965.258685328668;2965.4662160811044;17116.572085697833;17403.1298435079;17461.82538174615;17489.125543722763;17517.912410437868;8363.916360836374;8568.143185681382;8609.698660546914;8631.050584435707;8632.093630168805;5430.8913821723345;5672.4611161939965;5742.1338065129;5766.826995190151;5773.364929857712;2187.125149940021;2232.8548644338066;2243.6298010828195;2288.2448791898632;2294.804941579968;31930.680693193004;31972.84013579917;32260.677393226237;32307.6769232307;32344.83827580847;6851.657417129124;6877.552959057645;7017.122859642544;7085.078939737904;7237.8552428951125;20657.89671051635;20702.585948281252;20805.89597052005;20807.68788468174;20865.68701469479;8149.103598604149;8443.57782110892;8448.07071222165;8456.11210822867;8457.873131903027;7633.122190948001;7678.924950506934;7717.151113377524;7726.961365193138;7740.845183096307;5834.679092649945;6084.543659225487;6095.939040609625;6100.816975490742;6126;1564.311702850742;1596.3135851583932;1660.518449649588;1698.3206717313174;1739.20866005967;951.0727041134838;960.9039096090335;964.3538828984597;964.7298756348234;968.0754879090451;9609.519524023772;9629;9644.228461723045;9706.417614943126;9739.415635061778;1079.1097344690504;1086.435053772034;1090.231386872253;1097.3251450357973;1106.2201148190507;5071.112555302807;5624.268845050199;5836;5893.823185304448;6005.879882402415;25952;26809.329766755985;26967;26985.1904442867;27008.32479188036;25182.11862584801;26176.8691156543;26193.083242086435;26530.46939061208;26740.59889101665;11393.550386666906;11806.822897656542;11910.940445297718;11921.940390298167;12038.759224815454;8996.775080623036;9006.459612423276;9011.504367259864;9121.45271283725;9160.908390916005;21303.041363139007;21895.12419503202;22232.666510002688;22409.663855042512;22787;22850.657240141078;22899.19852805144;23282;23312.950917209106;23442.531149377257;13007.674808129872;13203.471861125447;13204.933975330061;13210.867891320964;13212.537561185696;16218;16223.188840557688;16333.591660208589;16405.17974101314;16426.507204783877;13306.201627902357;13344.266065366499;13428.261445620194;13450.529231476852;13488.93255533716;13276.86723132795;13300;13327.800082998956;13372.06395552302;13464.93267533656;10792.190585705946;11078.335299882034;11080.335179889233;11082.390468523987;11088.445577721242;8036.678532858854;8240.340772738047;8251.752447426587;8289;8345.457545259538;7690.230976902265;7841.09827369837;7882.369410447036;7883.921160788319;7949.443538952218 19:42:48 INFO - PID 9727 | |4;prototype.html;572.9169270455784;584.4856526256893;599.6042611876162;604.8336707405466;608.5861614102412;6411.358864113592;6458;6478.093066970628;6489.6430696311745;6509.153810004693;6399.264084630269;6419.390157935;6422.421982021617;6455.515836312284;6501.187351581052;5229.921551176735;5260.500252476008;5264.263003179558;5281.973590132044;5289.656172348795;5031.572316353096;5143.948560514403;5181.533661970422;5263.34208223972;5296.285001524783;5122.846314610568;5127.717975511346;5130.179171332569;5138.460461651529;5154.793808247666;5106.974465127688;5107.361579802525;5111.309973153614;5114.386273647149;5126.512981266755;4665.370175026362;4687.578117969365;4698.530146985274;4707.505711900236;4722.480527142012;6279.968600157015;6414.230292364946;6429.842628326899;6441.549091563593;6477.157969463987;6103.115048318014;6109.969450152766;6125.540584456184;6200.751969921243;6201.069839524062;6903.7196937898325;6914.343137401939;6923;6935.133108361455;6965.930340696629;5105.795768169269;5284.10170271051;5313.3868969100995;5346.599005074635;5371.597130215249;7032.50772445929;7109.857802843966;7113.288671132897;7116.217216106224;7163.391111755507;5382.650127741685;5456.91814622781;5475.890482190334;5488.204210389471;5502.174673798882;5519.448055194529;5553.77784888608;5557.749901254453;5572.749226284827;5600.635958662674;6114.969425152845;6123.112148738408;6152.723127459275;6168.290646575611;6169.629822210682;6933.722651094001;6944.340287672663;7023.473239507058;7033.824154396181;7050.541714788523;1876.118224434541;1882.2376937340398;1897.2790339670837;1928.633559623667;1940.7282980382759;1269.57469247803;1352.7226918481688;1353.5736243083397;1370.944372832933;1372.4853180057478;6270.965290726979;6378.617282963037;6414.422701956846;6431.614103153732;6510.641914694737;6774.288699686561;6986.755463558751;7064.364207221375;7094.50338476312;7099.290070992963;6436.388543088399;6472.514561407913;6503.06564528463;6503.154589903377;6561.343865613496;3708.592054873962;3718.4050551911564;3731.794751288651;3750.6811920986474;3784.6783023443318;7280.126384833748;7316.487845850846;7346.889796653055;7353.301436363537;7395.520895820858;7225.349718525358;7251.274872512707;7311.23232060626;7377.852442951111;7409.147947985943;7116.71533138679;7232.42140628759;7285.854282914312;7324.802559232209;7370.152432470334;7038.894416583653;7074.929250707427;7101.005859179616;7113.359797618239;7158.355747982707;6886.552374095668;6948.409385202314;7036.401905838061;7066.152061752623;7066.611336376343;7217.927820721936;7220.855582888418;7223.963880180566;7306.525075870051;7335.559866408033;5306.442823503631;5375.596830237826;5381.946180538301;5423.7830486780085;5429.891402172014;6722.697478613572;7016.543924644881;7023.613701246276;7051.012858199753;7083.547872685984;6370.617762934239;6781.932180678327;6787.966060169668;6798.184217893884;6856.314368563104;6689.46484281266;6701.832454188684;6728.030462027427;6761.802414215302;6764.627945463047;6542.313057128933;6652.401283884474;6680;6695.598264104169;6744.898826517508;6778.93221067783;6789.796306110825;6806.455483561204;6829.52193346461;6991.650417479207;6565.949448088283;6645.534812563074;6660.367265109807;6664.500487390298;6703.29615390377 19:42:48 INFO - PID 9727 | |5;yui.html;995.8655581496496;1018.6180182431589;1038.5170895533581;1042.8122937871185;1061.045059446498;21831.781682183173;22358.664620030693;22371.217007404743;22392.216272430447;22634.54730905381;19003.239870405167;19086.141123649435;19115.808841911577;19798.901005494987;20423.489412764688;1086.7717779266343;1118.7874303882277;1119.4738472917716;1119.927204731694;1119.9888001119966;1046.6022911293699;1085.8371244313337;1106.1206340958956;1124.269225003746;1125.4766533561892;1183.9881601183963;1197.9401029948467;1197.9580714674944;1198.796204645208;1200.4597930931072;1181.645506348092;1192.7256730951885;1193.8865807748336;1193.910456715741;1197.7185361440054;868.9826203475958;877.3770622857778;879.7272845417941;880.1418616848586;881.431476697533;21309.5738085239;21897.452563685874;21942.122315107375;21978.120875164976;22006.339809805733;24222;24376;24435.144769932966;24862.129825456203;24869.87565062163;19619.939906309774;19635.31276405333;19666;19774.20903163872;19862.900685496625;4155.231282212801;4189.518205406387;4196.769177695225;4197.958020419787;4198.622124008823;164855;168404;168820.77948051153;170224;171232;18197.181126849322;18523.536911577048;18542;18542.351017714314;18578.81421185771;35768.46347304792;35886.28227435489;36165.63834361675;36172;36294.8185259072;9994.900050999397;10007.699769006796;10089.495525223709;10096.596136154472;10173.898261017295;4743.335932969387;4866.245731911559;4879.1461494238365;4889.951100488949;4895.902081958412;6564.770233041821;6597.703103360361;6616;6632.071509988606;6722.899156512656;1234.2717796500108;1251.3805666195292;1255.7049093463029;1260.577706468325;1268.3277862732582;745.8098184962909;749.1272667342485;764.7361660227212;769.103993847173;770.5992883700557;12911.806322905164;13693.109947853358;13907.443702251796;13968.231747253998;14136.293185340692;758.3288789421401;760.4980712729572;765.1124695353363;766.1227894061269;775.8719811231197;6554.868902621921;6575.408213260734;6593.736250549924;6594.472442204612;6618.602883826986;155784;159344;159416.202918987;160805;166213;140269.29865350813;142625.28687356703;144410;145332;148126;120166.39916800534;122240;122426;123749.38125309316;125684;12169.878301216875;12353.444095015566;12403.75192496163;12422.503099875903;12429;59485.40514594799;59838.70080649569;60491;60725;61516.38483615286;73017;73839.63080184565;75449.24550754423;76604;77312;18264.81735182631;18387.172577234323;18574.349897753513;18589.814101858807;18599.90700046491;19912.203511859363;19996.50008749793;20057.097430615948;20069.498262543555;20266;18935.715964260824;19130.330438434587;19216.423507294803;19377.031148442802;19409.32067377635;17851.37520186787;17905.552361191076;17930.5517362067;17965.191566379282;18081.276748929897;17641.470755877344;17727.73408398901;17785.555361116076;17906.731399028766;17929.641407172043;13773.173609583457;13838.169709817443;13962.511312104027;14021.579352619437;14097.224652644203;14177.645558861112;14187.432502699776;14210.360533775798;14220.715585688435;14293.213873236657 19:42:48 INFO - PID 9727 | __end_tp_report 19:42:48 INFO - PID 9727 | __start_cc_report 19:42:48 INFO - PID 9727 | _x_x_mozilla_cycle_collect,247 19:42:48 INFO - PID 9727 | __end_cc_report 19:42:48 INFO - PID 9727 | __startTimestamp1507171367999__endTimestamp 19:42:48 INFO - PID 9727 | 19:42:48 INFO - PID 9727 | ------- Summary: start ------- 19:42:48 INFO - PID 9727 | Number of tests: 6 19:42:48 INFO - PID 9727 | 19:42:48 INFO - PID 9727 | [#0] dojo.html Cycles:165 Average:6609.91 Median:6883.14 stddev:762.04 (11.1%) stddev-sans-first:736.22 19:42:48 INFO - PID 9727 | Values: 3993.7 3994.7 4010.1 4015.6 4028.8 6433.0 6465.1 6502.5 6558.1 6575.8 6473.7 6504.5 6569.7 6592.3 6599.0 5762.8 5823.8 5883.7 5884.1 5900.3 5894.2 5900.9 5900.9 5924.5 5946.7 5910.5 5934.8 5953.1 5974.6 6006.0 5905.4 5919.4 5934.9 5975.3 5988.2 5813.8 5856.2 5911.1 5966.2 6010.2 6491.3 6558.5 6560.1 6563.6 6593.7 7049.0 7062.8 7145.7 7150.7 7157.7 7070.3 7124.9 7160.3 7212.1 7227.3 6664.6 6691.9 6703.0 6704.0 6707.0 7276.1 7277.8 7292.6 7365.4 7375.7 6994.3 7029.9 7095.5 7111.6 7112.3 7141.2 7187.7 7193.2 7198.9 7210.2 7170.8 7179.2 7199.1 7265.8 7280.2 7217.1 7227.3 7313.3 7313.8 7314.0 5277.4 5326.1 5385.3 5385.4 5468.1 4908.5 4920.5 4984.5 4998.6 5021.3 6604.2 6676.2 6765.0 6774.1 6779.5 6956.3 7063.0 7180.0 7200.1 7293.5 6653.7 6724.2 6729.7 6747.9 6748.1 6086.9 6150.2 6153.9 6172.7 6179.2 7235.6 7306.6 7358.5 7364.5 7386.1 7102.3 7107.4 7108.0 7109.0 7116.8 7387.8 7393.7 7405.6 7471.4 7499.2 6992.3 6999.4 7051.4 7088.7 7089.1 7119.3 7120.2 7206.1 7207.2 7227.0 6809.2 6856.3 6883.1 6887.1 6951.3 6988.9 7010.0 7010.6 7024.4 7027.2 6908.8 6928.5 6985.2 7015.1 7017.8 6968.2 7071.5 7125.5 7161.1 7161.2 6727.8 6760.9 6784.2 6800.3 6863.6 19:42:48 INFO - PID 9727 | 19:42:48 INFO - PID 9727 | [#1] ext.html Cycles:180 Average:45257.84 Median:3811.83 stddev:69597.40 (1825.8%) stddev-sans-first:69713.20 19:42:48 INFO - PID 9727 | Values: 970.5 993.2 996.6 1034.7 1039.4 46008.1 46769.3 46776.3 46778.3 46888.5 43734.6 43880.6 43972.0 44050.6 44123.8 8113.2 8228.0 8248.0 8269.5 8272.1 8057.4 8143.8 8163.3 8169.7 8172.6 8777.4 9063.5 9064.1 9065.0 9097.5 8960.8 9008.5 9019.7 9026.6 9035.3 8789.0 8935.0 8951.9 8954.2 8964.7 41249.2 41450.0 41460.6 41589.4 41733.2 3762.8 3790.5 3795.2 3803.3 3820.4 34247.7 34365.8 34396.8 34535.7 34736.7 5613.0 5630.1 5639.1 5681.3 5749.4 228342.0 229343.0 230031.0 230305.0 230388.0 53646.0 54398.5 54837.5 55216.4 55280.7 58515.4 58559.4 58656.4 58722.7 58724.0 17613.0 17721.1 17733.5 17787.6 17877.8 14561.3 14573.6 14585.6 14603.7 14609.7 15477.8 15537.2 15705.0 15755.7 15854.3 5977.4 6007.4 6058.4 6098.8 6153.8 1114.8 1118.4 1129.3 1130.4 1135.6 27078.9 27203.3 27220.0 27444.5 27498.9 1231.4 1238.8 1239.1 1245.8 1260.6 14113.7 14272.4 14289.5 14357.5 14625.9 310436.0 311295.0 312135.0 313470.0 316579.0 53214.7 53893.0 60639.4 61722.0 62319.0 1555.0 1573.3 1588.5 1590.1 1612.2 31800.2 31828.0 32582.8 32749.3 32789.5 43201.8 43323.8 43355.6 43358.0 43372.1 250646.0 250948.0 253881.0 254743.0 256061.0 38373.8 38380.6 38418.0 38721.4 38726.4 48878.3 49823.5 50654.7 50793.7 50795.7 36102.3 36437.9 36606.6 36711.0 37096.0 36218.8 36357.3 36551.0 36729.6 37149.8 42021.0 42145.4 42479.8 42515.4 42872.1 34574.0 34877.3 35017.5 35225.5 35252.8 26865.6 26903.3 26958.9 26989.0 27100.2 19:42:48 INFO - PID 9727 | 19:42:48 INFO - PID 9727 | [#2] jquery.html Cycles:180 Average:41554.92 Median:2275.42 stddev:106343.14 (4673.6%) stddev-sans-first:106597.03 19:42:48 INFO - PID 9727 | Values: 616.5 621.2 634.3 636.0 637.3 23939.2 24463.5 24567.6 24632.8 25987.0 25893.6 26810.7 26903.2 26986.3 27061.5 6988.8 7176.5 7186.2 7188.0 7191.5 6694.6 6954.5 6964.9 7002.6 7104.9 6936.1 6959.3 6961.5 6962.8 6963.7 6920.8 6947.5 6954.7 6957.6 6963.1 6474.9 6504.6 6509.0 6524.4 6527.9 31380.7 31557.4 32734.0 32903.2 32907.5 2262.4 2288.5 2293.8 2297.1 2297.2 19823.8 20445.0 20453.3 20471.5 20507.1 2574.7 2604.8 2686.9 2716.3 2729.8 138323.3 144234.0 155746.0 161379.2 161517.0 16668.3 17656.6 17736.6 17872.1 18042.5 27863.2 27999.2 28112.6 28195.6 28359.9 11432.8 11466.8 11497.9 11807.6 11840.7 10629.5 10641.3 10677.2 10799.0 10836.1 10020.0 10059.6 10243.3 10354.5 10723.4 2926.2 3015.0 3017.3 3022.9 3142.3 1042.3 1043.3 1056.5 1060.4 1079.4 21844.3 22325.1 22466.4 22487.7 22562.6 1122.7 1130.0 1155.9 1158.1 1164.9 12049.6 12394.9 12612.0 12652.9 12735.6 619263.0 634225.0 634271.0 646643.0 652887.0 124387.0 135546.3 138847.0 139981.0 141841.3 1181.4 1237.2 1274.3 1276.5 1325.1 23451.9 24069.3 24146.2 24152.0 24296.6 60166.0 61299.0 61410.4 62218.0 63755.0 78601.6 78611.0 78882.0 78922.6 79575.2 17125.7 17318.3 17363.7 17406.2 17417.1 25379.9 25624.1 25807.9 25928.9 26553.2 19861.7 20019.8 20046.1 20158.3 20205.3 18852.2 18888.3 19135.7 19213.9 19418.1 21250.7 21304.3 21553.5 21609.2 21920.1 16563.8 16744.0 16870.4 16889.7 16892.0 20891.7 20956.6 20971.9 20981.7 21168.8 19:42:48 INFO - PID 9727 | 19:42:48 INFO - PID 9727 | [#3] mootools.html Cycles:180 Average:10813.08 Median:2863.25 stddev:8172.84 (285.4%) stddev-sans-first:8165.22 19:42:48 INFO - PID 9727 | Values: 1408.6 1478.7 1481.0 1490.4 1495.8 17285.7 17993.1 18041.3 18083.0 18103.5 17540.5 17666.2 17673.2 17705.2 17714.7 2877.8 2913.5 2970.3 2974.3 2977.0 2920.9 2953.6 2954.5 2958.4 2968.5 2848.7 2947.3 2973.0 2974.6 2974.7 2940.9 2996.7 3009.6 3009.9 3055.6 2947.1 2953.2 2964.5 2965.3 2965.5 17116.6 17403.1 17461.8 17489.1 17517.9 8363.9 8568.1 8609.7 8631.1 8632.1 5430.9 5672.5 5742.1 5766.8 5773.4 2187.1 2232.9 2243.6 2288.2 2294.8 31930.7 31972.8 32260.7 32307.7 32344.8 6851.7 6877.6 7017.1 7085.1 7237.9 20657.9 20702.6 20805.9 20807.7 20865.7 8149.1 8443.6 8448.1 8456.1 8457.9 7633.1 7678.9 7717.2 7727.0 7740.8 5834.7 6084.5 6095.9 6100.8 6126.0 1564.3 1596.3 1660.5 1698.3 1739.2 951.1 960.9 964.4 964.7 968.1 9609.5 9629.0 9644.2 9706.4 9739.4 1079.1 1086.4 1090.2 1097.3 1106.2 5071.1 5624.3 5836.0 5893.8 6005.9 25952.0 26809.3 26967.0 26985.2 27008.3 25182.1 26176.9 26193.1 26530.5 26740.6 11393.6 11806.8 11910.9 11921.9 12038.8 8996.8 9006.5 9011.5 9121.5 9160.9 21303.0 21895.1 22232.7 22409.7 22787.0 22850.7 22899.2 23282.0 23313.0 23442.5 13007.7 13203.5 13204.9 13210.9 13212.5 16218.0 16223.2 16333.6 16405.2 16426.5 13306.2 13344.3 13428.3 13450.5 13488.9 13276.9 13300.0 13327.8 13372.1 13464.9 10792.2 11078.3 11080.3 11082.4 11088.4 8036.7 8240.3 8251.8 8289.0 8345.5 7690.2 7841.1 7882.4 7883.9 7949.4 19:42:48 INFO - PID 9727 | 19:42:48 INFO - PID 9727 | [#4] prototype.html Cycles:180 Average:5862.92 Median:6465.26 stddev:1634.85 (25.3%) stddev-sans-first:1590.49 19:42:48 INFO - PID 9727 | Values: 572.9 584.5 599.6 604.8 608.6 6411.4 6458.0 6478.1 6489.6 6509.2 6399.3 6419.4 6422.4 6455.5 6501.2 5229.9 5260.5 5264.3 5282.0 5289.7 5031.6 5143.9 5181.5 5263.3 5296.3 5122.8 5127.7 5130.2 5138.5 5154.8 5107.0 5107.4 5111.3 5114.4 5126.5 4665.4 4687.6 4698.5 4707.5 4722.5 6280.0 6414.2 6429.8 6441.5 6477.2 6103.1 6110.0 6125.5 6200.8 6201.1 6903.7 6914.3 6923.0 6935.1 6965.9 5105.8 5284.1 5313.4 5346.6 5371.6 7032.5 7109.9 7113.3 7116.2 7163.4 5382.7 5456.9 5475.9 5488.2 5502.2 5519.4 5553.8 5557.7 5572.7 5600.6 6115.0 6123.1 6152.7 6168.3 6169.6 6933.7 6944.3 7023.5 7033.8 7050.5 1876.1 1882.2 1897.3 1928.6 1940.7 1269.6 1352.7 1353.6 1370.9 1372.5 6271.0 6378.6 6414.4 6431.6 6510.6 6774.3 6986.8 7064.4 7094.5 7099.3 6436.4 6472.5 6503.1 6503.2 6561.3 3708.6 3718.4 3731.8 3750.7 3784.7 7280.1 7316.5 7346.9 7353.3 7395.5 7225.3 7251.3 7311.2 7377.9 7409.1 7116.7 7232.4 7285.9 7324.8 7370.2 7038.9 7074.9 7101.0 7113.4 7158.4 6886.6 6948.4 7036.4 7066.2 7066.6 7217.9 7220.9 7224.0 7306.5 7335.6 5306.4 5375.6 5381.9 5423.8 5429.9 6722.7 7016.5 7023.6 7051.0 7083.5 6370.6 6781.9 6788.0 6798.2 6856.3 6689.5 6701.8 6728.0 6761.8 6764.6 6542.3 6652.4 6680.0 6695.6 6744.9 6778.9 6789.8 6806.5 6829.5 6991.7 6565.9 6645.5 6660.4 6664.5 6703.3 19:42:48 INFO - PID 9727 | 19:42:48 INFO - PID 9727 | [#5] yui.html Cycles:180 Average:30096.20 Median:18584.31 stddev:45306.20 (243.8%) stddev-sans-first:45380.60 19:42:48 INFO - PID 9727 | Values: 995.9 1018.6 1038.5 1042.8 1061.0 21831.8 22358.7 22371.2 22392.2 22634.5 19003.2 19086.1 19115.8 19798.9 20423.5 1086.8 1118.8 1119.5 1119.9 1120.0 1046.6 1085.8 1106.1 1124.3 1125.5 1184.0 1197.9 1198.0 1198.8 1200.5 1181.6 1192.7 1193.9 1193.9 1197.7 869.0 877.4 879.7 880.1 881.4 21309.6 21897.5 21942.1 21978.1 22006.3 24222.0 24376.0 24435.1 24862.1 24869.9 19619.9 19635.3 19666.0 19774.2 19862.9 4155.2 4189.5 4196.8 4198.0 4198.6 164855.0 168404.0 168820.8 170224.0 171232.0 18197.2 18523.5 18542.0 18542.4 18578.8 35768.5 35886.3 36165.6 36172.0 36294.8 9994.9 10007.7 10089.5 10096.6 10173.9 4743.3 4866.2 4879.1 4890.0 4895.9 6564.8 6597.7 6616.0 6632.1 6722.9 1234.3 1251.4 1255.7 1260.6 1268.3 745.8 749.1 764.7 769.1 770.6 12911.8 13693.1 13907.4 13968.2 14136.3 758.3 760.5 765.1 766.1 775.9 6554.9 6575.4 6593.7 6594.5 6618.6 155784.0 159344.0 159416.2 160805.0 166213.0 140269.3 142625.3 144410.0 145332.0 148126.0 120166.4 122240.0 122426.0 123749.4 125684.0 12169.9 12353.4 12403.8 12422.5 12429.0 59485.4 59838.7 60491.0 60725.0 61516.4 73017.0 73839.6 75449.2 76604.0 77312.0 18264.8 18387.2 18574.3 18589.8 18599.9 19912.2 19996.5 20057.1 20069.5 20266.0 18935.7 19130.3 19216.4 19377.0 19409.3 17851.4 17905.6 17930.6 17965.2 18081.3 17641.5 17727.7 17785.6 17906.7 17929.6 13773.2 13838.2 13962.5 14021.6 14097.2 14177.6 14187.4 14210.4 14220.7 14293.2 19:42:48 INFO - PID 9727 | -------- Summary: end -------- 19:42:48 INFO - PID 9727 | 19:42:48 INFO - TEST-INFO | 9727: exit 0 19:42:48 INFO - TEST-OK | dromaeo_css | took 1106996ms 19:42:48 INFO - TEST-START | kraken 19:42:48 INFO - Initialising browser for kraken test... 19:42:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60452/getInfo.html -profile /tmp/tmpYJaS7R/profile 19:42:48 INFO - TEST-INFO | started process 10089 (/builds/slave/test/build/application/firefox/firefox http://localhost:60452/getInfo.html) 19:42:53 INFO - TEST-INFO | 10089: exit 0 19:42:53 INFO - Browser initialized. 19:42:53 INFO - Running cycle 1/1 for kraken test... 19:42:53 INFO - Using env: {'DISPLAY': ':0', 19:42:53 INFO - 'HOME': '/home/cltbld', 19:42:53 INFO - 'JSGC_DISABLE_POISONING': '1', 19:42:53 INFO - 'LANG': 'en_US.UTF-8', 19:42:53 INFO - 'LANGUAGE': 'en_US:en', 19:42:53 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:42:53 INFO - 'LOGNAME': 'cltbld', 19:42:53 INFO - 'MAIL': '/var/mail/cltbld', 19:42:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:42:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:42:53 INFO - 'MOZ_CRASHREPORTER': '1', 19:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:42:53 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:42:53 INFO - 'MOZ_NO_REMOTE': '1', 19:42:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:42:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:42:53 INFO - 'NO_EM_RESTART': '1', 19:42:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:42:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:42:53 INFO - 'PWD': '/builds/slave/test', 19:42:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:42:53 INFO - 'RUST_BACKTRACE': 'full', 19:42:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:42:53 INFO - 'SHELL': '/bin/bash', 19:42:53 INFO - 'SHLVL': '1', 19:42:53 INFO - 'TERM': 'linux', 19:42:53 INFO - 'TMOUT': '86400', 19:42:53 INFO - 'USER': 'cltbld', 19:42:53 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558', 19:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:42:53 INFO - '_': '/tools/buildbot/bin/python'} 19:42:53 INFO - TEST-INFO | started process 10322 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYJaS7R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:42:54 INFO - PID 10322 | 19:42:54 INFO - PID 10322 | (/builds/slave/test/build/application/firefox/firefox:10371): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:42:54 INFO - PID 10322 | 19:43:04 INFO - PID 10322 | RSS: Main: 142008320 19:43:04 INFO - PID 10322 | 19:43:07 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60452/tests/kraken/driver.html?testName=audio-beat-detection) 19:43:08 INFO - PID 10322 | RSS: Main: 147664896 19:43:08 INFO - PID 10322 | 19:43:10 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60452/tests/kraken/driver.html?testName=audio-dft) 19:43:11 INFO - PID 10322 | RSS: Main: 148189184 19:43:11 INFO - PID 10322 | 19:43:14 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60452/tests/kraken/driver.html?testName=audio-fft) 19:43:14 INFO - PID 10322 | RSS: Main: 149983232 19:43:14 INFO - PID 10322 | 19:43:16 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60452/tests/kraken/driver.html?testName=audio-oscillator) 19:43:17 INFO - PID 10322 | RSS: Main: 149651456 19:43:17 INFO - PID 10322 | 19:43:19 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60452/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:43:20 INFO - PID 10322 | RSS: Main: 149164032 19:43:20 INFO - PID 10322 | 19:43:23 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60452/tests/kraken/driver.html?testName=imaging-darkroom) 19:43:23 INFO - PID 10322 | RSS: Main: 148897792 19:43:23 INFO - PID 10322 | 19:43:27 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60452/tests/kraken/driver.html?testName=imaging-desaturate) 19:43:27 INFO - PID 10322 | RSS: Main: 152186880 19:43:27 INFO - PID 10322 | 19:43:30 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60452/tests/kraken/driver.html?testName=json-parse-financial) 19:43:31 INFO - PID 10322 | RSS: Main: 152391680 19:43:31 INFO - PID 10322 | 19:43:32 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60452/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:43:33 INFO - PID 10322 | RSS: Main: 152420352 19:43:33 INFO - PID 10322 | 19:43:34 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60452/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:43:35 INFO - PID 10322 | RSS: Main: 152199168 19:43:35 INFO - PID 10322 | 19:43:37 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60452/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:43:38 INFO - PID 10322 | RSS: Main: 149352448 19:43:38 INFO - PID 10322 | 19:43:40 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60452/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:43:41 INFO - PID 10322 | RSS: Main: 149397504 19:43:41 INFO - PID 10322 | 19:43:43 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60452/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:43:44 INFO - PID 10322 | RSS: Main: 149864448 19:43:44 INFO - PID 10322 | 19:43:45 INFO - PID 10322 | Cycle 1(1): loaded http://localhost:60452/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60452/tests/kraken/driver.html?testName=ai-astar) 19:43:46 INFO - PID 10322 | RSS: Main: 151322624 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | __start_tp_report 19:43:46 INFO - PID 10322 | _x_x_mozilla_page_load 19:43:46 INFO - PID 10322 | _x_x_mozilla_page_load_details 19:43:46 INFO - PID 10322 | |i|pagename|runs| 19:43:46 INFO - PID 10322 | |0;ai-astar;104;103;104;105;105;106;105;105;105;105 19:43:46 INFO - PID 10322 | |1;audio-beat-detection;110;111;114;112;110;111;138;111;112;110 19:43:46 INFO - PID 10322 | |2;audio-dft;160;160;162;160;162;161;161;159;162;161 19:43:46 INFO - PID 10322 | |3;audio-fft;80;72;72;72;72;73;73;74;74;74 19:43:46 INFO - PID 10322 | |4;audio-oscillator;79;80;80;77;78;77;77;77;76;78 19:43:46 INFO - PID 10322 | |5;imaging-gaussian-blur;113;114;114;113;114;114;114;117;114;117 19:43:46 INFO - PID 10322 | |6;imaging-darkroom;147;147;148;157;148;147;147;147;148;148 19:43:46 INFO - PID 10322 | |7;imaging-desaturate;94;94;94;93;94;94;95;96;94;94 19:43:46 INFO - PID 10322 | |8;json-parse-financial;42;47;43;41;43;44;42;43;43;42 19:43:46 INFO - PID 10322 | |9;json-stringify-tinderbox;38;38;38;38;38;38;38;38;38;44 19:43:46 INFO - PID 10322 | |10;stanford-crypto-aes;67;70;69;69;69;68;70;69;68;69 19:43:46 INFO - PID 10322 | |11;stanford-crypto-ccm;101;97;99;102;107;101;97;110;103;100 19:43:46 INFO - PID 10322 | |12;stanford-crypto-pbkdf2;146;146;142;143;144;145;143;152;146;145 19:43:46 INFO - PID 10322 | |13;stanford-crypto-sha256-iterative;66;57;64;56;59;64;60;60;63;61 19:43:46 INFO - PID 10322 | __end_tp_report 19:43:46 INFO - PID 10322 | __start_cc_report 19:43:46 INFO - PID 10322 | _x_x_mozilla_cycle_collect,689 19:43:46 INFO - PID 10322 | __end_cc_report 19:43:46 INFO - PID 10322 | __startTimestamp1507171426192__endTimestamp 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | ------- Summary: start ------- 19:43:46 INFO - PID 10322 | Number of tests: 14 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#0] ai-astar Cycles:10 Average:104.70 Median:105.00 stddev:0.82 (0.8%) stddev-sans-first:0.83 19:43:46 INFO - PID 10322 | Values: 104.0 103.0 104.0 105.0 105.0 106.0 105.0 105.0 105.0 105.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#1] audio-beat-detection Cycles:10 Average:113.90 Median:111.50 stddev:8.56 (7.7%) stddev-sans-first:8.96 19:43:46 INFO - PID 10322 | Values: 110.0 111.0 114.0 112.0 110.0 111.0 138.0 111.0 112.0 110.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 19:43:46 INFO - PID 10322 | Values: 160.0 160.0 162.0 160.0 162.0 161.0 161.0 159.0 162.0 161.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#3] audio-fft Cycles:10 Average:73.60 Median:73.50 stddev:2.41 (3.3%) stddev-sans-first:0.93 19:43:46 INFO - PID 10322 | Values: 80.0 72.0 72.0 72.0 72.0 73.0 73.0 74.0 74.0 74.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:78.00 stddev:1.37 (1.8%) stddev-sans-first:1.39 19:43:46 INFO - PID 10322 | Values: 79.0 80.0 80.0 77.0 78.0 77.0 77.0 77.0 76.0 78.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:1.43 (1.3%) stddev-sans-first:1.42 19:43:46 INFO - PID 10322 | Values: 113.0 114.0 114.0 113.0 114.0 114.0 114.0 117.0 114.0 117.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.00 stddev:3.06 (2.1%) stddev-sans-first:3.21 19:43:46 INFO - PID 10322 | Values: 147.0 147.0 148.0 157.0 148.0 147.0 147.0 147.0 148.0 148.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 19:43:46 INFO - PID 10322 | Values: 94.0 94.0 94.0 93.0 94.0 94.0 95.0 96.0 94.0 94.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#8] json-parse-financial Cycles:10 Average:43.00 Median:43.00 stddev:1.63 (3.8%) stddev-sans-first:1.69 19:43:46 INFO - PID 10322 | Values: 42.0 47.0 43.0 41.0 43.0 44.0 42.0 43.0 43.0 42.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:38.00 stddev:1.90 (5.0%) stddev-sans-first:2.00 19:43:46 INFO - PID 10322 | Values: 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 44.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#10] stanford-crypto-aes Cycles:10 Average:68.80 Median:69.00 stddev:0.92 (1.3%) stddev-sans-first:0.71 19:43:46 INFO - PID 10322 | Values: 67.0 70.0 69.0 69.0 69.0 68.0 70.0 69.0 68.0 69.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#11] stanford-crypto-ccm Cycles:10 Average:101.70 Median:108.50 stddev:4.14 (3.8%) stddev-sans-first:4.38 19:43:46 INFO - PID 10322 | Values: 101.0 97.0 99.0 102.0 107.0 101.0 97.0 110.0 103.0 100.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.20 Median:145.50 stddev:2.78 (1.9%) stddev-sans-first:2.93 19:43:46 INFO - PID 10322 | Values: 146.0 146.0 142.0 143.0 144.0 145.0 143.0 152.0 146.0 145.0 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:62.00 stddev:3.23 (5.2%) stddev-sans-first:2.88 19:43:46 INFO - PID 10322 | Values: 66.0 57.0 64.0 56.0 59.0 64.0 60.0 60.0 63.0 61.0 19:43:46 INFO - PID 10322 | -------- Summary: end -------- 19:43:46 INFO - PID 10322 | 19:43:46 INFO - PID 10322 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:43:46 INFO - TEST-INFO | 10322: exit 0 19:43:46 INFO - TEST-OK | kraken | took 58187ms 19:43:46 INFO - SUITE-END | took 1165s 19:43:46 INFO - Completed test suite (00:19:25) 19:43:46 INFO - javascript benchmark 19:43:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9500.530437212135, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3993.740406873553, 3994.6604538614215, 4010.1177740897006, 4015.5783642717533, 4028.7582745035293, 6432.967835160817, 6465.127207826942, 6502.479801615871, 6558.081868538408, 6575.802725918218, 6473.741050357992, 6504.47964162867, 6569.704363303663, 6592.274849766533, 6599.0, 5762.82711518653, 5823.767049318033, 5883.676397798119, 5884.117382392633, 5900.262467191601, 5894.233749612566, 5900.911486327687, 5900.940990590082, 5924.466797988181, 5946.70266486674, 5910.46805787479, 5934.792282270121, 5953.077273022688, 5974.641521508702, 6006.0, 5905.379935106795, 5919.408059194089, 5934.910976335358, 5975.282966044081, 5988.19159413478, 5813.82558523245, 5856.180134781134, 5911.083782013752, 5966.194563733884, 6010.188624535675, 6491.253505846841, 6558.475321974231, 6560.147180866456, 6563.638999855006, 6593.6703164841565, 7049.0, 7062.823429414254, 7145.7141714331365, 7150.678219480136, 7157.677904494258, 7070.29297070294, 7124.857502849965, 7160.319769621878, 7212.098487689039, 7227.277272272783, 6664.633445160514, 6691.866162676768, 6703.028060931138, 6704.0, 6706.966465167643, 7276.05411296528, 7277.781666550012, 7292.598907060056, 7365.373943214887, 7375.741849035258, 6994.300569942965, 7029.92970070303, 7095.467839912028, 7111.573305601577, 7112.324329188718, 7141.214466408691, 7187.712491500386, 7193.172785129777, 7198.856022879618, 7210.242924492853, 7170.820729481805, 7179.174394944648, 7199.1001124859395, 7265.818354541178, 7280.162781280221, 7217.097862767154, 7227.27727227273, 7313.268673132644, 7313.780586582411, 7314.049173607397, 5277.419483856773, 5326.121190003606, 5385.299911011543, 5385.407605163429, 5468.0977638689965, 4908.533689299511, 4920.4833492483485, 4984.526469985346, 4998.600111991032, 5021.347224860745, 6604.174478190226, 6676.165479315086, 6764.966175169093, 6774.119364482585, 6779.49153813466, 6956.339147780953, 7063.046488723957, 7179.964100179465, 7200.099987501562, 7293.489455738048, 6653.733850646016, 6724.159480064992, 6729.663516824139, 6747.898781518281, 6748.055272261888, 6086.908696369558, 6150.169727086875, 6153.938460615336, 6172.69136543171, 6179.196704428396, 7235.6020418876415, 7306.5981371024045, 7358.484906056632, 7364.484486085923, 7386.113666359964, 7102.289771022856, 7107.431405487652, 7108.0, 7108.964455177691, 7116.786496405116, 7387.7783666490095, 7393.667284972082, 7405.62971851416, 7471.439642026761, 7499.212582678741, 6992.3357281058215, 6999.405050570758, 7051.365377116084, 7088.716451341888, 7089.113860767404, 7119.288071192839, 7120.181179164462, 7206.099237595301, 7207.171175314906, 7226.963865180641, 6809.216940051958, 6856.280090590416, 6883.139607549057, 6887.104676392037, 6951.27011663768, 6988.9301106990315, 7010.0, 7010.614416207158, 7024.367806897404, 7027.22700502934, 6908.827279318058, 6928.4803639726215, 6985.161780586159, 7015.0529678494795, 7017.824554386181, 6968.163820341591, 7071.504994650325, 7125.5368401053765, 7161.104861892263, 7161.176464706626, 6727.798166055026, 6760.93239067603, 6784.21981472137, 6800.251972282946, 6863.588184708933], "value": 6559.248968841654, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [970.4613939263705, 993.1806259835637, 996.5515518016892, 1034.7051090439227, 1039.4387031003264, 46008.07983840321, 46769.29846052312, 46776.2983555247, 46778.29832552506, 46888.53111468893, 43734.56265437345, 43880.56119438804, 43971.99999999992, 44050.559494405046, 44123.77938110305, 8113.229243221899, 8227.95886020569, 8247.958760206191, 8269.462484938489, 8272.090070092285, 8057.435979481439, 8143.796405089861, 8163.346932245436, 8169.6732130715, 8172.6322315495645, 8777.42946708465, 9063.50150741709, 9064.138906803875, 9065.04816994216, 9097.454152750823, 8960.775980600472, 9008.549572521348, 9019.684311049115, 9026.593803278869, 9035.277177825757, 8788.956055219747, 8934.955325223333, 8951.910480895172, 8954.19412252894, 8964.68623598171, 41249.1750164995, 41450.0, 41460.58539414628, 41589.376159357635, 41733.1653366931, 3762.830672619739, 3790.469334293201, 3795.203007368456, 3803.2773772983046, 3820.3887378019385, 34247.6575234247, 34365.828170858986, 34396.82801586001, 34535.6546434535, 34736.6526334736, 5612.971935140339, 5630.09918413056, 5639.069553523664, 5681.318241810968, 5749.367569567345, 228342.0, 229343.0, 230031.0, 230305.0, 230388.0, 53646.0, 54398.45601543934, 54837.451625484035, 55216.44783552193, 55280.723596382566, 58515.41484585185, 58559.414405856245, 58656.41343586509, 58722.70638646865, 58724.0, 17613.0, 17721.11394430299, 17733.46799596014, 17787.644247114986, 17877.821221787875, 14561.344739486749, 14573.562793116223, 14585.635359115895, 14603.707925841423, 14609.707805843824, 15477.84522154764, 15537.223138843012, 15705.0, 15755.684886302439, 15854.286557104959, 5977.445864075372, 6007.3992600739575, 6058.363871793486, 6098.75604975796, 6153.815385538441, 1114.7714718482691, 1118.4128332625437, 1129.316763358173, 1130.3556972525746, 1135.5684839760838, 27078.864605676845, 27203.319917001838, 27220.0, 27444.45111097807, 27498.862505687342, 1231.4150778380376, 1238.7708273969345, 1239.0768877186554, 1245.8131280307846, 1260.602910083321, 14113.650555763707, 14272.429102835771, 14289.499867504588, 14357.497487587885, 14625.853741462663, 310436.0, 311295.0, 312135.0, 313470.0, 316579.0, 53214.7339263309, 53893.0, 60639.39360606426, 61722.0, 62319.0, 1554.9611259718597, 1573.2684301799488, 1588.4519840654955, 1590.0936466214148, 1612.1616759285107, 31800.204994875312, 31828.0, 32582.83708581442, 32749.345013100083, 32789.508157378135, 43201.78399107984, 43323.783381082896, 43355.566444335156, 43358.0, 43372.13255734931, 250646.0, 250948.0, 253881.0, 254743.0, 256061.0, 38373.808130959165, 38380.616193837704, 38418.0, 38721.4191787129, 38726.41910371403, 48878.26682599693, 49823.501764981884, 50654.74672626761, 50793.746031269606, 50795.74602126966, 36102.27795444129, 36437.90686279416, 36606.63393366032, 36711.0, 37096.0, 36218.818905905304, 36357.27285454223, 36551.0, 36729.63270367262, 37149.814250928575, 42021.0, 42145.36781948335, 42479.7876010618, 42515.36226956536, 42872.14255714806, 34574.0, 34877.302453951284, 35017.474737878445, 35225.47161792626, 35252.82373588115, 26865.597016045165, 26903.327416814736, 26958.865205673847, 26989.0, 27100.186994389413], "value": 19588.171426515943, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.5423984591404, 621.1986537366796, 634.3212762344293, 635.980920572383, 637.3021541412154, 23939.162129325476, 24463.510729785437, 24567.63148552773, 24632.753672463292, 25987.0, 25893.611595826078, 26810.731892681066, 26903.192904212858, 26986.325341866464, 27061.45877082457, 6988.755393561227, 7176.533525320863, 7186.209516953131, 7188.0, 7191.460640451961, 6694.631795251259, 6954.478414118961, 6964.895526567106, 7002.614856182908, 7104.857902841966, 6936.098307220054, 6959.30406959305, 6961.477889158308, 6962.756303529378, 6963.721451141948, 6920.75777347793, 6947.4789390795895, 6954.721811127549, 6957.582545047263, 6963.094797676295, 6474.935250647527, 6504.577202481823, 6508.967455162741, 6524.412802847766, 6527.93472065278, 31380.686193138004, 31557.368852623047, 32734.0, 32903.17742056444, 32907.50638740421, 2262.3778460923377, 2288.4622113803243, 2293.8050265727434, 2297.127091705158, 2297.24191016964, 19823.801761982482, 20444.977751112387, 20453.28413505535, 20471.4882127948, 20507.077181526718, 2574.7039090504645, 2604.8046396520335, 2686.892524299026, 2716.307341627872, 2729.849858257795, 138323.30838345844, 144234.0, 155746.0, 161379.19310403374, 161517.0, 16668.333266669197, 17656.646867062587, 17736.645267094846, 17872.106394680213, 18042.548936276697, 27863.16410507688, 27999.160025199275, 28112.578311325346, 28195.57706634402, 28359.858200708866, 11432.827762725055, 11466.763981023787, 11497.885021149848, 11807.645770626896, 11840.703982400337, 10629.46852657364, 10641.255112142077, 10677.199210059276, 10799.0, 10836.133109351233, 10020.0, 10059.597616095274, 10243.282970192015, 10354.53404596795, 10723.410212438392, 2926.1813333599857, 3015.0, 3017.260771111092, 3022.863971121305, 3142.261568531393, 1042.3485321673954, 1043.337480699761, 1056.4559251985233, 1060.4432672846663, 1079.433297518793, 21844.344669659935, 22325.10699571999, 22466.438339041655, 22487.66268505974, 22562.63092375277, 1122.6968718445974, 1129.9661010169707, 1155.8555180602425, 1158.1082566423806, 1164.947577359021, 12049.578264760692, 12394.938025309815, 12612.0, 12652.87347126517, 12735.617931462071, 619263.0, 634225.0, 634271.0, 646643.0, 652887.0, 124387.0, 135546.32226839, 138847.0, 139981.0, 141841.29079354744, 1181.3679681370425, 1237.1649136832602, 1274.286399616218, 1276.5276847566274, 1325.1121748428577, 23451.88274058619, 24069.27792166238, 24146.154884578955, 24152.0, 24296.635550466402, 60166.0, 61299.0, 61410.38589614225, 62218.0, 63755.0, 78601.60699196467, 78611.0, 78882.0, 78922.6053869727, 79575.20424795678, 17125.74311385355, 17318.30726770915, 17363.739543907104, 17406.216720247874, 17417.129143543025, 25379.873100634377, 25624.103156389436, 25807.870960645076, 25928.8703556481, 26553.203403897915, 19861.702074469184, 20019.799802002377, 20046.09792559309, 20158.29445969384, 20205.292814751414, 18852.24591016344, 18888.33890813815, 19135.71296430527, 19213.903930480257, 19418.126184322024, 21250.681239781108, 21304.254351097636, 21553.461163471038, 21609.243676471247, 21920.123195072658, 16563.83436165623, 16744.0, 16870.409535666193, 16889.746653799957, 16891.986480811192, 20891.712123176167, 20956.58086838285, 20971.89514052481, 20981.685274721196, 21168.78831211668], "value": 13447.537829370833, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.5844675820636, 1478.6746915678548, 1480.9555713328605, 1490.403838464614, 1495.8055452791136, 17285.654286914254, 17993.100344982762, 18041.278348866046, 18082.99999999998, 18103.547411314725, 17540.473785786446, 17666.205020774065, 17673.204705788237, 17705.203265853037, 17714.734278985794, 2877.8273303601745, 2913.5192693205595, 2970.3168271297614, 2974.271303530638, 2976.9702302977016, 2920.933859141409, 2953.616029916108, 2954.5125054366003, 2958.4231074940394, 2968.5398763191633, 2848.672402673688, 2947.3221159133413, 2973.0189037617642, 2974.643042834852, 2974.732274095331, 2940.882364705409, 2996.745276651476, 3009.608750862385, 3009.8946536871103, 3055.5569442430724, 2947.056941778632, 2953.2469220348676, 2964.5256758918467, 2965.258685328668, 2965.4662160811044, 17116.572085697833, 17403.1298435079, 17461.82538174615, 17489.125543722763, 17517.912410437868, 8363.916360836374, 8568.143185681382, 8609.698660546914, 8631.050584435707, 8632.093630168805, 5430.8913821723345, 5672.4611161939965, 5742.1338065129, 5766.826995190151, 5773.364929857712, 2187.125149940021, 2232.8548644338066, 2243.6298010828195, 2288.2448791898632, 2294.804941579968, 31930.680693193004, 31972.84013579917, 32260.677393226237, 32307.6769232307, 32344.83827580847, 6851.657417129124, 6877.552959057645, 7017.122859642544, 7085.078939737904, 7237.8552428951125, 20657.89671051635, 20702.585948281252, 20805.89597052005, 20807.68788468174, 20865.68701469479, 8149.103598604149, 8443.57782110892, 8448.07071222165, 8456.11210822867, 8457.873131903027, 7633.122190948001, 7678.924950506934, 7717.151113377524, 7726.961365193138, 7740.845183096307, 5834.679092649945, 6084.543659225487, 6095.939040609625, 6100.816975490742, 6126.0, 1564.311702850742, 1596.3135851583932, 1660.518449649588, 1698.3206717313174, 1739.20866005967, 951.0727041134838, 960.9039096090335, 964.3538828984597, 964.7298756348234, 968.0754879090451, 9609.519524023772, 9629.0, 9644.228461723045, 9706.417614943126, 9739.415635061778, 1079.1097344690504, 1086.435053772034, 1090.231386872253, 1097.3251450357973, 1106.2201148190507, 5071.112555302807, 5624.268845050199, 5836.0, 5893.823185304448, 6005.879882402415, 25952.0, 26809.329766755985, 26967.0, 26985.1904442867, 27008.32479188036, 25182.11862584801, 26176.8691156543, 26193.083242086435, 26530.46939061208, 26740.59889101665, 11393.550386666906, 11806.822897656542, 11910.940445297718, 11921.940390298167, 12038.759224815454, 8996.775080623036, 9006.459612423276, 9011.504367259864, 9121.45271283725, 9160.908390916005, 21303.041363139007, 21895.12419503202, 22232.666510002688, 22409.663855042512, 22787.0, 22850.657240141078, 22899.19852805144, 23282.0, 23312.950917209106, 23442.531149377257, 13007.674808129872, 13203.471861125447, 13204.933975330061, 13210.867891320964, 13212.537561185696, 16218.0, 16223.188840557688, 16333.591660208589, 16405.17974101314, 16426.507204783877, 13306.201627902357, 13344.266065366499, 13428.261445620194, 13450.529231476852, 13488.93255533716, 13276.86723132795, 13300.0, 13327.800082998956, 13372.06395552302, 13464.93267533656, 10792.190585705946, 11078.335299882034, 11080.335179889233, 11082.390468523987, 11088.445577721242, 8036.678532858854, 8240.340772738047, 8251.752447426587, 8289.0, 8345.457545259538, 7690.230976902265, 7841.09827369837, 7882.369410447036, 7883.921160788319, 7949.443538952218], "value": 7531.522578301497, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.9169270455784, 584.4856526256893, 599.6042611876162, 604.8336707405466, 608.5861614102412, 6411.358864113592, 6458.0, 6478.093066970628, 6489.6430696311745, 6509.153810004693, 6399.264084630269, 6419.390157935, 6422.421982021617, 6455.515836312284, 6501.187351581052, 5229.921551176735, 5260.500252476008, 5264.263003179558, 5281.973590132044, 5289.656172348795, 5031.572316353096, 5143.948560514403, 5181.533661970422, 5263.34208223972, 5296.285001524783, 5122.846314610568, 5127.717975511346, 5130.179171332569, 5138.460461651529, 5154.793808247666, 5106.974465127688, 5107.361579802525, 5111.309973153614, 5114.386273647149, 5126.512981266755, 4665.370175026362, 4687.578117969365, 4698.530146985274, 4707.505711900236, 4722.480527142012, 6279.968600157015, 6414.230292364946, 6429.842628326899, 6441.549091563593, 6477.157969463987, 6103.115048318014, 6109.969450152766, 6125.540584456184, 6200.751969921243, 6201.069839524062, 6903.7196937898325, 6914.343137401939, 6923.0, 6935.133108361455, 6965.930340696629, 5105.795768169269, 5284.10170271051, 5313.3868969100995, 5346.599005074635, 5371.597130215249, 7032.50772445929, 7109.857802843966, 7113.288671132897, 7116.217216106224, 7163.391111755507, 5382.650127741685, 5456.91814622781, 5475.890482190334, 5488.204210389471, 5502.174673798882, 5519.448055194529, 5553.77784888608, 5557.749901254453, 5572.749226284827, 5600.635958662674, 6114.969425152845, 6123.112148738408, 6152.723127459275, 6168.290646575611, 6169.629822210682, 6933.722651094001, 6944.340287672663, 7023.473239507058, 7033.824154396181, 7050.541714788523, 1876.118224434541, 1882.2376937340398, 1897.2790339670837, 1928.633559623667, 1940.7282980382759, 1269.57469247803, 1352.7226918481688, 1353.5736243083397, 1370.944372832933, 1372.4853180057478, 6270.965290726979, 6378.617282963037, 6414.422701956846, 6431.614103153732, 6510.641914694737, 6774.288699686561, 6986.755463558751, 7064.364207221375, 7094.50338476312, 7099.290070992963, 6436.388543088399, 6472.514561407913, 6503.06564528463, 6503.154589903377, 6561.343865613496, 3708.592054873962, 3718.4050551911564, 3731.794751288651, 3750.6811920986474, 3784.6783023443318, 7280.126384833748, 7316.487845850846, 7346.889796653055, 7353.301436363537, 7395.520895820858, 7225.349718525358, 7251.274872512707, 7311.23232060626, 7377.852442951111, 7409.147947985943, 7116.71533138679, 7232.42140628759, 7285.854282914312, 7324.802559232209, 7370.152432470334, 7038.894416583653, 7074.929250707427, 7101.005859179616, 7113.359797618239, 7158.355747982707, 6886.552374095668, 6948.409385202314, 7036.401905838061, 7066.152061752623, 7066.611336376343, 7217.927820721936, 7220.855582888418, 7223.963880180566, 7306.525075870051, 7335.559866408033, 5306.442823503631, 5375.596830237826, 5381.946180538301, 5423.7830486780085, 5429.891402172014, 6722.697478613572, 7016.543924644881, 7023.613701246276, 7051.012858199753, 7083.547872685984, 6370.617762934239, 6781.932180678327, 6787.966060169668, 6798.184217893884, 6856.314368563104, 6689.46484281266, 6701.832454188684, 6728.030462027427, 6761.802414215302, 6764.627945463047, 6542.313057128933, 6652.401283884474, 6680.0, 6695.598264104169, 6744.898826517508, 6778.93221067783, 6789.796306110825, 6806.455483561204, 6829.52193346461, 6991.650417479207, 6565.949448088283, 6645.534812563074, 6660.367265109807, 6664.500487390298, 6703.29615390377], "value": 5395.48125422938, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [995.8655581496496, 1018.6180182431589, 1038.5170895533581, 1042.8122937871185, 1061.045059446498, 21831.781682183173, 22358.664620030693, 22371.217007404743, 22392.216272430447, 22634.54730905381, 19003.239870405167, 19086.141123649435, 19115.808841911577, 19798.901005494987, 20423.489412764688, 1086.7717779266343, 1118.7874303882277, 1119.4738472917716, 1119.927204731694, 1119.9888001119966, 1046.6022911293699, 1085.8371244313337, 1106.1206340958956, 1124.269225003746, 1125.4766533561892, 1183.9881601183963, 1197.9401029948467, 1197.9580714674944, 1198.796204645208, 1200.4597930931072, 1181.645506348092, 1192.7256730951885, 1193.8865807748336, 1193.910456715741, 1197.7185361440054, 868.9826203475958, 877.3770622857778, 879.7272845417941, 880.1418616848586, 881.431476697533, 21309.5738085239, 21897.452563685874, 21942.122315107375, 21978.120875164976, 22006.339809805733, 24222.0, 24376.0, 24435.144769932966, 24862.129825456203, 24869.87565062163, 19619.939906309774, 19635.31276405333, 19666.0, 19774.20903163872, 19862.900685496625, 4155.231282212801, 4189.518205406387, 4196.769177695225, 4197.958020419787, 4198.622124008823, 164855.0, 168404.0, 168820.77948051153, 170224.0, 171232.0, 18197.181126849322, 18523.536911577048, 18542.0, 18542.351017714314, 18578.81421185771, 35768.46347304792, 35886.28227435489, 36165.63834361675, 36172.0, 36294.8185259072, 9994.900050999397, 10007.699769006796, 10089.495525223709, 10096.596136154472, 10173.898261017295, 4743.335932969387, 4866.245731911559, 4879.1461494238365, 4889.951100488949, 4895.902081958412, 6564.770233041821, 6597.703103360361, 6616.0, 6632.071509988606, 6722.899156512656, 1234.2717796500108, 1251.3805666195292, 1255.7049093463029, 1260.577706468325, 1268.3277862732582, 745.8098184962909, 749.1272667342485, 764.7361660227212, 769.103993847173, 770.5992883700557, 12911.806322905164, 13693.109947853358, 13907.443702251796, 13968.231747253998, 14136.293185340692, 758.3288789421401, 760.4980712729572, 765.1124695353363, 766.1227894061269, 775.8719811231197, 6554.868902621921, 6575.408213260734, 6593.736250549924, 6594.472442204612, 6618.602883826986, 155784.0, 159344.0, 159416.202918987, 160805.0, 166213.0, 140269.29865350813, 142625.28687356703, 144410.0, 145332.0, 148126.0, 120166.39916800534, 122240.0, 122426.0, 123749.38125309316, 125684.0, 12169.878301216875, 12353.444095015566, 12403.75192496163, 12422.503099875903, 12429.0, 59485.40514594799, 59838.70080649569, 60491.0, 60725.0, 61516.38483615286, 73017.0, 73839.63080184565, 75449.24550754423, 76604.0, 77312.0, 18264.81735182631, 18387.172577234323, 18574.349897753513, 18589.814101858807, 18599.90700046491, 19912.203511859363, 19996.50008749793, 20057.097430615948, 20069.498262543555, 20266.0, 18935.715964260824, 19130.330438434587, 19216.423507294803, 19377.031148442802, 19409.32067377635, 17851.37520186787, 17905.552361191076, 17930.5517362067, 17965.191566379282, 18081.276748929897, 17641.470755877344, 17727.73408398901, 17785.555361116076, 17906.731399028766, 17929.641407172043, 13773.173609583457, 13838.169709817443, 13962.511312104027, 14021.579352619437, 14097.224652644203, 14177.645558861112, 14187.432502699776, 14210.360533775798, 14220.715585688435, 14293.213873236657], "value": 10472.668190021668, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1346.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 103.0, 104.0, 105.0, 105.0, 106.0, 105.0, 105.0, 105.0, 105.0], "value": 104.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 114.0, 112.0, 110.0, 111.0, 138.0, 111.0, 112.0, 110.0], "value": 113.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 160.0, 162.0, 161.0, 161.0, 159.0, 162.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 72.0, 72.0, 72.0, 72.0, 73.0, 73.0, 74.0, 74.0, 74.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 80.0, 77.0, 78.0, 77.0, 77.0, 77.0, 76.0, 78.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 117.0, 114.0, 117.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 157.0, 148.0, 147.0, 147.0, 147.0, 148.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 95.0, 96.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 47.0, 43.0, 41.0, 43.0, 44.0, 42.0, 43.0, 43.0, 42.0], "value": 43.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 44.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 69.0, 69.0, 69.0, 68.0, 70.0, 69.0, 68.0, 69.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 97.0, 99.0, 102.0, 107.0, 101.0, 97.0, 110.0, 103.0, 100.0], "value": 101.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 142.0, 143.0, 144.0, 145.0, 143.0, 152.0, 146.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 57.0, 64.0, 56.0, 59.0, 64.0, 60.0, 60.0, 63.0, 61.0], "value": 61.0, "unit": "score"}]}]} 19:43:46 INFO - Return code: 0 19:43:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:43:47 INFO - # TBPL SUCCESS # 19:43:47 INFO - Running post-action listener: _package_coverage_data 19:43:47 INFO - Running post-action listener: _resource_record_post_action 19:43:47 INFO - [mozharness: 2017-10-05 02:43:47.007770Z] Finished run-tests step (success) 19:43:47 INFO - Running post-run listener: _resource_record_post_run 19:43:47 INFO - Total resource usage - Wall time: 1180s; CPU: 13.0%; Read bytes: 12288; Write bytes: 273604608; Read time: 44; Write time: 213652 19:43:47 INFO - TinderboxPrint: CPU usage
13.1% 19:43:47 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 44 19:43:47 INFO - TinderboxPrint: I/O write bytes / time
273,604,608 / 213,652 19:43:47 INFO - TinderboxPrint: CPU idle
8,180.5 (86.9%) 19:43:47 INFO - TinderboxPrint: CPU user
1,179.6 (12.5%) 19:43:47 INFO - TinderboxPrint: Swap in / out
0 / 0 19:43:47 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 471040; Read time: 0; Write time: 44 19:43:47 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:43:47 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 8192; Write bytes: 273133568; Read time: 32; Write time: 213608 19:43:47 INFO - Running post-run listener: _upload_blobber_files 19:43:47 INFO - Blob upload gear active. 19:43:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:43:47 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 19:43:47 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:43:47 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:43:48 INFO - (blobuploader) - INFO - Open directory for files ... 19:43:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:43:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:43:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:49 INFO - (blobuploader) - INFO - Done attempting. 19:43:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:43:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:43:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:53 INFO - (blobuploader) - INFO - Done attempting. 19:43:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:43:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:43:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:54 INFO - (blobuploader) - INFO - Done attempting. 19:43:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:43:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:43:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:54 INFO - (blobuploader) - INFO - Done attempting. 19:43:54 INFO - (blobuploader) - INFO - Iteration through files over. 19:43:54 INFO - Return code: 0 19:43:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:43:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:43:54 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6a7e0ddff09d5ac827d76cec04859150c9e8318f1684485742ea8634ac46293147e219ac87725b782c4ed22590a9d4c8d2f467d159e10a1c32771d9b490059c0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c85599aaa0eeddbffd3f626b0682378c12fbc691de53fbfaee04d7fd9cf9c41f2ba40c0a2cb1f4223c4960baa5f2d2b79ec9e684547b8736064151ec280502a0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/720f8a3a9c79d76a287981e8939777af752f36569962670f6c79a30fe71ff0da629d80a6e0d543763f344f47e6ca95ef49ddb4a4011c65d1258feaa572cded89", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e62cb27687e467ce43b99e2d88560d32c093170ed0d8c0ca65c5033073db634eedf8cce3134228d9f1deebe245c56da30c82498aa6340fdd59fdd1392744cb03"} 19:43:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:43:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:43:54 INFO - Contents: 19:43:54 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6a7e0ddff09d5ac827d76cec04859150c9e8318f1684485742ea8634ac46293147e219ac87725b782c4ed22590a9d4c8d2f467d159e10a1c32771d9b490059c0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c85599aaa0eeddbffd3f626b0682378c12fbc691de53fbfaee04d7fd9cf9c41f2ba40c0a2cb1f4223c4960baa5f2d2b79ec9e684547b8736064151ec280502a0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/720f8a3a9c79d76a287981e8939777af752f36569962670f6c79a30fe71ff0da629d80a6e0d543763f344f47e6ca95ef49ddb4a4011c65d1258feaa572cded89", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e62cb27687e467ce43b99e2d88560d32c093170ed0d8c0ca65c5033073db634eedf8cce3134228d9f1deebe245c56da30c82498aa6340fdd59fdd1392744cb03"} 19:43:54 INFO - Running post-run listener: copy_logs_to_upload_dir 19:43:54 INFO - Copying logs to upload dir... 19:43:54 INFO - mkdir: /builds/slave/test/build/upload/logs 19:43:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1228.793831 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2017-10-04 19:43:55.074879) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-04 19:43:55.080006) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6a7e0ddff09d5ac827d76cec04859150c9e8318f1684485742ea8634ac46293147e219ac87725b782c4ed22590a9d4c8d2f467d159e10a1c32771d9b490059c0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c85599aaa0eeddbffd3f626b0682378c12fbc691de53fbfaee04d7fd9cf9c41f2ba40c0a2cb1f4223c4960baa5f2d2b79ec9e684547b8736064151ec280502a0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/720f8a3a9c79d76a287981e8939777af752f36569962670f6c79a30fe71ff0da629d80a6e0d543763f344f47e6ca95ef49ddb4a4011c65d1258feaa572cded89", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e62cb27687e467ce43b99e2d88560d32c093170ed0d8c0ca65c5033073db634eedf8cce3134228d9f1deebe245c56da30c82498aa6340fdd59fdd1392744cb03"} build_url:https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/WYGk5NIrR0qAzKjoTlGHQQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6a7e0ddff09d5ac827d76cec04859150c9e8318f1684485742ea8634ac46293147e219ac87725b782c4ed22590a9d4c8d2f467d159e10a1c32771d9b490059c0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c85599aaa0eeddbffd3f626b0682378c12fbc691de53fbfaee04d7fd9cf9c41f2ba40c0a2cb1f4223c4960baa5f2d2b79ec9e684547b8736064151ec280502a0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/720f8a3a9c79d76a287981e8939777af752f36569962670f6c79a30fe71ff0da629d80a6e0d543763f344f47e6ca95ef49ddb4a4011c65d1258feaa572cded89", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e62cb27687e467ce43b99e2d88560d32c093170ed0d8c0ca65c5033073db634eedf8cce3134228d9f1deebe245c56da30c82498aa6340fdd59fdd1392744cb03"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-04 19:43:55.113654) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 19:43:55.114021) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1507169867.901998-262973558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004560 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 19:43:55.193607) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-04 19:43:55.193913) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-04 19:43:55.194273) ========= ========= Total master_lag: 0.25 =========