builder: mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s-pgo slave: talos-linux64-ix-015 starttime: 1506012745.06 results: success (0) revision: b14c75b83d0226333b1240466ea9f07cfb206ff3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:25.056302) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:25.056769) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:25.057042) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:25.194076) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:25.194397) ========= 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=20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 09:52:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 229M=0s 2017-09-21 09:52:25 (229 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118042 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:25.350571) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:25.354047) ========= 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=20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019533 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:25.430109) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:25.430403) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b14c75b83d0226333b1240466ea9f07cfb206ff3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev b14c75b83d0226333b1240466ea9f07cfb206ff3 --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=20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 09:52:25,822 truncating revision to first 12 chars 2017-09-21 09:52:25,822 Setting DEBUG logging. 2017-09-21 09:52:25,822 attempt 1/10 2017-09-21 09:52:25,822 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b14c75b83d02?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 09:52:26,196 unpacking tar archive at: mozilla-central-b14c75b83d02/testing/mozharness/ program finished with exit code 0 elapsedTime=0.651058 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:26.136023) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:26.136291) ========= script_repo_revision: b14c75b83d0226333b1240466ea9f07cfb206ff3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:26.136658) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:26.136937) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 09:52:26.192030) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-21 09:52:26.192341) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:52:26 INFO - MultiFileLogger online at 20170921 09:52:26 in /builds/slave/test 09:52:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:52:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:52:26 INFO - {'append_to_log': False, 09:52:26 INFO - 'base_work_dir': '/builds/slave/test', 09:52:26 INFO - 'blob_upload_branch': 'Firefox', 09:52:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:52:26 INFO - 'branch': 'Firefox', 09:52:26 INFO - 'buildbot_json_path': 'buildprops.json', 09:52:26 INFO - 'code_coverage': False, 09:52:26 INFO - 'config_files': ('talos/linux_config.py',), 09:52:26 INFO - 'default_actions': ('clobber', 09:52:26 INFO - 'read-buildbot-config', 09:52:26 INFO - 'download-and-extract', 09:52:26 INFO - 'populate-webroot', 09:52:26 INFO - 'create-virtualenv', 09:52:26 INFO - 'install', 09:52:26 INFO - 'setup-mitmproxy', 09:52:26 INFO - 'run-tests'), 09:52:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:52:26 INFO - 'disable_ccov_upload': False, 09:52:26 INFO - 'disable_stylo': False, 09:52:26 INFO - 'download_minidump_stackwalk': True, 09:52:26 INFO - 'download_symbols': 'ondemand', 09:52:26 INFO - 'enable_stylo': False, 09:52:26 INFO - 'enable_webrender': False, 09:52:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 09:52:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:52:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:52:26 INFO - 'gecko_profile': False, 09:52:26 INFO - 'gecko_profile_interval': 0, 09:52:26 INFO - 'installer_path': 'installer.exe', 09:52:26 INFO - 'log_level': 'info', 09:52:26 INFO - 'log_name': 'talos', 09:52:26 INFO - 'log_to_console': True, 09:52:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:52:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:52:26 INFO - 'opt_config_files': (), 09:52:26 INFO - 'pip_index': False, 09:52:26 INFO - 'suite': 'dromaeojs-stylo-disabled-e10s', 09:52:26 INFO - 'system_bits': '32', 09:52:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:52:26 INFO - 'title': 'talos-linux64-ix-015', 09:52:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:52:26 INFO - 'use_talos_json': True, 09:52:26 INFO - 'verify': 'False', 09:52:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:52:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:52:26 INFO - 'work_dir': 'build'} 09:52:26 INFO - [mozharness: 2017-09-21 16:52:26.708741Z] Running clobber step. 09:52:26 INFO - Running pre-action listener: _resource_record_pre_action 09:52:26 INFO - Running main action method: clobber 09:52:26 INFO - rmtree: /builds/slave/test/build 09:52:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:52:28 INFO - Running post-action listener: _resource_record_post_action 09:52:28 INFO - [mozharness: 2017-09-21 16:52:28.226905Z] Finished clobber step (success) 09:52:28 INFO - [mozharness: 2017-09-21 16:52:28.227002Z] Running read-buildbot-config step. 09:52:28 INFO - Running pre-action listener: _resource_record_pre_action 09:52:28 INFO - Running main action method: read_buildbot_config 09:52:28 INFO - Using buildbot properties: 09:52:28 INFO - { 09:52:28 INFO - "project": "", 09:52:28 INFO - "product": "firefox", 09:52:28 INFO - "who": "archaeopteryx@coole-files.de", 09:52:28 INFO - "installer_path": "public/build/target.tar.bz2", 09:52:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s", 09:52:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 09:52:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-stylo-disabled-e10s", 09:52:28 INFO - "stage_platform": "linux64-pgo", 09:52:28 INFO - "basedir": "/builds/slave/test", 09:52:28 INFO - "buildnumber": 46, 09:52:28 INFO - "platform": "ubuntu64_hw", 09:52:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:52:28 INFO - "slavebuilddir": "test", 09:52:28 INFO - "taskId": "VnX4Dt_oQFKXrQrtxrVLoQ", 09:52:28 INFO - "branch": "mozilla-central", 09:52:28 INFO - "script_repo_revision": "production", 09:52:28 INFO - "revision": "b14c75b83d0226333b1240466ea9f07cfb206ff3", 09:52:28 INFO - "slavename": "talos-linux64-ix-015", 09:52:28 INFO - "repo_path": "mozilla-central" 09:52:28 INFO - } 09:52:28 INFO - Finding installer, test and symbols from parent task. 09:52:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VnX4Dt_oQFKXrQrtxrVLoQ'}, attempt #1 09:52:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VnX4Dt_oQFKXrQrtxrVLoQ'}, attempt #1 09:52:29 INFO - Task dependencies: PaoJjzfISE2tJvHNzbVhiw 09:52:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw'}, attempt #1 09:52:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.tar.bz2 09:52:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.test_packages.json 09:52:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.crashreporter-symbols.zip 09:52:29 INFO - Running post-action listener: _resource_record_post_action 09:52:29 INFO - [mozharness: 2017-09-21 16:52:29.646517Z] Finished read-buildbot-config step (success) 09:52:29 INFO - [mozharness: 2017-09-21 16:52:29.646735Z] Running download-and-extract step. 09:52:29 INFO - Running pre-action listener: _resource_record_pre_action 09:52:29 INFO - Running main action method: download_and_extract 09:52:29 INFO - mkdir: /builds/slave/test/build/tests 09:52:29 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')]} 09:52:29 INFO - trying https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.test_packages.json 09:52:29 INFO - Downloading https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:52:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:52:30 INFO - Downloaded 989 bytes. 09:52:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:52:30 INFO - Using the following test package requirements: 09:52:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:52:30 INFO - u'common': [u'target.common.tests.zip'], 09:52:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:52:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:52:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:52:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:52:30 INFO - u'mozbase': [u'target.common.tests.zip'], 09:52:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:52:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:52:30 INFO - u'web-platform': [u'target.common.tests.zip', 09:52:30 INFO - u'target.web-platform.tests.tar.gz'], 09:52:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:52:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 09:52:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.common.tests.zip 09:52:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:52:30 INFO - Fetch https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.common.tests.zip into memory 09:52:32 INFO - Content-Length response header: 55326003 09:52:32 INFO - Bytes received: 55326003 09:52:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.talos.tests.zip 09:52:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:52:43 INFO - Fetch https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.talos.tests.zip into memory 09:52:44 INFO - Content-Length response header: 14509184 09:52:44 INFO - Bytes received: 14509184 09:52:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:44 INFO - trying https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.tar.bz2 09:52:44 INFO - Downloading https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:52:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:52:47 INFO - Downloaded 62334866 bytes. 09:52:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.tar.bz2 09:52:47 INFO - mkdir: /builds/slave/test/properties 09:52:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:52:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:52:47 INFO - Contents: 09:52:47 INFO - build_url:https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.tar.bz2 09:52:47 INFO - Running post-action listener: _resource_record_post_action 09:52:47 INFO - Running post-action listener: find_tests_for_verification 09:52:47 INFO - Running post-action listener: set_extra_try_arguments 09:52:47 INFO - [mozharness: 2017-09-21 16:52:47.477375Z] Finished download-and-extract step (success) 09:52:47 INFO - [mozharness: 2017-09-21 16:52:47.477471Z] Running populate-webroot step. 09:52:47 INFO - Running pre-action listener: _resource_record_pre_action 09:52:47 INFO - Running main action method: populate_webroot 09:52:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:52:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['tresize', 09:52:47 INFO - 'tcanvasmark']}, 09:52:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:52:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['dromaeo_css', 09:52:47 INFO - 'kraken']}, 09:52:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:52:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:52:47 INFO - 'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:52:47 INFO - 'tests': ['damp', 'tps']}, 09:52:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:52:47 INFO - 'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['damp', 'tps']}, 09:52:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:52:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['dromaeo_dom']}, 09:52:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:52:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['basic_compositor_video', 09:52:47 INFO - 'glvideo']}, 09:52:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:52:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:52:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:52:47 INFO - 'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['ts_paint_webext', 09:52:47 INFO - 'tp5o_webext']}, 09:52:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:52:47 INFO - 'ts_paint', 09:52:47 INFO - 'tpaint', 09:52:47 INFO - 'sessionrestore', 09:52:47 INFO - 'sessionrestore_many_windows', 09:52:47 INFO - 'sessionrestore_no_auto_restore', 09:52:47 INFO - 'tabpaint']}, 09:52:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['a11yr', 09:52:47 INFO - 'ts_paint', 09:52:47 INFO - 'tpaint', 09:52:47 INFO - 'sessionrestore', 09:52:47 INFO - 'sessionrestore_many_windows', 09:52:47 INFO - 'sessionrestore_no_auto_restore', 09:52:47 INFO - 'tabpaint']}, 09:52:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:52:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 09:52:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['perf_reftest_singletons']}, 09:52:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['bloom_basic']}, 09:52:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:52:47 INFO - 'tsvgr_opacity', 09:52:47 INFO - 'tart', 09:52:47 INFO - 'tscrollx', 09:52:47 INFO - 'tsvg_static']}, 09:52:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['tsvgx', 09:52:47 INFO - 'tsvgr_opacity', 09:52:47 INFO - 'tart', 09:52:47 INFO - 'tscrollx', 09:52:47 INFO - 'tsvg_static']}, 09:52:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:52:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:52:47 INFO - 'talos_options': ['--disable-stylo'], 09:52:47 INFO - 'tests': ['tp5o']}, 09:52:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:52:47 INFO - 'talos_options': ['--mitmproxy', 09:52:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:52:47 INFO - '--firstNonBlankPaint'], 09:52:47 INFO - 'tests': ['tp6_google', 09:52:47 INFO - 'tp6_youtube', 09:52:47 INFO - 'tp6_amazon', 09:52:47 INFO - 'tp6_facebook']}, 09:52:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:52:47 INFO - 'talos_options': ['--disable-stylo', 09:52:47 INFO - '--mitmproxy', 09:52:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:52:47 INFO - '--firstNonBlankPaint'], 09:52:47 INFO - 'tests': ['tp6_google', 09:52:47 INFO - 'tp6_youtube', 09:52:47 INFO - 'tp6_amazon', 09:52:47 INFO - 'tp6_facebook']}, 09:52:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:52:47 INFO - 'talos_options': ['--stylo-threads=1', 09:52:47 INFO - '--mitmproxy', 09:52:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:52:47 INFO - '--firstNonBlankPaint'], 09:52:47 INFO - 'tests': ['tp6_google', 09:52:47 INFO - 'tp6_youtube', 09:52:47 INFO - 'tp6_amazon', 09:52:47 INFO - 'tp6_facebook']}, 09:52:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:52:47 INFO - 'talos_options': ['--xperf_path', 09:52:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:47 INFO - 'tests': ['tp5n']}, 09:52:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:52:47 INFO - 'talos_options': ['--disable-stylo', 09:52:47 INFO - '--xperf_path', 09:52:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:47 INFO - 'tests': ['tp5n']}}} 09:52:47 INFO - Running post-action listener: _resource_record_post_action 09:52:47 INFO - [mozharness: 2017-09-21 16:52:47.492468Z] Finished populate-webroot step (success) 09:52:47 INFO - [mozharness: 2017-09-21 16:52:47.492558Z] Running create-virtualenv step. 09:52:47 INFO - Running pre-action listener: _resource_record_pre_action 09:52:47 INFO - Running main action method: create_virtualenv 09:52:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:52:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:52:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 09:52:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:52:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:52:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:52:48 INFO - Installing distribute.............................................................................................................................................................................................done. 09:52:48 INFO - Installing pip...............done. 09:52:48 INFO - Return code: 0 09:52:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:52:48 INFO - Reading from file tmpfile_stdout 09:52:48 INFO - Output received: 09:52:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 09:52:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:52:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:52:48 INFO - Using env: {'DISPLAY': ':0', 09:52:48 INFO - 'HOME': '/home/cltbld', 09:52:48 INFO - 'LANG': 'en_US.UTF-8', 09:52:48 INFO - 'LANGUAGE': 'en_US:en', 09:52:48 INFO - 'LOGNAME': 'cltbld', 09:52:48 INFO - 'MAIL': '/var/mail/cltbld', 09:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:48 INFO - 'MOZ_NO_REMOTE': '1', 09:52:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:48 INFO - 'NO_EM_RESTART': '1', 09:52:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:48 INFO - 'PWD': '/builds/slave/test', 09:52:48 INFO - 'SHELL': '/bin/bash', 09:52:48 INFO - 'SHLVL': '1', 09:52:48 INFO - 'TERM': 'linux', 09:52:48 INFO - 'TMOUT': '86400', 09:52:48 INFO - 'USER': 'cltbld', 09:52:48 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:48 INFO - '_': '/tools/buildbot/bin/python'} 09:52:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 09:52:49 INFO - Downloading/unpacking pip>=1.5 09:52:49 INFO - Running setup.py egg_info for package pip 09:52:49 INFO - warning: no files found matching 'pip/cacert.pem' 09:52:49 INFO - warning: no files found matching '*.html' under directory 'docs' 09:52:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:52:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:52:49 INFO - Installing collected packages: pip 09:52:49 INFO - Found existing installation: pip 1.1 09:52:49 INFO - Uninstalling pip: 09:52:49 INFO - Successfully uninstalled pip 09:52:49 INFO - Running setup.py install for pip 09:52:49 INFO - warning: no files found matching 'pip/cacert.pem' 09:52:49 INFO - warning: no files found matching '*.html' under directory 'docs' 09:52:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:52:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:52:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 09:52:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 09:52:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 09:52:49 INFO - Successfully installed pip 09:52:49 INFO - Cleaning up... 09:52:49 INFO - Return code: 0 09:52:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:52: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')]} 09:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52: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')]} 09:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:52:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:52:49 INFO - Using env: {'DISPLAY': ':0', 09:52:49 INFO - 'HOME': '/home/cltbld', 09:52:49 INFO - 'LANG': 'en_US.UTF-8', 09:52:49 INFO - 'LANGUAGE': 'en_US:en', 09:52:49 INFO - 'LOGNAME': 'cltbld', 09:52:49 INFO - 'MAIL': '/var/mail/cltbld', 09:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:49 INFO - 'MOZ_NO_REMOTE': '1', 09:52:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:49 INFO - 'NO_EM_RESTART': '1', 09:52:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:49 INFO - 'PWD': '/builds/slave/test', 09:52:49 INFO - 'SHELL': '/bin/bash', 09:52:49 INFO - 'SHLVL': '1', 09:52:49 INFO - 'TERM': 'linux', 09:52:49 INFO - 'TMOUT': '86400', 09:52:49 INFO - 'USER': 'cltbld', 09:52:49 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:49 INFO - '_': '/tools/buildbot/bin/python'} 09:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:49 INFO - Downloading/unpacking psutil>=3.1.1 09:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:52:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:51 INFO - Installing collected packages: psutil 09:52:51 INFO - Running setup.py install for psutil 09:52:51 INFO - building 'psutil._psutil_linux' extension 09:52:51 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 09:52:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:52:51 INFO - building 'psutil._psutil_posix' extension 09:52:51 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 09:52:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:52:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:51 INFO - Successfully installed psutil 09:52:51 INFO - Cleaning up... 09:52:51 INFO - Return code: 0 09:52:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:52:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:52:51 INFO - Using env: {'DISPLAY': ':0', 09:52:51 INFO - 'HOME': '/home/cltbld', 09:52:51 INFO - 'LANG': 'en_US.UTF-8', 09:52:51 INFO - 'LANGUAGE': 'en_US:en', 09:52:51 INFO - 'LOGNAME': 'cltbld', 09:52:51 INFO - 'MAIL': '/var/mail/cltbld', 09:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:51 INFO - 'MOZ_NO_REMOTE': '1', 09:52:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:51 INFO - 'NO_EM_RESTART': '1', 09:52:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:51 INFO - 'PWD': '/builds/slave/test', 09:52:51 INFO - 'SHELL': '/bin/bash', 09:52:51 INFO - 'SHLVL': '1', 09:52:51 INFO - 'TERM': 'linux', 09:52:51 INFO - 'TMOUT': '86400', 09:52:51 INFO - 'USER': 'cltbld', 09:52:51 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:51 INFO - '_': '/tools/buildbot/bin/python'} 09:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:52: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 09:52: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 09:52: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 09:52: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 09:52:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:52:53 INFO - Installing collected packages: mozsystemmonitor 09:52:53 INFO - Running setup.py install for mozsystemmonitor 09:52:53 INFO - Successfully installed mozsystemmonitor 09:52:53 INFO - Cleaning up... 09:52:53 INFO - Return code: 0 09:52:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:52:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:52:53 INFO - Using env: {'DISPLAY': ':0', 09:52:53 INFO - 'HOME': '/home/cltbld', 09:52:53 INFO - 'LANG': 'en_US.UTF-8', 09:52:53 INFO - 'LANGUAGE': 'en_US:en', 09:52:53 INFO - 'LOGNAME': 'cltbld', 09:52:53 INFO - 'MAIL': '/var/mail/cltbld', 09:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:53 INFO - 'MOZ_NO_REMOTE': '1', 09:52:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:53 INFO - 'NO_EM_RESTART': '1', 09:52:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:53 INFO - 'PWD': '/builds/slave/test', 09:52:53 INFO - 'SHELL': '/bin/bash', 09:52:53 INFO - 'SHLVL': '1', 09:52:53 INFO - 'TERM': 'linux', 09:52:53 INFO - 'TMOUT': '86400', 09:52:53 INFO - 'USER': 'cltbld', 09:52:53 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:53 INFO - '_': '/tools/buildbot/bin/python'} 09:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:53 INFO - Downloading/unpacking jsonschema==2.5.1 09:52: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 09:52: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 09:52: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 09:52: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 09:52:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:52:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:52: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 09:52: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 09:52: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 09:52: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 09:52:54 INFO - Downloading functools32-3.2.3-2.tar.gz 09:52:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:52:54 INFO - warning: no files found matching '*.txt' 09:52:54 INFO - no previously-included directories found matching 'build' 09:52:54 INFO - no previously-included directories found matching 'dist' 09:52:54 INFO - no previously-included directories found matching '.git*' 09:52:54 INFO - Installing collected packages: jsonschema, functools32 09:52:54 INFO - Running setup.py install for functools32 09:52:54 INFO - warning: no files found matching '*.txt' 09:52:54 INFO - no previously-included directories found matching 'build' 09:52:54 INFO - no previously-included directories found matching 'dist' 09:52:54 INFO - no previously-included directories found matching '.git*' 09:52:54 INFO - Successfully installed jsonschema functools32 09:52:54 INFO - Cleaning up... 09:52:55 INFO - Return code: 0 09:52:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:52:55 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')]} 09:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:55 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')]} 09:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:55 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 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:52:55 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 09:52:55 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 09:52:55 INFO - Using env: {'DISPLAY': ':0', 09:52:55 INFO - 'HOME': '/home/cltbld', 09:52:55 INFO - 'LANG': 'en_US.UTF-8', 09:52:55 INFO - 'LANGUAGE': 'en_US:en', 09:52:55 INFO - 'LOGNAME': 'cltbld', 09:52:55 INFO - 'MAIL': '/var/mail/cltbld', 09:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:55 INFO - 'MOZ_NO_REMOTE': '1', 09:52:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:55 INFO - 'NO_EM_RESTART': '1', 09:52:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:55 INFO - 'PWD': '/builds/slave/test', 09:52:55 INFO - 'SHELL': '/bin/bash', 09:52:55 INFO - 'SHLVL': '1', 09:52:55 INFO - 'TERM': 'linux', 09:52:55 INFO - 'TMOUT': '86400', 09:52:55 INFO - 'USER': 'cltbld', 09:52:55 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:55 INFO - '_': '/tools/buildbot/bin/python'} 09:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:52:55 INFO - Cleaning up... 09:52:55 INFO - Return code: 0 09:52:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:52:55 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')]} 09:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:55 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')]} 09:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:55 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 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:52:55 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 09:52:55 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 09:52:55 INFO - Using env: {'DISPLAY': ':0', 09:52:55 INFO - 'HOME': '/home/cltbld', 09:52:55 INFO - 'LANG': 'en_US.UTF-8', 09:52:55 INFO - 'LANGUAGE': 'en_US:en', 09:52:55 INFO - 'LOGNAME': 'cltbld', 09:52:55 INFO - 'MAIL': '/var/mail/cltbld', 09:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:55 INFO - 'MOZ_NO_REMOTE': '1', 09:52:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:55 INFO - 'NO_EM_RESTART': '1', 09:52:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:55 INFO - 'PWD': '/builds/slave/test', 09:52:55 INFO - 'SHELL': '/bin/bash', 09:52:55 INFO - 'SHLVL': '1', 09:52:55 INFO - 'TERM': 'linux', 09:52:55 INFO - 'TMOUT': '86400', 09:52:55 INFO - 'USER': 'cltbld', 09:52:55 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:55 INFO - '_': '/tools/buildbot/bin/python'} 09:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:52: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 09:52: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 09:52: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 09:52: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 09:52:56 INFO - Downloading blobuploader-1.2.4.tar.gz 09:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:52:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:52: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 09:52: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 09:52: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 09:52: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 09:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:52:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:52: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 09:52: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 09:52: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 09:52: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 09:52:57 INFO - Downloading docopt-0.6.1.tar.gz 09:52:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:52:57 INFO - Installing collected packages: blobuploader, requests, docopt 09:52:57 INFO - Running setup.py install for blobuploader 09:52:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:52:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:52:57 INFO - Running setup.py install for requests 09:52:57 INFO - Running setup.py install for docopt 09:52:57 INFO - Successfully installed blobuploader requests docopt 09:52:57 INFO - Cleaning up... 09:52:57 INFO - Return code: 0 09:52:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:52:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:52:57 INFO - Using env: {'DISPLAY': ':0', 09:52:57 INFO - 'HOME': '/home/cltbld', 09:52:57 INFO - 'LANG': 'en_US.UTF-8', 09:52:57 INFO - 'LANGUAGE': 'en_US:en', 09:52:57 INFO - 'LOGNAME': 'cltbld', 09:52:57 INFO - 'MAIL': '/var/mail/cltbld', 09:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:57 INFO - 'MOZ_NO_REMOTE': '1', 09:52:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:57 INFO - 'NO_EM_RESTART': '1', 09:52:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:57 INFO - 'PWD': '/builds/slave/test', 09:52:57 INFO - 'SHELL': '/bin/bash', 09:52:57 INFO - 'SHLVL': '1', 09:52:57 INFO - 'TERM': 'linux', 09:52:57 INFO - 'TMOUT': '86400', 09:52:57 INFO - 'USER': 'cltbld', 09:52:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:57 INFO - '_': '/tools/buildbot/bin/python'} 09:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:52:58 INFO - Running setup.py (path:/tmp/pip-bEv4Ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:52:58 INFO - Running setup.py (path:/tmp/pip-s_d8Gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:52:58 INFO - Running setup.py (path:/tmp/pip-j4cAaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:52:58 INFO - Running setup.py (path:/tmp/pip-p7dsNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:52:58 INFO - Running setup.py (path:/tmp/pip-BVeAW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:52:58 INFO - Running setup.py (path:/tmp/pip-W_Amac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:52:58 INFO - Running setup.py (path:/tmp/pip-YuASHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:52:58 INFO - Running setup.py (path:/tmp/pip-WwyFwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:52:58 INFO - Running setup.py (path:/tmp/pip-8O7J3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:52:58 INFO - Running setup.py (path:/tmp/pip-C3stNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:52:58 INFO - Running setup.py (path:/tmp/pip-rOtH22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:52:58 INFO - Running setup.py (path:/tmp/pip-Ie18HU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:52:58 INFO - Running setup.py (path:/tmp/pip-dBlsSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:52:59 INFO - Running setup.py (path:/tmp/pip-WC4YIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:59 INFO - Running setup.py (path:/tmp/pip-w6_gIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:52:59 INFO - Running setup.py (path:/tmp/pip-jCuFnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:52:59 INFO - Running setup.py (path:/tmp/pip-uZPyRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:52:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:52:59 INFO - Running setup.py install for manifestparser 09:52:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:52:59 INFO - Running setup.py install for mozcrash 09:52:59 INFO - Running setup.py install for mozdebug 09:52:59 INFO - Running setup.py install for mozdevice 09:52:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:52:59 INFO - Running setup.py install for mozfile 09:52:59 INFO - Running setup.py install for mozhttpd 09:52:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:52:59 INFO - Running setup.py install for mozinfo 09:52:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:52:59 INFO - Running setup.py install for mozInstall 09:53:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Running setup.py install for mozleak 09:53:00 INFO - Running setup.py install for mozlog 09:53:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Running setup.py install for moznetwork 09:53:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Running setup.py install for mozprocess 09:53:00 INFO - Running setup.py install for mozprofile 09:53:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Running setup.py install for mozrunner 09:53:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Running setup.py install for mozscreenshot 09:53:00 INFO - Running setup.py install for moztest 09:53:00 INFO - Running setup.py install for mozversion 09:53:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:53:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:53:01 INFO - Cleaning up... 09:53:01 INFO - Return code: 0 09:53:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53: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')]} 09:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53: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')]} 09:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53: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 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:53: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 09:53: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 09:53:01 INFO - Using env: {'DISPLAY': ':0', 09:53:01 INFO - 'HOME': '/home/cltbld', 09:53:01 INFO - 'LANG': 'en_US.UTF-8', 09:53:01 INFO - 'LANGUAGE': 'en_US:en', 09:53:01 INFO - 'LOGNAME': 'cltbld', 09:53:01 INFO - 'MAIL': '/var/mail/cltbld', 09:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:01 INFO - 'MOZ_NO_REMOTE': '1', 09:53:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:01 INFO - 'NO_EM_RESTART': '1', 09:53:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:01 INFO - 'PWD': '/builds/slave/test', 09:53:01 INFO - 'SHELL': '/bin/bash', 09:53:01 INFO - 'SHLVL': '1', 09:53:01 INFO - 'TERM': 'linux', 09:53:01 INFO - 'TMOUT': '86400', 09:53:01 INFO - 'USER': 'cltbld', 09:53:01 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:01 INFO - '_': '/tools/buildbot/bin/python'} 09:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:53:01 INFO - Running setup.py (path:/tmp/pip-VXYMNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:53:01 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)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:53:01 INFO - Running setup.py (path:/tmp/pip-XMfxDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:53:01 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)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:53:01 INFO - Running setup.py (path:/tmp/pip-MgZm3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:53:01 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)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:53:01 INFO - Running setup.py (path:/tmp/pip-GRDMpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:53:01 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)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:53:01 INFO - Running setup.py (path:/tmp/pip-TODdVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:53:01 INFO - Running setup.py (path:/tmp/pip-1sQjB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:53:01 INFO - Running setup.py (path:/tmp/pip-Za5fnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:53:01 INFO - Running setup.py (path:/tmp/pip-1rjso1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:53:01 INFO - Running setup.py (path:/tmp/pip-0B3Xbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:53:01 INFO - Running setup.py (path:/tmp/pip-XdTMUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:53:01 INFO - Running setup.py (path:/tmp/pip-AQrszP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:53: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)) 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:53:02 INFO - Running setup.py (path:/tmp/pip-kp9m_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:53:02 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)) 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:53:02 INFO - Running setup.py (path:/tmp/pip-37RTlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:53:02 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)) 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:53:02 INFO - Running setup.py (path:/tmp/pip-KlAaiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:53:02 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)) 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:02 INFO - Running setup.py (path:/tmp/pip-eVr97j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:02 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)) 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:53:02 INFO - Running setup.py (path:/tmp/pip-RmC5wB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:53:02 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)) 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:53:02 INFO - Running setup.py (path:/tmp/pip-KtCEwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:03 INFO - Downloading blessings-1.6.tar.gz 09:53:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:53:03 INFO - Installing collected packages: blessings 09:53:03 INFO - Running setup.py install for blessings 09:53:03 INFO - Successfully installed blessings 09:53:03 INFO - Cleaning up... 09:53:03 INFO - Return code: 0 09:53:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:53:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:04 INFO - Reading from file tmpfile_stdout 09:53:04 INFO - Current package versions: 09:53:04 INFO - argparse == 1.2.1 09:53:04 INFO - blessings == 1.6 09:53:04 INFO - blobuploader == 1.2.4 09:53:04 INFO - docopt == 0.6.1 09:53:04 INFO - functools32 == 3.2.3-2 09:53:04 INFO - jsonschema == 2.5.1 09:53:04 INFO - manifestparser == 1.1 09:53:04 INFO - mozInstall == 1.13 09:53:04 INFO - mozcrash == 1.0 09:53:04 INFO - mozdebug == 0.1 09:53:04 INFO - mozdevice == 0.51 09:53:04 INFO - mozfile == 1.2 09:53:04 INFO - mozhttpd == 0.7 09:53:04 INFO - mozinfo == 0.10 09:53:04 INFO - mozleak == 0.1 09:53:04 INFO - mozlog == 3.5 09:53:04 INFO - moznetwork == 0.27 09:53:04 INFO - mozprocess == 0.25 09:53:04 INFO - mozprofile == 0.28 09:53:04 INFO - mozrunner == 6.13 09:53:04 INFO - mozscreenshot == 0.1 09:53:04 INFO - mozsystemmonitor == 0.3 09:53:04 INFO - moztest == 0.8 09:53:04 INFO - mozversion == 1.4 09:53:04 INFO - psutil == 3.1.1 09:53:04 INFO - requests == 1.2.3 09:53:04 INFO - wsgiref == 0.1.2 09:53:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53: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')]} 09:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53: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')]} 09:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53: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 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:53: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 09:53: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 09:53:04 INFO - Using env: {'DISPLAY': ':0', 09:53:04 INFO - 'HOME': '/home/cltbld', 09:53:04 INFO - 'LANG': 'en_US.UTF-8', 09:53:04 INFO - 'LANGUAGE': 'en_US:en', 09:53:04 INFO - 'LOGNAME': 'cltbld', 09:53:04 INFO - 'MAIL': '/var/mail/cltbld', 09:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:04 INFO - 'MOZ_NO_REMOTE': '1', 09:53:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:04 INFO - 'NO_EM_RESTART': '1', 09:53:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:04 INFO - 'PWD': '/builds/slave/test', 09:53:04 INFO - 'SHELL': '/bin/bash', 09:53:04 INFO - 'SHLVL': '1', 09:53:04 INFO - 'TERM': 'linux', 09:53:04 INFO - 'TMOUT': '86400', 09:53:04 INFO - 'USER': 'cltbld', 09:53:04 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:04 INFO - '_': '/tools/buildbot/bin/python'} 09:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:04 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)) 09:53:04 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)) 09:53:04 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)) 09:53:04 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)) 09:53:04 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)) 09:53:04 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)) 09:53:04 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)) 09:53:04 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)) 09:53:04 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)) 09:53:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:53:05 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)) 09:53:05 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)) 09:53:05 INFO - Installing collected packages: simplejson 09:53:05 INFO - Running setup.py install for simplejson 09:53:05 INFO - building 'simplejson._speedups' extension 09:53:05 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 09:53:06 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 09:53:06 INFO - Successfully installed simplejson 09:53:06 INFO - Cleaning up... 09:53:06 INFO - Return code: 0 09:53:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:06 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 0x19fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', '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 09:53:06 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 09:53:06 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 09:53:06 INFO - Using env: {'DISPLAY': ':0', 09:53:06 INFO - 'HOME': '/home/cltbld', 09:53:06 INFO - 'LANG': 'en_US.UTF-8', 09:53:06 INFO - 'LANGUAGE': 'en_US:en', 09:53:06 INFO - 'LOGNAME': 'cltbld', 09:53:06 INFO - 'MAIL': '/var/mail/cltbld', 09:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:06 INFO - 'MOZ_NO_REMOTE': '1', 09:53:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:06 INFO - 'NO_EM_RESTART': '1', 09:53:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:06 INFO - 'PWD': '/builds/slave/test', 09:53:06 INFO - 'SHELL': '/bin/bash', 09:53:06 INFO - 'SHLVL': '1', 09:53:06 INFO - 'TERM': 'linux', 09:53:06 INFO - 'TMOUT': '86400', 09:53:06 INFO - 'USER': 'cltbld', 09:53:06 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:06 INFO - '_': '/tools/buildbot/bin/python'} 09:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:53:06 INFO - Cleaning up... 09:53:06 INFO - Return code: 0 09:53:06 INFO - Running post-action listener: _resource_record_post_action 09:53:06 INFO - Running post-action listener: _start_resource_monitoring 09:53:06 INFO - Starting resource monitoring. 09:53:06 INFO - [mozharness: 2017-09-21 16:53:06.747051Z] Finished create-virtualenv step (success) 09:53:06 INFO - [mozharness: 2017-09-21 16:53:06.747623Z] Running install step. 09:53:06 INFO - Running pre-action listener: _resource_record_pre_action 09:53:06 INFO - Running main action method: install 09:53:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:06 INFO - Reading from file tmpfile_stdout 09:53:06 INFO - Detecting whether we're running mozinstall >=1.0... 09:53:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:53:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:53:07 INFO - Reading from file tmpfile_stdout 09:53:07 INFO - Output received: 09:53:07 INFO - Usage: mozinstall [options] installer 09:53:07 INFO - Options: 09:53:07 INFO - -h, --help show this help message and exit 09:53:07 INFO - -d DEST, --destination=DEST 09:53:07 INFO - Directory to install application into. [default: 09:53:07 INFO - "/builds/slave/test"] 09:53:07 INFO - --app=APP Application being installed. [default: firefox] 09:53:07 INFO - mkdir: /builds/slave/test/build/application 09:53:07 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'] 09:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:53:24 INFO - Reading from file tmpfile_stdout 09:53:24 INFO - Output received: 09:53:24 INFO - /builds/slave/test/build/application/firefox/firefox 09:53:24 INFO - Running post-action listener: _resource_record_post_action 09:53:24 INFO - [mozharness: 2017-09-21 16:53:24.732504Z] Finished install step (success) 09:53:24 INFO - [mozharness: 2017-09-21 16:53:24.732755Z] Running setup-mitmproxy step. 09:53:24 INFO - Running pre-action listener: _resource_record_pre_action 09:53:24 INFO - Running main action method: setup_mitmproxy 09:53:24 INFO - Skipping: mitmproxy is not required 09:53:24 INFO - Running post-action listener: _resource_record_post_action 09:53:24 INFO - [mozharness: 2017-09-21 16:53:24.733884Z] Finished setup-mitmproxy step (success) 09:53:24 INFO - [mozharness: 2017-09-21 16:53:24.734104Z] Running run-tests step. 09:53:24 INFO - Running pre-action listener: _resource_record_pre_action 09:53:24 INFO - Running pre-action listener: _set_gcov_prefix 09:53:24 INFO - Running main action method: run_tests 09:53:24 WARNING - Try message not found. 09:53:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:53:24 INFO - Python 2.7.3 09:53:24 INFO - Return code: 0 09:53:24 INFO - grabbing minidump binary from tooltool 09:53:24 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')]} 09:53:24 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 0x1d6a090>, '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 0x1d6ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d64f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:53:24 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 09:53:24 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 09:53:24 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 09:53:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:53:24 INFO - Return code: 0 09:53:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:53:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:53:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:53:24 INFO - ENV: RUST_BACKTRACE is now full 09:53:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:53:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:53:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build 09:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log 09:53:24 INFO - Using env: {'DISPLAY': ':0', 09:53:24 INFO - 'HOME': '/home/cltbld', 09:53:24 INFO - 'LANG': 'en_US.UTF-8', 09:53:24 INFO - 'LANGUAGE': 'en_US:en', 09:53:24 INFO - 'LOGNAME': 'cltbld', 09:53:24 INFO - 'MAIL': '/var/mail/cltbld', 09:53:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:53:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:53:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:24 INFO - 'MOZ_NO_REMOTE': '1', 09:53:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:53:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:24 INFO - 'NO_EM_RESTART': '1', 09:53:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:24 INFO - 'PWD': '/builds/slave/test', 09:53:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:53:24 INFO - 'RUST_BACKTRACE': 'full', 09:53:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:53:24 INFO - 'SHELL': '/bin/bash', 09:53:24 INFO - 'SHLVL': '1', 09:53:24 INFO - 'TERM': 'linux', 09:53:24 INFO - 'TMOUT': '86400', 09:53:24 INFO - 'USER': 'cltbld', 09:53:24 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:53:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:24 INFO - '_': '/tools/buildbot/bin/python'} 09:53:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] with output_timeout 3600 09:53:25 INFO - mozversion application_buildid: 20170921152748 09:53:25 INFO - mozversion application_changeset: b14c75b83d0226333b1240466ea9f07cfb206ff3 09:53:25 INFO - mozversion application_display_name: Nightly 09:53:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:53:25 INFO - mozversion application_name: Firefox 09:53:25 INFO - mozversion application_remotingname: firefox 09:53:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:53:25 INFO - mozversion application_vendor: Mozilla 09:53:25 INFO - mozversion application_version: 58.0a1 09:53:25 INFO - mozversion platform_buildid: 20170921152748 09:53:25 INFO - mozversion platform_changeset: b14c75b83d0226333b1240466ea9f07cfb206ff3 09:53:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:53:25 INFO - mozversion platform_version: 58.0a1 09:53:25 INFO - using testdate: 1506012805 09:53:25 INFO - actual date: 1506012805 09:53:25 INFO - starting webserver on 'localhost:36913' 09:53:25 INFO - SUITE-START | Running 2 tests 09:53:25 INFO - TEST-START | dromaeo_css 09:53:25 INFO - Initialising browser for dromaeo_css test... 09:53:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36913/getInfo.html -profile /tmp/tmpvtfk5Q/profile 09:53:25 INFO - TEST-INFO | started process 14907 (/builds/slave/test/build/application/firefox/firefox http://localhost:36913/getInfo.html) 09:53:29 INFO - TEST-INFO | 14907: exit 0 09:53:30 INFO - Browser initialized. 09:53:30 INFO - Running cycle 1/1 for dromaeo_css test... 09:53:30 INFO - Using env: {'DISPLAY': ':0', 09:53:30 INFO - 'HOME': '/home/cltbld', 09:53:30 INFO - 'JSGC_DISABLE_POISONING': '1', 09:53:30 INFO - 'LANG': 'en_US.UTF-8', 09:53:30 INFO - 'LANGUAGE': 'en_US:en', 09:53:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:53:30 INFO - 'LOGNAME': 'cltbld', 09:53:30 INFO - 'MAIL': '/var/mail/cltbld', 09:53:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:53:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:53:30 INFO - 'MOZ_CRASHREPORTER': '1', 09:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:53:30 INFO - 'MOZ_NO_REMOTE': '1', 09:53:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:53:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:30 INFO - 'NO_EM_RESTART': '1', 09:53:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:30 INFO - 'PWD': '/builds/slave/test', 09:53:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:53:30 INFO - 'RUST_BACKTRACE': 'full', 09:53:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:53:30 INFO - 'SHELL': '/bin/bash', 09:53:30 INFO - 'SHLVL': '1', 09:53:30 INFO - 'STYLO_FORCE_DISABLED': '1', 09:53:30 INFO - 'TERM': 'linux', 09:53:30 INFO - 'TMOUT': '86400', 09:53:30 INFO - 'USER': 'cltbld', 09:53:30 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 09:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:30 INFO - '_': '/tools/buildbot/bin/python'} 09:53:30 INFO - TEST-INFO | started process 15148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvtfk5Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:30 INFO - PID 15148 | 09:53:30 INFO - PID 15148 | (/builds/slave/test/build/application/firefox/firefox:15198): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:53:30 INFO - PID 15148 | 09:53:41 INFO - PID 15148 | RSS: Main: 148287488 09:53:41 INFO - PID 15148 | 09:56:37 INFO - PID 15148 | Cycle 1(1): loaded http://localhost:36913/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36913/tests/dromaeo/cssquery-ext.html) 09:56:39 INFO - PID 15148 | RSS: Main: 148094976 09:56:39 INFO - PID 15148 | 09:59:40 INFO - PID 15148 | Cycle 1(1): loaded http://localhost:36913/tests/dromaeo/cssquery-ext.html (next: http://localhost:36913/tests/dromaeo/cssquery-jquery.html) 09:59:41 INFO - PID 15148 | RSS: Main: 148246528 09:59:41 INFO - PID 15148 | 10:02:42 INFO - PID 15148 | Cycle 1(1): loaded http://localhost:36913/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36913/tests/dromaeo/cssquery-mootools.html) 10:02:43 INFO - PID 15148 | RSS: Main: 150204416 10:02:43 INFO - PID 15148 | 10:05:45 INFO - PID 15148 | Cycle 1(1): loaded http://localhost:36913/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36913/tests/dromaeo/cssquery-prototype.html) 10:05:45 INFO - PID 15148 | RSS: Main: 149102592 10:05:45 INFO - PID 15148 | 10:08:48 INFO - PID 15148 | Cycle 1(1): loaded http://localhost:36913/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36913/tests/dromaeo/cssquery-yui.html) 10:08:49 INFO - PID 15148 | RSS: Main: 150396928 10:08:49 INFO - PID 15148 | 10:11:50 INFO - PID 15148 | Cycle 1(1): loaded http://localhost:36913/tests/dromaeo/cssquery-yui.html (next: http://localhost:36913/tests/dromaeo/cssquery-dojo.html) 10:11:50 INFO - PID 15148 | RSS: Main: 152952832 10:11:50 INFO - PID 15148 | 10:11:50 INFO - PID 15148 | __start_tp_report 10:11:50 INFO - PID 15148 | _x_x_mozilla_page_load 10:11:50 INFO - PID 15148 | _x_x_mozilla_page_load_details 10:11:50 INFO - PID 15148 | |i|pagename|runs| 10:11:50 INFO - PID 15148 | |0;dojo.html;3787.962120378796;3887.650111489966;3928.135810121774;3957.465742124812;3957.485526881505;6271.247450305958;6281.97430642736;6340.144080549125;6469.385408386208;6491.967540162298;6349.777757778479;6397.3922477364695;6401.359864013596;6443.097966284724;6444.96777516113;5725.14122881569;5729.169270455782;5749.022666146765;5775.509081728037;5798.797042103527;5691.971540142294;5698.715064246791;5746.885062298772;5760.193572899798;5772.797952071678;359.08913045553055;5669.121286200645;5792.391798861122;5848.824535263949;5853.648781073128;5675.148727690838;5677.40387259336;5764.481196692276;5796.971015144897;5822.854428639275;5701.315842098933;5704.258446401983;5778.42215778423;5785.595008349417;5786.4502872227495;6292.968535157341;6391.648459334735;6446.387593178687;6471.288158302583;6494.025896115573;6916.239213686541;6931.653417329113;6934.202566704843;7006.9649651751415;7020.262872398375;6967.965160174217;6972.407345375649;7063.434925205971;7072.151341839012;7083.433325333961;6383.138276332682;6464.870702585969;6517;6517.055027021102;6540;7197.928020719831;7202.891956620655;7225.566466011951;7234.674439650228;7256.383207427322;6855.5886646801355;6929.203141638675;6930.06444130036;6943.409810166192;6949.131358580177;6938.444924406035;6959.860802783916;6971.442284617218;7055.2239253682055;7074.22183559808;6657.733690652417;6761.966190169018;6763.864722705518;6767.695453704595;6768;6810.114685090906;6874.793756187322;6875.5874647521305;6876.931230687629;6879.346462086094;5110.97444512775;5123.308353372322;5132.56373208274;5143.665661731976;5145.639805213674;4584.564466375689;4625.884352891205;4681.8361357353015;4682.086993036325;4690.390249267573;6398.456131228898;6408.070829729663;6411.198600174978;6505.8698826024165;6509.511786616023;6976.790696279119;6995.26549712283;6997.1253593300835;6998.985147153634;7006.334398232159;6368.395002474757;6375.203099612549;6449.774257900951;6465.450436712838;6478.801421736994;5579.414161513064;5609.4390560943575;5654.40628733977;5678.659280443187;5685.744141513642;7080.256573059857;7092.361687448051;7121.786346409616;7156.391706704884;7171.462140339391;6940.618265995418;6968.721251149897;6979.685914133876;7010.123734533184;7029.507934444744;7149.320814522612;7230.313120253568;7303.598302093435;7329;7345.70617175307;6884;6899.551529150589;6953.23514413404;6959.304069593;6969.825754356181;6804.183497980274;6897.310268973263;6914.0666010088;6916.965415172892;6917.031615573925;6592.472602191909;6670.866582668223;6709;6769.3907548320885;6788.185417749902;6743.527953043436;6792.966035169792;6793.694283757144;6809.114815074008;6844;6599.934000660124;6632.668366581555;6648.235452922782;6733.966330168318;6739.966300168468;6670.366315200048;6731;6732.192136943598;6743.123393958754;6798.966005169942;6522.380373864476;6585.176852893388;6598.835029124119;6600.8349791255605;6641.070250164884 10:11:50 INFO - PID 15148 | |1;ext.html;965.9661911833088;978.2418625565188;1016.0702956794534;1017.4099022566912;1022.1975749037005;44305.999999999956;45177.32234016492;45183;45220;45321.773391133;42380.57619423805;44250;45189.32216016763;45225.54774452263;45318.32022519656;8495.405321627488;8624.18070283322;8642.222200002;8653.221210091091;8654.264387527068;8352.791180220482;8422.368322375816;8431.3254939605;8444.02893667227;8488.66045358188;8441.662333506652;8501.95749021254;8541.103184165635;8550.230479256865;8571.571421428935;8424.705135320295;8434.325253979667;8485.618147183392;8492.915070849274;8512.319014478826;8409.789755256106;8435.578221088921;8459.95770021152;8465.788355291106;8472.11042840499;40154.799226003975;40484;40539;41906.37140442896;42076.368854467204;3702.796346200958;3740.6259374062647;3753.5120434343635;3759.8496060157563;3772.735908486407;33111.503327449864;33132.83433582816;33146;33298.16754581155;33304.16739581506;5313.893722125575;5450.945490545084;5523.944760552383;5546.91679624806;5590.18942253371;224413;225702;226102;230070;244554.7772261145;53932.73033634807;54401.1839822403;54465.72767136139;54505.45494545083;54597;56658.150127748115;56811;56909;56919.43080569224;56974.715126424104;15069.698606027818;15101.546953591409;15160.924195378953;15165.24173791306;15396.07623542591;12264.448099835528;12304.323262220663;12319.69200769988;12327.815082773766;12370;14929.40282388714;14979.625509362135;15009.2495375233;15014.849851501564;15096.622584435478;5433.782648694087;5449.972750136303;5459.12653975362;5576.470235327637;5619.43805619443;1081.4862940103387;1087.2280680716622;1097.0894157848966;1107.8172101603145;1108.4568561404853;25358.873205633852;25615.23154305374;25723.356916076875;25815;25874.74125258723;1188.5067696905687;1194.4147367789894;1206.6983254186453;1210.576298295607;1215.6535387414722;13634;13809.861901381058;13812.240326782123;13987.440502379792;14000.929995350161;287220;288530;288723;289058;289199;58719.11921321184;58736.706316468146;58863.70568147217;59125.113123303185;59454.70272648609;1512.19853477657;1545.0961187705093;1552.7127481415748;1558.065160903449;1568.4118455579157;24846.503069938863;24849.254522364357;24850;24882.50234995326;25059.49881002333;40405;40504.59495405008;40509.1898162041;40550.79724601358;40584.79707601443;241204;241272;241521;241995;242250;33334.16664583405;33945.3210935775;37098.258034839695;37376;37405.251894962494;46335;46585.534144659476;46619.766901165276;46872.76563617297;46909;34880.47679284863;35059.12352191216;35103.297934041686;35137.64862351446;35184;34656.480152798234;34708.82645586756;34738.78414255489;35400.291994160485;35471.467927980586;40230.79307620776;40629.18741625092;40852.59147408488;40937.59062409457;40980.79509602433;33330.666693333726;33495.83252083724;33705;34025.65974340225;34154.14614634555;25213.74786252114;25848;26173.967696559277;26333;26390.604140938285 10:11:50 INFO - PID 15148 | |2;jquery.html;591.7661675406777;606;607.8966575682131;622.1849377315717;643.62991280014;23498.177563785248;23692.76307236927;24013.879930600346;24029.158979435724;24159.154429594968;23800.5239895202;24672.50654986899;24989.25032249031;25009.74990250097;25048.74951250487;6509.218893732758;6640.56836305641;6650.800475985704;6660.800175994728;6664.533482656216;6476.060971159179;6564.277929427759;6742.2246441659345;6744.123263975676;6774.220964589086;6685.264620891698;6687.73249070039;6691.498137639672;6796.490263230253;6800.047993280945;6559.278479367265;6723.092382528345;6819.863602727918;6833.453323734139;6847.246802851658;6366.968165159191;6373.6175829450385;6374.298827129012;6383.680815959184;6400.359964003609;29665.406691866043;30177;30188.69811301903;30198.24504387386;30235.395292094036;2187.8359123065834;2213.18112298449;2245.202952951695;2249.876256805875;2257.3115199864037;20029.298974535966;20753.8962305189;20830.37508874736;20911.477213069644;20975.790242097537;2444.804415646744;2451.9509609807706;2500.3499090236487;2512.7110382305905;2515.320863366899;142885.28557357145;148160;156078;159260;169988;15531.922340388226;15959.441419550492;16428.589285267724;16591.751123733153;16608.335666573203;26170.476590468083;26201.344966375993;26231.868840655676;26239.081632143167;26262.474750504884;10690.572377104983;10738.624148154777;10794;10862.131029517619;10908.50911708975;9480.24158067353;9784.217262618973;9789.608415663437;9832.262580306506;9842.360246583949;9586.904130958601;9751.122398984126;9905.554250058765;9999.50002499872;10035.79928401428;3041.9847900760797;3053.4503789318137;3060.7245347918793;3119.906402807919;3205.0705295464522;1058.4866339825178;1062.7236918401118;1073.5383784972537;1082.410086502858;1090.6346373964652;20680;21031.15875364968;21082;21199.470013249793;21293.254736084164;1108.2408550143177;1108.905743011853;1134.5745345495438;1138.7950168969664;1148.0700632487712;11566.710832229262;11640;11700.414979251003;11794.29234245948;11816.291022538675;600035;616662;624899;628016;642669;131225;132238.33880830533;133137.33431332782;133369;135753;1193.9343336116424;1213.6237766292302;1228.6375519221672;1236.202649291202;1238.3127364312713;18230.17964191641;18626.906865465586;18659.16033778456;18899.338523151622;19335.8066419334;62385.376146237955;64356;65557.34442655512;65668.34331656623;66371.66814165899;79282;79799;79877.20122798698;83090.16909830824;83905.5804720997;15183;15365;15505.84494155044;15519.534413967147;15528.301226445063;23150.1897433589;23228.3031509055;23289.883550582137;23302.883485582464;23383.298501044996;18660.626787464447;18717;18843.71734424012;18860.717089243946;19024.809751902303;16942.152892355087;16982.40561580339;17234.655306893543;17287.654246915245;17510.387136450165;19185.61628767389;19230.615387692447;19291.807081929;19559.902200488905;19629;15070.698586027998;15087.321070551632;15089;15162.620934476727;15210.16344101085;18650;18867.245310187438;18907;19037.238510459425;19104.808951910858 10:11:50 INFO - PID 15148 | |3;mootools.html;1441.956741297761;1479.6005078628766;1491.0308299605256;1493.4026389444214;1493.775933609959;16704.498865034053;17384.130793460306;17389.826101738978;17457.738133927985;17461;17214.741778873326;17219.311227550883;17299.654006919853;17339;17375.91312043438;2777.777777777783;2843.8862445502155;2900.7534359579354;2908.8691008904652;2915.79589428739;2885.3075261937192;2907.9854600726967;2915.1837485504093;2928.633920759905;2931.091361677876;2710.1598504463677;2826.8727907244224;2830.702776208489;2935.765138788892;2939.162338733461;2812.831230126199;2865.369618683886;2893.8263704177607;2894.7684185265234;2894.8842046318123;2696.2180967519394;2757.4760795449;2787.9581806272718;2812.789040821926;2821.44981728563;16933.153342332833;17046.40337588191;17067.914660426744;17073.658526829517;17089.145542722814;8389.916100838975;8617.698380556712;8703.129687031309;8704.825903481895;8706.129387061306;5882.735276912549;5940.673262970578;5971.193888824996;6026.728797204137;6040;2147.4953385954286;2152.406350347827;2165.664322030088;2217.2572188316935;2231.877246751428;31827.522587161213;32073.358532829214;32098.197545061328;32153.1961700957;32205.194870128435;7252.818679533054;7259;7383.372413344819;7423.109226892808;7426.034615500059;19691.60616787656;19942.90028549877;20164.69752953707;20208.29270975509;20208.393748187576;8266.710665126691;8562.74311770648;8564.357673174536;8619.353548483889;8655.221030107321;7689.884651730228;7699.730509432143;7806.375489960789;7818.530888146729;7849.843003140019;5725.77096916127;5864.354920958691;5920.79277225295;5937.792177273861;5963.910541341883;1634.5913521619595;1648.1429656578512;1650.966980660404;1673.8828282020386;1680.5058352272408;950.1828427552299;950.9524523773922;955.2071780422233;964.238251781099;964.5081008685621;8403.873941890877;8405.243528082501;8407.53758543286;8431.283340915968;8553.700620478254;789.3685051958411;789.6959670526888;802.6548584108773;805.0862271322092;805.1224165659343;4991.450940396553;5078.314427552307;5972.25346831646;6072.39276072398;6218.502519798405;24564.87717561401;25158.371040724127;25274.620880686805;25618.615720764203;25818.741812581633;25513.10704125384;25824.870875645876;25890.22329330123;25990;26030.6095408569;11412.771744565061;12166.635000949986;12375.938120309342;12417.813732794015;12549;9753.073458021476;9790.706278811647;9803.509824508603;9809.55856986423;9958.302918795904;21243;21249.57500850005;21528.246511372028;21594;22052;22544.5491090174;22578.88710556437;22581.774182258625;22806;22963;12011.279323240284;12494.937525312314;12568.74862502763;12571.622851314476;12593.118481706193;15679.843201567839;15719.921400392925;15722.05667659944;15847.920760396124;15860.206989650702;11787.764244715228;11980.221285616755;12032.458539365574;12039.458224379749;12051.156419050581;12157.817632735692;12254.571090011807;12455.93772031134;12531.561395351118;12732.618021459371;11265.493052812806;11581.536738530702;11584.304941703525;11602.129840261845;11610.6516804496;8073.111957684768;8180.550069746221;8326.25063744266;8328.708495202638;8334.958325208336;8291.212334828182;8297.419180657538;8386.958065209636;8408.369372297224;8432.789180270542 10:11:50 INFO - PID 15148 | |4;prototype.html;537.1325309624955;552.3758153286785;574.692539491372;577.2005772005771;586.3491524407907;6277.937220627792;6298.905516417258;6348.682565871711;6374.043893415978;6418.550701450895;6268;6280.183576135108;6366.9307475070555;6383.669960464432;6405.3914878086625;5005.749712514378;5069.923951140736;5099.821506247282;5175.482451754832;5210.895782084346;5083.466236045218;5095.84712458625;5103.795848166069;5141.537261646451;5148.6395952283165;5017.824376146836;5077.771500282478;5081.161608334621;5093.363329583802;5101.464346243647;5072.39131304244;5094.312267843849;5096.51583099605;5112.769925353368;5129.487051294878;4597.517260687647;4648;4648.3963032753645;4664.13713463007;4669.39297891272;6175.166352542395;6189.26700523854;6246.3441338659695;6269.153664255313;6275.6548389838545;5940.821775346746;5964.940350596482;6016.578839481194;6025.517958563304;6035.275766907956;6681.398674119304;6686.197656281277;6714.563553369016;6753.15585551806;6858.554193977426;5145.176771716508;5163.63854530183;5170.146925757245;5179.7410129493755;5194.558462530689;6868.381845633867;6891.2764159763;7080.256573059857;7118.715251389938;7120.821979450555;5298.258243845865;5328.227407025959;5329.973350133225;5350.5987050971335;5372.543333816591;5320.281761962163;5339;5390.460953904578;5431.293931788842;5448.291722076105;5864.824055278348;5967.433093856077;5978.624916269282;5992;6011.819645410645;6803.2856550062525;6806.353396427331;6843.144606924135;6855.211650660238;6881.483888708367;1856.860735444847;1858.6747319219082;1875.5123667846458;1878.286251224526;1892.5930924850948;1241.8385609870659;1298.9545365912147;1307.2875282970804;1310.0960337367187;1334.1928133479105;6286.685665716695;6290.559660823698;6326.746930122836;6387.648679322682;6408.359164083555;6759.357861003196;6839.829004274933;6941.583504989717;6975.197852246955;6991.056207411934;6181.876362472725;6301.842453938688;6320.304766475684;6354.714037868307;6402.103705481236;3584.5877724062066;3645;3691.8001649463495;3735.9813200934364;3738.140227747633;7194;7239.855202895913;7249.782506524813;7268.200497945222;7277.927220727831;6969.825754356181;7045.542039767502;7064.823379415556;7111.92888071123;7115.964420177866;7036.155661320572;7045.964770176219;7117.6085315308155;7152.570845749167;7199.8560028800175;6897.448204143757;6939.265183704074;6943.409810166192;6996.6151861648095;6999.370056694922;6865.519413641197;6888.380045795902;6890.724371025103;6902.551334163263;6933.237343892269;7044.612546309797;7047.506674532733;7120.715171393086;7203.423726102004;7213.855722885408;5238.568737476959;5260.342457192851;5313.495217954288;5319.840404787863;5359.14253719403;6727.73089076451;6858;6943.930560694531;6969.895451568332;6973;6602.88707613559;6605.603663779996;6668.766593169215;6683.365080317362;6783.066825954579;6532.314107018885;6631.6510162367695;6676.666166691549;6697.464202863662;6701.798946031627;6498.3826536478955;6505.8698826024165;6583.30875258091;6600.80197594073;6605.636689981905;6627.460189770023;6636.601803891782;6696.966515167393;6707.396334329934;6760;6450.258430223907;6465.224173099258;6505.479561634963;6551.80344589644;6587.176602924635 10:11:50 INFO - PID 15148 | |5;yui.html;931.4457897550955;957.1003256938477;960.3421656165532;965.2567523007284;982.4056445850263;18007.36974205903;18356;18372.540686482844;18449.44651660449;18485.722714159263;18824.341148059822;19020.14409351579;19054.61890762184;19070.713939290923;19076;1007.8891321954598;1012.6506355307407;1013.3109485549824;1013.4628646817162;1013.6553571785591;1028.0952761569808;1031.3863251365426;1031.8606988056629;1032.9535170917288;1033.1218464305318;1055.0609957138154;1076.5317087067112;1078.2613909472036;1085.6417382263874;1086.8858769829133;1067.9960836813448;1068.7488440216543;1070.507566519402;1073.1790180511875;1074.033369967032;767.244264399566;786.3591173193829;786.5909726942016;788.4008153803151;789.4473868292162;19995.600087998304;20115.89942050295;20117.79882201174;20123.698144527843;20177.79822201774;22766.65850012251;23156.18953336642;23406.531869362687;23768;23782.405439863967;17849.46451606441;17901.37345192925;18051.27794888203;18272.360467383576;18304.63390732178;3899.2786334528205;3944.842206311744;3948.4274780156716;3966.147278335143;3978.5026871641044;132602;161440;162606.1869690644;164091;166380;18223;18437.7234341485;18628.068596570116;18864.905675471535;18865.622687546173;34489.65510344915;34994.650053499645;35098.47352289718;35176;35570.822145889106;9739.805203895883;9875.111239988291;9880.703578892644;9892.703218903445;9917.256205784595;4474.686771925999;4498.8425405110665;4544.545545445429;4592.03567250881;4639.489656137822;6009.939900601025;6087.939120608826;6109.480694141048;6132.049532322497;6152.815415537541;1139.2480962564832;1173.4484792147678;1176.9293842369314;1185.6265276437894;1193.6180422264965;727.618000549705;745.9664315105833;746.3096635612037;758.5979430901631;763.9961800190963;13915.443382264799;14338.426462941365;14592.197429141286;14971;14988.850111498745;682;689.6655122265697;692.5879101934341;693.1612748574281;701.0325750464326;6137.60105593135;6304.527160462983;6388.680565971683;6392.4886009119155;6414.9679251604375;151375;151908;153220;153674;154238;133694.33152834175;135355.32322338325;135847;138961;141934;113515;114580.56258312264;115821.99999999831;116173;117508.41245793887;11615.361155136548;11780.352080635646;11967.940160299142;12150.696232594255;12358.876411236131;56323;56358.43641563532;58016.12975805282;58055.70972145112;58218.7089064552;71482.28517714757;72765.90851137131;72808;73601.26398735945;74859;17034.14829258557;17064.146792660566;17260.13699315004;17260.654786904444;17401.738973915148;17408.47774566765;17435.30258789634;17852.10739462996;18341.449756507325;18464.81535184631;17467.82532174713;17737.822621773616;17837.37569185124;17862.821371786114;17944.910275449063;18243.179056942736;18245.726314105035;18460.446186614423;18571.44285671432;18585.90707046456;16398.508044758677;16671;16681.332746689997;16685.082320472004;16848.57878553046;14284.214368209849;14360.138391696531;14433;14531.782023269448;14601.342939567532;13903.443862245396;13983.44066237339;14066;14090.084144530572;14111.435542578181 10:11:50 INFO - PID 15148 | __end_tp_report 10:11:50 INFO - PID 15148 | __start_cc_report 10:11:50 INFO - PID 15148 | _x_x_mozilla_cycle_collect,282 10:11:50 INFO - PID 15148 | __end_cc_report 10:11:50 INFO - PID 15148 | __startTimestamp1506013910983__endTimestamp 10:11:50 INFO - PID 15148 | 10:11:50 INFO - PID 15148 | ------- Summary: start ------- 10:11:50 INFO - PID 15148 | Number of tests: 6 10:11:50 INFO - PID 15148 | 10:11:50 INFO - PID 15148 | [#0] dojo.html Cycles:165 Average:6364.31 Median:6641.07 stddev:892.92 (13.4%) stddev-sans-first:872.48 10:11:50 INFO - PID 15148 | Values: 3788.0 3887.7 3928.1 3957.5 3957.5 6271.2 6282.0 6340.1 6469.4 6492.0 6349.8 6397.4 6401.4 6443.1 6445.0 5725.1 5729.2 5749.0 5775.5 5798.8 5692.0 5698.7 5746.9 5760.2 5772.8 359.1 5669.1 5792.4 5848.8 5853.6 5675.1 5677.4 5764.5 5797.0 5822.9 5701.3 5704.3 5778.4 5785.6 5786.5 6293.0 6391.6 6446.4 6471.3 6494.0 6916.2 6931.7 6934.2 7007.0 7020.3 6968.0 6972.4 7063.4 7072.2 7083.4 6383.1 6464.9 6517.0 6517.1 6540.0 7197.9 7202.9 7225.6 7234.7 7256.4 6855.6 6929.2 6930.1 6943.4 6949.1 6938.4 6959.9 6971.4 7055.2 7074.2 6657.7 6762.0 6763.9 6767.7 6768.0 6810.1 6874.8 6875.6 6876.9 6879.3 5111.0 5123.3 5132.6 5143.7 5145.6 4584.6 4625.9 4681.8 4682.1 4690.4 6398.5 6408.1 6411.2 6505.9 6509.5 6976.8 6995.3 6997.1 6999.0 7006.3 6368.4 6375.2 6449.8 6465.5 6478.8 5579.4 5609.4 5654.4 5678.7 5685.7 7080.3 7092.4 7121.8 7156.4 7171.5 6940.6 6968.7 6979.7 7010.1 7029.5 7149.3 7230.3 7303.6 7329.0 7345.7 6884.0 6899.6 6953.2 6959.3 6969.8 6804.2 6897.3 6914.1 6917.0 6917.0 6592.5 6670.9 6709.0 6769.4 6788.2 6743.5 6793.0 6793.7 6809.1 6844.0 6599.9 6632.7 6648.2 6734.0 6740.0 6670.4 6731.0 6732.2 6743.1 6799.0 6522.4 6585.2 6598.8 6600.8 6641.1 10:11:50 INFO - PID 15148 | 10:11:50 INFO - PID 15148 | [#1] ext.html Cycles:180 Average:43245.86 Median:20400.53 stddev:66302.04 (325.0%) stddev-sans-first:66412.04 10:11:50 INFO - PID 15148 | Values: 966.0 978.2 1016.1 1017.4 1022.2 44306.0 45177.3 45183.0 45220.0 45321.8 42380.6 44250.0 45189.3 45225.5 45318.3 8495.4 8624.2 8642.2 8653.2 8654.3 8352.8 8422.4 8431.3 8444.0 8488.7 8441.7 8502.0 8541.1 8550.2 8571.6 8424.7 8434.3 8485.6 8492.9 8512.3 8409.8 8435.6 8460.0 8465.8 8472.1 40154.8 40484.0 40539.0 41906.4 42076.4 3702.8 3740.6 3753.5 3759.8 3772.7 33111.5 33132.8 33146.0 33298.2 33304.2 5313.9 5450.9 5523.9 5546.9 5590.2 224413.0 225702.0 226102.0 230070.0 244554.8 53932.7 54401.2 54465.7 54505.5 54597.0 56658.2 56811.0 56909.0 56919.4 56974.7 15069.7 15101.5 15160.9 15165.2 15396.1 12264.4 12304.3 12319.7 12327.8 12370.0 14929.4 14979.6 15009.2 15014.8 15096.6 5433.8 5450.0 5459.1 5576.5 5619.4 1081.5 1087.2 1097.1 1107.8 1108.5 25358.9 25615.2 25723.4 25815.0 25874.7 1188.5 1194.4 1206.7 1210.6 1215.7 13634.0 13809.9 13812.2 13987.4 14000.9 287220.0 288530.0 288723.0 289058.0 289199.0 58719.1 58736.7 58863.7 59125.1 59454.7 1512.2 1545.1 1552.7 1558.1 1568.4 24846.5 24849.3 24850.0 24882.5 25059.5 40405.0 40504.6 40509.2 40550.8 40584.8 241204.0 241272.0 241521.0 241995.0 242250.0 33334.2 33945.3 37098.3 37376.0 37405.3 46335.0 46585.5 46619.8 46872.8 46909.0 34880.5 35059.1 35103.3 35137.6 35184.0 34656.5 34708.8 34738.8 35400.3 35471.5 40230.8 40629.2 40852.6 40937.6 40980.8 33330.7 33495.8 33705.0 34025.7 34154.1 25213.7 25848.0 26174.0 26333.0 26390.6 10:11:50 INFO - PID 15148 | 10:11:50 INFO - PID 15148 | [#2] jquery.html Cycles:180 Average:40383.06 Median:12703.75 stddev:104241.79 (820.6%) stddev-sans-first:104491.40 10:11:50 INFO - PID 15148 | Values: 591.8 606.0 607.9 622.2 643.6 23498.2 23692.8 24013.9 24029.2 24159.2 23800.5 24672.5 24989.3 25009.7 25048.7 6509.2 6640.6 6650.8 6660.8 6664.5 6476.1 6564.3 6742.2 6744.1 6774.2 6685.3 6687.7 6691.5 6796.5 6800.0 6559.3 6723.1 6819.9 6833.5 6847.2 6367.0 6373.6 6374.3 6383.7 6400.4 29665.4 30177.0 30188.7 30198.2 30235.4 2187.8 2213.2 2245.2 2249.9 2257.3 20029.3 20753.9 20830.4 20911.5 20975.8 2444.8 2452.0 2500.3 2512.7 2515.3 142885.3 148160.0 156078.0 159260.0 169988.0 15531.9 15959.4 16428.6 16591.8 16608.3 26170.5 26201.3 26231.9 26239.1 26262.5 10690.6 10738.6 10794.0 10862.1 10908.5 9480.2 9784.2 9789.6 9832.3 9842.4 9586.9 9751.1 9905.6 9999.5 10035.8 3042.0 3053.5 3060.7 3119.9 3205.1 1058.5 1062.7 1073.5 1082.4 1090.6 20680.0 21031.2 21082.0 21199.5 21293.3 1108.2 1108.9 1134.6 1138.8 1148.1 11566.7 11640.0 11700.4 11794.3 11816.3 600035.0 616662.0 624899.0 628016.0 642669.0 131225.0 132238.3 133137.3 133369.0 135753.0 1193.9 1213.6 1228.6 1236.2 1238.3 18230.2 18626.9 18659.2 18899.3 19335.8 62385.4 64356.0 65557.3 65668.3 66371.7 79282.0 79799.0 79877.2 83090.2 83905.6 15183.0 15365.0 15505.8 15519.5 15528.3 23150.2 23228.3 23289.9 23302.9 23383.3 18660.6 18717.0 18843.7 18860.7 19024.8 16942.2 16982.4 17234.7 17287.7 17510.4 19185.6 19230.6 19291.8 19559.9 19629.0 15070.7 15087.3 15089.0 15162.6 15210.2 18650.0 18867.2 18907.0 19037.2 19104.8 10:11:50 INFO - PID 15148 | 10:11:50 INFO - PID 15148 | [#3] mootools.html Cycles:180 Average:10595.95 Median:2812.81 stddev:7960.20 (283.0%) stddev-sans-first:7952.83 10:11:50 INFO - PID 15148 | Values: 1442.0 1479.6 1491.0 1493.4 1493.8 16704.5 17384.1 17389.8 17457.7 17461.0 17214.7 17219.3 17299.7 17339.0 17375.9 2777.8 2843.9 2900.8 2908.9 2915.8 2885.3 2908.0 2915.2 2928.6 2931.1 2710.2 2826.9 2830.7 2935.8 2939.2 2812.8 2865.4 2893.8 2894.8 2894.9 2696.2 2757.5 2788.0 2812.8 2821.4 16933.2 17046.4 17067.9 17073.7 17089.1 8389.9 8617.7 8703.1 8704.8 8706.1 5882.7 5940.7 5971.2 6026.7 6040.0 2147.5 2152.4 2165.7 2217.3 2231.9 31827.5 32073.4 32098.2 32153.2 32205.2 7252.8 7259.0 7383.4 7423.1 7426.0 19691.6 19942.9 20164.7 20208.3 20208.4 8266.7 8562.7 8564.4 8619.4 8655.2 7689.9 7699.7 7806.4 7818.5 7849.8 5725.8 5864.4 5920.8 5937.8 5963.9 1634.6 1648.1 1651.0 1673.9 1680.5 950.2 951.0 955.2 964.2 964.5 8403.9 8405.2 8407.5 8431.3 8553.7 789.4 789.7 802.7 805.1 805.1 4991.5 5078.3 5972.3 6072.4 6218.5 24564.9 25158.4 25274.6 25618.6 25818.7 25513.1 25824.9 25890.2 25990.0 26030.6 11412.8 12166.6 12375.9 12417.8 12549.0 9753.1 9790.7 9803.5 9809.6 9958.3 21243.0 21249.6 21528.2 21594.0 22052.0 22544.5 22578.9 22581.8 22806.0 22963.0 12011.3 12494.9 12568.7 12571.6 12593.1 15679.8 15719.9 15722.1 15847.9 15860.2 11787.8 11980.2 12032.5 12039.5 12051.2 12157.8 12254.6 12455.9 12531.6 12732.6 11265.5 11581.5 11584.3 11602.1 11610.7 8073.1 8180.6 8326.3 8328.7 8335.0 8291.2 8297.4 8387.0 8408.4 8432.8 10:11:50 INFO - PID 15148 | 10:11:51 INFO - PID 15148 | [#4] prototype.html Cycles:180 Average:5751.07 Median:6351.70 stddev:1604.85 (25.3%) stddev-sans-first:1560.91 10:11:51 INFO - PID 15148 | Values: 537.1 552.4 574.7 577.2 586.3 6277.9 6298.9 6348.7 6374.0 6418.6 6268.0 6280.2 6366.9 6383.7 6405.4 5005.7 5069.9 5099.8 5175.5 5210.9 5083.5 5095.8 5103.8 5141.5 5148.6 5017.8 5077.8 5081.2 5093.4 5101.5 5072.4 5094.3 5096.5 5112.8 5129.5 4597.5 4648.0 4648.4 4664.1 4669.4 6175.2 6189.3 6246.3 6269.2 6275.7 5940.8 5964.9 6016.6 6025.5 6035.3 6681.4 6686.2 6714.6 6753.2 6858.6 5145.2 5163.6 5170.1 5179.7 5194.6 6868.4 6891.3 7080.3 7118.7 7120.8 5298.3 5328.2 5330.0 5350.6 5372.5 5320.3 5339.0 5390.5 5431.3 5448.3 5864.8 5967.4 5978.6 5992.0 6011.8 6803.3 6806.4 6843.1 6855.2 6881.5 1856.9 1858.7 1875.5 1878.3 1892.6 1241.8 1299.0 1307.3 1310.1 1334.2 6286.7 6290.6 6326.7 6387.6 6408.4 6759.4 6839.8 6941.6 6975.2 6991.1 6181.9 6301.8 6320.3 6354.7 6402.1 3584.6 3645.0 3691.8 3736.0 3738.1 7194.0 7239.9 7249.8 7268.2 7277.9 6969.8 7045.5 7064.8 7111.9 7116.0 7036.2 7046.0 7117.6 7152.6 7199.9 6897.4 6939.3 6943.4 6996.6 6999.4 6865.5 6888.4 6890.7 6902.6 6933.2 7044.6 7047.5 7120.7 7203.4 7213.9 5238.6 5260.3 5313.5 5319.8 5359.1 6727.7 6858.0 6943.9 6969.9 6973.0 6602.9 6605.6 6668.8 6683.4 6783.1 6532.3 6631.7 6676.7 6697.5 6701.8 6498.4 6505.9 6583.3 6600.8 6605.6 6627.5 6636.6 6697.0 6707.4 6760.0 6450.3 6465.2 6505.5 6551.8 6587.2 10:11:51 INFO - PID 15148 | 10:11:51 INFO - PID 15148 | [#5] yui.html Cycles:180 Average:28565.22 Median:18288.50 stddev:42880.52 (234.5%) stddev-sans-first:42950.61 10:11:51 INFO - PID 15148 | Values: 931.4 957.1 960.3 965.3 982.4 18007.4 18356.0 18372.5 18449.4 18485.7 18824.3 19020.1 19054.6 19070.7 19076.0 1007.9 1012.7 1013.3 1013.5 1013.7 1028.1 1031.4 1031.9 1033.0 1033.1 1055.1 1076.5 1078.3 1085.6 1086.9 1068.0 1068.7 1070.5 1073.2 1074.0 767.2 786.4 786.6 788.4 789.4 19995.6 20115.9 20117.8 20123.7 20177.8 22766.7 23156.2 23406.5 23768.0 23782.4 17849.5 17901.4 18051.3 18272.4 18304.6 3899.3 3944.8 3948.4 3966.1 3978.5 132602.0 161440.0 162606.2 164091.0 166380.0 18223.0 18437.7 18628.1 18864.9 18865.6 34489.7 34994.7 35098.5 35176.0 35570.8 9739.8 9875.1 9880.7 9892.7 9917.3 4474.7 4498.8 4544.5 4592.0 4639.5 6009.9 6087.9 6109.5 6132.0 6152.8 1139.2 1173.4 1176.9 1185.6 1193.6 727.6 746.0 746.3 758.6 764.0 13915.4 14338.4 14592.2 14971.0 14988.9 682.0 689.7 692.6 693.2 701.0 6137.6 6304.5 6388.7 6392.5 6415.0 151375.0 151908.0 153220.0 153674.0 154238.0 133694.3 135355.3 135847.0 138961.0 141934.0 113515.0 114580.6 115822.0 116173.0 117508.4 11615.4 11780.4 11967.9 12150.7 12358.9 56323.0 56358.4 58016.1 58055.7 58218.7 71482.3 72765.9 72808.0 73601.3 74859.0 17034.1 17064.1 17260.1 17260.7 17401.7 17408.5 17435.3 17852.1 18341.4 18464.8 17467.8 17737.8 17837.4 17862.8 17944.9 18243.2 18245.7 18460.4 18571.4 18585.9 16398.5 16671.0 16681.3 16685.1 16848.6 14284.2 14360.1 14433.0 14531.8 14601.3 13903.4 13983.4 14066.0 14090.1 14111.4 10:11:51 INFO - PID 15148 | -------- Summary: end -------- 10:11:51 INFO - PID 15148 | 10:11:51 INFO - TEST-INFO | 15148: exit 0 10:11:51 INFO - TEST-OK | dromaeo_css | took 1106583ms 10:11:51 INFO - TEST-START | kraken 10:11:51 INFO - Initialising browser for kraken test... 10:11:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36913/getInfo.html -profile /tmp/tmpqvxZf1/profile 10:11:51 INFO - TEST-INFO | started process 15517 (/builds/slave/test/build/application/firefox/firefox http://localhost:36913/getInfo.html) 10:11:56 INFO - TEST-INFO | 15517: exit 0 10:11:56 INFO - Browser initialized. 10:11:56 INFO - Running cycle 1/1 for kraken test... 10:11:56 INFO - Using env: {'DISPLAY': ':0', 10:11:56 INFO - 'HOME': '/home/cltbld', 10:11:56 INFO - 'JSGC_DISABLE_POISONING': '1', 10:11:56 INFO - 'LANG': 'en_US.UTF-8', 10:11:56 INFO - 'LANGUAGE': 'en_US:en', 10:11:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 10:11:56 INFO - 'LOGNAME': 'cltbld', 10:11:56 INFO - 'MAIL': '/var/mail/cltbld', 10:11:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:11:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:11:56 INFO - 'MOZ_CRASHREPORTER': '1', 10:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 10:11:56 INFO - 'MOZ_NO_REMOTE': '1', 10:11:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:11:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:56 INFO - 'NO_EM_RESTART': '1', 10:11:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:56 INFO - 'PWD': '/builds/slave/test', 10:11:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:11:56 INFO - 'RUST_BACKTRACE': 'full', 10:11:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:11:56 INFO - 'SHELL': '/bin/bash', 10:11:56 INFO - 'SHLVL': '1', 10:11:56 INFO - 'STYLO_FORCE_DISABLED': '1', 10:11:56 INFO - 'TERM': 'linux', 10:11:56 INFO - 'TMOUT': '86400', 10:11:56 INFO - 'USER': 'cltbld', 10:11:56 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054', 10:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:56 INFO - '_': '/tools/buildbot/bin/python'} 10:11:56 INFO - TEST-INFO | started process 15756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqvxZf1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:11:57 INFO - PID 15756 | 10:11:57 INFO - PID 15756 | (/builds/slave/test/build/application/firefox/firefox:15806): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 10:11:57 INFO - PID 15756 | 10:12:07 INFO - PID 15756 | RSS: Main: 148344832 10:12:07 INFO - PID 15756 | 10:12:10 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36913/tests/kraken/driver.html?testName=audio-beat-detection) 10:12:11 INFO - PID 15756 | RSS: Main: 153432064 10:12:11 INFO - PID 15756 | 10:12:13 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36913/tests/kraken/driver.html?testName=audio-dft) 10:12:14 INFO - PID 15756 | RSS: Main: 153632768 10:12:14 INFO - PID 15756 | 10:12:17 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36913/tests/kraken/driver.html?testName=audio-fft) 10:12:17 INFO - PID 15756 | RSS: Main: 149987328 10:12:17 INFO - PID 15756 | 10:12:19 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36913/tests/kraken/driver.html?testName=audio-oscillator) 10:12:20 INFO - PID 15756 | RSS: Main: 151199744 10:12:20 INFO - PID 15756 | 10:12:22 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36913/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:12:23 INFO - PID 15756 | RSS: Main: 150945792 10:12:23 INFO - PID 15756 | 10:12:26 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36913/tests/kraken/driver.html?testName=imaging-darkroom) 10:12:26 INFO - PID 15756 | RSS: Main: 150675456 10:12:26 INFO - PID 15756 | 10:12:30 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36913/tests/kraken/driver.html?testName=imaging-desaturate) 10:12:31 INFO - PID 15756 | RSS: Main: 152334336 10:12:31 INFO - PID 15756 | 10:12:33 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36913/tests/kraken/driver.html?testName=json-parse-financial) 10:12:34 INFO - PID 15756 | RSS: Main: 154267648 10:12:34 INFO - PID 15756 | 10:12:36 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36913/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:12:36 INFO - PID 15756 | RSS: Main: 153440256 10:12:36 INFO - PID 15756 | 10:12:38 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36913/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:12:38 INFO - PID 15756 | RSS: Main: 154349568 10:12:38 INFO - PID 15756 | 10:12:40 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36913/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:12:41 INFO - PID 15756 | RSS: Main: 150728704 10:12:41 INFO - PID 15756 | 10:12:43 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36913/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:12:44 INFO - PID 15756 | RSS: Main: 151121920 10:12:44 INFO - PID 15756 | 10:12:47 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36913/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:12:47 INFO - PID 15756 | RSS: Main: 150896640 10:12:47 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | Cycle 1(1): loaded http://localhost:36913/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36913/tests/kraken/driver.html?testName=ai-astar) 10:12:49 INFO - PID 15756 | RSS: Main: 153182208 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | __start_tp_report 10:12:49 INFO - PID 15756 | _x_x_mozilla_page_load 10:12:49 INFO - PID 15756 | _x_x_mozilla_page_load_details 10:12:49 INFO - PID 15756 | |i|pagename|runs| 10:12:49 INFO - PID 15756 | |0;ai-astar;107;106;104;105;106;104;92;106;106;105 10:12:49 INFO - PID 15756 | |1;audio-beat-detection;113;118;112;118;117;112;113;114;116;114 10:12:49 INFO - PID 15756 | |2;audio-dft;160;163;161;160;162;162;162;162;162;165 10:12:49 INFO - PID 15756 | |3;audio-fft;73;73;72;72;75;75;77;76;72;72 10:12:49 INFO - PID 15756 | |4;audio-oscillator;78;78;79;77;78;78;77;77;77;77 10:12:49 INFO - PID 15756 | |5;imaging-gaussian-blur;110;116;110;113;113;116;114;112;117;114 10:12:49 INFO - PID 15756 | |6;imaging-darkroom;150;149;149;150;149;151;152;151;152;153 10:12:49 INFO - PID 15756 | |7;imaging-desaturate;95;94;93;94;94;93;94;94;94;95 10:12:49 INFO - PID 15756 | |8;json-parse-financial;46;47;47;45;47;45;47;46;46;49 10:12:49 INFO - PID 15756 | |9;json-stringify-tinderbox;37;37;37;36;37;37;37;37;37;37 10:12:49 INFO - PID 15756 | |10;stanford-crypto-aes;72;74;69;71;71;71;71;71;71;76 10:12:49 INFO - PID 15756 | |11;stanford-crypto-ccm;105;102;112;112;103;111;108;107;105;103 10:12:49 INFO - PID 15756 | |12;stanford-crypto-pbkdf2;151;149;160;157;149;149;157;150;150;155 10:12:49 INFO - PID 15756 | |13;stanford-crypto-sha256-iterative;60;59;60;61;59;58;59;58;58;58 10:12:49 INFO - PID 15756 | __end_tp_report 10:12:49 INFO - PID 15756 | __start_cc_report 10:12:49 INFO - PID 15756 | _x_x_mozilla_cycle_collect,732 10:12:49 INFO - PID 15756 | __end_cc_report 10:12:49 INFO - PID 15756 | __startTimestamp1506013969708__endTimestamp 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | ------- Summary: start ------- 10:12:49 INFO - PID 15756 | Number of tests: 14 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#0] ai-astar Cycles:10 Average:104.10 Median:106.00 stddev:4.36 (4.1%) stddev-sans-first:4.49 10:12:49 INFO - PID 15756 | Values: 107.0 106.0 104.0 105.0 106.0 104.0 92.0 106.0 106.0 105.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#1] audio-beat-detection Cycles:10 Average:114.70 Median:115.00 stddev:2.36 (2.1%) stddev-sans-first:2.42 10:12:49 INFO - PID 15756 | Values: 113.0 118.0 112.0 118.0 117.0 112.0 113.0 114.0 116.0 114.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.45 (0.9%) stddev-sans-first:1.36 10:12:49 INFO - PID 15756 | Values: 160.0 163.0 161.0 160.0 162.0 162.0 162.0 162.0 162.0 165.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#3] audio-fft Cycles:10 Average:73.70 Median:74.00 stddev:1.89 (2.6%) stddev-sans-first:1.99 10:12:49 INFO - PID 15756 | Values: 73.0 73.0 72.0 72.0 75.0 75.0 77.0 76.0 72.0 72.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.00 stddev:0.70 (0.9%) stddev-sans-first:0.73 10:12:49 INFO - PID 15756 | Values: 78.0 78.0 79.0 77.0 78.0 78.0 77.0 77.0 77.0 77.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:2.42 (2.1%) stddev-sans-first:2.20 10:12:49 INFO - PID 15756 | Values: 110.0 116.0 110.0 113.0 113.0 116.0 114.0 112.0 117.0 114.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:151.00 stddev:1.43 (0.9%) stddev-sans-first:1.50 10:12:49 INFO - PID 15756 | Values: 150.0 149.0 149.0 150.0 149.0 151.0 152.0 151.0 152.0 153.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 10:12:49 INFO - PID 15756 | Values: 95.0 94.0 93.0 94.0 94.0 93.0 94.0 94.0 94.0 95.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#8] json-parse-financial Cycles:10 Average:46.50 Median:47.00 stddev:1.18 (2.5%) stddev-sans-first:1.24 10:12:49 INFO - PID 15756 | Values: 46.0 47.0 47.0 45.0 47.0 45.0 47.0 46.0 46.0 49.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#9] json-stringify-tinderbox Cycles:10 Average:36.90 Median:37.00 stddev:0.32 (0.9%) stddev-sans-first:0.33 10:12:49 INFO - PID 15756 | Values: 37.0 37.0 37.0 36.0 37.0 37.0 37.0 37.0 37.0 37.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#10] stanford-crypto-aes Cycles:10 Average:71.70 Median:71.00 stddev:1.95 (2.7%) stddev-sans-first:2.06 10:12:49 INFO - PID 15756 | Values: 72.0 74.0 69.0 71.0 71.0 71.0 71.0 71.0 71.0 76.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#11] stanford-crypto-ccm Cycles:10 Average:106.80 Median:107.50 stddev:3.82 (3.6%) stddev-sans-first:4.00 10:12:49 INFO - PID 15756 | Values: 105.0 102.0 112.0 112.0 103.0 111.0 108.0 107.0 105.0 103.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:153.00 stddev:4.14 (2.7%) stddev-sans-first:4.34 10:12:49 INFO - PID 15756 | Values: 151.0 149.0 160.0 157.0 149.0 149.0 157.0 150.0 150.0 155.0 10:12:49 INFO - PID 15756 | 10:12:49 INFO - PID 15756 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.00 Median:59.00 stddev:1.05 (1.8%) stddev-sans-first:1.05 10:12:49 INFO - PID 15756 | Values: 60.0 59.0 60.0 61.0 59.0 58.0 59.0 58.0 58.0 58.0 10:12:49 INFO - PID 15756 | -------- Summary: end -------- 10:12:49 INFO - PID 15756 | 10:12:50 INFO - PID 15756 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:12:50 INFO - TEST-INFO | 15756: exit 0 10:12:50 INFO - TEST-OK | kraken | took 58679ms 10:12:50 INFO - SUITE-END | took 1165s 10:12:50 INFO - Completed test suite (00:19:25) 10:12:50 INFO - javascript benchmark 10:12:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9131.013749721335, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3787.962120378796, 3887.650111489966, 3928.135810121774, 3957.465742124812, 3957.485526881505, 6271.247450305958, 6281.97430642736, 6340.144080549125, 6469.385408386208, 6491.967540162298, 6349.777757778479, 6397.3922477364695, 6401.359864013596, 6443.097966284724, 6444.96777516113, 5725.14122881569, 5729.169270455782, 5749.022666146765, 5775.509081728037, 5798.797042103527, 5691.971540142294, 5698.715064246791, 5746.885062298772, 5760.193572899798, 5772.797952071678, 359.08913045553055, 5669.121286200645, 5792.391798861122, 5848.824535263949, 5853.648781073128, 5675.148727690838, 5677.40387259336, 5764.481196692276, 5796.971015144897, 5822.854428639275, 5701.315842098933, 5704.258446401983, 5778.42215778423, 5785.595008349417, 5786.4502872227495, 6292.968535157341, 6391.648459334735, 6446.387593178687, 6471.288158302583, 6494.025896115573, 6916.239213686541, 6931.653417329113, 6934.202566704843, 7006.9649651751415, 7020.262872398375, 6967.965160174217, 6972.407345375649, 7063.434925205971, 7072.151341839012, 7083.433325333961, 6383.138276332682, 6464.870702585969, 6517.0, 6517.055027021102, 6540.0, 7197.928020719831, 7202.891956620655, 7225.566466011951, 7234.674439650228, 7256.383207427322, 6855.5886646801355, 6929.203141638675, 6930.06444130036, 6943.409810166192, 6949.131358580177, 6938.444924406035, 6959.860802783916, 6971.442284617218, 7055.2239253682055, 7074.22183559808, 6657.733690652417, 6761.966190169018, 6763.864722705518, 6767.695453704595, 6768.0, 6810.114685090906, 6874.793756187322, 6875.5874647521305, 6876.931230687629, 6879.346462086094, 5110.97444512775, 5123.308353372322, 5132.56373208274, 5143.665661731976, 5145.639805213674, 4584.564466375689, 4625.884352891205, 4681.8361357353015, 4682.086993036325, 4690.390249267573, 6398.456131228898, 6408.070829729663, 6411.198600174978, 6505.8698826024165, 6509.511786616023, 6976.790696279119, 6995.26549712283, 6997.1253593300835, 6998.985147153634, 7006.334398232159, 6368.395002474757, 6375.203099612549, 6449.774257900951, 6465.450436712838, 6478.801421736994, 5579.414161513064, 5609.4390560943575, 5654.40628733977, 5678.659280443187, 5685.744141513642, 7080.256573059857, 7092.361687448051, 7121.786346409616, 7156.391706704884, 7171.462140339391, 6940.618265995418, 6968.721251149897, 6979.685914133876, 7010.123734533184, 7029.507934444744, 7149.320814522612, 7230.313120253568, 7303.598302093435, 7329.0, 7345.70617175307, 6884.0, 6899.551529150589, 6953.23514413404, 6959.304069593, 6969.825754356181, 6804.183497980274, 6897.310268973263, 6914.0666010088, 6916.965415172892, 6917.031615573925, 6592.472602191909, 6670.866582668223, 6709.0, 6769.3907548320885, 6788.185417749902, 6743.527953043436, 6792.966035169792, 6793.694283757144, 6809.114815074008, 6844.0, 6599.934000660124, 6632.668366581555, 6648.235452922782, 6733.966330168318, 6739.966300168468, 6670.366315200048, 6731.0, 6732.192136943598, 6743.123393958754, 6798.966005169942, 6522.380373864476, 6585.176852893388, 6598.835029124119, 6600.8349791255605, 6641.070250164884], "value": 6305.489016165703, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [965.9661911833088, 978.2418625565188, 1016.0702956794534, 1017.4099022566912, 1022.1975749037005, 44305.999999999956, 45177.32234016492, 45183.0, 45220.0, 45321.773391133, 42380.57619423805, 44250.0, 45189.32216016763, 45225.54774452263, 45318.32022519656, 8495.405321627488, 8624.18070283322, 8642.222200002, 8653.221210091091, 8654.264387527068, 8352.791180220482, 8422.368322375816, 8431.3254939605, 8444.02893667227, 8488.66045358188, 8441.662333506652, 8501.95749021254, 8541.103184165635, 8550.230479256865, 8571.571421428935, 8424.705135320295, 8434.325253979667, 8485.618147183392, 8492.915070849274, 8512.319014478826, 8409.789755256106, 8435.578221088921, 8459.95770021152, 8465.788355291106, 8472.11042840499, 40154.799226003975, 40484.0, 40539.0, 41906.37140442896, 42076.368854467204, 3702.796346200958, 3740.6259374062647, 3753.5120434343635, 3759.8496060157563, 3772.735908486407, 33111.503327449864, 33132.83433582816, 33146.0, 33298.16754581155, 33304.16739581506, 5313.893722125575, 5450.945490545084, 5523.944760552383, 5546.91679624806, 5590.18942253371, 224413.0, 225702.0, 226102.0, 230070.0, 244554.7772261145, 53932.73033634807, 54401.1839822403, 54465.72767136139, 54505.45494545083, 54597.0, 56658.150127748115, 56811.0, 56909.0, 56919.43080569224, 56974.715126424104, 15069.698606027818, 15101.546953591409, 15160.924195378953, 15165.24173791306, 15396.07623542591, 12264.448099835528, 12304.323262220663, 12319.69200769988, 12327.815082773766, 12370.0, 14929.40282388714, 14979.625509362135, 15009.2495375233, 15014.849851501564, 15096.622584435478, 5433.782648694087, 5449.972750136303, 5459.12653975362, 5576.470235327637, 5619.43805619443, 1081.4862940103387, 1087.2280680716622, 1097.0894157848966, 1107.8172101603145, 1108.4568561404853, 25358.873205633852, 25615.23154305374, 25723.356916076875, 25815.0, 25874.74125258723, 1188.5067696905687, 1194.4147367789894, 1206.6983254186453, 1210.576298295607, 1215.6535387414722, 13634.0, 13809.861901381058, 13812.240326782123, 13987.440502379792, 14000.929995350161, 287220.0, 288530.0, 288723.0, 289058.0, 289199.0, 58719.11921321184, 58736.706316468146, 58863.70568147217, 59125.113123303185, 59454.70272648609, 1512.19853477657, 1545.0961187705093, 1552.7127481415748, 1558.065160903449, 1568.4118455579157, 24846.503069938863, 24849.254522364357, 24850.0, 24882.50234995326, 25059.49881002333, 40405.0, 40504.59495405008, 40509.1898162041, 40550.79724601358, 40584.79707601443, 241204.0, 241272.0, 241521.0, 241995.0, 242250.0, 33334.16664583405, 33945.3210935775, 37098.258034839695, 37376.0, 37405.251894962494, 46335.0, 46585.534144659476, 46619.766901165276, 46872.76563617297, 46909.0, 34880.47679284863, 35059.12352191216, 35103.297934041686, 35137.64862351446, 35184.0, 34656.480152798234, 34708.82645586756, 34738.78414255489, 35400.291994160485, 35471.467927980586, 40230.79307620776, 40629.18741625092, 40852.59147408488, 40937.59062409457, 40980.79509602433, 33330.666693333726, 33495.83252083724, 33705.0, 34025.65974340225, 34154.14614634555, 25213.74786252114, 25848.0, 26173.967696559277, 26333.0, 26390.604140938285], "value": 18694.02545832394, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.7661675406777, 606.0, 607.8966575682131, 622.1849377315717, 643.62991280014, 23498.177563785248, 23692.76307236927, 24013.879930600346, 24029.158979435724, 24159.154429594968, 23800.5239895202, 24672.50654986899, 24989.25032249031, 25009.74990250097, 25048.74951250487, 6509.218893732758, 6640.56836305641, 6650.800475985704, 6660.800175994728, 6664.533482656216, 6476.060971159179, 6564.277929427759, 6742.2246441659345, 6744.123263975676, 6774.220964589086, 6685.264620891698, 6687.73249070039, 6691.498137639672, 6796.490263230253, 6800.047993280945, 6559.278479367265, 6723.092382528345, 6819.863602727918, 6833.453323734139, 6847.246802851658, 6366.968165159191, 6373.6175829450385, 6374.298827129012, 6383.680815959184, 6400.359964003609, 29665.406691866043, 30177.0, 30188.69811301903, 30198.24504387386, 30235.395292094036, 2187.8359123065834, 2213.18112298449, 2245.202952951695, 2249.876256805875, 2257.3115199864037, 20029.298974535966, 20753.8962305189, 20830.37508874736, 20911.477213069644, 20975.790242097537, 2444.804415646744, 2451.9509609807706, 2500.3499090236487, 2512.7110382305905, 2515.320863366899, 142885.28557357145, 148160.0, 156078.0, 159260.0, 169988.0, 15531.922340388226, 15959.441419550492, 16428.589285267724, 16591.751123733153, 16608.335666573203, 26170.476590468083, 26201.344966375993, 26231.868840655676, 26239.081632143167, 26262.474750504884, 10690.572377104983, 10738.624148154777, 10794.0, 10862.131029517619, 10908.50911708975, 9480.24158067353, 9784.217262618973, 9789.608415663437, 9832.262580306506, 9842.360246583949, 9586.904130958601, 9751.122398984126, 9905.554250058765, 9999.50002499872, 10035.79928401428, 3041.9847900760797, 3053.4503789318137, 3060.7245347918793, 3119.906402807919, 3205.0705295464522, 1058.4866339825178, 1062.7236918401118, 1073.5383784972537, 1082.410086502858, 1090.6346373964652, 20680.0, 21031.15875364968, 21082.0, 21199.470013249793, 21293.254736084164, 1108.2408550143177, 1108.905743011853, 1134.5745345495438, 1138.7950168969664, 1148.0700632487712, 11566.710832229262, 11640.0, 11700.414979251003, 11794.29234245948, 11816.291022538675, 600035.0, 616662.0, 624899.0, 628016.0, 642669.0, 131225.0, 132238.33880830533, 133137.33431332782, 133369.0, 135753.0, 1193.9343336116424, 1213.6237766292302, 1228.6375519221672, 1236.202649291202, 1238.3127364312713, 18230.17964191641, 18626.906865465586, 18659.16033778456, 18899.338523151622, 19335.8066419334, 62385.376146237955, 64356.0, 65557.34442655512, 65668.34331656623, 66371.66814165899, 79282.0, 79799.0, 79877.20122798698, 83090.16909830824, 83905.5804720997, 15183.0, 15365.0, 15505.84494155044, 15519.534413967147, 15528.301226445063, 23150.1897433589, 23228.3031509055, 23289.883550582137, 23302.883485582464, 23383.298501044996, 18660.626787464447, 18717.0, 18843.71734424012, 18860.717089243946, 19024.809751902303, 16942.152892355087, 16982.40561580339, 17234.655306893543, 17287.654246915245, 17510.387136450165, 19185.61628767389, 19230.615387692447, 19291.807081929, 19559.902200488905, 19629.0, 15070.698586027998, 15087.321070551632, 15089.0, 15162.620934476727, 15210.16344101085, 18650.0, 18867.245310187438, 18907.0, 19037.238510459425, 19104.808951910858], "value": 12765.05644349828, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.956741297761, 1479.6005078628766, 1491.0308299605256, 1493.4026389444214, 1493.775933609959, 16704.498865034053, 17384.130793460306, 17389.826101738978, 17457.738133927985, 17461.0, 17214.741778873326, 17219.311227550883, 17299.654006919853, 17339.0, 17375.91312043438, 2777.777777777783, 2843.8862445502155, 2900.7534359579354, 2908.8691008904652, 2915.79589428739, 2885.3075261937192, 2907.9854600726967, 2915.1837485504093, 2928.633920759905, 2931.091361677876, 2710.1598504463677, 2826.8727907244224, 2830.702776208489, 2935.765138788892, 2939.162338733461, 2812.831230126199, 2865.369618683886, 2893.8263704177607, 2894.7684185265234, 2894.8842046318123, 2696.2180967519394, 2757.4760795449, 2787.9581806272718, 2812.789040821926, 2821.44981728563, 16933.153342332833, 17046.40337588191, 17067.914660426744, 17073.658526829517, 17089.145542722814, 8389.916100838975, 8617.698380556712, 8703.129687031309, 8704.825903481895, 8706.129387061306, 5882.735276912549, 5940.673262970578, 5971.193888824996, 6026.728797204137, 6040.0, 2147.4953385954286, 2152.406350347827, 2165.664322030088, 2217.2572188316935, 2231.877246751428, 31827.522587161213, 32073.358532829214, 32098.197545061328, 32153.1961700957, 32205.194870128435, 7252.818679533054, 7259.0, 7383.372413344819, 7423.109226892808, 7426.034615500059, 19691.60616787656, 19942.90028549877, 20164.69752953707, 20208.29270975509, 20208.393748187576, 8266.710665126691, 8562.74311770648, 8564.357673174536, 8619.353548483889, 8655.221030107321, 7689.884651730228, 7699.730509432143, 7806.375489960789, 7818.530888146729, 7849.843003140019, 5725.77096916127, 5864.354920958691, 5920.79277225295, 5937.792177273861, 5963.910541341883, 1634.5913521619595, 1648.1429656578512, 1650.966980660404, 1673.8828282020386, 1680.5058352272408, 950.1828427552299, 950.9524523773922, 955.2071780422233, 964.238251781099, 964.5081008685621, 8403.873941890877, 8405.243528082501, 8407.53758543286, 8431.283340915968, 8553.700620478254, 789.3685051958411, 789.6959670526888, 802.6548584108773, 805.0862271322092, 805.1224165659343, 4991.450940396553, 5078.314427552307, 5972.25346831646, 6072.39276072398, 6218.502519798405, 24564.87717561401, 25158.371040724127, 25274.620880686805, 25618.615720764203, 25818.741812581633, 25513.10704125384, 25824.870875645876, 25890.22329330123, 25990.0, 26030.6095408569, 11412.771744565061, 12166.635000949986, 12375.938120309342, 12417.813732794015, 12549.0, 9753.073458021476, 9790.706278811647, 9803.509824508603, 9809.55856986423, 9958.302918795904, 21243.0, 21249.57500850005, 21528.246511372028, 21594.0, 22052.0, 22544.5491090174, 22578.88710556437, 22581.774182258625, 22806.0, 22963.0, 12011.279323240284, 12494.937525312314, 12568.74862502763, 12571.622851314476, 12593.118481706193, 15679.843201567839, 15719.921400392925, 15722.05667659944, 15847.920760396124, 15860.206989650702, 11787.764244715228, 11980.221285616755, 12032.458539365574, 12039.458224379749, 12051.156419050581, 12157.817632735692, 12254.571090011807, 12455.93772031134, 12531.561395351118, 12732.618021459371, 11265.493052812806, 11581.536738530702, 11584.304941703525, 11602.129840261845, 11610.6516804496, 8073.111957684768, 8180.550069746221, 8326.25063744266, 8328.708495202638, 8334.958325208336, 8291.212334828182, 8297.419180657538, 8386.958065209636, 8408.369372297224, 8432.789180270542], "value": 7358.413302111517, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.1325309624955, 552.3758153286785, 574.692539491372, 577.2005772005771, 586.3491524407907, 6277.937220627792, 6298.905516417258, 6348.682565871711, 6374.043893415978, 6418.550701450895, 6268.0, 6280.183576135108, 6366.9307475070555, 6383.669960464432, 6405.3914878086625, 5005.749712514378, 5069.923951140736, 5099.821506247282, 5175.482451754832, 5210.895782084346, 5083.466236045218, 5095.84712458625, 5103.795848166069, 5141.537261646451, 5148.6395952283165, 5017.824376146836, 5077.771500282478, 5081.161608334621, 5093.363329583802, 5101.464346243647, 5072.39131304244, 5094.312267843849, 5096.51583099605, 5112.769925353368, 5129.487051294878, 4597.517260687647, 4648.0, 4648.3963032753645, 4664.13713463007, 4669.39297891272, 6175.166352542395, 6189.26700523854, 6246.3441338659695, 6269.153664255313, 6275.6548389838545, 5940.821775346746, 5964.940350596482, 6016.578839481194, 6025.517958563304, 6035.275766907956, 6681.398674119304, 6686.197656281277, 6714.563553369016, 6753.15585551806, 6858.554193977426, 5145.176771716508, 5163.63854530183, 5170.146925757245, 5179.7410129493755, 5194.558462530689, 6868.381845633867, 6891.2764159763, 7080.256573059857, 7118.715251389938, 7120.821979450555, 5298.258243845865, 5328.227407025959, 5329.973350133225, 5350.5987050971335, 5372.543333816591, 5320.281761962163, 5339.0, 5390.460953904578, 5431.293931788842, 5448.291722076105, 5864.824055278348, 5967.433093856077, 5978.624916269282, 5992.0, 6011.819645410645, 6803.2856550062525, 6806.353396427331, 6843.144606924135, 6855.211650660238, 6881.483888708367, 1856.860735444847, 1858.6747319219082, 1875.5123667846458, 1878.286251224526, 1892.5930924850948, 1241.8385609870659, 1298.9545365912147, 1307.2875282970804, 1310.0960337367187, 1334.1928133479105, 6286.685665716695, 6290.559660823698, 6326.746930122836, 6387.648679322682, 6408.359164083555, 6759.357861003196, 6839.829004274933, 6941.583504989717, 6975.197852246955, 6991.056207411934, 6181.876362472725, 6301.842453938688, 6320.304766475684, 6354.714037868307, 6402.103705481236, 3584.5877724062066, 3645.0, 3691.8001649463495, 3735.9813200934364, 3738.140227747633, 7194.0, 7239.855202895913, 7249.782506524813, 7268.200497945222, 7277.927220727831, 6969.825754356181, 7045.542039767502, 7064.823379415556, 7111.92888071123, 7115.964420177866, 7036.155661320572, 7045.964770176219, 7117.6085315308155, 7152.570845749167, 7199.8560028800175, 6897.448204143757, 6939.265183704074, 6943.409810166192, 6996.6151861648095, 6999.370056694922, 6865.519413641197, 6888.380045795902, 6890.724371025103, 6902.551334163263, 6933.237343892269, 7044.612546309797, 7047.506674532733, 7120.715171393086, 7203.423726102004, 7213.855722885408, 5238.568737476959, 5260.342457192851, 5313.495217954288, 5319.840404787863, 5359.14253719403, 6727.73089076451, 6858.0, 6943.930560694531, 6969.895451568332, 6973.0, 6602.88707613559, 6605.603663779996, 6668.766593169215, 6683.365080317362, 6783.066825954579, 6532.314107018885, 6631.6510162367695, 6676.666166691549, 6697.464202863662, 6701.798946031627, 6498.3826536478955, 6505.8698826024165, 6583.30875258091, 6600.80197594073, 6605.636689981905, 6627.460189770023, 6636.601803891782, 6696.966515167393, 6707.396334329934, 6760.0, 6450.258430223907, 6465.224173099258, 6505.479561634963, 6551.80344589644, 6587.176602924635], "value": 5287.577438153329, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [931.4457897550955, 957.1003256938477, 960.3421656165532, 965.2567523007284, 982.4056445850263, 18007.36974205903, 18356.0, 18372.540686482844, 18449.44651660449, 18485.722714159263, 18824.341148059822, 19020.14409351579, 19054.61890762184, 19070.713939290923, 19076.0, 1007.8891321954598, 1012.6506355307407, 1013.3109485549824, 1013.4628646817162, 1013.6553571785591, 1028.0952761569808, 1031.3863251365426, 1031.8606988056629, 1032.9535170917288, 1033.1218464305318, 1055.0609957138154, 1076.5317087067112, 1078.2613909472036, 1085.6417382263874, 1086.8858769829133, 1067.9960836813448, 1068.7488440216543, 1070.507566519402, 1073.1790180511875, 1074.033369967032, 767.244264399566, 786.3591173193829, 786.5909726942016, 788.4008153803151, 789.4473868292162, 19995.600087998304, 20115.89942050295, 20117.79882201174, 20123.698144527843, 20177.79822201774, 22766.65850012251, 23156.18953336642, 23406.531869362687, 23768.0, 23782.405439863967, 17849.46451606441, 17901.37345192925, 18051.27794888203, 18272.360467383576, 18304.63390732178, 3899.2786334528205, 3944.842206311744, 3948.4274780156716, 3966.147278335143, 3978.5026871641044, 132602.0, 161440.0, 162606.1869690644, 164091.0, 166380.0, 18223.0, 18437.7234341485, 18628.068596570116, 18864.905675471535, 18865.622687546173, 34489.65510344915, 34994.650053499645, 35098.47352289718, 35176.0, 35570.822145889106, 9739.805203895883, 9875.111239988291, 9880.703578892644, 9892.703218903445, 9917.256205784595, 4474.686771925999, 4498.8425405110665, 4544.545545445429, 4592.03567250881, 4639.489656137822, 6009.939900601025, 6087.939120608826, 6109.480694141048, 6132.049532322497, 6152.815415537541, 1139.2480962564832, 1173.4484792147678, 1176.9293842369314, 1185.6265276437894, 1193.6180422264965, 727.618000549705, 745.9664315105833, 746.3096635612037, 758.5979430901631, 763.9961800190963, 13915.443382264799, 14338.426462941365, 14592.197429141286, 14971.0, 14988.850111498745, 682.0, 689.6655122265697, 692.5879101934341, 693.1612748574281, 701.0325750464326, 6137.60105593135, 6304.527160462983, 6388.680565971683, 6392.4886009119155, 6414.9679251604375, 151375.0, 151908.0, 153220.0, 153674.0, 154238.0, 133694.33152834175, 135355.32322338325, 135847.0, 138961.0, 141934.0, 113515.0, 114580.56258312264, 115821.99999999831, 116173.0, 117508.41245793887, 11615.361155136548, 11780.352080635646, 11967.940160299142, 12150.696232594255, 12358.876411236131, 56323.0, 56358.43641563532, 58016.12975805282, 58055.70972145112, 58218.7089064552, 71482.28517714757, 72765.90851137131, 72808.0, 73601.26398735945, 74859.0, 17034.14829258557, 17064.146792660566, 17260.13699315004, 17260.654786904444, 17401.738973915148, 17408.47774566765, 17435.30258789634, 17852.10739462996, 18341.449756507325, 18464.81535184631, 17467.82532174713, 17737.822621773616, 17837.37569185124, 17862.821371786114, 17944.910275449063, 18243.179056942736, 18245.726314105035, 18460.446186614423, 18571.44285671432, 18585.90707046456, 16398.508044758677, 16671.0, 16681.332746689997, 16685.082320472004, 16848.57878553046, 14284.214368209849, 14360.138391696531, 14433.0, 14531.782023269448, 14601.342939567532, 13903.443862245396, 13983.44066237339, 14066.0, 14090.084144530572, 14111.435542578181], "value": 9899.264877543143, "unit": "score"}]}, {"extraOptions": ["e10s", "stylo_disabled"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1363.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 106.0, 104.0, 105.0, 106.0, 104.0, 92.0, 106.0, 106.0, 105.0], "value": 104.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 118.0, 112.0, 118.0, 117.0, 112.0, 113.0, 114.0, 116.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 161.0, 160.0, 162.0, 162.0, 162.0, 162.0, 162.0, 165.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 72.0, 75.0, 75.0, 77.0, 76.0, 72.0, 72.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 77.0, 78.0, 78.0, 77.0, 77.0, 77.0, 77.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 110.0, 113.0, 113.0, 116.0, 114.0, 112.0, 117.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 149.0, 150.0, 149.0, 151.0, 152.0, 151.0, 152.0, 153.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 95.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 47.0, 47.0, 45.0, 47.0, 45.0, 47.0, 46.0, 46.0, 49.0], "value": 46.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 36.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0], "value": 36.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 69.0, 71.0, 71.0, 71.0, 71.0, 71.0, 71.0, 76.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 102.0, 112.0, 112.0, 103.0, 111.0, 108.0, 107.0, 105.0, 103.0], "value": 106.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 160.0, 157.0, 149.0, 149.0, 157.0, 150.0, 150.0, 155.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 61.0, 59.0, 58.0, 59.0, 58.0, 58.0, 58.0], "value": 59.0, "unit": "score"}]}]} 10:12:50 INFO - Return code: 0 10:12:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:12:50 INFO - # TBPL SUCCESS # 10:12:50 INFO - Running post-action listener: _package_coverage_data 10:12:50 INFO - Running post-action listener: _resource_record_post_action 10:12:50 INFO - [mozharness: 2017-09-21 17:12:50.513461Z] Finished run-tests step (success) 10:12:50 INFO - Running post-run listener: _resource_record_post_run 10:12:50 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 331087872; Read time: 12; Write time: 162460 10:12:50 INFO - TinderboxPrint: CPU usage
13.1% 10:12:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:12:50 INFO - TinderboxPrint: I/O write bytes / time
331,087,872 / 162,460 10:12:50 INFO - TinderboxPrint: CPU idle
8,199.3 (86.9%) 10:12:50 INFO - TinderboxPrint: CPU user
1,185.1 (12.6%) 10:12:50 INFO - TinderboxPrint: Swap in / out
0 / 0 10:12:50 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13115392; Read time: 0; Write time: 2404 10:12:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:12:50 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 4096; Write bytes: 317972480; Read time: 12; Write time: 160056 10:12:51 INFO - Running post-run listener: _upload_blobber_files 10:12:51 INFO - Blob upload gear active. 10:12:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:12:51 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:12:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:12:51 INFO - (blobuploader) - INFO - Open directory for files ... 10:12:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:12:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:12:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:12:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:12:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:12:58 INFO - (blobuploader) - INFO - Done attempting. 10:12:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log ... 10:12:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:12:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:12:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_errorsummary.log: uploaded 10:12:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:12:59 INFO - (blobuploader) - INFO - Done attempting. 10:12:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:12:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:12:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:13:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:00 INFO - (blobuploader) - INFO - Done attempting. 10:13:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log ... 10:13:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_raw.log: uploaded 10:13:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:01 INFO - (blobuploader) - INFO - Done attempting. 10:13:01 INFO - (blobuploader) - INFO - Iteration through files over. 10:13:01 INFO - Return code: 0 10:13:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:13:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:13:01 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83c23f91ce159504704a79a9d186f63ae1b4b98c749a8d04226aad45f72d7201edcfbb12e7446bffaf3ca84adb54db66689f73cd77b27fcadc02069e0e87a9a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e27ca91605a4019484c36f1463ffc2448b650636a53406cca81b02c3eadd84da50034a413bb55b2a5710578aef010d8665b9dc341e65791e143e88abb703e0a", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10914e26be0bd3bddf5691fdc1735838be20a2002880b9271ed56f2e5ee996e1febcc01929a9e839993b9291720347de00da30b040e7b12122d6e76f236b8bf7", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5604c682e31788cda30a5edc27fbab88a941b21809a81104d1bf5be66fbf3847f00e83a8b280f799df8be13f471f49f1f65d4f2c7c8f7b1ac5bcca746539a599"} 10:13:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:13:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:13:01 INFO - Contents: 10:13:01 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83c23f91ce159504704a79a9d186f63ae1b4b98c749a8d04226aad45f72d7201edcfbb12e7446bffaf3ca84adb54db66689f73cd77b27fcadc02069e0e87a9a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e27ca91605a4019484c36f1463ffc2448b650636a53406cca81b02c3eadd84da50034a413bb55b2a5710578aef010d8665b9dc341e65791e143e88abb703e0a", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10914e26be0bd3bddf5691fdc1735838be20a2002880b9271ed56f2e5ee996e1febcc01929a9e839993b9291720347de00da30b040e7b12122d6e76f236b8bf7", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5604c682e31788cda30a5edc27fbab88a941b21809a81104d1bf5be66fbf3847f00e83a8b280f799df8be13f471f49f1f65d4f2c7c8f7b1ac5bcca746539a599"} 10:13:01 INFO - Running post-run listener: copy_logs_to_upload_dir 10:13:01 INFO - Copying logs to upload dir... 10:13:01 INFO - mkdir: /builds/slave/test/build/upload/logs 10:13:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.820119 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-21 10:13:01.063389) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 10:13:01.071130) ========= 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=20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83c23f91ce159504704a79a9d186f63ae1b4b98c749a8d04226aad45f72d7201edcfbb12e7446bffaf3ca84adb54db66689f73cd77b27fcadc02069e0e87a9a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e27ca91605a4019484c36f1463ffc2448b650636a53406cca81b02c3eadd84da50034a413bb55b2a5710578aef010d8665b9dc341e65791e143e88abb703e0a", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10914e26be0bd3bddf5691fdc1735838be20a2002880b9271ed56f2e5ee996e1febcc01929a9e839993b9291720347de00da30b040e7b12122d6e76f236b8bf7", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5604c682e31788cda30a5edc27fbab88a941b21809a81104d1bf5be66fbf3847f00e83a8b280f799df8be13f471f49f1f65d4f2c7c8f7b1ac5bcca746539a599"} build_url:https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011312 build_url: 'https://queue.taskcluster.net/v1/task/PaoJjzfISE2tJvHNzbVhiw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83c23f91ce159504704a79a9d186f63ae1b4b98c749a8d04226aad45f72d7201edcfbb12e7446bffaf3ca84adb54db66689f73cd77b27fcadc02069e0e87a9a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e27ca91605a4019484c36f1463ffc2448b650636a53406cca81b02c3eadd84da50034a413bb55b2a5710578aef010d8665b9dc341e65791e143e88abb703e0a", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10914e26be0bd3bddf5691fdc1735838be20a2002880b9271ed56f2e5ee996e1febcc01929a9e839993b9291720347de00da30b040e7b12122d6e76f236b8bf7", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5604c682e31788cda30a5edc27fbab88a941b21809a81104d1bf5be66fbf3847f00e83a8b280f799df8be13f471f49f1f65d4f2c7c8f7b1ac5bcca746539a599"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 10:13:01.102264) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 10:13:01.102573) ========= 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=20e666a3d4cfbb71505a330e000001b4-1506011962.858494-1680930054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004658 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 10:13:01.181520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 10:13:01.184844) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 10:13:01.185199) ========= ========= Total master_lag: 0.29 =========