builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-024 starttime: 1507058458.55 results: success (0) revision: 9be05b2177667ed8221f9da4fdcc200dbdf3de62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:58.552307) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:58.552738) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:58.553049) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:58.570617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:58.571146) ========= 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=41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 12:20:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.7M=0s 2017-10-03 12:20:58 (36.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096226 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:58.685227) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:58.685552) ========= 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=41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015455 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:58.735022) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:58.735364) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9be05b2177667ed8221f9da4fdcc200dbdf3de62 --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 9be05b2177667ed8221f9da4fdcc200dbdf3de62 --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=41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 12:20:58,707 truncating revision to first 12 chars 2017-10-03 12:20:58,707 Setting DEBUG logging. 2017-10-03 12:20:58,707 attempt 1/10 2017-10-03 12:20:58,707 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9be05b217766?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 12:20:59,206 unpacking tar archive at: mozilla-central-9be05b217766/testing/mozharness/ program finished with exit code 0 elapsedTime=0.803528 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:59.557027) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:59.557576) ========= script_repo_revision: 9be05b2177667ed8221f9da4fdcc200dbdf3de62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:59.558172) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:59.558612) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 12:20:59.609237) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-10-03 12:20:59.609678) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:20:59 INFO - MultiFileLogger online at 20171003 12:20:59 in /builds/slave/test 12:20:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:20:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:20:59 INFO - {'append_to_log': False, 12:20:59 INFO - 'base_work_dir': '/builds/slave/test', 12:20:59 INFO - 'blob_upload_branch': 'Firefox', 12:20:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:20:59 INFO - 'branch': 'Firefox', 12:20:59 INFO - 'buildbot_json_path': 'buildprops.json', 12:20:59 INFO - 'code_coverage': False, 12:20:59 INFO - 'config_files': ('talos/linux_config.py',), 12:20:59 INFO - 'default_actions': ('clobber', 12:20:59 INFO - 'read-buildbot-config', 12:20:59 INFO - 'download-and-extract', 12:20:59 INFO - 'populate-webroot', 12:20:59 INFO - 'create-virtualenv', 12:20:59 INFO - 'install', 12:20:59 INFO - 'setup-mitmproxy', 12:20:59 INFO - 'run-tests'), 12:20:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:20:59 INFO - 'disable_ccov_upload': False, 12:20:59 INFO - 'disable_stylo': False, 12:20:59 INFO - 'download_minidump_stackwalk': True, 12:20:59 INFO - 'download_symbols': 'ondemand', 12:20:59 INFO - 'enable_stylo': False, 12:20:59 INFO - 'enable_webrender': False, 12:20:59 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:20:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:20:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:20:59 INFO - 'gecko_profile': False, 12:20:59 INFO - 'gecko_profile_interval': 0, 12:20:59 INFO - 'installer_path': 'installer.exe', 12:20:59 INFO - 'log_level': 'info', 12:20:59 INFO - 'log_name': 'talos', 12:20:59 INFO - 'log_to_console': True, 12:20:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:20:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:20:59 INFO - 'opt_config_files': (), 12:20:59 INFO - 'pip_index': False, 12:20:59 INFO - 'suite': 'dromaeojs-e10s', 12:20:59 INFO - 'system_bits': '32', 12:20:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:20:59 INFO - 'title': 'talos-linux64-ix-024', 12:20:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:20:59 INFO - 'use_talos_json': True, 12:20:59 INFO - 'verify': 'False', 12:20:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:20:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:20:59 INFO - 'work_dir': 'build'} 12:20:59 INFO - [mozharness: 2017-10-03 19:20:59.723581Z] Running clobber step. 12:20:59 INFO - Running pre-action listener: _resource_record_pre_action 12:20:59 INFO - Running main action method: clobber 12:20:59 INFO - rmtree: /builds/slave/test/build 12:20:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:21:00 INFO - Running post-action listener: _resource_record_post_action 12:21:00 INFO - [mozharness: 2017-10-03 19:21:00.792532Z] Finished clobber step (success) 12:21:00 INFO - [mozharness: 2017-10-03 19:21:00.792630Z] Running read-buildbot-config step. 12:21:00 INFO - Running pre-action listener: _resource_record_pre_action 12:21:00 INFO - Running main action method: read_buildbot_config 12:21:00 INFO - Using buildbot properties: 12:21:00 INFO - { 12:21:00 INFO - "project": "", 12:21:00 INFO - "product": "firefox", 12:21:00 INFO - "who": "ffxbld@noreply.mozilla.org", 12:21:00 INFO - "installer_path": "public/build/target.tar.bz2", 12:21:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:21:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:21:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:21:00 INFO - "stage_platform": "linux64-pgo", 12:21:00 INFO - "basedir": "/builds/slave/test", 12:21:00 INFO - "buildnumber": 217, 12:21:00 INFO - "platform": "ubuntu64_hw", 12:21:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:21:00 INFO - "slavebuilddir": "test", 12:21:00 INFO - "taskId": "Ryfm-e64SMyS-N1BzMqA1A", 12:21:00 INFO - "branch": "mozilla-central", 12:21:00 INFO - "script_repo_revision": "production", 12:21:00 INFO - "revision": "9be05b2177667ed8221f9da4fdcc200dbdf3de62", 12:21:00 INFO - "slavename": "talos-linux64-ix-024", 12:21:00 INFO - "repo_path": "mozilla-central" 12:21:00 INFO - } 12:21:00 INFO - Finding installer, test and symbols from parent task. 12:21:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ryfm-e64SMyS-N1BzMqA1A'}, attempt #1 12:21:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ryfm-e64SMyS-N1BzMqA1A'}, attempt #1 12:21:01 INFO - Task dependencies: Dq8jydmSRL6tZ237UTvWrg 12:21:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg'}, attempt #1 12:21:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.tar.bz2 12:21:02 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.test_packages.json 12:21:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.crashreporter-symbols.zip 12:21:02 INFO - Running post-action listener: _resource_record_post_action 12:21:02 INFO - [mozharness: 2017-10-03 19:21:02.198818Z] Finished read-buildbot-config step (success) 12:21:02 INFO - [mozharness: 2017-10-03 19:21:02.199036Z] Running download-and-extract step. 12:21:02 INFO - Running pre-action listener: _resource_record_pre_action 12:21:02 INFO - Running main action method: download_and_extract 12:21:02 INFO - mkdir: /builds/slave/test/build/tests 12:21:02 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')]} 12:21:02 INFO - trying https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.test_packages.json 12:21:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:21:02 INFO - Downloaded 989 bytes. 12:21:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:21:02 INFO - Using the following test package requirements: 12:21:02 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:21:02 INFO - u'common': [u'target.common.tests.zip'], 12:21:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:21:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:21:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:21:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:21:02 INFO - u'mozbase': [u'target.common.tests.zip'], 12:21:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:21:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:21:02 INFO - u'web-platform': [u'target.common.tests.zip', 12:21:02 INFO - u'target.web-platform.tests.tar.gz'], 12:21:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:21:02 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:21:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.common.tests.zip 12:21:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:21:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.common.tests.zip into memory 12:21:03 INFO - Content-Length response header: 55872803 12:21:03 INFO - Bytes received: 55872803 12:21:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.talos.tests.zip 12:21:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:21:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.talos.tests.zip into memory 12:21:15 INFO - Content-Length response header: 14522427 12:21:15 INFO - Bytes received: 14522427 12:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:15 INFO - trying https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.tar.bz2 12:21:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:21:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:21:16 INFO - Downloaded 62327451 bytes. 12:21:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.tar.bz2 12:21:17 INFO - mkdir: /builds/slave/test/properties 12:21:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:21:17 INFO - Writing to file /builds/slave/test/properties/build_url 12:21:17 INFO - Contents: 12:21:17 INFO - build_url:https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.tar.bz2 12:21:17 INFO - Running post-action listener: _resource_record_post_action 12:21:17 INFO - Running post-action listener: find_tests_for_verification 12:21:17 INFO - Running post-action listener: set_extra_try_arguments 12:21:17 INFO - [mozharness: 2017-10-03 19:21:17.186510Z] Finished download-and-extract step (success) 12:21:17 INFO - [mozharness: 2017-10-03 19:21:17.186612Z] Running populate-webroot step. 12:21:17 INFO - Running pre-action listener: _resource_record_pre_action 12:21:17 INFO - Running main action method: populate_webroot 12:21:17 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:21:17 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['tresize', 12:21:17 INFO - 'tcanvasmark']}, 12:21:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:21:17 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['dromaeo_css', 12:21:17 INFO - 'kraken']}, 12:21:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:21:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:17 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:21:17 INFO - 'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:21:17 INFO - 'tests': ['damp', 'tps']}, 12:21:17 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:21:17 INFO - 'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['damp', 'tps']}, 12:21:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:21:17 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['dromaeo_dom']}, 12:21:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:21:17 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['basic_compositor_video', 12:21:17 INFO - 'glvideo']}, 12:21:17 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:21:17 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:21:17 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:21:17 INFO - 'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['ts_paint_webext', 12:21:17 INFO - 'tp5o_webext']}, 12:21:17 INFO - 'other-e10s': {'tests': ['a11yr', 12:21:17 INFO - 'ts_paint', 12:21:17 INFO - 'tpaint', 12:21:17 INFO - 'sessionrestore', 12:21:17 INFO - 'sessionrestore_many_windows', 12:21:17 INFO - 'sessionrestore_no_auto_restore', 12:21:17 INFO - 'tabpaint', 12:21:17 INFO - 'cpstartup']}, 12:21:17 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['a11yr', 12:21:17 INFO - 'ts_paint', 12:21:17 INFO - 'tpaint', 12:21:17 INFO - 'sessionrestore', 12:21:17 INFO - 'sessionrestore_many_windows', 12:21:17 INFO - 'sessionrestore_no_auto_restore', 12:21:17 INFO - 'tabpaint', 12:21:17 INFO - 'cpstartup']}, 12:21:17 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:21:17 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:21:17 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['perf_reftest_singletons']}, 12:21:17 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['perf_reftest']}, 12:21:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:21:17 INFO - 'tsvgr_opacity', 12:21:17 INFO - 'tart', 12:21:17 INFO - 'tscrollx', 12:21:17 INFO - 'tsvg_static']}, 12:21:17 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['tsvgx', 12:21:17 INFO - 'tsvgr_opacity', 12:21:17 INFO - 'tart', 12:21:17 INFO - 'tscrollx', 12:21:17 INFO - 'tsvg_static']}, 12:21:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:21:17 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:21:17 INFO - 'talos_options': ['--disable-stylo'], 12:21:17 INFO - 'tests': ['tp5o']}, 12:21:17 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:21:17 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:21:17 INFO - 'talos_options': ['--mitmproxy', 12:21:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:21:17 INFO - '--firstNonBlankPaint'], 12:21:17 INFO - 'tests': ['tp6_google', 12:21:17 INFO - 'tp6_youtube', 12:21:17 INFO - 'tp6_amazon', 12:21:17 INFO - 'tp6_facebook']}, 12:21:17 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:21:17 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:21:17 INFO - 'talos_options': ['--disable-stylo', 12:21:17 INFO - '--mitmproxy', 12:21:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:21:17 INFO - '--firstNonBlankPaint'], 12:21:17 INFO - 'tests': ['tp6_google', 12:21:17 INFO - 'tp6_youtube', 12:21:17 INFO - 'tp6_amazon', 12:21:17 INFO - 'tp6_facebook']}, 12:21:17 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:21:17 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:21:17 INFO - 'talos_options': ['--stylo-threads=1', 12:21:17 INFO - '--mitmproxy', 12:21:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:21:17 INFO - '--firstNonBlankPaint'], 12:21:17 INFO - 'tests': ['tp6_google', 12:21:17 INFO - 'tp6_youtube', 12:21:17 INFO - 'tp6_amazon', 12:21:17 INFO - 'tp6_facebook']}, 12:21:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:21:17 INFO - 'talos_options': ['--xperf_path', 12:21:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:17 INFO - 'tests': ['tp5n']}, 12:21:17 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:21:17 INFO - 'talos_options': ['--disable-stylo', 12:21:17 INFO - '--xperf_path', 12:21:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:17 INFO - 'tests': ['tp5n']}}} 12:21:17 INFO - Running post-action listener: _resource_record_post_action 12:21:17 INFO - [mozharness: 2017-10-03 19:21:17.210677Z] Finished populate-webroot step (success) 12:21:17 INFO - [mozharness: 2017-10-03 19:21:17.210767Z] Running create-virtualenv step. 12:21:17 INFO - Running pre-action listener: _resource_record_pre_action 12:21:17 INFO - Running main action method: create_virtualenv 12:21:17 INFO - Creating virtualenv /builds/slave/test/build/venv 12:21:17 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:21:17 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:21:17 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:21:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:21:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:21:17 INFO - Installing distribute.............................................................................................................................................................................................done. 12:21:18 INFO - Installing pip...............done. 12:21:18 INFO - Return code: 0 12:21:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:21:18 INFO - Reading from file tmpfile_stdout 12:21:18 INFO - Output received: 12:21:18 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:21:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:21:18 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')]} 12:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:18 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')]} 12:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:18 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 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:18 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 12:21:18 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 12:21:18 INFO - Using env: {'DISPLAY': ':0', 12:21:18 INFO - 'HOME': '/home/cltbld', 12:21:18 INFO - 'LANG': 'en_US.UTF-8', 12:21:18 INFO - 'LANGUAGE': 'en_US:en', 12:21:18 INFO - 'LOGNAME': 'cltbld', 12:21:18 INFO - 'MAIL': '/var/mail/cltbld', 12:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:18 INFO - 'MOZ_NO_REMOTE': '1', 12:21:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:18 INFO - 'NO_EM_RESTART': '1', 12:21:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:18 INFO - 'PWD': '/builds/slave/test', 12:21:18 INFO - 'SHELL': '/bin/bash', 12:21:18 INFO - 'SHLVL': '1', 12:21:18 INFO - 'TERM': 'linux', 12:21:18 INFO - 'TMOUT': '86400', 12:21:18 INFO - 'USER': 'cltbld', 12:21:18 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:18 INFO - '_': '/tools/buildbot/bin/python'} 12:21:19 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:21:19 INFO - Downloading/unpacking pip>=1.5 12:21:19 INFO - Running setup.py egg_info for package pip 12:21:19 INFO - warning: no files found matching 'pip/cacert.pem' 12:21:19 INFO - warning: no files found matching '*.html' under directory 'docs' 12:21:19 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:21:19 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:21:19 INFO - Installing collected packages: pip 12:21:19 INFO - Found existing installation: pip 1.1 12:21:19 INFO - Uninstalling pip: 12:21:19 INFO - Successfully uninstalled pip 12:21:19 INFO - Running setup.py install for pip 12:21:19 INFO - warning: no files found matching 'pip/cacert.pem' 12:21:19 INFO - warning: no files found matching '*.html' under directory 'docs' 12:21:19 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:21:19 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:21:19 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:21:19 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:21:19 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:21:19 INFO - Successfully installed pip 12:21:19 INFO - Cleaning up... 12:21:19 INFO - Return code: 0 12:21:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:21:19 INFO - Using env: {'DISPLAY': ':0', 12:21:19 INFO - 'HOME': '/home/cltbld', 12:21:19 INFO - 'LANG': 'en_US.UTF-8', 12:21:19 INFO - 'LANGUAGE': 'en_US:en', 12:21:19 INFO - 'LOGNAME': 'cltbld', 12:21:19 INFO - 'MAIL': '/var/mail/cltbld', 12:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:19 INFO - 'MOZ_NO_REMOTE': '1', 12:21:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:19 INFO - 'NO_EM_RESTART': '1', 12:21:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:19 INFO - 'PWD': '/builds/slave/test', 12:21:19 INFO - 'SHELL': '/bin/bash', 12:21:19 INFO - 'SHLVL': '1', 12:21:19 INFO - 'TERM': 'linux', 12:21:19 INFO - 'TMOUT': '86400', 12:21:19 INFO - 'USER': 'cltbld', 12:21:19 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:19 INFO - '_': '/tools/buildbot/bin/python'} 12:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:19 INFO - Downloading/unpacking psutil>=3.1.1 12:21:19 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 12:21:19 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 12:21:19 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 12:21:19 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 12:21:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:21:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:20 INFO - Installing collected packages: psutil 12:21:20 INFO - Running setup.py install for psutil 12:21:21 INFO - building 'psutil._psutil_linux' extension 12:21:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:21:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:21:21 INFO - building 'psutil._psutil_posix' extension 12:21:21 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 12:21:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:21:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:21 INFO - Successfully installed psutil 12:21:21 INFO - Cleaning up... 12:21:21 INFO - Return code: 0 12:21:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:21:21 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')]} 12:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:21 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')]} 12:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:21 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 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:21 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 12:21:21 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 12:21:21 INFO - Using env: {'DISPLAY': ':0', 12:21:21 INFO - 'HOME': '/home/cltbld', 12:21:21 INFO - 'LANG': 'en_US.UTF-8', 12:21:21 INFO - 'LANGUAGE': 'en_US:en', 12:21:21 INFO - 'LOGNAME': 'cltbld', 12:21:21 INFO - 'MAIL': '/var/mail/cltbld', 12:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:21 INFO - 'MOZ_NO_REMOTE': '1', 12:21:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:21 INFO - 'NO_EM_RESTART': '1', 12:21:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:21 INFO - 'PWD': '/builds/slave/test', 12:21:21 INFO - 'SHELL': '/bin/bash', 12:21:21 INFO - 'SHLVL': '1', 12:21:21 INFO - 'TERM': 'linux', 12:21:21 INFO - 'TMOUT': '86400', 12:21:21 INFO - 'USER': 'cltbld', 12:21:21 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:21 INFO - '_': '/tools/buildbot/bin/python'} 12:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:21:21 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 12:21:21 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 12:21:21 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 12:21:21 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 12:21:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:21:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:21:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:21:22 INFO - Installing collected packages: mozsystemmonitor 12:21:22 INFO - Running setup.py install for mozsystemmonitor 12:21:23 INFO - Successfully installed mozsystemmonitor 12:21:23 INFO - Cleaning up... 12:21:23 INFO - Return code: 0 12:21:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:21:23 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')]} 12:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:23 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')]} 12:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:23 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 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:23 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 12:21:23 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 12:21:23 INFO - Using env: {'DISPLAY': ':0', 12:21:23 INFO - 'HOME': '/home/cltbld', 12:21:23 INFO - 'LANG': 'en_US.UTF-8', 12:21:23 INFO - 'LANGUAGE': 'en_US:en', 12:21:23 INFO - 'LOGNAME': 'cltbld', 12:21:23 INFO - 'MAIL': '/var/mail/cltbld', 12:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:23 INFO - 'MOZ_NO_REMOTE': '1', 12:21:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:23 INFO - 'NO_EM_RESTART': '1', 12:21:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:23 INFO - 'PWD': '/builds/slave/test', 12:21:23 INFO - 'SHELL': '/bin/bash', 12:21:23 INFO - 'SHLVL': '1', 12:21:23 INFO - 'TERM': 'linux', 12:21:23 INFO - 'TMOUT': '86400', 12:21:23 INFO - 'USER': 'cltbld', 12:21:23 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:23 INFO - '_': '/tools/buildbot/bin/python'} 12:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:23 INFO - Downloading/unpacking jsonschema==2.5.1 12:21:23 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 12:21:23 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 12:21:23 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 12:21:23 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 12:21:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:21:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:25 INFO - Downloading functools32-3.2.3-2.tar.gz 12:21:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:21:25 INFO - warning: no files found matching '*.txt' 12:21:25 INFO - no previously-included directories found matching 'build' 12:21:25 INFO - no previously-included directories found matching 'dist' 12:21:25 INFO - no previously-included directories found matching '.git*' 12:21:25 INFO - Installing collected packages: jsonschema, functools32 12:21:25 INFO - Running setup.py install for functools32 12:21:25 INFO - warning: no files found matching '*.txt' 12:21:25 INFO - no previously-included directories found matching 'build' 12:21:25 INFO - no previously-included directories found matching 'dist' 12:21:25 INFO - no previously-included directories found matching '.git*' 12:21:25 INFO - Successfully installed jsonschema functools32 12:21:25 INFO - Cleaning up... 12:21:25 INFO - Return code: 0 12:21:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:21:25 INFO - Using env: {'DISPLAY': ':0', 12:21:25 INFO - 'HOME': '/home/cltbld', 12:21:25 INFO - 'LANG': 'en_US.UTF-8', 12:21:25 INFO - 'LANGUAGE': 'en_US:en', 12:21:25 INFO - 'LOGNAME': 'cltbld', 12:21:25 INFO - 'MAIL': '/var/mail/cltbld', 12:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:25 INFO - 'MOZ_NO_REMOTE': '1', 12:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:25 INFO - 'NO_EM_RESTART': '1', 12:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:25 INFO - 'PWD': '/builds/slave/test', 12:21:25 INFO - 'SHELL': '/bin/bash', 12:21:25 INFO - 'SHLVL': '1', 12:21:25 INFO - 'TERM': 'linux', 12:21:25 INFO - 'TMOUT': '86400', 12:21:25 INFO - 'USER': 'cltbld', 12:21:25 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:25 INFO - '_': '/tools/buildbot/bin/python'} 12:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:21:25 INFO - Cleaning up... 12:21:25 INFO - Return code: 0 12:21:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:21:25 INFO - Using env: {'DISPLAY': ':0', 12:21:25 INFO - 'HOME': '/home/cltbld', 12:21:25 INFO - 'LANG': 'en_US.UTF-8', 12:21:25 INFO - 'LANGUAGE': 'en_US:en', 12:21:25 INFO - 'LOGNAME': 'cltbld', 12:21:25 INFO - 'MAIL': '/var/mail/cltbld', 12:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:25 INFO - 'MOZ_NO_REMOTE': '1', 12:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:25 INFO - 'NO_EM_RESTART': '1', 12:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:25 INFO - 'PWD': '/builds/slave/test', 12:21:25 INFO - 'SHELL': '/bin/bash', 12:21:25 INFO - 'SHLVL': '1', 12:21:25 INFO - 'TERM': 'linux', 12:21:25 INFO - 'TMOUT': '86400', 12:21:25 INFO - 'USER': 'cltbld', 12:21:25 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:25 INFO - '_': '/tools/buildbot/bin/python'} 12:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:25 INFO - Downloading/unpacking blobuploader==1.2.4 12:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:26 INFO - Downloading blobuploader-1.2.4.tar.gz 12:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:21:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:21:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:27 INFO - Downloading docopt-0.6.1.tar.gz 12:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:21:27 INFO - Installing collected packages: blobuploader, requests, docopt 12:21:27 INFO - Running setup.py install for blobuploader 12:21:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:21:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:21:27 INFO - Running setup.py install for requests 12:21:27 INFO - Running setup.py install for docopt 12:21:27 INFO - Successfully installed blobuploader requests docopt 12:21:27 INFO - Cleaning up... 12:21:28 INFO - Return code: 0 12:21:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:21:28 INFO - Using env: {'DISPLAY': ':0', 12:21:28 INFO - 'HOME': '/home/cltbld', 12:21:28 INFO - 'LANG': 'en_US.UTF-8', 12:21:28 INFO - 'LANGUAGE': 'en_US:en', 12:21:28 INFO - 'LOGNAME': 'cltbld', 12:21:28 INFO - 'MAIL': '/var/mail/cltbld', 12:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:28 INFO - 'MOZ_NO_REMOTE': '1', 12:21:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:28 INFO - 'NO_EM_RESTART': '1', 12:21:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:28 INFO - 'PWD': '/builds/slave/test', 12:21:28 INFO - 'SHELL': '/bin/bash', 12:21:28 INFO - 'SHLVL': '1', 12:21:28 INFO - 'TERM': 'linux', 12:21:28 INFO - 'TMOUT': '86400', 12:21:28 INFO - 'USER': 'cltbld', 12:21:28 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:28 INFO - '_': '/tools/buildbot/bin/python'} 12:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:28 INFO - Running setup.py (path:/tmp/pip-dRYkEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:28 INFO - Running setup.py (path:/tmp/pip-X8lutm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:28 INFO - Running setup.py (path:/tmp/pip-4_LKJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:28 INFO - Running setup.py (path:/tmp/pip-Y_CqXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:28 INFO - Running setup.py (path:/tmp/pip-RbCJQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:28 INFO - Running setup.py (path:/tmp/pip-NGdVTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:28 INFO - Running setup.py (path:/tmp/pip-5htFgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:28 INFO - Running setup.py (path:/tmp/pip-OGZvpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:28 INFO - Running setup.py (path:/tmp/pip-krEoFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:28 INFO - Running setup.py (path:/tmp/pip-JvuJMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:28 INFO - Running setup.py (path:/tmp/pip-WS8Jwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:29 INFO - Running setup.py (path:/tmp/pip-d5dMZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:29 INFO - Running setup.py (path:/tmp/pip-Du01Vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:29 INFO - Running setup.py (path:/tmp/pip-x0asva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:29 INFO - Running setup.py (path:/tmp/pip-4fnCHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:21:29 INFO - Running setup.py (path:/tmp/pip-GBumZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:21:29 INFO - Running setup.py (path:/tmp/pip-Yf5EpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:21:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:21:29 INFO - Running setup.py install for manifestparser 12:21:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:21:29 INFO - Running setup.py install for mozcrash 12:21:29 INFO - Running setup.py install for mozdebug 12:21:29 INFO - Running setup.py install for mozdevice 12:21:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:21:29 INFO - Running setup.py install for mozfile 12:21:29 INFO - Running setup.py install for mozhttpd 12:21:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Running setup.py install for mozinfo 12:21:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Running setup.py install for mozInstall 12:21:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Running setup.py install for mozleak 12:21:30 INFO - Running setup.py install for mozlog 12:21:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Running setup.py install for moznetwork 12:21:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Running setup.py install for mozprocess 12:21:30 INFO - Running setup.py install for mozprofile 12:21:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Running setup.py install for mozrunner 12:21:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:21:30 INFO - Running setup.py install for mozscreenshot 12:21:31 INFO - Running setup.py install for moztest 12:21:31 INFO - Running setup.py install for mozversion 12:21:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:21:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:21:31 INFO - Cleaning up... 12:21:31 INFO - Return code: 0 12:21:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:21:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:21:31 INFO - Using env: {'DISPLAY': ':0', 12:21:31 INFO - 'HOME': '/home/cltbld', 12:21:31 INFO - 'LANG': 'en_US.UTF-8', 12:21:31 INFO - 'LANGUAGE': 'en_US:en', 12:21:31 INFO - 'LOGNAME': 'cltbld', 12:21:31 INFO - 'MAIL': '/var/mail/cltbld', 12:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:31 INFO - 'MOZ_NO_REMOTE': '1', 12:21:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:31 INFO - 'NO_EM_RESTART': '1', 12:21:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:31 INFO - 'PWD': '/builds/slave/test', 12:21:31 INFO - 'SHELL': '/bin/bash', 12:21:31 INFO - 'SHLVL': '1', 12:21:31 INFO - 'TERM': 'linux', 12:21:31 INFO - 'TMOUT': '86400', 12:21:31 INFO - 'USER': 'cltbld', 12:21:31 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:31 INFO - '_': '/tools/buildbot/bin/python'} 12:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:31 INFO - Running setup.py (path:/tmp/pip-91kcCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:31 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)) 12:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:31 INFO - Running setup.py (path:/tmp/pip-pAslAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:31 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)) 12:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:31 INFO - Running setup.py (path:/tmp/pip-cYCFwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:31 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)) 12:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:31 INFO - Running setup.py (path:/tmp/pip-87kkjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:31 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)) 12:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:31 INFO - Running setup.py (path:/tmp/pip-hNH2x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:31 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)) 12:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:31 INFO - Running setup.py (path:/tmp/pip-pC6iuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:31 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)) 12:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:31 INFO - Running setup.py (path:/tmp/pip-3O0f8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:31 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)) 12:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:31 INFO - Running setup.py (path:/tmp/pip-lfUvdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:32 INFO - Running setup.py (path:/tmp/pip-97g9pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:32 INFO - Running setup.py (path:/tmp/pip-_HeKOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:32 INFO - Running setup.py (path:/tmp/pip-lC1IYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:32 INFO - Running setup.py (path:/tmp/pip-Mjsm76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:32 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)) 12:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:32 INFO - Running setup.py (path:/tmp/pip-GBdofG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:32 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)) 12:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:32 INFO - Running setup.py (path:/tmp/pip-nC7SjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:32 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)) 12:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:32 INFO - Running setup.py (path:/tmp/pip-bFnikO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:32 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)) 12:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:21:32 INFO - Running setup.py (path:/tmp/pip-zaEC9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:21:32 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)) 12:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:21:32 INFO - Running setup.py (path:/tmp/pip-4VTc7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:21:32 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)) 12:21:32 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)) 12:21:32 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)) 12:21:32 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)) 12:21:32 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)) 12:21:32 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)) 12:21:32 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)) 12:21:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:33 INFO - Downloading blessings-1.6.tar.gz 12:21:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:21:33 INFO - Installing collected packages: blessings 12:21:33 INFO - Running setup.py install for blessings 12:21:33 INFO - Successfully installed blessings 12:21:33 INFO - Cleaning up... 12:21:34 INFO - Return code: 0 12:21:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:21:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:21:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:21:34 INFO - Reading from file tmpfile_stdout 12:21:34 INFO - Current package versions: 12:21:34 INFO - argparse == 1.2.1 12:21:34 INFO - blessings == 1.6 12:21:34 INFO - blobuploader == 1.2.4 12:21:34 INFO - docopt == 0.6.1 12:21:34 INFO - functools32 == 3.2.3-2 12:21:34 INFO - jsonschema == 2.5.1 12:21:34 INFO - manifestparser == 1.1 12:21:34 INFO - mozInstall == 1.13 12:21:34 INFO - mozcrash == 1.0 12:21:34 INFO - mozdebug == 0.1 12:21:34 INFO - mozdevice == 0.51 12:21:34 INFO - mozfile == 1.2 12:21:34 INFO - mozhttpd == 0.7 12:21:34 INFO - mozinfo == 0.10 12:21:34 INFO - mozleak == 0.1 12:21:34 INFO - mozlog == 3.5 12:21:34 INFO - moznetwork == 0.27 12:21:34 INFO - mozprocess == 0.25 12:21:34 INFO - mozprofile == 0.28 12:21:34 INFO - mozrunner == 6.13 12:21:34 INFO - mozscreenshot == 0.1 12:21:34 INFO - mozsystemmonitor == 0.3 12:21:34 INFO - moztest == 0.8 12:21:34 INFO - mozversion == 1.4 12:21:34 INFO - psutil == 3.1.1 12:21:34 INFO - requests == 1.2.3 12:21:34 INFO - wsgiref == 0.1.2 12:21:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:21:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:21:34 INFO - Using env: {'DISPLAY': ':0', 12:21:34 INFO - 'HOME': '/home/cltbld', 12:21:34 INFO - 'LANG': 'en_US.UTF-8', 12:21:34 INFO - 'LANGUAGE': 'en_US:en', 12:21:34 INFO - 'LOGNAME': 'cltbld', 12:21:34 INFO - 'MAIL': '/var/mail/cltbld', 12:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:34 INFO - 'MOZ_NO_REMOTE': '1', 12:21:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:34 INFO - 'NO_EM_RESTART': '1', 12:21:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:34 INFO - 'PWD': '/builds/slave/test', 12:21:34 INFO - 'SHELL': '/bin/bash', 12:21:34 INFO - 'SHLVL': '1', 12:21:34 INFO - 'TERM': 'linux', 12:21:34 INFO - 'TMOUT': '86400', 12:21:34 INFO - 'USER': 'cltbld', 12:21:34 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:34 INFO - '_': '/tools/buildbot/bin/python'} 12:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:34 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)) 12:21:34 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)) 12:21:34 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)) 12:21:34 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)) 12:21:34 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)) 12:21:34 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)) 12:21:34 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)) 12:21:34 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)) 12:21:34 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)) 12:21:34 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:21:35 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)) 12:21:35 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)) 12:21:35 INFO - Installing collected packages: simplejson 12:21:35 INFO - Running setup.py install for simplejson 12:21:35 INFO - building 'simplejson._speedups' extension 12:21:35 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 12:21:36 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 12:21:36 INFO - Successfully installed simplejson 12:21:36 INFO - Cleaning up... 12:21:36 INFO - Return code: 0 12:21:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:36 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 0x1ca21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x200f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, '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': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', '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 12:21:36 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 12:21:36 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 12:21:36 INFO - Using env: {'DISPLAY': ':0', 12:21:36 INFO - 'HOME': '/home/cltbld', 12:21:36 INFO - 'LANG': 'en_US.UTF-8', 12:21:36 INFO - 'LANGUAGE': 'en_US:en', 12:21:36 INFO - 'LOGNAME': 'cltbld', 12:21:36 INFO - 'MAIL': '/var/mail/cltbld', 12:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:36 INFO - 'MOZ_NO_REMOTE': '1', 12:21:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:36 INFO - 'NO_EM_RESTART': '1', 12:21:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:36 INFO - 'PWD': '/builds/slave/test', 12:21:36 INFO - 'SHELL': '/bin/bash', 12:21:36 INFO - 'SHLVL': '1', 12:21:36 INFO - 'TERM': 'linux', 12:21:36 INFO - 'TMOUT': '86400', 12:21:36 INFO - 'USER': 'cltbld', 12:21:36 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:36 INFO - '_': '/tools/buildbot/bin/python'} 12:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:21:36 INFO - Cleaning up... 12:21:36 INFO - Return code: 0 12:21:36 INFO - Running post-action listener: _resource_record_post_action 12:21:36 INFO - Running post-action listener: _start_resource_monitoring 12:21:36 INFO - Starting resource monitoring. 12:21:36 INFO - [mozharness: 2017-10-03 19:21:36.946485Z] Finished create-virtualenv step (success) 12:21:36 INFO - [mozharness: 2017-10-03 19:21:36.947005Z] Running install step. 12:21:36 INFO - Running pre-action listener: _resource_record_pre_action 12:21:36 INFO - Running main action method: install 12:21:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:21:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:21:37 INFO - Reading from file tmpfile_stdout 12:21:37 INFO - Detecting whether we're running mozinstall >=1.0... 12:21:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:21:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:21:37 INFO - Reading from file tmpfile_stdout 12:21:37 INFO - Output received: 12:21:37 INFO - Usage: mozinstall [options] installer 12:21:37 INFO - Options: 12:21:37 INFO - -h, --help show this help message and exit 12:21:37 INFO - -d DEST, --destination=DEST 12:21:37 INFO - Directory to install application into. [default: 12:21:37 INFO - "/builds/slave/test"] 12:21:37 INFO - --app=APP Application being installed. [default: firefox] 12:21:37 INFO - mkdir: /builds/slave/test/build/application 12:21:37 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'] 12:21:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:21:54 INFO - Reading from file tmpfile_stdout 12:21:54 INFO - Output received: 12:21:54 INFO - /builds/slave/test/build/application/firefox/firefox 12:21:54 INFO - Running post-action listener: _resource_record_post_action 12:21:54 INFO - [mozharness: 2017-10-03 19:21:54.888813Z] Finished install step (success) 12:21:54 INFO - [mozharness: 2017-10-03 19:21:54.889066Z] Running setup-mitmproxy step. 12:21:54 INFO - Running pre-action listener: _resource_record_pre_action 12:21:54 INFO - Running main action method: setup_mitmproxy 12:21:54 INFO - Skipping: mitmproxy is not required 12:21:54 INFO - Running post-action listener: _resource_record_post_action 12:21:54 INFO - [mozharness: 2017-10-03 19:21:54.890262Z] Finished setup-mitmproxy step (success) 12:21:54 INFO - [mozharness: 2017-10-03 19:21:54.890497Z] Running run-tests step. 12:21:54 INFO - Running pre-action listener: _resource_record_pre_action 12:21:54 INFO - Running pre-action listener: _set_gcov_prefix 12:21:54 INFO - Running main action method: run_tests 12:21:54 WARNING - Try message not found. 12:21:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:21:54 INFO - Python 2.7.3 12:21:54 INFO - Return code: 0 12:21:54 INFO - grabbing minidump binary from tooltool 12:21:54 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')]} 12:21:54 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 0x200cd60>, '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 0x200da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x200def0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:21:54 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 12:21:54 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 12:21:54 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 12:21:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:21:55 INFO - Return code: 0 12:21:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:21:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:21:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:21:55 INFO - ENV: RUST_BACKTRACE is now full 12:21:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:21:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 12:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 12:21:55 INFO - Using env: {'DISPLAY': ':0', 12:21:55 INFO - 'HOME': '/home/cltbld', 12:21:55 INFO - 'LANG': 'en_US.UTF-8', 12:21:55 INFO - 'LANGUAGE': 'en_US:en', 12:21:55 INFO - 'LOGNAME': 'cltbld', 12:21:55 INFO - 'MAIL': '/var/mail/cltbld', 12:21:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:21:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:55 INFO - 'MOZ_NO_REMOTE': '1', 12:21:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:21:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:55 INFO - 'NO_EM_RESTART': '1', 12:21:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:55 INFO - 'PWD': '/builds/slave/test', 12:21:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:21:55 INFO - 'RUST_BACKTRACE': 'full', 12:21:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:21:55 INFO - 'SHELL': '/bin/bash', 12:21:55 INFO - 'SHLVL': '1', 12:21:55 INFO - 'TERM': 'linux', 12:21:55 INFO - 'TMOUT': '86400', 12:21:55 INFO - 'USER': 'cltbld', 12:21:55 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:55 INFO - '_': '/tools/buildbot/bin/python'} 12:21:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 12:21:55 INFO - mozversion application_buildid: 20171003175736 12:21:55 INFO - mozversion application_changeset: 9be05b2177667ed8221f9da4fdcc200dbdf3de62 12:21:55 INFO - mozversion application_display_name: Nightly 12:21:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:21:55 INFO - mozversion application_name: Firefox 12:21:55 INFO - mozversion application_remotingname: firefox 12:21:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:21:55 INFO - mozversion application_vendor: Mozilla 12:21:55 INFO - mozversion application_version: 58.0a1 12:21:55 INFO - mozversion platform_buildid: 20171003175736 12:21:55 INFO - mozversion platform_changeset: 9be05b2177667ed8221f9da4fdcc200dbdf3de62 12:21:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:21:55 INFO - mozversion platform_version: 58.0a1 12:21:55 INFO - using testdate: 1507058515 12:21:55 INFO - actual date: 1507058515 12:21:55 INFO - starting webserver on 'localhost:55347' 12:21:55 INFO - SUITE-START | Running 2 tests 12:21:55 INFO - TEST-START | dromaeo_css 12:21:55 INFO - Initialising browser for dromaeo_css test... 12:21:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55347/getInfo.html -profile /tmp/tmp3NrWFh/profile 12:21:55 INFO - TEST-INFO | started process 15462 (/builds/slave/test/build/application/firefox/firefox http://localhost:55347/getInfo.html) 12:22:00 INFO - TEST-INFO | 15462: exit 0 12:22:00 INFO - Browser initialized. 12:22:00 INFO - Running cycle 1/1 for dromaeo_css test... 12:22:00 INFO - Using env: {'DISPLAY': ':0', 12:22:00 INFO - 'HOME': '/home/cltbld', 12:22:00 INFO - 'JSGC_DISABLE_POISONING': '1', 12:22:00 INFO - 'LANG': 'en_US.UTF-8', 12:22:00 INFO - 'LANGUAGE': 'en_US:en', 12:22:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:22:00 INFO - 'LOGNAME': 'cltbld', 12:22:00 INFO - 'MAIL': '/var/mail/cltbld', 12:22:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:22:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:22:00 INFO - 'MOZ_CRASHREPORTER': '1', 12:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:22:00 INFO - 'MOZ_NO_REMOTE': '1', 12:22:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:22:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:22:00 INFO - 'NO_EM_RESTART': '1', 12:22:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:00 INFO - 'PWD': '/builds/slave/test', 12:22:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:22:00 INFO - 'RUST_BACKTRACE': 'full', 12:22:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:22:00 INFO - 'SHELL': '/bin/bash', 12:22:00 INFO - 'SHLVL': '1', 12:22:00 INFO - 'TERM': 'linux', 12:22:00 INFO - 'TMOUT': '86400', 12:22:00 INFO - 'USER': 'cltbld', 12:22:00 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:00 INFO - '_': '/tools/buildbot/bin/python'} 12:22:00 INFO - TEST-INFO | started process 15737 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3NrWFh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:01 INFO - PID 15737 | 12:22:01 INFO - PID 15737 | (/builds/slave/test/build/application/firefox/firefox:15786): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:22:01 INFO - PID 15737 | 12:22:11 INFO - PID 15737 | RSS: Main: 145965056 12:22:11 INFO - PID 15737 | 12:25:09 INFO - PID 15737 | Cycle 1(1): loaded http://localhost:55347/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55347/tests/dromaeo/cssquery-ext.html) 12:25:10 INFO - PID 15737 | RSS: Main: 143872000 12:25:10 INFO - PID 15737 | 12:28:12 INFO - PID 15737 | Cycle 1(1): loaded http://localhost:55347/tests/dromaeo/cssquery-ext.html (next: http://localhost:55347/tests/dromaeo/cssquery-jquery.html) 12:28:13 INFO - PID 15737 | RSS: Main: 144326656 12:28:13 INFO - PID 15737 | 12:31:14 INFO - PID 15737 | Cycle 1(1): loaded http://localhost:55347/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55347/tests/dromaeo/cssquery-mootools.html) 12:31:15 INFO - PID 15737 | RSS: Main: 145903616 12:31:15 INFO - PID 15737 | 12:34:16 INFO - PID 15737 | Cycle 1(1): loaded http://localhost:55347/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55347/tests/dromaeo/cssquery-prototype.html) 12:34:17 INFO - PID 15737 | RSS: Main: 145797120 12:34:17 INFO - PID 15737 | 12:37:20 INFO - PID 15737 | Cycle 1(1): loaded http://localhost:55347/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55347/tests/dromaeo/cssquery-yui.html) 12:37:21 INFO - PID 15737 | RSS: Main: 148467712 12:37:21 INFO - PID 15737 | 12:40:22 INFO - PID 15737 | Cycle 1(1): loaded http://localhost:55347/tests/dromaeo/cssquery-yui.html (next: http://localhost:55347/tests/dromaeo/cssquery-dojo.html) 12:40:22 INFO - PID 15737 | RSS: Main: 147718144 12:40:22 INFO - PID 15737 | 12:40:22 INFO - PID 15737 | __start_tp_report 12:40:22 INFO - PID 15737 | _x_x_mozilla_page_load 12:40:22 INFO - PID 15737 | _x_x_mozilla_page_load_details 12:40:22 INFO - PID 15737 | |i|pagename|runs| 12:40:22 INFO - PID 15737 | |0;dojo.html;3903.629155230253;3912.2371137628156;3930.862419815306;3936.1930804185145;3954.980225098874;6164.383561643834;6319.241292354616;6352.428281454674;6397.968010159954;6435.774747883814;6273.153124328214;6327.49380049596;6346.714397852096;6364.554481186319;6449.516286278525;5682.573806964494;5698.544116470673;5716.056850619642;5746.51154651853;5752.7986520471795;5536.972315138419;5641.915371269434;5653;5725.198472213893;5729.312482502106;5587.664740115565;5633.57748168889;5701.857453563653;5733.942660573403;5754.856128596777;5623.465770751772;5652.039153343901;5667.093265077568;5691.9146212806845;5719.3422756382715;5671.886562268773;5719.113537401709;5719.799807006735;5756.6546007239285;5764.048931926227;6267.655278959655;6297.464715499188;6348.079528468393;6362.1093046973465;6376.29860715321;6730.697118629673;6861.142357205349;6870.20992585849;6893.965530172367;6924.065251191126;7008.544444611084;7048.154221493453;7054.435645148427;7063.752768653124;7076.32774886385;6404.807855764335;6416.00551914454;6418.5507014509;6512.772052978123;6516.739330426777;6939.132608423947;7046.92953070473;7136.857262854714;7213.927860721431;7216.061911951413;6767.560108592926;6776.05135281061;6784.423324017415;6790.558613690095;6806.897896531556;6887.827804304932;6993.650317484207;7002.40479559233;7007.334303241184;7036.437085033185;6859.07402500666;6867.690953907087;6888.724451022003;6961.61711105894;6984.755533556301;6779.186497620317;6839.555428897106;6856.211535673461;6861.210960739479;6868.313168683092;4942.728149951715;4944.703317800943;4951.084049450863;4961.255811628284;4965.4041515018425;4513.909721805546;4524.796384162721;4542.295944128666;4581.518940511199;4637.304404339376;6389.297177310492;6400.167978162902;6456.12842266288;6474.061261117112;6505.772297969548;6827.453803695693;6864.38220560142;6884.414824739853;6979.197392299849;6990.790276291617;6389.904151437732;6419.679016049179;6443.29123796382;6448.484121270287;6449.129367535323;5566.304211973504;5621.325440947031;5635.774569017193;5636.520895723827;5647;7205.387542058984;7208.639568021683;7213.675384607705;7317.963410182915;7319.890201646979;6769.323067693192;6877.243503214742;6918.8616227676175;7001.264867188974;7015.473839462163;7131.536450130725;7140.143182818095;7141.928580714334;7143.749968751069;7147.606881621561;6828.5902845829405;6849.1438570178725;6926.307369263235;6932.480063995321;6933.687984040831;6917.342852429011;6985.895211571932;7043.330883566053;7062.540934839219;7073.646317684198;6659.4006539411685;6659.933400665931;6758.662066896733;6759.831004224934;6760.425363843931;6534.085228068169;6638.137042184485;6645.435138013323;6719.160104986877;6720.899186512304;6449.9355006451215;6529.771457998947;6532.542722009413;6568.310327415553;6592.24189218246;6607.900881486686;6629.370209830058;6633.9336606633315;6714.328567143247;6731.024001519851;6404.231492220963;6448.387403196689;6528.086067950396;6535.934640653532;6541.313162117909 12:40:22 INFO - PID 15737 | |1;ext.html;977.6773664690653;989.539863963257;998.7802683409648;999.5651891427225;1006.9647562335317;45162.548374516286;45554.772226138826;45861;45966.77016614912;46178.538214617845;42197.789011054905;42230;42528.14943701124;42547.57452425483;42716.359254611125;7750.689972401097;7762.223777622221;7792.259735325135;7797.025371828521;7808.921910780877;7748.341390981774;7788.6884524618945;7839.451238413313;7839.529628222296;7850.254225848564;8312.251897329239;8555.973283206024;8564.443311184754;8576.614052367659;8586.613602387908;8420.157984201593;8481.830363392759;8509.744707658749;8511.957440212822;8522.446041007377;8230.42387032908;8334.49993000422;8408.201220884068;8431.578421078983;8437.662493500253;42065.948351291154;42157.57842421567;42416.57583424188;42551.361729574084;42571.14857702829;3681.245344704356;3705.6109108543756;3727.7949712765785;3729.179580492287;3751.962480375216;34363.140921476916;34409.79565715188;34587.30825383478;34614.30771384583;34832.6516734832;5453.100238460649;5544.473275038865;5549.805756798533;5570.944290557123;5627.71861406928;219182;220798;221296.893515533;221877;222156;46611.766941165755;47129.76435117871;47170.76414617974;52504.212436813476;53400.198997015075;56329;56543;56551.43448565544;56570;56588.43411565832;17300.913495432444;17432;17450.91274543619;17461.301547938194;17476;14177.432902683777;14190.645233869029;14240.074395164282;14248.643783905485;14251.073680210753;14730.92634536842;14821.555353339418;14828.3327250274;14856.142982127234;14865.108093514424;5738.311402631711;5887.1463637772285;5907.1434641976675;5951.791687291011;5954.285485741652;1072.860528131338;1108.3349990006093;1111.8943700348455;1112.3492756737237;1121.1311233793874;26261.73738262631;26296.47407051848;26328.605070923568;26579.468410632067;26646.20061398161;1196.5273716881957;1212.9971095385195;1214.186495048302;1216.1608490141773;1236.140882086941;13758.93120534391;13813.51652692151;13821.930890345484;13877.167369957835;13922.86077139236;291317;292380;292822;293071;293267;60625;61136.694316528134;61193.69403152956;61257.38742612517;61461.38538614646;1502.1512845242403;1521.3610283681103;1553.060398458916;1554.1762865681208;1565.7025165218572;25556.488870222118;26178.60732088982;26313.473730525664;26397.868010660593;26430.339241518348;43106.78446607872;43458.56541434545;43540;43566.346504803085;43579.564204358816;230069;230450;230667;231397;232181;36505;37521.31154098127;39444.80277598594;39638.2072358557;39717.40423893586;49449.75275123601;50357;50436;50598.74700626473;50936.490635094655;37420;37552.624473756005;37628.24743505169;37847.81076094602;38032.429513556766;32413;33029.834850825595;34297.657023430445;35294.94115176549;36660.81669591635;42911.356329655704;43629.56370436255;43984.780076099414;44122.558774413126;44137.33793993157;35893.82053089718;36044.819775902004;36205.637943620226;36504;36802.8159859199;27322.316942075813;27446.725532744418;27447.313817154733;27504.862475687492;28026.579601305588 12:40:22 INFO - PID 15737 | |2;jquery.html;635.4364636074799;635.9976609204566;640.6933182044936;645.5157258618318;649.3121442125238;22885.656715149285;23757.287281381545;23774.643380349287;23788.16741414051;23887.522249554997;25291;25354.619680704804;25406.9837206512;25572.74427255727;25586.488270234586;6293.181886354768;6449.580777249487;6486.708098135595;6513.609183448996;6532.150820393343;6486.643234622094;6589;6591.604503729767;6606.669666516679;6624.56940298879;6559.934400656004;6573.901391479131;6588.275289718127;6602.240742314623;6841.144856892888;6786.762463313785;6820.840457122301;6833.521653484258;6844.041834143224;6859.657017149123;6393.2328120625825;6395.488360931115;6404.551681382305;6422.454091402236;6423;29404.11787646374;30272;30311.848440757876;30357;30434.695653043407;2274.636058230675;2307.573098976678;2311.4683622766856;2317.5596636638984;2335.486193037529;20468.283610073577;20594.176232950664;20879.582408351747;20916.163353465843;21003.684944725843;2477.6159695247266;2521.0798058708624;2553.438243586427;2593.390553219992;2611.777998870098;140673;144432.27783861014;156198;164265;164430.17784911118;16303;16454.259558319904;16574.502764917073;16765.664686706197;17329.306827727003;26963.1911042669;27008.729912701016;27043;27110.728892711213;27177.72822271752;10722.892771072346;10850.348979061124;10874.238803283693;10890.455477226265;10910.781784364426;9923.255755818342;9926.106650401498;9970.700878973643;10100.141487973458;10156.796864062677;9746.561404736804;10076.74808129788;10089.646862359783;10274.383536987805;10350.741231469123;2921.751651109637;3024.879004839782;3036.9544456832723;3060.4032213718556;3171.44499712554;1026.5534492495788;1028.4240825137947;1040.6562302576326;1049.632628579991;1058.751193469534;20036.799632003786;20764;20813.58372832565;21005.47486312824;21270.787292127192;1102.72431892027;1107.5182295701395;1108.0692218536467;1116.7375666718315;1118.9440527973568;11216.93439123282;11475.885241147482;11511.48198331077;11522.942385288019;11541.134414918746;568190;582239;583882;601772;619659;133207;138312;138338;139043;139318.30340848232;1136.3015026419212;1226.5645695778048;1263.620913725897;1293.6377814212035;1295.5489851366342;21422.464438389165;21520.031598578415;21902.78097219071;21984.89007554952;22446.214382496535;61729;62569;62748.68625656843;63803.68098159479;64665.676671618225;76692;77606.6119669398;77838.61080694561;80984.59507702659;82843;21007;21227.46931326729;21307.78692213058;21599.244026458997;21621.567568648225;24006.51986960286;24114.51770964606;24164.87917560401;24180;24316.392090197885;19293.324733634254;19624.90187549053;19715.40853774389;19848.10683519274;19935.302264420676;16216.918915405347;16526.256318465938;16701;17671.29314827444;17959.730604040687;19262.903685481484;19510.512237193616;19734.90132549328;20079.196832126552;20331.491712707302;21170.788292117497;21224.575508490052;21315.78684213138;21434;21519.78480215178;19289.710654340477;19342.129604167563;19437.4168774937;19475;19593.31423400174 12:40:22 INFO - PID 15737 | |3;mootools.html;1371.3897315694514;1460.788185713071;1478.6599082211085;1486.6283429142713;1494.8355680875102;16293.592660183487;17029.489115326545;17052.658946821055;17063.232154553043;17069.65860682789;16627.251773670185;16833.66332673346;16888.408905688302;16914.154292285366;16930.40743573975;2879.5680647902877;2888.638920134978;2941.3676059647173;2942.043835753378;2943.3524624582556;2839.148255523345;2850.8574571271456;2879.3089658481917;2888.205743420566;2888.494513460136;2784.874680639376;2853.571964205365;2873.209867286492;2882.1065469704354;2883.8558072096416;2828.109145619134;2835.1211124551455;2861.370498490329;2862.871170797309;2864.455753406846;2822.3085344090623;2830.2924268932766;2848.2594525423333;2848.3875966667265;2849.2307077089276;16473;16847.57881052971;16852.747208791756;16859.325626974907;16893.15534223284;8444.451110677848;8636.61135248909;8699.13008699125;8713;8715.346349023785;5013.6490445668815;5289.100852855024;5345.919811202835;5454.427285135083;5462.126059830448;2147.355793262015;2203.754878493646;2212.657038159072;2244.5735310290993;2261.106862789189;30617;30671.079867603785;30817.999999999774;30981.535276970862;31047;7335.156457007406;7461.85076298471;7497.850042999109;7515.624218789038;7600.1639819619795;19395.612087758447;19894.801051989296;20001;20218.29235976763;20270.59458810845;8330;8494.362922780816;8533.274671652855;8539.103394143649;8548.615312310962;7684.769456916301;7740.806479837936;7846;7875.803104922423;7887.9605601971625;5809.506191973645;5972.193753843262;5997.730102145414;6012.158297838306;6028.2766068072115;1762.5946032412617;1764.1796564597523;1798.8201179881908;1802.2340505285201;1838.960987042317;895.8924929008431;947.6541062512133;948.2793077261331;948.3266880514774;960.9279304052084;7996.440249182618;8026.518408895485;8116;8121.106678265386;8158.592070396575;761.8628646843621;796.1640277708383;796.9880451793227;805.2430715127762;819.2381085590339;4817.253325734517;4852.660313778002;4892.241702536113;5575.303087114111;5700.8859822803315;24147.63778543323;25394.74605253961;25621.48757024849;25807.74192258091;25855.870720646275;24362.756372436404;24867.378315542257;24905.87547062253;25125.74874251234;25251.62122568163;12658.987281017497;12791.680207994688;12866.935665321613;12897.032722546035;12997.220166789833;8709.738707838775;9213.170814626448;9227;9232.98437171924;9417.340786144903;20546.486337841674;20714.896425517774;20836.687449687965;21140.154393824076;21163.47091322729;21641.242556510446;21678.6748198774;21707.891460542596;21927.890360548095;22358.329250122522;12633.2420054797;12868.549600763929;12977.221366718028;12986.220826750425;12998.805017924928;15512.457064002705;15547.922260388626;15560.144192069545;15726.528204153894;15744.842551574338;12265.816012759995;12523;12724.109312348046;12724.363781811058;12825.935870320589;11912.702182445508;12240.020798335934;12339.197952133083;12489;12492.500299988262;10596.682099537025;10821.783564328827;10831.187660925305;10834.729131771768;10840.5121769519;7546.943427920205;7567.091948966159;7608.733694320672;7626.618669066635;7819.41354398411;8091.98850143732;8168.754937351889;8175.754727358189;8205.95897020511;8299.502029878227 12:40:22 INFO - PID 15737 | |4;prototype.html;559.4489427913506;570.4238718893921;585.3297973819977;588.4997751910877;591.1310373750586;6235.345288744685;6251.843703907399;6271.592346497475;6314.210723659543;6387.393197646222;6167.444929956303;6184.783532576361;6209.689515524217;6294;6325.620462772237;5176.767045482962;5187.6887386756725;5197.662151960129;5217.843464696046;5228.058949389108;5097.209932460474;5192.065428222918;5204.817831375903;5223.582113430916;5230;4896.094222568836;5002.12462819007;5041.445441001487;5056.544910958013;5079.492050794909;4979.10376132296;5021.57316628087;5035.244713292998;5071.061853557067;5078.085944529983;4522.344260082303;4570.9314360284625;4605.102005109005;4607.709841244457;4608.976955115203;6117.480014198798;6129.080637904305;6134.4172303631085;6139.693015349215;6162.969185154046;5959.7020148992815;5968.612040217372;5969.224000879901;5979.760809567656;5982.282126144891;6586.242582103072;6626.801195964081;6645.867082658368;6663.300353462865;6677.265500794909;5161.974190129063;5170.095233334189;5179.663321884103;5179.792808287664;5185.818496352609;6757.459403247728;6788.660566971681;6921.342472465108;6952.860942781167;7001.859962800715;5209.223313098049;5252.474752524717;5347.3048503695045;5352.357717073976;5386.8383948481605;5401.5948803839865;5410.188471729193;5430.15832545956;5430.701311427831;5437.32033495813;5958.821235362946;5982.786369043395;5994.130851026577;6023.548233882477;6025.216721826135;6677.499187560855;6679.732810687616;6762.594244345355;6823.965880170567;6834.350736680008;1804.4857215693464;1836.8989705566055;1848.5655870870335;1859.1819599376502;1860.6557786809212;1311.1608570514877;1337.0840973932886;1356.453642847155;1364.5087768403366;1366.091549119824;6153.323134455206;6181.990852972802;6236.126942228092;6264.248290205204;6272.435480806749;6659.733610655519;6675.966620166965;6711.637435666758;6712.261651218362;6768.898466523006;6224.408681175281;6266.561340706106;6284.402981716729;6310.968445157837;6329.240491141;3517.964820351815;3533.561842328785;3568.2149927016017;3600.8199590020395;3612.9819350903604;6955.6869940851775;7067.476814838507;7092.255313192144;7147.606881621561;7176.497645164893;7068.293170682993;7095.964520177469;7116.750913717995;7122.92877071233;7124.251953544906;7083.68123434457;7108.644567771591;7117.1459424869345;7169.749058783022;7212.71149154028;6600.174978127734;6775.864482710417;6786.694598743167;6840.521163518506;6849.6230459089375;6686.598804071771;6728.966355168192;6753.628550429577;6774.525783195129;6798.55809372409;7029.226785053742;7051.576905385693;7169.211386747349;7169.820754481179;7187.957746126887;5286.762095705625;5382.892342153056;5384.300040994645;5386.057439947915;5420.349558053059;6673.366030227121;6698.062271281927;6780.1524809398825;6893.620850853251;6909.44724422035;6384.712687929147;6564.702354176;6587.736490540325;6618.90071648916;6663.46692264608;6498.285188629342;6526.771562995273;6583.407493325623;6606.174228221473;6623.238327592196;6419.390157934989;6493.935060649333;6498.935010650022;6519.3480651936325;6544.44372228366;6638.634875081917;6643.169603799525;6677.565958212701;6731.427828634621;6733.494987876027;6416.294207637249;6435.22777266731;6455.2576453708425;6465.15743013239;6506.60960342381 12:40:22 INFO - PID 15737 | |5;yui.html;981.3817295104084;995.0397418275264;1002.9548670309836;1010.7574182196271;1021.8824835143961;20190.99045047747;20701.378958631227;20711.689324660143;20731.481712957182;20787.16851325949;17816.376426825063;18993.145308461117;19101.713474297896;19166.904165479154;19279.614407711837;1065.3288428290186;1099.2415233488907;1099.3623698254994;1100.1803656276086;1102.3716481605488;1071.223363061778;1081.118888106191;1084.728817795551;1090.2858627598935;1107.8061339265637;1161.6979585307838;1174.8531433570804;1177.0936378988174;1177.1171621284036;1178.7996040673106;1152.164680606562;1152.95964641238;1154.2439701995206;1155.895969362753;1166.9066474682047;799.1449149410133;814.9511029338199;819.0294501016256;821.4291067707945;823.4236034775681;20576;20637;20822.791772082237;20937.79062209389;20992.790072099237;21889.233876814236;22328;22661.43346416353;22815.77184228153;22950.77049229503;18346.26614935401;18543.165557549943;18634.25462981479;18786.62426751471;18807.435776926715;3904.4533765272886;3914.862979795722;3945.546262179829;3947.072437977102;3948.861789837342;166866.1656691721;167168;167626;167879.99999999878;170034;17286.13569321529;17306.48080557585;17361.7395739064;17367.56581085483;17401.65196696059;34552.308953820786;34884.651153488645;34900.47649285263;34958.12604684903;35325;8513.021002584659;8556.957215214008;8575.571221439028;8582.65669373218;8591.871121933176;4627.167109920247;4629.490756016836;4703.223968045234;4703.506131856174;4719.787609557578;6028.728707208186;6110.602810817282;6144.201253836973;6164.198654175018;6190.164327815777;1042.5777560088177;1065.658989123473;1071.5713714514102;1086.8206745886998;1089.716673664837;627.7991042866239;642.1780121444558;648.3743187823773;654.9148610680581;663.290279401046;11933.343666098448;12871.678208044874;12882.871171288354;13510.662233444045;13581.86418135806;738.2322384719938;738.7119023580808;740.4335683202354;747.5029105644794;749.2657195948002;5854.619449735754;5882.294124705063;5915.911261331084;5957.434043765836;5963.403659634002;151451;152501;154862;154973;156391;132261;132929;136488.99999999802;137598;145762;114637;125310.37344813399;125665.37167314287;126285;127111.99999999815;14403.495877644651;14419.711605767616;14538.127712337293;14624.926875365554;14650.267486625413;57771.71114144402;58154.7092264536;58437.4156258432;58486.7075664636;59545;75589;77824.22175778169;78178;78568.60715696384;79450.6027469859;20784.064717087465;20887.686684699438;20939;21173.576528469654;21179.152833886474;19054.809451905305;19303;19581.118849651513;19685.901570492057;19938.202471901543;21602.675959860928;21677.566448670623;21923.561528769653;22039.228626997978;22101.66847497257;20449;20916.47708807292;20998.47503812417;21143.365699029055;21155.365339039854;20529.58940821205;20878;20905.581888362452;21110.472238193553;21114.366569002956;14298.28508574588;14346.713065738835;14351.712965740835;14476.348564314841;14671.70656586841;14887.255637218312;14954;15042.548723538312;15162;15316.310766015333 12:40:22 INFO - PID 15737 | __end_tp_report 12:40:22 INFO - PID 15737 | __start_cc_report 12:40:22 INFO - PID 15737 | _x_x_mozilla_cycle_collect,263 12:40:22 INFO - PID 15737 | __end_cc_report 12:40:22 INFO - PID 15737 | __startTimestamp1507059622950__endTimestamp 12:40:22 INFO - PID 15737 | 12:40:22 INFO - PID 15737 | ------- Summary: start ------- 12:40:22 INFO - PID 15737 | Number of tests: 6 12:40:22 INFO - PID 15737 | 12:40:22 INFO - PID 15737 | [#0] dojo.html Cycles:165 Average:6351.37 Median:6535.93 stddev:771.39 (11.8%) stddev-sans-first:749.47 12:40:22 INFO - PID 15737 | Values: 3903.6 3912.2 3930.9 3936.2 3955.0 6164.4 6319.2 6352.4 6398.0 6435.8 6273.2 6327.5 6346.7 6364.6 6449.5 5682.6 5698.5 5716.1 5746.5 5752.8 5537.0 5641.9 5653.0 5725.2 5729.3 5587.7 5633.6 5701.9 5733.9 5754.9 5623.5 5652.0 5667.1 5691.9 5719.3 5671.9 5719.1 5719.8 5756.7 5764.0 6267.7 6297.5 6348.1 6362.1 6376.3 6730.7 6861.1 6870.2 6894.0 6924.1 7008.5 7048.2 7054.4 7063.8 7076.3 6404.8 6416.0 6418.6 6512.8 6516.7 6939.1 7046.9 7136.9 7213.9 7216.1 6767.6 6776.1 6784.4 6790.6 6806.9 6887.8 6993.7 7002.4 7007.3 7036.4 6859.1 6867.7 6888.7 6961.6 6984.8 6779.2 6839.6 6856.2 6861.2 6868.3 4942.7 4944.7 4951.1 4961.3 4965.4 4513.9 4524.8 4542.3 4581.5 4637.3 6389.3 6400.2 6456.1 6474.1 6505.8 6827.5 6864.4 6884.4 6979.2 6990.8 6389.9 6419.7 6443.3 6448.5 6449.1 5566.3 5621.3 5635.8 5636.5 5647.0 7205.4 7208.6 7213.7 7318.0 7319.9 6769.3 6877.2 6918.9 7001.3 7015.5 7131.5 7140.1 7141.9 7143.7 7147.6 6828.6 6849.1 6926.3 6932.5 6933.7 6917.3 6985.9 7043.3 7062.5 7073.6 6659.4 6659.9 6758.7 6759.8 6760.4 6534.1 6638.1 6645.4 6719.2 6720.9 6449.9 6529.8 6532.5 6568.3 6592.2 6607.9 6629.4 6633.9 6714.3 6731.0 6404.2 6448.4 6528.1 6535.9 6541.3 12:40:22 INFO - PID 15737 | 12:40:22 INFO - PID 15737 | [#1] ext.html Cycles:180 Average:43405.35 Median:37738.03 stddev:65046.42 (172.4%) stddev-sans-first:65150.88 12:40:22 INFO - PID 15737 | Values: 977.7 989.5 998.8 999.6 1007.0 45162.5 45554.8 45861.0 45966.8 46178.5 42197.8 42230.0 42528.1 42547.6 42716.4 7750.7 7762.2 7792.3 7797.0 7808.9 7748.3 7788.7 7839.5 7839.5 7850.3 8312.3 8556.0 8564.4 8576.6 8586.6 8420.2 8481.8 8509.7 8512.0 8522.4 8230.4 8334.5 8408.2 8431.6 8437.7 42065.9 42157.6 42416.6 42551.4 42571.1 3681.2 3705.6 3727.8 3729.2 3752.0 34363.1 34409.8 34587.3 34614.3 34832.7 5453.1 5544.5 5549.8 5570.9 5627.7 219182.0 220798.0 221296.9 221877.0 222156.0 46611.8 47129.8 47170.8 52504.2 53400.2 56329.0 56543.0 56551.4 56570.0 56588.4 17300.9 17432.0 17450.9 17461.3 17476.0 14177.4 14190.6 14240.1 14248.6 14251.1 14730.9 14821.6 14828.3 14856.1 14865.1 5738.3 5887.1 5907.1 5951.8 5954.3 1072.9 1108.3 1111.9 1112.3 1121.1 26261.7 26296.5 26328.6 26579.5 26646.2 1196.5 1213.0 1214.2 1216.2 1236.1 13758.9 13813.5 13821.9 13877.2 13922.9 291317.0 292380.0 292822.0 293071.0 293267.0 60625.0 61136.7 61193.7 61257.4 61461.4 1502.2 1521.4 1553.1 1554.2 1565.7 25556.5 26178.6 26313.5 26397.9 26430.3 43106.8 43458.6 43540.0 43566.3 43579.6 230069.0 230450.0 230667.0 231397.0 232181.0 36505.0 37521.3 39444.8 39638.2 39717.4 49449.8 50357.0 50436.0 50598.7 50936.5 37420.0 37552.6 37628.2 37847.8 38032.4 32413.0 33029.8 34297.7 35294.9 36660.8 42911.4 43629.6 43984.8 44122.6 44137.3 35893.8 36044.8 36205.6 36504.0 36802.8 27322.3 27446.7 27447.3 27504.9 28026.6 12:40:22 INFO - PID 15737 | 12:40:22 INFO - PID 15737 | [#2] jquery.html Cycles:180 Average:40041.10 Median:12360.43 stddev:99324.66 (803.6%) stddev-sans-first:99559.22 12:40:22 INFO - PID 15737 | Values: 635.4 636.0 640.7 645.5 649.3 22885.7 23757.3 23774.6 23788.2 23887.5 25291.0 25354.6 25407.0 25572.7 25586.5 6293.2 6449.6 6486.7 6513.6 6532.2 6486.6 6589.0 6591.6 6606.7 6624.6 6559.9 6573.9 6588.3 6602.2 6841.1 6786.8 6820.8 6833.5 6844.0 6859.7 6393.2 6395.5 6404.6 6422.5 6423.0 29404.1 30272.0 30311.8 30357.0 30434.7 2274.6 2307.6 2311.5 2317.6 2335.5 20468.3 20594.2 20879.6 20916.2 21003.7 2477.6 2521.1 2553.4 2593.4 2611.8 140673.0 144432.3 156198.0 164265.0 164430.2 16303.0 16454.3 16574.5 16765.7 17329.3 26963.2 27008.7 27043.0 27110.7 27177.7 10722.9 10850.3 10874.2 10890.5 10910.8 9923.3 9926.1 9970.7 10100.1 10156.8 9746.6 10076.7 10089.6 10274.4 10350.7 2921.8 3024.9 3037.0 3060.4 3171.4 1026.6 1028.4 1040.7 1049.6 1058.8 20036.8 20764.0 20813.6 21005.5 21270.8 1102.7 1107.5 1108.1 1116.7 1118.9 11216.9 11475.9 11511.5 11522.9 11541.1 568190.0 582239.0 583882.0 601772.0 619659.0 133207.0 138312.0 138338.0 139043.0 139318.3 1136.3 1226.6 1263.6 1293.6 1295.5 21422.5 21520.0 21902.8 21984.9 22446.2 61729.0 62569.0 62748.7 63803.7 64665.7 76692.0 77606.6 77838.6 80984.6 82843.0 21007.0 21227.5 21307.8 21599.2 21621.6 24006.5 24114.5 24164.9 24180.0 24316.4 19293.3 19624.9 19715.4 19848.1 19935.3 16216.9 16526.3 16701.0 17671.3 17959.7 19262.9 19510.5 19734.9 20079.2 20331.5 21170.8 21224.6 21315.8 21434.0 21519.8 19289.7 19342.1 19437.4 19475.0 19593.3 12:40:22 INFO - PID 15737 | 12:40:22 INFO - PID 15737 | [#3] mootools.html Cycles:180 Average:10412.00 Median:2843.70 stddev:7765.46 (273.1%) stddev-sans-first:7757.54 12:40:22 INFO - PID 15737 | Values: 1371.4 1460.8 1478.7 1486.6 1494.8 16293.6 17029.5 17052.7 17063.2 17069.7 16627.3 16833.7 16888.4 16914.2 16930.4 2879.6 2888.6 2941.4 2942.0 2943.4 2839.1 2850.9 2879.3 2888.2 2888.5 2784.9 2853.6 2873.2 2882.1 2883.9 2828.1 2835.1 2861.4 2862.9 2864.5 2822.3 2830.3 2848.3 2848.4 2849.2 16473.0 16847.6 16852.7 16859.3 16893.2 8444.5 8636.6 8699.1 8713.0 8715.3 5013.6 5289.1 5345.9 5454.4 5462.1 2147.4 2203.8 2212.7 2244.6 2261.1 30617.0 30671.1 30818.0 30981.5 31047.0 7335.2 7461.9 7497.9 7515.6 7600.2 19395.6 19894.8 20001.0 20218.3 20270.6 8330.0 8494.4 8533.3 8539.1 8548.6 7684.8 7740.8 7846.0 7875.8 7888.0 5809.5 5972.2 5997.7 6012.2 6028.3 1762.6 1764.2 1798.8 1802.2 1839.0 895.9 947.7 948.3 948.3 960.9 7996.4 8026.5 8116.0 8121.1 8158.6 761.9 796.2 797.0 805.2 819.2 4817.3 4852.7 4892.2 5575.3 5700.9 24147.6 25394.7 25621.5 25807.7 25855.9 24362.8 24867.4 24905.9 25125.7 25251.6 12659.0 12791.7 12866.9 12897.0 12997.2 8709.7 9213.2 9227.0 9233.0 9417.3 20546.5 20714.9 20836.7 21140.2 21163.5 21641.2 21678.7 21707.9 21927.9 22358.3 12633.2 12868.5 12977.2 12986.2 12998.8 15512.5 15547.9 15560.1 15726.5 15744.8 12265.8 12523.0 12724.1 12724.4 12825.9 11912.7 12240.0 12339.2 12489.0 12492.5 10596.7 10821.8 10831.2 10834.7 10840.5 7546.9 7567.1 7608.7 7626.6 7819.4 8092.0 8168.8 8175.8 8206.0 8299.5 12:40:22 INFO - PID 15737 | 12:40:22 INFO - PID 15737 | [#4] prototype.html Cycles:180 Average:5702.64 Median:6269.08 stddev:1577.55 (25.2%) stddev-sans-first:1534.02 12:40:22 INFO - PID 15737 | Values: 559.4 570.4 585.3 588.5 591.1 6235.3 6251.8 6271.6 6314.2 6387.4 6167.4 6184.8 6209.7 6294.0 6325.6 5176.8 5187.7 5197.7 5217.8 5228.1 5097.2 5192.1 5204.8 5223.6 5230.0 4896.1 5002.1 5041.4 5056.5 5079.5 4979.1 5021.6 5035.2 5071.1 5078.1 4522.3 4570.9 4605.1 4607.7 4609.0 6117.5 6129.1 6134.4 6139.7 6163.0 5959.7 5968.6 5969.2 5979.8 5982.3 6586.2 6626.8 6645.9 6663.3 6677.3 5162.0 5170.1 5179.7 5179.8 5185.8 6757.5 6788.7 6921.3 6952.9 7001.9 5209.2 5252.5 5347.3 5352.4 5386.8 5401.6 5410.2 5430.2 5430.7 5437.3 5958.8 5982.8 5994.1 6023.5 6025.2 6677.5 6679.7 6762.6 6824.0 6834.4 1804.5 1836.9 1848.6 1859.2 1860.7 1311.2 1337.1 1356.5 1364.5 1366.1 6153.3 6182.0 6236.1 6264.2 6272.4 6659.7 6676.0 6711.6 6712.3 6768.9 6224.4 6266.6 6284.4 6311.0 6329.2 3518.0 3533.6 3568.2 3600.8 3613.0 6955.7 7067.5 7092.3 7147.6 7176.5 7068.3 7096.0 7116.8 7122.9 7124.3 7083.7 7108.6 7117.1 7169.7 7212.7 6600.2 6775.9 6786.7 6840.5 6849.6 6686.6 6729.0 6753.6 6774.5 6798.6 7029.2 7051.6 7169.2 7169.8 7188.0 5286.8 5382.9 5384.3 5386.1 5420.3 6673.4 6698.1 6780.2 6893.6 6909.4 6384.7 6564.7 6587.7 6618.9 6663.5 6498.3 6526.8 6583.4 6606.2 6623.2 6419.4 6493.9 6498.9 6519.3 6544.4 6638.6 6643.2 6677.6 6731.4 6733.5 6416.3 6435.2 6455.3 6465.2 6506.6 12:40:22 INFO - PID 15737 | 12:40:22 INFO - PID 15737 | [#5] yui.html Cycles:180 Average:29779.90 Median:20552.79 stddev:44347.63 (215.8%) stddev-sans-first:44419.32 12:40:22 INFO - PID 15737 | Values: 981.4 995.0 1003.0 1010.8 1021.9 20191.0 20701.4 20711.7 20731.5 20787.2 17816.4 18993.1 19101.7 19166.9 19279.6 1065.3 1099.2 1099.4 1100.2 1102.4 1071.2 1081.1 1084.7 1090.3 1107.8 1161.7 1174.9 1177.1 1177.1 1178.8 1152.2 1153.0 1154.2 1155.9 1166.9 799.1 815.0 819.0 821.4 823.4 20576.0 20637.0 20822.8 20937.8 20992.8 21889.2 22328.0 22661.4 22815.8 22950.8 18346.3 18543.2 18634.3 18786.6 18807.4 3904.5 3914.9 3945.5 3947.1 3948.9 166866.2 167168.0 167626.0 167880.0 170034.0 17286.1 17306.5 17361.7 17367.6 17401.7 34552.3 34884.7 34900.5 34958.1 35325.0 8513.0 8557.0 8575.6 8582.7 8591.9 4627.2 4629.5 4703.2 4703.5 4719.8 6028.7 6110.6 6144.2 6164.2 6190.2 1042.6 1065.7 1071.6 1086.8 1089.7 627.8 642.2 648.4 654.9 663.3 11933.3 12871.7 12882.9 13510.7 13581.9 738.2 738.7 740.4 747.5 749.3 5854.6 5882.3 5915.9 5957.4 5963.4 151451.0 152501.0 154862.0 154973.0 156391.0 132261.0 132929.0 136489.0 137598.0 145762.0 114637.0 125310.4 125665.4 126285.0 127112.0 14403.5 14419.7 14538.1 14624.9 14650.3 57771.7 58154.7 58437.4 58486.7 59545.0 75589.0 77824.2 78178.0 78568.6 79450.6 20784.1 20887.7 20939.0 21173.6 21179.2 19054.8 19303.0 19581.1 19685.9 19938.2 21602.7 21677.6 21923.6 22039.2 22101.7 20449.0 20916.5 20998.5 21143.4 21155.4 20529.6 20878.0 20905.6 21110.5 21114.4 14298.3 14346.7 14351.7 14476.3 14671.7 14887.3 14954.0 15042.5 15162.0 15316.3 12:40:22 INFO - PID 15737 | -------- Summary: end -------- 12:40:22 INFO - PID 15737 | 12:40:23 INFO - TEST-INFO | 15737: exit 0 12:40:23 INFO - TEST-OK | dromaeo_css | took 1108171ms 12:40:23 INFO - TEST-START | kraken 12:40:23 INFO - Initialising browser for kraken test... 12:40:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55347/getInfo.html -profile /tmp/tmpzrutfl/profile 12:40:23 INFO - TEST-INFO | started process 16137 (/builds/slave/test/build/application/firefox/firefox http://localhost:55347/getInfo.html) 12:40:28 INFO - TEST-INFO | 16137: exit 0 12:40:28 INFO - Browser initialized. 12:40:28 INFO - Running cycle 1/1 for kraken test... 12:40:28 INFO - Using env: {'DISPLAY': ':0', 12:40:28 INFO - 'HOME': '/home/cltbld', 12:40:28 INFO - 'JSGC_DISABLE_POISONING': '1', 12:40:28 INFO - 'LANG': 'en_US.UTF-8', 12:40:28 INFO - 'LANGUAGE': 'en_US:en', 12:40:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:40:28 INFO - 'LOGNAME': 'cltbld', 12:40:28 INFO - 'MAIL': '/var/mail/cltbld', 12:40:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:40:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:40:28 INFO - 'MOZ_CRASHREPORTER': '1', 12:40:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:40:28 INFO - 'MOZ_NO_REMOTE': '1', 12:40:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:40:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:28 INFO - 'NO_EM_RESTART': '1', 12:40:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:28 INFO - 'PWD': '/builds/slave/test', 12:40:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:40:28 INFO - 'RUST_BACKTRACE': 'full', 12:40:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:40:28 INFO - 'SHELL': '/bin/bash', 12:40:28 INFO - 'SHLVL': '1', 12:40:28 INFO - 'TERM': 'linux', 12:40:28 INFO - 'TMOUT': '86400', 12:40:28 INFO - 'USER': 'cltbld', 12:40:28 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851', 12:40:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:28 INFO - '_': '/tools/buildbot/bin/python'} 12:40:28 INFO - TEST-INFO | started process 16409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzrutfl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:40:29 INFO - PID 16409 | 12:40:29 INFO - PID 16409 | (/builds/slave/test/build/application/firefox/firefox:16457): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:40:29 INFO - PID 16409 | 12:40:39 INFO - PID 16409 | RSS: Main: 147992576 12:40:39 INFO - PID 16409 | 12:40:42 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55347/tests/kraken/driver.html?testName=audio-beat-detection) 12:40:42 INFO - PID 16409 | RSS: Main: 153493504 12:40:42 INFO - PID 16409 | 12:40:45 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55347/tests/kraken/driver.html?testName=audio-dft) 12:40:46 INFO - PID 16409 | RSS: Main: 153722880 12:40:46 INFO - PID 16409 | 12:40:49 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55347/tests/kraken/driver.html?testName=audio-fft) 12:40:49 INFO - PID 16409 | RSS: Main: 149614592 12:40:49 INFO - PID 16409 | 12:40:51 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55347/tests/kraken/driver.html?testName=audio-oscillator) 12:40:52 INFO - PID 16409 | RSS: Main: 150863872 12:40:52 INFO - PID 16409 | 12:40:54 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55347/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:40:54 INFO - PID 16409 | RSS: Main: 150495232 12:40:54 INFO - PID 16409 | 12:40:57 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55347/tests/kraken/driver.html?testName=imaging-darkroom) 12:40:58 INFO - PID 16409 | RSS: Main: 151252992 12:40:58 INFO - PID 16409 | 12:41:02 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55347/tests/kraken/driver.html?testName=imaging-desaturate) 12:41:02 INFO - PID 16409 | RSS: Main: 151867392 12:41:02 INFO - PID 16409 | 12:41:05 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55347/tests/kraken/driver.html?testName=json-parse-financial) 12:41:06 INFO - PID 16409 | RSS: Main: 153378816 12:41:06 INFO - PID 16409 | 12:41:07 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55347/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:41:08 INFO - PID 16409 | RSS: Main: 153182208 12:41:08 INFO - PID 16409 | 12:41:09 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55347/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:41:10 INFO - PID 16409 | RSS: Main: 153329664 12:41:10 INFO - PID 16409 | 12:41:12 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55347/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:41:13 INFO - PID 16409 | RSS: Main: 149983232 12:41:13 INFO - PID 16409 | 12:41:15 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:41:16 INFO - PID 16409 | RSS: Main: 150216704 12:41:16 INFO - PID 16409 | 12:41:18 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:41:19 INFO - PID 16409 | RSS: Main: 150876160 12:41:19 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | Cycle 1(1): loaded http://localhost:55347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55347/tests/kraken/driver.html?testName=ai-astar) 12:41:21 INFO - PID 16409 | RSS: Main: 152227840 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | __start_tp_report 12:41:21 INFO - PID 16409 | _x_x_mozilla_page_load 12:41:21 INFO - PID 16409 | _x_x_mozilla_page_load_details 12:41:21 INFO - PID 16409 | |i|pagename|runs| 12:41:21 INFO - PID 16409 | |0;ai-astar;107;107;104;106;105;107;107;106;106;105 12:41:21 INFO - PID 16409 | |1;audio-beat-detection;114;116;112;112;119;113;112;116;114;114 12:41:21 INFO - PID 16409 | |2;audio-dft;162;164;162;163;161;161;162;163;163;166 12:41:21 INFO - PID 16409 | |3;audio-fft;73;76;74;83;73;73;73;73;74;75 12:41:21 INFO - PID 16409 | |4;audio-oscillator;79;78;78;80;78;76;77;77;77;78 12:41:21 INFO - PID 16409 | |5;imaging-gaussian-blur;114;113;116;110;113;114;115;114;114;114 12:41:21 INFO - PID 16409 | |6;imaging-darkroom;149;151;155;150;150;151;149;149;149;152 12:41:21 INFO - PID 16409 | |7;imaging-desaturate;94;96;93;95;94;94;94;94;94;94 12:41:21 INFO - PID 16409 | |8;json-parse-financial;43;43;43;43;47;43;43;43;43;43 12:41:21 INFO - PID 16409 | |9;json-stringify-tinderbox;38;41;38;37;38;38;38;38;38;38 12:41:21 INFO - PID 16409 | |10;stanford-crypto-aes;70;70;68;82;67;69;68;69;69;70 12:41:21 INFO - PID 16409 | |11;stanford-crypto-ccm;107;102;102;103;108;104;109;107;104;108 12:41:21 INFO - PID 16409 | |12;stanford-crypto-pbkdf2;153;146;152;150;151;149;148;146;154;150 12:41:21 INFO - PID 16409 | |13;stanford-crypto-sha256-iterative;61;58;66;71;60;60;55;61;59;57 12:41:21 INFO - PID 16409 | __end_tp_report 12:41:21 INFO - PID 16409 | __start_cc_report 12:41:21 INFO - PID 16409 | _x_x_mozilla_cycle_collect,721 12:41:21 INFO - PID 16409 | __end_cc_report 12:41:21 INFO - PID 16409 | __startTimestamp1507059681503__endTimestamp 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | ------- Summary: start ------- 12:41:21 INFO - PID 16409 | Number of tests: 14 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#0] ai-astar Cycles:10 Average:106.00 Median:106.50 stddev:1.05 (1.0%) stddev-sans-first:1.05 12:41:21 INFO - PID 16409 | Values: 107.0 107.0 104.0 106.0 105.0 107.0 107.0 106.0 106.0 105.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#1] audio-beat-detection Cycles:10 Average:114.20 Median:114.00 stddev:2.25 (2.0%) stddev-sans-first:2.39 12:41:21 INFO - PID 16409 | Values: 114.0 116.0 112.0 112.0 119.0 113.0 112.0 116.0 114.0 114.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.49 (0.9%) stddev-sans-first:1.56 12:41:21 INFO - PID 16409 | Values: 162.0 164.0 162.0 163.0 161.0 161.0 162.0 163.0 163.0 166.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#3] audio-fft Cycles:10 Average:74.70 Median:74.00 stddev:3.09 (4.2%) stddev-sans-first:3.22 12:41:21 INFO - PID 16409 | Values: 73.0 76.0 74.0 83.0 73.0 73.0 73.0 73.0 74.0 75.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:1.14 (1.5%) stddev-sans-first:1.12 12:41:21 INFO - PID 16409 | Values: 79.0 78.0 78.0 80.0 78.0 76.0 77.0 77.0 77.0 78.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:1.57 (1.4%) stddev-sans-first:1.66 12:41:21 INFO - PID 16409 | Values: 114.0 113.0 116.0 110.0 113.0 114.0 115.0 114.0 114.0 114.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:150.50 stddev:1.90 (1.3%) stddev-sans-first:1.94 12:41:21 INFO - PID 16409 | Values: 149.0 151.0 155.0 150.0 150.0 151.0 149.0 149.0 149.0 152.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 12:41:21 INFO - PID 16409 | Values: 94.0 96.0 93.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#8] json-parse-financial Cycles:10 Average:43.40 Median:43.00 stddev:1.26 (2.9%) stddev-sans-first:1.33 12:41:21 INFO - PID 16409 | Values: 43.0 43.0 43.0 43.0 47.0 43.0 43.0 43.0 43.0 43.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:1.03 (2.7%) stddev-sans-first:1.09 12:41:21 INFO - PID 16409 | Values: 38.0 41.0 38.0 37.0 38.0 38.0 38.0 38.0 38.0 38.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#10] stanford-crypto-aes Cycles:10 Average:70.20 Median:69.50 stddev:4.26 (6.1%) stddev-sans-first:4.52 12:41:21 INFO - PID 16409 | Values: 70.0 70.0 68.0 82.0 67.0 69.0 68.0 69.0 69.0 70.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#11] stanford-crypto-ccm Cycles:10 Average:105.40 Median:107.00 stddev:2.67 (2.5%) stddev-sans-first:2.77 12:41:21 INFO - PID 16409 | Values: 107.0 102.0 102.0 103.0 108.0 104.0 109.0 107.0 104.0 108.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.90 Median:150.50 stddev:2.73 (1.8%) stddev-sans-first:2.65 12:41:21 INFO - PID 16409 | Values: 153.0 146.0 152.0 150.0 151.0 149.0 148.0 146.0 154.0 150.0 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:60.50 stddev:4.61 (7.6%) stddev-sans-first:4.89 12:41:21 INFO - PID 16409 | Values: 61.0 58.0 66.0 71.0 60.0 60.0 55.0 61.0 59.0 57.0 12:41:21 INFO - PID 16409 | -------- Summary: end -------- 12:41:21 INFO - PID 16409 | 12:41:21 INFO - PID 16409 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:41:22 INFO - TEST-INFO | 16409: exit 0 12:41:22 INFO - TEST-OK | kraken | took 58739ms 12:41:22 INFO - SUITE-END | took 1166s 12:41:22 INFO - Completed test suite (00:19:26) 12:41:22 INFO - javascript benchmark 12:41:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9223.275494340885, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3903.629155230253, 3912.2371137628156, 3930.862419815306, 3936.1930804185145, 3954.980225098874, 6164.383561643834, 6319.241292354616, 6352.428281454674, 6397.968010159954, 6435.774747883814, 6273.153124328214, 6327.49380049596, 6346.714397852096, 6364.554481186319, 6449.516286278525, 5682.573806964494, 5698.544116470673, 5716.056850619642, 5746.51154651853, 5752.7986520471795, 5536.972315138419, 5641.915371269434, 5653.0, 5725.198472213893, 5729.312482502106, 5587.664740115565, 5633.57748168889, 5701.857453563653, 5733.942660573403, 5754.856128596777, 5623.465770751772, 5652.039153343901, 5667.093265077568, 5691.9146212806845, 5719.3422756382715, 5671.886562268773, 5719.113537401709, 5719.799807006735, 5756.6546007239285, 5764.048931926227, 6267.655278959655, 6297.464715499188, 6348.079528468393, 6362.1093046973465, 6376.29860715321, 6730.697118629673, 6861.142357205349, 6870.20992585849, 6893.965530172367, 6924.065251191126, 7008.544444611084, 7048.154221493453, 7054.435645148427, 7063.752768653124, 7076.32774886385, 6404.807855764335, 6416.00551914454, 6418.5507014509, 6512.772052978123, 6516.739330426777, 6939.132608423947, 7046.92953070473, 7136.857262854714, 7213.927860721431, 7216.061911951413, 6767.560108592926, 6776.05135281061, 6784.423324017415, 6790.558613690095, 6806.897896531556, 6887.827804304932, 6993.650317484207, 7002.40479559233, 7007.334303241184, 7036.437085033185, 6859.07402500666, 6867.690953907087, 6888.724451022003, 6961.61711105894, 6984.755533556301, 6779.186497620317, 6839.555428897106, 6856.211535673461, 6861.210960739479, 6868.313168683092, 4942.728149951715, 4944.703317800943, 4951.084049450863, 4961.255811628284, 4965.4041515018425, 4513.909721805546, 4524.796384162721, 4542.295944128666, 4581.518940511199, 4637.304404339376, 6389.297177310492, 6400.167978162902, 6456.12842266288, 6474.061261117112, 6505.772297969548, 6827.453803695693, 6864.38220560142, 6884.414824739853, 6979.197392299849, 6990.790276291617, 6389.904151437732, 6419.679016049179, 6443.29123796382, 6448.484121270287, 6449.129367535323, 5566.304211973504, 5621.325440947031, 5635.774569017193, 5636.520895723827, 5647.0, 7205.387542058984, 7208.639568021683, 7213.675384607705, 7317.963410182915, 7319.890201646979, 6769.323067693192, 6877.243503214742, 6918.8616227676175, 7001.264867188974, 7015.473839462163, 7131.536450130725, 7140.143182818095, 7141.928580714334, 7143.749968751069, 7147.606881621561, 6828.5902845829405, 6849.1438570178725, 6926.307369263235, 6932.480063995321, 6933.687984040831, 6917.342852429011, 6985.895211571932, 7043.330883566053, 7062.540934839219, 7073.646317684198, 6659.4006539411685, 6659.933400665931, 6758.662066896733, 6759.831004224934, 6760.425363843931, 6534.085228068169, 6638.137042184485, 6645.435138013323, 6719.160104986877, 6720.899186512304, 6449.9355006451215, 6529.771457998947, 6532.542722009413, 6568.310327415553, 6592.24189218246, 6607.900881486686, 6629.370209830058, 6633.9336606633315, 6714.328567143247, 6731.024001519851, 6404.231492220963, 6448.387403196689, 6528.086067950396, 6535.934640653532, 6541.313162117909], "value": 6297.921446073205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [977.6773664690653, 989.539863963257, 998.7802683409648, 999.5651891427225, 1006.9647562335317, 45162.548374516286, 45554.772226138826, 45861.0, 45966.77016614912, 46178.538214617845, 42197.789011054905, 42230.0, 42528.14943701124, 42547.57452425483, 42716.359254611125, 7750.689972401097, 7762.223777622221, 7792.259735325135, 7797.025371828521, 7808.921910780877, 7748.341390981774, 7788.6884524618945, 7839.451238413313, 7839.529628222296, 7850.254225848564, 8312.251897329239, 8555.973283206024, 8564.443311184754, 8576.614052367659, 8586.613602387908, 8420.157984201593, 8481.830363392759, 8509.744707658749, 8511.957440212822, 8522.446041007377, 8230.42387032908, 8334.49993000422, 8408.201220884068, 8431.578421078983, 8437.662493500253, 42065.948351291154, 42157.57842421567, 42416.57583424188, 42551.361729574084, 42571.14857702829, 3681.245344704356, 3705.6109108543756, 3727.7949712765785, 3729.179580492287, 3751.962480375216, 34363.140921476916, 34409.79565715188, 34587.30825383478, 34614.30771384583, 34832.6516734832, 5453.100238460649, 5544.473275038865, 5549.805756798533, 5570.944290557123, 5627.71861406928, 219182.0, 220798.0, 221296.893515533, 221877.0, 222156.0, 46611.766941165755, 47129.76435117871, 47170.76414617974, 52504.212436813476, 53400.198997015075, 56329.0, 56543.0, 56551.43448565544, 56570.0, 56588.43411565832, 17300.913495432444, 17432.0, 17450.91274543619, 17461.301547938194, 17476.0, 14177.432902683777, 14190.645233869029, 14240.074395164282, 14248.643783905485, 14251.073680210753, 14730.92634536842, 14821.555353339418, 14828.3327250274, 14856.142982127234, 14865.108093514424, 5738.311402631711, 5887.1463637772285, 5907.1434641976675, 5951.791687291011, 5954.285485741652, 1072.860528131338, 1108.3349990006093, 1111.8943700348455, 1112.3492756737237, 1121.1311233793874, 26261.73738262631, 26296.47407051848, 26328.605070923568, 26579.468410632067, 26646.20061398161, 1196.5273716881957, 1212.9971095385195, 1214.186495048302, 1216.1608490141773, 1236.140882086941, 13758.93120534391, 13813.51652692151, 13821.930890345484, 13877.167369957835, 13922.86077139236, 291317.0, 292380.0, 292822.0, 293071.0, 293267.0, 60625.0, 61136.694316528134, 61193.69403152956, 61257.38742612517, 61461.38538614646, 1502.1512845242403, 1521.3610283681103, 1553.060398458916, 1554.1762865681208, 1565.7025165218572, 25556.488870222118, 26178.60732088982, 26313.473730525664, 26397.868010660593, 26430.339241518348, 43106.78446607872, 43458.56541434545, 43540.0, 43566.346504803085, 43579.564204358816, 230069.0, 230450.0, 230667.0, 231397.0, 232181.0, 36505.0, 37521.31154098127, 39444.80277598594, 39638.2072358557, 39717.40423893586, 49449.75275123601, 50357.0, 50436.0, 50598.74700626473, 50936.490635094655, 37420.0, 37552.624473756005, 37628.24743505169, 37847.81076094602, 38032.429513556766, 32413.0, 33029.834850825595, 34297.657023430445, 35294.94115176549, 36660.81669591635, 42911.356329655704, 43629.56370436255, 43984.780076099414, 44122.558774413126, 44137.33793993157, 35893.82053089718, 36044.819775902004, 36205.637943620226, 36504.0, 36802.8159859199, 27322.316942075813, 27446.725532744418, 27447.313817154733, 27504.862475687492, 28026.579601305588], "value": 19018.07928959332, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.4364636074799, 635.9976609204566, 640.6933182044936, 645.5157258618318, 649.3121442125238, 22885.656715149285, 23757.287281381545, 23774.643380349287, 23788.16741414051, 23887.522249554997, 25291.0, 25354.619680704804, 25406.9837206512, 25572.74427255727, 25586.488270234586, 6293.181886354768, 6449.580777249487, 6486.708098135595, 6513.609183448996, 6532.150820393343, 6486.643234622094, 6589.0, 6591.604503729767, 6606.669666516679, 6624.56940298879, 6559.934400656004, 6573.901391479131, 6588.275289718127, 6602.240742314623, 6841.144856892888, 6786.762463313785, 6820.840457122301, 6833.521653484258, 6844.041834143224, 6859.657017149123, 6393.2328120625825, 6395.488360931115, 6404.551681382305, 6422.454091402236, 6423.0, 29404.11787646374, 30272.0, 30311.848440757876, 30357.0, 30434.695653043407, 2274.636058230675, 2307.573098976678, 2311.4683622766856, 2317.5596636638984, 2335.486193037529, 20468.283610073577, 20594.176232950664, 20879.582408351747, 20916.163353465843, 21003.684944725843, 2477.6159695247266, 2521.0798058708624, 2553.438243586427, 2593.390553219992, 2611.777998870098, 140673.0, 144432.27783861014, 156198.0, 164265.0, 164430.17784911118, 16303.0, 16454.259558319904, 16574.502764917073, 16765.664686706197, 17329.306827727003, 26963.1911042669, 27008.729912701016, 27043.0, 27110.728892711213, 27177.72822271752, 10722.892771072346, 10850.348979061124, 10874.238803283693, 10890.455477226265, 10910.781784364426, 9923.255755818342, 9926.106650401498, 9970.700878973643, 10100.141487973458, 10156.796864062677, 9746.561404736804, 10076.74808129788, 10089.646862359783, 10274.383536987805, 10350.741231469123, 2921.751651109637, 3024.879004839782, 3036.9544456832723, 3060.4032213718556, 3171.44499712554, 1026.5534492495788, 1028.4240825137947, 1040.6562302576326, 1049.632628579991, 1058.751193469534, 20036.799632003786, 20764.0, 20813.58372832565, 21005.47486312824, 21270.787292127192, 1102.72431892027, 1107.5182295701395, 1108.0692218536467, 1116.7375666718315, 1118.9440527973568, 11216.93439123282, 11475.885241147482, 11511.48198331077, 11522.942385288019, 11541.134414918746, 568190.0, 582239.0, 583882.0, 601772.0, 619659.0, 133207.0, 138312.0, 138338.0, 139043.0, 139318.30340848232, 1136.3015026419212, 1226.5645695778048, 1263.620913725897, 1293.6377814212035, 1295.5489851366342, 21422.464438389165, 21520.031598578415, 21902.78097219071, 21984.89007554952, 22446.214382496535, 61729.0, 62569.0, 62748.68625656843, 63803.68098159479, 64665.676671618225, 76692.0, 77606.6119669398, 77838.61080694561, 80984.59507702659, 82843.0, 21007.0, 21227.46931326729, 21307.78692213058, 21599.244026458997, 21621.567568648225, 24006.51986960286, 24114.51770964606, 24164.87917560401, 24180.0, 24316.392090197885, 19293.324733634254, 19624.90187549053, 19715.40853774389, 19848.10683519274, 19935.302264420676, 16216.918915405347, 16526.256318465938, 16701.0, 17671.29314827444, 17959.730604040687, 19262.903685481484, 19510.512237193616, 19734.90132549328, 20079.196832126552, 20331.491712707302, 21170.788292117497, 21224.575508490052, 21315.78684213138, 21434.0, 21519.78480215178, 19289.710654340477, 19342.129604167563, 19437.4168774937, 19475.0, 19593.31423400174], "value": 13120.468421212281, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.3897315694514, 1460.788185713071, 1478.6599082211085, 1486.6283429142713, 1494.8355680875102, 16293.592660183487, 17029.489115326545, 17052.658946821055, 17063.232154553043, 17069.65860682789, 16627.251773670185, 16833.66332673346, 16888.408905688302, 16914.154292285366, 16930.40743573975, 2879.5680647902877, 2888.638920134978, 2941.3676059647173, 2942.043835753378, 2943.3524624582556, 2839.148255523345, 2850.8574571271456, 2879.3089658481917, 2888.205743420566, 2888.494513460136, 2784.874680639376, 2853.571964205365, 2873.209867286492, 2882.1065469704354, 2883.8558072096416, 2828.109145619134, 2835.1211124551455, 2861.370498490329, 2862.871170797309, 2864.455753406846, 2822.3085344090623, 2830.2924268932766, 2848.2594525423333, 2848.3875966667265, 2849.2307077089276, 16473.0, 16847.57881052971, 16852.747208791756, 16859.325626974907, 16893.15534223284, 8444.451110677848, 8636.61135248909, 8699.13008699125, 8713.0, 8715.346349023785, 5013.6490445668815, 5289.100852855024, 5345.919811202835, 5454.427285135083, 5462.126059830448, 2147.355793262015, 2203.754878493646, 2212.657038159072, 2244.5735310290993, 2261.106862789189, 30617.0, 30671.079867603785, 30817.999999999774, 30981.535276970862, 31047.0, 7335.156457007406, 7461.85076298471, 7497.850042999109, 7515.624218789038, 7600.1639819619795, 19395.612087758447, 19894.801051989296, 20001.0, 20218.29235976763, 20270.59458810845, 8330.0, 8494.362922780816, 8533.274671652855, 8539.103394143649, 8548.615312310962, 7684.769456916301, 7740.806479837936, 7846.0, 7875.803104922423, 7887.9605601971625, 5809.506191973645, 5972.193753843262, 5997.730102145414, 6012.158297838306, 6028.2766068072115, 1762.5946032412617, 1764.1796564597523, 1798.8201179881908, 1802.2340505285201, 1838.960987042317, 895.8924929008431, 947.6541062512133, 948.2793077261331, 948.3266880514774, 960.9279304052084, 7996.440249182618, 8026.518408895485, 8116.0, 8121.106678265386, 8158.592070396575, 761.8628646843621, 796.1640277708383, 796.9880451793227, 805.2430715127762, 819.2381085590339, 4817.253325734517, 4852.660313778002, 4892.241702536113, 5575.303087114111, 5700.8859822803315, 24147.63778543323, 25394.74605253961, 25621.48757024849, 25807.74192258091, 25855.870720646275, 24362.756372436404, 24867.378315542257, 24905.87547062253, 25125.74874251234, 25251.62122568163, 12658.987281017497, 12791.680207994688, 12866.935665321613, 12897.032722546035, 12997.220166789833, 8709.738707838775, 9213.170814626448, 9227.0, 9232.98437171924, 9417.340786144903, 20546.486337841674, 20714.896425517774, 20836.687449687965, 21140.154393824076, 21163.47091322729, 21641.242556510446, 21678.6748198774, 21707.891460542596, 21927.890360548095, 22358.329250122522, 12633.2420054797, 12868.549600763929, 12977.221366718028, 12986.220826750425, 12998.805017924928, 15512.457064002705, 15547.922260388626, 15560.144192069545, 15726.528204153894, 15744.842551574338, 12265.816012759995, 12523.0, 12724.109312348046, 12724.363781811058, 12825.935870320589, 11912.702182445508, 12240.020798335934, 12339.197952133083, 12489.0, 12492.500299988262, 10596.682099537025, 10821.783564328827, 10831.187660925305, 10834.729131771768, 10840.5121769519, 7546.943427920205, 7567.091948966159, 7608.733694320672, 7626.618669066635, 7819.41354398411, 8091.98850143732, 8168.754937351889, 8175.754727358189, 8205.95897020511, 8299.502029878227], "value": 7263.259075111748, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.4489427913506, 570.4238718893921, 585.3297973819977, 588.4997751910877, 591.1310373750586, 6235.345288744685, 6251.843703907399, 6271.592346497475, 6314.210723659543, 6387.393197646222, 6167.444929956303, 6184.783532576361, 6209.689515524217, 6294.0, 6325.620462772237, 5176.767045482962, 5187.6887386756725, 5197.662151960129, 5217.843464696046, 5228.058949389108, 5097.209932460474, 5192.065428222918, 5204.817831375903, 5223.582113430916, 5230.0, 4896.094222568836, 5002.12462819007, 5041.445441001487, 5056.544910958013, 5079.492050794909, 4979.10376132296, 5021.57316628087, 5035.244713292998, 5071.061853557067, 5078.085944529983, 4522.344260082303, 4570.9314360284625, 4605.102005109005, 4607.709841244457, 4608.976955115203, 6117.480014198798, 6129.080637904305, 6134.4172303631085, 6139.693015349215, 6162.969185154046, 5959.7020148992815, 5968.612040217372, 5969.224000879901, 5979.760809567656, 5982.282126144891, 6586.242582103072, 6626.801195964081, 6645.867082658368, 6663.300353462865, 6677.265500794909, 5161.974190129063, 5170.095233334189, 5179.663321884103, 5179.792808287664, 5185.818496352609, 6757.459403247728, 6788.660566971681, 6921.342472465108, 6952.860942781167, 7001.859962800715, 5209.223313098049, 5252.474752524717, 5347.3048503695045, 5352.357717073976, 5386.8383948481605, 5401.5948803839865, 5410.188471729193, 5430.15832545956, 5430.701311427831, 5437.32033495813, 5958.821235362946, 5982.786369043395, 5994.130851026577, 6023.548233882477, 6025.216721826135, 6677.499187560855, 6679.732810687616, 6762.594244345355, 6823.965880170567, 6834.350736680008, 1804.4857215693464, 1836.8989705566055, 1848.5655870870335, 1859.1819599376502, 1860.6557786809212, 1311.1608570514877, 1337.0840973932886, 1356.453642847155, 1364.5087768403366, 1366.091549119824, 6153.323134455206, 6181.990852972802, 6236.126942228092, 6264.248290205204, 6272.435480806749, 6659.733610655519, 6675.966620166965, 6711.637435666758, 6712.261651218362, 6768.898466523006, 6224.408681175281, 6266.561340706106, 6284.402981716729, 6310.968445157837, 6329.240491141, 3517.964820351815, 3533.561842328785, 3568.2149927016017, 3600.8199590020395, 3612.9819350903604, 6955.6869940851775, 7067.476814838507, 7092.255313192144, 7147.606881621561, 7176.497645164893, 7068.293170682993, 7095.964520177469, 7116.750913717995, 7122.92877071233, 7124.251953544906, 7083.68123434457, 7108.644567771591, 7117.1459424869345, 7169.749058783022, 7212.71149154028, 6600.174978127734, 6775.864482710417, 6786.694598743167, 6840.521163518506, 6849.6230459089375, 6686.598804071771, 6728.966355168192, 6753.628550429577, 6774.525783195129, 6798.55809372409, 7029.226785053742, 7051.576905385693, 7169.211386747349, 7169.820754481179, 7187.957746126887, 5286.762095705625, 5382.892342153056, 5384.300040994645, 5386.057439947915, 5420.349558053059, 6673.366030227121, 6698.062271281927, 6780.1524809398825, 6893.620850853251, 6909.44724422035, 6384.712687929147, 6564.702354176, 6587.736490540325, 6618.90071648916, 6663.46692264608, 6498.285188629342, 6526.771562995273, 6583.407493325623, 6606.174228221473, 6623.238327592196, 6419.390157934989, 6493.935060649333, 6498.935010650022, 6519.3480651936325, 6544.44372228366, 6638.634875081917, 6643.169603799525, 6677.565958212701, 6731.427828634621, 6733.494987876027, 6416.294207637249, 6435.22777266731, 6455.2576453708425, 6465.15743013239, 6506.60960342381], "value": 5253.004295184184, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [981.3817295104084, 995.0397418275264, 1002.9548670309836, 1010.7574182196271, 1021.8824835143961, 20190.99045047747, 20701.378958631227, 20711.689324660143, 20731.481712957182, 20787.16851325949, 17816.376426825063, 18993.145308461117, 19101.713474297896, 19166.904165479154, 19279.614407711837, 1065.3288428290186, 1099.2415233488907, 1099.3623698254994, 1100.1803656276086, 1102.3716481605488, 1071.223363061778, 1081.118888106191, 1084.728817795551, 1090.2858627598935, 1107.8061339265637, 1161.6979585307838, 1174.8531433570804, 1177.0936378988174, 1177.1171621284036, 1178.7996040673106, 1152.164680606562, 1152.95964641238, 1154.2439701995206, 1155.895969362753, 1166.9066474682047, 799.1449149410133, 814.9511029338199, 819.0294501016256, 821.4291067707945, 823.4236034775681, 20576.0, 20637.0, 20822.791772082237, 20937.79062209389, 20992.790072099237, 21889.233876814236, 22328.0, 22661.43346416353, 22815.77184228153, 22950.77049229503, 18346.26614935401, 18543.165557549943, 18634.25462981479, 18786.62426751471, 18807.435776926715, 3904.4533765272886, 3914.862979795722, 3945.546262179829, 3947.072437977102, 3948.861789837342, 166866.1656691721, 167168.0, 167626.0, 167879.99999999878, 170034.0, 17286.13569321529, 17306.48080557585, 17361.7395739064, 17367.56581085483, 17401.65196696059, 34552.308953820786, 34884.651153488645, 34900.47649285263, 34958.12604684903, 35325.0, 8513.021002584659, 8556.957215214008, 8575.571221439028, 8582.65669373218, 8591.871121933176, 4627.167109920247, 4629.490756016836, 4703.223968045234, 4703.506131856174, 4719.787609557578, 6028.728707208186, 6110.602810817282, 6144.201253836973, 6164.198654175018, 6190.164327815777, 1042.5777560088177, 1065.658989123473, 1071.5713714514102, 1086.8206745886998, 1089.716673664837, 627.7991042866239, 642.1780121444558, 648.3743187823773, 654.9148610680581, 663.290279401046, 11933.343666098448, 12871.678208044874, 12882.871171288354, 13510.662233444045, 13581.86418135806, 738.2322384719938, 738.7119023580808, 740.4335683202354, 747.5029105644794, 749.2657195948002, 5854.619449735754, 5882.294124705063, 5915.911261331084, 5957.434043765836, 5963.403659634002, 151451.0, 152501.0, 154862.0, 154973.0, 156391.0, 132261.0, 132929.0, 136488.99999999802, 137598.0, 145762.0, 114637.0, 125310.37344813399, 125665.37167314287, 126285.0, 127111.99999999815, 14403.495877644651, 14419.711605767616, 14538.127712337293, 14624.926875365554, 14650.267486625413, 57771.71114144402, 58154.7092264536, 58437.4156258432, 58486.7075664636, 59545.0, 75589.0, 77824.22175778169, 78178.0, 78568.60715696384, 79450.6027469859, 20784.064717087465, 20887.686684699438, 20939.0, 21173.576528469654, 21179.152833886474, 19054.809451905305, 19303.0, 19581.118849651513, 19685.901570492057, 19938.202471901543, 21602.675959860928, 21677.566448670623, 21923.561528769653, 22039.228626997978, 22101.66847497257, 20449.0, 20916.47708807292, 20998.47503812417, 21143.365699029055, 21155.365339039854, 20529.58940821205, 20878.0, 20905.581888362452, 21110.472238193553, 21114.366569002956, 14298.28508574588, 14346.713065738835, 14351.712965740835, 14476.348564314841, 14671.70656586841, 14887.255637218312, 14954.0, 15042.548723538312, 15162.0, 15316.310766015333], "value": 10266.713034225239, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1361.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 104.0, 106.0, 105.0, 107.0, 107.0, 106.0, 106.0, 105.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 112.0, 112.0, 119.0, 113.0, 112.0, 116.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 162.0, 163.0, 161.0, 161.0, 162.0, 163.0, 163.0, 166.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 74.0, 83.0, 73.0, 73.0, 73.0, 73.0, 74.0, 75.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 80.0, 78.0, 76.0, 77.0, 77.0, 77.0, 78.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 116.0, 110.0, 113.0, 114.0, 115.0, 114.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 155.0, 150.0, 150.0, 151.0, 149.0, 149.0, 149.0, 152.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 93.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 43.0, 43.0, 47.0, 43.0, 43.0, 43.0, 43.0, 43.0], "value": 43.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 41.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 68.0, 82.0, 67.0, 69.0, 68.0, 69.0, 69.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 102.0, 102.0, 103.0, 108.0, 104.0, 109.0, 107.0, 104.0, 108.0], "value": 105.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 146.0, 152.0, 150.0, 151.0, 149.0, 148.0, 146.0, 154.0, 150.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 66.0, 71.0, 60.0, 60.0, 55.0, 61.0, 59.0, 57.0], "value": 60.8, "unit": "score"}]}]} 12:41:22 INFO - Return code: 0 12:41:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:41:22 INFO - # TBPL SUCCESS # 12:41:22 INFO - Running post-action listener: _package_coverage_data 12:41:22 INFO - Running post-action listener: _resource_record_post_action 12:41:22 INFO - [mozharness: 2017-10-03 19:41:22.268356Z] Finished run-tests step (success) 12:41:22 INFO - Running post-run listener: _resource_record_post_run 12:41:22 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327184384; Read time: 12; Write time: 169588 12:41:22 INFO - TinderboxPrint: CPU usage
13.1% 12:41:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:41:22 INFO - TinderboxPrint: I/O write bytes / time
327,184,384 / 169,588 12:41:22 INFO - TinderboxPrint: CPU idle
8,212.2 (86.9%) 12:41:22 INFO - TinderboxPrint: CPU user
1,186.8 (12.6%) 12:41:22 INFO - TinderboxPrint: Swap in / out
0 / 0 12:41:22 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10534912; Read time: 0; Write time: 1544 12:41:22 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:41:22 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 4096; Write bytes: 314077184; Read time: 12; Write time: 167948 12:41:22 INFO - Running post-run listener: _upload_blobber_files 12:41:22 INFO - Blob upload gear active. 12:41:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:41:22 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 12:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:41:23 INFO - (blobuploader) - INFO - Open directory for files ... 12:41:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:41:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:41:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:24 INFO - (blobuploader) - INFO - Done attempting. 12:41:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:41:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:41:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:31 INFO - (blobuploader) - INFO - Done attempting. 12:41:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:41:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:41:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:32 INFO - (blobuploader) - INFO - Done attempting. 12:41:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:41:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:41:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:33 INFO - (blobuploader) - INFO - Done attempting. 12:41:33 INFO - (blobuploader) - INFO - Iteration through files over. 12:41:33 INFO - Return code: 0 12:41:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:41:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:41:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38127eb3e4bf1601e12e4cc2c6ad69e06e14c625fd6715f794651ec149a24b862156f005d4a63ae532f44ae3f3a2056a8f17ab441ac36419bcbf6939a3491ec9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56eca7cdf4c928710b4596693a5b1fc0fcc2c6b722726d0ab3e634e2ff4a6ecb61292634b94ea2e654a847392c278d2269703ae0b29c4f528b8edf74805b56d1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fbe3fadd47bbb8338e44c56256b25c4b45409278ec3e5831c41cf4bff93fc625a88cbd4e27a33da965ee407e6146bb722a9c21bd5430ce8ae4b2b0dc7dff32b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc031b4d0f2cc68c1eff7f5eda04532fbc22238fe39b859c4dcb8d1cb8d7d601729e7dd0b2a5da159086c7a4fe4ee82130a98972bd59edb480d7b90d43d0d545"} 12:41:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:41:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:41:33 INFO - Contents: 12:41:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38127eb3e4bf1601e12e4cc2c6ad69e06e14c625fd6715f794651ec149a24b862156f005d4a63ae532f44ae3f3a2056a8f17ab441ac36419bcbf6939a3491ec9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56eca7cdf4c928710b4596693a5b1fc0fcc2c6b722726d0ab3e634e2ff4a6ecb61292634b94ea2e654a847392c278d2269703ae0b29c4f528b8edf74805b56d1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fbe3fadd47bbb8338e44c56256b25c4b45409278ec3e5831c41cf4bff93fc625a88cbd4e27a33da965ee407e6146bb722a9c21bd5430ce8ae4b2b0dc7dff32b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc031b4d0f2cc68c1eff7f5eda04532fbc22238fe39b859c4dcb8d1cb8d7d601729e7dd0b2a5da159086c7a4fe4ee82130a98972bd59edb480d7b90d43d0d545"} 12:41:33 INFO - Running post-run listener: copy_logs_to_upload_dir 12:41:33 INFO - Copying logs to upload dir... 12:41:33 INFO - mkdir: /builds/slave/test/build/upload/logs 12:41:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.420993 ========= master_lag: 0.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-10-03 12:41:34.188611) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 12:41:34.196326) ========= 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=41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38127eb3e4bf1601e12e4cc2c6ad69e06e14c625fd6715f794651ec149a24b862156f005d4a63ae532f44ae3f3a2056a8f17ab441ac36419bcbf6939a3491ec9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56eca7cdf4c928710b4596693a5b1fc0fcc2c6b722726d0ab3e634e2ff4a6ecb61292634b94ea2e654a847392c278d2269703ae0b29c4f528b8edf74805b56d1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fbe3fadd47bbb8338e44c56256b25c4b45409278ec3e5831c41cf4bff93fc625a88cbd4e27a33da965ee407e6146bb722a9c21bd5430ce8ae4b2b0dc7dff32b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc031b4d0f2cc68c1eff7f5eda04532fbc22238fe39b859c4dcb8d1cb8d7d601729e7dd0b2a5da159086c7a4fe4ee82130a98972bd59edb480d7b90d43d0d545"} build_url:https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011213 build_url: 'https://queue.taskcluster.net/v1/task/Dq8jydmSRL6tZ237UTvWrg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38127eb3e4bf1601e12e4cc2c6ad69e06e14c625fd6715f794651ec149a24b862156f005d4a63ae532f44ae3f3a2056a8f17ab441ac36419bcbf6939a3491ec9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56eca7cdf4c928710b4596693a5b1fc0fcc2c6b722726d0ab3e634e2ff4a6ecb61292634b94ea2e654a847392c278d2269703ae0b29c4f528b8edf74805b56d1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fbe3fadd47bbb8338e44c56256b25c4b45409278ec3e5831c41cf4bff93fc625a88cbd4e27a33da965ee407e6146bb722a9c21bd5430ce8ae4b2b0dc7dff32b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc031b4d0f2cc68c1eff7f5eda04532fbc22238fe39b859c4dcb8d1cb8d7d601729e7dd0b2a5da159086c7a4fe4ee82130a98972bd59edb480d7b90d43d0d545"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 12:41:34.227836) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:41:34.228174) ========= 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=41a7e32711b8e08598a63237000001d6-1507058457.392387-1079795851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:41:34.277677) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 12:41:34.278082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 12:41:34.278359) ========= ========= Total master_lag: 0.29 =========