builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-056 starttime: 1508370623.8 results: success (0) revision: 1d56d3922195efcabe9807b8ec0ea216397f90b4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:23.796416) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:23.796897) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:23.797179) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:23.818333) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:23.818736) ========= 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=52faae308a556c440401f8e5000001e8-1508370623.26101-956867479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-18 16:50:23-- 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.4M=0s 2017-10-18 16:50:24 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108524 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:23.943918) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:23.944235) ========= 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=52faae308a556c440401f8e5000001e8-1508370623.26101-956867479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022463 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:23.993897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:23.994242) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1d56d3922195efcabe9807b8ec0ea216397f90b4 --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 1d56d3922195efcabe9807b8ec0ea216397f90b4 --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=52faae308a556c440401f8e5000001e8-1508370623.26101-956867479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-18 16:50:24,164 truncating revision to first 12 chars 2017-10-18 16:50:24,164 Setting DEBUG logging. 2017-10-18 16:50:24,164 attempt 1/10 2017-10-18 16:50:24,164 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1d56d3922195?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-18 16:50:24,592 unpacking tar archive at: mozilla-beta-1d56d3922195/testing/mozharness/ program finished with exit code 0 elapsedTime=0.683547 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:24.696650) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:24.696992) ========= script_repo_revision: 1d56d3922195efcabe9807b8ec0ea216397f90b4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:24.697374) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:24.697659) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-18 16:50:24.712391) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-18 16:50:24.712753) ========= /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=52faae308a556c440401f8e5000001e8-1508370623.26101-956867479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:50:25 INFO - MultiFileLogger online at 20171018 16:50:25 in /builds/slave/test 16:50:25 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 16:50:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:50:25 INFO - {'append_to_log': False, 16:50:25 INFO - 'base_work_dir': '/builds/slave/test', 16:50:25 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 16:50:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:50:25 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 16:50:25 INFO - 'buildbot_json_path': 'buildprops.json', 16:50:25 INFO - 'code_coverage': False, 16:50:25 INFO - 'config_files': ('talos/linux_config.py',), 16:50:25 INFO - 'default_actions': ('clobber', 16:50:25 INFO - 'read-buildbot-config', 16:50:25 INFO - 'download-and-extract', 16:50:25 INFO - 'populate-webroot', 16:50:25 INFO - 'create-virtualenv', 16:50:25 INFO - 'install', 16:50:25 INFO - 'setup-mitmproxy', 16:50:25 INFO - 'run-tests'), 16:50:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:50:25 INFO - 'disable_ccov_upload': False, 16:50:25 INFO - 'disable_stylo': False, 16:50:25 INFO - 'download_minidump_stackwalk': True, 16:50:25 INFO - 'download_symbols': 'ondemand', 16:50:25 INFO - 'enable_stylo': False, 16:50:25 INFO - 'enable_webrender': False, 16:50:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:50:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:50:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:50:25 INFO - 'gecko_profile': False, 16:50:25 INFO - 'gecko_profile_interval': 0, 16:50:25 INFO - 'installer_path': 'installer.exe', 16:50:25 INFO - 'log_level': 'info', 16:50:25 INFO - 'log_name': 'talos', 16:50:25 INFO - 'log_to_console': True, 16:50:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:50:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:50:25 INFO - 'opt_config_files': (), 16:50:25 INFO - 'pip_index': False, 16:50:25 INFO - 'suite': 'dromaeojs-e10s', 16:50:25 INFO - 'system_bits': '32', 16:50:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:50:25 INFO - 'title': 'talos-linux64-ix-056', 16:50:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:50:25 INFO - 'use_talos_json': True, 16:50:25 INFO - 'verify': 'False', 16:50:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:50:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:50:25 INFO - 'work_dir': 'build'} 16:50:25 INFO - [mozharness: 2017-10-18 23:50:25.033932Z] Running clobber step. 16:50:25 INFO - Running pre-action listener: _resource_record_pre_action 16:50:25 INFO - Running main action method: clobber 16:50:25 INFO - rmtree: /builds/slave/test/build 16:50:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:50:26 INFO - Running post-action listener: _resource_record_post_action 16:50:26 INFO - [mozharness: 2017-10-18 23:50:26.164751Z] Finished clobber step (success) 16:50:26 INFO - [mozharness: 2017-10-18 23:50:26.164848Z] Running read-buildbot-config step. 16:50:26 INFO - Running pre-action listener: _resource_record_pre_action 16:50:26 INFO - Running main action method: read_buildbot_config 16:50:26 INFO - Using buildbot properties: 16:50:26 INFO - { 16:50:26 INFO - "project": "", 16:50:26 INFO - "product": "firefox", 16:50:26 INFO - "who": "ryanvm@gmail.com", 16:50:26 INFO - "installer_path": "public/build/target.tar.bz2", 16:50:26 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 16:50:26 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 16:50:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 16:50:26 INFO - "stage_platform": "linux64", 16:50:26 INFO - "basedir": "/builds/slave/test", 16:50:26 INFO - "buildnumber": 144, 16:50:26 INFO - "platform": "ubuntu64_hw", 16:50:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:50:26 INFO - "slavebuilddir": "test", 16:50:26 INFO - "taskId": "TBYz56WKSxmZkpL2UVsUcw", 16:50:26 INFO - "branch": "mozilla-beta", 16:50:26 INFO - "script_repo_revision": "production", 16:50:26 INFO - "revision": "1d56d3922195efcabe9807b8ec0ea216397f90b4", 16:50:26 INFO - "slavename": "talos-linux64-ix-056", 16:50:26 INFO - "repo_path": "releases/mozilla-beta" 16:50:26 INFO - } 16:50:26 INFO - Finding installer, test and symbols from parent task. 16:50:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TBYz56WKSxmZkpL2UVsUcw'}, attempt #1 16:50:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TBYz56WKSxmZkpL2UVsUcw'}, attempt #1 16:50:27 INFO - Task dependencies: EF0Tq52EQkanUOofGtjDJg 16:50:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg'}, attempt #1 16:50:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.tar.bz2 16:50:27 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.test_packages.json 16:50:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.crashreporter-symbols.zip 16:50:27 INFO - Running post-action listener: _resource_record_post_action 16:50:27 INFO - [mozharness: 2017-10-18 23:50:27.747440Z] Finished read-buildbot-config step (success) 16:50:27 INFO - [mozharness: 2017-10-18 23:50:27.747657Z] Running download-and-extract step. 16:50:27 INFO - Running pre-action listener: _resource_record_pre_action 16:50:27 INFO - Running main action method: download_and_extract 16:50:27 INFO - mkdir: /builds/slave/test/build/tests 16:50:27 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')]} 16:50:27 INFO - trying https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.test_packages.json 16:50:27 INFO - Downloading https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:50:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:50:28 INFO - Downloaded 989 bytes. 16:50:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:50:28 INFO - Using the following test package requirements: 16:50:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:50:28 INFO - u'common': [u'target.common.tests.zip'], 16:50:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:50:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:50:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:50:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:50:28 INFO - u'mozbase': [u'target.common.tests.zip'], 16:50:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:50:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:50:28 INFO - u'web-platform': [u'target.common.tests.zip', 16:50:28 INFO - u'target.web-platform.tests.tar.gz'], 16:50:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:50:28 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:50:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.common.tests.zip 16:50:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:50:28 INFO - Fetch https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.common.tests.zip into memory 16:50:31 INFO - Content-Length response header: 55175944 16:50:31 INFO - Bytes received: 55175944 16:50:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.talos.tests.zip 16:50:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:50:42 INFO - Fetch https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.talos.tests.zip into memory 16:50:43 INFO - Content-Length response header: 14509251 16:50:43 INFO - Bytes received: 14509251 16:50: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')]} 16:50:43 INFO - trying https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.tar.bz2 16:50:43 INFO - Downloading https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:50:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:50:47 INFO - Downloaded 53517220 bytes. 16:50:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.tar.bz2 16:50:47 INFO - mkdir: /builds/slave/test/properties 16:50:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:50:47 INFO - Writing to file /builds/slave/test/properties/build_url 16:50:47 INFO - Contents: 16:50:47 INFO - build_url:https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.tar.bz2 16:50:47 INFO - Running post-action listener: _resource_record_post_action 16:50:47 INFO - Running post-action listener: find_tests_for_verification 16:50:47 INFO - Running post-action listener: set_extra_try_arguments 16:50:47 INFO - [mozharness: 2017-10-18 23:50:47.047141Z] Finished download-and-extract step (success) 16:50:47 INFO - [mozharness: 2017-10-18 23:50:47.047368Z] Running populate-webroot step. 16:50:47 INFO - Running pre-action listener: _resource_record_pre_action 16:50:47 INFO - Running main action method: populate_webroot 16:50:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:50:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['tresize', 16:50:47 INFO - 'tcanvasmark']}, 16:50:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:50:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['dromaeo_css', 16:50:47 INFO - 'kraken']}, 16:50:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:50:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:50:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:50:47 INFO - 'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:50:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:50:47 INFO - 'tests': ['damp', 'tps']}, 16:50:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:50:47 INFO - 'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['damp', 'tps']}, 16:50:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:50:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['dromaeo_dom']}, 16:50:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:50:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['basic_compositor_video', 16:50:47 INFO - 'glvideo']}, 16:50:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:50:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:50:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:50:47 INFO - 'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['ts_paint_webext', 16:50:47 INFO - 'tp5o_webext']}, 16:50:47 INFO - 'other-e10s': {'tests': ['a11yr', 16:50:47 INFO - 'ts_paint', 16:50:47 INFO - 'tpaint', 16:50:47 INFO - 'sessionrestore', 16:50:47 INFO - 'sessionrestore_many_windows', 16:50:47 INFO - 'sessionrestore_no_auto_restore', 16:50:47 INFO - 'tabpaint']}, 16:50:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['a11yr', 16:50:47 INFO - 'ts_paint', 16:50:47 INFO - 'tpaint', 16:50:47 INFO - 'sessionrestore', 16:50:47 INFO - 'sessionrestore_many_windows', 16:50:47 INFO - 'sessionrestore_no_auto_restore', 16:50:47 INFO - 'tabpaint']}, 16:50:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:50:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:50:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['perf_reftest_singletons']}, 16:50:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['bloom_basic']}, 16:50:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:50:47 INFO - 'tsvgr_opacity', 16:50:47 INFO - 'tart', 16:50:47 INFO - 'tscrollx', 16:50:47 INFO - 'tsvg_static']}, 16:50:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['tsvgx', 16:50:47 INFO - 'tsvgr_opacity', 16:50:47 INFO - 'tart', 16:50:47 INFO - 'tscrollx', 16:50:47 INFO - 'tsvg_static']}, 16:50:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:50:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:50:47 INFO - 'talos_options': ['--disable-stylo'], 16:50:47 INFO - 'tests': ['tp5o']}, 16:50:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:50:47 INFO - 'talos_options': ['--mitmproxy', 16:50:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:50:47 INFO - '--firstNonBlankPaint'], 16:50:47 INFO - 'tests': ['tp6_google', 16:50:47 INFO - 'tp6_youtube', 16:50:47 INFO - 'tp6_amazon', 16:50:47 INFO - 'tp6_facebook']}, 16:50:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:50:47 INFO - 'talos_options': ['--disable-stylo', 16:50:47 INFO - '--mitmproxy', 16:50:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:50:47 INFO - '--firstNonBlankPaint'], 16:50:47 INFO - 'tests': ['tp6_google', 16:50:47 INFO - 'tp6_youtube', 16:50:47 INFO - 'tp6_amazon', 16:50:47 INFO - 'tp6_facebook']}, 16:50:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:50:47 INFO - 'talos_options': ['--stylo-threads=1', 16:50:47 INFO - '--mitmproxy', 16:50:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:50:47 INFO - '--firstNonBlankPaint'], 16:50:47 INFO - 'tests': ['tp6_google', 16:50:47 INFO - 'tp6_youtube', 16:50:47 INFO - 'tp6_amazon', 16:50:47 INFO - 'tp6_facebook']}, 16:50:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:50:47 INFO - 'talos_options': ['--xperf_path', 16:50:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:50:47 INFO - 'tests': ['tp5n']}, 16:50:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:50:47 INFO - 'talos_options': ['--disable-stylo', 16:50:47 INFO - '--xperf_path', 16:50:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:50:47 INFO - 'tests': ['tp5n']}}} 16:50:47 INFO - Running post-action listener: _resource_record_post_action 16:50:47 INFO - [mozharness: 2017-10-18 23:50:47.063591Z] Finished populate-webroot step (success) 16:50:47 INFO - [mozharness: 2017-10-18 23:50:47.063680Z] Running create-virtualenv step. 16:50:47 INFO - Running pre-action listener: _resource_record_pre_action 16:50:47 INFO - Running main action method: create_virtualenv 16:50:47 INFO - Creating virtualenv /builds/slave/test/build/venv 16:50:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:50:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:50:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:50:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:50:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:50:47 INFO - Installing distribute.............................................................................................................................................................................................done. 16:50:47 INFO - Installing pip...............done. 16:50:47 INFO - Return code: 0 16:50:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:50:48 INFO - Reading from file tmpfile_stdout 16:50:48 INFO - Output received: 16:50:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:50:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:50: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')]} 16:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50: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')]} 16:50:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:50: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', 'pip>=1.5'] in /builds/slave/test/build 16:50: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 pip>=1.5 16:50:48 INFO - Using env: {'DISPLAY': ':0', 16:50:48 INFO - 'HOME': '/home/cltbld', 16:50:48 INFO - 'LANG': 'en_US.UTF-8', 16:50:48 INFO - 'LANGUAGE': 'en_US:en', 16:50:48 INFO - 'LOGNAME': 'cltbld', 16:50:48 INFO - 'MAIL': '/var/mail/cltbld', 16:50:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:48 INFO - 'MOZ_NO_REMOTE': '1', 16:50:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:48 INFO - 'NO_EM_RESTART': '1', 16:50:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:48 INFO - 'PWD': '/builds/slave/test', 16:50:48 INFO - 'SHELL': '/bin/bash', 16:50:48 INFO - 'SHLVL': '1', 16:50:48 INFO - 'TERM': 'linux', 16:50:48 INFO - 'TMOUT': '86400', 16:50:48 INFO - 'USER': 'cltbld', 16:50:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:50:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:48 INFO - '_': '/tools/buildbot/bin/python'} 16:50:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:50:49 INFO - Downloading/unpacking pip>=1.5 16:50:49 INFO - Running setup.py egg_info for package pip 16:50:49 INFO - warning: no files found matching 'pip/cacert.pem' 16:50:49 INFO - warning: no files found matching '*.html' under directory 'docs' 16:50:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:50:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:50:49 INFO - Installing collected packages: pip 16:50:49 INFO - Found existing installation: pip 1.1 16:50:49 INFO - Uninstalling pip: 16:50:49 INFO - Successfully uninstalled pip 16:50:49 INFO - Running setup.py install for pip 16:50:49 INFO - warning: no files found matching 'pip/cacert.pem' 16:50:49 INFO - warning: no files found matching '*.html' under directory 'docs' 16:50:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:50:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:50:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:50:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:50:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:50:49 INFO - Successfully installed pip 16:50:49 INFO - Cleaning up... 16:50:49 INFO - Return code: 0 16:50:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:50:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:50:49 INFO - Using env: {'DISPLAY': ':0', 16:50:49 INFO - 'HOME': '/home/cltbld', 16:50:49 INFO - 'LANG': 'en_US.UTF-8', 16:50:49 INFO - 'LANGUAGE': 'en_US:en', 16:50:49 INFO - 'LOGNAME': 'cltbld', 16:50:49 INFO - 'MAIL': '/var/mail/cltbld', 16:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:49 INFO - 'MOZ_NO_REMOTE': '1', 16:50:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:49 INFO - 'NO_EM_RESTART': '1', 16:50:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:49 INFO - 'PWD': '/builds/slave/test', 16:50:49 INFO - 'SHELL': '/bin/bash', 16:50:49 INFO - 'SHLVL': '1', 16:50:49 INFO - 'TERM': 'linux', 16:50:49 INFO - 'TMOUT': '86400', 16:50:49 INFO - 'USER': 'cltbld', 16:50:49 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:49 INFO - '_': '/tools/buildbot/bin/python'} 16:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:49 INFO - Downloading/unpacking psutil>=3.1.1 16:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:50:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:50:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:50:50 INFO - Installing collected packages: psutil 16:50:50 INFO - Running setup.py install for psutil 16:50:50 INFO - building 'psutil._psutil_linux' extension 16:50: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 16:50: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 16:50:50 INFO - building 'psutil._psutil_posix' extension 16:50: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 16:50:51 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 16:50:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:50:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:50:51 INFO - Successfully installed psutil 16:50:51 INFO - Cleaning up... 16:50:51 INFO - Return code: 0 16:50:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:50:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:51 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 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:50:51 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 16:50:51 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 16:50:51 INFO - Using env: {'DISPLAY': ':0', 16:50:51 INFO - 'HOME': '/home/cltbld', 16:50:51 INFO - 'LANG': 'en_US.UTF-8', 16:50:51 INFO - 'LANGUAGE': 'en_US:en', 16:50:51 INFO - 'LOGNAME': 'cltbld', 16:50:51 INFO - 'MAIL': '/var/mail/cltbld', 16:50:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:51 INFO - 'MOZ_NO_REMOTE': '1', 16:50:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:51 INFO - 'NO_EM_RESTART': '1', 16:50:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:51 INFO - 'PWD': '/builds/slave/test', 16:50:51 INFO - 'SHELL': '/bin/bash', 16:50:51 INFO - 'SHLVL': '1', 16:50:51 INFO - 'TERM': 'linux', 16:50:51 INFO - 'TMOUT': '86400', 16:50:51 INFO - 'USER': 'cltbld', 16:50:51 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:50:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:51 INFO - '_': '/tools/buildbot/bin/python'} 16:50:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:50:51 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 16:50:51 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 16:50:51 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 16:50:51 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 16:50:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:50:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:50:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:50:52 INFO - Installing collected packages: mozsystemmonitor 16:50:52 INFO - Running setup.py install for mozsystemmonitor 16:50:52 INFO - Successfully installed mozsystemmonitor 16:50:52 INFO - Cleaning up... 16:50:52 INFO - Return code: 0 16:50:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:50: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')]} 16:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50: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')]} 16:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50: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 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:50: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 16:50: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 16:50:52 INFO - Using env: {'DISPLAY': ':0', 16:50:52 INFO - 'HOME': '/home/cltbld', 16:50:52 INFO - 'LANG': 'en_US.UTF-8', 16:50:52 INFO - 'LANGUAGE': 'en_US:en', 16:50:52 INFO - 'LOGNAME': 'cltbld', 16:50:52 INFO - 'MAIL': '/var/mail/cltbld', 16:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:52 INFO - 'MOZ_NO_REMOTE': '1', 16:50:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:52 INFO - 'NO_EM_RESTART': '1', 16:50:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:52 INFO - 'PWD': '/builds/slave/test', 16:50:52 INFO - 'SHELL': '/bin/bash', 16:50:52 INFO - 'SHLVL': '1', 16:50:52 INFO - 'TERM': 'linux', 16:50:52 INFO - 'TMOUT': '86400', 16:50:52 INFO - 'USER': 'cltbld', 16:50:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:52 INFO - '_': '/tools/buildbot/bin/python'} 16:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:52 INFO - Downloading/unpacking jsonschema==2.5.1 16:50: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 16:50: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 16:50: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 16:50: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 16:50:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:50:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:50: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 16:50: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 16:50: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 16:50: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 16:50:54 INFO - Downloading functools32-3.2.3-2.tar.gz 16:50:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:50:54 INFO - warning: no files found matching '*.txt' 16:50:54 INFO - no previously-included directories found matching 'build' 16:50:54 INFO - no previously-included directories found matching 'dist' 16:50:54 INFO - no previously-included directories found matching '.git*' 16:50:54 INFO - Installing collected packages: jsonschema, functools32 16:50:54 INFO - Running setup.py install for functools32 16:50:54 INFO - warning: no files found matching '*.txt' 16:50:54 INFO - no previously-included directories found matching 'build' 16:50:54 INFO - no previously-included directories found matching 'dist' 16:50:54 INFO - no previously-included directories found matching '.git*' 16:50:54 INFO - Successfully installed jsonschema functools32 16:50:54 INFO - Cleaning up... 16:50:54 INFO - Return code: 0 16:50:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:50: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')]} 16:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50: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')]} 16:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50: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', '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 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:50: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:50: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 functools32==3.2.3-2 16:50:54 INFO - Using env: {'DISPLAY': ':0', 16:50:54 INFO - 'HOME': '/home/cltbld', 16:50:54 INFO - 'LANG': 'en_US.UTF-8', 16:50:54 INFO - 'LANGUAGE': 'en_US:en', 16:50:54 INFO - 'LOGNAME': 'cltbld', 16:50:54 INFO - 'MAIL': '/var/mail/cltbld', 16:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:54 INFO - 'MOZ_NO_REMOTE': '1', 16:50:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:54 INFO - 'NO_EM_RESTART': '1', 16:50:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:54 INFO - 'PWD': '/builds/slave/test', 16:50:54 INFO - 'SHELL': '/bin/bash', 16:50:54 INFO - 'SHLVL': '1', 16:50:54 INFO - 'TERM': 'linux', 16:50:54 INFO - 'TMOUT': '86400', 16:50:54 INFO - 'USER': 'cltbld', 16:50:54 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:54 INFO - '_': '/tools/buildbot/bin/python'} 16:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:50:54 INFO - Cleaning up... 16:50:54 INFO - Return code: 0 16:50:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:50: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')]} 16:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50: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')]} 16:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50: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 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:50: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 16:50: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 16:50:54 INFO - Using env: {'DISPLAY': ':0', 16:50:54 INFO - 'HOME': '/home/cltbld', 16:50:54 INFO - 'LANG': 'en_US.UTF-8', 16:50:54 INFO - 'LANGUAGE': 'en_US:en', 16:50:54 INFO - 'LOGNAME': 'cltbld', 16:50:54 INFO - 'MAIL': '/var/mail/cltbld', 16:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:54 INFO - 'MOZ_NO_REMOTE': '1', 16:50:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:54 INFO - 'NO_EM_RESTART': '1', 16:50:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:54 INFO - 'PWD': '/builds/slave/test', 16:50:54 INFO - 'SHELL': '/bin/bash', 16:50:54 INFO - 'SHLVL': '1', 16:50:54 INFO - 'TERM': 'linux', 16:50:54 INFO - 'TMOUT': '86400', 16:50:54 INFO - 'USER': 'cltbld', 16:50:54 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:54 INFO - '_': '/tools/buildbot/bin/python'} 16:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:54 INFO - Downloading/unpacking blobuploader==1.2.4 16:50: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 16:50: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 16:50: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 16:50: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 16:50:56 INFO - Downloading blobuploader-1.2.4.tar.gz 16:50:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:50:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:50:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:56 INFO - Downloading docopt-0.6.1.tar.gz 16:50:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:50:56 INFO - Installing collected packages: blobuploader, requests, docopt 16:50:56 INFO - Running setup.py install for blobuploader 16:50:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:50:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:50:56 INFO - Running setup.py install for requests 16:50:57 INFO - Running setup.py install for docopt 16:50:57 INFO - Successfully installed blobuploader requests docopt 16:50:57 INFO - Cleaning up... 16:50:57 INFO - Return code: 0 16:50:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:50:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:57 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 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:50:57 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 16:50:57 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 16:50:57 INFO - Using env: {'DISPLAY': ':0', 16:50:57 INFO - 'HOME': '/home/cltbld', 16:50:57 INFO - 'LANG': 'en_US.UTF-8', 16:50:57 INFO - 'LANGUAGE': 'en_US:en', 16:50:57 INFO - 'LOGNAME': 'cltbld', 16:50:57 INFO - 'MAIL': '/var/mail/cltbld', 16:50:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:57 INFO - 'MOZ_NO_REMOTE': '1', 16:50:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:57 INFO - 'NO_EM_RESTART': '1', 16:50:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:57 INFO - 'PWD': '/builds/slave/test', 16:50:57 INFO - 'SHELL': '/bin/bash', 16:50:57 INFO - 'SHLVL': '1', 16:50:57 INFO - 'TERM': 'linux', 16:50:57 INFO - 'TMOUT': '86400', 16:50:57 INFO - 'USER': 'cltbld', 16:50:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:50:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:57 INFO - '_': '/tools/buildbot/bin/python'} 16:50:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:50:57 INFO - Running setup.py (path:/tmp/pip-aZdSRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:50:57 INFO - Running setup.py (path:/tmp/pip-l_86UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:50:57 INFO - Running setup.py (path:/tmp/pip-hyHwS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:50:57 INFO - Running setup.py (path:/tmp/pip-aHuBdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:50:57 INFO - Running setup.py (path:/tmp/pip-isq5UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:50:57 INFO - Running setup.py (path:/tmp/pip-PduL4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:50:57 INFO - Running setup.py (path:/tmp/pip-LEbwU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:50:58 INFO - Running setup.py (path:/tmp/pip-8AaV9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:50:58 INFO - Running setup.py (path:/tmp/pip-Nyr42B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:50:58 INFO - Running setup.py (path:/tmp/pip-CPyLr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:50:58 INFO - Running setup.py (path:/tmp/pip-CGpl2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:50:58 INFO - Running setup.py (path:/tmp/pip-SkGVCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:50:58 INFO - Running setup.py (path:/tmp/pip-7_xm5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:50:58 INFO - Running setup.py (path:/tmp/pip-1SFrWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:58 INFO - Running setup.py (path:/tmp/pip-M3mo8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:50:58 INFO - Running setup.py (path:/tmp/pip-QS4ise-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:50:58 INFO - Running setup.py (path:/tmp/pip-bJ_m_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:50:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:50:58 INFO - Running setup.py install for manifestparser 16:50:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:50:58 INFO - Running setup.py install for mozcrash 16:50:58 INFO - Running setup.py install for mozdebug 16:50:59 INFO - Running setup.py install for mozdevice 16:50:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:50:59 INFO - Running setup.py install for mozfile 16:50:59 INFO - Running setup.py install for mozhttpd 16:50:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:50:59 INFO - Running setup.py install for mozinfo 16:50:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:50:59 INFO - Running setup.py install for mozInstall 16:50:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:50:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:50:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:50:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:50:59 INFO - Running setup.py install for mozleak 16:50:59 INFO - Running setup.py install for mozlog 16:50:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:50:59 INFO - Running setup.py install for moznetwork 16:50:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:50:59 INFO - Running setup.py install for mozprocess 16:50:59 INFO - Running setup.py install for mozprofile 16:51:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:51:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:51:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:51:00 INFO - Running setup.py install for mozrunner 16:51:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:51:00 INFO - Running setup.py install for mozscreenshot 16:51:00 INFO - Running setup.py install for moztest 16:51:00 INFO - Running setup.py install for mozversion 16:51:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:51:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:51:00 INFO - Cleaning up... 16:51:00 INFO - Return code: 0 16:51:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:51: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')]} 16:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51: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')]} 16:51:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51: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 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:51: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 16:51: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 16:51:00 INFO - Using env: {'DISPLAY': ':0', 16:51:00 INFO - 'HOME': '/home/cltbld', 16:51:00 INFO - 'LANG': 'en_US.UTF-8', 16:51:00 INFO - 'LANGUAGE': 'en_US:en', 16:51:00 INFO - 'LOGNAME': 'cltbld', 16:51:00 INFO - 'MAIL': '/var/mail/cltbld', 16:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:00 INFO - 'MOZ_NO_REMOTE': '1', 16:51:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:00 INFO - 'NO_EM_RESTART': '1', 16:51:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:00 INFO - 'PWD': '/builds/slave/test', 16:51:00 INFO - 'SHELL': '/bin/bash', 16:51:00 INFO - 'SHLVL': '1', 16:51:00 INFO - 'TERM': 'linux', 16:51:00 INFO - 'TMOUT': '86400', 16:51:00 INFO - 'USER': 'cltbld', 16:51:00 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:00 INFO - '_': '/tools/buildbot/bin/python'} 16:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:51:00 INFO - Running setup.py (path:/tmp/pip-nsSpMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:51: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)) 16:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:51:00 INFO - Running setup.py (path:/tmp/pip-zfIm3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:51: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)) 16:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:51:00 INFO - Running setup.py (path:/tmp/pip-fO8szR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:51: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)) 16:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:51:00 INFO - Running setup.py (path:/tmp/pip-tMELtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:51: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)) 16:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:51:01 INFO - Running setup.py (path:/tmp/pip-4SEzMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:51:01 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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:51:01 INFO - Running setup.py (path:/tmp/pip-QsASvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:51:01 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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:51:01 INFO - Running setup.py (path:/tmp/pip-d7qxgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:51:01 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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:51:01 INFO - Running setup.py (path:/tmp/pip-sukfyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:51:01 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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:51:01 INFO - Running setup.py (path:/tmp/pip-uf1v_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:51:01 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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:51:01 INFO - Running setup.py (path:/tmp/pip-eTOfir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:51: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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:51:01 INFO - Running setup.py (path:/tmp/pip-yIBxdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:51: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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:51:01 INFO - Running setup.py (path:/tmp/pip-nZSV0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:51: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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:51:01 INFO - Running setup.py (path:/tmp/pip-uHBtKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:51: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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:51:01 INFO - Running setup.py (path:/tmp/pip-tS2pSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:51: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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:51:01 INFO - Running setup.py (path:/tmp/pip-wTtfr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:51: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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:51:01 INFO - Running setup.py (path:/tmp/pip-e0_BlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:51: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)) 16:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:51:01 INFO - Running setup.py (path:/tmp/pip-5ZqZMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:51: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 16:51: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 16:51: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 16:51: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 16:51:03 INFO - Downloading blessings-1.6.tar.gz 16:51:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:51:03 INFO - Installing collected packages: blessings 16:51:03 INFO - Running setup.py install for blessings 16:51:03 INFO - Successfully installed blessings 16:51:03 INFO - Cleaning up... 16:51:03 INFO - Return code: 0 16:51:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:51:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:51:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:51:03 INFO - Reading from file tmpfile_stdout 16:51:03 INFO - Current package versions: 16:51:03 INFO - argparse == 1.2.1 16:51:03 INFO - blessings == 1.6 16:51:03 INFO - blobuploader == 1.2.4 16:51:03 INFO - docopt == 0.6.1 16:51:03 INFO - functools32 == 3.2.3-2 16:51:03 INFO - jsonschema == 2.5.1 16:51:03 INFO - manifestparser == 1.1 16:51:03 INFO - mozInstall == 1.13 16:51:03 INFO - mozcrash == 1.0 16:51:03 INFO - mozdebug == 0.1 16:51:03 INFO - mozdevice == 0.51 16:51:03 INFO - mozfile == 1.2 16:51:03 INFO - mozhttpd == 0.7 16:51:03 INFO - mozinfo == 0.10 16:51:03 INFO - mozleak == 0.1 16:51:03 INFO - mozlog == 3.5 16:51:03 INFO - moznetwork == 0.27 16:51:03 INFO - mozprocess == 0.25 16:51:03 INFO - mozprofile == 0.28 16:51:03 INFO - mozrunner == 6.13 16:51:03 INFO - mozscreenshot == 0.1 16:51:03 INFO - mozsystemmonitor == 0.3 16:51:03 INFO - moztest == 0.8 16:51:03 INFO - mozversion == 1.4 16:51:03 INFO - psutil == 3.1.1 16:51:03 INFO - requests == 1.2.3 16:51:03 INFO - wsgiref == 0.1.2 16:51:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:51: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')]} 16:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51: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')]} 16:51:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51: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 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:51: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 16:51: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 16:51:03 INFO - Using env: {'DISPLAY': ':0', 16:51:03 INFO - 'HOME': '/home/cltbld', 16:51:03 INFO - 'LANG': 'en_US.UTF-8', 16:51:03 INFO - 'LANGUAGE': 'en_US:en', 16:51:03 INFO - 'LOGNAME': 'cltbld', 16:51:03 INFO - 'MAIL': '/var/mail/cltbld', 16:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:03 INFO - 'MOZ_NO_REMOTE': '1', 16:51:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:03 INFO - 'NO_EM_RESTART': '1', 16:51:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:03 INFO - 'PWD': '/builds/slave/test', 16:51:03 INFO - 'SHELL': '/bin/bash', 16:51:03 INFO - 'SHLVL': '1', 16:51:03 INFO - 'TERM': 'linux', 16:51:03 INFO - 'TMOUT': '86400', 16:51:03 INFO - 'USER': 'cltbld', 16:51:03 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:03 INFO - '_': '/tools/buildbot/bin/python'} 16:51:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51: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)) 16:51:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:51: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 16:51: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 16:51: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 16:51: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 16:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:51: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)) 16:51: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)) 16:51:04 INFO - Installing collected packages: simplejson 16:51:04 INFO - Running setup.py install for simplejson 16:51:04 INFO - building 'simplejson._speedups' extension 16:51: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 16:51: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 16:51:05 INFO - Successfully installed simplejson 16:51:05 INFO - Cleaning up... 16:51:05 INFO - Return code: 0 16:51:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:51: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')]} 16:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51: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')]} 16:51:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51: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 0x27a31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b0ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, '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': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', '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 16:51: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 16:51: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 16:51:05 INFO - Using env: {'DISPLAY': ':0', 16:51:05 INFO - 'HOME': '/home/cltbld', 16:51:05 INFO - 'LANG': 'en_US.UTF-8', 16:51:05 INFO - 'LANGUAGE': 'en_US:en', 16:51:05 INFO - 'LOGNAME': 'cltbld', 16:51:05 INFO - 'MAIL': '/var/mail/cltbld', 16:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:05 INFO - 'MOZ_NO_REMOTE': '1', 16:51:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:05 INFO - 'NO_EM_RESTART': '1', 16:51:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:05 INFO - 'PWD': '/builds/slave/test', 16:51:05 INFO - 'SHELL': '/bin/bash', 16:51:05 INFO - 'SHLVL': '1', 16:51:05 INFO - 'TERM': 'linux', 16:51:05 INFO - 'TMOUT': '86400', 16:51:05 INFO - 'USER': 'cltbld', 16:51:05 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:05 INFO - '_': '/tools/buildbot/bin/python'} 16:51:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:51:06 INFO - Cleaning up... 16:51:06 INFO - Return code: 0 16:51:06 INFO - Running post-action listener: _resource_record_post_action 16:51:06 INFO - Running post-action listener: _start_resource_monitoring 16:51:06 INFO - Starting resource monitoring. 16:51:06 INFO - [mozharness: 2017-10-18 23:51:06.230577Z] Finished create-virtualenv step (success) 16:51:06 INFO - [mozharness: 2017-10-18 23:51:06.231181Z] Running install step. 16:51:06 INFO - Running pre-action listener: _resource_record_pre_action 16:51:06 INFO - Running main action method: install 16:51:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:51:06 INFO - Reading from file tmpfile_stdout 16:51:06 INFO - Detecting whether we're running mozinstall >=1.0... 16:51:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:51:06 INFO - Reading from file tmpfile_stdout 16:51:06 INFO - Output received: 16:51:06 INFO - Usage: mozinstall [options] installer 16:51:06 INFO - Options: 16:51:06 INFO - -h, --help show this help message and exit 16:51:06 INFO - -d DEST, --destination=DEST 16:51:06 INFO - Directory to install application into. [default: 16:51:06 INFO - "/builds/slave/test"] 16:51:06 INFO - --app=APP Application being installed. [default: firefox] 16:51:06 INFO - mkdir: /builds/slave/test/build/application 16:51: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'] 16:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:51:21 INFO - Reading from file tmpfile_stdout 16:51:21 INFO - Output received: 16:51:21 INFO - /builds/slave/test/build/application/firefox/firefox 16:51:21 INFO - Running post-action listener: _resource_record_post_action 16:51:21 INFO - [mozharness: 2017-10-18 23:51:21.530314Z] Finished install step (success) 16:51:21 INFO - [mozharness: 2017-10-18 23:51:21.530562Z] Running setup-mitmproxy step. 16:51:21 INFO - Running pre-action listener: _resource_record_pre_action 16:51:21 INFO - Running main action method: setup_mitmproxy 16:51:21 INFO - Skipping: mitmproxy is not required 16:51:21 INFO - Running post-action listener: _resource_record_post_action 16:51:21 INFO - [mozharness: 2017-10-18 23:51:21.531677Z] Finished setup-mitmproxy step (success) 16:51:21 INFO - [mozharness: 2017-10-18 23:51:21.531894Z] Running run-tests step. 16:51:21 INFO - Running pre-action listener: _resource_record_pre_action 16:51:21 INFO - Running pre-action listener: _set_gcov_prefix 16:51:21 INFO - Running main action method: run_tests 16:51:21 WARNING - Try message not found. 16:51:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:51:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:51:21 INFO - Python 2.7.3 16:51:21 INFO - Return code: 0 16:51:21 INFO - grabbing minidump binary from tooltool 16:51: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')]} 16:51: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 0x2b12090>, '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 0x2b12d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b0cf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:51: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 16:51: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 16:51: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 16:51:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:51:21 INFO - Return code: 0 16:51:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:51:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:51:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:51:21 INFO - ENV: RUST_BACKTRACE is now full 16:51:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:51:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:51: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/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 16:51: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/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 16:51:21 INFO - Using env: {'DISPLAY': ':0', 16:51:21 INFO - 'HOME': '/home/cltbld', 16:51:21 INFO - 'LANG': 'en_US.UTF-8', 16:51:21 INFO - 'LANGUAGE': 'en_US:en', 16:51:21 INFO - 'LOGNAME': 'cltbld', 16:51:21 INFO - 'MAIL': '/var/mail/cltbld', 16:51:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:51:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:21 INFO - 'MOZ_NO_REMOTE': '1', 16:51:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:51:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:21 INFO - 'NO_EM_RESTART': '1', 16:51:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:21 INFO - 'PWD': '/builds/slave/test', 16:51:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:51:21 INFO - 'RUST_BACKTRACE': 'full', 16:51:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:51:21 INFO - 'SHELL': '/bin/bash', 16:51:21 INFO - 'SHLVL': '1', 16:51:21 INFO - 'TERM': 'linux', 16:51:21 INFO - 'TMOUT': '86400', 16:51:21 INFO - 'USER': 'cltbld', 16:51:21 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:21 INFO - '_': '/tools/buildbot/bin/python'} 16:51: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/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 16:51:21 INFO - mozversion application_buildid: 20171018223515 16:51:21 INFO - mozversion application_changeset: 1d56d3922195efcabe9807b8ec0ea216397f90b4 16:51:21 INFO - mozversion application_display_name: Firefox 16:51:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:51:21 INFO - mozversion application_name: Firefox 16:51:21 INFO - mozversion application_remotingname: firefox 16:51:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:51:21 INFO - mozversion application_vendor: Mozilla 16:51:21 INFO - mozversion application_version: 57.0 16:51:21 INFO - mozversion platform_buildid: 20171018223515 16:51:21 INFO - mozversion platform_changeset: 1d56d3922195efcabe9807b8ec0ea216397f90b4 16:51:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:51:21 INFO - mozversion platform_version: 57.0 16:51:21 INFO - using testdate: 1508370681 16:51:21 INFO - actual date: 1508370681 16:51:21 INFO - starting webserver on 'localhost:57306' 16:51:21 INFO - SUITE-START | Running 2 tests 16:51:21 INFO - TEST-START | dromaeo_css 16:51:21 INFO - Initialising browser for dromaeo_css test... 16:51:21 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57306/getInfo.html -profile /tmp/tmpeD_slH/profile 16:51:21 INFO - TEST-INFO | started process 6196 (/builds/slave/test/build/application/firefox/firefox http://localhost:57306/getInfo.html) 16:51:27 INFO - TEST-INFO | 6196: exit 0 16:51:27 INFO - Browser initialized. 16:51:27 INFO - Running cycle 1/1 for dromaeo_css test... 16:51:27 INFO - Using env: {'DISPLAY': ':0', 16:51:27 INFO - 'HOME': '/home/cltbld', 16:51:27 INFO - 'JSGC_DISABLE_POISONING': '1', 16:51:27 INFO - 'LANG': 'en_US.UTF-8', 16:51:27 INFO - 'LANGUAGE': 'en_US:en', 16:51:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:51:27 INFO - 'LOGNAME': 'cltbld', 16:51:27 INFO - 'MAIL': '/var/mail/cltbld', 16:51:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:51:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:51:27 INFO - 'MOZ_CRASHREPORTER': '1', 16:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:51:27 INFO - 'MOZ_NO_REMOTE': '1', 16:51:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:51:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:27 INFO - 'NO_EM_RESTART': '1', 16:51:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:27 INFO - 'PWD': '/builds/slave/test', 16:51:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:51:27 INFO - 'RUST_BACKTRACE': 'full', 16:51:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:51:27 INFO - 'SHELL': '/bin/bash', 16:51:27 INFO - 'SHLVL': '1', 16:51:27 INFO - 'TERM': 'linux', 16:51:27 INFO - 'TMOUT': '86400', 16:51:27 INFO - 'USER': 'cltbld', 16:51:27 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 16:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:27 INFO - '_': '/tools/buildbot/bin/python'} 16:51:27 INFO - TEST-INFO | started process 6429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeD_slH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:28 INFO - PID 6429 | 16:51:28 INFO - PID 6429 | (/builds/slave/test/build/application/firefox/firefox:6478): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:51:28 INFO - PID 6429 | 16:51:38 INFO - PID 6429 | RSS: Main: 143851520 16:51:38 INFO - PID 6429 | 16:54:35 INFO - PID 6429 | Cycle 1(1): loaded http://localhost:57306/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57306/tests/dromaeo/cssquery-ext.html) 16:54:37 INFO - PID 6429 | RSS: Main: 146276352 16:54:37 INFO - PID 6429 | 16:57:38 INFO - PID 6429 | Cycle 1(1): loaded http://localhost:57306/tests/dromaeo/cssquery-ext.html (next: http://localhost:57306/tests/dromaeo/cssquery-jquery.html) 16:57:39 INFO - PID 6429 | RSS: Main: 144973824 16:57:39 INFO - PID 6429 | 17:00:40 INFO - PID 6429 | Cycle 1(1): loaded http://localhost:57306/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57306/tests/dromaeo/cssquery-mootools.html) 17:00:41 INFO - PID 6429 | RSS: Main: 146694144 17:00:41 INFO - PID 6429 | 17:03:42 INFO - PID 6429 | Cycle 1(1): loaded http://localhost:57306/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57306/tests/dromaeo/cssquery-prototype.html) 17:03:43 INFO - PID 6429 | RSS: Main: 146804736 17:03:43 INFO - PID 6429 | 17:06:46 INFO - PID 6429 | Cycle 1(1): loaded http://localhost:57306/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57306/tests/dromaeo/cssquery-yui.html) 17:06:47 INFO - PID 6429 | RSS: Main: 146108416 17:06:47 INFO - PID 6429 | 17:09:48 INFO - PID 6429 | Cycle 1(1): loaded http://localhost:57306/tests/dromaeo/cssquery-yui.html (next: http://localhost:57306/tests/dromaeo/cssquery-dojo.html) 17:09:48 INFO - PID 6429 | RSS: Main: 150011904 17:09:48 INFO - PID 6429 | 17:09:48 INFO - PID 6429 | __start_tp_report 17:09:48 INFO - PID 6429 | _x_x_mozilla_page_load 17:09:48 INFO - PID 6429 | _x_x_mozilla_page_load_details 17:09:48 INFO - PID 6429 | |i|pagename|runs| 17:09:48 INFO - PID 6429 | |0;dojo.html;4010.1378203686204;4036.616521430465;4047.696422768291;4050.5139383274013;4083.5916408359167;6353.80938571843;6467.70895309711;6485.643289619075;6536.411722944934;6589.802305930818;6496.252930912947;6517.80446586602;6523;6591.736330546784;6597.406233438989;5798.246227990356;5896.5282777377815;5901.616394934337;5910.0543912973935;5941.465268125868;5834.066549352105;5873.207117039187;5901.675407852566;6014.278286605613;6032.487238584726;5845.357010728838;5887.205227294325;5908.1433192187105;5921.319048309435;5965.582409231356;5861.677607731573;5869.50109240715;5887.205227294303;5927.762889484416;5947.375525569818;5787.397461981974;5885.79399721012;5900.055991041413;5909.084091965752;5909.3204281507315;6297.464715499188;6352.777652782131;6382.61704297739;6454.354564543508;6464.676766161673;7054.0829692140205;7056.788296351117;7098.041764361797;7117.110361204849;7165.390941769956;7033.859322813515;7110.60891650959;7111.8222044448785;7120.643967801641;7238.348548630596;6345.650989195592;6465.19185101867;6465.224173099258;6482.611043337368;6483.384078512534;7206.675699593531;7225.710971561079;7243.529170603894;7243.89134162988;7249.166345870292;6932.514723969273;6965.547239429421;6989.196242432084;6989.650517474106;7010.894836577456;7020.052292940388;7039.19049309336;7058.6823592938445;7115.324044215791;7123.857522849514;6848.6918088686125;6932.826679333057;6948.305169483112;7007.894881576781;7015.719371225196;6960.199577048603;7078.539894906815;7133.357997780225;7146.2496437874315;7151.392131668762;5216.26972223889;5269.762860671279;5286.048511268009;5290.365156181283;5309.734513274321;4751.168545504592;4793.808247670124;4797.496262892416;4808.182608956416;4812.35033270504;6463.903041454382;6494.902576461357;6522.34776522344;6617.371349721769;6687.331266873273;6984.511084224056;7025.262347453546;7075.92924070763;7081.89377159343;7164.641767911688;6430.967845160744;6510.869782604322;6517.087607734921;6601.801945941629;6605.306442823435;5891.116332550152;5896.587238893322;5918.319393269743;5957.404259574095;5965.970170149209;7332.340089391981;7349.375303099189;7371.078615173104;7430.368418684472;7456.7390141344795;6977.232504424611;7016.19313778922;7030.3321184487395;7049.259827718016;7078.823029424203;7175.461840362098;7302.415806735342;7305.5981920994855;7326.96336518314;7335.486515943832;6901.275366086689;6979.965100174467;6996.090508233897;7026.191987921455;7063.682134304071;7065.081539400051;7102.360787529147;7147.035150254857;7148.320909513588;7150.463715221275;6636.767713130017;6774;6774.8645027100165;6857.759978400732;6895.6552172390175;6697.3637504437;6710.194776626836;6748.089007984056;6769.627670478171;6824.829379265558;6665;6721.798346049626;6732.360425759545;6735.955926831349;6877.552959057645;6829.4877884157895;6841;6841.350071743176;6911.239763626098;6945.236024037453;6481.287058423664;6558.868822623616;6601.471882249312;6684.097646817618;6687.933120668731 17:09:48 INFO - PID 6429 | |1;ext.html;833.4540875726401;868.2055918834267;901.7384958362076;905.2034209895294;905.6920646980029;44606.33090503641;45207.32189017159;45240.54759452413;45554.316685249745;45570.54429455708;42789.572104278945;42924;42956.78521607396;43162.35256471155;43425;7986.121526632066;8122.390820688443;8185.140560241178;8190.303824174952;8202.630881610341;7959.1244963054305;7996.360291176721;8017.879731804028;8034.678612855479;8039.718609848656;7952;8748.606312715943;8857;8872.467651940871;8888.777780555472;8657.610407531676;8728.214460698537;8736.039035706068;8747.125287471266;8774;8430.45202061864;8643.697470588499;8651.264642505395;8669.523176225304;8730.126987301282;40678.38982415266;40777.38833917493;40898.1820363594;40915;41151.58848411508;3813.466114743938;3816;3816.0459885028745;3818.6563209311294;3819.751716138442;33042.17394565131;33169;33182;33438.498422523684;33459.330813383596;5751.798687045975;5751.913721294184;5773.191753154561;5811.883762324772;5836.562257830679;225845;229327;230654;231836;246899.76550117315;51887.74056129771;51983;52179.217311739594;52226.477735222164;52240.73879630578;53200.46799531955;53207.743380529464;53243;53336.46663533393;53385.466145338825;18188.90905545464;18375;18394.908025460056;18521.814781852278;18735.344262951003;15474.613134671723;15476.76784848228;15478.613034674223;15493.457728979432;15517.301721422564;14345.497907573394;14437;14472.421103155755;14532.418703251751;14658.926705366404;5813.651180929073;5836.533077353802;5852.122181672698;5961.4038596140035;5985.40145985398;1111.8999290063932;1141.406468636321;1142.3317359344876;1143.3940011793763;1143.988560114405;25899.352516186867;25937.481250374887;26113.477730445284;26161.73838261631;26372.076977305704;1239.6219153158374;1245.9065570082098;1263.7977923532374;1264.3930913161735;1270.0665011216747;13807.309634518233;13869.029167958348;13894.791578126336;13950.930245348709;14016.579502614937;309624;310555;311217;311604;313299;57255;57318;57484.425155748744;57994.42005579975;58011.41988580144;1514.3412615512289;1545.95362139136;1594.0435738556773;1605.2134454117631;1622.8377162283914;26029.739702602732;26079.217623471326;26142;26333.4733305329;26533;40784.184316312916;41210.38184427176;41418.1716365665;41538.792306038275;41549.37675934803;250077;250951;251051;252798;253351;33024.33951320912;33602.15994600154;35120.12199695028;35223;35923;46779.53220467752;47030.76484617555;47168.764156179;47432.76283618698;47784.5221547794;35108;35233.29533409266;35347.64652353546;35464.29071418506;35552;34350;34713.30573388569;34789.43447544917;34891.47662785111;35408.29183416369;40265.194696105325;40338.394924075576;40455.393169101895;41135;41264.381034283906;33228;33671.4949275766;33680.66319336873;33735.662643374235;34132.317353653285;25382;25577.360565986;26309.86845065839;26522.336941576614;26902.865485672446 17:09:48 INFO - PID 6429 | |2;jquery.html;636.6803467339852;642.1628278622427;648.0523957256119;651.6079442612383;655.6070343247144;23647.290581282567;24280.39299017523;24524.50950980979;24543.75456245437;24555.75444245557;26414;26447;26674.73325266747;26681.733182668217;26733.331666708295;6513.250976137734;6687.832804179886;6732.596044237337;6767.289434609369;6835.624040677748;6695.933040669604;6757.560758550703;6821.4542836572955;6824.317568243161;6851.520393572452;6580.967095164518;6639.435647969928;6775.32246775321;6806.183258009046;6857.27998560149;6861.4510839133;6907.205671347809;6919.411849992756;6923.411510021654;6929.896051559231;6446.580972236789;6465.4827613790785;6471.061696054046;6480.287168411471;6482.351764823527;29836.254093647614;31128;31472.527912081336;31787.84106079478;31809.363812723615;2286.1312701173615;2289.3933107726466;2353.1411034972284;2353.8587684738973;2359.4691194481275;20635;21173.15307387718;21364.359069227947;21398.144074237167;21411;2556.872156392173;2608.2044976282227;2656.827306225089;2659.0693257359962;2673.906413275526;140935;148747;164608;170913;171203.99999999875;16602.50192494227;16610.335586576643;16739.748903766453;16911.323547058226;17103;27150.86424567904;27265.1820445383;27372.31569210746;27397.863010684818;27486.17541473759;10888.29226100301;11033;11283.435828208556;11321.547138114383;11356.94321528387;9976.251781116445;10115.140213081824;10136.695899122891;10193.949030254802;10197.541110650038;10203.336783109075;10340.638077667245;10353.948230258951;10416.843747343795;10625;2942.5880376747273;2973.152651494314;2998.445287621797;3002.022320425445;3031.6968303169506;1053.6048981631889;1056.5984925728328;1080.1250986700798;1083.0360978728938;1092.9672109836717;20845.166193352095;21585.67621485679;21947.890260548596;21948.231811886508;21953.231636892633;1131.1572878205789;1134.6879608107672;1153.3887039868882;1154.2093665838927;1154.440096553171;11967.40162991847;12083.275003499819;12085.335306558049;12099.637010889688;12117.454714537867;655813;674215;677149;679234;689974;133786;138534.6146538542;138881.3055934734;139440;140860.29569852084;1146.7075895646556;1211.0432758120985;1255.0587059705222;1300.1029289790015;1344.7176093020576;22888.31335059951;23448.765512344657;23465.76534234704;23546;23567.764322356557;67093.66453167703;67174;68191.65904170448;69542;70435.64782176261;88003.55998219967;89269.55365223132;89539.10460895568;89767.55116224378;90348.5482572583;21716.67424988595;22226.55546889021;22429.10283588638;22467.550648987257;22552;24541.75458245466;24874.378140546632;25138.874305628357;25257.368565786004;25305.74694253034;20270.695939560625;20559.280425185047;20716.68924966157;20742.481437964172;20751.58496830025;17657;17658.91170544139;17998.280068797103;18386.908065459585;18640.906795465937;20634;20822.687659684812;20860;21051.8947405262;21061.052252648362;22045.559088817812;22084.88957555202;22104.557908841412;22144.446388840406;22191.22330718417;18082;18211.362602308855;19822.207111715947;20026.899865501164;20177.09203085835 17:09:48 INFO - PID 6429 | |3;mootools.html;1444.5666300109965;1498.35570704597;1506.5630967019565;1506.676064646101;1526.8549487798662;17162.48512544624;17662.470125896227;17828.91085544572;17926.82073179271;17928.28286868527;17402.39091631793;17410.303587856502;17460.56348591286;17563.209655565497;17614.735778963324;3051.6643169251365;3080.8767649294;3145.213696575856;3145.6067991501063;3148.6064241969752;2783.582462630601;2837.6878543360212;2847.985760071207;2852.5435930251165;2855.957160642592;3095.659477457489;3140.8115513069174;3259.820709860957;3260.315333779909;3264.689854463822;3116.688331166888;3123.672014438474;3137.30979184579;3147.0244224290545;3149.275666596684;2896.478633845907;2994.326276587772;3018.894338698157;3058.3424563718686;3098.783085184038;17319;17389.739153912702;17482.213300401385;17490.91254543719;17491.912540437344;8773.473591584525;9090.545472726404;9121.726348209564;9185.908140918573;9198.540072996324;5457.2359869618285;5689.9146512802345;5694.829155125353;5774.971125144348;5823.53411727065;2114.587655407196;2162.4918144236094;2179.2917301876796;2221.9000144993515;2231.263682984611;31751.52372714411;31807.84096079505;31846.363072738648;31904;32028.679713203037;7512.812179695442;7525.58609276495;7593.088829340514;7633.198514156044;7779.611019449005;21213.575728485343;21331.253406130712;21366;21376;21377.57244855094;8670.13298670128;9018.278537716968;9085.909140908507;9112.635494580276;9133.543322833832;8261.256486916087;8372.413931024892;8429.283510901641;8453.323734101257;8496.532690701946;6150.661713605795;6225;6285.182926219562;6337.524685648595;6401.263854656681;1393.470481217131;1406.4585134723204;1658.4112640012863;1730.4981555348809;1734.6357264974245;1022.3814592171777;1030.160419258302;1032.0814475117152;1036.9014943580348;1057.127869507644;9348.1119293667;9372.67195648149;9381.249500039981;9438.575264113131;9464.1009104135;1087.5106202209038;1112.1325366214367;1117.8826223246604;1126.0878688262533;1139.2424038014792;5634.32388113429;5713.74430271177;5766.481016708516;5819.15622234782;5866.208061911672;26025.869870650524;26370.604440933403;26607.33481662935;26711.332216694736;26791.464170716477;26071.739282607312;26316.605250921253;26339.34151646186;26451.867740661175;26808.59787103195;13122.868771312165;13162.60512184636;13187.868121318856;13216.801747973788;13308.600741977756;9906.851397229191;9936.93923098836;10066.144377727975;10137.898621013695;10249.897501025192;21722.891385543604;22014.00936957809;22245.666315004964;22582.20962266313;23154;22580.20969266068;22946.655800162676;23161.536769264858;23264;23403.180888668816;12171.513139474677;12309.44607492683;12357.073219508393;12372;12375.62873113808;15555.222238888236;15593.532194034196;15661.21693915277;15698.764518532003;15819;12920.741585168433;12924.224546527239;12985.610431687064;13035.891949184426;13057.347132643139;12573.245605263714;12796.680082998;12873.35633218354;12886.677833053873;13041.087123901236;10997.890021100007;11235.325880447199;11331.660050198507;11344.602938897098;11353.716157095809;8430.578471076544;8508.872366914378;8520.360972926932;8543.316534677087;8608.182222688834;8932.776680583038;9032.503212323385;9037.277017838689;9045.638174472948;9060.864087038568 17:09:48 INFO - PID 6429 | |4;prototype.html;563.7434967089972;572.9286234741035;583.5681595619243;596.0909612840419;599.110321173058;6193.349698281683;6299.77950771723;6337.841553961153;6393.968030159855;6477.643729594871;6178.474829639486;6182.814515564529;6211.254649442062;6282.277538083122;6364.236291645009;5026.271190677349;5097.9235311470175;5210.635255532115;5234.8691282717855;5241.528262456378;5130.56390206831;5146.356705411824;5149.562287205592;5171.215930503989;5172.27588137661;5080.974595127019;5102.948970510284;5109.105906466372;5123.5388815006645;5125.333706618153;5094.796208151651;5112.284280200774;5124.205748109049;5154.664946778447;5178.663386879841;4712.95287047132;4719.504452032522;4724.126036683225;4732.2428411454;4738.47876733559;6246.125542424064;6279.466245369149;6288.52836037299;6346.619202847844;6353.301136874987;5996.700164991733;6006.429389208019;6010;6033.456988870978;6066.150738896558;6778.864422711567;6794.742972550094;6804.727810887559;6818.863622727517;6885.2770459101575;4889.237580766855;5131.640785145041;5137.922931156036;5182.948170518284;5193.480651934814;6732.966335168342;6847.281035491302;6884.690188941559;7006.684699188549;7071.36357727807;5286.444923283077;5292.126799078186;5322.946770532322;5331.893362132736;5341.465853414705;5288.413475957197;5343.759530821122;5371.731413429391;5377.919331210035;5410.242566040757;5920.763169473173;5935.465808077293;5998.130271110665;5998.850028749316;6012.549058820606;6751.459883209332;6770.289119642465;6790.864182716318;6800.217974932946;6809.829754256084;1842.4196378140844;1846.6306738652324;1864.9160787764583;1869.3831035758167;1874.728164416152;1286.4725462560305;1305.3799445263614;1328.4486937920656;1338.1569611145112;1341.2891167681144;6270.7491700331475;6287.779927702509;6288.213973253343;6295.937040629627;6368.331325210879;6682.198136223587;6788.524803263823;6793.607310501336;6812.420944219797;6862.348076932683;6276.905846412307;6313.337099604475;6335.778247761398;6359.491240700733;6404.615723056631;3553.8578456861437;3594.5506811648543;3619.8914032579064;3625.963740362615;3657.762245454037;7005.302958360966;7008.584591707192;7066.823329416703;7078.761216787145;7128.180259270147;6968.581885086911;6996.825079373057;7001.68944248407;7056.847661463303;7067.056559446193;7026.789196324118;7077.858442831114;7094.751683691046;7102.254263302486;7111.928880711127;6645.9003114954285;6726.293739157318;6743.797686069426;6806.604646047647;6873.965630171817;6759.904429626831;6760.560563563353;6764.289749576224;6776.661166941534;6823.351781580742;7003.789886303419;7016.912558474255;7040.331168538981;7049;7053.788386348418;5236.659617124875;5290.867728306823;5297.364316282071;5317.20241963693;5319.813806516753;6642.693037658537;6787.438889055644;6815.284395138638;6872.10662613857;6878.415334696607;6353.872922541431;6578.572392794453;6591.472682185509;6644.249361706403;6660.63366514827;6517.73929042852;6518.502233458898;6630.801075967729;6674.532782705164;6696.799096027127;6317.431431171033;6455.935440645721;6504.869902602016;6527.11883895668;6540.182477190351;6592.868142637025;6653.268140504638;6672.565398439358;6701.899471508029;6744.831379215362;6363.87272254543;6421.935780642134;6437.613743175424;6550.082988381535;6596.868062638816 17:09:48 INFO - PID 6429 | |5;yui.html;965.6620182935969;993.1310103659298;999.8150342186691;1027.0858935547365;1034.5861655337865;20691.586168276626;21061.684074738892;21178.470538236532;21182.258620948232;21198.788012119876;18231.270749170017;18295.176717047732;20988.16047358104;21520;21689.457763555918;1059.3008614314554;1080.6325849211266;1081.5187241677456;1081.875584307803;1081.8864019277942;1082.2370228988584;1102.2504696806168;1109.6282745280341;1111.3554138599593;1112.2770199370434;1169.9649010529697;1172.917895747298;1173.6655053309807;1175.8471398718154;1175.8882906123904;1174.2426135142784;1175.5944199251244;1177.464253764535;1177.8351030855688;1178.3460179600318;862.1206369503071;862.5126803356084;866.8309679612478;869.752120645613;870.8345414371313;17631.5592110197;17655.11724413787;17672.823271767247;17709.911450442793;18436.815631843645;20956.266530671506;21371.893140534354;21469.892650536647;21472.463188420257;21477;19272.903635481875;19683.409497715093;19798.505037374038;19816;19851.602967940562;4119.176164767028;4134.979325103385;4158.4178215049915;4187.120704652027;4187.45563076798;154026;167125;168847.15576422162;171555;173121;17304.567385815204;17855.28578856857;17872.28510859551;17912.373066942855;17914.104294785207;30194.396112077637;31665.84167079196;31668.36663266768;31738.841305793325;31767.364652706816;10175.338602990782;10333.431661258703;10399.53202105907;10461.372317660966;10473.266871318934;5034.194528875362;5120.462351453119;5134.486551344836;5136.922946155811;5138.768755406016;6361;6426.582272152295;6474.6762661866715;6487.416132548094;6518.771842985473;1294.5792617399384;1302.7199152182327;1303.6024012676035;1307.3267267357319;1318.2617734068951;734.9559026458429;739.8816189409669;752.0449029732209;760.9695612175451;772.3435080181913;12786.29675367845;12864.807027894589;12923.806142907864;13040.413181406859;13411.865881341062;746.813296675831;747.0773594610652;753.6156560154251;757.7688804914444;762.7787941497015;6578.111954886029;6591.472682185414;6638.634875081917;6643;6646.136002319668;145173;151665;154360;154552.3634290973;155039;131044.3447782755;131409;134251;139009;146828;111592;115611;117964.41017795028;122212.38893805473;124261.13608295882;14741.262936853329;15131.697366052838;15162.696746064796;15177.848221517643;15453.5363939082;57929.13106303493;58676.70661646665;58775.70612146912;59307;59451.51371215754;72784;73046;73530.26469735234;76096;76795.23204768104;21080.367588972356;21338.253161139284;21398.465038374165;21494.355169344944;21556.353309400743;22610.773892260866;22979.77020229843;23290.767092329537;23324.53350932938;23511.647325290476;20268.493287667927;20302.390928272176;20310.89844550827;20328.186872524933;20459.38621841347;18980.145893434554;19007.524811879815;19026;19067.237310507426;19092.23631054742;20842.37472875816;21071;21139.577208456052;21324.14703411846;21364;16142.789290803003;16299.837001629832;16386;16527.33890644409;16561.91719041397;15570.143642099792;15620.68758624844;15913.761293580374;15958.68082638364;16266.67466650684 17:09:48 INFO - PID 6429 | __end_tp_report 17:09:48 INFO - PID 6429 | __start_cc_report 17:09:48 INFO - PID 6429 | _x_x_mozilla_cycle_collect,250 17:09:48 INFO - PID 6429 | __end_cc_report 17:09:48 INFO - PID 6429 | __startTimestamp1508371788766__endTimestamp 17:09:48 INFO - PID 6429 | 17:09:48 INFO - PID 6429 | ------- Summary: start ------- 17:09:48 INFO - PID 6429 | Number of tests: 6 17:09:48 INFO - PID 6429 | 17:09:48 INFO - PID 6429 | [#0] dojo.html Cycles:165 Average:6506.14 Median:6697.36 stddev:742.47 (11.1%) stddev-sans-first:718.46 17:09:48 INFO - PID 6429 | Values: 4010.1 4036.6 4047.7 4050.5 4083.6 6353.8 6467.7 6485.6 6536.4 6589.8 6496.3 6517.8 6523.0 6591.7 6597.4 5798.2 5896.5 5901.6 5910.1 5941.5 5834.1 5873.2 5901.7 6014.3 6032.5 5845.4 5887.2 5908.1 5921.3 5965.6 5861.7 5869.5 5887.2 5927.8 5947.4 5787.4 5885.8 5900.1 5909.1 5909.3 6297.5 6352.8 6382.6 6454.4 6464.7 7054.1 7056.8 7098.0 7117.1 7165.4 7033.9 7110.6 7111.8 7120.6 7238.3 6345.7 6465.2 6465.2 6482.6 6483.4 7206.7 7225.7 7243.5 7243.9 7249.2 6932.5 6965.5 6989.2 6989.7 7010.9 7020.1 7039.2 7058.7 7115.3 7123.9 6848.7 6932.8 6948.3 7007.9 7015.7 6960.2 7078.5 7133.4 7146.2 7151.4 5216.3 5269.8 5286.0 5290.4 5309.7 4751.2 4793.8 4797.5 4808.2 4812.4 6463.9 6494.9 6522.3 6617.4 6687.3 6984.5 7025.3 7075.9 7081.9 7164.6 6431.0 6510.9 6517.1 6601.8 6605.3 5891.1 5896.6 5918.3 5957.4 5966.0 7332.3 7349.4 7371.1 7430.4 7456.7 6977.2 7016.2 7030.3 7049.3 7078.8 7175.5 7302.4 7305.6 7327.0 7335.5 6901.3 6980.0 6996.1 7026.2 7063.7 7065.1 7102.4 7147.0 7148.3 7150.5 6636.8 6774.0 6774.9 6857.8 6895.7 6697.4 6710.2 6748.1 6769.6 6824.8 6665.0 6721.8 6732.4 6736.0 6877.6 6829.5 6841.0 6841.4 6911.2 6945.2 6481.3 6558.9 6601.5 6684.1 6687.9 17:09:48 INFO - PID 6429 | 17:09:48 INFO - PID 6429 | [#1] ext.html Cycles:180 Average:44227.92 Median:3814.73 stddev:69618.01 (1825.0%) stddev-sans-first:69737.06 17:09:48 INFO - PID 6429 | Values: 833.5 868.2 901.7 905.2 905.7 44606.3 45207.3 45240.5 45554.3 45570.5 42789.6 42924.0 42956.8 43162.4 43425.0 7986.1 8122.4 8185.1 8190.3 8202.6 7959.1 7996.4 8017.9 8034.7 8039.7 7952.0 8748.6 8857.0 8872.5 8888.8 8657.6 8728.2 8736.0 8747.1 8774.0 8430.5 8643.7 8651.3 8669.5 8730.1 40678.4 40777.4 40898.2 40915.0 41151.6 3813.5 3816.0 3816.0 3818.7 3819.8 33042.2 33169.0 33182.0 33438.5 33459.3 5751.8 5751.9 5773.2 5811.9 5836.6 225845.0 229327.0 230654.0 231836.0 246899.8 51887.7 51983.0 52179.2 52226.5 52240.7 53200.5 53207.7 53243.0 53336.5 53385.5 18188.9 18375.0 18394.9 18521.8 18735.3 15474.6 15476.8 15478.6 15493.5 15517.3 14345.5 14437.0 14472.4 14532.4 14658.9 5813.7 5836.5 5852.1 5961.4 5985.4 1111.9 1141.4 1142.3 1143.4 1144.0 25899.4 25937.5 26113.5 26161.7 26372.1 1239.6 1245.9 1263.8 1264.4 1270.1 13807.3 13869.0 13894.8 13950.9 14016.6 309624.0 310555.0 311217.0 311604.0 313299.0 57255.0 57318.0 57484.4 57994.4 58011.4 1514.3 1546.0 1594.0 1605.2 1622.8 26029.7 26079.2 26142.0 26333.5 26533.0 40784.2 41210.4 41418.2 41538.8 41549.4 250077.0 250951.0 251051.0 252798.0 253351.0 33024.3 33602.2 35120.1 35223.0 35923.0 46779.5 47030.8 47168.8 47432.8 47784.5 35108.0 35233.3 35347.6 35464.3 35552.0 34350.0 34713.3 34789.4 34891.5 35408.3 40265.2 40338.4 40455.4 41135.0 41264.4 33228.0 33671.5 33680.7 33735.7 34132.3 25382.0 25577.4 26309.9 26522.3 26902.9 17:09:48 INFO - PID 6429 | 17:09:48 INFO - PID 6429 | [#2] jquery.html Cycles:180 Average:43357.70 Median:12588.85 stddev:112668.73 (895.0%) stddev-sans-first:112939.14 17:09:48 INFO - PID 6429 | Values: 636.7 642.2 648.1 651.6 655.6 23647.3 24280.4 24524.5 24543.8 24555.8 26414.0 26447.0 26674.7 26681.7 26733.3 6513.3 6687.8 6732.6 6767.3 6835.6 6695.9 6757.6 6821.5 6824.3 6851.5 6581.0 6639.4 6775.3 6806.2 6857.3 6861.5 6907.2 6919.4 6923.4 6929.9 6446.6 6465.5 6471.1 6480.3 6482.4 29836.3 31128.0 31472.5 31787.8 31809.4 2286.1 2289.4 2353.1 2353.9 2359.5 20635.0 21173.2 21364.4 21398.1 21411.0 2556.9 2608.2 2656.8 2659.1 2673.9 140935.0 148747.0 164608.0 170913.0 171204.0 16602.5 16610.3 16739.7 16911.3 17103.0 27150.9 27265.2 27372.3 27397.9 27486.2 10888.3 11033.0 11283.4 11321.5 11356.9 9976.3 10115.1 10136.7 10193.9 10197.5 10203.3 10340.6 10353.9 10416.8 10625.0 2942.6 2973.2 2998.4 3002.0 3031.7 1053.6 1056.6 1080.1 1083.0 1093.0 20845.2 21585.7 21947.9 21948.2 21953.2 1131.2 1134.7 1153.4 1154.2 1154.4 11967.4 12083.3 12085.3 12099.6 12117.5 655813.0 674215.0 677149.0 679234.0 689974.0 133786.0 138534.6 138881.3 139440.0 140860.3 1146.7 1211.0 1255.1 1300.1 1344.7 22888.3 23448.8 23465.8 23546.0 23567.8 67093.7 67174.0 68191.7 69542.0 70435.6 88003.6 89269.6 89539.1 89767.6 90348.5 21716.7 22226.6 22429.1 22467.6 22552.0 24541.8 24874.4 25138.9 25257.4 25305.7 20270.7 20559.3 20716.7 20742.5 20751.6 17657.0 17658.9 17998.3 18386.9 18640.9 20634.0 20822.7 20860.0 21051.9 21061.1 22045.6 22084.9 22104.6 22144.4 22191.2 18082.0 18211.4 19822.2 20026.9 20177.1 17:09:48 INFO - PID 6429 | 17:09:48 INFO - PID 6429 | [#3] mootools.html Cycles:180 Average:10942.64 Median:26327.97 stddev:8093.66 (30.7%) stddev-sans-first:8084.91 17:09:48 INFO - PID 6429 | Values: 1444.6 1498.4 1506.6 1506.7 1526.9 17162.5 17662.5 17828.9 17926.8 17928.3 17402.4 17410.3 17460.6 17563.2 17614.7 3051.7 3080.9 3145.2 3145.6 3148.6 2783.6 2837.7 2848.0 2852.5 2856.0 3095.7 3140.8 3259.8 3260.3 3264.7 3116.7 3123.7 3137.3 3147.0 3149.3 2896.5 2994.3 3018.9 3058.3 3098.8 17319.0 17389.7 17482.2 17490.9 17491.9 8773.5 9090.5 9121.7 9185.9 9198.5 5457.2 5689.9 5694.8 5775.0 5823.5 2114.6 2162.5 2179.3 2221.9 2231.3 31751.5 31807.8 31846.4 31904.0 32028.7 7512.8 7525.6 7593.1 7633.2 7779.6 21213.6 21331.3 21366.0 21376.0 21377.6 8670.1 9018.3 9085.9 9112.6 9133.5 8261.3 8372.4 8429.3 8453.3 8496.5 6150.7 6225.0 6285.2 6337.5 6401.3 1393.5 1406.5 1658.4 1730.5 1734.6 1022.4 1030.2 1032.1 1036.9 1057.1 9348.1 9372.7 9381.2 9438.6 9464.1 1087.5 1112.1 1117.9 1126.1 1139.2 5634.3 5713.7 5766.5 5819.2 5866.2 26025.9 26370.6 26607.3 26711.3 26791.5 26071.7 26316.6 26339.3 26451.9 26808.6 13122.9 13162.6 13187.9 13216.8 13308.6 9906.9 9936.9 10066.1 10137.9 10249.9 21722.9 22014.0 22245.7 22582.2 23154.0 22580.2 22946.7 23161.5 23264.0 23403.2 12171.5 12309.4 12357.1 12372.0 12375.6 15555.2 15593.5 15661.2 15698.8 15819.0 12920.7 12924.2 12985.6 13035.9 13057.3 12573.2 12796.7 12873.4 12886.7 13041.1 10997.9 11235.3 11331.7 11344.6 11353.7 8430.6 8508.9 8520.4 8543.3 8608.2 8932.8 9032.5 9037.3 9045.6 9060.9 17:09:48 INFO - PID 6429 | 17:09:48 INFO - PID 6429 | [#4] prototype.html Cycles:180 Average:5718.49 Median:6326.60 stddev:1577.92 (24.9%) stddev-sans-first:1534.18 17:09:48 INFO - PID 6429 | Values: 563.7 572.9 583.6 596.1 599.1 6193.3 6299.8 6337.8 6394.0 6477.6 6178.5 6182.8 6211.3 6282.3 6364.2 5026.3 5097.9 5210.6 5234.9 5241.5 5130.6 5146.4 5149.6 5171.2 5172.3 5081.0 5102.9 5109.1 5123.5 5125.3 5094.8 5112.3 5124.2 5154.7 5178.7 4713.0 4719.5 4724.1 4732.2 4738.5 6246.1 6279.5 6288.5 6346.6 6353.3 5996.7 6006.4 6010.0 6033.5 6066.2 6778.9 6794.7 6804.7 6818.9 6885.3 4889.2 5131.6 5137.9 5182.9 5193.5 6733.0 6847.3 6884.7 7006.7 7071.4 5286.4 5292.1 5322.9 5331.9 5341.5 5288.4 5343.8 5371.7 5377.9 5410.2 5920.8 5935.5 5998.1 5998.9 6012.5 6751.5 6770.3 6790.9 6800.2 6809.8 1842.4 1846.6 1864.9 1869.4 1874.7 1286.5 1305.4 1328.4 1338.2 1341.3 6270.7 6287.8 6288.2 6295.9 6368.3 6682.2 6788.5 6793.6 6812.4 6862.3 6276.9 6313.3 6335.8 6359.5 6404.6 3553.9 3594.6 3619.9 3626.0 3657.8 7005.3 7008.6 7066.8 7078.8 7128.2 6968.6 6996.8 7001.7 7056.8 7067.1 7026.8 7077.9 7094.8 7102.3 7111.9 6645.9 6726.3 6743.8 6806.6 6874.0 6759.9 6760.6 6764.3 6776.7 6823.4 7003.8 7016.9 7040.3 7049.0 7053.8 5236.7 5290.9 5297.4 5317.2 5319.8 6642.7 6787.4 6815.3 6872.1 6878.4 6353.9 6578.6 6591.5 6644.2 6660.6 6517.7 6518.5 6630.8 6674.5 6696.8 6317.4 6455.9 6504.9 6527.1 6540.2 6592.9 6653.3 6672.6 6701.9 6744.8 6363.9 6421.9 6437.6 6550.1 6596.9 17:09:48 INFO - PID 6429 | 17:09:48 INFO - PID 6429 | [#5] yui.html Cycles:180 Average:29549.87 Median:19740.96 stddev:43594.69 (220.8%) stddev-sans-first:43664.15 17:09:48 INFO - PID 6429 | Values: 965.7 993.1 999.8 1027.1 1034.6 20691.6 21061.7 21178.5 21182.3 21198.8 18231.3 18295.2 20988.2 21520.0 21689.5 1059.3 1080.6 1081.5 1081.9 1081.9 1082.2 1102.3 1109.6 1111.4 1112.3 1170.0 1172.9 1173.7 1175.8 1175.9 1174.2 1175.6 1177.5 1177.8 1178.3 862.1 862.5 866.8 869.8 870.8 17631.6 17655.1 17672.8 17709.9 18436.8 20956.3 21371.9 21469.9 21472.5 21477.0 19272.9 19683.4 19798.5 19816.0 19851.6 4119.2 4135.0 4158.4 4187.1 4187.5 154026.0 167125.0 168847.2 171555.0 173121.0 17304.6 17855.3 17872.3 17912.4 17914.1 30194.4 31665.8 31668.4 31738.8 31767.4 10175.3 10333.4 10399.5 10461.4 10473.3 5034.2 5120.5 5134.5 5136.9 5138.8 6361.0 6426.6 6474.7 6487.4 6518.8 1294.6 1302.7 1303.6 1307.3 1318.3 735.0 739.9 752.0 761.0 772.3 12786.3 12864.8 12923.8 13040.4 13411.9 746.8 747.1 753.6 757.8 762.8 6578.1 6591.5 6638.6 6643.0 6646.1 145173.0 151665.0 154360.0 154552.4 155039.0 131044.3 131409.0 134251.0 139009.0 146828.0 111592.0 115611.0 117964.4 122212.4 124261.1 14741.3 15131.7 15162.7 15177.8 15453.5 57929.1 58676.7 58775.7 59307.0 59451.5 72784.0 73046.0 73530.3 76096.0 76795.2 21080.4 21338.3 21398.5 21494.4 21556.4 22610.8 22979.8 23290.8 23324.5 23511.6 20268.5 20302.4 20310.9 20328.2 20459.4 18980.1 19007.5 19026.0 19067.2 19092.2 20842.4 21071.0 21139.6 21324.1 21364.0 16142.8 16299.8 16386.0 16527.3 16561.9 15570.1 15620.7 15913.8 15958.7 16266.7 17:09:48 INFO - PID 6429 | -------- Summary: end -------- 17:09:48 INFO - PID 6429 | 17:09:49 INFO - TEST-INFO | 6429: exit 0 17:09:49 INFO - TEST-OK | dromaeo_css | took 1107448ms 17:09:49 INFO - TEST-START | kraken 17:09:49 INFO - Initialising browser for kraken test... 17:09:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57306/getInfo.html -profile /tmp/tmpLTFAfI/profile 17:09:49 INFO - TEST-INFO | started process 6797 (/builds/slave/test/build/application/firefox/firefox http://localhost:57306/getInfo.html) 17:09:54 INFO - TEST-INFO | 6797: exit 0 17:09:55 INFO - Browser initialized. 17:09:55 INFO - Running cycle 1/1 for kraken test... 17:09:55 INFO - Using env: {'DISPLAY': ':0', 17:09:55 INFO - 'HOME': '/home/cltbld', 17:09:55 INFO - 'JSGC_DISABLE_POISONING': '1', 17:09:55 INFO - 'LANG': 'en_US.UTF-8', 17:09:55 INFO - 'LANGUAGE': 'en_US:en', 17:09:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:09:55 INFO - 'LOGNAME': 'cltbld', 17:09:55 INFO - 'MAIL': '/var/mail/cltbld', 17:09:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:09:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:09:55 INFO - 'MOZ_CRASHREPORTER': '1', 17:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:09:55 INFO - 'MOZ_NO_REMOTE': '1', 17:09:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:09:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:55 INFO - 'NO_EM_RESTART': '1', 17:09:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:55 INFO - 'PWD': '/builds/slave/test', 17:09:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:09:55 INFO - 'RUST_BACKTRACE': 'full', 17:09:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:09:55 INFO - 'SHELL': '/bin/bash', 17:09:55 INFO - 'SHLVL': '1', 17:09:55 INFO - 'TERM': 'linux', 17:09:55 INFO - 'TMOUT': '86400', 17:09:55 INFO - 'USER': 'cltbld', 17:09:55 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508370623.26101-956867479', 17:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:55 INFO - '_': '/tools/buildbot/bin/python'} 17:09:55 INFO - TEST-INFO | started process 7036 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLTFAfI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:09:55 INFO - PID 7036 | 17:09:55 INFO - PID 7036 | (/builds/slave/test/build/application/firefox/firefox:7086): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:09:55 INFO - PID 7036 | 17:10:06 INFO - PID 7036 | RSS: Main: 141631488 17:10:06 INFO - PID 7036 | 17:10:08 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57306/tests/kraken/driver.html?testName=audio-beat-detection) 17:10:09 INFO - PID 7036 | RSS: Main: 148733952 17:10:09 INFO - PID 7036 | 17:10:11 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57306/tests/kraken/driver.html?testName=audio-dft) 17:10:12 INFO - PID 7036 | RSS: Main: 149049344 17:10:12 INFO - PID 7036 | 17:10:15 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57306/tests/kraken/driver.html?testName=audio-fft) 17:10:16 INFO - PID 7036 | RSS: Main: 150474752 17:10:16 INFO - PID 7036 | 17:10:18 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57306/tests/kraken/driver.html?testName=audio-oscillator) 17:10:18 INFO - PID 7036 | RSS: Main: 150282240 17:10:18 INFO - PID 7036 | 17:10:20 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57306/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:10:21 INFO - PID 7036 | RSS: Main: 150532096 17:10:21 INFO - PID 7036 | 17:10:24 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57306/tests/kraken/driver.html?testName=imaging-darkroom) 17:10:25 INFO - PID 7036 | RSS: Main: 150818816 17:10:25 INFO - PID 7036 | 17:10:28 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57306/tests/kraken/driver.html?testName=imaging-desaturate) 17:10:29 INFO - PID 7036 | RSS: Main: 151117824 17:10:29 INFO - PID 7036 | 17:10:32 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57306/tests/kraken/driver.html?testName=json-parse-financial) 17:10:32 INFO - PID 7036 | RSS: Main: 152698880 17:10:32 INFO - PID 7036 | 17:10:34 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57306/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:10:34 INFO - PID 7036 | RSS: Main: 152985600 17:10:34 INFO - PID 7036 | 17:10:36 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57306/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:10:36 INFO - PID 7036 | RSS: Main: 152698880 17:10:36 INFO - PID 7036 | 17:10:38 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57306/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:10:39 INFO - PID 7036 | RSS: Main: 149692416 17:10:39 INFO - PID 7036 | 17:10:41 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57306/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:10:42 INFO - PID 7036 | RSS: Main: 149778432 17:10:42 INFO - PID 7036 | 17:10:45 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57306/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:10:45 INFO - PID 7036 | RSS: Main: 150814720 17:10:45 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | Cycle 1(1): loaded http://localhost:57306/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57306/tests/kraken/driver.html?testName=ai-astar) 17:10:47 INFO - PID 7036 | RSS: Main: 151846912 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | __start_tp_report 17:10:47 INFO - PID 7036 | _x_x_mozilla_page_load 17:10:47 INFO - PID 7036 | _x_x_mozilla_page_load_details 17:10:47 INFO - PID 7036 | |i|pagename|runs| 17:10:47 INFO - PID 7036 | |0;ai-astar;104;103;105;106;106;106;106;107;112;104 17:10:47 INFO - PID 7036 | |1;audio-beat-detection;110;111;114;110;110;114;146;111;113;111 17:10:47 INFO - PID 7036 | |2;audio-dft;162;161;162;162;162;161;163;164;163;161 17:10:47 INFO - PID 7036 | |3;audio-fft;74;74;75;72;73;73;72;73;74;72 17:10:47 INFO - PID 7036 | |4;audio-oscillator;78;78;76;78;79;80;78;77;78;78 17:10:47 INFO - PID 7036 | |5;imaging-gaussian-blur;114;115;113;113;109;115;114;114;113;114 17:10:47 INFO - PID 7036 | |6;imaging-darkroom;150;151;151;147;149;150;149;150;151;149 17:10:47 INFO - PID 7036 | |7;imaging-desaturate;94;96;95;95;94;95;94;94;94;94 17:10:47 INFO - PID 7036 | |8;json-parse-financial;46;44;44;45;45;44;43;43;41;42 17:10:47 INFO - PID 7036 | |9;json-stringify-tinderbox;38;38;39;38;38;39;38;39;38;38 17:10:47 INFO - PID 7036 | |10;stanford-crypto-aes;74;73;76;69;69;69;68;71;70;69 17:10:47 INFO - PID 7036 | |11;stanford-crypto-ccm;106;105;115;108;107;103;102;104;103;108 17:10:47 INFO - PID 7036 | |12;stanford-crypto-pbkdf2;153;160;149;163;158;155;149;147;149;149 17:10:47 INFO - PID 7036 | |13;stanford-crypto-sha256-iterative;59;62;58;74;66;58;58;71;59;58 17:10:47 INFO - PID 7036 | __end_tp_report 17:10:47 INFO - PID 7036 | __start_cc_report 17:10:47 INFO - PID 7036 | _x_x_mozilla_cycle_collect,722 17:10:47 INFO - PID 7036 | __end_cc_report 17:10:47 INFO - PID 7036 | __startTimestamp1508371847700__endTimestamp 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | ------- Summary: start ------- 17:10:47 INFO - PID 7036 | Number of tests: 14 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#0] ai-astar Cycles:10 Average:105.90 Median:106.00 stddev:2.47 (2.3%) stddev-sans-first:2.52 17:10:47 INFO - PID 7036 | Values: 104.0 103.0 105.0 106.0 106.0 106.0 106.0 107.0 112.0 104.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#1] audio-beat-detection Cycles:10 Average:115.00 Median:112.00 stddev:11.01 (9.8%) stddev-sans-first:11.52 17:10:47 INFO - PID 7036 | Values: 110.0 111.0 114.0 110.0 110.0 114.0 146.0 111.0 113.0 111.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 17:10:47 INFO - PID 7036 | Values: 162.0 161.0 162.0 162.0 162.0 161.0 163.0 164.0 163.0 161.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#3] audio-fft Cycles:10 Average:73.20 Median:73.50 stddev:1.03 (1.4%) stddev-sans-first:1.05 17:10:47 INFO - PID 7036 | Values: 74.0 74.0 75.0 72.0 73.0 73.0 72.0 73.0 74.0 72.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:78.00 stddev:1.05 (1.4%) stddev-sans-first:1.12 17:10:47 INFO - PID 7036 | Values: 78.0 78.0 76.0 78.0 79.0 80.0 78.0 77.0 78.0 78.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:114.00 stddev:1.71 (1.5%) stddev-sans-first:1.80 17:10:47 INFO - PID 7036 | Values: 114.0 115.0 113.0 113.0 109.0 115.0 114.0 114.0 113.0 114.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:1.25 (0.8%) stddev-sans-first:1.32 17:10:47 INFO - PID 7036 | Values: 150.0 151.0 151.0 147.0 149.0 150.0 149.0 150.0 151.0 149.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 17:10:47 INFO - PID 7036 | Values: 94.0 96.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#8] json-parse-financial Cycles:10 Average:43.70 Median:44.00 stddev:1.49 (3.4%) stddev-sans-first:1.33 17:10:47 INFO - PID 7036 | Values: 46.0 44.0 44.0 45.0 45.0 44.0 43.0 43.0 41.0 42.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 17:10:47 INFO - PID 7036 | Values: 38.0 38.0 39.0 38.0 38.0 39.0 38.0 39.0 38.0 38.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#10] stanford-crypto-aes Cycles:10 Average:70.80 Median:70.50 stddev:2.66 (3.8%) stddev-sans-first:2.55 17:10:47 INFO - PID 7036 | Values: 74.0 73.0 76.0 69.0 69.0 69.0 68.0 71.0 70.0 69.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#11] stanford-crypto-ccm Cycles:10 Average:106.10 Median:106.50 stddev:3.78 (3.6%) stddev-sans-first:4.01 17:10:47 INFO - PID 7036 | Values: 106.0 105.0 115.0 108.0 107.0 103.0 102.0 104.0 103.0 108.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:154.00 stddev:5.55 (3.6%) stddev-sans-first:5.89 17:10:47 INFO - PID 7036 | Values: 153.0 160.0 149.0 163.0 158.0 155.0 149.0 147.0 149.0 149.0 17:10:47 INFO - PID 7036 | 17:10:47 INFO - PID 7036 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:60.50 stddev:5.98 (9.9%) stddev-sans-first:6.22 17:10:47 INFO - PID 7036 | Values: 59.0 62.0 58.0 74.0 66.0 58.0 58.0 71.0 59.0 58.0 17:10:47 INFO - PID 7036 | -------- Summary: end -------- 17:10:47 INFO - PID 7036 | 17:10:48 INFO - PID 7036 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:10:48 INFO - TEST-INFO | 7036: exit 0 17:10:48 INFO - TEST-OK | kraken | took 58946ms 17:10:48 INFO - SUITE-END | took 1166s 17:10:48 INFO - Completed test suite (00:19:26) 17:10:48 INFO - javascript benchmark 17:10:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9444.238993251092, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4010.1378203686204, 4036.616521430465, 4047.696422768291, 4050.5139383274013, 4083.5916408359167, 6353.80938571843, 6467.70895309711, 6485.643289619075, 6536.411722944934, 6589.802305930818, 6496.252930912947, 6517.80446586602, 6523.0, 6591.736330546784, 6597.406233438989, 5798.246227990356, 5896.5282777377815, 5901.616394934337, 5910.0543912973935, 5941.465268125868, 5834.066549352105, 5873.207117039187, 5901.675407852566, 6014.278286605613, 6032.487238584726, 5845.357010728838, 5887.205227294325, 5908.1433192187105, 5921.319048309435, 5965.582409231356, 5861.677607731573, 5869.50109240715, 5887.205227294303, 5927.762889484416, 5947.375525569818, 5787.397461981974, 5885.79399721012, 5900.055991041413, 5909.084091965752, 5909.3204281507315, 6297.464715499188, 6352.777652782131, 6382.61704297739, 6454.354564543508, 6464.676766161673, 7054.0829692140205, 7056.788296351117, 7098.041764361797, 7117.110361204849, 7165.390941769956, 7033.859322813515, 7110.60891650959, 7111.8222044448785, 7120.643967801641, 7238.348548630596, 6345.650989195592, 6465.19185101867, 6465.224173099258, 6482.611043337368, 6483.384078512534, 7206.675699593531, 7225.710971561079, 7243.529170603894, 7243.89134162988, 7249.166345870292, 6932.514723969273, 6965.547239429421, 6989.196242432084, 6989.650517474106, 7010.894836577456, 7020.052292940388, 7039.19049309336, 7058.6823592938445, 7115.324044215791, 7123.857522849514, 6848.6918088686125, 6932.826679333057, 6948.305169483112, 7007.894881576781, 7015.719371225196, 6960.199577048603, 7078.539894906815, 7133.357997780225, 7146.2496437874315, 7151.392131668762, 5216.26972223889, 5269.762860671279, 5286.048511268009, 5290.365156181283, 5309.734513274321, 4751.168545504592, 4793.808247670124, 4797.496262892416, 4808.182608956416, 4812.35033270504, 6463.903041454382, 6494.902576461357, 6522.34776522344, 6617.371349721769, 6687.331266873273, 6984.511084224056, 7025.262347453546, 7075.92924070763, 7081.89377159343, 7164.641767911688, 6430.967845160744, 6510.869782604322, 6517.087607734921, 6601.801945941629, 6605.306442823435, 5891.116332550152, 5896.587238893322, 5918.319393269743, 5957.404259574095, 5965.970170149209, 7332.340089391981, 7349.375303099189, 7371.078615173104, 7430.368418684472, 7456.7390141344795, 6977.232504424611, 7016.19313778922, 7030.3321184487395, 7049.259827718016, 7078.823029424203, 7175.461840362098, 7302.415806735342, 7305.5981920994855, 7326.96336518314, 7335.486515943832, 6901.275366086689, 6979.965100174467, 6996.090508233897, 7026.191987921455, 7063.682134304071, 7065.081539400051, 7102.360787529147, 7147.035150254857, 7148.320909513588, 7150.463715221275, 6636.767713130017, 6774.0, 6774.8645027100165, 6857.759978400732, 6895.6552172390175, 6697.3637504437, 6710.194776626836, 6748.089007984056, 6769.627670478171, 6824.829379265558, 6665.0, 6721.798346049626, 6732.360425759545, 6735.955926831349, 6877.552959057645, 6829.4877884157895, 6841.0, 6841.350071743176, 6911.239763626098, 6945.236024037453, 6481.287058423664, 6558.868822623616, 6601.471882249312, 6684.097646817618, 6687.933120668731], "value": 6457.8183320802755, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.4540875726401, 868.2055918834267, 901.7384958362076, 905.2034209895294, 905.6920646980029, 44606.33090503641, 45207.32189017159, 45240.54759452413, 45554.316685249745, 45570.54429455708, 42789.572104278945, 42924.0, 42956.78521607396, 43162.35256471155, 43425.0, 7986.121526632066, 8122.390820688443, 8185.140560241178, 8190.303824174952, 8202.630881610341, 7959.1244963054305, 7996.360291176721, 8017.879731804028, 8034.678612855479, 8039.718609848656, 7952.0, 8748.606312715943, 8857.0, 8872.467651940871, 8888.777780555472, 8657.610407531676, 8728.214460698537, 8736.039035706068, 8747.125287471266, 8774.0, 8430.45202061864, 8643.697470588499, 8651.264642505395, 8669.523176225304, 8730.126987301282, 40678.38982415266, 40777.38833917493, 40898.1820363594, 40915.0, 41151.58848411508, 3813.466114743938, 3816.0, 3816.0459885028745, 3818.6563209311294, 3819.751716138442, 33042.17394565131, 33169.0, 33182.0, 33438.498422523684, 33459.330813383596, 5751.798687045975, 5751.913721294184, 5773.191753154561, 5811.883762324772, 5836.562257830679, 225845.0, 229327.0, 230654.0, 231836.0, 246899.76550117315, 51887.74056129771, 51983.0, 52179.217311739594, 52226.477735222164, 52240.73879630578, 53200.46799531955, 53207.743380529464, 53243.0, 53336.46663533393, 53385.466145338825, 18188.90905545464, 18375.0, 18394.908025460056, 18521.814781852278, 18735.344262951003, 15474.613134671723, 15476.76784848228, 15478.613034674223, 15493.457728979432, 15517.301721422564, 14345.497907573394, 14437.0, 14472.421103155755, 14532.418703251751, 14658.926705366404, 5813.651180929073, 5836.533077353802, 5852.122181672698, 5961.4038596140035, 5985.40145985398, 1111.8999290063932, 1141.406468636321, 1142.3317359344876, 1143.3940011793763, 1143.988560114405, 25899.352516186867, 25937.481250374887, 26113.477730445284, 26161.73838261631, 26372.076977305704, 1239.6219153158374, 1245.9065570082098, 1263.7977923532374, 1264.3930913161735, 1270.0665011216747, 13807.309634518233, 13869.029167958348, 13894.791578126336, 13950.930245348709, 14016.579502614937, 309624.0, 310555.0, 311217.0, 311604.0, 313299.0, 57255.0, 57318.0, 57484.425155748744, 57994.42005579975, 58011.41988580144, 1514.3412615512289, 1545.95362139136, 1594.0435738556773, 1605.2134454117631, 1622.8377162283914, 26029.739702602732, 26079.217623471326, 26142.0, 26333.4733305329, 26533.0, 40784.184316312916, 41210.38184427176, 41418.1716365665, 41538.792306038275, 41549.37675934803, 250077.0, 250951.0, 251051.0, 252798.0, 253351.0, 33024.33951320912, 33602.15994600154, 35120.12199695028, 35223.0, 35923.0, 46779.53220467752, 47030.76484617555, 47168.764156179, 47432.76283618698, 47784.5221547794, 35108.0, 35233.29533409266, 35347.64652353546, 35464.29071418506, 35552.0, 34350.0, 34713.30573388569, 34789.43447544917, 34891.47662785111, 35408.29183416369, 40265.194696105325, 40338.394924075576, 40455.393169101895, 41135.0, 41264.381034283906, 33228.0, 33671.4949275766, 33680.66319336873, 33735.662643374235, 34132.317353653285, 25382.0, 25577.360565986, 26309.86845065839, 26522.336941576614, 26902.865485672446], "value": 18956.742922008692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.6803467339852, 642.1628278622427, 648.0523957256119, 651.6079442612383, 655.6070343247144, 23647.290581282567, 24280.39299017523, 24524.50950980979, 24543.75456245437, 24555.75444245557, 26414.0, 26447.0, 26674.73325266747, 26681.733182668217, 26733.331666708295, 6513.250976137734, 6687.832804179886, 6732.596044237337, 6767.289434609369, 6835.624040677748, 6695.933040669604, 6757.560758550703, 6821.4542836572955, 6824.317568243161, 6851.520393572452, 6580.967095164518, 6639.435647969928, 6775.32246775321, 6806.183258009046, 6857.27998560149, 6861.4510839133, 6907.205671347809, 6919.411849992756, 6923.411510021654, 6929.896051559231, 6446.580972236789, 6465.4827613790785, 6471.061696054046, 6480.287168411471, 6482.351764823527, 29836.254093647614, 31128.0, 31472.527912081336, 31787.84106079478, 31809.363812723615, 2286.1312701173615, 2289.3933107726466, 2353.1411034972284, 2353.8587684738973, 2359.4691194481275, 20635.0, 21173.15307387718, 21364.359069227947, 21398.144074237167, 21411.0, 2556.872156392173, 2608.2044976282227, 2656.827306225089, 2659.0693257359962, 2673.906413275526, 140935.0, 148747.0, 164608.0, 170913.0, 171203.99999999875, 16602.50192494227, 16610.335586576643, 16739.748903766453, 16911.323547058226, 17103.0, 27150.86424567904, 27265.1820445383, 27372.31569210746, 27397.863010684818, 27486.17541473759, 10888.29226100301, 11033.0, 11283.435828208556, 11321.547138114383, 11356.94321528387, 9976.251781116445, 10115.140213081824, 10136.695899122891, 10193.949030254802, 10197.541110650038, 10203.336783109075, 10340.638077667245, 10353.948230258951, 10416.843747343795, 10625.0, 2942.5880376747273, 2973.152651494314, 2998.445287621797, 3002.022320425445, 3031.6968303169506, 1053.6048981631889, 1056.5984925728328, 1080.1250986700798, 1083.0360978728938, 1092.9672109836717, 20845.166193352095, 21585.67621485679, 21947.890260548596, 21948.231811886508, 21953.231636892633, 1131.1572878205789, 1134.6879608107672, 1153.3887039868882, 1154.2093665838927, 1154.440096553171, 11967.40162991847, 12083.275003499819, 12085.335306558049, 12099.637010889688, 12117.454714537867, 655813.0, 674215.0, 677149.0, 679234.0, 689974.0, 133786.0, 138534.6146538542, 138881.3055934734, 139440.0, 140860.29569852084, 1146.7075895646556, 1211.0432758120985, 1255.0587059705222, 1300.1029289790015, 1344.7176093020576, 22888.31335059951, 23448.765512344657, 23465.76534234704, 23546.0, 23567.764322356557, 67093.66453167703, 67174.0, 68191.65904170448, 69542.0, 70435.64782176261, 88003.55998219967, 89269.55365223132, 89539.10460895568, 89767.55116224378, 90348.5482572583, 21716.67424988595, 22226.55546889021, 22429.10283588638, 22467.550648987257, 22552.0, 24541.75458245466, 24874.378140546632, 25138.874305628357, 25257.368565786004, 25305.74694253034, 20270.695939560625, 20559.280425185047, 20716.68924966157, 20742.481437964172, 20751.58496830025, 17657.0, 17658.91170544139, 17998.280068797103, 18386.908065459585, 18640.906795465937, 20634.0, 20822.687659684812, 20860.0, 21051.8947405262, 21061.052252648362, 22045.559088817812, 22084.88957555202, 22104.557908841412, 22144.446388840406, 22191.22330718417, 18082.0, 18211.362602308855, 19822.207111715947, 20026.899865501164, 20177.09203085835], "value": 13571.450922242773, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.5666300109965, 1498.35570704597, 1506.5630967019565, 1506.676064646101, 1526.8549487798662, 17162.48512544624, 17662.470125896227, 17828.91085544572, 17926.82073179271, 17928.28286868527, 17402.39091631793, 17410.303587856502, 17460.56348591286, 17563.209655565497, 17614.735778963324, 3051.6643169251365, 3080.8767649294, 3145.213696575856, 3145.6067991501063, 3148.6064241969752, 2783.582462630601, 2837.6878543360212, 2847.985760071207, 2852.5435930251165, 2855.957160642592, 3095.659477457489, 3140.8115513069174, 3259.820709860957, 3260.315333779909, 3264.689854463822, 3116.688331166888, 3123.672014438474, 3137.30979184579, 3147.0244224290545, 3149.275666596684, 2896.478633845907, 2994.326276587772, 3018.894338698157, 3058.3424563718686, 3098.783085184038, 17319.0, 17389.739153912702, 17482.213300401385, 17490.91254543719, 17491.912540437344, 8773.473591584525, 9090.545472726404, 9121.726348209564, 9185.908140918573, 9198.540072996324, 5457.2359869618285, 5689.9146512802345, 5694.829155125353, 5774.971125144348, 5823.53411727065, 2114.587655407196, 2162.4918144236094, 2179.2917301876796, 2221.9000144993515, 2231.263682984611, 31751.52372714411, 31807.84096079505, 31846.363072738648, 31904.0, 32028.679713203037, 7512.812179695442, 7525.58609276495, 7593.088829340514, 7633.198514156044, 7779.611019449005, 21213.575728485343, 21331.253406130712, 21366.0, 21376.0, 21377.57244855094, 8670.13298670128, 9018.278537716968, 9085.909140908507, 9112.635494580276, 9133.543322833832, 8261.256486916087, 8372.413931024892, 8429.283510901641, 8453.323734101257, 8496.532690701946, 6150.661713605795, 6225.0, 6285.182926219562, 6337.524685648595, 6401.263854656681, 1393.470481217131, 1406.4585134723204, 1658.4112640012863, 1730.4981555348809, 1734.6357264974245, 1022.3814592171777, 1030.160419258302, 1032.0814475117152, 1036.9014943580348, 1057.127869507644, 9348.1119293667, 9372.67195648149, 9381.249500039981, 9438.575264113131, 9464.1009104135, 1087.5106202209038, 1112.1325366214367, 1117.8826223246604, 1126.0878688262533, 1139.2424038014792, 5634.32388113429, 5713.74430271177, 5766.481016708516, 5819.15622234782, 5866.208061911672, 26025.869870650524, 26370.604440933403, 26607.33481662935, 26711.332216694736, 26791.464170716477, 26071.739282607312, 26316.605250921253, 26339.34151646186, 26451.867740661175, 26808.59787103195, 13122.868771312165, 13162.60512184636, 13187.868121318856, 13216.801747973788, 13308.600741977756, 9906.851397229191, 9936.93923098836, 10066.144377727975, 10137.898621013695, 10249.897501025192, 21722.891385543604, 22014.00936957809, 22245.666315004964, 22582.20962266313, 23154.0, 22580.20969266068, 22946.655800162676, 23161.536769264858, 23264.0, 23403.180888668816, 12171.513139474677, 12309.44607492683, 12357.073219508393, 12372.0, 12375.62873113808, 15555.222238888236, 15593.532194034196, 15661.21693915277, 15698.764518532003, 15819.0, 12920.741585168433, 12924.224546527239, 12985.610431687064, 13035.891949184426, 13057.347132643139, 12573.245605263714, 12796.680082998, 12873.35633218354, 12886.677833053873, 13041.087123901236, 10997.890021100007, 11235.325880447199, 11331.660050198507, 11344.602938897098, 11353.716157095809, 8430.578471076544, 8508.872366914378, 8520.360972926932, 8543.316534677087, 8608.182222688834, 8932.776680583038, 9032.503212323385, 9037.277017838689, 9045.638174472948, 9060.864087038568], "value": 7692.752711905015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.7434967089972, 572.9286234741035, 583.5681595619243, 596.0909612840419, 599.110321173058, 6193.349698281683, 6299.77950771723, 6337.841553961153, 6393.968030159855, 6477.643729594871, 6178.474829639486, 6182.814515564529, 6211.254649442062, 6282.277538083122, 6364.236291645009, 5026.271190677349, 5097.9235311470175, 5210.635255532115, 5234.8691282717855, 5241.528262456378, 5130.56390206831, 5146.356705411824, 5149.562287205592, 5171.215930503989, 5172.27588137661, 5080.974595127019, 5102.948970510284, 5109.105906466372, 5123.5388815006645, 5125.333706618153, 5094.796208151651, 5112.284280200774, 5124.205748109049, 5154.664946778447, 5178.663386879841, 4712.95287047132, 4719.504452032522, 4724.126036683225, 4732.2428411454, 4738.47876733559, 6246.125542424064, 6279.466245369149, 6288.52836037299, 6346.619202847844, 6353.301136874987, 5996.700164991733, 6006.429389208019, 6010.0, 6033.456988870978, 6066.150738896558, 6778.864422711567, 6794.742972550094, 6804.727810887559, 6818.863622727517, 6885.2770459101575, 4889.237580766855, 5131.640785145041, 5137.922931156036, 5182.948170518284, 5193.480651934814, 6732.966335168342, 6847.281035491302, 6884.690188941559, 7006.684699188549, 7071.36357727807, 5286.444923283077, 5292.126799078186, 5322.946770532322, 5331.893362132736, 5341.465853414705, 5288.413475957197, 5343.759530821122, 5371.731413429391, 5377.919331210035, 5410.242566040757, 5920.763169473173, 5935.465808077293, 5998.130271110665, 5998.850028749316, 6012.549058820606, 6751.459883209332, 6770.289119642465, 6790.864182716318, 6800.217974932946, 6809.829754256084, 1842.4196378140844, 1846.6306738652324, 1864.9160787764583, 1869.3831035758167, 1874.728164416152, 1286.4725462560305, 1305.3799445263614, 1328.4486937920656, 1338.1569611145112, 1341.2891167681144, 6270.7491700331475, 6287.779927702509, 6288.213973253343, 6295.937040629627, 6368.331325210879, 6682.198136223587, 6788.524803263823, 6793.607310501336, 6812.420944219797, 6862.348076932683, 6276.905846412307, 6313.337099604475, 6335.778247761398, 6359.491240700733, 6404.615723056631, 3553.8578456861437, 3594.5506811648543, 3619.8914032579064, 3625.963740362615, 3657.762245454037, 7005.302958360966, 7008.584591707192, 7066.823329416703, 7078.761216787145, 7128.180259270147, 6968.581885086911, 6996.825079373057, 7001.68944248407, 7056.847661463303, 7067.056559446193, 7026.789196324118, 7077.858442831114, 7094.751683691046, 7102.254263302486, 7111.928880711127, 6645.9003114954285, 6726.293739157318, 6743.797686069426, 6806.604646047647, 6873.965630171817, 6759.904429626831, 6760.560563563353, 6764.289749576224, 6776.661166941534, 6823.351781580742, 7003.789886303419, 7016.912558474255, 7040.331168538981, 7049.0, 7053.788386348418, 5236.659617124875, 5290.867728306823, 5297.364316282071, 5317.20241963693, 5319.813806516753, 6642.693037658537, 6787.438889055644, 6815.284395138638, 6872.10662613857, 6878.415334696607, 6353.872922541431, 6578.572392794453, 6591.472682185509, 6644.249361706403, 6660.63366514827, 6517.73929042852, 6518.502233458898, 6630.801075967729, 6674.532782705164, 6696.799096027127, 6317.431431171033, 6455.935440645721, 6504.869902602016, 6527.11883895668, 6540.182477190351, 6592.868142637025, 6653.268140504638, 6672.565398439358, 6701.899471508029, 6744.831379215362, 6363.87272254543, 6421.935780642134, 6437.613743175424, 6550.082988381535, 6596.868062638816], "value": 5267.733667047424, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [965.6620182935969, 993.1310103659298, 999.8150342186691, 1027.0858935547365, 1034.5861655337865, 20691.586168276626, 21061.684074738892, 21178.470538236532, 21182.258620948232, 21198.788012119876, 18231.270749170017, 18295.176717047732, 20988.16047358104, 21520.0, 21689.457763555918, 1059.3008614314554, 1080.6325849211266, 1081.5187241677456, 1081.875584307803, 1081.8864019277942, 1082.2370228988584, 1102.2504696806168, 1109.6282745280341, 1111.3554138599593, 1112.2770199370434, 1169.9649010529697, 1172.917895747298, 1173.6655053309807, 1175.8471398718154, 1175.8882906123904, 1174.2426135142784, 1175.5944199251244, 1177.464253764535, 1177.8351030855688, 1178.3460179600318, 862.1206369503071, 862.5126803356084, 866.8309679612478, 869.752120645613, 870.8345414371313, 17631.5592110197, 17655.11724413787, 17672.823271767247, 17709.911450442793, 18436.815631843645, 20956.266530671506, 21371.893140534354, 21469.892650536647, 21472.463188420257, 21477.0, 19272.903635481875, 19683.409497715093, 19798.505037374038, 19816.0, 19851.602967940562, 4119.176164767028, 4134.979325103385, 4158.4178215049915, 4187.120704652027, 4187.45563076798, 154026.0, 167125.0, 168847.15576422162, 171555.0, 173121.0, 17304.567385815204, 17855.28578856857, 17872.28510859551, 17912.373066942855, 17914.104294785207, 30194.396112077637, 31665.84167079196, 31668.36663266768, 31738.841305793325, 31767.364652706816, 10175.338602990782, 10333.431661258703, 10399.53202105907, 10461.372317660966, 10473.266871318934, 5034.194528875362, 5120.462351453119, 5134.486551344836, 5136.922946155811, 5138.768755406016, 6361.0, 6426.582272152295, 6474.6762661866715, 6487.416132548094, 6518.771842985473, 1294.5792617399384, 1302.7199152182327, 1303.6024012676035, 1307.3267267357319, 1318.2617734068951, 734.9559026458429, 739.8816189409669, 752.0449029732209, 760.9695612175451, 772.3435080181913, 12786.29675367845, 12864.807027894589, 12923.806142907864, 13040.413181406859, 13411.865881341062, 746.813296675831, 747.0773594610652, 753.6156560154251, 757.7688804914444, 762.7787941497015, 6578.111954886029, 6591.472682185414, 6638.634875081917, 6643.0, 6646.136002319668, 145173.0, 151665.0, 154360.0, 154552.3634290973, 155039.0, 131044.3447782755, 131409.0, 134251.0, 139009.0, 146828.0, 111592.0, 115611.0, 117964.41017795028, 122212.38893805473, 124261.13608295882, 14741.262936853329, 15131.697366052838, 15162.696746064796, 15177.848221517643, 15453.5363939082, 57929.13106303493, 58676.70661646665, 58775.70612146912, 59307.0, 59451.51371215754, 72784.0, 73046.0, 73530.26469735234, 76096.0, 76795.23204768104, 21080.367588972356, 21338.253161139284, 21398.465038374165, 21494.355169344944, 21556.353309400743, 22610.773892260866, 22979.77020229843, 23290.767092329537, 23324.53350932938, 23511.647325290476, 20268.493287667927, 20302.390928272176, 20310.89844550827, 20328.186872524933, 20459.38621841347, 18980.145893434554, 19007.524811879815, 19026.0, 19067.237310507426, 19092.23631054742, 20842.37472875816, 21071.0, 21139.577208456052, 21324.14703411846, 21364.0, 16142.789290803003, 16299.837001629832, 16386.0, 16527.33890644409, 16561.91719041397, 15570.143642099792, 15620.68758624844, 15913.761293580374, 15958.68082638364, 16266.67466650684], "value": 10538.870146307649, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1366.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 103.0, 105.0, 106.0, 106.0, 106.0, 106.0, 107.0, 112.0, 104.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 114.0, 110.0, 110.0, 114.0, 146.0, 111.0, 113.0, 111.0], "value": 115.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 162.0, 161.0, 163.0, 164.0, 163.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 72.0, 73.0, 73.0, 72.0, 73.0, 74.0, 72.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 76.0, 78.0, 79.0, 80.0, 78.0, 77.0, 78.0, 78.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 113.0, 109.0, 115.0, 114.0, 114.0, 113.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 151.0, 147.0, 149.0, 150.0, 149.0, 150.0, 151.0, 149.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 44.0, 44.0, 45.0, 45.0, 44.0, 43.0, 43.0, 41.0, 42.0], "value": 43.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 38.0, 38.0, 39.0, 38.0, 39.0, 38.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 76.0, 69.0, 69.0, 69.0, 68.0, 71.0, 70.0, 69.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 115.0, 108.0, 107.0, 103.0, 102.0, 104.0, 103.0, 108.0], "value": 106.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 160.0, 149.0, 163.0, 158.0, 155.0, 149.0, 147.0, 149.0, 149.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 58.0, 74.0, 66.0, 58.0, 58.0, 71.0, 59.0, 58.0], "value": 62.3, "unit": "score"}]}]} 17:10:48 INFO - Return code: 0 17:10:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:10:48 INFO - # TBPL SUCCESS # 17:10:48 INFO - Running post-action listener: _package_coverage_data 17:10:48 INFO - Running post-action listener: _resource_record_post_action 17:10:48 INFO - [mozharness: 2017-10-19 00:10:48.518407Z] Finished run-tests step (success) 17:10:48 INFO - Running post-run listener: _resource_record_post_run 17:10:48 INFO - Total resource usage - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 618143744; Read time: 16; Write time: 856884 17:10:48 INFO - TinderboxPrint: CPU usage
13.1% 17:10:48 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 17:10:48 INFO - TinderboxPrint: I/O write bytes / time
618,143,744 / 856,884 17:10:48 INFO - TinderboxPrint: CPU idle
8,185.5 (86.9%) 17:10:48 INFO - TinderboxPrint: CPU user
1,180.9 (12.5%) 17:10:48 INFO - TinderboxPrint: Swap in / out
0 / 0 17:10:48 INFO - install - Wall time: 15s; CPU: 17.0%; Read bytes: 0; Write bytes: 318996480; Read time: 0; Write time: 625136 17:10:48 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:10:48 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 0; Write bytes: 270692352; Read time: 0; Write time: 194492 17:10:49 INFO - Running post-run listener: _upload_blobber_files 17:10:49 INFO - Blob upload gear active. 17:10:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:10:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:10:49 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'] 17:10:49 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 17:10:49 INFO - (blobuploader) - INFO - Open directory for files ... 17:10:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:10:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:10:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:50 INFO - (blobuploader) - INFO - Done attempting. 17:10:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:10:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:10:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:57 INFO - (blobuploader) - INFO - Done attempting. 17:10:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:10:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:10:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:59 INFO - (blobuploader) - INFO - Done attempting. 17:10:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:10:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:11:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:11:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:11:00 INFO - (blobuploader) - INFO - Done attempting. 17:11:00 INFO - (blobuploader) - INFO - Iteration through files over. 17:11:00 INFO - Return code: 0 17:11:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:11:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:11:00 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c6fe838b5fbd6e46f2cfd7ec6803da09f2d7f3d109040f3fe874aa16378c20dc1a8bf2bfbbec022ff881548dbef52390300f6064ee2599a7ecb4499fc77b2903", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/27d271843abca7bf93cdb1de09718003ebea4fecd19a8a6d36b6f4736c705da9992ef4ed82710d3229325b263e3738d4aabaca5a3c3317ecd402f165a2ac15a7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ce4dd998da3f2de72b82c6993d22f06860578a45987dfc23f69c31772c55aa00cc8ad3dfc86fbb70c9d0c7512940f43aebc0cdafc5c0d7ff5cac4f0bf7ee0519", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/64e6085b7f93f81b2c85a7397ad049271eed46bb85cb019bfb1ff0604f53e433e03fe028f40d3d52e6770ea8de6b94c5d04e0ad90403ff1c187deb4a60f9fac4"} 17:11:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:11:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:11:00 INFO - Contents: 17:11:00 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c6fe838b5fbd6e46f2cfd7ec6803da09f2d7f3d109040f3fe874aa16378c20dc1a8bf2bfbbec022ff881548dbef52390300f6064ee2599a7ecb4499fc77b2903", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/27d271843abca7bf93cdb1de09718003ebea4fecd19a8a6d36b6f4736c705da9992ef4ed82710d3229325b263e3738d4aabaca5a3c3317ecd402f165a2ac15a7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ce4dd998da3f2de72b82c6993d22f06860578a45987dfc23f69c31772c55aa00cc8ad3dfc86fbb70c9d0c7512940f43aebc0cdafc5c0d7ff5cac4f0bf7ee0519", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/64e6085b7f93f81b2c85a7397ad049271eed46bb85cb019bfb1ff0604f53e433e03fe028f40d3d52e6770ea8de6b94c5d04e0ad90403ff1c187deb4a60f9fac4"} 17:11:00 INFO - Running post-run listener: copy_logs_to_upload_dir 17:11:00 INFO - Copying logs to upload dir... 17:11:00 INFO - mkdir: /builds/slave/test/build/upload/logs 17:11:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.847512 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-18 17:11:00.594215) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-18 17:11:00.598507) ========= 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=52faae308a556c440401f8e5000001e8-1508370623.26101-956867479 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/c6fe838b5fbd6e46f2cfd7ec6803da09f2d7f3d109040f3fe874aa16378c20dc1a8bf2bfbbec022ff881548dbef52390300f6064ee2599a7ecb4499fc77b2903", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/27d271843abca7bf93cdb1de09718003ebea4fecd19a8a6d36b6f4736c705da9992ef4ed82710d3229325b263e3738d4aabaca5a3c3317ecd402f165a2ac15a7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ce4dd998da3f2de72b82c6993d22f06860578a45987dfc23f69c31772c55aa00cc8ad3dfc86fbb70c9d0c7512940f43aebc0cdafc5c0d7ff5cac4f0bf7ee0519", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/64e6085b7f93f81b2c85a7397ad049271eed46bb85cb019bfb1ff0604f53e433e03fe028f40d3d52e6770ea8de6b94c5d04e0ad90403ff1c187deb4a60f9fac4"} build_url:https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011334 build_url: 'https://queue.taskcluster.net/v1/task/EF0Tq52EQkanUOofGtjDJg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c6fe838b5fbd6e46f2cfd7ec6803da09f2d7f3d109040f3fe874aa16378c20dc1a8bf2bfbbec022ff881548dbef52390300f6064ee2599a7ecb4499fc77b2903", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/27d271843abca7bf93cdb1de09718003ebea4fecd19a8a6d36b6f4736c705da9992ef4ed82710d3229325b263e3738d4aabaca5a3c3317ecd402f165a2ac15a7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ce4dd998da3f2de72b82c6993d22f06860578a45987dfc23f69c31772c55aa00cc8ad3dfc86fbb70c9d0c7512940f43aebc0cdafc5c0d7ff5cac4f0bf7ee0519", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/64e6085b7f93f81b2c85a7397ad049271eed46bb85cb019bfb1ff0604f53e433e03fe028f40d3d52e6770ea8de6b94c5d04e0ad90403ff1c187deb4a60f9fac4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-18 17:11:00.636943) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-18 17:11:00.637309) ========= 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=52faae308a556c440401f8e5000001e8-1508370623.26101-956867479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004615 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-18 17:11:00.720354) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-18 17:11:00.720741) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-18 17:11:00.721120) ========= ========= Total master_lag: 0.20 =========