builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-088 starttime: 1504259796.74 results: success (0) revision: a3585c77e2b1bc5f5fea907e97762f7b47a12033 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:36.735963) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:36.736389) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:36.736660) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:36.836788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:36.837089) ========= 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=9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 02:56:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 57.6M=0s 2017-09-01 02:56:36 (57.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132066 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:37.002750) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:37.007559) ========= 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=9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020154 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:37.069619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:37.072004) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a3585c77e2b1bc5f5fea907e97762f7b47a12033 --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 a3585c77e2b1bc5f5fea907e97762f7b47a12033 --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=9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 02:56:37,079 truncating revision to first 12 chars 2017-09-01 02:56:37,079 Setting DEBUG logging. 2017-09-01 02:56:37,079 attempt 1/10 2017-09-01 02:56:37,079 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a3585c77e2b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 02:56:37,495 unpacking tar archive at: mozilla-central-a3585c77e2b1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.794683 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:37.889152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:37.889474) ========= script_repo_revision: a3585c77e2b1bc5f5fea907e97762f7b47a12033 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:37.889894) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:37.890168) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:56:37.919851) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-01 02:56:37.920211) ========= /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=9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:56:38 INFO - MultiFileLogger online at 20170901 02:56:38 in /builds/slave/test 02:56:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:56:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:56:38 INFO - {'append_to_log': False, 02:56:38 INFO - 'base_work_dir': '/builds/slave/test', 02:56:38 INFO - 'blob_upload_branch': 'Firefox', 02:56:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:56:38 INFO - 'branch': 'Firefox', 02:56:38 INFO - 'buildbot_json_path': 'buildprops.json', 02:56:38 INFO - 'code_coverage': False, 02:56:38 INFO - 'config_files': ('talos/linux_config.py',), 02:56:38 INFO - 'default_actions': ('clobber', 02:56:38 INFO - 'read-buildbot-config', 02:56:38 INFO - 'download-and-extract', 02:56:38 INFO - 'populate-webroot', 02:56:38 INFO - 'create-virtualenv', 02:56:38 INFO - 'install', 02:56:38 INFO - 'setup-mitmproxy', 02:56:38 INFO - 'run-tests'), 02:56:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:56:38 INFO - 'disable_ccov_upload': False, 02:56:38 INFO - 'disable_stylo': False, 02:56:38 INFO - 'download_minidump_stackwalk': True, 02:56:38 INFO - 'download_symbols': 'ondemand', 02:56:38 INFO - 'enable_stylo': False, 02:56:38 INFO - 'enable_webrender': False, 02:56:38 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:56:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:56:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:56:38 INFO - 'gecko_profile': False, 02:56:38 INFO - 'gecko_profile_interval': 0, 02:56:38 INFO - 'installer_path': 'installer.exe', 02:56:38 INFO - 'log_level': 'info', 02:56:38 INFO - 'log_name': 'talos', 02:56:38 INFO - 'log_to_console': True, 02:56:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:56:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:56:38 INFO - 'opt_config_files': (), 02:56:38 INFO - 'pip_index': False, 02:56:38 INFO - 'suite': 'dromaeojs-e10s', 02:56:38 INFO - 'system_bits': '32', 02:56:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:56:38 INFO - 'title': 'talos-linux64-ix-088', 02:56:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:56:38 INFO - 'use_talos_json': True, 02:56:38 INFO - 'verify': 'False', 02:56:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:56:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:56:38 INFO - 'work_dir': 'build'} 02:56:38 INFO - [mozharness: 2017-09-01 09:56:38.063364Z] Running clobber step. 02:56:38 INFO - Running pre-action listener: _resource_record_pre_action 02:56:38 INFO - Running main action method: clobber 02:56:38 INFO - rmtree: /builds/slave/test/build 02:56:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:56:39 INFO - Running post-action listener: _resource_record_post_action 02:56:39 INFO - [mozharness: 2017-09-01 09:56:39.081132Z] Finished clobber step (success) 02:56:39 INFO - [mozharness: 2017-09-01 09:56:39.081229Z] Running read-buildbot-config step. 02:56:39 INFO - Running pre-action listener: _resource_record_pre_action 02:56:39 INFO - Running main action method: read_buildbot_config 02:56:39 INFO - Using buildbot properties: 02:56:39 INFO - { 02:56:39 INFO - "project": "", 02:56:39 INFO - "product": "firefox", 02:56:39 INFO - "who": "archaeopteryx@coole-files.de", 02:56:39 INFO - "installer_path": "public/build/target.tar.bz2", 02:56:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:56:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:56:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 02:56:39 INFO - "stage_platform": "linux64-pgo", 02:56:39 INFO - "basedir": "/builds/slave/test", 02:56:39 INFO - "buildnumber": 133, 02:56:39 INFO - "platform": "ubuntu64_hw", 02:56:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:56:39 INFO - "slavebuilddir": "test", 02:56:39 INFO - "taskId": "YM7O4yl0SyiEIAmEybhH_Q", 02:56:39 INFO - "branch": "mozilla-central", 02:56:39 INFO - "script_repo_revision": "production", 02:56:39 INFO - "revision": "a3585c77e2b1bc5f5fea907e97762f7b47a12033", 02:56:39 INFO - "slavename": "talos-linux64-ix-088", 02:56:39 INFO - "repo_path": "mozilla-central" 02:56:39 INFO - } 02:56:39 INFO - Finding installer, test and symbols from parent task. 02:56:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YM7O4yl0SyiEIAmEybhH_Q'}, attempt #1 02:56:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YM7O4yl0SyiEIAmEybhH_Q'}, attempt #1 02:56:39 INFO - Task dependencies: eencB-1WTx2oDdiXBsZwYA 02:56:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA'}, attempt #1 02:56:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.tar.bz2 02:56:40 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.test_packages.json 02:56:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.crashreporter-symbols.zip 02:56:40 INFO - Running post-action listener: _resource_record_post_action 02:56:40 INFO - [mozharness: 2017-09-01 09:56:40.422063Z] Finished read-buildbot-config step (success) 02:56:40 INFO - [mozharness: 2017-09-01 09:56:40.422278Z] Running download-and-extract step. 02:56:40 INFO - Running pre-action listener: _resource_record_pre_action 02:56:40 INFO - Running main action method: download_and_extract 02:56:40 INFO - mkdir: /builds/slave/test/build/tests 02:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:40 INFO - trying https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.test_packages.json 02:56:40 INFO - Downloading https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:56:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:56:40 INFO - Downloaded 989 bytes. 02:56:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:56:40 INFO - Using the following test package requirements: 02:56:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:56:40 INFO - u'common': [u'target.common.tests.zip'], 02:56:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:56:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:56:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:56:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:56:40 INFO - u'mozbase': [u'target.common.tests.zip'], 02:56:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:56:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:56:40 INFO - u'web-platform': [u'target.common.tests.zip', 02:56:40 INFO - u'target.web-platform.tests.tar.gz'], 02:56:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:56:40 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:56:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.common.tests.zip 02:56:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:56:40 INFO - Fetch https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.common.tests.zip into memory 02:56:43 INFO - Content-Length response header: 55584048 02:56:43 INFO - Bytes received: 55584048 02:56:54 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:56:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.common.tests.zip 02:56:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:56:54 INFO - Fetch https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.common.tests.zip into memory 02:56:55 INFO - Content-Length response header: 55584048 02:56:55 INFO - Bytes received: 55584048 02:57:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.talos.tests.zip 02:57:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:57:07 INFO - Fetch https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.talos.tests.zip into memory 02:57:08 INFO - Content-Length response header: 14496650 02:57:08 INFO - Bytes received: 14496650 02:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:08 INFO - trying https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.tar.bz2 02:57:08 INFO - Downloading https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:57:10 INFO - Downloaded 63388961 bytes. 02:57:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.tar.bz2 02:57:10 INFO - mkdir: /builds/slave/test/properties 02:57:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:57:10 INFO - Writing to file /builds/slave/test/properties/build_url 02:57:10 INFO - Contents: 02:57:10 INFO - build_url:https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.tar.bz2 02:57:10 INFO - Running post-action listener: _resource_record_post_action 02:57:10 INFO - Running post-action listener: find_tests_for_verification 02:57:10 INFO - Running post-action listener: set_extra_try_arguments 02:57:10 INFO - [mozharness: 2017-09-01 09:57:10.715004Z] Finished download-and-extract step (success) 02:57:10 INFO - [mozharness: 2017-09-01 09:57:10.715126Z] Running populate-webroot step. 02:57:10 INFO - Running pre-action listener: _resource_record_pre_action 02:57:10 INFO - Running main action method: populate_webroot 02:57:10 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:57:10 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:57:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:57:10 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:57:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:57:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:10 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:57:10 INFO - 'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:57:10 INFO - 'tests': ['damp', 'tps']}, 02:57:10 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:57:10 INFO - 'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['damp', 'tps']}, 02:57:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:57:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:57:10 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:57:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:57:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:57:10 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:57:10 INFO - 'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:57:10 INFO - 'other-e10s': {'tests': ['a11yr', 02:57:10 INFO - 'ts_paint', 02:57:10 INFO - 'tpaint', 02:57:10 INFO - 'sessionrestore', 02:57:10 INFO - 'sessionrestore_many_windows', 02:57:10 INFO - 'sessionrestore_no_auto_restore', 02:57:10 INFO - 'tabpaint']}, 02:57:10 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['a11yr', 02:57:10 INFO - 'ts_paint', 02:57:10 INFO - 'tpaint', 02:57:10 INFO - 'sessionrestore', 02:57:10 INFO - 'sessionrestore_many_windows', 02:57:10 INFO - 'sessionrestore_no_auto_restore', 02:57:10 INFO - 'tabpaint']}, 02:57:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:57:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:57:10 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['bloom_basic_singleton']}, 02:57:10 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['bloom_basic']}, 02:57:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:57:10 INFO - 'tsvgr_opacity', 02:57:10 INFO - 'tart', 02:57:10 INFO - 'tscrollx', 02:57:10 INFO - 'tsvg_static']}, 02:57:10 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['tsvgx', 02:57:10 INFO - 'tsvgr_opacity', 02:57:10 INFO - 'tart', 02:57:10 INFO - 'tscrollx', 02:57:10 INFO - 'tsvg_static']}, 02:57:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:57:10 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:57:10 INFO - 'talos_options': ['--enable-stylo'], 02:57:10 INFO - 'tests': ['tp5o']}, 02:57:10 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:57:10 INFO - 'talos_options': ['--mitmproxy', 02:57:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:57:10 INFO - '--firstNonBlankPaint'], 02:57:10 INFO - 'tests': ['tp6_google', 02:57:10 INFO - 'tp6_youtube', 02:57:10 INFO - 'tp6_amazon', 02:57:10 INFO - 'tp6_facebook']}, 02:57:10 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:57:10 INFO - 'talos_options': ['--enable-stylo', 02:57:10 INFO - '--mitmproxy', 02:57:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:57:10 INFO - '--firstNonBlankPaint'], 02:57:10 INFO - 'tests': ['tp6_google', 02:57:10 INFO - 'tp6_youtube', 02:57:10 INFO - 'tp6_amazon', 02:57:10 INFO - 'tp6_facebook']}, 02:57:10 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:57:10 INFO - 'talos_options': ['--enable-stylo', 02:57:10 INFO - '--stylo-threads=1', 02:57:10 INFO - '--mitmproxy', 02:57:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:57:10 INFO - '--firstNonBlankPaint'], 02:57:10 INFO - 'tests': ['tp6_google', 02:57:10 INFO - 'tp6_youtube', 02:57:10 INFO - 'tp6_amazon', 02:57:10 INFO - 'tp6_facebook']}, 02:57:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:57:10 INFO - 'talos_options': ['--xperf_path', 02:57:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:10 INFO - 'tests': ['tp5n']}, 02:57:10 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:57:10 INFO - 'talos_options': ['--enable-stylo', 02:57:10 INFO - '--xperf_path', 02:57:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:10 INFO - 'tests': ['tp5n']}}} 02:57:10 INFO - Running post-action listener: _resource_record_post_action 02:57:10 INFO - [mozharness: 2017-09-01 09:57:10.730709Z] Finished populate-webroot step (success) 02:57:10 INFO - [mozharness: 2017-09-01 09:57:10.730808Z] Running create-virtualenv step. 02:57:10 INFO - Running pre-action listener: _resource_record_pre_action 02:57:10 INFO - Running main action method: create_virtualenv 02:57:10 INFO - Creating virtualenv /builds/slave/test/build/venv 02:57:10 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:57:10 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:57:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:57:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:57:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:57:11 INFO - Installing distribute.............................................................................................................................................................................................done. 02:57:11 INFO - Installing pip...............done. 02:57:11 INFO - Return code: 0 02:57:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:57:11 INFO - Reading from file tmpfile_stdout 02:57:11 INFO - Output received: 02:57:11 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:57:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:11 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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:57:11 INFO - Using env: {'DISPLAY': ':0', 02:57:11 INFO - 'HOME': '/home/cltbld', 02:57:11 INFO - 'LANG': 'en_US.UTF-8', 02:57:11 INFO - 'LANGUAGE': 'en_US:en', 02:57:11 INFO - 'LOGNAME': 'cltbld', 02:57:11 INFO - 'MAIL': '/var/mail/cltbld', 02:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:11 INFO - 'MOZ_NO_REMOTE': '1', 02:57:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:11 INFO - 'NO_EM_RESTART': '1', 02:57:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:11 INFO - 'PWD': '/builds/slave/test', 02:57:11 INFO - 'SHELL': '/bin/bash', 02:57:11 INFO - 'SHLVL': '1', 02:57:11 INFO - 'TERM': 'linux', 02:57:11 INFO - 'TMOUT': '86400', 02:57:11 INFO - 'USER': 'cltbld', 02:57:11 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:11 INFO - '_': '/tools/buildbot/bin/python'} 02:57:12 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:57:12 INFO - Downloading/unpacking pip>=1.5 02:57:12 INFO - Running setup.py egg_info for package pip 02:57:12 INFO - warning: no files found matching 'pip/cacert.pem' 02:57:12 INFO - warning: no files found matching '*.html' under directory 'docs' 02:57:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:57:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:57:12 INFO - Installing collected packages: pip 02:57:12 INFO - Found existing installation: pip 1.1 02:57:12 INFO - Uninstalling pip: 02:57:12 INFO - Successfully uninstalled pip 02:57:12 INFO - Running setup.py install for pip 02:57:12 INFO - warning: no files found matching 'pip/cacert.pem' 02:57:12 INFO - warning: no files found matching '*.html' under directory 'docs' 02:57:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:57:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:57:12 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:57:12 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:57:12 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:57:12 INFO - Successfully installed pip 02:57:12 INFO - Cleaning up... 02:57:12 INFO - Return code: 0 02:57:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:12 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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:57:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:57:12 INFO - Using env: {'DISPLAY': ':0', 02:57:12 INFO - 'HOME': '/home/cltbld', 02:57:12 INFO - 'LANG': 'en_US.UTF-8', 02:57:12 INFO - 'LANGUAGE': 'en_US:en', 02:57:12 INFO - 'LOGNAME': 'cltbld', 02:57:12 INFO - 'MAIL': '/var/mail/cltbld', 02:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:12 INFO - 'MOZ_NO_REMOTE': '1', 02:57:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:12 INFO - 'NO_EM_RESTART': '1', 02:57:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:12 INFO - 'PWD': '/builds/slave/test', 02:57:12 INFO - 'SHELL': '/bin/bash', 02:57:12 INFO - 'SHLVL': '1', 02:57:12 INFO - 'TERM': 'linux', 02:57:12 INFO - 'TMOUT': '86400', 02:57:12 INFO - 'USER': 'cltbld', 02:57:12 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:12 INFO - '_': '/tools/buildbot/bin/python'} 02:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:13 INFO - Downloading/unpacking psutil>=3.1.1 02:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:57:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:14 INFO - Installing collected packages: psutil 02:57:14 INFO - Running setup.py install for psutil 02:57:14 INFO - building 'psutil._psutil_linux' extension 02:57:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:57:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:57:14 INFO - building 'psutil._psutil_posix' extension 02:57:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 02:57:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:57:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:14 INFO - Successfully installed psutil 02:57:14 INFO - Cleaning up... 02:57:14 INFO - Return code: 0 02:57:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:14 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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:57:14 INFO - Using env: {'DISPLAY': ':0', 02:57:14 INFO - 'HOME': '/home/cltbld', 02:57:14 INFO - 'LANG': 'en_US.UTF-8', 02:57:14 INFO - 'LANGUAGE': 'en_US:en', 02:57:14 INFO - 'LOGNAME': 'cltbld', 02:57:14 INFO - 'MAIL': '/var/mail/cltbld', 02:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:14 INFO - 'MOZ_NO_REMOTE': '1', 02:57:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:14 INFO - 'NO_EM_RESTART': '1', 02:57:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:14 INFO - 'PWD': '/builds/slave/test', 02:57:14 INFO - 'SHELL': '/bin/bash', 02:57:14 INFO - 'SHLVL': '1', 02:57:14 INFO - 'TERM': 'linux', 02:57:14 INFO - 'TMOUT': '86400', 02:57:14 INFO - 'USER': 'cltbld', 02:57:14 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:14 INFO - '_': '/tools/buildbot/bin/python'} 02:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:57:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:57:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:57:16 INFO - Installing collected packages: mozsystemmonitor 02:57:16 INFO - Running setup.py install for mozsystemmonitor 02:57:16 INFO - Successfully installed mozsystemmonitor 02:57:16 INFO - Cleaning up... 02:57:16 INFO - Return code: 0 02:57:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:16 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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:57:16 INFO - Using env: {'DISPLAY': ':0', 02:57:16 INFO - 'HOME': '/home/cltbld', 02:57:16 INFO - 'LANG': 'en_US.UTF-8', 02:57:16 INFO - 'LANGUAGE': 'en_US:en', 02:57:16 INFO - 'LOGNAME': 'cltbld', 02:57:16 INFO - 'MAIL': '/var/mail/cltbld', 02:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:16 INFO - 'MOZ_NO_REMOTE': '1', 02:57:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:16 INFO - 'NO_EM_RESTART': '1', 02:57:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:16 INFO - 'PWD': '/builds/slave/test', 02:57:16 INFO - 'SHELL': '/bin/bash', 02:57:16 INFO - 'SHLVL': '1', 02:57:16 INFO - 'TERM': 'linux', 02:57:16 INFO - 'TMOUT': '86400', 02:57:16 INFO - 'USER': 'cltbld', 02:57:16 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:16 INFO - '_': '/tools/buildbot/bin/python'} 02:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:16 INFO - Downloading/unpacking jsonschema==2.5.1 02:57: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 02:57: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 02:57: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 02:57: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 02:57:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:57:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:17 INFO - Downloading functools32-3.2.3-2.tar.gz 02:57:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:57:17 INFO - warning: no files found matching '*.txt' 02:57:17 INFO - no previously-included directories found matching 'build' 02:57:17 INFO - no previously-included directories found matching 'dist' 02:57:17 INFO - no previously-included directories found matching '.git*' 02:57:17 INFO - Installing collected packages: jsonschema, functools32 02:57:17 INFO - Running setup.py install for functools32 02:57:18 INFO - warning: no files found matching '*.txt' 02:57:18 INFO - no previously-included directories found matching 'build' 02:57:18 INFO - no previously-included directories found matching 'dist' 02:57:18 INFO - no previously-included directories found matching '.git*' 02:57:18 INFO - Successfully installed jsonschema functools32 02:57:18 INFO - Cleaning up... 02:57:18 INFO - Return code: 0 02:57:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57: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', '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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:57: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 functools32==3.2.3-2 02:57:18 INFO - Using env: {'DISPLAY': ':0', 02:57:18 INFO - 'HOME': '/home/cltbld', 02:57:18 INFO - 'LANG': 'en_US.UTF-8', 02:57:18 INFO - 'LANGUAGE': 'en_US:en', 02:57:18 INFO - 'LOGNAME': 'cltbld', 02:57:18 INFO - 'MAIL': '/var/mail/cltbld', 02:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:18 INFO - 'MOZ_NO_REMOTE': '1', 02:57:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:18 INFO - 'NO_EM_RESTART': '1', 02:57:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:18 INFO - 'PWD': '/builds/slave/test', 02:57:18 INFO - 'SHELL': '/bin/bash', 02:57:18 INFO - 'SHLVL': '1', 02:57:18 INFO - 'TERM': 'linux', 02:57:18 INFO - 'TMOUT': '86400', 02:57:18 INFO - 'USER': 'cltbld', 02:57:18 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:18 INFO - '_': '/tools/buildbot/bin/python'} 02:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:57:18 INFO - Cleaning up... 02:57:18 INFO - Return code: 0 02:57:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57: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', '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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:57: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 blobuploader==1.2.4 02:57:18 INFO - Using env: {'DISPLAY': ':0', 02:57:18 INFO - 'HOME': '/home/cltbld', 02:57:18 INFO - 'LANG': 'en_US.UTF-8', 02:57:18 INFO - 'LANGUAGE': 'en_US:en', 02:57:18 INFO - 'LOGNAME': 'cltbld', 02:57:18 INFO - 'MAIL': '/var/mail/cltbld', 02:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:18 INFO - 'MOZ_NO_REMOTE': '1', 02:57:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:18 INFO - 'NO_EM_RESTART': '1', 02:57:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:18 INFO - 'PWD': '/builds/slave/test', 02:57:18 INFO - 'SHELL': '/bin/bash', 02:57:18 INFO - 'SHLVL': '1', 02:57:18 INFO - 'TERM': 'linux', 02:57:18 INFO - 'TMOUT': '86400', 02:57:18 INFO - 'USER': 'cltbld', 02:57:18 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:18 INFO - '_': '/tools/buildbot/bin/python'} 02:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:18 INFO - Downloading/unpacking blobuploader==1.2.4 02:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:19 INFO - Downloading blobuploader-1.2.4.tar.gz 02:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:57:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:57:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:20 INFO - Downloading docopt-0.6.1.tar.gz 02:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:57:20 INFO - Installing collected packages: blobuploader, requests, docopt 02:57:20 INFO - Running setup.py install for blobuploader 02:57:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:57:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:57:20 INFO - Running setup.py install for requests 02:57:20 INFO - Running setup.py install for docopt 02:57:20 INFO - Successfully installed blobuploader requests docopt 02:57:20 INFO - Cleaning up... 02:57:20 INFO - Return code: 0 02:57:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:20 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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:57:20 INFO - Using env: {'DISPLAY': ':0', 02:57:20 INFO - 'HOME': '/home/cltbld', 02:57:20 INFO - 'LANG': 'en_US.UTF-8', 02:57:20 INFO - 'LANGUAGE': 'en_US:en', 02:57:20 INFO - 'LOGNAME': 'cltbld', 02:57:20 INFO - 'MAIL': '/var/mail/cltbld', 02:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:20 INFO - 'MOZ_NO_REMOTE': '1', 02:57:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:20 INFO - 'NO_EM_RESTART': '1', 02:57:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:20 INFO - 'PWD': '/builds/slave/test', 02:57:20 INFO - 'SHELL': '/bin/bash', 02:57:20 INFO - 'SHLVL': '1', 02:57:20 INFO - 'TERM': 'linux', 02:57:20 INFO - 'TMOUT': '86400', 02:57:20 INFO - 'USER': 'cltbld', 02:57:20 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:20 INFO - '_': '/tools/buildbot/bin/python'} 02:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:21 INFO - Running setup.py (path:/tmp/pip-igAViJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:21 INFO - Running setup.py (path:/tmp/pip-LfPNQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:21 INFO - Running setup.py (path:/tmp/pip-nZFh4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:21 INFO - Running setup.py (path:/tmp/pip-XjWYtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:21 INFO - Running setup.py (path:/tmp/pip-px1HQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:21 INFO - Running setup.py (path:/tmp/pip-bsL2OY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:21 INFO - Running setup.py (path:/tmp/pip-hAe0AT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:21 INFO - Running setup.py (path:/tmp/pip-3yHj8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:21 INFO - Running setup.py (path:/tmp/pip-uLKdiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:21 INFO - Running setup.py (path:/tmp/pip-xkRlaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:21 INFO - Running setup.py (path:/tmp/pip-sJg3Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:21 INFO - Running setup.py (path:/tmp/pip-xOyobd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:21 INFO - Running setup.py (path:/tmp/pip-38G3lR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:21 INFO - Running setup.py (path:/tmp/pip-ibAewm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:22 INFO - Running setup.py (path:/tmp/pip-n_k1oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:22 INFO - Running setup.py (path:/tmp/pip-XHMiT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:22 INFO - Running setup.py (path:/tmp/pip-ALmSqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:57:22 INFO - Running setup.py install for manifestparser 02:57:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:57:22 INFO - Running setup.py install for mozcrash 02:57:22 INFO - Running setup.py install for mozdebug 02:57:22 INFO - Running setup.py install for mozdevice 02:57:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:57:22 INFO - Running setup.py install for mozfile 02:57:22 INFO - Running setup.py install for mozhttpd 02:57:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:57:22 INFO - Running setup.py install for mozinfo 02:57:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:57:22 INFO - Running setup.py install for mozInstall 02:57:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Running setup.py install for mozleak 02:57:23 INFO - Running setup.py install for mozlog 02:57:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Running setup.py install for moznetwork 02:57:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Running setup.py install for mozprocess 02:57:23 INFO - Running setup.py install for mozprofile 02:57:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Running setup.py install for mozrunner 02:57:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Running setup.py install for mozscreenshot 02:57:23 INFO - Running setup.py install for moztest 02:57:23 INFO - Running setup.py install for mozversion 02:57:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:57:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:57:23 INFO - Cleaning up... 02:57:24 INFO - Return code: 0 02:57:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:24 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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:57:24 INFO - Using env: {'DISPLAY': ':0', 02:57:24 INFO - 'HOME': '/home/cltbld', 02:57:24 INFO - 'LANG': 'en_US.UTF-8', 02:57:24 INFO - 'LANGUAGE': 'en_US:en', 02:57:24 INFO - 'LOGNAME': 'cltbld', 02:57:24 INFO - 'MAIL': '/var/mail/cltbld', 02:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:24 INFO - 'MOZ_NO_REMOTE': '1', 02:57:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:24 INFO - 'NO_EM_RESTART': '1', 02:57:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:24 INFO - 'PWD': '/builds/slave/test', 02:57:24 INFO - 'SHELL': '/bin/bash', 02:57:24 INFO - 'SHLVL': '1', 02:57:24 INFO - 'TERM': 'linux', 02:57:24 INFO - 'TMOUT': '86400', 02:57:24 INFO - 'USER': 'cltbld', 02:57:24 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:24 INFO - '_': '/tools/buildbot/bin/python'} 02:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:24 INFO - Running setup.py (path:/tmp/pip-kRvyNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:24 INFO - Running setup.py (path:/tmp/pip-jwV9LH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:24 INFO - Running setup.py (path:/tmp/pip-ENZGC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:24 INFO - Running setup.py (path:/tmp/pip-dzudXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:24 INFO - Running setup.py (path:/tmp/pip-BPWSVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:24 INFO - Running setup.py (path:/tmp/pip-u511cM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:24 INFO - Running setup.py (path:/tmp/pip-TZIKBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:24 INFO - Running setup.py (path:/tmp/pip-Nz1ZLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:24 INFO - Running setup.py (path:/tmp/pip-XNltF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:24 INFO - Running setup.py (path:/tmp/pip-tIagEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:24 INFO - Running setup.py (path:/tmp/pip-9xPf18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:25 INFO - Running setup.py (path:/tmp/pip-P6ZCrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:25 INFO - Running setup.py (path:/tmp/pip-ngAtDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:25 INFO - Running setup.py (path:/tmp/pip-DgBFcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:25 INFO - Running setup.py (path:/tmp/pip-BLTUqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:25 INFO - Running setup.py (path:/tmp/pip-QW6ZQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:25 INFO - Running setup.py (path:/tmp/pip-5T7AFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:57:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:26 INFO - Downloading blessings-1.6.tar.gz 02:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:57:26 INFO - Installing collected packages: blessings 02:57:26 INFO - Running setup.py install for blessings 02:57:26 INFO - Successfully installed blessings 02:57:26 INFO - Cleaning up... 02:57:26 INFO - Return code: 0 02:57:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:57:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:26 INFO - Reading from file tmpfile_stdout 02:57:26 INFO - Current package versions: 02:57:26 INFO - argparse == 1.2.1 02:57:26 INFO - blessings == 1.6 02:57:26 INFO - blobuploader == 1.2.4 02:57:26 INFO - docopt == 0.6.1 02:57:26 INFO - functools32 == 3.2.3-2 02:57:26 INFO - jsonschema == 2.5.1 02:57:26 INFO - manifestparser == 1.1 02:57:26 INFO - mozInstall == 1.13 02:57:26 INFO - mozcrash == 1.0 02:57:26 INFO - mozdebug == 0.1 02:57:26 INFO - mozdevice == 0.51 02:57:26 INFO - mozfile == 1.2 02:57:26 INFO - mozhttpd == 0.7 02:57:26 INFO - mozinfo == 0.10 02:57:26 INFO - mozleak == 0.1 02:57:26 INFO - mozlog == 3.5 02:57:26 INFO - moznetwork == 0.27 02:57:26 INFO - mozprocess == 0.25 02:57:26 INFO - mozprofile == 0.28 02:57:26 INFO - mozrunner == 6.13 02:57:26 INFO - mozscreenshot == 0.1 02:57:26 INFO - mozsystemmonitor == 0.3 02:57:26 INFO - moztest == 0.8 02:57:26 INFO - mozversion == 1.4 02:57:26 INFO - psutil == 3.1.1 02:57:26 INFO - requests == 1.2.3 02:57:26 INFO - wsgiref == 0.1.2 02:57:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:27 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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:57:27 INFO - Using env: {'DISPLAY': ':0', 02:57:27 INFO - 'HOME': '/home/cltbld', 02:57:27 INFO - 'LANG': 'en_US.UTF-8', 02:57:27 INFO - 'LANGUAGE': 'en_US:en', 02:57:27 INFO - 'LOGNAME': 'cltbld', 02:57:27 INFO - 'MAIL': '/var/mail/cltbld', 02:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:27 INFO - 'MOZ_NO_REMOTE': '1', 02:57:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:27 INFO - 'NO_EM_RESTART': '1', 02:57:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:27 INFO - 'PWD': '/builds/slave/test', 02:57:27 INFO - 'SHELL': '/bin/bash', 02:57:27 INFO - 'SHLVL': '1', 02:57:27 INFO - 'TERM': 'linux', 02:57:27 INFO - 'TMOUT': '86400', 02:57:27 INFO - 'USER': 'cltbld', 02:57:27 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:27 INFO - '_': '/tools/buildbot/bin/python'} 02:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:57:27 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:57:28 INFO - Installing collected packages: simplejson 02:57:28 INFO - Running setup.py install for simplejson 02:57:28 INFO - building 'simplejson._speedups' extension 02:57:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 02:57:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 02:57:29 INFO - Successfully installed simplejson 02:57:29 INFO - Cleaning up... 02:57:29 INFO - Return code: 0 02:57:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:29 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 0x1c871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e50a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:57:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:57:29 INFO - Using env: {'DISPLAY': ':0', 02:57:29 INFO - 'HOME': '/home/cltbld', 02:57:29 INFO - 'LANG': 'en_US.UTF-8', 02:57:29 INFO - 'LANGUAGE': 'en_US:en', 02:57:29 INFO - 'LOGNAME': 'cltbld', 02:57:29 INFO - 'MAIL': '/var/mail/cltbld', 02:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:29 INFO - 'MOZ_NO_REMOTE': '1', 02:57:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:29 INFO - 'NO_EM_RESTART': '1', 02:57:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:29 INFO - 'PWD': '/builds/slave/test', 02:57:29 INFO - 'SHELL': '/bin/bash', 02:57:29 INFO - 'SHLVL': '1', 02:57:29 INFO - 'TERM': 'linux', 02:57:29 INFO - 'TMOUT': '86400', 02:57:29 INFO - 'USER': 'cltbld', 02:57:29 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:29 INFO - '_': '/tools/buildbot/bin/python'} 02:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:57:29 INFO - Cleaning up... 02:57:29 INFO - Return code: 0 02:57:29 INFO - Running post-action listener: _resource_record_post_action 02:57:29 INFO - Running post-action listener: _start_resource_monitoring 02:57:29 INFO - Starting resource monitoring. 02:57:29 INFO - [mozharness: 2017-09-01 09:57:29.693411Z] Finished create-virtualenv step (success) 02:57:29 INFO - [mozharness: 2017-09-01 09:57:29.693943Z] Running install step. 02:57:29 INFO - Running pre-action listener: _resource_record_pre_action 02:57:29 INFO - Running main action method: install 02:57:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:29 INFO - Reading from file tmpfile_stdout 02:57:29 INFO - Detecting whether we're running mozinstall >=1.0... 02:57:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:57:30 INFO - Reading from file tmpfile_stdout 02:57:30 INFO - Output received: 02:57:30 INFO - Usage: mozinstall [options] installer 02:57:30 INFO - Options: 02:57:30 INFO - -h, --help show this help message and exit 02:57:30 INFO - -d DEST, --destination=DEST 02:57:30 INFO - Directory to install application into. [default: 02:57:30 INFO - "/builds/slave/test"] 02:57:30 INFO - --app=APP Application being installed. [default: firefox] 02:57:30 INFO - mkdir: /builds/slave/test/build/application 02:57:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:57:46 INFO - Reading from file tmpfile_stdout 02:57:46 INFO - Output received: 02:57:46 INFO - /builds/slave/test/build/application/firefox/firefox 02:57:46 INFO - Running post-action listener: _resource_record_post_action 02:57:46 INFO - [mozharness: 2017-09-01 09:57:46.371725Z] Finished install step (success) 02:57:46 INFO - [mozharness: 2017-09-01 09:57:46.371976Z] Running setup-mitmproxy step. 02:57:46 INFO - Running pre-action listener: _resource_record_pre_action 02:57:46 INFO - Running main action method: setup_mitmproxy 02:57:46 INFO - Skipping: mitmproxy is not required 02:57:46 INFO - Running post-action listener: _resource_record_post_action 02:57:46 INFO - [mozharness: 2017-09-01 09:57:46.373167Z] Finished setup-mitmproxy step (success) 02:57:46 INFO - [mozharness: 2017-09-01 09:57:46.373386Z] Running run-tests step. 02:57:46 INFO - Running pre-action listener: _resource_record_pre_action 02:57:46 INFO - Running pre-action listener: _set_gcov_prefix 02:57:46 INFO - Running main action method: run_tests 02:57:46 WARNING - Try message not found. 02:57:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:57:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:57:46 INFO - Python 2.7.3 02:57:46 INFO - Return code: 0 02:57:46 INFO - grabbing minidump binary from tooltool 02:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:46 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 0x1ff7090>, '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 0x1ff7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff1f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:57:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:57:46 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 02:57:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:57:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:57:46 INFO - Return code: 0 02:57:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:57:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:57:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:57:46 INFO - ENV: RUST_BACKTRACE is now full 02:57:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:57:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:57:46 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/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:57:46 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/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:57:46 INFO - Using env: {'DISPLAY': ':0', 02:57:46 INFO - 'HOME': '/home/cltbld', 02:57:46 INFO - 'LANG': 'en_US.UTF-8', 02:57:46 INFO - 'LANGUAGE': 'en_US:en', 02:57:46 INFO - 'LOGNAME': 'cltbld', 02:57:46 INFO - 'MAIL': '/var/mail/cltbld', 02:57:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:57:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:46 INFO - 'MOZ_NO_REMOTE': '1', 02:57:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:57:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:46 INFO - 'NO_EM_RESTART': '1', 02:57:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:46 INFO - 'PWD': '/builds/slave/test', 02:57:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:57:46 INFO - 'RUST_BACKTRACE': 'full', 02:57:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:57:46 INFO - 'SHELL': '/bin/bash', 02:57:46 INFO - 'SHLVL': '1', 02:57:46 INFO - 'TERM': 'linux', 02:57:46 INFO - 'TMOUT': '86400', 02:57:46 INFO - 'USER': 'cltbld', 02:57:46 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:46 INFO - '_': '/tools/buildbot/bin/python'} 02:57:46 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/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:57:46 INFO - mozversion application_buildid: 20170901083953 02:57:46 INFO - mozversion application_changeset: a3585c77e2b1bc5f5fea907e97762f7b47a12033 02:57:46 INFO - mozversion application_display_name: Nightly 02:57:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:57:46 INFO - mozversion application_name: Firefox 02:57:46 INFO - mozversion application_remotingname: firefox 02:57:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:57:46 INFO - mozversion application_vendor: Mozilla 02:57:46 INFO - mozversion application_version: 57.0a1 02:57:46 INFO - mozversion platform_buildid: 20170901083953 02:57:46 INFO - mozversion platform_changeset: a3585c77e2b1bc5f5fea907e97762f7b47a12033 02:57:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:57:46 INFO - mozversion platform_version: 57.0a1 02:57:46 INFO - using testdate: 1504259866 02:57:46 INFO - actual date: 1504259866 02:57:46 INFO - starting webserver on 'localhost:33826' 02:57:46 INFO - SUITE-START | Running 2 tests 02:57:46 INFO - TEST-START | dromaeo_css 02:57:46 INFO - Initialising browser for dromaeo_css test... 02:57:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33826/getInfo.html -profile /tmp/tmpIAxc0N/profile 02:57:46 INFO - TEST-INFO | started process 13413 (/builds/slave/test/build/application/firefox/firefox http://localhost:33826/getInfo.html) 02:57:51 INFO - TEST-INFO | 13413: exit 0 02:57:51 INFO - Browser initialized. 02:57:51 INFO - Running cycle 1/1 for dromaeo_css test... 02:57:51 INFO - Using env: {'DISPLAY': ':0', 02:57:51 INFO - 'HOME': '/home/cltbld', 02:57:51 INFO - 'JSGC_DISABLE_POISONING': '1', 02:57:51 INFO - 'LANG': 'en_US.UTF-8', 02:57:51 INFO - 'LANGUAGE': 'en_US:en', 02:57:51 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:57:51 INFO - 'LOGNAME': 'cltbld', 02:57:51 INFO - 'MAIL': '/var/mail/cltbld', 02:57:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:57:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:57:51 INFO - 'MOZ_CRASHREPORTER': '1', 02:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:51 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:57:51 INFO - 'MOZ_NO_REMOTE': '1', 02:57:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:57:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:51 INFO - 'NO_EM_RESTART': '1', 02:57:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:51 INFO - 'PWD': '/builds/slave/test', 02:57:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:57:51 INFO - 'RUST_BACKTRACE': 'full', 02:57:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:57:51 INFO - 'SHELL': '/bin/bash', 02:57:51 INFO - 'SHLVL': '1', 02:57:51 INFO - 'TERM': 'linux', 02:57:51 INFO - 'TMOUT': '86400', 02:57:51 INFO - 'USER': 'cltbld', 02:57:51 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 02:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:51 INFO - '_': '/tools/buildbot/bin/python'} 02:57:51 INFO - TEST-INFO | started process 13599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIAxc0N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:57:52 INFO - PID 13599 | 02:57:52 INFO - PID 13599 | (/builds/slave/test/build/application/firefox/firefox:13651): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:57:52 INFO - PID 13599 | 02:58:02 INFO - PID 13599 | RSS: Main: 164216832 02:58:02 INFO - PID 13599 | 03:01:01 INFO - PID 13599 | Cycle 1(1): loaded http://localhost:33826/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33826/tests/dromaeo/cssquery-ext.html) 03:01:03 INFO - PID 13599 | RSS: Main: 165629952 03:01:03 INFO - PID 13599 | 03:04:04 INFO - PID 13599 | Cycle 1(1): loaded http://localhost:33826/tests/dromaeo/cssquery-ext.html (next: http://localhost:33826/tests/dromaeo/cssquery-jquery.html) 03:04:05 INFO - PID 13599 | RSS: Main: 162238464 03:04:05 INFO - PID 13599 | 03:07:06 INFO - PID 13599 | Cycle 1(1): loaded http://localhost:33826/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33826/tests/dromaeo/cssquery-mootools.html) 03:07:07 INFO - PID 13599 | RSS: Main: 164864000 03:07:07 INFO - PID 13599 | 03:10:09 INFO - PID 13599 | Cycle 1(1): loaded http://localhost:33826/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33826/tests/dromaeo/cssquery-prototype.html) 03:10:09 INFO - PID 13599 | RSS: Main: 162697216 03:10:09 INFO - PID 13599 | 03:13:13 INFO - PID 13599 | Cycle 1(1): loaded http://localhost:33826/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33826/tests/dromaeo/cssquery-yui.html) 03:13:13 INFO - PID 13599 | RSS: Main: 164237312 03:13:13 INFO - PID 13599 | 03:16:15 INFO - PID 13599 | Cycle 1(1): loaded http://localhost:33826/tests/dromaeo/cssquery-yui.html (next: http://localhost:33826/tests/dromaeo/cssquery-dojo.html) 03:16:15 INFO - PID 13599 | RSS: Main: 166535168 03:16:15 INFO - PID 13599 | 03:16:15 INFO - PID 13599 | __start_tp_report 03:16:15 INFO - PID 13599 | _x_x_mozilla_page_load 03:16:15 INFO - PID 13599 | _x_x_mozilla_page_load_details 03:16:15 INFO - PID 13599 | |i|pagename|runs| 03:16:15 INFO - PID 13599 | |0;dojo.html;3899.1811719538896;3918.138009637879;3929.705272104591;3933.7639741615503;3957.1887763008585;6824.249332573419;6838.213605435377;6890.310968903107;6940.965295173517;6944.09726735525;6939.791806245808;6950.7914762557075;7013.43892488601;7030.507864449491;7050.647467626611;6159.8768024639485;6162.537809664271;6235.501159907197;6236.314005459396;6267.122602835607;6093.969530152343;6175.47508461779;6256.249250089995;6261.780837670683;6267.310595834455;6111.174991376174;6111.449969502722;6214.720337584819;6214.720337584819;6236.469900058501;6066.8786624267705;6152.5693201475915;6169.043798211284;6244.188255526752;6259.467945224661;6136.9079463808075;6137.754489820401;6156.322804491502;6162.137300777895;6185.907211391833;6955.374016338554;6973.37239648429;6980.616066116361;6996.370326670573;7011.544249623758;7450.701971921117;7461.253874612496;7463.104427468738;7580.317771400545;7585.924140758577;7524.134724506697;7538.283863033003;7609.048868891388;7629.847403051908;7636.274553917369;6856.52004359695;6936.687849046805;6943.756968506129;6958.756443524503;6959.547629404123;7450.552966822008;7635.465517413839;7645.350145237717;7746.728864489716;7754.9224507755325;7365.484416090822;7458.216887226763;7479.102507699002;7483.775486735407;7503;7476.738314158978;7486.176520582731;7497.2877576630135;7590.316871481593;7606.695732170651;7235.782926512213;7357.043584334002;7391.704331826774;7409.740659076906;7431.071116110486;7399.926000740032;7495.063117110361;7585.58279294633;7586.127595326608;7608;6335.809925702236;6365.140706004723;6430.453411460078;6442.387973142544;6460.418562329319;5454.836354909359;5472;5495.505404513613;5554.361248456479;5570.972145139248;6315.747370105144;6361.840953976188;6368.172137622079;6368.777092801823;6389.456896163785;7286.854262914712;7359.926400735924;7450.441216908754;7451.85096298071;7457.216992215739;6192.938070619236;6225.315215326311;6294.370562943669;6308.495320374359;6380.361963803675;6691.899621505681;6721.059051732761;6771.153605799275;6774.72901083961;6776.864462710718;7192.7122915083855;7231.45764067697;7256.6734496947765;7295.161056478573;7323.670434830446;7174.713011479483;7345.8898116527225;7365.594892280762;7389.076365454318;7400.888986665312;7250.057492525938;7275.054242948383;7275.563466192046;7277.672504737194;7279.963600181965;7344.853102938018;7389.778306650809;7439.442041846775;7480.700771969278;7521.962390188014;7549.7357592483995;7553.7356192533;7622.885656715264;7630.542167469969;7632.19861914491;7185.928140718526;7195.496315257882;7203.927960720326;7310.488265821342;7335.083114610674;7037.648117593997;7140.892886606601;7156.320149585781;7164;7164.928350716426;7104.218535961144;7111.964440177766;7122.002919591365;7131.82170445743;7145.678444469907;7151.82120446993;7197;7204.135503739585;7283.126024877049;7312.634368281459;7082.681279342545;7091.290870912868;7164.928350716426;7165.140183178052;7168.247334029852 03:16:15 INFO - PID 13599 | |1;ext.html;907.959141838617;972.6936015155226;1001.8397056470961;1364.5292374130927;1384.1487485196608;44251.557484425146;45289.77355113228;45349.7732511337;45351.31973020407;45403.31895021578;43234;43665.78167109168;43686.907827304334;43734.12531749371;43735.12529749403;7289.343959043672;7362.37419819316;7363.77908662741;7364.226756190603;7365.447591430638;7395.926040739604;7472.813179670525;7497.362724168425;7499.325060744532;7501.212372700869;7823.5305881647;7924.088729796061;7924.524528528278;7930.088039875402;7930.881036784453;7805.180456052118;7893.289603935645;7913.643886025143;7923.445358824884;7928.207179282025;7797;7804.804879878048;7995.160508146677;8001.559914204717;8011;40437.59562404397;40752.59247407547;40787.59212407868;40804;40877.795611022055;3536.717062634983;3578.6063533011347;3590.174259920207;3591.048372181374;3591.6947059499976;33548.83225583856;33597.6640233597;33654.83172584146;33675.831620841986;33714.83142584296;5383.757730902119;5451.890962180773;5471.23402723619;5503.422140675211;5520.889582208373;217926;220517.8974105135;222217;222860;224261;52356.214656780176;52733;52812.20781688278;52839.471605283456;52987.73506132445;54751;55215.72392138014;55335.44664553383;55393.44606553883;55670.72164639232;16854.07302598344;17332.740008899877;17338.219780109925;17338.959662420297;17340.566485837702;14144.080634758708;14156.787648185285;14160.433582656784;14165.433382664578;14170.504032358818;13602.863971360357;13788.655283617789;15443.918925675094;15458.149801761012;15712.528624141294;5850.0932355484965;6059;6074.331823499411;6086.60437071589;6119.143319935212;1107.1917500224881;1111.994440027811;1117.5697356517799;1123.5955056179769;1129.8700649425257;25667.229983100537;25762.22713318566;25859.74140258611;25869.482610347688;25937.74062259391;1197.7424853656332;1212.4362171590253;1230.926144431337;1242.0932719115096;1257.7170136719164;13400.329983500786;13449.125806822525;13555.118917270345;13739.381727822272;13813.309334533233;307946;308454;309520;309805;310194;56496.71751641216;56567.43432565704;56904.43095568991;57378.426215738145;57454.138187927216;1469.6399382151442;1537.4465192530904;1543.5832325272113;1546.2423412528005;1550.70536598046;24874.837151029555;24927.753612319673;24940.75059249384;25191;25196.622050669623;40266.597334026286;40267.1946561073;40281.79859100686;40416.79791601023;40426.191476169726;232736;233432;233557;233647;233841;36453.81773091118;36705.63294367022;36795.44806827846;36873.63126368702;37088.443673345464;46068.30897536607;49088.2636760456;50778.49221507738;51018.74490627523;51054.23418648649;35940;36280.27439451249;37119.44320835244;37175.81412092922;37472.62527374691;36532.269354613294;36542.8172859134;37041.62958370382;37135.628643713215;37643.247135057696;42159;43662.34506482469;43738.34392484052;43771.56228437675;43836;36043.27913441664;36332.636673633984;36368.636313636525;36572;36758.816205918796;26607.33481662974;27545.035923742573;27618.447631047668;27710.307242318297;27893.302667433476 03:16:15 INFO - PID 13599 | |2;jquery.html;561.4469747298913;582.6853499110481;584.6813486649776;586.4282324733385;607.8115784106926;23205;23496.412589685267;23822.523549529;23874.52250954982;23965.04139834409;27658.723412765867;28031.29921751952;28035.719642803564;28041.158765237076;28115.85942070287;6642.5350225484235;6779.423748981342;6791.76228831991;6800.591964482147;6804.3876051155385;6434.292227854932;6714.63069531178;6726.562773419712;6797;6850.931490685104;6667.133272674546;6667.466602671775;6672.9666351668175;6674.532782705212;6683.899741503881;6664.700088495981;6666.200055993312;6678.398944095031;6681.799546013627;6683.064370988041;6212.81361559154;6216.502679785606;6216.533759968021;6218.689065546704;6220.751169953196;31581.84209078963;31954.52068218955;32307.51538726921;32442.837785811156;32538.511922320948;2069.3895300886275;2090.644590419632;2096.643570593003;2096.863703859244;2097.276439628326;20654.07056682453;21313;21325.36023919285;21332.146714131417;21339;2478.1698131126095;2542.453372524916;2593.5850263957864;2609.699884513267;2618.7904967602544;161028.19485902612;164660;165254;165415;165747.17126414413;16052.154002289886;16286.26711797972;16436.260368283216;16737.16314184286;16746.916265418597;25203.495930081295;26242.212733618024;26350.86824565865;26411.07561235386;26497.602535961974;10791.83812242817;10913.890861091446;11007.61473348429;11250.212485126127;11347;10465.843012354668;10476.109530689824;10515.211359147941;10518.894811051945;10698.41158736277;9579.856302155473;9848.113669769611;10043.744531933507;10043.932207455991;10239.59041638341;2721.333273348003;2831.8725657345467;2837.609057176654;2920.4159168166025;2983.8358890261243;1065.3181963543338;1075.0754351257913;1078.245228340165;1088.8638920134983;1090.1006669497697;22316.442088947908;22700.772992270195;22706.43233919165;23114.8844255781;23121.421964451023;1075.6772968109535;1142.3716955674347;1149.8792626774236;1158.1140427572914;1158.9072874170047;12122.757544849053;12541.247525148521;12614.684632884251;12702.872971270353;12760.6809829755;653223;653275;655879;663269;668403;129110.35444822715;133266;136673;138822;139421.30289348488;1310.7902735562263;1319.280991859439;1347.3936728472227;1348.1978222957325;1348.8179095822134;19715;19841.900790495954;19936.700949486058;20475.897620512398;20541.178352865718;61288.38711613005;61545.692271538355;61940.69029654823;61976.69011654913;64476.355236448915;79114;79315;79389;79822;80964.59517702374;15958.281877315316;16108.27512761905;16147.192640367699;16293.51119466418;16317.18414079315;25495.617565736156;25674.871625641754;25778.226653200432;25828.35429114212;25864.8706756465;20666.070026849127;20799.584008320053;20953.790462095185;21232.150713971267;21265.149394024684;17954.371596994042;18002;18204.36284730028;18252.81747182511;18316.908415457838;19565.902170489055;19704.605907881476;20026.399208023784;20870;21058.684119738522;15911.68176636484;15928.123953182223;15928.123953182687;15939.203039847729;15975.520734377988;20155.39533813988;20460.181592736124;20460.79539204589;20590.073446695234;20690.689639655717 03:16:15 INFO - PID 13599 | |3;mootools.html;1455.7088582283543;1501.2193659297166;1510.6223444138966;1525.328855303667;1534.0565552185406;16450.506484805475;16861.578460538494;17562.736558951627;17586.648267034652;17611.295548178074;17473.65052698945;17768.555786105353;17774.64450710985;17785.288588456446;17790.64418711625;2869.282679330173;2929.3701854101364;2929.5166297560877;2930;2931.1792698044583;2752.477029364414;2786.7352601502926;2824.2939265183704;2828.7312705292966;2987.4622567937763;2901.651801783789;2938.867750951212;2939.720726530976;2940.2502361897687;2941.161768895865;2781.805273630847;2800.229936767385;2821.5203415419223;2826.2086615747626;2846.8007239493245;2714.443539074485;2752.1055656911385;2754.0085569195076;2754.256350785277;2755.5040092783292;16874.240659170246;16980.15099245033;17060;17103.401380951607;17205.569860753458;8652.740417787476;8918.777030574223;8930.642774289021;8930.955345223232;8942.821143577092;5624.690642014688;5835.503982161521;5982.641041537521;6012.488938440238;6081.3006504252135;2129.7124888140056;2153.6231159547015;2196.1544805249932;2220.3117033719436;2230.82153427727;31089.689103108907;31395.529067063784;31466;31510.52734208989;31529;7261.709531618676;7380.225076367012;7462.589557574385;7591.4685971981435;7618.657160427794;19662;19692.606147876962;19717.211311547664;19722.408327750192;19734.111964961612;8599.484030958163;8772.956135219283;8778.341624378198;8778.824423511494;8783.472991620523;7979.680812767424;8143.42995990275;8168.469049511763;8178.67285308581;8201.13888041759;5699.572532060112;5733.569982251348;5750.1949727038245;5815.2149459822385;5845.473907348359;1548.4270819796748;1557.6573153906122;1655.4702495201661;1660.5599516128236;1683.9326426942785;990.094063931509;1006.06938581813;1019.4087429290994;1022.7187523431116;1035.7307100154014;9521.809563808823;9612.51937403141;9681.177099946442;9757.024297570328;9789.559469823875;1053.2521909444226;1084.2735367303926;1087.260383386575;1102.1843835561626;1108.3737688206138;5779.537636989031;5930.584859059911;5937.2578427696535;6002.339742628307;6446.387593178734;24236.515269694508;24510.264692059267;25103.246902592953;25474.363140921254;25477.872610636827;23788.762112379;24839.50320993606;24895.5020899581;24982.750172498043;25034;12185.878141218474;12434.56479023248;12514;12589.30758808256;12675.619731408073;9749.951250243987;9903.752406189902;9941.453220072965;10070.546825392728;10712.089472394933;20922;21119;21312.254071107436;21582.244621438174;21875.562488749816;22088.44778880541;22644.886775566018;22650.320490385315;22673.659895101257;23101.88449057744;13278.468861245829;13570.253636050113;13579.117357371739;13588.79616805729;13595.79606305926;15355.539333820003;15527.146006969273;15600.84399155994;15707.528774136794;15712.292946817191;12575.245485270912;12660.746785064432;12683.11218214716;12968.35158242103;13095.214287142802;12351.93824030874;12539.37303134858;12569.183003104768;12581.245125292511;12755.681107972376;11250.718732031764;11253.887461125283;11361.43192840338;11383.829242561535;11392.316461012366;8444.535550544779;8492.660293588435;8531.189536993976;8614.913850861662;8641.308695304235;8586.914130858611;8741.213290803858;8751.781205469913;8789.296856251356;8924.776880578038 03:16:15 INFO - PID 13599 | |4;prototype.html;532.7575952941412;536.4045909040964;565.869849934515;566.8072855229221;572.2560671127533;6736;6764.932350676492;6775.188296856811;6798.932010679891;6835.589864608127;6496.187976502937;6630.535862489628;6654.600723956554;6666.966665166679;6683.198016238046;5280.231122573942;5280.366356037281;5335.359756829186;5383.43473935237;5404.756785944623;5230.437085827936;5255.058705970522;5257.868553286179;5299.28459657947;5553.972230138844;5473.726313684319;5505;5521.365043020044;5539.14143307788;5549.417311182328;5452.263944367499;5533.612647114704;5539.086050801613;5550.057479882049;5554.2779438673115;5219.1993762152;5221.347331583552;5237.319148510707;5278.868028299285;5309.840704778863;6613.090240294255;6696.933030669679;6716.194056713177;6758.763443279511;6766;6495.447886929569;6502.148066739925;6547.9017814732815;6583.572067815577;6664.333566643345;7202.4958252922315;7219.639018049129;7291.197968223491;7324.340809327186;7336.266373362674;5865.413458654143;5900.1739756434135;5903.645781253095;5917.408259174092;5918.053111502182;7541.924580754232;7599.316061554376;7615.80960475987;7708.190639982777;7754.224577542257;5984.880302393928;6029.88447137281;6088.756449742049;6099.481544068715;6156.138140660311;6146.323904370515;6168.599041062316;6182.1345011698395;6188.659623720739;6231.2522497300615;6545.8363540910905;6577.375149360803;6647.767328143492;6690.59856408617;6702.463802895757;6895.862082758316;7189.5686258824635;7248.383887369527;7250.492465527363;7352.338289553966;2069.2964392106755;2069.430906500694;2112.904919278636;2130.2970019893396;2142.967855482169;1544.5675210940954;1556.7976163098724;1583.4616230481693;1591.2282542966884;1631.2414727151931;6044.879102417927;6049.758009679564;6052.909206361908;6093.360197179321;6190.350013248634;7093.751718689821;7147.499675022698;7279.708811647475;7283.162436319785;7293.927060729325;6141.140240366352;6158.476529494954;6209.405040270168;6214.706205710981;6215.658138802319;3769.8115094245177;3813.256414999104;3825.158465137665;3880.9133442635543;3903.2974064668633;6911.827204319831;6997.790066298019;7007.474439417064;7076.292370762985;7105.147382314155;7474.5888976106835;7487.80682817613;7492.587907665131;7534.924650753423;7572.5835079070075;6925.68834402443;6943.375096241362;7006.264342244093;7057.50597458173;7082;7111.715531378687;7114.430845532448;7177.174624917992;7180.640967951477;7279.235680253497;7131.607761573163;7184.9640751795905;7214.531055481377;7253.637318134178;7289.234630363735;7180.246074162138;7217.927820721936;7247.637618119178;7258.3104605062435;7263.237360077116;5506.421825708403;5541.695206763667;5556.833295001156;5587.273654424899;5615.606907516435;7029.753958611424;7089.503734738519;7118.822029449305;7242.746503872339;7325;6915.308469153045;6930.341617546526;6979.30206979298;7069.575825450489;7113.399485115808;6962;6964.582125072512;6993.580385176906;7021.192562855133;7029.261927497743;7007.544509606859;7064.066184405125;7072.469564782765;7099.290070992859;7102.05264815096;6986.16166060076;7082.960839447162;7104.715811367694;7129.429645628234;7129.500934934505;6829.385355317847;6882.552634078769;6906.758263460755;6915.51591388598;6927.618980955895 03:16:15 INFO - PID 13599 | |5;yui.html;991.9057689519498;996.72091814292;1023.8617786598807;1038.3665963762105;1038.9688309350722;18099.99999999998;18994.335198268065;19001.524961875977;19049.42851714449;20962.475938101554;19796;19802.405927822187;21458.678119828215;22042.88978555109;22121;1063.1176123817233;1092.2136062035322;1092.6667366453228;1092.808758467269;1092.8142215823289;1011.893751156129;1101.0695961912172;1104.6078642082066;1106.391484683425;1113.048343666166;1140.0879296562757;1140.5095808802212;1140.680609429357;1142.5772464188262;1147.506572173965;1134.8354488599148;1138.9202755807096;1142.4858813533942;1143.0284258380352;1143.4282858570714;842.1073661918387;842.6250318608222;842.7092653034694;842.7640260727006;842.8145807922309;19578.804211957842;19928.70106948397;20105.39683809488;20167.697484537744;20337.694934575993;23033.539329213323;23470.882645586662;23764.168254111195;23998.640020399707;24033.87983060091;18586;18604.62790744191;18733;19023.61952760937;19131.904340478348;4050.898727531806;4075.510938687347;4077.062275676611;4077.1641813428178;4078.082431452929;148186.25906870505;148660.2566987169;149047;149545;151477;18088.372046515877;18172.636547269245;18306.725399119023;18479.907600461913;18654.253829846653;34213.48679769805;34530.654693453245;34974.300513990085;35187.4721879172;35240.118997024765;8558.144185581392;8904.109589041043;9587.424754514752;10128.949355253177;10304;4456.353828694886;4520.954790452053;4546.227141385973;4559.065391594715;4561.794719237642;6156.661383623854;6449.6130232186215;6777.423918966844;6815.897761533581;6818.8636227276165;1309.8100775387516;1330.2351148089888;1339.4575197045212;1350.6825895914453;1354.939027743754;734.5225603357753;742.9145648250412;745.1803016681607;753.7286576832336;755.1957165618599;12482.687932801753;12933.348663523955;14957;15161.469348572748;15386.692266154614;754.8943147959291;785.0500893918309;785.4187900953368;786.0959896119394;793.0760663826693;6182.598131121463;6460.806175814733;6657.633830139292;6672.633005184664;6774.796756097325;124459;134015.32992334975;134089.32955335357;134423.32788336193;139351;108207;109742;109782;110990.44504777425;111150;105033.47483262687;105738;105903;107471.46264268628;111336.4433177845;12343.567975120828;12380.380980950737;12530.248185108923;12694.555690550786;12698.111132220656;51375.743121285646;52461.21308180457;52902.20646690226;53094.73452632712;53613.731931340095;82213;85432.57283713542;86837.56581217053;86906.56546717226;93398.53300733452;21343.35969920905;21457.141714331876;21492;21555.568888622453;21688;23014.19450319231;23069.88465057664;24164.87917560401;24482.51034979255;24925.75074249307;21837.344879653636;22206.66689999619;22216.333509994725;22256.109755610243;22269.109235630393;20679.379618611467;21213.15147394087;21348.89325553362;21453.570928581652;21646.783532164478;20491.79508204899;20874.269400570905;21197.894010530465;21322.57354852863;21606;14951.925240373728;15243.847561524686;15527.922360388126;15833.33749956284;16201.75697364564;16432.671346572763;16477.34090636361;16506.092164931044;16547.420840270534;16683.416080437128 03:16:15 INFO - PID 13599 | __end_tp_report 03:16:15 INFO - PID 13599 | __start_cc_report 03:16:15 INFO - PID 13599 | _x_x_mozilla_cycle_collect,335 03:16:15 INFO - PID 13599 | __end_cc_report 03:16:15 INFO - PID 13599 | __startTimestamp1504260975780__endTimestamp 03:16:15 INFO - PID 13599 | 03:16:15 INFO - PID 13599 | ------- Summary: start ------- 03:16:15 INFO - PID 13599 | Number of tests: 6 03:16:15 INFO - PID 13599 | 03:16:15 INFO - PID 13599 | [#0] dojo.html Cycles:165 Average:6871.69 Median:7140.89 stddev:759.90 (10.6%) stddev-sans-first:725.57 03:16:15 INFO - PID 13599 | Values: 3899.2 3918.1 3929.7 3933.8 3957.2 6824.2 6838.2 6890.3 6941.0 6944.1 6939.8 6950.8 7013.4 7030.5 7050.6 6159.9 6162.5 6235.5 6236.3 6267.1 6094.0 6175.5 6256.2 6261.8 6267.3 6111.2 6111.4 6214.7 6214.7 6236.5 6066.9 6152.6 6169.0 6244.2 6259.5 6136.9 6137.8 6156.3 6162.1 6185.9 6955.4 6973.4 6980.6 6996.4 7011.5 7450.7 7461.3 7463.1 7580.3 7585.9 7524.1 7538.3 7609.0 7629.8 7636.3 6856.5 6936.7 6943.8 6958.8 6959.5 7450.6 7635.5 7645.4 7746.7 7754.9 7365.5 7458.2 7479.1 7483.8 7503.0 7476.7 7486.2 7497.3 7590.3 7606.7 7235.8 7357.0 7391.7 7409.7 7431.1 7399.9 7495.1 7585.6 7586.1 7608.0 6335.8 6365.1 6430.5 6442.4 6460.4 5454.8 5472.0 5495.5 5554.4 5571.0 6315.7 6361.8 6368.2 6368.8 6389.5 7286.9 7359.9 7450.4 7451.9 7457.2 6192.9 6225.3 6294.4 6308.5 6380.4 6691.9 6721.1 6771.2 6774.7 6776.9 7192.7 7231.5 7256.7 7295.2 7323.7 7174.7 7345.9 7365.6 7389.1 7400.9 7250.1 7275.1 7275.6 7277.7 7280.0 7344.9 7389.8 7439.4 7480.7 7522.0 7549.7 7553.7 7622.9 7630.5 7632.2 7185.9 7195.5 7203.9 7310.5 7335.1 7037.6 7140.9 7156.3 7164.0 7164.9 7104.2 7112.0 7122.0 7131.8 7145.7 7151.8 7197.0 7204.1 7283.1 7312.6 7082.7 7091.3 7164.9 7165.1 7168.2 03:16:15 INFO - PID 13599 | 03:16:15 INFO - PID 13599 | [#1] ext.html Cycles:180 Average:43593.21 Median:36957.63 stddev:67122.28 (181.6%) stddev-sans-first:67234.05 03:16:15 INFO - PID 13599 | Values: 908.0 972.7 1001.8 1364.5 1384.1 44251.6 45289.8 45349.8 45351.3 45403.3 43234.0 43665.8 43686.9 43734.1 43735.1 7289.3 7362.4 7363.8 7364.2 7365.4 7395.9 7472.8 7497.4 7499.3 7501.2 7823.5 7924.1 7924.5 7930.1 7930.9 7805.2 7893.3 7913.6 7923.4 7928.2 7797.0 7804.8 7995.2 8001.6 8011.0 40437.6 40752.6 40787.6 40804.0 40877.8 3536.7 3578.6 3590.2 3591.0 3591.7 33548.8 33597.7 33654.8 33675.8 33714.8 5383.8 5451.9 5471.2 5503.4 5520.9 217926.0 220517.9 222217.0 222860.0 224261.0 52356.2 52733.0 52812.2 52839.5 52987.7 54751.0 55215.7 55335.4 55393.4 55670.7 16854.1 17332.7 17338.2 17339.0 17340.6 14144.1 14156.8 14160.4 14165.4 14170.5 13602.9 13788.7 15443.9 15458.1 15712.5 5850.1 6059.0 6074.3 6086.6 6119.1 1107.2 1112.0 1117.6 1123.6 1129.9 25667.2 25762.2 25859.7 25869.5 25937.7 1197.7 1212.4 1230.9 1242.1 1257.7 13400.3 13449.1 13555.1 13739.4 13813.3 307946.0 308454.0 309520.0 309805.0 310194.0 56496.7 56567.4 56904.4 57378.4 57454.1 1469.6 1537.4 1543.6 1546.2 1550.7 24874.8 24927.8 24940.8 25191.0 25196.6 40266.6 40267.2 40281.8 40416.8 40426.2 232736.0 233432.0 233557.0 233647.0 233841.0 36453.8 36705.6 36795.4 36873.6 37088.4 46068.3 49088.3 50778.5 51018.7 51054.2 35940.0 36280.3 37119.4 37175.8 37472.6 36532.3 36542.8 37041.6 37135.6 37643.2 42159.0 43662.3 43738.3 43771.6 43836.0 36043.3 36332.6 36368.6 36572.0 36758.8 26607.3 27545.0 27618.4 27710.3 27893.3 03:16:15 INFO - PID 13599 | 03:16:15 INFO - PID 13599 | [#2] jquery.html Cycles:180 Average:42155.81 Median:21328.75 stddev:110096.08 (516.2%) stddev-sans-first:110360.63 03:16:15 INFO - PID 13599 | Values: 561.4 582.7 584.7 586.4 607.8 23205.0 23496.4 23822.5 23874.5 23965.0 27658.7 28031.3 28035.7 28041.2 28115.9 6642.5 6779.4 6791.8 6800.6 6804.4 6434.3 6714.6 6726.6 6797.0 6850.9 6667.1 6667.5 6673.0 6674.5 6683.9 6664.7 6666.2 6678.4 6681.8 6683.1 6212.8 6216.5 6216.5 6218.7 6220.8 31581.8 31954.5 32307.5 32442.8 32538.5 2069.4 2090.6 2096.6 2096.9 2097.3 20654.1 21313.0 21325.4 21332.1 21339.0 2478.2 2542.5 2593.6 2609.7 2618.8 161028.2 164660.0 165254.0 165415.0 165747.2 16052.2 16286.3 16436.3 16737.2 16746.9 25203.5 26242.2 26350.9 26411.1 26497.6 10791.8 10913.9 11007.6 11250.2 11347.0 10465.8 10476.1 10515.2 10518.9 10698.4 9579.9 9848.1 10043.7 10043.9 10239.6 2721.3 2831.9 2837.6 2920.4 2983.8 1065.3 1075.1 1078.2 1088.9 1090.1 22316.4 22700.8 22706.4 23114.9 23121.4 1075.7 1142.4 1149.9 1158.1 1158.9 12122.8 12541.2 12614.7 12702.9 12760.7 653223.0 653275.0 655879.0 663269.0 668403.0 129110.4 133266.0 136673.0 138822.0 139421.3 1310.8 1319.3 1347.4 1348.2 1348.8 19715.0 19841.9 19936.7 20475.9 20541.2 61288.4 61545.7 61940.7 61976.7 64476.4 79114.0 79315.0 79389.0 79822.0 80964.6 15958.3 16108.3 16147.2 16293.5 16317.2 25495.6 25674.9 25778.2 25828.4 25864.9 20666.1 20799.6 20953.8 21232.2 21265.1 17954.4 18002.0 18204.4 18252.8 18316.9 19565.9 19704.6 20026.4 20870.0 21058.7 15911.7 15928.1 15928.1 15939.2 15975.5 20155.4 20460.2 20460.8 20590.1 20690.7 03:16:15 INFO - PID 13599 | 03:16:15 INFO - PID 13599 | [#3] mootools.html Cycles:180 Average:10684.33 Median:24939.13 stddev:7801.27 (31.3%) stddev-sans-first:7792.34 03:16:15 INFO - PID 13599 | Values: 1455.7 1501.2 1510.6 1525.3 1534.1 16450.5 16861.6 17562.7 17586.6 17611.3 17473.7 17768.6 17774.6 17785.3 17790.6 2869.3 2929.4 2929.5 2930.0 2931.2 2752.5 2786.7 2824.3 2828.7 2987.5 2901.7 2938.9 2939.7 2940.3 2941.2 2781.8 2800.2 2821.5 2826.2 2846.8 2714.4 2752.1 2754.0 2754.3 2755.5 16874.2 16980.2 17060.0 17103.4 17205.6 8652.7 8918.8 8930.6 8931.0 8942.8 5624.7 5835.5 5982.6 6012.5 6081.3 2129.7 2153.6 2196.2 2220.3 2230.8 31089.7 31395.5 31466.0 31510.5 31529.0 7261.7 7380.2 7462.6 7591.5 7618.7 19662.0 19692.6 19717.2 19722.4 19734.1 8599.5 8773.0 8778.3 8778.8 8783.5 7979.7 8143.4 8168.5 8178.7 8201.1 5699.6 5733.6 5750.2 5815.2 5845.5 1548.4 1557.7 1655.5 1660.6 1683.9 990.1 1006.1 1019.4 1022.7 1035.7 9521.8 9612.5 9681.2 9757.0 9789.6 1053.3 1084.3 1087.3 1102.2 1108.4 5779.5 5930.6 5937.3 6002.3 6446.4 24236.5 24510.3 25103.2 25474.4 25477.9 23788.8 24839.5 24895.5 24982.8 25034.0 12185.9 12434.6 12514.0 12589.3 12675.6 9750.0 9903.8 9941.5 10070.5 10712.1 20922.0 21119.0 21312.3 21582.2 21875.6 22088.4 22644.9 22650.3 22673.7 23101.9 13278.5 13570.3 13579.1 13588.8 13595.8 15355.5 15527.1 15600.8 15707.5 15712.3 12575.2 12660.7 12683.1 12968.4 13095.2 12351.9 12539.4 12569.2 12581.2 12755.7 11250.7 11253.9 11361.4 11383.8 11392.3 8444.5 8492.7 8531.2 8614.9 8641.3 8586.9 8741.2 8751.8 8789.3 8924.8 03:16:15 INFO - PID 13599 | 03:16:15 INFO - PID 13599 | [#4] prototype.html Cycles:180 Average:6083.93 Median:6699.70 stddev:1635.05 (24.4%) stddev-sans-first:1585.66 03:16:15 INFO - PID 13599 | Values: 532.8 536.4 565.9 566.8 572.3 6736.0 6764.9 6775.2 6798.9 6835.6 6496.2 6630.5 6654.6 6667.0 6683.2 5280.2 5280.4 5335.4 5383.4 5404.8 5230.4 5255.1 5257.9 5299.3 5554.0 5473.7 5505.0 5521.4 5539.1 5549.4 5452.3 5533.6 5539.1 5550.1 5554.3 5219.2 5221.3 5237.3 5278.9 5309.8 6613.1 6696.9 6716.2 6758.8 6766.0 6495.4 6502.1 6547.9 6583.6 6664.3 7202.5 7219.6 7291.2 7324.3 7336.3 5865.4 5900.2 5903.6 5917.4 5918.1 7541.9 7599.3 7615.8 7708.2 7754.2 5984.9 6029.9 6088.8 6099.5 6156.1 6146.3 6168.6 6182.1 6188.7 6231.3 6545.8 6577.4 6647.8 6690.6 6702.5 6895.9 7189.6 7248.4 7250.5 7352.3 2069.3 2069.4 2112.9 2130.3 2143.0 1544.6 1556.8 1583.5 1591.2 1631.2 6044.9 6049.8 6052.9 6093.4 6190.4 7093.8 7147.5 7279.7 7283.2 7293.9 6141.1 6158.5 6209.4 6214.7 6215.7 3769.8 3813.3 3825.2 3880.9 3903.3 6911.8 6997.8 7007.5 7076.3 7105.1 7474.6 7487.8 7492.6 7534.9 7572.6 6925.7 6943.4 7006.3 7057.5 7082.0 7111.7 7114.4 7177.2 7180.6 7279.2 7131.6 7185.0 7214.5 7253.6 7289.2 7180.2 7217.9 7247.6 7258.3 7263.2 5506.4 5541.7 5556.8 5587.3 5615.6 7029.8 7089.5 7118.8 7242.7 7325.0 6915.3 6930.3 6979.3 7069.6 7113.4 6962.0 6964.6 6993.6 7021.2 7029.3 7007.5 7064.1 7072.5 7099.3 7102.1 6986.2 7083.0 7104.7 7129.4 7129.5 6829.4 6882.6 6906.8 6915.5 6927.6 03:16:15 INFO - PID 13599 | 03:16:15 INFO - PID 13599 | [#5] yui.html Cycles:180 Average:27902.59 Median:20414.75 stddev:38547.94 (188.8%) stddev-sans-first:38603.12 03:16:15 INFO - PID 13599 | Values: 991.9 996.7 1023.9 1038.4 1039.0 18100.0 18994.3 19001.5 19049.4 20962.5 19796.0 19802.4 21458.7 22042.9 22121.0 1063.1 1092.2 1092.7 1092.8 1092.8 1011.9 1101.1 1104.6 1106.4 1113.0 1140.1 1140.5 1140.7 1142.6 1147.5 1134.8 1138.9 1142.5 1143.0 1143.4 842.1 842.6 842.7 842.8 842.8 19578.8 19928.7 20105.4 20167.7 20337.7 23033.5 23470.9 23764.2 23998.6 24033.9 18586.0 18604.6 18733.0 19023.6 19131.9 4050.9 4075.5 4077.1 4077.2 4078.1 148186.3 148660.3 149047.0 149545.0 151477.0 18088.4 18172.6 18306.7 18479.9 18654.3 34213.5 34530.7 34974.3 35187.5 35240.1 8558.1 8904.1 9587.4 10128.9 10304.0 4456.4 4521.0 4546.2 4559.1 4561.8 6156.7 6449.6 6777.4 6815.9 6818.9 1309.8 1330.2 1339.5 1350.7 1354.9 734.5 742.9 745.2 753.7 755.2 12482.7 12933.3 14957.0 15161.5 15386.7 754.9 785.1 785.4 786.1 793.1 6182.6 6460.8 6657.6 6672.6 6774.8 124459.0 134015.3 134089.3 134423.3 139351.0 108207.0 109742.0 109782.0 110990.4 111150.0 105033.5 105738.0 105903.0 107471.5 111336.4 12343.6 12380.4 12530.2 12694.6 12698.1 51375.7 52461.2 52902.2 53094.7 53613.7 82213.0 85432.6 86837.6 86906.6 93398.5 21343.4 21457.1 21492.0 21555.6 21688.0 23014.2 23069.9 24164.9 24482.5 24925.8 21837.3 22206.7 22216.3 22256.1 22269.1 20679.4 21213.2 21348.9 21453.6 21646.8 20491.8 20874.3 21197.9 21322.6 21606.0 14951.9 15243.8 15527.9 15833.3 16201.8 16432.7 16477.3 16506.1 16547.4 16683.4 03:16:15 INFO - PID 13599 | -------- Summary: end -------- 03:16:15 INFO - PID 13599 | 03:16:16 INFO - TEST-INFO | 13599: exit 0 03:16:16 INFO - TEST-OK | dromaeo_css | took 1109723ms 03:16:16 INFO - TEST-START | kraken 03:16:16 INFO - Initialising browser for kraken test... 03:16:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33826/getInfo.html -profile /tmp/tmpMHdCTd/profile 03:16:16 INFO - TEST-INFO | started process 13977 (/builds/slave/test/build/application/firefox/firefox http://localhost:33826/getInfo.html) 03:16:21 INFO - TEST-INFO | 13977: exit 0 03:16:21 INFO - Browser initialized. 03:16:21 INFO - Running cycle 1/1 for kraken test... 03:16:21 INFO - Using env: {'DISPLAY': ':0', 03:16:21 INFO - 'HOME': '/home/cltbld', 03:16:21 INFO - 'JSGC_DISABLE_POISONING': '1', 03:16:21 INFO - 'LANG': 'en_US.UTF-8', 03:16:21 INFO - 'LANGUAGE': 'en_US:en', 03:16:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:16:21 INFO - 'LOGNAME': 'cltbld', 03:16:21 INFO - 'MAIL': '/var/mail/cltbld', 03:16:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:16:21 INFO - 'MOZ_CRASHREPORTER': '1', 03:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:16:21 INFO - 'MOZ_NO_REMOTE': '1', 03:16:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:21 INFO - 'NO_EM_RESTART': '1', 03:16:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:21 INFO - 'PWD': '/builds/slave/test', 03:16:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:21 INFO - 'RUST_BACKTRACE': 'full', 03:16:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:16:21 INFO - 'SHELL': '/bin/bash', 03:16:21 INFO - 'SHLVL': '1', 03:16:21 INFO - 'TERM': 'linux', 03:16:21 INFO - 'TMOUT': '86400', 03:16:21 INFO - 'USER': 'cltbld', 03:16:21 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072', 03:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:21 INFO - '_': '/tools/buildbot/bin/python'} 03:16:21 INFO - TEST-INFO | started process 14170 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMHdCTd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:22 INFO - PID 14170 | 03:16:22 INFO - PID 14170 | (/builds/slave/test/build/application/firefox/firefox:14222): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:16:22 INFO - PID 14170 | 03:16:32 INFO - PID 14170 | RSS: Main: 162095104 03:16:32 INFO - PID 14170 | 03:16:35 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33826/tests/kraken/driver.html?testName=audio-beat-detection) 03:16:36 INFO - PID 14170 | RSS: Main: 166060032 03:16:36 INFO - PID 14170 | 03:16:38 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33826/tests/kraken/driver.html?testName=audio-dft) 03:16:39 INFO - PID 14170 | RSS: Main: 170643456 03:16:39 INFO - PID 14170 | 03:16:42 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33826/tests/kraken/driver.html?testName=audio-fft) 03:16:42 INFO - PID 14170 | RSS: Main: 163368960 03:16:42 INFO - PID 14170 | 03:16:44 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33826/tests/kraken/driver.html?testName=audio-oscillator) 03:16:45 INFO - PID 14170 | RSS: Main: 164876288 03:16:45 INFO - PID 14170 | 03:16:47 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33826/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:16:48 INFO - PID 14170 | RSS: Main: 164773888 03:16:48 INFO - PID 14170 | 03:16:51 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33826/tests/kraken/driver.html?testName=imaging-darkroom) 03:16:51 INFO - PID 14170 | RSS: Main: 165294080 03:16:51 INFO - PID 14170 | 03:16:55 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33826/tests/kraken/driver.html?testName=imaging-desaturate) 03:16:55 INFO - PID 14170 | RSS: Main: 166043648 03:16:55 INFO - PID 14170 | 03:16:58 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33826/tests/kraken/driver.html?testName=json-parse-financial) 03:16:59 INFO - PID 14170 | RSS: Main: 166334464 03:16:59 INFO - PID 14170 | 03:17:00 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33826/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:17:01 INFO - PID 14170 | RSS: Main: 166432768 03:17:01 INFO - PID 14170 | 03:17:02 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33826/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:17:03 INFO - PID 14170 | RSS: Main: 166903808 03:17:03 INFO - PID 14170 | 03:17:05 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33826/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:17:06 INFO - PID 14170 | RSS: Main: 163352576 03:17:06 INFO - PID 14170 | 03:17:08 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33826/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:17:09 INFO - PID 14170 | RSS: Main: 163160064 03:17:09 INFO - PID 14170 | 03:17:12 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33826/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:17:12 INFO - PID 14170 | RSS: Main: 163598336 03:17:12 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | Cycle 1(1): loaded http://localhost:33826/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33826/tests/kraken/driver.html?testName=ai-astar) 03:17:14 INFO - PID 14170 | RSS: Main: 165146624 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | __start_tp_report 03:17:14 INFO - PID 14170 | _x_x_mozilla_page_load 03:17:14 INFO - PID 14170 | _x_x_mozilla_page_load_details 03:17:14 INFO - PID 14170 | |i|pagename|runs| 03:17:14 INFO - PID 14170 | |0;ai-astar;99;105;98;105;106;105;106;98;107;109 03:17:14 INFO - PID 14170 | |1;audio-beat-detection;139;112;139;160;112;138;147;113;170;116 03:17:14 INFO - PID 14170 | |2;audio-dft;164;162;161;162;161;161;160;163;161;164 03:17:14 INFO - PID 14170 | |3;audio-fft;74;75;73;181;73;73;74;73;151;74 03:17:14 INFO - PID 14170 | |4;audio-oscillator;79;78;137;79;78;79;140;79;78;120 03:17:14 INFO - PID 14170 | |5;imaging-gaussian-blur;114;114;114;113;113;114;118;115;114;114 03:17:14 INFO - PID 14170 | |6;imaging-darkroom;151;149;150;150;149;150;149;151;150;150 03:17:14 INFO - PID 14170 | |7;imaging-desaturate;94;95;95;95;94;96;94;95;94;93 03:17:14 INFO - PID 14170 | |8;json-parse-financial;43;47;43;43;44;43;46;45;44;45 03:17:14 INFO - PID 14170 | |9;json-stringify-tinderbox;39;38;39;39;39;39;39;40;39;39 03:17:14 INFO - PID 14170 | |10;stanford-crypto-aes;72;74;70;71;75;72;71;72;72;74 03:17:14 INFO - PID 14170 | |11;stanford-crypto-ccm;112;121;118;118;113;112;113;109;109;147 03:17:14 INFO - PID 14170 | |12;stanford-crypto-pbkdf2;162;163;154;153;159;162;158;156;158;155 03:17:14 INFO - PID 14170 | |13;stanford-crypto-sha256-iterative;59;59;64;59;57;61;58;62;58;65 03:17:14 INFO - PID 14170 | __end_tp_report 03:17:14 INFO - PID 14170 | __start_cc_report 03:17:14 INFO - PID 14170 | _x_x_mozilla_cycle_collect,918 03:17:14 INFO - PID 14170 | __end_cc_report 03:17:14 INFO - PID 14170 | __startTimestamp1504261034724__endTimestamp 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | ------- Summary: start ------- 03:17:14 INFO - PID 14170 | Number of tests: 14 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#0] ai-astar Cycles:10 Average:103.80 Median:108.00 stddev:3.97 (3.7%) stddev-sans-first:3.81 03:17:14 INFO - PID 14170 | Values: 99.0 105.0 98.0 105.0 106.0 105.0 106.0 98.0 107.0 109.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:139.00 stddev:20.91 (15.0%) stddev-sans-first:22.12 03:17:14 INFO - PID 14170 | Values: 139.0 112.0 139.0 160.0 112.0 138.0 147.0 113.0 170.0 116.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.37 (0.8%) stddev-sans-first:1.22 03:17:14 INFO - PID 14170 | Values: 164.0 162.0 161.0 162.0 161.0 161.0 160.0 163.0 161.0 164.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#3] audio-fft Cycles:10 Average:92.10 Median:73.50 stddev:39.59 (53.9%) stddev-sans-first:41.45 03:17:14 INFO - PID 14170 | Values: 74.0 75.0 73.0 181.0 73.0 73.0 74.0 73.0 151.0 74.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:78.50 stddev:26.47 (33.7%) stddev-sans-first:27.46 03:17:14 INFO - PID 14170 | Values: 79.0 78.0 137.0 79.0 78.0 79.0 140.0 79.0 78.0 120.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.42 (1.2%) stddev-sans-first:1.50 03:17:14 INFO - PID 14170 | Values: 114.0 114.0 114.0 113.0 113.0 114.0 118.0 115.0 114.0 114.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:150.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 03:17:14 INFO - PID 14170 | Values: 151.0 149.0 150.0 150.0 149.0 150.0 149.0 151.0 150.0 150.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 03:17:14 INFO - PID 14170 | Values: 94.0 95.0 95.0 95.0 94.0 96.0 94.0 95.0 94.0 93.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#8] json-parse-financial Cycles:10 Average:44.30 Median:44.50 stddev:1.42 (3.2%) stddev-sans-first:1.42 03:17:14 INFO - PID 14170 | Values: 43.0 47.0 43.0 43.0 44.0 43.0 46.0 45.0 44.0 45.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#9] json-stringify-tinderbox Cycles:10 Average:39.00 Median:39.00 stddev:0.47 (1.2%) stddev-sans-first:0.50 03:17:14 INFO - PID 14170 | Values: 39.0 38.0 39.0 39.0 39.0 39.0 39.0 40.0 39.0 39.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#10] stanford-crypto-aes Cycles:10 Average:72.30 Median:72.00 stddev:1.57 (2.2%) stddev-sans-first:1.66 03:17:14 INFO - PID 14170 | Values: 72.0 74.0 70.0 71.0 75.0 72.0 71.0 72.0 72.0 74.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#11] stanford-crypto-ccm Cycles:10 Average:117.20 Median:115.50 stddev:11.19 (9.7%) stddev-sans-first:11.71 03:17:14 INFO - PID 14170 | Values: 112.0 121.0 118.0 118.0 113.0 112.0 113.0 109.0 109.0 147.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.00 Median:158.50 stddev:3.53 (2.2%) stddev-sans-first:3.43 03:17:14 INFO - PID 14170 | Values: 162.0 163.0 154.0 153.0 159.0 162.0 158.0 156.0 158.0 155.0 03:17:14 INFO - PID 14170 | 03:17:14 INFO - PID 14170 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.20 Median:60.00 stddev:2.70 (4.5%) stddev-sans-first:2.83 03:17:14 INFO - PID 14170 | Values: 59.0 59.0 64.0 59.0 57.0 61.0 58.0 62.0 58.0 65.0 03:17:14 INFO - PID 14170 | -------- Summary: end -------- 03:17:14 INFO - PID 14170 | 03:17:15 INFO - PID 14170 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:17:15 INFO - TEST-INFO | 14170: exit 0 03:17:15 INFO - TEST-OK | kraken | took 58972ms 03:17:15 INFO - SUITE-END | took 1168s 03:17:15 INFO - Completed test suite (00:19:29) 03:17:15 INFO - javascript benchmark 03:17:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9516.233291266046, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3899.1811719538896, 3918.138009637879, 3929.705272104591, 3933.7639741615503, 3957.1887763008585, 6824.249332573419, 6838.213605435377, 6890.310968903107, 6940.965295173517, 6944.09726735525, 6939.791806245808, 6950.7914762557075, 7013.43892488601, 7030.507864449491, 7050.647467626611, 6159.8768024639485, 6162.537809664271, 6235.501159907197, 6236.314005459396, 6267.122602835607, 6093.969530152343, 6175.47508461779, 6256.249250089995, 6261.780837670683, 6267.310595834455, 6111.174991376174, 6111.449969502722, 6214.720337584819, 6214.720337584819, 6236.469900058501, 6066.8786624267705, 6152.5693201475915, 6169.043798211284, 6244.188255526752, 6259.467945224661, 6136.9079463808075, 6137.754489820401, 6156.322804491502, 6162.137300777895, 6185.907211391833, 6955.374016338554, 6973.37239648429, 6980.616066116361, 6996.370326670573, 7011.544249623758, 7450.701971921117, 7461.253874612496, 7463.104427468738, 7580.317771400545, 7585.924140758577, 7524.134724506697, 7538.283863033003, 7609.048868891388, 7629.847403051908, 7636.274553917369, 6856.52004359695, 6936.687849046805, 6943.756968506129, 6958.756443524503, 6959.547629404123, 7450.552966822008, 7635.465517413839, 7645.350145237717, 7746.728864489716, 7754.9224507755325, 7365.484416090822, 7458.216887226763, 7479.102507699002, 7483.775486735407, 7503.0, 7476.738314158978, 7486.176520582731, 7497.2877576630135, 7590.316871481593, 7606.695732170651, 7235.782926512213, 7357.043584334002, 7391.704331826774, 7409.740659076906, 7431.071116110486, 7399.926000740032, 7495.063117110361, 7585.58279294633, 7586.127595326608, 7608.0, 6335.809925702236, 6365.140706004723, 6430.453411460078, 6442.387973142544, 6460.418562329319, 5454.836354909359, 5472.0, 5495.505404513613, 5554.361248456479, 5570.972145139248, 6315.747370105144, 6361.840953976188, 6368.172137622079, 6368.777092801823, 6389.456896163785, 7286.854262914712, 7359.926400735924, 7450.441216908754, 7451.85096298071, 7457.216992215739, 6192.938070619236, 6225.315215326311, 6294.370562943669, 6308.495320374359, 6380.361963803675, 6691.899621505681, 6721.059051732761, 6771.153605799275, 6774.72901083961, 6776.864462710718, 7192.7122915083855, 7231.45764067697, 7256.6734496947765, 7295.161056478573, 7323.670434830446, 7174.713011479483, 7345.8898116527225, 7365.594892280762, 7389.076365454318, 7400.888986665312, 7250.057492525938, 7275.054242948383, 7275.563466192046, 7277.672504737194, 7279.963600181965, 7344.853102938018, 7389.778306650809, 7439.442041846775, 7480.700771969278, 7521.962390188014, 7549.7357592483995, 7553.7356192533, 7622.885656715264, 7630.542167469969, 7632.19861914491, 7185.928140718526, 7195.496315257882, 7203.927960720326, 7310.488265821342, 7335.083114610674, 7037.648117593997, 7140.892886606601, 7156.320149585781, 7164.0, 7164.928350716426, 7104.218535961144, 7111.964440177766, 7122.002919591365, 7131.82170445743, 7145.678444469907, 7151.82120446993, 7197.0, 7204.135503739585, 7283.126024877049, 7312.634368281459, 7082.681279342545, 7091.290870912868, 7164.928350716426, 7165.140183178052, 7168.247334029852], "value": 6821.68426312235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [907.959141838617, 972.6936015155226, 1001.8397056470961, 1364.5292374130927, 1384.1487485196608, 44251.557484425146, 45289.77355113228, 45349.7732511337, 45351.31973020407, 45403.31895021578, 43234.0, 43665.78167109168, 43686.907827304334, 43734.12531749371, 43735.12529749403, 7289.343959043672, 7362.37419819316, 7363.77908662741, 7364.226756190603, 7365.447591430638, 7395.926040739604, 7472.813179670525, 7497.362724168425, 7499.325060744532, 7501.212372700869, 7823.5305881647, 7924.088729796061, 7924.524528528278, 7930.088039875402, 7930.881036784453, 7805.180456052118, 7893.289603935645, 7913.643886025143, 7923.445358824884, 7928.207179282025, 7797.0, 7804.804879878048, 7995.160508146677, 8001.559914204717, 8011.0, 40437.59562404397, 40752.59247407547, 40787.59212407868, 40804.0, 40877.795611022055, 3536.717062634983, 3578.6063533011347, 3590.174259920207, 3591.048372181374, 3591.6947059499976, 33548.83225583856, 33597.6640233597, 33654.83172584146, 33675.831620841986, 33714.83142584296, 5383.757730902119, 5451.890962180773, 5471.23402723619, 5503.422140675211, 5520.889582208373, 217926.0, 220517.8974105135, 222217.0, 222860.0, 224261.0, 52356.214656780176, 52733.0, 52812.20781688278, 52839.471605283456, 52987.73506132445, 54751.0, 55215.72392138014, 55335.44664553383, 55393.44606553883, 55670.72164639232, 16854.07302598344, 17332.740008899877, 17338.219780109925, 17338.959662420297, 17340.566485837702, 14144.080634758708, 14156.787648185285, 14160.433582656784, 14165.433382664578, 14170.504032358818, 13602.863971360357, 13788.655283617789, 15443.918925675094, 15458.149801761012, 15712.528624141294, 5850.0932355484965, 6059.0, 6074.331823499411, 6086.60437071589, 6119.143319935212, 1107.1917500224881, 1111.994440027811, 1117.5697356517799, 1123.5955056179769, 1129.8700649425257, 25667.229983100537, 25762.22713318566, 25859.74140258611, 25869.482610347688, 25937.74062259391, 1197.7424853656332, 1212.4362171590253, 1230.926144431337, 1242.0932719115096, 1257.7170136719164, 13400.329983500786, 13449.125806822525, 13555.118917270345, 13739.381727822272, 13813.309334533233, 307946.0, 308454.0, 309520.0, 309805.0, 310194.0, 56496.71751641216, 56567.43432565704, 56904.43095568991, 57378.426215738145, 57454.138187927216, 1469.6399382151442, 1537.4465192530904, 1543.5832325272113, 1546.2423412528005, 1550.70536598046, 24874.837151029555, 24927.753612319673, 24940.75059249384, 25191.0, 25196.622050669623, 40266.597334026286, 40267.1946561073, 40281.79859100686, 40416.79791601023, 40426.191476169726, 232736.0, 233432.0, 233557.0, 233647.0, 233841.0, 36453.81773091118, 36705.63294367022, 36795.44806827846, 36873.63126368702, 37088.443673345464, 46068.30897536607, 49088.2636760456, 50778.49221507738, 51018.74490627523, 51054.23418648649, 35940.0, 36280.27439451249, 37119.44320835244, 37175.81412092922, 37472.62527374691, 36532.269354613294, 36542.8172859134, 37041.62958370382, 37135.628643713215, 37643.247135057696, 42159.0, 43662.34506482469, 43738.34392484052, 43771.56228437675, 43836.0, 36043.27913441664, 36332.636673633984, 36368.636313636525, 36572.0, 36758.816205918796, 26607.33481662974, 27545.035923742573, 27618.447631047668, 27710.307242318297, 27893.302667433476], "value": 18808.103162781652, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.4469747298913, 582.6853499110481, 584.6813486649776, 586.4282324733385, 607.8115784106926, 23205.0, 23496.412589685267, 23822.523549529, 23874.52250954982, 23965.04139834409, 27658.723412765867, 28031.29921751952, 28035.719642803564, 28041.158765237076, 28115.85942070287, 6642.5350225484235, 6779.423748981342, 6791.76228831991, 6800.591964482147, 6804.3876051155385, 6434.292227854932, 6714.63069531178, 6726.562773419712, 6797.0, 6850.931490685104, 6667.133272674546, 6667.466602671775, 6672.9666351668175, 6674.532782705212, 6683.899741503881, 6664.700088495981, 6666.200055993312, 6678.398944095031, 6681.799546013627, 6683.064370988041, 6212.81361559154, 6216.502679785606, 6216.533759968021, 6218.689065546704, 6220.751169953196, 31581.84209078963, 31954.52068218955, 32307.51538726921, 32442.837785811156, 32538.511922320948, 2069.3895300886275, 2090.644590419632, 2096.643570593003, 2096.863703859244, 2097.276439628326, 20654.07056682453, 21313.0, 21325.36023919285, 21332.146714131417, 21339.0, 2478.1698131126095, 2542.453372524916, 2593.5850263957864, 2609.699884513267, 2618.7904967602544, 161028.19485902612, 164660.0, 165254.0, 165415.0, 165747.17126414413, 16052.154002289886, 16286.26711797972, 16436.260368283216, 16737.16314184286, 16746.916265418597, 25203.495930081295, 26242.212733618024, 26350.86824565865, 26411.07561235386, 26497.602535961974, 10791.83812242817, 10913.890861091446, 11007.61473348429, 11250.212485126127, 11347.0, 10465.843012354668, 10476.109530689824, 10515.211359147941, 10518.894811051945, 10698.41158736277, 9579.856302155473, 9848.113669769611, 10043.744531933507, 10043.932207455991, 10239.59041638341, 2721.333273348003, 2831.8725657345467, 2837.609057176654, 2920.4159168166025, 2983.8358890261243, 1065.3181963543338, 1075.0754351257913, 1078.245228340165, 1088.8638920134983, 1090.1006669497697, 22316.442088947908, 22700.772992270195, 22706.43233919165, 23114.8844255781, 23121.421964451023, 1075.6772968109535, 1142.3716955674347, 1149.8792626774236, 1158.1140427572914, 1158.9072874170047, 12122.757544849053, 12541.247525148521, 12614.684632884251, 12702.872971270353, 12760.6809829755, 653223.0, 653275.0, 655879.0, 663269.0, 668403.0, 129110.35444822715, 133266.0, 136673.0, 138822.0, 139421.30289348488, 1310.7902735562263, 1319.280991859439, 1347.3936728472227, 1348.1978222957325, 1348.8179095822134, 19715.0, 19841.900790495954, 19936.700949486058, 20475.897620512398, 20541.178352865718, 61288.38711613005, 61545.692271538355, 61940.69029654823, 61976.69011654913, 64476.355236448915, 79114.0, 79315.0, 79389.0, 79822.0, 80964.59517702374, 15958.281877315316, 16108.27512761905, 16147.192640367699, 16293.51119466418, 16317.18414079315, 25495.617565736156, 25674.871625641754, 25778.226653200432, 25828.35429114212, 25864.8706756465, 20666.070026849127, 20799.584008320053, 20953.790462095185, 21232.150713971267, 21265.149394024684, 17954.371596994042, 18002.0, 18204.36284730028, 18252.81747182511, 18316.908415457838, 19565.902170489055, 19704.605907881476, 20026.399208023784, 20870.0, 21058.684119738522, 15911.68176636484, 15928.123953182223, 15928.123953182687, 15939.203039847729, 15975.520734377988, 20155.39533813988, 20460.181592736124, 20460.79539204589, 20590.073446695234, 20690.689639655717], "value": 13133.858717728745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.7088582283543, 1501.2193659297166, 1510.6223444138966, 1525.328855303667, 1534.0565552185406, 16450.506484805475, 16861.578460538494, 17562.736558951627, 17586.648267034652, 17611.295548178074, 17473.65052698945, 17768.555786105353, 17774.64450710985, 17785.288588456446, 17790.64418711625, 2869.282679330173, 2929.3701854101364, 2929.5166297560877, 2930.0, 2931.1792698044583, 2752.477029364414, 2786.7352601502926, 2824.2939265183704, 2828.7312705292966, 2987.4622567937763, 2901.651801783789, 2938.867750951212, 2939.720726530976, 2940.2502361897687, 2941.161768895865, 2781.805273630847, 2800.229936767385, 2821.5203415419223, 2826.2086615747626, 2846.8007239493245, 2714.443539074485, 2752.1055656911385, 2754.0085569195076, 2754.256350785277, 2755.5040092783292, 16874.240659170246, 16980.15099245033, 17060.0, 17103.401380951607, 17205.569860753458, 8652.740417787476, 8918.777030574223, 8930.642774289021, 8930.955345223232, 8942.821143577092, 5624.690642014688, 5835.503982161521, 5982.641041537521, 6012.488938440238, 6081.3006504252135, 2129.7124888140056, 2153.6231159547015, 2196.1544805249932, 2220.3117033719436, 2230.82153427727, 31089.689103108907, 31395.529067063784, 31466.0, 31510.52734208989, 31529.0, 7261.709531618676, 7380.225076367012, 7462.589557574385, 7591.4685971981435, 7618.657160427794, 19662.0, 19692.606147876962, 19717.211311547664, 19722.408327750192, 19734.111964961612, 8599.484030958163, 8772.956135219283, 8778.341624378198, 8778.824423511494, 8783.472991620523, 7979.680812767424, 8143.42995990275, 8168.469049511763, 8178.67285308581, 8201.13888041759, 5699.572532060112, 5733.569982251348, 5750.1949727038245, 5815.2149459822385, 5845.473907348359, 1548.4270819796748, 1557.6573153906122, 1655.4702495201661, 1660.5599516128236, 1683.9326426942785, 990.094063931509, 1006.06938581813, 1019.4087429290994, 1022.7187523431116, 1035.7307100154014, 9521.809563808823, 9612.51937403141, 9681.177099946442, 9757.024297570328, 9789.559469823875, 1053.2521909444226, 1084.2735367303926, 1087.260383386575, 1102.1843835561626, 1108.3737688206138, 5779.537636989031, 5930.584859059911, 5937.2578427696535, 6002.339742628307, 6446.387593178734, 24236.515269694508, 24510.264692059267, 25103.246902592953, 25474.363140921254, 25477.872610636827, 23788.762112379, 24839.50320993606, 24895.5020899581, 24982.750172498043, 25034.0, 12185.878141218474, 12434.56479023248, 12514.0, 12589.30758808256, 12675.619731408073, 9749.951250243987, 9903.752406189902, 9941.453220072965, 10070.546825392728, 10712.089472394933, 20922.0, 21119.0, 21312.254071107436, 21582.244621438174, 21875.562488749816, 22088.44778880541, 22644.886775566018, 22650.320490385315, 22673.659895101257, 23101.88449057744, 13278.468861245829, 13570.253636050113, 13579.117357371739, 13588.79616805729, 13595.79606305926, 15355.539333820003, 15527.146006969273, 15600.84399155994, 15707.528774136794, 15712.292946817191, 12575.245485270912, 12660.746785064432, 12683.11218214716, 12968.35158242103, 13095.214287142802, 12351.93824030874, 12539.37303134858, 12569.183003104768, 12581.245125292511, 12755.681107972376, 11250.718732031764, 11253.887461125283, 11361.43192840338, 11383.829242561535, 11392.316461012366, 8444.535550544779, 8492.660293588435, 8531.189536993976, 8614.913850861662, 8641.308695304235, 8586.914130858611, 8741.213290803858, 8751.781205469913, 8789.296856251356, 8924.776880578038], "value": 7525.01351633506, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.7575952941412, 536.4045909040964, 565.869849934515, 566.8072855229221, 572.2560671127533, 6736.0, 6764.932350676492, 6775.188296856811, 6798.932010679891, 6835.589864608127, 6496.187976502937, 6630.535862489628, 6654.600723956554, 6666.966665166679, 6683.198016238046, 5280.231122573942, 5280.366356037281, 5335.359756829186, 5383.43473935237, 5404.756785944623, 5230.437085827936, 5255.058705970522, 5257.868553286179, 5299.28459657947, 5553.972230138844, 5473.726313684319, 5505.0, 5521.365043020044, 5539.14143307788, 5549.417311182328, 5452.263944367499, 5533.612647114704, 5539.086050801613, 5550.057479882049, 5554.2779438673115, 5219.1993762152, 5221.347331583552, 5237.319148510707, 5278.868028299285, 5309.840704778863, 6613.090240294255, 6696.933030669679, 6716.194056713177, 6758.763443279511, 6766.0, 6495.447886929569, 6502.148066739925, 6547.9017814732815, 6583.572067815577, 6664.333566643345, 7202.4958252922315, 7219.639018049129, 7291.197968223491, 7324.340809327186, 7336.266373362674, 5865.413458654143, 5900.1739756434135, 5903.645781253095, 5917.408259174092, 5918.053111502182, 7541.924580754232, 7599.316061554376, 7615.80960475987, 7708.190639982777, 7754.224577542257, 5984.880302393928, 6029.88447137281, 6088.756449742049, 6099.481544068715, 6156.138140660311, 6146.323904370515, 6168.599041062316, 6182.1345011698395, 6188.659623720739, 6231.2522497300615, 6545.8363540910905, 6577.375149360803, 6647.767328143492, 6690.59856408617, 6702.463802895757, 6895.862082758316, 7189.5686258824635, 7248.383887369527, 7250.492465527363, 7352.338289553966, 2069.2964392106755, 2069.430906500694, 2112.904919278636, 2130.2970019893396, 2142.967855482169, 1544.5675210940954, 1556.7976163098724, 1583.4616230481693, 1591.2282542966884, 1631.2414727151931, 6044.879102417927, 6049.758009679564, 6052.909206361908, 6093.360197179321, 6190.350013248634, 7093.751718689821, 7147.499675022698, 7279.708811647475, 7283.162436319785, 7293.927060729325, 6141.140240366352, 6158.476529494954, 6209.405040270168, 6214.706205710981, 6215.658138802319, 3769.8115094245177, 3813.256414999104, 3825.158465137665, 3880.9133442635543, 3903.2974064668633, 6911.827204319831, 6997.790066298019, 7007.474439417064, 7076.292370762985, 7105.147382314155, 7474.5888976106835, 7487.80682817613, 7492.587907665131, 7534.924650753423, 7572.5835079070075, 6925.68834402443, 6943.375096241362, 7006.264342244093, 7057.50597458173, 7082.0, 7111.715531378687, 7114.430845532448, 7177.174624917992, 7180.640967951477, 7279.235680253497, 7131.607761573163, 7184.9640751795905, 7214.531055481377, 7253.637318134178, 7289.234630363735, 7180.246074162138, 7217.927820721936, 7247.637618119178, 7258.3104605062435, 7263.237360077116, 5506.421825708403, 5541.695206763667, 5556.833295001156, 5587.273654424899, 5615.606907516435, 7029.753958611424, 7089.503734738519, 7118.822029449305, 7242.746503872339, 7325.0, 6915.308469153045, 6930.341617546526, 6979.30206979298, 7069.575825450489, 7113.399485115808, 6962.0, 6964.582125072512, 6993.580385176906, 7021.192562855133, 7029.261927497743, 7007.544509606859, 7064.066184405125, 7072.469564782765, 7099.290070992859, 7102.05264815096, 6986.16166060076, 7082.960839447162, 7104.715811367694, 7129.429645628234, 7129.500934934505, 6829.385355317847, 6882.552634078769, 6906.758263460755, 6915.51591388598, 6927.618980955895], "value": 5615.308532646651, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [991.9057689519498, 996.72091814292, 1023.8617786598807, 1038.3665963762105, 1038.9688309350722, 18099.99999999998, 18994.335198268065, 19001.524961875977, 19049.42851714449, 20962.475938101554, 19796.0, 19802.405927822187, 21458.678119828215, 22042.88978555109, 22121.0, 1063.1176123817233, 1092.2136062035322, 1092.6667366453228, 1092.808758467269, 1092.8142215823289, 1011.893751156129, 1101.0695961912172, 1104.6078642082066, 1106.391484683425, 1113.048343666166, 1140.0879296562757, 1140.5095808802212, 1140.680609429357, 1142.5772464188262, 1147.506572173965, 1134.8354488599148, 1138.9202755807096, 1142.4858813533942, 1143.0284258380352, 1143.4282858570714, 842.1073661918387, 842.6250318608222, 842.7092653034694, 842.7640260727006, 842.8145807922309, 19578.804211957842, 19928.70106948397, 20105.39683809488, 20167.697484537744, 20337.694934575993, 23033.539329213323, 23470.882645586662, 23764.168254111195, 23998.640020399707, 24033.87983060091, 18586.0, 18604.62790744191, 18733.0, 19023.61952760937, 19131.904340478348, 4050.898727531806, 4075.510938687347, 4077.062275676611, 4077.1641813428178, 4078.082431452929, 148186.25906870505, 148660.2566987169, 149047.0, 149545.0, 151477.0, 18088.372046515877, 18172.636547269245, 18306.725399119023, 18479.907600461913, 18654.253829846653, 34213.48679769805, 34530.654693453245, 34974.300513990085, 35187.4721879172, 35240.118997024765, 8558.144185581392, 8904.109589041043, 9587.424754514752, 10128.949355253177, 10304.0, 4456.353828694886, 4520.954790452053, 4546.227141385973, 4559.065391594715, 4561.794719237642, 6156.661383623854, 6449.6130232186215, 6777.423918966844, 6815.897761533581, 6818.8636227276165, 1309.8100775387516, 1330.2351148089888, 1339.4575197045212, 1350.6825895914453, 1354.939027743754, 734.5225603357753, 742.9145648250412, 745.1803016681607, 753.7286576832336, 755.1957165618599, 12482.687932801753, 12933.348663523955, 14957.0, 15161.469348572748, 15386.692266154614, 754.8943147959291, 785.0500893918309, 785.4187900953368, 786.0959896119394, 793.0760663826693, 6182.598131121463, 6460.806175814733, 6657.633830139292, 6672.633005184664, 6774.796756097325, 124459.0, 134015.32992334975, 134089.32955335357, 134423.32788336193, 139351.0, 108207.0, 109742.0, 109782.0, 110990.44504777425, 111150.0, 105033.47483262687, 105738.0, 105903.0, 107471.46264268628, 111336.4433177845, 12343.567975120828, 12380.380980950737, 12530.248185108923, 12694.555690550786, 12698.111132220656, 51375.743121285646, 52461.21308180457, 52902.20646690226, 53094.73452632712, 53613.731931340095, 82213.0, 85432.57283713542, 86837.56581217053, 86906.56546717226, 93398.53300733452, 21343.35969920905, 21457.141714331876, 21492.0, 21555.568888622453, 21688.0, 23014.19450319231, 23069.88465057664, 24164.87917560401, 24482.51034979255, 24925.75074249307, 21837.344879653636, 22206.66689999619, 22216.333509994725, 22256.109755610243, 22269.109235630393, 20679.379618611467, 21213.15147394087, 21348.89325553362, 21453.570928581652, 21646.783532164478, 20491.79508204899, 20874.269400570905, 21197.894010530465, 21322.57354852863, 21606.0, 14951.925240373728, 15243.847561524686, 15527.922360388126, 15833.33749956284, 16201.75697364564, 16432.671346572763, 16477.34090636361, 16506.092164931044, 16547.420840270534, 16683.416080437128], "value": 10429.366549699802, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1436.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 105.0, 98.0, 105.0, 106.0, 105.0, 106.0, 98.0, 107.0, 109.0], "value": 103.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 139.0, 160.0, 112.0, 138.0, 147.0, 113.0, 170.0, 116.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 161.0, 162.0, 161.0, 161.0, 160.0, 163.0, 161.0, 164.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 181.0, 73.0, 73.0, 74.0, 73.0, 151.0, 74.0], "value": 92.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 137.0, 79.0, 78.0, 79.0, 140.0, 79.0, 78.0, 120.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 113.0, 114.0, 118.0, 115.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 150.0, 150.0, 149.0, 150.0, 149.0, 151.0, 150.0, 150.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 96.0, 94.0, 95.0, 94.0, 93.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 47.0, 43.0, 43.0, 44.0, 43.0, 46.0, 45.0, 44.0, 45.0], "value": 44.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 39.0, 39.0, 39.0, 39.0, 39.0, 40.0, 39.0, 39.0], "value": 39.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 70.0, 71.0, 75.0, 72.0, 71.0, 72.0, 72.0, 74.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 121.0, 118.0, 118.0, 113.0, 112.0, 113.0, 109.0, 109.0, 147.0], "value": 117.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 154.0, 153.0, 159.0, 162.0, 158.0, 156.0, 158.0, 155.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 64.0, 59.0, 57.0, 61.0, 58.0, 62.0, 58.0, 65.0], "value": 60.2, "unit": "score"}]}]} 03:17:15 INFO - Return code: 0 03:17:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:17:16 INFO - # TBPL SUCCESS # 03:17:16 INFO - Running post-action listener: _package_coverage_data 03:17:16 INFO - Running post-action listener: _resource_record_post_action 03:17:16 INFO - [mozharness: 2017-09-01 10:17:16.015263Z] Finished run-tests step (success) 03:17:16 INFO - Running post-run listener: _resource_record_post_run 03:17:16 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 375603200; Read time: 0; Write time: 225864 03:17:16 INFO - TinderboxPrint: CPU usage
13.1% 03:17:16 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 03:17:16 INFO - TinderboxPrint: I/O write bytes / time
375,603,200 / 225,864 03:17:16 INFO - TinderboxPrint: CPU idle
8,220.4 (86.9%) 03:17:16 INFO - TinderboxPrint: CPU user
1,187.0 (12.6%) 03:17:16 INFO - TinderboxPrint: Swap in / out
0 / 0 03:17:16 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99794944; Read time: 0; Write time: 80636 03:17:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:17:16 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 275808256; Read time: 0; Write time: 145228 03:17:16 INFO - Running post-run listener: _upload_blobber_files 03:17:16 INFO - Blob upload gear active. 03:17:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:17:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:17:17 INFO - (blobuploader) - INFO - Open directory for files ... 03:17:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:17:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:17:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:18 INFO - (blobuploader) - INFO - Done attempting. 03:17:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:17:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:17:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:21 INFO - (blobuploader) - INFO - Done attempting. 03:17:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:17:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:17:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:22 INFO - (blobuploader) - INFO - Done attempting. 03:17:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:17:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:17:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:23 INFO - (blobuploader) - INFO - Done attempting. 03:17:23 INFO - (blobuploader) - INFO - Iteration through files over. 03:17:23 INFO - Return code: 0 03:17:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:17:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:17:23 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/439a80b4828f87a8e72df9409ccbb9dd0320e5531aca3e4a16fdea05fad46c22168249a84efe0a6e8686136274d3d4e88d560ff84bc5f164d387f4c67f96fa1b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9f6926743011b0dad9d4b0c3604d63be4c76b9f9c7d3546c08e8224f2a7c0e2fef5513d69e6773d1afda37c1ef101815fa862ed7b701831305ea62b187f680e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4b95bbf67971b30223e3be9bda86361fa923433b1ff00c685c81a05483224b2cf8ad6526b864320d6d7fc29b7f2ed84f6e8e22f3f957ffc4a1adc5abd89746a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a931b700cad637bf02dbfdfbeb5024e4d775ef20432e20e0079888d65af5630dd6cb09f08752dab1ab1bdcb1a4ef5c7de0d0e4202143ca2f0f990ea50aa7104"} 03:17:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:17:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:17:23 INFO - Contents: 03:17:23 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/439a80b4828f87a8e72df9409ccbb9dd0320e5531aca3e4a16fdea05fad46c22168249a84efe0a6e8686136274d3d4e88d560ff84bc5f164d387f4c67f96fa1b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9f6926743011b0dad9d4b0c3604d63be4c76b9f9c7d3546c08e8224f2a7c0e2fef5513d69e6773d1afda37c1ef101815fa862ed7b701831305ea62b187f680e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4b95bbf67971b30223e3be9bda86361fa923433b1ff00c685c81a05483224b2cf8ad6526b864320d6d7fc29b7f2ed84f6e8e22f3f957ffc4a1adc5abd89746a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a931b700cad637bf02dbfdfbeb5024e4d775ef20432e20e0079888d65af5630dd6cb09f08752dab1ab1bdcb1a4ef5c7de0d0e4202143ca2f0f990ea50aa7104"} 03:17:23 INFO - Running post-run listener: copy_logs_to_upload_dir 03:17:23 INFO - Copying logs to upload dir... 03:17:23 INFO - mkdir: /builds/slave/test/build/upload/logs 03:17:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.540420 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-01 03:17:23.531557) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 03:17:23.538302) ========= 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=9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072 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/439a80b4828f87a8e72df9409ccbb9dd0320e5531aca3e4a16fdea05fad46c22168249a84efe0a6e8686136274d3d4e88d560ff84bc5f164d387f4c67f96fa1b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9f6926743011b0dad9d4b0c3604d63be4c76b9f9c7d3546c08e8224f2a7c0e2fef5513d69e6773d1afda37c1ef101815fa862ed7b701831305ea62b187f680e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4b95bbf67971b30223e3be9bda86361fa923433b1ff00c685c81a05483224b2cf8ad6526b864320d6d7fc29b7f2ed84f6e8e22f3f957ffc4a1adc5abd89746a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a931b700cad637bf02dbfdfbeb5024e4d775ef20432e20e0079888d65af5630dd6cb09f08752dab1ab1bdcb1a4ef5c7de0d0e4202143ca2f0f990ea50aa7104"} build_url:https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011364 build_url: 'https://queue.taskcluster.net/v1/task/eencB-1WTx2oDdiXBsZwYA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/439a80b4828f87a8e72df9409ccbb9dd0320e5531aca3e4a16fdea05fad46c22168249a84efe0a6e8686136274d3d4e88d560ff84bc5f164d387f4c67f96fa1b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9f6926743011b0dad9d4b0c3604d63be4c76b9f9c7d3546c08e8224f2a7c0e2fef5513d69e6773d1afda37c1ef101815fa862ed7b701831305ea62b187f680e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4b95bbf67971b30223e3be9bda86361fa923433b1ff00c685c81a05483224b2cf8ad6526b864320d6d7fc29b7f2ed84f6e8e22f3f957ffc4a1adc5abd89746a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a931b700cad637bf02dbfdfbeb5024e4d775ef20432e20e0079888d65af5630dd6cb09f08752dab1ab1bdcb1a4ef5c7de0d0e4202143ca2f0f990ea50aa7104"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 03:17:23.569323) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 03:17:23.569696) ========= 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=9f7544b6a75ada7b6b576032000001a7-1504259302.371409-2072983072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004572 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 03:17:23.649158) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 03:17:23.649499) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 03:17:23.649839) ========= ========= Total master_lag: 0.26 =========