builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-067 starttime: 1506445287.02 results: success (0) revision: ea2f13a74a0d1c35af0907f47b261af9022a9816 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:27.024936) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:27.025375) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:27.025641) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:27.042717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:27.043043) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-26 10:01:27-- 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% 34.7M=0s 2017-09-26 10:01:27 (34.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135704 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:27.198346) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:27.198670) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019747 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:27.247873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-26 10:01:27.251269) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ea2f13a74a0d1c35af0907f47b261af9022a9816 --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 ea2f13a74a0d1c35af0907f47b261af9022a9816 --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=e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-26 10:01:27,637 truncating revision to first 12 chars 2017-09-26 10:01:27,637 Setting DEBUG logging. 2017-09-26 10:01:27,637 attempt 1/10 2017-09-26 10:01:27,637 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ea2f13a74a0d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-26 10:01:28,562 unpacking tar archive at: mozilla-beta-ea2f13a74a0d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.184251 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-26 10:01:28.451681) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:28.455086) ========= script_repo_revision: ea2f13a74a0d1c35af0907f47b261af9022a9816 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:28.455567) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:28.455831) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 10:01:28.472554) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2017-09-26 10:01:28.472972) ========= /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=e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:01:28 INFO - MultiFileLogger online at 20170926 10:01:28 in /builds/slave/test 10:01:28 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 10:01:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:01:28 INFO - {'append_to_log': False, 10:01:29 INFO - 'base_work_dir': '/builds/slave/test', 10:01:29 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 10:01:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:01:29 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 10:01:29 INFO - 'buildbot_json_path': 'buildprops.json', 10:01:29 INFO - 'code_coverage': False, 10:01:29 INFO - 'config_files': ('talos/linux_config.py',), 10:01:29 INFO - 'default_actions': ('clobber', 10:01:29 INFO - 'read-buildbot-config', 10:01:29 INFO - 'download-and-extract', 10:01:29 INFO - 'populate-webroot', 10:01:29 INFO - 'create-virtualenv', 10:01:29 INFO - 'install', 10:01:29 INFO - 'setup-mitmproxy', 10:01:29 INFO - 'run-tests'), 10:01:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:01:29 INFO - 'disable_ccov_upload': False, 10:01:29 INFO - 'disable_stylo': False, 10:01:29 INFO - 'download_minidump_stackwalk': True, 10:01:29 INFO - 'download_symbols': 'ondemand', 10:01:29 INFO - 'enable_stylo': False, 10:01:29 INFO - 'enable_webrender': False, 10:01:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 10:01:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:01:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:01:29 INFO - 'gecko_profile': False, 10:01:29 INFO - 'gecko_profile_interval': 0, 10:01:29 INFO - 'installer_path': 'installer.exe', 10:01:29 INFO - 'log_level': 'info', 10:01:29 INFO - 'log_name': 'talos', 10:01:29 INFO - 'log_to_console': True, 10:01:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:01:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:01:29 INFO - 'opt_config_files': (), 10:01:29 INFO - 'pip_index': False, 10:01:29 INFO - 'suite': 'dromaeojs-e10s', 10:01:29 INFO - 'system_bits': '32', 10:01:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:01:29 INFO - 'title': 'talos-linux64-ix-067', 10:01:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:01:29 INFO - 'use_talos_json': True, 10:01:29 INFO - 'verify': 'False', 10:01:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:01:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:01:29 INFO - 'work_dir': 'build'} 10:01:29 INFO - [mozharness: 2017-09-26 17:01:29.010313Z] Running clobber step. 10:01:29 INFO - Running pre-action listener: _resource_record_pre_action 10:01:29 INFO - Running main action method: clobber 10:01:29 INFO - rmtree: /builds/slave/test/build 10:01:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:01:30 INFO - Running post-action listener: _resource_record_post_action 10:01:30 INFO - [mozharness: 2017-09-26 17:01:30.547976Z] Finished clobber step (success) 10:01:30 INFO - [mozharness: 2017-09-26 17:01:30.548073Z] Running read-buildbot-config step. 10:01:30 INFO - Running pre-action listener: _resource_record_pre_action 10:01:30 INFO - Running main action method: read_buildbot_config 10:01:30 INFO - Using buildbot properties: 10:01:30 INFO - { 10:01:30 INFO - "project": "", 10:01:30 INFO - "product": "firefox", 10:01:30 INFO - "who": "ryanvm@gmail.com", 10:01:30 INFO - "installer_path": "public/build/target.tar.bz2", 10:01:30 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 10:01:30 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 10:01:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 10:01:30 INFO - "stage_platform": "linux64", 10:01:30 INFO - "basedir": "/builds/slave/test", 10:01:30 INFO - "buildnumber": 128, 10:01:30 INFO - "platform": "ubuntu64_hw", 10:01:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:01:30 INFO - "slavebuilddir": "test", 10:01:30 INFO - "taskId": "Mb-QHaiUSY6hCdxtyJQf0Q", 10:01:30 INFO - "branch": "mozilla-beta", 10:01:30 INFO - "script_repo_revision": "production", 10:01:30 INFO - "revision": "ea2f13a74a0d1c35af0907f47b261af9022a9816", 10:01:30 INFO - "slavename": "talos-linux64-ix-067", 10:01:30 INFO - "repo_path": "releases/mozilla-beta" 10:01:30 INFO - } 10:01:30 INFO - Finding installer, test and symbols from parent task. 10:01:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mb-QHaiUSY6hCdxtyJQf0Q'}, attempt #1 10:01:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mb-QHaiUSY6hCdxtyJQf0Q'}, attempt #1 10:01:32 INFO - Task dependencies: IJs3sNtqQOyJ87TYSa9wLA 10:01:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA'}, attempt #1 10:01:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.tar.bz2 10:01:33 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.test_packages.json 10:01:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.crashreporter-symbols.zip 10:01:33 INFO - Running post-action listener: _resource_record_post_action 10:01:33 INFO - [mozharness: 2017-09-26 17:01:33.586135Z] Finished read-buildbot-config step (success) 10:01:33 INFO - [mozharness: 2017-09-26 17:01:33.586354Z] Running download-and-extract step. 10:01:33 INFO - Running pre-action listener: _resource_record_pre_action 10:01:33 INFO - Running main action method: download_and_extract 10:01:33 INFO - mkdir: /builds/slave/test/build/tests 10:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:33 INFO - trying https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.test_packages.json 10:01:33 INFO - Downloading https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:01:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:01:34 INFO - Downloaded 989 bytes. 10:01:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:01:34 INFO - Using the following test package requirements: 10:01:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 10:01:34 INFO - u'common': [u'target.common.tests.zip'], 10:01:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:01:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:01:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:01:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:01:34 INFO - u'mozbase': [u'target.common.tests.zip'], 10:01:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:01:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:01:34 INFO - u'web-platform': [u'target.common.tests.zip', 10:01:34 INFO - u'target.web-platform.tests.tar.gz'], 10:01:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:01:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 10:01:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.common.tests.zip 10:01:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:01:34 INFO - Fetch https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.common.tests.zip into memory 10:01:35 INFO - Content-Length response header: 55174443 10:01:35 INFO - Bytes received: 55174443 10:01:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.talos.tests.zip 10:01:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:01:46 INFO - Fetch https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.talos.tests.zip into memory 10:01:46 INFO - Content-Length response header: 14509184 10:01:46 INFO - Bytes received: 14509184 10:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:47 INFO - trying https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.tar.bz2 10:01:47 INFO - Downloading https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:01:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:01:48 INFO - Downloaded 53465246 bytes. 10:01:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.tar.bz2 10:01:48 INFO - mkdir: /builds/slave/test/properties 10:01:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:01:48 INFO - Writing to file /builds/slave/test/properties/build_url 10:01:48 INFO - Contents: 10:01:48 INFO - build_url:https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.tar.bz2 10:01:48 INFO - Running post-action listener: _resource_record_post_action 10:01:48 INFO - Running post-action listener: find_tests_for_verification 10:01:48 INFO - Running post-action listener: set_extra_try_arguments 10:01:48 INFO - [mozharness: 2017-09-26 17:01:48.710850Z] Finished download-and-extract step (success) 10:01:48 INFO - [mozharness: 2017-09-26 17:01:48.710950Z] Running populate-webroot step. 10:01:48 INFO - Running pre-action listener: _resource_record_pre_action 10:01:48 INFO - Running main action method: populate_webroot 10:01:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:01:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['tresize', 10:01:48 INFO - 'tcanvasmark']}, 10:01:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:01:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['dromaeo_css', 10:01:48 INFO - 'kraken']}, 10:01:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:01:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:01:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:01:48 INFO - 'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:01:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:01:48 INFO - 'tests': ['damp', 'tps']}, 10:01:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:01:48 INFO - 'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['damp', 'tps']}, 10:01:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:01:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['dromaeo_dom']}, 10:01:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:01:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['basic_compositor_video', 10:01:48 INFO - 'glvideo']}, 10:01:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:01:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:01:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:01:48 INFO - 'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['ts_paint_webext', 10:01:48 INFO - 'tp5o_webext']}, 10:01:48 INFO - 'other-e10s': {'tests': ['a11yr', 10:01:48 INFO - 'ts_paint', 10:01:48 INFO - 'tpaint', 10:01:48 INFO - 'sessionrestore', 10:01:48 INFO - 'sessionrestore_many_windows', 10:01:48 INFO - 'sessionrestore_no_auto_restore', 10:01:48 INFO - 'tabpaint']}, 10:01:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['a11yr', 10:01:48 INFO - 'ts_paint', 10:01:48 INFO - 'tpaint', 10:01:48 INFO - 'sessionrestore', 10:01:48 INFO - 'sessionrestore_many_windows', 10:01:48 INFO - 'sessionrestore_no_auto_restore', 10:01:48 INFO - 'tabpaint']}, 10:01:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 10:01:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 10:01:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['perf_reftest_singletons']}, 10:01:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['bloom_basic']}, 10:01:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:01:48 INFO - 'tsvgr_opacity', 10:01:48 INFO - 'tart', 10:01:48 INFO - 'tscrollx', 10:01:48 INFO - 'tsvg_static']}, 10:01:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['tsvgx', 10:01:48 INFO - 'tsvgr_opacity', 10:01:48 INFO - 'tart', 10:01:48 INFO - 'tscrollx', 10:01:48 INFO - 'tsvg_static']}, 10:01:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:01:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:01:48 INFO - 'talos_options': ['--disable-stylo'], 10:01:48 INFO - 'tests': ['tp5o']}, 10:01:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:01:48 INFO - 'talos_options': ['--mitmproxy', 10:01:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:01:48 INFO - '--firstNonBlankPaint'], 10:01:48 INFO - 'tests': ['tp6_google', 10:01:48 INFO - 'tp6_youtube', 10:01:48 INFO - 'tp6_amazon', 10:01:48 INFO - 'tp6_facebook']}, 10:01:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:01:48 INFO - 'talos_options': ['--disable-stylo', 10:01:48 INFO - '--mitmproxy', 10:01:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:01:48 INFO - '--firstNonBlankPaint'], 10:01:48 INFO - 'tests': ['tp6_google', 10:01:48 INFO - 'tp6_youtube', 10:01:48 INFO - 'tp6_amazon', 10:01:48 INFO - 'tp6_facebook']}, 10:01:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:01:48 INFO - 'talos_options': ['--stylo-threads=1', 10:01:48 INFO - '--mitmproxy', 10:01:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:01:48 INFO - '--firstNonBlankPaint'], 10:01:48 INFO - 'tests': ['tp6_google', 10:01:48 INFO - 'tp6_youtube', 10:01:48 INFO - 'tp6_amazon', 10:01:48 INFO - 'tp6_facebook']}, 10:01:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:01:48 INFO - 'talos_options': ['--xperf_path', 10:01:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:48 INFO - 'tests': ['tp5n']}, 10:01:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 10:01:48 INFO - 'talos_options': ['--disable-stylo', 10:01:48 INFO - '--xperf_path', 10:01:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:48 INFO - 'tests': ['tp5n']}}} 10:01:48 INFO - Running post-action listener: _resource_record_post_action 10:01:48 INFO - [mozharness: 2017-09-26 17:01:48.726153Z] Finished populate-webroot step (success) 10:01:48 INFO - [mozharness: 2017-09-26 17:01:48.726244Z] Running create-virtualenv step. 10:01:48 INFO - Running pre-action listener: _resource_record_pre_action 10:01:48 INFO - Running main action method: create_virtualenv 10:01:48 INFO - Creating virtualenv /builds/slave/test/build/venv 10:01:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:01:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 10:01:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:01:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:01:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:01:49 INFO - Installing distribute.............................................................................................................................................................................................done. 10:01:49 INFO - Installing pip...............done. 10:01:49 INFO - Return code: 0 10:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:01:49 INFO - Reading from file tmpfile_stdout 10:01:49 INFO - Output received: 10:01:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 10:01:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:01: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')]} 10:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01: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')]} 10:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:01:49 INFO - Using env: {'DISPLAY': ':0', 10:01:49 INFO - 'HOME': '/home/cltbld', 10:01:49 INFO - 'LANG': 'en_US.UTF-8', 10:01:49 INFO - 'LANGUAGE': 'en_US:en', 10:01:49 INFO - 'LOGNAME': 'cltbld', 10:01:49 INFO - 'MAIL': '/var/mail/cltbld', 10:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:49 INFO - 'MOZ_NO_REMOTE': '1', 10:01:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:49 INFO - 'NO_EM_RESTART': '1', 10:01:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:49 INFO - 'PWD': '/builds/slave/test', 10:01:49 INFO - 'SHELL': '/bin/bash', 10:01:49 INFO - 'SHLVL': '1', 10:01:49 INFO - 'TERM': 'linux', 10:01:49 INFO - 'TMOUT': '86400', 10:01:49 INFO - 'USER': 'cltbld', 10:01:49 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:49 INFO - '_': '/tools/buildbot/bin/python'} 10:01:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 10:01:50 INFO - Downloading/unpacking pip>=1.5 10:01:50 INFO - Running setup.py egg_info for package pip 10:01:50 INFO - warning: no files found matching 'pip/cacert.pem' 10:01:50 INFO - warning: no files found matching '*.html' under directory 'docs' 10:01:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 10:01:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 10:01:50 INFO - Installing collected packages: pip 10:01:50 INFO - Found existing installation: pip 1.1 10:01:50 INFO - Uninstalling pip: 10:01:50 INFO - Successfully uninstalled pip 10:01:50 INFO - Running setup.py install for pip 10:01:50 INFO - warning: no files found matching 'pip/cacert.pem' 10:01:50 INFO - warning: no files found matching '*.html' under directory 'docs' 10:01:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 10:01:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 10:01:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 10:01:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 10:01:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 10:01:50 INFO - Successfully installed pip 10:01:50 INFO - Cleaning up... 10:01:50 INFO - Return code: 0 10:01:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:01:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:01:50 INFO - Using env: {'DISPLAY': ':0', 10:01:50 INFO - 'HOME': '/home/cltbld', 10:01:50 INFO - 'LANG': 'en_US.UTF-8', 10:01:50 INFO - 'LANGUAGE': 'en_US:en', 10:01:50 INFO - 'LOGNAME': 'cltbld', 10:01:50 INFO - 'MAIL': '/var/mail/cltbld', 10:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:50 INFO - 'MOZ_NO_REMOTE': '1', 10:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:50 INFO - 'NO_EM_RESTART': '1', 10:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:50 INFO - 'PWD': '/builds/slave/test', 10:01:50 INFO - 'SHELL': '/bin/bash', 10:01:50 INFO - 'SHLVL': '1', 10:01:50 INFO - 'TERM': 'linux', 10:01:50 INFO - 'TMOUT': '86400', 10:01:50 INFO - 'USER': 'cltbld', 10:01:50 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:50 INFO - '_': '/tools/buildbot/bin/python'} 10:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:51 INFO - Downloading/unpacking psutil>=3.1.1 10:01: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 10:01: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 10:01: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 10:01: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 10:01:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:01:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:01:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:01:52 INFO - Installing collected packages: psutil 10:01:52 INFO - Running setup.py install for psutil 10:01:52 INFO - building 'psutil._psutil_linux' extension 10:01:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:01:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:01:52 INFO - building 'psutil._psutil_posix' extension 10:01:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:01:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:01:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:01:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:01:52 INFO - Successfully installed psutil 10:01:52 INFO - Cleaning up... 10:01:52 INFO - Return code: 0 10:01:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:01: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')]} 10:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01: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')]} 10:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:01:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:01:52 INFO - Using env: {'DISPLAY': ':0', 10:01:52 INFO - 'HOME': '/home/cltbld', 10:01:52 INFO - 'LANG': 'en_US.UTF-8', 10:01:52 INFO - 'LANGUAGE': 'en_US:en', 10:01:52 INFO - 'LOGNAME': 'cltbld', 10:01:52 INFO - 'MAIL': '/var/mail/cltbld', 10:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:52 INFO - 'MOZ_NO_REMOTE': '1', 10:01:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:52 INFO - 'NO_EM_RESTART': '1', 10:01:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:52 INFO - 'PWD': '/builds/slave/test', 10:01:52 INFO - 'SHELL': '/bin/bash', 10:01:52 INFO - 'SHLVL': '1', 10:01:52 INFO - 'TERM': 'linux', 10:01:52 INFO - 'TMOUT': '86400', 10:01:52 INFO - 'USER': 'cltbld', 10:01:52 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:52 INFO - '_': '/tools/buildbot/bin/python'} 10:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:01:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:01:54 INFO - Installing collected packages: mozsystemmonitor 10:01:54 INFO - Running setup.py install for mozsystemmonitor 10:01:54 INFO - Successfully installed mozsystemmonitor 10:01:54 INFO - Cleaning up... 10:01:54 INFO - Return code: 0 10:01:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:01: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')]} 10:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01: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')]} 10:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:01:54 INFO - Using env: {'DISPLAY': ':0', 10:01:54 INFO - 'HOME': '/home/cltbld', 10:01:54 INFO - 'LANG': 'en_US.UTF-8', 10:01:54 INFO - 'LANGUAGE': 'en_US:en', 10:01:54 INFO - 'LOGNAME': 'cltbld', 10:01:54 INFO - 'MAIL': '/var/mail/cltbld', 10:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:54 INFO - 'MOZ_NO_REMOTE': '1', 10:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:54 INFO - 'NO_EM_RESTART': '1', 10:01:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:54 INFO - 'PWD': '/builds/slave/test', 10:01:54 INFO - 'SHELL': '/bin/bash', 10:01:54 INFO - 'SHLVL': '1', 10:01:54 INFO - 'TERM': 'linux', 10:01:54 INFO - 'TMOUT': '86400', 10:01:54 INFO - 'USER': 'cltbld', 10:01:54 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:54 INFO - '_': '/tools/buildbot/bin/python'} 10:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:54 INFO - Downloading/unpacking jsonschema==2.5.1 10:01: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 10:01: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 10:01: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 10:01: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 10:01:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:01:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:55 INFO - Downloading functools32-3.2.3-2.tar.gz 10:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:01:55 INFO - warning: no files found matching '*.txt' 10:01:55 INFO - no previously-included directories found matching 'build' 10:01:55 INFO - no previously-included directories found matching 'dist' 10:01:55 INFO - no previously-included directories found matching '.git*' 10:01:55 INFO - Installing collected packages: jsonschema, functools32 10:01:55 INFO - Running setup.py install for functools32 10:01:56 INFO - warning: no files found matching '*.txt' 10:01:56 INFO - no previously-included directories found matching 'build' 10:01:56 INFO - no previously-included directories found matching 'dist' 10:01:56 INFO - no previously-included directories found matching '.git*' 10:01:56 INFO - Successfully installed jsonschema functools32 10:01:56 INFO - Cleaning up... 10:01:56 INFO - Return code: 0 10:01:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:01:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:01:56 INFO - Using env: {'DISPLAY': ':0', 10:01:56 INFO - 'HOME': '/home/cltbld', 10:01:56 INFO - 'LANG': 'en_US.UTF-8', 10:01:56 INFO - 'LANGUAGE': 'en_US:en', 10:01:56 INFO - 'LOGNAME': 'cltbld', 10:01:56 INFO - 'MAIL': '/var/mail/cltbld', 10:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:56 INFO - 'MOZ_NO_REMOTE': '1', 10:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:56 INFO - 'NO_EM_RESTART': '1', 10:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:56 INFO - 'PWD': '/builds/slave/test', 10:01:56 INFO - 'SHELL': '/bin/bash', 10:01:56 INFO - 'SHLVL': '1', 10:01:56 INFO - 'TERM': 'linux', 10:01:56 INFO - 'TMOUT': '86400', 10:01:56 INFO - 'USER': 'cltbld', 10:01:56 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:56 INFO - '_': '/tools/buildbot/bin/python'} 10:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:01:56 INFO - Cleaning up... 10:01:56 INFO - Return code: 0 10:01:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:01:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:01:56 INFO - Using env: {'DISPLAY': ':0', 10:01:56 INFO - 'HOME': '/home/cltbld', 10:01:56 INFO - 'LANG': 'en_US.UTF-8', 10:01:56 INFO - 'LANGUAGE': 'en_US:en', 10:01:56 INFO - 'LOGNAME': 'cltbld', 10:01:56 INFO - 'MAIL': '/var/mail/cltbld', 10:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:56 INFO - 'MOZ_NO_REMOTE': '1', 10:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:56 INFO - 'NO_EM_RESTART': '1', 10:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:56 INFO - 'PWD': '/builds/slave/test', 10:01:56 INFO - 'SHELL': '/bin/bash', 10:01:56 INFO - 'SHLVL': '1', 10:01:56 INFO - 'TERM': 'linux', 10:01:56 INFO - 'TMOUT': '86400', 10:01:56 INFO - 'USER': 'cltbld', 10:01:56 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:56 INFO - '_': '/tools/buildbot/bin/python'} 10:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:56 INFO - Downloading/unpacking blobuploader==1.2.4 10:01: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 10:01: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 10:01: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 10:01: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 10:01:57 INFO - Downloading blobuploader-1.2.4.tar.gz 10:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:01:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:01:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:58 INFO - Downloading docopt-0.6.1.tar.gz 10:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:01:58 INFO - Installing collected packages: blobuploader, requests, docopt 10:01:58 INFO - Running setup.py install for blobuploader 10:01:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:01:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:01:58 INFO - Running setup.py install for requests 10:01:58 INFO - Running setup.py install for docopt 10:01:58 INFO - Successfully installed blobuploader requests docopt 10:01:58 INFO - Cleaning up... 10:01:58 INFO - Return code: 0 10:01:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:58 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 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:01:58 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 10:01:58 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 10:01:58 INFO - Using env: {'DISPLAY': ':0', 10:01:58 INFO - 'HOME': '/home/cltbld', 10:01:58 INFO - 'LANG': 'en_US.UTF-8', 10:01:58 INFO - 'LANGUAGE': 'en_US:en', 10:01:58 INFO - 'LOGNAME': 'cltbld', 10:01:58 INFO - 'MAIL': '/var/mail/cltbld', 10:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:58 INFO - 'MOZ_NO_REMOTE': '1', 10:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:58 INFO - 'NO_EM_RESTART': '1', 10:01:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:58 INFO - 'PWD': '/builds/slave/test', 10:01:58 INFO - 'SHELL': '/bin/bash', 10:01:58 INFO - 'SHLVL': '1', 10:01:58 INFO - 'TERM': 'linux', 10:01:58 INFO - 'TMOUT': '86400', 10:01:58 INFO - 'USER': 'cltbld', 10:01:58 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:58 INFO - '_': '/tools/buildbot/bin/python'} 10:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:01:59 INFO - Running setup.py (path:/tmp/pip-KbLoqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:01:59 INFO - Running setup.py (path:/tmp/pip-gSb_8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:01:59 INFO - Running setup.py (path:/tmp/pip-8QFD15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:01:59 INFO - Running setup.py (path:/tmp/pip-OBEiv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:01:59 INFO - Running setup.py (path:/tmp/pip-LB1jRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:01:59 INFO - Running setup.py (path:/tmp/pip-jMcM0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:01:59 INFO - Running setup.py (path:/tmp/pip-dHwzYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:01:59 INFO - Running setup.py (path:/tmp/pip-moF1ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:01:59 INFO - Running setup.py (path:/tmp/pip-iez_PU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:01:59 INFO - Running setup.py (path:/tmp/pip-gZYGfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:01:59 INFO - Running setup.py (path:/tmp/pip-SPb1YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:01:59 INFO - Running setup.py (path:/tmp/pip-QsSCxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:01:59 INFO - Running setup.py (path:/tmp/pip-7hMiot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:01:59 INFO - Running setup.py (path:/tmp/pip-eUkEpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:02:00 INFO - Running setup.py (path:/tmp/pip-YZbiCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:02:00 INFO - Running setup.py (path:/tmp/pip-6CUJb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:02:00 INFO - Running setup.py (path:/tmp/pip-c0LzlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:02:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:02:00 INFO - Running setup.py install for manifestparser 10:02:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:02:00 INFO - Running setup.py install for mozcrash 10:02:00 INFO - Running setup.py install for mozdebug 10:02:00 INFO - Running setup.py install for mozdevice 10:02:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:02:00 INFO - Running setup.py install for mozfile 10:02:00 INFO - Running setup.py install for mozhttpd 10:02:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:02:00 INFO - Running setup.py install for mozinfo 10:02:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:02:00 INFO - Running setup.py install for mozInstall 10:02:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Running setup.py install for mozleak 10:02:01 INFO - Running setup.py install for mozlog 10:02:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Running setup.py install for moznetwork 10:02:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Running setup.py install for mozprocess 10:02:01 INFO - Running setup.py install for mozprofile 10:02:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Running setup.py install for mozrunner 10:02:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Running setup.py install for mozscreenshot 10:02:01 INFO - Running setup.py install for moztest 10:02:01 INFO - Running setup.py install for mozversion 10:02:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:02:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:02:01 INFO - Cleaning up... 10:02:01 INFO - Return code: 0 10:02:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:02:01 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')]} 10:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:01 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')]} 10:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:01 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 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:02:01 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 10:02:01 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 10:02:02 INFO - Using env: {'DISPLAY': ':0', 10:02:02 INFO - 'HOME': '/home/cltbld', 10:02:02 INFO - 'LANG': 'en_US.UTF-8', 10:02:02 INFO - 'LANGUAGE': 'en_US:en', 10:02:02 INFO - 'LOGNAME': 'cltbld', 10:02:02 INFO - 'MAIL': '/var/mail/cltbld', 10:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:02 INFO - 'MOZ_NO_REMOTE': '1', 10:02:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:02:02 INFO - 'NO_EM_RESTART': '1', 10:02:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:02:02 INFO - 'PWD': '/builds/slave/test', 10:02:02 INFO - 'SHELL': '/bin/bash', 10:02:02 INFO - 'SHLVL': '1', 10:02:02 INFO - 'TERM': 'linux', 10:02:02 INFO - 'TMOUT': '86400', 10:02:02 INFO - 'USER': 'cltbld', 10:02:02 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:02:02 INFO - '_': '/tools/buildbot/bin/python'} 10:02:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:02:02 INFO - Running setup.py (path:/tmp/pip-WCCp4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:02:02 INFO - Running setup.py (path:/tmp/pip-g3SXZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:02:02 INFO - Running setup.py (path:/tmp/pip-1cbOHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:02:02 INFO - Running setup.py (path:/tmp/pip-Bc1Aqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:02:02 INFO - Running setup.py (path:/tmp/pip-87PtfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:02:02 INFO - Running setup.py (path:/tmp/pip-ODT74b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:02:02 INFO - Running setup.py (path:/tmp/pip-s5NHah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:02:02 INFO - Running setup.py (path:/tmp/pip-2dpL76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:02:02 INFO - Running setup.py (path:/tmp/pip-fPt6HU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:02:02 INFO - Running setup.py (path:/tmp/pip-ZI_ybZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:02:02 INFO - Running setup.py (path:/tmp/pip-8sDioJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:02:02 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)) 10:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:02:03 INFO - Running setup.py (path:/tmp/pip-DtLUeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:02:03 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)) 10:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:02:03 INFO - Running setup.py (path:/tmp/pip-BRduvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:02:03 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)) 10:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:02:03 INFO - Running setup.py (path:/tmp/pip-t0jQpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:02:03 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)) 10:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:02:03 INFO - Running setup.py (path:/tmp/pip-ppx3IK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:02:03 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)) 10:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:02:03 INFO - Running setup.py (path:/tmp/pip-ITLkKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:02:03 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)) 10:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:02:03 INFO - Running setup.py (path:/tmp/pip-LlAS0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:02:03 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)) 10:02:03 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)) 10:02:03 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)) 10:02:03 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)) 10:02:03 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)) 10:02:03 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)) 10:02:03 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)) 10:02:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:02: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 10:02: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 10:02: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 10:02: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 10:02:04 INFO - Downloading blessings-1.6.tar.gz 10:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:02:04 INFO - Installing collected packages: blessings 10:02:04 INFO - Running setup.py install for blessings 10:02:04 INFO - Successfully installed blessings 10:02:04 INFO - Cleaning up... 10:02:04 INFO - Return code: 0 10:02:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:02:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:02:04 INFO - Reading from file tmpfile_stdout 10:02:04 INFO - Current package versions: 10:02:04 INFO - argparse == 1.2.1 10:02:04 INFO - blessings == 1.6 10:02:04 INFO - blobuploader == 1.2.4 10:02:04 INFO - docopt == 0.6.1 10:02:04 INFO - functools32 == 3.2.3-2 10:02:04 INFO - jsonschema == 2.5.1 10:02:04 INFO - manifestparser == 1.1 10:02:04 INFO - mozInstall == 1.13 10:02:04 INFO - mozcrash == 1.0 10:02:04 INFO - mozdebug == 0.1 10:02:04 INFO - mozdevice == 0.51 10:02:04 INFO - mozfile == 1.2 10:02:04 INFO - mozhttpd == 0.7 10:02:04 INFO - mozinfo == 0.10 10:02:04 INFO - mozleak == 0.1 10:02:04 INFO - mozlog == 3.5 10:02:04 INFO - moznetwork == 0.27 10:02:04 INFO - mozprocess == 0.25 10:02:04 INFO - mozprofile == 0.28 10:02:04 INFO - mozrunner == 6.13 10:02:04 INFO - mozscreenshot == 0.1 10:02:04 INFO - mozsystemmonitor == 0.3 10:02:04 INFO - moztest == 0.8 10:02:04 INFO - mozversion == 1.4 10:02:04 INFO - psutil == 3.1.1 10:02:04 INFO - requests == 1.2.3 10:02:04 INFO - wsgiref == 0.1.2 10:02:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:04 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 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:02:04 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 10:02:04 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 10:02:04 INFO - Using env: {'DISPLAY': ':0', 10:02:04 INFO - 'HOME': '/home/cltbld', 10:02:04 INFO - 'LANG': 'en_US.UTF-8', 10:02:04 INFO - 'LANGUAGE': 'en_US:en', 10:02:04 INFO - 'LOGNAME': 'cltbld', 10:02:04 INFO - 'MAIL': '/var/mail/cltbld', 10:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:04 INFO - 'MOZ_NO_REMOTE': '1', 10:02:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:02:04 INFO - 'NO_EM_RESTART': '1', 10:02:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:02:04 INFO - 'PWD': '/builds/slave/test', 10:02:04 INFO - 'SHELL': '/bin/bash', 10:02:04 INFO - 'SHLVL': '1', 10:02:04 INFO - 'TERM': 'linux', 10:02:04 INFO - 'TMOUT': '86400', 10:02:04 INFO - 'USER': 'cltbld', 10:02:04 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:02:04 INFO - '_': '/tools/buildbot/bin/python'} 10:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:05 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)) 10:02:05 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)) 10:02:05 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)) 10:02:05 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)) 10:02:05 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)) 10:02:05 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)) 10:02:05 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)) 10:02:05 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)) 10:02:05 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)) 10:02:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 10:02:06 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)) 10:02:06 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)) 10:02:06 INFO - Installing collected packages: simplejson 10:02:06 INFO - Running setup.py install for simplejson 10:02:06 INFO - building 'simplejson._speedups' extension 10:02:06 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 10:02:07 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 10:02:07 INFO - Successfully installed simplejson 10:02:07 INFO - Cleaning up... 10:02:07 INFO - Return code: 0 10:02:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:07 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 0x26881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2850a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', '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 10:02:07 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 10:02:07 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 10:02:07 INFO - Using env: {'DISPLAY': ':0', 10:02:07 INFO - 'HOME': '/home/cltbld', 10:02:07 INFO - 'LANG': 'en_US.UTF-8', 10:02:07 INFO - 'LANGUAGE': 'en_US:en', 10:02:07 INFO - 'LOGNAME': 'cltbld', 10:02:07 INFO - 'MAIL': '/var/mail/cltbld', 10:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:07 INFO - 'MOZ_NO_REMOTE': '1', 10:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:02:07 INFO - 'NO_EM_RESTART': '1', 10:02:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:02:07 INFO - 'PWD': '/builds/slave/test', 10:02:07 INFO - 'SHELL': '/bin/bash', 10:02:07 INFO - 'SHLVL': '1', 10:02:07 INFO - 'TERM': 'linux', 10:02:07 INFO - 'TMOUT': '86400', 10:02:07 INFO - 'USER': 'cltbld', 10:02:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:02:07 INFO - '_': '/tools/buildbot/bin/python'} 10:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:02:07 INFO - Cleaning up... 10:02:07 INFO - Return code: 0 10:02:07 INFO - Running post-action listener: _resource_record_post_action 10:02:07 INFO - Running post-action listener: _start_resource_monitoring 10:02:07 INFO - Starting resource monitoring. 10:02:07 INFO - [mozharness: 2017-09-26 17:02:07.720382Z] Finished create-virtualenv step (success) 10:02:07 INFO - [mozharness: 2017-09-26 17:02:07.720909Z] Running install step. 10:02:07 INFO - Running pre-action listener: _resource_record_pre_action 10:02:07 INFO - Running main action method: install 10:02:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:02:07 INFO - Reading from file tmpfile_stdout 10:02:07 INFO - Detecting whether we're running mozinstall >=1.0... 10:02:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:02:08 INFO - Reading from file tmpfile_stdout 10:02:08 INFO - Output received: 10:02:08 INFO - Usage: mozinstall [options] installer 10:02:08 INFO - Options: 10:02:08 INFO - -h, --help show this help message and exit 10:02:08 INFO - -d DEST, --destination=DEST 10:02:08 INFO - Directory to install application into. [default: 10:02:08 INFO - "/builds/slave/test"] 10:02:08 INFO - --app=APP Application being installed. [default: firefox] 10:02:08 INFO - mkdir: /builds/slave/test/build/application 10:02:08 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'] 10:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:02:22 INFO - Reading from file tmpfile_stdout 10:02:22 INFO - Output received: 10:02:22 INFO - /builds/slave/test/build/application/firefox/firefox 10:02:22 INFO - Running post-action listener: _resource_record_post_action 10:02:22 INFO - [mozharness: 2017-09-26 17:02:22.784191Z] Finished install step (success) 10:02:22 INFO - [mozharness: 2017-09-26 17:02:22.784520Z] Running setup-mitmproxy step. 10:02:22 INFO - Running pre-action listener: _resource_record_pre_action 10:02:22 INFO - Running main action method: setup_mitmproxy 10:02:22 INFO - Skipping: mitmproxy is not required 10:02:22 INFO - Running post-action listener: _resource_record_post_action 10:02:22 INFO - [mozharness: 2017-09-26 17:02:22.785634Z] Finished setup-mitmproxy step (success) 10:02:22 INFO - [mozharness: 2017-09-26 17:02:22.785852Z] Running run-tests step. 10:02:22 INFO - Running pre-action listener: _resource_record_pre_action 10:02:22 INFO - Running pre-action listener: _set_gcov_prefix 10:02:22 INFO - Running main action method: run_tests 10:02:22 WARNING - Try message not found. 10:02:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:02:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:02:22 INFO - Python 2.7.3 10:02:22 INFO - Return code: 0 10:02:22 INFO - grabbing minidump binary from tooltool 10:02:22 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')]} 10:02:22 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 0x29f7090>, '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 0x29f7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f1f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:02:22 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 10:02:22 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 10:02:22 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 10:02:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:02:22 INFO - Return code: 0 10:02:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:02:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:02:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:02:22 INFO - ENV: RUST_BACKTRACE is now full 10:02:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:02:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:02:22 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/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 10:02:22 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/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 10:02:22 INFO - Using env: {'DISPLAY': ':0', 10:02:22 INFO - 'HOME': '/home/cltbld', 10:02:22 INFO - 'LANG': 'en_US.UTF-8', 10:02:22 INFO - 'LANGUAGE': 'en_US:en', 10:02:22 INFO - 'LOGNAME': 'cltbld', 10:02:22 INFO - 'MAIL': '/var/mail/cltbld', 10:02:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:02:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:22 INFO - 'MOZ_NO_REMOTE': '1', 10:02:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:02:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:02:22 INFO - 'NO_EM_RESTART': '1', 10:02:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:02:22 INFO - 'PWD': '/builds/slave/test', 10:02:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:02:22 INFO - 'RUST_BACKTRACE': 'full', 10:02:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:02:22 INFO - 'SHELL': '/bin/bash', 10:02:22 INFO - 'SHLVL': '1', 10:02:22 INFO - 'TERM': 'linux', 10:02:22 INFO - 'TMOUT': '86400', 10:02:22 INFO - 'USER': 'cltbld', 10:02:22 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:02:22 INFO - '_': '/tools/buildbot/bin/python'} 10:02:22 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/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 10:02:23 INFO - mozversion application_buildid: 20170926154407 10:02:23 INFO - mozversion application_changeset: ea2f13a74a0d1c35af0907f47b261af9022a9816 10:02:23 INFO - mozversion application_display_name: Firefox 10:02:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:02:23 INFO - mozversion application_name: Firefox 10:02:23 INFO - mozversion application_remotingname: firefox 10:02:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:02:23 INFO - mozversion application_vendor: Mozilla 10:02:23 INFO - mozversion application_version: 57.0 10:02:23 INFO - mozversion platform_buildid: 20170926154407 10:02:23 INFO - mozversion platform_changeset: ea2f13a74a0d1c35af0907f47b261af9022a9816 10:02:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:02:23 INFO - mozversion platform_version: 57.0 10:02:23 INFO - using testdate: 1506445343 10:02:23 INFO - actual date: 1506445343 10:02:23 INFO - starting webserver on 'localhost:48007' 10:02:23 INFO - SUITE-START | Running 2 tests 10:02:23 INFO - TEST-START | dromaeo_css 10:02:23 INFO - Initialising browser for dromaeo_css test... 10:02:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48007/getInfo.html -profile /tmp/tmpScgG1P/profile 10:02:23 INFO - TEST-INFO | started process 28608 (/builds/slave/test/build/application/firefox/firefox http://localhost:48007/getInfo.html) 10:02:27 INFO - TEST-INFO | 28608: exit 0 10:02:27 INFO - Browser initialized. 10:02:27 INFO - Running cycle 1/1 for dromaeo_css test... 10:02:27 INFO - Using env: {'DISPLAY': ':0', 10:02:27 INFO - 'HOME': '/home/cltbld', 10:02:27 INFO - 'JSGC_DISABLE_POISONING': '1', 10:02:27 INFO - 'LANG': 'en_US.UTF-8', 10:02:27 INFO - 'LANGUAGE': 'en_US:en', 10:02:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 10:02:27 INFO - 'LOGNAME': 'cltbld', 10:02:27 INFO - 'MAIL': '/var/mail/cltbld', 10:02:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:02:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:02:27 INFO - 'MOZ_CRASHREPORTER': '1', 10:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 10:02:27 INFO - 'MOZ_NO_REMOTE': '1', 10:02:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:02:27 INFO - 'NO_EM_RESTART': '1', 10:02:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:02:27 INFO - 'PWD': '/builds/slave/test', 10:02:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:02:27 INFO - 'RUST_BACKTRACE': 'full', 10:02:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:02:27 INFO - 'SHELL': '/bin/bash', 10:02:27 INFO - 'SHLVL': '1', 10:02:27 INFO - 'TERM': 'linux', 10:02:27 INFO - 'TMOUT': '86400', 10:02:27 INFO - 'USER': 'cltbld', 10:02:27 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:02:27 INFO - '_': '/tools/buildbot/bin/python'} 10:02:27 INFO - TEST-INFO | started process 28800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpScgG1P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:02:28 INFO - PID 28800 | 10:02:28 INFO - PID 28800 | (/builds/slave/test/build/application/firefox/firefox:28848): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 10:02:28 INFO - PID 28800 | 10:02:39 INFO - PID 28800 | RSS: Main: 143224832 10:02:39 INFO - PID 28800 | 10:05:36 INFO - PID 28800 | Cycle 1(1): loaded http://localhost:48007/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48007/tests/dromaeo/cssquery-ext.html) 10:05:37 INFO - PID 28800 | RSS: Main: 145887232 10:05:37 INFO - PID 28800 | 10:08:39 INFO - PID 28800 | Cycle 1(1): loaded http://localhost:48007/tests/dromaeo/cssquery-ext.html (next: http://localhost:48007/tests/dromaeo/cssquery-jquery.html) 10:08:39 INFO - PID 28800 | RSS: Main: 144621568 10:08:39 INFO - PID 28800 | 10:11:41 INFO - PID 28800 | Cycle 1(1): loaded http://localhost:48007/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48007/tests/dromaeo/cssquery-mootools.html) 10:11:42 INFO - PID 28800 | RSS: Main: 145883136 10:11:42 INFO - PID 28800 | 10:14:43 INFO - PID 28800 | Cycle 1(1): loaded http://localhost:48007/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48007/tests/dromaeo/cssquery-prototype.html) 10:14:44 INFO - PID 28800 | RSS: Main: 145735680 10:14:44 INFO - PID 28800 | 10:17:47 INFO - PID 28800 | Cycle 1(1): loaded http://localhost:48007/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48007/tests/dromaeo/cssquery-yui.html) 10:17:47 INFO - PID 28800 | RSS: Main: 147169280 10:17:47 INFO - PID 28800 | 10:20:49 INFO - PID 28800 | Cycle 1(1): loaded http://localhost:48007/tests/dromaeo/cssquery-yui.html (next: http://localhost:48007/tests/dromaeo/cssquery-dojo.html) 10:20:49 INFO - PID 28800 | RSS: Main: 149618688 10:20:49 INFO - PID 28800 | 10:20:49 INFO - PID 28800 | __start_tp_report 10:20:49 INFO - PID 28800 | _x_x_mozilla_page_load 10:20:49 INFO - PID 28800 | _x_x_mozilla_page_load_details 10:20:49 INFO - PID 28800 | |i|pagename|runs| 10:20:49 INFO - PID 28800 | |0;dojo.html;4047.8785636430916;4055.128147448298;4058.7767672777986;4063.2686116499026;4090.0388408723934;6513.544051916368;6516.250631177416;6571.145751052357;6693.966530167355;6699.330066993298;6572.244191917931;6580.572262802914;6583.7037333319995;6654.367835055674;6656.866862662756;5901.17383566301;5959.761609535613;5981.9700901495435;5989.970050149765;5996.400359964002;5940.346561878211;5974.4921681657115;5985.042393217086;6077.665728384959;6089.299730531001;5999.280086389639;6002.249718785152;6052.334243233241;6058.485028772559;6071.726772295236;5962;6064.363241859607;6091.0558863376245;6105.023196288595;6127.325994140686;5925.466707996258;5986.161937328778;6014.578979471439;6070.423309785563;6110.205673262448;6444.742210311582;6460.644664543448;6483.254425741005;6519.185101862267;6569.770058047945;7160.749373771945;7164.498485106044;7166.319199676022;7168.139823221247;7171.569705817667;7090.858182836314;7200.639968001579;7206;7257.854842903165;7263.745768898116;6626.7017984190825;6655.733770649216;6726.966365168192;6752.5948443093575;6770.898436523456;7310.268973102648;7327.032551816523;7422.220666830013;7422.257774222535;7438.6280685965485;7052.153741551047;7057.505974581833;7135.357817796424;7140.571565706075;7156.320149585885;7158.105236845395;7212.639368031578;7242.891356629655;7283;7297.452691048193;7145.964270178616;7169.354758071799;7176.82057948545;7181.102362204724;7208.4954053216825;7094.716211351488;7110;7167.390771784352;7179.605121718251;7263.0921134858145;5267.841964741064;5275.920861187085;5344.385395679469;5358.571314294847;5364.30264065669;4774.737389443614;4777.235642297216;4838.250071238964;4848.612111031109;4935.284383764334;6690.866182676319;6779.72881084761;6779.796606101825;6785.694643740945;6788.049673045777;7011.3339232772805;7127.4298056155385;7130.750423735248;7218.494705370574;7221.025161603221;6537.313582073813;6596.208454985336;6601.504887133484;6687;6775.1192344995825;6002.819915402545;6012.939870601325;6018.909716354258;6035.185249991195;6044.455998960027;7465.477416580896;7465.738699145504;7571.886421703679;7572.810679733051;7594.31651151399;7035.437165026888;7125.572465652078;7228.349448549656;7233.770259056078;7245.275472452712;7367.631618419165;7455.105387353552;7508.0990281166605;7519.022527071445;7543.057117860268;7091.929080709333;7098.751543696152;7162.176349719849;7165.247648996989;7181.856362872609;7152.713891444492;7186.137663480206;7201.207867134715;7243.348098671145;7290.234525374759;6902.654867256718;6926.965365173141;6934.93065069363;6936.965315173392;6941.722331106699;6902.447804175554;6928.792136235921;6987.336203060701;7000.47496437759;7027.086478757728;6789.762358317435;6795.966020169867;6803.9319606805275;6874.140732408449;6888.690008949709;6876.621785801829;6946.687399066953;6974.616396098263;7031.929680703128;7073.681684324115;6729.327067293231;6818.693158807766;6831.726730930707;6837.863242735217;6845.897311540231 10:20:49 INFO - PID 28800 | |1;ext.html;987.7629368951452;994.3288280410721;1018.2770233134474;1022.8925962773909;1023.5291765787737;47531.76234118829;48073.759631201836;48111.27833082502;48198.27702584464;48270.51729482704;45621.54378456214;45719;45772.542274577325;45883.54116458834;46103.53896461034;8051.476654017485;8194.508329500219;8203.95898020509;8223.753287401387;8246.216609422096;8052.516848989079;8157.632906519191;8158.5104893706275;8160.6327715252955;8173.182681731839;8088.716894908679;8775.648974041063;8973.820523589524;8996.730098097067;9008.279337653004;8863.822723545525;8888.955555222183;8925.55372231389;8928.464292142427;8938.776530586721;8483;8572.399932004762;8573.485590864568;8581.613827377783;8583.742487725378;42471.36292955608;42650;42707;42755.14489710188;42901.57098429007;3895.7078219133678;3944.2111577684577;3944.2900277949957;3946.131850992777;3948.545917219528;35660;35722;35783.28433431317;35809.82095089534;35821.28357432863;5672.631278966854;5679.318481782213;5694.142083924158;5695.857603559903;5724.971375143139;234442;235699;235955;236484.81757591275;236781;50367.49632503628;50407.747961259956;57784;57881.710591446776;58947.41052589419;61371.38628613746;61379.38620613826;61601.69199153975;61625;61751.38248617546;18714;18802.52993675169;18912.148953297135;18950.43148705541;19003.524911877037;15402.6149346265;15462.690746185013;15500.147491887828;15727.370905163665;15782;15990.520284391487;16073.196340182943;16086.276117574504;16197.757033644504;16207.837921620869;6210.72051757672;6259.248890133122;6260.7495700171485;6293.671341603636;6525;1117.9720506987228;1121.3552207480568;1131.852859128308;1145.7021174494691;1158.8377627132209;26322.6051609226;26553;26791;26966.32584185372;27256.863715681295;1233.2045830439317;1237.6472705278952;1253.849538055439;1257.8176164456102;1263.3304348695206;14053.578392648236;14136.575902722934;14163.716725665428;14174.220417877115;14312.06971546846;274656;278203;278599;279570;280231;62804.685976569825;63054;63118.368816311246;63134;63135;1547.8220004699604;1558.6960542694067;1592.4824432059627;1599.2163839718455;1603.7193491139003;25944.870275649257;26392.472150557267;26436;26498.7350126504;26679;43969;44001.45994890163;44060;44098.11803763971;44145.117097657225;231848;231921;233116;234179;234977;39501.2099758009;42012;42541.57458425376;42874.35688464738;43075.353869692604;54439.455605443436;54698.45301547093;54752.45247547473;54826.72586637041;55041.72479137579;38582.2283554333;38622.42066369063;38910.80544597259;39020.414693780185;39568.80215598903;38838.41742373923;38941.44234230599;39342.803285983384;39351.40972885466;39773.60226397699;46035;46653.76673116613;46754.29868552042;46832;47176.29235561538;37730;37995;38420.231595367375;38565.6143438562;38712.22575548417;27640.723592763814;28931;29086.27284317909;29255.268618283862;29361 10:20:49 INFO - PID 28800 | |2;jquery.html;623.3236937922354;629.1066685306864;636.703932671308;643.0868167202573;651.1339917909181;24377.390565235855;24987.875060624672;25100;25405.491890162208;25802.354941126483;27902.720972790266;28399.43201135976;28414.573781393243;28445.715542844566;28449.715502845018;6610.636414997173;6883.208431030421;6902.654867256642;6980.511364204508;7009.088818453594;6927.3072692730575;6975.5814651120845;6985.720571177147;7010.1587809462935;7019.157701075878;6926.549774264682;6932.826679333032;6972.267911869257;6976.267491913352;6980.685869135901;6857.245702972694;6908.309169083102;6934.549254298455;6945.340192681687;6965.442764578822;6484.6433446160445;6492.610443373413;6510.153680021567;6524.836879078013;6529.2817790043055;31279.218019549466;33496.33007339864;33634.49548256778;33687.831560842285;33807.492887606706;2240.0815665577034;2274.1358283852196;2275.488015196584;2293.0827668932393;2294.093832935998;21308.680369794467;21637.783622163734;22055;22272.88863555672;22572.774272257397;2625.868706564664;2640.511505371493;2673.1445937300073;2675.5852842809395;2683.5303821831103;148056;154598;162833.371666283;168567;169042;17037.829621703873;17565.473035808947;17635.55911102207;17658.91170544139;18078;28239.294017649314;28244.293892652437;28386;28400.431991360056;28414;11822.822657660143;11842.585509507126;11861;11908.702282442835;12032.699182520508;11057.944710276397;11105.555777768799;11161.441927903572;11171.83242251367;11344.546218151347;10000.249881306467;10408.47957602132;10462.372257664565;10599.629012984662;10705.87588303217;2927.5462303343015;2942.5880376747273;2966.258435391152;3020.1694534002886;3038.8632511537035;1049.7795462952713;1064.808334499782;1074.6775967209805;1087.2497976396262;1090.4057288777635;22369.88815055947;22719.432014199447;22728.65907011396;22809.429764256027;22829.885850570972;1146.1117633833846;1154.2439701995206;1160.4545863444168;1162.354893034374;1164.138537482257;12211.633650990485;12315.014798816073;12484.81272780909;12530.81203781944;12570;621907;623679;626061;646150;648721;138357.3082134603;141717;143780;144501;147578;1228.9078319126281;1244.3653736594401;1264.9177803442747;1271.0340141492347;1271.8855303022772;19859.503512412306;20219;20232.69650955266;20460.283890063776;20499.590008199455;63426.682866587216;63997;64923.35076649362;65111;65612.67193664;79283.60358198172;79574.60212698899;79617.60191199007;80354.59822701084;80635.19364806512;16268.593285167966;16327.59181020484;16409.507714768577;16515.58711032234;16533.421330253383;24557.894894730136;24580.877095614407;24876.37809054788;25247.495050099264;25289.62065568981;19680.60638787245;19808.306709265107;19985.400437986882;20122.19511219535;20133.49666258355;17925.731114033562;18247;18475.353362632242;18708.7193692092;18856.811431886053;21204.15183392647;21265.149394024684;21306.680399794324;21523.784762152805;21722.2397216103;16194.190290485663;16316.67366652684;16332.918335408247;16351.67296654084;16423.343066277215;20286.289979850633;20416.897915510326;20469.2835750748;20519.281825136048;20578.485537861674 10:20:49 INFO - PID 28800 | |3;mootools.html;1409.1826740490512;1473.2265560580695;1478.090974050959;1485.2944851195684;1486.7546854768964;17308.91345543272;17949.19228634711;17983.19075641596;17996.55008624785;18051.458456246302;17476;17496.737548936744;17606.20772065257;17628;17675;3068.601081859355;3151.086185006345;3201.8719251229923;3205.7916235444623;3224.2423030587793;3013.9397212055746;3054.831984240866;3059.2351912021995;3068.0642404066753;3082.6300843898653;3076.1694342527503;3093.64423091344;3102.565640810288;3103.301757104656;3104.3480869017535;3088.938221235574;3108.3161704424992;3113.9221519462194;3135.7648176386747;3138.8116712997066;2880.2079428157217;3044.4824379855477;3075.354175623122;3086.0587520806143;3088.5212792017273;17155.57111072221;17188.140592970303;17273.568160795956;17317.56706082358;17444.47666570005;9244.815103697889;9517.524123793783;9531.428114313163;9546.140847323704;9560.90439095614;5749.626274292157;6023.518118550549;6041.365656606037;6141.170941922828;6143.4470897618985;2202.9559408811892;2210.060724192212;2223.2440970070093;2226.1651880544796;2273.3975496493445;31856.522152167738;31962.840185798923;32059.198520037186;32092;32207.355852883047;7994.200579941959;7999.920020794519;8053.27520523144;8155.5514446706;8182.549959752271;20568.588628227353;20698.79301206999;20698.896505517376;20709.171633134505;20710;8850;8928.464292142493;8944.821103577891;8958.68644597449;8961.283097352327;8054.798630034296;8360.83278334442;8369.246767790928;8612.052674205832;8615.439996400213;6229.906551401733;6240.4071613196675;6294.928115015938;6305.180326557518;6326.639772448945;1759.894406335624;1803.2426380920044;1851.2594962015032;1883.5008722688224;1912.2064343297654;999.2156157416539;1006.104566935428;1021.3565453764081;1022.8874823769379;1038.7403149212696;8863.335249856287;9005.63977440895;9074.183323500916;9141.131592498701;9185.908140918638;1088.2327958789192;1093.8523299354486;1095.6932059023486;1102.779444111181;1103.61925135828;5512.862178445571;5566.860828479321;5586.301712285965;5646.378898321182;5700.800471983461;25631.487370252864;26330.078447254255;26905.192844214314;26910.32724181911;27062.864685676446;26031.869840650674;26305.473890522084;26846.06038788633;27050.458990820072;27053.458930821274;12888.355582220851;12906.290154041619;13080.803787943189;13153.473861045642;13169.868301316865;9849.606015759577;9890.109890109925;9921.851172232567;9936.105750482491;9992.450415227233;21244.89377553102;21420.464488387915;22022.559548809255;22446;22896.31311060671;23384.18155364554;23645.408864778517;23649.52700945937;23852;23998.88000559986;13187.80218296707;13473.663158421117;13484.393202306115;13486.595402137951;13490.258035808125;16681;16681.165941702624;16748.74876876872;16751.1624418781;16848;13108.672283192996;13232.40454179545;13308.201507909556;13515.121517101357;13557.79663305071;13082;13095.934520327337;13104.737905242033;13226.470941162246;13318.467261309826;10858.402787846428;11118.722031949266;11120.44397780124;11120.666380008613;11126.388048657402;8544.530050847263;8555.957220214108;8588.570571471526;8756.912430875609;8763.956180219058;8469.66121355139;8474.66101355939;8600.526971016403;8607.8708819369;8624.266937310398 10:20:49 INFO - PID 28800 | |4;prototype.html;594.2601461180831;610.6275172144993;611.0681211152988;612.5773216480629;612.9478994285486;6446.935530644692;6465.547411681184;6483.189601299838;6514.088027676124;6542.313057129004;6348.428641422271;6395.936040639593;6396.520260980422;6417.550771446001;6433.072951806928;5304.4165141834565;5328.573714102862;5330.840074797762;5341.839744807662;5357.651752636086;5060.721660308682;5185.896282074356;5294.073537131006;5320.893582128355;5351.652142610717;5161.587073034167;5169.793208271664;5179.2749015137915;5190.844274671766;5208.479152084799;5140.614453915971;5143.382794064718;5147.485251474823;5150.407703114152;5158.767855446514;4676.438827340707;4684.765761711901;4687.414073240845;4699.506551812045;4706.082313948781;6312.52656050798;6341.4926805855885;6377.202849643794;6380.298167201604;6407.070974708688;6059.727312270914;6095.299040610343;6103.6032657877095;6131.172291740602;6147.569670123094;6744.482491439436;6754.864902701967;6781.72873085076;6845.794626161223;6858.622775747332;5065.974670126626;5189.740512974374;5266.815661451869;5283.313169287968;5306.124489459235;7047.647617619098;7070.787876363717;7151.856962860714;7232.2406147354295;7271.418286537064;5399.757010934517;5414.864628384322;5416.485433883775;5421;5430.239766432703;5460.6177567570685;5488.4785945335125;5492.65429969652;5503.449655034545;5529.668219906818;5955.731992060368;6001.489873360813;6055.545834062463;6056.273247210363;6087.208662873798;6898.586084834926;6919.861602767916;6924.965375173092;6943.9652801735665;6951.304869513008;1884.2745542966056;1900.7814101378242;1912.3689182569997;1912.9426117216506;1916.176044300964;1266.714989127439;1326.9270190139441;1337.2845527642664;1338.377654390713;1362.2099182474142;6385.616862988236;6443.871122577522;6498.5126115541525;6500.869982600321;6528.412442880163;6768.390844823989;6820.863582728318;6870.484713646497;6929.930700693029;7017.298270172942;6495.870082598416;6518.837029074216;6560.901586476207;6562.77030303937;6575.704093315812;3656.926861462756;3679.816009199583;3681.9079523011605;3710.14666626672;3713.4986776785327;7322.670479828421;7338.156112047076;7345.522541034816;7348.5958272294465;7368.815779605553;6977.720891164398;7097.828858238333;7188.640567971581;7224.602646854369;7242.7102915883825;7237.746678866214;7268.491205615662;7326.67029983652;7340.082489688789;7347.228541003225;6910.965445172742;6971.198312194163;7002.509824312453;7058.858822823618;7101.721690095832;6925.89611155823;6929.861402772017;6947.826304342432;6972;7019.859602808017;7119.537230080028;7209;7288.817779555554;7317.451191160578;7335.413166946525;5384.919226211531;5398.299535646259;5400.864978375572;5404.2163886235485;5423.566114710892;6769.1876974763345;6813.4549236061985;6969.651517424007;7022.824429389102;7073.893891591527;6673.366030227121;6679.833004174934;6704.7988560343265;6718.563293385914;6832.965835170792;6648.5705573301975;6650.733970641314;6683.498737594797;6696.861533539408;6800.52396332252;6400.23197216337;6638.137042184485;6684.487970619909;6709.194896612437;6741.59550426976;6774.830629234309;6809.69356378974;6838.623875686885;6848.931510684829;6853.623050732258;6568.376004279586;6589.6705164740615;6702.185082265362;6703.011708033712;6789.966050169717 10:20:49 INFO - PID 28800 | |5;yui.html;939.3931839146372;981.5828272983985;982.4301904895161;1021.2493817044474;1049.2235745548294;20857.165713371465;21382.037808298624;21460.141594336248;21496.355109346725;21499.892500537495;19071.332503362384;19206.903965480153;20041.699374509393;20130.89934550325;20349.59300813983;1053.2048303530842;1077.4828082520396;1077.5312738958578;1078.56857257097;1083.4582708645678;1032.7882784029293;1086.8261078227486;1093.8687357517108;1097.1277834121852;1097.341595042972;1172.7185475485908;1180.2741314091857;1181.373871847922;1181.5510106159647;1181.94681239344;1172.82407638854;1173.4426147579916;1175.424042219315;1175.9588414405541;1176.5470293936853;861.190480947907;862.9568521573896;863.6631713631689;864.1013346120028;866.2680035370103;20225.8988705057;20733.58532829335;20950.685739713917;20984.580308393746;21598.784012159835;24315.513689726282;24438.14466493682;24930.50138997228;24933.875330623232;25026.374340641447;19263.711044334344;19365.12856921442;19493.122809473607;19510;19542.70685939712;4195.139996300751;4231.449911511484;4233.5554766749665;4237.4279472271155;4238.7032907696475;163696;168855;170410.1479492607;172435;173790.99999999875;18539.258429662932;18730.625387492175;18753.156107975174;18877.339293124674;18892.433227003214;36980.81509592489;37183.62816371856;37644.62355376466;37653.43519847204;37689.62310376916;9488.28837837165;9543.42739435636;9551.617935282511;9579.473128977974;9614.95192524033;4811.711297322102;4891.51084891508;4910.410750709866;4914.631402644816;4932.235503496964;6535.183102112236;6572.86854262912;6622.735090596419;6646.966765166143;6650.933490665128;1112.249231768553;1138.1236447935044;1152.2164927849144;1154.3535620052796;1156.4160099149876;750.9849803003909;766.6856588798565;777.5995362388375;788.3220430429826;793.6785601831267;13305.268210248336;13596.456141754219;13880;14503.782443263359;14550.927245363706;772.9265719756614;780.0288640642323;781.1329424338979;782.9138794732575;794.0947320055142;6249.6562689051625;6469.708863101172;6491.026346048036;6521.673916304166;6562.540622156499;152193;152703.23648381687;153193;158340.20829895776;158831;135465;135486;136848.26303473875;137870;140250;121191;121585.39207303907;122675.38662306631;124422;125556.37221813832;11456.19806613529;11852.348120853436;11896.76206475883;12005.53748044655;12136.878631213574;59962.70018649879;60059.69970150121;60192.39807601868;61244;61263;73306.63346683446;74935.5012899728;75450.24549754433;75674;76971;18139;18324.358647447276;18527;18763.53091172677;18775;20265.49336266605;20553.177872884917;20647.483812904797;20673.069711862703;20754.688679669514;18774.155163017425;19180.71228931595;19220.519487012938;19278.22887084501;19362.806371936666;18397;18461.446156615322;18569.16438760286;18670;18792.436226913214;17931.462056138338;18057.367992120213;18113;18120.27518899229;18160.90919545394;13855.307234638429;13970.79043814364;13983.440662373798;14024.719505610035;14140.363683634056;12057.276563406223;12306.323152226714;12557.121001529806;12604.747905042032;12728.045396595107 10:20:49 INFO - PID 28800 | __end_tp_report 10:20:49 INFO - PID 28800 | __start_cc_report 10:20:49 INFO - PID 28800 | _x_x_mozilla_cycle_collect,248 10:20:49 INFO - PID 28800 | __end_cc_report 10:20:49 INFO - PID 28800 | __startTimestamp1506446449322__endTimestamp 10:20:49 INFO - PID 28800 | 10:20:49 INFO - PID 28800 | ------- Summary: start ------- 10:20:49 INFO - PID 28800 | Number of tests: 6 10:20:49 INFO - PID 28800 | 10:20:49 INFO - PID 28800 | [#0] dojo.html Cycles:165 Average:6630.62 Median:6845.90 stddev:768.01 (11.2%) stddev-sans-first:743.16 10:20:49 INFO - PID 28800 | Values: 4047.9 4055.1 4058.8 4063.3 4090.0 6513.5 6516.3 6571.1 6694.0 6699.3 6572.2 6580.6 6583.7 6654.4 6656.9 5901.2 5959.8 5982.0 5990.0 5996.4 5940.3 5974.5 5985.0 6077.7 6089.3 5999.3 6002.2 6052.3 6058.5 6071.7 5962.0 6064.4 6091.1 6105.0 6127.3 5925.5 5986.2 6014.6 6070.4 6110.2 6444.7 6460.6 6483.3 6519.2 6569.8 7160.7 7164.5 7166.3 7168.1 7171.6 7090.9 7200.6 7206.0 7257.9 7263.7 6626.7 6655.7 6727.0 6752.6 6770.9 7310.3 7327.0 7422.2 7422.3 7438.6 7052.2 7057.5 7135.4 7140.6 7156.3 7158.1 7212.6 7242.9 7283.0 7297.5 7146.0 7169.4 7176.8 7181.1 7208.5 7094.7 7110.0 7167.4 7179.6 7263.1 5267.8 5275.9 5344.4 5358.6 5364.3 4774.7 4777.2 4838.3 4848.6 4935.3 6690.9 6779.7 6779.8 6785.7 6788.0 7011.3 7127.4 7130.8 7218.5 7221.0 6537.3 6596.2 6601.5 6687.0 6775.1 6002.8 6012.9 6018.9 6035.2 6044.5 7465.5 7465.7 7571.9 7572.8 7594.3 7035.4 7125.6 7228.3 7233.8 7245.3 7367.6 7455.1 7508.1 7519.0 7543.1 7091.9 7098.8 7162.2 7165.2 7181.9 7152.7 7186.1 7201.2 7243.3 7290.2 6902.7 6927.0 6934.9 6937.0 6941.7 6902.4 6928.8 6987.3 7000.5 7027.1 6789.8 6796.0 6803.9 6874.1 6888.7 6876.6 6946.7 6974.6 7031.9 7073.7 6729.3 6818.7 6831.7 6837.9 6845.9 10:20:49 INFO - PID 28800 | 10:20:49 INFO - PID 28800 | [#1] ext.html Cycles:180 Average:44819.97 Median:21724.88 stddev:64864.44 (298.6%) stddev-sans-first:64962.90 10:20:49 INFO - PID 28800 | Values: 987.8 994.3 1018.3 1022.9 1023.5 47531.8 48073.8 48111.3 48198.3 48270.5 45621.5 45719.0 45772.5 45883.5 46103.5 8051.5 8194.5 8204.0 8223.8 8246.2 8052.5 8157.6 8158.5 8160.6 8173.2 8088.7 8775.6 8973.8 8996.7 9008.3 8863.8 8889.0 8925.6 8928.5 8938.8 8483.0 8572.4 8573.5 8581.6 8583.7 42471.4 42650.0 42707.0 42755.1 42901.6 3895.7 3944.2 3944.3 3946.1 3948.5 35660.0 35722.0 35783.3 35809.8 35821.3 5672.6 5679.3 5694.1 5695.9 5725.0 234442.0 235699.0 235955.0 236484.8 236781.0 50367.5 50407.7 57784.0 57881.7 58947.4 61371.4 61379.4 61601.7 61625.0 61751.4 18714.0 18802.5 18912.1 18950.4 19003.5 15402.6 15462.7 15500.1 15727.4 15782.0 15990.5 16073.2 16086.3 16197.8 16207.8 6210.7 6259.2 6260.7 6293.7 6525.0 1118.0 1121.4 1131.9 1145.7 1158.8 26322.6 26553.0 26791.0 26966.3 27256.9 1233.2 1237.6 1253.8 1257.8 1263.3 14053.6 14136.6 14163.7 14174.2 14312.1 274656.0 278203.0 278599.0 279570.0 280231.0 62804.7 63054.0 63118.4 63134.0 63135.0 1547.8 1558.7 1592.5 1599.2 1603.7 25944.9 26392.5 26436.0 26498.7 26679.0 43969.0 44001.5 44060.0 44098.1 44145.1 231848.0 231921.0 233116.0 234179.0 234977.0 39501.2 42012.0 42541.6 42874.4 43075.4 54439.5 54698.5 54752.5 54826.7 55041.7 38582.2 38622.4 38910.8 39020.4 39568.8 38838.4 38941.4 39342.8 39351.4 39773.6 46035.0 46653.8 46754.3 46832.0 47176.3 37730.0 37995.0 38420.2 38565.6 38712.2 27640.7 28931.0 29086.3 29255.3 29361.0 10:20:49 INFO - PID 28800 | 10:20:49 INFO - PID 28800 | [#2] jquery.html Cycles:180 Average:41922.25 Median:12406.43 stddev:106163.87 (855.7%) stddev-sans-first:106416.40 10:20:49 INFO - PID 28800 | Values: 623.3 629.1 636.7 643.1 651.1 24377.4 24987.9 25100.0 25405.5 25802.4 27902.7 28399.4 28414.6 28445.7 28449.7 6610.6 6883.2 6902.7 6980.5 7009.1 6927.3 6975.6 6985.7 7010.2 7019.2 6926.5 6932.8 6972.3 6976.3 6980.7 6857.2 6908.3 6934.5 6945.3 6965.4 6484.6 6492.6 6510.2 6524.8 6529.3 31279.2 33496.3 33634.5 33687.8 33807.5 2240.1 2274.1 2275.5 2293.1 2294.1 21308.7 21637.8 22055.0 22272.9 22572.8 2625.9 2640.5 2673.1 2675.6 2683.5 148056.0 154598.0 162833.4 168567.0 169042.0 17037.8 17565.5 17635.6 17658.9 18078.0 28239.3 28244.3 28386.0 28400.4 28414.0 11822.8 11842.6 11861.0 11908.7 12032.7 11057.9 11105.6 11161.4 11171.8 11344.5 10000.2 10408.5 10462.4 10599.6 10705.9 2927.5 2942.6 2966.3 3020.2 3038.9 1049.8 1064.8 1074.7 1087.2 1090.4 22369.9 22719.4 22728.7 22809.4 22829.9 1146.1 1154.2 1160.5 1162.4 1164.1 12211.6 12315.0 12484.8 12530.8 12570.0 621907.0 623679.0 626061.0 646150.0 648721.0 138357.3 141717.0 143780.0 144501.0 147578.0 1228.9 1244.4 1264.9 1271.0 1271.9 19859.5 20219.0 20232.7 20460.3 20499.6 63426.7 63997.0 64923.4 65111.0 65612.7 79283.6 79574.6 79617.6 80354.6 80635.2 16268.6 16327.6 16409.5 16515.6 16533.4 24557.9 24580.9 24876.4 25247.5 25289.6 19680.6 19808.3 19985.4 20122.2 20133.5 17925.7 18247.0 18475.4 18708.7 18856.8 21204.2 21265.1 21306.7 21523.8 21722.2 16194.2 16316.7 16332.9 16351.7 16423.3 20286.3 20416.9 20469.3 20519.3 20578.5 10:20:49 INFO - PID 28800 | 10:20:49 INFO - PID 28800 | [#3] mootools.html Cycles:180 Average:11034.15 Median:26980.39 stddev:8130.87 (30.1%) stddev-sans-first:8121.52 10:20:49 INFO - PID 28800 | Values: 1409.2 1473.2 1478.1 1485.3 1486.8 17308.9 17949.2 17983.2 17996.6 18051.5 17476.0 17496.7 17606.2 17628.0 17675.0 3068.6 3151.1 3201.9 3205.8 3224.2 3013.9 3054.8 3059.2 3068.1 3082.6 3076.2 3093.6 3102.6 3103.3 3104.3 3088.9 3108.3 3113.9 3135.8 3138.8 2880.2 3044.5 3075.4 3086.1 3088.5 17155.6 17188.1 17273.6 17317.6 17444.5 9244.8 9517.5 9531.4 9546.1 9560.9 5749.6 6023.5 6041.4 6141.2 6143.4 2203.0 2210.1 2223.2 2226.2 2273.4 31856.5 31962.8 32059.2 32092.0 32207.4 7994.2 7999.9 8053.3 8155.6 8182.5 20568.6 20698.8 20698.9 20709.2 20710.0 8850.0 8928.5 8944.8 8958.7 8961.3 8054.8 8360.8 8369.2 8612.1 8615.4 6229.9 6240.4 6294.9 6305.2 6326.6 1759.9 1803.2 1851.3 1883.5 1912.2 999.2 1006.1 1021.4 1022.9 1038.7 8863.3 9005.6 9074.2 9141.1 9185.9 1088.2 1093.9 1095.7 1102.8 1103.6 5512.9 5566.9 5586.3 5646.4 5700.8 25631.5 26330.1 26905.2 26910.3 27062.9 26031.9 26305.5 26846.1 27050.5 27053.5 12888.4 12906.3 13080.8 13153.5 13169.9 9849.6 9890.1 9921.9 9936.1 9992.5 21244.9 21420.5 22022.6 22446.0 22896.3 23384.2 23645.4 23649.5 23852.0 23998.9 13187.8 13473.7 13484.4 13486.6 13490.3 16681.0 16681.2 16748.7 16751.2 16848.0 13108.7 13232.4 13308.2 13515.1 13557.8 13082.0 13095.9 13104.7 13226.5 13318.5 10858.4 11118.7 11120.4 11120.7 11126.4 8544.5 8556.0 8588.6 8756.9 8764.0 8469.7 8474.7 8600.5 8607.9 8624.3 10:20:49 INFO - PID 28800 | 10:20:49 INFO - PID 28800 | [#4] prototype.html Cycles:180 Average:5842.30 Median:6489.53 stddev:1623.28 (25.0%) stddev-sans-first:1579.32 10:20:49 INFO - PID 28800 | Values: 594.3 610.6 611.1 612.6 612.9 6446.9 6465.5 6483.2 6514.1 6542.3 6348.4 6395.9 6396.5 6417.6 6433.1 5304.4 5328.6 5330.8 5341.8 5357.7 5060.7 5185.9 5294.1 5320.9 5351.7 5161.6 5169.8 5179.3 5190.8 5208.5 5140.6 5143.4 5147.5 5150.4 5158.8 4676.4 4684.8 4687.4 4699.5 4706.1 6312.5 6341.5 6377.2 6380.3 6407.1 6059.7 6095.3 6103.6 6131.2 6147.6 6744.5 6754.9 6781.7 6845.8 6858.6 5066.0 5189.7 5266.8 5283.3 5306.1 7047.6 7070.8 7151.9 7232.2 7271.4 5399.8 5414.9 5416.5 5421.0 5430.2 5460.6 5488.5 5492.7 5503.4 5529.7 5955.7 6001.5 6055.5 6056.3 6087.2 6898.6 6919.9 6925.0 6944.0 6951.3 1884.3 1900.8 1912.4 1912.9 1916.2 1266.7 1326.9 1337.3 1338.4 1362.2 6385.6 6443.9 6498.5 6500.9 6528.4 6768.4 6820.9 6870.5 6929.9 7017.3 6495.9 6518.8 6560.9 6562.8 6575.7 3656.9 3679.8 3681.9 3710.1 3713.5 7322.7 7338.2 7345.5 7348.6 7368.8 6977.7 7097.8 7188.6 7224.6 7242.7 7237.7 7268.5 7326.7 7340.1 7347.2 6911.0 6971.2 7002.5 7058.9 7101.7 6925.9 6929.9 6947.8 6972.0 7019.9 7119.5 7209.0 7288.8 7317.5 7335.4 5384.9 5398.3 5400.9 5404.2 5423.6 6769.2 6813.5 6969.7 7022.8 7073.9 6673.4 6679.8 6704.8 6718.6 6833.0 6648.6 6650.7 6683.5 6696.9 6800.5 6400.2 6638.1 6684.5 6709.2 6741.6 6774.8 6809.7 6838.6 6848.9 6853.6 6568.4 6589.7 6702.2 6703.0 6790.0 10:20:49 INFO - PID 28800 | 10:20:49 INFO - PID 28800 | [#5] yui.html Cycles:180 Average:29758.76 Median:19213.71 stddev:44558.49 (231.9%) stddev-sans-first:44630.95 10:20:49 INFO - PID 28800 | Values: 939.4 981.6 982.4 1021.2 1049.2 20857.2 21382.0 21460.1 21496.4 21499.9 19071.3 19206.9 20041.7 20130.9 20349.6 1053.2 1077.5 1077.5 1078.6 1083.5 1032.8 1086.8 1093.9 1097.1 1097.3 1172.7 1180.3 1181.4 1181.6 1181.9 1172.8 1173.4 1175.4 1176.0 1176.5 861.2 863.0 863.7 864.1 866.3 20225.9 20733.6 20950.7 20984.6 21598.8 24315.5 24438.1 24930.5 24933.9 25026.4 19263.7 19365.1 19493.1 19510.0 19542.7 4195.1 4231.4 4233.6 4237.4 4238.7 163696.0 168855.0 170410.1 172435.0 173791.0 18539.3 18730.6 18753.2 18877.3 18892.4 36980.8 37183.6 37644.6 37653.4 37689.6 9488.3 9543.4 9551.6 9579.5 9615.0 4811.7 4891.5 4910.4 4914.6 4932.2 6535.2 6572.9 6622.7 6647.0 6650.9 1112.2 1138.1 1152.2 1154.4 1156.4 751.0 766.7 777.6 788.3 793.7 13305.3 13596.5 13880.0 14503.8 14550.9 772.9 780.0 781.1 782.9 794.1 6249.7 6469.7 6491.0 6521.7 6562.5 152193.0 152703.2 153193.0 158340.2 158831.0 135465.0 135486.0 136848.3 137870.0 140250.0 121191.0 121585.4 122675.4 124422.0 125556.4 11456.2 11852.3 11896.8 12005.5 12136.9 59962.7 60059.7 60192.4 61244.0 61263.0 73306.6 74935.5 75450.2 75674.0 76971.0 18139.0 18324.4 18527.0 18763.5 18775.0 20265.5 20553.2 20647.5 20673.1 20754.7 18774.2 19180.7 19220.5 19278.2 19362.8 18397.0 18461.4 18569.2 18670.0 18792.4 17931.5 18057.4 18113.0 18120.3 18160.9 13855.3 13970.8 13983.4 14024.7 14140.4 12057.3 12306.3 12557.1 12604.7 12728.0 10:20:49 INFO - PID 28800 | -------- Summary: end -------- 10:20:49 INFO - PID 28800 | 10:20:49 INFO - TEST-INFO | 28800: exit 0 10:20:49 INFO - TEST-OK | dromaeo_css | took 1106735ms 10:20:49 INFO - TEST-START | kraken 10:20:49 INFO - Initialising browser for kraken test... 10:20:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48007/getInfo.html -profile /tmp/tmp0ldeUo/profile 10:20:49 INFO - TEST-INFO | started process 29168 (/builds/slave/test/build/application/firefox/firefox http://localhost:48007/getInfo.html) 10:20:53 INFO - TEST-INFO | 29168: exit 0 10:20:54 INFO - Browser initialized. 10:20:54 INFO - Running cycle 1/1 for kraken test... 10:20:54 INFO - Using env: {'DISPLAY': ':0', 10:20:54 INFO - 'HOME': '/home/cltbld', 10:20:54 INFO - 'JSGC_DISABLE_POISONING': '1', 10:20:54 INFO - 'LANG': 'en_US.UTF-8', 10:20:54 INFO - 'LANGUAGE': 'en_US:en', 10:20:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 10:20:54 INFO - 'LOGNAME': 'cltbld', 10:20:54 INFO - 'MAIL': '/var/mail/cltbld', 10:20:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:20:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:20:54 INFO - 'MOZ_CRASHREPORTER': '1', 10:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 10:20:54 INFO - 'MOZ_NO_REMOTE': '1', 10:20:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:20:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:54 INFO - 'NO_EM_RESTART': '1', 10:20:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:54 INFO - 'PWD': '/builds/slave/test', 10:20:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:20:54 INFO - 'RUST_BACKTRACE': 'full', 10:20:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:20:54 INFO - 'SHELL': '/bin/bash', 10:20:54 INFO - 'SHLVL': '1', 10:20:54 INFO - 'TERM': 'linux', 10:20:54 INFO - 'TMOUT': '86400', 10:20:54 INFO - 'USER': 'cltbld', 10:20:54 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782', 10:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:54 INFO - '_': '/tools/buildbot/bin/python'} 10:20:54 INFO - TEST-INFO | started process 29359 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0ldeUo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:20:54 INFO - PID 29359 | 10:20:54 INFO - PID 29359 | (/builds/slave/test/build/application/firefox/firefox:29408): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 10:20:54 INFO - PID 29359 | 10:21:05 INFO - PID 29359 | RSS: Main: 143294464 10:21:05 INFO - PID 29359 | 10:21:07 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48007/tests/kraken/driver.html?testName=audio-beat-detection) 10:21:08 INFO - PID 29359 | RSS: Main: 150409216 10:21:08 INFO - PID 29359 | 10:21:11 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48007/tests/kraken/driver.html?testName=audio-dft) 10:21:11 INFO - PID 29359 | RSS: Main: 150695936 10:21:11 INFO - PID 29359 | 10:21:14 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48007/tests/kraken/driver.html?testName=audio-fft) 10:21:15 INFO - PID 29359 | RSS: Main: 152764416 10:21:15 INFO - PID 29359 | 10:21:17 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48007/tests/kraken/driver.html?testName=audio-oscillator) 10:21:17 INFO - PID 29359 | RSS: Main: 152612864 10:21:17 INFO - PID 29359 | 10:21:19 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48007/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:21:20 INFO - PID 29359 | RSS: Main: 152137728 10:21:20 INFO - PID 29359 | 10:21:23 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48007/tests/kraken/driver.html?testName=imaging-darkroom) 10:21:24 INFO - PID 29359 | RSS: Main: 152707072 10:21:24 INFO - PID 29359 | 10:21:27 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48007/tests/kraken/driver.html?testName=imaging-desaturate) 10:21:28 INFO - PID 29359 | RSS: Main: 151764992 10:21:28 INFO - PID 29359 | 10:21:31 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48007/tests/kraken/driver.html?testName=json-parse-financial) 10:21:31 INFO - PID 29359 | RSS: Main: 153219072 10:21:31 INFO - PID 29359 | 10:21:33 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48007/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:21:33 INFO - PID 29359 | RSS: Main: 153427968 10:21:33 INFO - PID 29359 | 10:21:35 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48007/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:21:35 INFO - PID 29359 | RSS: Main: 153473024 10:21:35 INFO - PID 29359 | 10:21:37 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48007/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:21:38 INFO - PID 29359 | RSS: Main: 150601728 10:21:38 INFO - PID 29359 | 10:21:40 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:21:41 INFO - PID 29359 | RSS: Main: 150355968 10:21:41 INFO - PID 29359 | 10:21:44 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:21:44 INFO - PID 29359 | RSS: Main: 150519808 10:21:44 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | Cycle 1(1): loaded http://localhost:48007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48007/tests/kraken/driver.html?testName=ai-astar) 10:21:46 INFO - PID 29359 | RSS: Main: 152326144 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | __start_tp_report 10:21:46 INFO - PID 29359 | _x_x_mozilla_page_load 10:21:46 INFO - PID 29359 | _x_x_mozilla_page_load_details 10:21:46 INFO - PID 29359 | |i|pagename|runs| 10:21:46 INFO - PID 29359 | |0;ai-astar;105;104;105;105;106;106;106;105;103;104 10:21:46 INFO - PID 29359 | |1;audio-beat-detection;119;113;111;115;113;110;111;134;113;116 10:21:46 INFO - PID 29359 | |2;audio-dft;161;163;161;161;161;163;164;163;162;162 10:21:46 INFO - PID 29359 | |3;audio-fft;73;73;73;73;75;73;73;73;74;73 10:21:46 INFO - PID 29359 | |4;audio-oscillator;77;77;77;78;76;77;78;78;77;78 10:21:46 INFO - PID 29359 | |5;imaging-gaussian-blur;114;118;113;117;117;113;114;114;115;113 10:21:46 INFO - PID 29359 | |6;imaging-darkroom;149;148;149;148;149;148;148;149;149;148 10:21:46 INFO - PID 29359 | |7;imaging-desaturate;93;93;100;99;94;94;95;94;95;94 10:21:46 INFO - PID 29359 | |8;json-parse-financial;42;44;44;43;45;43;43;43;43;42 10:21:46 INFO - PID 29359 | |9;json-stringify-tinderbox;37;37;37;38;40;38;38;37;37;37 10:21:46 INFO - PID 29359 | |10;stanford-crypto-aes;75;72;70;71;69;75;68;75;69;69 10:21:46 INFO - PID 29359 | |11;stanford-crypto-ccm;109;100;100;104;105;102;105;107;101;103 10:21:46 INFO - PID 29359 | |12;stanford-crypto-pbkdf2;151;151;151;152;150;148;147;153;146;145 10:21:46 INFO - PID 29359 | |13;stanford-crypto-sha256-iterative;59;60;65;58;59;60;59;59;58;58 10:21:46 INFO - PID 29359 | __end_tp_report 10:21:46 INFO - PID 29359 | __start_cc_report 10:21:46 INFO - PID 29359 | _x_x_mozilla_cycle_collect,668 10:21:46 INFO - PID 29359 | __end_cc_report 10:21:46 INFO - PID 29359 | __startTimestamp1506446506623__endTimestamp 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | ------- Summary: start ------- 10:21:46 INFO - PID 29359 | Number of tests: 14 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#0] ai-astar Cycles:10 Average:104.90 Median:105.00 stddev:0.99 (0.9%) stddev-sans-first:1.05 10:21:46 INFO - PID 29359 | Values: 105.0 104.0 105.0 105.0 106.0 106.0 106.0 105.0 103.0 104.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#1] audio-beat-detection Cycles:10 Average:115.50 Median:114.00 stddev:7.03 (6.2%) stddev-sans-first:7.34 10:21:46 INFO - PID 29359 | Values: 119.0 113.0 111.0 115.0 113.0 110.0 111.0 134.0 113.0 116.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:1.10 (0.7%) stddev-sans-first:1.09 10:21:46 INFO - PID 29359 | Values: 161.0 163.0 161.0 161.0 161.0 163.0 164.0 163.0 162.0 162.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#3] audio-fft Cycles:10 Average:73.30 Median:73.00 stddev:0.67 (0.9%) stddev-sans-first:0.71 10:21:46 INFO - PID 29359 | Values: 73.0 73.0 73.0 73.0 75.0 73.0 73.0 73.0 74.0 73.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:77.50 stddev:0.67 (0.9%) stddev-sans-first:0.71 10:21:46 INFO - PID 29359 | Values: 77.0 77.0 77.0 78.0 76.0 77.0 78.0 78.0 77.0 78.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#5] imaging-gaussian-blur Cycles:10 Average:114.80 Median:114.50 stddev:1.87 (1.6%) stddev-sans-first:1.96 10:21:46 INFO - PID 29359 | Values: 114.0 118.0 113.0 117.0 117.0 113.0 114.0 114.0 115.0 113.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 10:21:46 INFO - PID 29359 | Values: 149.0 148.0 149.0 148.0 149.0 148.0 148.0 149.0 149.0 148.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:94.00 stddev:2.42 (2.6%) stddev-sans-first:2.45 10:21:46 INFO - PID 29359 | Values: 93.0 93.0 100.0 99.0 94.0 94.0 95.0 94.0 95.0 94.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#8] json-parse-financial Cycles:10 Average:43.20 Median:43.00 stddev:0.92 (2.1%) stddev-sans-first:0.87 10:21:46 INFO - PID 29359 | Values: 42.0 44.0 44.0 43.0 45.0 43.0 43.0 43.0 43.0 42.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#9] json-stringify-tinderbox Cycles:10 Average:37.60 Median:37.50 stddev:0.97 (2.6%) stddev-sans-first:1.00 10:21:46 INFO - PID 29359 | Values: 37.0 37.0 37.0 38.0 40.0 38.0 38.0 37.0 37.0 37.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#10] stanford-crypto-aes Cycles:10 Average:71.30 Median:71.50 stddev:2.79 (3.9%) stddev-sans-first:2.62 10:21:46 INFO - PID 29359 | Values: 75.0 72.0 70.0 71.0 69.0 75.0 68.0 75.0 69.0 69.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#11] stanford-crypto-ccm Cycles:10 Average:103.60 Median:104.50 stddev:2.99 (2.9%) stddev-sans-first:2.45 10:21:46 INFO - PID 29359 | Values: 109.0 100.0 100.0 104.0 105.0 102.0 105.0 107.0 101.0 103.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.40 Median:151.00 stddev:2.72 (1.8%) stddev-sans-first:2.82 10:21:46 INFO - PID 29359 | Values: 151.0 151.0 151.0 152.0 150.0 148.0 147.0 153.0 146.0 145.0 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.50 Median:59.00 stddev:2.07 (3.5%) stddev-sans-first:2.19 10:21:46 INFO - PID 29359 | Values: 59.0 60.0 65.0 58.0 59.0 60.0 59.0 59.0 58.0 58.0 10:21:46 INFO - PID 29359 | -------- Summary: end -------- 10:21:46 INFO - PID 29359 | 10:21:46 INFO - PID 29359 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:21:47 INFO - TEST-INFO | 29359: exit 0 10:21:47 INFO - TEST-OK | kraken | took 57341ms 10:21:47 INFO - SUITE-END | took 1164s 10:21:47 INFO - Completed test suite (00:19:24) 10:21:47 INFO - javascript benchmark 10:21:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9562.048833261653, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4047.8785636430916, 4055.128147448298, 4058.7767672777986, 4063.2686116499026, 4090.0388408723934, 6513.544051916368, 6516.250631177416, 6571.145751052357, 6693.966530167355, 6699.330066993298, 6572.244191917931, 6580.572262802914, 6583.7037333319995, 6654.367835055674, 6656.866862662756, 5901.17383566301, 5959.761609535613, 5981.9700901495435, 5989.970050149765, 5996.400359964002, 5940.346561878211, 5974.4921681657115, 5985.042393217086, 6077.665728384959, 6089.299730531001, 5999.280086389639, 6002.249718785152, 6052.334243233241, 6058.485028772559, 6071.726772295236, 5962.0, 6064.363241859607, 6091.0558863376245, 6105.023196288595, 6127.325994140686, 5925.466707996258, 5986.161937328778, 6014.578979471439, 6070.423309785563, 6110.205673262448, 6444.742210311582, 6460.644664543448, 6483.254425741005, 6519.185101862267, 6569.770058047945, 7160.749373771945, 7164.498485106044, 7166.319199676022, 7168.139823221247, 7171.569705817667, 7090.858182836314, 7200.639968001579, 7206.0, 7257.854842903165, 7263.745768898116, 6626.7017984190825, 6655.733770649216, 6726.966365168192, 6752.5948443093575, 6770.898436523456, 7310.268973102648, 7327.032551816523, 7422.220666830013, 7422.257774222535, 7438.6280685965485, 7052.153741551047, 7057.505974581833, 7135.357817796424, 7140.571565706075, 7156.320149585885, 7158.105236845395, 7212.639368031578, 7242.891356629655, 7283.0, 7297.452691048193, 7145.964270178616, 7169.354758071799, 7176.82057948545, 7181.102362204724, 7208.4954053216825, 7094.716211351488, 7110.0, 7167.390771784352, 7179.605121718251, 7263.0921134858145, 5267.841964741064, 5275.920861187085, 5344.385395679469, 5358.571314294847, 5364.30264065669, 4774.737389443614, 4777.235642297216, 4838.250071238964, 4848.612111031109, 4935.284383764334, 6690.866182676319, 6779.72881084761, 6779.796606101825, 6785.694643740945, 6788.049673045777, 7011.3339232772805, 7127.4298056155385, 7130.750423735248, 7218.494705370574, 7221.025161603221, 6537.313582073813, 6596.208454985336, 6601.504887133484, 6687.0, 6775.1192344995825, 6002.819915402545, 6012.939870601325, 6018.909716354258, 6035.185249991195, 6044.455998960027, 7465.477416580896, 7465.738699145504, 7571.886421703679, 7572.810679733051, 7594.31651151399, 7035.437165026888, 7125.572465652078, 7228.349448549656, 7233.770259056078, 7245.275472452712, 7367.631618419165, 7455.105387353552, 7508.0990281166605, 7519.022527071445, 7543.057117860268, 7091.929080709333, 7098.751543696152, 7162.176349719849, 7165.247648996989, 7181.856362872609, 7152.713891444492, 7186.137663480206, 7201.207867134715, 7243.348098671145, 7290.234525374759, 6902.654867256718, 6926.965365173141, 6934.93065069363, 6936.965315173392, 6941.722331106699, 6902.447804175554, 6928.792136235921, 6987.336203060701, 7000.47496437759, 7027.086478757728, 6789.762358317435, 6795.966020169867, 6803.9319606805275, 6874.140732408449, 6888.690008949709, 6876.621785801829, 6946.687399066953, 6974.616396098263, 7031.929680703128, 7073.681684324115, 6729.327067293231, 6818.693158807766, 6831.726730930707, 6837.863242735217, 6845.897311540231], "value": 6579.410834088825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [987.7629368951452, 994.3288280410721, 1018.2770233134474, 1022.8925962773909, 1023.5291765787737, 47531.76234118829, 48073.759631201836, 48111.27833082502, 48198.27702584464, 48270.51729482704, 45621.54378456214, 45719.0, 45772.542274577325, 45883.54116458834, 46103.53896461034, 8051.476654017485, 8194.508329500219, 8203.95898020509, 8223.753287401387, 8246.216609422096, 8052.516848989079, 8157.632906519191, 8158.5104893706275, 8160.6327715252955, 8173.182681731839, 8088.716894908679, 8775.648974041063, 8973.820523589524, 8996.730098097067, 9008.279337653004, 8863.822723545525, 8888.955555222183, 8925.55372231389, 8928.464292142427, 8938.776530586721, 8483.0, 8572.399932004762, 8573.485590864568, 8581.613827377783, 8583.742487725378, 42471.36292955608, 42650.0, 42707.0, 42755.14489710188, 42901.57098429007, 3895.7078219133678, 3944.2111577684577, 3944.2900277949957, 3946.131850992777, 3948.545917219528, 35660.0, 35722.0, 35783.28433431317, 35809.82095089534, 35821.28357432863, 5672.631278966854, 5679.318481782213, 5694.142083924158, 5695.857603559903, 5724.971375143139, 234442.0, 235699.0, 235955.0, 236484.81757591275, 236781.0, 50367.49632503628, 50407.747961259956, 57784.0, 57881.710591446776, 58947.41052589419, 61371.38628613746, 61379.38620613826, 61601.69199153975, 61625.0, 61751.38248617546, 18714.0, 18802.52993675169, 18912.148953297135, 18950.43148705541, 19003.524911877037, 15402.6149346265, 15462.690746185013, 15500.147491887828, 15727.370905163665, 15782.0, 15990.520284391487, 16073.196340182943, 16086.276117574504, 16197.757033644504, 16207.837921620869, 6210.72051757672, 6259.248890133122, 6260.7495700171485, 6293.671341603636, 6525.0, 1117.9720506987228, 1121.3552207480568, 1131.852859128308, 1145.7021174494691, 1158.8377627132209, 26322.6051609226, 26553.0, 26791.0, 26966.32584185372, 27256.863715681295, 1233.2045830439317, 1237.6472705278952, 1253.849538055439, 1257.8176164456102, 1263.3304348695206, 14053.578392648236, 14136.575902722934, 14163.716725665428, 14174.220417877115, 14312.06971546846, 274656.0, 278203.0, 278599.0, 279570.0, 280231.0, 62804.685976569825, 63054.0, 63118.368816311246, 63134.0, 63135.0, 1547.8220004699604, 1558.6960542694067, 1592.4824432059627, 1599.2163839718455, 1603.7193491139003, 25944.870275649257, 26392.472150557267, 26436.0, 26498.7350126504, 26679.0, 43969.0, 44001.45994890163, 44060.0, 44098.11803763971, 44145.117097657225, 231848.0, 231921.0, 233116.0, 234179.0, 234977.0, 39501.2099758009, 42012.0, 42541.57458425376, 42874.35688464738, 43075.353869692604, 54439.455605443436, 54698.45301547093, 54752.45247547473, 54826.72586637041, 55041.72479137579, 38582.2283554333, 38622.42066369063, 38910.80544597259, 39020.414693780185, 39568.80215598903, 38838.41742373923, 38941.44234230599, 39342.803285983384, 39351.40972885466, 39773.60226397699, 46035.0, 46653.76673116613, 46754.29868552042, 46832.0, 47176.29235561538, 37730.0, 37995.0, 38420.231595367375, 38565.6143438562, 38712.22575548417, 27640.723592763814, 28931.0, 29086.27284317909, 29255.268618283862, 29361.0], "value": 19920.3008526766, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.3236937922354, 629.1066685306864, 636.703932671308, 643.0868167202573, 651.1339917909181, 24377.390565235855, 24987.875060624672, 25100.0, 25405.491890162208, 25802.354941126483, 27902.720972790266, 28399.43201135976, 28414.573781393243, 28445.715542844566, 28449.715502845018, 6610.636414997173, 6883.208431030421, 6902.654867256642, 6980.511364204508, 7009.088818453594, 6927.3072692730575, 6975.5814651120845, 6985.720571177147, 7010.1587809462935, 7019.157701075878, 6926.549774264682, 6932.826679333032, 6972.267911869257, 6976.267491913352, 6980.685869135901, 6857.245702972694, 6908.309169083102, 6934.549254298455, 6945.340192681687, 6965.442764578822, 6484.6433446160445, 6492.610443373413, 6510.153680021567, 6524.836879078013, 6529.2817790043055, 31279.218019549466, 33496.33007339864, 33634.49548256778, 33687.831560842285, 33807.492887606706, 2240.0815665577034, 2274.1358283852196, 2275.488015196584, 2293.0827668932393, 2294.093832935998, 21308.680369794467, 21637.783622163734, 22055.0, 22272.88863555672, 22572.774272257397, 2625.868706564664, 2640.511505371493, 2673.1445937300073, 2675.5852842809395, 2683.5303821831103, 148056.0, 154598.0, 162833.371666283, 168567.0, 169042.0, 17037.829621703873, 17565.473035808947, 17635.55911102207, 17658.91170544139, 18078.0, 28239.294017649314, 28244.293892652437, 28386.0, 28400.431991360056, 28414.0, 11822.822657660143, 11842.585509507126, 11861.0, 11908.702282442835, 12032.699182520508, 11057.944710276397, 11105.555777768799, 11161.441927903572, 11171.83242251367, 11344.546218151347, 10000.249881306467, 10408.47957602132, 10462.372257664565, 10599.629012984662, 10705.87588303217, 2927.5462303343015, 2942.5880376747273, 2966.258435391152, 3020.1694534002886, 3038.8632511537035, 1049.7795462952713, 1064.808334499782, 1074.6775967209805, 1087.2497976396262, 1090.4057288777635, 22369.88815055947, 22719.432014199447, 22728.65907011396, 22809.429764256027, 22829.885850570972, 1146.1117633833846, 1154.2439701995206, 1160.4545863444168, 1162.354893034374, 1164.138537482257, 12211.633650990485, 12315.014798816073, 12484.81272780909, 12530.81203781944, 12570.0, 621907.0, 623679.0, 626061.0, 646150.0, 648721.0, 138357.3082134603, 141717.0, 143780.0, 144501.0, 147578.0, 1228.9078319126281, 1244.3653736594401, 1264.9177803442747, 1271.0340141492347, 1271.8855303022772, 19859.503512412306, 20219.0, 20232.69650955266, 20460.283890063776, 20499.590008199455, 63426.682866587216, 63997.0, 64923.35076649362, 65111.0, 65612.67193664, 79283.60358198172, 79574.60212698899, 79617.60191199007, 80354.59822701084, 80635.19364806512, 16268.593285167966, 16327.59181020484, 16409.507714768577, 16515.58711032234, 16533.421330253383, 24557.894894730136, 24580.877095614407, 24876.37809054788, 25247.495050099264, 25289.62065568981, 19680.60638787245, 19808.306709265107, 19985.400437986882, 20122.19511219535, 20133.49666258355, 17925.731114033562, 18247.0, 18475.353362632242, 18708.7193692092, 18856.811431886053, 21204.15183392647, 21265.149394024684, 21306.680399794324, 21523.784762152805, 21722.2397216103, 16194.190290485663, 16316.67366652684, 16332.918335408247, 16351.67296654084, 16423.343066277215, 20286.289979850633, 20416.897915510326, 20469.2835750748, 20519.281825136048, 20578.485537861674], "value": 13465.073464567085, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.1826740490512, 1473.2265560580695, 1478.090974050959, 1485.2944851195684, 1486.7546854768964, 17308.91345543272, 17949.19228634711, 17983.19075641596, 17996.55008624785, 18051.458456246302, 17476.0, 17496.737548936744, 17606.20772065257, 17628.0, 17675.0, 3068.601081859355, 3151.086185006345, 3201.8719251229923, 3205.7916235444623, 3224.2423030587793, 3013.9397212055746, 3054.831984240866, 3059.2351912021995, 3068.0642404066753, 3082.6300843898653, 3076.1694342527503, 3093.64423091344, 3102.565640810288, 3103.301757104656, 3104.3480869017535, 3088.938221235574, 3108.3161704424992, 3113.9221519462194, 3135.7648176386747, 3138.8116712997066, 2880.2079428157217, 3044.4824379855477, 3075.354175623122, 3086.0587520806143, 3088.5212792017273, 17155.57111072221, 17188.140592970303, 17273.568160795956, 17317.56706082358, 17444.47666570005, 9244.815103697889, 9517.524123793783, 9531.428114313163, 9546.140847323704, 9560.90439095614, 5749.626274292157, 6023.518118550549, 6041.365656606037, 6141.170941922828, 6143.4470897618985, 2202.9559408811892, 2210.060724192212, 2223.2440970070093, 2226.1651880544796, 2273.3975496493445, 31856.522152167738, 31962.840185798923, 32059.198520037186, 32092.0, 32207.355852883047, 7994.200579941959, 7999.920020794519, 8053.27520523144, 8155.5514446706, 8182.549959752271, 20568.588628227353, 20698.79301206999, 20698.896505517376, 20709.171633134505, 20710.0, 8850.0, 8928.464292142493, 8944.821103577891, 8958.68644597449, 8961.283097352327, 8054.798630034296, 8360.83278334442, 8369.246767790928, 8612.052674205832, 8615.439996400213, 6229.906551401733, 6240.4071613196675, 6294.928115015938, 6305.180326557518, 6326.639772448945, 1759.894406335624, 1803.2426380920044, 1851.2594962015032, 1883.5008722688224, 1912.2064343297654, 999.2156157416539, 1006.104566935428, 1021.3565453764081, 1022.8874823769379, 1038.7403149212696, 8863.335249856287, 9005.63977440895, 9074.183323500916, 9141.131592498701, 9185.908140918638, 1088.2327958789192, 1093.8523299354486, 1095.6932059023486, 1102.779444111181, 1103.61925135828, 5512.862178445571, 5566.860828479321, 5586.301712285965, 5646.378898321182, 5700.800471983461, 25631.487370252864, 26330.078447254255, 26905.192844214314, 26910.32724181911, 27062.864685676446, 26031.869840650674, 26305.473890522084, 26846.06038788633, 27050.458990820072, 27053.458930821274, 12888.355582220851, 12906.290154041619, 13080.803787943189, 13153.473861045642, 13169.868301316865, 9849.606015759577, 9890.109890109925, 9921.851172232567, 9936.105750482491, 9992.450415227233, 21244.89377553102, 21420.464488387915, 22022.559548809255, 22446.0, 22896.31311060671, 23384.18155364554, 23645.408864778517, 23649.52700945937, 23852.0, 23998.88000559986, 13187.80218296707, 13473.663158421117, 13484.393202306115, 13486.595402137951, 13490.258035808125, 16681.0, 16681.165941702624, 16748.74876876872, 16751.1624418781, 16848.0, 13108.672283192996, 13232.40454179545, 13308.201507909556, 13515.121517101357, 13557.79663305071, 13082.0, 13095.934520327337, 13104.737905242033, 13226.470941162246, 13318.467261309826, 10858.402787846428, 11118.722031949266, 11120.44397780124, 11120.666380008613, 11126.388048657402, 8544.530050847263, 8555.957220214108, 8588.570571471526, 8756.912430875609, 8763.956180219058, 8469.66121355139, 8474.66101355939, 8600.526971016403, 8607.8708819369, 8624.266937310398], "value": 7776.003115767259, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.2601461180831, 610.6275172144993, 611.0681211152988, 612.5773216480629, 612.9478994285486, 6446.935530644692, 6465.547411681184, 6483.189601299838, 6514.088027676124, 6542.313057129004, 6348.428641422271, 6395.936040639593, 6396.520260980422, 6417.550771446001, 6433.072951806928, 5304.4165141834565, 5328.573714102862, 5330.840074797762, 5341.839744807662, 5357.651752636086, 5060.721660308682, 5185.896282074356, 5294.073537131006, 5320.893582128355, 5351.652142610717, 5161.587073034167, 5169.793208271664, 5179.2749015137915, 5190.844274671766, 5208.479152084799, 5140.614453915971, 5143.382794064718, 5147.485251474823, 5150.407703114152, 5158.767855446514, 4676.438827340707, 4684.765761711901, 4687.414073240845, 4699.506551812045, 4706.082313948781, 6312.52656050798, 6341.4926805855885, 6377.202849643794, 6380.298167201604, 6407.070974708688, 6059.727312270914, 6095.299040610343, 6103.6032657877095, 6131.172291740602, 6147.569670123094, 6744.482491439436, 6754.864902701967, 6781.72873085076, 6845.794626161223, 6858.622775747332, 5065.974670126626, 5189.740512974374, 5266.815661451869, 5283.313169287968, 5306.124489459235, 7047.647617619098, 7070.787876363717, 7151.856962860714, 7232.2406147354295, 7271.418286537064, 5399.757010934517, 5414.864628384322, 5416.485433883775, 5421.0, 5430.239766432703, 5460.6177567570685, 5488.4785945335125, 5492.65429969652, 5503.449655034545, 5529.668219906818, 5955.731992060368, 6001.489873360813, 6055.545834062463, 6056.273247210363, 6087.208662873798, 6898.586084834926, 6919.861602767916, 6924.965375173092, 6943.9652801735665, 6951.304869513008, 1884.2745542966056, 1900.7814101378242, 1912.3689182569997, 1912.9426117216506, 1916.176044300964, 1266.714989127439, 1326.9270190139441, 1337.2845527642664, 1338.377654390713, 1362.2099182474142, 6385.616862988236, 6443.871122577522, 6498.5126115541525, 6500.869982600321, 6528.412442880163, 6768.390844823989, 6820.863582728318, 6870.484713646497, 6929.930700693029, 7017.298270172942, 6495.870082598416, 6518.837029074216, 6560.901586476207, 6562.77030303937, 6575.704093315812, 3656.926861462756, 3679.816009199583, 3681.9079523011605, 3710.14666626672, 3713.4986776785327, 7322.670479828421, 7338.156112047076, 7345.522541034816, 7348.5958272294465, 7368.815779605553, 6977.720891164398, 7097.828858238333, 7188.640567971581, 7224.602646854369, 7242.7102915883825, 7237.746678866214, 7268.491205615662, 7326.67029983652, 7340.082489688789, 7347.228541003225, 6910.965445172742, 6971.198312194163, 7002.509824312453, 7058.858822823618, 7101.721690095832, 6925.89611155823, 6929.861402772017, 6947.826304342432, 6972.0, 7019.859602808017, 7119.537230080028, 7209.0, 7288.817779555554, 7317.451191160578, 7335.413166946525, 5384.919226211531, 5398.299535646259, 5400.864978375572, 5404.2163886235485, 5423.566114710892, 6769.1876974763345, 6813.4549236061985, 6969.651517424007, 7022.824429389102, 7073.893891591527, 6673.366030227121, 6679.833004174934, 6704.7988560343265, 6718.563293385914, 6832.965835170792, 6648.5705573301975, 6650.733970641314, 6683.498737594797, 6696.861533539408, 6800.52396332252, 6400.23197216337, 6638.137042184485, 6684.487970619909, 6709.194896612437, 6741.59550426976, 6774.830629234309, 6809.69356378974, 6838.623875686885, 6848.931510684829, 6853.623050732258, 6568.376004279586, 6589.6705164740615, 6702.185082265362, 6703.011708033712, 6789.966050169717], "value": 5380.331116478875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [939.3931839146372, 981.5828272983985, 982.4301904895161, 1021.2493817044474, 1049.2235745548294, 20857.165713371465, 21382.037808298624, 21460.141594336248, 21496.355109346725, 21499.892500537495, 19071.332503362384, 19206.903965480153, 20041.699374509393, 20130.89934550325, 20349.59300813983, 1053.2048303530842, 1077.4828082520396, 1077.5312738958578, 1078.56857257097, 1083.4582708645678, 1032.7882784029293, 1086.8261078227486, 1093.8687357517108, 1097.1277834121852, 1097.341595042972, 1172.7185475485908, 1180.2741314091857, 1181.373871847922, 1181.5510106159647, 1181.94681239344, 1172.82407638854, 1173.4426147579916, 1175.424042219315, 1175.9588414405541, 1176.5470293936853, 861.190480947907, 862.9568521573896, 863.6631713631689, 864.1013346120028, 866.2680035370103, 20225.8988705057, 20733.58532829335, 20950.685739713917, 20984.580308393746, 21598.784012159835, 24315.513689726282, 24438.14466493682, 24930.50138997228, 24933.875330623232, 25026.374340641447, 19263.711044334344, 19365.12856921442, 19493.122809473607, 19510.0, 19542.70685939712, 4195.139996300751, 4231.449911511484, 4233.5554766749665, 4237.4279472271155, 4238.7032907696475, 163696.0, 168855.0, 170410.1479492607, 172435.0, 173790.99999999875, 18539.258429662932, 18730.625387492175, 18753.156107975174, 18877.339293124674, 18892.433227003214, 36980.81509592489, 37183.62816371856, 37644.62355376466, 37653.43519847204, 37689.62310376916, 9488.28837837165, 9543.42739435636, 9551.617935282511, 9579.473128977974, 9614.95192524033, 4811.711297322102, 4891.51084891508, 4910.410750709866, 4914.631402644816, 4932.235503496964, 6535.183102112236, 6572.86854262912, 6622.735090596419, 6646.966765166143, 6650.933490665128, 1112.249231768553, 1138.1236447935044, 1152.2164927849144, 1154.3535620052796, 1156.4160099149876, 750.9849803003909, 766.6856588798565, 777.5995362388375, 788.3220430429826, 793.6785601831267, 13305.268210248336, 13596.456141754219, 13880.0, 14503.782443263359, 14550.927245363706, 772.9265719756614, 780.0288640642323, 781.1329424338979, 782.9138794732575, 794.0947320055142, 6249.6562689051625, 6469.708863101172, 6491.026346048036, 6521.673916304166, 6562.540622156499, 152193.0, 152703.23648381687, 153193.0, 158340.20829895776, 158831.0, 135465.0, 135486.0, 136848.26303473875, 137870.0, 140250.0, 121191.0, 121585.39207303907, 122675.38662306631, 124422.0, 125556.37221813832, 11456.19806613529, 11852.348120853436, 11896.76206475883, 12005.53748044655, 12136.878631213574, 59962.70018649879, 60059.69970150121, 60192.39807601868, 61244.0, 61263.0, 73306.63346683446, 74935.5012899728, 75450.24549754433, 75674.0, 76971.0, 18139.0, 18324.358647447276, 18527.0, 18763.53091172677, 18775.0, 20265.49336266605, 20553.177872884917, 20647.483812904797, 20673.069711862703, 20754.688679669514, 18774.155163017425, 19180.71228931595, 19220.519487012938, 19278.22887084501, 19362.806371936666, 18397.0, 18461.446156615322, 18569.16438760286, 18670.0, 18792.436226913214, 17931.462056138338, 18057.367992120213, 18113.0, 18120.27518899229, 18160.90919545394, 13855.307234638429, 13970.79043814364, 13983.440662373798, 14024.719505610035, 14140.363683634056, 12057.276563406223, 12306.323152226714, 12557.121001529806, 12604.747905042032, 12728.045396595107], "value": 10351.960815574881, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1356.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 104.0, 105.0, 105.0, 106.0, 106.0, 106.0, 105.0, 103.0, 104.0], "value": 104.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 113.0, 111.0, 115.0, 113.0, 110.0, 111.0, 134.0, 113.0, 116.0], "value": 115.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 161.0, 161.0, 163.0, 164.0, 163.0, 162.0, 162.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 75.0, 73.0, 73.0, 73.0, 74.0, 73.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 78.0, 76.0, 77.0, 78.0, 78.0, 77.0, 78.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 113.0, 117.0, 117.0, 113.0, 114.0, 114.0, 115.0, 113.0], "value": 114.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 149.0, 148.0, 149.0, 148.0, 148.0, 149.0, 149.0, 148.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 100.0, 99.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 44.0, 44.0, 43.0, 45.0, 43.0, 43.0, 43.0, 43.0, 42.0], "value": 43.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 38.0, 40.0, 38.0, 38.0, 37.0, 37.0, 37.0], "value": 37.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 70.0, 71.0, 69.0, 75.0, 68.0, 75.0, 69.0, 69.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 100.0, 100.0, 104.0, 105.0, 102.0, 105.0, 107.0, 101.0, 103.0], "value": 103.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 152.0, 150.0, 148.0, 147.0, 153.0, 146.0, 145.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 65.0, 58.0, 59.0, 60.0, 59.0, 59.0, 58.0, 58.0], "value": 59.5, "unit": "score"}]}]} 10:21:47 INFO - Return code: 0 10:21:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:21:47 INFO - # TBPL SUCCESS # 10:21:47 INFO - Running post-action listener: _package_coverage_data 10:21:47 INFO - Running post-action listener: _resource_record_post_action 10:21:47 INFO - [mozharness: 2017-09-26 17:21:47.417787Z] Finished run-tests step (success) 10:21:47 INFO - Running post-run listener: _resource_record_post_run 10:21:47 INFO - Total resource usage - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 645599232; Read time: 12; Write time: 469468 10:21:47 INFO - TinderboxPrint: CPU usage
13.1% 10:21:47 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:21:47 INFO - TinderboxPrint: I/O write bytes / time
645,599,232 / 469,468 10:21:47 INFO - TinderboxPrint: CPU idle
8,171.4 (86.9%) 10:21:47 INFO - TinderboxPrint: CPU user
1,179.4 (12.5%) 10:21:47 INFO - TinderboxPrint: Swap in / out
0 / 0 10:21:47 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 349384704; Read time: 0; Write time: 310324 10:21:47 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:21:47 INFO - run-tests - Wall time: 1165s; CPU: 13.0%; Read bytes: 0; Write bytes: 295780352; Read time: 0; Write time: 159072 10:21:48 INFO - Running post-run listener: _upload_blobber_files 10:21:48 INFO - Blob upload gear active. 10:21:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:21:48 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 10:21:48 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'] 10:21:48 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 10:21:48 INFO - (blobuploader) - INFO - Open directory for files ... 10:21:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:21:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:21:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:49 INFO - (blobuploader) - INFO - Done attempting. 10:21:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:21:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:21:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:50 INFO - (blobuploader) - INFO - Done attempting. 10:21:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:21:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:21:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:57 INFO - (blobuploader) - INFO - Done attempting. 10:21:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:21:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:21:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:58 INFO - (blobuploader) - INFO - Done attempting. 10:21:58 INFO - (blobuploader) - INFO - Iteration through files over. 10:21:58 INFO - Return code: 0 10:21:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:21:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:21:58 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/48a73d2f781d3d1689abf30cec03b4fe7760fcf38033d505b0582425ef0002f16082eb53759f3c49ede615a283e2551b6092fee94a429e17f0488251934514fe", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9d28433d39b54a2915e2037354f24d4788f1c9ef562fda23d7d9a1c8eb11aed4f7b7b39abddc3a62dc399878cbc19efd5005d2e7a4df9c860171059346761125", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bb3585f76c313757ca4bc146ac7184e5b913e8fa5eaeb331a775f8ee76041cc7c4f0abd2b72652af89b2b4825f2c86941408b51991c75c666c2a5f092df2f0a5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8407ce5ae5360955458c3ea885375c1aa2ab81b560497855f7db629e0d5c836fba5d1390dbc2dfea86818fbeb0b7dedcc3ed7eb33ad45b19153ca4d9816f962a"} 10:21:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:21:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:21:58 INFO - Contents: 10:21:58 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/48a73d2f781d3d1689abf30cec03b4fe7760fcf38033d505b0582425ef0002f16082eb53759f3c49ede615a283e2551b6092fee94a429e17f0488251934514fe", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9d28433d39b54a2915e2037354f24d4788f1c9ef562fda23d7d9a1c8eb11aed4f7b7b39abddc3a62dc399878cbc19efd5005d2e7a4df9c860171059346761125", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bb3585f76c313757ca4bc146ac7184e5b913e8fa5eaeb331a775f8ee76041cc7c4f0abd2b72652af89b2b4825f2c86941408b51991c75c666c2a5f092df2f0a5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8407ce5ae5360955458c3ea885375c1aa2ab81b560497855f7db629e0d5c836fba5d1390dbc2dfea86818fbeb0b7dedcc3ed7eb33ad45b19153ca4d9816f962a"} 10:21:58 INFO - Running post-run listener: copy_logs_to_upload_dir 10:21:58 INFO - Copying logs to upload dir... 10:21:58 INFO - mkdir: /builds/slave/test/build/upload/logs 10:21:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.562347 ========= master_lag: 0.37 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2017-09-26 10:21:58.407067) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 10:21:58.414203) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782 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/48a73d2f781d3d1689abf30cec03b4fe7760fcf38033d505b0582425ef0002f16082eb53759f3c49ede615a283e2551b6092fee94a429e17f0488251934514fe", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9d28433d39b54a2915e2037354f24d4788f1c9ef562fda23d7d9a1c8eb11aed4f7b7b39abddc3a62dc399878cbc19efd5005d2e7a4df9c860171059346761125", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bb3585f76c313757ca4bc146ac7184e5b913e8fa5eaeb331a775f8ee76041cc7c4f0abd2b72652af89b2b4825f2c86941408b51991c75c666c2a5f092df2f0a5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8407ce5ae5360955458c3ea885375c1aa2ab81b560497855f7db629e0d5c836fba5d1390dbc2dfea86818fbeb0b7dedcc3ed7eb33ad45b19153ca4d9816f962a"} build_url:https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://queue.taskcluster.net/v1/task/IJs3sNtqQOyJ87TYSa9wLA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/48a73d2f781d3d1689abf30cec03b4fe7760fcf38033d505b0582425ef0002f16082eb53759f3c49ede615a283e2551b6092fee94a429e17f0488251934514fe", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9d28433d39b54a2915e2037354f24d4788f1c9ef562fda23d7d9a1c8eb11aed4f7b7b39abddc3a62dc399878cbc19efd5005d2e7a4df9c860171059346761125", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bb3585f76c313757ca4bc146ac7184e5b913e8fa5eaeb331a775f8ee76041cc7c4f0abd2b72652af89b2b4825f2c86941408b51991c75c666c2a5f092df2f0a5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8407ce5ae5360955458c3ea885375c1aa2ab81b560497855f7db629e0d5c836fba5d1390dbc2dfea86818fbeb0b7dedcc3ed7eb33ad45b19153ca4d9816f962a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 10:21:58.447685) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 10:21:58.448016) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1506445286.185799-1109436782 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004523 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 10:21:58.528602) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 10:21:58.531925) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 10:21:58.532508) ========= ========= Total master_lag: 0.54 =========