builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-079 starttime: 1506559934.26 results: success (0) revision: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:14.257408) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:14.257961) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:14.258280) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:14.274595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:14.274994) ========= 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=1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 17:52:14-- 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% 37.2M=0s 2017-09-27 17:52:14 (37.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120863 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:14.413897) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:14.414362) ========= 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=1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018575 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:14.465332) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 17:52:14.465741) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 --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 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 --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=1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 17:52:14,523 truncating revision to first 12 chars 2017-09-27 17:52:14,523 Setting DEBUG logging. 2017-09-27 17:52:14,523 attempt 1/10 2017-09-27 17:52:14,523 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5ebe2e8980c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 17:52:15,496 unpacking tar archive at: mozilla-central-5ebe2e8980c6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.312090 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 17:52:15.794952) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:15.795341) ========= script_repo_revision: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:15.795782) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:15.796095) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 17:52:15.810402) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-27 17:52:15.810961) ========= /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=1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:52:15 INFO - MultiFileLogger online at 20170927 17:52:15 in /builds/slave/test 17:52:15 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 17:52:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:52:16 INFO - {'append_to_log': False, 17:52:16 INFO - 'base_work_dir': '/builds/slave/test', 17:52:16 INFO - 'blob_upload_branch': 'Firefox', 17:52:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:52:16 INFO - 'branch': 'Firefox', 17:52:16 INFO - 'buildbot_json_path': 'buildprops.json', 17:52:16 INFO - 'code_coverage': False, 17:52:16 INFO - 'config_files': ('talos/linux_config.py',), 17:52:16 INFO - 'default_actions': ('clobber', 17:52:16 INFO - 'read-buildbot-config', 17:52:16 INFO - 'download-and-extract', 17:52:16 INFO - 'populate-webroot', 17:52:16 INFO - 'create-virtualenv', 17:52:16 INFO - 'install', 17:52:16 INFO - 'setup-mitmproxy', 17:52:16 INFO - 'run-tests'), 17:52:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:52:16 INFO - 'disable_ccov_upload': False, 17:52:16 INFO - 'disable_stylo': False, 17:52:16 INFO - 'download_minidump_stackwalk': True, 17:52:16 INFO - 'download_symbols': 'ondemand', 17:52:16 INFO - 'enable_stylo': False, 17:52:16 INFO - 'enable_webrender': False, 17:52:16 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:52:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:52:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:52:16 INFO - 'gecko_profile': False, 17:52:16 INFO - 'gecko_profile_interval': 0, 17:52:16 INFO - 'installer_path': 'installer.exe', 17:52:16 INFO - 'log_level': 'info', 17:52:16 INFO - 'log_name': 'talos', 17:52:16 INFO - 'log_to_console': True, 17:52:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:52:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:52:16 INFO - 'opt_config_files': (), 17:52:16 INFO - 'pip_index': False, 17:52:16 INFO - 'suite': 'dromaeojs-e10s', 17:52:16 INFO - 'system_bits': '32', 17:52:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:52:16 INFO - 'title': 'talos-linux64-ix-079', 17:52:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:52:16 INFO - 'use_talos_json': True, 17:52:16 INFO - 'verify': 'False', 17:52:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:52:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:52:16 INFO - 'work_dir': 'build'} 17:52:16 INFO - [mozharness: 2017-09-28 00:52:16.010556Z] Running clobber step. 17:52:16 INFO - Running pre-action listener: _resource_record_pre_action 17:52:16 INFO - Running main action method: clobber 17:52:16 INFO - rmtree: /builds/slave/test/build 17:52:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:52:17 INFO - Running post-action listener: _resource_record_post_action 17:52:17 INFO - [mozharness: 2017-09-28 00:52:17.562665Z] Finished clobber step (success) 17:52:17 INFO - [mozharness: 2017-09-28 00:52:17.562764Z] Running read-buildbot-config step. 17:52:17 INFO - Running pre-action listener: _resource_record_pre_action 17:52:17 INFO - Running main action method: read_buildbot_config 17:52:17 INFO - Using buildbot properties: 17:52:17 INFO - { 17:52:17 INFO - "project": "", 17:52:17 INFO - "product": "firefox", 17:52:17 INFO - "who": "kwierso@gmail.com", 17:52:17 INFO - "installer_path": "public/build/target.tar.bz2", 17:52:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 17:52:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:52:17 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 17:52:17 INFO - "stage_platform": "linux64-qr", 17:52:17 INFO - "basedir": "/builds/slave/test", 17:52:17 INFO - "buildnumber": 90, 17:52:17 INFO - "platform": "ubuntu64_hw_qr", 17:52:17 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:52:17 INFO - "slavebuilddir": "test", 17:52:17 INFO - "taskId": "COBi6j3fR2y9aZDSCCJxqw", 17:52:17 INFO - "branch": "mozilla-central", 17:52:17 INFO - "script_repo_revision": "production", 17:52:17 INFO - "revision": "5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369", 17:52:17 INFO - "slavename": "talos-linux64-ix-079", 17:52:17 INFO - "repo_path": "mozilla-central" 17:52:17 INFO - } 17:52:17 INFO - Finding installer, test and symbols from parent task. 17:52:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/COBi6j3fR2y9aZDSCCJxqw'}, attempt #1 17:52:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/COBi6j3fR2y9aZDSCCJxqw'}, attempt #1 17:52:18 INFO - Task dependencies: ROcRQ0zqS7mg-39GJg0GHQ 17:52:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ'}, attempt #1 17:52:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 17:52:18 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.test_packages.json 17:52:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.crashreporter-symbols.zip 17:52:18 INFO - Running post-action listener: _resource_record_post_action 17:52:18 INFO - [mozharness: 2017-09-28 00:52:18.891753Z] Finished read-buildbot-config step (success) 17:52:18 INFO - [mozharness: 2017-09-28 00:52:18.891973Z] Running download-and-extract step. 17:52:18 INFO - Running pre-action listener: _resource_record_pre_action 17:52:18 INFO - Running main action method: download_and_extract 17:52:18 INFO - mkdir: /builds/slave/test/build/tests 17:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:18 INFO - trying https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.test_packages.json 17:52:18 INFO - Downloading https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:52:19 INFO - Downloaded 989 bytes. 17:52:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:52:19 INFO - Using the following test package requirements: 17:52:19 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:52:19 INFO - u'common': [u'target.common.tests.zip'], 17:52:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:52:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:52:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:52:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:52:19 INFO - u'mozbase': [u'target.common.tests.zip'], 17:52:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:52:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:52:19 INFO - u'web-platform': [u'target.common.tests.zip', 17:52:19 INFO - u'target.web-platform.tests.tar.gz'], 17:52:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:52:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:52:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.common.tests.zip 17:52:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:52:19 INFO - Fetch https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.common.tests.zip into memory 17:52:20 INFO - Content-Length response header: 54946002 17:52:20 INFO - Bytes received: 54946002 17:52:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.talos.tests.zip 17:52:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:52:31 INFO - Fetch https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.talos.tests.zip into memory 17:52:32 INFO - Content-Length response header: 14521162 17:52:32 INFO - Bytes received: 14521162 17:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:32 INFO - trying https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 17:52:32 INFO - Downloading https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:52:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:52:33 INFO - Downloaded 61824142 bytes. 17:52:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 17:52:33 INFO - mkdir: /builds/slave/test/properties 17:52:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:52:33 INFO - Writing to file /builds/slave/test/properties/build_url 17:52:33 INFO - Contents: 17:52:33 INFO - build_url:https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 17:52:33 INFO - Running post-action listener: _resource_record_post_action 17:52:33 INFO - Running post-action listener: find_tests_for_verification 17:52:33 INFO - Running post-action listener: set_extra_try_arguments 17:52:33 INFO - [mozharness: 2017-09-28 00:52:33.725281Z] Finished download-and-extract step (success) 17:52:33 INFO - [mozharness: 2017-09-28 00:52:33.725393Z] Running populate-webroot step. 17:52:33 INFO - Running pre-action listener: _resource_record_pre_action 17:52:33 INFO - Running main action method: populate_webroot 17:52:33 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:52:33 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['tresize', 17:52:33 INFO - 'tcanvasmark']}, 17:52:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:52:33 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['dromaeo_css', 17:52:33 INFO - 'kraken']}, 17:52:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:52:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:52:33 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:52:33 INFO - 'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:52:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:52:33 INFO - 'tests': ['damp', 'tps']}, 17:52:33 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:52:33 INFO - 'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['damp', 'tps']}, 17:52:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:52:33 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['dromaeo_dom']}, 17:52:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:52:33 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['basic_compositor_video', 17:52:33 INFO - 'glvideo']}, 17:52:33 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:52:33 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:52:33 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:52:33 INFO - 'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['ts_paint_webext', 17:52:33 INFO - 'tp5o_webext']}, 17:52:33 INFO - 'other-e10s': {'tests': ['a11yr', 17:52:33 INFO - 'ts_paint', 17:52:33 INFO - 'tpaint', 17:52:33 INFO - 'sessionrestore', 17:52:33 INFO - 'sessionrestore_many_windows', 17:52:33 INFO - 'sessionrestore_no_auto_restore', 17:52:33 INFO - 'tabpaint', 17:52:33 INFO - 'cpstartup']}, 17:52:33 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['a11yr', 17:52:33 INFO - 'ts_paint', 17:52:33 INFO - 'tpaint', 17:52:33 INFO - 'sessionrestore', 17:52:33 INFO - 'sessionrestore_many_windows', 17:52:33 INFO - 'sessionrestore_no_auto_restore', 17:52:33 INFO - 'tabpaint', 17:52:33 INFO - 'cpstartup']}, 17:52:33 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 17:52:33 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 17:52:33 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['perf_reftest_singletons']}, 17:52:33 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['perf_reftest']}, 17:52:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:52:33 INFO - 'tsvgr_opacity', 17:52:33 INFO - 'tart', 17:52:33 INFO - 'tscrollx', 17:52:33 INFO - 'tsvg_static']}, 17:52:33 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['tsvgx', 17:52:33 INFO - 'tsvgr_opacity', 17:52:33 INFO - 'tart', 17:52:33 INFO - 'tscrollx', 17:52:33 INFO - 'tsvg_static']}, 17:52:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:52:33 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:52:33 INFO - 'talos_options': ['--disable-stylo'], 17:52:33 INFO - 'tests': ['tp5o']}, 17:52:33 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:52:33 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:52:33 INFO - 'talos_options': ['--mitmproxy', 17:52:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:52:33 INFO - '--firstNonBlankPaint'], 17:52:33 INFO - 'tests': ['tp6_google', 17:52:33 INFO - 'tp6_youtube', 17:52:33 INFO - 'tp6_amazon', 17:52:33 INFO - 'tp6_facebook']}, 17:52:33 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:52:33 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:52:33 INFO - 'talos_options': ['--disable-stylo', 17:52:33 INFO - '--mitmproxy', 17:52:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:52:33 INFO - '--firstNonBlankPaint'], 17:52:33 INFO - 'tests': ['tp6_google', 17:52:33 INFO - 'tp6_youtube', 17:52:33 INFO - 'tp6_amazon', 17:52:33 INFO - 'tp6_facebook']}, 17:52:33 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:52:33 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:52:33 INFO - 'talos_options': ['--stylo-threads=1', 17:52:33 INFO - '--mitmproxy', 17:52:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:52:33 INFO - '--firstNonBlankPaint'], 17:52:33 INFO - 'tests': ['tp6_google', 17:52:33 INFO - 'tp6_youtube', 17:52:33 INFO - 'tp6_amazon', 17:52:33 INFO - 'tp6_facebook']}, 17:52:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:52:33 INFO - 'talos_options': ['--xperf_path', 17:52:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:52:33 INFO - 'tests': ['tp5n']}, 17:52:33 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:52:33 INFO - 'talos_options': ['--disable-stylo', 17:52:33 INFO - '--xperf_path', 17:52:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:52:33 INFO - 'tests': ['tp5n']}}} 17:52:33 INFO - Running post-action listener: _resource_record_post_action 17:52:33 INFO - [mozharness: 2017-09-28 00:52:33.743014Z] Finished populate-webroot step (success) 17:52:33 INFO - [mozharness: 2017-09-28 00:52:33.743122Z] Running create-virtualenv step. 17:52:33 INFO - Running pre-action listener: _resource_record_pre_action 17:52:33 INFO - Running main action method: create_virtualenv 17:52:33 INFO - Creating virtualenv /builds/slave/test/build/venv 17:52:33 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:52:33 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:52:33 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:52:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:52:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:52:34 INFO - Installing distribute.............................................................................................................................................................................................done. 17:52:35 INFO - Installing pip...............done. 17:52:35 INFO - Return code: 0 17:52:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:52:35 INFO - Reading from file tmpfile_stdout 17:52:35 INFO - Output received: 17:52:35 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:52:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:52:35 INFO - Using env: {'DISPLAY': ':0', 17:52:35 INFO - 'HOME': '/home/cltbld', 17:52:35 INFO - 'LANG': 'en_US.UTF-8', 17:52:35 INFO - 'LANGUAGE': 'en_US:en', 17:52:35 INFO - 'LOGNAME': 'cltbld', 17:52:35 INFO - 'MAIL': '/var/mail/cltbld', 17:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:35 INFO - 'MOZ_NO_REMOTE': '1', 17:52:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:35 INFO - 'NO_EM_RESTART': '1', 17:52:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:35 INFO - 'PWD': '/builds/slave/test', 17:52:35 INFO - 'SHELL': '/bin/bash', 17:52:35 INFO - 'SHLVL': '1', 17:52:35 INFO - 'TERM': 'linux', 17:52:35 INFO - 'TMOUT': '86400', 17:52:35 INFO - 'USER': 'cltbld', 17:52:35 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:35 INFO - '_': '/tools/buildbot/bin/python'} 17:52:36 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:52:36 INFO - Downloading/unpacking pip>=1.5 17:52:36 INFO - Running setup.py egg_info for package pip 17:52:36 INFO - warning: no files found matching 'pip/cacert.pem' 17:52:36 INFO - warning: no files found matching '*.html' under directory 'docs' 17:52:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:52:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:52:36 INFO - Installing collected packages: pip 17:52:36 INFO - Found existing installation: pip 1.1 17:52:36 INFO - Uninstalling pip: 17:52:36 INFO - Successfully uninstalled pip 17:52:36 INFO - Running setup.py install for pip 17:52:36 INFO - warning: no files found matching 'pip/cacert.pem' 17:52:36 INFO - warning: no files found matching '*.html' under directory 'docs' 17:52:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:52:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:52:36 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:52:36 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:52:36 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:52:36 INFO - Successfully installed pip 17:52:36 INFO - Cleaning up... 17:52:36 INFO - Return code: 0 17:52:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:52:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:52:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:52:36 INFO - Using env: {'DISPLAY': ':0', 17:52:36 INFO - 'HOME': '/home/cltbld', 17:52:36 INFO - 'LANG': 'en_US.UTF-8', 17:52:36 INFO - 'LANGUAGE': 'en_US:en', 17:52:36 INFO - 'LOGNAME': 'cltbld', 17:52:36 INFO - 'MAIL': '/var/mail/cltbld', 17:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:36 INFO - 'MOZ_NO_REMOTE': '1', 17:52:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:36 INFO - 'NO_EM_RESTART': '1', 17:52:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:36 INFO - 'PWD': '/builds/slave/test', 17:52:36 INFO - 'SHELL': '/bin/bash', 17:52:36 INFO - 'SHLVL': '1', 17:52:36 INFO - 'TERM': 'linux', 17:52:36 INFO - 'TMOUT': '86400', 17:52:36 INFO - 'USER': 'cltbld', 17:52:36 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:36 INFO - '_': '/tools/buildbot/bin/python'} 17:52:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:36 INFO - Downloading/unpacking psutil>=3.1.1 17:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:52:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:52:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:52:38 INFO - Installing collected packages: psutil 17:52:38 INFO - Running setup.py install for psutil 17:52:38 INFO - building 'psutil._psutil_linux' extension 17:52:38 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 17:52:38 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 17:52:38 INFO - building 'psutil._psutil_posix' extension 17:52:38 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 17:52:38 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 17:52:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:52:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:52:38 INFO - Successfully installed psutil 17:52:38 INFO - Cleaning up... 17:52:38 INFO - Return code: 0 17:52:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:38 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 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:38 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 17:52:38 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 17:52:38 INFO - Using env: {'DISPLAY': ':0', 17:52:38 INFO - 'HOME': '/home/cltbld', 17:52:38 INFO - 'LANG': 'en_US.UTF-8', 17:52:38 INFO - 'LANGUAGE': 'en_US:en', 17:52:38 INFO - 'LOGNAME': 'cltbld', 17:52:38 INFO - 'MAIL': '/var/mail/cltbld', 17:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:38 INFO - 'MOZ_NO_REMOTE': '1', 17:52:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:38 INFO - 'NO_EM_RESTART': '1', 17:52:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:38 INFO - 'PWD': '/builds/slave/test', 17:52:38 INFO - 'SHELL': '/bin/bash', 17:52:38 INFO - 'SHLVL': '1', 17:52:38 INFO - 'TERM': 'linux', 17:52:38 INFO - 'TMOUT': '86400', 17:52:38 INFO - 'USER': 'cltbld', 17:52:38 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:38 INFO - '_': '/tools/buildbot/bin/python'} 17:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:52:38 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 17:52:38 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 17:52:38 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 17:52:38 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 17:52:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:52:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:52:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:52:40 INFO - Installing collected packages: mozsystemmonitor 17:52:40 INFO - Running setup.py install for mozsystemmonitor 17:52:41 INFO - Successfully installed mozsystemmonitor 17:52:41 INFO - Cleaning up... 17:52:42 INFO - Return code: 0 17:52:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:52:42 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')]} 17:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:42 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')]} 17:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:42 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 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:42 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 17:52:42 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 17:52:42 INFO - Using env: {'DISPLAY': ':0', 17:52:42 INFO - 'HOME': '/home/cltbld', 17:52:42 INFO - 'LANG': 'en_US.UTF-8', 17:52:42 INFO - 'LANGUAGE': 'en_US:en', 17:52:42 INFO - 'LOGNAME': 'cltbld', 17:52:42 INFO - 'MAIL': '/var/mail/cltbld', 17:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:42 INFO - 'MOZ_NO_REMOTE': '1', 17:52:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:42 INFO - 'NO_EM_RESTART': '1', 17:52:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:42 INFO - 'PWD': '/builds/slave/test', 17:52:42 INFO - 'SHELL': '/bin/bash', 17:52:42 INFO - 'SHLVL': '1', 17:52:42 INFO - 'TERM': 'linux', 17:52:42 INFO - 'TMOUT': '86400', 17:52:42 INFO - 'USER': 'cltbld', 17:52:42 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:42 INFO - '_': '/tools/buildbot/bin/python'} 17:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:42 INFO - Downloading/unpacking jsonschema==2.5.1 17:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:52:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:52:43 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 17:52:43 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 17:52:43 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 17:52:43 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 17:52:43 INFO - Downloading functools32-3.2.3-2.tar.gz 17:52:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:52:43 INFO - warning: no files found matching '*.txt' 17:52:43 INFO - no previously-included directories found matching 'build' 17:52:43 INFO - no previously-included directories found matching 'dist' 17:52:43 INFO - no previously-included directories found matching '.git*' 17:52:43 INFO - Installing collected packages: jsonschema, functools32 17:52:43 INFO - Running setup.py install for functools32 17:52:43 INFO - warning: no files found matching '*.txt' 17:52:43 INFO - no previously-included directories found matching 'build' 17:52:43 INFO - no previously-included directories found matching 'dist' 17:52:43 INFO - no previously-included directories found matching '.git*' 17:52:43 INFO - Successfully installed jsonschema functools32 17:52:43 INFO - Cleaning up... 17:52:43 INFO - Return code: 0 17:52:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:52: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')]} 17:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52: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')]} 17:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:43 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 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:43 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 17:52:43 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 17:52:43 INFO - Using env: {'DISPLAY': ':0', 17:52:43 INFO - 'HOME': '/home/cltbld', 17:52:43 INFO - 'LANG': 'en_US.UTF-8', 17:52:43 INFO - 'LANGUAGE': 'en_US:en', 17:52:43 INFO - 'LOGNAME': 'cltbld', 17:52:43 INFO - 'MAIL': '/var/mail/cltbld', 17:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:43 INFO - 'MOZ_NO_REMOTE': '1', 17:52:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:43 INFO - 'NO_EM_RESTART': '1', 17:52:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:43 INFO - 'PWD': '/builds/slave/test', 17:52:43 INFO - 'SHELL': '/bin/bash', 17:52:43 INFO - 'SHLVL': '1', 17:52:43 INFO - 'TERM': 'linux', 17:52:43 INFO - 'TMOUT': '86400', 17:52:43 INFO - 'USER': 'cltbld', 17:52:43 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:43 INFO - '_': '/tools/buildbot/bin/python'} 17:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:52:43 INFO - Cleaning up... 17:52:43 INFO - Return code: 0 17:52:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:52: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')]} 17:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52: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')]} 17:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:43 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 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:43 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 17:52:43 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 17:52:43 INFO - Using env: {'DISPLAY': ':0', 17:52:43 INFO - 'HOME': '/home/cltbld', 17:52:43 INFO - 'LANG': 'en_US.UTF-8', 17:52:43 INFO - 'LANGUAGE': 'en_US:en', 17:52:43 INFO - 'LOGNAME': 'cltbld', 17:52:43 INFO - 'MAIL': '/var/mail/cltbld', 17:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:43 INFO - 'MOZ_NO_REMOTE': '1', 17:52:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:43 INFO - 'NO_EM_RESTART': '1', 17:52:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:43 INFO - 'PWD': '/builds/slave/test', 17:52:43 INFO - 'SHELL': '/bin/bash', 17:52:43 INFO - 'SHLVL': '1', 17:52:43 INFO - 'TERM': 'linux', 17:52:43 INFO - 'TMOUT': '86400', 17:52:43 INFO - 'USER': 'cltbld', 17:52:43 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:43 INFO - '_': '/tools/buildbot/bin/python'} 17:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:44 INFO - Downloading/unpacking blobuploader==1.2.4 17:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:45 INFO - Downloading blobuploader-1.2.4.tar.gz 17:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:52:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:52:45 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 17:52:45 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 17:52:45 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 17:52:45 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 17:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:52:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:52:45 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 17:52:45 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 17:52:45 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 17:52:45 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 17:52:45 INFO - Downloading docopt-0.6.1.tar.gz 17:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:52:45 INFO - Installing collected packages: blobuploader, requests, docopt 17:52:45 INFO - Running setup.py install for blobuploader 17:52:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:52:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:52:46 INFO - Running setup.py install for requests 17:52:46 INFO - Running setup.py install for docopt 17:52:46 INFO - Successfully installed blobuploader requests docopt 17:52:46 INFO - Cleaning up... 17:52:46 INFO - Return code: 0 17:52:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:52: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')]} 17:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52: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')]} 17:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:46 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 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:46 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 17:52:46 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 17:52:46 INFO - Using env: {'DISPLAY': ':0', 17:52:46 INFO - 'HOME': '/home/cltbld', 17:52:46 INFO - 'LANG': 'en_US.UTF-8', 17:52:46 INFO - 'LANGUAGE': 'en_US:en', 17:52:46 INFO - 'LOGNAME': 'cltbld', 17:52:46 INFO - 'MAIL': '/var/mail/cltbld', 17:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:46 INFO - 'MOZ_NO_REMOTE': '1', 17:52:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:46 INFO - 'NO_EM_RESTART': '1', 17:52:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:46 INFO - 'PWD': '/builds/slave/test', 17:52:46 INFO - 'SHELL': '/bin/bash', 17:52:46 INFO - 'SHLVL': '1', 17:52:46 INFO - 'TERM': 'linux', 17:52:46 INFO - 'TMOUT': '86400', 17:52:46 INFO - 'USER': 'cltbld', 17:52:46 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:46 INFO - '_': '/tools/buildbot/bin/python'} 17:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:52:46 INFO - Running setup.py (path:/tmp/pip-hFxOl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:52:46 INFO - Running setup.py (path:/tmp/pip-3ThB2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:52:46 INFO - Running setup.py (path:/tmp/pip-an8RHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:52:46 INFO - Running setup.py (path:/tmp/pip-myI18G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:52:47 INFO - Running setup.py (path:/tmp/pip-bZoDaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:52:47 INFO - Running setup.py (path:/tmp/pip-BiR8uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:52:47 INFO - Running setup.py (path:/tmp/pip-z5l1aT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:52:47 INFO - Running setup.py (path:/tmp/pip-lQ6_vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:52:47 INFO - Running setup.py (path:/tmp/pip-PxZmsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:52:47 INFO - Running setup.py (path:/tmp/pip-YLkc4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:52:47 INFO - Running setup.py (path:/tmp/pip-m_ni9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:52:47 INFO - Running setup.py (path:/tmp/pip-qGUhQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:52:47 INFO - Running setup.py (path:/tmp/pip-t9uqH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:52:47 INFO - Running setup.py (path:/tmp/pip-oUER6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:52:47 INFO - Running setup.py (path:/tmp/pip-gf21OZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:52:47 INFO - Running setup.py (path:/tmp/pip-s0zci2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:52:47 INFO - Running setup.py (path:/tmp/pip-kVyV8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:52:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:52:47 INFO - Running setup.py install for manifestparser 17:52:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:52:48 INFO - Running setup.py install for mozcrash 17:52:48 INFO - Running setup.py install for mozdebug 17:52:48 INFO - Running setup.py install for mozdevice 17:52:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:52:48 INFO - Running setup.py install for mozfile 17:52:48 INFO - Running setup.py install for mozhttpd 17:52:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:52:48 INFO - Running setup.py install for mozinfo 17:52:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:52:48 INFO - Running setup.py install for mozInstall 17:52:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:52:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:52:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:52:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:52:48 INFO - Running setup.py install for mozleak 17:52:48 INFO - Running setup.py install for mozlog 17:52:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:52:48 INFO - Running setup.py install for moznetwork 17:52:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:52:49 INFO - Running setup.py install for mozprocess 17:52:49 INFO - Running setup.py install for mozprofile 17:52:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:52:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:52:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:52:49 INFO - Running setup.py install for mozrunner 17:52:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:52:49 INFO - Running setup.py install for mozscreenshot 17:52:49 INFO - Running setup.py install for moztest 17:52:49 INFO - Running setup.py install for mozversion 17:52:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:52:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:52:49 INFO - Cleaning up... 17:52:49 INFO - Return code: 0 17:52:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:49 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 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:49 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 17:52:49 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 17:52:49 INFO - Using env: {'DISPLAY': ':0', 17:52:49 INFO - 'HOME': '/home/cltbld', 17:52:49 INFO - 'LANG': 'en_US.UTF-8', 17:52:49 INFO - 'LANGUAGE': 'en_US:en', 17:52:49 INFO - 'LOGNAME': 'cltbld', 17:52:49 INFO - 'MAIL': '/var/mail/cltbld', 17:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:49 INFO - 'MOZ_NO_REMOTE': '1', 17:52:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:49 INFO - 'NO_EM_RESTART': '1', 17:52:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:49 INFO - 'PWD': '/builds/slave/test', 17:52:49 INFO - 'SHELL': '/bin/bash', 17:52:49 INFO - 'SHLVL': '1', 17:52:49 INFO - 'TERM': 'linux', 17:52:49 INFO - 'TMOUT': '86400', 17:52:49 INFO - 'USER': 'cltbld', 17:52:49 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:49 INFO - '_': '/tools/buildbot/bin/python'} 17:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:52:49 INFO - Running setup.py (path:/tmp/pip-rE7qc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:52:49 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)) 17:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:52:49 INFO - Running setup.py (path:/tmp/pip-9zpUrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:52:50 INFO - Running setup.py (path:/tmp/pip-6fHim3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:52:50 INFO - Running setup.py (path:/tmp/pip-ErbVua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:52:50 INFO - Running setup.py (path:/tmp/pip-e76vbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:52:50 INFO - Running setup.py (path:/tmp/pip-CKG87v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:52:50 INFO - Running setup.py (path:/tmp/pip-8VEasG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:52:50 INFO - Running setup.py (path:/tmp/pip-05zOEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:52:50 INFO - Running setup.py (path:/tmp/pip-ZPkuZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:52:50 INFO - Running setup.py (path:/tmp/pip-VgjBha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:52:50 INFO - Running setup.py (path:/tmp/pip-wvq3Dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:52:50 INFO - Running setup.py (path:/tmp/pip-sRifA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:52:50 INFO - Running setup.py (path:/tmp/pip-ORPDI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:52:50 INFO - Running setup.py (path:/tmp/pip-atC07U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:52:50 INFO - Running setup.py (path:/tmp/pip-juCLeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:52:50 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)) 17:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:52:50 INFO - Running setup.py (path:/tmp/pip-bL9rMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:52:51 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)) 17:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:52:51 INFO - Running setup.py (path:/tmp/pip-xGTeep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:52:51 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)) 17:52:51 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)) 17:52:51 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)) 17:52:51 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)) 17:52:51 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)) 17:52:51 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)) 17:52:51 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)) 17:52:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:52: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 17:52: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 17:52: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 17:52: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 17:52:52 INFO - Downloading blessings-1.6.tar.gz 17:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:52:52 INFO - Installing collected packages: blessings 17:52:52 INFO - Running setup.py install for blessings 17:52:52 INFO - Successfully installed blessings 17:52:52 INFO - Cleaning up... 17:52:52 INFO - Return code: 0 17:52:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:52:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:52:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:52:52 INFO - Reading from file tmpfile_stdout 17:52:52 INFO - Current package versions: 17:52:52 INFO - argparse == 1.2.1 17:52:52 INFO - blessings == 1.6 17:52:52 INFO - blobuploader == 1.2.4 17:52:52 INFO - docopt == 0.6.1 17:52:52 INFO - functools32 == 3.2.3-2 17:52:52 INFO - jsonschema == 2.5.1 17:52:52 INFO - manifestparser == 1.1 17:52:52 INFO - mozInstall == 1.13 17:52:52 INFO - mozcrash == 1.0 17:52:52 INFO - mozdebug == 0.1 17:52:52 INFO - mozdevice == 0.51 17:52:52 INFO - mozfile == 1.2 17:52:52 INFO - mozhttpd == 0.7 17:52:52 INFO - mozinfo == 0.10 17:52:52 INFO - mozleak == 0.1 17:52:52 INFO - mozlog == 3.5 17:52:52 INFO - moznetwork == 0.27 17:52:52 INFO - mozprocess == 0.25 17:52:52 INFO - mozprofile == 0.28 17:52:52 INFO - mozrunner == 6.13 17:52:52 INFO - mozscreenshot == 0.1 17:52:52 INFO - mozsystemmonitor == 0.3 17:52:52 INFO - moztest == 0.8 17:52:52 INFO - mozversion == 1.4 17:52:52 INFO - psutil == 3.1.1 17:52:52 INFO - requests == 1.2.3 17:52:52 INFO - wsgiref == 0.1.2 17:52:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:52: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')]} 17:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52: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')]} 17:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:52 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 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:52 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 17:52:52 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 17:52:52 INFO - Using env: {'DISPLAY': ':0', 17:52:52 INFO - 'HOME': '/home/cltbld', 17:52:52 INFO - 'LANG': 'en_US.UTF-8', 17:52:52 INFO - 'LANGUAGE': 'en_US:en', 17:52:52 INFO - 'LOGNAME': 'cltbld', 17:52:52 INFO - 'MAIL': '/var/mail/cltbld', 17:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:52 INFO - 'MOZ_NO_REMOTE': '1', 17:52:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:52 INFO - 'NO_EM_RESTART': '1', 17:52:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:52 INFO - 'PWD': '/builds/slave/test', 17:52:52 INFO - 'SHELL': '/bin/bash', 17:52:52 INFO - 'SHLVL': '1', 17:52:52 INFO - 'TERM': 'linux', 17:52:52 INFO - 'TMOUT': '86400', 17:52:52 INFO - 'USER': 'cltbld', 17:52:52 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:52 INFO - '_': '/tools/buildbot/bin/python'} 17:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:52 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)) 17:52:52 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)) 17:52:52 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)) 17:52:52 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)) 17:52:52 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)) 17:52:52 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)) 17:52:52 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)) 17:52:52 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)) 17:52:52 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)) 17:52:52 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:52: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 17:52: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 17:52: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 17:52: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 17:52:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:52:54 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)) 17:52:54 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)) 17:52:54 INFO - Installing collected packages: simplejson 17:52:54 INFO - Running setup.py install for simplejson 17:52:54 INFO - building 'simplejson._speedups' extension 17:52:54 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 17:52:55 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 17:52:55 INFO - Successfully installed simplejson 17:52:55 INFO - Cleaning up... 17:52:55 INFO - Return code: 0 17:52:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:55 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 0x1afe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', '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 17:52:55 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 17:52:55 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 17:52:55 INFO - Using env: {'DISPLAY': ':0', 17:52:55 INFO - 'HOME': '/home/cltbld', 17:52:55 INFO - 'LANG': 'en_US.UTF-8', 17:52:55 INFO - 'LANGUAGE': 'en_US:en', 17:52:55 INFO - 'LOGNAME': 'cltbld', 17:52:55 INFO - 'MAIL': '/var/mail/cltbld', 17:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:55 INFO - 'MOZ_NO_REMOTE': '1', 17:52:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:55 INFO - 'NO_EM_RESTART': '1', 17:52:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:55 INFO - 'PWD': '/builds/slave/test', 17:52:55 INFO - 'SHELL': '/bin/bash', 17:52:55 INFO - 'SHLVL': '1', 17:52:55 INFO - 'TERM': 'linux', 17:52:55 INFO - 'TMOUT': '86400', 17:52:55 INFO - 'USER': 'cltbld', 17:52:55 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:55 INFO - '_': '/tools/buildbot/bin/python'} 17:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:52:55 INFO - Cleaning up... 17:52:55 INFO - Return code: 0 17:52:55 INFO - Running post-action listener: _resource_record_post_action 17:52:55 INFO - Running post-action listener: _start_resource_monitoring 17:52:55 INFO - Starting resource monitoring. 17:52:55 INFO - [mozharness: 2017-09-28 00:52:55.402792Z] Finished create-virtualenv step (success) 17:52:55 INFO - [mozharness: 2017-09-28 00:52:55.403261Z] Running install step. 17:52:55 INFO - Running pre-action listener: _resource_record_pre_action 17:52:55 INFO - Running main action method: install 17:52:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:52:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:52:55 INFO - Reading from file tmpfile_stdout 17:52:55 INFO - Detecting whether we're running mozinstall >=1.0... 17:52:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:52:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:52:55 INFO - Reading from file tmpfile_stdout 17:52:55 INFO - Output received: 17:52:55 INFO - Usage: mozinstall [options] installer 17:52:55 INFO - Options: 17:52:55 INFO - -h, --help show this help message and exit 17:52:55 INFO - -d DEST, --destination=DEST 17:52:55 INFO - Directory to install application into. [default: 17:52:55 INFO - "/builds/slave/test"] 17:52:55 INFO - --app=APP Application being installed. [default: firefox] 17:52:55 INFO - mkdir: /builds/slave/test/build/application 17:52:55 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'] 17:52:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:53:13 INFO - Reading from file tmpfile_stdout 17:53:13 INFO - Output received: 17:53:13 INFO - /builds/slave/test/build/application/firefox/firefox 17:53:13 INFO - Running post-action listener: _resource_record_post_action 17:53:13 INFO - [mozharness: 2017-09-28 00:53:13.552043Z] Finished install step (success) 17:53:13 INFO - [mozharness: 2017-09-28 00:53:13.552384Z] Running setup-mitmproxy step. 17:53:13 INFO - Running pre-action listener: _resource_record_pre_action 17:53:13 INFO - Running main action method: setup_mitmproxy 17:53:13 INFO - Skipping: mitmproxy is not required 17:53:13 INFO - Running post-action listener: _resource_record_post_action 17:53:13 INFO - [mozharness: 2017-09-28 00:53:13.554042Z] Finished setup-mitmproxy step (success) 17:53:13 INFO - [mozharness: 2017-09-28 00:53:13.554376Z] Running run-tests step. 17:53:13 INFO - Running pre-action listener: _resource_record_pre_action 17:53:13 INFO - Running pre-action listener: _set_gcov_prefix 17:53:13 INFO - Running main action method: run_tests 17:53:13 WARNING - Try message not found. 17:53:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:53:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:53:13 INFO - Python 2.7.3 17:53:13 INFO - Return code: 0 17:53:13 INFO - grabbing minidump binary from tooltool 17:53:13 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')]} 17:53:13 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 0x1e68d60>, '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 0x1e69a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e69ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:53:13 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 17:53:13 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 17:53:13 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 17:53:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:53:13 INFO - Return code: 0 17:53:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:53:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:53:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:53:13 INFO - ENV: RUST_BACKTRACE is now full 17:53:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:53:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:53:13 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/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 17:53:13 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/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --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 17:53:13 INFO - Using env: {'DISPLAY': ':0', 17:53:13 INFO - 'HOME': '/home/cltbld', 17:53:13 INFO - 'LANG': 'en_US.UTF-8', 17:53:13 INFO - 'LANGUAGE': 'en_US:en', 17:53:13 INFO - 'LOGNAME': 'cltbld', 17:53:13 INFO - 'MAIL': '/var/mail/cltbld', 17:53:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:53:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:53:13 INFO - 'MOZ_ACCELERATED': '1', 17:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:13 INFO - 'MOZ_NO_REMOTE': '1', 17:53:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:53:13 INFO - 'MOZ_WEBRENDER': '1', 17:53:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:53:13 INFO - 'NO_EM_RESTART': '1', 17:53:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:13 INFO - 'PWD': '/builds/slave/test', 17:53:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:53:13 INFO - 'RUST_BACKTRACE': 'full', 17:53:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:53:13 INFO - 'SHELL': '/bin/bash', 17:53:13 INFO - 'SHLVL': '1', 17:53:13 INFO - 'TERM': 'linux', 17:53:13 INFO - 'TMOUT': '86400', 17:53:13 INFO - 'USER': 'cltbld', 17:53:13 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:13 INFO - '_': '/tools/buildbot/bin/python'} 17:53:13 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/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 17:53:13 INFO - mozversion application_buildid: 20170927235256 17:53:13 INFO - mozversion application_changeset: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 17:53:13 INFO - mozversion application_display_name: Nightly 17:53:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:53:13 INFO - mozversion application_name: Firefox 17:53:13 INFO - mozversion application_remotingname: firefox 17:53:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:53:13 INFO - mozversion application_vendor: Mozilla 17:53:13 INFO - mozversion application_version: 58.0a1 17:53:13 INFO - mozversion platform_buildid: 20170927235256 17:53:13 INFO - mozversion platform_changeset: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 17:53:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:53:13 INFO - mozversion platform_version: 58.0a1 17:53:13 INFO - using testdate: 1506559993 17:53:13 INFO - actual date: 1506559993 17:53:13 INFO - starting webserver on 'localhost:45716' 17:53:13 INFO - SUITE-START | Running 2 tests 17:53:13 INFO - TEST-START | dromaeo_css 17:53:13 INFO - Initialising browser for dromaeo_css test... 17:53:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45716/getInfo.html -profile /tmp/tmpQJD5tJ/profile 17:53:13 INFO - TEST-INFO | started process 15802 (/builds/slave/test/build/application/firefox/firefox http://localhost:45716/getInfo.html) 17:53:20 INFO - TEST-INFO | 15802: exit 0 17:53:20 INFO - Browser initialized. 17:53:21 INFO - Running cycle 1/1 for dromaeo_css test... 17:53:21 INFO - Using env: {'DISPLAY': ':0', 17:53:21 INFO - 'HOME': '/home/cltbld', 17:53:21 INFO - 'JSGC_DISABLE_POISONING': '1', 17:53:21 INFO - 'LANG': 'en_US.UTF-8', 17:53:21 INFO - 'LANGUAGE': 'en_US:en', 17:53:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:53:21 INFO - 'LOGNAME': 'cltbld', 17:53:21 INFO - 'MAIL': '/var/mail/cltbld', 17:53:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:53:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:53:21 INFO - 'MOZ_ACCELERATED': '1', 17:53:21 INFO - 'MOZ_CRASHREPORTER': '1', 17:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:53:21 INFO - 'MOZ_NO_REMOTE': '1', 17:53:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:53:21 INFO - 'MOZ_WEBRENDER': '1', 17:53:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:53:21 INFO - 'NO_EM_RESTART': '1', 17:53:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:21 INFO - 'PWD': '/builds/slave/test', 17:53:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:53:21 INFO - 'RUST_BACKTRACE': 'full', 17:53:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:53:21 INFO - 'SHELL': '/bin/bash', 17:53:21 INFO - 'SHLVL': '1', 17:53:21 INFO - 'TERM': 'linux', 17:53:21 INFO - 'TMOUT': '86400', 17:53:21 INFO - 'USER': 'cltbld', 17:53:21 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 17:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:21 INFO - '_': '/tools/buildbot/bin/python'} 17:53:21 INFO - TEST-INFO | started process 16047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQJD5tJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:53:21 INFO - PID 16047 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:53:21 INFO - PID 16047 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:53:22 INFO - PID 16047 | 17:53:22 INFO - PID 16047 | (/builds/slave/test/build/application/firefox/firefox:16107): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:53:22 INFO - PID 16047 | 17:53:32 INFO - PID 16047 | RSS: Main: 189087744 17:53:32 INFO - PID 16047 | 17:56:30 INFO - PID 16047 | Cycle 1(1): loaded http://localhost:45716/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45716/tests/dromaeo/cssquery-ext.html) 17:56:31 INFO - PID 16047 | RSS: Main: 187871232 17:56:31 INFO - PID 16047 | 17:59:33 INFO - PID 16047 | Cycle 1(1): loaded http://localhost:45716/tests/dromaeo/cssquery-ext.html (next: http://localhost:45716/tests/dromaeo/cssquery-jquery.html) 17:59:34 INFO - PID 16047 | RSS: Main: 186408960 17:59:34 INFO - PID 16047 | 18:02:36 INFO - PID 16047 | Cycle 1(1): loaded http://localhost:45716/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45716/tests/dromaeo/cssquery-mootools.html) 18:02:37 INFO - PID 16047 | RSS: Main: 187695104 18:02:37 INFO - PID 16047 | 18:05:38 INFO - PID 16047 | Cycle 1(1): loaded http://localhost:45716/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45716/tests/dromaeo/cssquery-prototype.html) 18:05:39 INFO - PID 16047 | RSS: Main: 187273216 18:05:39 INFO - PID 16047 | 18:08:43 INFO - PID 16047 | Cycle 1(1): loaded http://localhost:45716/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45716/tests/dromaeo/cssquery-yui.html) 18:08:43 INFO - PID 16047 | RSS: Main: 189853696 18:08:43 INFO - PID 16047 | 18:11:45 INFO - PID 16047 | Cycle 1(1): loaded http://localhost:45716/tests/dromaeo/cssquery-yui.html (next: http://localhost:45716/tests/dromaeo/cssquery-dojo.html) 18:11:45 INFO - PID 16047 | RSS: Main: 191311872 18:11:45 INFO - PID 16047 | 18:11:45 INFO - PID 16047 | __start_tp_report 18:11:45 INFO - PID 16047 | _x_x_mozilla_page_load 18:11:45 INFO - PID 16047 | _x_x_mozilla_page_load_details 18:11:45 INFO - PID 16047 | |i|pagename|runs| 18:11:45 INFO - PID 16047 | |0;dojo.html;3164.003338948231;3193.0261270312553;3205.3108581654938;3207.53490743842;3235.2235463488755;6158.722857471414;6160.5687601867885;6218.284897236807;6236.844078898024;6295.968520157404;6168.321484636686;6273.404026617475;6275.717592708328;6276.340984196662;6277.5291853110975;5718;5724.885502289952;5750.1949727038245;5774.335951365605;5806.10005449154;5709.971450142743;5739.51214146796;5740.7703691852275;5842.912356314659;5857.179994800731;5742;5804.129380592924;5837.6497410155525;5852.268466441695;5920.911186332209;5639.68981706006;5677.886442271173;5736.7992120276;5737.196792449061;5777.19119323295;5499.945000550023;5597.076482380402;5601;5610.4670056344585;5627.887442251132;6035.939640603582;6103;6150.261968563756;6179;6226;6475.643839588821;6500.93499065008;6548.443382312462;6564.21229452464;6633.402993730588;6644.368784965421;6716.697748601325;6752.527323087385;6786.864262714767;6793.898091528631;6183.227096612924;6222.875542489171;6229;6231.065340198916;6246;6762.391384775394;6764.120664313607;6764.188297404343;6848.863022739617;6872.862542749117;6338.049292606146;6510.08858759774;6545;6559;6578.868422631521;6544.312847151075;6551.475881929434;6580;6662.433693136138;6717.764878229238;6611.471082313404;6649.966750166218;6665.566738162004;6690.3978641921485;6697.062411262427;6587.374199451045;6588.53880228389;6653.933460665331;6662.43369313604;6665.500087493458;4847.466778654345;4895.534924182197;4924.212126059813;4940.752962351868;4968.981358821433;4242.066745316026;4295.22685916538;4316.395704601358;4325.675574331937;4399.12017596482;5701.0308247598;5727.484526392562;5751.9424805751405;5754.827355179351;5758;6664.700088496029;6711.228208755958;6751.189857217068;6756.763513277012;6788.151481064867;5658.066419040894;5711.6858572778065;5775.451332123442;5792.218050563204;5796.130580412888;5553.305836770403;5609.298837645294;5663.150527420919;5681.517071049007;5766.04860198071;6338.207724034496;6343.619382837044;6346.9682651586445;6354.142190804275;6357.523185761086;6445.16212892321;6552.475801935833;6557.475401967927;6607.438367738796;6735.0570920072205;6471.7734879279;6522.34776522344;6539.6403197824575;6557.147570815763;6560.081588577698;6551.21385433751;6552.63960482178;6560.540762146604;6607.2071351438135;6625.204975402982;6496.350364963655;6664.966675166593;6673.232578253563;6682.966585167043;6720.798376048726;6306.054091886253;6397.136386587751;6403.039544068427;6409.647469389042;6457.7094030767785;6524.771632992823;6546.803595892131;6564.606123632598;6577.539572229898;6580.407763301135;6400.071989561395;6431.614103153825;6475.287718351072;6497.805065848032;6520.152380190545;6544.869102617826;6552.180977377828;6598.241202261801;6630;6650.268470468342;6188.3502232266765;6269.122322874892;6308.842278943063;6346.714397852015;6347.460465860268 18:11:45 INFO - PID 16047 | |1;ext.html;881.2245224202701;899.2760827533837;899.3614533681086;903.6882275614911;911.3666002128522;40082.398764018566;43610;43860.561394386044;43988.12023759523;44000.55999440005;39521.40717889227;40307.19385612286;40372.394414083814;40605.79697101518;40659.390109148386;7210.459215558841;7329.413646908234;7352.375048120916;7355.889661655079;7397.260273972586;7166.964165179167;7262.055932728765;7311.086114235721;7321.743738969137;7331.230220826817;7255.2744725527555;7296.160941491717;7312.8171795705;7328.523645962995;7338.926610733904;7223.855522889539;7840.176781437953;8061.072976607707;8064.153263907323;8067.273945344947;7969;8035.116137224901;8061.032676078849;8066.556339401331;8069.475484093574;37308.44037339442;37640.81179594085;37870;37949;38004.23991520182;3462.342154990544;3575.803330816804;3579.0515513388973;3579.8926032219074;3582.623824498416;32551;32642.183945401554;32966.175845604055;32971.83514082414;32979.83510082458;5160.303359046518;5197.948020519822;5208.374995000624;5217.8173763918085;5285.524302812765;181072;181716;183126;183342.16657833385;183863;42850.35724464135;43484;49490.25764613534;49541.752291238314;49571.75214123906;45615.31577026347;51376.944922202696;52385.2142217867;52554.474455255724;52554.73722631362;15682.607934801494;15736.370545178064;15863.68272634564;15929.203539822962;15985.200739962955;12825.230486170673;12946.546870859474;12977.675558111123;13009.21944683303;13027.283499407624;14572.271386430635;14627.634309142357;14664.780028299583;14797.556073317603;14845.554633361016;5673.716314184274;5717.313922329347;5736.6844823534275;5876.6180198286975;5904.380040095814;995.2535598301274;1014.6043043213152;1019.1286450085071;1026.573971801694;1043.1498328862106;23612.7638723614;23984.04063837462;23992.94588858892;24075.277741667778;24108.276751697478;1123.3598945539604;1133.8525991621036;1138.2032577195996;1143.451143451148;1146.4095990564865;12707.983361331257;12811.359432028361;12914.289714065628;12961.805572916413;13002;237795;239198;240171;240514;240542;53231.73384133132;53273.73363133237;53527;53703.46296537063;53720.462795372325;1394.1077710265438;1456.0535651826397;1464.4435114656576;1476.4094362254968;1484.0798704803092;22522.774772252724;23094.76905230926;23189.652155217347;23217.535649287256;23669;37918.24163516659;37992.810035949646;38003.61996380112;38012.80993595014;38075.61924380721;191853;192053;192599;192978;192982;30800.69199308068;34266;34570.65429345674;35113.648863511036;35142.12144696301;43912;44333.77833110814;44425.77787111173;44705.32942005807;44908.550914490435;31696.20759480939;31804.204894877814;31880.5217921736;32088.679113208567;32543.674563254066;31723.20691982719;31859;32115.678843212205;32128;32209.838950805097;34769.826150870096;35616.46575301321;35792.46311305281;36222.818885906454;36511.45232821457;31114;31193.84403078061;31257.8437107813;31540.36919261556;31927;23621.142645927393;23992.64011039801;24047.879760601783;24067.67627780489;24206.757932421155 18:11:45 INFO - PID 16047 | |2;jquery.html;551.7654996626433;574.8641162458788;579.7651950959863;596.6867394617825;615.1080932647662;21807.45481362967;22663.54672906541;22738.431539211506;22744.88627556864;22795.202167924126;22749.180065594755;23251.767482325173;23453;24045.038198472084;24718;6474.902876456857;6565.212174539062;6631.469482441393;6646.567973081735;6661.533692641517;6482.57863238888;6510.739570417178;6577.539572229922;6639.203295604534;6643.93356066438;6744.224414192382;6768.627725475097;6775.42408895242;6800.727970881158;6804.455643548505;6776.898346524806;6783.219929708072;6790.0493930849725;6796.4562834973085;6834.897476537856;6063.848403789896;6311.305756366796;6320.5575609706875;6341.302456729756;6342.5243106767175;29472.410551788846;29486.70513294861;29734.85132574323;29766;29860.55209171864;2061.9484512887148;2074.8858812765293;2083.645780217367;2092.215419217793;2093.9895300523553;19719;19778.109985050563;19879.30422435222;19885.60228795416;19978.70031949522;2255.3684968208763;2312.7571604981404;2312.757160498157;2317.9420514487106;2318.8260880434036;126577.36711316477;132103;134328.32835835757;142242;143831.2808435951;14683.412663493555;14990.175540345386;15041.924790376197;15111.31999060045;15268.389264429521;23980.40048998754;24026.39934001664;24045.879770601034;24133.637995430083;24183.39541511441;10243.59025638981;10281.588736450607;10422.791544169075;10466.842997355046;10600.416977066336;9354.017828127948;9528.95235523818;9553;9596;9769.316147869724;8978.371513993958;9084.591193396313;9187.540622968825;9247.861282080774;9450.480223587769;2787.8606069696434;2788.261110805638;2803.8037337386186;2831.9858400707863;2842.374677570931;963.8843338799389;972.4359871274644;986.9555869985868;990.5988074829705;994.7065615643403;19550.804491955183;19898;20573.279935202198;20585.58828823415;20729.0671919764;1023.8464230365505;1036.1045650250053;1044.1698849414734;1046.7278507588082;1054.383185836279;11149.777004459866;11300.491477883515;11401.030912372542;11535.077193824642;11630.476628551736;487925;495526;500017;507274;521496;110671.44664276627;111265;112532;113807;114273.99999999834;1142.086330935265;1155.225998580936;1170.5551890281638;1170.8770579089244;1182.6629410617932;17583.736243956606;18109.818901810813;18234.270629174684;18364.632707346045;18465.353712619995;55050;55863.44136558582;57143;57455;57716.4228357711;70302.64848675724;70913;71493.642531787;72560;72708;14603;15063.322150503034;15151.090934543963;15214.087154770748;15313.081215127128;22785.316440506813;23028.194013209457;23337.88331058334;23403.88298058499;23699.881500592386;16378.099204543865;17463.912680436515;17474.650506989536;17565.385211517536;17567.209475573887;16529.917350413172;16582.917085414494;16716.546173101116;17118.48644540666;17328.913355433142;17938.372156974445;18606.53483662919;19025;19105.617887641893;19320.613587728447;14825.629359266104;14835.851641483447;14929.402823887358;14991.625209369417;15091.698166036836;18090.638187236444;18234.908825455786;18412.171452284947;18419.81580184181;18437.17032733557 18:11:45 INFO - PID 16047 | |3;mootools.html;1319.5579480873907;1336.8262125923634;1367.311370457485;1369.7945308203775;1373.6565858535366;15181.848181518195;15965.12191829449;16040.278187481563;16054.59863503413;16069.758953615676;15701.921490392531;15784.21078946051;15892.125933073676;15915.204239788021;15926.124063176521;2722.6324446199706;2858.8427636480087;2859.2708859240865;2867.8135921165162;2876.3240638449947;2814.3949050954143;2815.0710265612297;2845.2175651695743;2853.443578502193;2858.9285267868368;2752.559590465526;2773.084881988939;2847.4162796626642;2852.0588205892004;2857.7570906472974;2817.9436411271763;2842.7441530262167;2843.6161118249083;2844.601755754196;2850.6009158717798;2715.497632937893;2791.6091747155415;2794.4969905417015;2799.0063527447664;2799.1042866282796;15291.311890964933;15739.055656660637;15764.842351576566;15772.447964321309;15777.526674199793;8109.878351824727;8290;8344.332453403713;8378.12029736881;8413.074561798197;5531.806386776443;5663.5186009189265;5665.263515742969;5797.9417306855885;5826.1843341931735;1998.3405476192822;2002.909869055896;2010.1959216313446;2065.2255404223415;2087.8956052197327;27381.31546711348;27775.722242777516;27922.3019424514;27955.301117472023;27959.30101747452;6782;6961.23426423093;6977.441804655616;7170.533915295489;7223.169335526377;17724.91137544304;17851.910740446216;17868.910655446638;17938.192781324873;17984.190711418018;7869.213078692085;8241.052278987872;8243.587820608946;8245.051819040767;8248.216419440143;7351.191368949411;7534.020577324913;7582.696692132363;7590.962045189739;7648.885266721004;5130.358705161855;5266.131088370453;5324.893502129936;5360.517553420211;5365.302510673588;1528.9617759555877;1538.5922730476432;1539.3919401836213;1562.0861795849328;1591.124881315272;836.7740710008385;852.3266619370752;871.9389642724948;873.3624454148508;878.332467324825;7132.893006604905;7220.74727384539;7237.529560578546;7267.491275610657;7277.8544429111125;722.8409749855024;722.9602371869597;728.2971932085563;740.3189066059239;752.9661165247577;4499.392582001388;4635.976820115878;4804.975975120102;5270.235815806764;5306.867328316823;21080.578388432146;21204.469888252916;21539.8923005384;21701.240456583942;21704;19698.64901297057;20736.377908662766;20960.266390676254;21075.8946205268;21266.2556810514;11094.1124710023;11274.210805243718;11282.435878206057;11480;11494.712632184095;8154.2253485918745;8346.081930987708;8378.622961966605;8401.873971890303;8572.314214862921;19272.8072719271;19331.806681933;19373.43283283865;19442.12510437062;19879.602407951472;19220.615587688448;19896.104675289356;20192.899035504728;20409.489762755456;20652.483687907323;10544.947275263574;10613.893861061599;11034;11101.833472497758;11214.215004949576;14358.425662973781;14431.711365772835;14483.637909052359;14538.055026423248;14540.98213125071;10798.352098874093;11022.283551569122;11040.061594764535;11059.225854190452;11160.776784464428;10681.679549613524;10829;11016.834747478633;11067.72330691739;11285.040771534183;9490.905090949002;9851.556679949275;9851.753706157404;9911.851322230028;9921.15670168015;6939.410150137295;6942.166939967236;7072.08062951813;7088.2202957675645;7191.101112360955;7289.161746399232;7376.446766492427;7379.483436159632;7444.702211911463;7515.39876809843 18:11:45 INFO - PID 16047 | |4;prototype.html;498.7182242033251;516.217929836298;531.4074806590653;534.7113456569666;539.4928766959058;6235.501159907202;6256.186695729561;6281.968590157055;6293.685315734207;6331.303556608769;6129.785457508977;6138.140660307549;6150.969245153779;6160.26076870775;6167.4757645600175;5030.1448753711775;5103.591712662987;5119.206522988943;5141.845744627667;5155.5360017598405;5025.64820462566;5177.611679124062;5178.197379406198;5184.248283998818;5186.24799404088;4805.927911081337;4862.854114376557;4902.705837649734;4935.605151587864;4941.357623508939;4844.346013288196;4916.237983112587;4937.23472861707;4956.628252881031;4962.131626965267;4436.600705936481;4461.330800379969;4473.686841921067;4482.5965663090155;4498.865034048984;5824.4466775656665;5923.200367950315;6017.518598512109;6026.0960855871535;6050.3647117052515;5542.695151766651;5619.634723742944;5638.746256418471;5640.858978525529;5671.291088613923;6439.613623182624;6444.226692796848;6480.837979050514;6487.7080531376205;6525;4594.264917613166;4732.077244937239;4763.404574428197;4811.158047341702;4888.388951381077;6499.967500162516;6599.208095028579;6661.700223489906;6677.3656502632175;6712.899306510406;4946.282788995575;4955.578775804097;4999.75001249936;5005.674631148963;5008.549230569266;4991.026749783758;4997.225430058346;5017.347744793226;5051.4695956924725;5069.5437410632485;5534.695591742412;5561.610687251854;5603.627111357662;5611.354694210137;5638.661680299195;6399.744010239631;6526.967365163238;6527.412532871969;6557.2131344239;6589.769358072444;1716.7081596128687;1724.7499112628598;1724.8533874620546;1725.284007137024;1737.8262173782773;1156.9305841649527;1203.8374819399444;1213.8846809553254;1223.6206775899323;1244.1726252042472;5496.258005169331;5566.332040155126;5566.860828479239;5585.301837270341;5587.189857470644;6503.544751867324;6613.933860661428;6644.966775166094;6651.102101216371;6700.396964273239;5531.806386776524;5552.139418390076;5581.553475721932;5594.9720251398485;5605.887882242414;3415.470602056685;3450.3949518114164;3465.9480107798404;3470.305938812248;3499.61504234534;6316.30520642729;6429.228492580826;6445.903311450332;6451.999880037203;6514.05546195799;6718.966405167943;6749.021391898147;6757.222919364238;6833.282505336968;6837;5989.700514974321;6326.778562750374;6352.650604216812;6359.268684101387;6412.487001039999;6419.839504012437;6454.967725161344;6484.870302594016;6519.3480651936325;6623.801285961429;6409.596401349494;6427.710753016219;6435.710393032231;6513.837154071186;6518.608883467007;6282.738214242961;6314.463270622048;6367.790119877209;6396.680165991589;6433.228012638513;4911.189653707098;4924.335214745964;4958;4976.552110310089;4982.601391888713;6395.712192951235;6503.414692677682;6591.802245932629;6615.636140012345;6671.46628269747;6113.786017489367;6299.14175318273;6394.872102558016;6434.646094464662;6451.70967306463;6402.71018417652;6424.261209960914;6445.677716114269;6457.644829534421;6477.157969464128;6336.968315158395;6385.106085148176;6395.584287021329;6405.167328247298;6441.935580644134;6304.432601065926;6370.77702280418;6488;6507.642079685663;6555.639439830665;6221.3156552778255;6224.595401298901;6230.691554773367;6256.523454471862;6338 18:11:46 INFO - PID 16047 | |5;yui.html;883.2580632268892;899.878516400286;915.3455279475174;921.728090213387;938.6151677812094;19895.502612434695;20763.792362076376;20766.27318043869;20856.061477233525;21083.789162108373;18605.348812791555;18872.33946811862;19187.616247675072;19592.90203548984;19668;965.1410244882061;987.8518222266681;990.4700984973041;992.9205663546899;996.6362277768635;990.425553179158;996.4121168510577;1000.4547521600708;1001.1140140975217;1001.7095042437684;998.7952469743722;1033.1734612310122;1034.1416624201895;1038;1041.526105621944;1024.7847951930066;1026.5893642543042;1032.6901929421144;1033.7363972187081;1037.071820720451;717.4619035723208;731.0934441292811;740.311510295425;752.3379426104992;760.9391248700091;20093.698594521094;20136.697949530768;20240.898795505927;20310.390688279374;20351.49121271965;20324.28864989733;21918.89040554803;22131.77868221313;22135.557288854292;22371;16807.159642017974;16933.3226670933;17019.23413446398;17106;17255;3782.9432558511644;3796.9620303796883;3807.638274363931;3847.7306588538813;3848.8460461581503;145570.27214863963;146036;148763;150681;150850;17163.399281025104;17491.212895419736;17836.643267134583;17855.553611159827;18040.548986275448;31786.887458938825;33023;33342.83328583342;33506.49740253898;33544.32911341808;8785;8849.336299777542;8965.775855603662;9035.45787252767;9040.547972601344;4531.954680453153;4548.226801443762;4562.8631141065825;4571.291449825216;4594.908101837945;5503.2020357048;5616.9157462638095;5624;5644.943550564524;5661.631993920382;922.6263363337858;937.4984383354872;948.8434408322549;949;958.0467434902178;657.8092353217513;670;674.20780582815;704.1972151747012;704.9989015597863;11487.597934072268;11682;12063.526976969875;12693.809592856114;12766.744665106831;664.707528687376;675.8479342148075;683.6923384477005;687.3950923187563;697.4106879686657;5701.771929122871;5787.363390027093;5883;5900.262467191601;5922.822315330547;123218;123810;124665;125207.3739631296;125246;93926;112670.43664781624;115272.42363788295;116062;119134.40432797954;95265;97435;98035.50982245043;98076.50961745146;99917;12218.38908054576;12461.439235234255;12568.245905245714;12623.215245833127;12707.04697147736;41939.58060419479;43017;43487;44324.55675443333;44844.32733509065;59376.40623593709;59503.702481487315;59821.102683458914;60487.69756151191;60975;17143.142842857556;17844.643107137526;17904.55238619045;18145.092745362414;18306.81693183051;20959.790402096394;21200.4699882498;21232.575348493254;21336;21601.45996350041;18414.355497558056;18562.44312670622;18575.071246437354;18624.16191271369;18771.90614046921;18843.90578047101;19267.036648167817;19268.518287042374;19383.321583745066;19590.60818783645;16912.830871691127;16972;17142.40001599938;17358.218880150172;17533.912330438267;13441.731165376834;13520.18878867271;13570.79643805324;13734.244616546186;13924.094933829268;12320.507179712711;12593.307368094476;12639.557615483414;12777;12825.935870320589 18:11:46 INFO - PID 16047 | __end_tp_report 18:11:46 INFO - PID 16047 | __start_cc_report 18:11:46 INFO - PID 16047 | _x_x_mozilla_cycle_collect,264 18:11:46 INFO - PID 16047 | __end_cc_report 18:11:46 INFO - PID 16047 | __startTimestamp1506561105987__endTimestamp 18:11:46 INFO - PID 16047 | 18:11:46 INFO - PID 16047 | ------- Summary: start ------- 18:11:46 INFO - PID 16047 | Number of tests: 6 18:11:46 INFO - PID 16047 | 18:11:46 INFO - PID 16047 | [#0] dojo.html Cycles:165 Average:6099.69 Median:6346.71 stddev:751.79 (11.8%) stddev-sans-first:717.95 18:11:46 INFO - PID 16047 | Values: 3164.0 3193.0 3205.3 3207.5 3235.2 6158.7 6160.6 6218.3 6236.8 6296.0 6168.3 6273.4 6275.7 6276.3 6277.5 5718.0 5724.9 5750.2 5774.3 5806.1 5710.0 5739.5 5740.8 5842.9 5857.2 5742.0 5804.1 5837.6 5852.3 5920.9 5639.7 5677.9 5736.8 5737.2 5777.2 5499.9 5597.1 5601.0 5610.5 5627.9 6035.9 6103.0 6150.3 6179.0 6226.0 6475.6 6500.9 6548.4 6564.2 6633.4 6644.4 6716.7 6752.5 6786.9 6793.9 6183.2 6222.9 6229.0 6231.1 6246.0 6762.4 6764.1 6764.2 6848.9 6872.9 6338.0 6510.1 6545.0 6559.0 6578.9 6544.3 6551.5 6580.0 6662.4 6717.8 6611.5 6650.0 6665.6 6690.4 6697.1 6587.4 6588.5 6653.9 6662.4 6665.5 4847.5 4895.5 4924.2 4940.8 4969.0 4242.1 4295.2 4316.4 4325.7 4399.1 5701.0 5727.5 5751.9 5754.8 5758.0 6664.7 6711.2 6751.2 6756.8 6788.2 5658.1 5711.7 5775.5 5792.2 5796.1 5553.3 5609.3 5663.2 5681.5 5766.0 6338.2 6343.6 6347.0 6354.1 6357.5 6445.2 6552.5 6557.5 6607.4 6735.1 6471.8 6522.3 6539.6 6557.1 6560.1 6551.2 6552.6 6560.5 6607.2 6625.2 6496.4 6665.0 6673.2 6683.0 6720.8 6306.1 6397.1 6403.0 6409.6 6457.7 6524.8 6546.8 6564.6 6577.5 6580.4 6400.1 6431.6 6475.3 6497.8 6520.2 6544.9 6552.2 6598.2 6630.0 6650.3 6188.4 6269.1 6308.8 6346.7 6347.5 18:11:46 INFO - PID 16047 | 18:11:46 INFO - PID 16047 | [#1] ext.html Cycles:180 Average:37557.70 Median:35379.29 stddev:53498.82 (151.2%) stddev-sans-first:53578.01 18:11:46 INFO - PID 16047 | Values: 881.2 899.3 899.4 903.7 911.4 40082.4 43610.0 43860.6 43988.1 44000.6 39521.4 40307.2 40372.4 40605.8 40659.4 7210.5 7329.4 7352.4 7355.9 7397.3 7167.0 7262.1 7311.1 7321.7 7331.2 7255.3 7296.2 7312.8 7328.5 7338.9 7223.9 7840.2 8061.1 8064.2 8067.3 7969.0 8035.1 8061.0 8066.6 8069.5 37308.4 37640.8 37870.0 37949.0 38004.2 3462.3 3575.8 3579.1 3579.9 3582.6 32551.0 32642.2 32966.2 32971.8 32979.8 5160.3 5197.9 5208.4 5217.8 5285.5 181072.0 181716.0 183126.0 183342.2 183863.0 42850.4 43484.0 49490.3 49541.8 49571.8 45615.3 51376.9 52385.2 52554.5 52554.7 15682.6 15736.4 15863.7 15929.2 15985.2 12825.2 12946.5 12977.7 13009.2 13027.3 14572.3 14627.6 14664.8 14797.6 14845.6 5673.7 5717.3 5736.7 5876.6 5904.4 995.3 1014.6 1019.1 1026.6 1043.1 23612.8 23984.0 23992.9 24075.3 24108.3 1123.4 1133.9 1138.2 1143.5 1146.4 12708.0 12811.4 12914.3 12961.8 13002.0 237795.0 239198.0 240171.0 240514.0 240542.0 53231.7 53273.7 53527.0 53703.5 53720.5 1394.1 1456.1 1464.4 1476.4 1484.1 22522.8 23094.8 23189.7 23217.5 23669.0 37918.2 37992.8 38003.6 38012.8 38075.6 191853.0 192053.0 192599.0 192978.0 192982.0 30800.7 34266.0 34570.7 35113.6 35142.1 43912.0 44333.8 44425.8 44705.3 44908.6 31696.2 31804.2 31880.5 32088.7 32543.7 31723.2 31859.0 32115.7 32128.0 32209.8 34769.8 35616.5 35792.5 36222.8 36511.5 31114.0 31193.8 31257.8 31540.4 31927.0 23621.1 23992.6 24047.9 24067.7 24206.8 18:11:46 INFO - PID 16047 | 18:11:46 INFO - PID 16047 | [#2] jquery.html Cycles:180 Average:35035.86 Median:22767.25 stddev:84344.79 (370.5%) stddev-sans-first:84541.66 18:11:46 INFO - PID 16047 | Values: 551.8 574.9 579.8 596.7 615.1 21807.5 22663.5 22738.4 22744.9 22795.2 22749.2 23251.8 23453.0 24045.0 24718.0 6474.9 6565.2 6631.5 6646.6 6661.5 6482.6 6510.7 6577.5 6639.2 6643.9 6744.2 6768.6 6775.4 6800.7 6804.5 6776.9 6783.2 6790.0 6796.5 6834.9 6063.8 6311.3 6320.6 6341.3 6342.5 29472.4 29486.7 29734.9 29766.0 29860.6 2061.9 2074.9 2083.6 2092.2 2094.0 19719.0 19778.1 19879.3 19885.6 19978.7 2255.4 2312.8 2312.8 2317.9 2318.8 126577.4 132103.0 134328.3 142242.0 143831.3 14683.4 14990.2 15041.9 15111.3 15268.4 23980.4 24026.4 24045.9 24133.6 24183.4 10243.6 10281.6 10422.8 10466.8 10600.4 9354.0 9529.0 9553.0 9596.0 9769.3 8978.4 9084.6 9187.5 9247.9 9450.5 2787.9 2788.3 2803.8 2832.0 2842.4 963.9 972.4 987.0 990.6 994.7 19550.8 19898.0 20573.3 20585.6 20729.1 1023.8 1036.1 1044.2 1046.7 1054.4 11149.8 11300.5 11401.0 11535.1 11630.5 487925.0 495526.0 500017.0 507274.0 521496.0 110671.4 111265.0 112532.0 113807.0 114274.0 1142.1 1155.2 1170.6 1170.9 1182.7 17583.7 18109.8 18234.3 18364.6 18465.4 55050.0 55863.4 57143.0 57455.0 57716.4 70302.6 70913.0 71493.6 72560.0 72708.0 14603.0 15063.3 15151.1 15214.1 15313.1 22785.3 23028.2 23337.9 23403.9 23699.9 16378.1 17463.9 17474.7 17565.4 17567.2 16529.9 16582.9 16716.5 17118.5 17328.9 17938.4 18606.5 19025.0 19105.6 19320.6 14825.6 14835.9 14929.4 14991.6 15091.7 18090.6 18234.9 18412.2 18419.8 18437.2 18:11:46 INFO - PID 16047 | 18:11:46 INFO - PID 16047 | [#3] mootools.html Cycles:180 Average:9469.23 Median:15379.15 stddev:6856.24 (44.6%) stddev-sans-first:6848.14 18:11:46 INFO - PID 16047 | Values: 1319.6 1336.8 1367.3 1369.8 1373.7 15181.8 15965.1 16040.3 16054.6 16069.8 15701.9 15784.2 15892.1 15915.2 15926.1 2722.6 2858.8 2859.3 2867.8 2876.3 2814.4 2815.1 2845.2 2853.4 2858.9 2752.6 2773.1 2847.4 2852.1 2857.8 2817.9 2842.7 2843.6 2844.6 2850.6 2715.5 2791.6 2794.5 2799.0 2799.1 15291.3 15739.1 15764.8 15772.4 15777.5 8109.9 8290.0 8344.3 8378.1 8413.1 5531.8 5663.5 5665.3 5797.9 5826.2 1998.3 2002.9 2010.2 2065.2 2087.9 27381.3 27775.7 27922.3 27955.3 27959.3 6782.0 6961.2 6977.4 7170.5 7223.2 17724.9 17851.9 17868.9 17938.2 17984.2 7869.2 8241.1 8243.6 8245.1 8248.2 7351.2 7534.0 7582.7 7591.0 7648.9 5130.4 5266.1 5324.9 5360.5 5365.3 1529.0 1538.6 1539.4 1562.1 1591.1 836.8 852.3 871.9 873.4 878.3 7132.9 7220.7 7237.5 7267.5 7277.9 722.8 723.0 728.3 740.3 753.0 4499.4 4636.0 4805.0 5270.2 5306.9 21080.6 21204.5 21539.9 21701.2 21704.0 19698.6 20736.4 20960.3 21075.9 21266.3 11094.1 11274.2 11282.4 11480.0 11494.7 8154.2 8346.1 8378.6 8401.9 8572.3 19272.8 19331.8 19373.4 19442.1 19879.6 19220.6 19896.1 20192.9 20409.5 20652.5 10544.9 10613.9 11034.0 11101.8 11214.2 14358.4 14431.7 14483.6 14538.1 14541.0 10798.4 11022.3 11040.1 11059.2 11160.8 10681.7 10829.0 11016.8 11067.7 11285.0 9490.9 9851.6 9851.8 9911.9 9921.2 6939.4 6942.2 7072.1 7088.2 7191.1 7289.2 7376.4 7379.5 7444.7 7515.4 18:11:46 INFO - PID 16047 | 18:11:46 INFO - PID 16047 | [#4] prototype.html Cycles:180 Average:5428.33 Median:6082.08 stddev:1508.57 (24.8%) stddev-sans-first:1466.72 18:11:46 INFO - PID 16047 | Values: 498.7 516.2 531.4 534.7 539.5 6235.5 6256.2 6282.0 6293.7 6331.3 6129.8 6138.1 6151.0 6160.3 6167.5 5030.1 5103.6 5119.2 5141.8 5155.5 5025.6 5177.6 5178.2 5184.2 5186.2 4805.9 4862.9 4902.7 4935.6 4941.4 4844.3 4916.2 4937.2 4956.6 4962.1 4436.6 4461.3 4473.7 4482.6 4498.9 5824.4 5923.2 6017.5 6026.1 6050.4 5542.7 5619.6 5638.7 5640.9 5671.3 6439.6 6444.2 6480.8 6487.7 6525.0 4594.3 4732.1 4763.4 4811.2 4888.4 6500.0 6599.2 6661.7 6677.4 6712.9 4946.3 4955.6 4999.8 5005.7 5008.5 4991.0 4997.2 5017.3 5051.5 5069.5 5534.7 5561.6 5603.6 5611.4 5638.7 6399.7 6527.0 6527.4 6557.2 6589.8 1716.7 1724.7 1724.9 1725.3 1737.8 1156.9 1203.8 1213.9 1223.6 1244.2 5496.3 5566.3 5566.9 5585.3 5587.2 6503.5 6613.9 6645.0 6651.1 6700.4 5531.8 5552.1 5581.6 5595.0 5605.9 3415.5 3450.4 3465.9 3470.3 3499.6 6316.3 6429.2 6445.9 6452.0 6514.1 6719.0 6749.0 6757.2 6833.3 6837.0 5989.7 6326.8 6352.7 6359.3 6412.5 6419.8 6455.0 6484.9 6519.3 6623.8 6409.6 6427.7 6435.7 6513.8 6518.6 6282.7 6314.5 6367.8 6396.7 6433.2 4911.2 4924.3 4958.0 4976.6 4982.6 6395.7 6503.4 6591.8 6615.6 6671.5 6113.8 6299.1 6394.9 6434.6 6451.7 6402.7 6424.3 6445.7 6457.6 6477.2 6337.0 6385.1 6395.6 6405.2 6441.9 6304.4 6370.8 6488.0 6507.6 6555.6 6221.3 6224.6 6230.7 6256.5 6338.0 18:11:46 INFO - PID 16047 | 18:11:46 INFO - PID 16047 | [#5] yui.html Cycles:180 Average:25472.15 Median:20907.93 stddev:36499.37 (174.6%) stddev-sans-first:36555.06 18:11:46 INFO - PID 16047 | Values: 883.3 899.9 915.3 921.7 938.6 19895.5 20763.8 20766.3 20856.1 21083.8 18605.3 18872.3 19187.6 19592.9 19668.0 965.1 987.9 990.5 992.9 996.6 990.4 996.4 1000.5 1001.1 1001.7 998.8 1033.2 1034.1 1038.0 1041.5 1024.8 1026.6 1032.7 1033.7 1037.1 717.5 731.1 740.3 752.3 760.9 20093.7 20136.7 20240.9 20310.4 20351.5 20324.3 21918.9 22131.8 22135.6 22371.0 16807.2 16933.3 17019.2 17106.0 17255.0 3782.9 3797.0 3807.6 3847.7 3848.8 145570.3 146036.0 148763.0 150681.0 150850.0 17163.4 17491.2 17836.6 17855.6 18040.5 31786.9 33023.0 33342.8 33506.5 33544.3 8785.0 8849.3 8965.8 9035.5 9040.5 4532.0 4548.2 4562.9 4571.3 4594.9 5503.2 5616.9 5624.0 5644.9 5661.6 922.6 937.5 948.8 949.0 958.0 657.8 670.0 674.2 704.2 705.0 11487.6 11682.0 12063.5 12693.8 12766.7 664.7 675.8 683.7 687.4 697.4 5701.8 5787.4 5883.0 5900.3 5922.8 123218.0 123810.0 124665.0 125207.4 125246.0 93926.0 112670.4 115272.4 116062.0 119134.4 95265.0 97435.0 98035.5 98076.5 99917.0 12218.4 12461.4 12568.2 12623.2 12707.0 41939.6 43017.0 43487.0 44324.6 44844.3 59376.4 59503.7 59821.1 60487.7 60975.0 17143.1 17844.6 17904.6 18145.1 18306.8 20959.8 21200.5 21232.6 21336.0 21601.5 18414.4 18562.4 18575.1 18624.2 18771.9 18843.9 19267.0 19268.5 19383.3 19590.6 16912.8 16972.0 17142.4 17358.2 17533.9 13441.7 13520.2 13570.8 13734.2 13924.1 12320.5 12593.3 12639.6 12777.0 12825.9 18:11:46 INFO - PID 16047 | -------- Summary: end -------- 18:11:46 INFO - PID 16047 | 18:11:46 INFO - TEST-INFO | 16047: exit 0 18:11:46 INFO - TEST-OK | dromaeo_css | took 1112696ms 18:11:46 INFO - TEST-START | kraken 18:11:46 INFO - Initialising browser for kraken test... 18:11:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45716/getInfo.html -profile /tmp/tmp9Yh14a/profile 18:11:46 INFO - TEST-INFO | started process 16421 (/builds/slave/test/build/application/firefox/firefox http://localhost:45716/getInfo.html) 18:11:52 INFO - TEST-INFO | 16421: exit 0 18:11:52 INFO - Browser initialized. 18:11:52 INFO - Running cycle 1/1 for kraken test... 18:11:52 INFO - Using env: {'DISPLAY': ':0', 18:11:52 INFO - 'HOME': '/home/cltbld', 18:11:52 INFO - 'JSGC_DISABLE_POISONING': '1', 18:11:52 INFO - 'LANG': 'en_US.UTF-8', 18:11:52 INFO - 'LANGUAGE': 'en_US:en', 18:11:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:11:52 INFO - 'LOGNAME': 'cltbld', 18:11:52 INFO - 'MAIL': '/var/mail/cltbld', 18:11:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:11:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:11:52 INFO - 'MOZ_ACCELERATED': '1', 18:11:52 INFO - 'MOZ_CRASHREPORTER': '1', 18:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:11:52 INFO - 'MOZ_NO_REMOTE': '1', 18:11:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:11:52 INFO - 'MOZ_WEBRENDER': '1', 18:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:52 INFO - 'NO_EM_RESTART': '1', 18:11:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:52 INFO - 'PWD': '/builds/slave/test', 18:11:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:11:52 INFO - 'RUST_BACKTRACE': 'full', 18:11:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:11:52 INFO - 'SHELL': '/bin/bash', 18:11:52 INFO - 'SHLVL': '1', 18:11:52 INFO - 'TERM': 'linux', 18:11:52 INFO - 'TMOUT': '86400', 18:11:52 INFO - 'USER': 'cltbld', 18:11:52 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911', 18:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:52 INFO - '_': '/tools/buildbot/bin/python'} 18:11:52 INFO - TEST-INFO | started process 16664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Yh14a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:11:53 INFO - PID 16664 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:11:53 INFO - PID 16664 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:11:54 INFO - PID 16664 | 18:11:54 INFO - PID 16664 | (/builds/slave/test/build/application/firefox/firefox:16722): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:11:54 INFO - PID 16664 | 18:12:04 INFO - PID 16664 | RSS: Main: 192339968 18:12:04 INFO - PID 16664 | 18:12:07 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45716/tests/kraken/driver.html?testName=audio-beat-detection) 18:12:08 INFO - PID 16664 | RSS: Main: 189067264 18:12:08 INFO - PID 16664 | 18:12:10 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45716/tests/kraken/driver.html?testName=audio-dft) 18:12:11 INFO - PID 16664 | RSS: Main: 189022208 18:12:11 INFO - PID 16664 | 18:12:14 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45716/tests/kraken/driver.html?testName=audio-fft) 18:12:14 INFO - PID 16664 | RSS: Main: 186073088 18:12:14 INFO - PID 16664 | 18:12:17 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45716/tests/kraken/driver.html?testName=audio-oscillator) 18:12:17 INFO - PID 16664 | RSS: Main: 185946112 18:12:17 INFO - PID 16664 | 18:12:19 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45716/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:12:20 INFO - PID 16664 | RSS: Main: 186728448 18:12:20 INFO - PID 16664 | 18:12:23 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45716/tests/kraken/driver.html?testName=imaging-darkroom) 18:12:24 INFO - PID 16664 | RSS: Main: 188891136 18:12:24 INFO - PID 16664 | 18:12:27 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45716/tests/kraken/driver.html?testName=imaging-desaturate) 18:12:28 INFO - PID 16664 | RSS: Main: 189014016 18:12:28 INFO - PID 16664 | 18:12:31 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45716/tests/kraken/driver.html?testName=json-parse-financial) 18:12:32 INFO - PID 16664 | RSS: Main: 189165568 18:12:32 INFO - PID 16664 | 18:12:33 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45716/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:12:34 INFO - PID 16664 | RSS: Main: 189104128 18:12:34 INFO - PID 16664 | 18:12:36 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45716/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:12:36 INFO - PID 16664 | RSS: Main: 186380288 18:12:36 INFO - PID 16664 | 18:12:38 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45716/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:12:39 INFO - PID 16664 | RSS: Main: 186327040 18:12:39 INFO - PID 16664 | 18:12:42 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45716/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:12:42 INFO - PID 16664 | RSS: Main: 186765312 18:12:42 INFO - PID 16664 | 18:12:45 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45716/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:12:46 INFO - PID 16664 | RSS: Main: 186953728 18:12:46 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | Cycle 1(1): loaded http://localhost:45716/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45716/tests/kraken/driver.html?testName=ai-astar) 18:12:48 INFO - PID 16664 | RSS: Main: 187940864 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | __start_tp_report 18:12:48 INFO - PID 16664 | _x_x_mozilla_page_load 18:12:48 INFO - PID 16664 | _x_x_mozilla_page_load_details 18:12:48 INFO - PID 16664 | |i|pagename|runs| 18:12:48 INFO - PID 16664 | |0;ai-astar;107;116;110;113;108;109;115;114;115;118 18:12:48 INFO - PID 16664 | |1;audio-beat-detection;122;119;125;116;115;116;125;124;119;119 18:12:48 INFO - PID 16664 | |2;audio-dft;171;170;162;164;162;179;167;167;167;170 18:12:48 INFO - PID 16664 | |3;audio-fft;76;79;80;76;77;82;78;74;82;77 18:12:48 INFO - PID 16664 | |4;audio-oscillator;79;82;86;84;89;86;86;78;79;77 18:12:48 INFO - PID 16664 | |5;imaging-gaussian-blur;114;113;116;114;120;122;114;112;117;117 18:12:48 INFO - PID 16664 | |6;imaging-darkroom;153;156;149;148;148;149;152;150;148;151 18:12:48 INFO - PID 16664 | |7;imaging-desaturate;100;95;95;95;94;94;101;94;94;94 18:12:48 INFO - PID 16664 | |8;json-parse-financial;51;60;53;61;52;59;52;56;54;52 18:12:48 INFO - PID 16664 | |9;json-stringify-tinderbox;42;42;37;48;45;38;45;41;39;36 18:12:48 INFO - PID 16664 | |10;stanford-crypto-aes;77;75;72;74;79;79;77;78;75;79 18:12:48 INFO - PID 16664 | |11;stanford-crypto-ccm;109;113;114;110;117;111;118;115;116;114 18:12:48 INFO - PID 16664 | |12;stanford-crypto-pbkdf2;172;160;164;168;174;164;164;168;170;163 18:12:48 INFO - PID 16664 | |13;stanford-crypto-sha256-iterative;75;67;65;63;63;63;64;65;68;63 18:12:48 INFO - PID 16664 | __end_tp_report 18:12:48 INFO - PID 16664 | __start_cc_report 18:12:48 INFO - PID 16664 | _x_x_mozilla_cycle_collect,745 18:12:48 INFO - PID 16664 | __end_cc_report 18:12:48 INFO - PID 16664 | __startTimestamp1506561168293__endTimestamp 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | ------- Summary: start ------- 18:12:48 INFO - PID 16664 | Number of tests: 14 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#0] ai-astar Cycles:10 Average:112.50 Median:114.50 stddev:3.75 (3.3%) stddev-sans-first:3.41 18:12:48 INFO - PID 16664 | Values: 107.0 116.0 110.0 113.0 108.0 109.0 115.0 114.0 115.0 118.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:120.50 stddev:3.80 (3.2%) stddev-sans-first:3.96 18:12:48 INFO - PID 16664 | Values: 122.0 119.0 125.0 116.0 115.0 116.0 125.0 124.0 119.0 119.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#2] audio-dft Cycles:10 Average:167.90 Median:168.50 stddev:5.04 (3.0%) stddev-sans-first:5.22 18:12:48 INFO - PID 16664 | Values: 171.0 170.0 162.0 164.0 162.0 179.0 167.0 167.0 167.0 170.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#3] audio-fft Cycles:10 Average:78.10 Median:78.50 stddev:2.64 (3.4%) stddev-sans-first:2.69 18:12:48 INFO - PID 16664 | Values: 76.0 79.0 80.0 76.0 77.0 82.0 78.0 74.0 82.0 77.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:85.00 stddev:4.17 (4.9%) stddev-sans-first:4.21 18:12:48 INFO - PID 16664 | Values: 79.0 82.0 86.0 84.0 89.0 86.0 86.0 78.0 79.0 77.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#5] imaging-gaussian-blur Cycles:10 Average:115.90 Median:116.50 stddev:3.18 (2.7%) stddev-sans-first:3.30 18:12:48 INFO - PID 16664 | Values: 114.0 113.0 116.0 114.0 120.0 122.0 114.0 112.0 117.0 117.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:150.50 stddev:2.63 (1.7%) stddev-sans-first:2.62 18:12:48 INFO - PID 16664 | Values: 153.0 156.0 149.0 148.0 148.0 149.0 152.0 150.0 148.0 151.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:94.00 stddev:2.63 (2.8%) stddev-sans-first:2.26 18:12:48 INFO - PID 16664 | Values: 100.0 95.0 95.0 95.0 94.0 94.0 101.0 94.0 94.0 94.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:3.74 (6.8%) stddev-sans-first:3.68 18:12:48 INFO - PID 16664 | Values: 51.0 60.0 53.0 61.0 52.0 59.0 52.0 56.0 54.0 52.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#9] json-stringify-tinderbox Cycles:10 Average:41.30 Median:42.00 stddev:3.89 (9.3%) stddev-sans-first:4.12 18:12:48 INFO - PID 16664 | Values: 42.0 42.0 37.0 48.0 45.0 38.0 45.0 41.0 39.0 36.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#10] stanford-crypto-aes Cycles:10 Average:76.50 Median:77.50 stddev:2.42 (3.1%) stddev-sans-first:2.55 18:12:48 INFO - PID 16664 | Values: 77.0 75.0 72.0 74.0 79.0 79.0 77.0 78.0 75.0 79.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#11] stanford-crypto-ccm Cycles:10 Average:113.70 Median:114.50 stddev:2.98 (2.6%) stddev-sans-first:2.64 18:12:48 INFO - PID 16664 | Values: 109.0 113.0 114.0 110.0 117.0 111.0 118.0 115.0 116.0 114.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:168.00 stddev:4.42 (2.6%) stddev-sans-first:4.26 18:12:48 INFO - PID 16664 | Values: 172.0 160.0 164.0 168.0 174.0 164.0 164.0 168.0 170.0 163.0 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.00 stddev:3.75 (5.8%) stddev-sans-first:1.88 18:12:48 INFO - PID 16664 | Values: 75.0 67.0 65.0 63.0 63.0 63.0 64.0 65.0 68.0 63.0 18:12:48 INFO - PID 16664 | -------- Summary: end -------- 18:12:48 INFO - PID 16664 | 18:12:48 INFO - PID 16664 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:12:49 INFO - TEST-INFO | 16664: exit 0 18:12:49 INFO - TEST-OK | kraken | took 62494ms 18:12:49 INFO - SUITE-END | took 1175s 18:12:49 INFO - Completed test suite (00:19:35) 18:12:49 INFO - javascript benchmark 18:12:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8557.688272486239, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3164.003338948231, 3193.0261270312553, 3205.3108581654938, 3207.53490743842, 3235.2235463488755, 6158.722857471414, 6160.5687601867885, 6218.284897236807, 6236.844078898024, 6295.968520157404, 6168.321484636686, 6273.404026617475, 6275.717592708328, 6276.340984196662, 6277.5291853110975, 5718.0, 5724.885502289952, 5750.1949727038245, 5774.335951365605, 5806.10005449154, 5709.971450142743, 5739.51214146796, 5740.7703691852275, 5842.912356314659, 5857.179994800731, 5742.0, 5804.129380592924, 5837.6497410155525, 5852.268466441695, 5920.911186332209, 5639.68981706006, 5677.886442271173, 5736.7992120276, 5737.196792449061, 5777.19119323295, 5499.945000550023, 5597.076482380402, 5601.0, 5610.4670056344585, 5627.887442251132, 6035.939640603582, 6103.0, 6150.261968563756, 6179.0, 6226.0, 6475.643839588821, 6500.93499065008, 6548.443382312462, 6564.21229452464, 6633.402993730588, 6644.368784965421, 6716.697748601325, 6752.527323087385, 6786.864262714767, 6793.898091528631, 6183.227096612924, 6222.875542489171, 6229.0, 6231.065340198916, 6246.0, 6762.391384775394, 6764.120664313607, 6764.188297404343, 6848.863022739617, 6872.862542749117, 6338.049292606146, 6510.08858759774, 6545.0, 6559.0, 6578.868422631521, 6544.312847151075, 6551.475881929434, 6580.0, 6662.433693136138, 6717.764878229238, 6611.471082313404, 6649.966750166218, 6665.566738162004, 6690.3978641921485, 6697.062411262427, 6587.374199451045, 6588.53880228389, 6653.933460665331, 6662.43369313604, 6665.500087493458, 4847.466778654345, 4895.534924182197, 4924.212126059813, 4940.752962351868, 4968.981358821433, 4242.066745316026, 4295.22685916538, 4316.395704601358, 4325.675574331937, 4399.12017596482, 5701.0308247598, 5727.484526392562, 5751.9424805751405, 5754.827355179351, 5758.0, 6664.700088496029, 6711.228208755958, 6751.189857217068, 6756.763513277012, 6788.151481064867, 5658.066419040894, 5711.6858572778065, 5775.451332123442, 5792.218050563204, 5796.130580412888, 5553.305836770403, 5609.298837645294, 5663.150527420919, 5681.517071049007, 5766.04860198071, 6338.207724034496, 6343.619382837044, 6346.9682651586445, 6354.142190804275, 6357.523185761086, 6445.16212892321, 6552.475801935833, 6557.475401967927, 6607.438367738796, 6735.0570920072205, 6471.7734879279, 6522.34776522344, 6539.6403197824575, 6557.147570815763, 6560.081588577698, 6551.21385433751, 6552.63960482178, 6560.540762146604, 6607.2071351438135, 6625.204975402982, 6496.350364963655, 6664.966675166593, 6673.232578253563, 6682.966585167043, 6720.798376048726, 6306.054091886253, 6397.136386587751, 6403.039544068427, 6409.647469389042, 6457.7094030767785, 6524.771632992823, 6546.803595892131, 6564.606123632598, 6577.539572229898, 6580.407763301135, 6400.071989561395, 6431.614103153825, 6475.287718351072, 6497.805065848032, 6520.152380190545, 6544.869102617826, 6552.180977377828, 6598.241202261801, 6630.0, 6650.268470468342, 6188.3502232266765, 6269.122322874892, 6308.842278943063, 6346.714397852015, 6347.460465860268], "value": 6041.60635759565, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [881.2245224202701, 899.2760827533837, 899.3614533681086, 903.6882275614911, 911.3666002128522, 40082.398764018566, 43610.0, 43860.561394386044, 43988.12023759523, 44000.55999440005, 39521.40717889227, 40307.19385612286, 40372.394414083814, 40605.79697101518, 40659.390109148386, 7210.459215558841, 7329.413646908234, 7352.375048120916, 7355.889661655079, 7397.260273972586, 7166.964165179167, 7262.055932728765, 7311.086114235721, 7321.743738969137, 7331.230220826817, 7255.2744725527555, 7296.160941491717, 7312.8171795705, 7328.523645962995, 7338.926610733904, 7223.855522889539, 7840.176781437953, 8061.072976607707, 8064.153263907323, 8067.273945344947, 7969.0, 8035.116137224901, 8061.032676078849, 8066.556339401331, 8069.475484093574, 37308.44037339442, 37640.81179594085, 37870.0, 37949.0, 38004.23991520182, 3462.342154990544, 3575.803330816804, 3579.0515513388973, 3579.8926032219074, 3582.623824498416, 32551.0, 32642.183945401554, 32966.175845604055, 32971.83514082414, 32979.83510082458, 5160.303359046518, 5197.948020519822, 5208.374995000624, 5217.8173763918085, 5285.524302812765, 181072.0, 181716.0, 183126.0, 183342.16657833385, 183863.0, 42850.35724464135, 43484.0, 49490.25764613534, 49541.752291238314, 49571.75214123906, 45615.31577026347, 51376.944922202696, 52385.2142217867, 52554.474455255724, 52554.73722631362, 15682.607934801494, 15736.370545178064, 15863.68272634564, 15929.203539822962, 15985.200739962955, 12825.230486170673, 12946.546870859474, 12977.675558111123, 13009.21944683303, 13027.283499407624, 14572.271386430635, 14627.634309142357, 14664.780028299583, 14797.556073317603, 14845.554633361016, 5673.716314184274, 5717.313922329347, 5736.6844823534275, 5876.6180198286975, 5904.380040095814, 995.2535598301274, 1014.6043043213152, 1019.1286450085071, 1026.573971801694, 1043.1498328862106, 23612.7638723614, 23984.04063837462, 23992.94588858892, 24075.277741667778, 24108.276751697478, 1123.3598945539604, 1133.8525991621036, 1138.2032577195996, 1143.451143451148, 1146.4095990564865, 12707.983361331257, 12811.359432028361, 12914.289714065628, 12961.805572916413, 13002.0, 237795.0, 239198.0, 240171.0, 240514.0, 240542.0, 53231.73384133132, 53273.73363133237, 53527.0, 53703.46296537063, 53720.462795372325, 1394.1077710265438, 1456.0535651826397, 1464.4435114656576, 1476.4094362254968, 1484.0798704803092, 22522.774772252724, 23094.76905230926, 23189.652155217347, 23217.535649287256, 23669.0, 37918.24163516659, 37992.810035949646, 38003.61996380112, 38012.80993595014, 38075.61924380721, 191853.0, 192053.0, 192599.0, 192978.0, 192982.0, 30800.69199308068, 34266.0, 34570.65429345674, 35113.648863511036, 35142.12144696301, 43912.0, 44333.77833110814, 44425.77787111173, 44705.32942005807, 44908.550914490435, 31696.20759480939, 31804.204894877814, 31880.5217921736, 32088.679113208567, 32543.674563254066, 31723.20691982719, 31859.0, 32115.678843212205, 32128.0, 32209.838950805097, 34769.826150870096, 35616.46575301321, 35792.46311305281, 36222.818885906454, 36511.45232821457, 31114.0, 31193.84403078061, 31257.8437107813, 31540.36919261556, 31927.0, 23621.142645927393, 23992.64011039801, 24047.879760601783, 24067.67627780489, 24206.757932421155], "value": 17225.72902997668, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.7654996626433, 574.8641162458788, 579.7651950959863, 596.6867394617825, 615.1080932647662, 21807.45481362967, 22663.54672906541, 22738.431539211506, 22744.88627556864, 22795.202167924126, 22749.180065594755, 23251.767482325173, 23453.0, 24045.038198472084, 24718.0, 6474.902876456857, 6565.212174539062, 6631.469482441393, 6646.567973081735, 6661.533692641517, 6482.57863238888, 6510.739570417178, 6577.539572229922, 6639.203295604534, 6643.93356066438, 6744.224414192382, 6768.627725475097, 6775.42408895242, 6800.727970881158, 6804.455643548505, 6776.898346524806, 6783.219929708072, 6790.0493930849725, 6796.4562834973085, 6834.897476537856, 6063.848403789896, 6311.305756366796, 6320.5575609706875, 6341.302456729756, 6342.5243106767175, 29472.410551788846, 29486.70513294861, 29734.85132574323, 29766.0, 29860.55209171864, 2061.9484512887148, 2074.8858812765293, 2083.645780217367, 2092.215419217793, 2093.9895300523553, 19719.0, 19778.109985050563, 19879.30422435222, 19885.60228795416, 19978.70031949522, 2255.3684968208763, 2312.7571604981404, 2312.757160498157, 2317.9420514487106, 2318.8260880434036, 126577.36711316477, 132103.0, 134328.32835835757, 142242.0, 143831.2808435951, 14683.412663493555, 14990.175540345386, 15041.924790376197, 15111.31999060045, 15268.389264429521, 23980.40048998754, 24026.39934001664, 24045.879770601034, 24133.637995430083, 24183.39541511441, 10243.59025638981, 10281.588736450607, 10422.791544169075, 10466.842997355046, 10600.416977066336, 9354.017828127948, 9528.95235523818, 9553.0, 9596.0, 9769.316147869724, 8978.371513993958, 9084.591193396313, 9187.540622968825, 9247.861282080774, 9450.480223587769, 2787.8606069696434, 2788.261110805638, 2803.8037337386186, 2831.9858400707863, 2842.374677570931, 963.8843338799389, 972.4359871274644, 986.9555869985868, 990.5988074829705, 994.7065615643403, 19550.804491955183, 19898.0, 20573.279935202198, 20585.58828823415, 20729.0671919764, 1023.8464230365505, 1036.1045650250053, 1044.1698849414734, 1046.7278507588082, 1054.383185836279, 11149.777004459866, 11300.491477883515, 11401.030912372542, 11535.077193824642, 11630.476628551736, 487925.0, 495526.0, 500017.0, 507274.0, 521496.0, 110671.44664276627, 111265.0, 112532.0, 113807.0, 114273.99999999834, 1142.086330935265, 1155.225998580936, 1170.5551890281638, 1170.8770579089244, 1182.6629410617932, 17583.736243956606, 18109.818901810813, 18234.270629174684, 18364.632707346045, 18465.353712619995, 55050.0, 55863.44136558582, 57143.0, 57455.0, 57716.4228357711, 70302.64848675724, 70913.0, 71493.642531787, 72560.0, 72708.0, 14603.0, 15063.322150503034, 15151.090934543963, 15214.087154770748, 15313.081215127128, 22785.316440506813, 23028.194013209457, 23337.88331058334, 23403.88298058499, 23699.881500592386, 16378.099204543865, 17463.912680436515, 17474.650506989536, 17565.385211517536, 17567.209475573887, 16529.917350413172, 16582.917085414494, 16716.546173101116, 17118.48644540666, 17328.913355433142, 17938.372156974445, 18606.53483662919, 19025.0, 19105.617887641893, 19320.613587728447, 14825.629359266104, 14835.851641483447, 14929.402823887358, 14991.625209369417, 15091.698166036836, 18090.638187236444, 18234.908825455786, 18412.171452284947, 18419.81580184181, 18437.17032733557], "value": 12056.195790622061, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.5579480873907, 1336.8262125923634, 1367.311370457485, 1369.7945308203775, 1373.6565858535366, 15181.848181518195, 15965.12191829449, 16040.278187481563, 16054.59863503413, 16069.758953615676, 15701.921490392531, 15784.21078946051, 15892.125933073676, 15915.204239788021, 15926.124063176521, 2722.6324446199706, 2858.8427636480087, 2859.2708859240865, 2867.8135921165162, 2876.3240638449947, 2814.3949050954143, 2815.0710265612297, 2845.2175651695743, 2853.443578502193, 2858.9285267868368, 2752.559590465526, 2773.084881988939, 2847.4162796626642, 2852.0588205892004, 2857.7570906472974, 2817.9436411271763, 2842.7441530262167, 2843.6161118249083, 2844.601755754196, 2850.6009158717798, 2715.497632937893, 2791.6091747155415, 2794.4969905417015, 2799.0063527447664, 2799.1042866282796, 15291.311890964933, 15739.055656660637, 15764.842351576566, 15772.447964321309, 15777.526674199793, 8109.878351824727, 8290.0, 8344.332453403713, 8378.12029736881, 8413.074561798197, 5531.806386776443, 5663.5186009189265, 5665.263515742969, 5797.9417306855885, 5826.1843341931735, 1998.3405476192822, 2002.909869055896, 2010.1959216313446, 2065.2255404223415, 2087.8956052197327, 27381.31546711348, 27775.722242777516, 27922.3019424514, 27955.301117472023, 27959.30101747452, 6782.0, 6961.23426423093, 6977.441804655616, 7170.533915295489, 7223.169335526377, 17724.91137544304, 17851.910740446216, 17868.910655446638, 17938.192781324873, 17984.190711418018, 7869.213078692085, 8241.052278987872, 8243.587820608946, 8245.051819040767, 8248.216419440143, 7351.191368949411, 7534.020577324913, 7582.696692132363, 7590.962045189739, 7648.885266721004, 5130.358705161855, 5266.131088370453, 5324.893502129936, 5360.517553420211, 5365.302510673588, 1528.9617759555877, 1538.5922730476432, 1539.3919401836213, 1562.0861795849328, 1591.124881315272, 836.7740710008385, 852.3266619370752, 871.9389642724948, 873.3624454148508, 878.332467324825, 7132.893006604905, 7220.74727384539, 7237.529560578546, 7267.491275610657, 7277.8544429111125, 722.8409749855024, 722.9602371869597, 728.2971932085563, 740.3189066059239, 752.9661165247577, 4499.392582001388, 4635.976820115878, 4804.975975120102, 5270.235815806764, 5306.867328316823, 21080.578388432146, 21204.469888252916, 21539.8923005384, 21701.240456583942, 21704.0, 19698.64901297057, 20736.377908662766, 20960.266390676254, 21075.8946205268, 21266.2556810514, 11094.1124710023, 11274.210805243718, 11282.435878206057, 11480.0, 11494.712632184095, 8154.2253485918745, 8346.081930987708, 8378.622961966605, 8401.873971890303, 8572.314214862921, 19272.8072719271, 19331.806681933, 19373.43283283865, 19442.12510437062, 19879.602407951472, 19220.615587688448, 19896.104675289356, 20192.899035504728, 20409.489762755456, 20652.483687907323, 10544.947275263574, 10613.893861061599, 11034.0, 11101.833472497758, 11214.215004949576, 14358.425662973781, 14431.711365772835, 14483.637909052359, 14538.055026423248, 14540.98213125071, 10798.352098874093, 11022.283551569122, 11040.061594764535, 11059.225854190452, 11160.776784464428, 10681.679549613524, 10829.0, 11016.834747478633, 11067.72330691739, 11285.040771534183, 9490.905090949002, 9851.556679949275, 9851.753706157404, 9911.851322230028, 9921.15670168015, 6939.410150137295, 6942.166939967236, 7072.08062951813, 7088.2202957675645, 7191.101112360955, 7289.161746399232, 7376.446766492427, 7379.483436159632, 7444.702211911463, 7515.39876809843], "value": 6712.444894824278, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.7182242033251, 516.217929836298, 531.4074806590653, 534.7113456569666, 539.4928766959058, 6235.501159907202, 6256.186695729561, 6281.968590157055, 6293.685315734207, 6331.303556608769, 6129.785457508977, 6138.140660307549, 6150.969245153779, 6160.26076870775, 6167.4757645600175, 5030.1448753711775, 5103.591712662987, 5119.206522988943, 5141.845744627667, 5155.5360017598405, 5025.64820462566, 5177.611679124062, 5178.197379406198, 5184.248283998818, 5186.24799404088, 4805.927911081337, 4862.854114376557, 4902.705837649734, 4935.605151587864, 4941.357623508939, 4844.346013288196, 4916.237983112587, 4937.23472861707, 4956.628252881031, 4962.131626965267, 4436.600705936481, 4461.330800379969, 4473.686841921067, 4482.5965663090155, 4498.865034048984, 5824.4466775656665, 5923.200367950315, 6017.518598512109, 6026.0960855871535, 6050.3647117052515, 5542.695151766651, 5619.634723742944, 5638.746256418471, 5640.858978525529, 5671.291088613923, 6439.613623182624, 6444.226692796848, 6480.837979050514, 6487.7080531376205, 6525.0, 4594.264917613166, 4732.077244937239, 4763.404574428197, 4811.158047341702, 4888.388951381077, 6499.967500162516, 6599.208095028579, 6661.700223489906, 6677.3656502632175, 6712.899306510406, 4946.282788995575, 4955.578775804097, 4999.75001249936, 5005.674631148963, 5008.549230569266, 4991.026749783758, 4997.225430058346, 5017.347744793226, 5051.4695956924725, 5069.5437410632485, 5534.695591742412, 5561.610687251854, 5603.627111357662, 5611.354694210137, 5638.661680299195, 6399.744010239631, 6526.967365163238, 6527.412532871969, 6557.2131344239, 6589.769358072444, 1716.7081596128687, 1724.7499112628598, 1724.8533874620546, 1725.284007137024, 1737.8262173782773, 1156.9305841649527, 1203.8374819399444, 1213.8846809553254, 1223.6206775899323, 1244.1726252042472, 5496.258005169331, 5566.332040155126, 5566.860828479239, 5585.301837270341, 5587.189857470644, 6503.544751867324, 6613.933860661428, 6644.966775166094, 6651.102101216371, 6700.396964273239, 5531.806386776524, 5552.139418390076, 5581.553475721932, 5594.9720251398485, 5605.887882242414, 3415.470602056685, 3450.3949518114164, 3465.9480107798404, 3470.305938812248, 3499.61504234534, 6316.30520642729, 6429.228492580826, 6445.903311450332, 6451.999880037203, 6514.05546195799, 6718.966405167943, 6749.021391898147, 6757.222919364238, 6833.282505336968, 6837.0, 5989.700514974321, 6326.778562750374, 6352.650604216812, 6359.268684101387, 6412.487001039999, 6419.839504012437, 6454.967725161344, 6484.870302594016, 6519.3480651936325, 6623.801285961429, 6409.596401349494, 6427.710753016219, 6435.710393032231, 6513.837154071186, 6518.608883467007, 6282.738214242961, 6314.463270622048, 6367.790119877209, 6396.680165991589, 6433.228012638513, 4911.189653707098, 4924.335214745964, 4958.0, 4976.552110310089, 4982.601391888713, 6395.712192951235, 6503.414692677682, 6591.802245932629, 6615.636140012345, 6671.46628269747, 6113.786017489367, 6299.14175318273, 6394.872102558016, 6434.646094464662, 6451.70967306463, 6402.71018417652, 6424.261209960914, 6445.677716114269, 6457.644829534421, 6477.157969464128, 6336.968315158395, 6385.106085148176, 6395.584287021329, 6405.167328247298, 6441.935580644134, 6304.432601065926, 6370.77702280418, 6488.0, 6507.642079685663, 6555.639439830665, 6221.3156552778255, 6224.595401298901, 6230.691554773367, 6256.523454471862, 6338.0], "value": 4987.631676307473, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [883.2580632268892, 899.878516400286, 915.3455279475174, 921.728090213387, 938.6151677812094, 19895.502612434695, 20763.792362076376, 20766.27318043869, 20856.061477233525, 21083.789162108373, 18605.348812791555, 18872.33946811862, 19187.616247675072, 19592.90203548984, 19668.0, 965.1410244882061, 987.8518222266681, 990.4700984973041, 992.9205663546899, 996.6362277768635, 990.425553179158, 996.4121168510577, 1000.4547521600708, 1001.1140140975217, 1001.7095042437684, 998.7952469743722, 1033.1734612310122, 1034.1416624201895, 1038.0, 1041.526105621944, 1024.7847951930066, 1026.5893642543042, 1032.6901929421144, 1033.7363972187081, 1037.071820720451, 717.4619035723208, 731.0934441292811, 740.311510295425, 752.3379426104992, 760.9391248700091, 20093.698594521094, 20136.697949530768, 20240.898795505927, 20310.390688279374, 20351.49121271965, 20324.28864989733, 21918.89040554803, 22131.77868221313, 22135.557288854292, 22371.0, 16807.159642017974, 16933.3226670933, 17019.23413446398, 17106.0, 17255.0, 3782.9432558511644, 3796.9620303796883, 3807.638274363931, 3847.7306588538813, 3848.8460461581503, 145570.27214863963, 146036.0, 148763.0, 150681.0, 150850.0, 17163.399281025104, 17491.212895419736, 17836.643267134583, 17855.553611159827, 18040.548986275448, 31786.887458938825, 33023.0, 33342.83328583342, 33506.49740253898, 33544.32911341808, 8785.0, 8849.336299777542, 8965.775855603662, 9035.45787252767, 9040.547972601344, 4531.954680453153, 4548.226801443762, 4562.8631141065825, 4571.291449825216, 4594.908101837945, 5503.2020357048, 5616.9157462638095, 5624.0, 5644.943550564524, 5661.631993920382, 922.6263363337858, 937.4984383354872, 948.8434408322549, 949.0, 958.0467434902178, 657.8092353217513, 670.0, 674.20780582815, 704.1972151747012, 704.9989015597863, 11487.597934072268, 11682.0, 12063.526976969875, 12693.809592856114, 12766.744665106831, 664.707528687376, 675.8479342148075, 683.6923384477005, 687.3950923187563, 697.4106879686657, 5701.771929122871, 5787.363390027093, 5883.0, 5900.262467191601, 5922.822315330547, 123218.0, 123810.0, 124665.0, 125207.3739631296, 125246.0, 93926.0, 112670.43664781624, 115272.42363788295, 116062.0, 119134.40432797954, 95265.0, 97435.0, 98035.50982245043, 98076.50961745146, 99917.0, 12218.38908054576, 12461.439235234255, 12568.245905245714, 12623.215245833127, 12707.04697147736, 41939.58060419479, 43017.0, 43487.0, 44324.55675443333, 44844.32733509065, 59376.40623593709, 59503.702481487315, 59821.102683458914, 60487.69756151191, 60975.0, 17143.142842857556, 17844.643107137526, 17904.55238619045, 18145.092745362414, 18306.81693183051, 20959.790402096394, 21200.4699882498, 21232.575348493254, 21336.0, 21601.45996350041, 18414.355497558056, 18562.44312670622, 18575.071246437354, 18624.16191271369, 18771.90614046921, 18843.90578047101, 19267.036648167817, 19268.518287042374, 19383.321583745066, 19590.60818783645, 16912.830871691127, 16972.0, 17142.40001599938, 17358.218880150172, 17533.912330438267, 13441.731165376834, 13520.18878867271, 13570.79643805324, 13734.244616546186, 13924.094933829268, 12320.507179712711, 12593.307368094476, 12639.557615483414, 12777.0, 12825.935870320589], "value": 9349.674606942039, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1441.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 110.0, 113.0, 108.0, 109.0, 115.0, 114.0, 115.0, 118.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 119.0, 125.0, 116.0, 115.0, 116.0, 125.0, 124.0, 119.0, 119.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 162.0, 164.0, 162.0, 179.0, 167.0, 167.0, 167.0, 170.0], "value": 167.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 79.0, 80.0, 76.0, 77.0, 82.0, 78.0, 74.0, 82.0, 77.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 86.0, 84.0, 89.0, 86.0, 86.0, 78.0, 79.0, 77.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 116.0, 114.0, 120.0, 122.0, 114.0, 112.0, 117.0, 117.0], "value": 115.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 156.0, 149.0, 148.0, 148.0, 149.0, 152.0, 150.0, 148.0, 151.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 95.0, 95.0, 95.0, 94.0, 94.0, 101.0, 94.0, 94.0, 94.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 60.0, 53.0, 61.0, 52.0, 59.0, 52.0, 56.0, 54.0, 52.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 42.0, 37.0, 48.0, 45.0, 38.0, 45.0, 41.0, 39.0, 36.0], "value": 41.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 72.0, 74.0, 79.0, 79.0, 77.0, 78.0, 75.0, 79.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 114.0, 110.0, 117.0, 111.0, 118.0, 115.0, 116.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 160.0, 164.0, 168.0, 174.0, 164.0, 164.0, 168.0, 170.0, 163.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 67.0, 65.0, 63.0, 63.0, 63.0, 64.0, 65.0, 68.0, 63.0], "value": 65.6, "unit": "score"}]}]} 18:12:49 INFO - Return code: 0 18:12:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:12:49 INFO - # TBPL SUCCESS # 18:12:49 INFO - Running post-action listener: _package_coverage_data 18:12:49 INFO - Running post-action listener: _resource_record_post_action 18:12:49 INFO - [mozharness: 2017-09-28 01:12:49.573420Z] Finished run-tests step (success) 18:12:49 INFO - Running post-run listener: _resource_record_post_run 18:12:49 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4157440; Write bytes: 337129472; Read time: 1080; Write time: 185952 18:12:49 INFO - TinderboxPrint: CPU usage
13.2% 18:12:49 INFO - TinderboxPrint: I/O read bytes / time
4,157,440 / 1,080 18:12:49 INFO - TinderboxPrint: I/O write bytes / time
337,129,472 / 185,952 18:12:49 INFO - TinderboxPrint: CPU idle
8,255.9 (86.7%) 18:12:49 INFO - TinderboxPrint: CPU user
1,198.7 (12.6%) 18:12:49 INFO - TinderboxPrint: Swap in / out
0 / 0 18:12:49 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13438976; Read time: 0; Write time: 2272 18:12:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:12:49 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4153344; Write bytes: 323690496; Read time: 1064; Write time: 183680 18:12:50 INFO - Running post-run listener: _upload_blobber_files 18:12:50 INFO - Blob upload gear active. 18:12:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:12:50 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 18:12:50 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'] 18:12:50 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 18:12:50 INFO - (blobuploader) - INFO - Open directory for files ... 18:12:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:12:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:12:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:12:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:12:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:12:51 INFO - (blobuploader) - INFO - Done attempting. 18:12:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:12:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:12:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:12:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:12:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:12:52 INFO - (blobuploader) - INFO - Done attempting. 18:12:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:12:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:12:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:12:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:12:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:12:53 INFO - (blobuploader) - INFO - Done attempting. 18:12:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:12:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:12:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:13:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:13:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:13:01 INFO - (blobuploader) - INFO - Done attempting. 18:13:01 INFO - (blobuploader) - INFO - Iteration through files over. 18:13:01 INFO - Return code: 0 18:13:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:13:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:13:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e51ee8c2e56974ab8cb0f6d87cec03505b5d113b34b1b1d7e844cba311ae87efb17c086146a082bd130f438eeaa71bd51ad070c7107b8a310de136a3f7def7ff", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bb8d5fb5dfe8ad48a04039b4240887faa07135c4d40c349ab7b795f0e0c332499122f6129b522e7591950efb83f3d637970dd747cb3f35f6c464dba0ddb4ba3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/375978c95892fbafc57cbe12d4ff9726d17b61e2427bff47efa9915d6f0099d0508700db02a9a0f26512eb8b1af2f1ecfaafa51f05f341802f51f1130587db98", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f8de149b0dec7f660b81c6f520893c2f29d0582354a097fea7082e5486bf8891cf7521f0c6ce8aeed2d4570e3c5fa0e3ff47b25f4520f8aeb4c0e71ebddc77"} 18:13:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:13:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:13:01 INFO - Contents: 18:13:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e51ee8c2e56974ab8cb0f6d87cec03505b5d113b34b1b1d7e844cba311ae87efb17c086146a082bd130f438eeaa71bd51ad070c7107b8a310de136a3f7def7ff", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bb8d5fb5dfe8ad48a04039b4240887faa07135c4d40c349ab7b795f0e0c332499122f6129b522e7591950efb83f3d637970dd747cb3f35f6c464dba0ddb4ba3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/375978c95892fbafc57cbe12d4ff9726d17b61e2427bff47efa9915d6f0099d0508700db02a9a0f26512eb8b1af2f1ecfaafa51f05f341802f51f1130587db98", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f8de149b0dec7f660b81c6f520893c2f29d0582354a097fea7082e5486bf8891cf7521f0c6ce8aeed2d4570e3c5fa0e3ff47b25f4520f8aeb4c0e71ebddc77"} 18:13:01 INFO - Running post-run listener: copy_logs_to_upload_dir 18:13:01 INFO - Copying logs to upload dir... 18:13:01 INFO - mkdir: /builds/slave/test/build/upload/logs 18:13:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.426290 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-27 18:13:01.274075) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 18:13:01.278482) ========= 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=1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911 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/e51ee8c2e56974ab8cb0f6d87cec03505b5d113b34b1b1d7e844cba311ae87efb17c086146a082bd130f438eeaa71bd51ad070c7107b8a310de136a3f7def7ff", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bb8d5fb5dfe8ad48a04039b4240887faa07135c4d40c349ab7b795f0e0c332499122f6129b522e7591950efb83f3d637970dd747cb3f35f6c464dba0ddb4ba3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/375978c95892fbafc57cbe12d4ff9726d17b61e2427bff47efa9915d6f0099d0508700db02a9a0f26512eb8b1af2f1ecfaafa51f05f341802f51f1130587db98", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f8de149b0dec7f660b81c6f520893c2f29d0582354a097fea7082e5486bf8891cf7521f0c6ce8aeed2d4570e3c5fa0e3ff47b25f4520f8aeb4c0e71ebddc77"} build_url:https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011522 build_url: 'https://queue.taskcluster.net/v1/task/ROcRQ0zqS7mg-39GJg0GHQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e51ee8c2e56974ab8cb0f6d87cec03505b5d113b34b1b1d7e844cba311ae87efb17c086146a082bd130f438eeaa71bd51ad070c7107b8a310de136a3f7def7ff", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bb8d5fb5dfe8ad48a04039b4240887faa07135c4d40c349ab7b795f0e0c332499122f6129b522e7591950efb83f3d637970dd747cb3f35f6c464dba0ddb4ba3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/375978c95892fbafc57cbe12d4ff9726d17b61e2427bff47efa9915d6f0099d0508700db02a9a0f26512eb8b1af2f1ecfaafa51f05f341802f51f1130587db98", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f8de149b0dec7f660b81c6f520893c2f29d0582354a097fea7082e5486bf8891cf7521f0c6ce8aeed2d4570e3c5fa0e3ff47b25f4520f8aeb4c0e71ebddc77"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 18:13:01.308905) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:13:01.309268) ========= 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=1258088b6da462a47ba2cd90000001a2-1506559933.374032-922839911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004539 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:13:01.395780) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 18:13:01.396351) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 18:13:01.396880) ========= ========= Total master_lag: 0.21 =========