builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-011 starttime: 1507026805.61 results: success (0) revision: 14841c4d8a9783ad55a0c560ec9312ba16200ac1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:25.610089) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:25.610745) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:25.611025) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:25.645814) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:25.646227) ========= 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=5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 03:33:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.8M=0s 2017-10-03 03:33:25 (46.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121310 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:25.789550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:25.789917) ========= 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=5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015556 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:25.854853) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 03:33:25.855192) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 14841c4d8a9783ad55a0c560ec9312ba16200ac1 --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 14841c4d8a9783ad55a0c560ec9312ba16200ac1 --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=5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 03:33:25,922 truncating revision to first 12 chars 2017-10-03 03:33:25,922 Setting DEBUG logging. 2017-10-03 03:33:25,922 attempt 1/10 2017-10-03 03:33:25,922 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/14841c4d8a97?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 03:33:26,573 unpacking tar archive at: mozilla-central-14841c4d8a97/testing/mozharness/ program finished with exit code 0 elapsedTime=0.994349 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 03:33:26.869269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:26.869568) ========= script_repo_revision: 14841c4d8a9783ad55a0c560ec9312ba16200ac1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:26.872980) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:26.873244) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 03:33:26.888970) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-10-03 03:33:26.889305) ========= /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=5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:33:27 INFO - MultiFileLogger online at 20171003 03:33:27 in /builds/slave/test 03:33:27 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:33:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:33:27 INFO - {'append_to_log': False, 03:33:27 INFO - 'base_work_dir': '/builds/slave/test', 03:33:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:33:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:33:27 INFO - 'branch': 'Firefox-Non-PGO', 03:33:27 INFO - 'buildbot_json_path': 'buildprops.json', 03:33:27 INFO - 'code_coverage': False, 03:33:27 INFO - 'config_files': ('talos/linux_config.py',), 03:33:27 INFO - 'default_actions': ('clobber', 03:33:27 INFO - 'read-buildbot-config', 03:33:27 INFO - 'download-and-extract', 03:33:27 INFO - 'populate-webroot', 03:33:27 INFO - 'create-virtualenv', 03:33:27 INFO - 'install', 03:33:27 INFO - 'setup-mitmproxy', 03:33:27 INFO - 'run-tests'), 03:33:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:33:27 INFO - 'disable_ccov_upload': False, 03:33:27 INFO - 'disable_stylo': False, 03:33:27 INFO - 'download_minidump_stackwalk': True, 03:33:27 INFO - 'download_symbols': 'ondemand', 03:33:27 INFO - 'enable_stylo': False, 03:33:27 INFO - 'enable_webrender': False, 03:33:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:33:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:33:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:33:27 INFO - 'gecko_profile': False, 03:33:27 INFO - 'gecko_profile_interval': 0, 03:33:27 INFO - 'installer_path': 'installer.exe', 03:33:27 INFO - 'log_level': 'info', 03:33:27 INFO - 'log_name': 'talos', 03:33:27 INFO - 'log_to_console': True, 03:33:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:33:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:33:27 INFO - 'opt_config_files': (), 03:33:27 INFO - 'pip_index': False, 03:33:27 INFO - 'suite': 'dromaeojs-e10s', 03:33:27 INFO - 'system_bits': '32', 03:33:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:33:27 INFO - 'title': 'talos-linux64-ix-011', 03:33:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:33:27 INFO - 'use_talos_json': True, 03:33:27 INFO - 'verify': 'False', 03:33:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:33:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:33:27 INFO - 'work_dir': 'build'} 03:33:27 INFO - [mozharness: 2017-10-03 10:33:27.103212Z] Running clobber step. 03:33:27 INFO - Running pre-action listener: _resource_record_pre_action 03:33:27 INFO - Running main action method: clobber 03:33:27 INFO - rmtree: /builds/slave/test/build 03:33:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:33:28 INFO - Running post-action listener: _resource_record_post_action 03:33:28 INFO - [mozharness: 2017-10-03 10:33:28.612807Z] Finished clobber step (success) 03:33:28 INFO - [mozharness: 2017-10-03 10:33:28.612906Z] Running read-buildbot-config step. 03:33:28 INFO - Running pre-action listener: _resource_record_pre_action 03:33:28 INFO - Running main action method: read_buildbot_config 03:33:28 INFO - Using buildbot properties: 03:33:28 INFO - { 03:33:28 INFO - "project": "", 03:33:28 INFO - "product": "firefox", 03:33:28 INFO - "who": "archaeopteryx@coole-files.de", 03:33:28 INFO - "installer_path": "public/build/target.tar.bz2", 03:33:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:33:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:33:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:33:28 INFO - "stage_platform": "linux64", 03:33:28 INFO - "basedir": "/builds/slave/test", 03:33:28 INFO - "buildnumber": 891, 03:33:28 INFO - "platform": "ubuntu64_hw", 03:33:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:33:28 INFO - "slavebuilddir": "test", 03:33:28 INFO - "taskId": "GrudWy9WRMOou2g8vEX0GA", 03:33:28 INFO - "branch": "mozilla-central", 03:33:28 INFO - "script_repo_revision": "production", 03:33:28 INFO - "revision": "14841c4d8a9783ad55a0c560ec9312ba16200ac1", 03:33:28 INFO - "slavename": "talos-linux64-ix-011", 03:33:28 INFO - "repo_path": "mozilla-central" 03:33:28 INFO - } 03:33:28 INFO - Finding installer, test and symbols from parent task. 03:33:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrudWy9WRMOou2g8vEX0GA'}, attempt #1 03:33:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrudWy9WRMOou2g8vEX0GA'}, attempt #1 03:33:29 INFO - Task dependencies: PisX6-VjSaOdJA46TjmVsA 03:33:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA'}, attempt #1 03:33:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.tar.bz2 03:33:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.test_packages.json 03:33:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.crashreporter-symbols.zip 03:33:29 INFO - Running post-action listener: _resource_record_post_action 03:33:29 INFO - [mozharness: 2017-10-03 10:33:29.969048Z] Finished read-buildbot-config step (success) 03:33:29 INFO - [mozharness: 2017-10-03 10:33:29.969269Z] Running download-and-extract step. 03:33:29 INFO - Running pre-action listener: _resource_record_pre_action 03:33:29 INFO - Running main action method: download_and_extract 03:33:29 INFO - mkdir: /builds/slave/test/build/tests 03:33:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:29 INFO - trying https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.test_packages.json 03:33:29 INFO - Downloading https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:33:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:33:30 INFO - Downloaded 989 bytes. 03:33:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:33:30 INFO - Using the following test package requirements: 03:33:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:33:30 INFO - u'common': [u'target.common.tests.zip'], 03:33:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:33:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:33:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:33:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:33:30 INFO - u'mozbase': [u'target.common.tests.zip'], 03:33:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:33:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:33:30 INFO - u'web-platform': [u'target.common.tests.zip', 03:33:30 INFO - u'target.web-platform.tests.tar.gz'], 03:33:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:33:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:33:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.common.tests.zip 03:33:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:33:30 INFO - Fetch https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.common.tests.zip into memory 03:33:32 INFO - Content-Length response header: 55346470 03:33:32 INFO - Bytes received: 55346470 03:33:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.talos.tests.zip 03:33:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:33:42 INFO - Fetch https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.talos.tests.zip into memory 03:33:44 INFO - Content-Length response header: 14522427 03:33:44 INFO - Bytes received: 14522427 03:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:44 INFO - trying https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.tar.bz2 03:33:44 INFO - Downloading https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:33:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:33:46 INFO - Downloaded 61618739 bytes. 03:33:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.tar.bz2 03:33:46 INFO - mkdir: /builds/slave/test/properties 03:33:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:33:46 INFO - Writing to file /builds/slave/test/properties/build_url 03:33:46 INFO - Contents: 03:33:46 INFO - build_url:https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.tar.bz2 03:33:46 INFO - Running post-action listener: _resource_record_post_action 03:33:46 INFO - Running post-action listener: find_tests_for_verification 03:33:46 INFO - Running post-action listener: set_extra_try_arguments 03:33:46 INFO - [mozharness: 2017-10-03 10:33:46.926171Z] Finished download-and-extract step (success) 03:33:46 INFO - [mozharness: 2017-10-03 10:33:46.926403Z] Running populate-webroot step. 03:33:46 INFO - Running pre-action listener: _resource_record_pre_action 03:33:46 INFO - Running main action method: populate_webroot 03:33:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:33:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['tresize', 03:33:46 INFO - 'tcanvasmark']}, 03:33:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:33:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['dromaeo_css', 03:33:46 INFO - 'kraken']}, 03:33:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:33:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:33:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:33:46 INFO - 'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:33:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:33:46 INFO - 'tests': ['damp', 'tps']}, 03:33:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:33:46 INFO - 'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['damp', 'tps']}, 03:33:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:33:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['dromaeo_dom']}, 03:33:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:33:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['basic_compositor_video', 03:33:46 INFO - 'glvideo']}, 03:33:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:33:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:33:46 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:33:46 INFO - 'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['ts_paint_webext', 03:33:46 INFO - 'tp5o_webext']}, 03:33:46 INFO - 'other-e10s': {'tests': ['a11yr', 03:33:46 INFO - 'ts_paint', 03:33:46 INFO - 'tpaint', 03:33:46 INFO - 'sessionrestore', 03:33:46 INFO - 'sessionrestore_many_windows', 03:33:46 INFO - 'sessionrestore_no_auto_restore', 03:33:46 INFO - 'tabpaint', 03:33:46 INFO - 'cpstartup']}, 03:33:46 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['a11yr', 03:33:46 INFO - 'ts_paint', 03:33:46 INFO - 'tpaint', 03:33:46 INFO - 'sessionrestore', 03:33:46 INFO - 'sessionrestore_many_windows', 03:33:46 INFO - 'sessionrestore_no_auto_restore', 03:33:46 INFO - 'tabpaint', 03:33:46 INFO - 'cpstartup']}, 03:33:46 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 03:33:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:33:46 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['perf_reftest_singletons']}, 03:33:46 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['perf_reftest']}, 03:33:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:33:46 INFO - 'tsvgr_opacity', 03:33:46 INFO - 'tart', 03:33:46 INFO - 'tscrollx', 03:33:46 INFO - 'tsvg_static']}, 03:33:46 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['tsvgx', 03:33:46 INFO - 'tsvgr_opacity', 03:33:46 INFO - 'tart', 03:33:46 INFO - 'tscrollx', 03:33:46 INFO - 'tsvg_static']}, 03:33:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:33:46 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:33:46 INFO - 'talos_options': ['--disable-stylo'], 03:33:46 INFO - 'tests': ['tp5o']}, 03:33:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:33:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:33:46 INFO - 'talos_options': ['--mitmproxy', 03:33:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:33:46 INFO - '--firstNonBlankPaint'], 03:33:46 INFO - 'tests': ['tp6_google', 03:33:46 INFO - 'tp6_youtube', 03:33:46 INFO - 'tp6_amazon', 03:33:46 INFO - 'tp6_facebook']}, 03:33:46 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:33:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:33:46 INFO - 'talos_options': ['--disable-stylo', 03:33:46 INFO - '--mitmproxy', 03:33:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:33:46 INFO - '--firstNonBlankPaint'], 03:33:46 INFO - 'tests': ['tp6_google', 03:33:46 INFO - 'tp6_youtube', 03:33:46 INFO - 'tp6_amazon', 03:33:46 INFO - 'tp6_facebook']}, 03:33:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:33:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:33:46 INFO - 'talos_options': ['--stylo-threads=1', 03:33:46 INFO - '--mitmproxy', 03:33:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:33:46 INFO - '--firstNonBlankPaint'], 03:33:46 INFO - 'tests': ['tp6_google', 03:33:46 INFO - 'tp6_youtube', 03:33:46 INFO - 'tp6_amazon', 03:33:46 INFO - 'tp6_facebook']}, 03:33:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:33:46 INFO - 'talos_options': ['--xperf_path', 03:33:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:33:46 INFO - 'tests': ['tp5n']}, 03:33:46 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:33:46 INFO - 'talos_options': ['--disable-stylo', 03:33:46 INFO - '--xperf_path', 03:33:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:33:46 INFO - 'tests': ['tp5n']}}} 03:33:46 INFO - Running post-action listener: _resource_record_post_action 03:33:46 INFO - [mozharness: 2017-10-03 10:33:46.946122Z] Finished populate-webroot step (success) 03:33:46 INFO - [mozharness: 2017-10-03 10:33:46.946213Z] Running create-virtualenv step. 03:33:46 INFO - Running pre-action listener: _resource_record_pre_action 03:33:46 INFO - Running main action method: create_virtualenv 03:33:46 INFO - Creating virtualenv /builds/slave/test/build/venv 03:33:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:33:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:33:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:33:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:33:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:33:47 INFO - Installing distribute.............................................................................................................................................................................................done. 03:33:47 INFO - Installing pip...............done. 03:33:47 INFO - Return code: 0 03:33:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:33:47 INFO - Reading from file tmpfile_stdout 03:33:47 INFO - Output received: 03:33:47 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:33:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:47 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 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:33:47 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:33:47 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:33:47 INFO - Using env: {'DISPLAY': ':0', 03:33:47 INFO - 'HOME': '/home/cltbld', 03:33:47 INFO - 'LANG': 'en_US.UTF-8', 03:33:47 INFO - 'LANGUAGE': 'en_US:en', 03:33:47 INFO - 'LOGNAME': 'cltbld', 03:33:47 INFO - 'MAIL': '/var/mail/cltbld', 03:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:47 INFO - 'MOZ_NO_REMOTE': '1', 03:33:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:47 INFO - 'NO_EM_RESTART': '1', 03:33:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:47 INFO - 'PWD': '/builds/slave/test', 03:33:47 INFO - 'SHELL': '/bin/bash', 03:33:47 INFO - 'SHLVL': '1', 03:33:47 INFO - 'TERM': 'linux', 03:33:47 INFO - 'TMOUT': '86400', 03:33:47 INFO - 'USER': 'cltbld', 03:33:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:47 INFO - '_': '/tools/buildbot/bin/python'} 03:33:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:33:49 INFO - Downloading/unpacking pip>=1.5 03:33:49 INFO - Running setup.py egg_info for package pip 03:33:49 INFO - warning: no files found matching 'pip/cacert.pem' 03:33:49 INFO - warning: no files found matching '*.html' under directory 'docs' 03:33:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:33:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:33:49 INFO - Installing collected packages: pip 03:33:49 INFO - Found existing installation: pip 1.1 03:33:49 INFO - Uninstalling pip: 03:33:49 INFO - Successfully uninstalled pip 03:33:49 INFO - Running setup.py install for pip 03:33:49 INFO - warning: no files found matching 'pip/cacert.pem' 03:33:49 INFO - warning: no files found matching '*.html' under directory 'docs' 03:33:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:33:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:33:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:33:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:33:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:33:49 INFO - Successfully installed pip 03:33:49 INFO - Cleaning up... 03:33:49 INFO - Return code: 0 03:33:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:33:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:33:49 INFO - Using env: {'DISPLAY': ':0', 03:33:49 INFO - 'HOME': '/home/cltbld', 03:33:49 INFO - 'LANG': 'en_US.UTF-8', 03:33:49 INFO - 'LANGUAGE': 'en_US:en', 03:33:49 INFO - 'LOGNAME': 'cltbld', 03:33:49 INFO - 'MAIL': '/var/mail/cltbld', 03:33:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:49 INFO - 'MOZ_NO_REMOTE': '1', 03:33:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:49 INFO - 'NO_EM_RESTART': '1', 03:33:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:49 INFO - 'PWD': '/builds/slave/test', 03:33:49 INFO - 'SHELL': '/bin/bash', 03:33:49 INFO - 'SHLVL': '1', 03:33:49 INFO - 'TERM': 'linux', 03:33:49 INFO - 'TMOUT': '86400', 03:33:49 INFO - 'USER': 'cltbld', 03:33:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:33:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:49 INFO - '_': '/tools/buildbot/bin/python'} 03:33:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:49 INFO - Downloading/unpacking psutil>=3.1.1 03:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:33:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:33:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:33:50 INFO - Installing collected packages: psutil 03:33:50 INFO - Running setup.py install for psutil 03:33:50 INFO - building 'psutil._psutil_linux' extension 03:33:50 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:33:50 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:33:50 INFO - building 'psutil._psutil_posix' extension 03:33:50 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:33:50 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:33:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:33:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:33:51 INFO - Successfully installed psutil 03:33:51 INFO - Cleaning up... 03:33:52 INFO - Return code: 0 03:33:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:33:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:33:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:33:52 INFO - Using env: {'DISPLAY': ':0', 03:33:52 INFO - 'HOME': '/home/cltbld', 03:33:52 INFO - 'LANG': 'en_US.UTF-8', 03:33:52 INFO - 'LANGUAGE': 'en_US:en', 03:33:52 INFO - 'LOGNAME': 'cltbld', 03:33:52 INFO - 'MAIL': '/var/mail/cltbld', 03:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:52 INFO - 'MOZ_NO_REMOTE': '1', 03:33:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:52 INFO - 'NO_EM_RESTART': '1', 03:33:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:52 INFO - 'PWD': '/builds/slave/test', 03:33:52 INFO - 'SHELL': '/bin/bash', 03:33:52 INFO - 'SHLVL': '1', 03:33:52 INFO - 'TERM': 'linux', 03:33:52 INFO - 'TMOUT': '86400', 03:33:52 INFO - 'USER': 'cltbld', 03:33:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:52 INFO - '_': '/tools/buildbot/bin/python'} 03:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:33:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:33:54 INFO - Installing collected packages: mozsystemmonitor 03:33:54 INFO - Running setup.py install for mozsystemmonitor 03:33:55 INFO - Successfully installed mozsystemmonitor 03:33:55 INFO - Cleaning up... 03:33:56 INFO - Return code: 0 03:33:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:33:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:33:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:33:56 INFO - Using env: {'DISPLAY': ':0', 03:33:56 INFO - 'HOME': '/home/cltbld', 03:33:56 INFO - 'LANG': 'en_US.UTF-8', 03:33:56 INFO - 'LANGUAGE': 'en_US:en', 03:33:56 INFO - 'LOGNAME': 'cltbld', 03:33:56 INFO - 'MAIL': '/var/mail/cltbld', 03:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:56 INFO - 'MOZ_NO_REMOTE': '1', 03:33:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:56 INFO - 'NO_EM_RESTART': '1', 03:33:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:56 INFO - 'PWD': '/builds/slave/test', 03:33:56 INFO - 'SHELL': '/bin/bash', 03:33:56 INFO - 'SHLVL': '1', 03:33:56 INFO - 'TERM': 'linux', 03:33:56 INFO - 'TMOUT': '86400', 03:33:56 INFO - 'USER': 'cltbld', 03:33:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:56 INFO - '_': '/tools/buildbot/bin/python'} 03:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:56 INFO - Downloading/unpacking jsonschema==2.5.1 03:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:33:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:57 INFO - Downloading functools32-3.2.3-2.tar.gz 03:33:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:33:57 INFO - warning: no files found matching '*.txt' 03:33:57 INFO - no previously-included directories found matching 'build' 03:33:57 INFO - no previously-included directories found matching 'dist' 03:33:57 INFO - no previously-included directories found matching '.git*' 03:33:57 INFO - Installing collected packages: jsonschema, functools32 03:33:57 INFO - Running setup.py install for functools32 03:33:57 INFO - warning: no files found matching '*.txt' 03:33:57 INFO - no previously-included directories found matching 'build' 03:33:57 INFO - no previously-included directories found matching 'dist' 03:33:57 INFO - no previously-included directories found matching '.git*' 03:33:57 INFO - Successfully installed jsonschema functools32 03:33:57 INFO - Cleaning up... 03:33:57 INFO - Return code: 0 03:33:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:57 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 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:33:57 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:33:57 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:33:57 INFO - Using env: {'DISPLAY': ':0', 03:33:57 INFO - 'HOME': '/home/cltbld', 03:33:57 INFO - 'LANG': 'en_US.UTF-8', 03:33:57 INFO - 'LANGUAGE': 'en_US:en', 03:33:57 INFO - 'LOGNAME': 'cltbld', 03:33:57 INFO - 'MAIL': '/var/mail/cltbld', 03:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:57 INFO - 'MOZ_NO_REMOTE': '1', 03:33:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:57 INFO - 'NO_EM_RESTART': '1', 03:33:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:57 INFO - 'PWD': '/builds/slave/test', 03:33:57 INFO - 'SHELL': '/bin/bash', 03:33:57 INFO - 'SHLVL': '1', 03:33:57 INFO - 'TERM': 'linux', 03:33:57 INFO - 'TMOUT': '86400', 03:33:57 INFO - 'USER': 'cltbld', 03:33:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:57 INFO - '_': '/tools/buildbot/bin/python'} 03:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:33:58 INFO - Cleaning up... 03:33:58 INFO - Return code: 0 03:33:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:33:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:33:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:33:58 INFO - Using env: {'DISPLAY': ':0', 03:33:58 INFO - 'HOME': '/home/cltbld', 03:33:58 INFO - 'LANG': 'en_US.UTF-8', 03:33:58 INFO - 'LANGUAGE': 'en_US:en', 03:33:58 INFO - 'LOGNAME': 'cltbld', 03:33:58 INFO - 'MAIL': '/var/mail/cltbld', 03:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:58 INFO - 'MOZ_NO_REMOTE': '1', 03:33:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:58 INFO - 'NO_EM_RESTART': '1', 03:33:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:58 INFO - 'PWD': '/builds/slave/test', 03:33:58 INFO - 'SHELL': '/bin/bash', 03:33:58 INFO - 'SHLVL': '1', 03:33:58 INFO - 'TERM': 'linux', 03:33:58 INFO - 'TMOUT': '86400', 03:33:58 INFO - 'USER': 'cltbld', 03:33:58 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:58 INFO - '_': '/tools/buildbot/bin/python'} 03:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:58 INFO - Downloading/unpacking blobuploader==1.2.4 03:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:59 INFO - Downloading blobuploader-1.2.4.tar.gz 03:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:33:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:33:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:59 INFO - Downloading docopt-0.6.1.tar.gz 03:34:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:34:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:34:00 INFO - Running setup.py install for blobuploader 03:34:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:34:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:34:00 INFO - Running setup.py install for requests 03:34:00 INFO - Running setup.py install for docopt 03:34:00 INFO - Successfully installed blobuploader requests docopt 03:34:00 INFO - Cleaning up... 03:34:00 INFO - Return code: 0 03:34:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:34:00 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:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:00 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:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:00 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 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:34:00 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:34:00 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:34:00 INFO - Using env: {'DISPLAY': ':0', 03:34:00 INFO - 'HOME': '/home/cltbld', 03:34:00 INFO - 'LANG': 'en_US.UTF-8', 03:34:00 INFO - 'LANGUAGE': 'en_US:en', 03:34:00 INFO - 'LOGNAME': 'cltbld', 03:34:00 INFO - 'MAIL': '/var/mail/cltbld', 03:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:00 INFO - 'MOZ_NO_REMOTE': '1', 03:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:00 INFO - 'NO_EM_RESTART': '1', 03:34:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:00 INFO - 'PWD': '/builds/slave/test', 03:34:00 INFO - 'SHELL': '/bin/bash', 03:34:00 INFO - 'SHLVL': '1', 03:34:00 INFO - 'TERM': 'linux', 03:34:00 INFO - 'TMOUT': '86400', 03:34:00 INFO - 'USER': 'cltbld', 03:34:00 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:00 INFO - '_': '/tools/buildbot/bin/python'} 03:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:34:00 INFO - Running setup.py (path:/tmp/pip-f23f4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:34:00 INFO - Running setup.py (path:/tmp/pip-SVJpRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:34:01 INFO - Running setup.py (path:/tmp/pip-IAGVVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:34:01 INFO - Running setup.py (path:/tmp/pip-LoLfwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:34:01 INFO - Running setup.py (path:/tmp/pip-LXi_Vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:34:01 INFO - Running setup.py (path:/tmp/pip-Fax9e8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:34:01 INFO - Running setup.py (path:/tmp/pip-b0DfNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:34:01 INFO - Running setup.py (path:/tmp/pip-Z0JGRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:34:01 INFO - Running setup.py (path:/tmp/pip-czxy3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:34:01 INFO - Running setup.py (path:/tmp/pip-qiM6sP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:34:01 INFO - Running setup.py (path:/tmp/pip-IIq_To-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:34:01 INFO - Running setup.py (path:/tmp/pip-UDb8zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:34:01 INFO - Running setup.py (path:/tmp/pip-MHpFtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:34:01 INFO - Running setup.py (path:/tmp/pip-uFbTHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:34:01 INFO - Running setup.py (path:/tmp/pip-jImFfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:34:01 INFO - Running setup.py (path:/tmp/pip-aQgW9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:34:01 INFO - Running setup.py (path:/tmp/pip-Il5vYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:34:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:34:02 INFO - Running setup.py install for manifestparser 03:34:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:34:02 INFO - Running setup.py install for mozcrash 03:34:02 INFO - Running setup.py install for mozdebug 03:34:02 INFO - Running setup.py install for mozdevice 03:34:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:34:02 INFO - Running setup.py install for mozfile 03:34:02 INFO - Running setup.py install for mozhttpd 03:34:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:34:02 INFO - Running setup.py install for mozinfo 03:34:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:34:02 INFO - Running setup.py install for mozInstall 03:34:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:34:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:34:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:34:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:34:02 INFO - Running setup.py install for mozleak 03:34:02 INFO - Running setup.py install for mozlog 03:34:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:34:03 INFO - Running setup.py install for moznetwork 03:34:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:34:03 INFO - Running setup.py install for mozprocess 03:34:03 INFO - Running setup.py install for mozprofile 03:34:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:34:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:34:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:34:03 INFO - Running setup.py install for mozrunner 03:34:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:34:03 INFO - Running setup.py install for mozscreenshot 03:34:03 INFO - Running setup.py install for moztest 03:34:03 INFO - Running setup.py install for mozversion 03:34:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:34:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:34:03 INFO - Cleaning up... 03:34:03 INFO - Return code: 0 03:34:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:03 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 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:34:03 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:34:03 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:34:03 INFO - Using env: {'DISPLAY': ':0', 03:34:03 INFO - 'HOME': '/home/cltbld', 03:34:03 INFO - 'LANG': 'en_US.UTF-8', 03:34:03 INFO - 'LANGUAGE': 'en_US:en', 03:34:03 INFO - 'LOGNAME': 'cltbld', 03:34:03 INFO - 'MAIL': '/var/mail/cltbld', 03:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:03 INFO - 'MOZ_NO_REMOTE': '1', 03:34:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:03 INFO - 'NO_EM_RESTART': '1', 03:34:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:03 INFO - 'PWD': '/builds/slave/test', 03:34:03 INFO - 'SHELL': '/bin/bash', 03:34:03 INFO - 'SHLVL': '1', 03:34:03 INFO - 'TERM': 'linux', 03:34:03 INFO - 'TMOUT': '86400', 03:34:03 INFO - 'USER': 'cltbld', 03:34:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:03 INFO - '_': '/tools/buildbot/bin/python'} 03:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:34:04 INFO - Running setup.py (path:/tmp/pip-7BI3S3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:34:04 INFO - Running setup.py (path:/tmp/pip-XsdUNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:34:04 INFO - Running setup.py (path:/tmp/pip-ZDjxli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:34:04 INFO - Running setup.py (path:/tmp/pip-Ez5FgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:34:04 INFO - Running setup.py (path:/tmp/pip-huFgAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:34:04 INFO - Running setup.py (path:/tmp/pip-JjRZGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:34:04 INFO - Running setup.py (path:/tmp/pip-0dSq7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:34:04 INFO - Running setup.py (path:/tmp/pip-uQnUp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:34:04 INFO - Running setup.py (path:/tmp/pip-lTr6Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:34:04 INFO - Running setup.py (path:/tmp/pip-nC1IyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:34:04 INFO - Running setup.py (path:/tmp/pip-Gjt6nI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:34:04 INFO - Running setup.py (path:/tmp/pip-QMah4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:34:04 INFO - Running setup.py (path:/tmp/pip-EPuCZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:34:04 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:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:34:04 INFO - Running setup.py (path:/tmp/pip-Nrauge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:34:05 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:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:34:05 INFO - Running setup.py (path:/tmp/pip-0Iawv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:34:05 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:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:34:05 INFO - Running setup.py (path:/tmp/pip-OCEt0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:34:05 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:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:34:05 INFO - Running setup.py (path:/tmp/pip-L0Ec6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:34:05 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:34:05 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:34:05 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:34:05 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:34:05 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:34:05 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:34:05 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:34:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:06 INFO - Downloading blessings-1.6.tar.gz 03:34:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:34:06 INFO - Installing collected packages: blessings 03:34:06 INFO - Running setup.py install for blessings 03:34:06 INFO - Successfully installed blessings 03:34:06 INFO - Cleaning up... 03:34:06 INFO - Return code: 0 03:34:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:34:06 INFO - Reading from file tmpfile_stdout 03:34:06 INFO - Current package versions: 03:34:06 INFO - argparse == 1.2.1 03:34:06 INFO - blessings == 1.6 03:34:06 INFO - blobuploader == 1.2.4 03:34:06 INFO - docopt == 0.6.1 03:34:06 INFO - functools32 == 3.2.3-2 03:34:06 INFO - jsonschema == 2.5.1 03:34:06 INFO - manifestparser == 1.1 03:34:06 INFO - mozInstall == 1.13 03:34:06 INFO - mozcrash == 1.0 03:34:06 INFO - mozdebug == 0.1 03:34:06 INFO - mozdevice == 0.51 03:34:06 INFO - mozfile == 1.2 03:34:06 INFO - mozhttpd == 0.7 03:34:06 INFO - mozinfo == 0.10 03:34:06 INFO - mozleak == 0.1 03:34:06 INFO - mozlog == 3.5 03:34:06 INFO - moznetwork == 0.27 03:34:06 INFO - mozprocess == 0.25 03:34:06 INFO - mozprofile == 0.28 03:34:06 INFO - mozrunner == 6.13 03:34:06 INFO - mozscreenshot == 0.1 03:34:06 INFO - mozsystemmonitor == 0.3 03:34:06 INFO - moztest == 0.8 03:34:06 INFO - mozversion == 1.4 03:34:06 INFO - psutil == 3.1.1 03:34:06 INFO - requests == 1.2.3 03:34:06 INFO - wsgiref == 0.1.2 03:34:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:06 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 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:34:06 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:34:06 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:34:06 INFO - Using env: {'DISPLAY': ':0', 03:34:06 INFO - 'HOME': '/home/cltbld', 03:34:06 INFO - 'LANG': 'en_US.UTF-8', 03:34:06 INFO - 'LANGUAGE': 'en_US:en', 03:34:06 INFO - 'LOGNAME': 'cltbld', 03:34:06 INFO - 'MAIL': '/var/mail/cltbld', 03:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:06 INFO - 'MOZ_NO_REMOTE': '1', 03:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:06 INFO - 'NO_EM_RESTART': '1', 03:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:06 INFO - 'PWD': '/builds/slave/test', 03:34:06 INFO - 'SHELL': '/bin/bash', 03:34:06 INFO - 'SHLVL': '1', 03:34:06 INFO - 'TERM': 'linux', 03:34:06 INFO - 'TMOUT': '86400', 03:34:06 INFO - 'USER': 'cltbld', 03:34:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:06 INFO - '_': '/tools/buildbot/bin/python'} 03:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:07 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:34:07 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:34:07 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:34:07 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:34:07 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:34:07 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:34:07 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:34:07 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:34:07 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:34:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:34:08 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:34:08 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:34:08 INFO - Installing collected packages: simplejson 03:34:08 INFO - Running setup.py install for simplejson 03:34:08 INFO - building 'simplejson._speedups' extension 03:34:08 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:34:09 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:34:09 INFO - Successfully installed simplejson 03:34:09 INFO - Cleaning up... 03:34:09 INFO - Return code: 0 03:34:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:34:09 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:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:09 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:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:09 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 0x215e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2326b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', '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:34:09 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:34:09 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:34:09 INFO - Using env: {'DISPLAY': ':0', 03:34:09 INFO - 'HOME': '/home/cltbld', 03:34:09 INFO - 'LANG': 'en_US.UTF-8', 03:34:09 INFO - 'LANGUAGE': 'en_US:en', 03:34:09 INFO - 'LOGNAME': 'cltbld', 03:34:09 INFO - 'MAIL': '/var/mail/cltbld', 03:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:09 INFO - 'MOZ_NO_REMOTE': '1', 03:34:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:09 INFO - 'NO_EM_RESTART': '1', 03:34:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:09 INFO - 'PWD': '/builds/slave/test', 03:34:09 INFO - 'SHELL': '/bin/bash', 03:34:09 INFO - 'SHLVL': '1', 03:34:09 INFO - 'TERM': 'linux', 03:34:09 INFO - 'TMOUT': '86400', 03:34:09 INFO - 'USER': 'cltbld', 03:34:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:09 INFO - '_': '/tools/buildbot/bin/python'} 03:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:34:09 INFO - Cleaning up... 03:34:09 INFO - Return code: 0 03:34:09 INFO - Running post-action listener: _resource_record_post_action 03:34:09 INFO - Running post-action listener: _start_resource_monitoring 03:34:09 INFO - Starting resource monitoring. 03:34:09 INFO - [mozharness: 2017-10-03 10:34:09.549256Z] Finished create-virtualenv step (success) 03:34:09 INFO - [mozharness: 2017-10-03 10:34:09.549728Z] Running install step. 03:34:09 INFO - Running pre-action listener: _resource_record_pre_action 03:34:09 INFO - Running main action method: install 03:34:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:34:09 INFO - Reading from file tmpfile_stdout 03:34:09 INFO - Detecting whether we're running mozinstall >=1.0... 03:34:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:34:09 INFO - Reading from file tmpfile_stdout 03:34:09 INFO - Output received: 03:34:09 INFO - Usage: mozinstall [options] installer 03:34:09 INFO - Options: 03:34:09 INFO - -h, --help show this help message and exit 03:34:09 INFO - -d DEST, --destination=DEST 03:34:09 INFO - Directory to install application into. [default: 03:34:09 INFO - "/builds/slave/test"] 03:34:09 INFO - --app=APP Application being installed. [default: firefox] 03:34:09 INFO - mkdir: /builds/slave/test/build/application 03:34:09 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:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:34:27 INFO - Reading from file tmpfile_stdout 03:34:27 INFO - Output received: 03:34:27 INFO - /builds/slave/test/build/application/firefox/firefox 03:34:27 INFO - Running post-action listener: _resource_record_post_action 03:34:27 INFO - [mozharness: 2017-10-03 10:34:27.700582Z] Finished install step (success) 03:34:27 INFO - [mozharness: 2017-10-03 10:34:27.700729Z] Running setup-mitmproxy step. 03:34:27 INFO - Running pre-action listener: _resource_record_pre_action 03:34:27 INFO - Running main action method: setup_mitmproxy 03:34:27 INFO - Skipping: mitmproxy is not required 03:34:27 INFO - Running post-action listener: _resource_record_post_action 03:34:27 INFO - [mozharness: 2017-10-03 10:34:27.701680Z] Finished setup-mitmproxy step (success) 03:34:27 INFO - [mozharness: 2017-10-03 10:34:27.701917Z] Running run-tests step. 03:34:27 INFO - Running pre-action listener: _resource_record_pre_action 03:34:27 INFO - Running pre-action listener: _set_gcov_prefix 03:34:27 INFO - Running main action method: run_tests 03:34:27 WARNING - Try message not found. 03:34:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:34:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:34:27 INFO - Python 2.7.3 03:34:27 INFO - Return code: 0 03:34:27 INFO - grabbing minidump binary from tooltool 03:34:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:27 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 0x24c8d60>, '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 0x24c9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c9ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:34:27 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:34:27 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:34:27 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:34:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:34:27 INFO - Return code: 0 03:34:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:34:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:34:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:34:27 INFO - ENV: RUST_BACKTRACE is now full 03:34:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:34:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:34:27 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/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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:34:27 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/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --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:34:27 INFO - Using env: {'DISPLAY': ':0', 03:34:27 INFO - 'HOME': '/home/cltbld', 03:34:27 INFO - 'LANG': 'en_US.UTF-8', 03:34:27 INFO - 'LANGUAGE': 'en_US:en', 03:34:27 INFO - 'LOGNAME': 'cltbld', 03:34:27 INFO - 'MAIL': '/var/mail/cltbld', 03:34:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:34:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:34:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:27 INFO - 'MOZ_NO_REMOTE': '1', 03:34:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:34:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:27 INFO - 'NO_EM_RESTART': '1', 03:34:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:27 INFO - 'PWD': '/builds/slave/test', 03:34:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:34:27 INFO - 'RUST_BACKTRACE': 'full', 03:34:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:34:27 INFO - 'SHELL': '/bin/bash', 03:34:27 INFO - 'SHLVL': '1', 03:34:27 INFO - 'TERM': 'linux', 03:34:27 INFO - 'TMOUT': '86400', 03:34:27 INFO - 'USER': 'cltbld', 03:34:27 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:34:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:27 INFO - '_': '/tools/buildbot/bin/python'} 03:34:27 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/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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:34:28 INFO - mozversion application_buildid: 20171003094133 03:34:28 INFO - mozversion application_changeset: 14841c4d8a9783ad55a0c560ec9312ba16200ac1 03:34:28 INFO - mozversion application_display_name: Nightly 03:34:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:34:28 INFO - mozversion application_name: Firefox 03:34:28 INFO - mozversion application_remotingname: firefox 03:34:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:34:28 INFO - mozversion application_vendor: Mozilla 03:34:28 INFO - mozversion application_version: 58.0a1 03:34:28 INFO - mozversion platform_buildid: 20171003094133 03:34:28 INFO - mozversion platform_changeset: 14841c4d8a9783ad55a0c560ec9312ba16200ac1 03:34:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:34:28 INFO - mozversion platform_version: 58.0a1 03:34:28 INFO - using testdate: 1507026868 03:34:28 INFO - actual date: 1507026868 03:34:28 INFO - starting webserver on 'localhost:53672' 03:34:28 INFO - SUITE-START | Running 2 tests 03:34:28 INFO - TEST-START | dromaeo_css 03:34:28 INFO - Initialising browser for dromaeo_css test... 03:34:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53672/getInfo.html -profile /tmp/tmpLMQPgY/profile 03:34:28 INFO - TEST-INFO | started process 8651 (/builds/slave/test/build/application/firefox/firefox http://localhost:53672/getInfo.html) 03:34:34 INFO - TEST-INFO | 8651: exit 0 03:34:34 INFO - Browser initialized. 03:34:34 INFO - Running cycle 1/1 for dromaeo_css test... 03:34:34 INFO - Using env: {'DISPLAY': ':0', 03:34:34 INFO - 'HOME': '/home/cltbld', 03:34:34 INFO - 'JSGC_DISABLE_POISONING': '1', 03:34:34 INFO - 'LANG': 'en_US.UTF-8', 03:34:34 INFO - 'LANGUAGE': 'en_US:en', 03:34:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:34:34 INFO - 'LOGNAME': 'cltbld', 03:34:34 INFO - 'MAIL': '/var/mail/cltbld', 03:34:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:34:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:34:34 INFO - 'MOZ_CRASHREPORTER': '1', 03:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:34:34 INFO - 'MOZ_NO_REMOTE': '1', 03:34:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:34:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:34 INFO - 'NO_EM_RESTART': '1', 03:34:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:34 INFO - 'PWD': '/builds/slave/test', 03:34:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:34:34 INFO - 'RUST_BACKTRACE': 'full', 03:34:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:34:34 INFO - 'SHELL': '/bin/bash', 03:34:34 INFO - 'SHLVL': '1', 03:34:34 INFO - 'TERM': 'linux', 03:34:34 INFO - 'TMOUT': '86400', 03:34:34 INFO - 'USER': 'cltbld', 03:34:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:34 INFO - '_': '/tools/buildbot/bin/python'} 03:34:34 INFO - TEST-INFO | started process 8923 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLMQPgY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:34:35 INFO - PID 8923 | 03:34:35 INFO - PID 8923 | (/builds/slave/test/build/application/firefox/firefox:8972): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:34:35 INFO - PID 8923 | 03:34:45 INFO - PID 8923 | RSS: Main: 152530944 03:34:45 INFO - PID 8923 | 03:37:42 INFO - PID 8923 | Cycle 1(1): loaded http://localhost:53672/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53672/tests/dromaeo/cssquery-ext.html) 03:37:43 INFO - PID 8923 | RSS: Main: 150564864 03:37:43 INFO - PID 8923 | 03:40:45 INFO - PID 8923 | Cycle 1(1): loaded http://localhost:53672/tests/dromaeo/cssquery-ext.html (next: http://localhost:53672/tests/dromaeo/cssquery-jquery.html) 03:40:46 INFO - PID 8923 | RSS: Main: 151437312 03:40:46 INFO - PID 8923 | 03:43:47 INFO - PID 8923 | Cycle 1(1): loaded http://localhost:53672/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53672/tests/dromaeo/cssquery-mootools.html) 03:43:48 INFO - PID 8923 | RSS: Main: 152420352 03:43:48 INFO - PID 8923 | 03:46:50 INFO - PID 8923 | Cycle 1(1): loaded http://localhost:53672/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53672/tests/dromaeo/cssquery-prototype.html) 03:46:50 INFO - PID 8923 | RSS: Main: 151179264 03:46:50 INFO - PID 8923 | 03:49:53 INFO - PID 8923 | Cycle 1(1): loaded http://localhost:53672/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53672/tests/dromaeo/cssquery-yui.html) 03:49:54 INFO - PID 8923 | RSS: Main: 155545600 03:49:54 INFO - PID 8923 | 03:52:56 INFO - PID 8923 | Cycle 1(1): loaded http://localhost:53672/tests/dromaeo/cssquery-yui.html (next: http://localhost:53672/tests/dromaeo/cssquery-dojo.html) 03:52:56 INFO - PID 8923 | RSS: Main: 155230208 03:52:56 INFO - PID 8923 | 03:52:56 INFO - PID 8923 | __start_tp_report 03:52:56 INFO - PID 8923 | _x_x_mozilla_page_load 03:52:56 INFO - PID 8923 | _x_x_mozilla_page_load_details 03:52:56 INFO - PID 8923 | |i|pagename|runs| 03:52:56 INFO - PID 8923 | |0;dojo.html;3165.8733650653976;3200.215947092963;3203.5194720791887;3207.903762887114;3232.208109013292;6104.389561043893;6234.563580549363;6274.498040156788;6367.808965731029;6374.936250637492;6162.414570615784;6243.781467648633;6273.498120150389;6296.212973378328;6307;5744.712764361776;5745.138229265602;5798.159266906296;5840.532757379421;5930.822075337746;5828.41715828418;5846.093855452412;5893.528517718593;5901.085331773582;5929.347771745104;1210.2131425347995;5658.349289831681;5666.320041594994;5668.801591944303;5808.8838223235725;5675.290588676416;5742.196092547047;5750.367459579443;5755.22304488897;5763.9135412968835;5650;5674.659520428788;5729.226554415142;5746.994276001683;5748.683822389767;6155.72299246535;6179.938200618026;6188.133661287423;6254.655993920333;6263.592866463665;6623.238327592292;6631.834204144887;6634.038064480624;6717;6734.427573656245;6612.07430959666;6714.328567143295;6729.629870357128;6771.864562708767;6822.283660215656;6188;6282.905756413657;6305.558610897239;6312.715927783261;6342.587731797465;6901;6986.16166060076;6996.650167491605;6997.685104170325;7002.754903578452;6556.672166391757;6565.934340656628;6578;6658.766943156967;6662.6335551545135;6567.310432404624;6662.467002639777;6677.565958212701;6685.76599819004;6686.930091185484;6584.209894812653;6590.868182636321;6625.503087268474;6719.227288861745;6724.361185687352;6630;6739.663016849138;6742.089817874622;6743.730250790011;6749.730010799612;4827.444843842933;4888.9488759916785;4920.45874953762;4953.504649535018;4965.82619608312;4281.914361712748;4358.825646974149;4359.934600980988;4370.38814565961;4382.298832186835;5796.362400135981;5821.2141360916585;5869.735861886225;5901.085331773582;5902;6712.295209003082;6784.626845523544;6785.321467853274;6785.830354241085;6794.3545363190415;5758.884822303614;5790.131480277992;5806.622569532967;5809.622374545641;5846.619969701956;5683.374828768832;5685;5737.340205876294;5758.136279558016;5769.105788602773;6282.465990390777;6428.6785660717715;6441.259255185713;6507.219133703986;6528.2166140062545;6606.735730570916;6623.172103487064;6625.105610742585;6625.503087268377;6625.933740662532;6430.421262086435;6433.517486188648;6508;6515.804525864231;6535.019747037983;6554.442872355903;6626.403623673699;6644.734210631521;6651.26836048044;6655.068290439439;6684;6702.229243637044;6756.560823546651;6769.08617336673;6779.423748981392;6309.33751956038;6413.230412350548;6455.61266324022;6467.547271690936;6563.737450501926;6302.275238347649;6440.259370172303;6441.291457939717;6449.097126402214;6482.967585162044;6373.776917807854;6444.420002199637;6456.515761317826;6460.676966151767;6466.838329041623;6461.19235095613;6507.381798729113;6542.018697195268;6545.803625891231;6606.306337834651;6150.446459818638;6182.412670796267;6239.812805615838;6268.247810262797;6314.242290925118 03:52:56 INFO - PID 8923 | |1;ext.html;840.5209030852412;850.1583432401924;868.578739311434;898.2320116300563;900.9414388064775;40707.79646101769;41909.58090419095;41911.79044104779;41923;42131;38244.23511529768;43168.136637267235;43704.56295437053;43990.56009439904;44002.779986100104;7998.320142787856;8100.878486822702;8159.061707903578;8166.550839703814;8175.100738918714;7903.367730581568;8055.073666528337;8061.355091592688;8062.274395304421;8100.675972961104;8104.351651867866;8109.837803243961;8120.553369564701;8125.228103330203;8133.227343402367;8053.073896501889;8053.677852885907;8079.272865442138;8104.270615644591;8136.7152149674475;7919.326857217144;7938.563379014152;8016.879746803803;8017.278444939925;8020.197980201991;39707;39771;39851.8007409964;39862.800685996386;40109.598904010876;3617.927641447183;3624.72814538908;3642.7450078494517;3645.908852278688;3657.835397407123;32473.188170295696;32602.510962335586;32915.341693166;32926.17684557881;33066;4890.462049174588;5041.143005689005;5114.872128196787;5158.535731784157;5161.019406312788;202747;204047;204795;205048;205241;50281.74859125681;50483.49516504861;50740.746296268284;50794.74602626963;50795.23807142896;52564.21153682698;52668;52690.73654631702;52692.473075269525;52705.47294527082;15866.68266634684;16192.67614647724;16207.837921620869;16236.837631623768;16268.51194464168;13280.136791108547;13284.601461955963;13299.401526931311;13306.600801975956;13317.200967941955;12990.480380784662;13347.132436391603;13390.933045334907;13406.865931340757;13545.796813047813;4900.656954013185;5052.747362631853;5136.666116702402;5138.177891537336;5241.475852414805;1014.5586669798514;1016.3190662256306;1026.7792424628592;1032.1794173631981;1038.9532471038822;23184.884075579852;23464;23749.606637462086;23760.405989850045;23773.405664858517;1107.7175320293275;1110.667598044101;1112.260346869339;1119.457063324287;1129.4635048351968;12110.939445302893;12180.878191217975;12223.1443798935;12259.632211033684;12343.19769214998;236726;237845;238378;238396;238754;54499.72750136224;59861;61471;61665;61705.382946169964;1493.1936754152853;1495.1253516692857;1511.9622009449631;1519.0657745486446;1532.4483186053012;27748.44503109967;27839.304017398917;28118.859405702842;28186;28485.857570712844;39648.40527392149;39762.40356394598;39905.80047099862;39959.80020099881;40086;203514;203826;204708;204979;205144;36654.26691466102;36769.08077298089;36818.815905920295;36823.815880920425;36895.44656830096;43592;44551.77724111488;45580.54419455763;45618.77190614026;46096.539034609224;32774.836125819216;33015.50476742899;33258.835940741956;33527.497087544194;33611.83194084014;32584.022479325657;32601;32651.510227346134;32666.34667306593;32668.67331326656;36730;37447.81276093602;37828.24343513169;37844.432333514465;37941.81029094837;31476.527852081777;32016.35967280688;32108.357832843678;32181;32384.676153238164;22953.196638117584;23591.41021474477;23594;23663.763362366157;24144.87927560351 03:52:56 INFO - PID 8923 | |2;jquery.html;588.5909293041333;591.2963573347716;593.9138824870397;611.9051547010214;632.3423639415008;22403.55192896143;23228.767712322893;23246;23270.418239544;23278.3016509505;23382.76617233827;25516.872415637896;25574.488510229832;25615.87192064037;25742.74257257427;6622.53642245043;6772.864542709143;6776.15298087739;6785.491088168383;6804.897926531081;6575.736970521198;6684.364985326386;6803.625800580966;6804.421624161952;6812.523123381366;6778.322167783232;6780.762673306435;6809.829754256159;6810.284920083394;6812.829679258033;6779.0509328694025;6793.286704895989;6800.319968003209;6805;6814.591124532519;6035.275766907999;6274.874502509924;6277.246730392382;6281;6285.025820997753;28795.28011799701;29100.27249318763;29260.41479170426;29371.412571748446;29375.70624293751;2036.6741321388654;2039.0518408939913;2056.28030189434;2056.7840376760373;2057.763357213925;19528.70706939397;20294.594108117904;20570.38288851337;20744.688829667568;20860.165593376245;2469.0494159748446;2476.05909754294;2480.379905023744;2503.8998440062373;2554.4507930794784;119048.80951190463;119313.40343298228;125234;125363.37318313442;143972.2801385997;14560.199189044704;15240.390384384722;15277.083374997535;15646.374145034071;16323.918380408022;24459.38851528726;24669.876650616632;24685.87657061703;24732.876335618206;24763.876180618983;10218.744531386776;10239;10398.844017339747;10436.63471778484;10472.120166954148;9332.30007749408;9381.577828997712;9473.100055494717;9535.38020028696;9559.617615295449;9612.423254604746;9803.313768036169;9843.852342214872;9863.605455781688;9972.252081093948;2808.8314701117997;2866.813657112281;2867.5268580683955;2918.5622156676245;3012.6083609130674;967.8354679704468;969.1568335547977;977.6382738386842;981.4356744871727;997.2570435025953;19860.50348741293;19891;19992.700109498368;20536.07587658559;20738.48153796167;1025.4257615735064;1045.9058684718411;1046.0324200115015;1046.8482070099792;1050.2280823594651;11167.38579378142;11300.491477883515;11344.319340839576;11393.886061139283;11441.771164576829;439775;453083;456209;458485;474930;107581.46209268903;108050.45974770233;109015;109170.45414772876;109681;1168.1530890104775;1182.603827717707;1204.590439250659;1212.3635091576994;1214.3988725580894;18165.636687265916;18305.542361441072;18330.266789328278;18422.263109475472;18460.81539184645;51481.742591286806;52223.73888130687;52583.211251830486;52599.47400525946;53550.196747048045;63599.682001589696;63856.68071659612;64528.35471645223;65753.67123164545;66501.66749166223;14971.775423368876;14972.251387430802;15248.313825878084;15339.079655220723;15348.539543813704;23269.185578504672;23346.18288359899;23378.64932025987;23418.18036368719;23506;18598.53503662419;18668.253269869598;18694.158762855976;18984;19046.85718856873;16483.258253378386;17062.658746825244;17114.914425427793;17478.82521174823;17587.736183956997;18895.527611809266;19042.762220455625;19235.326763563207;19520.414387568395;19527.511812204808;14656.560303190921;14850.702985940437;14919.477818276308;14919.552413427615;14928.85071149318;18702.812971870106;18759.437216883514;18792.436226913214;18818.905905470387;18932 03:52:56 INFO - PID 8923 | |3;mootools.html;1359.361100282867;1409.32352470814;1420.460225114457;1423.9074460160089;1427.828660560733;14852.183129927864;15545.533633990999;15554.066755994634;15574.29915653794;15587.610309742233;15171.696566068673;15225.467108651199;15226.619334516616;15280.84719152811;15311.617209569766;2541.949161016774;2686.4224191798758;2687.852168130752;2696.6224728538023;2698.406350602864;2623.213036089175;2665.307020174749;2672.599110133476;2677.7857771378244;2681.9731802681918;2405.3144853716694;2466.938326541833;2467.1734968785477;2468.666729991446;2472.0359059966627;2465.630155476675;2698.3119304577403;2700.7974401919932;2708.1740069278862;2714.742099500544;2575.381908342003;2603.8307510011787;2614.2680049586147;2623.252373073665;2633.710291867893;15431.074135551902;15432.074075555503;15454.1500217488;15491.690166196613;15578.454754083552;7999;8247.257746802816;8310.25207731301;8313.41806073575;8321.16788321169;5141.254518094893;5263.842084737464;5433.864153396196;5453.563714902798;5520.779168833242;1830.377671591652;1863.5341164708823;1890.1305399516239;1917.213942283657;1924.9326273580357;26629.467410651872;26700;26722;26848.060317888976;27025.189244322504;6772.255051944282;7284.417246620258;7294.1247050354295;7559.092908850973;7565.205653406423;17665.205065772072;17792.822071779374;17797.46607601774;17800.46598602044;17834.643307133785;7988.680452781824;8125.837483250302;8130.186981301941;8138.226868447488;8157.2250636189465;7413.518121322097;7433.479656423999;7499.137599176164;7561.017067781263;7562.962185189149;5162.6386152968935;5306.8142615009065;5316.574674026068;5355.277037599952;5432.185172224198;1535.9001664891746;1555.2223888055971;1561.3364320164112;1561.757927521236;1571.4892659885584;906.5603182456504;932.2914584915513;936.4287784451479;942.7737343037621;944.7732544189345;8007.479513831699;8061.798455038671;8063.475874068166;8076.959615201887;8097.433179677484;690.5649440852231;697.7627606613776;720.9567425954459;740.5186628691285;741.2069086077953;5159.7678104485385;5314.309139811877;5325.360956685223;5565.638233514808;5780.624259423208;21542.892285538474;22357.55284894293;22440.77559224387;22552.436189095075;22711.432214194447;21273.149074036864;21957;22274.888625556767;22521.324360269216;22786;11909.76180476403;11913.88086119145;12002.459889305002;12098.637040888787;12103.939480302543;8457.534835584102;8583.527905965231;8748.300135988979;8748.737537873874;8804.251638610789;18670.813291866907;18823.623527529646;18895.716564251274;19027.048647567844;19099.90450047741;19250.185634621186;19262.4221273362;19680;19783.703244451055;19800.306989255307;10901.291416057931;10948.069414099573;11020.338779673246;11040.5031773572;11051.281666691639;13820.930895345458;13900.026998110035;13938.372773225432;13961.371738271599;13966.720665586834;11522.308661480338;11528.711782205512;11531.423428828693;11646.475908583965;11835.526578936746;10977.670669879917;11221.55113795439;11262.943685281522;11413.45918301052;11570.247933884271;9769.755756106155;9979.60081596728;10001.449920254165;10001.499925003865;10010.849837252592;6732.932670673426;7341.59621220838;7349.228331025379;7367.963160184379;7428.851422971618;7405.148407933157;7560.735374261874;7572.39420846342;7599.16409194977;7631.732889348847 03:52:56 INFO - PID 8923 | |4;prototype.html;525.9605529585282;537.5860587347743;539.5306083707176;547.5291249525408;553.8006317725618;6337.36626337366;6345.175127233465;6350.14273073135;6354.80935571933;6402.327755585655;6226.252849658036;6260.591366942435;6283;6307.400796924297;6334.651594162319;5184.662996905198;5195.688258704471;5233.1103712368795;5242.292290540767;5243.895122097556;4891.5353041461;5198.792048318062;5225.843224703265;5237.162054071349;5266.315379000744;5021.297018417425;5041.873953151164;5042.747862606873;5050.7979680812905;5054.292399064134;4987.700737955734;5030.144875371196;5037.622178336639;5045.495450454961;5067.265246539249;4393.406890069864;4456.6880318377725;4468.731876087412;4470.083632855257;4520.480144783359;6147.631142131487;6160.507159427235;6169.858576163424;6180.969095154495;6181.282313859661;5652.152177173416;5700.657960522382;5701.800436984686;5724.341700704431;5739.885202295931;6627.271000189975;6634.971579405152;6640.472691280984;6646.468282537434;6664.233613134455;4907.288443175756;4917.36074310341;4917.91812795379;4929.309896614477;4943.728094954776;6601.471882249409;6704.5250044990025;6783.389494945479;6885.173779146535;6923.273056329063;4930.654854160174;4993.300937868701;5007.924881126786;5026.74866256693;5038.823641172541;5016.6237532184505;5053.36832895888;5062.443131255559;5104.770285337168;5114.897702045938;5660.801871934545;5681.2330335404195;5707.828765137053;5749.85625359361;5775.220345253421;6706.83232919181;6722.932770672231;6723.394894459522;6734.494912881553;6740.39336459721;1746.9475915722549;1759.2611103336628;1761.9471415857545;1763.162497813554;1769.7788525917076;1144.6852115668257;1192.773373059116;1200.5557943560764;1219.0978675779859;1226.1233218248994;5648;5720.8283751487525;5732.799352022659;5751.396103409165;5768.7692492300675;6691;6716.83207919806;6797.830054248683;6835.897461538081;6884.242733299333;5731.340895796953;5743.85640358986;5747.741351639186;5750.539956803445;5763.250777398911;3530.6822385985383;3541.5395998520025;3598.9100272492874;3614.150674591469;3623.112337477335;6425.453836423956;6432.51756118293;6515.73937042513;6516.837079072966;6550.868982620321;6718.529702920847;6796.966015169893;6813;6832.555883867532;6843.213030501456;6345.619262844244;6365.236171659245;6462.030695395634;6503.219613646298;6506.25178104524;6231.102537320168;6592.901106483502;6707.295733947865;6708;6785.491088168308;6567.179102612174;6590.637514936724;6602.137749416645;6681.694815288718;6717.798466046026;6447.323031081669;6505.902411463927;6526.575772574767;6554.4756419485375;6600.217941155957;4906.116898958222;4952.133376659142;5024.949750502448;5027.74861256943;5033.974830125826;6315.789473684174;6609.074729537772;6620.172478440195;6749.190097188369;6784.660766961731;6328.968355158195;6468.288488266193;6493.74025039012;6498.805035848932;6511.3163117871945;6368.872622547616;6439.613623182624;6493.188351456068;6505.447037001908;6521.934780652133;6355.88771964895;6363.840903977252;6412.6793660317735;6428.4857211423905;6443.131491867306;6492.253390859923;6503.317151699003;6584.539082264196;6617.683810666421;6641.369069938348;6332.3034466207755;6357.173567436389;6398.584092034189;6437.452816510649;6440.967795160994 03:52:56 INFO - PID 8923 | |5;yui.html;902.5442151713384;908.9227415669668;925.7454200094973;928.9628414863406;931.7950050988783;19815.900920495395;20713;20727.170913163474;20734.377968660945;20755.16979320829;16794.244259008345;16915.154242287867;16949.830501694978;16955.57611059721;16981.83018169818;965.6089283840021;978.7699890525721;981.8870829854551;982.8132654795565;982.9852552211722;977.8288799460101;985.3102828020378;990.4750482244386;990.782027953849;992.047634271097;1009.3698676637215;1047.528612124543;1050.4012712753695;1052.2108418685987;1056.8256237720805;1043.8695163104612;1046.3930920066343;1046.4035499765062;1047.6542740895525;1053.1048608682597;729.4601994524014;741.5513614263401;746.2425637977457;750.1261030898996;753.3822265742093;20455.28406505765;20612.072456739483;20630.896845515676;20647.380578582666;20666;20951;21002;21778.128874844988;21863.234786782545;21888.562228755494;16232.350705971865;16434.342626294932;16497.257623406855;16604.50186494407;16909.91545042279;3743.3636281832155;3783.205526839367;3793.3930571108767;3794.772313661189;3797.373433383488;101115.98884011139;133933.33033334772;134852.32573837068;136597;137415;17511.474655760347;17692.91153544224;17715.29138834458;17765;17779.911100444417;31372;32841.3431731364;32888.34223315568;33115.337693246;33169.170770730445;9415.435073895589;9658.661946831828;9663.806723865484;9685.854712179324;9709.320347575602;4338.370936214231;4476.194285028726;4483.50681425043;4511.661625378109;4512.2329204035395;5227.3988491323225;5376.623636345496;5377.085895397791;5386.676799392049;5391.784328626811;1024.4416792847917;1031.417249254184;1037.2479952034714;1039.5633833789827;1041.3127335958231;626.139058794158;641.5733537197711;645.8934275844433;654.8101050695245;654.898490733937;11148.944255278671;11247.718807029725;11576.90019448151;12116;12343.13598048146;638.090720722968;642.1299139665779;644.8452371430917;654.8330175805235;659.3933581105395;5447.809326673548;5482.533984611273;5497.39528651848;5543.113101903757;5574.442555744474;119451;122696;123227;123298;125172.37413812873;80934;104430.47784761028;104721.47639261755;109912;113581;95397.99999999862;95687;96361;97447;99569.50215249023;11890.762184756084;12001.879981200076;12078.27530348182;12087;12183.817242741543;42527;42916.78541607272;43303.35044974265;43924;43997;55357;55992;56898;57362.7131864338;57467.1379929293;16492.17539123063;16918.32326706967;17033.14834258307;17131.400400985905;17379.739303910705;19157.712634310774;19451.902740486206;19468.8053119467;19584.216631334588;19878.90060549746;17843.553911151805;17951.640967180843;18020.819791801914;18128.637427251644;18456.446306610822;16977.405790797264;17226.65546689034;17242.051687156825;17248.827511725223;17428.564285892448;17116.229769660145;17746;17757.289708411517;17782.199801008726;18146.81853181451;11756.764864702829;12035.45840437165;12073.154879158375;12073.758524829278;12168.513259469522;13443.932780336036;13524.79712804289;13625.25061121608;13655.385507652378;13660.93169534146 03:52:56 INFO - PID 8923 | __end_tp_report 03:52:56 INFO - PID 8923 | __start_cc_report 03:52:56 INFO - PID 8923 | _x_x_mozilla_cycle_collect,302 03:52:56 INFO - PID 8923 | __end_cc_report 03:52:56 INFO - PID 8923 | __startTimestamp1507027976503__endTimestamp 03:52:56 INFO - PID 8923 | 03:52:56 INFO - PID 8923 | ------- Summary: start ------- 03:52:56 INFO - PID 8923 | Number of tests: 6 03:52:56 INFO - PID 8923 | 03:52:56 INFO - PID 8923 | [#0] dojo.html Cycles:165 Average:6108.18 Median:6373.78 stddev:848.58 (13.3%) stddev-sans-first:819.19 03:52:56 INFO - PID 8923 | Values: 3165.9 3200.2 3203.5 3207.9 3232.2 6104.4 6234.6 6274.5 6367.8 6374.9 6162.4 6243.8 6273.5 6296.2 6307.0 5744.7 5745.1 5798.2 5840.5 5930.8 5828.4 5846.1 5893.5 5901.1 5929.3 1210.2 5658.3 5666.3 5668.8 5808.9 5675.3 5742.2 5750.4 5755.2 5763.9 5650.0 5674.7 5729.2 5747.0 5748.7 6155.7 6179.9 6188.1 6254.7 6263.6 6623.2 6631.8 6634.0 6717.0 6734.4 6612.1 6714.3 6729.6 6771.9 6822.3 6188.0 6282.9 6305.6 6312.7 6342.6 6901.0 6986.2 6996.7 6997.7 7002.8 6556.7 6565.9 6578.0 6658.8 6662.6 6567.3 6662.5 6677.6 6685.8 6686.9 6584.2 6590.9 6625.5 6719.2 6724.4 6630.0 6739.7 6742.1 6743.7 6749.7 4827.4 4888.9 4920.5 4953.5 4965.8 4281.9 4358.8 4359.9 4370.4 4382.3 5796.4 5821.2 5869.7 5901.1 5902.0 6712.3 6784.6 6785.3 6785.8 6794.4 5758.9 5790.1 5806.6 5809.6 5846.6 5683.4 5685.0 5737.3 5758.1 5769.1 6282.5 6428.7 6441.3 6507.2 6528.2 6606.7 6623.2 6625.1 6625.5 6625.9 6430.4 6433.5 6508.0 6515.8 6535.0 6554.4 6626.4 6644.7 6651.3 6655.1 6684.0 6702.2 6756.6 6769.1 6779.4 6309.3 6413.2 6455.6 6467.5 6563.7 6302.3 6440.3 6441.3 6449.1 6483.0 6373.8 6444.4 6456.5 6460.7 6466.8 6461.2 6507.4 6542.0 6545.8 6606.3 6150.4 6182.4 6239.8 6268.2 6314.2 03:52:56 INFO - PID 8923 | 03:52:56 INFO - PID 8923 | [#1] ext.html Cycles:180 Average:39252.80 Median:36692.13 stddev:56069.36 (152.8%) stddev-sans-first:56152.46 03:52:56 INFO - PID 8923 | Values: 840.5 850.2 868.6 898.2 900.9 40707.8 41909.6 41911.8 41923.0 42131.0 38244.2 43168.1 43704.6 43990.6 44002.8 7998.3 8100.9 8159.1 8166.6 8175.1 7903.4 8055.1 8061.4 8062.3 8100.7 8104.4 8109.8 8120.6 8125.2 8133.2 8053.1 8053.7 8079.3 8104.3 8136.7 7919.3 7938.6 8016.9 8017.3 8020.2 39707.0 39771.0 39851.8 39862.8 40109.6 3617.9 3624.7 3642.7 3645.9 3657.8 32473.2 32602.5 32915.3 32926.2 33066.0 4890.5 5041.1 5114.9 5158.5 5161.0 202747.0 204047.0 204795.0 205048.0 205241.0 50281.7 50483.5 50740.7 50794.7 50795.2 52564.2 52668.0 52690.7 52692.5 52705.5 15866.7 16192.7 16207.8 16236.8 16268.5 13280.1 13284.6 13299.4 13306.6 13317.2 12990.5 13347.1 13390.9 13406.9 13545.8 4900.7 5052.7 5136.7 5138.2 5241.5 1014.6 1016.3 1026.8 1032.2 1039.0 23184.9 23464.0 23749.6 23760.4 23773.4 1107.7 1110.7 1112.3 1119.5 1129.5 12110.9 12180.9 12223.1 12259.6 12343.2 236726.0 237845.0 238378.0 238396.0 238754.0 54499.7 59861.0 61471.0 61665.0 61705.4 1493.2 1495.1 1512.0 1519.1 1532.4 27748.4 27839.3 28118.9 28186.0 28485.9 39648.4 39762.4 39905.8 39959.8 40086.0 203514.0 203826.0 204708.0 204979.0 205144.0 36654.3 36769.1 36818.8 36823.8 36895.4 43592.0 44551.8 45580.5 45618.8 46096.5 32774.8 33015.5 33258.8 33527.5 33611.8 32584.0 32601.0 32651.5 32666.3 32668.7 36730.0 37447.8 37828.2 37844.4 37941.8 31476.5 32016.4 32108.4 32181.0 32384.7 22953.2 23591.4 23594.0 23663.8 24144.9 03:52:56 INFO - PID 8923 | 03:52:56 INFO - PID 8923 | [#2] jquery.html Cycles:180 Average:33291.77 Median:23312.24 stddev:76691.77 (329.0%) stddev-sans-first:76867.61 03:52:56 INFO - PID 8923 | Values: 588.6 591.3 593.9 611.9 632.3 22403.6 23228.8 23246.0 23270.4 23278.3 23382.8 25516.9 25574.5 25615.9 25742.7 6622.5 6772.9 6776.2 6785.5 6804.9 6575.7 6684.4 6803.6 6804.4 6812.5 6778.3 6780.8 6809.8 6810.3 6812.8 6779.1 6793.3 6800.3 6805.0 6814.6 6035.3 6274.9 6277.2 6281.0 6285.0 28795.3 29100.3 29260.4 29371.4 29375.7 2036.7 2039.1 2056.3 2056.8 2057.8 19528.7 20294.6 20570.4 20744.7 20860.2 2469.0 2476.1 2480.4 2503.9 2554.5 119048.8 119313.4 125234.0 125363.4 143972.3 14560.2 15240.4 15277.1 15646.4 16323.9 24459.4 24669.9 24685.9 24732.9 24763.9 10218.7 10239.0 10398.8 10436.6 10472.1 9332.3 9381.6 9473.1 9535.4 9559.6 9612.4 9803.3 9843.9 9863.6 9972.3 2808.8 2866.8 2867.5 2918.6 3012.6 967.8 969.2 977.6 981.4 997.3 19860.5 19891.0 19992.7 20536.1 20738.5 1025.4 1045.9 1046.0 1046.8 1050.2 11167.4 11300.5 11344.3 11393.9 11441.8 439775.0 453083.0 456209.0 458485.0 474930.0 107581.5 108050.5 109015.0 109170.5 109681.0 1168.2 1182.6 1204.6 1212.4 1214.4 18165.6 18305.5 18330.3 18422.3 18460.8 51481.7 52223.7 52583.2 52599.5 53550.2 63599.7 63856.7 64528.4 65753.7 66501.7 14971.8 14972.3 15248.3 15339.1 15348.5 23269.2 23346.2 23378.6 23418.2 23506.0 18598.5 18668.3 18694.2 18984.0 19046.9 16483.3 17062.7 17114.9 17478.8 17587.7 18895.5 19042.8 19235.3 19520.4 19527.5 14656.6 14850.7 14919.5 14919.6 14928.9 18702.8 18759.4 18792.4 18818.9 18932.0 03:52:56 INFO - PID 8923 | 03:52:56 INFO - PID 8923 | [#3] mootools.html Cycles:180 Average:9492.78 Median:2589.61 stddev:6833.92 (263.9%) stddev-sans-first:6825.77 03:52:56 INFO - PID 8923 | Values: 1359.4 1409.3 1420.5 1423.9 1427.8 14852.2 15545.5 15554.1 15574.3 15587.6 15171.7 15225.5 15226.6 15280.8 15311.6 2541.9 2686.4 2687.9 2696.6 2698.4 2623.2 2665.3 2672.6 2677.8 2682.0 2405.3 2466.9 2467.2 2468.7 2472.0 2465.6 2698.3 2700.8 2708.2 2714.7 2575.4 2603.8 2614.3 2623.3 2633.7 15431.1 15432.1 15454.2 15491.7 15578.5 7999.0 8247.3 8310.3 8313.4 8321.2 5141.3 5263.8 5433.9 5453.6 5520.8 1830.4 1863.5 1890.1 1917.2 1924.9 26629.5 26700.0 26722.0 26848.1 27025.2 6772.3 7284.4 7294.1 7559.1 7565.2 17665.2 17792.8 17797.5 17800.5 17834.6 7988.7 8125.8 8130.2 8138.2 8157.2 7413.5 7433.5 7499.1 7561.0 7563.0 5162.6 5306.8 5316.6 5355.3 5432.2 1535.9 1555.2 1561.3 1561.8 1571.5 906.6 932.3 936.4 942.8 944.8 8007.5 8061.8 8063.5 8077.0 8097.4 690.6 697.8 721.0 740.5 741.2 5159.8 5314.3 5325.4 5565.6 5780.6 21542.9 22357.6 22440.8 22552.4 22711.4 21273.1 21957.0 22274.9 22521.3 22786.0 11909.8 11913.9 12002.5 12098.6 12103.9 8457.5 8583.5 8748.3 8748.7 8804.3 18670.8 18823.6 18895.7 19027.0 19099.9 19250.2 19262.4 19680.0 19783.7 19800.3 10901.3 10948.1 11020.3 11040.5 11051.3 13820.9 13900.0 13938.4 13961.4 13966.7 11522.3 11528.7 11531.4 11646.5 11835.5 10977.7 11221.6 11262.9 11413.5 11570.2 9769.8 9979.6 10001.4 10001.5 10010.8 6732.9 7341.6 7349.2 7368.0 7428.9 7405.1 7560.7 7572.4 7599.2 7631.7 03:52:56 INFO - PID 8923 | 03:52:56 INFO - PID 8923 | [#4] prototype.html Cycles:180 Average:5528.00 Median:6228.68 stddev:1537.87 (24.7%) stddev-sans-first:1495.65 03:52:56 INFO - PID 8923 | Values: 526.0 537.6 539.5 547.5 553.8 6337.4 6345.2 6350.1 6354.8 6402.3 6226.3 6260.6 6283.0 6307.4 6334.7 5184.7 5195.7 5233.1 5242.3 5243.9 4891.5 5198.8 5225.8 5237.2 5266.3 5021.3 5041.9 5042.7 5050.8 5054.3 4987.7 5030.1 5037.6 5045.5 5067.3 4393.4 4456.7 4468.7 4470.1 4520.5 6147.6 6160.5 6169.9 6181.0 6181.3 5652.2 5700.7 5701.8 5724.3 5739.9 6627.3 6635.0 6640.5 6646.5 6664.2 4907.3 4917.4 4917.9 4929.3 4943.7 6601.5 6704.5 6783.4 6885.2 6923.3 4930.7 4993.3 5007.9 5026.7 5038.8 5016.6 5053.4 5062.4 5104.8 5114.9 5660.8 5681.2 5707.8 5749.9 5775.2 6706.8 6722.9 6723.4 6734.5 6740.4 1746.9 1759.3 1761.9 1763.2 1769.8 1144.7 1192.8 1200.6 1219.1 1226.1 5648.0 5720.8 5732.8 5751.4 5768.8 6691.0 6716.8 6797.8 6835.9 6884.2 5731.3 5743.9 5747.7 5750.5 5763.3 3530.7 3541.5 3598.9 3614.2 3623.1 6425.5 6432.5 6515.7 6516.8 6550.9 6718.5 6797.0 6813.0 6832.6 6843.2 6345.6 6365.2 6462.0 6503.2 6506.3 6231.1 6592.9 6707.3 6708.0 6785.5 6567.2 6590.6 6602.1 6681.7 6717.8 6447.3 6505.9 6526.6 6554.5 6600.2 4906.1 4952.1 5024.9 5027.7 5034.0 6315.8 6609.1 6620.2 6749.2 6784.7 6329.0 6468.3 6493.7 6498.8 6511.3 6368.9 6439.6 6493.2 6505.4 6521.9 6355.9 6363.8 6412.7 6428.5 6443.1 6492.3 6503.3 6584.5 6617.7 6641.4 6332.3 6357.2 6398.6 6437.5 6441.0 03:52:56 INFO - PID 8923 | 03:52:56 INFO - PID 8923 | [#5] yui.html Cycles:180 Average:24256.01 Median:20533.68 stddev:34042.95 (165.8%) stddev-sans-first:34093.28 03:52:56 INFO - PID 8923 | Values: 902.5 908.9 925.7 929.0 931.8 19815.9 20713.0 20727.2 20734.4 20755.2 16794.2 16915.2 16949.8 16955.6 16981.8 965.6 978.8 981.9 982.8 983.0 977.8 985.3 990.5 990.8 992.0 1009.4 1047.5 1050.4 1052.2 1056.8 1043.9 1046.4 1046.4 1047.7 1053.1 729.5 741.6 746.2 750.1 753.4 20455.3 20612.1 20630.9 20647.4 20666.0 20951.0 21002.0 21778.1 21863.2 21888.6 16232.4 16434.3 16497.3 16604.5 16909.9 3743.4 3783.2 3793.4 3794.8 3797.4 101116.0 133933.3 134852.3 136597.0 137415.0 17511.5 17692.9 17715.3 17765.0 17779.9 31372.0 32841.3 32888.3 33115.3 33169.2 9415.4 9658.7 9663.8 9685.9 9709.3 4338.4 4476.2 4483.5 4511.7 4512.2 5227.4 5376.6 5377.1 5386.7 5391.8 1024.4 1031.4 1037.2 1039.6 1041.3 626.1 641.6 645.9 654.8 654.9 11148.9 11247.7 11576.9 12116.0 12343.1 638.1 642.1 644.8 654.8 659.4 5447.8 5482.5 5497.4 5543.1 5574.4 119451.0 122696.0 123227.0 123298.0 125172.4 80934.0 104430.5 104721.5 109912.0 113581.0 95398.0 95687.0 96361.0 97447.0 99569.5 11890.8 12001.9 12078.3 12087.0 12183.8 42527.0 42916.8 43303.4 43924.0 43997.0 55357.0 55992.0 56898.0 57362.7 57467.1 16492.2 16918.3 17033.1 17131.4 17379.7 19157.7 19451.9 19468.8 19584.2 19878.9 17843.6 17951.6 18020.8 18128.6 18456.4 16977.4 17226.7 17242.1 17248.8 17428.6 17116.2 17746.0 17757.3 17782.2 18146.8 11756.8 12035.5 12073.2 12073.8 12168.5 13443.9 13524.8 13625.3 13655.4 13660.9 03:52:56 INFO - PID 8923 | -------- Summary: end -------- 03:52:56 INFO - PID 8923 | 03:52:56 INFO - TEST-INFO | 8923: exit 0 03:52:57 INFO - TEST-OK | dromaeo_css | took 1108937ms 03:52:57 INFO - TEST-START | kraken 03:52:57 INFO - Initialising browser for kraken test... 03:52:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53672/getInfo.html -profile /tmp/tmpGZKtcW/profile 03:52:57 INFO - TEST-INFO | started process 9321 (/builds/slave/test/build/application/firefox/firefox http://localhost:53672/getInfo.html) 03:53:03 INFO - TEST-INFO | 9321: exit 0 03:53:03 INFO - Browser initialized. 03:53:03 INFO - Running cycle 1/1 for kraken test... 03:53:03 INFO - Using env: {'DISPLAY': ':0', 03:53:03 INFO - 'HOME': '/home/cltbld', 03:53:03 INFO - 'JSGC_DISABLE_POISONING': '1', 03:53:03 INFO - 'LANG': 'en_US.UTF-8', 03:53:03 INFO - 'LANGUAGE': 'en_US:en', 03:53:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:53:03 INFO - 'LOGNAME': 'cltbld', 03:53:03 INFO - 'MAIL': '/var/mail/cltbld', 03:53:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:53:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:53:03 INFO - 'MOZ_CRASHREPORTER': '1', 03:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:53:03 INFO - 'MOZ_NO_REMOTE': '1', 03:53:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:03 INFO - 'NO_EM_RESTART': '1', 03:53:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:03 INFO - 'PWD': '/builds/slave/test', 03:53:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:53:03 INFO - 'RUST_BACKTRACE': 'full', 03:53:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:53:03 INFO - 'SHELL': '/bin/bash', 03:53:03 INFO - 'SHLVL': '1', 03:53:03 INFO - 'TERM': 'linux', 03:53:03 INFO - 'TMOUT': '86400', 03:53:03 INFO - 'USER': 'cltbld', 03:53:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569', 03:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:03 INFO - '_': '/tools/buildbot/bin/python'} 03:53:03 INFO - TEST-INFO | started process 9594 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGZKtcW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:53:04 INFO - PID 9594 | 03:53:04 INFO - PID 9594 | (/builds/slave/test/build/application/firefox/firefox:9642): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:53:04 INFO - PID 9594 | 03:53:14 INFO - PID 9594 | RSS: Main: 152100864 03:53:14 INFO - PID 9594 | 03:53:16 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53672/tests/kraken/driver.html?testName=audio-beat-detection) 03:53:17 INFO - PID 9594 | RSS: Main: 159313920 03:53:17 INFO - PID 9594 | 03:53:20 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53672/tests/kraken/driver.html?testName=audio-dft) 03:53:21 INFO - PID 9594 | RSS: Main: 158904320 03:53:21 INFO - PID 9594 | 03:53:24 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53672/tests/kraken/driver.html?testName=audio-fft) 03:53:24 INFO - PID 9594 | RSS: Main: 155713536 03:53:24 INFO - PID 9594 | 03:53:26 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53672/tests/kraken/driver.html?testName=audio-oscillator) 03:53:27 INFO - PID 9594 | RSS: Main: 155615232 03:53:27 INFO - PID 9594 | 03:53:29 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53672/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:53:30 INFO - PID 9594 | RSS: Main: 155897856 03:53:30 INFO - PID 9594 | 03:53:33 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53672/tests/kraken/driver.html?testName=imaging-darkroom) 03:53:33 INFO - PID 9594 | RSS: Main: 158384128 03:53:33 INFO - PID 9594 | 03:53:37 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53672/tests/kraken/driver.html?testName=imaging-desaturate) 03:53:38 INFO - PID 9594 | RSS: Main: 158126080 03:53:38 INFO - PID 9594 | 03:53:41 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53672/tests/kraken/driver.html?testName=json-parse-financial) 03:53:41 INFO - PID 9594 | RSS: Main: 158117888 03:53:41 INFO - PID 9594 | 03:53:43 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53672/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:53:43 INFO - PID 9594 | RSS: Main: 158146560 03:53:43 INFO - PID 9594 | 03:53:45 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53672/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:53:46 INFO - PID 9594 | RSS: Main: 158457856 03:53:46 INFO - PID 9594 | 03:53:48 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53672/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:53:48 INFO - PID 9594 | RSS: Main: 155385856 03:53:48 INFO - PID 9594 | 03:53:51 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53672/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:53:52 INFO - PID 9594 | RSS: Main: 155361280 03:53:52 INFO - PID 9594 | 03:53:54 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53672/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:53:55 INFO - PID 9594 | RSS: Main: 155635712 03:53:55 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | Cycle 1(1): loaded http://localhost:53672/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53672/tests/kraken/driver.html?testName=ai-astar) 03:53:57 INFO - PID 9594 | RSS: Main: 157446144 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | __start_tp_report 03:53:57 INFO - PID 9594 | _x_x_mozilla_page_load 03:53:57 INFO - PID 9594 | _x_x_mozilla_page_load_details 03:53:57 INFO - PID 9594 | |i|pagename|runs| 03:53:57 INFO - PID 9594 | |0;ai-astar;107;107;107;107;107;110;107;106;108;105 03:53:57 INFO - PID 9594 | |1;audio-beat-detection;118;114;116;115;115;139;119;116;117;119 03:53:57 INFO - PID 9594 | |2;audio-dft;165;162;163;162;161;163;163;170;166;166 03:53:57 INFO - PID 9594 | |3;audio-fft;73;74;75;73;98;75;73;74;73;74 03:53:57 INFO - PID 9594 | |4;audio-oscillator;80;78;77;78;78;78;78;133;79;77 03:53:57 INFO - PID 9594 | |5;imaging-gaussian-blur;110;113;114;113;113;114;114;114;113;114 03:53:57 INFO - PID 9594 | |6;imaging-darkroom;149;146;148;149;148;148;148;148;148;148 03:53:57 INFO - PID 9594 | |7;imaging-desaturate;93;94;94;94;93;93;94;93;94;94 03:53:57 INFO - PID 9594 | |8;json-parse-financial;52;50;53;52;52;52;52;52;53;51 03:53:57 INFO - PID 9594 | |9;json-stringify-tinderbox;37;38;38;38;36;38;38;37;37;37 03:53:57 INFO - PID 9594 | |10;stanford-crypto-aes;72;73;73;71;73;73;74;73;72;77 03:53:57 INFO - PID 9594 | |11;stanford-crypto-ccm;110;107;114;111;111;110;116;112;107;106 03:53:57 INFO - PID 9594 | |12;stanford-crypto-pbkdf2;165;160;161;167;162;161;162;171;166;162 03:53:57 INFO - PID 9594 | |13;stanford-crypto-sha256-iterative;61;64;65;64;63;64;62;70;64;66 03:53:57 INFO - PID 9594 | __end_tp_report 03:53:57 INFO - PID 9594 | __start_cc_report 03:53:57 INFO - PID 9594 | _x_x_mozilla_cycle_collect,713 03:53:57 INFO - PID 9594 | __end_cc_report 03:53:57 INFO - PID 9594 | __startTimestamp1507028037555__endTimestamp 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | ------- Summary: start ------- 03:53:57 INFO - PID 9594 | Number of tests: 14 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#0] ai-astar Cycles:10 Average:107.10 Median:107.00 stddev:1.29 (1.2%) stddev-sans-first:1.36 03:53:57 INFO - PID 9594 | Values: 107.0 107.0 107.0 107.0 107.0 110.0 107.0 106.0 108.0 105.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#1] audio-beat-detection Cycles:10 Average:118.80 Median:117.50 stddev:7.30 (6.2%) stddev-sans-first:7.74 03:53:57 INFO - PID 9594 | Values: 118.0 114.0 116.0 115.0 115.0 139.0 119.0 116.0 117.0 119.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.69 (1.6%) stddev-sans-first:2.83 03:53:57 INFO - PID 9594 | Values: 165.0 162.0 163.0 162.0 161.0 163.0 163.0 170.0 166.0 166.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#3] audio-fft Cycles:10 Average:76.20 Median:74.00 stddev:7.70 (10.4%) stddev-sans-first:8.08 03:53:57 INFO - PID 9594 | Values: 73.0 74.0 75.0 73.0 98.0 75.0 73.0 74.0 73.0 74.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:78.00 stddev:17.38 (22.3%) stddev-sans-first:18.38 03:53:57 INFO - PID 9594 | Values: 80.0 78.0 77.0 78.0 78.0 78.0 78.0 133.0 79.0 77.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:114.00 stddev:1.23 (1.1%) stddev-sans-first:0.53 03:53:57 INFO - PID 9594 | Values: 110.0 113.0 114.0 113.0 113.0 114.0 114.0 114.0 113.0 114.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 03:53:57 INFO - PID 9594 | Values: 149.0 146.0 148.0 149.0 148.0 148.0 148.0 148.0 148.0 148.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:94.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 03:53:57 INFO - PID 9594 | Values: 93.0 94.0 94.0 94.0 93.0 93.0 94.0 93.0 94.0 94.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:0.88 (1.7%) stddev-sans-first:0.93 03:53:57 INFO - PID 9594 | Values: 52.0 50.0 53.0 52.0 52.0 52.0 52.0 52.0 53.0 51.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#9] json-stringify-tinderbox Cycles:10 Average:37.40 Median:38.00 stddev:0.70 (1.8%) stddev-sans-first:0.73 03:53:57 INFO - PID 9594 | Values: 37.0 38.0 38.0 38.0 36.0 38.0 38.0 37.0 37.0 37.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:73.00 stddev:1.60 (2.2%) stddev-sans-first:1.64 03:53:57 INFO - PID 9594 | Values: 72.0 73.0 73.0 71.0 73.0 73.0 74.0 73.0 72.0 77.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#11] stanford-crypto-ccm Cycles:10 Average:110.40 Median:111.00 stddev:3.17 (2.9%) stddev-sans-first:3.36 03:53:57 INFO - PID 9594 | Values: 110.0 107.0 114.0 111.0 111.0 110.0 116.0 112.0 107.0 106.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.70 Median:163.50 stddev:3.47 (2.1%) stddev-sans-first:3.64 03:53:57 INFO - PID 9594 | Values: 165.0 160.0 161.0 167.0 162.0 161.0 162.0 171.0 166.0 162.0 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:2.45 (3.8%) stddev-sans-first:2.29 03:53:57 INFO - PID 9594 | Values: 61.0 64.0 65.0 64.0 63.0 64.0 62.0 70.0 64.0 66.0 03:53:57 INFO - PID 9594 | -------- Summary: end -------- 03:53:57 INFO - PID 9594 | 03:53:57 INFO - PID 9594 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:53:58 INFO - TEST-INFO | 9594: exit 0 03:53:58 INFO - TEST-OK | kraken | took 61260ms 03:53:58 INFO - SUITE-END | took 1170s 03:53:58 INFO - Completed test suite (00:19:30) 03:53:58 INFO - javascript benchmark 03:53:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8591.900673110424, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3165.8733650653976, 3200.215947092963, 3203.5194720791887, 3207.903762887114, 3232.208109013292, 6104.389561043893, 6234.563580549363, 6274.498040156788, 6367.808965731029, 6374.936250637492, 6162.414570615784, 6243.781467648633, 6273.498120150389, 6296.212973378328, 6307.0, 5744.712764361776, 5745.138229265602, 5798.159266906296, 5840.532757379421, 5930.822075337746, 5828.41715828418, 5846.093855452412, 5893.528517718593, 5901.085331773582, 5929.347771745104, 1210.2131425347995, 5658.349289831681, 5666.320041594994, 5668.801591944303, 5808.8838223235725, 5675.290588676416, 5742.196092547047, 5750.367459579443, 5755.22304488897, 5763.9135412968835, 5650.0, 5674.659520428788, 5729.226554415142, 5746.994276001683, 5748.683822389767, 6155.72299246535, 6179.938200618026, 6188.133661287423, 6254.655993920333, 6263.592866463665, 6623.238327592292, 6631.834204144887, 6634.038064480624, 6717.0, 6734.427573656245, 6612.07430959666, 6714.328567143295, 6729.629870357128, 6771.864562708767, 6822.283660215656, 6188.0, 6282.905756413657, 6305.558610897239, 6312.715927783261, 6342.587731797465, 6901.0, 6986.16166060076, 6996.650167491605, 6997.685104170325, 7002.754903578452, 6556.672166391757, 6565.934340656628, 6578.0, 6658.766943156967, 6662.6335551545135, 6567.310432404624, 6662.467002639777, 6677.565958212701, 6685.76599819004, 6686.930091185484, 6584.209894812653, 6590.868182636321, 6625.503087268474, 6719.227288861745, 6724.361185687352, 6630.0, 6739.663016849138, 6742.089817874622, 6743.730250790011, 6749.730010799612, 4827.444843842933, 4888.9488759916785, 4920.45874953762, 4953.504649535018, 4965.82619608312, 4281.914361712748, 4358.825646974149, 4359.934600980988, 4370.38814565961, 4382.298832186835, 5796.362400135981, 5821.2141360916585, 5869.735861886225, 5901.085331773582, 5902.0, 6712.295209003082, 6784.626845523544, 6785.321467853274, 6785.830354241085, 6794.3545363190415, 5758.884822303614, 5790.131480277992, 5806.622569532967, 5809.622374545641, 5846.619969701956, 5683.374828768832, 5685.0, 5737.340205876294, 5758.136279558016, 5769.105788602773, 6282.465990390777, 6428.6785660717715, 6441.259255185713, 6507.219133703986, 6528.2166140062545, 6606.735730570916, 6623.172103487064, 6625.105610742585, 6625.503087268377, 6625.933740662532, 6430.421262086435, 6433.517486188648, 6508.0, 6515.804525864231, 6535.019747037983, 6554.442872355903, 6626.403623673699, 6644.734210631521, 6651.26836048044, 6655.068290439439, 6684.0, 6702.229243637044, 6756.560823546651, 6769.08617336673, 6779.423748981392, 6309.33751956038, 6413.230412350548, 6455.61266324022, 6467.547271690936, 6563.737450501926, 6302.275238347649, 6440.259370172303, 6441.291457939717, 6449.097126402214, 6482.967585162044, 6373.776917807854, 6444.420002199637, 6456.515761317826, 6460.676966151767, 6466.838329041623, 6461.19235095613, 6507.381798729113, 6542.018697195268, 6545.803625891231, 6606.306337834651, 6150.446459818638, 6182.412670796267, 6239.812805615838, 6268.247810262797, 6314.242290925118], "value": 6045.115170875835, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [840.5209030852412, 850.1583432401924, 868.578739311434, 898.2320116300563, 900.9414388064775, 40707.79646101769, 41909.58090419095, 41911.79044104779, 41923.0, 42131.0, 38244.23511529768, 43168.136637267235, 43704.56295437053, 43990.56009439904, 44002.779986100104, 7998.320142787856, 8100.878486822702, 8159.061707903578, 8166.550839703814, 8175.100738918714, 7903.367730581568, 8055.073666528337, 8061.355091592688, 8062.274395304421, 8100.675972961104, 8104.351651867866, 8109.837803243961, 8120.553369564701, 8125.228103330203, 8133.227343402367, 8053.073896501889, 8053.677852885907, 8079.272865442138, 8104.270615644591, 8136.7152149674475, 7919.326857217144, 7938.563379014152, 8016.879746803803, 8017.278444939925, 8020.197980201991, 39707.0, 39771.0, 39851.8007409964, 39862.800685996386, 40109.598904010876, 3617.927641447183, 3624.72814538908, 3642.7450078494517, 3645.908852278688, 3657.835397407123, 32473.188170295696, 32602.510962335586, 32915.341693166, 32926.17684557881, 33066.0, 4890.462049174588, 5041.143005689005, 5114.872128196787, 5158.535731784157, 5161.019406312788, 202747.0, 204047.0, 204795.0, 205048.0, 205241.0, 50281.74859125681, 50483.49516504861, 50740.746296268284, 50794.74602626963, 50795.23807142896, 52564.21153682698, 52668.0, 52690.73654631702, 52692.473075269525, 52705.47294527082, 15866.68266634684, 16192.67614647724, 16207.837921620869, 16236.837631623768, 16268.51194464168, 13280.136791108547, 13284.601461955963, 13299.401526931311, 13306.600801975956, 13317.200967941955, 12990.480380784662, 13347.132436391603, 13390.933045334907, 13406.865931340757, 13545.796813047813, 4900.656954013185, 5052.747362631853, 5136.666116702402, 5138.177891537336, 5241.475852414805, 1014.5586669798514, 1016.3190662256306, 1026.7792424628592, 1032.1794173631981, 1038.9532471038822, 23184.884075579852, 23464.0, 23749.606637462086, 23760.405989850045, 23773.405664858517, 1107.7175320293275, 1110.667598044101, 1112.260346869339, 1119.457063324287, 1129.4635048351968, 12110.939445302893, 12180.878191217975, 12223.1443798935, 12259.632211033684, 12343.19769214998, 236726.0, 237845.0, 238378.0, 238396.0, 238754.0, 54499.72750136224, 59861.0, 61471.0, 61665.0, 61705.382946169964, 1493.1936754152853, 1495.1253516692857, 1511.9622009449631, 1519.0657745486446, 1532.4483186053012, 27748.44503109967, 27839.304017398917, 28118.859405702842, 28186.0, 28485.857570712844, 39648.40527392149, 39762.40356394598, 39905.80047099862, 39959.80020099881, 40086.0, 203514.0, 203826.0, 204708.0, 204979.0, 205144.0, 36654.26691466102, 36769.08077298089, 36818.815905920295, 36823.815880920425, 36895.44656830096, 43592.0, 44551.77724111488, 45580.54419455763, 45618.77190614026, 46096.539034609224, 32774.836125819216, 33015.50476742899, 33258.835940741956, 33527.497087544194, 33611.83194084014, 32584.022479325657, 32601.0, 32651.510227346134, 32666.34667306593, 32668.67331326656, 36730.0, 37447.81276093602, 37828.24343513169, 37844.432333514465, 37941.81029094837, 31476.527852081777, 32016.35967280688, 32108.357832843678, 32181.0, 32384.676153238164, 22953.196638117584, 23591.41021474477, 23594.0, 23663.763362366157, 24144.87927560351], "value": 17700.754462499546, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.5909293041333, 591.2963573347716, 593.9138824870397, 611.9051547010214, 632.3423639415008, 22403.55192896143, 23228.767712322893, 23246.0, 23270.418239544, 23278.3016509505, 23382.76617233827, 25516.872415637896, 25574.488510229832, 25615.87192064037, 25742.74257257427, 6622.53642245043, 6772.864542709143, 6776.15298087739, 6785.491088168383, 6804.897926531081, 6575.736970521198, 6684.364985326386, 6803.625800580966, 6804.421624161952, 6812.523123381366, 6778.322167783232, 6780.762673306435, 6809.829754256159, 6810.284920083394, 6812.829679258033, 6779.0509328694025, 6793.286704895989, 6800.319968003209, 6805.0, 6814.591124532519, 6035.275766907999, 6274.874502509924, 6277.246730392382, 6281.0, 6285.025820997753, 28795.28011799701, 29100.27249318763, 29260.41479170426, 29371.412571748446, 29375.70624293751, 2036.6741321388654, 2039.0518408939913, 2056.28030189434, 2056.7840376760373, 2057.763357213925, 19528.70706939397, 20294.594108117904, 20570.38288851337, 20744.688829667568, 20860.165593376245, 2469.0494159748446, 2476.05909754294, 2480.379905023744, 2503.8998440062373, 2554.4507930794784, 119048.80951190463, 119313.40343298228, 125234.0, 125363.37318313442, 143972.2801385997, 14560.199189044704, 15240.390384384722, 15277.083374997535, 15646.374145034071, 16323.918380408022, 24459.38851528726, 24669.876650616632, 24685.87657061703, 24732.876335618206, 24763.876180618983, 10218.744531386776, 10239.0, 10398.844017339747, 10436.63471778484, 10472.120166954148, 9332.30007749408, 9381.577828997712, 9473.100055494717, 9535.38020028696, 9559.617615295449, 9612.423254604746, 9803.313768036169, 9843.852342214872, 9863.605455781688, 9972.252081093948, 2808.8314701117997, 2866.813657112281, 2867.5268580683955, 2918.5622156676245, 3012.6083609130674, 967.8354679704468, 969.1568335547977, 977.6382738386842, 981.4356744871727, 997.2570435025953, 19860.50348741293, 19891.0, 19992.700109498368, 20536.07587658559, 20738.48153796167, 1025.4257615735064, 1045.9058684718411, 1046.0324200115015, 1046.8482070099792, 1050.2280823594651, 11167.38579378142, 11300.491477883515, 11344.319340839576, 11393.886061139283, 11441.771164576829, 439775.0, 453083.0, 456209.0, 458485.0, 474930.0, 107581.46209268903, 108050.45974770233, 109015.0, 109170.45414772876, 109681.0, 1168.1530890104775, 1182.603827717707, 1204.590439250659, 1212.3635091576994, 1214.3988725580894, 18165.636687265916, 18305.542361441072, 18330.266789328278, 18422.263109475472, 18460.81539184645, 51481.742591286806, 52223.73888130687, 52583.211251830486, 52599.47400525946, 53550.196747048045, 63599.682001589696, 63856.68071659612, 64528.35471645223, 65753.67123164545, 66501.66749166223, 14971.775423368876, 14972.251387430802, 15248.313825878084, 15339.079655220723, 15348.539543813704, 23269.185578504672, 23346.18288359899, 23378.64932025987, 23418.18036368719, 23506.0, 18598.53503662419, 18668.253269869598, 18694.158762855976, 18984.0, 19046.85718856873, 16483.258253378386, 17062.658746825244, 17114.914425427793, 17478.82521174823, 17587.736183956997, 18895.527611809266, 19042.762220455625, 19235.326763563207, 19520.414387568395, 19527.511812204808, 14656.560303190921, 14850.702985940437, 14919.477818276308, 14919.552413427615, 14928.85071149318, 18702.812971870106, 18759.437216883514, 18792.436226913214, 18818.905905470387, 18932.0], "value": 12115.769917425596, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.361100282867, 1409.32352470814, 1420.460225114457, 1423.9074460160089, 1427.828660560733, 14852.183129927864, 15545.533633990999, 15554.066755994634, 15574.29915653794, 15587.610309742233, 15171.696566068673, 15225.467108651199, 15226.619334516616, 15280.84719152811, 15311.617209569766, 2541.949161016774, 2686.4224191798758, 2687.852168130752, 2696.6224728538023, 2698.406350602864, 2623.213036089175, 2665.307020174749, 2672.599110133476, 2677.7857771378244, 2681.9731802681918, 2405.3144853716694, 2466.938326541833, 2467.1734968785477, 2468.666729991446, 2472.0359059966627, 2465.630155476675, 2698.3119304577403, 2700.7974401919932, 2708.1740069278862, 2714.742099500544, 2575.381908342003, 2603.8307510011787, 2614.2680049586147, 2623.252373073665, 2633.710291867893, 15431.074135551902, 15432.074075555503, 15454.1500217488, 15491.690166196613, 15578.454754083552, 7999.0, 8247.257746802816, 8310.25207731301, 8313.41806073575, 8321.16788321169, 5141.254518094893, 5263.842084737464, 5433.864153396196, 5453.563714902798, 5520.779168833242, 1830.377671591652, 1863.5341164708823, 1890.1305399516239, 1917.213942283657, 1924.9326273580357, 26629.467410651872, 26700.0, 26722.0, 26848.060317888976, 27025.189244322504, 6772.255051944282, 7284.417246620258, 7294.1247050354295, 7559.092908850973, 7565.205653406423, 17665.205065772072, 17792.822071779374, 17797.46607601774, 17800.46598602044, 17834.643307133785, 7988.680452781824, 8125.837483250302, 8130.186981301941, 8138.226868447488, 8157.2250636189465, 7413.518121322097, 7433.479656423999, 7499.137599176164, 7561.017067781263, 7562.962185189149, 5162.6386152968935, 5306.8142615009065, 5316.574674026068, 5355.277037599952, 5432.185172224198, 1535.9001664891746, 1555.2223888055971, 1561.3364320164112, 1561.757927521236, 1571.4892659885584, 906.5603182456504, 932.2914584915513, 936.4287784451479, 942.7737343037621, 944.7732544189345, 8007.479513831699, 8061.798455038671, 8063.475874068166, 8076.959615201887, 8097.433179677484, 690.5649440852231, 697.7627606613776, 720.9567425954459, 740.5186628691285, 741.2069086077953, 5159.7678104485385, 5314.309139811877, 5325.360956685223, 5565.638233514808, 5780.624259423208, 21542.892285538474, 22357.55284894293, 22440.77559224387, 22552.436189095075, 22711.432214194447, 21273.149074036864, 21957.0, 22274.888625556767, 22521.324360269216, 22786.0, 11909.76180476403, 11913.88086119145, 12002.459889305002, 12098.637040888787, 12103.939480302543, 8457.534835584102, 8583.527905965231, 8748.300135988979, 8748.737537873874, 8804.251638610789, 18670.813291866907, 18823.623527529646, 18895.716564251274, 19027.048647567844, 19099.90450047741, 19250.185634621186, 19262.4221273362, 19680.0, 19783.703244451055, 19800.306989255307, 10901.291416057931, 10948.069414099573, 11020.338779673246, 11040.5031773572, 11051.281666691639, 13820.930895345458, 13900.026998110035, 13938.372773225432, 13961.371738271599, 13966.720665586834, 11522.308661480338, 11528.711782205512, 11531.423428828693, 11646.475908583965, 11835.526578936746, 10977.670669879917, 11221.55113795439, 11262.943685281522, 11413.45918301052, 11570.247933884271, 9769.755756106155, 9979.60081596728, 10001.449920254165, 10001.499925003865, 10010.849837252592, 6732.932670673426, 7341.59621220838, 7349.228331025379, 7367.963160184379, 7428.851422971618, 7405.148407933157, 7560.735374261874, 7572.39420846342, 7599.16409194977, 7631.732889348847], "value": 6700.9825646439685, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.9605529585282, 537.5860587347743, 539.5306083707176, 547.5291249525408, 553.8006317725618, 6337.36626337366, 6345.175127233465, 6350.14273073135, 6354.80935571933, 6402.327755585655, 6226.252849658036, 6260.591366942435, 6283.0, 6307.400796924297, 6334.651594162319, 5184.662996905198, 5195.688258704471, 5233.1103712368795, 5242.292290540767, 5243.895122097556, 4891.5353041461, 5198.792048318062, 5225.843224703265, 5237.162054071349, 5266.315379000744, 5021.297018417425, 5041.873953151164, 5042.747862606873, 5050.7979680812905, 5054.292399064134, 4987.700737955734, 5030.144875371196, 5037.622178336639, 5045.495450454961, 5067.265246539249, 4393.406890069864, 4456.6880318377725, 4468.731876087412, 4470.083632855257, 4520.480144783359, 6147.631142131487, 6160.507159427235, 6169.858576163424, 6180.969095154495, 6181.282313859661, 5652.152177173416, 5700.657960522382, 5701.800436984686, 5724.341700704431, 5739.885202295931, 6627.271000189975, 6634.971579405152, 6640.472691280984, 6646.468282537434, 6664.233613134455, 4907.288443175756, 4917.36074310341, 4917.91812795379, 4929.309896614477, 4943.728094954776, 6601.471882249409, 6704.5250044990025, 6783.389494945479, 6885.173779146535, 6923.273056329063, 4930.654854160174, 4993.300937868701, 5007.924881126786, 5026.74866256693, 5038.823641172541, 5016.6237532184505, 5053.36832895888, 5062.443131255559, 5104.770285337168, 5114.897702045938, 5660.801871934545, 5681.2330335404195, 5707.828765137053, 5749.85625359361, 5775.220345253421, 6706.83232919181, 6722.932770672231, 6723.394894459522, 6734.494912881553, 6740.39336459721, 1746.9475915722549, 1759.2611103336628, 1761.9471415857545, 1763.162497813554, 1769.7788525917076, 1144.6852115668257, 1192.773373059116, 1200.5557943560764, 1219.0978675779859, 1226.1233218248994, 5648.0, 5720.8283751487525, 5732.799352022659, 5751.396103409165, 5768.7692492300675, 6691.0, 6716.83207919806, 6797.830054248683, 6835.897461538081, 6884.242733299333, 5731.340895796953, 5743.85640358986, 5747.741351639186, 5750.539956803445, 5763.250777398911, 3530.6822385985383, 3541.5395998520025, 3598.9100272492874, 3614.150674591469, 3623.112337477335, 6425.453836423956, 6432.51756118293, 6515.73937042513, 6516.837079072966, 6550.868982620321, 6718.529702920847, 6796.966015169893, 6813.0, 6832.555883867532, 6843.213030501456, 6345.619262844244, 6365.236171659245, 6462.030695395634, 6503.219613646298, 6506.25178104524, 6231.102537320168, 6592.901106483502, 6707.295733947865, 6708.0, 6785.491088168308, 6567.179102612174, 6590.637514936724, 6602.137749416645, 6681.694815288718, 6717.798466046026, 6447.323031081669, 6505.902411463927, 6526.575772574767, 6554.4756419485375, 6600.217941155957, 4906.116898958222, 4952.133376659142, 5024.949750502448, 5027.74861256943, 5033.974830125826, 6315.789473684174, 6609.074729537772, 6620.172478440195, 6749.190097188369, 6784.660766961731, 6328.968355158195, 6468.288488266193, 6493.74025039012, 6498.805035848932, 6511.3163117871945, 6368.872622547616, 6439.613623182624, 6493.188351456068, 6505.447037001908, 6521.934780652133, 6355.88771964895, 6363.840903977252, 6412.6793660317735, 6428.4857211423905, 6443.131491867306, 6492.253390859923, 6503.317151699003, 6584.539082264196, 6617.683810666421, 6641.369069938348, 6332.3034466207755, 6357.173567436389, 6398.584092034189, 6437.452816510649, 6440.967795160994], "value": 5078.216287829831, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [902.5442151713384, 908.9227415669668, 925.7454200094973, 928.9628414863406, 931.7950050988783, 19815.900920495395, 20713.0, 20727.170913163474, 20734.377968660945, 20755.16979320829, 16794.244259008345, 16915.154242287867, 16949.830501694978, 16955.57611059721, 16981.83018169818, 965.6089283840021, 978.7699890525721, 981.8870829854551, 982.8132654795565, 982.9852552211722, 977.8288799460101, 985.3102828020378, 990.4750482244386, 990.782027953849, 992.047634271097, 1009.3698676637215, 1047.528612124543, 1050.4012712753695, 1052.2108418685987, 1056.8256237720805, 1043.8695163104612, 1046.3930920066343, 1046.4035499765062, 1047.6542740895525, 1053.1048608682597, 729.4601994524014, 741.5513614263401, 746.2425637977457, 750.1261030898996, 753.3822265742093, 20455.28406505765, 20612.072456739483, 20630.896845515676, 20647.380578582666, 20666.0, 20951.0, 21002.0, 21778.128874844988, 21863.234786782545, 21888.562228755494, 16232.350705971865, 16434.342626294932, 16497.257623406855, 16604.50186494407, 16909.91545042279, 3743.3636281832155, 3783.205526839367, 3793.3930571108767, 3794.772313661189, 3797.373433383488, 101115.98884011139, 133933.33033334772, 134852.32573837068, 136597.0, 137415.0, 17511.474655760347, 17692.91153544224, 17715.29138834458, 17765.0, 17779.911100444417, 31372.0, 32841.3431731364, 32888.34223315568, 33115.337693246, 33169.170770730445, 9415.435073895589, 9658.661946831828, 9663.806723865484, 9685.854712179324, 9709.320347575602, 4338.370936214231, 4476.194285028726, 4483.50681425043, 4511.661625378109, 4512.2329204035395, 5227.3988491323225, 5376.623636345496, 5377.085895397791, 5386.676799392049, 5391.784328626811, 1024.4416792847917, 1031.417249254184, 1037.2479952034714, 1039.5633833789827, 1041.3127335958231, 626.139058794158, 641.5733537197711, 645.8934275844433, 654.8101050695245, 654.898490733937, 11148.944255278671, 11247.718807029725, 11576.90019448151, 12116.0, 12343.13598048146, 638.090720722968, 642.1299139665779, 644.8452371430917, 654.8330175805235, 659.3933581105395, 5447.809326673548, 5482.533984611273, 5497.39528651848, 5543.113101903757, 5574.442555744474, 119451.0, 122696.0, 123227.0, 123298.0, 125172.37413812873, 80934.0, 104430.47784761028, 104721.47639261755, 109912.0, 113581.0, 95397.99999999862, 95687.0, 96361.0, 97447.0, 99569.50215249023, 11890.762184756084, 12001.879981200076, 12078.27530348182, 12087.0, 12183.817242741543, 42527.0, 42916.78541607272, 43303.35044974265, 43924.0, 43997.0, 55357.0, 55992.0, 56898.0, 57362.7131864338, 57467.1379929293, 16492.17539123063, 16918.32326706967, 17033.14834258307, 17131.400400985905, 17379.739303910705, 19157.712634310774, 19451.902740486206, 19468.8053119467, 19584.216631334588, 19878.90060549746, 17843.553911151805, 17951.640967180843, 18020.819791801914, 18128.637427251644, 18456.446306610822, 16977.405790797264, 17226.65546689034, 17242.051687156825, 17248.827511725223, 17428.564285892448, 17116.229769660145, 17746.0, 17757.289708411517, 17782.199801008726, 18146.81853181451, 11756.764864702829, 12035.45840437165, 12073.154879158375, 12073.758524829278, 12168.513259469522, 13443.932780336036, 13524.79712804289, 13625.25061121608, 13655.385507652378, 13660.93169534146], "value": 9118.250813678651, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1405.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 107.0, 107.0, 107.0, 110.0, 107.0, 106.0, 108.0, 105.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 114.0, 116.0, 115.0, 115.0, 139.0, 119.0, 116.0, 117.0, 119.0], "value": 118.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 163.0, 162.0, 161.0, 163.0, 163.0, 170.0, 166.0, 166.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 73.0, 98.0, 75.0, 73.0, 74.0, 73.0, 74.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 77.0, 78.0, 78.0, 78.0, 78.0, 133.0, 79.0, 77.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 114.0, 113.0, 113.0, 114.0, 114.0, 114.0, 113.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 146.0, 148.0, 149.0, 148.0, 148.0, 148.0, 148.0, 148.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0, 94.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 53.0, 51.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 38.0, 36.0, 38.0, 38.0, 37.0, 37.0, 37.0], "value": 37.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 73.0, 71.0, 73.0, 73.0, 74.0, 73.0, 72.0, 77.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 107.0, 114.0, 111.0, 111.0, 110.0, 116.0, 112.0, 107.0, 106.0], "value": 110.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 160.0, 161.0, 167.0, 162.0, 161.0, 162.0, 171.0, 166.0, 162.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 64.0, 63.0, 64.0, 62.0, 70.0, 64.0, 66.0], "value": 64.3, "unit": "score"}]}]} 03:53:58 INFO - Return code: 0 03:53:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:53:58 INFO - # TBPL SUCCESS # 03:53:58 INFO - Running post-action listener: _package_coverage_data 03:53:58 INFO - Running post-action listener: _resource_record_post_action 03:53:58 INFO - [mozharness: 2017-10-03 10:53:58.832111Z] Finished run-tests step (success) 03:53:58 INFO - Running post-run listener: _resource_record_post_run 03:53:58 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 12288; Write bytes: 335884288; Read time: 344; Write time: 317736 03:53:58 INFO - TinderboxPrint: CPU usage
13.1% 03:53:58 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 344 03:53:58 INFO - TinderboxPrint: I/O write bytes / time
335,884,288 / 317,736 03:53:58 INFO - TinderboxPrint: CPU idle
8,236.0 (86.9%) 03:53:58 INFO - TinderboxPrint: CPU user
1,193.0 (12.6%) 03:53:58 INFO - TinderboxPrint: Swap in / out
0 / 0 03:53:58 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13209600; Read time: 0; Write time: 2476 03:53:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:53:58 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 8192; Write bytes: 322674688; Read time: 328; Write time: 315260 03:53:59 INFO - Running post-run listener: _upload_blobber_files 03:53:59 INFO - Blob upload gear active. 03:53:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:53:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:53:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:53:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:53:59 INFO - (blobuploader) - INFO - Open directory for files ... 03:53:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:53:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:54:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:54:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:54:00 INFO - (blobuploader) - INFO - Done attempting. 03:54:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:54:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:54:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:54:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:54:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:54:01 INFO - (blobuploader) - INFO - Done attempting. 03:54:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:54:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:54:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:54:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:54:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:54:09 INFO - (blobuploader) - INFO - Done attempting. 03:54:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:54:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:54:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:54:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:54:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:54:10 INFO - (blobuploader) - INFO - Done attempting. 03:54:10 INFO - (blobuploader) - INFO - Iteration through files over. 03:54:10 INFO - Return code: 0 03:54:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:54:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:54:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eecf760b1e94e21590c3f3ae22c95b6787b39adc941bb6d84bc617952b377a2b6cd10b63551665945a71803276867f178090197bb6cc95a47a8c838ee5b5244a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32bd660feecaefa17730788a446d570b5703fa04e959cc43cfd76ed9a7be8d26c95914d56bb58f2ba77e8cb41ab09754976fc20c3b5506103a70a753c5d5594d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/390dd6f443f6b8a7e3e901f7503eee9df9e33a97439594ebf550bec450f471b91e2775e97e50e0f5ae690b8a751f4b67109b47c18a504a42b54f7fa957deebf5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73e3cf5a57c214e86391f901db16664f492a14a9fcf3efe061f84752f4c196b8eb9065bb3aec008ab8f5611e431a37d5da397a5fed46ed9f55fe64133be2a61b"} 03:54:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:54:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:54:10 INFO - Contents: 03:54:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eecf760b1e94e21590c3f3ae22c95b6787b39adc941bb6d84bc617952b377a2b6cd10b63551665945a71803276867f178090197bb6cc95a47a8c838ee5b5244a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32bd660feecaefa17730788a446d570b5703fa04e959cc43cfd76ed9a7be8d26c95914d56bb58f2ba77e8cb41ab09754976fc20c3b5506103a70a753c5d5594d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/390dd6f443f6b8a7e3e901f7503eee9df9e33a97439594ebf550bec450f471b91e2775e97e50e0f5ae690b8a751f4b67109b47c18a504a42b54f7fa957deebf5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73e3cf5a57c214e86391f901db16664f492a14a9fcf3efe061f84752f4c196b8eb9065bb3aec008ab8f5611e431a37d5da397a5fed46ed9f55fe64133be2a61b"} 03:54:10 INFO - Running post-run listener: copy_logs_to_upload_dir 03:54:10 INFO - Copying logs to upload dir... 03:54:10 INFO - mkdir: /builds/slave/test/build/upload/logs 03:54:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.504387 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-10-03 03:54:10.446206) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 03:54:10.451136) ========= 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=5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569 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/eecf760b1e94e21590c3f3ae22c95b6787b39adc941bb6d84bc617952b377a2b6cd10b63551665945a71803276867f178090197bb6cc95a47a8c838ee5b5244a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32bd660feecaefa17730788a446d570b5703fa04e959cc43cfd76ed9a7be8d26c95914d56bb58f2ba77e8cb41ab09754976fc20c3b5506103a70a753c5d5594d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/390dd6f443f6b8a7e3e901f7503eee9df9e33a97439594ebf550bec450f471b91e2775e97e50e0f5ae690b8a751f4b67109b47c18a504a42b54f7fa957deebf5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73e3cf5a57c214e86391f901db16664f492a14a9fcf3efe061f84752f4c196b8eb9065bb3aec008ab8f5611e431a37d5da397a5fed46ed9f55fe64133be2a61b"} build_url:https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/PisX6-VjSaOdJA46TjmVsA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eecf760b1e94e21590c3f3ae22c95b6787b39adc941bb6d84bc617952b377a2b6cd10b63551665945a71803276867f178090197bb6cc95a47a8c838ee5b5244a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32bd660feecaefa17730788a446d570b5703fa04e959cc43cfd76ed9a7be8d26c95914d56bb58f2ba77e8cb41ab09754976fc20c3b5506103a70a753c5d5594d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/390dd6f443f6b8a7e3e901f7503eee9df9e33a97439594ebf550bec450f471b91e2775e97e50e0f5ae690b8a751f4b67109b47c18a504a42b54f7fa957deebf5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73e3cf5a57c214e86391f901db16664f492a14a9fcf3efe061f84752f4c196b8eb9065bb3aec008ab8f5611e431a37d5da397a5fed46ed9f55fe64133be2a61b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 03:54:10.481333) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 03:54:10.481676) ========= 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=5cf47e208febbcd81da23b2e0000026f-1507026383.161700-1999842569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 03:54:10.562034) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 03:54:10.562345) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 03:54:10.562668) ========= ========= Total master_lag: 0.24 =========