builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-028 starttime: 1506163024.64 results: success (0) revision: 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:04.638836) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:04.639284) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:04.639555) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:04.658843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:04.659238) ========= 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=836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 03:37:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.8M=0s 2017-09-23 03:37:04 (46.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076420 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:04.755158) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:04.755527) ========= 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=836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016268 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:04.809064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-23 03:37:04.811973) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 --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 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 --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=836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 03:37:04,860 truncating revision to first 12 chars 2017-09-23 03:37:04,860 Setting DEBUG logging. 2017-09-23 03:37:04,860 attempt 1/10 2017-09-23 03:37:04,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c3a15583223?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 03:37:05,584 unpacking tar archive at: mozilla-central-8c3a15583223/testing/mozharness/ program finished with exit code 0 elapsedTime=1.057954 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-23 03:37:06.104584) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:06.104988) ========= script_repo_revision: 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:06.110645) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:06.110982) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 03:37:06.128048) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-23 03:37:06.128507) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:37:06 INFO - MultiFileLogger online at 20170923 03:37:06 in /builds/slave/test 03:37:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:37:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:37:06 INFO - {'append_to_log': False, 03:37:06 INFO - 'base_work_dir': '/builds/slave/test', 03:37:06 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:37:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:37:06 INFO - 'branch': 'Firefox-Non-PGO', 03:37:06 INFO - 'buildbot_json_path': 'buildprops.json', 03:37:06 INFO - 'code_coverage': False, 03:37:06 INFO - 'config_files': ('talos/linux_config.py',), 03:37:06 INFO - 'default_actions': ('clobber', 03:37:06 INFO - 'read-buildbot-config', 03:37:06 INFO - 'download-and-extract', 03:37:06 INFO - 'populate-webroot', 03:37:06 INFO - 'create-virtualenv', 03:37:06 INFO - 'install', 03:37:06 INFO - 'setup-mitmproxy', 03:37:06 INFO - 'run-tests'), 03:37:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:37:06 INFO - 'disable_ccov_upload': False, 03:37:06 INFO - 'disable_stylo': False, 03:37:06 INFO - 'download_minidump_stackwalk': True, 03:37:06 INFO - 'download_symbols': 'ondemand', 03:37:06 INFO - 'enable_stylo': False, 03:37:06 INFO - 'enable_webrender': False, 03:37:06 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:37:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:37:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:37:06 INFO - 'gecko_profile': False, 03:37:06 INFO - 'gecko_profile_interval': 0, 03:37:06 INFO - 'installer_path': 'installer.exe', 03:37:06 INFO - 'log_level': 'info', 03:37:06 INFO - 'log_name': 'talos', 03:37:06 INFO - 'log_to_console': True, 03:37:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:37:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:37:06 INFO - 'opt_config_files': (), 03:37:06 INFO - 'pip_index': False, 03:37:06 INFO - 'suite': 'dromaeojs-e10s', 03:37:06 INFO - 'system_bits': '32', 03:37:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:37:06 INFO - 'title': 'talos-linux64-ix-028', 03:37:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:37:06 INFO - 'use_talos_json': True, 03:37:06 INFO - 'verify': 'False', 03:37:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:37:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:37:06 INFO - 'work_dir': 'build'} 03:37:06 INFO - [mozharness: 2017-09-23 10:37:06.319464Z] Running clobber step. 03:37:06 INFO - Running pre-action listener: _resource_record_pre_action 03:37:06 INFO - Running main action method: clobber 03:37:06 INFO - rmtree: /builds/slave/test/build 03:37:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:37:07 INFO - Running post-action listener: _resource_record_post_action 03:37:07 INFO - [mozharness: 2017-09-23 10:37:07.294394Z] Finished clobber step (success) 03:37:07 INFO - [mozharness: 2017-09-23 10:37:07.294491Z] Running read-buildbot-config step. 03:37:07 INFO - Running pre-action listener: _resource_record_pre_action 03:37:07 INFO - Running main action method: read_buildbot_config 03:37:07 INFO - Using buildbot properties: 03:37:07 INFO - { 03:37:07 INFO - "project": "", 03:37:07 INFO - "product": "firefox", 03:37:07 INFO - "who": "archaeopteryx@coole-files.de", 03:37:07 INFO - "installer_path": "public/build/target.tar.bz2", 03:37:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:37:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:37:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:37:07 INFO - "stage_platform": "linux64", 03:37:07 INFO - "basedir": "/builds/slave/test", 03:37:07 INFO - "buildnumber": 856, 03:37:07 INFO - "platform": "ubuntu64_hw", 03:37:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:37:07 INFO - "slavebuilddir": "test", 03:37:07 INFO - "taskId": "XTpGSAO_TM6yg6U8yGIZJg", 03:37:07 INFO - "branch": "mozilla-central", 03:37:07 INFO - "script_repo_revision": "production", 03:37:07 INFO - "revision": "8c3a1558322331c1fab9d1d2d7a94ef69f2a9212", 03:37:07 INFO - "slavename": "talos-linux64-ix-028", 03:37:07 INFO - "repo_path": "mozilla-central" 03:37:07 INFO - } 03:37:07 INFO - Finding installer, test and symbols from parent task. 03:37:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XTpGSAO_TM6yg6U8yGIZJg'}, attempt #1 03:37:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XTpGSAO_TM6yg6U8yGIZJg'}, attempt #1 03:37:08 INFO - Task dependencies: O6UesEjKQLKs2XYkonDMAA 03:37:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA'}, attempt #1 03:37:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.tar.bz2 03:37:11 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.test_packages.json 03:37:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.crashreporter-symbols.zip 03:37:11 INFO - Running post-action listener: _resource_record_post_action 03:37:11 INFO - [mozharness: 2017-09-23 10:37:11.642500Z] Finished read-buildbot-config step (success) 03:37:11 INFO - [mozharness: 2017-09-23 10:37:11.642723Z] Running download-and-extract step. 03:37:11 INFO - Running pre-action listener: _resource_record_pre_action 03:37:11 INFO - Running main action method: download_and_extract 03:37:11 INFO - mkdir: /builds/slave/test/build/tests 03:37: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:37:11 INFO - trying https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.test_packages.json 03:37:11 INFO - Downloading https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:37:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:37:12 INFO - Downloaded 989 bytes. 03:37:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:37:12 INFO - Using the following test package requirements: 03:37:12 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:37:12 INFO - u'common': [u'target.common.tests.zip'], 03:37:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:37:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:37:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:37:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:37:12 INFO - u'mozbase': [u'target.common.tests.zip'], 03:37:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:37:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:37:12 INFO - u'web-platform': [u'target.common.tests.zip', 03:37:12 INFO - u'target.web-platform.tests.tar.gz'], 03:37:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:37:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:37:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.common.tests.zip 03:37:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:37:12 INFO - Fetch https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.common.tests.zip into memory 03:37:13 INFO - Content-Length response header: 54943889 03:37:13 INFO - Bytes received: 54943889 03:37:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.talos.tests.zip 03:37:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:37:24 INFO - Fetch https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.talos.tests.zip into memory 03:37:25 INFO - Content-Length response header: 14513823 03:37:25 INFO - Bytes received: 14513823 03:37:25 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:37:25 INFO - trying https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.tar.bz2 03:37:25 INFO - Downloading https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:37:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:37:26 INFO - Downloaded 61717343 bytes. 03:37:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.tar.bz2 03:37:26 INFO - mkdir: /builds/slave/test/properties 03:37:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:37:26 INFO - Writing to file /builds/slave/test/properties/build_url 03:37:26 INFO - Contents: 03:37:26 INFO - build_url:https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.tar.bz2 03:37:26 INFO - Running post-action listener: _resource_record_post_action 03:37:26 INFO - Running post-action listener: find_tests_for_verification 03:37:26 INFO - Running post-action listener: set_extra_try_arguments 03:37:26 INFO - [mozharness: 2017-09-23 10:37:26.448261Z] Finished download-and-extract step (success) 03:37:26 INFO - [mozharness: 2017-09-23 10:37:26.448360Z] Running populate-webroot step. 03:37:26 INFO - Running pre-action listener: _resource_record_pre_action 03:37:26 INFO - Running main action method: populate_webroot 03:37:26 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:37:26 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['tresize', 03:37:26 INFO - 'tcanvasmark']}, 03:37:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:37:26 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['dromaeo_css', 03:37:26 INFO - 'kraken']}, 03:37:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:37:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:26 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:26 INFO - 'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:37:26 INFO - 'tests': ['damp', 'tps']}, 03:37:26 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:26 INFO - 'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['damp', 'tps']}, 03:37:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:37:26 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['dromaeo_dom']}, 03:37:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:37:26 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['basic_compositor_video', 03:37:26 INFO - 'glvideo']}, 03:37:26 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:37:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:37:26 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:26 INFO - 'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['ts_paint_webext', 03:37:26 INFO - 'tp5o_webext']}, 03:37:26 INFO - 'other-e10s': {'tests': ['a11yr', 03:37:26 INFO - 'ts_paint', 03:37:26 INFO - 'tpaint', 03:37:26 INFO - 'sessionrestore', 03:37:26 INFO - 'sessionrestore_many_windows', 03:37:26 INFO - 'sessionrestore_no_auto_restore', 03:37:26 INFO - 'tabpaint']}, 03:37:26 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['a11yr', 03:37:26 INFO - 'ts_paint', 03:37:26 INFO - 'tpaint', 03:37:26 INFO - 'sessionrestore', 03:37:26 INFO - 'sessionrestore_many_windows', 03:37:26 INFO - 'sessionrestore_no_auto_restore', 03:37:26 INFO - 'tabpaint']}, 03:37:26 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 03:37:26 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:37:26 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['perf_reftest_singletons']}, 03:37:26 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['perf_reftest']}, 03:37:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:37:26 INFO - 'tsvgr_opacity', 03:37:26 INFO - 'tart', 03:37:26 INFO - 'tscrollx', 03:37:26 INFO - 'tsvg_static']}, 03:37:26 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['tsvgx', 03:37:26 INFO - 'tsvgr_opacity', 03:37:26 INFO - 'tart', 03:37:26 INFO - 'tscrollx', 03:37:26 INFO - 'tsvg_static']}, 03:37:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:37:26 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:26 INFO - 'talos_options': ['--disable-stylo'], 03:37:26 INFO - 'tests': ['tp5o']}, 03:37:26 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:37:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:37:26 INFO - 'talos_options': ['--mitmproxy', 03:37:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:37:26 INFO - '--firstNonBlankPaint'], 03:37:26 INFO - 'tests': ['tp6_google', 03:37:26 INFO - 'tp6_youtube', 03:37:26 INFO - 'tp6_amazon', 03:37:26 INFO - 'tp6_facebook']}, 03:37:26 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:37:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:37:26 INFO - 'talos_options': ['--disable-stylo', 03:37:26 INFO - '--mitmproxy', 03:37:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:37:26 INFO - '--firstNonBlankPaint'], 03:37:26 INFO - 'tests': ['tp6_google', 03:37:26 INFO - 'tp6_youtube', 03:37:26 INFO - 'tp6_amazon', 03:37:26 INFO - 'tp6_facebook']}, 03:37:26 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:37:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:37:26 INFO - 'talos_options': ['--stylo-threads=1', 03:37:26 INFO - '--mitmproxy', 03:37:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:37:26 INFO - '--firstNonBlankPaint'], 03:37:26 INFO - 'tests': ['tp6_google', 03:37:26 INFO - 'tp6_youtube', 03:37:26 INFO - 'tp6_amazon', 03:37:26 INFO - 'tp6_facebook']}, 03:37:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:37:26 INFO - 'talos_options': ['--xperf_path', 03:37:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:26 INFO - 'tests': ['tp5n']}, 03:37:26 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:26 INFO - 'talos_options': ['--disable-stylo', 03:37:26 INFO - '--xperf_path', 03:37:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:26 INFO - 'tests': ['tp5n']}}} 03:37:26 INFO - Running post-action listener: _resource_record_post_action 03:37:26 INFO - [mozharness: 2017-09-23 10:37:26.463755Z] Finished populate-webroot step (success) 03:37:26 INFO - [mozharness: 2017-09-23 10:37:26.463845Z] Running create-virtualenv step. 03:37:26 INFO - Running pre-action listener: _resource_record_pre_action 03:37:26 INFO - Running main action method: create_virtualenv 03:37:26 INFO - Creating virtualenv /builds/slave/test/build/venv 03:37:26 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:37:26 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:37:26 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:37:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:37:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:37:27 INFO - Installing distribute.............................................................................................................................................................................................done. 03:37:27 INFO - Installing pip...............done. 03:37:27 INFO - Return code: 0 03:37:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:37:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:37:27 INFO - Reading from file tmpfile_stdout 03:37:27 INFO - Output received: 03:37:27 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:37:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:27 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:27 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:37:27 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:37:27 INFO - Using env: {'DISPLAY': ':0', 03:37:27 INFO - 'HOME': '/home/cltbld', 03:37:27 INFO - 'LANG': 'en_US.UTF-8', 03:37:27 INFO - 'LANGUAGE': 'en_US:en', 03:37:27 INFO - 'LOGNAME': 'cltbld', 03:37:27 INFO - 'MAIL': '/var/mail/cltbld', 03:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:27 INFO - 'MOZ_NO_REMOTE': '1', 03:37:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:27 INFO - 'NO_EM_RESTART': '1', 03:37:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:27 INFO - 'PWD': '/builds/slave/test', 03:37:27 INFO - 'SHELL': '/bin/bash', 03:37:27 INFO - 'SHLVL': '1', 03:37:27 INFO - 'TERM': 'linux', 03:37:27 INFO - 'TMOUT': '86400', 03:37:27 INFO - 'USER': 'cltbld', 03:37:27 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:27 INFO - '_': '/tools/buildbot/bin/python'} 03:37:28 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:37:28 INFO - Downloading/unpacking pip>=1.5 03:37:28 INFO - Running setup.py egg_info for package pip 03:37:28 INFO - warning: no files found matching 'pip/cacert.pem' 03:37:28 INFO - warning: no files found matching '*.html' under directory 'docs' 03:37:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:37:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:37:28 INFO - Installing collected packages: pip 03:37:28 INFO - Found existing installation: pip 1.1 03:37:28 INFO - Uninstalling pip: 03:37:28 INFO - Successfully uninstalled pip 03:37:28 INFO - Running setup.py install for pip 03:37:28 INFO - warning: no files found matching 'pip/cacert.pem' 03:37:28 INFO - warning: no files found matching '*.html' under directory 'docs' 03:37:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:37:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:37:28 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:37:28 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:37:28 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:37:28 INFO - Successfully installed pip 03:37:28 INFO - Cleaning up... 03:37:28 INFO - Return code: 0 03:37:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:28 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:28 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:37:28 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:37:28 INFO - Using env: {'DISPLAY': ':0', 03:37:28 INFO - 'HOME': '/home/cltbld', 03:37:28 INFO - 'LANG': 'en_US.UTF-8', 03:37:28 INFO - 'LANGUAGE': 'en_US:en', 03:37:28 INFO - 'LOGNAME': 'cltbld', 03:37:28 INFO - 'MAIL': '/var/mail/cltbld', 03:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:28 INFO - 'MOZ_NO_REMOTE': '1', 03:37:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:28 INFO - 'NO_EM_RESTART': '1', 03:37:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:28 INFO - 'PWD': '/builds/slave/test', 03:37:28 INFO - 'SHELL': '/bin/bash', 03:37:28 INFO - 'SHLVL': '1', 03:37:28 INFO - 'TERM': 'linux', 03:37:28 INFO - 'TMOUT': '86400', 03:37:28 INFO - 'USER': 'cltbld', 03:37:28 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:28 INFO - '_': '/tools/buildbot/bin/python'} 03:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:29 INFO - Downloading/unpacking psutil>=3.1.1 03:37:29 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:37:29 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:37:29 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:37:29 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:37:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:37:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:30 INFO - Installing collected packages: psutil 03:37:30 INFO - Running setup.py install for psutil 03:37:30 INFO - building 'psutil._psutil_linux' extension 03:37:30 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:37:30 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:37:30 INFO - building 'psutil._psutil_posix' extension 03:37:30 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:37:30 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:37:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:31 INFO - Successfully installed psutil 03:37:31 INFO - Cleaning up... 03:37:32 INFO - Return code: 0 03:37:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:37:32 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:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:32 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:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:32 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:32 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:37:32 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:37:32 INFO - Using env: {'DISPLAY': ':0', 03:37:32 INFO - 'HOME': '/home/cltbld', 03:37:32 INFO - 'LANG': 'en_US.UTF-8', 03:37:32 INFO - 'LANGUAGE': 'en_US:en', 03:37:32 INFO - 'LOGNAME': 'cltbld', 03:37:32 INFO - 'MAIL': '/var/mail/cltbld', 03:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:32 INFO - 'MOZ_NO_REMOTE': '1', 03:37:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:32 INFO - 'NO_EM_RESTART': '1', 03:37:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:32 INFO - 'PWD': '/builds/slave/test', 03:37:32 INFO - 'SHELL': '/bin/bash', 03:37:32 INFO - 'SHLVL': '1', 03:37:32 INFO - 'TERM': 'linux', 03:37:32 INFO - 'TMOUT': '86400', 03:37:32 INFO - 'USER': 'cltbld', 03:37:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:32 INFO - '_': '/tools/buildbot/bin/python'} 03:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:37:32 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:37:32 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:37:32 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:37:32 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:37:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:37:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:37:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:37:33 INFO - Installing collected packages: mozsystemmonitor 03:37:33 INFO - Running setup.py install for mozsystemmonitor 03:37:33 INFO - Successfully installed mozsystemmonitor 03:37:33 INFO - Cleaning up... 03:37:33 INFO - Return code: 0 03:37:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:33 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:33 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:37:33 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:37:33 INFO - Using env: {'DISPLAY': ':0', 03:37:33 INFO - 'HOME': '/home/cltbld', 03:37:33 INFO - 'LANG': 'en_US.UTF-8', 03:37:33 INFO - 'LANGUAGE': 'en_US:en', 03:37:33 INFO - 'LOGNAME': 'cltbld', 03:37:33 INFO - 'MAIL': '/var/mail/cltbld', 03:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:33 INFO - 'MOZ_NO_REMOTE': '1', 03:37:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:33 INFO - 'NO_EM_RESTART': '1', 03:37:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:33 INFO - 'PWD': '/builds/slave/test', 03:37:33 INFO - 'SHELL': '/bin/bash', 03:37:33 INFO - 'SHLVL': '1', 03:37:33 INFO - 'TERM': 'linux', 03:37:33 INFO - 'TMOUT': '86400', 03:37:33 INFO - 'USER': 'cltbld', 03:37:33 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:33 INFO - '_': '/tools/buildbot/bin/python'} 03:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:33 INFO - Downloading/unpacking jsonschema==2.5.1 03:37:33 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:37:33 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:37:33 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:37:33 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:37:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:37:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:37:34 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:37:34 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:37:34 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:37:34 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:37:35 INFO - Downloading functools32-3.2.3-2.tar.gz 03:37:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:37:35 INFO - warning: no files found matching '*.txt' 03:37:35 INFO - no previously-included directories found matching 'build' 03:37:35 INFO - no previously-included directories found matching 'dist' 03:37:35 INFO - no previously-included directories found matching '.git*' 03:37:35 INFO - Installing collected packages: jsonschema, functools32 03:37:35 INFO - Running setup.py install for functools32 03:37:35 INFO - warning: no files found matching '*.txt' 03:37:35 INFO - no previously-included directories found matching 'build' 03:37:35 INFO - no previously-included directories found matching 'dist' 03:37:35 INFO - no previously-included directories found matching '.git*' 03:37:35 INFO - Successfully installed jsonschema functools32 03:37:35 INFO - Cleaning up... 03:37:35 INFO - Return code: 0 03:37:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:37:35 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:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:35 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:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:35 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:35 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:37:35 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:37:35 INFO - Using env: {'DISPLAY': ':0', 03:37:35 INFO - 'HOME': '/home/cltbld', 03:37:35 INFO - 'LANG': 'en_US.UTF-8', 03:37:35 INFO - 'LANGUAGE': 'en_US:en', 03:37:35 INFO - 'LOGNAME': 'cltbld', 03:37:35 INFO - 'MAIL': '/var/mail/cltbld', 03:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:35 INFO - 'MOZ_NO_REMOTE': '1', 03:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:35 INFO - 'NO_EM_RESTART': '1', 03:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:35 INFO - 'PWD': '/builds/slave/test', 03:37:35 INFO - 'SHELL': '/bin/bash', 03:37:35 INFO - 'SHLVL': '1', 03:37:35 INFO - 'TERM': 'linux', 03:37:35 INFO - 'TMOUT': '86400', 03:37:35 INFO - 'USER': 'cltbld', 03:37:35 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:35 INFO - '_': '/tools/buildbot/bin/python'} 03:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:37:35 INFO - Cleaning up... 03:37:35 INFO - Return code: 0 03:37:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:37:35 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:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:35 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:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:35 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:35 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:37:35 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:37:35 INFO - Using env: {'DISPLAY': ':0', 03:37:35 INFO - 'HOME': '/home/cltbld', 03:37:35 INFO - 'LANG': 'en_US.UTF-8', 03:37:35 INFO - 'LANGUAGE': 'en_US:en', 03:37:35 INFO - 'LOGNAME': 'cltbld', 03:37:35 INFO - 'MAIL': '/var/mail/cltbld', 03:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:35 INFO - 'MOZ_NO_REMOTE': '1', 03:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:35 INFO - 'NO_EM_RESTART': '1', 03:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:35 INFO - 'PWD': '/builds/slave/test', 03:37:35 INFO - 'SHELL': '/bin/bash', 03:37:35 INFO - 'SHLVL': '1', 03:37:35 INFO - 'TERM': 'linux', 03:37:35 INFO - 'TMOUT': '86400', 03:37:35 INFO - 'USER': 'cltbld', 03:37:35 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:35 INFO - '_': '/tools/buildbot/bin/python'} 03:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:35 INFO - Downloading/unpacking blobuploader==1.2.4 03:37:35 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:37:35 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:37:35 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:37:35 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:37:36 INFO - Downloading blobuploader-1.2.4.tar.gz 03:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:37:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:37:36 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:37:36 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:37:36 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:37:36 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:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:37:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:37:37 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:37:37 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:37:37 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:37:37 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:37:37 INFO - Downloading docopt-0.6.1.tar.gz 03:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:37:37 INFO - Installing collected packages: blobuploader, requests, docopt 03:37:37 INFO - Running setup.py install for blobuploader 03:37:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:37:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:37:37 INFO - Running setup.py install for requests 03:37:37 INFO - Running setup.py install for docopt 03:37:37 INFO - Successfully installed blobuploader requests docopt 03:37:37 INFO - Cleaning up... 03:37:38 INFO - Return code: 0 03:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:38 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:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:38 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:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:38 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:38 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:37:38 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:37:38 INFO - Using env: {'DISPLAY': ':0', 03:37:38 INFO - 'HOME': '/home/cltbld', 03:37:38 INFO - 'LANG': 'en_US.UTF-8', 03:37:38 INFO - 'LANGUAGE': 'en_US:en', 03:37:38 INFO - 'LOGNAME': 'cltbld', 03:37:38 INFO - 'MAIL': '/var/mail/cltbld', 03:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:38 INFO - 'MOZ_NO_REMOTE': '1', 03:37:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:38 INFO - 'NO_EM_RESTART': '1', 03:37:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:38 INFO - 'PWD': '/builds/slave/test', 03:37:38 INFO - 'SHELL': '/bin/bash', 03:37:38 INFO - 'SHLVL': '1', 03:37:38 INFO - 'TERM': 'linux', 03:37:38 INFO - 'TMOUT': '86400', 03:37:38 INFO - 'USER': 'cltbld', 03:37:38 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:38 INFO - '_': '/tools/buildbot/bin/python'} 03:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:37:38 INFO - Running setup.py (path:/tmp/pip-cGtlQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:37:38 INFO - Running setup.py (path:/tmp/pip-wyKAwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:37:38 INFO - Running setup.py (path:/tmp/pip-XdQRNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:37:38 INFO - Running setup.py (path:/tmp/pip-_3u6dW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:37:38 INFO - Running setup.py (path:/tmp/pip-r2gvG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:37:38 INFO - Running setup.py (path:/tmp/pip-1Fheuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:37:38 INFO - Running setup.py (path:/tmp/pip-Vvy5MV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:37:38 INFO - Running setup.py (path:/tmp/pip-NKAJ3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:37:38 INFO - Running setup.py (path:/tmp/pip-duOToR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:37:38 INFO - Running setup.py (path:/tmp/pip-Yta07a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:37:38 INFO - Running setup.py (path:/tmp/pip-1wknB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:37:39 INFO - Running setup.py (path:/tmp/pip-4upJVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:37:39 INFO - Running setup.py (path:/tmp/pip-yW8g3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:37:39 INFO - Running setup.py (path:/tmp/pip-ML0Ccv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:39 INFO - Running setup.py (path:/tmp/pip-9FuIfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:37:39 INFO - Running setup.py (path:/tmp/pip-USHnYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:37:39 INFO - Running setup.py (path:/tmp/pip-mcmBYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:37:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:37:39 INFO - Running setup.py install for manifestparser 03:37:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:37:39 INFO - Running setup.py install for mozcrash 03:37:39 INFO - Running setup.py install for mozdebug 03:37:39 INFO - Running setup.py install for mozdevice 03:37:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:37:39 INFO - Running setup.py install for mozfile 03:37:39 INFO - Running setup.py install for mozhttpd 03:37:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Running setup.py install for mozinfo 03:37:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Running setup.py install for mozInstall 03:37:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Running setup.py install for mozleak 03:37:40 INFO - Running setup.py install for mozlog 03:37:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Running setup.py install for moznetwork 03:37:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Running setup.py install for mozprocess 03:37:40 INFO - Running setup.py install for mozprofile 03:37:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Running setup.py install for mozrunner 03:37:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:37:40 INFO - Running setup.py install for mozscreenshot 03:37:40 INFO - Running setup.py install for moztest 03:37:41 INFO - Running setup.py install for mozversion 03:37:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:37:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:37:41 INFO - Cleaning up... 03:37:41 INFO - Return code: 0 03:37:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:41 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:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:41 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:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:41 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:41 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:37:41 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:37:41 INFO - Using env: {'DISPLAY': ':0', 03:37:41 INFO - 'HOME': '/home/cltbld', 03:37:41 INFO - 'LANG': 'en_US.UTF-8', 03:37:41 INFO - 'LANGUAGE': 'en_US:en', 03:37:41 INFO - 'LOGNAME': 'cltbld', 03:37:41 INFO - 'MAIL': '/var/mail/cltbld', 03:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:41 INFO - 'MOZ_NO_REMOTE': '1', 03:37:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:41 INFO - 'NO_EM_RESTART': '1', 03:37:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:41 INFO - 'PWD': '/builds/slave/test', 03:37:41 INFO - 'SHELL': '/bin/bash', 03:37:41 INFO - 'SHLVL': '1', 03:37:41 INFO - 'TERM': 'linux', 03:37:41 INFO - 'TMOUT': '86400', 03:37:41 INFO - 'USER': 'cltbld', 03:37:41 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:41 INFO - '_': '/tools/buildbot/bin/python'} 03:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:37:41 INFO - Running setup.py (path:/tmp/pip-47Bhhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:37:41 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:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:37:41 INFO - Running setup.py (path:/tmp/pip-fUD3bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:37:41 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:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:37:41 INFO - Running setup.py (path:/tmp/pip-tM58fS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:37:41 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:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:37:41 INFO - Running setup.py (path:/tmp/pip-8Aj1D2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:37:41 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:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:37:41 INFO - Running setup.py (path:/tmp/pip-dvXvQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:37:41 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:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:37:41 INFO - Running setup.py (path:/tmp/pip-S44E9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:37:41 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:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:37:41 INFO - Running setup.py (path:/tmp/pip-xj6Kn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:37:41 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:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:37:41 INFO - Running setup.py (path:/tmp/pip-PNY7YF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:37:41 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:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:37:41 INFO - Running setup.py (path:/tmp/pip-KXkMIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:37:42 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:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:37:42 INFO - Running setup.py (path:/tmp/pip-3ssaZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:37:42 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:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:37:42 INFO - Running setup.py (path:/tmp/pip-bIPtHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:37:42 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:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:37:42 INFO - Running setup.py (path:/tmp/pip-S8uXdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:37:42 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:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:37:42 INFO - Running setup.py (path:/tmp/pip-5EsOJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:37:42 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:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:37:42 INFO - Running setup.py (path:/tmp/pip-sG2sYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:37:42 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:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:42 INFO - Running setup.py (path:/tmp/pip-jGukQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:42 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:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:37:42 INFO - Running setup.py (path:/tmp/pip-hbUZrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:37:42 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:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:37:42 INFO - Running setup.py (path:/tmp/pip-E5LfVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:37:42 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:37:42 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:37:42 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:37:42 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:37:42 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:37:42 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:37:42 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:37:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:37:42 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:37:42 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:37:42 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:37:42 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:37:43 INFO - Downloading blessings-1.6.tar.gz 03:37:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:37:43 INFO - Installing collected packages: blessings 03:37:43 INFO - Running setup.py install for blessings 03:37:43 INFO - Successfully installed blessings 03:37:43 INFO - Cleaning up... 03:37:44 INFO - Return code: 0 03:37:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:37:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:37:44 INFO - Reading from file tmpfile_stdout 03:37:44 INFO - Current package versions: 03:37:44 INFO - argparse == 1.2.1 03:37:44 INFO - blessings == 1.6 03:37:44 INFO - blobuploader == 1.2.4 03:37:44 INFO - docopt == 0.6.1 03:37:44 INFO - functools32 == 3.2.3-2 03:37:44 INFO - jsonschema == 2.5.1 03:37:44 INFO - manifestparser == 1.1 03:37:44 INFO - mozInstall == 1.13 03:37:44 INFO - mozcrash == 1.0 03:37:44 INFO - mozdebug == 0.1 03:37:44 INFO - mozdevice == 0.51 03:37:44 INFO - mozfile == 1.2 03:37:44 INFO - mozhttpd == 0.7 03:37:44 INFO - mozinfo == 0.10 03:37:44 INFO - mozleak == 0.1 03:37:44 INFO - mozlog == 3.5 03:37:44 INFO - moznetwork == 0.27 03:37:44 INFO - mozprocess == 0.25 03:37:44 INFO - mozprofile == 0.28 03:37:44 INFO - mozrunner == 6.13 03:37:44 INFO - mozscreenshot == 0.1 03:37:44 INFO - mozsystemmonitor == 0.3 03:37:44 INFO - moztest == 0.8 03:37:44 INFO - mozversion == 1.4 03:37:44 INFO - psutil == 3.1.1 03:37:44 INFO - requests == 1.2.3 03:37:44 INFO - wsgiref == 0.1.2 03:37:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:44 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:44 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:37:44 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:37:44 INFO - Using env: {'DISPLAY': ':0', 03:37:44 INFO - 'HOME': '/home/cltbld', 03:37:44 INFO - 'LANG': 'en_US.UTF-8', 03:37:44 INFO - 'LANGUAGE': 'en_US:en', 03:37:44 INFO - 'LOGNAME': 'cltbld', 03:37:44 INFO - 'MAIL': '/var/mail/cltbld', 03:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:44 INFO - 'MOZ_NO_REMOTE': '1', 03:37:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:44 INFO - 'NO_EM_RESTART': '1', 03:37:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:44 INFO - 'PWD': '/builds/slave/test', 03:37:44 INFO - 'SHELL': '/bin/bash', 03:37:44 INFO - 'SHLVL': '1', 03:37:44 INFO - 'TERM': 'linux', 03:37:44 INFO - 'TMOUT': '86400', 03:37:44 INFO - 'USER': 'cltbld', 03:37:44 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:44 INFO - '_': '/tools/buildbot/bin/python'} 03:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:44 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:37:44 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:37:44 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:37:44 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:37:44 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:37:44 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:37:44 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:37:44 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:37:44 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:37:44 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:37:44 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:37:44 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:37:44 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:37:44 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:37:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:37:45 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:37:45 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:37:45 INFO - Installing collected packages: simplejson 03:37:45 INFO - Running setup.py install for simplejson 03:37:45 INFO - building 'simplejson._speedups' extension 03:37:45 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:37:46 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:37:46 INFO - Successfully installed simplejson 03:37:46 INFO - Cleaning up... 03:37:46 INFO - Return code: 0 03:37:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:37:46 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:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:46 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:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:46 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 0x1dfd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', '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:37:46 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:37:46 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:37:46 INFO - Using env: {'DISPLAY': ':0', 03:37:46 INFO - 'HOME': '/home/cltbld', 03:37:46 INFO - 'LANG': 'en_US.UTF-8', 03:37:46 INFO - 'LANGUAGE': 'en_US:en', 03:37:46 INFO - 'LOGNAME': 'cltbld', 03:37:46 INFO - 'MAIL': '/var/mail/cltbld', 03:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:46 INFO - 'MOZ_NO_REMOTE': '1', 03:37:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:46 INFO - 'NO_EM_RESTART': '1', 03:37:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:46 INFO - 'PWD': '/builds/slave/test', 03:37:46 INFO - 'SHELL': '/bin/bash', 03:37:46 INFO - 'SHLVL': '1', 03:37:46 INFO - 'TERM': 'linux', 03:37:46 INFO - 'TMOUT': '86400', 03:37:46 INFO - 'USER': 'cltbld', 03:37:46 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:46 INFO - '_': '/tools/buildbot/bin/python'} 03:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:37:46 INFO - Cleaning up... 03:37:46 INFO - Return code: 0 03:37:46 INFO - Running post-action listener: _resource_record_post_action 03:37:46 INFO - Running post-action listener: _start_resource_monitoring 03:37:46 INFO - Starting resource monitoring. 03:37:46 INFO - [mozharness: 2017-09-23 10:37:46.926721Z] Finished create-virtualenv step (success) 03:37:46 INFO - [mozharness: 2017-09-23 10:37:46.927186Z] Running install step. 03:37:46 INFO - Running pre-action listener: _resource_record_pre_action 03:37:46 INFO - Running main action method: install 03:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:37:47 INFO - Reading from file tmpfile_stdout 03:37:47 INFO - Detecting whether we're running mozinstall >=1.0... 03:37:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:37:47 INFO - Reading from file tmpfile_stdout 03:37:47 INFO - Output received: 03:37:47 INFO - Usage: mozinstall [options] installer 03:37:47 INFO - Options: 03:37:47 INFO - -h, --help show this help message and exit 03:37:47 INFO - -d DEST, --destination=DEST 03:37:47 INFO - Directory to install application into. [default: 03:37:47 INFO - "/builds/slave/test"] 03:37:47 INFO - --app=APP Application being installed. [default: firefox] 03:37:47 INFO - mkdir: /builds/slave/test/build/application 03:37:47 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:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:38:05 INFO - Reading from file tmpfile_stdout 03:38:05 INFO - Output received: 03:38:05 INFO - /builds/slave/test/build/application/firefox/firefox 03:38:05 INFO - Running post-action listener: _resource_record_post_action 03:38:05 INFO - [mozharness: 2017-09-23 10:38:05.079068Z] Finished install step (success) 03:38:05 INFO - [mozharness: 2017-09-23 10:38:05.079323Z] Running setup-mitmproxy step. 03:38:05 INFO - Running pre-action listener: _resource_record_pre_action 03:38:05 INFO - Running main action method: setup_mitmproxy 03:38:05 INFO - Skipping: mitmproxy is not required 03:38:05 INFO - Running post-action listener: _resource_record_post_action 03:38:05 INFO - [mozharness: 2017-09-23 10:38:05.080458Z] Finished setup-mitmproxy step (success) 03:38:05 INFO - [mozharness: 2017-09-23 10:38:05.080679Z] Running run-tests step. 03:38:05 INFO - Running pre-action listener: _resource_record_pre_action 03:38:05 INFO - Running pre-action listener: _set_gcov_prefix 03:38:05 INFO - Running main action method: run_tests 03:38:05 WARNING - Try message not found. 03:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:38:05 INFO - Python 2.7.3 03:38:05 INFO - Return code: 0 03:38:05 INFO - grabbing minidump binary from tooltool 03:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:05 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 0x2167d60>, '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 0x2168a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2168ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:38:05 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:38:05 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:38:05 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:38:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:38:05 INFO - Return code: 0 03:38:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:38:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:38:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:38:05 INFO - ENV: RUST_BACKTRACE is now full 03:38:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:38:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-028 --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:38:05 INFO - Using env: {'DISPLAY': ':0', 03:38:05 INFO - 'HOME': '/home/cltbld', 03:38:05 INFO - 'LANG': 'en_US.UTF-8', 03:38:05 INFO - 'LANGUAGE': 'en_US:en', 03:38:05 INFO - 'LOGNAME': 'cltbld', 03:38:05 INFO - 'MAIL': '/var/mail/cltbld', 03:38:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:38:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:05 INFO - 'MOZ_NO_REMOTE': '1', 03:38:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:38:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:05 INFO - 'NO_EM_RESTART': '1', 03:38:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:05 INFO - 'PWD': '/builds/slave/test', 03:38:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:38:05 INFO - 'RUST_BACKTRACE': 'full', 03:38:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:38:05 INFO - 'SHELL': '/bin/bash', 03:38:05 INFO - 'SHLVL': '1', 03:38:05 INFO - 'TERM': 'linux', 03:38:05 INFO - 'TMOUT': '86400', 03:38:05 INFO - 'USER': 'cltbld', 03:38:05 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:05 INFO - '_': '/tools/buildbot/bin/python'} 03:38:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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:38:05 INFO - mozversion application_buildid: 20170923093626 03:38:05 INFO - mozversion application_changeset: 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 03:38:05 INFO - mozversion application_display_name: Nightly 03:38:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:38:05 INFO - mozversion application_name: Firefox 03:38:05 INFO - mozversion application_remotingname: firefox 03:38:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:38:05 INFO - mozversion application_vendor: Mozilla 03:38:05 INFO - mozversion application_version: 58.0a1 03:38:05 INFO - mozversion platform_buildid: 20170923093626 03:38:05 INFO - mozversion platform_changeset: 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 03:38:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:38:05 INFO - mozversion platform_version: 58.0a1 03:38:05 INFO - using testdate: 1506163085 03:38:05 INFO - actual date: 1506163085 03:38:05 INFO - starting webserver on 'localhost:35931' 03:38:05 INFO - SUITE-START | Running 2 tests 03:38:05 INFO - TEST-START | dromaeo_css 03:38:05 INFO - Initialising browser for dromaeo_css test... 03:38:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35931/getInfo.html -profile /tmp/tmp8mLD_k/profile 03:38:05 INFO - TEST-INFO | started process 7397 (/builds/slave/test/build/application/firefox/firefox http://localhost:35931/getInfo.html) 03:38:10 INFO - TEST-INFO | 7397: exit 0 03:38:10 INFO - Browser initialized. 03:38:10 INFO - Running cycle 1/1 for dromaeo_css test... 03:38:10 INFO - Using env: {'DISPLAY': ':0', 03:38:10 INFO - 'HOME': '/home/cltbld', 03:38:10 INFO - 'JSGC_DISABLE_POISONING': '1', 03:38:10 INFO - 'LANG': 'en_US.UTF-8', 03:38:10 INFO - 'LANGUAGE': 'en_US:en', 03:38:10 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:38:10 INFO - 'LOGNAME': 'cltbld', 03:38:10 INFO - 'MAIL': '/var/mail/cltbld', 03:38:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:38:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:38:10 INFO - 'MOZ_CRASHREPORTER': '1', 03:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:10 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:38:10 INFO - 'MOZ_NO_REMOTE': '1', 03:38:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:10 INFO - 'NO_EM_RESTART': '1', 03:38:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:10 INFO - 'PWD': '/builds/slave/test', 03:38:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:38:10 INFO - 'RUST_BACKTRACE': 'full', 03:38:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:38:10 INFO - 'SHELL': '/bin/bash', 03:38:10 INFO - 'SHLVL': '1', 03:38:10 INFO - 'TERM': 'linux', 03:38:10 INFO - 'TMOUT': '86400', 03:38:10 INFO - 'USER': 'cltbld', 03:38:10 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:10 INFO - '_': '/tools/buildbot/bin/python'} 03:38:10 INFO - TEST-INFO | started process 7630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8mLD_k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:38:11 INFO - PID 7630 | 03:38:11 INFO - PID 7630 | (/builds/slave/test/build/application/firefox/firefox:7679): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:38:11 INFO - PID 7630 | 03:38:22 INFO - PID 7630 | RSS: Main: 153153536 03:38:22 INFO - PID 7630 | 03:41:18 INFO - PID 7630 | Cycle 1(1): loaded http://localhost:35931/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35931/tests/dromaeo/cssquery-ext.html) 03:41:20 INFO - PID 7630 | RSS: Main: 153956352 03:41:20 INFO - PID 7630 | 03:44:22 INFO - PID 7630 | Cycle 1(1): loaded http://localhost:35931/tests/dromaeo/cssquery-ext.html (next: http://localhost:35931/tests/dromaeo/cssquery-jquery.html) 03:44:22 INFO - PID 7630 | RSS: Main: 153612288 03:44:22 INFO - PID 7630 | 03:47:24 INFO - PID 7630 | Cycle 1(1): loaded http://localhost:35931/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35931/tests/dromaeo/cssquery-mootools.html) 03:47:24 INFO - PID 7630 | RSS: Main: 154324992 03:47:24 INFO - PID 7630 | 03:50:26 INFO - PID 7630 | Cycle 1(1): loaded http://localhost:35931/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35931/tests/dromaeo/cssquery-prototype.html) 03:50:27 INFO - PID 7630 | RSS: Main: 153964544 03:50:27 INFO - PID 7630 | 03:53:30 INFO - PID 7630 | Cycle 1(1): loaded http://localhost:35931/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35931/tests/dromaeo/cssquery-yui.html) 03:53:30 INFO - PID 7630 | RSS: Main: 154955776 03:53:30 INFO - PID 7630 | 03:56:32 INFO - PID 7630 | Cycle 1(1): loaded http://localhost:35931/tests/dromaeo/cssquery-yui.html (next: http://localhost:35931/tests/dromaeo/cssquery-dojo.html) 03:56:32 INFO - PID 7630 | RSS: Main: 157683712 03:56:32 INFO - PID 7630 | 03:56:32 INFO - PID 7630 | __start_tp_report 03:56:32 INFO - PID 7630 | _x_x_mozilla_page_load 03:56:32 INFO - PID 7630 | _x_x_mozilla_page_load_details 03:56:32 INFO - PID 7630 | |i|pagename|runs| 03:56:32 INFO - PID 7630 | |0;dojo.html;3117.750579953604;3135.764817638678;3145.040762567419;3146.7010633989776;3159.605049368829;6069.362716914727;6126.938730612704;6202.100695399165;6267.905981410283;6314.052892066193;6157.661328626924;6196.566240363177;6219.253689557259;6224.875502489947;6242.84392890178;5761.107028410603;5809.186713860063;5830.4461076197695;5830.591858569902;5844.211031510756;1264.822134387352;5670.518005969477;5776;5902.291724993007;5909.172715819789;5322.33470816148;5675.971620141893;5732.369439361667;5774.307083150007;5810.157527158581;5737;5737.942620573783;5803.82588522345;5807.419258074201;5824.068149096167;5676.205331253628;5728.112142617901;5764.07774756037;5778.624389414675;5778.942210577882;6106.542009349317;6211.87576248477;6273.05904114382;6281.8743625127245;6331.96834015827;6480.351964803482;6588.901166482507;6604.966975165094;6699.9665001674675;6714.227863795677;6630.73477060917;6631.171103612049;6657.034729964176;6658.93341066588;6660.333966603349;6060.3939606039485;6159.137720719058;6254.7498100075945;6290.24517057956;6310.652914089723;6864.279250678706;6935.6878940447805;6953.965230173817;6959.130108736408;6967.965160174167;6543.869122617616;6556.311587283267;6601.570897891717;6647.1026411434805;6660.333966603301;6446.290908000116;6539.869202615921;6550.148480697479;6627.701753421107;6638;6568.113304703899;6575.178102737158;6591.670416479253;6690.063391125246;6698.8995165072565;6690.297518760557;6755.1556055493065;6758;6762.019507171432;6774.627395493295;5292.179712144623;5296.576273898079;5375.629214550265;5384.865378365494;5403.405625381205;4560.270356742972;4607.861764147081;4609.9308510372375;4640.419947506562;4705.294205869079;5869.735861886225;5872.442117998783;5884.47039766423;5886.205362276038;5890.175375447441;6709;6709.7651582194385;6776.864462710718;6793.796186114425;6806.4554835613035;5806.419358064244;5809.302883653989;5838.678872662044;5885.411458854166;5887.4406931341455;5666.4050274721385;5774.653520788766;5788.392218816964;5877.177195192677;5892.263467066617;6644.966775166094;6661.866762664719;6663.100481435041;6669.666516674147;6688.498362622823;6689.799306020827;6689.933100668931;6692.498062645418;6766.69549870257;6775.186977562724;6544.116544266653;6562.376574225441;6591.176102987127;6622.10601568782;6663.100481434944;6627.768028118992;6642.136522252076;6661.800145995628;6662.167229096363;6664.566803157779;6727.629980351127;6801.217859945971;6808.285130061272;6818.147731533559;6820.522563420712;6469.644169570531;6477.060826180272;6483.384078512534;6565.376289252514;6575.539712220098;6241.251049874044;6430.742770289136;6444.967775161094;6473.190851143607;6569.540132190701;6336.112944187726;6349.365063493614;6397.744090236339;6494.512911531559;6500.382463665944;6446.452051575481;6597.60414375139;6612.537122401386;6621.437177839744;6674;6286.339934306832;6293.307736149111;6373.107764912823;6379.043143528323;6379.74481020754 03:56:32 INFO - PID 7630 | |1;ext.html;879.7492714576344;906.2885634776702;910.3036177324344;910.6949172027371;911.0525053943901;38248.42627360588;41646.37530437046;41857;41896.790516047455;41899;39161;39379.606203937954;39425;39428.80285598568;39512;7241.456890733177;7343.522671026393;7350;8020.518768873886;8235.50586964784;8053.355731541492;8158.836823263531;8160.061592916802;8162.673493060271;8169.183081691843;8143.429959902809;8201;8205.466644668108;8207.466514676527;8211.38414618903;8128.9187108129045;8153.1439198884445;8153.266206041455;8157.918420815835;8158.99999999997;8029.277365037175;8064;8075.111737708848;8087.514749115072;8098.068722096976;37844.24311513782;37966.24067518634;37976;38162.42756358657;38231.4265286021;3532.858685652571;3556.2176321209295;3570.892873213808;3573.3032058748554;3576.177479179777;30952.071437856674;31023.224419389695;31143;31189;31300.686993130006;5186.222066689989;5188.766505507224;5238.5023422774775;5243.554297884684;5259.921101183485;180301;181096;181439;181710.09144954322;182437;41499.58500414957;42340.3648945266;47645.52354476411;47734;47789.52210477851;50252;50738;51020.23469647958;51198;51234.74382628063;15851.207439627973;15927.044377337397;15930.601734956486;15960.920195398949;16047.598810029609;13132.146410483288;13188.142770719873;13235.272060036597;13246.933765331112;13279.800802987962;13831.585052448443;13876.167429954034;13885.097468664504;13926.930365348311;14038.578842634737;5604.346717718283;5705.857353566111;5766.711664416846;5776.422357764274;5780.450857168562;1005.6580762540772;1011.5801942194054;1020.7550187954986;1035.9430231337199;1042.6559235452435;22748;22915.312540623807;22942.082316707143;23027;23103.422414439436;1118.2395970739974;1125.909927205838;1127.4531852051748;1136.6135513925306;1142.1833389126648;12091.758164836654;12343.19769214998;12350.938245308716;12353.77490318196;12386.504539818307;219532;220647;221410;221551;221626;52636;52713.2093018605;52740.472595274325;52778.472215277354;52904.73547632237;1445.4940770730161;1483.5401025682288;1496.872765814918;1497.7753336999538;1498.19846382185;22726.772732272464;22763.658545121507;23013.769862301833;23072.4231894204;23097.19159829398;37244.81377593203;37303.44044839275;37336.25327493489;37346.25307493889;37359;185509;185525;185721;185902;186315;33557.66442335643;34805.65194348024;35114.12214694653;35275.118122047155;35439.645603543635;43330;44683.553164467936;44872.77563612161;44879;45223.321650174614;31918.840405797822;31924.5211321835;32488.837555812068;32756.83621581877;33376.1655958603;32317.676823231468;32600.83699581487;32875.835620821745;32979;33068.669313307524;35418.468722969694;36846;36859.63140368669;36870.44694329641;37148;30760;31200;31400.685993139778;31719.524207137372;31776.205594860312;23957.16149934744;24127.396815079763;24210.75789242085;24479.877600611882;24483.63274550919 03:56:32 INFO - PID 7630 | |2;jquery.html;585.2158108135098;591.8047044475322;591.8964181268277;605.2525131462644;607.6020206764568;21602;22335.218267360644;22354.21760238392;22375.216867409643;22413;22948.88525557374;23886.283411497683;24475.143369982055;24486;24602.138925137624;6720.1599800025;6751.122354093961;6792;6796.25241223465;6798.626075565842;6638.834029149261;6725.461963042945;6731.360520750521;6752.561083529579;6755.763548275811;6641.568298060634;6689.698963546628;6696.665166741668;6699.531032827704;6699.866002679943;5906.4093590640805;6550;6569.737210511574;6600.471962243009;6715.093462382565;6135.693215339215;6139.26328840542;6146.262448506163;6151.753929842801;6163.506919446433;27091.45817083667;28017.719822801715;28045.15864524047;28087.578686319517;28103.29741756452;1947.4060411574465;1990.3332383651493;2004.4187185716125;2005.0776642744356;2005.0977060322914;19144.712829307573;19157.425277241706;19597.902010489997;19796.80203197964;19887.303944362022;2401.2556107606697;2408.6146216605434;2411.987940060306;2445.1808644104244;2452.8037756979397;123019;125029;130406;139302;139495.30252348774;14896.478623248135;14903.254837258093;15036.548903532692;15287;15408.229588520753;23688.881555592456;23787.881060594933;23875.403114922265;23877;23896.283111506684;10188.184945204366;10199.175309259028;10316.277860549839;10412.271141020208;10597.364158150536;9523.619055237852;9586.568604412818;9681.273904457194;9712.660056897974;9725.90274097264;8895.644174233088;9195.586198621078;9260.583273752698;9397.57710903011;9559.139677429064;2653.6550248467574;2680.303121188505;2734.480448714738;2785.804993650425;2823.929401764972;951.728757304165;975.9853602195973;983.4001259232006;986.156835905305;994.5773046455228;18634.813651863307;18650.813491865178;19200.61598768017;19421.805781942283;19554.902225488782;1019.6082347595627;1041.385582506325;1043.8486419469134;1049.4333060147587;1057.5663977769232;10479.371237725762;10487;10718.517666705016;10763.300385474919;10800.891991079989;444842;452409;457147;459240;463523;111990.44004780088;116072;116330;118638;119544;1165.4871856383165;1176.7705297466912;1217;1219.2440686774257;1224.014668192114;16887.831121689116;16898.577535561708;17031.914840425718;17252.482425527254;17430.738538921672;54124.729376352865;56154.719226403606;56717.149242762214;56995;57009.714951424976;71586;72161.63919180371;73278.63360683376;73409.6329518349;73630;14188.148711077369;14581.343839527035;14586;14587.416503340171;14588.635284117981;22032.11871525121;22131.55736885221;22333.664995024763;22345.44136396603;22348.21781237649;17528.82471175323;17643.55891102733;17754.822451775835;17869.46391608254;17907.283708651506;14591.343389547283;14615.561533154023;15987;16032.919835400748;16660.50018499447;17733;18040.09799509993;18061.638767224318;18153.45539633813;18215.635687285914;14241.145531268157;14262.42950281977;14338.928305358406;14339.928300358431;14363.425462981782;17531.298748049936;17593.736093958858;17736.467905962327;17930.731039034163;17938.19278132513 03:56:32 INFO - PID 7630 | |3;mootools.html;1323.4243104249651;1387.445021991204;1419.5173640962078;1425.8930580206484;1429.5139652518142;15232;15915.363385464567;15920.601984950397;15929.283182256797;15947.20263986802;15803.367865285403;15918.761218581702;15931.044137351739;15945;15957;2790.246633408978;2819.971800282002;2866.7563257123065;2872.5116730155924;2874.425114977013;2739.02764518596;2795.4688609164195;2809.1712944681267;2811.63448751662;2812.1704097291245;2714.4571085782823;2752.6559180102486;2800.3979144483933;2805.2566069991467;2808.1575527341715;2658.069675613539;2673.9598906016427;2696.892124315025;2697.9055733049445;2700.0819721294756;2702.148823120711;2713.4301796622535;2716.3888125171875;2725.74105459981;2726.495598314298;15181.696366072727;15631.452899148475;15631.843681563267;15639.296231669603;15641.921790390976;8165.8366832663605;8421.284190843786;8424.073351931283;8427;8429.4942303462;5244.60665450089;5455.1271796512765;5486.506214440719;5589.413111623302;5593.52455041322;1967.6950072738748;1982.7521559805025;1993.3521605478277;2044.0801639262243;2052.79472052795;27350;27646.44707105867;27671.58492622612;27677;27696.446071078666;6531.771388001397;6657.367550082734;6703.3296670332575;6898.379145876795;6903.1371078615175;17684.911575442296;17760.200790964438;17777.733334;17804.55488612795;17826.197821097823;8030.718924837603;8212.137725538852;8215.835683286421;8236.629351679188;8243.958780206061;7454.40364770817;7478.887816682754;7602.619869006528;7635.809104772425;7638.847223055619;5332.3867755207875;5447.918281225785;5456.61803673747;5469.917951230735;5489.149181876815;1393.3451277899371;1523.6495606010458;1538.5615099696533;1543.1204213598367;1553.324303927795;807.8949736534213;901.4140808474544;919.6827094652468;919.7792529792802;919.9402038867452;7382.667779949915;7600.42996775233;7633.580153091633;7710.306072453394;7723.9613801931755;988.3081842710131;1011.6696544044557;1011.9392836429838;1028.3521381529588;1037.3620223562455;4577.816887324537;4603.723776573416;4644.419447569054;4695.319178719066;4700.989287303246;20784.792152078284;21124;21322;21385.78614213838;21412.143514259256;21487;21662.56674866494;21675.78324216769;21694.783052169278;21729.67405488919;11203.383813890314;11441.141914356456;11468.13988950832;11533.308001519768;11562.710932226762;8459.154084591737;8480.957595211985;8533.487990720576;8643.611037503453;8677.1756683115;18102.5474363142;18523.166457509713;18621.162047707614;19093.331733389266;19209.90395048016;18860.717089243946;19120.23519059222;19186.13662385224;19189.904050480218;19296.903515482332;10938.45307734626;11223.943880280873;11304.265222760494;11312;11313;13608.727825443239;13867.375968081258;13991;14023.158610483404;14065.648358791112;10688.412137332522;10783.460826958777;10840.945795270973;10945.124390048937;10953.83569246446;10460.320079194827;10579.682609521727;10682.412467314372;10746.570137194425;10854.131669466266;9977.700668979942;10238.488075596339;10240.436775977094;10240.487975601041;10243.692689219335;7507.399408047452;7536.171021187555;7539.623018848926;7609.239076092346;7624.8475030500185;7583.355414789805;7641.808954776175;7643.121041080347;7665.6550455228535;7756.689732410866 03:56:32 INFO - PID 7630 | |4;prototype.html;516.5351183934458;522.1306524636482;538.4868519460317;549.2859282932187;553.9501444869961;6090.48230900374;6137.938620613803;6182.536309776768;6199.88402087624;6215.160953271307;6057.757689692418;6118.785842495514;6127;6143.232095988002;6149.661768602725;5131.769070391832;5162.716050617215;5164.896702065957;5168.896622067557;5195.818146364878;4839.709617422948;5097.9235311470175;5121;5184.896302073975;5218.765155568009;4884.780184891671;4918;4928.778204980785;4937.456879743226;4950.900981980359;4851.223804191312;4865.586425153866;4931.309616653672;4933.876653083666;4944.629152813535;4331.7184383015;4463.843765468193;4466.3747075409465;4470.687051906368;4492.820287188509;5872.9786032942075;5892.882142357129;5906.468417842372;5942.187632772003;5954.702264886739;5663.575231857586;5669.744861481243;5671.943280567224;5686.687232202227;5688.175214593861;6308.400701933309;6314.242290925118;6319.304876463585;6356.682165891687;6374.58565193261;4646.9070618587775;4856;4880.585150262232;4884.438289596706;4893.119238537062;6550.508711846583;6591.6704164792045;6699.531032827704;6718.49611279156;6738.157730283715;4932.778024988884;4933.432655244621;4956.851294461172;4972.428170760337;4995.850124496271;4937.802487900515;4944.777485013183;4964.230544265644;5000.499950004971;5049.091163590589;5583.906035236786;5607.327120745455;5611.130274807411;5625.606207565431;5627.324721033502;6480.740770369227;6487.610743355413;6490.610563366119;6501.1548498694865;6503.577267477599;1685.890417122883;1729.1181497436398;1749.5101371615967;1750.9649807004043;1764.514758441439;1177.3465726521863;1195.7070517723214;1208.709909621702;1240.0079936051304;1245.2466257914014;5605.682664573822;5689.800856969986;5718.513926316226;5719.799807006735;5733;6520.771772988018;6521.217453905561;6580.5064620152725;6595.175603049619;6633.237177724527;5613.687924124704;5674.432556744293;5681.77272909079;5728.570357223225;5732.942670573324;3448.534447849508;3462.1690794209208;3469.3061387722555;3482.529858469074;3495.178633021238;6518.837029074216;6546.672666366758;6559.376859198369;6566;6581.835454113685;6734.326567343324;6769.261516938163;6782.152230971129;6813.194503456588;6829.624370659562;5946.940530594638;6313.137624400721;6316.368088349218;6352.174217351807;6390.360963903666;6274.843128921813;6421.550491465552;6425.967870160619;6431.163948686641;6488.156539649815;6248.343923888108;6332.226976446603;6343.777967771106;6377.585456945284;6476.384743449365;6359.55483116196;6369.45859601939;6470.902936455863;6472.770173666988;6482.967585162044;4848.224284114524;4851.846299904949;4927.457979622311;4962.702237865739;4969.204927211629;6331.398517140864;6470.705858828159;6533.607983521004;6576.8355791105605;6642.6014439133805;6125.877482450237;6307.810765677037;6322.178116844781;6413.935860641521;6470.288268290578;6242.719077641426;6323;6349.269833968968;6423.334316258701;6461.579997300349;6167.537434692326;6189.412005859436;6233.130060981778;6243.594166379171;6339.337478259235;6329.430351268408;6365.586236894587;6405.167328247298;6470.320616335217;6491.383318584727;6155.4767844731905;6199.225096862892;6228.532860035606;6250;6269.717862696099 03:56:32 INFO - PID 7630 | |5;yui.html;901.0493928905003;926.157196950775;929.479491484769;936.4989730494187;942.6559305853364;18578.90710546449;19616.9019154904;19618.215271389126;19631.31290404836;19659.705104423447;18217.27130914765;18442.354517591888;18473.538161545966;18490.16794244259;18523.16645750941;952.1811242331606;978.7161723100293;984.5274268351196;984.6996666016862;985.4530735441826;983.2379905573166;992.1814503034989;996.2577879479798;996.4818294486862;1003.9899601004006;997.920166386691;1027.0551092994463;1033.8707661542308;1045.6235755128148;1047.6909311753013;1034.095166729112;1034.5189486888596;1034.8344264917653;1034.9327293725885;1036.4196050211906;729.2670865779858;742.8254360225343;748.4835463530147;755.1504557372955;756.4175584799729;17977.640447191116;17995.640087198182;18018.189181486865;18083.09584520769;18083.819161808344;21453.78546214549;21607.459813504633;22557.66163507549;22611.886940565357;22812;17565.385211517536;17794.55513612157;17832.643347133115;17941.64116717658;17947.461576152735;3700.888973330804;3704.0369503928905;3709.870154544578;3720.8511659533856;3723.6834869035893;103417.4829125857;129728;131932.34033829864;135263.32368338096;137854;16203.513894582946;16762.664746705;16849.157542122844;16856.32574697023;16861;31761;32014.359712805614;32448.675513245038;32662.510062349083;32666.836665816518;8827.337949653802;8846.380753347332;8873.73378798637;8884.111588841064;8889.066648001995;4299.097189590158;4578.130155270488;4591.4260717410325;4603.58567728906;4638.582527572535;5494.972525137348;5626.071698169756;5645.858853528613;5671.092625179951;5699.202111704365;1065.1957771882335;1104.1939384249383;1116.9944150279196;1120.165476719849;1120.4341807387218;615.3907631444837;617.3764497857196;626.2141013028711;627.5638431290209;643.1606753187042;11834.881651183377;11964.401779911144;11985.52057917691;12051.337176455203;12240.143189976616;649.8407890066964;654.443722835586;656.9014647802773;661.7948435984764;677.5155763629028;5782.971085144632;5832.562557808182;5846.795362162389;5884.499817515559;6031.306399764083;120652;122713.38643306906;123447.38276308561;127680.36159819142;128414;97866;99508;99626;100209.49895250477;100669;91841;93668.0633193673;94779.52610236904;96527.51736241415;96847;10598.152147828001;10666.36001839892;10679.786404271716;10835.295705779099;10971.725706857073;42544;43648.34527482027;43762;44292.33561496516;44919.32621010622;54257.18614220711;54697.72651136719;54916.17625735537;55206;56113.719431402584;14970.251487425803;15196.696066078837;15225.086494810346;15350.155741433882;15364.23178841031;17107.315707371567;17303.91348043252;17379.304827806747;17385.217665204844;17513.21190546454;14785.852141478448;14841.480548180762;14948.850511494746;15129.167895765398;15130.319135638703;15594.766078509057;15671.6492526122;15756.369745210062;15921.681566368376;15931.123788191297;13812.516561920285;13918.652033698834;14021.859781402056;14037.1577705338;14067.226302553458;10956.780864382827;10989.61536346256;11073.501692423699;11198.552057917592;11264.887351126712;11656.242344247936;11717.161625496552;11730.472128754056;11925.642230733092;11945.387372704574 03:56:32 INFO - PID 7630 | __end_tp_report 03:56:32 INFO - PID 7630 | __start_cc_report 03:56:32 INFO - PID 7630 | _x_x_mozilla_cycle_collect,262 03:56:32 INFO - PID 7630 | __end_cc_report 03:56:32 INFO - PID 7630 | __startTimestamp1506164192807__endTimestamp 03:56:32 INFO - PID 7630 | 03:56:32 INFO - PID 7630 | ------- Summary: start ------- 03:56:32 INFO - PID 7630 | Number of tests: 6 03:56:32 INFO - PID 7630 | 03:56:32 INFO - PID 7630 | [#0] dojo.html Cycles:165 Average:6140.44 Median:6379.74 stddev:825.51 (12.9%) stddev-sans-first:793.25 03:56:32 INFO - PID 7630 | Values: 3117.8 3135.8 3145.0 3146.7 3159.6 6069.4 6126.9 6202.1 6267.9 6314.1 6157.7 6196.6 6219.3 6224.9 6242.8 5761.1 5809.2 5830.4 5830.6 5844.2 1264.8 5670.5 5776.0 5902.3 5909.2 5322.3 5676.0 5732.4 5774.3 5810.2 5737.0 5737.9 5803.8 5807.4 5824.1 5676.2 5728.1 5764.1 5778.6 5778.9 6106.5 6211.9 6273.1 6281.9 6332.0 6480.4 6588.9 6605.0 6700.0 6714.2 6630.7 6631.2 6657.0 6658.9 6660.3 6060.4 6159.1 6254.7 6290.2 6310.7 6864.3 6935.7 6954.0 6959.1 6968.0 6543.9 6556.3 6601.6 6647.1 6660.3 6446.3 6539.9 6550.1 6627.7 6638.0 6568.1 6575.2 6591.7 6690.1 6698.9 6690.3 6755.2 6758.0 6762.0 6774.6 5292.2 5296.6 5375.6 5384.9 5403.4 4560.3 4607.9 4609.9 4640.4 4705.3 5869.7 5872.4 5884.5 5886.2 5890.2 6709.0 6709.8 6776.9 6793.8 6806.5 5806.4 5809.3 5838.7 5885.4 5887.4 5666.4 5774.7 5788.4 5877.2 5892.3 6645.0 6661.9 6663.1 6669.7 6688.5 6689.8 6689.9 6692.5 6766.7 6775.2 6544.1 6562.4 6591.2 6622.1 6663.1 6627.8 6642.1 6661.8 6662.2 6664.6 6727.6 6801.2 6808.3 6818.1 6820.5 6469.6 6477.1 6483.4 6565.4 6575.5 6241.3 6430.7 6445.0 6473.2 6569.5 6336.1 6349.4 6397.7 6494.5 6500.4 6446.5 6597.6 6612.5 6621.4 6674.0 6286.3 6293.3 6373.1 6379.0 6379.7 03:56:32 INFO - PID 7630 | 03:56:32 INFO - PID 7630 | [#1] ext.html Cycles:180 Average:36711.42 Median:35429.06 stddev:50885.16 (143.6%) stddev-sans-first:50956.77 03:56:32 INFO - PID 7630 | Values: 879.7 906.3 910.3 910.7 911.1 38248.4 41646.4 41857.0 41896.8 41899.0 39161.0 39379.6 39425.0 39428.8 39512.0 7241.5 7343.5 7350.0 8020.5 8235.5 8053.4 8158.8 8160.1 8162.7 8169.2 8143.4 8201.0 8205.5 8207.5 8211.4 8128.9 8153.1 8153.3 8157.9 8159.0 8029.3 8064.0 8075.1 8087.5 8098.1 37844.2 37966.2 37976.0 38162.4 38231.4 3532.9 3556.2 3570.9 3573.3 3576.2 30952.1 31023.2 31143.0 31189.0 31300.7 5186.2 5188.8 5238.5 5243.6 5259.9 180301.0 181096.0 181439.0 181710.1 182437.0 41499.6 42340.4 47645.5 47734.0 47789.5 50252.0 50738.0 51020.2 51198.0 51234.7 15851.2 15927.0 15930.6 15960.9 16047.6 13132.1 13188.1 13235.3 13246.9 13279.8 13831.6 13876.2 13885.1 13926.9 14038.6 5604.3 5705.9 5766.7 5776.4 5780.5 1005.7 1011.6 1020.8 1035.9 1042.7 22748.0 22915.3 22942.1 23027.0 23103.4 1118.2 1125.9 1127.5 1136.6 1142.2 12091.8 12343.2 12350.9 12353.8 12386.5 219532.0 220647.0 221410.0 221551.0 221626.0 52636.0 52713.2 52740.5 52778.5 52904.7 1445.5 1483.5 1496.9 1497.8 1498.2 22726.8 22763.7 23013.8 23072.4 23097.2 37244.8 37303.4 37336.3 37346.3 37359.0 185509.0 185525.0 185721.0 185902.0 186315.0 33557.7 34805.7 35114.1 35275.1 35439.6 43330.0 44683.6 44872.8 44879.0 45223.3 31918.8 31924.5 32488.8 32756.8 33376.2 32317.7 32600.8 32875.8 32979.0 33068.7 35418.5 36846.0 36859.6 36870.4 37148.0 30760.0 31200.0 31400.7 31719.5 31776.2 23957.2 24127.4 24210.8 24479.9 24483.6 03:56:32 INFO - PID 7630 | 03:56:32 INFO - PID 7630 | [#2] jquery.html Cycles:180 Average:33473.24 Median:22346.83 stddev:77120.59 (345.1%) stddev-sans-first:77297.40 03:56:32 INFO - PID 7630 | Values: 585.2 591.8 591.9 605.3 607.6 21602.0 22335.2 22354.2 22375.2 22413.0 22948.9 23886.3 24475.1 24486.0 24602.1 6720.2 6751.1 6792.0 6796.3 6798.6 6638.8 6725.5 6731.4 6752.6 6755.8 6641.6 6689.7 6696.7 6699.5 6699.9 5906.4 6550.0 6569.7 6600.5 6715.1 6135.7 6139.3 6146.3 6151.8 6163.5 27091.5 28017.7 28045.2 28087.6 28103.3 1947.4 1990.3 2004.4 2005.1 2005.1 19144.7 19157.4 19597.9 19796.8 19887.3 2401.3 2408.6 2412.0 2445.2 2452.8 123019.0 125029.0 130406.0 139302.0 139495.3 14896.5 14903.3 15036.5 15287.0 15408.2 23688.9 23787.9 23875.4 23877.0 23896.3 10188.2 10199.2 10316.3 10412.3 10597.4 9523.6 9586.6 9681.3 9712.7 9725.9 8895.6 9195.6 9260.6 9397.6 9559.1 2653.7 2680.3 2734.5 2785.8 2823.9 951.7 976.0 983.4 986.2 994.6 18634.8 18650.8 19200.6 19421.8 19554.9 1019.6 1041.4 1043.8 1049.4 1057.6 10479.4 10487.0 10718.5 10763.3 10800.9 444842.0 452409.0 457147.0 459240.0 463523.0 111990.4 116072.0 116330.0 118638.0 119544.0 1165.5 1176.8 1217.0 1219.2 1224.0 16887.8 16898.6 17031.9 17252.5 17430.7 54124.7 56154.7 56717.1 56995.0 57009.7 71586.0 72161.6 73278.6 73409.6 73630.0 14188.1 14581.3 14586.0 14587.4 14588.6 22032.1 22131.6 22333.7 22345.4 22348.2 17528.8 17643.6 17754.8 17869.5 17907.3 14591.3 14615.6 15987.0 16032.9 16660.5 17733.0 18040.1 18061.6 18153.5 18215.6 14241.1 14262.4 14338.9 14339.9 14363.4 17531.3 17593.7 17736.5 17930.7 17938.2 03:56:32 INFO - PID 7630 | 03:56:32 INFO - PID 7630 | [#3] mootools.html Cycles:180 Average:9445.53 Median:2697.40 stddev:6782.48 (251.4%) stddev-sans-first:6774.06 03:56:32 INFO - PID 7630 | Values: 1323.4 1387.4 1419.5 1425.9 1429.5 15232.0 15915.4 15920.6 15929.3 15947.2 15803.4 15918.8 15931.0 15945.0 15957.0 2790.2 2820.0 2866.8 2872.5 2874.4 2739.0 2795.5 2809.2 2811.6 2812.2 2714.5 2752.7 2800.4 2805.3 2808.2 2658.1 2674.0 2696.9 2697.9 2700.1 2702.1 2713.4 2716.4 2725.7 2726.5 15181.7 15631.5 15631.8 15639.3 15641.9 8165.8 8421.3 8424.1 8427.0 8429.5 5244.6 5455.1 5486.5 5589.4 5593.5 1967.7 1982.8 1993.4 2044.1 2052.8 27350.0 27646.4 27671.6 27677.0 27696.4 6531.8 6657.4 6703.3 6898.4 6903.1 17684.9 17760.2 17777.7 17804.6 17826.2 8030.7 8212.1 8215.8 8236.6 8244.0 7454.4 7478.9 7602.6 7635.8 7638.8 5332.4 5447.9 5456.6 5469.9 5489.1 1393.3 1523.6 1538.6 1543.1 1553.3 807.9 901.4 919.7 919.8 919.9 7382.7 7600.4 7633.6 7710.3 7724.0 988.3 1011.7 1011.9 1028.4 1037.4 4577.8 4603.7 4644.4 4695.3 4701.0 20784.8 21124.0 21322.0 21385.8 21412.1 21487.0 21662.6 21675.8 21694.8 21729.7 11203.4 11441.1 11468.1 11533.3 11562.7 8459.2 8481.0 8533.5 8643.6 8677.2 18102.5 18523.2 18621.2 19093.3 19209.9 18860.7 19120.2 19186.1 19189.9 19296.9 10938.5 11223.9 11304.3 11312.0 11313.0 13608.7 13867.4 13991.0 14023.2 14065.6 10688.4 10783.5 10840.9 10945.1 10953.8 10460.3 10579.7 10682.4 10746.6 10854.1 9977.7 10238.5 10240.4 10240.5 10243.7 7507.4 7536.2 7539.6 7609.2 7624.8 7583.4 7641.8 7643.1 7665.7 7756.7 03:56:32 INFO - PID 7630 | 03:56:32 INFO - PID 7630 | [#4] prototype.html Cycles:180 Average:5415.51 Median:6006.23 stddev:1494.93 (24.9%) stddev-sans-first:1453.20 03:56:32 INFO - PID 7630 | Values: 516.5 522.1 538.5 549.3 554.0 6090.5 6137.9 6182.5 6199.9 6215.2 6057.8 6118.8 6127.0 6143.2 6149.7 5131.8 5162.7 5164.9 5168.9 5195.8 4839.7 5097.9 5121.0 5184.9 5218.8 4884.8 4918.0 4928.8 4937.5 4950.9 4851.2 4865.6 4931.3 4933.9 4944.6 4331.7 4463.8 4466.4 4470.7 4492.8 5873.0 5892.9 5906.5 5942.2 5954.7 5663.6 5669.7 5671.9 5686.7 5688.2 6308.4 6314.2 6319.3 6356.7 6374.6 4646.9 4856.0 4880.6 4884.4 4893.1 6550.5 6591.7 6699.5 6718.5 6738.2 4932.8 4933.4 4956.9 4972.4 4995.9 4937.8 4944.8 4964.2 5000.5 5049.1 5583.9 5607.3 5611.1 5625.6 5627.3 6480.7 6487.6 6490.6 6501.2 6503.6 1685.9 1729.1 1749.5 1751.0 1764.5 1177.3 1195.7 1208.7 1240.0 1245.2 5605.7 5689.8 5718.5 5719.8 5733.0 6520.8 6521.2 6580.5 6595.2 6633.2 5613.7 5674.4 5681.8 5728.6 5732.9 3448.5 3462.2 3469.3 3482.5 3495.2 6518.8 6546.7 6559.4 6566.0 6581.8 6734.3 6769.3 6782.2 6813.2 6829.6 5946.9 6313.1 6316.4 6352.2 6390.4 6274.8 6421.6 6426.0 6431.2 6488.2 6248.3 6332.2 6343.8 6377.6 6476.4 6359.6 6369.5 6470.9 6472.8 6483.0 4848.2 4851.8 4927.5 4962.7 4969.2 6331.4 6470.7 6533.6 6576.8 6642.6 6125.9 6307.8 6322.2 6413.9 6470.3 6242.7 6323.0 6349.3 6423.3 6461.6 6167.5 6189.4 6233.1 6243.6 6339.3 6329.4 6365.6 6405.2 6470.3 6491.4 6155.5 6199.2 6228.5 6250.0 6269.7 03:56:32 INFO - PID 7630 | 03:56:32 INFO - PID 7630 | [#5] yui.html Cycles:180 Average:23610.48 Median:18506.67 stddev:33774.85 (182.5%) stddev-sans-first:33826.55 03:56:32 INFO - PID 7630 | Values: 901.0 926.2 929.5 936.5 942.7 18578.9 19616.9 19618.2 19631.3 19659.7 18217.3 18442.4 18473.5 18490.2 18523.2 952.2 978.7 984.5 984.7 985.5 983.2 992.2 996.3 996.5 1004.0 997.9 1027.1 1033.9 1045.6 1047.7 1034.1 1034.5 1034.8 1034.9 1036.4 729.3 742.8 748.5 755.2 756.4 17977.6 17995.6 18018.2 18083.1 18083.8 21453.8 21607.5 22557.7 22611.9 22812.0 17565.4 17794.6 17832.6 17941.6 17947.5 3700.9 3704.0 3709.9 3720.9 3723.7 103417.5 129728.0 131932.3 135263.3 137854.0 16203.5 16762.7 16849.2 16856.3 16861.0 31761.0 32014.4 32448.7 32662.5 32666.8 8827.3 8846.4 8873.7 8884.1 8889.1 4299.1 4578.1 4591.4 4603.6 4638.6 5495.0 5626.1 5645.9 5671.1 5699.2 1065.2 1104.2 1117.0 1120.2 1120.4 615.4 617.4 626.2 627.6 643.2 11834.9 11964.4 11985.5 12051.3 12240.1 649.8 654.4 656.9 661.8 677.5 5783.0 5832.6 5846.8 5884.5 6031.3 120652.0 122713.4 123447.4 127680.4 128414.0 97866.0 99508.0 99626.0 100209.5 100669.0 91841.0 93668.1 94779.5 96527.5 96847.0 10598.2 10666.4 10679.8 10835.3 10971.7 42544.0 43648.3 43762.0 44292.3 44919.3 54257.2 54697.7 54916.2 55206.0 56113.7 14970.3 15196.7 15225.1 15350.2 15364.2 17107.3 17303.9 17379.3 17385.2 17513.2 14785.9 14841.5 14948.9 15129.2 15130.3 15594.8 15671.6 15756.4 15921.7 15931.1 13812.5 13918.7 14021.9 14037.2 14067.2 10956.8 10989.6 11073.5 11198.6 11264.9 11656.2 11717.2 11730.5 11925.6 11945.4 03:56:32 INFO - PID 7630 | -------- Summary: end -------- 03:56:32 INFO - PID 7630 | 03:56:33 INFO - TEST-INFO | 7630: exit 0 03:56:33 INFO - TEST-OK | dromaeo_css | took 1108128ms 03:56:33 INFO - TEST-START | kraken 03:56:33 INFO - Initialising browser for kraken test... 03:56:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35931/getInfo.html -profile /tmp/tmpkI0CIH/profile 03:56:33 INFO - TEST-INFO | started process 8005 (/builds/slave/test/build/application/firefox/firefox http://localhost:35931/getInfo.html) 03:56:38 INFO - TEST-INFO | 8005: exit 0 03:56:39 INFO - Browser initialized. 03:56:39 INFO - Running cycle 1/1 for kraken test... 03:56:39 INFO - Using env: {'DISPLAY': ':0', 03:56:39 INFO - 'HOME': '/home/cltbld', 03:56:39 INFO - 'JSGC_DISABLE_POISONING': '1', 03:56:39 INFO - 'LANG': 'en_US.UTF-8', 03:56:39 INFO - 'LANGUAGE': 'en_US:en', 03:56:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:56:39 INFO - 'LOGNAME': 'cltbld', 03:56:39 INFO - 'MAIL': '/var/mail/cltbld', 03:56:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:56:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:56:39 INFO - 'MOZ_CRASHREPORTER': '1', 03:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:56:39 INFO - 'MOZ_NO_REMOTE': '1', 03:56:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:56:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:39 INFO - 'NO_EM_RESTART': '1', 03:56:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:39 INFO - 'PWD': '/builds/slave/test', 03:56:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:56:39 INFO - 'RUST_BACKTRACE': 'full', 03:56:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:56:39 INFO - 'SHELL': '/bin/bash', 03:56:39 INFO - 'SHLVL': '1', 03:56:39 INFO - 'TERM': 'linux', 03:56:39 INFO - 'TMOUT': '86400', 03:56:39 INFO - 'USER': 'cltbld', 03:56:39 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050', 03:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:39 INFO - '_': '/tools/buildbot/bin/python'} 03:56:39 INFO - TEST-INFO | started process 8238 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkI0CIH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:56:39 INFO - PID 8238 | 03:56:39 INFO - PID 8238 | (/builds/slave/test/build/application/firefox/firefox:8288): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:56:39 INFO - PID 8238 | 03:56:50 INFO - PID 8238 | RSS: Main: 152997888 03:56:50 INFO - PID 8238 | 03:56:52 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35931/tests/kraken/driver.html?testName=audio-beat-detection) 03:56:53 INFO - PID 8238 | RSS: Main: 159633408 03:56:53 INFO - PID 8238 | 03:56:56 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35931/tests/kraken/driver.html?testName=audio-dft) 03:56:57 INFO - PID 8238 | RSS: Main: 159756288 03:56:57 INFO - PID 8238 | 03:57:00 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35931/tests/kraken/driver.html?testName=audio-fft) 03:57:00 INFO - PID 8238 | RSS: Main: 156323840 03:57:00 INFO - PID 8238 | 03:57:02 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35931/tests/kraken/driver.html?testName=audio-oscillator) 03:57:03 INFO - PID 8238 | RSS: Main: 157040640 03:57:03 INFO - PID 8238 | 03:57:05 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35931/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:57:06 INFO - PID 8238 | RSS: Main: 157179904 03:57:06 INFO - PID 8238 | 03:57:09 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35931/tests/kraken/driver.html?testName=imaging-darkroom) 03:57:09 INFO - PID 8238 | RSS: Main: 159481856 03:57:09 INFO - PID 8238 | 03:57:13 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35931/tests/kraken/driver.html?testName=imaging-desaturate) 03:57:14 INFO - PID 8238 | RSS: Main: 160018432 03:57:14 INFO - PID 8238 | 03:57:17 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35931/tests/kraken/driver.html?testName=json-parse-financial) 03:57:17 INFO - PID 8238 | RSS: Main: 160014336 03:57:17 INFO - PID 8238 | 03:57:19 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35931/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:57:19 INFO - PID 8238 | RSS: Main: 159789056 03:57:19 INFO - PID 8238 | 03:57:21 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35931/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:57:22 INFO - PID 8238 | RSS: Main: 159744000 03:57:22 INFO - PID 8238 | 03:57:24 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35931/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:57:25 INFO - PID 8238 | RSS: Main: 156647424 03:57:25 INFO - PID 8238 | 03:57:27 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35931/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:57:28 INFO - PID 8238 | RSS: Main: 157016064 03:57:28 INFO - PID 8238 | 03:57:31 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35931/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:57:31 INFO - PID 8238 | RSS: Main: 157253632 03:57:31 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | Cycle 1(1): loaded http://localhost:35931/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35931/tests/kraken/driver.html?testName=ai-astar) 03:57:33 INFO - PID 8238 | RSS: Main: 158683136 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | __start_tp_report 03:57:33 INFO - PID 8238 | _x_x_mozilla_page_load 03:57:33 INFO - PID 8238 | _x_x_mozilla_page_load_details 03:57:33 INFO - PID 8238 | |i|pagename|runs| 03:57:33 INFO - PID 8238 | |0;ai-astar;107;108;108;108;107;107;108;106;108;108 03:57:33 INFO - PID 8238 | |1;audio-beat-detection;118;118;117;115;119;145;122;120;118;117 03:57:33 INFO - PID 8238 | |2;audio-dft;162;160;162;163;162;161;161;163;165;170 03:57:33 INFO - PID 8238 | |3;audio-fft;74;75;74;73;79;73;73;76;74;75 03:57:33 INFO - PID 8238 | |4;audio-oscillator;80;79;80;79;79;79;78;79;86;79 03:57:33 INFO - PID 8238 | |5;imaging-gaussian-blur;113;117;116;115;114;116;117;111;114;114 03:57:33 INFO - PID 8238 | |6;imaging-darkroom;148;147;147;148;148;148;149;147;147;148 03:57:33 INFO - PID 8238 | |7;imaging-desaturate;95;94;94;97;94;96;94;94;94;95 03:57:33 INFO - PID 8238 | |8;json-parse-financial;56;57;52;53;53;55;55;55;53;53 03:57:33 INFO - PID 8238 | |9;json-stringify-tinderbox;38;41;38;47;38;39;38;38;38;39 03:57:33 INFO - PID 8238 | |10;stanford-crypto-aes;80;77;80;79;76;77;75;76;74;75 03:57:33 INFO - PID 8238 | |11;stanford-crypto-ccm;125;118;110;116;117;117;119;111;115;116 03:57:33 INFO - PID 8238 | |12;stanford-crypto-pbkdf2;169;186;172;177;183;169;179;175;174;172 03:57:33 INFO - PID 8238 | |13;stanford-crypto-sha256-iterative;69;67;69;65;74;73;67;67;64;66 03:57:33 INFO - PID 8238 | __end_tp_report 03:57:33 INFO - PID 8238 | __start_cc_report 03:57:33 INFO - PID 8238 | _x_x_mozilla_cycle_collect,758 03:57:33 INFO - PID 8238 | __end_cc_report 03:57:33 INFO - PID 8238 | __startTimestamp1506164253887__endTimestamp 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | ------- Summary: start ------- 03:57:33 INFO - PID 8238 | Number of tests: 14 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#0] ai-astar Cycles:10 Average:107.50 Median:108.00 stddev:0.71 (0.7%) stddev-sans-first:0.73 03:57:33 INFO - PID 8238 | Values: 107.0 108.0 108.0 108.0 107.0 107.0 108.0 106.0 108.0 108.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#1] audio-beat-detection Cycles:10 Average:120.90 Median:118.50 stddev:8.67 (7.3%) stddev-sans-first:9.13 03:57:33 INFO - PID 8238 | Values: 118.0 118.0 117.0 115.0 119.0 145.0 122.0 120.0 118.0 117.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#2] audio-dft Cycles:10 Average:162.90 Median:162.50 stddev:2.85 (1.8%) stddev-sans-first:3.00 03:57:33 INFO - PID 8238 | Values: 162.0 160.0 162.0 163.0 162.0 161.0 161.0 163.0 165.0 170.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#3] audio-fft Cycles:10 Average:74.60 Median:74.50 stddev:1.84 (2.5%) stddev-sans-first:1.94 03:57:33 INFO - PID 8238 | Values: 74.0 75.0 74.0 73.0 79.0 73.0 73.0 76.0 74.0 75.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:79.00 stddev:2.25 (2.8%) stddev-sans-first:2.39 03:57:33 INFO - PID 8238 | Values: 80.0 79.0 80.0 79.0 79.0 79.0 78.0 79.0 86.0 79.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:115.50 stddev:1.89 (1.6%) stddev-sans-first:1.90 03:57:33 INFO - PID 8238 | Values: 113.0 117.0 116.0 115.0 114.0 116.0 117.0 111.0 114.0 114.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 03:57:33 INFO - PID 8238 | Values: 148.0 147.0 147.0 148.0 148.0 148.0 149.0 147.0 147.0 148.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.50 stddev:1.06 (1.1%) stddev-sans-first:1.12 03:57:33 INFO - PID 8238 | Values: 95.0 94.0 94.0 97.0 94.0 96.0 94.0 94.0 94.0 95.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:55.00 stddev:1.62 (2.9%) stddev-sans-first:1.58 03:57:33 INFO - PID 8238 | Values: 56.0 57.0 52.0 53.0 53.0 55.0 55.0 55.0 53.0 53.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#9] json-stringify-tinderbox Cycles:10 Average:39.40 Median:38.50 stddev:2.84 (7.4%) stddev-sans-first:2.96 03:57:33 INFO - PID 8238 | Values: 38.0 41.0 38.0 47.0 38.0 39.0 38.0 38.0 38.0 39.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:77.00 stddev:2.13 (2.8%) stddev-sans-first:1.94 03:57:33 INFO - PID 8238 | Values: 80.0 77.0 80.0 79.0 76.0 77.0 75.0 76.0 74.0 75.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#11] stanford-crypto-ccm Cycles:10 Average:116.40 Median:117.00 stddev:4.17 (3.6%) stddev-sans-first:3.05 03:57:33 INFO - PID 8238 | Values: 125.0 118.0 110.0 116.0 117.0 117.0 119.0 111.0 115.0 116.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:176.00 stddev:5.70 (3.2%) stddev-sans-first:5.52 03:57:33 INFO - PID 8238 | Values: 169.0 186.0 172.0 177.0 183.0 169.0 179.0 175.0 174.0 172.0 03:57:33 INFO - PID 8238 | 03:57:33 INFO - PID 8238 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:3.25 (4.8%) stddev-sans-first:3.43 03:57:33 INFO - PID 8238 | Values: 69.0 67.0 69.0 65.0 74.0 73.0 67.0 67.0 64.0 66.0 03:57:33 INFO - PID 8238 | -------- Summary: end -------- 03:57:33 INFO - PID 8238 | 03:57:34 INFO - PID 8238 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:57:34 INFO - TEST-INFO | 8238: exit 0 03:57:34 INFO - TEST-OK | kraken | took 60996ms 03:57:34 INFO - SUITE-END | took 1169s 03:57:35 INFO - Completed test suite (00:19:29) 03:57:35 INFO - javascript benchmark 03:57:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8472.13138374031, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3117.750579953604, 3135.764817638678, 3145.040762567419, 3146.7010633989776, 3159.605049368829, 6069.362716914727, 6126.938730612704, 6202.100695399165, 6267.905981410283, 6314.052892066193, 6157.661328626924, 6196.566240363177, 6219.253689557259, 6224.875502489947, 6242.84392890178, 5761.107028410603, 5809.186713860063, 5830.4461076197695, 5830.591858569902, 5844.211031510756, 1264.822134387352, 5670.518005969477, 5776.0, 5902.291724993007, 5909.172715819789, 5322.33470816148, 5675.971620141893, 5732.369439361667, 5774.307083150007, 5810.157527158581, 5737.0, 5737.942620573783, 5803.82588522345, 5807.419258074201, 5824.068149096167, 5676.205331253628, 5728.112142617901, 5764.07774756037, 5778.624389414675, 5778.942210577882, 6106.542009349317, 6211.87576248477, 6273.05904114382, 6281.8743625127245, 6331.96834015827, 6480.351964803482, 6588.901166482507, 6604.966975165094, 6699.9665001674675, 6714.227863795677, 6630.73477060917, 6631.171103612049, 6657.034729964176, 6658.93341066588, 6660.333966603349, 6060.3939606039485, 6159.137720719058, 6254.7498100075945, 6290.24517057956, 6310.652914089723, 6864.279250678706, 6935.6878940447805, 6953.965230173817, 6959.130108736408, 6967.965160174167, 6543.869122617616, 6556.311587283267, 6601.570897891717, 6647.1026411434805, 6660.333966603301, 6446.290908000116, 6539.869202615921, 6550.148480697479, 6627.701753421107, 6638.0, 6568.113304703899, 6575.178102737158, 6591.670416479253, 6690.063391125246, 6698.8995165072565, 6690.297518760557, 6755.1556055493065, 6758.0, 6762.019507171432, 6774.627395493295, 5292.179712144623, 5296.576273898079, 5375.629214550265, 5384.865378365494, 5403.405625381205, 4560.270356742972, 4607.861764147081, 4609.9308510372375, 4640.419947506562, 4705.294205869079, 5869.735861886225, 5872.442117998783, 5884.47039766423, 5886.205362276038, 5890.175375447441, 6709.0, 6709.7651582194385, 6776.864462710718, 6793.796186114425, 6806.4554835613035, 5806.419358064244, 5809.302883653989, 5838.678872662044, 5885.411458854166, 5887.4406931341455, 5666.4050274721385, 5774.653520788766, 5788.392218816964, 5877.177195192677, 5892.263467066617, 6644.966775166094, 6661.866762664719, 6663.100481435041, 6669.666516674147, 6688.498362622823, 6689.799306020827, 6689.933100668931, 6692.498062645418, 6766.69549870257, 6775.186977562724, 6544.116544266653, 6562.376574225441, 6591.176102987127, 6622.10601568782, 6663.100481434944, 6627.768028118992, 6642.136522252076, 6661.800145995628, 6662.167229096363, 6664.566803157779, 6727.629980351127, 6801.217859945971, 6808.285130061272, 6818.147731533559, 6820.522563420712, 6469.644169570531, 6477.060826180272, 6483.384078512534, 6565.376289252514, 6575.539712220098, 6241.251049874044, 6430.742770289136, 6444.967775161094, 6473.190851143607, 6569.540132190701, 6336.112944187726, 6349.365063493614, 6397.744090236339, 6494.512911531559, 6500.382463665944, 6446.452051575481, 6597.60414375139, 6612.537122401386, 6621.437177839744, 6674.0, 6286.339934306832, 6293.307736149111, 6373.107764912823, 6379.043143528323, 6379.74481020754], "value": 6081.0906074229815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [879.7492714576344, 906.2885634776702, 910.3036177324344, 910.6949172027371, 911.0525053943901, 38248.42627360588, 41646.37530437046, 41857.0, 41896.790516047455, 41899.0, 39161.0, 39379.606203937954, 39425.0, 39428.80285598568, 39512.0, 7241.456890733177, 7343.522671026393, 7350.0, 8020.518768873886, 8235.50586964784, 8053.355731541492, 8158.836823263531, 8160.061592916802, 8162.673493060271, 8169.183081691843, 8143.429959902809, 8201.0, 8205.466644668108, 8207.466514676527, 8211.38414618903, 8128.9187108129045, 8153.1439198884445, 8153.266206041455, 8157.918420815835, 8158.99999999997, 8029.277365037175, 8064.0, 8075.111737708848, 8087.514749115072, 8098.068722096976, 37844.24311513782, 37966.24067518634, 37976.0, 38162.42756358657, 38231.4265286021, 3532.858685652571, 3556.2176321209295, 3570.892873213808, 3573.3032058748554, 3576.177479179777, 30952.071437856674, 31023.224419389695, 31143.0, 31189.0, 31300.686993130006, 5186.222066689989, 5188.766505507224, 5238.5023422774775, 5243.554297884684, 5259.921101183485, 180301.0, 181096.0, 181439.0, 181710.09144954322, 182437.0, 41499.58500414957, 42340.3648945266, 47645.52354476411, 47734.0, 47789.52210477851, 50252.0, 50738.0, 51020.23469647958, 51198.0, 51234.74382628063, 15851.207439627973, 15927.044377337397, 15930.601734956486, 15960.920195398949, 16047.598810029609, 13132.146410483288, 13188.142770719873, 13235.272060036597, 13246.933765331112, 13279.800802987962, 13831.585052448443, 13876.167429954034, 13885.097468664504, 13926.930365348311, 14038.578842634737, 5604.346717718283, 5705.857353566111, 5766.711664416846, 5776.422357764274, 5780.450857168562, 1005.6580762540772, 1011.5801942194054, 1020.7550187954986, 1035.9430231337199, 1042.6559235452435, 22748.0, 22915.312540623807, 22942.082316707143, 23027.0, 23103.422414439436, 1118.2395970739974, 1125.909927205838, 1127.4531852051748, 1136.6135513925306, 1142.1833389126648, 12091.758164836654, 12343.19769214998, 12350.938245308716, 12353.77490318196, 12386.504539818307, 219532.0, 220647.0, 221410.0, 221551.0, 221626.0, 52636.0, 52713.2093018605, 52740.472595274325, 52778.472215277354, 52904.73547632237, 1445.4940770730161, 1483.5401025682288, 1496.872765814918, 1497.7753336999538, 1498.19846382185, 22726.772732272464, 22763.658545121507, 23013.769862301833, 23072.4231894204, 23097.19159829398, 37244.81377593203, 37303.44044839275, 37336.25327493489, 37346.25307493889, 37359.0, 185509.0, 185525.0, 185721.0, 185902.0, 186315.0, 33557.66442335643, 34805.65194348024, 35114.12214694653, 35275.118122047155, 35439.645603543635, 43330.0, 44683.553164467936, 44872.77563612161, 44879.0, 45223.321650174614, 31918.840405797822, 31924.5211321835, 32488.837555812068, 32756.83621581877, 33376.1655958603, 32317.676823231468, 32600.83699581487, 32875.835620821745, 32979.0, 33068.669313307524, 35418.468722969694, 36846.0, 36859.63140368669, 36870.44694329641, 37148.0, 30760.0, 31200.0, 31400.685993139778, 31719.524207137372, 31776.205594860312, 23957.16149934744, 24127.396815079763, 24210.75789242085, 24479.877600611882, 24483.63274550919], "value": 17222.46318460504, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.2158108135098, 591.8047044475322, 591.8964181268277, 605.2525131462644, 607.6020206764568, 21602.0, 22335.218267360644, 22354.21760238392, 22375.216867409643, 22413.0, 22948.88525557374, 23886.283411497683, 24475.143369982055, 24486.0, 24602.138925137624, 6720.1599800025, 6751.122354093961, 6792.0, 6796.25241223465, 6798.626075565842, 6638.834029149261, 6725.461963042945, 6731.360520750521, 6752.561083529579, 6755.763548275811, 6641.568298060634, 6689.698963546628, 6696.665166741668, 6699.531032827704, 6699.866002679943, 5906.4093590640805, 6550.0, 6569.737210511574, 6600.471962243009, 6715.093462382565, 6135.693215339215, 6139.26328840542, 6146.262448506163, 6151.753929842801, 6163.506919446433, 27091.45817083667, 28017.719822801715, 28045.15864524047, 28087.578686319517, 28103.29741756452, 1947.4060411574465, 1990.3332383651493, 2004.4187185716125, 2005.0776642744356, 2005.0977060322914, 19144.712829307573, 19157.425277241706, 19597.902010489997, 19796.80203197964, 19887.303944362022, 2401.2556107606697, 2408.6146216605434, 2411.987940060306, 2445.1808644104244, 2452.8037756979397, 123019.0, 125029.0, 130406.0, 139302.0, 139495.30252348774, 14896.478623248135, 14903.254837258093, 15036.548903532692, 15287.0, 15408.229588520753, 23688.881555592456, 23787.881060594933, 23875.403114922265, 23877.0, 23896.283111506684, 10188.184945204366, 10199.175309259028, 10316.277860549839, 10412.271141020208, 10597.364158150536, 9523.619055237852, 9586.568604412818, 9681.273904457194, 9712.660056897974, 9725.90274097264, 8895.644174233088, 9195.586198621078, 9260.583273752698, 9397.57710903011, 9559.139677429064, 2653.6550248467574, 2680.303121188505, 2734.480448714738, 2785.804993650425, 2823.929401764972, 951.728757304165, 975.9853602195973, 983.4001259232006, 986.156835905305, 994.5773046455228, 18634.813651863307, 18650.813491865178, 19200.61598768017, 19421.805781942283, 19554.902225488782, 1019.6082347595627, 1041.385582506325, 1043.8486419469134, 1049.4333060147587, 1057.5663977769232, 10479.371237725762, 10487.0, 10718.517666705016, 10763.300385474919, 10800.891991079989, 444842.0, 452409.0, 457147.0, 459240.0, 463523.0, 111990.44004780088, 116072.0, 116330.0, 118638.0, 119544.0, 1165.4871856383165, 1176.7705297466912, 1217.0, 1219.2440686774257, 1224.014668192114, 16887.831121689116, 16898.577535561708, 17031.914840425718, 17252.482425527254, 17430.738538921672, 54124.729376352865, 56154.719226403606, 56717.149242762214, 56995.0, 57009.714951424976, 71586.0, 72161.63919180371, 73278.63360683376, 73409.6329518349, 73630.0, 14188.148711077369, 14581.343839527035, 14586.0, 14587.416503340171, 14588.635284117981, 22032.11871525121, 22131.55736885221, 22333.664995024763, 22345.44136396603, 22348.21781237649, 17528.82471175323, 17643.55891102733, 17754.822451775835, 17869.46391608254, 17907.283708651506, 14591.343389547283, 14615.561533154023, 15987.0, 16032.919835400748, 16660.50018499447, 17733.0, 18040.09799509993, 18061.638767224318, 18153.45539633813, 18215.635687285914, 14241.145531268157, 14262.42950281977, 14338.928305358406, 14339.928300358431, 14363.425462981782, 17531.298748049936, 17593.736093958858, 17736.467905962327, 17930.731039034163, 17938.19278132513], "value": 11856.36392507501, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.4243104249651, 1387.445021991204, 1419.5173640962078, 1425.8930580206484, 1429.5139652518142, 15232.0, 15915.363385464567, 15920.601984950397, 15929.283182256797, 15947.20263986802, 15803.367865285403, 15918.761218581702, 15931.044137351739, 15945.0, 15957.0, 2790.246633408978, 2819.971800282002, 2866.7563257123065, 2872.5116730155924, 2874.425114977013, 2739.02764518596, 2795.4688609164195, 2809.1712944681267, 2811.63448751662, 2812.1704097291245, 2714.4571085782823, 2752.6559180102486, 2800.3979144483933, 2805.2566069991467, 2808.1575527341715, 2658.069675613539, 2673.9598906016427, 2696.892124315025, 2697.9055733049445, 2700.0819721294756, 2702.148823120711, 2713.4301796622535, 2716.3888125171875, 2725.74105459981, 2726.495598314298, 15181.696366072727, 15631.452899148475, 15631.843681563267, 15639.296231669603, 15641.921790390976, 8165.8366832663605, 8421.284190843786, 8424.073351931283, 8427.0, 8429.4942303462, 5244.60665450089, 5455.1271796512765, 5486.506214440719, 5589.413111623302, 5593.52455041322, 1967.6950072738748, 1982.7521559805025, 1993.3521605478277, 2044.0801639262243, 2052.79472052795, 27350.0, 27646.44707105867, 27671.58492622612, 27677.0, 27696.446071078666, 6531.771388001397, 6657.367550082734, 6703.3296670332575, 6898.379145876795, 6903.1371078615175, 17684.911575442296, 17760.200790964438, 17777.733334, 17804.55488612795, 17826.197821097823, 8030.718924837603, 8212.137725538852, 8215.835683286421, 8236.629351679188, 8243.958780206061, 7454.40364770817, 7478.887816682754, 7602.619869006528, 7635.809104772425, 7638.847223055619, 5332.3867755207875, 5447.918281225785, 5456.61803673747, 5469.917951230735, 5489.149181876815, 1393.3451277899371, 1523.6495606010458, 1538.5615099696533, 1543.1204213598367, 1553.324303927795, 807.8949736534213, 901.4140808474544, 919.6827094652468, 919.7792529792802, 919.9402038867452, 7382.667779949915, 7600.42996775233, 7633.580153091633, 7710.306072453394, 7723.9613801931755, 988.3081842710131, 1011.6696544044557, 1011.9392836429838, 1028.3521381529588, 1037.3620223562455, 4577.816887324537, 4603.723776573416, 4644.419447569054, 4695.319178719066, 4700.989287303246, 20784.792152078284, 21124.0, 21322.0, 21385.78614213838, 21412.143514259256, 21487.0, 21662.56674866494, 21675.78324216769, 21694.783052169278, 21729.67405488919, 11203.383813890314, 11441.141914356456, 11468.13988950832, 11533.308001519768, 11562.710932226762, 8459.154084591737, 8480.957595211985, 8533.487990720576, 8643.611037503453, 8677.1756683115, 18102.5474363142, 18523.166457509713, 18621.162047707614, 19093.331733389266, 19209.90395048016, 18860.717089243946, 19120.23519059222, 19186.13662385224, 19189.904050480218, 19296.903515482332, 10938.45307734626, 11223.943880280873, 11304.265222760494, 11312.0, 11313.0, 13608.727825443239, 13867.375968081258, 13991.0, 14023.158610483404, 14065.648358791112, 10688.412137332522, 10783.460826958777, 10840.945795270973, 10945.124390048937, 10953.83569246446, 10460.320079194827, 10579.682609521727, 10682.412467314372, 10746.570137194425, 10854.131669466266, 9977.700668979942, 10238.488075596339, 10240.436775977094, 10240.487975601041, 10243.692689219335, 7507.399408047452, 7536.171021187555, 7539.623018848926, 7609.239076092346, 7624.8475030500185, 7583.355414789805, 7641.808954776175, 7643.121041080347, 7665.6550455228535, 7756.689732410866], "value": 6758.612663050386, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.5351183934458, 522.1306524636482, 538.4868519460317, 549.2859282932187, 553.9501444869961, 6090.48230900374, 6137.938620613803, 6182.536309776768, 6199.88402087624, 6215.160953271307, 6057.757689692418, 6118.785842495514, 6127.0, 6143.232095988002, 6149.661768602725, 5131.769070391832, 5162.716050617215, 5164.896702065957, 5168.896622067557, 5195.818146364878, 4839.709617422948, 5097.9235311470175, 5121.0, 5184.896302073975, 5218.765155568009, 4884.780184891671, 4918.0, 4928.778204980785, 4937.456879743226, 4950.900981980359, 4851.223804191312, 4865.586425153866, 4931.309616653672, 4933.876653083666, 4944.629152813535, 4331.7184383015, 4463.843765468193, 4466.3747075409465, 4470.687051906368, 4492.820287188509, 5872.9786032942075, 5892.882142357129, 5906.468417842372, 5942.187632772003, 5954.702264886739, 5663.575231857586, 5669.744861481243, 5671.943280567224, 5686.687232202227, 5688.175214593861, 6308.400701933309, 6314.242290925118, 6319.304876463585, 6356.682165891687, 6374.58565193261, 4646.9070618587775, 4856.0, 4880.585150262232, 4884.438289596706, 4893.119238537062, 6550.508711846583, 6591.6704164792045, 6699.531032827704, 6718.49611279156, 6738.157730283715, 4932.778024988884, 4933.432655244621, 4956.851294461172, 4972.428170760337, 4995.850124496271, 4937.802487900515, 4944.777485013183, 4964.230544265644, 5000.499950004971, 5049.091163590589, 5583.906035236786, 5607.327120745455, 5611.130274807411, 5625.606207565431, 5627.324721033502, 6480.740770369227, 6487.610743355413, 6490.610563366119, 6501.1548498694865, 6503.577267477599, 1685.890417122883, 1729.1181497436398, 1749.5101371615967, 1750.9649807004043, 1764.514758441439, 1177.3465726521863, 1195.7070517723214, 1208.709909621702, 1240.0079936051304, 1245.2466257914014, 5605.682664573822, 5689.800856969986, 5718.513926316226, 5719.799807006735, 5733.0, 6520.771772988018, 6521.217453905561, 6580.5064620152725, 6595.175603049619, 6633.237177724527, 5613.687924124704, 5674.432556744293, 5681.77272909079, 5728.570357223225, 5732.942670573324, 3448.534447849508, 3462.1690794209208, 3469.3061387722555, 3482.529858469074, 3495.178633021238, 6518.837029074216, 6546.672666366758, 6559.376859198369, 6566.0, 6581.835454113685, 6734.326567343324, 6769.261516938163, 6782.152230971129, 6813.194503456588, 6829.624370659562, 5946.940530594638, 6313.137624400721, 6316.368088349218, 6352.174217351807, 6390.360963903666, 6274.843128921813, 6421.550491465552, 6425.967870160619, 6431.163948686641, 6488.156539649815, 6248.343923888108, 6332.226976446603, 6343.777967771106, 6377.585456945284, 6476.384743449365, 6359.55483116196, 6369.45859601939, 6470.902936455863, 6472.770173666988, 6482.967585162044, 4848.224284114524, 4851.846299904949, 4927.457979622311, 4962.702237865739, 4969.204927211629, 6331.398517140864, 6470.705858828159, 6533.607983521004, 6576.8355791105605, 6642.6014439133805, 6125.877482450237, 6307.810765677037, 6322.178116844781, 6413.935860641521, 6470.288268290578, 6242.719077641426, 6323.0, 6349.269833968968, 6423.334316258701, 6461.579997300349, 6167.537434692326, 6189.412005859436, 6233.130060981778, 6243.594166379171, 6339.337478259235, 6329.430351268408, 6365.586236894587, 6405.167328247298, 6470.320616335217, 6491.383318584727, 6155.4767844731905, 6199.225096862892, 6228.532860035606, 6250.0, 6269.717862696099], "value": 4982.1590367445815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [901.0493928905003, 926.157196950775, 929.479491484769, 936.4989730494187, 942.6559305853364, 18578.90710546449, 19616.9019154904, 19618.215271389126, 19631.31290404836, 19659.705104423447, 18217.27130914765, 18442.354517591888, 18473.538161545966, 18490.16794244259, 18523.16645750941, 952.1811242331606, 978.7161723100293, 984.5274268351196, 984.6996666016862, 985.4530735441826, 983.2379905573166, 992.1814503034989, 996.2577879479798, 996.4818294486862, 1003.9899601004006, 997.920166386691, 1027.0551092994463, 1033.8707661542308, 1045.6235755128148, 1047.6909311753013, 1034.095166729112, 1034.5189486888596, 1034.8344264917653, 1034.9327293725885, 1036.4196050211906, 729.2670865779858, 742.8254360225343, 748.4835463530147, 755.1504557372955, 756.4175584799729, 17977.640447191116, 17995.640087198182, 18018.189181486865, 18083.09584520769, 18083.819161808344, 21453.78546214549, 21607.459813504633, 22557.66163507549, 22611.886940565357, 22812.0, 17565.385211517536, 17794.55513612157, 17832.643347133115, 17941.64116717658, 17947.461576152735, 3700.888973330804, 3704.0369503928905, 3709.870154544578, 3720.8511659533856, 3723.6834869035893, 103417.4829125857, 129728.0, 131932.34033829864, 135263.32368338096, 137854.0, 16203.513894582946, 16762.664746705, 16849.157542122844, 16856.32574697023, 16861.0, 31761.0, 32014.359712805614, 32448.675513245038, 32662.510062349083, 32666.836665816518, 8827.337949653802, 8846.380753347332, 8873.73378798637, 8884.111588841064, 8889.066648001995, 4299.097189590158, 4578.130155270488, 4591.4260717410325, 4603.58567728906, 4638.582527572535, 5494.972525137348, 5626.071698169756, 5645.858853528613, 5671.092625179951, 5699.202111704365, 1065.1957771882335, 1104.1939384249383, 1116.9944150279196, 1120.165476719849, 1120.4341807387218, 615.3907631444837, 617.3764497857196, 626.2141013028711, 627.5638431290209, 643.1606753187042, 11834.881651183377, 11964.401779911144, 11985.52057917691, 12051.337176455203, 12240.143189976616, 649.8407890066964, 654.443722835586, 656.9014647802773, 661.7948435984764, 677.5155763629028, 5782.971085144632, 5832.562557808182, 5846.795362162389, 5884.499817515559, 6031.306399764083, 120652.0, 122713.38643306906, 123447.38276308561, 127680.36159819142, 128414.0, 97866.0, 99508.0, 99626.0, 100209.49895250477, 100669.0, 91841.0, 93668.0633193673, 94779.52610236904, 96527.51736241415, 96847.0, 10598.152147828001, 10666.36001839892, 10679.786404271716, 10835.295705779099, 10971.725706857073, 42544.0, 43648.34527482027, 43762.0, 44292.33561496516, 44919.32621010622, 54257.18614220711, 54697.72651136719, 54916.17625735537, 55206.0, 56113.719431402584, 14970.251487425803, 15196.696066078837, 15225.086494810346, 15350.155741433882, 15364.23178841031, 17107.315707371567, 17303.91348043252, 17379.304827806747, 17385.217665204844, 17513.21190546454, 14785.852141478448, 14841.480548180762, 14948.850511494746, 15129.167895765398, 15130.319135638703, 15594.766078509057, 15671.6492526122, 15756.369745210062, 15921.681566368376, 15931.123788191297, 13812.516561920285, 13918.652033698834, 14021.859781402056, 14037.1577705338, 14067.226302553458, 10956.780864382827, 10989.61536346256, 11073.501692423699, 11198.552057917592, 11264.887351126712, 11656.242344247936, 11717.161625496552, 11730.472128754056, 11925.642230733092, 11945.387372704574], "value": 8843.54648211605, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1433.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 108.0, 108.0, 108.0, 107.0, 107.0, 108.0, 106.0, 108.0, 108.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 115.0, 119.0, 145.0, 122.0, 120.0, 118.0, 117.0], "value": 120.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 163.0, 162.0, 161.0, 161.0, 163.0, 165.0, 170.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 73.0, 79.0, 73.0, 73.0, 76.0, 74.0, 75.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 80.0, 79.0, 79.0, 79.0, 78.0, 79.0, 86.0, 79.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 116.0, 115.0, 114.0, 116.0, 117.0, 111.0, 114.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 148.0, 148.0, 148.0, 149.0, 147.0, 147.0, 148.0], "value": 147.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 97.0, 94.0, 96.0, 94.0, 94.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 52.0, 53.0, 53.0, 55.0, 55.0, 55.0, 53.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 41.0, 38.0, 47.0, 38.0, 39.0, 38.0, 38.0, 38.0, 39.0], "value": 39.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 80.0, 79.0, 76.0, 77.0, 75.0, 76.0, 74.0, 75.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 118.0, 110.0, 116.0, 117.0, 117.0, 119.0, 111.0, 115.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 186.0, 172.0, 177.0, 183.0, 169.0, 179.0, 175.0, 174.0, 172.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 65.0, 74.0, 73.0, 67.0, 67.0, 64.0, 66.0], "value": 68.1, "unit": "score"}]}]} 03:57:35 INFO - Return code: 0 03:57:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:57:35 INFO - # TBPL SUCCESS # 03:57:35 INFO - Running post-action listener: _package_coverage_data 03:57:35 INFO - Running post-action listener: _resource_record_post_action 03:57:35 INFO - [mozharness: 2017-09-23 10:57:35.165388Z] Finished run-tests step (success) 03:57:35 INFO - Running post-run listener: _resource_record_post_run 03:57:35 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 421888; Write bytes: 443904000; Read time: 40; Write time: 549760 03:57:35 INFO - TinderboxPrint: CPU usage
13.1% 03:57:35 INFO - TinderboxPrint: I/O read bytes / time
421,888 / 40 03:57:35 INFO - TinderboxPrint: I/O write bytes / time
443,904,000 / 549,760 03:57:35 INFO - TinderboxPrint: CPU idle
8,224.9 (86.8%) 03:57:35 INFO - TinderboxPrint: CPU user
1,192.6 (12.6%) 03:57:35 INFO - TinderboxPrint: Swap in / out
0 / 0 03:57:35 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 113049600; Read time: 0; Write time: 148436 03:57:35 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:57:35 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 417792; Write bytes: 330448896; Read time: 20; Write time: 401288 03:57:35 INFO - Running post-run listener: _upload_blobber_files 03:57:35 INFO - Blob upload gear active. 03:57:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:57:35 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 03:57:35 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:57:35 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:57:36 INFO - (blobuploader) - INFO - Open directory for files ... 03:57:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:57:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:57:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:37 INFO - (blobuploader) - INFO - Done attempting. 03:57:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:57:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:57:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:38 INFO - (blobuploader) - INFO - Done attempting. 03:57:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:57:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:57:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:45 INFO - (blobuploader) - INFO - Done attempting. 03:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:46 INFO - (blobuploader) - INFO - Done attempting. 03:57:46 INFO - (blobuploader) - INFO - Iteration through files over. 03:57:46 INFO - Return code: 0 03:57:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:57:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:57:46 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0705d519d4487f75002de7f52c2299471c520394def55d833e12a696df98ff55841e7ae89050b72a2f77519abb81f73039ce849a1ce5f6d3f5f1b5ca754d0076", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b13e75a6ec50953f726cdb6a3f3fade4174ec2ec8b54d436574b86eca54c23071fb2301a55561ad2172e554f4432b0a96c9c5bd1704b004d54e923135002959d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/db2f1af88c67cb7beb80ea9bafb732b5325fd9816a1a77dd189cb158c0c8b9c349adbbeb52a1e95190f8bfc009fcf0c7578675522059a2e9157e88f2a9f8ba46", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a8f18c013f4e59b9bc0d8c3e19e4a3dac4abc392edfd0a8b689af92a28313ff7fdfbcb9dbb3cc91e88ae6df275f71c201267ef221df873c930fbf3b617fdae20"} 03:57:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:57:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:57:46 INFO - Contents: 03:57:46 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0705d519d4487f75002de7f52c2299471c520394def55d833e12a696df98ff55841e7ae89050b72a2f77519abb81f73039ce849a1ce5f6d3f5f1b5ca754d0076", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b13e75a6ec50953f726cdb6a3f3fade4174ec2ec8b54d436574b86eca54c23071fb2301a55561ad2172e554f4432b0a96c9c5bd1704b004d54e923135002959d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/db2f1af88c67cb7beb80ea9bafb732b5325fd9816a1a77dd189cb158c0c8b9c349adbbeb52a1e95190f8bfc009fcf0c7578675522059a2e9157e88f2a9f8ba46", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a8f18c013f4e59b9bc0d8c3e19e4a3dac4abc392edfd0a8b689af92a28313ff7fdfbcb9dbb3cc91e88ae6df275f71c201267ef221df873c930fbf3b617fdae20"} 03:57:46 INFO - Running post-run listener: copy_logs_to_upload_dir 03:57:46 INFO - Copying logs to upload dir... 03:57:46 INFO - mkdir: /builds/slave/test/build/upload/logs 03:57:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.491378 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-23 03:57:46.673252) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 03:57:46.677641) ========= 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=836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0705d519d4487f75002de7f52c2299471c520394def55d833e12a696df98ff55841e7ae89050b72a2f77519abb81f73039ce849a1ce5f6d3f5f1b5ca754d0076", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b13e75a6ec50953f726cdb6a3f3fade4174ec2ec8b54d436574b86eca54c23071fb2301a55561ad2172e554f4432b0a96c9c5bd1704b004d54e923135002959d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/db2f1af88c67cb7beb80ea9bafb732b5325fd9816a1a77dd189cb158c0c8b9c349adbbeb52a1e95190f8bfc009fcf0c7578675522059a2e9157e88f2a9f8ba46", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a8f18c013f4e59b9bc0d8c3e19e4a3dac4abc392edfd0a8b689af92a28313ff7fdfbcb9dbb3cc91e88ae6df275f71c201267ef221df873c930fbf3b617fdae20"} build_url:https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011290 build_url: 'https://queue.taskcluster.net/v1/task/O6UesEjKQLKs2XYkonDMAA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0705d519d4487f75002de7f52c2299471c520394def55d833e12a696df98ff55841e7ae89050b72a2f77519abb81f73039ce849a1ce5f6d3f5f1b5ca754d0076", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b13e75a6ec50953f726cdb6a3f3fade4174ec2ec8b54d436574b86eca54c23071fb2301a55561ad2172e554f4432b0a96c9c5bd1704b004d54e923135002959d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/db2f1af88c67cb7beb80ea9bafb732b5325fd9816a1a77dd189cb158c0c8b9c349adbbeb52a1e95190f8bfc009fcf0c7578675522059a2e9157e88f2a9f8ba46", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a8f18c013f4e59b9bc0d8c3e19e4a3dac4abc392edfd0a8b689af92a28313ff7fdfbcb9dbb3cc91e88ae6df275f71c201267ef221df873c930fbf3b617fdae20"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 03:57:46.711214) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 03:57:46.711551) ========= 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=836cfdc75598d54cb747368e000001a9-1506163022.866553-1648376050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004548 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 03:57:46.795408) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 03:57:46.795768) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 03:57:46.796111) ========= ========= Total master_lag: 0.45 =========