builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-072 starttime: 1506336895.99 results: success (0) revision: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:55.989293) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:55.989703) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:55.990172) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.013212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.013502) ========= 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=199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-25 03:54:56-- 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% 42.1M=0s 2017-09-25 03:54:56 (42.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126588 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.158357) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.158634) ========= 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=199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014987 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.217943) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.218485) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 --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 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 --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=199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-25 03:54:56,267 truncating revision to first 12 chars 2017-09-25 03:54:56,267 Setting DEBUG logging. 2017-09-25 03:54:56,267 attempt 1/10 2017-09-25 03:54:56,267 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5f3f19824efa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-25 03:54:56,662 unpacking tar archive at: mozilla-central-5f3f19824efa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.704511 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.939023) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.939317) ========= script_repo_revision: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.939663) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.939937) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:56.953023) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-25 03:54:56.953289) ========= /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=199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:54:57 INFO - MultiFileLogger online at 20170925 03:54:57 in /builds/slave/test 03:54:57 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:54:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:54:57 INFO - {'append_to_log': False, 03:54:57 INFO - 'base_work_dir': '/builds/slave/test', 03:54:57 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:54:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:54:57 INFO - 'branch': 'Firefox-Non-PGO', 03:54:57 INFO - 'buildbot_json_path': 'buildprops.json', 03:54:57 INFO - 'code_coverage': False, 03:54:57 INFO - 'config_files': ('talos/linux_config.py',), 03:54:57 INFO - 'default_actions': ('clobber', 03:54:57 INFO - 'read-buildbot-config', 03:54:57 INFO - 'download-and-extract', 03:54:57 INFO - 'populate-webroot', 03:54:57 INFO - 'create-virtualenv', 03:54:57 INFO - 'install', 03:54:57 INFO - 'setup-mitmproxy', 03:54:57 INFO - 'run-tests'), 03:54:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:54:57 INFO - 'disable_ccov_upload': False, 03:54:57 INFO - 'disable_stylo': False, 03:54:57 INFO - 'download_minidump_stackwalk': True, 03:54:57 INFO - 'download_symbols': 'ondemand', 03:54:57 INFO - 'enable_stylo': False, 03:54:57 INFO - 'enable_webrender': False, 03:54:57 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:54:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:54:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:54:57 INFO - 'gecko_profile': False, 03:54:57 INFO - 'gecko_profile_interval': 0, 03:54:57 INFO - 'installer_path': 'installer.exe', 03:54:57 INFO - 'log_level': 'info', 03:54:57 INFO - 'log_name': 'talos', 03:54:57 INFO - 'log_to_console': True, 03:54:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:54:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:54:57 INFO - 'opt_config_files': (), 03:54:57 INFO - 'pip_index': False, 03:54:57 INFO - 'suite': 'dromaeojs-e10s', 03:54:57 INFO - 'system_bits': '32', 03:54:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:54:57 INFO - 'title': 'talos-linux64-ix-072', 03:54:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:54:57 INFO - 'use_talos_json': True, 03:54:57 INFO - 'verify': 'False', 03:54:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:54:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:54:57 INFO - 'work_dir': 'build'} 03:54:57 INFO - [mozharness: 2017-09-25 10:54:57.150134Z] Running clobber step. 03:54:57 INFO - Running pre-action listener: _resource_record_pre_action 03:54:57 INFO - Running main action method: clobber 03:54:57 INFO - rmtree: /builds/slave/test/build 03:54:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:54:58 INFO - Running post-action listener: _resource_record_post_action 03:54:58 INFO - [mozharness: 2017-09-25 10:54:58.145006Z] Finished clobber step (success) 03:54:58 INFO - [mozharness: 2017-09-25 10:54:58.145104Z] Running read-buildbot-config step. 03:54:58 INFO - Running pre-action listener: _resource_record_pre_action 03:54:58 INFO - Running main action method: read_buildbot_config 03:54:58 INFO - Using buildbot properties: 03:54:58 INFO - { 03:54:58 INFO - "project": "", 03:54:58 INFO - "product": "firefox", 03:54:58 INFO - "who": "archaeopteryx@coole-files.de", 03:54:58 INFO - "installer_path": "public/build/target.tar.bz2", 03:54:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:54:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:54:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:54:58 INFO - "stage_platform": "linux64", 03:54:58 INFO - "basedir": "/builds/slave/test", 03:54:58 INFO - "buildnumber": 1176, 03:54:58 INFO - "platform": "ubuntu64_hw", 03:54:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:54:58 INFO - "slavebuilddir": "test", 03:54:58 INFO - "taskId": "AHMyeJbJQXm8NWmengSo8w", 03:54:58 INFO - "branch": "mozilla-central", 03:54:58 INFO - "script_repo_revision": "production", 03:54:58 INFO - "revision": "5f3f19824efa14cc6db546baf59c54a0fc15ddc9", 03:54:58 INFO - "slavename": "talos-linux64-ix-072", 03:54:58 INFO - "repo_path": "mozilla-central" 03:54:58 INFO - } 03:54:58 INFO - Finding installer, test and symbols from parent task. 03:54:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AHMyeJbJQXm8NWmengSo8w'}, attempt #1 03:54:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AHMyeJbJQXm8NWmengSo8w'}, attempt #1 03:54:59 INFO - Task dependencies: SaUzvixmSkCRJGqkRd3BZQ 03:54:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ'}, attempt #1 03:54:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 03:54:59 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.test_packages.json 03:54:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.crashreporter-symbols.zip 03:54:59 INFO - Running post-action listener: _resource_record_post_action 03:54:59 INFO - [mozharness: 2017-09-25 10:54:59.447346Z] Finished read-buildbot-config step (success) 03:54:59 INFO - [mozharness: 2017-09-25 10:54:59.447564Z] Running download-and-extract step. 03:54:59 INFO - Running pre-action listener: _resource_record_pre_action 03:54:59 INFO - Running main action method: download_and_extract 03:54:59 INFO - mkdir: /builds/slave/test/build/tests 03:54:59 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:54:59 INFO - trying https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.test_packages.json 03:54:59 INFO - Downloading https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:54:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:55:00 INFO - Downloaded 989 bytes. 03:55:00 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:55:00 INFO - Using the following test package requirements: 03:55:00 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:55:00 INFO - u'common': [u'target.common.tests.zip'], 03:55:00 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:55:00 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:55:00 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:55:00 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:55:00 INFO - u'mozbase': [u'target.common.tests.zip'], 03:55:00 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:55:00 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:55:00 INFO - u'web-platform': [u'target.common.tests.zip', 03:55:00 INFO - u'target.web-platform.tests.tar.gz'], 03:55:00 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:55:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:55:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.common.tests.zip 03:55:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:55:00 INFO - Fetch https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.common.tests.zip into memory 03:55:02 INFO - Content-Length response header: 54943940 03:55:02 INFO - Bytes received: 54943940 03:55:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.talos.tests.zip 03:55:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:55:12 INFO - Fetch https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.talos.tests.zip into memory 03:55:14 INFO - Content-Length response header: 14513823 03:55:14 INFO - Bytes received: 14513823 03:55:14 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:55:14 INFO - trying https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 03:55:14 INFO - Downloading https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:55:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:55:18 INFO - Downloaded 61631429 bytes. 03:55:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 03:55:18 INFO - mkdir: /builds/slave/test/properties 03:55:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:55:18 INFO - Writing to file /builds/slave/test/properties/build_url 03:55:18 INFO - Contents: 03:55:18 INFO - build_url:https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 03:55:18 INFO - Running post-action listener: _resource_record_post_action 03:55:18 INFO - Running post-action listener: find_tests_for_verification 03:55:18 INFO - Running post-action listener: set_extra_try_arguments 03:55:18 INFO - [mozharness: 2017-09-25 10:55:18.101780Z] Finished download-and-extract step (success) 03:55:18 INFO - [mozharness: 2017-09-25 10:55:18.101877Z] Running populate-webroot step. 03:55:18 INFO - Running pre-action listener: _resource_record_pre_action 03:55:18 INFO - Running main action method: populate_webroot 03:55:18 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:55:18 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['tresize', 03:55:18 INFO - 'tcanvasmark']}, 03:55:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:55:18 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['dromaeo_css', 03:55:18 INFO - 'kraken']}, 03:55:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:18 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'tests': ['damp', 'tps']}, 03:55:18 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['damp', 'tps']}, 03:55:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:55:18 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['dromaeo_dom']}, 03:55:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:55:18 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['basic_compositor_video', 03:55:18 INFO - 'glvideo']}, 03:55:18 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:55:18 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['ts_paint_webext', 03:55:18 INFO - 'tp5o_webext']}, 03:55:18 INFO - 'other-e10s': {'tests': ['a11yr', 03:55:18 INFO - 'ts_paint', 03:55:18 INFO - 'tpaint', 03:55:18 INFO - 'sessionrestore', 03:55:18 INFO - 'sessionrestore_many_windows', 03:55:18 INFO - 'sessionrestore_no_auto_restore', 03:55:18 INFO - 'tabpaint']}, 03:55:18 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['a11yr', 03:55:18 INFO - 'ts_paint', 03:55:18 INFO - 'tpaint', 03:55:18 INFO - 'sessionrestore', 03:55:18 INFO - 'sessionrestore_many_windows', 03:55:18 INFO - 'sessionrestore_no_auto_restore', 03:55:18 INFO - 'tabpaint']}, 03:55:18 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 03:55:18 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:55:18 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['perf_reftest_singletons']}, 03:55:18 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['perf_reftest']}, 03:55:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:55:18 INFO - 'tsvgr_opacity', 03:55:18 INFO - 'tart', 03:55:18 INFO - 'tscrollx', 03:55:18 INFO - 'tsvg_static']}, 03:55:18 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['tsvgx', 03:55:18 INFO - 'tsvgr_opacity', 03:55:18 INFO - 'tart', 03:55:18 INFO - 'tscrollx', 03:55:18 INFO - 'tsvg_static']}, 03:55:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:55:18 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['tp5o']}, 03:55:18 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:55:18 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:55:18 INFO - 'talos_options': ['--mitmproxy', 03:55:18 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:55:18 INFO - '--firstNonBlankPaint'], 03:55:18 INFO - 'tests': ['tp6_google', 03:55:18 INFO - 'tp6_youtube', 03:55:18 INFO - 'tp6_amazon', 03:55:18 INFO - 'tp6_facebook']}, 03:55:18 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:55:18 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:55:18 INFO - 'talos_options': ['--disable-stylo', 03:55:18 INFO - '--mitmproxy', 03:55:18 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:55:18 INFO - '--firstNonBlankPaint'], 03:55:18 INFO - 'tests': ['tp6_google', 03:55:18 INFO - 'tp6_youtube', 03:55:18 INFO - 'tp6_amazon', 03:55:18 INFO - 'tp6_facebook']}, 03:55:18 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:55:18 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:55:18 INFO - 'talos_options': ['--stylo-threads=1', 03:55:18 INFO - '--mitmproxy', 03:55:18 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:55:18 INFO - '--firstNonBlankPaint'], 03:55:18 INFO - 'tests': ['tp6_google', 03:55:18 INFO - 'tp6_youtube', 03:55:18 INFO - 'tp6_amazon', 03:55:18 INFO - 'tp6_facebook']}, 03:55:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--xperf_path', 03:55:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:18 INFO - 'tests': ['tp5n']}, 03:55:18 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo', 03:55:18 INFO - '--xperf_path', 03:55:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:18 INFO - 'tests': ['tp5n']}}} 03:55:18 INFO - Running post-action listener: _resource_record_post_action 03:55:18 INFO - [mozharness: 2017-09-25 10:55:18.117332Z] Finished populate-webroot step (success) 03:55:18 INFO - [mozharness: 2017-09-25 10:55:18.117422Z] Running create-virtualenv step. 03:55:18 INFO - Running pre-action listener: _resource_record_pre_action 03:55:18 INFO - Running main action method: create_virtualenv 03:55:18 INFO - Creating virtualenv /builds/slave/test/build/venv 03:55:18 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:55:18 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:55:18 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:55:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:55:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:55:18 INFO - Installing distribute.............................................................................................................................................................................................done. 03:55:18 INFO - Installing pip...............done. 03:55:18 INFO - Return code: 0 03:55:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:55:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:55:19 INFO - Reading from file tmpfile_stdout 03:55:19 INFO - Output received: 03:55:19 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:55:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:55:19 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:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:19 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:55:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:19 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:19 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:55:19 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:55:19 INFO - Using env: {'DISPLAY': ':0', 03:55:19 INFO - 'HOME': '/home/cltbld', 03:55:19 INFO - 'LANG': 'en_US.UTF-8', 03:55:19 INFO - 'LANGUAGE': 'en_US:en', 03:55:19 INFO - 'LOGNAME': 'cltbld', 03:55:19 INFO - 'MAIL': '/var/mail/cltbld', 03:55:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:19 INFO - 'MOZ_NO_REMOTE': '1', 03:55:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:19 INFO - 'NO_EM_RESTART': '1', 03:55:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:19 INFO - 'PWD': '/builds/slave/test', 03:55:19 INFO - 'SHELL': '/bin/bash', 03:55:19 INFO - 'SHLVL': '1', 03:55:19 INFO - 'TERM': 'linux', 03:55:19 INFO - 'TMOUT': '86400', 03:55:19 INFO - 'USER': 'cltbld', 03:55:19 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:19 INFO - '_': '/tools/buildbot/bin/python'} 03:55:20 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:55:20 INFO - Downloading/unpacking pip>=1.5 03:55:20 INFO - Running setup.py egg_info for package pip 03:55:20 INFO - warning: no files found matching 'pip/cacert.pem' 03:55:20 INFO - warning: no files found matching '*.html' under directory 'docs' 03:55:20 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:55:20 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:55:20 INFO - Installing collected packages: pip 03:55:20 INFO - Found existing installation: pip 1.1 03:55:20 INFO - Uninstalling pip: 03:55:20 INFO - Successfully uninstalled pip 03:55:20 INFO - Running setup.py install for pip 03:55:20 INFO - warning: no files found matching 'pip/cacert.pem' 03:55:20 INFO - warning: no files found matching '*.html' under directory 'docs' 03:55:20 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:55:20 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:55:20 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:55:20 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:55:20 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:55:20 INFO - Successfully installed pip 03:55:20 INFO - Cleaning up... 03:55:20 INFO - Return code: 0 03:55:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:55:20 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:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:20 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:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:20 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:20 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:55:20 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:55:20 INFO - Using env: {'DISPLAY': ':0', 03:55:20 INFO - 'HOME': '/home/cltbld', 03:55:20 INFO - 'LANG': 'en_US.UTF-8', 03:55:20 INFO - 'LANGUAGE': 'en_US:en', 03:55:20 INFO - 'LOGNAME': 'cltbld', 03:55:20 INFO - 'MAIL': '/var/mail/cltbld', 03:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:20 INFO - 'MOZ_NO_REMOTE': '1', 03:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:20 INFO - 'NO_EM_RESTART': '1', 03:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:20 INFO - 'PWD': '/builds/slave/test', 03:55:20 INFO - 'SHELL': '/bin/bash', 03:55:20 INFO - 'SHLVL': '1', 03:55:20 INFO - 'TERM': 'linux', 03:55:20 INFO - 'TMOUT': '86400', 03:55:20 INFO - 'USER': 'cltbld', 03:55:20 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:20 INFO - '_': '/tools/buildbot/bin/python'} 03:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:20 INFO - Downloading/unpacking psutil>=3.1.1 03:55:20 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:55:20 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:55:20 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:55:20 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:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:55:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:21 INFO - Installing collected packages: psutil 03:55:21 INFO - Running setup.py install for psutil 03:55:21 INFO - building 'psutil._psutil_linux' extension 03:55:21 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:55:21 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:55:22 INFO - building 'psutil._psutil_posix' extension 03:55:22 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:55:22 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:55:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:22 INFO - Successfully installed psutil 03:55:22 INFO - Cleaning up... 03:55:22 INFO - Return code: 0 03:55:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:22 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:22 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:55:22 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:55:22 INFO - Using env: {'DISPLAY': ':0', 03:55:22 INFO - 'HOME': '/home/cltbld', 03:55:22 INFO - 'LANG': 'en_US.UTF-8', 03:55:22 INFO - 'LANGUAGE': 'en_US:en', 03:55:22 INFO - 'LOGNAME': 'cltbld', 03:55:22 INFO - 'MAIL': '/var/mail/cltbld', 03:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:22 INFO - 'MOZ_NO_REMOTE': '1', 03:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:22 INFO - 'NO_EM_RESTART': '1', 03:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:22 INFO - 'PWD': '/builds/slave/test', 03:55:22 INFO - 'SHELL': '/bin/bash', 03:55:22 INFO - 'SHLVL': '1', 03:55:22 INFO - 'TERM': 'linux', 03:55:22 INFO - 'TMOUT': '86400', 03:55:22 INFO - 'USER': 'cltbld', 03:55:22 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:22 INFO - '_': '/tools/buildbot/bin/python'} 03:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:55:22 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:55:22 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:55:22 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:55:22 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:55:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:55:23 INFO - Installing collected packages: mozsystemmonitor 03:55:23 INFO - Running setup.py install for mozsystemmonitor 03:55:23 INFO - Successfully installed mozsystemmonitor 03:55:23 INFO - Cleaning up... 03:55:24 INFO - Return code: 0 03:55:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:24 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:24 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:55:24 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:55:24 INFO - Using env: {'DISPLAY': ':0', 03:55:24 INFO - 'HOME': '/home/cltbld', 03:55:24 INFO - 'LANG': 'en_US.UTF-8', 03:55:24 INFO - 'LANGUAGE': 'en_US:en', 03:55:24 INFO - 'LOGNAME': 'cltbld', 03:55:24 INFO - 'MAIL': '/var/mail/cltbld', 03:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:24 INFO - 'MOZ_NO_REMOTE': '1', 03:55:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:24 INFO - 'NO_EM_RESTART': '1', 03:55:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:24 INFO - 'PWD': '/builds/slave/test', 03:55:24 INFO - 'SHELL': '/bin/bash', 03:55:24 INFO - 'SHLVL': '1', 03:55:24 INFO - 'TERM': 'linux', 03:55:24 INFO - 'TMOUT': '86400', 03:55:24 INFO - 'USER': 'cltbld', 03:55:24 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:24 INFO - '_': '/tools/buildbot/bin/python'} 03:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:24 INFO - Downloading/unpacking jsonschema==2.5.1 03:55:24 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:55:24 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:55:24 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:55:24 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:55:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:55:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:55:25 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:55:25 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:55:25 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:55:25 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:55:25 INFO - Downloading functools32-3.2.3-2.tar.gz 03:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:55:25 INFO - warning: no files found matching '*.txt' 03:55:25 INFO - no previously-included directories found matching 'build' 03:55:25 INFO - no previously-included directories found matching 'dist' 03:55:25 INFO - no previously-included directories found matching '.git*' 03:55:25 INFO - Installing collected packages: jsonschema, functools32 03:55:25 INFO - Running setup.py install for functools32 03:55:25 INFO - warning: no files found matching '*.txt' 03:55:25 INFO - no previously-included directories found matching 'build' 03:55:25 INFO - no previously-included directories found matching 'dist' 03:55:25 INFO - no previously-included directories found matching '.git*' 03:55:25 INFO - Successfully installed jsonschema functools32 03:55:25 INFO - Cleaning up... 03:55:25 INFO - Return code: 0 03:55:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:55: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:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55: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:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:25 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:25 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:55:25 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:55:25 INFO - Using env: {'DISPLAY': ':0', 03:55:25 INFO - 'HOME': '/home/cltbld', 03:55:25 INFO - 'LANG': 'en_US.UTF-8', 03:55:25 INFO - 'LANGUAGE': 'en_US:en', 03:55:25 INFO - 'LOGNAME': 'cltbld', 03:55:25 INFO - 'MAIL': '/var/mail/cltbld', 03:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:25 INFO - 'MOZ_NO_REMOTE': '1', 03:55:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:25 INFO - 'NO_EM_RESTART': '1', 03:55:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:25 INFO - 'PWD': '/builds/slave/test', 03:55:25 INFO - 'SHELL': '/bin/bash', 03:55:25 INFO - 'SHLVL': '1', 03:55:25 INFO - 'TERM': 'linux', 03:55:25 INFO - 'TMOUT': '86400', 03:55:25 INFO - 'USER': 'cltbld', 03:55:25 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:25 INFO - '_': '/tools/buildbot/bin/python'} 03:55:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:55:26 INFO - Cleaning up... 03:55:26 INFO - Return code: 0 03:55:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:55:26 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:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:26 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:55:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:26 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:26 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:55:26 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:55:26 INFO - Using env: {'DISPLAY': ':0', 03:55:26 INFO - 'HOME': '/home/cltbld', 03:55:26 INFO - 'LANG': 'en_US.UTF-8', 03:55:26 INFO - 'LANGUAGE': 'en_US:en', 03:55:26 INFO - 'LOGNAME': 'cltbld', 03:55:26 INFO - 'MAIL': '/var/mail/cltbld', 03:55:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:26 INFO - 'MOZ_NO_REMOTE': '1', 03:55:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:26 INFO - 'NO_EM_RESTART': '1', 03:55:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:26 INFO - 'PWD': '/builds/slave/test', 03:55:26 INFO - 'SHELL': '/bin/bash', 03:55:26 INFO - 'SHLVL': '1', 03:55:26 INFO - 'TERM': 'linux', 03:55:26 INFO - 'TMOUT': '86400', 03:55:26 INFO - 'USER': 'cltbld', 03:55:26 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:26 INFO - '_': '/tools/buildbot/bin/python'} 03:55:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:26 INFO - Downloading/unpacking blobuploader==1.2.4 03:55:26 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:55:26 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:55:26 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:55:26 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:55:27 INFO - Downloading blobuploader-1.2.4.tar.gz 03:55:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:55:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:55:27 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:55:27 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:55:27 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:55:27 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:55:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:55:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:55:27 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:55:27 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:55:27 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:55:27 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:55:28 INFO - Downloading docopt-0.6.1.tar.gz 03:55:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:55:28 INFO - Installing collected packages: blobuploader, requests, docopt 03:55:28 INFO - Running setup.py install for blobuploader 03:55:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:55:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:55:28 INFO - Running setup.py install for requests 03:55:28 INFO - Running setup.py install for docopt 03:55:28 INFO - Successfully installed blobuploader requests docopt 03:55:28 INFO - Cleaning up... 03:55:28 INFO - Return code: 0 03:55:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55: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:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55: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:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:28 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:28 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:55:28 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:55:28 INFO - Using env: {'DISPLAY': ':0', 03:55:28 INFO - 'HOME': '/home/cltbld', 03:55:28 INFO - 'LANG': 'en_US.UTF-8', 03:55:28 INFO - 'LANGUAGE': 'en_US:en', 03:55:28 INFO - 'LOGNAME': 'cltbld', 03:55:28 INFO - 'MAIL': '/var/mail/cltbld', 03:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:28 INFO - 'MOZ_NO_REMOTE': '1', 03:55:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:28 INFO - 'NO_EM_RESTART': '1', 03:55:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:28 INFO - 'PWD': '/builds/slave/test', 03:55:28 INFO - 'SHELL': '/bin/bash', 03:55:28 INFO - 'SHLVL': '1', 03:55:28 INFO - 'TERM': 'linux', 03:55:28 INFO - 'TMOUT': '86400', 03:55:28 INFO - 'USER': 'cltbld', 03:55:28 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:28 INFO - '_': '/tools/buildbot/bin/python'} 03:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:28 INFO - Running setup.py (path:/tmp/pip-R6BcMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:29 INFO - Running setup.py (path:/tmp/pip-0bJTPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:29 INFO - Running setup.py (path:/tmp/pip-I7MdtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:29 INFO - Running setup.py (path:/tmp/pip-G5LeEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:29 INFO - Running setup.py (path:/tmp/pip-Fw2nQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:29 INFO - Running setup.py (path:/tmp/pip-M5fdEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:29 INFO - Running setup.py (path:/tmp/pip-wtPZUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:29 INFO - Running setup.py (path:/tmp/pip-ovRvlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:29 INFO - Running setup.py (path:/tmp/pip-TBCzwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:29 INFO - Running setup.py (path:/tmp/pip-oapXny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:29 INFO - Running setup.py (path:/tmp/pip-mnxGZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:29 INFO - Running setup.py (path:/tmp/pip-CVRSoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:29 INFO - Running setup.py (path:/tmp/pip-ECFQGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:29 INFO - Running setup.py (path:/tmp/pip-NN4q6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:29 INFO - Running setup.py (path:/tmp/pip-_gylYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:30 INFO - Running setup.py (path:/tmp/pip-q4ZLHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:30 INFO - Running setup.py (path:/tmp/pip-qqINqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:55:30 INFO - Running setup.py install for manifestparser 03:55:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:55:30 INFO - Running setup.py install for mozcrash 03:55:30 INFO - Running setup.py install for mozdebug 03:55:30 INFO - Running setup.py install for mozdevice 03:55:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:55:30 INFO - Running setup.py install for mozfile 03:55:30 INFO - Running setup.py install for mozhttpd 03:55:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:55:30 INFO - Running setup.py install for mozinfo 03:55:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:55:30 INFO - Running setup.py install for mozInstall 03:55:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:55:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:55:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:55:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:55:30 INFO - Running setup.py install for mozleak 03:55:31 INFO - Running setup.py install for mozlog 03:55:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:55:31 INFO - Running setup.py install for moznetwork 03:55:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:55:31 INFO - Running setup.py install for mozprocess 03:55:31 INFO - Running setup.py install for mozprofile 03:55:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:55:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:55:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:55:31 INFO - Running setup.py install for mozrunner 03:55:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:55:31 INFO - Running setup.py install for mozscreenshot 03:55:31 INFO - Running setup.py install for moztest 03:55:31 INFO - Running setup.py install for mozversion 03:55:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:55:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:55:31 INFO - Cleaning up... 03:55:31 INFO - Return code: 0 03:55:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55:31 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:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:31 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:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:31 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:31 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:55:31 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:55:31 INFO - Using env: {'DISPLAY': ':0', 03:55:31 INFO - 'HOME': '/home/cltbld', 03:55:31 INFO - 'LANG': 'en_US.UTF-8', 03:55:31 INFO - 'LANGUAGE': 'en_US:en', 03:55:31 INFO - 'LOGNAME': 'cltbld', 03:55:31 INFO - 'MAIL': '/var/mail/cltbld', 03:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:31 INFO - 'MOZ_NO_REMOTE': '1', 03:55:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:31 INFO - 'NO_EM_RESTART': '1', 03:55:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:31 INFO - 'PWD': '/builds/slave/test', 03:55:31 INFO - 'SHELL': '/bin/bash', 03:55:31 INFO - 'SHLVL': '1', 03:55:31 INFO - 'TERM': 'linux', 03:55:31 INFO - 'TMOUT': '86400', 03:55:31 INFO - 'USER': 'cltbld', 03:55:31 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:31 INFO - '_': '/tools/buildbot/bin/python'} 03:55:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:32 INFO - Running setup.py (path:/tmp/pip-8xsB_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:32 INFO - Running setup.py (path:/tmp/pip-8I8MpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:32 INFO - Running setup.py (path:/tmp/pip-kFIc3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:32 INFO - Running setup.py (path:/tmp/pip-BSAZv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:32 INFO - Running setup.py (path:/tmp/pip-9pCcVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:32 INFO - Running setup.py (path:/tmp/pip-UyPrKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:32 INFO - Running setup.py (path:/tmp/pip-BKRPEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:32 INFO - Running setup.py (path:/tmp/pip-N1BskS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:32 INFO - Running setup.py (path:/tmp/pip-W8FCKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:32 INFO - Running setup.py (path:/tmp/pip-TwEdkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:32 INFO - Running setup.py (path:/tmp/pip-2FGFDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:32 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:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:32 INFO - Running setup.py (path:/tmp/pip-4XhsY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:33 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:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:33 INFO - Running setup.py (path:/tmp/pip-H6blFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:33 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:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:33 INFO - Running setup.py (path:/tmp/pip-QbQOXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:33 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:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:33 INFO - Running setup.py (path:/tmp/pip-c2_yg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:33 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:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:33 INFO - Running setup.py (path:/tmp/pip-69RoKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:33 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:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:33 INFO - Running setup.py (path:/tmp/pip-F4LfEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:33 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:55:33 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:55:33 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:55:33 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:55:33 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:55:33 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:55:33 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:55:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:55: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:55: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:55: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:55: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:55:34 INFO - Downloading blessings-1.6.tar.gz 03:55:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:55:34 INFO - Installing collected packages: blessings 03:55:34 INFO - Running setup.py install for blessings 03:55:34 INFO - Successfully installed blessings 03:55:34 INFO - Cleaning up... 03:55:34 INFO - Return code: 0 03:55:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:55:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:34 INFO - Reading from file tmpfile_stdout 03:55:34 INFO - Current package versions: 03:55:34 INFO - argparse == 1.2.1 03:55:34 INFO - blessings == 1.6 03:55:34 INFO - blobuploader == 1.2.4 03:55:34 INFO - docopt == 0.6.1 03:55:34 INFO - functools32 == 3.2.3-2 03:55:34 INFO - jsonschema == 2.5.1 03:55:34 INFO - manifestparser == 1.1 03:55:34 INFO - mozInstall == 1.13 03:55:34 INFO - mozcrash == 1.0 03:55:34 INFO - mozdebug == 0.1 03:55:34 INFO - mozdevice == 0.51 03:55:34 INFO - mozfile == 1.2 03:55:34 INFO - mozhttpd == 0.7 03:55:34 INFO - mozinfo == 0.10 03:55:34 INFO - mozleak == 0.1 03:55:34 INFO - mozlog == 3.5 03:55:34 INFO - moznetwork == 0.27 03:55:34 INFO - mozprocess == 0.25 03:55:34 INFO - mozprofile == 0.28 03:55:34 INFO - mozrunner == 6.13 03:55:34 INFO - mozscreenshot == 0.1 03:55:34 INFO - mozsystemmonitor == 0.3 03:55:34 INFO - moztest == 0.8 03:55:34 INFO - mozversion == 1.4 03:55:34 INFO - psutil == 3.1.1 03:55:34 INFO - requests == 1.2.3 03:55:34 INFO - wsgiref == 0.1.2 03:55:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55:34 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:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:34 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:55:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:34 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:34 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:55:34 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:55:34 INFO - Using env: {'DISPLAY': ':0', 03:55:34 INFO - 'HOME': '/home/cltbld', 03:55:34 INFO - 'LANG': 'en_US.UTF-8', 03:55:34 INFO - 'LANGUAGE': 'en_US:en', 03:55:34 INFO - 'LOGNAME': 'cltbld', 03:55:34 INFO - 'MAIL': '/var/mail/cltbld', 03:55:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:34 INFO - 'MOZ_NO_REMOTE': '1', 03:55:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:34 INFO - 'NO_EM_RESTART': '1', 03:55:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:34 INFO - 'PWD': '/builds/slave/test', 03:55:34 INFO - 'SHELL': '/bin/bash', 03:55:34 INFO - 'SHLVL': '1', 03:55:34 INFO - 'TERM': 'linux', 03:55:34 INFO - 'TMOUT': '86400', 03:55:34 INFO - 'USER': 'cltbld', 03:55:34 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:34 INFO - '_': '/tools/buildbot/bin/python'} 03:55:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:35 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:55:35 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:55:35 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:55:35 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:55:35 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:55:35 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:55:35 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:55:35 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:55:35 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:55:35 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:55: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:55: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:55: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:55: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:55:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:55:36 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:55:36 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:55:36 INFO - Installing collected packages: simplejson 03:55:36 INFO - Running setup.py install for simplejson 03:55:36 INFO - building 'simplejson._speedups' extension 03:55:36 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:55:37 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:55:37 INFO - Successfully installed simplejson 03:55:37 INFO - Cleaning up... 03:55:37 INFO - Return code: 0 03:55:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:55:37 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:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:37 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:55:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:37 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 0x156c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1734b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d99c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, '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': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', '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:55:37 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:55:37 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:55:37 INFO - Using env: {'DISPLAY': ':0', 03:55:37 INFO - 'HOME': '/home/cltbld', 03:55:37 INFO - 'LANG': 'en_US.UTF-8', 03:55:37 INFO - 'LANGUAGE': 'en_US:en', 03:55:37 INFO - 'LOGNAME': 'cltbld', 03:55:37 INFO - 'MAIL': '/var/mail/cltbld', 03:55:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:37 INFO - 'MOZ_NO_REMOTE': '1', 03:55:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:37 INFO - 'NO_EM_RESTART': '1', 03:55:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:37 INFO - 'PWD': '/builds/slave/test', 03:55:37 INFO - 'SHELL': '/bin/bash', 03:55:37 INFO - 'SHLVL': '1', 03:55:37 INFO - 'TERM': 'linux', 03:55:37 INFO - 'TMOUT': '86400', 03:55:37 INFO - 'USER': 'cltbld', 03:55:37 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:37 INFO - '_': '/tools/buildbot/bin/python'} 03:55:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:55:37 INFO - Cleaning up... 03:55:37 INFO - Return code: 0 03:55:37 INFO - Running post-action listener: _resource_record_post_action 03:55:37 INFO - Running post-action listener: _start_resource_monitoring 03:55:37 INFO - Starting resource monitoring. 03:55:37 INFO - [mozharness: 2017-09-25 10:55:37.646662Z] Finished create-virtualenv step (success) 03:55:37 INFO - [mozharness: 2017-09-25 10:55:37.647181Z] Running install step. 03:55:37 INFO - Running pre-action listener: _resource_record_pre_action 03:55:37 INFO - Running main action method: install 03:55:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:37 INFO - Reading from file tmpfile_stdout 03:55:37 INFO - Detecting whether we're running mozinstall >=1.0... 03:55:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:55:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:55:38 INFO - Reading from file tmpfile_stdout 03:55:38 INFO - Output received: 03:55:38 INFO - Usage: mozinstall [options] installer 03:55:38 INFO - Options: 03:55:38 INFO - -h, --help show this help message and exit 03:55:38 INFO - -d DEST, --destination=DEST 03:55:38 INFO - Directory to install application into. [default: 03:55:38 INFO - "/builds/slave/test"] 03:55:38 INFO - --app=APP Application being installed. [default: firefox] 03:55:38 INFO - mkdir: /builds/slave/test/build/application 03:55:38 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:55:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:55:55 INFO - Reading from file tmpfile_stdout 03:55:55 INFO - Output received: 03:55:55 INFO - /builds/slave/test/build/application/firefox/firefox 03:55:55 INFO - Running post-action listener: _resource_record_post_action 03:55:55 INFO - [mozharness: 2017-09-25 10:55:55.776667Z] Finished install step (success) 03:55:55 INFO - [mozharness: 2017-09-25 10:55:55.776917Z] Running setup-mitmproxy step. 03:55:55 INFO - Running pre-action listener: _resource_record_pre_action 03:55:55 INFO - Running main action method: setup_mitmproxy 03:55:55 INFO - Skipping: mitmproxy is not required 03:55:55 INFO - Running post-action listener: _resource_record_post_action 03:55:55 INFO - [mozharness: 2017-09-25 10:55:55.778150Z] Finished setup-mitmproxy step (success) 03:55:55 INFO - [mozharness: 2017-09-25 10:55:55.778372Z] Running run-tests step. 03:55:55 INFO - Running pre-action listener: _resource_record_pre_action 03:55:55 INFO - Running pre-action listener: _set_gcov_prefix 03:55:55 INFO - Running main action method: run_tests 03:55:55 WARNING - Try message not found. 03:55:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:55:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:55:55 INFO - Python 2.7.3 03:55:55 INFO - Return code: 0 03:55:55 INFO - grabbing minidump binary from tooltool 03:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:55 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 0x18d6d60>, '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 0x18d7a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d7ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:55:55 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:55:55 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:55:55 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:55:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:55:55 INFO - Return code: 0 03:55:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:55:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:55:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:55:55 INFO - ENV: RUST_BACKTRACE is now full 03:55:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:55:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:55:55 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/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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:55:55 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/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-072 --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:55:55 INFO - Using env: {'DISPLAY': ':0', 03:55:55 INFO - 'HOME': '/home/cltbld', 03:55:55 INFO - 'LANG': 'en_US.UTF-8', 03:55:55 INFO - 'LANGUAGE': 'en_US:en', 03:55:55 INFO - 'LOGNAME': 'cltbld', 03:55:55 INFO - 'MAIL': '/var/mail/cltbld', 03:55:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:55:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:55 INFO - 'MOZ_NO_REMOTE': '1', 03:55:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:55:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:55 INFO - 'NO_EM_RESTART': '1', 03:55:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:55 INFO - 'PWD': '/builds/slave/test', 03:55:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:55:55 INFO - 'RUST_BACKTRACE': 'full', 03:55:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:55:55 INFO - 'SHELL': '/bin/bash', 03:55:55 INFO - 'SHLVL': '1', 03:55:55 INFO - 'TERM': 'linux', 03:55:55 INFO - 'TMOUT': '86400', 03:55:55 INFO - 'USER': 'cltbld', 03:55:55 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:55 INFO - '_': '/tools/buildbot/bin/python'} 03:55:55 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/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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:55:56 INFO - mozversion application_buildid: 20170925095842 03:55:56 INFO - mozversion application_changeset: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 03:55:56 INFO - mozversion application_display_name: Nightly 03:55:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:55:56 INFO - mozversion application_name: Firefox 03:55:56 INFO - mozversion application_remotingname: firefox 03:55:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:55:56 INFO - mozversion application_vendor: Mozilla 03:55:56 INFO - mozversion application_version: 58.0a1 03:55:56 INFO - mozversion platform_buildid: 20170925095842 03:55:56 INFO - mozversion platform_changeset: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 03:55:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:55:56 INFO - mozversion platform_version: 58.0a1 03:55:56 INFO - using testdate: 1506336956 03:55:56 INFO - actual date: 1506336956 03:55:56 INFO - starting webserver on 'localhost:41271' 03:55:56 INFO - SUITE-START | Running 2 tests 03:55:56 INFO - TEST-START | dromaeo_css 03:55:56 INFO - Initialising browser for dromaeo_css test... 03:55:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41271/getInfo.html -profile /tmp/tmpFRHrOV/profile 03:55:56 INFO - TEST-INFO | started process 21469 (/builds/slave/test/build/application/firefox/firefox http://localhost:41271/getInfo.html) 03:56:02 INFO - TEST-INFO | 21469: exit 0 03:56:02 INFO - Browser initialized. 03:56:02 INFO - Running cycle 1/1 for dromaeo_css test... 03:56:02 INFO - Using env: {'DISPLAY': ':0', 03:56:02 INFO - 'HOME': '/home/cltbld', 03:56:02 INFO - 'JSGC_DISABLE_POISONING': '1', 03:56:02 INFO - 'LANG': 'en_US.UTF-8', 03:56:02 INFO - 'LANGUAGE': 'en_US:en', 03:56:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:56:02 INFO - 'LOGNAME': 'cltbld', 03:56:02 INFO - 'MAIL': '/var/mail/cltbld', 03:56:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:56:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:56:02 INFO - 'MOZ_CRASHREPORTER': '1', 03:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:56:02 INFO - 'MOZ_NO_REMOTE': '1', 03:56:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:56:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:02 INFO - 'NO_EM_RESTART': '1', 03:56:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:02 INFO - 'PWD': '/builds/slave/test', 03:56:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:56:02 INFO - 'RUST_BACKTRACE': 'full', 03:56:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:56:02 INFO - 'SHELL': '/bin/bash', 03:56:02 INFO - 'SHLVL': '1', 03:56:02 INFO - 'TERM': 'linux', 03:56:02 INFO - 'TMOUT': '86400', 03:56:02 INFO - 'USER': 'cltbld', 03:56:02 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 03:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:02 INFO - '_': '/tools/buildbot/bin/python'} 03:56:02 INFO - TEST-INFO | started process 21703 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFRHrOV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:56:03 INFO - PID 21703 | 03:56:03 INFO - PID 21703 | (/builds/slave/test/build/application/firefox/firefox:21753): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:56:03 INFO - PID 21703 | 03:56:13 INFO - PID 21703 | RSS: Main: 147513344 03:56:13 INFO - PID 21703 | 03:59:11 INFO - PID 21703 | Cycle 1(1): loaded http://localhost:41271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41271/tests/dromaeo/cssquery-ext.html) 03:59:12 INFO - PID 21703 | RSS: Main: 152166400 03:59:12 INFO - PID 21703 | 04:02:14 INFO - PID 21703 | Cycle 1(1): loaded http://localhost:41271/tests/dromaeo/cssquery-ext.html (next: http://localhost:41271/tests/dromaeo/cssquery-jquery.html) 04:02:15 INFO - PID 21703 | RSS: Main: 151760896 04:02:15 INFO - PID 21703 | 04:05:16 INFO - PID 21703 | Cycle 1(1): loaded http://localhost:41271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41271/tests/dromaeo/cssquery-mootools.html) 04:05:17 INFO - PID 21703 | RSS: Main: 153194496 04:05:17 INFO - PID 21703 | 04:08:19 INFO - PID 21703 | Cycle 1(1): loaded http://localhost:41271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41271/tests/dromaeo/cssquery-prototype.html) 04:08:19 INFO - PID 21703 | RSS: Main: 152731648 04:08:19 INFO - PID 21703 | 04:11:22 INFO - PID 21703 | Cycle 1(1): loaded http://localhost:41271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41271/tests/dromaeo/cssquery-yui.html) 04:11:23 INFO - PID 21703 | RSS: Main: 153817088 04:11:23 INFO - PID 21703 | 04:14:25 INFO - PID 21703 | Cycle 1(1): loaded http://localhost:41271/tests/dromaeo/cssquery-yui.html (next: http://localhost:41271/tests/dromaeo/cssquery-dojo.html) 04:14:25 INFO - PID 21703 | RSS: Main: 156626944 04:14:25 INFO - PID 21703 | 04:14:25 INFO - PID 21703 | __start_tp_report 04:14:25 INFO - PID 21703 | _x_x_mozilla_page_load 04:14:25 INFO - PID 21703 | _x_x_mozilla_page_load_details 04:14:25 INFO - PID 21703 | |i|pagename|runs| 04:14:25 INFO - PID 21703 | |0;dojo.html;3142.2615685313954;3157.4316623007862;3173.3018735878127;3183.602049743781;3203.8558264878093;6248;6312.9684351578235;6347.1431356766825;6381.266154392246;6463.676816159191;6276.215473065867;6334;6339.9683001585045;6382.4574911132495;6386.265579458363;5826.621269617472;5840.503557197643;5863.882722345551;5899.646021238739;5931.051031834907;5895.35151133375;5903.055511118222;5934.406559344075;5967.880642387149;5972.492338151241;5834.912476312858;5858.297004359483;5889.558283128783;5956.463918247357;5998.340182579913;5827.271591051119;5867.11993201019;5909.290885093773;5957.493613042897;5976.372480889488;5762.97118514409;5791.793963398235;5811.215485909391;5864.472197502203;5877.794277200277;6282.340354262828;6286.654234017127;6289.339619339949;6299.7165127568915;6301.432871041582;6719.092922455504;6726.697298621574;6809.250982391933;6816.522843400964;6820.556663816836;6682.465402767767;6791.4906382021545;6797.116374871235;6869.587824730482;6882.793516194522;6288.685565721696;6417.582857114273;6445.83885402864;6449.871002579969;6462.289148193742;7029.22678505364;7039.8240043998285;7109.324614161646;7125.608091555015;7144.214136444987;6522.934770652233;6653.567518111307;6691.230508491489;6718.798436046829;6820.897686534706;6740.325967403221;6752.83117922046;6755.898661520081;6760.121184246016;6770.322967703289;6675.566088204251;6717.832054198684;6739.663016849138;6742.224644165885;6743.898841517381;6570.868582628416;6713.12729345182;6765;6818.181818181751;6834.58992460444;5299.867503312371;5355.410904800468;5362.034833729888;5368;5394.865128371744;4604.930926036112;4623.491415944243;4634.907301853944;4679.344891715163;4691.929621055687;5790.073588225863;5833.125031245262;5855.268091488564;5856.707164641751;5868.061110222344;6819.67016431791;6912.274211207851;6923.9653801730665;6953.652317384111;6970.093887794555;5901.557383196277;5906.970465147732;5965.642061476326;5979;5980.372060933626;5743.282089738783;5810.535157187415;5843.328017277982;5884.176215329857;5950;6649.368310010541;6716.126903502513;6724.899126513106;6729.6971636276485;6732.562383445158;6830.351116643911;6831.59010459374;6841.93158068413;6844.315568443116;6932.7226910923;6548.476121910331;6635.701393437404;6652.035454859115;6676.9332306676315;6721.697523611547;6781.93218067813;6803.965980170067;6813.69338379784;6843.828904277433;6849.691763870737;6838.418734407432;6865.656717164022;6867.450603951772;6947.201071876799;6955.130608673916;6590.934090659032;6596.8350791230605;6622.1722284714415;6686.1642294713165;6692.564983276072;6474.870502590016;6582.880910245174;6585.670716464253;6587.47300215972;6592.307807680317;6450.096986421999;6485.967570162119;6488.6755662215755;6493.188351456068;6540.934590654223;6601.207855057424;6717.529772915849;6726.529142960141;6809.455243580601;6810.012548180585;6222.942099843128;6405.071264666691;6449.3873082057125;6479.287278399466;6585.670716464253 04:14:25 INFO - PID 21703 | |1;ext.html;867.5532100968003;878.9340799440043;880.8546589812681;916.7249825052485;917.1378903830399;39741;40293.798531007305;40470.7976460118;40500.59499405005;40649.3902591461;37818.43272350917;38124.42813357802;38190.42714359287;38225.808870955676;38274.425883611766;7065.717371305154;7228.0603521542125;7231.855362892739;7233.638318084075;7235.312645298688;7080.468964827633;7196.928030719678;7198.7120515179595;7210.783676489714;7226.349628533431;7186.928130718704;7791.259830316111;7963.721269755531;7969.043714754238;7986.880196797053;7865.134835168127;7931.246531579548;7938.404619653549;7938.880916786253;7946.0464744230485;7687.231276872324;7790.259925307087;7859.410544209207;7859.96070019652;7875.763727088197;37213.813930930446;37416.43875341872;37494.81252593747;37505.24989500194;37579.81210093959;3498.825058747078;3525.1892064825233;3540.7698499597445;3546;3548.1307079765365;30405.847970760005;30470.54294185589;30500.38999220003;30531.542026869614;30538.08385748431;5094.6688465249645;5136.0241554104605;5136.486351364871;5155.7679904404395;5180.352455943007;188028;188149;188149;188860;189297;46221.30668039982;46347.30479042817;46363.53636463592;46439.99999999932;46495.07009859852;48196.27705584419;48328.5167148331;48338.27492587614;48431.5156848427;48487.757561212675;15784.36862525509;16184.10987395681;16185.109818959834;16192.514224573282;16198.595035124215;13113.409896554678;13188.340582970814;13207.933960330329;13210.669733256553;13212.273324967218;13530.323483825769;13629.522966696317;13675.863241367659;13817.240051797247;13845.307734613229;5450.8909821803345;5521.723913804293;5550;5577.330720313588;5738.254026976467;982.9115379615725;997.2620261006948;1008.3193844155165;1014.3660212367271;1019.2814066083394;22669;22917.656235156486;23028;23084;23153.305400838002;1089;1097.3470784882982;1102.8180350242121;1114.3146964616703;1116.4027245423658;12209.877901220874;12312.75374492523;12330.753384932252;12382.938085309695;12502.874971250352;217897;220159;220734;221687;222381;44441.55558444439;45087.32369014467;51188.232176517384;51918;52146.47853521416;1423.978640320396;1442.213993373614;1443.487561915526;1446.5660301909386;1457.0383546859234;27064.594031089942;27069.86465067662;27360.315992100357;27405.45189096167;28040.579391309555;36320;36558.35487403126;36662.26675466529;36706;36726.26547468982;192567;193722;194209;194442;194513;30822;31483.370332593677;31779.682203178596;31812.8409357961;31817;40455;40931;41147.1770564593;41694.166116678105;41706.58293417027;30845.537316939815;31189;31490;31678.36643267168;31899.84050079735;30934.381312373178;31010.379792404477;31108.377832443675;31198.68801311958;31649.367012659157;35309;35458.113547160494;35527.11182220465;35582.644173557936;35670.464943026396;30416;30730;30872.536911946787;30932.53601195939;31146;22697.546049078595;22760;23010.654840177074;23030.193943211907;23044.308670739905 04:14:25 INFO - PID 21703 | |2;jquery.html;588.3263663105747;594.5095296380484;600.9639421634703;603.3483837455549;606.5178183344242;22367.55264894701;22988.08047678093;23011.194608188718;23025.539489210205;23133.652995205044;23388.298351049496;24302.75697243027;25326.113586024494;25497.362565935862;25556.488870222584;6477.708503117347;6593.472522198213;6632.403083722464;6641.203055633307;6665.733370665168;6340.587861788992;6466.805995820133;6596.967015164917;6606.207255129391;6623.735050597995;6595.97762346837;6614.536982411233;6622.602643841385;6638.302978187293;6643.402093811556;6599.57102788322;6601.2408573014;6614.239362473305;6615.636140012298;6627.1716035495565;5901.23283973085;6149.13912052313;6151.5386346024225;6151.846203844895;6163.137160797492;28989.275268118254;29522.852385738148;29590.408191836043;29611.407771844442;29682.257943551584;1981.5838673878502;1993.9102740376718;2008.4777957731096;2011.0246530432876;2016.6974953756908;18712.251509939586;18963.71554426671;19257.4222773317;19260.325888593972;19402.902985485125;2379.3218932604123;2382.702162229721;2455.3002394317714;2455.8526488410753;2464.9260522184363;123077;128803.35598322042;133997;140708.77873663805;141786.29106854502;14700.941868138469;15347.23263836826;15382.846171538366;15509.379624814881;15649.687006259812;23151.652725209136;23174.53650926972;23280.7671923282;23294.3011709649;23332.7666723334;10288.742781430525;10298.279120461646;10320.019598138166;10371.222158338154;10461.581536738446;9454.669086581936;9497.81550243448;9592.808143837225;9592.856107158399;9756.560954757053;9028.909710902939;9134.314926380548;9207.447553146832;9283.25733941283;9462.073239605625;2576.5362234797917;2593.5590949538623;2648.510025645262;2705.7947772565294;2854.414844956779;949.8290307744672;966.1546147121269;984.7981163861391;993.6124911284605;1005.3666190300211;19344.129514171895;19883.204671813255;19986.400407987785;20158;20290.79709202889;1028.2236911132047;1037.258360272409;1054.4306074719718;1056.9788604227872;1064.1274155192668;10977.506012229469;10991.890081099247;10998.615048473424;11010.50452729629;11302.830457543143;437425;461135;466407;476292;477487;106788;108863.45568272108;111116.44441777901;111283.44358278156;111655;1063.4363787192801;1093;1159.5535718748342;1171.2211379432752;1209.2683926224684;20542.281020164224;20902.47743806417;20975.685364719848;21074.15703371848;21260.149594016686;54401;54461;55252.85228956497;55412.16881746858;56457.71771141118;68744.62510749858;68949;69033.65483172552;69668;69801;13871.23708196059;14361.425542978583;14550.636234094232;14681.41274349014;14763.114213147246;22582.322530324116;22632.66051009269;22760.658590121493;23014.539709205386;23101.422464438525;18320.08399580042;18448.26206951707;18671.692981491164;18749.718754218426;18803.435896923114;15111.697766044837;15602.297896594453;15999.440019599258;16039.67920641604;16606.750898736285;18150.636987260445;18949.810501894804;18978.145983430502;19084.904575477034;19090.618187635893;14428.42286308536;14443.566692999228;14493.7825932609;14525.564233073024;14544.927275363556;17764.55588610295;17780;18362.72455913189;18500;18521.259149633865 04:14:25 INFO - PID 21703 | |3;mootools.html;1346.2057386142174;1412.660961369271;1420.7584710599197;1424.0885833066832;1438.546857739812;15232.69534609307;15940;15978.84021159788;15980.920095399535;15991.800614953896;15702.607434814136;15707.92146039271;15780.132092734895;15810.209489525507;15828.287727052282;2802.187365663955;2841.4459180459817;2891.0893068683404;2896.9275768105754;2910.4179164167144;2705.864706764664;2769.2523018784914;2772.265349682336;2774.4173723517983;2781.5132351838347;2336.707911511061;2415.5531226723024;2424.793892519138;2722.7413395727376;2786.512360336943;2579.548578998668;2593.1053786443645;2599.8180127391092;2606.713261541229;2612.6211699303685;2577.935551611206;2628.6451329070524;2652.814302998791;2657.3755167535614;2657.4419371931726;15134.545963620998;15459.613509662237;15493.225338733018;15529.223538823013;15545.689086218212;7743;8039.0353157620875;8040.155783642692;8047.718329858485;8052.033755949324;4972.925406118911;5375.650582712111;5533.3083364579425;5548.361938377098;5695.117256825199;1947.7273181754565;1974.1807150032719;1975.3481351154085;2035.1452389996236;2035.2266138867283;27360.315992100157;27376.58935115975;27641.170764876886;27778;27836.860815695793;7166.641667916583;7191.1011123608505;7269.818254543678;7398.815029624195;7408;20904.47738806542;20994.37016889496;21077.894610526848;21111.155553777677;21113.366599002056;8451.746447606582;8464.687973364138;8475.194856488624;8478.618462169217;8482.321414286842;7331.963340183265;7593.392528597699;7630.542167469969;7633.351165150913;7643.617819109022;5649.152627105967;5824.475797178275;5837.328707198726;5886.676232807151;5919.940800591939;1796.2186448894772;1847.1503108569939;1856.1832793570786;1858.3774435564237;1877.802830702784;892.0900681305162;901.454619954931;902.7066203483894;914.2685851318919;915.3409545127497;8401.663933442594;8688.087750786202;8763.693270735494;8819.250363719028;8845.955770221108;738.0737174845534;740.6629983357559;741.195802554224;746.145663215626;759.400073941591;4659.976700116477;4681.461631912305;4703.223968045303;4716.268978308368;4730.810767569327;20480.283190088274;21638.242661507087;21818.781812181674;22176.334709958726;22265;21636.026378812676;21845.126194952023;21973.02242798156;22115.33653990383;22276.443088922908;10354.896451035393;10420.583176673;10712.625058122929;10723.249372544004;10794.460276986276;8494.660213591387;8634.438761480735;8663.783405414915;8667.263282620796;8698.956505217433;18255.08724563793;19029;19198.328058517884;19418.417447476597;19435.056494351018;19610.80389196147;19660;19745.308914187935;19948.3018094366;20496.282630107875;12456.875431245571;12756.170848894792;12773.425195866022;12776.61670149897;12777.425015874494;14133;14168.787468188191;14197.432102715775;14346.784798227825;14440.927795360956;11338.376389298668;11427.485763140512;11516.884831151916;11779.941100294443;11834.349110798988;10937.781244375226;11137.554497819996;11274;11372.943135284271;11651.359175245103;9578.856317155109;9642.325037247607;9751.073648003428;9765.804683906425;9885.851712224467;7330.560166390033;7487.887681684888;7536;7552.886706699294;7665.731699390718;7070.893936590853;7151.106111736033;7154.928450715426;7176.425885929218;7226.819329516804 04:14:25 INFO - PID 21703 | |4;prototype.html;509.77315094782836;520.2924023328271;539.606087556084;548.3748526679589;554.4455544455544;6251.781187658433;6300;6304.968475157623;6308.716107775155;6341.778037768679;6031.898309149777;6090.23872015998;6187.876242475148;6191.566590338678;6206;5197.558207552353;5206.193040078794;5236.659617124875;5239.397469291023;5241.42344342122;4744.739039352834;4942.3327850740225;5057.620678449131;5195.714235717034;5229.633925625208;4945.381827271591;4946.505349465061;4960.70235785854;4979.253112033188;4980.352554167953;4906.803727850882;4916.975415122919;4917.336159618477;4976.154053810861;4983.6295018870105;4435.270244604607;4707.552782485658;4751.95248047522;4822.927656085161;4836.733979631119;6024.427679370453;6055.788047418363;6111.480524155452;6117.357677443893;6149.692515374258;5843.795467158629;5898;5926.051831706949;5980.671063091529;5988.490978266853;6526.836829079263;6546.6072035677535;6601.63690996995;6602.496540907203;6610.768623098168;5038.370203724535;5247.790088396459;5329.200619907045;5334.946650533484;5335.439778823206;6653.866922661519;6699.899501507482;6801.251862295144;6846.178458584902;6883.8967415488805;5411;5425.294711687456;5438.91841622376;5450.482204190595;5523.226748255248;5559.607909751332;5582.525485333792;5621.325440947113;5623.212750214973;5632.239647647515;5973.49225315853;5975.312839023568;5992.940070599238;6038.547108966845;6046.244219472566;6524.380183882524;6530.444912182517;6583.144191255106;6604.900926486107;6610.30591787865;1883.3125909043101;1904.8190421910174;1927.3447028010544;1945.105251584259;1946.7955864634293;1295.2163940815672;1357.1856885868597;1427.8286605607393;1435.3397437178805;1459.0807791091545;5542.8337149885565;5560.165975103686;5594.496495315441;5617.41017193197;5660.830175094754;6506.707198176094;6608.207015158116;6627;6637.103990961255;6691.698873550702;5622.935500152426;5626.5524496844;5658.094704847287;5685.346185188673;5691.402402747734;3407.591089069278;3411.1813164840755;3442.2599141184264;3461.3077384523194;3471.062813040465;6129.3450768293205;6233.407826256589;6318.4313411793155;6349.238091429058;6386.521010924106;6717.607608157947;6783.152105986752;6806.285340039224;6808.2170550386345;6873.766026041557;6361.777337793247;6377.426031656987;6412.9679351602945;6484.610923344614;6506.414422701885;6451.774187903401;6504;6552.83617909537;6578.506612004215;6624.90062649051;6472.611643301417;6535.313792051957;6570.954721655992;6608.58786542918;6655.633940133339;6189.721462534287;6390.265119511316;6454.354564543508;6462.192225971754;6509.641969691523;5005.34930459038;5028.120078986236;5045.596352291881;5058.291839142449;5072.54347108762;6747.696353663999;6751.223609284799;6798.32016798336;6808.285130061272;6845.760398386033;6320.336364681642;6411.551191416556;6444;6454.903176452263;6611.438027767694;6439;6442.226932768098;6471.417572418505;6490.578112422678;6553.4101930826455;6404.398900274931;6409.935900640934;6423.903641445476;6444.871102578016;6453.128827608213;6487.740490380521;6500.504883876641;6521.34786521363;6551.44312733423;6555.983822507519;6115.541334400027;6199.5660303780105;6253.405926436982;6272.8431789203805;6286.277078136073 04:14:25 INFO - PID 21703 | |5;yui.html;910.0853642089698;928.5078908178659;934.1219253901332;935.8034812689339;941.6986564299423;20000.299989500352;20271.898640506814;20671.586568268627;20675;20680.172793088277;19002.71495927562;19336.936468494227;19348.419547413563;19355.903220483913;19397.806021939778;966.5747071288628;980.4460479828874;984.714432814483;985.635314933472;985.7634167799748;959.7984423271122;990.2078337330144;997.6807421625083;998.1665309466567;998.4807899892052;1047.0524175621076;1047.1361127070159;1048.391932679048;1048.4076496779333;1049.7847941172079;992.8907820139779;1031.6234574380371;1045.7594753206768;1049.275999560301;1050.154625526451;750.0586763611688;750.9699612015513;753.7851712261979;754.728297812787;755.7770457714987;20288.188472461232;20365.694514582294;20433.5913281735;20504.17983280667;20591.279305224394;21038.894805525873;22425.99083041267;22706.772932270633;22707.432314192112;22743.20398786051;17983.370582029565;18011.72982405252;18017;18018.36935707244;18142.45572632823;3784.072902138966;3798.4112462568346;3812.066043819282;3814.5041144651295;3821.675157611606;140924;141104.29447852695;141125.2943735285;141129;145074;17001.829981700026;17300.307987680604;17383.47849564515;17429.04140272272;17443.302267909396;31804.363912722078;32142.517862231616;32432.675673243437;32580.185495362806;32819.34361312808;7966.123726390092;8082.232187942253;8090.110087890327;8097.2307630774985;8144.470609410369;4219.409282700424;4252.468441444819;4260.169266992907;4268.9786551067045;4301.526832048472;5520.392756796749;5670.8015219467125;5684.261046063985;5703.771849126071;5717.199592057115;891.4027601507006;964;968.2592816495387;971.723058928202;975.7560609847538;686.9553479023848;710.3961632612238;711.8398360368875;715.5206011971991;727.0076345788067;11114.772103239946;11300.943495282636;11673;12319.63041108768;12400.503979840885;678.9219239787487;686.7321744519642;688.1707542411382;699.930006999296;700.7407259314086;5306.495882891119;5344.732763361816;5353.652012619167;5365.980463711882;5406.432324605938;111888.4405577967;112579.18552036265;114348;115412;116552;86502.56748716216;102185.48907255565;108252;108272;111317.44341278242;92285;92581.53709231546;92969.5351523238;93148;94145;11682.532698691957;11933.462994165457;12014.879851201726;12066.818997714865;12108.515659373526;41099.7945010273;42795.786021069696;43015.35476967911;43059.569404305556;44198;74971.25028749861;75922;76151;78199.60900195462;79595;18361;18834.15246313892;18895.905520472308;19123;19129.52176195606;21201.151953922286;21695;22090;22377.888110559343;22438.663420049037;15649.765253520978;15700.607484812972;15703;15863.603409914844;16189.433369831999;17582.73625895587;17668;17927.73108403349;18029.729554056437;18381.080945952915;16356.427525036566;17320.30718771235;18321.908390457964;18497.260109595467;18599.90700046491;11692.35692036946;12386.81419778722;12624.621261362174;12719.236845789283;12766.61700148997;12719.93640031794;12948.598200862063;13252.734945301232;13294.601161965156;13741.519046833313 04:14:25 INFO - PID 21703 | __end_tp_report 04:14:25 INFO - PID 21703 | __start_cc_report 04:14:25 INFO - PID 21703 | _x_x_mozilla_cycle_collect,285 04:14:25 INFO - PID 21703 | __end_cc_report 04:14:25 INFO - PID 21703 | __startTimestamp1506338065587__endTimestamp 04:14:25 INFO - PID 21703 | 04:14:25 INFO - PID 21703 | ------- Summary: start ------- 04:14:25 INFO - PID 21703 | Number of tests: 6 04:14:25 INFO - PID 21703 | 04:14:25 INFO - PID 21703 | [#0] dojo.html Cycles:165 Average:6270.44 Median:6488.68 stddev:756.00 (11.7%) stddev-sans-first:717.38 04:14:25 INFO - PID 21703 | Values: 3142.3 3157.4 3173.3 3183.6 3203.9 6248.0 6313.0 6347.1 6381.3 6463.7 6276.2 6334.0 6340.0 6382.5 6386.3 5826.6 5840.5 5863.9 5899.6 5931.1 5895.4 5903.1 5934.4 5967.9 5972.5 5834.9 5858.3 5889.6 5956.5 5998.3 5827.3 5867.1 5909.3 5957.5 5976.4 5763.0 5791.8 5811.2 5864.5 5877.8 6282.3 6286.7 6289.3 6299.7 6301.4 6719.1 6726.7 6809.3 6816.5 6820.6 6682.5 6791.5 6797.1 6869.6 6882.8 6288.7 6417.6 6445.8 6449.9 6462.3 7029.2 7039.8 7109.3 7125.6 7144.2 6522.9 6653.6 6691.2 6718.8 6820.9 6740.3 6752.8 6755.9 6760.1 6770.3 6675.6 6717.8 6739.7 6742.2 6743.9 6570.9 6713.1 6765.0 6818.2 6834.6 5299.9 5355.4 5362.0 5368.0 5394.9 4604.9 4623.5 4634.9 4679.3 4691.9 5790.1 5833.1 5855.3 5856.7 5868.1 6819.7 6912.3 6924.0 6953.7 6970.1 5901.6 5907.0 5965.6 5979.0 5980.4 5743.3 5810.5 5843.3 5884.2 5950.0 6649.4 6716.1 6724.9 6729.7 6732.6 6830.4 6831.6 6841.9 6844.3 6932.7 6548.5 6635.7 6652.0 6676.9 6721.7 6781.9 6804.0 6813.7 6843.8 6849.7 6838.4 6865.7 6867.5 6947.2 6955.1 6590.9 6596.8 6622.2 6686.2 6692.6 6474.9 6582.9 6585.7 6587.5 6592.3 6450.1 6486.0 6488.7 6493.2 6540.9 6601.2 6717.5 6726.5 6809.5 6810.0 6222.9 6405.1 6449.4 6479.3 6585.7 04:14:25 INFO - PID 21703 | 04:14:25 INFO - PID 21703 | [#1] ext.html Cycles:180 Average:36568.92 Median:19424.88 stddev:52076.20 (268.1%) stddev-sans-first:52153.29 04:14:25 INFO - PID 21703 | Values: 867.6 878.9 880.9 916.7 917.1 39741.0 40293.8 40470.8 40500.6 40649.4 37818.4 38124.4 38190.4 38225.8 38274.4 7065.7 7228.1 7231.9 7233.6 7235.3 7080.5 7196.9 7198.7 7210.8 7226.3 7186.9 7791.3 7963.7 7969.0 7986.9 7865.1 7931.2 7938.4 7938.9 7946.0 7687.2 7790.3 7859.4 7860.0 7875.8 37213.8 37416.4 37494.8 37505.2 37579.8 3498.8 3525.2 3540.8 3546.0 3548.1 30405.8 30470.5 30500.4 30531.5 30538.1 5094.7 5136.0 5136.5 5155.8 5180.4 188028.0 188149.0 188149.0 188860.0 189297.0 46221.3 46347.3 46363.5 46440.0 46495.1 48196.3 48328.5 48338.3 48431.5 48487.8 15784.4 16184.1 16185.1 16192.5 16198.6 13113.4 13188.3 13207.9 13210.7 13212.3 13530.3 13629.5 13675.9 13817.2 13845.3 5450.9 5521.7 5550.0 5577.3 5738.3 982.9 997.3 1008.3 1014.4 1019.3 22669.0 22917.7 23028.0 23084.0 23153.3 1089.0 1097.3 1102.8 1114.3 1116.4 12209.9 12312.8 12330.8 12382.9 12502.9 217897.0 220159.0 220734.0 221687.0 222381.0 44441.6 45087.3 51188.2 51918.0 52146.5 1424.0 1442.2 1443.5 1446.6 1457.0 27064.6 27069.9 27360.3 27405.5 28040.6 36320.0 36558.4 36662.3 36706.0 36726.3 192567.0 193722.0 194209.0 194442.0 194513.0 30822.0 31483.4 31779.7 31812.8 31817.0 40455.0 40931.0 41147.2 41694.2 41706.6 30845.5 31189.0 31490.0 31678.4 31899.8 30934.4 31010.4 31108.4 31198.7 31649.4 35309.0 35458.1 35527.1 35582.6 35670.5 30416.0 30730.0 30872.5 30932.5 31146.0 22697.5 22760.0 23010.7 23030.2 23044.3 04:14:25 INFO - PID 21703 | 04:14:25 INFO - PID 21703 | [#2] jquery.html Cycles:180 Average:33732.52 Median:22999.64 stddev:78215.18 (340.1%) stddev-sans-first:78395.01 04:14:25 INFO - PID 21703 | Values: 588.3 594.5 601.0 603.3 606.5 22367.6 22988.1 23011.2 23025.5 23133.7 23388.3 24302.8 25326.1 25497.4 25556.5 6477.7 6593.5 6632.4 6641.2 6665.7 6340.6 6466.8 6597.0 6606.2 6623.7 6596.0 6614.5 6622.6 6638.3 6643.4 6599.6 6601.2 6614.2 6615.6 6627.2 5901.2 6149.1 6151.5 6151.8 6163.1 28989.3 29522.9 29590.4 29611.4 29682.3 1981.6 1993.9 2008.5 2011.0 2016.7 18712.3 18963.7 19257.4 19260.3 19402.9 2379.3 2382.7 2455.3 2455.9 2464.9 123077.0 128803.4 133997.0 140708.8 141786.3 14700.9 15347.2 15382.8 15509.4 15649.7 23151.7 23174.5 23280.8 23294.3 23332.8 10288.7 10298.3 10320.0 10371.2 10461.6 9454.7 9497.8 9592.8 9592.9 9756.6 9028.9 9134.3 9207.4 9283.3 9462.1 2576.5 2593.6 2648.5 2705.8 2854.4 949.8 966.2 984.8 993.6 1005.4 19344.1 19883.2 19986.4 20158.0 20290.8 1028.2 1037.3 1054.4 1057.0 1064.1 10977.5 10991.9 10998.6 11010.5 11302.8 437425.0 461135.0 466407.0 476292.0 477487.0 106788.0 108863.5 111116.4 111283.4 111655.0 1063.4 1093.0 1159.6 1171.2 1209.3 20542.3 20902.5 20975.7 21074.2 21260.1 54401.0 54461.0 55252.9 55412.2 56457.7 68744.6 68949.0 69033.7 69668.0 69801.0 13871.2 14361.4 14550.6 14681.4 14763.1 22582.3 22632.7 22760.7 23014.5 23101.4 18320.1 18448.3 18671.7 18749.7 18803.4 15111.7 15602.3 15999.4 16039.7 16606.8 18150.6 18949.8 18978.1 19084.9 19090.6 14428.4 14443.6 14493.8 14525.6 14544.9 17764.6 17780.0 18362.7 18500.0 18521.3 04:14:25 INFO - PID 21703 | 04:14:25 INFO - PID 21703 | [#3] mootools.html Cycles:180 Average:9665.89 Median:2655.09 stddev:7011.43 (264.1%) stddev-sans-first:7003.23 04:14:25 INFO - PID 21703 | Values: 1346.2 1412.7 1420.8 1424.1 1438.5 15232.7 15940.0 15978.8 15980.9 15991.8 15702.6 15707.9 15780.1 15810.2 15828.3 2802.2 2841.4 2891.1 2896.9 2910.4 2705.9 2769.3 2772.3 2774.4 2781.5 2336.7 2415.6 2424.8 2722.7 2786.5 2579.5 2593.1 2599.8 2606.7 2612.6 2577.9 2628.6 2652.8 2657.4 2657.4 15134.5 15459.6 15493.2 15529.2 15545.7 7743.0 8039.0 8040.2 8047.7 8052.0 4972.9 5375.7 5533.3 5548.4 5695.1 1947.7 1974.2 1975.3 2035.1 2035.2 27360.3 27376.6 27641.2 27778.0 27836.9 7166.6 7191.1 7269.8 7398.8 7408.0 20904.5 20994.4 21077.9 21111.2 21113.4 8451.7 8464.7 8475.2 8478.6 8482.3 7332.0 7593.4 7630.5 7633.4 7643.6 5649.2 5824.5 5837.3 5886.7 5919.9 1796.2 1847.2 1856.2 1858.4 1877.8 892.1 901.5 902.7 914.3 915.3 8401.7 8688.1 8763.7 8819.3 8846.0 738.1 740.7 741.2 746.1 759.4 4660.0 4681.5 4703.2 4716.3 4730.8 20480.3 21638.2 21818.8 22176.3 22265.0 21636.0 21845.1 21973.0 22115.3 22276.4 10354.9 10420.6 10712.6 10723.2 10794.5 8494.7 8634.4 8663.8 8667.3 8699.0 18255.1 19029.0 19198.3 19418.4 19435.1 19610.8 19660.0 19745.3 19948.3 20496.3 12456.9 12756.2 12773.4 12776.6 12777.4 14133.0 14168.8 14197.4 14346.8 14440.9 11338.4 11427.5 11516.9 11779.9 11834.3 10937.8 11137.6 11274.0 11372.9 11651.4 9578.9 9642.3 9751.1 9765.8 9885.9 7330.6 7487.9 7536.0 7552.9 7665.7 7070.9 7151.1 7154.9 7176.4 7226.8 04:14:25 INFO - PID 21703 | 04:14:25 INFO - PID 21703 | [#4] prototype.html Cycles:180 Average:5546.92 Median:6116.45 stddev:1491.61 (24.4%) stddev-sans-first:1447.09 04:14:25 INFO - PID 21703 | Values: 509.8 520.3 539.6 548.4 554.4 6251.8 6300.0 6305.0 6308.7 6341.8 6031.9 6090.2 6187.9 6191.6 6206.0 5197.6 5206.2 5236.7 5239.4 5241.4 4744.7 4942.3 5057.6 5195.7 5229.6 4945.4 4946.5 4960.7 4979.3 4980.4 4906.8 4917.0 4917.3 4976.2 4983.6 4435.3 4707.6 4752.0 4822.9 4836.7 6024.4 6055.8 6111.5 6117.4 6149.7 5843.8 5898.0 5926.1 5980.7 5988.5 6526.8 6546.6 6601.6 6602.5 6610.8 5038.4 5247.8 5329.2 5334.9 5335.4 6653.9 6699.9 6801.3 6846.2 6883.9 5411.0 5425.3 5438.9 5450.5 5523.2 5559.6 5582.5 5621.3 5623.2 5632.2 5973.5 5975.3 5992.9 6038.5 6046.2 6524.4 6530.4 6583.1 6604.9 6610.3 1883.3 1904.8 1927.3 1945.1 1946.8 1295.2 1357.2 1427.8 1435.3 1459.1 5542.8 5560.2 5594.5 5617.4 5660.8 6506.7 6608.2 6627.0 6637.1 6691.7 5622.9 5626.6 5658.1 5685.3 5691.4 3407.6 3411.2 3442.3 3461.3 3471.1 6129.3 6233.4 6318.4 6349.2 6386.5 6717.6 6783.2 6806.3 6808.2 6873.8 6361.8 6377.4 6413.0 6484.6 6506.4 6451.8 6504.0 6552.8 6578.5 6624.9 6472.6 6535.3 6571.0 6608.6 6655.6 6189.7 6390.3 6454.4 6462.2 6509.6 5005.3 5028.1 5045.6 5058.3 5072.5 6747.7 6751.2 6798.3 6808.3 6845.8 6320.3 6411.6 6444.0 6454.9 6611.4 6439.0 6442.2 6471.4 6490.6 6553.4 6404.4 6409.9 6423.9 6444.9 6453.1 6487.7 6500.5 6521.3 6551.4 6556.0 6115.5 6199.6 6253.4 6272.8 6286.3 04:14:25 INFO - PID 21703 | 04:14:25 INFO - PID 21703 | [#5] yui.html Cycles:180 Average:24982.20 Median:21892.50 stddev:34957.75 (159.7%) stddev-sans-first:35009.08 04:14:25 INFO - PID 21703 | Values: 910.1 928.5 934.1 935.8 941.7 20000.3 20271.9 20671.6 20675.0 20680.2 19002.7 19336.9 19348.4 19355.9 19397.8 966.6 980.4 984.7 985.6 985.8 959.8 990.2 997.7 998.2 998.5 1047.1 1047.1 1048.4 1048.4 1049.8 992.9 1031.6 1045.8 1049.3 1050.2 750.1 751.0 753.8 754.7 755.8 20288.2 20365.7 20433.6 20504.2 20591.3 21038.9 22426.0 22706.8 22707.4 22743.2 17983.4 18011.7 18017.0 18018.4 18142.5 3784.1 3798.4 3812.1 3814.5 3821.7 140924.0 141104.3 141125.3 141129.0 145074.0 17001.8 17300.3 17383.5 17429.0 17443.3 31804.4 32142.5 32432.7 32580.2 32819.3 7966.1 8082.2 8090.1 8097.2 8144.5 4219.4 4252.5 4260.2 4269.0 4301.5 5520.4 5670.8 5684.3 5703.8 5717.2 891.4 964.0 968.3 971.7 975.8 687.0 710.4 711.8 715.5 727.0 11114.8 11300.9 11673.0 12319.6 12400.5 678.9 686.7 688.2 699.9 700.7 5306.5 5344.7 5353.7 5366.0 5406.4 111888.4 112579.2 114348.0 115412.0 116552.0 86502.6 102185.5 108252.0 108272.0 111317.4 92285.0 92581.5 92969.5 93148.0 94145.0 11682.5 11933.5 12014.9 12066.8 12108.5 41099.8 42795.8 43015.4 43059.6 44198.0 74971.3 75922.0 76151.0 78199.6 79595.0 18361.0 18834.2 18895.9 19123.0 19129.5 21201.2 21695.0 22090.0 22377.9 22438.7 15649.8 15700.6 15703.0 15863.6 16189.4 17582.7 17668.0 17927.7 18029.7 18381.1 16356.4 17320.3 18321.9 18497.3 18599.9 11692.4 12386.8 12624.6 12719.2 12766.6 12719.9 12948.6 13252.7 13294.6 13741.5 04:14:25 INFO - PID 21703 | -------- Summary: end -------- 04:14:25 INFO - PID 21703 | 04:14:26 INFO - TEST-INFO | 21703: exit 0 04:14:26 INFO - TEST-OK | dromaeo_css | took 1110154ms 04:14:26 INFO - TEST-START | kraken 04:14:26 INFO - Initialising browser for kraken test... 04:14:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41271/getInfo.html -profile /tmp/tmp1a8ZH1/profile 04:14:26 INFO - TEST-INFO | started process 22069 (/builds/slave/test/build/application/firefox/firefox http://localhost:41271/getInfo.html) 04:14:32 INFO - TEST-INFO | 22069: exit 0 04:14:32 INFO - Browser initialized. 04:14:32 INFO - Running cycle 1/1 for kraken test... 04:14:32 INFO - Using env: {'DISPLAY': ':0', 04:14:32 INFO - 'HOME': '/home/cltbld', 04:14:32 INFO - 'JSGC_DISABLE_POISONING': '1', 04:14:32 INFO - 'LANG': 'en_US.UTF-8', 04:14:32 INFO - 'LANGUAGE': 'en_US:en', 04:14:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:14:32 INFO - 'LOGNAME': 'cltbld', 04:14:32 INFO - 'MAIL': '/var/mail/cltbld', 04:14:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:14:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:14:32 INFO - 'MOZ_CRASHREPORTER': '1', 04:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:14:32 INFO - 'MOZ_NO_REMOTE': '1', 04:14:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:14:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:32 INFO - 'NO_EM_RESTART': '1', 04:14:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:32 INFO - 'PWD': '/builds/slave/test', 04:14:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:14:32 INFO - 'RUST_BACKTRACE': 'full', 04:14:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:14:32 INFO - 'SHELL': '/bin/bash', 04:14:32 INFO - 'SHLVL': '1', 04:14:32 INFO - 'TERM': 'linux', 04:14:32 INFO - 'TMOUT': '86400', 04:14:32 INFO - 'USER': 'cltbld', 04:14:32 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939', 04:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:32 INFO - '_': '/tools/buildbot/bin/python'} 04:14:32 INFO - TEST-INFO | started process 22305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1a8ZH1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:33 INFO - PID 22305 | 04:14:33 INFO - PID 22305 | (/builds/slave/test/build/application/firefox/firefox:22354): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:14:33 INFO - PID 22305 | 04:14:43 INFO - PID 22305 | RSS: Main: 151539712 04:14:43 INFO - PID 22305 | 04:14:46 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41271/tests/kraken/driver.html?testName=audio-beat-detection) 04:14:47 INFO - PID 22305 | RSS: Main: 158928896 04:14:47 INFO - PID 22305 | 04:14:49 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41271/tests/kraken/driver.html?testName=audio-dft) 04:14:50 INFO - PID 22305 | RSS: Main: 158720000 04:14:50 INFO - PID 22305 | 04:14:53 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41271/tests/kraken/driver.html?testName=audio-fft) 04:14:53 INFO - PID 22305 | RSS: Main: 154083328 04:14:53 INFO - PID 22305 | 04:14:55 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41271/tests/kraken/driver.html?testName=audio-oscillator) 04:14:56 INFO - PID 22305 | RSS: Main: 154873856 04:14:56 INFO - PID 22305 | 04:14:58 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:14:59 INFO - PID 22305 | RSS: Main: 154701824 04:14:59 INFO - PID 22305 | 04:15:02 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41271/tests/kraken/driver.html?testName=imaging-darkroom) 04:15:03 INFO - PID 22305 | RSS: Main: 157614080 04:15:03 INFO - PID 22305 | 04:15:06 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41271/tests/kraken/driver.html?testName=imaging-desaturate) 04:15:07 INFO - PID 22305 | RSS: Main: 157978624 04:15:07 INFO - PID 22305 | 04:15:10 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41271/tests/kraken/driver.html?testName=json-parse-financial) 04:15:10 INFO - PID 22305 | RSS: Main: 158101504 04:15:10 INFO - PID 22305 | 04:15:12 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:15:13 INFO - PID 22305 | RSS: Main: 157982720 04:15:13 INFO - PID 22305 | 04:15:14 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41271/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:15:15 INFO - PID 22305 | RSS: Main: 157892608 04:15:15 INFO - PID 22305 | 04:15:17 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:15:18 INFO - PID 22305 | RSS: Main: 154693632 04:15:18 INFO - PID 22305 | 04:15:20 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:15:21 INFO - PID 22305 | RSS: Main: 155156480 04:15:21 INFO - PID 22305 | 04:15:24 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:15:24 INFO - PID 22305 | RSS: Main: 155262976 04:15:24 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:41271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41271/tests/kraken/driver.html?testName=ai-astar) 04:15:26 INFO - PID 22305 | RSS: Main: 156684288 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | __start_tp_report 04:15:26 INFO - PID 22305 | _x_x_mozilla_page_load 04:15:26 INFO - PID 22305 | _x_x_mozilla_page_load_details 04:15:26 INFO - PID 22305 | |i|pagename|runs| 04:15:26 INFO - PID 22305 | |0;ai-astar;113;107;107;107;107;109;108;107;106;106 04:15:26 INFO - PID 22305 | |1;audio-beat-detection;119;117;116;122;115;116;118;119;117;141 04:15:26 INFO - PID 22305 | |2;audio-dft;164;162;163;164;164;164;163;164;166;170 04:15:26 INFO - PID 22305 | |3;audio-fft;74;75;74;74;75;75;75;75;75;75 04:15:26 INFO - PID 22305 | |4;audio-oscillator;80;80;79;80;79;80;135;79;81;80 04:15:26 INFO - PID 22305 | |5;imaging-gaussian-blur;110;113;113;114;114;115;113;114;115;117 04:15:26 INFO - PID 22305 | |6;imaging-darkroom;149;148;148;147;147;147;147;149;148;150 04:15:26 INFO - PID 22305 | |7;imaging-desaturate;99;94;94;94;94;93;94;95;95;96 04:15:26 INFO - PID 22305 | |8;json-parse-financial;53;55;56;55;55;54;54;57;55;52 04:15:26 INFO - PID 22305 | |9;json-stringify-tinderbox;38;38;38;40;38;39;39;38;38;39 04:15:26 INFO - PID 22305 | |10;stanford-crypto-aes;73;71;73;76;72;86;77;71;71;76 04:15:26 INFO - PID 22305 | |11;stanford-crypto-ccm;111;114;113;119;113;111;110;113;114;111 04:15:26 INFO - PID 22305 | |12;stanford-crypto-pbkdf2;164;169;173;166;168;165;168;169;173;168 04:15:26 INFO - PID 22305 | |13;stanford-crypto-sha256-iterative;72;73;64;66;65;67;68;69;66;72 04:15:26 INFO - PID 22305 | __end_tp_report 04:15:26 INFO - PID 22305 | __start_cc_report 04:15:26 INFO - PID 22305 | _x_x_mozilla_cycle_collect,747 04:15:26 INFO - PID 22305 | __end_cc_report 04:15:26 INFO - PID 22305 | __startTimestamp1506338126968__endTimestamp 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | ------- Summary: start ------- 04:15:26 INFO - PID 22305 | Number of tests: 14 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#0] ai-astar Cycles:10 Average:107.70 Median:107.00 stddev:2.06 (1.9%) stddev-sans-first:0.93 04:15:26 INFO - PID 22305 | Values: 113.0 107.0 107.0 107.0 107.0 109.0 108.0 107.0 106.0 106.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:118.50 stddev:7.64 (6.5%) stddev-sans-first:8.10 04:15:26 INFO - PID 22305 | Values: 119.0 117.0 116.0 122.0 115.0 116.0 118.0 119.0 117.0 141.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.00 stddev:2.22 (1.4%) stddev-sans-first:2.35 04:15:26 INFO - PID 22305 | Values: 164.0 162.0 163.0 164.0 164.0 164.0 163.0 164.0 166.0 170.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#3] audio-fft Cycles:10 Average:74.70 Median:75.00 stddev:0.48 (0.6%) stddev-sans-first:0.44 04:15:26 INFO - PID 22305 | Values: 74.0 75.0 74.0 74.0 75.0 75.0 75.0 75.0 75.0 75.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:80.00 stddev:17.47 (21.8%) stddev-sans-first:18.43 04:15:26 INFO - PID 22305 | Values: 80.0 80.0 79.0 80.0 79.0 80.0 135.0 79.0 81.0 80.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:1.81 (1.6%) stddev-sans-first:1.30 04:15:26 INFO - PID 22305 | Values: 110.0 113.0 113.0 114.0 114.0 115.0 113.0 114.0 115.0 117.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 04:15:26 INFO - PID 22305 | Values: 149.0 148.0 148.0 147.0 147.0 147.0 147.0 149.0 148.0 150.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.50 stddev:1.69 (1.8%) stddev-sans-first:0.87 04:15:26 INFO - PID 22305 | Values: 99.0 94.0 94.0 94.0 94.0 93.0 94.0 95.0 95.0 96.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.43 (2.6%) stddev-sans-first:1.39 04:15:26 INFO - PID 22305 | Values: 53.0 55.0 56.0 55.0 55.0 54.0 54.0 57.0 55.0 52.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:38.50 stddev:0.71 (1.8%) stddev-sans-first:0.73 04:15:26 INFO - PID 22305 | Values: 38.0 38.0 38.0 40.0 38.0 39.0 39.0 38.0 38.0 39.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.50 stddev:4.60 (6.2%) stddev-sans-first:4.84 04:15:26 INFO - PID 22305 | Values: 73.0 71.0 73.0 76.0 72.0 86.0 77.0 71.0 71.0 76.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#11] stanford-crypto-ccm Cycles:10 Average:112.90 Median:113.00 stddev:2.56 (2.3%) stddev-sans-first:2.62 04:15:26 INFO - PID 22305 | Values: 111.0 114.0 113.0 119.0 113.0 111.0 110.0 113.0 114.0 111.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:168.50 stddev:2.98 (1.8%) stddev-sans-first:2.73 04:15:26 INFO - PID 22305 | Values: 164.0 169.0 173.0 166.0 168.0 165.0 168.0 169.0 173.0 168.0 04:15:26 INFO - PID 22305 | 04:15:26 INFO - PID 22305 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.50 stddev:3.19 (4.7%) stddev-sans-first:3.07 04:15:26 INFO - PID 22305 | Values: 72.0 73.0 64.0 66.0 65.0 67.0 68.0 69.0 66.0 72.0 04:15:26 INFO - PID 22305 | -------- Summary: end -------- 04:15:26 INFO - PID 22305 | 04:15:27 INFO - PID 22305 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:15:27 INFO - TEST-INFO | 22305: exit 0 04:15:27 INFO - TEST-OK | kraken | took 61401ms 04:15:27 INFO - SUITE-END | took 1171s 04:15:28 INFO - Completed test suite (00:19:31) 04:15:28 INFO - javascript benchmark 04:15:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8604.135995157298, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3142.2615685313954, 3157.4316623007862, 3173.3018735878127, 3183.602049743781, 3203.8558264878093, 6248.0, 6312.9684351578235, 6347.1431356766825, 6381.266154392246, 6463.676816159191, 6276.215473065867, 6334.0, 6339.9683001585045, 6382.4574911132495, 6386.265579458363, 5826.621269617472, 5840.503557197643, 5863.882722345551, 5899.646021238739, 5931.051031834907, 5895.35151133375, 5903.055511118222, 5934.406559344075, 5967.880642387149, 5972.492338151241, 5834.912476312858, 5858.297004359483, 5889.558283128783, 5956.463918247357, 5998.340182579913, 5827.271591051119, 5867.11993201019, 5909.290885093773, 5957.493613042897, 5976.372480889488, 5762.97118514409, 5791.793963398235, 5811.215485909391, 5864.472197502203, 5877.794277200277, 6282.340354262828, 6286.654234017127, 6289.339619339949, 6299.7165127568915, 6301.432871041582, 6719.092922455504, 6726.697298621574, 6809.250982391933, 6816.522843400964, 6820.556663816836, 6682.465402767767, 6791.4906382021545, 6797.116374871235, 6869.587824730482, 6882.793516194522, 6288.685565721696, 6417.582857114273, 6445.83885402864, 6449.871002579969, 6462.289148193742, 7029.22678505364, 7039.8240043998285, 7109.324614161646, 7125.608091555015, 7144.214136444987, 6522.934770652233, 6653.567518111307, 6691.230508491489, 6718.798436046829, 6820.897686534706, 6740.325967403221, 6752.83117922046, 6755.898661520081, 6760.121184246016, 6770.322967703289, 6675.566088204251, 6717.832054198684, 6739.663016849138, 6742.224644165885, 6743.898841517381, 6570.868582628416, 6713.12729345182, 6765.0, 6818.181818181751, 6834.58992460444, 5299.867503312371, 5355.410904800468, 5362.034833729888, 5368.0, 5394.865128371744, 4604.930926036112, 4623.491415944243, 4634.907301853944, 4679.344891715163, 4691.929621055687, 5790.073588225863, 5833.125031245262, 5855.268091488564, 5856.707164641751, 5868.061110222344, 6819.67016431791, 6912.274211207851, 6923.9653801730665, 6953.652317384111, 6970.093887794555, 5901.557383196277, 5906.970465147732, 5965.642061476326, 5979.0, 5980.372060933626, 5743.282089738783, 5810.535157187415, 5843.328017277982, 5884.176215329857, 5950.0, 6649.368310010541, 6716.126903502513, 6724.899126513106, 6729.6971636276485, 6732.562383445158, 6830.351116643911, 6831.59010459374, 6841.93158068413, 6844.315568443116, 6932.7226910923, 6548.476121910331, 6635.701393437404, 6652.035454859115, 6676.9332306676315, 6721.697523611547, 6781.93218067813, 6803.965980170067, 6813.69338379784, 6843.828904277433, 6849.691763870737, 6838.418734407432, 6865.656717164022, 6867.450603951772, 6947.201071876799, 6955.130608673916, 6590.934090659032, 6596.8350791230605, 6622.1722284714415, 6686.1642294713165, 6692.564983276072, 6474.870502590016, 6582.880910245174, 6585.670716464253, 6587.47300215972, 6592.307807680317, 6450.096986421999, 6485.967570162119, 6488.6755662215755, 6493.188351456068, 6540.934590654223, 6601.207855057424, 6717.529772915849, 6726.529142960141, 6809.455243580601, 6810.012548180585, 6222.942099843128, 6405.071264666691, 6449.3873082057125, 6479.287278399466, 6585.670716464253], "value": 6212.1186447196005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [867.5532100968003, 878.9340799440043, 880.8546589812681, 916.7249825052485, 917.1378903830399, 39741.0, 40293.798531007305, 40470.7976460118, 40500.59499405005, 40649.3902591461, 37818.43272350917, 38124.42813357802, 38190.42714359287, 38225.808870955676, 38274.425883611766, 7065.717371305154, 7228.0603521542125, 7231.855362892739, 7233.638318084075, 7235.312645298688, 7080.468964827633, 7196.928030719678, 7198.7120515179595, 7210.783676489714, 7226.349628533431, 7186.928130718704, 7791.259830316111, 7963.721269755531, 7969.043714754238, 7986.880196797053, 7865.134835168127, 7931.246531579548, 7938.404619653549, 7938.880916786253, 7946.0464744230485, 7687.231276872324, 7790.259925307087, 7859.410544209207, 7859.96070019652, 7875.763727088197, 37213.813930930446, 37416.43875341872, 37494.81252593747, 37505.24989500194, 37579.81210093959, 3498.825058747078, 3525.1892064825233, 3540.7698499597445, 3546.0, 3548.1307079765365, 30405.847970760005, 30470.54294185589, 30500.38999220003, 30531.542026869614, 30538.08385748431, 5094.6688465249645, 5136.0241554104605, 5136.486351364871, 5155.7679904404395, 5180.352455943007, 188028.0, 188149.0, 188149.0, 188860.0, 189297.0, 46221.30668039982, 46347.30479042817, 46363.53636463592, 46439.99999999932, 46495.07009859852, 48196.27705584419, 48328.5167148331, 48338.27492587614, 48431.5156848427, 48487.757561212675, 15784.36862525509, 16184.10987395681, 16185.109818959834, 16192.514224573282, 16198.595035124215, 13113.409896554678, 13188.340582970814, 13207.933960330329, 13210.669733256553, 13212.273324967218, 13530.323483825769, 13629.522966696317, 13675.863241367659, 13817.240051797247, 13845.307734613229, 5450.8909821803345, 5521.723913804293, 5550.0, 5577.330720313588, 5738.254026976467, 982.9115379615725, 997.2620261006948, 1008.3193844155165, 1014.3660212367271, 1019.2814066083394, 22669.0, 22917.656235156486, 23028.0, 23084.0, 23153.305400838002, 1089.0, 1097.3470784882982, 1102.8180350242121, 1114.3146964616703, 1116.4027245423658, 12209.877901220874, 12312.75374492523, 12330.753384932252, 12382.938085309695, 12502.874971250352, 217897.0, 220159.0, 220734.0, 221687.0, 222381.0, 44441.55558444439, 45087.32369014467, 51188.232176517384, 51918.0, 52146.47853521416, 1423.978640320396, 1442.213993373614, 1443.487561915526, 1446.5660301909386, 1457.0383546859234, 27064.594031089942, 27069.86465067662, 27360.315992100357, 27405.45189096167, 28040.579391309555, 36320.0, 36558.35487403126, 36662.26675466529, 36706.0, 36726.26547468982, 192567.0, 193722.0, 194209.0, 194442.0, 194513.0, 30822.0, 31483.370332593677, 31779.682203178596, 31812.8409357961, 31817.0, 40455.0, 40931.0, 41147.1770564593, 41694.166116678105, 41706.58293417027, 30845.537316939815, 31189.0, 31490.0, 31678.36643267168, 31899.84050079735, 30934.381312373178, 31010.379792404477, 31108.377832443675, 31198.68801311958, 31649.367012659157, 35309.0, 35458.113547160494, 35527.11182220465, 35582.644173557936, 35670.464943026396, 30416.0, 30730.0, 30872.536911946787, 30932.53601195939, 31146.0, 22697.546049078595, 22760.0, 23010.654840177074, 23030.193943211907, 23044.308670739905], "value": 16863.430909808045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.3263663105747, 594.5095296380484, 600.9639421634703, 603.3483837455549, 606.5178183344242, 22367.55264894701, 22988.08047678093, 23011.194608188718, 23025.539489210205, 23133.652995205044, 23388.298351049496, 24302.75697243027, 25326.113586024494, 25497.362565935862, 25556.488870222584, 6477.708503117347, 6593.472522198213, 6632.403083722464, 6641.203055633307, 6665.733370665168, 6340.587861788992, 6466.805995820133, 6596.967015164917, 6606.207255129391, 6623.735050597995, 6595.97762346837, 6614.536982411233, 6622.602643841385, 6638.302978187293, 6643.402093811556, 6599.57102788322, 6601.2408573014, 6614.239362473305, 6615.636140012298, 6627.1716035495565, 5901.23283973085, 6149.13912052313, 6151.5386346024225, 6151.846203844895, 6163.137160797492, 28989.275268118254, 29522.852385738148, 29590.408191836043, 29611.407771844442, 29682.257943551584, 1981.5838673878502, 1993.9102740376718, 2008.4777957731096, 2011.0246530432876, 2016.6974953756908, 18712.251509939586, 18963.71554426671, 19257.4222773317, 19260.325888593972, 19402.902985485125, 2379.3218932604123, 2382.702162229721, 2455.3002394317714, 2455.8526488410753, 2464.9260522184363, 123077.0, 128803.35598322042, 133997.0, 140708.77873663805, 141786.29106854502, 14700.941868138469, 15347.23263836826, 15382.846171538366, 15509.379624814881, 15649.687006259812, 23151.652725209136, 23174.53650926972, 23280.7671923282, 23294.3011709649, 23332.7666723334, 10288.742781430525, 10298.279120461646, 10320.019598138166, 10371.222158338154, 10461.581536738446, 9454.669086581936, 9497.81550243448, 9592.808143837225, 9592.856107158399, 9756.560954757053, 9028.909710902939, 9134.314926380548, 9207.447553146832, 9283.25733941283, 9462.073239605625, 2576.5362234797917, 2593.5590949538623, 2648.510025645262, 2705.7947772565294, 2854.414844956779, 949.8290307744672, 966.1546147121269, 984.7981163861391, 993.6124911284605, 1005.3666190300211, 19344.129514171895, 19883.204671813255, 19986.400407987785, 20158.0, 20290.79709202889, 1028.2236911132047, 1037.258360272409, 1054.4306074719718, 1056.9788604227872, 1064.1274155192668, 10977.506012229469, 10991.890081099247, 10998.615048473424, 11010.50452729629, 11302.830457543143, 437425.0, 461135.0, 466407.0, 476292.0, 477487.0, 106788.0, 108863.45568272108, 111116.44441777901, 111283.44358278156, 111655.0, 1063.4363787192801, 1093.0, 1159.5535718748342, 1171.2211379432752, 1209.2683926224684, 20542.281020164224, 20902.47743806417, 20975.685364719848, 21074.15703371848, 21260.149594016686, 54401.0, 54461.0, 55252.85228956497, 55412.16881746858, 56457.71771141118, 68744.62510749858, 68949.0, 69033.65483172552, 69668.0, 69801.0, 13871.23708196059, 14361.425542978583, 14550.636234094232, 14681.41274349014, 14763.114213147246, 22582.322530324116, 22632.66051009269, 22760.658590121493, 23014.539709205386, 23101.422464438525, 18320.08399580042, 18448.26206951707, 18671.692981491164, 18749.718754218426, 18803.435896923114, 15111.697766044837, 15602.297896594453, 15999.440019599258, 16039.67920641604, 16606.750898736285, 18150.636987260445, 18949.810501894804, 18978.145983430502, 19084.904575477034, 19090.618187635893, 14428.42286308536, 14443.566692999228, 14493.7825932609, 14525.564233073024, 14544.927275363556, 17764.55588610295, 17780.0, 18362.72455913189, 18500.0, 18521.259149633865], "value": 11965.599176238997, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.2057386142174, 1412.660961369271, 1420.7584710599197, 1424.0885833066832, 1438.546857739812, 15232.69534609307, 15940.0, 15978.84021159788, 15980.920095399535, 15991.800614953896, 15702.607434814136, 15707.92146039271, 15780.132092734895, 15810.209489525507, 15828.287727052282, 2802.187365663955, 2841.4459180459817, 2891.0893068683404, 2896.9275768105754, 2910.4179164167144, 2705.864706764664, 2769.2523018784914, 2772.265349682336, 2774.4173723517983, 2781.5132351838347, 2336.707911511061, 2415.5531226723024, 2424.793892519138, 2722.7413395727376, 2786.512360336943, 2579.548578998668, 2593.1053786443645, 2599.8180127391092, 2606.713261541229, 2612.6211699303685, 2577.935551611206, 2628.6451329070524, 2652.814302998791, 2657.3755167535614, 2657.4419371931726, 15134.545963620998, 15459.613509662237, 15493.225338733018, 15529.223538823013, 15545.689086218212, 7743.0, 8039.0353157620875, 8040.155783642692, 8047.718329858485, 8052.033755949324, 4972.925406118911, 5375.650582712111, 5533.3083364579425, 5548.361938377098, 5695.117256825199, 1947.7273181754565, 1974.1807150032719, 1975.3481351154085, 2035.1452389996236, 2035.2266138867283, 27360.315992100157, 27376.58935115975, 27641.170764876886, 27778.0, 27836.860815695793, 7166.641667916583, 7191.1011123608505, 7269.818254543678, 7398.815029624195, 7408.0, 20904.47738806542, 20994.37016889496, 21077.894610526848, 21111.155553777677, 21113.366599002056, 8451.746447606582, 8464.687973364138, 8475.194856488624, 8478.618462169217, 8482.321414286842, 7331.963340183265, 7593.392528597699, 7630.542167469969, 7633.351165150913, 7643.617819109022, 5649.152627105967, 5824.475797178275, 5837.328707198726, 5886.676232807151, 5919.940800591939, 1796.2186448894772, 1847.1503108569939, 1856.1832793570786, 1858.3774435564237, 1877.802830702784, 892.0900681305162, 901.454619954931, 902.7066203483894, 914.2685851318919, 915.3409545127497, 8401.663933442594, 8688.087750786202, 8763.693270735494, 8819.250363719028, 8845.955770221108, 738.0737174845534, 740.6629983357559, 741.195802554224, 746.145663215626, 759.400073941591, 4659.976700116477, 4681.461631912305, 4703.223968045303, 4716.268978308368, 4730.810767569327, 20480.283190088274, 21638.242661507087, 21818.781812181674, 22176.334709958726, 22265.0, 21636.026378812676, 21845.126194952023, 21973.02242798156, 22115.33653990383, 22276.443088922908, 10354.896451035393, 10420.583176673, 10712.625058122929, 10723.249372544004, 10794.460276986276, 8494.660213591387, 8634.438761480735, 8663.783405414915, 8667.263282620796, 8698.956505217433, 18255.08724563793, 19029.0, 19198.328058517884, 19418.417447476597, 19435.056494351018, 19610.80389196147, 19660.0, 19745.308914187935, 19948.3018094366, 20496.282630107875, 12456.875431245571, 12756.170848894792, 12773.425195866022, 12776.61670149897, 12777.425015874494, 14133.0, 14168.787468188191, 14197.432102715775, 14346.784798227825, 14440.927795360956, 11338.376389298668, 11427.485763140512, 11516.884831151916, 11779.941100294443, 11834.349110798988, 10937.781244375226, 11137.554497819996, 11274.0, 11372.943135284271, 11651.359175245103, 9578.856317155109, 9642.325037247607, 9751.073648003428, 9765.804683906425, 9885.851712224467, 7330.560166390033, 7487.887681684888, 7536.0, 7552.886706699294, 7665.731699390718, 7070.893936590853, 7151.106111736033, 7154.928450715426, 7176.425885929218, 7226.819329516804], "value": 6824.518285921441, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.77315094782836, 520.2924023328271, 539.606087556084, 548.3748526679589, 554.4455544455544, 6251.781187658433, 6300.0, 6304.968475157623, 6308.716107775155, 6341.778037768679, 6031.898309149777, 6090.23872015998, 6187.876242475148, 6191.566590338678, 6206.0, 5197.558207552353, 5206.193040078794, 5236.659617124875, 5239.397469291023, 5241.42344342122, 4744.739039352834, 4942.3327850740225, 5057.620678449131, 5195.714235717034, 5229.633925625208, 4945.381827271591, 4946.505349465061, 4960.70235785854, 4979.253112033188, 4980.352554167953, 4906.803727850882, 4916.975415122919, 4917.336159618477, 4976.154053810861, 4983.6295018870105, 4435.270244604607, 4707.552782485658, 4751.95248047522, 4822.927656085161, 4836.733979631119, 6024.427679370453, 6055.788047418363, 6111.480524155452, 6117.357677443893, 6149.692515374258, 5843.795467158629, 5898.0, 5926.051831706949, 5980.671063091529, 5988.490978266853, 6526.836829079263, 6546.6072035677535, 6601.63690996995, 6602.496540907203, 6610.768623098168, 5038.370203724535, 5247.790088396459, 5329.200619907045, 5334.946650533484, 5335.439778823206, 6653.866922661519, 6699.899501507482, 6801.251862295144, 6846.178458584902, 6883.8967415488805, 5411.0, 5425.294711687456, 5438.91841622376, 5450.482204190595, 5523.226748255248, 5559.607909751332, 5582.525485333792, 5621.325440947113, 5623.212750214973, 5632.239647647515, 5973.49225315853, 5975.312839023568, 5992.940070599238, 6038.547108966845, 6046.244219472566, 6524.380183882524, 6530.444912182517, 6583.144191255106, 6604.900926486107, 6610.30591787865, 1883.3125909043101, 1904.8190421910174, 1927.3447028010544, 1945.105251584259, 1946.7955864634293, 1295.2163940815672, 1357.1856885868597, 1427.8286605607393, 1435.3397437178805, 1459.0807791091545, 5542.8337149885565, 5560.165975103686, 5594.496495315441, 5617.41017193197, 5660.830175094754, 6506.707198176094, 6608.207015158116, 6627.0, 6637.103990961255, 6691.698873550702, 5622.935500152426, 5626.5524496844, 5658.094704847287, 5685.346185188673, 5691.402402747734, 3407.591089069278, 3411.1813164840755, 3442.2599141184264, 3461.3077384523194, 3471.062813040465, 6129.3450768293205, 6233.407826256589, 6318.4313411793155, 6349.238091429058, 6386.521010924106, 6717.607608157947, 6783.152105986752, 6806.285340039224, 6808.2170550386345, 6873.766026041557, 6361.777337793247, 6377.426031656987, 6412.9679351602945, 6484.610923344614, 6506.414422701885, 6451.774187903401, 6504.0, 6552.83617909537, 6578.506612004215, 6624.90062649051, 6472.611643301417, 6535.313792051957, 6570.954721655992, 6608.58786542918, 6655.633940133339, 6189.721462534287, 6390.265119511316, 6454.354564543508, 6462.192225971754, 6509.641969691523, 5005.34930459038, 5028.120078986236, 5045.596352291881, 5058.291839142449, 5072.54347108762, 6747.696353663999, 6751.223609284799, 6798.32016798336, 6808.285130061272, 6845.760398386033, 6320.336364681642, 6411.551191416556, 6444.0, 6454.903176452263, 6611.438027767694, 6439.0, 6442.226932768098, 6471.417572418505, 6490.578112422678, 6553.4101930826455, 6404.398900274931, 6409.935900640934, 6423.903641445476, 6444.871102578016, 6453.128827608213, 6487.740490380521, 6500.504883876641, 6521.34786521363, 6551.44312733423, 6555.983822507519, 6115.541334400027, 6199.5660303780105, 6253.405926436982, 6272.8431789203805, 6286.277078136073], "value": 5123.079208861339, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [910.0853642089698, 928.5078908178659, 934.1219253901332, 935.8034812689339, 941.6986564299423, 20000.299989500352, 20271.898640506814, 20671.586568268627, 20675.0, 20680.172793088277, 19002.71495927562, 19336.936468494227, 19348.419547413563, 19355.903220483913, 19397.806021939778, 966.5747071288628, 980.4460479828874, 984.714432814483, 985.635314933472, 985.7634167799748, 959.7984423271122, 990.2078337330144, 997.6807421625083, 998.1665309466567, 998.4807899892052, 1047.0524175621076, 1047.1361127070159, 1048.391932679048, 1048.4076496779333, 1049.7847941172079, 992.8907820139779, 1031.6234574380371, 1045.7594753206768, 1049.275999560301, 1050.154625526451, 750.0586763611688, 750.9699612015513, 753.7851712261979, 754.728297812787, 755.7770457714987, 20288.188472461232, 20365.694514582294, 20433.5913281735, 20504.17983280667, 20591.279305224394, 21038.894805525873, 22425.99083041267, 22706.772932270633, 22707.432314192112, 22743.20398786051, 17983.370582029565, 18011.72982405252, 18017.0, 18018.36935707244, 18142.45572632823, 3784.072902138966, 3798.4112462568346, 3812.066043819282, 3814.5041144651295, 3821.675157611606, 140924.0, 141104.29447852695, 141125.2943735285, 141129.0, 145074.0, 17001.829981700026, 17300.307987680604, 17383.47849564515, 17429.04140272272, 17443.302267909396, 31804.363912722078, 32142.517862231616, 32432.675673243437, 32580.185495362806, 32819.34361312808, 7966.123726390092, 8082.232187942253, 8090.110087890327, 8097.2307630774985, 8144.470609410369, 4219.409282700424, 4252.468441444819, 4260.169266992907, 4268.9786551067045, 4301.526832048472, 5520.392756796749, 5670.8015219467125, 5684.261046063985, 5703.771849126071, 5717.199592057115, 891.4027601507006, 964.0, 968.2592816495387, 971.723058928202, 975.7560609847538, 686.9553479023848, 710.3961632612238, 711.8398360368875, 715.5206011971991, 727.0076345788067, 11114.772103239946, 11300.943495282636, 11673.0, 12319.63041108768, 12400.503979840885, 678.9219239787487, 686.7321744519642, 688.1707542411382, 699.930006999296, 700.7407259314086, 5306.495882891119, 5344.732763361816, 5353.652012619167, 5365.980463711882, 5406.432324605938, 111888.4405577967, 112579.18552036265, 114348.0, 115412.0, 116552.0, 86502.56748716216, 102185.48907255565, 108252.0, 108272.0, 111317.44341278242, 92285.0, 92581.53709231546, 92969.5351523238, 93148.0, 94145.0, 11682.532698691957, 11933.462994165457, 12014.879851201726, 12066.818997714865, 12108.515659373526, 41099.7945010273, 42795.786021069696, 43015.35476967911, 43059.569404305556, 44198.0, 74971.25028749861, 75922.0, 76151.0, 78199.60900195462, 79595.0, 18361.0, 18834.15246313892, 18895.905520472308, 19123.0, 19129.52176195606, 21201.151953922286, 21695.0, 22090.0, 22377.888110559343, 22438.663420049037, 15649.765253520978, 15700.607484812972, 15703.0, 15863.603409914844, 16189.433369831999, 17582.73625895587, 17668.0, 17927.73108403349, 18029.729554056437, 18381.080945952915, 16356.427525036566, 17320.30718771235, 18321.908390457964, 18497.260109595467, 18599.90700046491, 11692.35692036946, 12386.81419778722, 12624.621261362174, 12719.236845789283, 12766.61700148997, 12719.93640031794, 12948.598200862063, 13252.734945301232, 13294.601161965156, 13741.519046833313], "value": 9257.547921947375, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1425.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 107.0, 107.0, 107.0, 109.0, 108.0, 107.0, 106.0, 106.0], "value": 107.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 116.0, 122.0, 115.0, 116.0, 118.0, 119.0, 117.0, 141.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 164.0, 164.0, 164.0, 163.0, 164.0, 166.0, 170.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 74.0, 75.0, 75.0, 75.0, 75.0, 75.0, 75.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 80.0, 79.0, 80.0, 135.0, 79.0, 81.0, 80.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 113.0, 114.0, 114.0, 115.0, 113.0, 114.0, 115.0, 117.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 149.0, 148.0, 150.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 95.0, 96.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 56.0, 55.0, 55.0, 54.0, 54.0, 57.0, 55.0, 52.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 40.0, 38.0, 39.0, 39.0, 38.0, 38.0, 39.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 73.0, 76.0, 72.0, 86.0, 77.0, 71.0, 71.0, 76.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 113.0, 119.0, 113.0, 111.0, 110.0, 113.0, 114.0, 111.0], "value": 112.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 169.0, 173.0, 166.0, 168.0, 165.0, 168.0, 169.0, 173.0, 168.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 64.0, 66.0, 65.0, 67.0, 68.0, 69.0, 66.0, 72.0], "value": 68.2, "unit": "score"}]}]} 04:15:28 INFO - Return code: 0 04:15:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:15:28 INFO - # TBPL SUCCESS # 04:15:28 INFO - Running post-action listener: _package_coverage_data 04:15:28 INFO - Running post-action listener: _resource_record_post_action 04:15:28 INFO - [mozharness: 2017-09-25 11:15:28.265333Z] Finished run-tests step (success) 04:15:28 INFO - Running post-run listener: _resource_record_post_run 04:15:28 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 36864; Write bytes: 349175808; Read time: 40; Write time: 185180 04:15:28 INFO - TinderboxPrint: CPU usage
13.1% 04:15:28 INFO - TinderboxPrint: I/O read bytes / time
36,864 / 40 04:15:28 INFO - TinderboxPrint: I/O write bytes / time
349,175,808 / 185,180 04:15:28 INFO - TinderboxPrint: CPU idle
8,241.4 (86.9%) 04:15:28 INFO - TinderboxPrint: CPU user
1,193.2 (12.6%) 04:15:28 INFO - TinderboxPrint: Swap in / out
0 / 0 04:15:28 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13058048; Read time: 0; Write time: 1120 04:15:28 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:15:28 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 32768; Write bytes: 336117760; Read time: 24; Write time: 184060 04:15:28 INFO - Running post-run listener: _upload_blobber_files 04:15:28 INFO - Blob upload gear active. 04:15:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:15:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:15:28 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'] 04:15:28 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 04:15:29 INFO - (blobuploader) - INFO - Open directory for files ... 04:15:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:15:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:15:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:32 INFO - (blobuploader) - INFO - Done attempting. 04:15:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:15:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:15:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:33 INFO - (blobuploader) - INFO - Done attempting. 04:15:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:15:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:15:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:34 INFO - (blobuploader) - INFO - Done attempting. 04:15:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:15:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:15:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:35 INFO - (blobuploader) - INFO - Done attempting. 04:15:35 INFO - (blobuploader) - INFO - Iteration through files over. 04:15:35 INFO - Return code: 0 04:15:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:15:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:15:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8eefe9c4f538b26c754a67ad90ca2c030789dad3134c74712674aa7374ff60dfad2015a1d8798e4eb69be2230bf1f1c52d0a0b15b140a34d52cf4cf01ee28dcb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9a954c0680e3af468f6f143420c3334da349cd9b9893f4234c0076e7647f5636f3c1ecfc42a4fac413e1f6bb08a2ab051b1d608815e0bced3a8f97f5cc7672c0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2fb003f040c9e01b624fb85f2ce7e62241e67c0b2ef74c46b1dc734bd3e793308ee4c74b2afa68211ea3552f717c433eac0cb4f8c64babe49b9201206b711313", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fbe8a08cdcdbb711e36fc3eebc962341487c2d03ed9a8fd9a0729b66d0150eb71448fdbd10cb45eb57de5e62b2a43dbea9aa9db0f4733defc53e5812b6b6f27"} 04:15:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:15:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:15:35 INFO - Contents: 04:15:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8eefe9c4f538b26c754a67ad90ca2c030789dad3134c74712674aa7374ff60dfad2015a1d8798e4eb69be2230bf1f1c52d0a0b15b140a34d52cf4cf01ee28dcb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9a954c0680e3af468f6f143420c3334da349cd9b9893f4234c0076e7647f5636f3c1ecfc42a4fac413e1f6bb08a2ab051b1d608815e0bced3a8f97f5cc7672c0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2fb003f040c9e01b624fb85f2ce7e62241e67c0b2ef74c46b1dc734bd3e793308ee4c74b2afa68211ea3552f717c433eac0cb4f8c64babe49b9201206b711313", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fbe8a08cdcdbb711e36fc3eebc962341487c2d03ed9a8fd9a0729b66d0150eb71448fdbd10cb45eb57de5e62b2a43dbea9aa9db0f4733defc53e5812b6b6f27"} 04:15:35 INFO - Running post-run listener: copy_logs_to_upload_dir 04:15:35 INFO - Copying logs to upload dir... 04:15:35 INFO - mkdir: /builds/slave/test/build/upload/logs 04:15:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.589641 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-25 04:15:35.580776) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 04:15:35.584369) ========= 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=199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8eefe9c4f538b26c754a67ad90ca2c030789dad3134c74712674aa7374ff60dfad2015a1d8798e4eb69be2230bf1f1c52d0a0b15b140a34d52cf4cf01ee28dcb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9a954c0680e3af468f6f143420c3334da349cd9b9893f4234c0076e7647f5636f3c1ecfc42a4fac413e1f6bb08a2ab051b1d608815e0bced3a8f97f5cc7672c0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2fb003f040c9e01b624fb85f2ce7e62241e67c0b2ef74c46b1dc734bd3e793308ee4c74b2afa68211ea3552f717c433eac0cb4f8c64babe49b9201206b711313", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fbe8a08cdcdbb711e36fc3eebc962341487c2d03ed9a8fd9a0729b66d0150eb71448fdbd10cb45eb57de5e62b2a43dbea9aa9db0f4733defc53e5812b6b6f27"} build_url:https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011231 build_url: 'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8eefe9c4f538b26c754a67ad90ca2c030789dad3134c74712674aa7374ff60dfad2015a1d8798e4eb69be2230bf1f1c52d0a0b15b140a34d52cf4cf01ee28dcb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9a954c0680e3af468f6f143420c3334da349cd9b9893f4234c0076e7647f5636f3c1ecfc42a4fac413e1f6bb08a2ab051b1d608815e0bced3a8f97f5cc7672c0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2fb003f040c9e01b624fb85f2ce7e62241e67c0b2ef74c46b1dc734bd3e793308ee4c74b2afa68211ea3552f717c433eac0cb4f8c64babe49b9201206b711313", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fbe8a08cdcdbb711e36fc3eebc962341487c2d03ed9a8fd9a0729b66d0150eb71448fdbd10cb45eb57de5e62b2a43dbea9aa9db0f4733defc53e5812b6b6f27"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 04:15:35.612897) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:15:35.613215) ========= 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=199fb4c72b90437b990b419b000001c5-1506336847.334245-840777939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:15:35.696197) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 04:15:35.696518) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 04:15:35.696842) ========= ========= Total master_lag: 0.21 =========