builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1504478785.23 results: success (0) revision: dbf9f7430406ca3220529c5b4c05b26511efa3dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.228886) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.229342) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.229617) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.376393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.376652) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 15:46:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.0M=0s 2017-09-03 15:46:25 (70.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152333 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.595310) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.595590) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015081 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.683388) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.683746) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dbf9f7430406ca3220529c5b4c05b26511efa3dc --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 dbf9f7430406ca3220529c5b4c05b26511efa3dc --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=56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 15:46:25,817 truncating revision to first 12 chars 2017-09-03 15:46:25,817 Setting DEBUG logging. 2017-09-03 15:46:25,817 attempt 1/10 2017-09-03 15:46:25,817 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dbf9f7430406?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-03 15:46:26,170 unpacking tar archive at: mozilla-central-dbf9f7430406/testing/mozharness/ program finished with exit code 0 elapsedTime=0.740497 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.451244) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.451550) ========= script_repo_revision: dbf9f7430406ca3220529c5b4c05b26511efa3dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.451917) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.452191) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.465533) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-09-03 15:46:26.465896) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:46:26 INFO - MultiFileLogger online at 20170903 15:46:26 in /builds/slave/test 15:46:26 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 15:46:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:46:26 INFO - {'append_to_log': False, 15:46:26 INFO - 'base_work_dir': '/builds/slave/test', 15:46:26 INFO - 'blob_upload_branch': 'Firefox', 15:46:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:46:26 INFO - 'branch': 'Firefox', 15:46:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:46:26 INFO - 'code_coverage': False, 15:46:26 INFO - 'config_files': ('talos/linux_config.py',), 15:46:26 INFO - 'default_actions': ('clobber', 15:46:26 INFO - 'read-buildbot-config', 15:46:26 INFO - 'download-and-extract', 15:46:26 INFO - 'populate-webroot', 15:46:26 INFO - 'create-virtualenv', 15:46:26 INFO - 'install', 15:46:26 INFO - 'setup-mitmproxy', 15:46:26 INFO - 'run-tests'), 15:46:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:46:26 INFO - 'disable_ccov_upload': False, 15:46:26 INFO - 'disable_stylo': False, 15:46:26 INFO - 'download_minidump_stackwalk': True, 15:46:26 INFO - 'download_symbols': 'ondemand', 15:46:26 INFO - 'enable_stylo': False, 15:46:26 INFO - 'enable_webrender': False, 15:46:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:46:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:46:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:46:26 INFO - 'gecko_profile': False, 15:46:26 INFO - 'gecko_profile_interval': 0, 15:46:26 INFO - 'installer_path': 'installer.exe', 15:46:26 INFO - 'log_level': 'info', 15:46:26 INFO - 'log_name': 'talos', 15:46:26 INFO - 'log_to_console': True, 15:46:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:46:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:46:26 INFO - 'opt_config_files': (), 15:46:26 INFO - 'pip_index': False, 15:46:26 INFO - 'suite': 'dromaeojs-e10s', 15:46:26 INFO - 'system_bits': '32', 15:46:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:46:26 INFO - 'title': 'talos-linux64-ix-037', 15:46:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:46:26 INFO - 'use_talos_json': True, 15:46:26 INFO - 'verify': 'False', 15:46:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:46:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:46:26 INFO - 'work_dir': 'build'} 15:46:26 INFO - [mozharness: 2017-09-03 22:46:26.791319Z] Running clobber step. 15:46:26 INFO - Running pre-action listener: _resource_record_pre_action 15:46:26 INFO - Running main action method: clobber 15:46:26 INFO - rmtree: /builds/slave/test/build 15:46:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:46:27 INFO - Running post-action listener: _resource_record_post_action 15:46:27 INFO - [mozharness: 2017-09-03 22:46:27.776411Z] Finished clobber step (success) 15:46:27 INFO - [mozharness: 2017-09-03 22:46:27.776508Z] Running read-buildbot-config step. 15:46:27 INFO - Running pre-action listener: _resource_record_pre_action 15:46:27 INFO - Running main action method: read_buildbot_config 15:46:27 INFO - Using buildbot properties: 15:46:27 INFO - { 15:46:27 INFO - "project": "", 15:46:27 INFO - "product": "firefox", 15:46:27 INFO - "who": "archaeopteryx@coole-files.de", 15:46:27 INFO - "installer_path": "public/build/target.tar.bz2", 15:46:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 15:46:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:46:27 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 15:46:27 INFO - "stage_platform": "linux64-stylosequential", 15:46:27 INFO - "basedir": "/builds/slave/test", 15:46:27 INFO - "buildnumber": 185, 15:46:27 INFO - "platform": "ubuntu64_hw_styloseq", 15:46:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:46:27 INFO - "slavebuilddir": "test", 15:46:27 INFO - "taskId": "Vnu9VAYJTvGifXXzveC79w", 15:46:27 INFO - "branch": "mozilla-central", 15:46:27 INFO - "script_repo_revision": "production", 15:46:27 INFO - "revision": "dbf9f7430406ca3220529c5b4c05b26511efa3dc", 15:46:27 INFO - "slavename": "talos-linux64-ix-037", 15:46:27 INFO - "repo_path": "mozilla-central" 15:46:27 INFO - } 15:46:27 INFO - Finding installer, test and symbols from parent task. 15:46:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vnu9VAYJTvGifXXzveC79w'}, attempt #1 15:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vnu9VAYJTvGifXXzveC79w'}, attempt #1 15:46:28 INFO - Task dependencies: aMXnMtCbR1yOPtHcAMbvtA 15:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA'}, attempt #1 15:46:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.tar.bz2 15:46:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.test_packages.json 15:46:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.crashreporter-symbols.zip 15:46:29 INFO - Running post-action listener: _resource_record_post_action 15:46:29 INFO - [mozharness: 2017-09-03 22:46:29.305830Z] Finished read-buildbot-config step (success) 15:46:29 INFO - [mozharness: 2017-09-03 22:46:29.306047Z] Running download-and-extract step. 15:46:29 INFO - Running pre-action listener: _resource_record_pre_action 15:46:29 INFO - Running main action method: download_and_extract 15:46:29 INFO - mkdir: /builds/slave/test/build/tests 15:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:29 INFO - trying https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.test_packages.json 15:46:29 INFO - Downloading https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:46:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:46:29 INFO - Downloaded 989 bytes. 15:46:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:46:29 INFO - Using the following test package requirements: 15:46:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:46:29 INFO - u'common': [u'target.common.tests.zip'], 15:46:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:46:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:46:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:46:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:46:29 INFO - u'mozbase': [u'target.common.tests.zip'], 15:46:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:46:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:46:29 INFO - u'web-platform': [u'target.common.tests.zip', 15:46:29 INFO - u'target.web-platform.tests.tar.gz'], 15:46:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:46:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:46:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.common.tests.zip 15:46:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:46:29 INFO - Fetch https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.common.tests.zip into memory 15:46:31 INFO - Content-Length response header: 55022268 15:46:31 INFO - Bytes received: 55022268 15:46:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:46:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.common.tests.zip 15:46:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:46:42 INFO - Fetch https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.common.tests.zip into memory 15:46:43 INFO - Content-Length response header: 55022268 15:46:43 INFO - Bytes received: 55022268 15:46:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.talos.tests.zip 15:46:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:46:56 INFO - Fetch https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.talos.tests.zip into memory 15:46:57 INFO - Content-Length response header: 14496650 15:46:57 INFO - Bytes received: 14496650 15:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:57 INFO - trying https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.tar.bz2 15:46:57 INFO - Downloading https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:46:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:46:58 INFO - Downloaded 62639199 bytes. 15:46:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.tar.bz2 15:46:58 INFO - mkdir: /builds/slave/test/properties 15:46:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:46:58 INFO - Writing to file /builds/slave/test/properties/build_url 15:46:58 INFO - Contents: 15:46:58 INFO - build_url:https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.tar.bz2 15:46:58 INFO - Running post-action listener: _resource_record_post_action 15:46:58 INFO - Running post-action listener: find_tests_for_verification 15:46:58 INFO - Running post-action listener: set_extra_try_arguments 15:46:58 INFO - [mozharness: 2017-09-03 22:46:58.492895Z] Finished download-and-extract step (success) 15:46:58 INFO - [mozharness: 2017-09-03 22:46:58.492992Z] Running populate-webroot step. 15:46:58 INFO - Running pre-action listener: _resource_record_pre_action 15:46:58 INFO - Running main action method: populate_webroot 15:46:58 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:46:58 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:46:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:46:58 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:46:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:46:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:58 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:58 INFO - 'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:46:58 INFO - 'tests': ['damp', 'tps']}, 15:46:58 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:58 INFO - 'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['damp', 'tps']}, 15:46:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:46:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:46:58 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:46:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:46:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:46:58 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:58 INFO - 'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:46:58 INFO - 'other-e10s': {'tests': ['a11yr', 15:46:58 INFO - 'ts_paint', 15:46:58 INFO - 'tpaint', 15:46:58 INFO - 'sessionrestore', 15:46:58 INFO - 'sessionrestore_many_windows', 15:46:58 INFO - 'sessionrestore_no_auto_restore', 15:46:58 INFO - 'tabpaint']}, 15:46:58 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['a11yr', 15:46:58 INFO - 'ts_paint', 15:46:58 INFO - 'tpaint', 15:46:58 INFO - 'sessionrestore', 15:46:58 INFO - 'sessionrestore_many_windows', 15:46:58 INFO - 'sessionrestore_no_auto_restore', 15:46:58 INFO - 'tabpaint']}, 15:46:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:46:58 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:46:58 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['bloom_basic_singleton']}, 15:46:58 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['bloom_basic']}, 15:46:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:46:58 INFO - 'tsvgr_opacity', 15:46:58 INFO - 'tart', 15:46:58 INFO - 'tscrollx', 15:46:58 INFO - 'tsvg_static']}, 15:46:58 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['tsvgx', 15:46:58 INFO - 'tsvgr_opacity', 15:46:58 INFO - 'tart', 15:46:58 INFO - 'tscrollx', 15:46:58 INFO - 'tsvg_static']}, 15:46:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:46:58 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:58 INFO - 'talos_options': ['--enable-stylo'], 15:46:58 INFO - 'tests': ['tp5o']}, 15:46:58 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:58 INFO - 'talos_options': ['--mitmproxy', 15:46:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:58 INFO - '--firstNonBlankPaint'], 15:46:58 INFO - 'tests': ['tp6_google', 15:46:58 INFO - 'tp6_youtube', 15:46:58 INFO - 'tp6_amazon', 15:46:58 INFO - 'tp6_facebook']}, 15:46:58 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:58 INFO - 'talos_options': ['--enable-stylo', 15:46:58 INFO - '--mitmproxy', 15:46:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:58 INFO - '--firstNonBlankPaint'], 15:46:58 INFO - 'tests': ['tp6_google', 15:46:58 INFO - 'tp6_youtube', 15:46:58 INFO - 'tp6_amazon', 15:46:58 INFO - 'tp6_facebook']}, 15:46:58 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:58 INFO - 'talos_options': ['--enable-stylo', 15:46:58 INFO - '--stylo-threads=1', 15:46:58 INFO - '--mitmproxy', 15:46:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:58 INFO - '--firstNonBlankPaint'], 15:46:58 INFO - 'tests': ['tp6_google', 15:46:58 INFO - 'tp6_youtube', 15:46:58 INFO - 'tp6_amazon', 15:46:58 INFO - 'tp6_facebook']}, 15:46:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:46:58 INFO - 'talos_options': ['--xperf_path', 15:46:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:58 INFO - 'tests': ['tp5n']}, 15:46:58 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:58 INFO - 'talos_options': ['--enable-stylo', 15:46:58 INFO - '--xperf_path', 15:46:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:58 INFO - 'tests': ['tp5n']}}} 15:46:58 INFO - Running post-action listener: _resource_record_post_action 15:46:58 INFO - [mozharness: 2017-09-03 22:46:58.507278Z] Finished populate-webroot step (success) 15:46:58 INFO - [mozharness: 2017-09-03 22:46:58.507366Z] Running create-virtualenv step. 15:46:58 INFO - Running pre-action listener: _resource_record_pre_action 15:46:58 INFO - Running main action method: create_virtualenv 15:46:58 INFO - Creating virtualenv /builds/slave/test/build/venv 15:46:58 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:46:58 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:46:58 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:46:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:46:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:46:59 INFO - Installing distribute.............................................................................................................................................................................................done. 15:46:59 INFO - Installing pip...............done. 15:46:59 INFO - Return code: 0 15:46:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:46:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:46:59 INFO - Reading from file tmpfile_stdout 15:46:59 INFO - Output received: 15:46:59 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:46:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:59 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 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:46:59 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 15:46:59 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 15:46:59 INFO - Using env: {'DISPLAY': ':0', 15:46:59 INFO - 'HOME': '/home/cltbld', 15:46:59 INFO - 'LANG': 'en_US.UTF-8', 15:46:59 INFO - 'LANGUAGE': 'en_US:en', 15:46:59 INFO - 'LOGNAME': 'cltbld', 15:46:59 INFO - 'MAIL': '/var/mail/cltbld', 15:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:59 INFO - 'MOZ_NO_REMOTE': '1', 15:46:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:59 INFO - 'NO_EM_RESTART': '1', 15:46:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:59 INFO - 'PWD': '/builds/slave/test', 15:46:59 INFO - 'SHELL': '/bin/bash', 15:46:59 INFO - 'SHLVL': '1', 15:46:59 INFO - 'TERM': 'linux', 15:46:59 INFO - 'TMOUT': '86400', 15:46:59 INFO - 'USER': 'cltbld', 15:46:59 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:59 INFO - '_': '/tools/buildbot/bin/python'} 15:47:01 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:47:01 INFO - Downloading/unpacking pip>=1.5 15:47:01 INFO - Running setup.py egg_info for package pip 15:47:01 INFO - warning: no files found matching 'pip/cacert.pem' 15:47:01 INFO - warning: no files found matching '*.html' under directory 'docs' 15:47:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:47:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:47:01 INFO - Installing collected packages: pip 15:47:01 INFO - Found existing installation: pip 1.1 15:47:01 INFO - Uninstalling pip: 15:47:01 INFO - Successfully uninstalled pip 15:47:01 INFO - Running setup.py install for pip 15:47:01 INFO - warning: no files found matching 'pip/cacert.pem' 15:47:01 INFO - warning: no files found matching '*.html' under directory 'docs' 15:47:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:47:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:47:01 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:47:01 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:47:01 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:47:01 INFO - Successfully installed pip 15:47:01 INFO - Cleaning up... 15:47:01 INFO - Return code: 0 15:47:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:47:01 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')]} 15:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:01 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')]} 15:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:01 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 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:47:01 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 15:47:01 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 15:47:01 INFO - Using env: {'DISPLAY': ':0', 15:47:01 INFO - 'HOME': '/home/cltbld', 15:47:01 INFO - 'LANG': 'en_US.UTF-8', 15:47:01 INFO - 'LANGUAGE': 'en_US:en', 15:47:01 INFO - 'LOGNAME': 'cltbld', 15:47:01 INFO - 'MAIL': '/var/mail/cltbld', 15:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:01 INFO - 'MOZ_NO_REMOTE': '1', 15:47:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:01 INFO - 'NO_EM_RESTART': '1', 15:47:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:01 INFO - 'PWD': '/builds/slave/test', 15:47:01 INFO - 'SHELL': '/bin/bash', 15:47:01 INFO - 'SHLVL': '1', 15:47:01 INFO - 'TERM': 'linux', 15:47:01 INFO - 'TMOUT': '86400', 15:47:01 INFO - 'USER': 'cltbld', 15:47:01 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:01 INFO - '_': '/tools/buildbot/bin/python'} 15:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:01 INFO - Downloading/unpacking psutil>=3.1.1 15:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:47:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:02 INFO - Installing collected packages: psutil 15:47:02 INFO - Running setup.py install for psutil 15:47:02 INFO - building 'psutil._psutil_linux' extension 15:47:02 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 15:47:03 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 15:47:03 INFO - building 'psutil._psutil_posix' extension 15:47:03 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 15:47:03 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 15:47:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:03 INFO - Successfully installed psutil 15:47:03 INFO - Cleaning up... 15:47:04 INFO - Return code: 0 15:47:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:47:04 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')]} 15:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:04 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')]} 15:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:04 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 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:47:04 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 15:47:04 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 15:47:04 INFO - Using env: {'DISPLAY': ':0', 15:47:04 INFO - 'HOME': '/home/cltbld', 15:47:04 INFO - 'LANG': 'en_US.UTF-8', 15:47:04 INFO - 'LANGUAGE': 'en_US:en', 15:47:04 INFO - 'LOGNAME': 'cltbld', 15:47:04 INFO - 'MAIL': '/var/mail/cltbld', 15:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:04 INFO - 'MOZ_NO_REMOTE': '1', 15:47:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:04 INFO - 'NO_EM_RESTART': '1', 15:47:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:04 INFO - 'PWD': '/builds/slave/test', 15:47:04 INFO - 'SHELL': '/bin/bash', 15:47:04 INFO - 'SHLVL': '1', 15:47:04 INFO - 'TERM': 'linux', 15:47:04 INFO - 'TMOUT': '86400', 15:47:04 INFO - 'USER': 'cltbld', 15:47:04 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:04 INFO - '_': '/tools/buildbot/bin/python'} 15:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:47:04 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 15:47:04 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 15:47:04 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 15:47:04 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 15:47:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:47:05 INFO - Installing collected packages: mozsystemmonitor 15:47:05 INFO - Running setup.py install for mozsystemmonitor 15:47:05 INFO - Successfully installed mozsystemmonitor 15:47:05 INFO - Cleaning up... 15:47:05 INFO - Return code: 0 15:47:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:47:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:47:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:47:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:47:05 INFO - Using env: {'DISPLAY': ':0', 15:47:05 INFO - 'HOME': '/home/cltbld', 15:47:05 INFO - 'LANG': 'en_US.UTF-8', 15:47:05 INFO - 'LANGUAGE': 'en_US:en', 15:47:05 INFO - 'LOGNAME': 'cltbld', 15:47:05 INFO - 'MAIL': '/var/mail/cltbld', 15:47:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:05 INFO - 'MOZ_NO_REMOTE': '1', 15:47:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:05 INFO - 'NO_EM_RESTART': '1', 15:47:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:05 INFO - 'PWD': '/builds/slave/test', 15:47:05 INFO - 'SHELL': '/bin/bash', 15:47:05 INFO - 'SHLVL': '1', 15:47:05 INFO - 'TERM': 'linux', 15:47:05 INFO - 'TMOUT': '86400', 15:47:05 INFO - 'USER': 'cltbld', 15:47:05 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:05 INFO - '_': '/tools/buildbot/bin/python'} 15:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:05 INFO - Downloading/unpacking jsonschema==2.5.1 15:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:47:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:47:06 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 15:47:06 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 15:47:06 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 15:47:06 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 15:47:06 INFO - Downloading functools32-3.2.3-2.tar.gz 15:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:47:07 INFO - warning: no files found matching '*.txt' 15:47:07 INFO - no previously-included directories found matching 'build' 15:47:07 INFO - no previously-included directories found matching 'dist' 15:47:07 INFO - no previously-included directories found matching '.git*' 15:47:07 INFO - Installing collected packages: jsonschema, functools32 15:47:07 INFO - Running setup.py install for functools32 15:47:07 INFO - warning: no files found matching '*.txt' 15:47:07 INFO - no previously-included directories found matching 'build' 15:47:07 INFO - no previously-included directories found matching 'dist' 15:47:07 INFO - no previously-included directories found matching '.git*' 15:47:07 INFO - Successfully installed jsonschema functools32 15:47:07 INFO - Cleaning up... 15:47:07 INFO - Return code: 0 15:47:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:47:07 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')]} 15:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:07 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')]} 15:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:07 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 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:47:07 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 15:47:07 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 15:47:07 INFO - Using env: {'DISPLAY': ':0', 15:47:07 INFO - 'HOME': '/home/cltbld', 15:47:07 INFO - 'LANG': 'en_US.UTF-8', 15:47:07 INFO - 'LANGUAGE': 'en_US:en', 15:47:07 INFO - 'LOGNAME': 'cltbld', 15:47:07 INFO - 'MAIL': '/var/mail/cltbld', 15:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:07 INFO - 'MOZ_NO_REMOTE': '1', 15:47:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:07 INFO - 'NO_EM_RESTART': '1', 15:47:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:07 INFO - 'PWD': '/builds/slave/test', 15:47:07 INFO - 'SHELL': '/bin/bash', 15:47:07 INFO - 'SHLVL': '1', 15:47:07 INFO - 'TERM': 'linux', 15:47:07 INFO - 'TMOUT': '86400', 15:47:07 INFO - 'USER': 'cltbld', 15:47:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:07 INFO - '_': '/tools/buildbot/bin/python'} 15:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:47:07 INFO - Cleaning up... 15:47:07 INFO - Return code: 0 15:47:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:47:07 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')]} 15:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:07 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')]} 15:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:07 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 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:47:07 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 15:47:07 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 15:47:07 INFO - Using env: {'DISPLAY': ':0', 15:47:07 INFO - 'HOME': '/home/cltbld', 15:47:07 INFO - 'LANG': 'en_US.UTF-8', 15:47:07 INFO - 'LANGUAGE': 'en_US:en', 15:47:07 INFO - 'LOGNAME': 'cltbld', 15:47:07 INFO - 'MAIL': '/var/mail/cltbld', 15:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:07 INFO - 'MOZ_NO_REMOTE': '1', 15:47:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:07 INFO - 'NO_EM_RESTART': '1', 15:47:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:07 INFO - 'PWD': '/builds/slave/test', 15:47:07 INFO - 'SHELL': '/bin/bash', 15:47:07 INFO - 'SHLVL': '1', 15:47:07 INFO - 'TERM': 'linux', 15:47:07 INFO - 'TMOUT': '86400', 15:47:07 INFO - 'USER': 'cltbld', 15:47:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:07 INFO - '_': '/tools/buildbot/bin/python'} 15:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:07 INFO - Downloading/unpacking blobuploader==1.2.4 15:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:08 INFO - Downloading blobuploader-1.2.4.tar.gz 15:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:47:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:47:08 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 15:47:08 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 15:47:08 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 15:47:08 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 15:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:47:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:47:09 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 15:47:09 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 15:47:09 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 15:47:09 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 15:47:09 INFO - Downloading docopt-0.6.1.tar.gz 15:47:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:47:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:47:09 INFO - Running setup.py install for blobuploader 15:47:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:47:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:47:09 INFO - Running setup.py install for requests 15:47:09 INFO - Running setup.py install for docopt 15:47:09 INFO - Successfully installed blobuploader requests docopt 15:47:09 INFO - Cleaning up... 15:47:09 INFO - Return code: 0 15:47:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:09 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 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:47:09 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 15:47:09 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 15:47:09 INFO - Using env: {'DISPLAY': ':0', 15:47:09 INFO - 'HOME': '/home/cltbld', 15:47:09 INFO - 'LANG': 'en_US.UTF-8', 15:47:09 INFO - 'LANGUAGE': 'en_US:en', 15:47:09 INFO - 'LOGNAME': 'cltbld', 15:47:09 INFO - 'MAIL': '/var/mail/cltbld', 15:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:09 INFO - 'MOZ_NO_REMOTE': '1', 15:47:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:09 INFO - 'NO_EM_RESTART': '1', 15:47:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:09 INFO - 'PWD': '/builds/slave/test', 15:47:09 INFO - 'SHELL': '/bin/bash', 15:47:09 INFO - 'SHLVL': '1', 15:47:09 INFO - 'TERM': 'linux', 15:47:09 INFO - 'TMOUT': '86400', 15:47:09 INFO - 'USER': 'cltbld', 15:47:09 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:09 INFO - '_': '/tools/buildbot/bin/python'} 15:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:10 INFO - Running setup.py (path:/tmp/pip-PdwDQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:10 INFO - Running setup.py (path:/tmp/pip-cWEhfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:10 INFO - Running setup.py (path:/tmp/pip-aYDmn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:10 INFO - Running setup.py (path:/tmp/pip-Ov2Hit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:10 INFO - Running setup.py (path:/tmp/pip-Nx0RCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:10 INFO - Running setup.py (path:/tmp/pip-mqT8F7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:10 INFO - Running setup.py (path:/tmp/pip-jesTYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:10 INFO - Running setup.py (path:/tmp/pip-Hn5u0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:10 INFO - Running setup.py (path:/tmp/pip-R43r_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:10 INFO - Running setup.py (path:/tmp/pip-7S9sHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:10 INFO - Running setup.py (path:/tmp/pip-0BCUPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:10 INFO - Running setup.py (path:/tmp/pip-RhjIKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:10 INFO - Running setup.py (path:/tmp/pip-PgZCXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:11 INFO - Running setup.py (path:/tmp/pip-O28dCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:11 INFO - Running setup.py (path:/tmp/pip-4uEFS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:11 INFO - Running setup.py (path:/tmp/pip-NObz1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:11 INFO - Running setup.py (path:/tmp/pip-lbAk5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:47:11 INFO - Running setup.py install for manifestparser 15:47:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for mozcrash 15:47:11 INFO - Running setup.py install for mozdebug 15:47:11 INFO - Running setup.py install for mozdevice 15:47:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for mozfile 15:47:11 INFO - Running setup.py install for mozhttpd 15:47:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for mozinfo 15:47:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Running setup.py install for mozInstall 15:47:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Running setup.py install for mozleak 15:47:12 INFO - Running setup.py install for mozlog 15:47:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Running setup.py install for moznetwork 15:47:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Running setup.py install for mozprocess 15:47:12 INFO - Running setup.py install for mozprofile 15:47:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Running setup.py install for mozrunner 15:47:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Running setup.py install for mozscreenshot 15:47:12 INFO - Running setup.py install for moztest 15:47:12 INFO - Running setup.py install for mozversion 15:47:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:47:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:47:13 INFO - Cleaning up... 15:47:13 INFO - Return code: 0 15:47:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47: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')]} 15:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:13 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 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:47:13 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 15:47:13 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 15:47:13 INFO - Using env: {'DISPLAY': ':0', 15:47:13 INFO - 'HOME': '/home/cltbld', 15:47:13 INFO - 'LANG': 'en_US.UTF-8', 15:47:13 INFO - 'LANGUAGE': 'en_US:en', 15:47:13 INFO - 'LOGNAME': 'cltbld', 15:47:13 INFO - 'MAIL': '/var/mail/cltbld', 15:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:13 INFO - 'MOZ_NO_REMOTE': '1', 15:47:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:13 INFO - 'NO_EM_RESTART': '1', 15:47:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:13 INFO - 'PWD': '/builds/slave/test', 15:47:13 INFO - 'SHELL': '/bin/bash', 15:47:13 INFO - 'SHLVL': '1', 15:47:13 INFO - 'TERM': 'linux', 15:47:13 INFO - 'TMOUT': '86400', 15:47:13 INFO - 'USER': 'cltbld', 15:47:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:13 INFO - '_': '/tools/buildbot/bin/python'} 15:47:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:13 INFO - Running setup.py (path:/tmp/pip-hBmmTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:13 INFO - Running setup.py (path:/tmp/pip-zmDgDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:13 INFO - Running setup.py (path:/tmp/pip-7Jnasa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:13 INFO - Running setup.py (path:/tmp/pip-7NGoUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:13 INFO - Running setup.py (path:/tmp/pip-hbdVyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:13 INFO - Running setup.py (path:/tmp/pip-l7TZJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:13 INFO - Running setup.py (path:/tmp/pip-fK7ECU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:13 INFO - Running setup.py (path:/tmp/pip-U0tEQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:13 INFO - Running setup.py (path:/tmp/pip-bMtA37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:13 INFO - Running setup.py (path:/tmp/pip-L0VVLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:14 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)) 15:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:14 INFO - Running setup.py (path:/tmp/pip-oNOl8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:14 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)) 15:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:14 INFO - Running setup.py (path:/tmp/pip-XWw4au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:14 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)) 15:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:14 INFO - Running setup.py (path:/tmp/pip-q7Ps4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:14 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)) 15:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:14 INFO - Running setup.py (path:/tmp/pip-oj6Ats-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:14 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)) 15:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:14 INFO - Running setup.py (path:/tmp/pip-6EJPVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:14 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)) 15:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:14 INFO - Running setup.py (path:/tmp/pip-pn6qgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:14 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)) 15:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:14 INFO - Running setup.py (path:/tmp/pip-v_SD70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:14 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)) 15:47:14 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)) 15:47:14 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)) 15:47:14 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)) 15:47:14 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)) 15:47:14 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)) 15:47:14 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)) 15:47:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:47:14 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 15:47:14 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 15:47:14 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 15:47:14 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 15:47:15 INFO - Downloading blessings-1.6.tar.gz 15:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:47:15 INFO - Installing collected packages: blessings 15:47:15 INFO - Running setup.py install for blessings 15:47:15 INFO - Successfully installed blessings 15:47:15 INFO - Cleaning up... 15:47:15 INFO - Return code: 0 15:47:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:16 INFO - Reading from file tmpfile_stdout 15:47:16 INFO - Current package versions: 15:47:16 INFO - argparse == 1.2.1 15:47:16 INFO - blessings == 1.6 15:47:16 INFO - blobuploader == 1.2.4 15:47:16 INFO - docopt == 0.6.1 15:47:16 INFO - functools32 == 3.2.3-2 15:47:16 INFO - jsonschema == 2.5.1 15:47:16 INFO - manifestparser == 1.1 15:47:16 INFO - mozInstall == 1.13 15:47:16 INFO - mozcrash == 1.0 15:47:16 INFO - mozdebug == 0.1 15:47:16 INFO - mozdevice == 0.51 15:47:16 INFO - mozfile == 1.2 15:47:16 INFO - mozhttpd == 0.7 15:47:16 INFO - mozinfo == 0.10 15:47:16 INFO - mozleak == 0.1 15:47:16 INFO - mozlog == 3.5 15:47:16 INFO - moznetwork == 0.27 15:47:16 INFO - mozprocess == 0.25 15:47:16 INFO - mozprofile == 0.28 15:47:16 INFO - mozrunner == 6.13 15:47:16 INFO - mozscreenshot == 0.1 15:47:16 INFO - mozsystemmonitor == 0.3 15:47:16 INFO - moztest == 0.8 15:47:16 INFO - mozversion == 1.4 15:47:16 INFO - psutil == 3.1.1 15:47:16 INFO - requests == 1.2.3 15:47:16 INFO - wsgiref == 0.1.2 15:47:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:16 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')]} 15:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:16 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')]} 15:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:16 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 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:47:16 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 15:47:16 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 15:47:16 INFO - Using env: {'DISPLAY': ':0', 15:47:16 INFO - 'HOME': '/home/cltbld', 15:47:16 INFO - 'LANG': 'en_US.UTF-8', 15:47:16 INFO - 'LANGUAGE': 'en_US:en', 15:47:16 INFO - 'LOGNAME': 'cltbld', 15:47:16 INFO - 'MAIL': '/var/mail/cltbld', 15:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:16 INFO - 'MOZ_NO_REMOTE': '1', 15:47:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:16 INFO - 'NO_EM_RESTART': '1', 15:47:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:16 INFO - 'PWD': '/builds/slave/test', 15:47:16 INFO - 'SHELL': '/bin/bash', 15:47:16 INFO - 'SHLVL': '1', 15:47:16 INFO - 'TERM': 'linux', 15:47:16 INFO - 'TMOUT': '86400', 15:47:16 INFO - 'USER': 'cltbld', 15:47:16 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:16 INFO - '_': '/tools/buildbot/bin/python'} 15:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:16 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)) 15:47:16 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)) 15:47:16 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)) 15:47:16 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)) 15:47:16 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)) 15:47:16 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)) 15:47:16 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)) 15:47:16 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)) 15:47:16 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)) 15:47:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:47:16 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 15:47:16 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 15:47:16 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 15:47:16 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 15:47:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:47:17 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)) 15:47:17 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)) 15:47:17 INFO - Installing collected packages: simplejson 15:47:17 INFO - Running setup.py install for simplejson 15:47:17 INFO - building 'simplejson._speedups' extension 15:47:17 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 15:47:18 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 15:47:18 INFO - Successfully installed simplejson 15:47:18 INFO - Cleaning up... 15:47:18 INFO - Return code: 0 15:47:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47: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')]} 15:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xebc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1228a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', '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 15:47:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:47:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:47:18 INFO - Using env: {'DISPLAY': ':0', 15:47:18 INFO - 'HOME': '/home/cltbld', 15:47:18 INFO - 'LANG': 'en_US.UTF-8', 15:47:18 INFO - 'LANGUAGE': 'en_US:en', 15:47:18 INFO - 'LOGNAME': 'cltbld', 15:47:18 INFO - 'MAIL': '/var/mail/cltbld', 15:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:18 INFO - 'MOZ_NO_REMOTE': '1', 15:47:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:18 INFO - 'NO_EM_RESTART': '1', 15:47:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:18 INFO - 'PWD': '/builds/slave/test', 15:47:18 INFO - 'SHELL': '/bin/bash', 15:47:18 INFO - 'SHLVL': '1', 15:47:18 INFO - 'TERM': 'linux', 15:47:18 INFO - 'TMOUT': '86400', 15:47:18 INFO - 'USER': 'cltbld', 15:47:18 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:18 INFO - '_': '/tools/buildbot/bin/python'} 15:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:47:18 INFO - Cleaning up... 15:47:18 INFO - Return code: 0 15:47:18 INFO - Running post-action listener: _resource_record_post_action 15:47:18 INFO - Running post-action listener: _start_resource_monitoring 15:47:18 INFO - Starting resource monitoring. 15:47:18 INFO - [mozharness: 2017-09-03 22:47:18.784852Z] Finished create-virtualenv step (success) 15:47:18 INFO - [mozharness: 2017-09-03 22:47:18.785826Z] Running install step. 15:47:18 INFO - Running pre-action listener: _resource_record_pre_action 15:47:18 INFO - Running main action method: install 15:47:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:18 INFO - Reading from file tmpfile_stdout 15:47:18 INFO - Detecting whether we're running mozinstall >=1.0... 15:47:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:47:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:47:19 INFO - Reading from file tmpfile_stdout 15:47:19 INFO - Output received: 15:47:19 INFO - Usage: mozinstall [options] installer 15:47:19 INFO - Options: 15:47:19 INFO - -h, --help show this help message and exit 15:47:19 INFO - -d DEST, --destination=DEST 15:47:19 INFO - Directory to install application into. [default: 15:47:19 INFO - "/builds/slave/test"] 15:47:19 INFO - --app=APP Application being installed. [default: firefox] 15:47:19 INFO - mkdir: /builds/slave/test/build/application 15:47:19 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'] 15:47:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:47:35 INFO - Reading from file tmpfile_stdout 15:47:35 INFO - Output received: 15:47:35 INFO - /builds/slave/test/build/application/firefox/firefox 15:47:35 INFO - Running post-action listener: _resource_record_post_action 15:47:35 INFO - [mozharness: 2017-09-03 22:47:35.658835Z] Finished install step (success) 15:47:35 INFO - [mozharness: 2017-09-03 22:47:35.659087Z] Running setup-mitmproxy step. 15:47:35 INFO - Running pre-action listener: _resource_record_pre_action 15:47:35 INFO - Running main action method: setup_mitmproxy 15:47:35 INFO - Skipping: mitmproxy is not required 15:47:35 INFO - Running post-action listener: _resource_record_post_action 15:47:35 INFO - [mozharness: 2017-09-03 22:47:35.660211Z] Finished setup-mitmproxy step (success) 15:47:35 INFO - [mozharness: 2017-09-03 22:47:35.660430Z] Running run-tests step. 15:47:35 INFO - Running pre-action listener: _resource_record_pre_action 15:47:35 INFO - Running pre-action listener: _set_gcov_prefix 15:47:35 INFO - Running main action method: run_tests 15:47:35 WARNING - Try message not found. 15:47:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:47:35 INFO - Python 2.7.3 15:47:35 INFO - Return code: 0 15:47:35 INFO - grabbing minidump binary from tooltool 15:47: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')]} 15:47:35 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 0x122c090>, '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 0x122cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:47:35 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 15:47:35 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 15:47:35 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 15:47:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:47:35 INFO - Return code: 0 15:47:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:47:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:47:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:47:35 INFO - ENV: RUST_BACKTRACE is now full 15:47:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:47:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:47:35 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/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 15:47:35 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/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 15:47:35 INFO - Using env: {'DISPLAY': ':0', 15:47:35 INFO - 'HOME': '/home/cltbld', 15:47:35 INFO - 'LANG': 'en_US.UTF-8', 15:47:35 INFO - 'LANGUAGE': 'en_US:en', 15:47:35 INFO - 'LOGNAME': 'cltbld', 15:47:35 INFO - 'MAIL': '/var/mail/cltbld', 15:47:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:47:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:35 INFO - 'MOZ_NO_REMOTE': '1', 15:47:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:47:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:35 INFO - 'NO_EM_RESTART': '1', 15:47:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:35 INFO - 'PWD': '/builds/slave/test', 15:47:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:47:35 INFO - 'RUST_BACKTRACE': 'full', 15:47:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:47:35 INFO - 'SHELL': '/bin/bash', 15:47:35 INFO - 'SHLVL': '1', 15:47:35 INFO - 'STYLO_FORCE_ENABLED': '1', 15:47:35 INFO - 'STYLO_THREADS': '1', 15:47:35 INFO - 'TERM': 'linux', 15:47:35 INFO - 'TMOUT': '86400', 15:47:35 INFO - 'USER': 'cltbld', 15:47:35 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:35 INFO - '_': '/tools/buildbot/bin/python'} 15:47:35 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/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 15:47:36 INFO - mozversion application_buildid: 20170903215607 15:47:36 INFO - mozversion application_changeset: dbf9f7430406ca3220529c5b4c05b26511efa3dc 15:47:36 INFO - mozversion application_display_name: Nightly 15:47:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:47:36 INFO - mozversion application_name: Firefox 15:47:36 INFO - mozversion application_remotingname: firefox 15:47:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:47:36 INFO - mozversion application_vendor: Mozilla 15:47:36 INFO - mozversion application_version: 57.0a1 15:47:36 INFO - mozversion platform_buildid: 20170903215607 15:47:36 INFO - mozversion platform_changeset: dbf9f7430406ca3220529c5b4c05b26511efa3dc 15:47:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:47:36 INFO - mozversion platform_version: 57.0a1 15:47:36 INFO - using testdate: 1504478856 15:47:36 INFO - actual date: 1504478856 15:47:36 INFO - starting webserver on 'localhost:49718' 15:47:36 INFO - SUITE-START | Running 2 tests 15:47:36 INFO - TEST-START | dromaeo_css 15:47:36 INFO - Initialising browser for dromaeo_css test... 15:47:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49718/getInfo.html -profile /tmp/tmpDGQa84/profile 15:47:36 INFO - TEST-INFO | started process 3554 (/builds/slave/test/build/application/firefox/firefox http://localhost:49718/getInfo.html) 15:47:45 INFO - TEST-INFO | 3554: exit 0 15:47:45 INFO - Browser initialized. 15:47:45 INFO - Running cycle 1/1 for dromaeo_css test... 15:47:45 INFO - Using env: {'DISPLAY': ':0', 15:47:45 INFO - 'HOME': '/home/cltbld', 15:47:45 INFO - 'JSGC_DISABLE_POISONING': '1', 15:47:45 INFO - 'LANG': 'en_US.UTF-8', 15:47:45 INFO - 'LANGUAGE': 'en_US:en', 15:47:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:47:45 INFO - 'LOGNAME': 'cltbld', 15:47:45 INFO - 'MAIL': '/var/mail/cltbld', 15:47:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:47:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:47:45 INFO - 'MOZ_CRASHREPORTER': '1', 15:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:47:45 INFO - 'MOZ_NO_REMOTE': '1', 15:47:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:45 INFO - 'NO_EM_RESTART': '1', 15:47:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:45 INFO - 'PWD': '/builds/slave/test', 15:47:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:47:45 INFO - 'RUST_BACKTRACE': 'full', 15:47:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:47:45 INFO - 'SHELL': '/bin/bash', 15:47:45 INFO - 'SHLVL': '1', 15:47:45 INFO - 'STYLO_FORCE_ENABLED': '1', 15:47:45 INFO - 'STYLO_THREADS': '1', 15:47:45 INFO - 'TERM': 'linux', 15:47:45 INFO - 'TMOUT': '86400', 15:47:45 INFO - 'USER': 'cltbld', 15:47:45 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 15:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:45 INFO - '_': '/tools/buildbot/bin/python'} 15:47:45 INFO - TEST-INFO | started process 3743 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDGQa84/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:47:46 INFO - PID 3743 | 15:47:46 INFO - PID 3743 | (/builds/slave/test/build/application/firefox/firefox:3795): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:47:46 INFO - PID 3743 | 15:47:56 INFO - PID 3743 | RSS: Main: 166748160 15:47:56 INFO - PID 3743 | 15:50:55 INFO - PID 3743 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49718/tests/dromaeo/cssquery-ext.html) 15:50:56 INFO - PID 3743 | RSS: Main: 173191168 15:50:56 INFO - PID 3743 | 15:53:58 INFO - PID 3743 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-ext.html (next: http://localhost:49718/tests/dromaeo/cssquery-jquery.html) 15:53:59 INFO - PID 3743 | RSS: Main: 169619456 15:53:59 INFO - PID 3743 | 15:57:00 INFO - PID 3743 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49718/tests/dromaeo/cssquery-mootools.html) 15:57:01 INFO - PID 3743 | RSS: Main: 169955328 15:57:01 INFO - PID 3743 | 16:00:03 INFO - PID 3743 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49718/tests/dromaeo/cssquery-prototype.html) 16:00:03 INFO - PID 3743 | RSS: Main: 169402368 16:00:03 INFO - PID 3743 | 16:03:07 INFO - PID 3743 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49718/tests/dromaeo/cssquery-yui.html) 16:03:08 INFO - PID 3743 | RSS: Main: 171646976 16:03:08 INFO - PID 3743 | 16:06:09 INFO - PID 3743 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-yui.html (next: http://localhost:49718/tests/dromaeo/cssquery-dojo.html) 16:06:10 INFO - PID 3743 | RSS: Main: 174301184 16:06:10 INFO - PID 3743 | 16:06:10 INFO - PID 3743 | __start_tp_report 16:06:10 INFO - PID 3743 | _x_x_mozilla_page_load 16:06:10 INFO - PID 3743 | _x_x_mozilla_page_load_details 16:06:10 INFO - PID 3743 | |i|pagename|runs| 16:06:10 INFO - PID 3743 | |0;dojo.html;3336.33273345331;3369.494575813629;3382.137554923495;3385.441402168642;3412.3345947540224;6359.745610175593;6468.741250349986;6517.902231466532;6518.217813862338;6607.768728094517;6394.328595497475;6504.317046710098;6515.348465153483;6573.539852210347;6676.098726671898;6071.817845464643;6076.513878889689;6098.634081955075;6116.204893363856;6133.325334213233;5989.0417533194695;6053.394660533955;6085.2393450818645;6150.876982460348;6194.535409844257;6012.338642749294;6030.788922387739;6119.938800611982;6175;6229.595076320047;5835.824925252249;5903.49820265278;5951.2858456985;6017.7592896284095;6054.909176362358;5969.671668058299;5990.73041713124;5994.640321580676;6014.3985601439945;6020.127081573148;6452.90320645186;6512.902306465407;6513.120728701612;6611.140551728293;6644.169478815148;6817.761378351734;7031.8945215821805;7100.857982840314;7116.822079448003;7128.750493732695;6966.12923384577;7034.964825175893;7103.218645948941;7147.642617869085;7173.569585824815;6509.893318135881;6560.475161987029;6588.439982601484;6609.107770450975;6617.801465955981;7246.130464344313;7252.709891604382;7269.963650181715;7341.632918354061;7354.448416368794;6752.324767523209;6857.074294970215;6897.206821215524;6986.301369863075;6991.580505169706;7083.929160708327;7103.396211321992;7116.786496405116;7130.108736407949;7150.964245178845;6760.391564759196;6836.555623884432;6849.1096157499205;6909.930900691029;7086.149662040588;6941.132358455193;6947.687354069079;6962.164540255202;6964.7562335319035;6969.58182509051;5567.3040869891265;5582.63712858671;5584.525315348241;5589.301337332833;5595.496405323459;4799.66402351832;4850.902981940341;4857.611391088704;4860.1008813369635;4878.0975519529;5982.551308651869;6126.816195514141;6156.6921653917125;6228.128062071313;6235.906461403082;7000.054992576039;7031.613261270577;7109.573425594481;7110.608916509642;7115.039469671631;6102.4507794298725;6159.969200153971;6168.8766224675255;6178.135061091451;6183.536234782409;6041.78853740117;6059.7273122709585;6143.416375444325;6147.784827531015;6155.784547540815;6794.048833163361;6834.419074378634;6883.621400823003;6910.48171387148;6928.995295682098;6879.690413931486;7058.71765129389;7079.82300442493;7160.785176444715;7198.8920166198595;6727.663616819237;6738.59568425896;6753.729850806109;6817.863642727217;6836.453083753188;7018.508704390643;7045.365917067488;7056.1179852518435;7065.752698655522;7068.540544864567;7098.787036388917;7109.146902371748;7115.53749006313;7220.277972202738;7222.530535515175;6644;6727;6814.114165158498;6888.931110688829;6896.103506544117;6761.560498567577;6849.1438570178725;6849.965750171217;6853.760118395832;6864.176298844171;6726.3609957054005;6757.831054223684;6791.286914874065;6811.727530898708;6819.283975182534;6852.417544508772;6882.8623427532175;6889.379955804002;6944;6991.860162796614;6469.741210351722;6574.934250657623;6616.073749675145;6641.402273795382;6721.462283017445 16:06:10 INFO - PID 3743 | |1;ext.html;787.1577412168981;826.6486743134167;841.6969890839299;865.3466632692318;866.1598249697798;41800.16399672008;42716.57283427165;42745.35881961765;42771.57228427715;42840.143197136036;39223.21553568934;39284.410733838944;39339.40990885139;39537;39629.60370396295;7007.859842803128;7132.0371749813885;7132.6077065761365;7133.251008644095;7152.141742990849;7010.929890701104;7082.185548661892;7094;7097.0418993436;7099.041629380045;7691.615419229044;7874.094479134916;7874.960625196866;7880.448368614199;7880.605969701521;7773.572453515055;7803.921960780376;7804.985351904273;7841.960790196041;7847.1368149503505;7543.924560754377;7721.7683469496005;7721.806954826118;7723.845523089563;7728.613569321567;37522.8123859379;37965.43051854224;38030.42954355687;38105.237895242215;38282.80858595717;3570.071781336871;3626.365386057456;3636.6363363663686;3637.6726094651344;3640.526731524911;31398.529022064686;31590.36819263602;31622.209444763834;31684.524732129037;31740;5211.348581427322;5238.738063096868;5249.501297376743;5259.789608415659;5307.097793375096;174591.12704436525;174815.12592437083;174897.1255143729;175192.12403937898;175781;46729;46943.29585056227;47016.7649161752;47253.76373118181;47389.76305118452;48980.51019489831;49234.75382623064;49268.26097608539;49387.50612493829;49528.50471495311;14208.289585520683;14464.927675361556;14472.421103155755;14515.419383224764;14521;11503.424828758529;11532.077433805276;11541.711457213636;11558.133140014532;11559.075273978062;14321;14517.128972261487;14568.854311456962;14576.562703118923;14629.487967921285;5427.348718153847;5654.321481422256;5659.858503537363;5707.68607726579;5738.626989245769;989.099919073654;994.9502524873727;1000.4897502273748;1013.7009582173276;1017.4149863828328;22946.082156713877;22964.311070667907;23057.538849223256;23075.88462057679;23105.768942310362;1108.6230681568306;1109.578360223126;1112.7496313329436;1113.365381732421;1120.551779288275;12225.633231003085;12322.938385308016;12342.197757145936;12346.506139754309;12377.566785162477;223524;225867;225973;226040;226055;52049.739751301;52168.95662086737;53849.73075134599;56242;56348.15477767837;1426.664733787559;1443.2855736410545;1444.5594093801492;1454.7817827325773;1455.825300963891;26566.734332656426;26838.194854154408;27163.456730865666;27374.315642109108;28115;50091.248631271286;50295.74852125716;50299.24551131663;50386.49613503965;50441.24338134858;198118;198290;198695;198718;198847;32944.1763955903;33050.17374565655;33135.17162070871;33228;35699.64300356963;45972.540274596824;45986;46747.29879051885;46903.29645055259;46938.530614694784;32309.35381292408;32625.347493049532;32745.34509309848;33047.66952330446;33134.502982454804;32272;32579.511307330882;32666.673333266364;32917.50623740694;33223.33553328968;37134.6286537142;37407.251854962204;37684.048926858755;37736.62263377331;37898;32249.03252902323;32659.836700816344;32722.50916236211;32804.34391312113;33314.16714582155;22840.428989275402;23778.762212378348;23859;23943.52112957766;24208.636870446604 16:06:10 INFO - PID 3743 | |2;jquery.html;568.5508448325822;569.8914018132909;570.4979617936216;580.3036356372353;584.6375247346645;22409.215677451273;22567;23235.535289294203;23349;23380.532389352204;25109;25472;25649.974001039984;25661.74338256617;25685.101021464252;6676.09872667191;6799.42204912583;6815.38661520463;6819.522633415663;6822.7953161404985;6129.724162412702;6768.492363072764;6769.52613317068;6777.288384719583;6779.0509328694025;6697.631630260334;6705.228898676666;6705.430038446738;6706.061151438803;6714.865702685944;6687.431568316699;6696.363845434676;6696.698648560827;6708.161479815023;6727.42816860565;6189.319174890758;6201.441870231656;6211.844703882394;6213.658248796314;6213.937860621426;28476.288092797844;29012.564811527845;29560.556591651144;29603.407931841244;29625.40749185004;1995.6607376745842;2037.2869495676543;2047.457423782684;2048.0374224114644;2048.8258498027685;19425.611487770308;19661;19968.500787480283;20439.182432702673;20453;2464.0267094497754;2481.3796550862285;2531.303891429853;2547.6178573214165;2547.872606369674;140382;143771;144371.27814360967;144519.27740361335;144730.55269447275;14591.00884318712;14635;14839.554813355617;15185.924070379577;15520.92239538795;23421.41446463818;23470.64794028091;23477.88261058684;23494;23649.881750591136;10354.792904142025;10506.842397364046;10572.788544229072;10578.259521833552;10587.84118238227;8421.494710317278;9774.951125244328;9805.509724513746;9824.950875245579;9937.304388692723;7942.324902383362;9073.954630226806;9216.2626989842;9237.491937943478;9327.533623318806;2660.9467810643678;2678.2902530829347;2736.4253506763803;2814.8451835148853;2877.3525956659582;954.3224310739453;966.8784210315999;980.2696990741942;983.179045497018;983.9901600983899;19598.21607135727;19864.87768584307;19867.900660496605;20221.39335819928;20432.080556375;1023.329719034019;1045.6444808765057;1049.2864851900629;1059.7986382587283;1059.9205059620406;10877.401742904216;10968.835467467994;11047.668569942914;11237.157213209042;11271.718207044889;475117;481964;482073;500699;508715;118862.40568797101;119036.40481797536;119502.402487987;122209;122533;1099.7580532282884;1192.6004788395985;1193.3913704011018;1198.2211562484456;1207.1368971185645;17549.82450175533;17589;17734;17837.910810445865;17968.101594920463;53250.46749532455;53684.92630147453;54067.18899216436;54333.185002225786;55218.44781552294;63661.6816915931;64139.35860641334;66294.66852665899;67368.32631673817;67573.66213168902;14266.714665706835;14394.424223030961;14547.127172369692;14595.562133136023;14611.707765844836;22666.43333916665;22776.430589235402;22791;23126;23133.190338338078;17808;17960.910195448938;17979.101044947438;18041.548961276072;18472.907635461735;16007.119608421182;16196.67606647884;16420.917895410446;16997;17047.48857534276;18486.907565462086;18543.165557549673;18765.812341876408;18811.341603043827;18820.811791881904;14328.283585820876;14332.211728354623;14362.712745744817;14403.711925761216;14406.351714172677;17958;18095.547611309823;18228.088595570433;18251.634967300844;18263.54341141482 16:06:10 INFO - PID 3743 | |3;mootools.html;1337.3513845784794;1381.0470775165136;1384.8130502382178;1390.2562129260848;1391.20005996552;14985.850141498595;15272.69454610907;15613.843861561381;15649.139297338641;15658.21708914554;15792.605184870385;16013.83986160138;16033.35866565336;16035.278412471438;16049.759253611175;2738.4523095380905;2777.750002499775;2785.4846853332097;2785.8189217700888;2786.7352601502826;2788.1914244869067;2882;2890.0607302626627;2893.5514995175677;2897.8695958681806;2719.8912043518235;2730.140005898146;2739.2467071555284;2756.0491630387482;2758.1036163247027;2704.9323766905786;2707.783377329809;2718.1301983365233;2719.157061311;2734.986325068382;2568.22953114065;2630.3818602628367;2648.3908700998686;2650.7879369650573;2651.68179818423;15187.772183417148;15314.081155130616;15332.693346133015;15333;15336;8050.11448740638;8265.504069755834;8323.209295117014;8354.958225208897;8358.164183581594;5295.496927791892;5357.544608708265;5485.6434331768305;5552.333719953591;5556.16657501378;1947.4449781812116;1947.7078438234237;1958.4614231086566;1982.137770070024;1995.9002049897592;26588.33529161767;26745.33136671599;27143.728562714317;27167.184984450498;27350;6776;6788.117544719254;6962.746705593042;6964.442844572523;6984.4412447004115;17267;17296.654066918592;17350.91324543369;17366.39217627402;17397.130143492774;7480.738174163987;7639.04511936008;7639.465237433326;7642.847143057108;7643.235676432423;6832.624205668637;7052.730508508415;7174.246704096099;7180.3537681608905;7197.856042879218;5093.210552364315;5189.662671926313;5268.762905669254;5278;5334;1475.513080683372;1477.4976507987121;1494.8280947691155;1498.6852760920328;1513.175319450902;904.3443503459939;938.2775263047552;943.3396622364236;948.1608776233084;952.7856232347805;7981.840363192704;8009.19908009206;8027.7993050173045;8030.718924837603;8068.354531637455;716.8422946951662;737.2222305467783;991.3556188477547;1029.3067903299032;1035.2184101003845;5448.45515448452;5523;5598.4961353477565;5621.606487545914;5789.536837053026;21196.258130965343;21741.78258217429;22069.227577034726;22498.21256256023;22571.322860314216;21886.671699924515;22061.448463788212;22109.115635374405;22115.115395384328;22255.109795608318;10968.122550195965;11626.069914406828;11737.647870563897;11793.52825886955;11819.172657914036;8506.149385061444;8573.485590864319;8619.91380086191;8634;8655.264302534355;18472.353467628567;18888.811111888706;18889.338873139375;19115.330963416214;19189.424317270503;19406.417807465798;19430.222791088763;19448.90275548613;19461.90269048646;19713.310034148737;10868.130549556176;11060.059894909024;11067.72330691739;11968.222065565711;12543.686407839512;14107.435702572193;14266.358013889192;14281;14287.714245715235;14301.785473217702;11182.32906025641;11229.775404492028;11265;11365.602203922823;11494;10972.670819875417;10988.725281868017;10989;11152.275102118336;11343.773124537298;9631.71104866855;9762.658306959222;9769.85345219808;9784.168345690696;9807;7356.742514011984;7389.371903388273;7414.961905333365;7421.406287497095;7429.777106686808;7055.941608758727;7059.623373442129;7162.823200558966;7201.351878330765;7248.637568121468 16:06:10 INFO - PID 3743 | |4;prototype.html;496.79879648742275;506.2204205523493;533.7704786941614;540.8080131553298;551.1264645536824;6451.354864513546;6463.676816159191;6477;6479.384458476443;6536.999999999988;6108;6280.654563998978;6306;6334.588251763632;6344.492440604752;4981.452040275567;5034.187692430722;5096.617753668481;5121.308623335858;5141.537261646451;5050.267711181877;5088.160453525183;5110.9744451277875;5114.411842638088;5140.306058682087;4896.265560165968;5025.522575355335;5046.974765126188;5077.746112694369;5104.157813960692;5076.543111119999;5110.361204849394;5122.410922743895;5136.229565565176;5138.741008452916;4591.219492686251;4600.838970636045;4605.907881842344;4608.907821843578;4613.792379342937;6222.937770622327;6229.483624372339;6234.475037609775;6246.25044994599;6283.96858015707;5775.942240577582;5798.159266906275;5836.649801011953;5844.795432159854;5864;6704.2625311215725;6727.3763232339425;6729.663516824139;6744.031532094083;6747.190337159524;4951.83082826991;4966.975165124188;4967.329410529568;4969;4980.800767969277;6993.755218567377;7046.859062818715;7077.292270772933;7082.327178917994;7097;5141.9742901286;5142.2286657001005;5153.768080436389;5159.7678104485385;5161.27742116104;5185.196294574347;5197.518707168439;5199.818006369759;5204.4535323791215;5216.634835561475;5783.94216057834;5792.82621521355;5805.7387417566315;5825.126231065289;5836.795712150054;6663.333666633394;6712.160979877515;6717.227518835299;6719.832004199934;6767.255601883789;1689.4509284482592;1766.7703198584102;1773.1931970953185;1774.1484087638007;1776.1296964487308;1156.8669602995594;1178.452019810792;1224.7121926347302;1245.993770031144;1247.482294847628;5879.617824841372;5896.7641294348605;5933.940660593339;5955.76176952917;5967.492763115184;6755.088063111417;6759.662016899136;6806.795796126025;6807.863842723217;6870.072540207108;5895;5898.675572843449;5964;5964.7315870785915;5988.383136553106;3588.964110358915;3624.800635965047;3629.927401451956;3643.289558536113;3646.936573278459;6498.837529061717;6557.082008518812;6620.404163625297;6642.933570664328;6659.633720145439;6890.758823441155;6979.89530157048;7017.228104908456;7051.506394552434;7066;6490.610563366213;6517.641529715911;6523.151990241238;6613.404793568602;6627.271000189975;6675;6710.295418981132;6758.789574951858;6772.52592318533;6777.152855893013;6749.49378796602;6766.4625765338;6777.762778302933;6781.966090169518;6848.417884479875;6554.868902621825;6567.671616419256;6609.900851487328;6611.867762644816;6641.468682505485;5072.213806859942;5073.985202959349;5077.543021128116;5099.6685215460875;5117.667351622282;6777.390034896883;6800.809858274683;6864.622445765531;6879.105716256855;6934.618595977269;6593.142891424084;6686.598804071771;6692.464602831665;6707.966460167668;6708.463322934056;6594.934050659432;6600.141981542369;6608.7356505739235;6613.2394774601535;6619.569727967667;6002.339742628395;6449.647097608758;6534.105109518377;6573.737050518117;6603.306652801387;6555.541112122106;6566.967165164335;6613.107230523818;6626.204855417381;6757.358050985148;6435.549511534147;6438.356164383524;6444.677766111582;6451.70967306463;6464.515161362822 16:06:10 INFO - PID 3743 | |5;yui.html;910.9817803643928;919.6827094652342;927.7680579855037;932.5896605493584;932.7434955387267;17277.65444691107;18075.186616602252;18081.367152149676;18111.184996675147;20624.381268561967;19148.42554723357;19192.424227273204;19318.22727090918;19319;19360.4191874244;979.2655508368706;1014.031599822168;1017.2370721958531;1018.9184865210802;1022.1311884897815;973.6154219083458;1018.7504061504942;1021.2289721260476;1021.3004092196832;1025.6410256410234;1062.904338609525;1071.9946400267988;1072.855164552787;1072.8766191887955;1073.1629329123296;1066.163061996333;1067.3382502848206;1070.411273799415;1070.84472751451;1071.201954543862;783.4085265624474;783.8510682970275;784.6547519091581;785.4501848705871;786;15758.41997910547;15999.28003239857;16132.919335403365;16237.350505979746;17503.47489575302;21551.892240538855;21717;21840.126394944342;22164.22425215126;22295.331140065824;16741.07924064176;16946.745798813026;16998.66002679952;17130.48608541746;17171.227294771765;3749.4000959846567;3781.149241420686;3784.375578029622;3784.9621503784883;3786.1291902862495;131027.34486327603;132976.3351183238;133130;134216.32891835575;135461.3226933869;16228.350865965229;16423.6715265694;16552.172391380383;16688;16741.330346786235;28713.99501017496;29282.560761588593;29283.560746588817;31207.375852482823;31211.375772484877;7881.369490440751;8197.631106600218;9201.125893040151;9360.15758581731;9372.671956481627;4382.846600368972;4529.9547004530195;4538.795754191004;4564.589186973189;4572.0627271409285;5019;5113.258577506241;5128.487151284841;5133.383993920754;5145.099607568661;1008.621766837436;1020.4285600063839;1031.9948400257952;1034.0280136671654;1037.3464717228248;642.1748053750907;644.7646608987686;647.6437959122463;654.6268626882727;665.2781731821028;11171;11190.048845848194;11570.305781653127;12225.777422257881;12341.197822141532;586.3110844757393;607.4836389069255;638.2596188421409;652.8237375908478;665.1685393258427;5279.80255696722;5534.363548192009;5539.307586551681;5841.7371218295275;5853.268341457318;105766;106561.46719266353;107100;107699.46150269355;110734;91029;93032.53483732538;96005;98370;101910.49044754729;85990.57004714936;87947;90016.54991725;90958.54520727486;91414;11914.285142891107;11918.52325906954;12171;12246.571370002008;12369.505219791468;40444.797776010935;41303.58696413117;41888.3716744243;43145.7842710797;43320.56679433165;72875;73553;73818;74687.62656186902;76814.61592692001;17469.912650436665;17489.387871424948;17509.562260943578;17539;17700.291988320838;19669;20095.799042009392;20541.486462838548;20807.063682134038;20908.16367345289;16716.498505044867;16962.151892405578;17050.317987280865;17173.74239386435;17655.117244138;16606.916965415578;16821.07484088387;16871.493855184362;16923.323067077672;17242;16864.578385539968;17053.403130890358;17866.196021178817;17959.640807184045;18612;12262.264264143823;12541.561045363369;12758.532768731344;12799.872001279868;13191;12310.630681079581;12506.937465312616;12697.873021269668;12771.93614031924;12895.871041289467 16:06:10 INFO - PID 3743 | __end_tp_report 16:06:10 INFO - PID 3743 | __start_cc_report 16:06:10 INFO - PID 3743 | _x_x_mozilla_cycle_collect,383 16:06:10 INFO - PID 3743 | __end_cc_report 16:06:10 INFO - PID 3743 | __startTimestamp1504479970001__endTimestamp 16:06:10 INFO - PID 3743 | 16:06:10 INFO - PID 3743 | ------- Summary: start ------- 16:06:10 INFO - PID 3743 | Number of tests: 6 16:06:10 INFO - PID 3743 | 16:06:10 INFO - PID 3743 | [#0] dojo.html Cycles:165 Average:6479.90 Median:6727.00 stddev:762.97 (11.3%) stddev-sans-first:724.36 16:06:10 INFO - PID 3743 | Values: 3336.3 3369.5 3382.1 3385.4 3412.3 6359.7 6468.7 6517.9 6518.2 6607.8 6394.3 6504.3 6515.3 6573.5 6676.1 6071.8 6076.5 6098.6 6116.2 6133.3 5989.0 6053.4 6085.2 6150.9 6194.5 6012.3 6030.8 6119.9 6175.0 6229.6 5835.8 5903.5 5951.3 6017.8 6054.9 5969.7 5990.7 5994.6 6014.4 6020.1 6452.9 6512.9 6513.1 6611.1 6644.2 6817.8 7031.9 7100.9 7116.8 7128.8 6966.1 7035.0 7103.2 7147.6 7173.6 6509.9 6560.5 6588.4 6609.1 6617.8 7246.1 7252.7 7270.0 7341.6 7354.4 6752.3 6857.1 6897.2 6986.3 6991.6 7083.9 7103.4 7116.8 7130.1 7151.0 6760.4 6836.6 6849.1 6909.9 7086.1 6941.1 6947.7 6962.2 6964.8 6969.6 5567.3 5582.6 5584.5 5589.3 5595.5 4799.7 4850.9 4857.6 4860.1 4878.1 5982.6 6126.8 6156.7 6228.1 6235.9 7000.1 7031.6 7109.6 7110.6 7115.0 6102.5 6160.0 6168.9 6178.1 6183.5 6041.8 6059.7 6143.4 6147.8 6155.8 6794.0 6834.4 6883.6 6910.5 6929.0 6879.7 7058.7 7079.8 7160.8 7198.9 6727.7 6738.6 6753.7 6817.9 6836.5 7018.5 7045.4 7056.1 7065.8 7068.5 7098.8 7109.1 7115.5 7220.3 7222.5 6644.0 6727.0 6814.1 6888.9 6896.1 6761.6 6849.1 6850.0 6853.8 6864.2 6726.4 6757.8 6791.3 6811.7 6819.3 6852.4 6882.9 6889.4 6944.0 6991.9 6469.7 6574.9 6616.1 6641.4 6721.5 16:06:10 INFO - PID 3743 | 16:06:10 INFO - PID 3743 | [#1] ext.html Cycles:180 Average:37516.83 Median:3637.15 stddev:52046.11 (1431.0%) stddev-sans-first:52119.04 16:06:10 INFO - PID 3743 | Values: 787.2 826.6 841.7 865.3 866.2 41800.2 42716.6 42745.4 42771.6 42840.1 39223.2 39284.4 39339.4 39537.0 39629.6 7007.9 7132.0 7132.6 7133.3 7152.1 7010.9 7082.2 7094.0 7097.0 7099.0 7691.6 7874.1 7875.0 7880.4 7880.6 7773.6 7803.9 7805.0 7842.0 7847.1 7543.9 7721.8 7721.8 7723.8 7728.6 37522.8 37965.4 38030.4 38105.2 38282.8 3570.1 3626.4 3636.6 3637.7 3640.5 31398.5 31590.4 31622.2 31684.5 31740.0 5211.3 5238.7 5249.5 5259.8 5307.1 174591.1 174815.1 174897.1 175192.1 175781.0 46729.0 46943.3 47016.8 47253.8 47389.8 48980.5 49234.8 49268.3 49387.5 49528.5 14208.3 14464.9 14472.4 14515.4 14521.0 11503.4 11532.1 11541.7 11558.1 11559.1 14321.0 14517.1 14568.9 14576.6 14629.5 5427.3 5654.3 5659.9 5707.7 5738.6 989.1 995.0 1000.5 1013.7 1017.4 22946.1 22964.3 23057.5 23075.9 23105.8 1108.6 1109.6 1112.7 1113.4 1120.6 12225.6 12322.9 12342.2 12346.5 12377.6 223524.0 225867.0 225973.0 226040.0 226055.0 52049.7 52169.0 53849.7 56242.0 56348.2 1426.7 1443.3 1444.6 1454.8 1455.8 26566.7 26838.2 27163.5 27374.3 28115.0 50091.2 50295.7 50299.2 50386.5 50441.2 198118.0 198290.0 198695.0 198718.0 198847.0 32944.2 33050.2 33135.2 33228.0 35699.6 45972.5 45986.0 46747.3 46903.3 46938.5 32309.4 32625.3 32745.3 33047.7 33134.5 32272.0 32579.5 32666.7 32917.5 33223.3 37134.6 37407.3 37684.0 37736.6 37898.0 32249.0 32659.8 32722.5 32804.3 33314.2 22840.4 23778.8 23859.0 23943.5 24208.6 16:06:10 INFO - PID 3743 | 16:06:10 INFO - PID 3743 | [#2] jquery.html Cycles:180 Average:34860.31 Median:23129.60 stddev:82793.86 (358.0%) stddev-sans-first:82986.08 16:06:10 INFO - PID 3743 | Values: 568.6 569.9 570.5 580.3 584.6 22409.2 22567.0 23235.5 23349.0 23380.5 25109.0 25472.0 25650.0 25661.7 25685.1 6676.1 6799.4 6815.4 6819.5 6822.8 6129.7 6768.5 6769.5 6777.3 6779.1 6697.6 6705.2 6705.4 6706.1 6714.9 6687.4 6696.4 6696.7 6708.2 6727.4 6189.3 6201.4 6211.8 6213.7 6213.9 28476.3 29012.6 29560.6 29603.4 29625.4 1995.7 2037.3 2047.5 2048.0 2048.8 19425.6 19661.0 19968.5 20439.2 20453.0 2464.0 2481.4 2531.3 2547.6 2547.9 140382.0 143771.0 144371.3 144519.3 144730.6 14591.0 14635.0 14839.6 15185.9 15520.9 23421.4 23470.6 23477.9 23494.0 23649.9 10354.8 10506.8 10572.8 10578.3 10587.8 8421.5 9775.0 9805.5 9825.0 9937.3 7942.3 9074.0 9216.3 9237.5 9327.5 2660.9 2678.3 2736.4 2814.8 2877.4 954.3 966.9 980.3 983.2 984.0 19598.2 19864.9 19867.9 20221.4 20432.1 1023.3 1045.6 1049.3 1059.8 1059.9 10877.4 10968.8 11047.7 11237.2 11271.7 475117.0 481964.0 482073.0 500699.0 508715.0 118862.4 119036.4 119502.4 122209.0 122533.0 1099.8 1192.6 1193.4 1198.2 1207.1 17549.8 17589.0 17734.0 17837.9 17968.1 53250.5 53684.9 54067.2 54333.2 55218.4 63661.7 64139.4 66294.7 67368.3 67573.7 14266.7 14394.4 14547.1 14595.6 14611.7 22666.4 22776.4 22791.0 23126.0 23133.2 17808.0 17960.9 17979.1 18041.5 18472.9 16007.1 16196.7 16420.9 16997.0 17047.5 18486.9 18543.2 18765.8 18811.3 18820.8 14328.3 14332.2 14362.7 14403.7 14406.4 17958.0 18095.5 18228.1 18251.6 18263.5 16:06:10 INFO - PID 3743 | 16:06:10 INFO - PID 3743 | [#3] mootools.html Cycles:180 Average:9465.68 Median:14925.76 stddev:6814.75 (45.7%) stddev-sans-first:6806.50 16:06:10 INFO - PID 3743 | Values: 1337.4 1381.0 1384.8 1390.3 1391.2 14985.9 15272.7 15613.8 15649.1 15658.2 15792.6 16013.8 16033.4 16035.3 16049.8 2738.5 2777.8 2785.5 2785.8 2786.7 2788.2 2882.0 2890.1 2893.6 2897.9 2719.9 2730.1 2739.2 2756.0 2758.1 2704.9 2707.8 2718.1 2719.2 2735.0 2568.2 2630.4 2648.4 2650.8 2651.7 15187.8 15314.1 15332.7 15333.0 15336.0 8050.1 8265.5 8323.2 8355.0 8358.2 5295.5 5357.5 5485.6 5552.3 5556.2 1947.4 1947.7 1958.5 1982.1 1995.9 26588.3 26745.3 27143.7 27167.2 27350.0 6776.0 6788.1 6962.7 6964.4 6984.4 17267.0 17296.7 17350.9 17366.4 17397.1 7480.7 7639.0 7639.5 7642.8 7643.2 6832.6 7052.7 7174.2 7180.4 7197.9 5093.2 5189.7 5268.8 5278.0 5334.0 1475.5 1477.5 1494.8 1498.7 1513.2 904.3 938.3 943.3 948.2 952.8 7981.8 8009.2 8027.8 8030.7 8068.4 716.8 737.2 991.4 1029.3 1035.2 5448.5 5523.0 5598.5 5621.6 5789.5 21196.3 21741.8 22069.2 22498.2 22571.3 21886.7 22061.4 22109.1 22115.1 22255.1 10968.1 11626.1 11737.6 11793.5 11819.2 8506.1 8573.5 8619.9 8634.0 8655.3 18472.4 18888.8 18889.3 19115.3 19189.4 19406.4 19430.2 19448.9 19461.9 19713.3 10868.1 11060.1 11067.7 11968.2 12543.7 14107.4 14266.4 14281.0 14287.7 14301.8 11182.3 11229.8 11265.0 11365.6 11494.0 10972.7 10988.7 10989.0 11152.3 11343.8 9631.7 9762.7 9769.9 9784.2 9807.0 7356.7 7389.4 7415.0 7421.4 7429.8 7055.9 7059.6 7162.8 7201.4 7248.6 16:06:10 INFO - PID 3743 | 16:06:10 INFO - PID 3743 | [#4] prototype.html Cycles:180 Average:5615.92 Median:6263.45 stddev:1574.02 (25.1%) stddev-sans-first:1530.82 16:06:10 INFO - PID 3743 | Values: 496.8 506.2 533.8 540.8 551.1 6451.4 6463.7 6477.0 6479.4 6537.0 6108.0 6280.7 6306.0 6334.6 6344.5 4981.5 5034.2 5096.6 5121.3 5141.5 5050.3 5088.2 5111.0 5114.4 5140.3 4896.3 5025.5 5047.0 5077.7 5104.2 5076.5 5110.4 5122.4 5136.2 5138.7 4591.2 4600.8 4605.9 4608.9 4613.8 6222.9 6229.5 6234.5 6246.3 6284.0 5775.9 5798.2 5836.6 5844.8 5864.0 6704.3 6727.4 6729.7 6744.0 6747.2 4951.8 4967.0 4967.3 4969.0 4980.8 6993.8 7046.9 7077.3 7082.3 7097.0 5142.0 5142.2 5153.8 5159.8 5161.3 5185.2 5197.5 5199.8 5204.5 5216.6 5783.9 5792.8 5805.7 5825.1 5836.8 6663.3 6712.2 6717.2 6719.8 6767.3 1689.5 1766.8 1773.2 1774.1 1776.1 1156.9 1178.5 1224.7 1246.0 1247.5 5879.6 5896.8 5933.9 5955.8 5967.5 6755.1 6759.7 6806.8 6807.9 6870.1 5895.0 5898.7 5964.0 5964.7 5988.4 3589.0 3624.8 3629.9 3643.3 3646.9 6498.8 6557.1 6620.4 6642.9 6659.6 6890.8 6979.9 7017.2 7051.5 7066.0 6490.6 6517.6 6523.2 6613.4 6627.3 6675.0 6710.3 6758.8 6772.5 6777.2 6749.5 6766.5 6777.8 6782.0 6848.4 6554.9 6567.7 6609.9 6611.9 6641.5 5072.2 5074.0 5077.5 5099.7 5117.7 6777.4 6800.8 6864.6 6879.1 6934.6 6593.1 6686.6 6692.5 6708.0 6708.5 6594.9 6600.1 6608.7 6613.2 6619.6 6002.3 6449.6 6534.1 6573.7 6603.3 6555.5 6567.0 6613.1 6626.2 6757.4 6435.5 6438.4 6444.7 6451.7 6464.5 16:06:10 INFO - PID 3743 | 16:06:10 INFO - PID 3743 | [#5] yui.html Cycles:180 Average:23800.89 Median:18096.28 stddev:32921.10 (181.9%) stddev-sans-first:32968.59 16:06:10 INFO - PID 3743 | Values: 911.0 919.7 927.8 932.6 932.7 17277.7 18075.2 18081.4 18111.2 20624.4 19148.4 19192.4 19318.2 19319.0 19360.4 979.3 1014.0 1017.2 1018.9 1022.1 973.6 1018.8 1021.2 1021.3 1025.6 1062.9 1072.0 1072.9 1072.9 1073.2 1066.2 1067.3 1070.4 1070.8 1071.2 783.4 783.9 784.7 785.5 786.0 15758.4 15999.3 16132.9 16237.4 17503.5 21551.9 21717.0 21840.1 22164.2 22295.3 16741.1 16946.7 16998.7 17130.5 17171.2 3749.4 3781.1 3784.4 3785.0 3786.1 131027.3 132976.3 133130.0 134216.3 135461.3 16228.4 16423.7 16552.2 16688.0 16741.3 28714.0 29282.6 29283.6 31207.4 31211.4 7881.4 8197.6 9201.1 9360.2 9372.7 4382.8 4530.0 4538.8 4564.6 4572.1 5019.0 5113.3 5128.5 5133.4 5145.1 1008.6 1020.4 1032.0 1034.0 1037.3 642.2 644.8 647.6 654.6 665.3 11171.0 11190.0 11570.3 12225.8 12341.2 586.3 607.5 638.3 652.8 665.2 5279.8 5534.4 5539.3 5841.7 5853.3 105766.0 106561.5 107100.0 107699.5 110734.0 91029.0 93032.5 96005.0 98370.0 101910.5 85990.6 87947.0 90016.5 90958.5 91414.0 11914.3 11918.5 12171.0 12246.6 12369.5 40444.8 41303.6 41888.4 43145.8 43320.6 72875.0 73553.0 73818.0 74687.6 76814.6 17469.9 17489.4 17509.6 17539.0 17700.3 19669.0 20095.8 20541.5 20807.1 20908.2 16716.5 16962.2 17050.3 17173.7 17655.1 16606.9 16821.1 16871.5 16923.3 17242.0 16864.6 17053.4 17866.2 17959.6 18612.0 12262.3 12541.6 12758.5 12799.9 13191.0 12310.6 12506.9 12697.9 12771.9 12895.9 16:06:10 INFO - PID 3743 | -------- Summary: end -------- 16:06:10 INFO - PID 3743 | 16:06:10 INFO - TEST-INFO | 3743: exit 0 16:06:10 INFO - TEST-OK | dromaeo_css | took 1114666ms 16:06:10 INFO - TEST-START | kraken 16:06:10 INFO - Initialising browser for kraken test... 16:06:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49718/getInfo.html -profile /tmp/tmpnwAO1_/profile 16:06:10 INFO - TEST-INFO | started process 4108 (/builds/slave/test/build/application/firefox/firefox http://localhost:49718/getInfo.html) 16:06:16 INFO - TEST-INFO | 4108: exit 0 16:06:17 INFO - Browser initialized. 16:06:17 INFO - Running cycle 1/1 for kraken test... 16:06:17 INFO - Using env: {'DISPLAY': ':0', 16:06:17 INFO - 'HOME': '/home/cltbld', 16:06:17 INFO - 'JSGC_DISABLE_POISONING': '1', 16:06:17 INFO - 'LANG': 'en_US.UTF-8', 16:06:17 INFO - 'LANGUAGE': 'en_US:en', 16:06:17 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:06:17 INFO - 'LOGNAME': 'cltbld', 16:06:17 INFO - 'MAIL': '/var/mail/cltbld', 16:06:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:06:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:06:17 INFO - 'MOZ_CRASHREPORTER': '1', 16:06:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:17 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:06:17 INFO - 'MOZ_NO_REMOTE': '1', 16:06:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:06:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:17 INFO - 'NO_EM_RESTART': '1', 16:06:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:17 INFO - 'PWD': '/builds/slave/test', 16:06:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:06:17 INFO - 'RUST_BACKTRACE': 'full', 16:06:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:06:17 INFO - 'SHELL': '/bin/bash', 16:06:17 INFO - 'SHLVL': '1', 16:06:17 INFO - 'STYLO_FORCE_ENABLED': '1', 16:06:17 INFO - 'STYLO_THREADS': '1', 16:06:17 INFO - 'TERM': 'linux', 16:06:17 INFO - 'TMOUT': '86400', 16:06:17 INFO - 'USER': 'cltbld', 16:06:17 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001', 16:06:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:17 INFO - '_': '/tools/buildbot/bin/python'} 16:06:17 INFO - TEST-INFO | started process 4296 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnwAO1_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:06:18 INFO - PID 4296 | 16:06:18 INFO - PID 4296 | (/builds/slave/test/build/application/firefox/firefox:4349): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:06:18 INFO - PID 4296 | 16:06:28 INFO - PID 4296 | RSS: Main: 170594304 16:06:28 INFO - PID 4296 | 16:06:31 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49718/tests/kraken/driver.html?testName=audio-beat-detection) 16:06:32 INFO - PID 4296 | RSS: Main: 176295936 16:06:32 INFO - PID 4296 | 16:06:34 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49718/tests/kraken/driver.html?testName=audio-dft) 16:06:35 INFO - PID 4296 | RSS: Main: 180428800 16:06:35 INFO - PID 4296 | 16:06:38 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49718/tests/kraken/driver.html?testName=audio-fft) 16:06:38 INFO - PID 4296 | RSS: Main: 173604864 16:06:38 INFO - PID 4296 | 16:06:40 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49718/tests/kraken/driver.html?testName=audio-oscillator) 16:06:41 INFO - PID 4296 | RSS: Main: 173428736 16:06:41 INFO - PID 4296 | 16:06:43 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49718/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:06:44 INFO - PID 4296 | RSS: Main: 173871104 16:06:44 INFO - PID 4296 | 16:06:47 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49718/tests/kraken/driver.html?testName=imaging-darkroom) 16:06:48 INFO - PID 4296 | RSS: Main: 174759936 16:06:48 INFO - PID 4296 | 16:06:51 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49718/tests/kraken/driver.html?testName=imaging-desaturate) 16:06:52 INFO - PID 4296 | RSS: Main: 174841856 16:06:52 INFO - PID 4296 | 16:06:55 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49718/tests/kraken/driver.html?testName=json-parse-financial) 16:06:55 INFO - PID 4296 | RSS: Main: 175206400 16:06:55 INFO - PID 4296 | 16:06:57 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49718/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:06:57 INFO - PID 4296 | RSS: Main: 175788032 16:06:57 INFO - PID 4296 | 16:06:59 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:07:00 INFO - PID 4296 | RSS: Main: 174931968 16:07:00 INFO - PID 4296 | 16:07:02 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:07:02 INFO - PID 4296 | RSS: Main: 171855872 16:07:02 INFO - PID 4296 | 16:07:05 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:07:06 INFO - PID 4296 | RSS: Main: 172322816 16:07:06 INFO - PID 4296 | 16:07:09 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:07:09 INFO - PID 4296 | RSS: Main: 172503040 16:07:09 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49718/tests/kraken/driver.html?testName=ai-astar) 16:07:11 INFO - PID 4296 | RSS: Main: 174010368 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | __start_tp_report 16:07:11 INFO - PID 4296 | _x_x_mozilla_page_load 16:07:11 INFO - PID 4296 | _x_x_mozilla_page_load_details 16:07:11 INFO - PID 4296 | |i|pagename|runs| 16:07:11 INFO - PID 4296 | |0;ai-astar;99;109;100;108;122;109;108;98;109;108 16:07:11 INFO - PID 4296 | |1;audio-beat-detection;144;115;137;163;118;144;142;117;182;116 16:07:11 INFO - PID 4296 | |2;audio-dft;161;163;165;164;161;162;165;167;159;161 16:07:11 INFO - PID 4296 | |3;audio-fft;74;74;73;158;76;73;73;86;97;74 16:07:11 INFO - PID 4296 | |4;audio-oscillator;80;79;129;79;78;78;123;80;82;131 16:07:11 INFO - PID 4296 | |5;imaging-gaussian-blur;113;114;114;114;113;113;113;114;113;113 16:07:11 INFO - PID 4296 | |6;imaging-darkroom;146;149;150;152;147;148;148;148;148;148 16:07:11 INFO - PID 4296 | |7;imaging-desaturate;93;94;93;94;94;94;95;93;94;93 16:07:11 INFO - PID 4296 | |8;json-parse-financial;53;53;52;54;54;53;54;52;54;53 16:07:11 INFO - PID 4296 | |9;json-stringify-tinderbox;38;37;38;38;37;38;38;38;38;38 16:07:11 INFO - PID 4296 | |10;stanford-crypto-aes;75;79;73;74;73;74;74;73;75;76 16:07:11 INFO - PID 4296 | |11;stanford-crypto-ccm;117;122;117;124;118;119;128;126;117;157 16:07:11 INFO - PID 4296 | |12;stanford-crypto-pbkdf2;178;169;169;175;176;165;169;172;167;172 16:07:11 INFO - PID 4296 | |13;stanford-crypto-sha256-iterative;63;63;67;68;71;63;62;64;64;67 16:07:11 INFO - PID 4296 | __end_tp_report 16:07:11 INFO - PID 4296 | __start_cc_report 16:07:11 INFO - PID 4296 | _x_x_mozilla_cycle_collect,928 16:07:11 INFO - PID 4296 | __end_cc_report 16:07:11 INFO - PID 4296 | __startTimestamp1504480031919__endTimestamp 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | ------- Summary: start ------- 16:07:11 INFO - PID 4296 | Number of tests: 14 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#0] ai-astar Cycles:10 Average:107.00 Median:109.00 stddev:6.94 (6.4%) stddev-sans-first:6.74 16:07:11 INFO - PID 4296 | Values: 99.0 109.0 100.0 108.0 122.0 109.0 108.0 98.0 109.0 108.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:143.00 stddev:22.37 (15.6%) stddev-sans-first:23.61 16:07:11 INFO - PID 4296 | Values: 144.0 115.0 137.0 163.0 118.0 144.0 142.0 117.0 182.0 116.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.50 stddev:2.44 (1.5%) stddev-sans-first:2.50 16:07:11 INFO - PID 4296 | Values: 161.0 163.0 165.0 164.0 161.0 162.0 165.0 167.0 159.0 161.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#3] audio-fft Cycles:10 Average:85.80 Median:74.00 stddev:26.55 (35.9%) stddev-sans-first:27.81 16:07:11 INFO - PID 4296 | Values: 74.0 74.0 73.0 158.0 76.0 73.0 73.0 86.0 97.0 74.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:79.00 stddev:23.41 (29.6%) stddev-sans-first:24.29 16:07:11 INFO - PID 4296 | Values: 80.0 79.0 129.0 79.0 78.0 78.0 123.0 80.0 82.0 131.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:113.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:07:11 INFO - PID 4296 | Values: 113.0 114.0 114.0 114.0 113.0 113.0 113.0 114.0 113.0 113.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.00 stddev:1.65 (1.1%) stddev-sans-first:1.50 16:07:11 INFO - PID 4296 | Values: 146.0 149.0 150.0 152.0 147.0 148.0 148.0 148.0 148.0 148.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 16:07:11 INFO - PID 4296 | Values: 93.0 94.0 93.0 94.0 94.0 94.0 95.0 93.0 94.0 93.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.50 stddev:0.79 (1.5%) stddev-sans-first:0.83 16:07:11 INFO - PID 4296 | Values: 53.0 53.0 52.0 54.0 54.0 53.0 54.0 52.0 54.0 53.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 16:07:11 INFO - PID 4296 | Values: 38.0 37.0 38.0 38.0 37.0 38.0 38.0 38.0 38.0 38.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.50 stddev:1.84 (2.5%) stddev-sans-first:1.94 16:07:11 INFO - PID 4296 | Values: 75.0 79.0 73.0 74.0 73.0 74.0 74.0 73.0 75.0 76.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#11] stanford-crypto-ccm Cycles:10 Average:124.50 Median:123.00 stddev:12.10 (9.8%) stddev-sans-first:12.53 16:07:11 INFO - PID 4296 | Values: 117.0 122.0 117.0 124.0 118.0 119.0 128.0 126.0 117.0 157.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:172.00 stddev:4.16 (2.4%) stddev-sans-first:3.61 16:07:11 INFO - PID 4296 | Values: 178.0 169.0 169.0 175.0 176.0 165.0 169.0 172.0 167.0 172.0 16:07:11 INFO - PID 4296 | 16:07:11 INFO - PID 4296 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:2.90 (4.4%) stddev-sans-first:2.96 16:07:11 INFO - PID 4296 | Values: 63.0 63.0 67.0 68.0 71.0 63.0 62.0 64.0 64.0 67.0 16:07:11 INFO - PID 4296 | -------- Summary: end -------- 16:07:11 INFO - PID 4296 | 16:07:12 INFO - PID 4296 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:07:12 INFO - TEST-INFO | 4296: exit 0 16:07:12 INFO - TEST-OK | kraken | took 61845ms 16:07:12 INFO - SUITE-END | took 1176s 16:07:13 INFO - Completed test suite (00:19:36) 16:07:13 INFO - javascript benchmark 16:07:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8646.314781674662, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.33273345331, 3369.494575813629, 3382.137554923495, 3385.441402168642, 3412.3345947540224, 6359.745610175593, 6468.741250349986, 6517.902231466532, 6518.217813862338, 6607.768728094517, 6394.328595497475, 6504.317046710098, 6515.348465153483, 6573.539852210347, 6676.098726671898, 6071.817845464643, 6076.513878889689, 6098.634081955075, 6116.204893363856, 6133.325334213233, 5989.0417533194695, 6053.394660533955, 6085.2393450818645, 6150.876982460348, 6194.535409844257, 6012.338642749294, 6030.788922387739, 6119.938800611982, 6175.0, 6229.595076320047, 5835.824925252249, 5903.49820265278, 5951.2858456985, 6017.7592896284095, 6054.909176362358, 5969.671668058299, 5990.73041713124, 5994.640321580676, 6014.3985601439945, 6020.127081573148, 6452.90320645186, 6512.902306465407, 6513.120728701612, 6611.140551728293, 6644.169478815148, 6817.761378351734, 7031.8945215821805, 7100.857982840314, 7116.822079448003, 7128.750493732695, 6966.12923384577, 7034.964825175893, 7103.218645948941, 7147.642617869085, 7173.569585824815, 6509.893318135881, 6560.475161987029, 6588.439982601484, 6609.107770450975, 6617.801465955981, 7246.130464344313, 7252.709891604382, 7269.963650181715, 7341.632918354061, 7354.448416368794, 6752.324767523209, 6857.074294970215, 6897.206821215524, 6986.301369863075, 6991.580505169706, 7083.929160708327, 7103.396211321992, 7116.786496405116, 7130.108736407949, 7150.964245178845, 6760.391564759196, 6836.555623884432, 6849.1096157499205, 6909.930900691029, 7086.149662040588, 6941.132358455193, 6947.687354069079, 6962.164540255202, 6964.7562335319035, 6969.58182509051, 5567.3040869891265, 5582.63712858671, 5584.525315348241, 5589.301337332833, 5595.496405323459, 4799.66402351832, 4850.902981940341, 4857.611391088704, 4860.1008813369635, 4878.0975519529, 5982.551308651869, 6126.816195514141, 6156.6921653917125, 6228.128062071313, 6235.906461403082, 7000.054992576039, 7031.613261270577, 7109.573425594481, 7110.608916509642, 7115.039469671631, 6102.4507794298725, 6159.969200153971, 6168.8766224675255, 6178.135061091451, 6183.536234782409, 6041.78853740117, 6059.7273122709585, 6143.416375444325, 6147.784827531015, 6155.784547540815, 6794.048833163361, 6834.419074378634, 6883.621400823003, 6910.48171387148, 6928.995295682098, 6879.690413931486, 7058.71765129389, 7079.82300442493, 7160.785176444715, 7198.8920166198595, 6727.663616819237, 6738.59568425896, 6753.729850806109, 6817.863642727217, 6836.453083753188, 7018.508704390643, 7045.365917067488, 7056.1179852518435, 7065.752698655522, 7068.540544864567, 7098.787036388917, 7109.146902371748, 7115.53749006313, 7220.277972202738, 7222.530535515175, 6644.0, 6727.0, 6814.114165158498, 6888.931110688829, 6896.103506544117, 6761.560498567577, 6849.1438570178725, 6849.965750171217, 6853.760118395832, 6864.176298844171, 6726.3609957054005, 6757.831054223684, 6791.286914874065, 6811.727530898708, 6819.283975182534, 6852.417544508772, 6882.8623427532175, 6889.379955804002, 6944.0, 6991.860162796614, 6469.741210351722, 6574.934250657623, 6616.073749675145, 6641.402273795382, 6721.462283017445], "value": 6423.378401443507, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.1577412168981, 826.6486743134167, 841.6969890839299, 865.3466632692318, 866.1598249697798, 41800.16399672008, 42716.57283427165, 42745.35881961765, 42771.57228427715, 42840.143197136036, 39223.21553568934, 39284.410733838944, 39339.40990885139, 39537.0, 39629.60370396295, 7007.859842803128, 7132.0371749813885, 7132.6077065761365, 7133.251008644095, 7152.141742990849, 7010.929890701104, 7082.185548661892, 7094.0, 7097.0418993436, 7099.041629380045, 7691.615419229044, 7874.094479134916, 7874.960625196866, 7880.448368614199, 7880.605969701521, 7773.572453515055, 7803.921960780376, 7804.985351904273, 7841.960790196041, 7847.1368149503505, 7543.924560754377, 7721.7683469496005, 7721.806954826118, 7723.845523089563, 7728.613569321567, 37522.8123859379, 37965.43051854224, 38030.42954355687, 38105.237895242215, 38282.80858595717, 3570.071781336871, 3626.365386057456, 3636.6363363663686, 3637.6726094651344, 3640.526731524911, 31398.529022064686, 31590.36819263602, 31622.209444763834, 31684.524732129037, 31740.0, 5211.348581427322, 5238.738063096868, 5249.501297376743, 5259.789608415659, 5307.097793375096, 174591.12704436525, 174815.12592437083, 174897.1255143729, 175192.12403937898, 175781.0, 46729.0, 46943.29585056227, 47016.7649161752, 47253.76373118181, 47389.76305118452, 48980.51019489831, 49234.75382623064, 49268.26097608539, 49387.50612493829, 49528.50471495311, 14208.289585520683, 14464.927675361556, 14472.421103155755, 14515.419383224764, 14521.0, 11503.424828758529, 11532.077433805276, 11541.711457213636, 11558.133140014532, 11559.075273978062, 14321.0, 14517.128972261487, 14568.854311456962, 14576.562703118923, 14629.487967921285, 5427.348718153847, 5654.321481422256, 5659.858503537363, 5707.68607726579, 5738.626989245769, 989.099919073654, 994.9502524873727, 1000.4897502273748, 1013.7009582173276, 1017.4149863828328, 22946.082156713877, 22964.311070667907, 23057.538849223256, 23075.88462057679, 23105.768942310362, 1108.6230681568306, 1109.578360223126, 1112.7496313329436, 1113.365381732421, 1120.551779288275, 12225.633231003085, 12322.938385308016, 12342.197757145936, 12346.506139754309, 12377.566785162477, 223524.0, 225867.0, 225973.0, 226040.0, 226055.0, 52049.739751301, 52168.95662086737, 53849.73075134599, 56242.0, 56348.15477767837, 1426.664733787559, 1443.2855736410545, 1444.5594093801492, 1454.7817827325773, 1455.825300963891, 26566.734332656426, 26838.194854154408, 27163.456730865666, 27374.315642109108, 28115.0, 50091.248631271286, 50295.74852125716, 50299.24551131663, 50386.49613503965, 50441.24338134858, 198118.0, 198290.0, 198695.0, 198718.0, 198847.0, 32944.1763955903, 33050.17374565655, 33135.17162070871, 33228.0, 35699.64300356963, 45972.540274596824, 45986.0, 46747.29879051885, 46903.29645055259, 46938.530614694784, 32309.35381292408, 32625.347493049532, 32745.34509309848, 33047.66952330446, 33134.502982454804, 32272.0, 32579.511307330882, 32666.673333266364, 32917.50623740694, 33223.33553328968, 37134.6286537142, 37407.251854962204, 37684.048926858755, 37736.62263377331, 37898.0, 32249.03252902323, 32659.836700816344, 32722.50916236211, 32804.34391312113, 33314.16714582155, 22840.428989275402, 23778.762212378348, 23859.0, 23943.52112957766, 24208.636870446604], "value": 17220.310305155428, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.5508448325822, 569.8914018132909, 570.4979617936216, 580.3036356372353, 584.6375247346645, 22409.215677451273, 22567.0, 23235.535289294203, 23349.0, 23380.532389352204, 25109.0, 25472.0, 25649.974001039984, 25661.74338256617, 25685.101021464252, 6676.09872667191, 6799.42204912583, 6815.38661520463, 6819.522633415663, 6822.7953161404985, 6129.724162412702, 6768.492363072764, 6769.52613317068, 6777.288384719583, 6779.0509328694025, 6697.631630260334, 6705.228898676666, 6705.430038446738, 6706.061151438803, 6714.865702685944, 6687.431568316699, 6696.363845434676, 6696.698648560827, 6708.161479815023, 6727.42816860565, 6189.319174890758, 6201.441870231656, 6211.844703882394, 6213.658248796314, 6213.937860621426, 28476.288092797844, 29012.564811527845, 29560.556591651144, 29603.407931841244, 29625.40749185004, 1995.6607376745842, 2037.2869495676543, 2047.457423782684, 2048.0374224114644, 2048.8258498027685, 19425.611487770308, 19661.0, 19968.500787480283, 20439.182432702673, 20453.0, 2464.0267094497754, 2481.3796550862285, 2531.303891429853, 2547.6178573214165, 2547.872606369674, 140382.0, 143771.0, 144371.27814360967, 144519.27740361335, 144730.55269447275, 14591.00884318712, 14635.0, 14839.554813355617, 15185.924070379577, 15520.92239538795, 23421.41446463818, 23470.64794028091, 23477.88261058684, 23494.0, 23649.881750591136, 10354.792904142025, 10506.842397364046, 10572.788544229072, 10578.259521833552, 10587.84118238227, 8421.494710317278, 9774.951125244328, 9805.509724513746, 9824.950875245579, 9937.304388692723, 7942.324902383362, 9073.954630226806, 9216.2626989842, 9237.491937943478, 9327.533623318806, 2660.9467810643678, 2678.2902530829347, 2736.4253506763803, 2814.8451835148853, 2877.3525956659582, 954.3224310739453, 966.8784210315999, 980.2696990741942, 983.179045497018, 983.9901600983899, 19598.21607135727, 19864.87768584307, 19867.900660496605, 20221.39335819928, 20432.080556375, 1023.329719034019, 1045.6444808765057, 1049.2864851900629, 1059.7986382587283, 1059.9205059620406, 10877.401742904216, 10968.835467467994, 11047.668569942914, 11237.157213209042, 11271.718207044889, 475117.0, 481964.0, 482073.0, 500699.0, 508715.0, 118862.40568797101, 119036.40481797536, 119502.402487987, 122209.0, 122533.0, 1099.7580532282884, 1192.6004788395985, 1193.3913704011018, 1198.2211562484456, 1207.1368971185645, 17549.82450175533, 17589.0, 17734.0, 17837.910810445865, 17968.101594920463, 53250.46749532455, 53684.92630147453, 54067.18899216436, 54333.185002225786, 55218.44781552294, 63661.6816915931, 64139.35860641334, 66294.66852665899, 67368.32631673817, 67573.66213168902, 14266.714665706835, 14394.424223030961, 14547.127172369692, 14595.562133136023, 14611.707765844836, 22666.43333916665, 22776.430589235402, 22791.0, 23126.0, 23133.190338338078, 17808.0, 17960.910195448938, 17979.101044947438, 18041.548961276072, 18472.907635461735, 16007.119608421182, 16196.67606647884, 16420.917895410446, 16997.0, 17047.48857534276, 18486.907565462086, 18543.165557549673, 18765.812341876408, 18811.341603043827, 18820.811791881904, 14328.283585820876, 14332.211728354623, 14362.712745744817, 14403.711925761216, 14406.351714172677, 17958.0, 18095.547611309823, 18228.088595570433, 18251.634967300844, 18263.54341141482], "value": 12024.920288500827, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.3513845784794, 1381.0470775165136, 1384.8130502382178, 1390.2562129260848, 1391.20005996552, 14985.850141498595, 15272.69454610907, 15613.843861561381, 15649.139297338641, 15658.21708914554, 15792.605184870385, 16013.83986160138, 16033.35866565336, 16035.278412471438, 16049.759253611175, 2738.4523095380905, 2777.750002499775, 2785.4846853332097, 2785.8189217700888, 2786.7352601502826, 2788.1914244869067, 2882.0, 2890.0607302626627, 2893.5514995175677, 2897.8695958681806, 2719.8912043518235, 2730.140005898146, 2739.2467071555284, 2756.0491630387482, 2758.1036163247027, 2704.9323766905786, 2707.783377329809, 2718.1301983365233, 2719.157061311, 2734.986325068382, 2568.22953114065, 2630.3818602628367, 2648.3908700998686, 2650.7879369650573, 2651.68179818423, 15187.772183417148, 15314.081155130616, 15332.693346133015, 15333.0, 15336.0, 8050.11448740638, 8265.504069755834, 8323.209295117014, 8354.958225208897, 8358.164183581594, 5295.496927791892, 5357.544608708265, 5485.6434331768305, 5552.333719953591, 5556.16657501378, 1947.4449781812116, 1947.7078438234237, 1958.4614231086566, 1982.137770070024, 1995.9002049897592, 26588.33529161767, 26745.33136671599, 27143.728562714317, 27167.184984450498, 27350.0, 6776.0, 6788.117544719254, 6962.746705593042, 6964.442844572523, 6984.4412447004115, 17267.0, 17296.654066918592, 17350.91324543369, 17366.39217627402, 17397.130143492774, 7480.738174163987, 7639.04511936008, 7639.465237433326, 7642.847143057108, 7643.235676432423, 6832.624205668637, 7052.730508508415, 7174.246704096099, 7180.3537681608905, 7197.856042879218, 5093.210552364315, 5189.662671926313, 5268.762905669254, 5278.0, 5334.0, 1475.513080683372, 1477.4976507987121, 1494.8280947691155, 1498.6852760920328, 1513.175319450902, 904.3443503459939, 938.2775263047552, 943.3396622364236, 948.1608776233084, 952.7856232347805, 7981.840363192704, 8009.19908009206, 8027.7993050173045, 8030.718924837603, 8068.354531637455, 716.8422946951662, 737.2222305467783, 991.3556188477547, 1029.3067903299032, 1035.2184101003845, 5448.45515448452, 5523.0, 5598.4961353477565, 5621.606487545914, 5789.536837053026, 21196.258130965343, 21741.78258217429, 22069.227577034726, 22498.21256256023, 22571.322860314216, 21886.671699924515, 22061.448463788212, 22109.115635374405, 22115.115395384328, 22255.109795608318, 10968.122550195965, 11626.069914406828, 11737.647870563897, 11793.52825886955, 11819.172657914036, 8506.149385061444, 8573.485590864319, 8619.91380086191, 8634.0, 8655.264302534355, 18472.353467628567, 18888.811111888706, 18889.338873139375, 19115.330963416214, 19189.424317270503, 19406.417807465798, 19430.222791088763, 19448.90275548613, 19461.90269048646, 19713.310034148737, 10868.130549556176, 11060.059894909024, 11067.72330691739, 11968.222065565711, 12543.686407839512, 14107.435702572193, 14266.358013889192, 14281.0, 14287.714245715235, 14301.785473217702, 11182.32906025641, 11229.775404492028, 11265.0, 11365.602203922823, 11494.0, 10972.670819875417, 10988.725281868017, 10989.0, 11152.275102118336, 11343.773124537298, 9631.71104866855, 9762.658306959222, 9769.85345219808, 9784.168345690696, 9807.0, 7356.742514011984, 7389.371903388273, 7414.961905333365, 7421.406287497095, 7429.777106686808, 7055.941608758727, 7059.623373442129, 7162.823200558966, 7201.351878330765, 7248.637568121468], "value": 6746.777528796689, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.79879648742275, 506.2204205523493, 533.7704786941614, 540.8080131553298, 551.1264645536824, 6451.354864513546, 6463.676816159191, 6477.0, 6479.384458476443, 6536.999999999988, 6108.0, 6280.654563998978, 6306.0, 6334.588251763632, 6344.492440604752, 4981.452040275567, 5034.187692430722, 5096.617753668481, 5121.308623335858, 5141.537261646451, 5050.267711181877, 5088.160453525183, 5110.9744451277875, 5114.411842638088, 5140.306058682087, 4896.265560165968, 5025.522575355335, 5046.974765126188, 5077.746112694369, 5104.157813960692, 5076.543111119999, 5110.361204849394, 5122.410922743895, 5136.229565565176, 5138.741008452916, 4591.219492686251, 4600.838970636045, 4605.907881842344, 4608.907821843578, 4613.792379342937, 6222.937770622327, 6229.483624372339, 6234.475037609775, 6246.25044994599, 6283.96858015707, 5775.942240577582, 5798.159266906275, 5836.649801011953, 5844.795432159854, 5864.0, 6704.2625311215725, 6727.3763232339425, 6729.663516824139, 6744.031532094083, 6747.190337159524, 4951.83082826991, 4966.975165124188, 4967.329410529568, 4969.0, 4980.800767969277, 6993.755218567377, 7046.859062818715, 7077.292270772933, 7082.327178917994, 7097.0, 5141.9742901286, 5142.2286657001005, 5153.768080436389, 5159.7678104485385, 5161.27742116104, 5185.196294574347, 5197.518707168439, 5199.818006369759, 5204.4535323791215, 5216.634835561475, 5783.94216057834, 5792.82621521355, 5805.7387417566315, 5825.126231065289, 5836.795712150054, 6663.333666633394, 6712.160979877515, 6717.227518835299, 6719.832004199934, 6767.255601883789, 1689.4509284482592, 1766.7703198584102, 1773.1931970953185, 1774.1484087638007, 1776.1296964487308, 1156.8669602995594, 1178.452019810792, 1224.7121926347302, 1245.993770031144, 1247.482294847628, 5879.617824841372, 5896.7641294348605, 5933.940660593339, 5955.76176952917, 5967.492763115184, 6755.088063111417, 6759.662016899136, 6806.795796126025, 6807.863842723217, 6870.072540207108, 5895.0, 5898.675572843449, 5964.0, 5964.7315870785915, 5988.383136553106, 3588.964110358915, 3624.800635965047, 3629.927401451956, 3643.289558536113, 3646.936573278459, 6498.837529061717, 6557.082008518812, 6620.404163625297, 6642.933570664328, 6659.633720145439, 6890.758823441155, 6979.89530157048, 7017.228104908456, 7051.506394552434, 7066.0, 6490.610563366213, 6517.641529715911, 6523.151990241238, 6613.404793568602, 6627.271000189975, 6675.0, 6710.295418981132, 6758.789574951858, 6772.52592318533, 6777.152855893013, 6749.49378796602, 6766.4625765338, 6777.762778302933, 6781.966090169518, 6848.417884479875, 6554.868902621825, 6567.671616419256, 6609.900851487328, 6611.867762644816, 6641.468682505485, 5072.213806859942, 5073.985202959349, 5077.543021128116, 5099.6685215460875, 5117.667351622282, 6777.390034896883, 6800.809858274683, 6864.622445765531, 6879.105716256855, 6934.618595977269, 6593.142891424084, 6686.598804071771, 6692.464602831665, 6707.966460167668, 6708.463322934056, 6594.934050659432, 6600.141981542369, 6608.7356505739235, 6613.2394774601535, 6619.569727967667, 6002.339742628395, 6449.647097608758, 6534.105109518377, 6573.737050518117, 6603.306652801387, 6555.541112122106, 6566.967165164335, 6613.107230523818, 6626.204855417381, 6757.358050985148, 6435.549511534147, 6438.356164383524, 6444.677766111582, 6451.70967306463, 6464.515161362822], "value": 5149.355467771204, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [910.9817803643928, 919.6827094652342, 927.7680579855037, 932.5896605493584, 932.7434955387267, 17277.65444691107, 18075.186616602252, 18081.367152149676, 18111.184996675147, 20624.381268561967, 19148.42554723357, 19192.424227273204, 19318.22727090918, 19319.0, 19360.4191874244, 979.2655508368706, 1014.031599822168, 1017.2370721958531, 1018.9184865210802, 1022.1311884897815, 973.6154219083458, 1018.7504061504942, 1021.2289721260476, 1021.3004092196832, 1025.6410256410234, 1062.904338609525, 1071.9946400267988, 1072.855164552787, 1072.8766191887955, 1073.1629329123296, 1066.163061996333, 1067.3382502848206, 1070.411273799415, 1070.84472751451, 1071.201954543862, 783.4085265624474, 783.8510682970275, 784.6547519091581, 785.4501848705871, 786.0, 15758.41997910547, 15999.28003239857, 16132.919335403365, 16237.350505979746, 17503.47489575302, 21551.892240538855, 21717.0, 21840.126394944342, 22164.22425215126, 22295.331140065824, 16741.07924064176, 16946.745798813026, 16998.66002679952, 17130.48608541746, 17171.227294771765, 3749.4000959846567, 3781.149241420686, 3784.375578029622, 3784.9621503784883, 3786.1291902862495, 131027.34486327603, 132976.3351183238, 133130.0, 134216.32891835575, 135461.3226933869, 16228.350865965229, 16423.6715265694, 16552.172391380383, 16688.0, 16741.330346786235, 28713.99501017496, 29282.560761588593, 29283.560746588817, 31207.375852482823, 31211.375772484877, 7881.369490440751, 8197.631106600218, 9201.125893040151, 9360.15758581731, 9372.671956481627, 4382.846600368972, 4529.9547004530195, 4538.795754191004, 4564.589186973189, 4572.0627271409285, 5019.0, 5113.258577506241, 5128.487151284841, 5133.383993920754, 5145.099607568661, 1008.621766837436, 1020.4285600063839, 1031.9948400257952, 1034.0280136671654, 1037.3464717228248, 642.1748053750907, 644.7646608987686, 647.6437959122463, 654.6268626882727, 665.2781731821028, 11171.0, 11190.048845848194, 11570.305781653127, 12225.777422257881, 12341.197822141532, 586.3110844757393, 607.4836389069255, 638.2596188421409, 652.8237375908478, 665.1685393258427, 5279.80255696722, 5534.363548192009, 5539.307586551681, 5841.7371218295275, 5853.268341457318, 105766.0, 106561.46719266353, 107100.0, 107699.46150269355, 110734.0, 91029.0, 93032.53483732538, 96005.0, 98370.0, 101910.49044754729, 85990.57004714936, 87947.0, 90016.54991725, 90958.54520727486, 91414.0, 11914.285142891107, 11918.52325906954, 12171.0, 12246.571370002008, 12369.505219791468, 40444.797776010935, 41303.58696413117, 41888.3716744243, 43145.7842710797, 43320.56679433165, 72875.0, 73553.0, 73818.0, 74687.62656186902, 76814.61592692001, 17469.912650436665, 17489.387871424948, 17509.562260943578, 17539.0, 17700.291988320838, 19669.0, 20095.799042009392, 20541.486462838548, 20807.063682134038, 20908.16367345289, 16716.498505044867, 16962.151892405578, 17050.317987280865, 17173.74239386435, 17655.117244138, 16606.916965415578, 16821.07484088387, 16871.493855184362, 16923.323067077672, 17242.0, 16864.578385539968, 17053.403130890358, 17866.196021178817, 17959.640807184045, 18612.0, 12262.264264143823, 12541.561045363369, 12758.532768731344, 12799.872001279868, 13191.0, 12310.630681079581, 12506.937465312616, 12697.873021269668, 12771.93614031924, 12895.871041289467], "value": 9041.19825593239, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1469.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 100.0, 108.0, 122.0, 109.0, 108.0, 98.0, 109.0, 108.0], "value": 107.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 137.0, 163.0, 118.0, 144.0, 142.0, 117.0, 182.0, 116.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 165.0, 164.0, 161.0, 162.0, 165.0, 167.0, 159.0, 161.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 158.0, 76.0, 73.0, 73.0, 86.0, 97.0, 74.0], "value": 85.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 129.0, 79.0, 78.0, 78.0, 123.0, 80.0, 82.0, 131.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 149.0, 150.0, 152.0, 147.0, 148.0, 148.0, 148.0, 148.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 54.0, 54.0, 53.0, 54.0, 52.0, 54.0, 53.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 73.0, 74.0, 73.0, 74.0, 74.0, 73.0, 75.0, 76.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 122.0, 117.0, 124.0, 118.0, 119.0, 128.0, 126.0, 117.0, 157.0], "value": 124.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 169.0, 169.0, 175.0, 176.0, 165.0, 169.0, 172.0, 167.0, 172.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 67.0, 68.0, 71.0, 63.0, 62.0, 64.0, 64.0, 67.0], "value": 65.2, "unit": "score"}]}]} 16:07:13 INFO - Return code: 0 16:07:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:07:13 INFO - # TBPL SUCCESS # 16:07:13 INFO - Running post-action listener: _package_coverage_data 16:07:13 INFO - Running post-action listener: _resource_record_post_action 16:07:13 INFO - [mozharness: 2017-09-03 23:07:13.191399Z] Finished run-tests step (success) 16:07:13 INFO - Running post-run listener: _resource_record_post_run 16:07:13 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 7163904; Write bytes: 404086784; Read time: 4744; Write time: 287100 16:07:13 INFO - TinderboxPrint: CPU usage
13.1% 16:07:13 INFO - TinderboxPrint: I/O read bytes / time
7,163,904 / 4,744 16:07:13 INFO - TinderboxPrint: I/O write bytes / time
404,086,784 / 287,100 16:07:13 INFO - TinderboxPrint: CPU idle
8,270.0 (86.9%) 16:07:13 INFO - TinderboxPrint: CPU user
1,193.4 (12.5%) 16:07:13 INFO - TinderboxPrint: Swap in / out
0 / 0 16:07:13 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118484992; Read time: 0; Write time: 88480 16:07:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:07:13 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 5419008; Write bytes: 285601792; Read time: 4712; Write time: 198620 16:07:13 INFO - Running post-run listener: _upload_blobber_files 16:07:13 INFO - Blob upload gear active. 16:07:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:07:13 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 16:07:13 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'] 16:07:13 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 16:07:14 INFO - (blobuploader) - INFO - Open directory for files ... 16:07:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:07:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:07:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:15 INFO - (blobuploader) - INFO - Done attempting. 16:07:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:07:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:07:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:22 INFO - (blobuploader) - INFO - Done attempting. 16:07:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:07:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:07:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:23 INFO - (blobuploader) - INFO - Done attempting. 16:07:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:07:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:07:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:24 INFO - (blobuploader) - INFO - Done attempting. 16:07:24 INFO - (blobuploader) - INFO - Iteration through files over. 16:07:24 INFO - Return code: 0 16:07:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:07:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:07:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e53d152f665429743c4787345c342c360931c1af5e8689011ddb91479cc19f1147546a80350bd8627acde1dfecaec60b65930eabaec7416b588a318e41cac0e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07325c73fb7c7afcccac6ebb36a4925b70b3be4b671a3e0bd5683ea0cd1766a391a4e0f66bd911bffd752c8927f920f1eb759196f912272b5b2f45d2224994d7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc26a44af8319e0ab2b5f06d7e826e5e879ed1ad1882f5a69418ca1ced686c552ebdb28483a759b66b96431cc52c77a48de4d392eb4629184b7856ea0b8a639b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d74e68be24db3a1d782a39982e2754e23b83bdc36163cbe2a9bc672d079fcc513e674ca8712effe0eace99ae5150e5174e482cb45faba5bc1db3680712905c"} 16:07:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:07:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:07:24 INFO - Contents: 16:07:24 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e53d152f665429743c4787345c342c360931c1af5e8689011ddb91479cc19f1147546a80350bd8627acde1dfecaec60b65930eabaec7416b588a318e41cac0e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07325c73fb7c7afcccac6ebb36a4925b70b3be4b671a3e0bd5683ea0cd1766a391a4e0f66bd911bffd752c8927f920f1eb759196f912272b5b2f45d2224994d7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc26a44af8319e0ab2b5f06d7e826e5e879ed1ad1882f5a69418ca1ced686c552ebdb28483a759b66b96431cc52c77a48de4d392eb4629184b7856ea0b8a639b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d74e68be24db3a1d782a39982e2754e23b83bdc36163cbe2a9bc672d079fcc513e674ca8712effe0eace99ae5150e5174e482cb45faba5bc1db3680712905c"} 16:07:24 INFO - Running post-run listener: copy_logs_to_upload_dir 16:07:24 INFO - Copying logs to upload dir... 16:07:24 INFO - mkdir: /builds/slave/test/build/upload/logs 16:07:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.700121 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-09-03 16:07:24.313213) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 16:07:24.321489) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e53d152f665429743c4787345c342c360931c1af5e8689011ddb91479cc19f1147546a80350bd8627acde1dfecaec60b65930eabaec7416b588a318e41cac0e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07325c73fb7c7afcccac6ebb36a4925b70b3be4b671a3e0bd5683ea0cd1766a391a4e0f66bd911bffd752c8927f920f1eb759196f912272b5b2f45d2224994d7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc26a44af8319e0ab2b5f06d7e826e5e879ed1ad1882f5a69418ca1ced686c552ebdb28483a759b66b96431cc52c77a48de4d392eb4629184b7856ea0b8a639b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d74e68be24db3a1d782a39982e2754e23b83bdc36163cbe2a9bc672d079fcc513e674ca8712effe0eace99ae5150e5174e482cb45faba5bc1db3680712905c"} build_url:https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/aMXnMtCbR1yOPtHcAMbvtA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e53d152f665429743c4787345c342c360931c1af5e8689011ddb91479cc19f1147546a80350bd8627acde1dfecaec60b65930eabaec7416b588a318e41cac0e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07325c73fb7c7afcccac6ebb36a4925b70b3be4b671a3e0bd5683ea0cd1766a391a4e0f66bd911bffd752c8927f920f1eb759196f912272b5b2f45d2224994d7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc26a44af8319e0ab2b5f06d7e826e5e879ed1ad1882f5a69418ca1ced686c552ebdb28483a759b66b96431cc52c77a48de4d392eb4629184b7856ea0b8a639b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d74e68be24db3a1d782a39982e2754e23b83bdc36163cbe2a9bc672d079fcc513e674ca8712effe0eace99ae5150e5174e482cb45faba5bc1db3680712905c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 16:07:24.349694) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:07:24.354127) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504458576.212356-1579827001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:07:24.435103) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 16:07:24.435446) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 16:07:24.435810) ========= ========= Total master_lag: 0.41 =========