builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-048 starttime: 1506508765.42 results: success (0) revision: 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:25.422274) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:25.422705) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:25.422993) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:25.467436) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:25.467697) ========= 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=fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 03:39: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.8M=0s 2017-09-27 03:39:25 (69.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.180071 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:25.696103) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:25.696383) ========= 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=fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015900 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:25.751903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-27 03:39:25.752252) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 --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 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 --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=fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 03:39:25,816 truncating revision to first 12 chars 2017-09-27 03:39:25,816 Setting DEBUG logging. 2017-09-27 03:39:25,816 attempt 1/10 2017-09-27 03:39:25,816 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5563e7da39b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 03:39:26,311 attempt 1/10 2017-09-27 03:39:27,343 current task status: no status available at this point. state: STARTED 2017-09-27 03:39:27,344 sleeping for 9.00s (attempt 1/10) 2017-09-27 03:39:36,353 attempt 2/10 2017-09-27 03:39:36,784 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-27 03:39:37,077 unpacking tar archive at: mozilla-central-5563e7da39b2/testing/mozharness/ program finished with exit code 0 elapsedTime=11.561387 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-27 03:39:37.332118) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:37.332418) ========= script_repo_revision: 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:37.332810) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:37.333085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 03:39:37.348477) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-27 03:39:37.348770) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:39:37 INFO - MultiFileLogger online at 20170927 03:39:37 in /builds/slave/test 03:39:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:39:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:39:37 INFO - {'append_to_log': False, 03:39:37 INFO - 'base_work_dir': '/builds/slave/test', 03:39:37 INFO - 'blob_upload_branch': 'Firefox', 03:39:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:39:37 INFO - 'branch': 'Firefox', 03:39:37 INFO - 'buildbot_json_path': 'buildprops.json', 03:39:37 INFO - 'code_coverage': False, 03:39:37 INFO - 'config_files': ('talos/linux_config.py',), 03:39:37 INFO - 'default_actions': ('clobber', 03:39:37 INFO - 'read-buildbot-config', 03:39:37 INFO - 'download-and-extract', 03:39:37 INFO - 'populate-webroot', 03:39:37 INFO - 'create-virtualenv', 03:39:37 INFO - 'install', 03:39:37 INFO - 'setup-mitmproxy', 03:39:37 INFO - 'run-tests'), 03:39:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:39:37 INFO - 'disable_ccov_upload': False, 03:39:37 INFO - 'disable_stylo': False, 03:39:37 INFO - 'download_minidump_stackwalk': True, 03:39:37 INFO - 'download_symbols': 'ondemand', 03:39:37 INFO - 'enable_stylo': False, 03:39:37 INFO - 'enable_webrender': False, 03:39:37 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:39:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:39:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:39:37 INFO - 'gecko_profile': False, 03:39:37 INFO - 'gecko_profile_interval': 0, 03:39:37 INFO - 'installer_path': 'installer.exe', 03:39:37 INFO - 'log_level': 'info', 03:39:37 INFO - 'log_name': 'talos', 03:39:37 INFO - 'log_to_console': True, 03:39:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:39:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:39:37 INFO - 'opt_config_files': (), 03:39:37 INFO - 'pip_index': False, 03:39:37 INFO - 'suite': 'dromaeojs-e10s', 03:39:37 INFO - 'system_bits': '32', 03:39:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:39:37 INFO - 'title': 'talos-linux64-ix-048', 03:39:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:39:37 INFO - 'use_talos_json': True, 03:39:37 INFO - 'verify': 'False', 03:39:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:39:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:39:37 INFO - 'work_dir': 'build'} 03:39:37 INFO - [mozharness: 2017-09-27 10:39:37.632640Z] Running clobber step. 03:39:37 INFO - Running pre-action listener: _resource_record_pre_action 03:39:37 INFO - Running main action method: clobber 03:39:37 INFO - rmtree: /builds/slave/test/build 03:39:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:39:38 INFO - Running post-action listener: _resource_record_post_action 03:39:38 INFO - [mozharness: 2017-09-27 10:39:38.586050Z] Finished clobber step (success) 03:39:38 INFO - [mozharness: 2017-09-27 10:39:38.586147Z] Running read-buildbot-config step. 03:39:38 INFO - Running pre-action listener: _resource_record_pre_action 03:39:38 INFO - Running main action method: read_buildbot_config 03:39:38 INFO - Using buildbot properties: 03:39:38 INFO - { 03:39:38 INFO - "project": "", 03:39:38 INFO - "product": "firefox", 03:39:38 INFO - "who": "archaeopteryx@coole-files.de", 03:39:38 INFO - "installer_path": "public/build/target.tar.bz2", 03:39:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 03:39:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:39:38 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 03:39:38 INFO - "stage_platform": "linux64-qr", 03:39:38 INFO - "basedir": "/builds/slave/test", 03:39:38 INFO - "buildnumber": 101, 03:39:38 INFO - "platform": "ubuntu64_hw_qr", 03:39:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:39:38 INFO - "slavebuilddir": "test", 03:39:38 INFO - "taskId": "CQFi1FC1Tte4yip9wamIoQ", 03:39:38 INFO - "branch": "mozilla-central", 03:39:38 INFO - "script_repo_revision": "production", 03:39:38 INFO - "revision": "5563e7da39b265ed1ba7796ec058bdbcf6f792f6", 03:39:38 INFO - "slavename": "talos-linux64-ix-048", 03:39:38 INFO - "repo_path": "mozilla-central" 03:39:38 INFO - } 03:39:38 INFO - Finding installer, test and symbols from parent task. 03:39:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CQFi1FC1Tte4yip9wamIoQ'}, attempt #1 03:39:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CQFi1FC1Tte4yip9wamIoQ'}, attempt #1 03:39:39 INFO - Task dependencies: Epxi0CdYQW63JHszs_NJbg 03:39:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg'}, attempt #1 03:39:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.tar.bz2 03:39:40 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.test_packages.json 03:39:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.crashreporter-symbols.zip 03:39:40 INFO - Running post-action listener: _resource_record_post_action 03:39:40 INFO - [mozharness: 2017-09-27 10:39:40.035852Z] Finished read-buildbot-config step (success) 03:39:40 INFO - [mozharness: 2017-09-27 10:39:40.036070Z] Running download-and-extract step. 03:39:40 INFO - Running pre-action listener: _resource_record_pre_action 03:39:40 INFO - Running main action method: download_and_extract 03:39:40 INFO - mkdir: /builds/slave/test/build/tests 03:39:40 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')]} 03:39:40 INFO - trying https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.test_packages.json 03:39:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:39:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:39:40 INFO - Downloaded 989 bytes. 03:39:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:39:40 INFO - Using the following test package requirements: 03:39:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:39:40 INFO - u'common': [u'target.common.tests.zip'], 03:39:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:39:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:39:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:39:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:39:40 INFO - u'mozbase': [u'target.common.tests.zip'], 03:39:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:39:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:39:40 INFO - u'web-platform': [u'target.common.tests.zip', 03:39:40 INFO - u'target.web-platform.tests.tar.gz'], 03:39:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:39:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:39:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.common.tests.zip 03:39:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:39:40 INFO - Fetch https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.common.tests.zip into memory 03:39:42 INFO - Content-Length response header: 54946073 03:39:42 INFO - Bytes received: 54946073 03:39:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.talos.tests.zip 03:39:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:39:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.talos.tests.zip into memory 03:39:53 INFO - Content-Length response header: 14513823 03:39:53 INFO - Bytes received: 14513823 03:39: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')]} 03:39:53 INFO - trying https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.tar.bz2 03:39:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:39:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:39:54 INFO - Downloaded 61730604 bytes. 03:39:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.tar.bz2 03:39:54 INFO - mkdir: /builds/slave/test/properties 03:39:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:39:54 INFO - Writing to file /builds/slave/test/properties/build_url 03:39:54 INFO - Contents: 03:39:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.tar.bz2 03:39:54 INFO - Running post-action listener: _resource_record_post_action 03:39:54 INFO - Running post-action listener: find_tests_for_verification 03:39:54 INFO - Running post-action listener: set_extra_try_arguments 03:39:54 INFO - [mozharness: 2017-09-27 10:39:54.918785Z] Finished download-and-extract step (success) 03:39:54 INFO - [mozharness: 2017-09-27 10:39:54.918885Z] Running populate-webroot step. 03:39:54 INFO - Running pre-action listener: _resource_record_pre_action 03:39:54 INFO - Running main action method: populate_webroot 03:39:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:39:54 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['tresize', 03:39:54 INFO - 'tcanvasmark']}, 03:39:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:39:54 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['dromaeo_css', 03:39:54 INFO - 'kraken']}, 03:39:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:39:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:39:54 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:39:54 INFO - 'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:39:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:39:54 INFO - 'tests': ['damp', 'tps']}, 03:39:54 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:39:54 INFO - 'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['damp', 'tps']}, 03:39:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:39:54 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['dromaeo_dom']}, 03:39:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:39:54 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['basic_compositor_video', 03:39:54 INFO - 'glvideo']}, 03:39:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:39:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:39:54 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:39:54 INFO - 'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['ts_paint_webext', 03:39:54 INFO - 'tp5o_webext']}, 03:39:54 INFO - 'other-e10s': {'tests': ['a11yr', 03:39:54 INFO - 'ts_paint', 03:39:54 INFO - 'tpaint', 03:39:54 INFO - 'sessionrestore', 03:39:54 INFO - 'sessionrestore_many_windows', 03:39:54 INFO - 'sessionrestore_no_auto_restore', 03:39:54 INFO - 'tabpaint']}, 03:39:54 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['a11yr', 03:39:54 INFO - 'ts_paint', 03:39:54 INFO - 'tpaint', 03:39:54 INFO - 'sessionrestore', 03:39:54 INFO - 'sessionrestore_many_windows', 03:39:54 INFO - 'sessionrestore_no_auto_restore', 03:39:54 INFO - 'tabpaint']}, 03:39:54 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 03:39:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:39:54 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['perf_reftest_singletons']}, 03:39:54 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['perf_reftest']}, 03:39:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:39:54 INFO - 'tsvgr_opacity', 03:39:54 INFO - 'tart', 03:39:54 INFO - 'tscrollx', 03:39:54 INFO - 'tsvg_static']}, 03:39:54 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['tsvgx', 03:39:54 INFO - 'tsvgr_opacity', 03:39:54 INFO - 'tart', 03:39:54 INFO - 'tscrollx', 03:39:54 INFO - 'tsvg_static']}, 03:39:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:39:54 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:39:54 INFO - 'talos_options': ['--disable-stylo'], 03:39:54 INFO - 'tests': ['tp5o']}, 03:39:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:39:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:39:54 INFO - 'talos_options': ['--mitmproxy', 03:39:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:39:54 INFO - '--firstNonBlankPaint'], 03:39:54 INFO - 'tests': ['tp6_google', 03:39:54 INFO - 'tp6_youtube', 03:39:54 INFO - 'tp6_amazon', 03:39:54 INFO - 'tp6_facebook']}, 03:39:54 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:39:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:39:54 INFO - 'talos_options': ['--disable-stylo', 03:39:54 INFO - '--mitmproxy', 03:39:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:39:54 INFO - '--firstNonBlankPaint'], 03:39:54 INFO - 'tests': ['tp6_google', 03:39:54 INFO - 'tp6_youtube', 03:39:54 INFO - 'tp6_amazon', 03:39:54 INFO - 'tp6_facebook']}, 03:39:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:39:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:39:54 INFO - 'talos_options': ['--stylo-threads=1', 03:39:54 INFO - '--mitmproxy', 03:39:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:39:54 INFO - '--firstNonBlankPaint'], 03:39:54 INFO - 'tests': ['tp6_google', 03:39:54 INFO - 'tp6_youtube', 03:39:54 INFO - 'tp6_amazon', 03:39:54 INFO - 'tp6_facebook']}, 03:39:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:39:54 INFO - 'talos_options': ['--xperf_path', 03:39:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:39:54 INFO - 'tests': ['tp5n']}, 03:39:54 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:39:54 INFO - 'talos_options': ['--disable-stylo', 03:39:54 INFO - '--xperf_path', 03:39:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:39:54 INFO - 'tests': ['tp5n']}}} 03:39:54 INFO - Running post-action listener: _resource_record_post_action 03:39:54 INFO - [mozharness: 2017-09-27 10:39:54.934351Z] Finished populate-webroot step (success) 03:39:54 INFO - [mozharness: 2017-09-27 10:39:54.934440Z] Running create-virtualenv step. 03:39:54 INFO - Running pre-action listener: _resource_record_pre_action 03:39:54 INFO - Running main action method: create_virtualenv 03:39:54 INFO - Creating virtualenv /builds/slave/test/build/venv 03:39:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:39:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:39:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:39:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:39:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:39:55 INFO - Installing distribute.............................................................................................................................................................................................done. 03:39:56 INFO - Installing pip...............done. 03:39:56 INFO - Return code: 0 03:39:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:39:56 INFO - Reading from file tmpfile_stdout 03:39:56 INFO - Output received: 03:39:56 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:39:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:39:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:39:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:39:56 INFO - Using env: {'DISPLAY': ':0', 03:39:56 INFO - 'HOME': '/home/cltbld', 03:39:56 INFO - 'LANG': 'en_US.UTF-8', 03:39:56 INFO - 'LANGUAGE': 'en_US:en', 03:39:56 INFO - 'LOGNAME': 'cltbld', 03:39:56 INFO - 'MAIL': '/var/mail/cltbld', 03:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:39:56 INFO - 'MOZ_NO_REMOTE': '1', 03:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:39:56 INFO - 'NO_EM_RESTART': '1', 03:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:39:56 INFO - 'PWD': '/builds/slave/test', 03:39:56 INFO - 'SHELL': '/bin/bash', 03:39:56 INFO - 'SHLVL': '1', 03:39:56 INFO - 'TERM': 'linux', 03:39:56 INFO - 'TMOUT': '86400', 03:39:56 INFO - 'USER': 'cltbld', 03:39:56 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:39:56 INFO - '_': '/tools/buildbot/bin/python'} 03:39:58 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:39:58 INFO - Downloading/unpacking pip>=1.5 03:39:58 INFO - Running setup.py egg_info for package pip 03:39:58 INFO - warning: no files found matching 'pip/cacert.pem' 03:39:58 INFO - warning: no files found matching '*.html' under directory 'docs' 03:39:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:39:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:39:58 INFO - Installing collected packages: pip 03:39:58 INFO - Found existing installation: pip 1.1 03:39:58 INFO - Uninstalling pip: 03:39:58 INFO - Successfully uninstalled pip 03:39:58 INFO - Running setup.py install for pip 03:39:58 INFO - warning: no files found matching 'pip/cacert.pem' 03:39:58 INFO - warning: no files found matching '*.html' under directory 'docs' 03:39:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:39:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:39:58 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:39:58 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:39:58 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:39:58 INFO - Successfully installed pip 03:39:58 INFO - Cleaning up... 03:39:58 INFO - Return code: 0 03:39:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:39:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:39:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:39:58 INFO - Using env: {'DISPLAY': ':0', 03:39:58 INFO - 'HOME': '/home/cltbld', 03:39:58 INFO - 'LANG': 'en_US.UTF-8', 03:39:58 INFO - 'LANGUAGE': 'en_US:en', 03:39:58 INFO - 'LOGNAME': 'cltbld', 03:39:58 INFO - 'MAIL': '/var/mail/cltbld', 03:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:39:58 INFO - 'MOZ_NO_REMOTE': '1', 03:39:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:39:58 INFO - 'NO_EM_RESTART': '1', 03:39:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:39:58 INFO - 'PWD': '/builds/slave/test', 03:39:58 INFO - 'SHELL': '/bin/bash', 03:39:58 INFO - 'SHLVL': '1', 03:39:58 INFO - 'TERM': 'linux', 03:39:58 INFO - 'TMOUT': '86400', 03:39:58 INFO - 'USER': 'cltbld', 03:39:58 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:39:58 INFO - '_': '/tools/buildbot/bin/python'} 03:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:39:59 INFO - Downloading/unpacking psutil>=3.1.1 03:39:59 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 03:39:59 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 03:39:59 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 03:39:59 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 03:40:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:40:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:00 INFO - Installing collected packages: psutil 03:40:00 INFO - Running setup.py install for psutil 03:40:00 INFO - building 'psutil._psutil_linux' extension 03:40:00 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 03:40:01 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 03:40:01 INFO - building 'psutil._psutil_posix' extension 03:40:01 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 03:40:01 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 03:40:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:01 INFO - Successfully installed psutil 03:40:01 INFO - Cleaning up... 03:40:01 INFO - Return code: 0 03:40:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:40: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')]} 03:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40: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')]} 03:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:01 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 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:40:01 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 03:40:01 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 03:40:01 INFO - Using env: {'DISPLAY': ':0', 03:40:01 INFO - 'HOME': '/home/cltbld', 03:40:01 INFO - 'LANG': 'en_US.UTF-8', 03:40:01 INFO - 'LANGUAGE': 'en_US:en', 03:40:01 INFO - 'LOGNAME': 'cltbld', 03:40:01 INFO - 'MAIL': '/var/mail/cltbld', 03:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:01 INFO - 'MOZ_NO_REMOTE': '1', 03:40:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:01 INFO - 'NO_EM_RESTART': '1', 03:40:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:01 INFO - 'PWD': '/builds/slave/test', 03:40:01 INFO - 'SHELL': '/bin/bash', 03:40:01 INFO - 'SHLVL': '1', 03:40:01 INFO - 'TERM': 'linux', 03:40:01 INFO - 'TMOUT': '86400', 03:40:01 INFO - 'USER': 'cltbld', 03:40:01 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:01 INFO - '_': '/tools/buildbot/bin/python'} 03:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:40:03 INFO - Installing collected packages: mozsystemmonitor 03:40:03 INFO - Running setup.py install for mozsystemmonitor 03:40:03 INFO - Successfully installed mozsystemmonitor 03:40:03 INFO - Cleaning up... 03:40:03 INFO - Return code: 0 03:40:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:03 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 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:40:03 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 03:40:03 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 03:40:03 INFO - Using env: {'DISPLAY': ':0', 03:40:03 INFO - 'HOME': '/home/cltbld', 03:40:03 INFO - 'LANG': 'en_US.UTF-8', 03:40:03 INFO - 'LANGUAGE': 'en_US:en', 03:40:03 INFO - 'LOGNAME': 'cltbld', 03:40:03 INFO - 'MAIL': '/var/mail/cltbld', 03:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:03 INFO - 'MOZ_NO_REMOTE': '1', 03:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:03 INFO - 'NO_EM_RESTART': '1', 03:40:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:03 INFO - 'PWD': '/builds/slave/test', 03:40:03 INFO - 'SHELL': '/bin/bash', 03:40:03 INFO - 'SHLVL': '1', 03:40:03 INFO - 'TERM': 'linux', 03:40:03 INFO - 'TMOUT': '86400', 03:40:03 INFO - 'USER': 'cltbld', 03:40:03 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:03 INFO - '_': '/tools/buildbot/bin/python'} 03:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:04 INFO - Downloading/unpacking jsonschema==2.5.1 03:40: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 03:40: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 03:40: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 03:40: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 03:40:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:40:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:05 INFO - Downloading functools32-3.2.3-2.tar.gz 03:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:40:05 INFO - warning: no files found matching '*.txt' 03:40:05 INFO - no previously-included directories found matching 'build' 03:40:05 INFO - no previously-included directories found matching 'dist' 03:40:05 INFO - no previously-included directories found matching '.git*' 03:40:05 INFO - Installing collected packages: jsonschema, functools32 03:40:05 INFO - Running setup.py install for functools32 03:40:05 INFO - warning: no files found matching '*.txt' 03:40:05 INFO - no previously-included directories found matching 'build' 03:40:05 INFO - no previously-included directories found matching 'dist' 03:40:05 INFO - no previously-included directories found matching '.git*' 03:40:05 INFO - Successfully installed jsonschema functools32 03:40:05 INFO - Cleaning up... 03:40:06 INFO - Return code: 0 03:40:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:40: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')]} 03:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40: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')]} 03:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40: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', '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 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:40: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:40: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 functools32==3.2.3-2 03:40:06 INFO - Using env: {'DISPLAY': ':0', 03:40:06 INFO - 'HOME': '/home/cltbld', 03:40:06 INFO - 'LANG': 'en_US.UTF-8', 03:40:06 INFO - 'LANGUAGE': 'en_US:en', 03:40:06 INFO - 'LOGNAME': 'cltbld', 03:40:06 INFO - 'MAIL': '/var/mail/cltbld', 03:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:06 INFO - 'MOZ_NO_REMOTE': '1', 03:40:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:06 INFO - 'NO_EM_RESTART': '1', 03:40:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:06 INFO - 'PWD': '/builds/slave/test', 03:40:06 INFO - 'SHELL': '/bin/bash', 03:40:06 INFO - 'SHLVL': '1', 03:40:06 INFO - 'TERM': 'linux', 03:40:06 INFO - 'TMOUT': '86400', 03:40:06 INFO - 'USER': 'cltbld', 03:40:06 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:06 INFO - '_': '/tools/buildbot/bin/python'} 03:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:40:06 INFO - Cleaning up... 03:40:06 INFO - Return code: 0 03:40:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:40: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')]} 03:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40: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')]} 03:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40: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', '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 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:40: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:40: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 blobuploader==1.2.4 03:40:06 INFO - Using env: {'DISPLAY': ':0', 03:40:06 INFO - 'HOME': '/home/cltbld', 03:40:06 INFO - 'LANG': 'en_US.UTF-8', 03:40:06 INFO - 'LANGUAGE': 'en_US:en', 03:40:06 INFO - 'LOGNAME': 'cltbld', 03:40:06 INFO - 'MAIL': '/var/mail/cltbld', 03:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:06 INFO - 'MOZ_NO_REMOTE': '1', 03:40:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:06 INFO - 'NO_EM_RESTART': '1', 03:40:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:06 INFO - 'PWD': '/builds/slave/test', 03:40:06 INFO - 'SHELL': '/bin/bash', 03:40:06 INFO - 'SHLVL': '1', 03:40:06 INFO - 'TERM': 'linux', 03:40:06 INFO - 'TMOUT': '86400', 03:40:06 INFO - 'USER': 'cltbld', 03:40:06 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:06 INFO - '_': '/tools/buildbot/bin/python'} 03:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:06 INFO - Downloading/unpacking blobuploader==1.2.4 03:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:07 INFO - Downloading blobuploader-1.2.4.tar.gz 03:40:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:40:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:40:07 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 03:40:07 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 03:40:07 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 03:40:07 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 03:40:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:40:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:40:07 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 03:40:07 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 03:40:07 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 03:40:07 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 03:40:08 INFO - Downloading docopt-0.6.1.tar.gz 03:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:40:08 INFO - Installing collected packages: blobuploader, requests, docopt 03:40:08 INFO - Running setup.py install for blobuploader 03:40:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:40:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:40:08 INFO - Running setup.py install for requests 03:40:08 INFO - Running setup.py install for docopt 03:40:08 INFO - Successfully installed blobuploader requests docopt 03:40:08 INFO - Cleaning up... 03:40:08 INFO - Return code: 0 03:40:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:08 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')]} 03:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:08 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')]} 03:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:08 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 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:40:08 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 03:40:08 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 03:40:08 INFO - Using env: {'DISPLAY': ':0', 03:40:08 INFO - 'HOME': '/home/cltbld', 03:40:08 INFO - 'LANG': 'en_US.UTF-8', 03:40:08 INFO - 'LANGUAGE': 'en_US:en', 03:40:08 INFO - 'LOGNAME': 'cltbld', 03:40:08 INFO - 'MAIL': '/var/mail/cltbld', 03:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:08 INFO - 'MOZ_NO_REMOTE': '1', 03:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:08 INFO - 'NO_EM_RESTART': '1', 03:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:08 INFO - 'PWD': '/builds/slave/test', 03:40:08 INFO - 'SHELL': '/bin/bash', 03:40:08 INFO - 'SHLVL': '1', 03:40:08 INFO - 'TERM': 'linux', 03:40:08 INFO - 'TMOUT': '86400', 03:40:08 INFO - 'USER': 'cltbld', 03:40:08 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:08 INFO - '_': '/tools/buildbot/bin/python'} 03:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:40:09 INFO - Running setup.py (path:/tmp/pip-poI8mE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:40:09 INFO - Running setup.py (path:/tmp/pip-QYXp9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:40:09 INFO - Running setup.py (path:/tmp/pip-7bmRf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:40:09 INFO - Running setup.py (path:/tmp/pip-e5Iasj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:40:09 INFO - Running setup.py (path:/tmp/pip-E3dfbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:40:09 INFO - Running setup.py (path:/tmp/pip-s0Yk6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:40:09 INFO - Running setup.py (path:/tmp/pip-in39bT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:40:09 INFO - Running setup.py (path:/tmp/pip-wwFlpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:40:09 INFO - Running setup.py (path:/tmp/pip-rVCrb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:40:09 INFO - Running setup.py (path:/tmp/pip-webmqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:40:09 INFO - Running setup.py (path:/tmp/pip-_Jb2Lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:40:09 INFO - Running setup.py (path:/tmp/pip-eBEhhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:40:09 INFO - Running setup.py (path:/tmp/pip-oEynKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:40:09 INFO - Running setup.py (path:/tmp/pip-h7lQDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:09 INFO - Running setup.py (path:/tmp/pip-i7Fh9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:40:10 INFO - Running setup.py (path:/tmp/pip-XijyOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:40:10 INFO - Running setup.py (path:/tmp/pip-ie5Cgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:40:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:40:10 INFO - Running setup.py install for manifestparser 03:40:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:40:10 INFO - Running setup.py install for mozcrash 03:40:10 INFO - Running setup.py install for mozdebug 03:40:10 INFO - Running setup.py install for mozdevice 03:40:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:40:10 INFO - Running setup.py install for mozfile 03:40:10 INFO - Running setup.py install for mozhttpd 03:40:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:40:10 INFO - Running setup.py install for mozinfo 03:40:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:40:10 INFO - Running setup.py install for mozInstall 03:40:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:40:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:40:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:40:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:40:10 INFO - Running setup.py install for mozleak 03:40:11 INFO - Running setup.py install for mozlog 03:40:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:40:11 INFO - Running setup.py install for moznetwork 03:40:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:40:11 INFO - Running setup.py install for mozprocess 03:40:11 INFO - Running setup.py install for mozprofile 03:40:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:40:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:40:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:40:11 INFO - Running setup.py install for mozrunner 03:40:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:40:11 INFO - Running setup.py install for mozscreenshot 03:40:11 INFO - Running setup.py install for moztest 03:40:11 INFO - Running setup.py install for mozversion 03:40:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:40:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:40:11 INFO - Cleaning up... 03:40:11 INFO - Return code: 0 03:40:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:11 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')]} 03:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:11 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')]} 03:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:11 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 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:40:11 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 03:40:11 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 03:40:11 INFO - Using env: {'DISPLAY': ':0', 03:40:11 INFO - 'HOME': '/home/cltbld', 03:40:11 INFO - 'LANG': 'en_US.UTF-8', 03:40:11 INFO - 'LANGUAGE': 'en_US:en', 03:40:11 INFO - 'LOGNAME': 'cltbld', 03:40:11 INFO - 'MAIL': '/var/mail/cltbld', 03:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:11 INFO - 'MOZ_NO_REMOTE': '1', 03:40:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:11 INFO - 'NO_EM_RESTART': '1', 03:40:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:11 INFO - 'PWD': '/builds/slave/test', 03:40:11 INFO - 'SHELL': '/bin/bash', 03:40:11 INFO - 'SHLVL': '1', 03:40:11 INFO - 'TERM': 'linux', 03:40:11 INFO - 'TMOUT': '86400', 03:40:11 INFO - 'USER': 'cltbld', 03:40:11 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:11 INFO - '_': '/tools/buildbot/bin/python'} 03:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:40:12 INFO - Running setup.py (path:/tmp/pip-VMO8pW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:40:12 INFO - Running setup.py (path:/tmp/pip-qAqWJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:40:12 INFO - Running setup.py (path:/tmp/pip-m1rhHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:40:12 INFO - Running setup.py (path:/tmp/pip-SGlqLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:40:12 INFO - Running setup.py (path:/tmp/pip-WNAGWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:40:12 INFO - Running setup.py (path:/tmp/pip-fmxEOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:40:12 INFO - Running setup.py (path:/tmp/pip-dR7Cn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:40:12 INFO - Running setup.py (path:/tmp/pip-WxCON3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:40:12 INFO - Running setup.py (path:/tmp/pip-hPIo0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:40:12 INFO - Running setup.py (path:/tmp/pip-yfS2sA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:40:12 INFO - Running setup.py (path:/tmp/pip-2jKID6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:40:12 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)) 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:40:12 INFO - Running setup.py (path:/tmp/pip-LegNNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:40:13 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)) 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:40:13 INFO - Running setup.py (path:/tmp/pip-W6bmPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:40:13 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)) 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:40:13 INFO - Running setup.py (path:/tmp/pip-9bujLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:40:13 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)) 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:13 INFO - Running setup.py (path:/tmp/pip-JVbAC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:13 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)) 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:40:13 INFO - Running setup.py (path:/tmp/pip-Ku97uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:40:13 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)) 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:40:13 INFO - Running setup.py (path:/tmp/pip-ng_0yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:40:13 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)) 03:40:13 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)) 03:40:13 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)) 03:40:13 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)) 03:40:13 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)) 03:40:13 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)) 03:40:13 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)) 03:40:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:14 INFO - Downloading blessings-1.6.tar.gz 03:40:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:40:14 INFO - Installing collected packages: blessings 03:40:14 INFO - Running setup.py install for blessings 03:40:14 INFO - Successfully installed blessings 03:40:14 INFO - Cleaning up... 03:40:14 INFO - Return code: 0 03:40:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:40:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:40:15 INFO - Reading from file tmpfile_stdout 03:40:15 INFO - Current package versions: 03:40:15 INFO - argparse == 1.2.1 03:40:15 INFO - blessings == 1.6 03:40:15 INFO - blobuploader == 1.2.4 03:40:15 INFO - docopt == 0.6.1 03:40:15 INFO - functools32 == 3.2.3-2 03:40:15 INFO - jsonschema == 2.5.1 03:40:15 INFO - manifestparser == 1.1 03:40:15 INFO - mozInstall == 1.13 03:40:15 INFO - mozcrash == 1.0 03:40:15 INFO - mozdebug == 0.1 03:40:15 INFO - mozdevice == 0.51 03:40:15 INFO - mozfile == 1.2 03:40:15 INFO - mozhttpd == 0.7 03:40:15 INFO - mozinfo == 0.10 03:40:15 INFO - mozleak == 0.1 03:40:15 INFO - mozlog == 3.5 03:40:15 INFO - moznetwork == 0.27 03:40:15 INFO - mozprocess == 0.25 03:40:15 INFO - mozprofile == 0.28 03:40:15 INFO - mozrunner == 6.13 03:40:15 INFO - mozscreenshot == 0.1 03:40:15 INFO - mozsystemmonitor == 0.3 03:40:15 INFO - moztest == 0.8 03:40:15 INFO - mozversion == 1.4 03:40:15 INFO - psutil == 3.1.1 03:40:15 INFO - requests == 1.2.3 03:40:15 INFO - wsgiref == 0.1.2 03:40:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:15 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 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:40:15 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 03:40:15 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 03:40:15 INFO - Using env: {'DISPLAY': ':0', 03:40:15 INFO - 'HOME': '/home/cltbld', 03:40:15 INFO - 'LANG': 'en_US.UTF-8', 03:40:15 INFO - 'LANGUAGE': 'en_US:en', 03:40:15 INFO - 'LOGNAME': 'cltbld', 03:40:15 INFO - 'MAIL': '/var/mail/cltbld', 03:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:15 INFO - 'MOZ_NO_REMOTE': '1', 03:40:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:15 INFO - 'NO_EM_RESTART': '1', 03:40:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:15 INFO - 'PWD': '/builds/slave/test', 03:40:15 INFO - 'SHELL': '/bin/bash', 03:40:15 INFO - 'SHLVL': '1', 03:40:15 INFO - 'TERM': 'linux', 03:40:15 INFO - 'TMOUT': '86400', 03:40:15 INFO - 'USER': 'cltbld', 03:40:15 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:15 INFO - '_': '/tools/buildbot/bin/python'} 03:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:15 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)) 03:40:15 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)) 03:40:15 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)) 03:40:15 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)) 03:40:15 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)) 03:40:15 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)) 03:40:15 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)) 03:40:15 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)) 03:40:15 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)) 03:40:15 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:40:15 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 03:40:15 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 03:40:15 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 03:40:15 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 03:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:40:16 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)) 03:40:16 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)) 03:40:16 INFO - Installing collected packages: simplejson 03:40:16 INFO - Running setup.py install for simplejson 03:40:16 INFO - building 'simplejson._speedups' extension 03:40:16 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 03:40:17 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 03:40:17 INFO - Successfully installed simplejson 03:40:17 INFO - Cleaning up... 03:40:17 INFO - Return code: 0 03:40:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:40:17 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')]} 03:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:17 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')]} 03:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:17 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 0x18bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a83b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c26df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, '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': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', '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 03:40:17 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 03:40:17 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 03:40:17 INFO - Using env: {'DISPLAY': ':0', 03:40:17 INFO - 'HOME': '/home/cltbld', 03:40:17 INFO - 'LANG': 'en_US.UTF-8', 03:40:17 INFO - 'LANGUAGE': 'en_US:en', 03:40:17 INFO - 'LOGNAME': 'cltbld', 03:40:17 INFO - 'MAIL': '/var/mail/cltbld', 03:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:17 INFO - 'MOZ_NO_REMOTE': '1', 03:40:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:17 INFO - 'NO_EM_RESTART': '1', 03:40:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:17 INFO - 'PWD': '/builds/slave/test', 03:40:17 INFO - 'SHELL': '/bin/bash', 03:40:17 INFO - 'SHLVL': '1', 03:40:17 INFO - 'TERM': 'linux', 03:40:17 INFO - 'TMOUT': '86400', 03:40:17 INFO - 'USER': 'cltbld', 03:40:17 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:17 INFO - '_': '/tools/buildbot/bin/python'} 03:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:40:17 INFO - Cleaning up... 03:40:17 INFO - Return code: 0 03:40:17 INFO - Running post-action listener: _resource_record_post_action 03:40:17 INFO - Running post-action listener: _start_resource_monitoring 03:40:17 INFO - Starting resource monitoring. 03:40:17 INFO - [mozharness: 2017-09-27 10:40:17.855100Z] Finished create-virtualenv step (success) 03:40:17 INFO - [mozharness: 2017-09-27 10:40:17.856106Z] Running install step. 03:40:17 INFO - Running pre-action listener: _resource_record_pre_action 03:40:17 INFO - Running main action method: install 03:40:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:40:18 INFO - Reading from file tmpfile_stdout 03:40:18 INFO - Detecting whether we're running mozinstall >=1.0... 03:40:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:40:18 INFO - Reading from file tmpfile_stdout 03:40:18 INFO - Output received: 03:40:18 INFO - Usage: mozinstall [options] installer 03:40:18 INFO - Options: 03:40:18 INFO - -h, --help show this help message and exit 03:40:18 INFO - -d DEST, --destination=DEST 03:40:18 INFO - Directory to install application into. [default: 03:40:18 INFO - "/builds/slave/test"] 03:40:18 INFO - --app=APP Application being installed. [default: firefox] 03:40:18 INFO - mkdir: /builds/slave/test/build/application 03:40:18 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'] 03:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:40:36 INFO - Reading from file tmpfile_stdout 03:40:36 INFO - Output received: 03:40:36 INFO - /builds/slave/test/build/application/firefox/firefox 03:40:36 INFO - Running post-action listener: _resource_record_post_action 03:40:36 INFO - [mozharness: 2017-09-27 10:40:36.039219Z] Finished install step (success) 03:40:36 INFO - [mozharness: 2017-09-27 10:40:36.039470Z] Running setup-mitmproxy step. 03:40:36 INFO - Running pre-action listener: _resource_record_pre_action 03:40:36 INFO - Running main action method: setup_mitmproxy 03:40:36 INFO - Skipping: mitmproxy is not required 03:40:36 INFO - Running post-action listener: _resource_record_post_action 03:40:36 INFO - [mozharness: 2017-09-27 10:40:36.040594Z] Finished setup-mitmproxy step (success) 03:40:36 INFO - [mozharness: 2017-09-27 10:40:36.040814Z] Running run-tests step. 03:40:36 INFO - Running pre-action listener: _resource_record_pre_action 03:40:36 INFO - Running pre-action listener: _set_gcov_prefix 03:40:36 INFO - Running main action method: run_tests 03:40:36 WARNING - Try message not found. 03:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:40:36 INFO - Python 2.7.3 03:40:36 INFO - Return code: 0 03:40:36 INFO - grabbing minidump binary from tooltool 03:40:36 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')]} 03:40:36 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 0x1c2a440>, '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 0x1c2b130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c25320>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:40:36 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 03:40:36 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 03:40:36 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 03:40:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:40:36 INFO - Return code: 0 03:40:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:40:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:40:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:40:36 INFO - ENV: RUST_BACKTRACE is now full 03:40:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:40:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-048 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:40:36 INFO - Using env: {'DISPLAY': ':0', 03:40:36 INFO - 'HOME': '/home/cltbld', 03:40:36 INFO - 'LANG': 'en_US.UTF-8', 03:40:36 INFO - 'LANGUAGE': 'en_US:en', 03:40:36 INFO - 'LOGNAME': 'cltbld', 03:40:36 INFO - 'MAIL': '/var/mail/cltbld', 03:40:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:40:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:40:36 INFO - 'MOZ_ACCELERATED': '1', 03:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:36 INFO - 'MOZ_NO_REMOTE': '1', 03:40:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:40:36 INFO - 'MOZ_WEBRENDER': '1', 03:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:36 INFO - 'NO_EM_RESTART': '1', 03:40:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:36 INFO - 'PWD': '/builds/slave/test', 03:40:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:40:36 INFO - 'RUST_BACKTRACE': 'full', 03:40:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:40:36 INFO - 'SHELL': '/bin/bash', 03:40:36 INFO - 'SHLVL': '1', 03:40:36 INFO - 'TERM': 'linux', 03:40:36 INFO - 'TMOUT': '86400', 03:40:36 INFO - 'USER': 'cltbld', 03:40:36 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:36 INFO - '_': '/tools/buildbot/bin/python'} 03:40:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:40:36 INFO - mozversion application_buildid: 20170927094612 03:40:36 INFO - mozversion application_changeset: 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 03:40:36 INFO - mozversion application_display_name: Nightly 03:40:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:40:36 INFO - mozversion application_name: Firefox 03:40:36 INFO - mozversion application_remotingname: firefox 03:40:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:40:36 INFO - mozversion application_vendor: Mozilla 03:40:36 INFO - mozversion application_version: 58.0a1 03:40:36 INFO - mozversion platform_buildid: 20170927094612 03:40:36 INFO - mozversion platform_changeset: 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 03:40:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:40:36 INFO - mozversion platform_version: 58.0a1 03:40:36 INFO - using testdate: 1506508836 03:40:36 INFO - actual date: 1506508836 03:40:36 INFO - starting webserver on 'localhost:40059' 03:40:36 INFO - SUITE-START | Running 2 tests 03:40:36 INFO - TEST-START | dromaeo_css 03:40:36 INFO - Initialising browser for dromaeo_css test... 03:40:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40059/getInfo.html -profile /tmp/tmpd0Y0EF/profile 03:40:36 INFO - TEST-INFO | started process 3562 (/builds/slave/test/build/application/firefox/firefox http://localhost:40059/getInfo.html) 03:40:45 INFO - TEST-INFO | 3562: exit 0 03:40:45 INFO - Browser initialized. 03:40:45 INFO - Running cycle 1/1 for dromaeo_css test... 03:40:45 INFO - Using env: {'DISPLAY': ':0', 03:40:45 INFO - 'HOME': '/home/cltbld', 03:40:45 INFO - 'JSGC_DISABLE_POISONING': '1', 03:40:45 INFO - 'LANG': 'en_US.UTF-8', 03:40:45 INFO - 'LANGUAGE': 'en_US:en', 03:40:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:40:45 INFO - 'LOGNAME': 'cltbld', 03:40:45 INFO - 'MAIL': '/var/mail/cltbld', 03:40:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:40:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:40:45 INFO - 'MOZ_ACCELERATED': '1', 03:40:45 INFO - 'MOZ_CRASHREPORTER': '1', 03:40:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:40:45 INFO - 'MOZ_NO_REMOTE': '1', 03:40:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:40:45 INFO - 'MOZ_WEBRENDER': '1', 03:40:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:45 INFO - 'NO_EM_RESTART': '1', 03:40:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:45 INFO - 'PWD': '/builds/slave/test', 03:40:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:40:45 INFO - 'RUST_BACKTRACE': 'full', 03:40:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:40:45 INFO - 'SHELL': '/bin/bash', 03:40:45 INFO - 'SHLVL': '1', 03:40:45 INFO - 'TERM': 'linux', 03:40:45 INFO - 'TMOUT': '86400', 03:40:45 INFO - 'USER': 'cltbld', 03:40:45 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:40:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:45 INFO - '_': '/tools/buildbot/bin/python'} 03:40:45 INFO - TEST-INFO | started process 3802 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd0Y0EF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:40:46 INFO - PID 3802 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:40:46 INFO - PID 3802 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:40:46 INFO - PID 3802 | 03:40:46 INFO - PID 3802 | (/builds/slave/test/build/application/firefox/firefox:3862): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:40:46 INFO - PID 3802 | 03:40:57 INFO - PID 3802 | RSS: Main: 192045056 03:40:57 INFO - PID 3802 | 03:43:55 INFO - PID 3802 | Cycle 1(1): loaded http://localhost:40059/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40059/tests/dromaeo/cssquery-ext.html) 03:43:56 INFO - PID 3802 | RSS: Main: 194355200 03:43:56 INFO - PID 3802 | 03:46:58 INFO - PID 3802 | Cycle 1(1): loaded http://localhost:40059/tests/dromaeo/cssquery-ext.html (next: http://localhost:40059/tests/dromaeo/cssquery-jquery.html) 03:46:58 INFO - PID 3802 | RSS: Main: 192512000 03:46:58 INFO - PID 3802 | 03:50:00 INFO - PID 3802 | Cycle 1(1): loaded http://localhost:40059/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40059/tests/dromaeo/cssquery-mootools.html) 03:50:01 INFO - PID 3802 | RSS: Main: 194609152 03:50:01 INFO - PID 3802 | 03:53:03 INFO - PID 3802 | Cycle 1(1): loaded http://localhost:40059/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40059/tests/dromaeo/cssquery-prototype.html) 03:53:04 INFO - PID 3802 | RSS: Main: 192040960 03:53:04 INFO - PID 3802 | 03:56:07 INFO - PID 3802 | Cycle 1(1): loaded http://localhost:40059/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40059/tests/dromaeo/cssquery-yui.html) 03:56:08 INFO - PID 3802 | RSS: Main: 194326528 03:56:08 INFO - PID 3802 | 03:59:10 INFO - PID 3802 | Cycle 1(1): loaded http://localhost:40059/tests/dromaeo/cssquery-yui.html (next: http://localhost:40059/tests/dromaeo/cssquery-dojo.html) 03:59:10 INFO - PID 3802 | RSS: Main: 195915776 03:59:10 INFO - PID 3802 | 03:59:10 INFO - PID 3802 | __start_tp_report 03:59:10 INFO - PID 3802 | _x_x_mozilla_page_load 03:59:10 INFO - PID 3802 | _x_x_mozilla_page_load_details 03:59:10 INFO - PID 3802 | |i|pagename|runs| 03:59:10 INFO - PID 3802 | |0;dojo.html;3077.9846100769505;3096.2878537936276;3097.984510077449;3112.1752735525088;3118.6413562440325;6032.457078862902;6143.569950103494;6182.474489668378;6182.814515564534;6189.504839612831;6127.111568822518;6229.096780966757;6254.562180647356;6258;6305.653189074599;5555.250041244441;5664.688442135681;5762.711864406784;5785.363610002907;5804.419558044204;5690.829275121753;5724.570657200706;5756.884862302752;5797.101449275348;5822.039363505017;5537.058700021006;5603.60774745768;5627.493525582697;5633.63381380209;5736.856578585527;5534.335879694422;5546.667199968015;5553.055980483327;5553.500184983371;5603.271574695264;5505.917411238795;5518.420565840569;5588.636738611977;5609.242752228438;5615.213870058195;6081.270247570275;6114.541409394269;6150.96924515379;6168.352323006065;6214.813555593294;6578.243501997284;6597.076409302702;6634.203895532519;6660.766873159416;6686.732530698766;6527.15147030883;6535.934640653581;6583.407493325576;6599.043138744855;6599.307072757388;5996.310424301218;6084.026555751058;6095.9695201524155;6097.451229389332;6115.816525504242;6806.149231346082;6899.206591241971;6942.583444993215;6946.7221311148;6949.339812717783;6520.315366886503;6547.9017814732815;6574;6615.900761488581;6617.205935287796;6543.5419520633095;6621.370969757962;6632.900506492407;6716.093327400738;6754.696038678271;6474.741010359533;6509.414152726183;6539.607623542603;6620.933790662128;6667.266600673922;6633.4693224541925;6656.600603963778;6689.6989635466525;6706.429953454011;6711.765088221889;5240.6069544784295;5274.7098909560345;5281;5284.947150528523;5291.3385826771655;4559.293309537027;4608.078384323148;4627.444706635225;4657.580817726421;4666.603338716247;5627.8593035174445;5668.433156684298;5722.856928576735;5731.484166424958;5756.050251708505;6367.204099487564;6562.606243625398;6677.933220667828;6716.093327400836;6768.120144381199;5695.060315047971;5722.2561067061015;5722.484976352148;5738.741756620962;5740.913886291709;5686.715664216773;5713.600047996601;5789.594728368974;5797.246357973437;5804.332501762267;6412.294647588761;6423.100765892778;6452.032195170762;6523.18460192476;6531.8693626127215;6425.807225783234;6584.176977877765;6646.468282537482;6686.063951046955;6723.899141512979;6423.261324947504;6454.096426500388;6469.708863101077;6481.157449531531;6494.09082728409;6508;6544.312847151171;6621.735130594915;6652.534322597372;6661.833454163685;6644.269130395749;6645.969874669434;6692.330766923269;6746.561473504206;6775.525713200029;6205.875882482415;6443.065755465337;6501.772437964838;6539.051837483443;6544.869102617826;6357.713902874474;6381.93618063832;6463.676816159267;6492.448141907991;6515.609063456208;6255.124282600348;6348.650824204713;6354.015127655221;6391.58454700443;6419.935800641933;6390.169277993831;6437.420632143129;6470.676466176767;6503.479721622353;6521.869562608626;6084.178635884099;6165.876682466415;6196.659183744938;6241.438270555672;6304.211973503312 03:59:10 INFO - PID 3802 | |1;ext.html;858.7466697324289;858.9871151932718;874.4578361415921;909.8817153770011;910.0308171797036;40573.579924702644;41934.37098443518;42140.36789448157;42190.15619687612;42280.577194228084;38688.61311386885;41639;41652.16695666085;41753.79123104388;42327;7867.763967080982;8036;8056.919430805705;8058.838823223532;8090.352771778273;7840.098388685317;7900.170482099355;7931.24653157952;7962.203779622049;7975.441719079667;7983.161768014362;7999.40004499662;8008.159143289958;8010.479318844256;8015.7194498192575;7852.21477852216;7944.523328600302;7953.721619743281;7970.08344040437;7973.60131993398;7742.7677169685;7750.457467977244;7751.612419379008;7751.883721744179;7763.3789296856685;37952.62047379546;38121.61878381208;38317.808410958045;38338.42492362617;38371.424428633596;3563.6436356364156;3600;3604.9639503605154;3605.080704420382;3612.476190952297;31579.842100789578;31841.681583184334;31854.681453185403;32088.679113208804;32302;5160.225966105077;5161.941801930595;5192.480751924815;5251.264822924793;5323.254744335796;190412;190536.0473197639;191244;191896;192605;46254.306185407244;46578.30132548015;46597.534024659995;46650.30024549634;46689.5331046692;47808.760956194994;48849.26726099112;53089.73455132777;53616.19575706367;54023.18965215525;15742.291596878167;15815.762763558556;15839.604009899846;15902.602434938988;15921.965072270264;12872.420741066488;12912.612621621367;12916;12937.935310323577;12975.156614820007;14829.703405932038;14976.62558436026;15017.624559385884;15063.096214227182;15114.622134446507;5917.792877249276;5980.37206093354;5990.161377407167;5995.970020149872;6154.5076393888385;1001.5493028137367;1007.6573964851985;1014.6854475112739;1021.9437930913722;1031.200819364983;22091.889540552194;22208.444788880406;22239.888800555895;22282.888585556968;22554.88722556377;1120.3557954176217;1133.353251668154;1135.250734515216;1139.5783560082657;1152.3949926288599;12273.509059637514;12275.324857132764;12406.193597416139;12491.312977786309;12552.87447125517;231559;233706;233853.3229335404;234659;235031;45484;46772.298415523794;50960.49039509631;51469.227961580604;52690.20964685533;1449.4633095247773;1466.1349803615917;1467.339697136293;1468.4273133477952;1474.5502621700484;26193.869030654725;26370.4725905477;26495.60256596114;26706;26733.732662673123;37206.25587488181;37297.81351093227;37305;37422;37606.62393376031;194330;194394;194909;195467;196112;33595.66404336023;34810.65189348074;34873.47689784706;35236.82381588076;35289.47065793964;43157.784211078746;43590.346144808485;43870.780646096566;44230.33654495249;44531.332030020225;30145.397092058476;31111.533327000565;31707.365852683277;31942.840285798422;32654.673453265164;31405.52891706672;31603.683963160995;31803.840980794947;31805.5229171558;31825.68174318227;35276.647233527336;35279.647203528664;35343.29313413666;35579.11052223715;35720;30858.845705771328;30981.53527697041;31076.91694007541;31263.84368078145;31853.840730796197;22814.771852281265;22886.08455661755;23004;23191.652125218472;23225.187118450773 03:59:10 INFO - PID 3802 | |2;jquery.html;577.3562477837213;580.973856176472;586.0857062981748;600.5555888642405;621.8041316985151;22456.43858903526;23112.537749245024;23217.883910580444;23372.64941025882;23424.414389640224;24398.146064887733;25419.491610167785;26172;26291.868540657317;26490.470190596225;6327.209098862642;6484.773032943825;6510.316416776242;6521.445677117427;6540.051692504584;6311.147995020659;6441.0982462455295;6459.386358295955;6533;6543.378379053982;6653.002049692536;6669.766558170465;6672.065910772471;6680.7327706891665;6682.966585167067;6664.166979127609;6673.93326066738;6676.766313179064;6678.933210667904;6683.2648408675;5861.17943487912;6116.357782432825;6138.693065346715;6142.293636231846;6201.410865967771;29166.562501562494;29543.852280738673;29578.556321655193;29619.11142665702;29696.70303296961;2064.0814837397365;2093.110428068065;2093.27781915239;2093.612681653899;2100.537881666031;19017.524561885926;19670.508237294038;19799.703004454946;19855.404337869888;20062.197512099498;2371.584972629783;2423.3941514621347;2426.381272775431;2428.5385776702547;2438.878056097188;114730;120721.39639301748;135506;138937.3053134738;141787.58212417847;14792.926035369754;14871.851281487263;15162.620934476727;15301.234938253043;15339.463118790789;24002.639960400607;24208.273751787474;24220.273391798273;24244.515109697706;24268.271951841474;10229.7442563935;10246.333988290737;10284.742881427874;10305.690829275134;10462.476876156314;9533.761655958657;9570.56932438042;9584.424934503952;9629.229661627052;9739.659111931049;8785.780355491164;9060.909390906138;9086.136817002374;9100.317476189311;9255.583498742573;2616.4243866349375;2640.6699162604673;2654.986725066401;2783.972160278371;2798.818076825;961.9711408657612;981.9263555233387;986.7878406142714;995.7510622344414;1017.2726500552141;19255.13351899168;19394.70907936382;19478.318258860872;19781.901090494455;20148.597028059357;1040.380973320873;1052.4632437456798;1057.7831544533353;1060.5492665617237;1064.7338165458636;10487.423191724529;10855.565777368975;10969.670909872717;11012.339259644446;11064.336139831636;470952;476170;480113;493216;503648;111231;115551.6889662202;117860.41069794596;117929;118607;1168.1647621950347;1168.9824652630218;1177.5231031432284;1181.4506254591483;1186.7211205366732;17915.462536123938;17960.371387001393;17975.820241797937;18041;18159;53603.19595206153;55433.445665544445;55573.166402503186;56777.432225678866;57550.99999999833;70609.29390706027;71001.28998709947;71362.64318678374;71950.64024679843;73015;14973.85026149768;14977;15041.097534147986;15057.322420490886;15216.239188040332;22479.10083596638;23197.88401057984;23392.415189620395;23440.882795585912;23541.52916941686;18499.260029598667;18523.44429667112;18593.34923277679;18633.90683046576;18952.24191032343;16064.277107529955;16150.353985840435;16224.837751622332;16870.409535666193;17116.0586167757;18786.530336741143;18854.151563179963;18915.527111822314;19066.428007159808;19347.806521935163;14775.92612036933;14828.036177648419;14869.702605948038;14871.925640371728;15126.621834454227;18123.54691132732;18460;18484.353047643268;18498.16758245855;18526.351577695255 03:59:10 INFO - PID 3802 | |3;mootools.html;1328.6080606221165;1403.1651167555308;1430.8998370114093;1433.8422773494908;1434.978475322871;12264.509419623215;12798.424070916808;12816.935915320422;12930.159539629918;15007.399704011827;15679.529614111567;15931.52205433836;15944.282507287171;15965.600859978505;15970.920145399286;2587.0557246604994;2637.063842335972;2638.498685249806;2640.08916923662;2640.9735902641014;2537.555927712643;2661.3878807874203;2661.920142395731;2664.2540088775077;2666.0535509894;2555.782758465533;2567.627693984371;2595.5976823592373;2596.6494523239403;2599;2561.743825617442;2582.0317380982133;2609.582466805321;2618.070584942356;2619.921402357923;2483.0688491815567;2486.1671340100906;2499.912538045956;2501.862397568133;2503.123906632682;15000.699986000329;15184.468543601031;15267.618309542238;15299.694006119815;15307.158106304149;7936.365090792723;8151.388645851584;8174.26431621151;8203.958980205061;8254.587270636444;4862.902741945177;5008.9499105008845;5100.566451851597;5103.872403189913;5112.3865136183895;1801.4145402744161;1841.2819000589782;1849.8150184981528;1893.640208360407;1894.3843250943498;26999.190024299303;27361.179164625093;27443.588346174824;27461.86269068662;27521;6662.700178491979;6810.557313774589;6811.693473793691;6871.9656401717675;7059.6117213552725;17836.643267134583;17931.551711207325;17938.103094845468;17944.282228710705;17952.55118622019;7868;7913.169117242722;7946.602669866484;7953.721619743281;7962.2037796221075;6877.1747390312485;7080.716771329089;7211.531250468807;7219.820601419046;7284.162321333009;5005.774740136702;5085.974570127126;5122.334096567422;5144.948550514447;5171.612129090333;1517.1124891938339;1527.5111964171358;1553.4019402530107;1555.4089446010444;1555.6110972256936;880.8106257154615;922.9630814767335;941.1012483078644;943.6980166346704;947.4126041854095;7917.247861453153;7966.601669916481;8022.27799498048;8654.740357789276;8815.559222038872;718.5365439291628;721.1202333153544;728.6629035719515;734.2583990169967;738.2101151767557;4676.55572720591;4677.321788340671;4790.616750659939;5453.754581043862;5494.752736126884;21381.78618213829;21989.890050549646;22010.88994555017;22040;22222.333330000125;19878.801211987986;21049;21615.78384216169;21681.457963551038;21778.564428711656;9723.902760972442;9857.802843943224;9934.602615895283;11088.611898583511;11193.552257909592;8372.790680233044;8433.536155511505;8443.915560844558;8452.197041281068;8475.83048339042;17823.554411139827;17947.371841985467;18569.071546422896;18746.437606871816;18774.155163017425;16145.515634530982;16564;19209.90395048016;19585;19824.107915143566;11515.654530364103;11524.250923689933;11573.594924177612;11590.826137608112;11595.362255076052;13962.162270263816;14193.07744996572;14216.289185540889;14218.502352417616;14268.072575282573;10931.617393391192;11227.326360418401;11277.774444511228;11327.830082548604;11331.603393881174;10023.398596084258;10802.621908233488;10836;10934.726631834268;11033.668989930315;10149.188064954933;10196.13332866685;10353.585856565653;10361.585536578452;10369.792604148026;7275.672594733144;7427.70289188448;7433.739819106306;7436.144843343085;7484.925150748423;7698.923010770045;7724.845503089794;7730.11103723079;7743.574103424366;7763.767086987399 03:59:10 INFO - PID 3802 | |4;prototype.html;509.5846884788897;535.4792389613497;539.0566508609932;546.8632841789553;548.4460694698356;6221.502279817615;6258.249010118792;6296.048225050237;6297.622142671432;6300.306966233717;6046.425589568995;6060.696965151747;6092.11664308675;6131.2642482902;6194.163787888634;5085.262636917645;5086.694798312104;5116.411612664554;5124.6668966517245;5143;5156.484351564851;5166.398416490881;5173.922391164136;5188.247704082905;5210.088234558937;4785.353977213083;4943.4067911850625;5093.567046801027;5173.689578625275;5183.714895680736;4925.876853078666;4983.277424773388;5002.774875130628;5004.599632029429;5007.574356179729;4456.732596044248;4462.308342206963;4472.038511719996;4476.440444944382;4484.641228701696;5699.658020518741;5922.733476993546;5964.612300200516;6016.458518733335;6069.666168360738;5610.214569960209;5634.6337488063145;5639.2668953035845;5649.12438572022;5664.490195882349;6325.430711236011;6395.744170233185;6472.385123413268;6484.221893372778;6502;4774;4789.233722604401;4796.112719146969;4811.951880481185;4846.297286893416;6432.935670643281;6599.571027883172;6664.533482656216;6707.618795327638;6758.932410675929;4974.1543937530705;4984.127777638972;4990.8253211137435;4992.625553083533;5005.34930459038;4988.501149884983;5023.924641130386;5027.421846487628;5055.443901170868;5087;5597.44025597437;5605.495505404533;5620.1288800236025;5637.887242255214;5666.178404131378;6454.612723236621;6474.417302442803;6540.574862633914;6548.541602087808;6556.213254409501;1758.4636685810694;1764.5676809181575;1766.8074050016187;1775.20115947824;1781.4299424184396;1151.3955173533961;1228.8586812516496;1236.7526494701096;1256.5162412471263;1260.426505939795;5533.762748791922;5566.805161819398;5571.9721401392735;5592.161175823578;5595.18869763882;6385.712642931079;6541.771038013647;6542.509311801538;6548.639824809586;6597.934020659828;5487.012337779158;5521.254630624894;5538.446155384429;5578.30271216098;5628.465295796893;3438.707709844691;3466.2027733621408;3466.8959931202103;3468.918648810744;3491.4064609016527;6218.253809542884;6246.312905580383;6260.154879091265;6282.183316168869;6377.5535712500605;6248.4064013918605;6631.469482441393;6652.234992975773;6681.832954176184;6758.918573028292;6150.969245153925;6354.142190804275;6408.615483070937;6414.102025716404;6416.165898433173;6438.420542151229;6508.707108180238;6532.542722009413;6559.409653131241;6610.727689793583;6544.8363790905605;6557.508186885907;6596.241432235162;6607.504437167136;6648.002799580102;6256.593321434092;6383.968080159755;6391.424771770563;6393.808185754435;6447.0007148892;4963.354763880672;5011.198208286657;5021.799128034838;5032.874178145576;5040.14317566022;6270.6551139685935;6504.447121994684;6521.282658907421;6679.06493090958;6707.195136583641;6328.082428047999;6368.42684158428;6437.130987316652;6464.159659244268;6470.511782290194;6304.18045654062;6343.207099112611;6388.616682999035;6408;6444.806655800334;6282.780102696383;6283.8114856554375;6284.654344010941;6316.494680425647;6379.649119298298;6323.336049714898;6341.936580634135;6390;6427.967860160669;6526.5105117117355;6139.171211886517;6180.381961803783;6238.344973777688;6251.843703907439;6252.468540174136 03:59:10 INFO - PID 3802 | |5;yui.html;878.8022694893646;885.1812073831707;914.798744276259;919.3426699909563;927.2165020557628;18087.18607662655;18874.716879246804;18892.055397230135;18944.905275473637;19008.524786880334;16514.174291285446;16591.751123733153;18303.633927321447;18814.247430102812;19220.038998050077;960.0975083421596;981.926355523335;984.1978787288373;987.1017374189489;988.3575675810708;976.2824324121765;977.1596427072711;979.8677178580907;980.7597138700993;991.1179050644931;991.3853410885262;1033.297357796698;1039.8752149742043;1041.7603951091253;1053.0522529723235;1023.2325755683238;1026.4918865161756;1033.6433930294036;1039.0336986602495;1039.2361614213546;718.9748358807417;745.571296504512;749.5315427857589;754.0160091081137;755.9168491465989;18606.813931860645;18689.345872894384;18855.905720471448;19278;19294;20995.370138895858;21153.04811283496;21335.25326613561;21603.783962160334;21836.89081554598;17583.472495825146;17716.29134834605;17855;17940.461786146436;18034.27862885483;3701.555813302394;3728;3729.254149170177;3735.551733791935;3754.080250338657;136439;137735.31132344375;140186;140689.2965535176;140780.29609851885;16938.576535586708;16953.576160595836;16979.91510042442;17038.48884533466;17192.31230750756;30778.230544236572;31571;31617.841910790758;32404.189895252337;32704.509432358533;8417.537035463109;8432.578371081421;8457.830843383097;8521.446106003214;8540.572971351408;3884.495015648004;3885.592012838668;3888.202918401721;3926.5680775114715;3940.8817735467983;5302.575793936476;5433.402325744165;5446.754896029688;5477.507024367826;5530.253415788898;942.3074040580168;969.6170012844887;974.8635191073255;981.601218263971;982.4007355513131;682.1234713393266;693.2443636436309;712.8752468318023;715.9284071592799;717.9497435179487;11326.546938122383;11417.029552488131;11596.067810812077;11895.643130706092;12059.939700301618;642.7172044300493;642.7943058221417;643.9632192170602;657.9144711187515;665.0589415976436;5139.717315547606;5194.32473778414;5271.42014378418;5279.1817268323475;5303.443138470482;126425;128518.35740821423;129832;130198.34900825625;131248.3437582806;105670;105735;107327;109045.45477272721;109257.45371273093;99770;100182;100407;100785;102396;9622.278329125204;9757.316987810786;9820.116189542976;9823.115919567274;9926.851097233692;45004.549954501344;45156;47159.528404715515;47354.52645473501;47425.52574474349;57148;60114.39885601263;60343;60723;62044;15851.128187949773;15980.8401915984;16279.430219942245;16316.347346106022;16363.18184090767;16171.27229274709;16181.59546011359;16573.751393728864;16612.916935415247;16630.417935372203;15436.614084647974;15437.922810385877;15463.613409664847;15626.531204063896;15632.92183539075;13798.586042418743;13830.585082447542;13904.58286251414;13923.234222117475;14197.858021419654;14423;14481.855181448473;14784.556463306119;14902.25488725581;15035.248237588296;11998.160128790902;12325.630231093082;12345.197562158432;12449.564265250672;12657.556985505831;12060.879391205975;12254.87745122573;12291.75416491683;12304.384780761106;12344.197627154206 03:59:10 INFO - PID 3802 | __end_tp_report 03:59:10 INFO - PID 3802 | __start_cc_report 03:59:10 INFO - PID 3802 | _x_x_mozilla_cycle_collect,294 03:59:10 INFO - PID 3802 | __end_cc_report 03:59:10 INFO - PID 3802 | __startTimestamp1506509950470__endTimestamp 03:59:10 INFO - PID 3802 | 03:59:10 INFO - PID 3802 | ------- Summary: start ------- 03:59:10 INFO - PID 3802 | Number of tests: 6 03:59:10 INFO - PID 3802 | 03:59:10 INFO - PID 3802 | [#0] dojo.html Cycles:165 Average:6089.79 Median:6348.65 stddev:732.04 (11.5%) stddev-sans-first:695.12 03:59:10 INFO - PID 3802 | Values: 3078.0 3096.3 3098.0 3112.2 3118.6 6032.5 6143.6 6182.5 6182.8 6189.5 6127.1 6229.1 6254.6 6258.0 6305.7 5555.3 5664.7 5762.7 5785.4 5804.4 5690.8 5724.6 5756.9 5797.1 5822.0 5537.1 5603.6 5627.5 5633.6 5736.9 5534.3 5546.7 5553.1 5553.5 5603.3 5505.9 5518.4 5588.6 5609.2 5615.2 6081.3 6114.5 6151.0 6168.4 6214.8 6578.2 6597.1 6634.2 6660.8 6686.7 6527.2 6535.9 6583.4 6599.0 6599.3 5996.3 6084.0 6096.0 6097.5 6115.8 6806.1 6899.2 6942.6 6946.7 6949.3 6520.3 6547.9 6574.0 6615.9 6617.2 6543.5 6621.4 6632.9 6716.1 6754.7 6474.7 6509.4 6539.6 6620.9 6667.3 6633.5 6656.6 6689.7 6706.4 6711.8 5240.6 5274.7 5281.0 5284.9 5291.3 4559.3 4608.1 4627.4 4657.6 4666.6 5627.9 5668.4 5722.9 5731.5 5756.1 6367.2 6562.6 6677.9 6716.1 6768.1 5695.1 5722.3 5722.5 5738.7 5740.9 5686.7 5713.6 5789.6 5797.2 5804.3 6412.3 6423.1 6452.0 6523.2 6531.9 6425.8 6584.2 6646.5 6686.1 6723.9 6423.3 6454.1 6469.7 6481.2 6494.1 6508.0 6544.3 6621.7 6652.5 6661.8 6644.3 6646.0 6692.3 6746.6 6775.5 6205.9 6443.1 6501.8 6539.1 6544.9 6357.7 6381.9 6463.7 6492.4 6515.6 6255.1 6348.7 6354.0 6391.6 6419.9 6390.2 6437.4 6470.7 6503.5 6521.9 6084.2 6165.9 6196.7 6241.4 6304.2 03:59:10 INFO - PID 3802 | 03:59:10 INFO - PID 3802 | [#1] ext.html Cycles:180 Average:37600.53 Median:35284.56 stddev:53714.57 (152.2%) stddev-sans-first:53794.40 03:59:10 INFO - PID 3802 | Values: 858.7 859.0 874.5 909.9 910.0 40573.6 41934.4 42140.4 42190.2 42280.6 38688.6 41639.0 41652.2 41753.8 42327.0 7867.8 8036.0 8056.9 8058.8 8090.4 7840.1 7900.2 7931.2 7962.2 7975.4 7983.2 7999.4 8008.2 8010.5 8015.7 7852.2 7944.5 7953.7 7970.1 7973.6 7742.8 7750.5 7751.6 7751.9 7763.4 37952.6 38121.6 38317.8 38338.4 38371.4 3563.6 3600.0 3605.0 3605.1 3612.5 31579.8 31841.7 31854.7 32088.7 32302.0 5160.2 5161.9 5192.5 5251.3 5323.3 190412.0 190536.0 191244.0 191896.0 192605.0 46254.3 46578.3 46597.5 46650.3 46689.5 47808.8 48849.3 53089.7 53616.2 54023.2 15742.3 15815.8 15839.6 15902.6 15922.0 12872.4 12912.6 12916.0 12937.9 12975.2 14829.7 14976.6 15017.6 15063.1 15114.6 5917.8 5980.4 5990.2 5996.0 6154.5 1001.5 1007.7 1014.7 1021.9 1031.2 22091.9 22208.4 22239.9 22282.9 22554.9 1120.4 1133.4 1135.3 1139.6 1152.4 12273.5 12275.3 12406.2 12491.3 12552.9 231559.0 233706.0 233853.3 234659.0 235031.0 45484.0 46772.3 50960.5 51469.2 52690.2 1449.5 1466.1 1467.3 1468.4 1474.6 26193.9 26370.5 26495.6 26706.0 26733.7 37206.3 37297.8 37305.0 37422.0 37606.6 194330.0 194394.0 194909.0 195467.0 196112.0 33595.7 34810.7 34873.5 35236.8 35289.5 43157.8 43590.3 43870.8 44230.3 44531.3 30145.4 31111.5 31707.4 31942.8 32654.7 31405.5 31603.7 31803.8 31805.5 31825.7 35276.6 35279.6 35343.3 35579.1 35720.0 30858.8 30981.5 31076.9 31263.8 31853.8 22814.8 22886.1 23004.0 23191.7 23225.2 03:59:10 INFO - PID 3802 | 03:59:10 INFO - PID 3802 | [#2] jquery.html Cycles:180 Average:34519.09 Median:23295.27 stddev:81547.88 (350.1%) stddev-sans-first:81736.82 03:59:10 INFO - PID 3802 | Values: 577.4 581.0 586.1 600.6 621.8 22456.4 23112.5 23217.9 23372.6 23424.4 24398.1 25419.5 26172.0 26291.9 26490.5 6327.2 6484.8 6510.3 6521.4 6540.1 6311.1 6441.1 6459.4 6533.0 6543.4 6653.0 6669.8 6672.1 6680.7 6683.0 6664.2 6673.9 6676.8 6678.9 6683.3 5861.2 6116.4 6138.7 6142.3 6201.4 29166.6 29543.9 29578.6 29619.1 29696.7 2064.1 2093.1 2093.3 2093.6 2100.5 19017.5 19670.5 19799.7 19855.4 20062.2 2371.6 2423.4 2426.4 2428.5 2438.9 114730.0 120721.4 135506.0 138937.3 141787.6 14792.9 14871.9 15162.6 15301.2 15339.5 24002.6 24208.3 24220.3 24244.5 24268.3 10229.7 10246.3 10284.7 10305.7 10462.5 9533.8 9570.6 9584.4 9629.2 9739.7 8785.8 9060.9 9086.1 9100.3 9255.6 2616.4 2640.7 2655.0 2784.0 2798.8 962.0 981.9 986.8 995.8 1017.3 19255.1 19394.7 19478.3 19781.9 20148.6 1040.4 1052.5 1057.8 1060.5 1064.7 10487.4 10855.6 10969.7 11012.3 11064.3 470952.0 476170.0 480113.0 493216.0 503648.0 111231.0 115551.7 117860.4 117929.0 118607.0 1168.2 1169.0 1177.5 1181.5 1186.7 17915.5 17960.4 17975.8 18041.0 18159.0 53603.2 55433.4 55573.2 56777.4 57551.0 70609.3 71001.3 71362.6 71950.6 73015.0 14973.9 14977.0 15041.1 15057.3 15216.2 22479.1 23197.9 23392.4 23440.9 23541.5 18499.3 18523.4 18593.3 18633.9 18952.2 16064.3 16150.4 16224.8 16870.4 17116.1 18786.5 18854.2 18915.5 19066.4 19347.8 14775.9 14828.0 14869.7 14871.9 15126.6 18123.5 18460.0 18484.4 18498.2 18526.4 03:59:10 INFO - PID 3802 | 03:59:10 INFO - PID 3802 | [#3] mootools.html Cycles:180 Average:9280.91 Median:2574.83 stddev:6730.99 (261.4%) stddev-sans-first:6723.35 03:59:10 INFO - PID 3802 | Values: 1328.6 1403.2 1430.9 1433.8 1435.0 12264.5 12798.4 12816.9 12930.2 15007.4 15679.5 15931.5 15944.3 15965.6 15970.9 2587.1 2637.1 2638.5 2640.1 2641.0 2537.6 2661.4 2661.9 2664.3 2666.1 2555.8 2567.6 2595.6 2596.6 2599.0 2561.7 2582.0 2609.6 2618.1 2619.9 2483.1 2486.2 2499.9 2501.9 2503.1 15000.7 15184.5 15267.6 15299.7 15307.2 7936.4 8151.4 8174.3 8204.0 8254.6 4862.9 5008.9 5100.6 5103.9 5112.4 1801.4 1841.3 1849.8 1893.6 1894.4 26999.2 27361.2 27443.6 27461.9 27521.0 6662.7 6810.6 6811.7 6872.0 7059.6 17836.6 17931.6 17938.1 17944.3 17952.6 7868.0 7913.2 7946.6 7953.7 7962.2 6877.2 7080.7 7211.5 7219.8 7284.2 5005.8 5086.0 5122.3 5144.9 5171.6 1517.1 1527.5 1553.4 1555.4 1555.6 880.8 923.0 941.1 943.7 947.4 7917.2 7966.6 8022.3 8654.7 8815.6 718.5 721.1 728.7 734.3 738.2 4676.6 4677.3 4790.6 5453.8 5494.8 21381.8 21989.9 22010.9 22040.0 22222.3 19878.8 21049.0 21615.8 21681.5 21778.6 9723.9 9857.8 9934.6 11088.6 11193.6 8372.8 8433.5 8443.9 8452.2 8475.8 17823.6 17947.4 18569.1 18746.4 18774.2 16145.5 16564.0 19209.9 19585.0 19824.1 11515.7 11524.3 11573.6 11590.8 11595.4 13962.2 14193.1 14216.3 14218.5 14268.1 10931.6 11227.3 11277.8 11327.8 11331.6 10023.4 10802.6 10836.0 10934.7 11033.7 10149.2 10196.1 10353.6 10361.6 10369.8 7275.7 7427.7 7433.7 7436.1 7484.9 7698.9 7724.8 7730.1 7743.6 7763.8 03:59:10 INFO - PID 3802 | 03:59:10 INFO - PID 3802 | [#4] prototype.html Cycles:180 Average:5427.76 Median:6065.18 stddev:1490.73 (24.6%) stddev-sans-first:1448.49 03:59:10 INFO - PID 3802 | Values: 509.6 535.5 539.1 546.9 548.4 6221.5 6258.2 6296.0 6297.6 6300.3 6046.4 6060.7 6092.1 6131.3 6194.2 5085.3 5086.7 5116.4 5124.7 5143.0 5156.5 5166.4 5173.9 5188.2 5210.1 4785.4 4943.4 5093.6 5173.7 5183.7 4925.9 4983.3 5002.8 5004.6 5007.6 4456.7 4462.3 4472.0 4476.4 4484.6 5699.7 5922.7 5964.6 6016.5 6069.7 5610.2 5634.6 5639.3 5649.1 5664.5 6325.4 6395.7 6472.4 6484.2 6502.0 4774.0 4789.2 4796.1 4812.0 4846.3 6432.9 6599.6 6664.5 6707.6 6758.9 4974.2 4984.1 4990.8 4992.6 5005.3 4988.5 5023.9 5027.4 5055.4 5087.0 5597.4 5605.5 5620.1 5637.9 5666.2 6454.6 6474.4 6540.6 6548.5 6556.2 1758.5 1764.6 1766.8 1775.2 1781.4 1151.4 1228.9 1236.8 1256.5 1260.4 5533.8 5566.8 5572.0 5592.2 5595.2 6385.7 6541.8 6542.5 6548.6 6597.9 5487.0 5521.3 5538.4 5578.3 5628.5 3438.7 3466.2 3466.9 3468.9 3491.4 6218.3 6246.3 6260.2 6282.2 6377.6 6248.4 6631.5 6652.2 6681.8 6758.9 6151.0 6354.1 6408.6 6414.1 6416.2 6438.4 6508.7 6532.5 6559.4 6610.7 6544.8 6557.5 6596.2 6607.5 6648.0 6256.6 6384.0 6391.4 6393.8 6447.0 4963.4 5011.2 5021.8 5032.9 5040.1 6270.7 6504.4 6521.3 6679.1 6707.2 6328.1 6368.4 6437.1 6464.2 6470.5 6304.2 6343.2 6388.6 6408.0 6444.8 6282.8 6283.8 6284.7 6316.5 6379.6 6323.3 6341.9 6390.0 6428.0 6526.5 6139.2 6180.4 6238.3 6251.8 6252.5 03:59:10 INFO - PID 3802 | 03:59:10 INFO - PID 3802 | [#5] yui.html Cycles:180 Average:24563.05 Median:19249.02 stddev:36155.65 (187.8%) stddev-sans-first:36213.34 03:59:10 INFO - PID 3802 | Values: 878.8 885.2 914.8 919.3 927.2 18087.2 18874.7 18892.1 18944.9 19008.5 16514.2 16591.8 18303.6 18814.2 19220.0 960.1 981.9 984.2 987.1 988.4 976.3 977.2 979.9 980.8 991.1 991.4 1033.3 1039.9 1041.8 1053.1 1023.2 1026.5 1033.6 1039.0 1039.2 719.0 745.6 749.5 754.0 755.9 18606.8 18689.3 18855.9 19278.0 19294.0 20995.4 21153.0 21335.3 21603.8 21836.9 17583.5 17716.3 17855.0 17940.5 18034.3 3701.6 3728.0 3729.3 3735.6 3754.1 136439.0 137735.3 140186.0 140689.3 140780.3 16938.6 16953.6 16979.9 17038.5 17192.3 30778.2 31571.0 31617.8 32404.2 32704.5 8417.5 8432.6 8457.8 8521.4 8540.6 3884.5 3885.6 3888.2 3926.6 3940.9 5302.6 5433.4 5446.8 5477.5 5530.3 942.3 969.6 974.9 981.6 982.4 682.1 693.2 712.9 715.9 717.9 11326.5 11417.0 11596.1 11895.6 12059.9 642.7 642.8 644.0 657.9 665.1 5139.7 5194.3 5271.4 5279.2 5303.4 126425.0 128518.4 129832.0 130198.3 131248.3 105670.0 105735.0 107327.0 109045.5 109257.5 99770.0 100182.0 100407.0 100785.0 102396.0 9622.3 9757.3 9820.1 9823.1 9926.9 45004.5 45156.0 47159.5 47354.5 47425.5 57148.0 60114.4 60343.0 60723.0 62044.0 15851.1 15980.8 16279.4 16316.3 16363.2 16171.3 16181.6 16573.8 16612.9 16630.4 15436.6 15437.9 15463.6 15626.5 15632.9 13798.6 13830.6 13904.6 13923.2 14197.9 14423.0 14481.9 14784.6 14902.3 15035.2 11998.2 12325.6 12345.2 12449.6 12657.6 12060.9 12254.9 12291.8 12304.4 12344.2 03:59:10 INFO - PID 3802 | -------- Summary: end -------- 03:59:10 INFO - PID 3802 | 03:59:11 INFO - TEST-INFO | 3802: exit 0 03:59:11 INFO - TEST-OK | dromaeo_css | took 1114901ms 03:59:11 INFO - TEST-START | kraken 03:59:11 INFO - Initialising browser for kraken test... 03:59:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40059/getInfo.html -profile /tmp/tmp5AOr8F/profile 03:59:11 INFO - TEST-INFO | started process 4187 (/builds/slave/test/build/application/firefox/firefox http://localhost:40059/getInfo.html) 03:59:17 INFO - TEST-INFO | 4187: exit 0 03:59:17 INFO - Browser initialized. 03:59:17 INFO - Running cycle 1/1 for kraken test... 03:59:17 INFO - Using env: {'DISPLAY': ':0', 03:59:17 INFO - 'HOME': '/home/cltbld', 03:59:17 INFO - 'JSGC_DISABLE_POISONING': '1', 03:59:17 INFO - 'LANG': 'en_US.UTF-8', 03:59:17 INFO - 'LANGUAGE': 'en_US:en', 03:59:17 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:59:17 INFO - 'LOGNAME': 'cltbld', 03:59:17 INFO - 'MAIL': '/var/mail/cltbld', 03:59:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:59:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:59:17 INFO - 'MOZ_ACCELERATED': '1', 03:59:17 INFO - 'MOZ_CRASHREPORTER': '1', 03:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:17 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:59:17 INFO - 'MOZ_NO_REMOTE': '1', 03:59:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:59:17 INFO - 'MOZ_WEBRENDER': '1', 03:59:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:59:17 INFO - 'NO_EM_RESTART': '1', 03:59:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:17 INFO - 'PWD': '/builds/slave/test', 03:59:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:59:17 INFO - 'RUST_BACKTRACE': 'full', 03:59:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:59:17 INFO - 'SHELL': '/bin/bash', 03:59:17 INFO - 'SHLVL': '1', 03:59:17 INFO - 'TERM': 'linux', 03:59:17 INFO - 'TMOUT': '86400', 03:59:17 INFO - 'USER': 'cltbld', 03:59:17 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139', 03:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:17 INFO - '_': '/tools/buildbot/bin/python'} 03:59:17 INFO - TEST-INFO | started process 4432 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5AOr8F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:59:18 INFO - PID 4432 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:59:18 INFO - PID 4432 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:59:19 INFO - PID 4432 | 03:59:19 INFO - PID 4432 | (/builds/slave/test/build/application/firefox/firefox:4491): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:59:19 INFO - PID 4432 | 03:59:29 INFO - PID 4432 | RSS: Main: 191893504 03:59:29 INFO - PID 4432 | 03:59:32 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40059/tests/kraken/driver.html?testName=audio-beat-detection) 03:59:33 INFO - PID 4432 | RSS: Main: 188280832 03:59:33 INFO - PID 4432 | 03:59:35 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40059/tests/kraken/driver.html?testName=audio-dft) 03:59:36 INFO - PID 4432 | RSS: Main: 188112896 03:59:36 INFO - PID 4432 | 03:59:39 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40059/tests/kraken/driver.html?testName=audio-fft) 03:59:40 INFO - PID 4432 | RSS: Main: 185196544 03:59:40 INFO - PID 4432 | 03:59:42 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40059/tests/kraken/driver.html?testName=audio-oscillator) 03:59:42 INFO - PID 4432 | RSS: Main: 185532416 03:59:42 INFO - PID 4432 | 03:59:45 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40059/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:59:45 INFO - PID 4432 | RSS: Main: 185626624 03:59:45 INFO - PID 4432 | 03:59:48 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40059/tests/kraken/driver.html?testName=imaging-darkroom) 03:59:49 INFO - PID 4432 | RSS: Main: 187703296 03:59:49 INFO - PID 4432 | 03:59:53 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40059/tests/kraken/driver.html?testName=imaging-desaturate) 03:59:53 INFO - PID 4432 | RSS: Main: 187490304 03:59:53 INFO - PID 4432 | 03:59:56 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40059/tests/kraken/driver.html?testName=json-parse-financial) 03:59:57 INFO - PID 4432 | RSS: Main: 188665856 03:59:57 INFO - PID 4432 | 03:59:59 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40059/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:59:59 INFO - PID 4432 | RSS: Main: 188407808 03:59:59 INFO - PID 4432 | 04:00:01 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40059/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:00:02 INFO - PID 4432 | RSS: Main: 188338176 04:00:02 INFO - PID 4432 | 04:00:04 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40059/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:00:04 INFO - PID 4432 | RSS: Main: 188899328 04:00:04 INFO - PID 4432 | 04:00:07 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40059/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:00:08 INFO - PID 4432 | RSS: Main: 189321216 04:00:08 INFO - PID 4432 | 04:00:11 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40059/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:00:11 INFO - PID 4432 | RSS: Main: 189288448 04:00:11 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | Cycle 1(1): loaded http://localhost:40059/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40059/tests/kraken/driver.html?testName=ai-astar) 04:00:13 INFO - PID 4432 | RSS: Main: 190496768 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | __start_tp_report 04:00:13 INFO - PID 4432 | _x_x_mozilla_page_load 04:00:13 INFO - PID 4432 | _x_x_mozilla_page_load_details 04:00:13 INFO - PID 4432 | |i|pagename|runs| 04:00:13 INFO - PID 4432 | |0;ai-astar;114;108;107;117;109;111;108;113;108;117 04:00:13 INFO - PID 4432 | |1;audio-beat-detection;125;126;118;116;117;145;124;124;128;128 04:00:13 INFO - PID 4432 | |2;audio-dft;165;165;164;164;169;172;167;171;163;163 04:00:13 INFO - PID 4432 | |3;audio-fft;75;78;77;82;77;82;81;80;78;73 04:00:13 INFO - PID 4432 | |4;audio-oscillator;82;90;82;87;78;83;79;86;87;80 04:00:13 INFO - PID 4432 | |5;imaging-gaussian-blur;114;115;115;115;116;120;114;114;115;117 04:00:13 INFO - PID 4432 | |6;imaging-darkroom;157;147;148;146;155;147;149;149;148;148 04:00:13 INFO - PID 4432 | |7;imaging-desaturate;99;97;94;95;99;94;104;97;95;95 04:00:13 INFO - PID 4432 | |8;json-parse-financial;54;53;51;51;51;51;64;53;55;54 04:00:13 INFO - PID 4432 | |9;json-stringify-tinderbox;42;41;45;41;37;43;39;38;44;38 04:00:13 INFO - PID 4432 | |10;stanford-crypto-aes;78;72;72;74;74;74;80;73;77;81 04:00:13 INFO - PID 4432 | |11;stanford-crypto-ccm;113;120;112;117;125;111;115;118;113;125 04:00:13 INFO - PID 4432 | |12;stanford-crypto-pbkdf2;170;168;171;173;176;177;165;172;167;173 04:00:13 INFO - PID 4432 | |13;stanford-crypto-sha256-iterative;73;64;66;66;75;64;72;70;65;64 04:00:13 INFO - PID 4432 | __end_tp_report 04:00:13 INFO - PID 4432 | __start_cc_report 04:00:13 INFO - PID 4432 | _x_x_mozilla_cycle_collect,745 04:00:13 INFO - PID 4432 | __end_cc_report 04:00:13 INFO - PID 4432 | __startTimestamp1506510013830__endTimestamp 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | ------- Summary: start ------- 04:00:13 INFO - PID 4432 | Number of tests: 14 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:3.82 (3.4%) stddev-sans-first:3.92 04:00:13 INFO - PID 4432 | Values: 114.0 108.0 107.0 117.0 109.0 111.0 108.0 113.0 108.0 117.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:125.50 stddev:8.27 (6.6%) stddev-sans-first:8.77 04:00:13 INFO - PID 4432 | Values: 125.0 126.0 118.0 116.0 117.0 145.0 124.0 124.0 128.0 128.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#2] audio-dft Cycles:10 Average:166.30 Median:166.00 stddev:3.30 (2.0%) stddev-sans-first:3.47 04:00:13 INFO - PID 4432 | Values: 165.0 165.0 164.0 164.0 169.0 172.0 167.0 171.0 163.0 163.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#3] audio-fft Cycles:10 Average:78.30 Median:79.00 stddev:2.98 (3.8%) stddev-sans-first:2.92 04:00:13 INFO - PID 4432 | Values: 75.0 78.0 77.0 82.0 77.0 82.0 81.0 80.0 78.0 73.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:84.50 stddev:3.95 (4.7%) stddev-sans-first:4.16 04:00:13 INFO - PID 4432 | Values: 82.0 90.0 82.0 87.0 78.0 83.0 79.0 86.0 87.0 80.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#5] imaging-gaussian-blur Cycles:10 Average:115.50 Median:115.00 stddev:1.84 (1.6%) stddev-sans-first:1.87 04:00:13 INFO - PID 4432 | Values: 114.0 115.0 115.0 115.0 116.0 120.0 114.0 114.0 115.0 117.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:148.50 stddev:3.63 (2.4%) stddev-sans-first:2.60 04:00:13 INFO - PID 4432 | Values: 157.0 147.0 148.0 146.0 155.0 147.0 149.0 149.0 148.0 148.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:96.00 stddev:3.11 (3.2%) stddev-sans-first:3.20 04:00:13 INFO - PID 4432 | Values: 99.0 97.0 94.0 95.0 99.0 94.0 104.0 97.0 95.0 95.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:53.50 stddev:3.92 (7.3%) stddev-sans-first:4.15 04:00:13 INFO - PID 4432 | Values: 54.0 53.0 51.0 51.0 51.0 51.0 64.0 53.0 55.0 54.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#9] json-stringify-tinderbox Cycles:10 Average:40.80 Median:41.50 stddev:2.74 (6.6%) stddev-sans-first:2.87 04:00:13 INFO - PID 4432 | Values: 42.0 41.0 45.0 41.0 37.0 43.0 39.0 38.0 44.0 38.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:75.50 stddev:3.27 (4.3%) stddev-sans-first:3.35 04:00:13 INFO - PID 4432 | Values: 78.0 72.0 72.0 74.0 74.0 74.0 80.0 73.0 77.0 81.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#11] stanford-crypto-ccm Cycles:10 Average:116.90 Median:117.50 stddev:5.11 (4.3%) stddev-sans-first:5.22 04:00:13 INFO - PID 4432 | Values: 113.0 120.0 112.0 117.0 125.0 111.0 115.0 118.0 113.0 125.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:172.50 stddev:3.82 (2.2%) stddev-sans-first:4.03 04:00:13 INFO - PID 4432 | Values: 170.0 168.0 171.0 173.0 176.0 177.0 165.0 172.0 167.0 173.0 04:00:13 INFO - PID 4432 | 04:00:13 INFO - PID 4432 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:4.20 (6.2%) stddev-sans-first:4.03 04:00:13 INFO - PID 4432 | Values: 73.0 64.0 66.0 66.0 75.0 64.0 72.0 70.0 65.0 64.0 04:00:13 INFO - PID 4432 | -------- Summary: end -------- 04:00:13 INFO - PID 4432 | 04:00:14 INFO - PID 4432 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:00:14 INFO - TEST-INFO | 4432: exit 0 04:00:14 INFO - TEST-OK | kraken | took 63367ms 04:00:14 INFO - SUITE-END | took 1178s 04:00:14 INFO - Completed test suite (00:19:38) 04:00:14 INFO - javascript benchmark 04:00:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8451.00017030229, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3077.9846100769505, 3096.2878537936276, 3097.984510077449, 3112.1752735525088, 3118.6413562440325, 6032.457078862902, 6143.569950103494, 6182.474489668378, 6182.814515564534, 6189.504839612831, 6127.111568822518, 6229.096780966757, 6254.562180647356, 6258.0, 6305.653189074599, 5555.250041244441, 5664.688442135681, 5762.711864406784, 5785.363610002907, 5804.419558044204, 5690.829275121753, 5724.570657200706, 5756.884862302752, 5797.101449275348, 5822.039363505017, 5537.058700021006, 5603.60774745768, 5627.493525582697, 5633.63381380209, 5736.856578585527, 5534.335879694422, 5546.667199968015, 5553.055980483327, 5553.500184983371, 5603.271574695264, 5505.917411238795, 5518.420565840569, 5588.636738611977, 5609.242752228438, 5615.213870058195, 6081.270247570275, 6114.541409394269, 6150.96924515379, 6168.352323006065, 6214.813555593294, 6578.243501997284, 6597.076409302702, 6634.203895532519, 6660.766873159416, 6686.732530698766, 6527.15147030883, 6535.934640653581, 6583.407493325576, 6599.043138744855, 6599.307072757388, 5996.310424301218, 6084.026555751058, 6095.9695201524155, 6097.451229389332, 6115.816525504242, 6806.149231346082, 6899.206591241971, 6942.583444993215, 6946.7221311148, 6949.339812717783, 6520.315366886503, 6547.9017814732815, 6574.0, 6615.900761488581, 6617.205935287796, 6543.5419520633095, 6621.370969757962, 6632.900506492407, 6716.093327400738, 6754.696038678271, 6474.741010359533, 6509.414152726183, 6539.607623542603, 6620.933790662128, 6667.266600673922, 6633.4693224541925, 6656.600603963778, 6689.6989635466525, 6706.429953454011, 6711.765088221889, 5240.6069544784295, 5274.7098909560345, 5281.0, 5284.947150528523, 5291.3385826771655, 4559.293309537027, 4608.078384323148, 4627.444706635225, 4657.580817726421, 4666.603338716247, 5627.8593035174445, 5668.433156684298, 5722.856928576735, 5731.484166424958, 5756.050251708505, 6367.204099487564, 6562.606243625398, 6677.933220667828, 6716.093327400836, 6768.120144381199, 5695.060315047971, 5722.2561067061015, 5722.484976352148, 5738.741756620962, 5740.913886291709, 5686.715664216773, 5713.600047996601, 5789.594728368974, 5797.246357973437, 5804.332501762267, 6412.294647588761, 6423.100765892778, 6452.032195170762, 6523.18460192476, 6531.8693626127215, 6425.807225783234, 6584.176977877765, 6646.468282537482, 6686.063951046955, 6723.899141512979, 6423.261324947504, 6454.096426500388, 6469.708863101077, 6481.157449531531, 6494.09082728409, 6508.0, 6544.312847151171, 6621.735130594915, 6652.534322597372, 6661.833454163685, 6644.269130395749, 6645.969874669434, 6692.330766923269, 6746.561473504206, 6775.525713200029, 6205.875882482415, 6443.065755465337, 6501.772437964838, 6539.051837483443, 6544.869102617826, 6357.713902874474, 6381.93618063832, 6463.676816159267, 6492.448141907991, 6515.609063456208, 6255.124282600348, 6348.650824204713, 6354.015127655221, 6391.58454700443, 6419.935800641933, 6390.169277993831, 6437.420632143129, 6470.676466176767, 6503.479721622353, 6521.869562608626, 6084.178635884099, 6165.876682466415, 6196.659183744938, 6241.438270555672, 6304.211973503312], "value": 6034.083536253101, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [858.7466697324289, 858.9871151932718, 874.4578361415921, 909.8817153770011, 910.0308171797036, 40573.579924702644, 41934.37098443518, 42140.36789448157, 42190.15619687612, 42280.577194228084, 38688.61311386885, 41639.0, 41652.16695666085, 41753.79123104388, 42327.0, 7867.763967080982, 8036.0, 8056.919430805705, 8058.838823223532, 8090.352771778273, 7840.098388685317, 7900.170482099355, 7931.24653157952, 7962.203779622049, 7975.441719079667, 7983.161768014362, 7999.40004499662, 8008.159143289958, 8010.479318844256, 8015.7194498192575, 7852.21477852216, 7944.523328600302, 7953.721619743281, 7970.08344040437, 7973.60131993398, 7742.7677169685, 7750.457467977244, 7751.612419379008, 7751.883721744179, 7763.3789296856685, 37952.62047379546, 38121.61878381208, 38317.808410958045, 38338.42492362617, 38371.424428633596, 3563.6436356364156, 3600.0, 3604.9639503605154, 3605.080704420382, 3612.476190952297, 31579.842100789578, 31841.681583184334, 31854.681453185403, 32088.679113208804, 32302.0, 5160.225966105077, 5161.941801930595, 5192.480751924815, 5251.264822924793, 5323.254744335796, 190412.0, 190536.0473197639, 191244.0, 191896.0, 192605.0, 46254.306185407244, 46578.30132548015, 46597.534024659995, 46650.30024549634, 46689.5331046692, 47808.760956194994, 48849.26726099112, 53089.73455132777, 53616.19575706367, 54023.18965215525, 15742.291596878167, 15815.762763558556, 15839.604009899846, 15902.602434938988, 15921.965072270264, 12872.420741066488, 12912.612621621367, 12916.0, 12937.935310323577, 12975.156614820007, 14829.703405932038, 14976.62558436026, 15017.624559385884, 15063.096214227182, 15114.622134446507, 5917.792877249276, 5980.37206093354, 5990.161377407167, 5995.970020149872, 6154.5076393888385, 1001.5493028137367, 1007.6573964851985, 1014.6854475112739, 1021.9437930913722, 1031.200819364983, 22091.889540552194, 22208.444788880406, 22239.888800555895, 22282.888585556968, 22554.88722556377, 1120.3557954176217, 1133.353251668154, 1135.250734515216, 1139.5783560082657, 1152.3949926288599, 12273.509059637514, 12275.324857132764, 12406.193597416139, 12491.312977786309, 12552.87447125517, 231559.0, 233706.0, 233853.3229335404, 234659.0, 235031.0, 45484.0, 46772.298415523794, 50960.49039509631, 51469.227961580604, 52690.20964685533, 1449.4633095247773, 1466.1349803615917, 1467.339697136293, 1468.4273133477952, 1474.5502621700484, 26193.869030654725, 26370.4725905477, 26495.60256596114, 26706.0, 26733.732662673123, 37206.25587488181, 37297.81351093227, 37305.0, 37422.0, 37606.62393376031, 194330.0, 194394.0, 194909.0, 195467.0, 196112.0, 33595.66404336023, 34810.65189348074, 34873.47689784706, 35236.82381588076, 35289.47065793964, 43157.784211078746, 43590.346144808485, 43870.780646096566, 44230.33654495249, 44531.332030020225, 30145.397092058476, 31111.533327000565, 31707.365852683277, 31942.840285798422, 32654.673453265164, 31405.52891706672, 31603.683963160995, 31803.840980794947, 31805.5229171558, 31825.68174318227, 35276.647233527336, 35279.647203528664, 35343.29313413666, 35579.11052223715, 35720.0, 30858.845705771328, 30981.53527697041, 31076.91694007541, 31263.84368078145, 31853.840730796197, 22814.771852281265, 22886.08455661755, 23004.0, 23191.652125218472, 23225.187118450773], "value": 17304.34644172545, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.3562477837213, 580.973856176472, 586.0857062981748, 600.5555888642405, 621.8041316985151, 22456.43858903526, 23112.537749245024, 23217.883910580444, 23372.64941025882, 23424.414389640224, 24398.146064887733, 25419.491610167785, 26172.0, 26291.868540657317, 26490.470190596225, 6327.209098862642, 6484.773032943825, 6510.316416776242, 6521.445677117427, 6540.051692504584, 6311.147995020659, 6441.0982462455295, 6459.386358295955, 6533.0, 6543.378379053982, 6653.002049692536, 6669.766558170465, 6672.065910772471, 6680.7327706891665, 6682.966585167067, 6664.166979127609, 6673.93326066738, 6676.766313179064, 6678.933210667904, 6683.2648408675, 5861.17943487912, 6116.357782432825, 6138.693065346715, 6142.293636231846, 6201.410865967771, 29166.562501562494, 29543.852280738673, 29578.556321655193, 29619.11142665702, 29696.70303296961, 2064.0814837397365, 2093.110428068065, 2093.27781915239, 2093.612681653899, 2100.537881666031, 19017.524561885926, 19670.508237294038, 19799.703004454946, 19855.404337869888, 20062.197512099498, 2371.584972629783, 2423.3941514621347, 2426.381272775431, 2428.5385776702547, 2438.878056097188, 114730.0, 120721.39639301748, 135506.0, 138937.3053134738, 141787.58212417847, 14792.926035369754, 14871.851281487263, 15162.620934476727, 15301.234938253043, 15339.463118790789, 24002.639960400607, 24208.273751787474, 24220.273391798273, 24244.515109697706, 24268.271951841474, 10229.7442563935, 10246.333988290737, 10284.742881427874, 10305.690829275134, 10462.476876156314, 9533.761655958657, 9570.56932438042, 9584.424934503952, 9629.229661627052, 9739.659111931049, 8785.780355491164, 9060.909390906138, 9086.136817002374, 9100.317476189311, 9255.583498742573, 2616.4243866349375, 2640.6699162604673, 2654.986725066401, 2783.972160278371, 2798.818076825, 961.9711408657612, 981.9263555233387, 986.7878406142714, 995.7510622344414, 1017.2726500552141, 19255.13351899168, 19394.70907936382, 19478.318258860872, 19781.901090494455, 20148.597028059357, 1040.380973320873, 1052.4632437456798, 1057.7831544533353, 1060.5492665617237, 1064.7338165458636, 10487.423191724529, 10855.565777368975, 10969.670909872717, 11012.339259644446, 11064.336139831636, 470952.0, 476170.0, 480113.0, 493216.0, 503648.0, 111231.0, 115551.6889662202, 117860.41069794596, 117929.0, 118607.0, 1168.1647621950347, 1168.9824652630218, 1177.5231031432284, 1181.4506254591483, 1186.7211205366732, 17915.462536123938, 17960.371387001393, 17975.820241797937, 18041.0, 18159.0, 53603.19595206153, 55433.445665544445, 55573.166402503186, 56777.432225678866, 57550.99999999833, 70609.29390706027, 71001.28998709947, 71362.64318678374, 71950.64024679843, 73015.0, 14973.85026149768, 14977.0, 15041.097534147986, 15057.322420490886, 15216.239188040332, 22479.10083596638, 23197.88401057984, 23392.415189620395, 23440.882795585912, 23541.52916941686, 18499.260029598667, 18523.44429667112, 18593.34923277679, 18633.90683046576, 18952.24191032343, 16064.277107529955, 16150.353985840435, 16224.837751622332, 16870.409535666193, 17116.0586167757, 18786.530336741143, 18854.151563179963, 18915.527111822314, 19066.428007159808, 19347.806521935163, 14775.92612036933, 14828.036177648419, 14869.702605948038, 14871.925640371728, 15126.621834454227, 18123.54691132732, 18460.0, 18484.353047643268, 18498.16758245855, 18526.351577695255], "value": 12044.048398753588, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.6080606221165, 1403.1651167555308, 1430.8998370114093, 1433.8422773494908, 1434.978475322871, 12264.509419623215, 12798.424070916808, 12816.935915320422, 12930.159539629918, 15007.399704011827, 15679.529614111567, 15931.52205433836, 15944.282507287171, 15965.600859978505, 15970.920145399286, 2587.0557246604994, 2637.063842335972, 2638.498685249806, 2640.08916923662, 2640.9735902641014, 2537.555927712643, 2661.3878807874203, 2661.920142395731, 2664.2540088775077, 2666.0535509894, 2555.782758465533, 2567.627693984371, 2595.5976823592373, 2596.6494523239403, 2599.0, 2561.743825617442, 2582.0317380982133, 2609.582466805321, 2618.070584942356, 2619.921402357923, 2483.0688491815567, 2486.1671340100906, 2499.912538045956, 2501.862397568133, 2503.123906632682, 15000.699986000329, 15184.468543601031, 15267.618309542238, 15299.694006119815, 15307.158106304149, 7936.365090792723, 8151.388645851584, 8174.26431621151, 8203.958980205061, 8254.587270636444, 4862.902741945177, 5008.9499105008845, 5100.566451851597, 5103.872403189913, 5112.3865136183895, 1801.4145402744161, 1841.2819000589782, 1849.8150184981528, 1893.640208360407, 1894.3843250943498, 26999.190024299303, 27361.179164625093, 27443.588346174824, 27461.86269068662, 27521.0, 6662.700178491979, 6810.557313774589, 6811.693473793691, 6871.9656401717675, 7059.6117213552725, 17836.643267134583, 17931.551711207325, 17938.103094845468, 17944.282228710705, 17952.55118622019, 7868.0, 7913.169117242722, 7946.602669866484, 7953.721619743281, 7962.2037796221075, 6877.1747390312485, 7080.716771329089, 7211.531250468807, 7219.820601419046, 7284.162321333009, 5005.774740136702, 5085.974570127126, 5122.334096567422, 5144.948550514447, 5171.612129090333, 1517.1124891938339, 1527.5111964171358, 1553.4019402530107, 1555.4089446010444, 1555.6110972256936, 880.8106257154615, 922.9630814767335, 941.1012483078644, 943.6980166346704, 947.4126041854095, 7917.247861453153, 7966.601669916481, 8022.27799498048, 8654.740357789276, 8815.559222038872, 718.5365439291628, 721.1202333153544, 728.6629035719515, 734.2583990169967, 738.2101151767557, 4676.55572720591, 4677.321788340671, 4790.616750659939, 5453.754581043862, 5494.752736126884, 21381.78618213829, 21989.890050549646, 22010.88994555017, 22040.0, 22222.333330000125, 19878.801211987986, 21049.0, 21615.78384216169, 21681.457963551038, 21778.564428711656, 9723.902760972442, 9857.802843943224, 9934.602615895283, 11088.611898583511, 11193.552257909592, 8372.790680233044, 8433.536155511505, 8443.915560844558, 8452.197041281068, 8475.83048339042, 17823.554411139827, 17947.371841985467, 18569.071546422896, 18746.437606871816, 18774.155163017425, 16145.515634530982, 16564.0, 19209.90395048016, 19585.0, 19824.107915143566, 11515.654530364103, 11524.250923689933, 11573.594924177612, 11590.826137608112, 11595.362255076052, 13962.162270263816, 14193.07744996572, 14216.289185540889, 14218.502352417616, 14268.072575282573, 10931.617393391192, 11227.326360418401, 11277.774444511228, 11327.830082548604, 11331.603393881174, 10023.398596084258, 10802.621908233488, 10836.0, 10934.726631834268, 11033.668989930315, 10149.188064954933, 10196.13332866685, 10353.585856565653, 10361.585536578452, 10369.792604148026, 7275.672594733144, 7427.70289188448, 7433.739819106306, 7436.144843343085, 7484.925150748423, 7698.923010770045, 7724.845503089794, 7730.11103723079, 7743.574103424366, 7763.767086987399], "value": 6565.274805870003, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.5846884788897, 535.4792389613497, 539.0566508609932, 546.8632841789553, 548.4460694698356, 6221.502279817615, 6258.249010118792, 6296.048225050237, 6297.622142671432, 6300.306966233717, 6046.425589568995, 6060.696965151747, 6092.11664308675, 6131.2642482902, 6194.163787888634, 5085.262636917645, 5086.694798312104, 5116.411612664554, 5124.6668966517245, 5143.0, 5156.484351564851, 5166.398416490881, 5173.922391164136, 5188.247704082905, 5210.088234558937, 4785.353977213083, 4943.4067911850625, 5093.567046801027, 5173.689578625275, 5183.714895680736, 4925.876853078666, 4983.277424773388, 5002.774875130628, 5004.599632029429, 5007.574356179729, 4456.732596044248, 4462.308342206963, 4472.038511719996, 4476.440444944382, 4484.641228701696, 5699.658020518741, 5922.733476993546, 5964.612300200516, 6016.458518733335, 6069.666168360738, 5610.214569960209, 5634.6337488063145, 5639.2668953035845, 5649.12438572022, 5664.490195882349, 6325.430711236011, 6395.744170233185, 6472.385123413268, 6484.221893372778, 6502.0, 4774.0, 4789.233722604401, 4796.112719146969, 4811.951880481185, 4846.297286893416, 6432.935670643281, 6599.571027883172, 6664.533482656216, 6707.618795327638, 6758.932410675929, 4974.1543937530705, 4984.127777638972, 4990.8253211137435, 4992.625553083533, 5005.34930459038, 4988.501149884983, 5023.924641130386, 5027.421846487628, 5055.443901170868, 5087.0, 5597.44025597437, 5605.495505404533, 5620.1288800236025, 5637.887242255214, 5666.178404131378, 6454.612723236621, 6474.417302442803, 6540.574862633914, 6548.541602087808, 6556.213254409501, 1758.4636685810694, 1764.5676809181575, 1766.8074050016187, 1775.20115947824, 1781.4299424184396, 1151.3955173533961, 1228.8586812516496, 1236.7526494701096, 1256.5162412471263, 1260.426505939795, 5533.762748791922, 5566.805161819398, 5571.9721401392735, 5592.161175823578, 5595.18869763882, 6385.712642931079, 6541.771038013647, 6542.509311801538, 6548.639824809586, 6597.934020659828, 5487.012337779158, 5521.254630624894, 5538.446155384429, 5578.30271216098, 5628.465295796893, 3438.707709844691, 3466.2027733621408, 3466.8959931202103, 3468.918648810744, 3491.4064609016527, 6218.253809542884, 6246.312905580383, 6260.154879091265, 6282.183316168869, 6377.5535712500605, 6248.4064013918605, 6631.469482441393, 6652.234992975773, 6681.832954176184, 6758.918573028292, 6150.969245153925, 6354.142190804275, 6408.615483070937, 6414.102025716404, 6416.165898433173, 6438.420542151229, 6508.707108180238, 6532.542722009413, 6559.409653131241, 6610.727689793583, 6544.8363790905605, 6557.508186885907, 6596.241432235162, 6607.504437167136, 6648.002799580102, 6256.593321434092, 6383.968080159755, 6391.424771770563, 6393.808185754435, 6447.0007148892, 4963.354763880672, 5011.198208286657, 5021.799128034838, 5032.874178145576, 5040.14317566022, 6270.6551139685935, 6504.447121994684, 6521.282658907421, 6679.06493090958, 6707.195136583641, 6328.082428047999, 6368.42684158428, 6437.130987316652, 6464.159659244268, 6470.511782290194, 6304.18045654062, 6343.207099112611, 6388.616682999035, 6408.0, 6444.806655800334, 6282.780102696383, 6283.8114856554375, 6284.654344010941, 6316.494680425647, 6379.649119298298, 6323.336049714898, 6341.936580634135, 6390.0, 6427.967860160669, 6526.5105117117355, 6139.171211886517, 6180.381961803783, 6238.344973777688, 6251.843703907439, 6252.468540174136], "value": 4996.7647725141605, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [878.8022694893646, 885.1812073831707, 914.798744276259, 919.3426699909563, 927.2165020557628, 18087.18607662655, 18874.716879246804, 18892.055397230135, 18944.905275473637, 19008.524786880334, 16514.174291285446, 16591.751123733153, 18303.633927321447, 18814.247430102812, 19220.038998050077, 960.0975083421596, 981.926355523335, 984.1978787288373, 987.1017374189489, 988.3575675810708, 976.2824324121765, 977.1596427072711, 979.8677178580907, 980.7597138700993, 991.1179050644931, 991.3853410885262, 1033.297357796698, 1039.8752149742043, 1041.7603951091253, 1053.0522529723235, 1023.2325755683238, 1026.4918865161756, 1033.6433930294036, 1039.0336986602495, 1039.2361614213546, 718.9748358807417, 745.571296504512, 749.5315427857589, 754.0160091081137, 755.9168491465989, 18606.813931860645, 18689.345872894384, 18855.905720471448, 19278.0, 19294.0, 20995.370138895858, 21153.04811283496, 21335.25326613561, 21603.783962160334, 21836.89081554598, 17583.472495825146, 17716.29134834605, 17855.0, 17940.461786146436, 18034.27862885483, 3701.555813302394, 3728.0, 3729.254149170177, 3735.551733791935, 3754.080250338657, 136439.0, 137735.31132344375, 140186.0, 140689.2965535176, 140780.29609851885, 16938.576535586708, 16953.576160595836, 16979.91510042442, 17038.48884533466, 17192.31230750756, 30778.230544236572, 31571.0, 31617.841910790758, 32404.189895252337, 32704.509432358533, 8417.537035463109, 8432.578371081421, 8457.830843383097, 8521.446106003214, 8540.572971351408, 3884.495015648004, 3885.592012838668, 3888.202918401721, 3926.5680775114715, 3940.8817735467983, 5302.575793936476, 5433.402325744165, 5446.754896029688, 5477.507024367826, 5530.253415788898, 942.3074040580168, 969.6170012844887, 974.8635191073255, 981.601218263971, 982.4007355513131, 682.1234713393266, 693.2443636436309, 712.8752468318023, 715.9284071592799, 717.9497435179487, 11326.546938122383, 11417.029552488131, 11596.067810812077, 11895.643130706092, 12059.939700301618, 642.7172044300493, 642.7943058221417, 643.9632192170602, 657.9144711187515, 665.0589415976436, 5139.717315547606, 5194.32473778414, 5271.42014378418, 5279.1817268323475, 5303.443138470482, 126425.0, 128518.35740821423, 129832.0, 130198.34900825625, 131248.3437582806, 105670.0, 105735.0, 107327.0, 109045.45477272721, 109257.45371273093, 99770.0, 100182.0, 100407.0, 100785.0, 102396.0, 9622.278329125204, 9757.316987810786, 9820.116189542976, 9823.115919567274, 9926.851097233692, 45004.549954501344, 45156.0, 47159.528404715515, 47354.52645473501, 47425.52574474349, 57148.0, 60114.39885601263, 60343.0, 60723.0, 62044.0, 15851.128187949773, 15980.8401915984, 16279.430219942245, 16316.347346106022, 16363.18184090767, 16171.27229274709, 16181.59546011359, 16573.751393728864, 16612.916935415247, 16630.417935372203, 15436.614084647974, 15437.922810385877, 15463.613409664847, 15626.531204063896, 15632.92183539075, 13798.586042418743, 13830.585082447542, 13904.58286251414, 13923.234222117475, 14197.858021419654, 14423.0, 14481.855181448473, 14784.556463306119, 14902.25488725581, 15035.248237588296, 11998.160128790902, 12325.630231093082, 12345.197562158432, 12449.564265250672, 12657.556985505831, 12060.879391205975, 12254.87745122573, 12291.75416491683, 12304.384780761106, 12344.197627154206], "value": 8829.595334134296, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1452.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 107.0, 117.0, 109.0, 111.0, 108.0, 113.0, 108.0, 117.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 126.0, 118.0, 116.0, 117.0, 145.0, 124.0, 124.0, 128.0, 128.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 164.0, 164.0, 169.0, 172.0, 167.0, 171.0, 163.0, 163.0], "value": 166.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 77.0, 82.0, 77.0, 82.0, 81.0, 80.0, 78.0, 73.0], "value": 78.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 82.0, 87.0, 78.0, 83.0, 79.0, 86.0, 87.0, 80.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 115.0, 116.0, 120.0, 114.0, 114.0, 115.0, 117.0], "value": 115.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 147.0, 148.0, 146.0, 155.0, 147.0, 149.0, 149.0, 148.0, 148.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 94.0, 95.0, 99.0, 94.0, 104.0, 97.0, 95.0, 95.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 51.0, 51.0, 51.0, 51.0, 64.0, 53.0, 55.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 41.0, 45.0, 41.0, 37.0, 43.0, 39.0, 38.0, 44.0, 38.0], "value": 40.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 72.0, 74.0, 74.0, 74.0, 80.0, 73.0, 77.0, 81.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 120.0, 112.0, 117.0, 125.0, 111.0, 115.0, 118.0, 113.0, 125.0], "value": 116.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 171.0, 173.0, 176.0, 177.0, 165.0, 172.0, 167.0, 173.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 64.0, 66.0, 66.0, 75.0, 64.0, 72.0, 70.0, 65.0, 64.0], "value": 67.9, "unit": "score"}]}]} 04:00:15 INFO - Return code: 0 04:00:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:00:15 INFO - # TBPL SUCCESS # 04:00:15 INFO - Running post-action listener: _package_coverage_data 04:00:15 INFO - Running post-action listener: _resource_record_post_action 04:00:15 INFO - [mozharness: 2017-09-27 11:00:15.135158Z] Finished run-tests step (success) 04:00:15 INFO - Running post-run listener: _resource_record_post_run 04:00:15 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 16961536; Write bytes: 393457664; Read time: 7200; Write time: 392988 04:00:15 INFO - TinderboxPrint: CPU usage
13.3% 04:00:15 INFO - TinderboxPrint: I/O read bytes / time
16,961,536 / 7,200 04:00:15 INFO - TinderboxPrint: I/O write bytes / time
393,457,664 / 392,988 04:00:15 INFO - TinderboxPrint: CPU idle
8,271.6 (86.7%) 04:00:15 INFO - TinderboxPrint: CPU user
1,198.8 (12.6%) 04:00:15 INFO - TinderboxPrint: Swap in / out
0 / 0 04:00:15 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 138055680; Read time: 0; Write time: 202268 04:00:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:00:15 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 14589952; Write bytes: 254115840; Read time: 6752; Write time: 188288 04:00:15 INFO - Running post-run listener: _upload_blobber_files 04:00:15 INFO - Blob upload gear active. 04:00:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:00:15 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 04:00:15 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'] 04:00:15 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 04:00:16 INFO - (blobuploader) - INFO - Open directory for files ... 04:00:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:00:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:00:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:17 INFO - (blobuploader) - INFO - Done attempting. 04:00:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:00:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:00:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:18 INFO - (blobuploader) - INFO - Done attempting. 04:00:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:00:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:00:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:25 INFO - (blobuploader) - INFO - Done attempting. 04:00:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:00:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:00:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:26 INFO - (blobuploader) - INFO - Done attempting. 04:00:26 INFO - (blobuploader) - INFO - Iteration through files over. 04:00:26 INFO - Return code: 0 04:00:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:00:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:00:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d84e49e209aed1c964ef171587969903b9543a0f509de48b7f115f3f03003997a9fe3275a9dd57dc1b4b5d587e062f820748c0d14b9fa2da3e4602ff2eb0e54a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d24f4db56cd56a45212157dfd2e0653e2fd30cf847d8794e2cdca44eac153ee38a0abbf8bc710b3b6974bb8dda368a4c66bdcc9e44070020a31dca933d5332ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9378dc7baa4adda2a2d945df6a564e722e1117a7e0672249cb97f491280a4e4da2755d703f59efb7011023e77740addcc6a32dd506da716a51a74c6a280391ac", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/190dfc1ca9c7daa04a641748d1c6e3091ad01789d01cc7c2abdb32de2cda5b9edf2204c867621721e3e01b64f3a402fbd99304cfc098c6bd4f69ebe3e602def0"} 04:00:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:00:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:00:26 INFO - Contents: 04:00:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d84e49e209aed1c964ef171587969903b9543a0f509de48b7f115f3f03003997a9fe3275a9dd57dc1b4b5d587e062f820748c0d14b9fa2da3e4602ff2eb0e54a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d24f4db56cd56a45212157dfd2e0653e2fd30cf847d8794e2cdca44eac153ee38a0abbf8bc710b3b6974bb8dda368a4c66bdcc9e44070020a31dca933d5332ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9378dc7baa4adda2a2d945df6a564e722e1117a7e0672249cb97f491280a4e4da2755d703f59efb7011023e77740addcc6a32dd506da716a51a74c6a280391ac", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/190dfc1ca9c7daa04a641748d1c6e3091ad01789d01cc7c2abdb32de2cda5b9edf2204c867621721e3e01b64f3a402fbd99304cfc098c6bd4f69ebe3e602def0"} 04:00:26 INFO - Running post-run listener: copy_logs_to_upload_dir 04:00:26 INFO - Copying logs to upload dir... 04:00:26 INFO - mkdir: /builds/slave/test/build/upload/logs 04:00:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.958657 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-27 04:00:26.352956) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 04:00:26.356356) ========= 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=fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d84e49e209aed1c964ef171587969903b9543a0f509de48b7f115f3f03003997a9fe3275a9dd57dc1b4b5d587e062f820748c0d14b9fa2da3e4602ff2eb0e54a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d24f4db56cd56a45212157dfd2e0653e2fd30cf847d8794e2cdca44eac153ee38a0abbf8bc710b3b6974bb8dda368a4c66bdcc9e44070020a31dca933d5332ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9378dc7baa4adda2a2d945df6a564e722e1117a7e0672249cb97f491280a4e4da2755d703f59efb7011023e77740addcc6a32dd506da716a51a74c6a280391ac", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/190dfc1ca9c7daa04a641748d1c6e3091ad01789d01cc7c2abdb32de2cda5b9edf2204c867621721e3e01b64f3a402fbd99304cfc098c6bd4f69ebe3e602def0"} build_url:https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011184 build_url: 'https://queue.taskcluster.net/v1/task/Epxi0CdYQW63JHszs_NJbg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d84e49e209aed1c964ef171587969903b9543a0f509de48b7f115f3f03003997a9fe3275a9dd57dc1b4b5d587e062f820748c0d14b9fa2da3e4602ff2eb0e54a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d24f4db56cd56a45212157dfd2e0653e2fd30cf847d8794e2cdca44eac153ee38a0abbf8bc710b3b6974bb8dda368a4c66bdcc9e44070020a31dca933d5332ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9378dc7baa4adda2a2d945df6a564e722e1117a7e0672249cb97f491280a4e4da2755d703f59efb7011023e77740addcc6a32dd506da716a51a74c6a280391ac", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/190dfc1ca9c7daa04a641748d1c6e3091ad01789d01cc7c2abdb32de2cda5b9edf2204c867621721e3e01b64f3a402fbd99304cfc098c6bd4f69ebe3e602def0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 04:00:26.387756) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:00:26.388117) ========= 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=fa44defc139c4ba8574fe040000001db-1506508603.29707-424457139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004555 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:00:26.470838) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 04:00:26.474160) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 04:00:26.474471) ========= ========= Total master_lag: 0.25 =========