builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-083 starttime: 1506764965.9 results: success (0) revision: 59e5ec5729db9ee88c1b469f3ae0092fcedc8c44 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:25.896960) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:25.897365) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:25.897655) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:25.941430) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:25.941690) ========= 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=3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-30 02:49:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2017-09-30 02:49:26 (228 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.179338 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:26.191280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:26.191553) ========= 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=3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017272 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:26.244283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:26.244585) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 59e5ec5729db9ee88c1b469f3ae0092fcedc8c44 --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 59e5ec5729db9ee88c1b469f3ae0092fcedc8c44 --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=3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-30 02:49:26,220 truncating revision to first 12 chars 2017-09-30 02:49:26,220 Setting DEBUG logging. 2017-09-30 02:49:26,220 attempt 1/10 2017-09-30 02:49:26,220 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/59e5ec5729db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-30 02:49:26,579 unpacking tar archive at: mozilla-central-59e5ec5729db/testing/mozharness/ program finished with exit code 0 elapsedTime=0.724226 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:27.001702) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:27.001981) ========= script_repo_revision: 59e5ec5729db9ee88c1b469f3ae0092fcedc8c44 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:27.002326) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:27.002594) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 02:49:27.028766) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-30 02:49:27.029036) ========= /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=3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:49:27 INFO - MultiFileLogger online at 20170930 02:49:27 in /builds/slave/test 02:49:27 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 02:49:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:49:27 INFO - {'append_to_log': False, 02:49:27 INFO - 'base_work_dir': '/builds/slave/test', 02:49:27 INFO - 'blob_upload_branch': 'Firefox', 02:49:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:49:27 INFO - 'branch': 'Firefox', 02:49:27 INFO - 'buildbot_json_path': 'buildprops.json', 02:49:27 INFO - 'code_coverage': False, 02:49:27 INFO - 'config_files': ('talos/linux_config.py',), 02:49:27 INFO - 'default_actions': ('clobber', 02:49:27 INFO - 'read-buildbot-config', 02:49:27 INFO - 'download-and-extract', 02:49:27 INFO - 'populate-webroot', 02:49:27 INFO - 'create-virtualenv', 02:49:27 INFO - 'install', 02:49:27 INFO - 'setup-mitmproxy', 02:49:27 INFO - 'run-tests'), 02:49:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:49:27 INFO - 'disable_ccov_upload': False, 02:49:27 INFO - 'disable_stylo': False, 02:49:27 INFO - 'download_minidump_stackwalk': True, 02:49:27 INFO - 'download_symbols': 'ondemand', 02:49:27 INFO - 'enable_stylo': False, 02:49:27 INFO - 'enable_webrender': False, 02:49:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:49:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:49:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:49:27 INFO - 'gecko_profile': False, 02:49:27 INFO - 'gecko_profile_interval': 0, 02:49:27 INFO - 'installer_path': 'installer.exe', 02:49:27 INFO - 'log_level': 'info', 02:49:27 INFO - 'log_name': 'talos', 02:49:27 INFO - 'log_to_console': True, 02:49:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:49:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:49:27 INFO - 'opt_config_files': (), 02:49:27 INFO - 'pip_index': False, 02:49:27 INFO - 'suite': 'dromaeojs-e10s', 02:49:27 INFO - 'system_bits': '32', 02:49:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:49:27 INFO - 'title': 'talos-linux64-ix-083', 02:49:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:49:27 INFO - 'use_talos_json': True, 02:49:27 INFO - 'verify': 'False', 02:49:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:49:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:49:27 INFO - 'work_dir': 'build'} 02:49:27 INFO - [mozharness: 2017-09-30 09:49:27.226888Z] Running clobber step. 02:49:27 INFO - Running pre-action listener: _resource_record_pre_action 02:49:27 INFO - Running main action method: clobber 02:49:27 INFO - rmtree: /builds/slave/test/build 02:49:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:49:28 INFO - Running post-action listener: _resource_record_post_action 02:49:28 INFO - [mozharness: 2017-09-30 09:49:28.190160Z] Finished clobber step (success) 02:49:28 INFO - [mozharness: 2017-09-30 09:49:28.190257Z] Running read-buildbot-config step. 02:49:28 INFO - Running pre-action listener: _resource_record_pre_action 02:49:28 INFO - Running main action method: read_buildbot_config 02:49:28 INFO - Using buildbot properties: 02:49:28 INFO - { 02:49:28 INFO - "project": "", 02:49:28 INFO - "product": "firefox", 02:49:28 INFO - "who": "archaeopteryx@coole-files.de", 02:49:28 INFO - "installer_path": "public/build/target.tar.bz2", 02:49:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:49:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:49:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 02:49:28 INFO - "stage_platform": "linux64-pgo", 02:49:28 INFO - "basedir": "/builds/slave/test", 02:49:28 INFO - "buildnumber": 222, 02:49:28 INFO - "platform": "ubuntu64_hw", 02:49:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:49:28 INFO - "slavebuilddir": "test", 02:49:28 INFO - "taskId": "VYlxurVZTUiFA1_W03U_3w", 02:49:28 INFO - "branch": "mozilla-central", 02:49:28 INFO - "script_repo_revision": "production", 02:49:28 INFO - "revision": "59e5ec5729db9ee88c1b469f3ae0092fcedc8c44", 02:49:28 INFO - "slavename": "talos-linux64-ix-083", 02:49:28 INFO - "repo_path": "mozilla-central" 02:49:28 INFO - } 02:49:28 INFO - Finding installer, test and symbols from parent task. 02:49:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYlxurVZTUiFA1_W03U_3w'}, attempt #1 02:49:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYlxurVZTUiFA1_W03U_3w'}, attempt #1 02:49:29 INFO - Task dependencies: JRZ__e4oQ--coc8ZDHOmCQ 02:49:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ'}, attempt #1 02:49:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.tar.bz2 02:49:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.test_packages.json 02:49:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.crashreporter-symbols.zip 02:49:29 INFO - Running post-action listener: _resource_record_post_action 02:49:29 INFO - [mozharness: 2017-09-30 09:49:29.615077Z] Finished read-buildbot-config step (success) 02:49:29 INFO - [mozharness: 2017-09-30 09:49:29.615294Z] Running download-and-extract step. 02:49:29 INFO - Running pre-action listener: _resource_record_pre_action 02:49:29 INFO - Running main action method: download_and_extract 02:49:29 INFO - mkdir: /builds/slave/test/build/tests 02:49:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:29 INFO - trying https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.test_packages.json 02:49:29 INFO - Downloading https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:49:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:49:30 INFO - Downloaded 989 bytes. 02:49:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:49:30 INFO - Using the following test package requirements: 02:49:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:49:30 INFO - u'common': [u'target.common.tests.zip'], 02:49:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:49:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:49:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:49:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:49:30 INFO - u'mozbase': [u'target.common.tests.zip'], 02:49:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:49:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:49:30 INFO - u'web-platform': [u'target.common.tests.zip', 02:49:30 INFO - u'target.web-platform.tests.tar.gz'], 02:49:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:49:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 02:49:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.common.tests.zip 02:49:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:49:30 INFO - Fetch https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.common.tests.zip into memory 02:49:31 INFO - Content-Length response header: 55913716 02:49:31 INFO - Bytes received: 55913716 02:49:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.talos.tests.zip 02:49:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:49:42 INFO - Fetch https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.talos.tests.zip into memory 02:49:43 INFO - Content-Length response header: 14522385 02:49:43 INFO - Bytes received: 14522385 02:49:43 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')]} 02:49:43 INFO - trying https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.tar.bz2 02:49:43 INFO - Downloading https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:49:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:49:44 INFO - Downloaded 62445612 bytes. 02:49:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.tar.bz2 02:49:44 INFO - mkdir: /builds/slave/test/properties 02:49:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:49:44 INFO - Writing to file /builds/slave/test/properties/build_url 02:49:44 INFO - Contents: 02:49:44 INFO - build_url:https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.tar.bz2 02:49:44 INFO - Running post-action listener: _resource_record_post_action 02:49:44 INFO - Running post-action listener: find_tests_for_verification 02:49:44 INFO - Running post-action listener: set_extra_try_arguments 02:49:44 INFO - [mozharness: 2017-09-30 09:49:44.908423Z] Finished download-and-extract step (success) 02:49:44 INFO - [mozharness: 2017-09-30 09:49:44.908548Z] Running populate-webroot step. 02:49:44 INFO - Running pre-action listener: _resource_record_pre_action 02:49:44 INFO - Running main action method: populate_webroot 02:49:44 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:49:44 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['tresize', 02:49:44 INFO - 'tcanvasmark']}, 02:49:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:49:44 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['dromaeo_css', 02:49:44 INFO - 'kraken']}, 02:49:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:49:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:49:44 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:49:44 INFO - 'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:49:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:49:44 INFO - 'tests': ['damp', 'tps']}, 02:49:44 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:49:44 INFO - 'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['damp', 'tps']}, 02:49:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:49:44 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['dromaeo_dom']}, 02:49:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:49:44 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['basic_compositor_video', 02:49:44 INFO - 'glvideo']}, 02:49:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:49:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:49:44 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:49:44 INFO - 'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['ts_paint_webext', 02:49:44 INFO - 'tp5o_webext']}, 02:49:44 INFO - 'other-e10s': {'tests': ['a11yr', 02:49:44 INFO - 'ts_paint', 02:49:44 INFO - 'tpaint', 02:49:44 INFO - 'sessionrestore', 02:49:44 INFO - 'sessionrestore_many_windows', 02:49:44 INFO - 'sessionrestore_no_auto_restore', 02:49:44 INFO - 'tabpaint', 02:49:44 INFO - 'cpstartup']}, 02:49:44 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['a11yr', 02:49:44 INFO - 'ts_paint', 02:49:44 INFO - 'tpaint', 02:49:44 INFO - 'sessionrestore', 02:49:44 INFO - 'sessionrestore_many_windows', 02:49:44 INFO - 'sessionrestore_no_auto_restore', 02:49:44 INFO - 'tabpaint', 02:49:44 INFO - 'cpstartup']}, 02:49:44 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 02:49:44 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 02:49:44 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['perf_reftest_singletons']}, 02:49:44 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['perf_reftest']}, 02:49:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:49:44 INFO - 'tsvgr_opacity', 02:49:44 INFO - 'tart', 02:49:44 INFO - 'tscrollx', 02:49:44 INFO - 'tsvg_static']}, 02:49:44 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['tsvgx', 02:49:44 INFO - 'tsvgr_opacity', 02:49:44 INFO - 'tart', 02:49:44 INFO - 'tscrollx', 02:49:44 INFO - 'tsvg_static']}, 02:49:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:49:44 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:49:44 INFO - 'talos_options': ['--disable-stylo'], 02:49:44 INFO - 'tests': ['tp5o']}, 02:49:44 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:49:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 02:49:44 INFO - 'talos_options': ['--mitmproxy', 02:49:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:49:44 INFO - '--firstNonBlankPaint'], 02:49:44 INFO - 'tests': ['tp6_google', 02:49:44 INFO - 'tp6_youtube', 02:49:44 INFO - 'tp6_amazon', 02:49:44 INFO - 'tp6_facebook']}, 02:49:44 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:49:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 02:49:44 INFO - 'talos_options': ['--disable-stylo', 02:49:44 INFO - '--mitmproxy', 02:49:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:49:44 INFO - '--firstNonBlankPaint'], 02:49:44 INFO - 'tests': ['tp6_google', 02:49:44 INFO - 'tp6_youtube', 02:49:44 INFO - 'tp6_amazon', 02:49:44 INFO - 'tp6_facebook']}, 02:49:44 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:49:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 02:49:44 INFO - 'talos_options': ['--stylo-threads=1', 02:49:44 INFO - '--mitmproxy', 02:49:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:49:44 INFO - '--firstNonBlankPaint'], 02:49:44 INFO - 'tests': ['tp6_google', 02:49:44 INFO - 'tp6_youtube', 02:49:44 INFO - 'tp6_amazon', 02:49:44 INFO - 'tp6_facebook']}, 02:49:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:49:44 INFO - 'talos_options': ['--xperf_path', 02:49:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:44 INFO - 'tests': ['tp5n']}, 02:49:44 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:49:44 INFO - 'talos_options': ['--disable-stylo', 02:49:44 INFO - '--xperf_path', 02:49:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:44 INFO - 'tests': ['tp5n']}}} 02:49:44 INFO - Running post-action listener: _resource_record_post_action 02:49:44 INFO - [mozharness: 2017-09-30 09:49:44.926869Z] Finished populate-webroot step (success) 02:49:44 INFO - [mozharness: 2017-09-30 09:49:44.926958Z] Running create-virtualenv step. 02:49:44 INFO - Running pre-action listener: _resource_record_pre_action 02:49:44 INFO - Running main action method: create_virtualenv 02:49:44 INFO - Creating virtualenv /builds/slave/test/build/venv 02:49:44 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:49:44 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:49:44 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:49:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:49:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:49:45 INFO - Installing distribute.............................................................................................................................................................................................done. 02:49:46 INFO - Installing pip...............done. 02:49:46 INFO - Return code: 0 02:49:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:49:46 INFO - Reading from file tmpfile_stdout 02:49:46 INFO - Output received: 02:49:46 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:49:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:49:46 INFO - Using env: {'DISPLAY': ':0', 02:49:46 INFO - 'HOME': '/home/cltbld', 02:49:46 INFO - 'LANG': 'en_US.UTF-8', 02:49:46 INFO - 'LANGUAGE': 'en_US:en', 02:49:46 INFO - 'LOGNAME': 'cltbld', 02:49:46 INFO - 'MAIL': '/var/mail/cltbld', 02:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:46 INFO - 'MOZ_NO_REMOTE': '1', 02:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:46 INFO - 'NO_EM_RESTART': '1', 02:49:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:46 INFO - 'PWD': '/builds/slave/test', 02:49:46 INFO - 'SHELL': '/bin/bash', 02:49:46 INFO - 'SHLVL': '1', 02:49:46 INFO - 'TERM': 'linux', 02:49:46 INFO - 'TMOUT': '86400', 02:49:46 INFO - 'USER': 'cltbld', 02:49:46 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:46 INFO - '_': '/tools/buildbot/bin/python'} 02:49:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:49:48 INFO - Downloading/unpacking pip>=1.5 02:49:48 INFO - Running setup.py egg_info for package pip 02:49:48 INFO - warning: no files found matching 'pip/cacert.pem' 02:49:48 INFO - warning: no files found matching '*.html' under directory 'docs' 02:49:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:49:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:49:48 INFO - Installing collected packages: pip 02:49:48 INFO - Found existing installation: pip 1.1 02:49:48 INFO - Uninstalling pip: 02:49:48 INFO - Successfully uninstalled pip 02:49:48 INFO - Running setup.py install for pip 02:49:48 INFO - warning: no files found matching 'pip/cacert.pem' 02:49:48 INFO - warning: no files found matching '*.html' under directory 'docs' 02:49:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:49:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:49:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:49:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:49:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:49:48 INFO - Successfully installed pip 02:49:48 INFO - Cleaning up... 02:49:48 INFO - Return code: 0 02:49:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:49:48 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')]} 02:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:48 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')]} 02:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:48 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:49:48 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 02:49:48 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 02:49:48 INFO - Using env: {'DISPLAY': ':0', 02:49:48 INFO - 'HOME': '/home/cltbld', 02:49:48 INFO - 'LANG': 'en_US.UTF-8', 02:49:48 INFO - 'LANGUAGE': 'en_US:en', 02:49:48 INFO - 'LOGNAME': 'cltbld', 02:49:48 INFO - 'MAIL': '/var/mail/cltbld', 02:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:48 INFO - 'MOZ_NO_REMOTE': '1', 02:49:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:48 INFO - 'NO_EM_RESTART': '1', 02:49:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:48 INFO - 'PWD': '/builds/slave/test', 02:49:48 INFO - 'SHELL': '/bin/bash', 02:49:48 INFO - 'SHLVL': '1', 02:49:48 INFO - 'TERM': 'linux', 02:49:48 INFO - 'TMOUT': '86400', 02:49:48 INFO - 'USER': 'cltbld', 02:49:48 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:48 INFO - '_': '/tools/buildbot/bin/python'} 02:49:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:48 INFO - Downloading/unpacking psutil>=3.1.1 02:49:48 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 02:49:48 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 02:49:48 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 02:49:48 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 02:49:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:49:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:50 INFO - Installing collected packages: psutil 02:49:50 INFO - Running setup.py install for psutil 02:49:50 INFO - building 'psutil._psutil_linux' extension 02:49:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:49:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:49:50 INFO - building 'psutil._psutil_posix' extension 02:49:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 02:49:51 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 02:49:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:51 INFO - Successfully installed psutil 02:49:51 INFO - Cleaning up... 02:49:51 INFO - Return code: 0 02:49:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:49:51 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')]} 02:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:51 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')]} 02:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:51 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:49:51 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 02:49:51 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 02:49:51 INFO - Using env: {'DISPLAY': ':0', 02:49:51 INFO - 'HOME': '/home/cltbld', 02:49:51 INFO - 'LANG': 'en_US.UTF-8', 02:49:51 INFO - 'LANGUAGE': 'en_US:en', 02:49:51 INFO - 'LOGNAME': 'cltbld', 02:49:51 INFO - 'MAIL': '/var/mail/cltbld', 02:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:51 INFO - 'MOZ_NO_REMOTE': '1', 02:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:51 INFO - 'NO_EM_RESTART': '1', 02:49:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:51 INFO - 'PWD': '/builds/slave/test', 02:49:51 INFO - 'SHELL': '/bin/bash', 02:49:51 INFO - 'SHLVL': '1', 02:49:51 INFO - 'TERM': 'linux', 02:49:51 INFO - 'TMOUT': '86400', 02:49:51 INFO - 'USER': 'cltbld', 02:49:51 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:51 INFO - '_': '/tools/buildbot/bin/python'} 02:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:49:51 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 02:49:51 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 02:49:51 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 02:49:51 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 02:49:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:49:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:49:52 INFO - Installing collected packages: mozsystemmonitor 02:49:52 INFO - Running setup.py install for mozsystemmonitor 02:49:52 INFO - Successfully installed mozsystemmonitor 02:49:52 INFO - Cleaning up... 02:49:52 INFO - Return code: 0 02:49:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:49:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:49:52 INFO - Using env: {'DISPLAY': ':0', 02:49:52 INFO - 'HOME': '/home/cltbld', 02:49:52 INFO - 'LANG': 'en_US.UTF-8', 02:49:52 INFO - 'LANGUAGE': 'en_US:en', 02:49:52 INFO - 'LOGNAME': 'cltbld', 02:49:52 INFO - 'MAIL': '/var/mail/cltbld', 02:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:52 INFO - 'MOZ_NO_REMOTE': '1', 02:49:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:52 INFO - 'NO_EM_RESTART': '1', 02:49:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:52 INFO - 'PWD': '/builds/slave/test', 02:49:52 INFO - 'SHELL': '/bin/bash', 02:49:52 INFO - 'SHLVL': '1', 02:49:52 INFO - 'TERM': 'linux', 02:49:52 INFO - 'TMOUT': '86400', 02:49:52 INFO - 'USER': 'cltbld', 02:49:52 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:52 INFO - '_': '/tools/buildbot/bin/python'} 02:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:52 INFO - Downloading/unpacking jsonschema==2.5.1 02:49:52 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 02:49:52 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 02:49:52 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 02:49:52 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 02:49:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:49:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:49:54 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 02:49:54 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 02:49:54 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 02:49:54 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 02:49:54 INFO - Downloading functools32-3.2.3-2.tar.gz 02:49:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:49:54 INFO - warning: no files found matching '*.txt' 02:49:54 INFO - no previously-included directories found matching 'build' 02:49:54 INFO - no previously-included directories found matching 'dist' 02:49:54 INFO - no previously-included directories found matching '.git*' 02:49:54 INFO - Installing collected packages: jsonschema, functools32 02:49:54 INFO - Running setup.py install for functools32 02:49:54 INFO - warning: no files found matching '*.txt' 02:49:54 INFO - no previously-included directories found matching 'build' 02:49:54 INFO - no previously-included directories found matching 'dist' 02:49:54 INFO - no previously-included directories found matching '.git*' 02:49:54 INFO - Successfully installed jsonschema functools32 02:49:54 INFO - Cleaning up... 02:49:54 INFO - Return code: 0 02:49:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:49: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')]} 02:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49: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')]} 02:49:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:54 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:49:54 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 02:49:54 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 02:49:54 INFO - Using env: {'DISPLAY': ':0', 02:49:54 INFO - 'HOME': '/home/cltbld', 02:49:54 INFO - 'LANG': 'en_US.UTF-8', 02:49:54 INFO - 'LANGUAGE': 'en_US:en', 02:49:54 INFO - 'LOGNAME': 'cltbld', 02:49:54 INFO - 'MAIL': '/var/mail/cltbld', 02:49:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:54 INFO - 'MOZ_NO_REMOTE': '1', 02:49:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:54 INFO - 'NO_EM_RESTART': '1', 02:49:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:54 INFO - 'PWD': '/builds/slave/test', 02:49:54 INFO - 'SHELL': '/bin/bash', 02:49:54 INFO - 'SHLVL': '1', 02:49:54 INFO - 'TERM': 'linux', 02:49:54 INFO - 'TMOUT': '86400', 02:49:54 INFO - 'USER': 'cltbld', 02:49:54 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:49:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:54 INFO - '_': '/tools/buildbot/bin/python'} 02:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:49:54 INFO - Cleaning up... 02:49:54 INFO - Return code: 0 02:49:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:49: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')]} 02:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49: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')]} 02:49:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:54 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:49:54 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 02:49:54 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 02:49:54 INFO - Using env: {'DISPLAY': ':0', 02:49:54 INFO - 'HOME': '/home/cltbld', 02:49:54 INFO - 'LANG': 'en_US.UTF-8', 02:49:54 INFO - 'LANGUAGE': 'en_US:en', 02:49:54 INFO - 'LOGNAME': 'cltbld', 02:49:54 INFO - 'MAIL': '/var/mail/cltbld', 02:49:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:54 INFO - 'MOZ_NO_REMOTE': '1', 02:49:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:54 INFO - 'NO_EM_RESTART': '1', 02:49:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:54 INFO - 'PWD': '/builds/slave/test', 02:49:54 INFO - 'SHELL': '/bin/bash', 02:49:54 INFO - 'SHLVL': '1', 02:49:54 INFO - 'TERM': 'linux', 02:49:54 INFO - 'TMOUT': '86400', 02:49:54 INFO - 'USER': 'cltbld', 02:49:54 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:49:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:54 INFO - '_': '/tools/buildbot/bin/python'} 02:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:54 INFO - Downloading/unpacking blobuploader==1.2.4 02:49:54 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 02:49:54 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 02:49:54 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 02:49:54 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 02:49:56 INFO - Downloading blobuploader-1.2.4.tar.gz 02:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:49:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:49:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:56 INFO - Downloading docopt-0.6.1.tar.gz 02:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:49:56 INFO - Installing collected packages: blobuploader, requests, docopt 02:49:56 INFO - Running setup.py install for blobuploader 02:49:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:49:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:49:56 INFO - Running setup.py install for requests 02:49:57 INFO - Running setup.py install for docopt 02:49:57 INFO - Successfully installed blobuploader requests docopt 02:49:57 INFO - Cleaning up... 02:49:57 INFO - Return code: 0 02:49:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:57 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:49:57 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 02:49:57 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 02:49:57 INFO - Using env: {'DISPLAY': ':0', 02:49:57 INFO - 'HOME': '/home/cltbld', 02:49:57 INFO - 'LANG': 'en_US.UTF-8', 02:49:57 INFO - 'LANGUAGE': 'en_US:en', 02:49:57 INFO - 'LOGNAME': 'cltbld', 02:49:57 INFO - 'MAIL': '/var/mail/cltbld', 02:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:57 INFO - 'MOZ_NO_REMOTE': '1', 02:49:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:57 INFO - 'NO_EM_RESTART': '1', 02:49:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:57 INFO - 'PWD': '/builds/slave/test', 02:49:57 INFO - 'SHELL': '/bin/bash', 02:49:57 INFO - 'SHLVL': '1', 02:49:57 INFO - 'TERM': 'linux', 02:49:57 INFO - 'TMOUT': '86400', 02:49:57 INFO - 'USER': 'cltbld', 02:49:57 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:57 INFO - '_': '/tools/buildbot/bin/python'} 02:49:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:49:57 INFO - Running setup.py (path:/tmp/pip-dQltZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:49:57 INFO - Running setup.py (path:/tmp/pip-1x83Ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:49:57 INFO - Running setup.py (path:/tmp/pip-jUBtaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:49:57 INFO - Running setup.py (path:/tmp/pip-BEGcyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:49:57 INFO - Running setup.py (path:/tmp/pip-VFVMVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:49:57 INFO - Running setup.py (path:/tmp/pip-8VMZco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:49:57 INFO - Running setup.py (path:/tmp/pip-3Wbn6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:49:57 INFO - Running setup.py (path:/tmp/pip-2jMm6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:49:58 INFO - Running setup.py (path:/tmp/pip-jWSJf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:49:58 INFO - Running setup.py (path:/tmp/pip-jbkf64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:49:58 INFO - Running setup.py (path:/tmp/pip-AQ13Ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:49:58 INFO - Running setup.py (path:/tmp/pip-IRw2Yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:49:58 INFO - Running setup.py (path:/tmp/pip-1cMcFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:49:58 INFO - Running setup.py (path:/tmp/pip-IWrEne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:58 INFO - Running setup.py (path:/tmp/pip-6gtYMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:49:58 INFO - Running setup.py (path:/tmp/pip-Qx8XwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:49:58 INFO - Running setup.py (path:/tmp/pip-t0DY2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:49:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:49:58 INFO - Running setup.py install for manifestparser 02:49:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:49:58 INFO - Running setup.py install for mozcrash 02:49:58 INFO - Running setup.py install for mozdebug 02:49:58 INFO - Running setup.py install for mozdevice 02:49:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Running setup.py install for mozfile 02:49:59 INFO - Running setup.py install for mozhttpd 02:49:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Running setup.py install for mozinfo 02:49:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Running setup.py install for mozInstall 02:49:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Running setup.py install for mozleak 02:49:59 INFO - Running setup.py install for mozlog 02:49:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Running setup.py install for moznetwork 02:49:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Running setup.py install for mozprocess 02:49:59 INFO - Running setup.py install for mozprofile 02:49:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:49:59 INFO - Running setup.py install for mozrunner 02:50:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:50:00 INFO - Running setup.py install for mozscreenshot 02:50:00 INFO - Running setup.py install for moztest 02:50:00 INFO - Running setup.py install for mozversion 02:50:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:50:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:50:00 INFO - Cleaning up... 02:50:00 INFO - Return code: 0 02:50:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:00 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:50:00 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 02:50:00 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 02:50:00 INFO - Using env: {'DISPLAY': ':0', 02:50:00 INFO - 'HOME': '/home/cltbld', 02:50:00 INFO - 'LANG': 'en_US.UTF-8', 02:50:00 INFO - 'LANGUAGE': 'en_US:en', 02:50:00 INFO - 'LOGNAME': 'cltbld', 02:50:00 INFO - 'MAIL': '/var/mail/cltbld', 02:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:00 INFO - 'MOZ_NO_REMOTE': '1', 02:50:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:50:00 INFO - 'NO_EM_RESTART': '1', 02:50:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:00 INFO - 'PWD': '/builds/slave/test', 02:50:00 INFO - 'SHELL': '/bin/bash', 02:50:00 INFO - 'SHLVL': '1', 02:50:00 INFO - 'TERM': 'linux', 02:50:00 INFO - 'TMOUT': '86400', 02:50:00 INFO - 'USER': 'cltbld', 02:50:00 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:00 INFO - '_': '/tools/buildbot/bin/python'} 02:50:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:50:00 INFO - Running setup.py (path:/tmp/pip-znEv6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:50:00 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)) 02:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:50:00 INFO - Running setup.py (path:/tmp/pip-yWZk_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:50:00 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)) 02:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:50:00 INFO - Running setup.py (path:/tmp/pip-mpYjgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:50:00 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)) 02:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:50:00 INFO - Running setup.py (path:/tmp/pip-1ix0bH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:50:00 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)) 02:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:50:00 INFO - Running setup.py (path:/tmp/pip-bJItEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:50:00 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)) 02:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:50:01 INFO - Running setup.py (path:/tmp/pip-vAB8D4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:50:01 INFO - Running setup.py (path:/tmp/pip-KdAkRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:50:01 INFO - Running setup.py (path:/tmp/pip-ZLcu_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:50:01 INFO - Running setup.py (path:/tmp/pip-qMT5F4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:50:01 INFO - Running setup.py (path:/tmp/pip-DwDbRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:50:01 INFO - Running setup.py (path:/tmp/pip-gjyEgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:50:01 INFO - Running setup.py (path:/tmp/pip-RWp09S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:50:01 INFO - Running setup.py (path:/tmp/pip-IgAK_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:50:01 INFO - Running setup.py (path:/tmp/pip-RIgPOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:01 INFO - Running setup.py (path:/tmp/pip-89qBPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:50:01 INFO - Running setup.py (path:/tmp/pip-3RUQwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:50:01 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)) 02:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:50:01 INFO - Running setup.py (path:/tmp/pip-TO4IOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:50:01 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)) 02:50:01 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)) 02:50:01 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)) 02:50:01 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)) 02:50:01 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)) 02:50:01 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)) 02:50:01 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)) 02:50:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:02 INFO - Downloading blessings-1.6.tar.gz 02:50:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:50:03 INFO - Installing collected packages: blessings 02:50:03 INFO - Running setup.py install for blessings 02:50:03 INFO - Successfully installed blessings 02:50:03 INFO - Cleaning up... 02:50:03 INFO - Return code: 0 02:50:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:50:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:03 INFO - Reading from file tmpfile_stdout 02:50:03 INFO - Current package versions: 02:50:03 INFO - argparse == 1.2.1 02:50:03 INFO - blessings == 1.6 02:50:03 INFO - blobuploader == 1.2.4 02:50:03 INFO - docopt == 0.6.1 02:50:03 INFO - functools32 == 3.2.3-2 02:50:03 INFO - jsonschema == 2.5.1 02:50:03 INFO - manifestparser == 1.1 02:50:03 INFO - mozInstall == 1.13 02:50:03 INFO - mozcrash == 1.0 02:50:03 INFO - mozdebug == 0.1 02:50:03 INFO - mozdevice == 0.51 02:50:03 INFO - mozfile == 1.2 02:50:03 INFO - mozhttpd == 0.7 02:50:03 INFO - mozinfo == 0.10 02:50:03 INFO - mozleak == 0.1 02:50:03 INFO - mozlog == 3.5 02:50:03 INFO - moznetwork == 0.27 02:50:03 INFO - mozprocess == 0.25 02:50:03 INFO - mozprofile == 0.28 02:50:03 INFO - mozrunner == 6.13 02:50:03 INFO - mozscreenshot == 0.1 02:50:03 INFO - mozsystemmonitor == 0.3 02:50:03 INFO - moztest == 0.8 02:50:03 INFO - mozversion == 1.4 02:50:03 INFO - psutil == 3.1.1 02:50:03 INFO - requests == 1.2.3 02:50:03 INFO - wsgiref == 0.1.2 02:50:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:03 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:50:03 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 02:50:03 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 02:50:03 INFO - Using env: {'DISPLAY': ':0', 02:50:03 INFO - 'HOME': '/home/cltbld', 02:50:03 INFO - 'LANG': 'en_US.UTF-8', 02:50:03 INFO - 'LANGUAGE': 'en_US:en', 02:50:03 INFO - 'LOGNAME': 'cltbld', 02:50:03 INFO - 'MAIL': '/var/mail/cltbld', 02:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:03 INFO - 'MOZ_NO_REMOTE': '1', 02:50:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:50:03 INFO - 'NO_EM_RESTART': '1', 02:50:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:03 INFO - 'PWD': '/builds/slave/test', 02:50:03 INFO - 'SHELL': '/bin/bash', 02:50:03 INFO - 'SHLVL': '1', 02:50:03 INFO - 'TERM': 'linux', 02:50:03 INFO - 'TMOUT': '86400', 02:50:03 INFO - 'USER': 'cltbld', 02:50:03 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:03 INFO - '_': '/tools/buildbot/bin/python'} 02:50:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:03 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)) 02:50:03 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)) 02:50:03 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)) 02:50:03 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)) 02:50:03 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)) 02:50:03 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)) 02:50:03 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)) 02:50:03 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)) 02:50:03 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)) 02:50:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:50:03 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 02:50:03 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 02:50:03 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 02:50:03 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 02:50:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:50:04 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)) 02:50:04 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)) 02:50:04 INFO - Installing collected packages: simplejson 02:50:04 INFO - Running setup.py install for simplejson 02:50:04 INFO - building 'simplejson._speedups' extension 02:50:04 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 02:50:05 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 02:50:05 INFO - Successfully installed simplejson 02:50:05 INFO - Cleaning up... 02:50:05 INFO - Return code: 0 02:50:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:05 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', '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 02:50:05 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 02:50:05 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 02:50:05 INFO - Using env: {'DISPLAY': ':0', 02:50:05 INFO - 'HOME': '/home/cltbld', 02:50:05 INFO - 'LANG': 'en_US.UTF-8', 02:50:05 INFO - 'LANGUAGE': 'en_US:en', 02:50:05 INFO - 'LOGNAME': 'cltbld', 02:50:05 INFO - 'MAIL': '/var/mail/cltbld', 02:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:05 INFO - 'MOZ_NO_REMOTE': '1', 02:50:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:50:05 INFO - 'NO_EM_RESTART': '1', 02:50:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:05 INFO - 'PWD': '/builds/slave/test', 02:50:05 INFO - 'SHELL': '/bin/bash', 02:50:05 INFO - 'SHLVL': '1', 02:50:05 INFO - 'TERM': 'linux', 02:50:05 INFO - 'TMOUT': '86400', 02:50:05 INFO - 'USER': 'cltbld', 02:50:05 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:05 INFO - '_': '/tools/buildbot/bin/python'} 02:50:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:50:06 INFO - Cleaning up... 02:50:06 INFO - Return code: 0 02:50:06 INFO - Running post-action listener: _resource_record_post_action 02:50:06 INFO - Running post-action listener: _start_resource_monitoring 02:50:06 INFO - Starting resource monitoring. 02:50:06 INFO - [mozharness: 2017-09-30 09:50:06.199301Z] Finished create-virtualenv step (success) 02:50:06 INFO - [mozharness: 2017-09-30 09:50:06.200309Z] Running install step. 02:50:06 INFO - Running pre-action listener: _resource_record_pre_action 02:50:06 INFO - Running main action method: install 02:50:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:06 INFO - Reading from file tmpfile_stdout 02:50:06 INFO - Detecting whether we're running mozinstall >=1.0... 02:50:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:50:06 INFO - Reading from file tmpfile_stdout 02:50:06 INFO - Output received: 02:50:06 INFO - Usage: mozinstall [options] installer 02:50:06 INFO - Options: 02:50:06 INFO - -h, --help show this help message and exit 02:50:06 INFO - -d DEST, --destination=DEST 02:50:06 INFO - Directory to install application into. [default: 02:50:06 INFO - "/builds/slave/test"] 02:50:06 INFO - --app=APP Application being installed. [default: firefox] 02:50:06 INFO - mkdir: /builds/slave/test/build/application 02:50:06 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'] 02:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:50:24 INFO - Reading from file tmpfile_stdout 02:50:24 INFO - Output received: 02:50:24 INFO - /builds/slave/test/build/application/firefox/firefox 02:50:24 INFO - Running post-action listener: _resource_record_post_action 02:50:24 INFO - [mozharness: 2017-09-30 09:50:24.315182Z] Finished install step (success) 02:50:24 INFO - [mozharness: 2017-09-30 09:50:24.315327Z] Running setup-mitmproxy step. 02:50:24 INFO - Running pre-action listener: _resource_record_pre_action 02:50:24 INFO - Running main action method: setup_mitmproxy 02:50:24 INFO - Skipping: mitmproxy is not required 02:50:24 INFO - Running post-action listener: _resource_record_post_action 02:50:24 INFO - [mozharness: 2017-09-30 09:50:24.316361Z] Finished setup-mitmproxy step (success) 02:50:24 INFO - [mozharness: 2017-09-30 09:50:24.316597Z] Running run-tests step. 02:50:24 INFO - Running pre-action listener: _resource_record_pre_action 02:50:24 INFO - Running pre-action listener: _set_gcov_prefix 02:50:24 INFO - Running main action method: run_tests 02:50:24 WARNING - Try message not found. 02:50:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:50:24 INFO - Python 2.7.3 02:50:24 INFO - Return code: 0 02:50:24 INFO - grabbing minidump binary from tooltool 02:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:24 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 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:50:24 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 02:50:24 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 02:50:24 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 02:50:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:50:24 INFO - Return code: 0 02:50:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:50:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:50:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:50:24 INFO - ENV: RUST_BACKTRACE is now full 02:50:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:50:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:50:24 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/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--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 02:50:24 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/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-083 --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 02:50:24 INFO - Using env: {'DISPLAY': ':0', 02:50:24 INFO - 'HOME': '/home/cltbld', 02:50:24 INFO - 'LANG': 'en_US.UTF-8', 02:50:24 INFO - 'LANGUAGE': 'en_US:en', 02:50:24 INFO - 'LOGNAME': 'cltbld', 02:50:24 INFO - 'MAIL': '/var/mail/cltbld', 02:50:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:50:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:24 INFO - 'MOZ_NO_REMOTE': '1', 02:50:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:50:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:50:24 INFO - 'NO_EM_RESTART': '1', 02:50:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:24 INFO - 'PWD': '/builds/slave/test', 02:50:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:50:24 INFO - 'RUST_BACKTRACE': 'full', 02:50:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:50:24 INFO - 'SHELL': '/bin/bash', 02:50:24 INFO - 'SHLVL': '1', 02:50:24 INFO - 'TERM': 'linux', 02:50:24 INFO - 'TMOUT': '86400', 02:50:24 INFO - 'USER': 'cltbld', 02:50:24 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:24 INFO - '_': '/tools/buildbot/bin/python'} 02:50:24 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/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--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 02:50:24 INFO - mozversion application_buildid: 20170930082026 02:50:24 INFO - mozversion application_changeset: 59e5ec5729db9ee88c1b469f3ae0092fcedc8c44 02:50:24 INFO - mozversion application_display_name: Nightly 02:50:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:50:24 INFO - mozversion application_name: Firefox 02:50:24 INFO - mozversion application_remotingname: firefox 02:50:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:50:24 INFO - mozversion application_vendor: Mozilla 02:50:24 INFO - mozversion application_version: 58.0a1 02:50:24 INFO - mozversion platform_buildid: 20170930082026 02:50:24 INFO - mozversion platform_changeset: 59e5ec5729db9ee88c1b469f3ae0092fcedc8c44 02:50:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:50:24 INFO - mozversion platform_version: 58.0a1 02:50:24 INFO - using testdate: 1506765024 02:50:24 INFO - actual date: 1506765024 02:50:24 INFO - starting webserver on 'localhost:49843' 02:50:24 INFO - SUITE-START | Running 2 tests 02:50:24 INFO - TEST-START | dromaeo_css 02:50:24 INFO - Initialising browser for dromaeo_css test... 02:50:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49843/getInfo.html -profile /tmp/tmpsYycwf/profile 02:50:24 INFO - TEST-INFO | started process 3643 (/builds/slave/test/build/application/firefox/firefox http://localhost:49843/getInfo.html) 02:50:31 INFO - TEST-INFO | 3643: exit 0 02:50:31 INFO - Browser initialized. 02:50:31 INFO - Running cycle 1/1 for dromaeo_css test... 02:50:31 INFO - Using env: {'DISPLAY': ':0', 02:50:31 INFO - 'HOME': '/home/cltbld', 02:50:31 INFO - 'JSGC_DISABLE_POISONING': '1', 02:50:31 INFO - 'LANG': 'en_US.UTF-8', 02:50:31 INFO - 'LANGUAGE': 'en_US:en', 02:50:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:50:31 INFO - 'LOGNAME': 'cltbld', 02:50:31 INFO - 'MAIL': '/var/mail/cltbld', 02:50:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:50:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:50:31 INFO - 'MOZ_CRASHREPORTER': '1', 02:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:50:31 INFO - 'MOZ_NO_REMOTE': '1', 02:50:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:50:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:50:31 INFO - 'NO_EM_RESTART': '1', 02:50:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:31 INFO - 'PWD': '/builds/slave/test', 02:50:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:50:31 INFO - 'RUST_BACKTRACE': 'full', 02:50:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:50:31 INFO - 'SHELL': '/bin/bash', 02:50:31 INFO - 'SHLVL': '1', 02:50:31 INFO - 'TERM': 'linux', 02:50:31 INFO - 'TMOUT': '86400', 02:50:31 INFO - 'USER': 'cltbld', 02:50:31 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 02:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:31 INFO - '_': '/tools/buildbot/bin/python'} 02:50:31 INFO - TEST-INFO | started process 3883 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsYycwf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:50:32 INFO - PID 3883 | 02:50:32 INFO - PID 3883 | (/builds/slave/test/build/application/firefox/firefox:3933): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:50:32 INFO - PID 3883 | 02:50:42 INFO - PID 3883 | RSS: Main: 144809984 02:50:42 INFO - PID 3883 | 02:53:39 INFO - PID 3883 | Cycle 1(1): loaded http://localhost:49843/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49843/tests/dromaeo/cssquery-ext.html) 02:53:41 INFO - PID 3883 | RSS: Main: 142184448 02:53:41 INFO - PID 3883 | 02:56:42 INFO - PID 3883 | Cycle 1(1): loaded http://localhost:49843/tests/dromaeo/cssquery-ext.html (next: http://localhost:49843/tests/dromaeo/cssquery-jquery.html) 02:56:43 INFO - PID 3883 | RSS: Main: 141840384 02:56:43 INFO - PID 3883 | 02:59:44 INFO - PID 3883 | Cycle 1(1): loaded http://localhost:49843/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49843/tests/dromaeo/cssquery-mootools.html) 02:59:45 INFO - PID 3883 | RSS: Main: 145035264 02:59:45 INFO - PID 3883 | 03:02:47 INFO - PID 3883 | Cycle 1(1): loaded http://localhost:49843/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49843/tests/dromaeo/cssquery-prototype.html) 03:02:47 INFO - PID 3883 | RSS: Main: 144486400 03:02:47 INFO - PID 3883 | 03:05:50 INFO - PID 3883 | Cycle 1(1): loaded http://localhost:49843/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49843/tests/dromaeo/cssquery-yui.html) 03:05:51 INFO - PID 3883 | RSS: Main: 147705856 03:05:51 INFO - PID 3883 | 03:08:52 INFO - PID 3883 | Cycle 1(1): loaded http://localhost:49843/tests/dromaeo/cssquery-yui.html (next: http://localhost:49843/tests/dromaeo/cssquery-dojo.html) 03:08:53 INFO - PID 3883 | RSS: Main: 149078016 03:08:53 INFO - PID 3883 | 03:08:53 INFO - PID 3883 | __start_tp_report 03:08:53 INFO - PID 3883 | _x_x_mozilla_page_load 03:08:53 INFO - PID 3883 | _x_x_mozilla_page_load_details 03:08:53 INFO - PID 3883 | |i|pagename|runs| 03:08:53 INFO - PID 3883 | |0;dojo.html;3850.9037274068164;3861.1312454697677;3900.84396624135;3930.5283365996083;3931.056546428857;6117.02127659574;6265.843353916149;6335.651539165344;6341.841453963653;6383.234011918565;6311.873762524747;6326.588771729835;6384.329645387237;6397.072424498445;6442.098106265115;5558.082916318803;5629.662220266797;5662.37713851476;5682.801101961421;5708.343540492834;5605.5515558755405;5638.154276858483;5642.181883626871;5653.604247702642;5675.2054712340305;5636.2954630671165;5721.8569535761735;5723;5728.226689396941;5734.856628584298;5624.100143976985;5634.12671035986;5714.857128571777;5725.055365864628;5744.511716504102;5483.643563168421;5556.361018482886;5638.915416268759;5707.828765137053;5723.971380143114;6154.96922515389;6214.751409943597;6248.3751624837605;6257.906131408033;6341.524385671047;6809.965950170267;6816.352446517573;6821.317868213188;6863.38229559342;6912.688928998207;6809.5233333666665;6846.212685541177;6869.896951545731;6956.965215173892;6976.093107895992;6298.118263443122;6301.338359472235;6371.267304260023;6420.133282006916;6448.774292899726;6967.372936435745;6991.8601627967155;7057.752978645723;7065.4700897432895;7065.858682826418;6712.194536655633;6725.125733654593;6743.224529179109;6744.5616034957575;6745.426638735665;6856.760013399607;6862.382385585322;6866.107406037183;6887.793366199022;6892.069570608004;6720.025596288511;6779.423748981392;6780.423663988616;6795.048693182958;6818.795436136924;6810.331136345671;6876.449884009267;6883.174019117738;6903.6548172591165;6911.516193866481;4943.134951383566;4963.205887058053;5019.548240658285;5026.773795179226;5033.295338652591;4392.912141757147;4498.797554110073;4546.249868771615;4554.407926969473;4556.863294101182;6119.755209791559;6271.216097987752;6286.434220920048;6293.1504246927;6296.496280297565;6905.585664860125;6924.549904256308;6939.201991771011;6947.652617369213;6955.478339124485;6279.748810047547;6281.309056003836;6286.214223222098;6375.075614036032;6405.615663060231;5370.516653501204;5504.53211477029;5535.308086489189;5590.552755779528;5608.214849921014;7067.505274630829;7111.7866464006165;7114.92885071153;7116;7116.359527642537;6604.14146160996;6692.364225398482;6695.665216739241;6739;6759.493038022069;7094.184168820651;7110.5022648414115;7161.749338773118;7184.712611495691;7203.567785932861;6801.149856267966;6802.0477133202385;6854.383105520527;6877.140357455318;6886.586804791728;6805.89791153143;6824;6889.793306200822;6905.93094069073;6909.689063992032;6501.382368674968;6570.8028759137305;6711.463082953254;6712.597244165366;6721.327867213239;6616;6622.536422450575;6697.028930805103;6707.396334329934;6707.631080290631;6521.054447105048;6530.281669016309;6616.338366163345;6619.867602648016;6622.768203112868;6515.087887695631;6586.37429444202;6654.068430419842;6669.533132680665;6678.332166783282;6475.061116138039;6480.319566445455;6493.026046093124;6563.803085907431;6570.408663220333 03:08:53 INFO - PID 3883 | |1;ext.html;886.5124181700063;947.3037317571584;971.8007808399279;1002.3885429887763;1009.8030883977623;43847.99999999996;44807.551924480744;44836.32745508816;44845.327320090146;44885.551144488585;41684.79157604208;41966.790166049126;41987;41988;42039.789801051025;7523.586202758846;7627.122880868688;7648.732294369698;7648.923510764905;7651.081870175587;7491.587962662052;7602.277783610576;7603.163651998276;7619.504732192418;7621.466497345188;7584.393248540104;8515.361347898901;8707.216350528452;8738.650453981832;8749.300055995536;8642.65429382824;8671.349648776335;8690.217880390763;8695.999960004618;8697.130286971316;8339.082700902896;8456.196661317164;8513.361497887683;8536.146385361477;8591.957040214822;41047;41330.38004429936;41357.379639305436;41360.37959430611;41419.37870931938;3651.178484840916;3696.3900956342172;3696.4085746280466;3700.85196592136;3701.481792549045;33450.83274583636;33500.32999340024;33540;33672.15819604505;33754.324913501594;5586.6368686035685;5597.244372009808;5606.103023516218;5630.352509461423;5685.061964775807;230701;231458;232160;232282;232560;53637;54090.91818163693;54174.187387189224;54211.45788542064;54366.72816635971;56150.43849561452;56175.71912140413;56316;56383.43616563864;56533.434665653636;16319.265633046542;16591.83408165927;16660.50018499447;16671.6665666686;16684.74972875408;13234.470621175238;13356.198628082346;13356.265405402602;13362.33188340579;13364.6658833528;15109.093454392772;15185.544433666786;15286.159261240517;15323.387064517294;15481.690366192839;5826.329972053183;5886.440788125121;5915.497182739429;5997.910031349533;6037.0642550404755;1067.727729429006;1077.4612693653173;1091.5306418240234;1096.5230124895536;1121.4673030310537;24910.51252824606;25371;25426.745732542808;25732.8713356432;26068;1191.9761604767857;1199.1605875886914;1205.9758804823855;1208.2327721896659;1221.584661215191;13497.66255843592;13600.863991360158;13630.469046146392;13637.318134093255;13979.161250325013;287214;287855;288203;288916;289120;60778;61114.38885611176;66410.66794665996;67385.32614673888;67588;1447.6019094749029;1485.4726572066977;1489.7541905585674;1496.498672944554;1498.2883130513137;30687.2328191797;30753.692463075084;30762.84618576893;30884.53673194859;31529.52705709462;41799.37300940549;41889;41926;41934.790326048176;41935.99999999878;241572;242431;243150;243152;243241;36074.4588831173;36355.45466818053;36421.27157456889;36610.26779464343;36615.45076823796;47773.522264776904;47774.52225477701;47827.28259076186;48232.7588362056;48379.75810120927;33969.49045764266;34311.656883431846;34443;34687.653123468444;34956.12609684778;33226.667733322356;33343;33832.830835846646;34039.31921361609;34135.487967680005;40063.39904901487;40149;40577.18845623012;40596.39105413362;40697.38953915753;33275.334493309514;33661;33727.325453491285;34289.48565771466;34475.482867757506;25125.371865703502;25250;25735.871320643277;25807.225783226535;25995 03:08:53 INFO - PID 3883 | |2;jquery.html;627.8932581461152;628.5976974736172;635.3392471829297;639.804859517847;640.8910485217513;24029.639555406684;24101.75898241017;24803.50392992139;24858.502829943413;25564.36089097769;26584.867075664642;28019.439611207763;28139.57790633137;28144;28179.15462536122;6369.6496692682;6543.116679248288;6555.672216389162;6559.868802623944;6565.244996825355;6376.776812811552;6617.602943823362;6621.536492445531;6623.53635245533;6626.469882409396;6530;6583.078369028316;6584.473242140617;6588.8023359299295;6590.538662293641;6523.217213934311;6538.509611779135;6548.443382312509;6564.245111812155;6568.047633093175;6176.69116544171;6177.289611694668;6191.597546159485;6193.628382297076;6194.101855230966;31355.686443135506;31423.528647070314;32480.837595812107;32523.83738081318;32685.6731432685;2269.4439862233694;2290.7022087128735;2322.09438319054;2322.628379459278;2323.94190145246;20760.68858967117;21573.67639485409;21610.675839862415;21613.135474581;21626.891865540572;2463.1379017343966;2541.2884392369983;2546.0834099724084;2560.8207425480223;2646.5765477523505;162587;169486.1525692376;170651;170857;170865;16890.40883569069;16920.40778572744;16946.322147113977;16968.830311696725;17420.390286339916;26854;26914.32714182161;27047.72952270452;27151.45697086087;27495.725042749316;10639.787204256027;10801.405922674328;10808.675739727822;10877.891221087688;10956.945215274032;9767.218622510181;9780.510974451248;9824.459654718916;9857.605695772232;9931.851022234672;10026.287644807075;10400.115990140772;10408.895911040943;10414.843777343345;10457.895421045692;2843.5876797864607;2879.07869481768;2930;2999.3851260491547;3040.695930406986;1034.363866222283;1041.8437234414898;1046.3355769086684;1052.1109662335314;1060.4326685223368;22132.33602991913;22331.66502502464;22450.438739031655;22579.88710056472;22835.88582057079;1098.4892025208158;1109.57836022311;1116.944152792357;1129.678041758095;1132.6998345438465;12272.447739851728;12392.318422486669;12436.875631243753;12487.31319777421;12610;602631;606526;618136;621644;627108;132526;136153;136348;137175;138450;1206.3003457994341;1223.9265644061384;1239.4918083585867;1261.7728808814318;1267.0876968582606;19856.90071549633;19871.205151793765;20050.09774560119;20128.39614811558;20629.277975270794;64695.67652161709;66060.66969665121;67555;67578;68567;80621.19378806137;80629.59685201537;81253;82092.17907821084;83129.16870831214;16605.169741512633;16686.332546697995;16780.077095759363;16885.746713799548;16926.66146677084;25787.871060645328;25788.35529111787;25915.87042064853;26120;26305;19667;19736.21055157835;19792.505187370432;20805.1677932881;21203.68194477115;18343.9910804907;18658.16038278308;18700.906495467436;18859.339923102627;19275.325363612206;19791.30730424486;19857.60284794325;20096;20216.292429764886;20279.29022484206;16502.33990640361;16611.335546578484;16629.50111496657;16670.833291666928;16722;20758.273460429416;20882.791172088084;21142.471438213553;21310.78689213088;21319.680204797252 03:08:53 INFO - PID 3883 | |3;mootools.html;1466.6480044789253;1504.2629111735246;1510.524184881763;1520.4906356370616;1537.723209822233;16436.589085272873;17108.74336884946;17109.914450427746;17132.400365987192;17142.228599713013;16547.58631034225;16696;16697.582560435996;16712.08083555407;16719.247633856474;2867.913962581121;2961.141269031989;2967.8367689777056;2970.5098658721286;2970.896018639348;2904.3610405710815;2913.3783964995737;2923.8391888446126;2936.721011503904;2943.558466230061;2912.7524160446283;2921;2933.354661974373;2934.3544420227518;2934.61849959505;2795.1893950754256;2818.929526761837;2819.8731057102273;2821.7319354661277;2826.943461130776;2730.57676060211;2766.21162968555;2774.667039955218;2775.2506823157632;2783.067672329772;16203.1088290144;16573.751393729104;16602.58493537659;16622.50132496027;16626.83373166277;8193.877091843628;8468.915310846874;8469.407141500098;8470.237678608955;8474.491530508189;5669.9433005669825;5765.4811067004175;5791.131330300446;5924.4667979882015;5924.851878703023;2128.5530038691895;2165.8700477971374;2178.270279456384;2196.7254093238344;2208.768079351661;28696.42607147866;28916.855415722996;30359.544606830914;31170.532442013387;31257.8437107813;7711.961440192876;7844.156623193545;7878.566678832604;8014.158513356014;8107.270345668918;23192.65211021836;23197.072117115127;23273.301800946;23321.53356932852;23344.949477273225;8691.130886911385;8852.159044890725;8875.822483550293;8886.155815197546;8923.330750193762;7773.766786996399;7939.920600793918;7945.801354966172;7959;8025.518468891885;6133.907991380132;6232.3144454110015;6243.843903902439;6273.560850740496;6356.872862542723;1949.0741897598812;1976.4959935216427;1979.604079184169;1990.5023744063983;2008.086320724067;910.7723069232692;961.5865177973399;965.9517024148764;971.9611215551299;975.3806332978468;8163.551004694798;8180.590970451453;8238.958805205935;8243.587820608946;8314.127016663284;803.7066470738139;812.5409143833715;823.8064054947083;824.1881746479713;836.6946064686346;5092.8472145835685;5151.639385242997;5508.366537848118;5647.322321321468;5774.94225057744;23591.528169436515;24693.506129877303;24806.37984050377;24838.254852354457;24845.751542484704;23311.300660980196;24074.87962560176;24148.275551733477;24246.393840154138;24380.146694865594;10942.36468218868;12096;12152.5138994441;12173.330466824233;12481.563145290052;9933.15568176713;9974.650887218913;9999.800003999733;10147.949260253652;10196;20050.799492005477;20244.190232390538;20434.59130817405;20450;20511.589768204853;21018.579628407042;21303.78696213018;21401.678974815703;21406;21615.027323770155;12382.442790074288;12592.055595830532;12600.495980161058;12647.494100235888;12651.114421990373;15154.924225378803;15193.316300766272;15322;15357.385704571692;15511.224438778241;12085.39573021363;12298.631041068782;12443.44004519817;12615.116941814353;12626.747465050465;11737.765244694887;12160.087993400353;12215.877841221829;12346.876531234573;12471.438785254504;10349.120324772479;10454.895451045391;10487.790244195226;10516.474176291307;10516.894831051592;7661.042369703787;7703.229677032252;7748.457607967388;7819.648115834687;7863.606819659109;7872.330851877426;7899.368050555827;7967.482113662593;8039.437239393186;8063.475874068166 03:08:53 INFO - PID 3883 | |4;prototype.html;547.2529996554701;556.9081101618233;581.5783556921235;582.897992851251;583.8919799837031;6252.718627661755;6272.679108729771;6347.270063942642;6446.194225721785;6483.805485835421;6273.592216505924;6291.1821463209835;6374.968125159367;6391.872162556746;6414.839629009277;5313.760880760375;5314.681119132845;5317.6809391436445;5322.175062865262;5329.227262047001;5172.793088276483;5222.138347172712;5293.6294459387855;5302.734863256841;5316.415194328621;5155.664881782672;5233.293505376782;5251.317328747276;5283;5334.973325133369;5242.374863792236;5291.603129765244;5307.150855863063;5311.920321195185;5332.226827110067;4933.210686290212;4954.157793175169;4969.155243608595;5016.874578135539;5021.121303771825;6351.9364806351805;6355.538226207998;6365.236171659384;6375.87248255037;6380.553361264713;6272.341404152589;6282.937170628281;6304.7793327233785;6352.618842869442;6359.65021923794;6725.7557351890055;6729.293424190439;6809.38715515606;6840.179178498563;6862.416694580967;5534.889302213974;5565.276514053187;5576.9163462548095;5581.5813813963705;5593.8321850344555;7132.928670713279;7144.214136444987;7154.499185057048;7197.208307086216;7211.278872112852;5785.537157027427;5799.057315799255;5804.738786754522;5842.970785146047;5856.677882716406;5897.439743224387;5903.468687818117;5915.556333275022;5934.851628709231;5950.9107363389585;6174.629522228591;6225.4514926491465;6281.78013769525;6296.653684047421;6321.818972454495;6472.417482426604;6545.639989800511;6658.300878407795;6713.757954778382;6815;1977.8516611254213;1994.3618042226637;2020.656488396976;2032.1058734156923;2032.6646103392775;1322.8081928120369;1515.9924200379148;1528.2654187497048;1535.1710076558534;1539.5227479481173;6209.937900620936;6225.346338634468;6230.071866222868;6299.716512756845;6335.208098987627;6819.965900170468;6916.23921368649;6926.826829329307;6972.2330543640155;6974.337437943387;6267.370483674277;6320.905186422116;6333.366663333722;6353.174087368612;6359.3640635936035;3604.8017359045502;3607.549056367954;3624.4925710400566;3635.618260082706;3687.520622319081;6987.213758575392;7078;7130.180029296594;7193.136823581099;7199.460040496879;6945.756898508629;7042.647867606599;7069.434445244368;7071.469639777038;7078.893816592756;7132.251113632994;7139.750108746169;7176.138863336433;7208.675609597476;7218.747343842941;6739.157605299338;6807.421369183675;6808.353206445379;6831.829204269933;6845.931540684529;6688.431483323778;6743;6761.22245941723;6764.966175169093;6817.829554261183;6966.1988871280455;7079.752208672672;7153.713851445884;7157.677904494206;7230.349268565854;5750.482456578928;5809.5933284671355;5817.621854579439;5822.388649191774;5829.6793676346515;6663.500237482305;6714;6882.621455819778;6958.826029349307;6965.164180298397;6646.634435106115;6681.2984636612455;6699.933000669931;6727.562708423937;6776.254611992776;6579.2104947406615;6654.866902662016;6721.193456785217;6730.427913627396;6755.256921738703;6462.935370646233;6541.934580654323;6587.209534855848;6593.769218077345;6619;6632.1709786276715;6740.123783908257;6743.393094621508;6809.931900681128;6839.965800170968;6503.544751867324;6519.967400162969;6539.607623542603;6542.640154791532;6559.8032059038305 03:08:53 INFO - PID 3883 | |5;yui.html;881.9118088191178;909.781652403423;920.8664743612172;998.6953979036393;1022.5705203814397;16883.0714310713;17624.295028198856;17652.205650745716;17695.823041769578;17707.20317585707;16329.18354082297;16854.915725421386;16963.49109526713;16988.575285617866;17100.828991710077;1011.822930987078;1030.90206430389;1032.1072272484284;1034.3535290443472;1035.2028937717953;1051.8842927277992;1057.6351158850184;1058.6294796821128;1061.0927656853396;1063.8351055586397;1058.6929790360787;1092.2736380307085;1095.5124969388626;1096.9341839489616;1099.6591056772427;1091.1270983213396;1093.7265683579105;1093.9890601093966;1094.7208461842258;1097.1497089755424;767.5087943716027;796.3549524884862;802.3060053054066;802.3661307567073;802.7029998900445;17367.47897563075;17416.65166696659;17428.477145685523;17465.476035718948;17468.82531174685;21984.560308793894;22424.103035878547;22500.32499025032;22554.54890902189;22672.886635566883;17581.472555823344;17626.911865440718;17752.91123544387;17873.46379608601;18057.187426565837;3756.436534519844;3771.377722675755;3777.490038844748;3792.639699228569;3799.1072098056934;164237.17881410636;165709;165720.17139914224;167136.16431917885;168760;17167;17294.308227671005;17352;17388.565285867953;17496.387626433014;35307;35436;36222.63777362193;36251.63748362535;36262.63737362645;8869.467831930106;8943.37396382247;8949;9010.234130098885;9014;3891.883243502643;3975.5428125765907;3992.900177495586;4000.4999375078114;4024.1348110155873;6050.45545900871;6124.203853499106;6134.938650613437;6166.660833654192;6180.536459765535;1063.3513556730388;1084.4198353880636;1087.472575800736;1103.178132291448;1104.0725790335996;634.0964126120352;644.7614382678439;654.564714464885;662.7812821768805;666.6266890541312;12076.75846483083;12255;12779.552715654907;13300.534481293109;13323.866761332456;686.4954258619911;689.0491122251261;700.8388070743757;702.884024136022;707.1761397971409;5813.3895940925595;5817.185594016841;5893.705314734246;5919.67441790697;6020.548458865603;157780.21109894378;159367;159457;165692;167351;137321;139825.30087349497;140540;142643;144886;118592.40703796598;118880;119177.40411297888;120134;121474;11066.39134847591;11079.058280046287;11128.332300062022;11244.943775281072;11287.379194144072;56830.147547785986;57262.7136864313;58935;59008.409915900294;59062;75973;76127.23872761201;76767.23232767601;77238;77495;17151.914240429218;17766.20052097633;17852.821471785115;17889.105544722974;17906.5523361917;14858.925705371405;15133.848661513244;15181.468648597245;15241.466548671187;15301.770473442684;16255.10596917181;16300.673986519965;16321.918390407973;16326.918365408097;16447.506574802774;14305.499307524187;14424.134551926918;14553.781693274397;14573.635659108608;14574.489892853699;15137.243137842843;15140.470083547023;15357.078575285519;15387.230638468256;15539.844601554292;12217.14479986428;12236.938815306223;12443.93778031104;12476.376181190722;12500.437480313225;12077.154599177973;12199.329036903055;12469.127161098637;12596.622101336974;12597.307148106576 03:08:53 INFO - PID 3883 | __end_tp_report 03:08:53 INFO - PID 3883 | __start_cc_report 03:08:53 INFO - PID 3883 | _x_x_mozilla_cycle_collect,273 03:08:53 INFO - PID 3883 | __end_cc_report 03:08:53 INFO - PID 3883 | __startTimestamp1506766133189__endTimestamp 03:08:53 INFO - PID 3883 | 03:08:53 INFO - PID 3883 | ------- Summary: start ------- 03:08:53 INFO - PID 3883 | Number of tests: 6 03:08:53 INFO - PID 3883 | 03:08:53 INFO - PID 3883 | [#0] dojo.html Cycles:165 Average:6295.40 Median:6563.80 stddev:755.70 (11.5%) stddev-sans-first:733.29 03:08:53 INFO - PID 3883 | Values: 3850.9 3861.1 3900.8 3930.5 3931.1 6117.0 6265.8 6335.7 6341.8 6383.2 6311.9 6326.6 6384.3 6397.1 6442.1 5558.1 5629.7 5662.4 5682.8 5708.3 5605.6 5638.2 5642.2 5653.6 5675.2 5636.3 5721.9 5723.0 5728.2 5734.9 5624.1 5634.1 5714.9 5725.1 5744.5 5483.6 5556.4 5638.9 5707.8 5724.0 6155.0 6214.8 6248.4 6257.9 6341.5 6810.0 6816.4 6821.3 6863.4 6912.7 6809.5 6846.2 6869.9 6957.0 6976.1 6298.1 6301.3 6371.3 6420.1 6448.8 6967.4 6991.9 7057.8 7065.5 7065.9 6712.2 6725.1 6743.2 6744.6 6745.4 6856.8 6862.4 6866.1 6887.8 6892.1 6720.0 6779.4 6780.4 6795.0 6818.8 6810.3 6876.4 6883.2 6903.7 6911.5 4943.1 4963.2 5019.5 5026.8 5033.3 4392.9 4498.8 4546.2 4554.4 4556.9 6119.8 6271.2 6286.4 6293.2 6296.5 6905.6 6924.5 6939.2 6947.7 6955.5 6279.7 6281.3 6286.2 6375.1 6405.6 5370.5 5504.5 5535.3 5590.6 5608.2 7067.5 7111.8 7114.9 7116.0 7116.4 6604.1 6692.4 6695.7 6739.0 6759.5 7094.2 7110.5 7161.7 7184.7 7203.6 6801.1 6802.0 6854.4 6877.1 6886.6 6805.9 6824.0 6889.8 6905.9 6909.7 6501.4 6570.8 6711.5 6712.6 6721.3 6616.0 6622.5 6697.0 6707.4 6707.6 6521.1 6530.3 6616.3 6619.9 6622.8 6515.1 6586.4 6654.1 6669.5 6678.3 6475.1 6480.3 6493.0 6563.8 6570.4 03:08:53 INFO - PID 3883 | 03:08:53 INFO - PID 3883 | [#1] ext.html Cycles:180 Average:43606.29 Median:20130.72 stddev:66479.03 (330.2%) stddev-sans-first:66588.13 03:08:53 INFO - PID 3883 | Values: 886.5 947.3 971.8 1002.4 1009.8 43848.0 44807.6 44836.3 44845.3 44885.6 41684.8 41966.8 41987.0 41988.0 42039.8 7523.6 7627.1 7648.7 7648.9 7651.1 7491.6 7602.3 7603.2 7619.5 7621.5 7584.4 8515.4 8707.2 8738.7 8749.3 8642.7 8671.3 8690.2 8696.0 8697.1 8339.1 8456.2 8513.4 8536.1 8592.0 41047.0 41330.4 41357.4 41360.4 41419.4 3651.2 3696.4 3696.4 3700.9 3701.5 33450.8 33500.3 33540.0 33672.2 33754.3 5586.6 5597.2 5606.1 5630.4 5685.1 230701.0 231458.0 232160.0 232282.0 232560.0 53637.0 54090.9 54174.2 54211.5 54366.7 56150.4 56175.7 56316.0 56383.4 56533.4 16319.3 16591.8 16660.5 16671.7 16684.7 13234.5 13356.2 13356.3 13362.3 13364.7 15109.1 15185.5 15286.2 15323.4 15481.7 5826.3 5886.4 5915.5 5997.9 6037.1 1067.7 1077.5 1091.5 1096.5 1121.5 24910.5 25371.0 25426.7 25732.9 26068.0 1192.0 1199.2 1206.0 1208.2 1221.6 13497.7 13600.9 13630.5 13637.3 13979.2 287214.0 287855.0 288203.0 288916.0 289120.0 60778.0 61114.4 66410.7 67385.3 67588.0 1447.6 1485.5 1489.8 1496.5 1498.3 30687.2 30753.7 30762.8 30884.5 31529.5 41799.4 41889.0 41926.0 41934.8 41936.0 241572.0 242431.0 243150.0 243152.0 243241.0 36074.5 36355.5 36421.3 36610.3 36615.5 47773.5 47774.5 47827.3 48232.8 48379.8 33969.5 34311.7 34443.0 34687.7 34956.1 33226.7 33343.0 33832.8 34039.3 34135.5 40063.4 40149.0 40577.2 40596.4 40697.4 33275.3 33661.0 33727.3 34289.5 34475.5 25125.4 25250.0 25735.9 25807.2 25995.0 03:08:53 INFO - PID 3883 | 03:08:53 INFO - PID 3883 | [#2] jquery.html Cycles:180 Average:41349.18 Median:12563.29 stddev:103509.71 (823.9%) stddev-sans-first:103754.92 03:08:53 INFO - PID 3883 | Values: 627.9 628.6 635.3 639.8 640.9 24029.6 24101.8 24803.5 24858.5 25564.4 26584.9 28019.4 28139.6 28144.0 28179.2 6369.6 6543.1 6555.7 6559.9 6565.2 6376.8 6617.6 6621.5 6623.5 6626.5 6530.0 6583.1 6584.5 6588.8 6590.5 6523.2 6538.5 6548.4 6564.2 6568.0 6176.7 6177.3 6191.6 6193.6 6194.1 31355.7 31423.5 32480.8 32523.8 32685.7 2269.4 2290.7 2322.1 2322.6 2323.9 20760.7 21573.7 21610.7 21613.1 21626.9 2463.1 2541.3 2546.1 2560.8 2646.6 162587.0 169486.2 170651.0 170857.0 170865.0 16890.4 16920.4 16946.3 16968.8 17420.4 26854.0 26914.3 27047.7 27151.5 27495.7 10639.8 10801.4 10808.7 10877.9 10956.9 9767.2 9780.5 9824.5 9857.6 9931.9 10026.3 10400.1 10408.9 10414.8 10457.9 2843.6 2879.1 2930.0 2999.4 3040.7 1034.4 1041.8 1046.3 1052.1 1060.4 22132.3 22331.7 22450.4 22579.9 22835.9 1098.5 1109.6 1116.9 1129.7 1132.7 12272.4 12392.3 12436.9 12487.3 12610.0 602631.0 606526.0 618136.0 621644.0 627108.0 132526.0 136153.0 136348.0 137175.0 138450.0 1206.3 1223.9 1239.5 1261.8 1267.1 19856.9 19871.2 20050.1 20128.4 20629.3 64695.7 66060.7 67555.0 67578.0 68567.0 80621.2 80629.6 81253.0 82092.2 83129.2 16605.2 16686.3 16780.1 16885.7 16926.7 25787.9 25788.4 25915.9 26120.0 26305.0 19667.0 19736.2 19792.5 20805.2 21203.7 18344.0 18658.2 18700.9 18859.3 19275.3 19791.3 19857.6 20096.0 20216.3 20279.3 16502.3 16611.3 16629.5 16670.8 16722.0 20758.3 20882.8 21142.5 21310.8 21319.7 03:08:53 INFO - PID 3883 | 03:08:53 INFO - PID 3883 | [#3] mootools.html Cycles:180 Average:10420.91 Median:2779.16 stddev:7658.32 (275.6%) stddev-sans-first:7650.26 03:08:53 INFO - PID 3883 | Values: 1466.6 1504.3 1510.5 1520.5 1537.7 16436.6 17108.7 17109.9 17132.4 17142.2 16547.6 16696.0 16697.6 16712.1 16719.2 2867.9 2961.1 2967.8 2970.5 2970.9 2904.4 2913.4 2923.8 2936.7 2943.6 2912.8 2921.0 2933.4 2934.4 2934.6 2795.2 2818.9 2819.9 2821.7 2826.9 2730.6 2766.2 2774.7 2775.3 2783.1 16203.1 16573.8 16602.6 16622.5 16626.8 8193.9 8468.9 8469.4 8470.2 8474.5 5669.9 5765.5 5791.1 5924.5 5924.9 2128.6 2165.9 2178.3 2196.7 2208.8 28696.4 28916.9 30359.5 31170.5 31257.8 7712.0 7844.2 7878.6 8014.2 8107.3 23192.7 23197.1 23273.3 23321.5 23344.9 8691.1 8852.2 8875.8 8886.2 8923.3 7773.8 7939.9 7945.8 7959.0 8025.5 6133.9 6232.3 6243.8 6273.6 6356.9 1949.1 1976.5 1979.6 1990.5 2008.1 910.8 961.6 966.0 972.0 975.4 8163.6 8180.6 8239.0 8243.6 8314.1 803.7 812.5 823.8 824.2 836.7 5092.8 5151.6 5508.4 5647.3 5774.9 23591.5 24693.5 24806.4 24838.3 24845.8 23311.3 24074.9 24148.3 24246.4 24380.1 10942.4 12096.0 12152.5 12173.3 12481.6 9933.2 9974.7 9999.8 10147.9 10196.0 20050.8 20244.2 20434.6 20450.0 20511.6 21018.6 21303.8 21401.7 21406.0 21615.0 12382.4 12592.1 12600.5 12647.5 12651.1 15154.9 15193.3 15322.0 15357.4 15511.2 12085.4 12298.6 12443.4 12615.1 12626.7 11737.8 12160.1 12215.9 12346.9 12471.4 10349.1 10454.9 10487.8 10516.5 10516.9 7661.0 7703.2 7748.5 7819.6 7863.6 7872.3 7899.4 7967.5 8039.4 8063.5 03:08:53 INFO - PID 3883 | 03:08:53 INFO - PID 3883 | [#4] prototype.html Cycles:180 Average:5854.98 Median:6359.51 stddev:1559.58 (24.5%) stddev-sans-first:1512.22 03:08:53 INFO - PID 3883 | Values: 547.3 556.9 581.6 582.9 583.9 6252.7 6272.7 6347.3 6446.2 6483.8 6273.6 6291.2 6375.0 6391.9 6414.8 5313.8 5314.7 5317.7 5322.2 5329.2 5172.8 5222.1 5293.6 5302.7 5316.4 5155.7 5233.3 5251.3 5283.0 5335.0 5242.4 5291.6 5307.2 5311.9 5332.2 4933.2 4954.2 4969.2 5016.9 5021.1 6351.9 6355.5 6365.2 6375.9 6380.6 6272.3 6282.9 6304.8 6352.6 6359.7 6725.8 6729.3 6809.4 6840.2 6862.4 5534.9 5565.3 5576.9 5581.6 5593.8 7132.9 7144.2 7154.5 7197.2 7211.3 5785.5 5799.1 5804.7 5843.0 5856.7 5897.4 5903.5 5915.6 5934.9 5950.9 6174.6 6225.5 6281.8 6296.7 6321.8 6472.4 6545.6 6658.3 6713.8 6815.0 1977.9 1994.4 2020.7 2032.1 2032.7 1322.8 1516.0 1528.3 1535.2 1539.5 6209.9 6225.3 6230.1 6299.7 6335.2 6820.0 6916.2 6926.8 6972.2 6974.3 6267.4 6320.9 6333.4 6353.2 6359.4 3604.8 3607.5 3624.5 3635.6 3687.5 6987.2 7078.0 7130.2 7193.1 7199.5 6945.8 7042.6 7069.4 7071.5 7078.9 7132.3 7139.8 7176.1 7208.7 7218.7 6739.2 6807.4 6808.4 6831.8 6845.9 6688.4 6743.0 6761.2 6765.0 6817.8 6966.2 7079.8 7153.7 7157.7 7230.3 5750.5 5809.6 5817.6 5822.4 5829.7 6663.5 6714.0 6882.6 6958.8 6965.2 6646.6 6681.3 6699.9 6727.6 6776.3 6579.2 6654.9 6721.2 6730.4 6755.3 6462.9 6541.9 6587.2 6593.8 6619.0 6632.2 6740.1 6743.4 6809.9 6840.0 6503.5 6520.0 6539.6 6542.6 6559.8 03:08:53 INFO - PID 3883 | 03:08:53 INFO - PID 3883 | [#5] yui.html Cycles:180 Average:28720.88 Median:17044.70 stddev:45168.29 (265.0%) stddev-sans-first:45246.63 03:08:53 INFO - PID 3883 | Values: 881.9 909.8 920.9 998.7 1022.6 16883.1 17624.3 17652.2 17695.8 17707.2 16329.2 16854.9 16963.5 16988.6 17100.8 1011.8 1030.9 1032.1 1034.4 1035.2 1051.9 1057.6 1058.6 1061.1 1063.8 1058.7 1092.3 1095.5 1096.9 1099.7 1091.1 1093.7 1094.0 1094.7 1097.1 767.5 796.4 802.3 802.4 802.7 17367.5 17416.7 17428.5 17465.5 17468.8 21984.6 22424.1 22500.3 22554.5 22672.9 17581.5 17626.9 17752.9 17873.5 18057.2 3756.4 3771.4 3777.5 3792.6 3799.1 164237.2 165709.0 165720.2 167136.2 168760.0 17167.0 17294.3 17352.0 17388.6 17496.4 35307.0 35436.0 36222.6 36251.6 36262.6 8869.5 8943.4 8949.0 9010.2 9014.0 3891.9 3975.5 3992.9 4000.5 4024.1 6050.5 6124.2 6134.9 6166.7 6180.5 1063.4 1084.4 1087.5 1103.2 1104.1 634.1 644.8 654.6 662.8 666.6 12076.8 12255.0 12779.6 13300.5 13323.9 686.5 689.0 700.8 702.9 707.2 5813.4 5817.2 5893.7 5919.7 6020.5 157780.2 159367.0 159457.0 165692.0 167351.0 137321.0 139825.3 140540.0 142643.0 144886.0 118592.4 118880.0 119177.4 120134.0 121474.0 11066.4 11079.1 11128.3 11244.9 11287.4 56830.1 57262.7 58935.0 59008.4 59062.0 75973.0 76127.2 76767.2 77238.0 77495.0 17151.9 17766.2 17852.8 17889.1 17906.6 14858.9 15133.8 15181.5 15241.5 15301.8 16255.1 16300.7 16321.9 16326.9 16447.5 14305.5 14424.1 14553.8 14573.6 14574.5 15137.2 15140.5 15357.1 15387.2 15539.8 12217.1 12236.9 12443.9 12476.4 12500.4 12077.2 12199.3 12469.1 12596.6 12597.3 03:08:53 INFO - PID 3883 | -------- Summary: end -------- 03:08:53 INFO - PID 3883 | 03:08:53 INFO - TEST-INFO | 3883: exit 0 03:08:53 INFO - TEST-OK | dromaeo_css | took 1109134ms 03:08:53 INFO - TEST-START | kraken 03:08:53 INFO - Initialising browser for kraken test... 03:08:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49843/getInfo.html -profile /tmp/tmp2OpQJo/profile 03:08:53 INFO - TEST-INFO | started process 4272 (/builds/slave/test/build/application/firefox/firefox http://localhost:49843/getInfo.html) 03:08:59 INFO - TEST-INFO | 4272: exit 0 03:08:59 INFO - Browser initialized. 03:08:59 INFO - Running cycle 1/1 for kraken test... 03:08:59 INFO - Using env: {'DISPLAY': ':0', 03:08:59 INFO - 'HOME': '/home/cltbld', 03:08:59 INFO - 'JSGC_DISABLE_POISONING': '1', 03:08:59 INFO - 'LANG': 'en_US.UTF-8', 03:08:59 INFO - 'LANGUAGE': 'en_US:en', 03:08:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:08:59 INFO - 'LOGNAME': 'cltbld', 03:08:59 INFO - 'MAIL': '/var/mail/cltbld', 03:08:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:08:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:08:59 INFO - 'MOZ_CRASHREPORTER': '1', 03:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:08:59 INFO - 'MOZ_NO_REMOTE': '1', 03:08:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:59 INFO - 'NO_EM_RESTART': '1', 03:08:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:59 INFO - 'PWD': '/builds/slave/test', 03:08:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:08:59 INFO - 'RUST_BACKTRACE': 'full', 03:08:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:08:59 INFO - 'SHELL': '/bin/bash', 03:08:59 INFO - 'SHLVL': '1', 03:08:59 INFO - 'TERM': 'linux', 03:08:59 INFO - 'TMOUT': '86400', 03:08:59 INFO - 'USER': 'cltbld', 03:08:59 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587', 03:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:59 INFO - '_': '/tools/buildbot/bin/python'} 03:08:59 INFO - TEST-INFO | started process 4512 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2OpQJo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:09:00 INFO - PID 4512 | 03:09:00 INFO - PID 4512 | (/builds/slave/test/build/application/firefox/firefox:4562): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:09:00 INFO - PID 4512 | 03:09:10 INFO - PID 4512 | RSS: Main: 145678336 03:09:10 INFO - PID 4512 | 03:09:13 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49843/tests/kraken/driver.html?testName=audio-beat-detection) 03:09:14 INFO - PID 4512 | RSS: Main: 152682496 03:09:14 INFO - PID 4512 | 03:09:16 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49843/tests/kraken/driver.html?testName=audio-dft) 03:09:17 INFO - PID 4512 | RSS: Main: 152924160 03:09:17 INFO - PID 4512 | 03:09:20 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49843/tests/kraken/driver.html?testName=audio-fft) 03:09:20 INFO - PID 4512 | RSS: Main: 148152320 03:09:20 INFO - PID 4512 | 03:09:22 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49843/tests/kraken/driver.html?testName=audio-oscillator) 03:09:23 INFO - PID 4512 | RSS: Main: 148447232 03:09:23 INFO - PID 4512 | 03:09:25 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49843/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:09:26 INFO - PID 4512 | RSS: Main: 148787200 03:09:26 INFO - PID 4512 | 03:09:29 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49843/tests/kraken/driver.html?testName=imaging-darkroom) 03:09:29 INFO - PID 4512 | RSS: Main: 149504000 03:09:29 INFO - PID 4512 | 03:09:33 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49843/tests/kraken/driver.html?testName=imaging-desaturate) 03:09:33 INFO - PID 4512 | RSS: Main: 152289280 03:09:33 INFO - PID 4512 | 03:09:36 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49843/tests/kraken/driver.html?testName=json-parse-financial) 03:09:37 INFO - PID 4512 | RSS: Main: 151670784 03:09:37 INFO - PID 4512 | 03:09:38 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49843/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:09:39 INFO - PID 4512 | RSS: Main: 151605248 03:09:39 INFO - PID 4512 | 03:09:41 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49843/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:09:41 INFO - PID 4512 | RSS: Main: 151420928 03:09:41 INFO - PID 4512 | 03:09:43 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49843/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:09:44 INFO - PID 4512 | RSS: Main: 148459520 03:09:44 INFO - PID 4512 | 03:09:46 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49843/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:09:47 INFO - PID 4512 | RSS: Main: 148455424 03:09:47 INFO - PID 4512 | 03:09:50 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49843/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:09:50 INFO - PID 4512 | RSS: Main: 148983808 03:09:50 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | Cycle 1(1): loaded http://localhost:49843/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49843/tests/kraken/driver.html?testName=ai-astar) 03:09:52 INFO - PID 4512 | RSS: Main: 150360064 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | __start_tp_report 03:09:52 INFO - PID 4512 | _x_x_mozilla_page_load 03:09:52 INFO - PID 4512 | _x_x_mozilla_page_load_details 03:09:52 INFO - PID 4512 | |i|pagename|runs| 03:09:52 INFO - PID 4512 | |0;ai-astar;107;105;105;104;107;110;105;105;106;107 03:09:52 INFO - PID 4512 | |1;audio-beat-detection;120;114;112;137;114;113;114;112;114;139 03:09:52 INFO - PID 4512 | |2;audio-dft;162;163;163;161;161;161;162;162;168;166 03:09:52 INFO - PID 4512 | |3;audio-fft;74;77;74;74;73;73;74;73;76;75 03:09:52 INFO - PID 4512 | |4;audio-oscillator;80;78;77;78;77;78;79;79;80;79 03:09:52 INFO - PID 4512 | |5;imaging-gaussian-blur;111;111;111;112;111;112;111;112;113;112 03:09:52 INFO - PID 4512 | |6;imaging-darkroom;150;150;150;150;151;150;150;153;150;151 03:09:52 INFO - PID 4512 | |7;imaging-desaturate;95;94;93;94;96;94;95;94;94;94 03:09:52 INFO - PID 4512 | |8;json-parse-financial;44;46;45;47;46;45;45;46;45;46 03:09:52 INFO - PID 4512 | |9;json-stringify-tinderbox;37;36;38;36;36;37;36;36;37;37 03:09:52 INFO - PID 4512 | |10;stanford-crypto-aes;73;73;71;71;77;73;69;72;74;69 03:09:52 INFO - PID 4512 | |11;stanford-crypto-ccm;108;108;109;101;105;101;111;103;107;102 03:09:52 INFO - PID 4512 | |12;stanford-crypto-pbkdf2;150;147;147;150;151;151;148;156;148;147 03:09:52 INFO - PID 4512 | |13;stanford-crypto-sha256-iterative;63;62;59;59;57;59;58;64;57;64 03:09:52 INFO - PID 4512 | __end_tp_report 03:09:52 INFO - PID 4512 | __start_cc_report 03:09:52 INFO - PID 4512 | _x_x_mozilla_cycle_collect,705 03:09:52 INFO - PID 4512 | __end_cc_report 03:09:52 INFO - PID 4512 | __startTimestamp1506766192634__endTimestamp 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | ------- Summary: start ------- 03:09:52 INFO - PID 4512 | Number of tests: 14 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#0] ai-astar Cycles:10 Average:106.10 Median:106.50 stddev:1.73 (1.6%) stddev-sans-first:1.80 03:09:52 INFO - PID 4512 | Values: 107.0 105.0 105.0 104.0 107.0 110.0 105.0 105.0 106.0 107.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#1] audio-beat-detection Cycles:10 Average:118.90 Median:114.00 stddev:10.32 (9.1%) stddev-sans-first:10.94 03:09:52 INFO - PID 4512 | Values: 120.0 114.0 112.0 137.0 114.0 113.0 114.0 112.0 114.0 139.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#2] audio-dft Cycles:10 Average:162.90 Median:162.50 stddev:2.33 (1.4%) stddev-sans-first:2.45 03:09:52 INFO - PID 4512 | Values: 162.0 163.0 163.0 161.0 161.0 161.0 162.0 162.0 168.0 166.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#3] audio-fft Cycles:10 Average:74.30 Median:74.00 stddev:1.34 (1.8%) stddev-sans-first:1.41 03:09:52 INFO - PID 4512 | Values: 74.0 77.0 74.0 74.0 73.0 73.0 74.0 73.0 76.0 75.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:79.00 stddev:1.08 (1.4%) stddev-sans-first:1.00 03:09:52 INFO - PID 4512 | Values: 80.0 78.0 77.0 78.0 77.0 78.0 79.0 79.0 80.0 79.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#5] imaging-gaussian-blur Cycles:10 Average:111.60 Median:112.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 03:09:52 INFO - PID 4512 | Values: 111.0 111.0 111.0 112.0 111.0 112.0 111.0 112.0 113.0 112.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:150.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 03:09:52 INFO - PID 4512 | Values: 150.0 150.0 150.0 150.0 151.0 150.0 150.0 153.0 150.0 151.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 03:09:52 INFO - PID 4512 | Values: 95.0 94.0 93.0 94.0 96.0 94.0 95.0 94.0 94.0 94.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#8] json-parse-financial Cycles:10 Average:45.50 Median:46.00 stddev:0.85 (1.8%) stddev-sans-first:0.71 03:09:52 INFO - PID 4512 | Values: 44.0 46.0 45.0 47.0 46.0 45.0 45.0 46.0 45.0 46.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#9] json-stringify-tinderbox Cycles:10 Average:36.60 Median:37.00 stddev:0.70 (1.9%) stddev-sans-first:0.73 03:09:52 INFO - PID 4512 | Values: 37.0 36.0 38.0 36.0 36.0 37.0 36.0 36.0 37.0 37.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#10] stanford-crypto-aes Cycles:10 Average:72.20 Median:73.00 stddev:2.39 (3.3%) stddev-sans-first:2.52 03:09:52 INFO - PID 4512 | Values: 73.0 73.0 71.0 71.0 77.0 73.0 69.0 72.0 74.0 69.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#11] stanford-crypto-ccm Cycles:10 Average:105.50 Median:107.50 stddev:3.60 (3.3%) stddev-sans-first:3.70 03:09:52 INFO - PID 4512 | Values: 108.0 108.0 109.0 101.0 105.0 101.0 111.0 103.0 107.0 102.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.50 Median:150.00 stddev:2.80 (1.9%) stddev-sans-first:2.96 03:09:52 INFO - PID 4512 | Values: 150.0 147.0 147.0 150.0 151.0 151.0 148.0 156.0 148.0 147.0 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.20 Median:60.50 stddev:2.78 (4.6%) stddev-sans-first:2.76 03:09:52 INFO - PID 4512 | Values: 63.0 62.0 59.0 59.0 57.0 59.0 58.0 64.0 57.0 64.0 03:09:52 INFO - PID 4512 | -------- Summary: end -------- 03:09:52 INFO - PID 4512 | 03:09:52 INFO - PID 4512 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:09:53 INFO - TEST-INFO | 4512: exit 0 03:09:53 INFO - TEST-OK | kraken | took 59432ms 03:09:53 INFO - SUITE-END | took 1168s 03:09:53 INFO - Completed test suite (00:19:29) 03:09:53 INFO - javascript benchmark 03:09:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9137.19470250796, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3850.9037274068164, 3861.1312454697677, 3900.84396624135, 3930.5283365996083, 3931.056546428857, 6117.02127659574, 6265.843353916149, 6335.651539165344, 6341.841453963653, 6383.234011918565, 6311.873762524747, 6326.588771729835, 6384.329645387237, 6397.072424498445, 6442.098106265115, 5558.082916318803, 5629.662220266797, 5662.37713851476, 5682.801101961421, 5708.343540492834, 5605.5515558755405, 5638.154276858483, 5642.181883626871, 5653.604247702642, 5675.2054712340305, 5636.2954630671165, 5721.8569535761735, 5723.0, 5728.226689396941, 5734.856628584298, 5624.100143976985, 5634.12671035986, 5714.857128571777, 5725.055365864628, 5744.511716504102, 5483.643563168421, 5556.361018482886, 5638.915416268759, 5707.828765137053, 5723.971380143114, 6154.96922515389, 6214.751409943597, 6248.3751624837605, 6257.906131408033, 6341.524385671047, 6809.965950170267, 6816.352446517573, 6821.317868213188, 6863.38229559342, 6912.688928998207, 6809.5233333666665, 6846.212685541177, 6869.896951545731, 6956.965215173892, 6976.093107895992, 6298.118263443122, 6301.338359472235, 6371.267304260023, 6420.133282006916, 6448.774292899726, 6967.372936435745, 6991.8601627967155, 7057.752978645723, 7065.4700897432895, 7065.858682826418, 6712.194536655633, 6725.125733654593, 6743.224529179109, 6744.5616034957575, 6745.426638735665, 6856.760013399607, 6862.382385585322, 6866.107406037183, 6887.793366199022, 6892.069570608004, 6720.025596288511, 6779.423748981392, 6780.423663988616, 6795.048693182958, 6818.795436136924, 6810.331136345671, 6876.449884009267, 6883.174019117738, 6903.6548172591165, 6911.516193866481, 4943.134951383566, 4963.205887058053, 5019.548240658285, 5026.773795179226, 5033.295338652591, 4392.912141757147, 4498.797554110073, 4546.249868771615, 4554.407926969473, 4556.863294101182, 6119.755209791559, 6271.216097987752, 6286.434220920048, 6293.1504246927, 6296.496280297565, 6905.585664860125, 6924.549904256308, 6939.201991771011, 6947.652617369213, 6955.478339124485, 6279.748810047547, 6281.309056003836, 6286.214223222098, 6375.075614036032, 6405.615663060231, 5370.516653501204, 5504.53211477029, 5535.308086489189, 5590.552755779528, 5608.214849921014, 7067.505274630829, 7111.7866464006165, 7114.92885071153, 7116.0, 7116.359527642537, 6604.14146160996, 6692.364225398482, 6695.665216739241, 6739.0, 6759.493038022069, 7094.184168820651, 7110.5022648414115, 7161.749338773118, 7184.712611495691, 7203.567785932861, 6801.149856267966, 6802.0477133202385, 6854.383105520527, 6877.140357455318, 6886.586804791728, 6805.89791153143, 6824.0, 6889.793306200822, 6905.93094069073, 6909.689063992032, 6501.382368674968, 6570.8028759137305, 6711.463082953254, 6712.597244165366, 6721.327867213239, 6616.0, 6622.536422450575, 6697.028930805103, 6707.396334329934, 6707.631080290631, 6521.054447105048, 6530.281669016309, 6616.338366163345, 6619.867602648016, 6622.768203112868, 6515.087887695631, 6586.37429444202, 6654.068430419842, 6669.533132680665, 6678.332166783282, 6475.061116138039, 6480.319566445455, 6493.026046093124, 6563.803085907431, 6570.408663220333], "value": 6243.570765480748, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.5124181700063, 947.3037317571584, 971.8007808399279, 1002.3885429887763, 1009.8030883977623, 43847.99999999996, 44807.551924480744, 44836.32745508816, 44845.327320090146, 44885.551144488585, 41684.79157604208, 41966.790166049126, 41987.0, 41988.0, 42039.789801051025, 7523.586202758846, 7627.122880868688, 7648.732294369698, 7648.923510764905, 7651.081870175587, 7491.587962662052, 7602.277783610576, 7603.163651998276, 7619.504732192418, 7621.466497345188, 7584.393248540104, 8515.361347898901, 8707.216350528452, 8738.650453981832, 8749.300055995536, 8642.65429382824, 8671.349648776335, 8690.217880390763, 8695.999960004618, 8697.130286971316, 8339.082700902896, 8456.196661317164, 8513.361497887683, 8536.146385361477, 8591.957040214822, 41047.0, 41330.38004429936, 41357.379639305436, 41360.37959430611, 41419.37870931938, 3651.178484840916, 3696.3900956342172, 3696.4085746280466, 3700.85196592136, 3701.481792549045, 33450.83274583636, 33500.32999340024, 33540.0, 33672.15819604505, 33754.324913501594, 5586.6368686035685, 5597.244372009808, 5606.103023516218, 5630.352509461423, 5685.061964775807, 230701.0, 231458.0, 232160.0, 232282.0, 232560.0, 53637.0, 54090.91818163693, 54174.187387189224, 54211.45788542064, 54366.72816635971, 56150.43849561452, 56175.71912140413, 56316.0, 56383.43616563864, 56533.434665653636, 16319.265633046542, 16591.83408165927, 16660.50018499447, 16671.6665666686, 16684.74972875408, 13234.470621175238, 13356.198628082346, 13356.265405402602, 13362.33188340579, 13364.6658833528, 15109.093454392772, 15185.544433666786, 15286.159261240517, 15323.387064517294, 15481.690366192839, 5826.329972053183, 5886.440788125121, 5915.497182739429, 5997.910031349533, 6037.0642550404755, 1067.727729429006, 1077.4612693653173, 1091.5306418240234, 1096.5230124895536, 1121.4673030310537, 24910.51252824606, 25371.0, 25426.745732542808, 25732.8713356432, 26068.0, 1191.9761604767857, 1199.1605875886914, 1205.9758804823855, 1208.2327721896659, 1221.584661215191, 13497.66255843592, 13600.863991360158, 13630.469046146392, 13637.318134093255, 13979.161250325013, 287214.0, 287855.0, 288203.0, 288916.0, 289120.0, 60778.0, 61114.38885611176, 66410.66794665996, 67385.32614673888, 67588.0, 1447.6019094749029, 1485.4726572066977, 1489.7541905585674, 1496.498672944554, 1498.2883130513137, 30687.2328191797, 30753.692463075084, 30762.84618576893, 30884.53673194859, 31529.52705709462, 41799.37300940549, 41889.0, 41926.0, 41934.790326048176, 41935.99999999878, 241572.0, 242431.0, 243150.0, 243152.0, 243241.0, 36074.4588831173, 36355.45466818053, 36421.27157456889, 36610.26779464343, 36615.45076823796, 47773.522264776904, 47774.52225477701, 47827.28259076186, 48232.7588362056, 48379.75810120927, 33969.49045764266, 34311.656883431846, 34443.0, 34687.653123468444, 34956.12609684778, 33226.667733322356, 33343.0, 33832.830835846646, 34039.31921361609, 34135.487967680005, 40063.39904901487, 40149.0, 40577.18845623012, 40596.39105413362, 40697.38953915753, 33275.334493309514, 33661.0, 33727.325453491285, 34289.48565771466, 34475.482867757506, 25125.371865703502, 25250.0, 25735.871320643277, 25807.225783226535, 25995.0], "value": 18808.809553282535, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.8932581461152, 628.5976974736172, 635.3392471829297, 639.804859517847, 640.8910485217513, 24029.639555406684, 24101.75898241017, 24803.50392992139, 24858.502829943413, 25564.36089097769, 26584.867075664642, 28019.439611207763, 28139.57790633137, 28144.0, 28179.15462536122, 6369.6496692682, 6543.116679248288, 6555.672216389162, 6559.868802623944, 6565.244996825355, 6376.776812811552, 6617.602943823362, 6621.536492445531, 6623.53635245533, 6626.469882409396, 6530.0, 6583.078369028316, 6584.473242140617, 6588.8023359299295, 6590.538662293641, 6523.217213934311, 6538.509611779135, 6548.443382312509, 6564.245111812155, 6568.047633093175, 6176.69116544171, 6177.289611694668, 6191.597546159485, 6193.628382297076, 6194.101855230966, 31355.686443135506, 31423.528647070314, 32480.837595812107, 32523.83738081318, 32685.6731432685, 2269.4439862233694, 2290.7022087128735, 2322.09438319054, 2322.628379459278, 2323.94190145246, 20760.68858967117, 21573.67639485409, 21610.675839862415, 21613.135474581, 21626.891865540572, 2463.1379017343966, 2541.2884392369983, 2546.0834099724084, 2560.8207425480223, 2646.5765477523505, 162587.0, 169486.1525692376, 170651.0, 170857.0, 170865.0, 16890.40883569069, 16920.40778572744, 16946.322147113977, 16968.830311696725, 17420.390286339916, 26854.0, 26914.32714182161, 27047.72952270452, 27151.45697086087, 27495.725042749316, 10639.787204256027, 10801.405922674328, 10808.675739727822, 10877.891221087688, 10956.945215274032, 9767.218622510181, 9780.510974451248, 9824.459654718916, 9857.605695772232, 9931.851022234672, 10026.287644807075, 10400.115990140772, 10408.895911040943, 10414.843777343345, 10457.895421045692, 2843.5876797864607, 2879.07869481768, 2930.0, 2999.3851260491547, 3040.695930406986, 1034.363866222283, 1041.8437234414898, 1046.3355769086684, 1052.1109662335314, 1060.4326685223368, 22132.33602991913, 22331.66502502464, 22450.438739031655, 22579.88710056472, 22835.88582057079, 1098.4892025208158, 1109.57836022311, 1116.944152792357, 1129.678041758095, 1132.6998345438465, 12272.447739851728, 12392.318422486669, 12436.875631243753, 12487.31319777421, 12610.0, 602631.0, 606526.0, 618136.0, 621644.0, 627108.0, 132526.0, 136153.0, 136348.0, 137175.0, 138450.0, 1206.3003457994341, 1223.9265644061384, 1239.4918083585867, 1261.7728808814318, 1267.0876968582606, 19856.90071549633, 19871.205151793765, 20050.09774560119, 20128.39614811558, 20629.277975270794, 64695.67652161709, 66060.66969665121, 67555.0, 67578.0, 68567.0, 80621.19378806137, 80629.59685201537, 81253.0, 82092.17907821084, 83129.16870831214, 16605.169741512633, 16686.332546697995, 16780.077095759363, 16885.746713799548, 16926.66146677084, 25787.871060645328, 25788.35529111787, 25915.87042064853, 26120.0, 26305.0, 19667.0, 19736.21055157835, 19792.505187370432, 20805.1677932881, 21203.68194477115, 18343.9910804907, 18658.16038278308, 18700.906495467436, 18859.339923102627, 19275.325363612206, 19791.30730424486, 19857.60284794325, 20096.0, 20216.292429764886, 20279.29022484206, 16502.33990640361, 16611.335546578484, 16629.50111496657, 16670.833291666928, 16722.0, 20758.273460429416, 20882.791172088084, 21142.471438213553, 21310.78689213088, 21319.680204797252], "value": 13202.377468911869, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.6480044789253, 1504.2629111735246, 1510.524184881763, 1520.4906356370616, 1537.723209822233, 16436.589085272873, 17108.74336884946, 17109.914450427746, 17132.400365987192, 17142.228599713013, 16547.58631034225, 16696.0, 16697.582560435996, 16712.08083555407, 16719.247633856474, 2867.913962581121, 2961.141269031989, 2967.8367689777056, 2970.5098658721286, 2970.896018639348, 2904.3610405710815, 2913.3783964995737, 2923.8391888446126, 2936.721011503904, 2943.558466230061, 2912.7524160446283, 2921.0, 2933.354661974373, 2934.3544420227518, 2934.61849959505, 2795.1893950754256, 2818.929526761837, 2819.8731057102273, 2821.7319354661277, 2826.943461130776, 2730.57676060211, 2766.21162968555, 2774.667039955218, 2775.2506823157632, 2783.067672329772, 16203.1088290144, 16573.751393729104, 16602.58493537659, 16622.50132496027, 16626.83373166277, 8193.877091843628, 8468.915310846874, 8469.407141500098, 8470.237678608955, 8474.491530508189, 5669.9433005669825, 5765.4811067004175, 5791.131330300446, 5924.4667979882015, 5924.851878703023, 2128.5530038691895, 2165.8700477971374, 2178.270279456384, 2196.7254093238344, 2208.768079351661, 28696.42607147866, 28916.855415722996, 30359.544606830914, 31170.532442013387, 31257.8437107813, 7711.961440192876, 7844.156623193545, 7878.566678832604, 8014.158513356014, 8107.270345668918, 23192.65211021836, 23197.072117115127, 23273.301800946, 23321.53356932852, 23344.949477273225, 8691.130886911385, 8852.159044890725, 8875.822483550293, 8886.155815197546, 8923.330750193762, 7773.766786996399, 7939.920600793918, 7945.801354966172, 7959.0, 8025.518468891885, 6133.907991380132, 6232.3144454110015, 6243.843903902439, 6273.560850740496, 6356.872862542723, 1949.0741897598812, 1976.4959935216427, 1979.604079184169, 1990.5023744063983, 2008.086320724067, 910.7723069232692, 961.5865177973399, 965.9517024148764, 971.9611215551299, 975.3806332978468, 8163.551004694798, 8180.590970451453, 8238.958805205935, 8243.587820608946, 8314.127016663284, 803.7066470738139, 812.5409143833715, 823.8064054947083, 824.1881746479713, 836.6946064686346, 5092.8472145835685, 5151.639385242997, 5508.366537848118, 5647.322321321468, 5774.94225057744, 23591.528169436515, 24693.506129877303, 24806.37984050377, 24838.254852354457, 24845.751542484704, 23311.300660980196, 24074.87962560176, 24148.275551733477, 24246.393840154138, 24380.146694865594, 10942.36468218868, 12096.0, 12152.5138994441, 12173.330466824233, 12481.563145290052, 9933.15568176713, 9974.650887218913, 9999.800003999733, 10147.949260253652, 10196.0, 20050.799492005477, 20244.190232390538, 20434.59130817405, 20450.0, 20511.589768204853, 21018.579628407042, 21303.78696213018, 21401.678974815703, 21406.0, 21615.027323770155, 12382.442790074288, 12592.055595830532, 12600.495980161058, 12647.494100235888, 12651.114421990373, 15154.924225378803, 15193.316300766272, 15322.0, 15357.385704571692, 15511.224438778241, 12085.39573021363, 12298.631041068782, 12443.44004519817, 12615.116941814353, 12626.747465050465, 11737.765244694887, 12160.087993400353, 12215.877841221829, 12346.876531234573, 12471.438785254504, 10349.120324772479, 10454.895451045391, 10487.790244195226, 10516.474176291307, 10516.894831051592, 7661.042369703787, 7703.229677032252, 7748.457607967388, 7819.648115834687, 7863.606819659109, 7872.330851877426, 7899.368050555827, 7967.482113662593, 8039.437239393186, 8063.475874068166], "value": 7338.53281201456, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.2529996554701, 556.9081101618233, 581.5783556921235, 582.897992851251, 583.8919799837031, 6252.718627661755, 6272.679108729771, 6347.270063942642, 6446.194225721785, 6483.805485835421, 6273.592216505924, 6291.1821463209835, 6374.968125159367, 6391.872162556746, 6414.839629009277, 5313.760880760375, 5314.681119132845, 5317.6809391436445, 5322.175062865262, 5329.227262047001, 5172.793088276483, 5222.138347172712, 5293.6294459387855, 5302.734863256841, 5316.415194328621, 5155.664881782672, 5233.293505376782, 5251.317328747276, 5283.0, 5334.973325133369, 5242.374863792236, 5291.603129765244, 5307.150855863063, 5311.920321195185, 5332.226827110067, 4933.210686290212, 4954.157793175169, 4969.155243608595, 5016.874578135539, 5021.121303771825, 6351.9364806351805, 6355.538226207998, 6365.236171659384, 6375.87248255037, 6380.553361264713, 6272.341404152589, 6282.937170628281, 6304.7793327233785, 6352.618842869442, 6359.65021923794, 6725.7557351890055, 6729.293424190439, 6809.38715515606, 6840.179178498563, 6862.416694580967, 5534.889302213974, 5565.276514053187, 5576.9163462548095, 5581.5813813963705, 5593.8321850344555, 7132.928670713279, 7144.214136444987, 7154.499185057048, 7197.208307086216, 7211.278872112852, 5785.537157027427, 5799.057315799255, 5804.738786754522, 5842.970785146047, 5856.677882716406, 5897.439743224387, 5903.468687818117, 5915.556333275022, 5934.851628709231, 5950.9107363389585, 6174.629522228591, 6225.4514926491465, 6281.78013769525, 6296.653684047421, 6321.818972454495, 6472.417482426604, 6545.639989800511, 6658.300878407795, 6713.757954778382, 6815.0, 1977.8516611254213, 1994.3618042226637, 2020.656488396976, 2032.1058734156923, 2032.6646103392775, 1322.8081928120369, 1515.9924200379148, 1528.2654187497048, 1535.1710076558534, 1539.5227479481173, 6209.937900620936, 6225.346338634468, 6230.071866222868, 6299.716512756845, 6335.208098987627, 6819.965900170468, 6916.23921368649, 6926.826829329307, 6972.2330543640155, 6974.337437943387, 6267.370483674277, 6320.905186422116, 6333.366663333722, 6353.174087368612, 6359.3640635936035, 3604.8017359045502, 3607.549056367954, 3624.4925710400566, 3635.618260082706, 3687.520622319081, 6987.213758575392, 7078.0, 7130.180029296594, 7193.136823581099, 7199.460040496879, 6945.756898508629, 7042.647867606599, 7069.434445244368, 7071.469639777038, 7078.893816592756, 7132.251113632994, 7139.750108746169, 7176.138863336433, 7208.675609597476, 7218.747343842941, 6739.157605299338, 6807.421369183675, 6808.353206445379, 6831.829204269933, 6845.931540684529, 6688.431483323778, 6743.0, 6761.22245941723, 6764.966175169093, 6817.829554261183, 6966.1988871280455, 7079.752208672672, 7153.713851445884, 7157.677904494206, 7230.349268565854, 5750.482456578928, 5809.5933284671355, 5817.621854579439, 5822.388649191774, 5829.6793676346515, 6663.500237482305, 6714.0, 6882.621455819778, 6958.826029349307, 6965.164180298397, 6646.634435106115, 6681.2984636612455, 6699.933000669931, 6727.562708423937, 6776.254611992776, 6579.2104947406615, 6654.866902662016, 6721.193456785217, 6730.427913627396, 6755.256921738703, 6462.935370646233, 6541.934580654323, 6587.209534855848, 6593.769218077345, 6619.0, 6632.1709786276715, 6740.123783908257, 6743.393094621508, 6809.931900681128, 6839.965800170968, 6503.544751867324, 6519.967400162969, 6539.607623542603, 6542.640154791532, 6559.8032059038305], "value": 5412.507099116225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [881.9118088191178, 909.781652403423, 920.8664743612172, 998.6953979036393, 1022.5705203814397, 16883.0714310713, 17624.295028198856, 17652.205650745716, 17695.823041769578, 17707.20317585707, 16329.18354082297, 16854.915725421386, 16963.49109526713, 16988.575285617866, 17100.828991710077, 1011.822930987078, 1030.90206430389, 1032.1072272484284, 1034.3535290443472, 1035.2028937717953, 1051.8842927277992, 1057.6351158850184, 1058.6294796821128, 1061.0927656853396, 1063.8351055586397, 1058.6929790360787, 1092.2736380307085, 1095.5124969388626, 1096.9341839489616, 1099.6591056772427, 1091.1270983213396, 1093.7265683579105, 1093.9890601093966, 1094.7208461842258, 1097.1497089755424, 767.5087943716027, 796.3549524884862, 802.3060053054066, 802.3661307567073, 802.7029998900445, 17367.47897563075, 17416.65166696659, 17428.477145685523, 17465.476035718948, 17468.82531174685, 21984.560308793894, 22424.103035878547, 22500.32499025032, 22554.54890902189, 22672.886635566883, 17581.472555823344, 17626.911865440718, 17752.91123544387, 17873.46379608601, 18057.187426565837, 3756.436534519844, 3771.377722675755, 3777.490038844748, 3792.639699228569, 3799.1072098056934, 164237.17881410636, 165709.0, 165720.17139914224, 167136.16431917885, 168760.0, 17167.0, 17294.308227671005, 17352.0, 17388.565285867953, 17496.387626433014, 35307.0, 35436.0, 36222.63777362193, 36251.63748362535, 36262.63737362645, 8869.467831930106, 8943.37396382247, 8949.0, 9010.234130098885, 9014.0, 3891.883243502643, 3975.5428125765907, 3992.900177495586, 4000.4999375078114, 4024.1348110155873, 6050.45545900871, 6124.203853499106, 6134.938650613437, 6166.660833654192, 6180.536459765535, 1063.3513556730388, 1084.4198353880636, 1087.472575800736, 1103.178132291448, 1104.0725790335996, 634.0964126120352, 644.7614382678439, 654.564714464885, 662.7812821768805, 666.6266890541312, 12076.75846483083, 12255.0, 12779.552715654907, 13300.534481293109, 13323.866761332456, 686.4954258619911, 689.0491122251261, 700.8388070743757, 702.884024136022, 707.1761397971409, 5813.3895940925595, 5817.185594016841, 5893.705314734246, 5919.67441790697, 6020.548458865603, 157780.21109894378, 159367.0, 159457.0, 165692.0, 167351.0, 137321.0, 139825.30087349497, 140540.0, 142643.0, 144886.0, 118592.40703796598, 118880.0, 119177.40411297888, 120134.0, 121474.0, 11066.39134847591, 11079.058280046287, 11128.332300062022, 11244.943775281072, 11287.379194144072, 56830.147547785986, 57262.7136864313, 58935.0, 59008.409915900294, 59062.0, 75973.0, 76127.23872761201, 76767.23232767601, 77238.0, 77495.0, 17151.914240429218, 17766.20052097633, 17852.821471785115, 17889.105544722974, 17906.5523361917, 14858.925705371405, 15133.848661513244, 15181.468648597245, 15241.466548671187, 15301.770473442684, 16255.10596917181, 16300.673986519965, 16321.918390407973, 16326.918365408097, 16447.506574802774, 14305.499307524187, 14424.134551926918, 14553.781693274397, 14573.635659108608, 14574.489892853699, 15137.243137842843, 15140.470083547023, 15357.078575285519, 15387.230638468256, 15539.844601554292, 12217.14479986428, 12236.938815306223, 12443.93778031104, 12476.376181190722, 12500.437480313225, 12077.154599177973, 12199.329036903055, 12469.127161098637, 12596.622101336974, 12597.307148106576], "value": 9449.249751057276, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1366.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 105.0, 105.0, 104.0, 107.0, 110.0, 105.0, 105.0, 106.0, 107.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 114.0, 112.0, 137.0, 114.0, 113.0, 114.0, 112.0, 114.0, 139.0], "value": 118.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 161.0, 161.0, 161.0, 162.0, 162.0, 168.0, 166.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 74.0, 74.0, 73.0, 73.0, 74.0, 73.0, 76.0, 75.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 77.0, 78.0, 77.0, 78.0, 79.0, 79.0, 80.0, 79.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 111.0, 112.0, 111.0, 112.0, 111.0, 112.0, 113.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 151.0, 150.0, 150.0, 153.0, 150.0, 151.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 94.0, 96.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 46.0, 45.0, 47.0, 46.0, 45.0, 45.0, 46.0, 45.0, 46.0], "value": 45.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 36.0, 38.0, 36.0, 36.0, 37.0, 36.0, 36.0, 37.0, 37.0], "value": 36.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 71.0, 71.0, 77.0, 73.0, 69.0, 72.0, 74.0, 69.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 109.0, 101.0, 105.0, 101.0, 111.0, 103.0, 107.0, 102.0], "value": 105.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 147.0, 147.0, 150.0, 151.0, 151.0, 148.0, 156.0, 148.0, 147.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 59.0, 59.0, 57.0, 59.0, 58.0, 64.0, 57.0, 64.0], "value": 60.2, "unit": "score"}]}]} 03:09:53 INFO - Return code: 0 03:09:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:09:53 INFO - # TBPL SUCCESS # 03:09:53 INFO - Running post-action listener: _package_coverage_data 03:09:53 INFO - Running post-action listener: _resource_record_post_action 03:09:53 INFO - [mozharness: 2017-09-30 10:09:53.912663Z] Finished run-tests step (success) 03:09:53 INFO - Running post-run listener: _resource_record_post_run 03:09:53 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 10997760; Write bytes: 451870720; Read time: 2896; Write time: 347232 03:09:53 INFO - TinderboxPrint: CPU usage
13.1% 03:09:53 INFO - TinderboxPrint: I/O read bytes / time
10,997,760 / 2,896 03:09:53 INFO - TinderboxPrint: I/O write bytes / time
451,870,720 / 347,232 03:09:53 INFO - TinderboxPrint: CPU idle
8,224.3 (86.9%) 03:09:53 INFO - TinderboxPrint: CPU user
1,187.8 (12.6%) 03:09:53 INFO - TinderboxPrint: Swap in / out
0 / 0 03:09:53 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 166838272; Read time: 164; Write time: 164720 03:09:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:09:54 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 8757248; Write bytes: 278163456; Read time: 2556; Write time: 173484 03:09:54 INFO - Running post-run listener: _upload_blobber_files 03:09:54 INFO - Blob upload gear active. 03:09:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:09:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:09:54 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'] 03:09:54 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 03:09:55 INFO - (blobuploader) - INFO - Open directory for files ... 03:09:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:09:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:09:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:09:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:09:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:09:56 INFO - (blobuploader) - INFO - Done attempting. 03:09:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:09:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:09:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:09:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:09:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:09:56 INFO - (blobuploader) - INFO - Done attempting. 03:09:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:09:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:09:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:09:58 INFO - (blobuploader) - INFO - Done attempting. 03:09:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:09:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:09:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:10:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:05 INFO - (blobuploader) - INFO - Done attempting. 03:10:05 INFO - (blobuploader) - INFO - Iteration through files over. 03:10:05 INFO - Return code: 0 03:10:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:10:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:10:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab05715585a51cde85aaab14f7036a075ae3167821472f519412609d3641312d89532ef73e41f48274c5d3037b255e2701b770c251cd3c9b5b6014e08c4d46b0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be86a4d2ebadb628239211e3a442aa4ad7b4858e453b201fc0367fcb6603059c3ab78398bbf65bc08b4b55ec1b3df0d5da9591ec3c965929648293faa1129de0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21137db63d439818b6438a28f8254c24f82c39589652e9150beaef8b108d3fe13cf9000261c2e0d5070ff5b22b9d5a5fe5f22af1971b10ce5b2cc86b3b81217d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/671db006444e82ba10ff9375600bebe3be62d45f10d4976a2967697e9f048cc2cfe84e265c2ba7e33edf4266e2b871fc396debfadd00ab7e50acad4f0a1d4ba3"} 03:10:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:10:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:10:05 INFO - Contents: 03:10:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab05715585a51cde85aaab14f7036a075ae3167821472f519412609d3641312d89532ef73e41f48274c5d3037b255e2701b770c251cd3c9b5b6014e08c4d46b0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be86a4d2ebadb628239211e3a442aa4ad7b4858e453b201fc0367fcb6603059c3ab78398bbf65bc08b4b55ec1b3df0d5da9591ec3c965929648293faa1129de0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21137db63d439818b6438a28f8254c24f82c39589652e9150beaef8b108d3fe13cf9000261c2e0d5070ff5b22b9d5a5fe5f22af1971b10ce5b2cc86b3b81217d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/671db006444e82ba10ff9375600bebe3be62d45f10d4976a2967697e9f048cc2cfe84e265c2ba7e33edf4266e2b871fc396debfadd00ab7e50acad4f0a1d4ba3"} 03:10:05 INFO - Running post-run listener: copy_logs_to_upload_dir 03:10:05 INFO - Copying logs to upload dir... 03:10:05 INFO - mkdir: /builds/slave/test/build/upload/logs 03:10:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.571439 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-30 03:10:05.660238) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 03:10:05.667259) ========= 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=3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587 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/ab05715585a51cde85aaab14f7036a075ae3167821472f519412609d3641312d89532ef73e41f48274c5d3037b255e2701b770c251cd3c9b5b6014e08c4d46b0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be86a4d2ebadb628239211e3a442aa4ad7b4858e453b201fc0367fcb6603059c3ab78398bbf65bc08b4b55ec1b3df0d5da9591ec3c965929648293faa1129de0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21137db63d439818b6438a28f8254c24f82c39589652e9150beaef8b108d3fe13cf9000261c2e0d5070ff5b22b9d5a5fe5f22af1971b10ce5b2cc86b3b81217d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/671db006444e82ba10ff9375600bebe3be62d45f10d4976a2967697e9f048cc2cfe84e265c2ba7e33edf4266e2b871fc396debfadd00ab7e50acad4f0a1d4ba3"} build_url:https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/JRZ__e4oQ--coc8ZDHOmCQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab05715585a51cde85aaab14f7036a075ae3167821472f519412609d3641312d89532ef73e41f48274c5d3037b255e2701b770c251cd3c9b5b6014e08c4d46b0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be86a4d2ebadb628239211e3a442aa4ad7b4858e453b201fc0367fcb6603059c3ab78398bbf65bc08b4b55ec1b3df0d5da9591ec3c965929648293faa1129de0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21137db63d439818b6438a28f8254c24f82c39589652e9150beaef8b108d3fe13cf9000261c2e0d5070ff5b22b9d5a5fe5f22af1971b10ce5b2cc86b3b81217d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/671db006444e82ba10ff9375600bebe3be62d45f10d4976a2967697e9f048cc2cfe84e265c2ba7e33edf4266e2b871fc396debfadd00ab7e50acad4f0a1d4ba3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 03:10:05.703559) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 03:10:05.703891) ========= 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=3297cc2eebb5444a7688c33f000001b5-1506763731.503259-867581587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 03:10:05.782557) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 03:10:05.784900) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 03:10:05.785187) ========= ========= Total master_lag: 0.30 =========