builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-031 starttime: 1504463416.62 results: success (0) revision: 0afabd3e5c27b0036517b96eecb1f8553d027179 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:16.623727) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:16.624162) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:16.624430) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:16.658101) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:16.658356) ========= 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=db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 11:30:16-- 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% 41.5M=0s 2017-09-03 11:30:16 (41.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118028 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:16.808358) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:16.808627) ========= 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=db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018565 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:16.870320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:16.870612) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0afabd3e5c27b0036517b96eecb1f8553d027179 --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 0afabd3e5c27b0036517b96eecb1f8553d027179 --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=db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 11:30:16,960 truncating revision to first 12 chars 2017-09-03 11:30:16,960 Setting DEBUG logging. 2017-09-03 11:30:16,960 attempt 1/10 2017-09-03 11:30:16,960 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0afabd3e5c27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-03 11:30:17,396 unpacking tar archive at: mozilla-central-0afabd3e5c27/testing/mozharness/ program finished with exit code 0 elapsedTime=0.833998 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:17.720605) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:17.721736) ========= script_repo_revision: 0afabd3e5c27b0036517b96eecb1f8553d027179 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:17.722132) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:17.722392) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 11:30:17.736727) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-09-03 11:30:17.737350) ========= /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=db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:30:17 INFO - MultiFileLogger online at 20170903 11:30:17 in /builds/slave/test 11:30:17 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 11:30:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:30:17 INFO - {'append_to_log': False, 11:30:17 INFO - 'base_work_dir': '/builds/slave/test', 11:30:17 INFO - 'blob_upload_branch': 'Firefox', 11:30:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:30:17 INFO - 'branch': 'Firefox', 11:30:17 INFO - 'buildbot_json_path': 'buildprops.json', 11:30:17 INFO - 'code_coverage': False, 11:30:17 INFO - 'config_files': ('talos/linux_config.py',), 11:30:17 INFO - 'default_actions': ('clobber', 11:30:17 INFO - 'read-buildbot-config', 11:30:17 INFO - 'download-and-extract', 11:30:17 INFO - 'populate-webroot', 11:30:17 INFO - 'create-virtualenv', 11:30:17 INFO - 'install', 11:30:17 INFO - 'setup-mitmproxy', 11:30:17 INFO - 'run-tests'), 11:30:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:30:17 INFO - 'disable_ccov_upload': False, 11:30:17 INFO - 'disable_stylo': False, 11:30:17 INFO - 'download_minidump_stackwalk': True, 11:30:17 INFO - 'download_symbols': 'ondemand', 11:30:17 INFO - 'enable_stylo': False, 11:30:17 INFO - 'enable_webrender': False, 11:30:17 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:30:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:30:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:30:17 INFO - 'gecko_profile': False, 11:30:17 INFO - 'gecko_profile_interval': 0, 11:30:17 INFO - 'installer_path': 'installer.exe', 11:30:17 INFO - 'log_level': 'info', 11:30:17 INFO - 'log_name': 'talos', 11:30:17 INFO - 'log_to_console': True, 11:30:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:30:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:30:17 INFO - 'opt_config_files': (), 11:30:17 INFO - 'pip_index': False, 11:30:17 INFO - 'suite': 'dromaeojs-e10s', 11:30:17 INFO - 'system_bits': '32', 11:30:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:30:17 INFO - 'title': 'talos-linux64-ix-031', 11:30:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:30:17 INFO - 'use_talos_json': True, 11:30:17 INFO - 'verify': 'False', 11:30:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:30:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:30:17 INFO - 'work_dir': 'build'} 11:30:17 INFO - [mozharness: 2017-09-03 18:30:17.967097Z] Running clobber step. 11:30:17 INFO - Running pre-action listener: _resource_record_pre_action 11:30:17 INFO - Running main action method: clobber 11:30:17 INFO - rmtree: /builds/slave/test/build 11:30:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:30:19 INFO - Running post-action listener: _resource_record_post_action 11:30:19 INFO - [mozharness: 2017-09-03 18:30:19.503596Z] Finished clobber step (success) 11:30:19 INFO - [mozharness: 2017-09-03 18:30:19.503693Z] Running read-buildbot-config step. 11:30:19 INFO - Running pre-action listener: _resource_record_pre_action 11:30:19 INFO - Running main action method: read_buildbot_config 11:30:19 INFO - Using buildbot properties: 11:30:19 INFO - { 11:30:19 INFO - "project": "", 11:30:19 INFO - "product": "firefox", 11:30:19 INFO - "who": "ffxbld@noreply.mozilla.org", 11:30:19 INFO - "installer_path": "public/build/target.tar.bz2", 11:30:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 11:30:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:30:19 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 11:30:19 INFO - "stage_platform": "linux64-stylosequential", 11:30:19 INFO - "basedir": "/builds/slave/test", 11:30:19 INFO - "buildnumber": 57, 11:30:19 INFO - "platform": "ubuntu64_hw_styloseq", 11:30:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:30:19 INFO - "slavebuilddir": "test", 11:30:19 INFO - "taskId": "JSwrYDvYSaaYKUO7W5OAbw", 11:30:19 INFO - "branch": "mozilla-central", 11:30:19 INFO - "script_repo_revision": "production", 11:30:19 INFO - "revision": "0afabd3e5c27b0036517b96eecb1f8553d027179", 11:30:19 INFO - "slavename": "talos-linux64-ix-031", 11:30:19 INFO - "repo_path": "mozilla-central" 11:30:19 INFO - } 11:30:19 INFO - Finding installer, test and symbols from parent task. 11:30:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JSwrYDvYSaaYKUO7W5OAbw'}, attempt #1 11:30:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JSwrYDvYSaaYKUO7W5OAbw'}, attempt #1 11:30:20 INFO - Task dependencies: aRxrosGuS-CHZHHVq2qPew 11:30:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew'}, attempt #1 11:30:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 11:30:20 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.test_packages.json 11:30:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.crashreporter-symbols.zip 11:30:20 INFO - Running post-action listener: _resource_record_post_action 11:30:20 INFO - [mozharness: 2017-09-03 18:30:20.830818Z] Finished read-buildbot-config step (success) 11:30:20 INFO - [mozharness: 2017-09-03 18:30:20.831035Z] Running download-and-extract step. 11:30:20 INFO - Running pre-action listener: _resource_record_pre_action 11:30:20 INFO - Running main action method: download_and_extract 11:30:20 INFO - mkdir: /builds/slave/test/build/tests 11:30: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')]} 11:30:20 INFO - trying https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.test_packages.json 11:30:20 INFO - Downloading https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:30:21 INFO - Downloaded 989 bytes. 11:30:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:30:21 INFO - Using the following test package requirements: 11:30:21 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:30:21 INFO - u'common': [u'target.common.tests.zip'], 11:30:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:30:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:30:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:30:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:30:21 INFO - u'mozbase': [u'target.common.tests.zip'], 11:30:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:30:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:30:21 INFO - u'web-platform': [u'target.common.tests.zip', 11:30:21 INFO - u'target.web-platform.tests.tar.gz'], 11:30:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:30:21 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:30:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip 11:30:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:30:21 INFO - Fetch https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip into memory 11:30:23 INFO - Content-Length response header: 55022343 11:30:23 INFO - Bytes received: 55022343 11:30:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:30:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip 11:30:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:30:34 INFO - Fetch https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.common.tests.zip into memory 11:30:35 INFO - Content-Length response header: 55022343 11:30:35 INFO - Bytes received: 55022343 11:30:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.talos.tests.zip 11:30:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:30:47 INFO - Fetch https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.talos.tests.zip into memory 11:30:48 INFO - Content-Length response header: 14496650 11:30:48 INFO - Bytes received: 14496650 11:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:48 INFO - trying https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 11:30:48 INFO - Downloading https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:30:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:30:51 INFO - Downloaded 62718993 bytes. 11:30:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 11:30:51 INFO - mkdir: /builds/slave/test/properties 11:30:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:30:51 INFO - Writing to file /builds/slave/test/properties/build_url 11:30:51 INFO - Contents: 11:30:51 INFO - build_url:https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 11:30:51 INFO - Running post-action listener: _resource_record_post_action 11:30:51 INFO - Running post-action listener: find_tests_for_verification 11:30:51 INFO - Running post-action listener: set_extra_try_arguments 11:30:51 INFO - [mozharness: 2017-09-03 18:30:51.996114Z] Finished download-and-extract step (success) 11:30:51 INFO - [mozharness: 2017-09-03 18:30:51.996340Z] Running populate-webroot step. 11:30:51 INFO - Running pre-action listener: _resource_record_pre_action 11:30:51 INFO - Running main action method: populate_webroot 11:30:52 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:30:52 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:30:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:30:52 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:30:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:30:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:52 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:52 INFO - 'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:30:52 INFO - 'tests': ['damp', 'tps']}, 11:30:52 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:52 INFO - 'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['damp', 'tps']}, 11:30:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:30:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:30:52 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:30:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:30:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:30:52 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:52 INFO - 'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:30:52 INFO - 'other-e10s': {'tests': ['a11yr', 11:30:52 INFO - 'ts_paint', 11:30:52 INFO - 'tpaint', 11:30:52 INFO - 'sessionrestore', 11:30:52 INFO - 'sessionrestore_many_windows', 11:30:52 INFO - 'sessionrestore_no_auto_restore', 11:30:52 INFO - 'tabpaint']}, 11:30:52 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['a11yr', 11:30:52 INFO - 'ts_paint', 11:30:52 INFO - 'tpaint', 11:30:52 INFO - 'sessionrestore', 11:30:52 INFO - 'sessionrestore_many_windows', 11:30:52 INFO - 'sessionrestore_no_auto_restore', 11:30:52 INFO - 'tabpaint']}, 11:30:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:30:52 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:30:52 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['bloom_basic_singleton']}, 11:30:52 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['bloom_basic']}, 11:30:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:30:52 INFO - 'tsvgr_opacity', 11:30:52 INFO - 'tart', 11:30:52 INFO - 'tscrollx', 11:30:52 INFO - 'tsvg_static']}, 11:30:52 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['tsvgx', 11:30:52 INFO - 'tsvgr_opacity', 11:30:52 INFO - 'tart', 11:30:52 INFO - 'tscrollx', 11:30:52 INFO - 'tsvg_static']}, 11:30:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:30:52 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:52 INFO - 'talos_options': ['--enable-stylo'], 11:30:52 INFO - 'tests': ['tp5o']}, 11:30:52 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:52 INFO - 'talos_options': ['--mitmproxy', 11:30:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:52 INFO - '--firstNonBlankPaint'], 11:30:52 INFO - 'tests': ['tp6_google', 11:30:52 INFO - 'tp6_youtube', 11:30:52 INFO - 'tp6_amazon', 11:30:52 INFO - 'tp6_facebook']}, 11:30:52 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:52 INFO - 'talos_options': ['--enable-stylo', 11:30:52 INFO - '--mitmproxy', 11:30:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:52 INFO - '--firstNonBlankPaint'], 11:30:52 INFO - 'tests': ['tp6_google', 11:30:52 INFO - 'tp6_youtube', 11:30:52 INFO - 'tp6_amazon', 11:30:52 INFO - 'tp6_facebook']}, 11:30:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:52 INFO - 'talos_options': ['--enable-stylo', 11:30:52 INFO - '--stylo-threads=1', 11:30:52 INFO - '--mitmproxy', 11:30:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:52 INFO - '--firstNonBlankPaint'], 11:30:52 INFO - 'tests': ['tp6_google', 11:30:52 INFO - 'tp6_youtube', 11:30:52 INFO - 'tp6_amazon', 11:30:52 INFO - 'tp6_facebook']}, 11:30:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:30:52 INFO - 'talos_options': ['--xperf_path', 11:30:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:52 INFO - 'tests': ['tp5n']}, 11:30:52 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:30:52 INFO - 'talos_options': ['--enable-stylo', 11:30:52 INFO - '--xperf_path', 11:30:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:52 INFO - 'tests': ['tp5n']}}} 11:30:52 INFO - Running post-action listener: _resource_record_post_action 11:30:52 INFO - [mozharness: 2017-09-03 18:30:52.013980Z] Finished populate-webroot step (success) 11:30:52 INFO - [mozharness: 2017-09-03 18:30:52.014069Z] Running create-virtualenv step. 11:30:52 INFO - Running pre-action listener: _resource_record_pre_action 11:30:52 INFO - Running main action method: create_virtualenv 11:30:52 INFO - Creating virtualenv /builds/slave/test/build/venv 11:30:52 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:30:52 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:30:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:30:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:30:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:30:52 INFO - Installing distribute.............................................................................................................................................................................................done. 11:30:52 INFO - Installing pip...............done. 11:30:52 INFO - Return code: 0 11:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:30:52 INFO - Reading from file tmpfile_stdout 11:30:52 INFO - Output received: 11:30:52 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:30:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:53 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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:30:53 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 11:30:53 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 11:30:53 INFO - Using env: {'DISPLAY': ':0', 11:30:53 INFO - 'HOME': '/home/cltbld', 11:30:53 INFO - 'LANG': 'en_US.UTF-8', 11:30:53 INFO - 'LANGUAGE': 'en_US:en', 11:30:53 INFO - 'LOGNAME': 'cltbld', 11:30:53 INFO - 'MAIL': '/var/mail/cltbld', 11:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:53 INFO - 'MOZ_NO_REMOTE': '1', 11:30:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:53 INFO - 'NO_EM_RESTART': '1', 11:30:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:53 INFO - 'PWD': '/builds/slave/test', 11:30:53 INFO - 'SHELL': '/bin/bash', 11:30:53 INFO - 'SHLVL': '1', 11:30:53 INFO - 'TERM': 'linux', 11:30:53 INFO - 'TMOUT': '86400', 11:30:53 INFO - 'USER': 'cltbld', 11:30:53 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:53 INFO - '_': '/tools/buildbot/bin/python'} 11:30:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:30:54 INFO - Downloading/unpacking pip>=1.5 11:30:54 INFO - Running setup.py egg_info for package pip 11:30:54 INFO - warning: no files found matching 'pip/cacert.pem' 11:30:54 INFO - warning: no files found matching '*.html' under directory 'docs' 11:30:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:30:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:30:54 INFO - Installing collected packages: pip 11:30:54 INFO - Found existing installation: pip 1.1 11:30:54 INFO - Uninstalling pip: 11:30:54 INFO - Successfully uninstalled pip 11:30:54 INFO - Running setup.py install for pip 11:30:54 INFO - warning: no files found matching 'pip/cacert.pem' 11:30:54 INFO - warning: no files found matching '*.html' under directory 'docs' 11:30:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:30:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:30:54 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:30:54 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:30:54 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:30:54 INFO - Successfully installed pip 11:30:54 INFO - Cleaning up... 11:30:54 INFO - Return code: 0 11:30:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:30:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:30:54 INFO - Using env: {'DISPLAY': ':0', 11:30:54 INFO - 'HOME': '/home/cltbld', 11:30:54 INFO - 'LANG': 'en_US.UTF-8', 11:30:54 INFO - 'LANGUAGE': 'en_US:en', 11:30:54 INFO - 'LOGNAME': 'cltbld', 11:30:54 INFO - 'MAIL': '/var/mail/cltbld', 11:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:54 INFO - 'MOZ_NO_REMOTE': '1', 11:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:54 INFO - 'NO_EM_RESTART': '1', 11:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:54 INFO - 'PWD': '/builds/slave/test', 11:30:54 INFO - 'SHELL': '/bin/bash', 11:30:54 INFO - 'SHLVL': '1', 11:30:54 INFO - 'TERM': 'linux', 11:30:54 INFO - 'TMOUT': '86400', 11:30:54 INFO - 'USER': 'cltbld', 11:30:54 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:54 INFO - '_': '/tools/buildbot/bin/python'} 11:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:54 INFO - Downloading/unpacking psutil>=3.1.1 11:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:30:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:55 INFO - Installing collected packages: psutil 11:30:55 INFO - Running setup.py install for psutil 11:30:55 INFO - building 'psutil._psutil_linux' extension 11:30:55 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 11:30:55 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 11:30:55 INFO - building 'psutil._psutil_posix' extension 11:30:55 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 11:30:56 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 11:30:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:56 INFO - Successfully installed psutil 11:30:56 INFO - Cleaning up... 11:30:56 INFO - Return code: 0 11:30:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:30:56 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')]} 11:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:56 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')]} 11:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:56 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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:30:56 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 11:30:56 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 11:30:56 INFO - Using env: {'DISPLAY': ':0', 11:30:56 INFO - 'HOME': '/home/cltbld', 11:30:56 INFO - 'LANG': 'en_US.UTF-8', 11:30:56 INFO - 'LANGUAGE': 'en_US:en', 11:30:56 INFO - 'LOGNAME': 'cltbld', 11:30:56 INFO - 'MAIL': '/var/mail/cltbld', 11:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:56 INFO - 'MOZ_NO_REMOTE': '1', 11:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:56 INFO - 'NO_EM_RESTART': '1', 11:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:56 INFO - 'PWD': '/builds/slave/test', 11:30:56 INFO - 'SHELL': '/bin/bash', 11:30:56 INFO - 'SHLVL': '1', 11:30:56 INFO - 'TERM': 'linux', 11:30:56 INFO - 'TMOUT': '86400', 11:30:56 INFO - 'USER': 'cltbld', 11:30:56 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:56 INFO - '_': '/tools/buildbot/bin/python'} 11:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:30:57 INFO - Installing collected packages: mozsystemmonitor 11:30:57 INFO - Running setup.py install for mozsystemmonitor 11:30:57 INFO - Successfully installed mozsystemmonitor 11:30:57 INFO - Cleaning up... 11:30:57 INFO - Return code: 0 11:30:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:57 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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:30:57 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 11:30:57 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 11:30:57 INFO - Using env: {'DISPLAY': ':0', 11:30:57 INFO - 'HOME': '/home/cltbld', 11:30:57 INFO - 'LANG': 'en_US.UTF-8', 11:30:57 INFO - 'LANGUAGE': 'en_US:en', 11:30:57 INFO - 'LOGNAME': 'cltbld', 11:30:57 INFO - 'MAIL': '/var/mail/cltbld', 11:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:57 INFO - 'MOZ_NO_REMOTE': '1', 11:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:57 INFO - 'NO_EM_RESTART': '1', 11:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:57 INFO - 'PWD': '/builds/slave/test', 11:30:57 INFO - 'SHELL': '/bin/bash', 11:30:57 INFO - 'SHLVL': '1', 11:30:57 INFO - 'TERM': 'linux', 11:30:57 INFO - 'TMOUT': '86400', 11:30:57 INFO - 'USER': 'cltbld', 11:30:57 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:57 INFO - '_': '/tools/buildbot/bin/python'} 11:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:57 INFO - Downloading/unpacking jsonschema==2.5.1 11:30:57 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 11:30:57 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 11:30:57 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 11:30:57 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 11:30:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:30:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:30:59 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 11:30:59 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 11:30:59 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 11:30:59 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 11:30:59 INFO - Downloading functools32-3.2.3-2.tar.gz 11:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:30:59 INFO - warning: no files found matching '*.txt' 11:30:59 INFO - no previously-included directories found matching 'build' 11:30:59 INFO - no previously-included directories found matching 'dist' 11:30:59 INFO - no previously-included directories found matching '.git*' 11:30:59 INFO - Installing collected packages: jsonschema, functools32 11:30:59 INFO - Running setup.py install for functools32 11:30:59 INFO - warning: no files found matching '*.txt' 11:30:59 INFO - no previously-included directories found matching 'build' 11:30:59 INFO - no previously-included directories found matching 'dist' 11:30:59 INFO - no previously-included directories found matching '.git*' 11:30:59 INFO - Successfully installed jsonschema functools32 11:30:59 INFO - Cleaning up... 11:30:59 INFO - Return code: 0 11:30:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:30:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:30:59 INFO - Using env: {'DISPLAY': ':0', 11:30:59 INFO - 'HOME': '/home/cltbld', 11:30:59 INFO - 'LANG': 'en_US.UTF-8', 11:30:59 INFO - 'LANGUAGE': 'en_US:en', 11:30:59 INFO - 'LOGNAME': 'cltbld', 11:30:59 INFO - 'MAIL': '/var/mail/cltbld', 11:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:59 INFO - 'MOZ_NO_REMOTE': '1', 11:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:59 INFO - 'NO_EM_RESTART': '1', 11:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:59 INFO - 'PWD': '/builds/slave/test', 11:30:59 INFO - 'SHELL': '/bin/bash', 11:30:59 INFO - 'SHLVL': '1', 11:30:59 INFO - 'TERM': 'linux', 11:30:59 INFO - 'TMOUT': '86400', 11:30:59 INFO - 'USER': 'cltbld', 11:30:59 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:59 INFO - '_': '/tools/buildbot/bin/python'} 11:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:30:59 INFO - Cleaning up... 11:30:59 INFO - Return code: 0 11:30:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:30:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:30:59 INFO - Using env: {'DISPLAY': ':0', 11:30:59 INFO - 'HOME': '/home/cltbld', 11:30:59 INFO - 'LANG': 'en_US.UTF-8', 11:30:59 INFO - 'LANGUAGE': 'en_US:en', 11:30:59 INFO - 'LOGNAME': 'cltbld', 11:30:59 INFO - 'MAIL': '/var/mail/cltbld', 11:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:59 INFO - 'MOZ_NO_REMOTE': '1', 11:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:59 INFO - 'NO_EM_RESTART': '1', 11:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:59 INFO - 'PWD': '/builds/slave/test', 11:30:59 INFO - 'SHELL': '/bin/bash', 11:30:59 INFO - 'SHLVL': '1', 11:30:59 INFO - 'TERM': 'linux', 11:30:59 INFO - 'TMOUT': '86400', 11:30:59 INFO - 'USER': 'cltbld', 11:30:59 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:59 INFO - '_': '/tools/buildbot/bin/python'} 11:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:59 INFO - Downloading/unpacking blobuploader==1.2.4 11:30:59 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 11:30:59 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 11:30:59 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 11:30:59 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 11:31:00 INFO - Downloading blobuploader-1.2.4.tar.gz 11:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:31:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:31:00 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 11:31:00 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 11:31:00 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 11:31:00 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 11:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:31:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:01 INFO - Downloading docopt-0.6.1.tar.gz 11:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:31:01 INFO - Installing collected packages: blobuploader, requests, docopt 11:31:01 INFO - Running setup.py install for blobuploader 11:31:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:31:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:31:01 INFO - Running setup.py install for requests 11:31:01 INFO - Running setup.py install for docopt 11:31:02 INFO - Successfully installed blobuploader requests docopt 11:31:02 INFO - Cleaning up... 11:31:02 INFO - Return code: 0 11:31:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:02 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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:31:02 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 11:31:02 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 11:31:02 INFO - Using env: {'DISPLAY': ':0', 11:31:02 INFO - 'HOME': '/home/cltbld', 11:31:02 INFO - 'LANG': 'en_US.UTF-8', 11:31:02 INFO - 'LANGUAGE': 'en_US:en', 11:31:02 INFO - 'LOGNAME': 'cltbld', 11:31:02 INFO - 'MAIL': '/var/mail/cltbld', 11:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:02 INFO - 'MOZ_NO_REMOTE': '1', 11:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:02 INFO - 'NO_EM_RESTART': '1', 11:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:02 INFO - 'PWD': '/builds/slave/test', 11:31:02 INFO - 'SHELL': '/bin/bash', 11:31:02 INFO - 'SHLVL': '1', 11:31:02 INFO - 'TERM': 'linux', 11:31:02 INFO - 'TMOUT': '86400', 11:31:02 INFO - 'USER': 'cltbld', 11:31:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:02 INFO - '_': '/tools/buildbot/bin/python'} 11:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:02 INFO - Running setup.py (path:/tmp/pip-zaZjhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:02 INFO - Running setup.py (path:/tmp/pip-0_Eiwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:02 INFO - Running setup.py (path:/tmp/pip-xTDPsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:02 INFO - Running setup.py (path:/tmp/pip-qhy4d0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:02 INFO - Running setup.py (path:/tmp/pip-W6KLei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:02 INFO - Running setup.py (path:/tmp/pip-QZmntp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:02 INFO - Running setup.py (path:/tmp/pip-lxaQ7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:02 INFO - Running setup.py (path:/tmp/pip-OI1wrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:02 INFO - Running setup.py (path:/tmp/pip-MXZ39O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:02 INFO - Running setup.py (path:/tmp/pip-LNn7Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:03 INFO - Running setup.py (path:/tmp/pip-jSow2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:03 INFO - Running setup.py (path:/tmp/pip-nAuH3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:03 INFO - Running setup.py (path:/tmp/pip-8aLRg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:03 INFO - Running setup.py (path:/tmp/pip-Jl1r56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:03 INFO - Running setup.py (path:/tmp/pip-boXREs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:03 INFO - Running setup.py (path:/tmp/pip-Hv9Ya3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:03 INFO - Running setup.py (path:/tmp/pip-5C65wY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:31:03 INFO - Running setup.py install for manifestparser 11:31:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:31:03 INFO - Running setup.py install for mozcrash 11:31:03 INFO - Running setup.py install for mozdebug 11:31:03 INFO - Running setup.py install for mozdevice 11:31:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:31:03 INFO - Running setup.py install for mozfile 11:31:04 INFO - Running setup.py install for mozhttpd 11:31:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Running setup.py install for mozinfo 11:31:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Running setup.py install for mozInstall 11:31:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Running setup.py install for mozleak 11:31:04 INFO - Running setup.py install for mozlog 11:31:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Running setup.py install for moznetwork 11:31:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Running setup.py install for mozprocess 11:31:04 INFO - Running setup.py install for mozprofile 11:31:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Running setup.py install for mozrunner 11:31:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Running setup.py install for mozscreenshot 11:31:05 INFO - Running setup.py install for moztest 11:31:05 INFO - Running setup.py install for mozversion 11:31:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:31:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:31:05 INFO - Cleaning up... 11:31:05 INFO - Return code: 0 11:31:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:05 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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:31:05 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 11:31:05 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 11:31:05 INFO - Using env: {'DISPLAY': ':0', 11:31:05 INFO - 'HOME': '/home/cltbld', 11:31:05 INFO - 'LANG': 'en_US.UTF-8', 11:31:05 INFO - 'LANGUAGE': 'en_US:en', 11:31:05 INFO - 'LOGNAME': 'cltbld', 11:31:05 INFO - 'MAIL': '/var/mail/cltbld', 11:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:05 INFO - 'MOZ_NO_REMOTE': '1', 11:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:05 INFO - 'NO_EM_RESTART': '1', 11:31:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:05 INFO - 'PWD': '/builds/slave/test', 11:31:05 INFO - 'SHELL': '/bin/bash', 11:31:05 INFO - 'SHLVL': '1', 11:31:05 INFO - 'TERM': 'linux', 11:31:05 INFO - 'TMOUT': '86400', 11:31:05 INFO - 'USER': 'cltbld', 11:31:05 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:05 INFO - '_': '/tools/buildbot/bin/python'} 11:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:05 INFO - Running setup.py (path:/tmp/pip-Bl8eeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:05 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)) 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:05 INFO - Running setup.py (path:/tmp/pip-pgvn0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:05 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)) 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:05 INFO - Running setup.py (path:/tmp/pip-rgrwsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:05 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)) 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:05 INFO - Running setup.py (path:/tmp/pip-4Fi52X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:05 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)) 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:05 INFO - Running setup.py (path:/tmp/pip-OdCfPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:05 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)) 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:05 INFO - Running setup.py (path:/tmp/pip-R3rwrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:05 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)) 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:05 INFO - Running setup.py (path:/tmp/pip-B2PHCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:06 INFO - Running setup.py (path:/tmp/pip-NZ8RpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:06 INFO - Running setup.py (path:/tmp/pip-Vg_W9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:06 INFO - Running setup.py (path:/tmp/pip-aSAsMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:06 INFO - Running setup.py (path:/tmp/pip-J8wKj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:06 INFO - Running setup.py (path:/tmp/pip-J47GIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:06 INFO - Running setup.py (path:/tmp/pip-ooLHEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:06 INFO - Running setup.py (path:/tmp/pip-Eeon2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:06 INFO - Running setup.py (path:/tmp/pip-wZJLab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:06 INFO - Running setup.py (path:/tmp/pip-5NUX4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:06 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)) 11:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:06 INFO - Running setup.py (path:/tmp/pip-dLBZy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:07 INFO - Downloading blessings-1.6.tar.gz 11:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:31:07 INFO - Installing collected packages: blessings 11:31:07 INFO - Running setup.py install for blessings 11:31:08 INFO - Successfully installed blessings 11:31:08 INFO - Cleaning up... 11:31:08 INFO - Return code: 0 11:31:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:31:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:08 INFO - Reading from file tmpfile_stdout 11:31:08 INFO - Current package versions: 11:31:08 INFO - argparse == 1.2.1 11:31:08 INFO - blessings == 1.6 11:31:08 INFO - blobuploader == 1.2.4 11:31:08 INFO - docopt == 0.6.1 11:31:08 INFO - functools32 == 3.2.3-2 11:31:08 INFO - jsonschema == 2.5.1 11:31:08 INFO - manifestparser == 1.1 11:31:08 INFO - mozInstall == 1.13 11:31:08 INFO - mozcrash == 1.0 11:31:08 INFO - mozdebug == 0.1 11:31:08 INFO - mozdevice == 0.51 11:31:08 INFO - mozfile == 1.2 11:31:08 INFO - mozhttpd == 0.7 11:31:08 INFO - mozinfo == 0.10 11:31:08 INFO - mozleak == 0.1 11:31:08 INFO - mozlog == 3.5 11:31:08 INFO - moznetwork == 0.27 11:31:08 INFO - mozprocess == 0.25 11:31:08 INFO - mozprofile == 0.28 11:31:08 INFO - mozrunner == 6.13 11:31:08 INFO - mozscreenshot == 0.1 11:31:08 INFO - mozsystemmonitor == 0.3 11:31:08 INFO - moztest == 0.8 11:31:08 INFO - mozversion == 1.4 11:31:08 INFO - psutil == 3.1.1 11:31:08 INFO - requests == 1.2.3 11:31:08 INFO - wsgiref == 0.1.2 11:31:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31: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')]} 11:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31: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')]} 11:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:08 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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:31:08 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 11:31:08 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 11:31:08 INFO - Using env: {'DISPLAY': ':0', 11:31:08 INFO - 'HOME': '/home/cltbld', 11:31:08 INFO - 'LANG': 'en_US.UTF-8', 11:31:08 INFO - 'LANGUAGE': 'en_US:en', 11:31:08 INFO - 'LOGNAME': 'cltbld', 11:31:08 INFO - 'MAIL': '/var/mail/cltbld', 11:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:08 INFO - 'MOZ_NO_REMOTE': '1', 11:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:08 INFO - 'NO_EM_RESTART': '1', 11:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:08 INFO - 'PWD': '/builds/slave/test', 11:31:08 INFO - 'SHELL': '/bin/bash', 11:31:08 INFO - 'SHLVL': '1', 11:31:08 INFO - 'TERM': 'linux', 11:31:08 INFO - 'TMOUT': '86400', 11:31:08 INFO - 'USER': 'cltbld', 11:31:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:08 INFO - '_': '/tools/buildbot/bin/python'} 11:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:31:09 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)) 11:31:09 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)) 11:31:09 INFO - Installing collected packages: simplejson 11:31:09 INFO - Running setup.py install for simplejson 11:31:09 INFO - building 'simplejson._speedups' extension 11:31:09 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 11:31:10 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 11:31:10 INFO - Successfully installed simplejson 11:31:10 INFO - Cleaning up... 11:31:10 INFO - Return code: 0 11:31:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:31:10 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')]} 11:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:10 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')]} 11:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:10 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 0x28ce1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, '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': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', '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 11:31:10 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 11:31:10 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 11:31:10 INFO - Using env: {'DISPLAY': ':0', 11:31:10 INFO - 'HOME': '/home/cltbld', 11:31:10 INFO - 'LANG': 'en_US.UTF-8', 11:31:10 INFO - 'LANGUAGE': 'en_US:en', 11:31:10 INFO - 'LOGNAME': 'cltbld', 11:31:10 INFO - 'MAIL': '/var/mail/cltbld', 11:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:10 INFO - 'MOZ_NO_REMOTE': '1', 11:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:10 INFO - 'NO_EM_RESTART': '1', 11:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:10 INFO - 'PWD': '/builds/slave/test', 11:31:10 INFO - 'SHELL': '/bin/bash', 11:31:10 INFO - 'SHLVL': '1', 11:31:10 INFO - 'TERM': 'linux', 11:31:10 INFO - 'TMOUT': '86400', 11:31:10 INFO - 'USER': 'cltbld', 11:31:10 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:10 INFO - '_': '/tools/buildbot/bin/python'} 11:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:31:10 INFO - Cleaning up... 11:31:10 INFO - Return code: 0 11:31:10 INFO - Running post-action listener: _resource_record_post_action 11:31:10 INFO - Running post-action listener: _start_resource_monitoring 11:31:11 INFO - Starting resource monitoring. 11:31:11 INFO - [mozharness: 2017-09-03 18:31:11.005189Z] Finished create-virtualenv step (success) 11:31:11 INFO - [mozharness: 2017-09-03 18:31:11.005695Z] Running install step. 11:31:11 INFO - Running pre-action listener: _resource_record_pre_action 11:31:11 INFO - Running main action method: install 11:31:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:11 INFO - Reading from file tmpfile_stdout 11:31:11 INFO - Detecting whether we're running mozinstall >=1.0... 11:31:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:31:11 INFO - Reading from file tmpfile_stdout 11:31:11 INFO - Output received: 11:31:11 INFO - Usage: mozinstall [options] installer 11:31:11 INFO - Options: 11:31:11 INFO - -h, --help show this help message and exit 11:31:11 INFO - -d DEST, --destination=DEST 11:31:11 INFO - Directory to install application into. [default: 11:31:11 INFO - "/builds/slave/test"] 11:31:11 INFO - --app=APP Application being installed. [default: firefox] 11:31:11 INFO - mkdir: /builds/slave/test/build/application 11:31:11 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'] 11:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:31:27 INFO - Reading from file tmpfile_stdout 11:31:27 INFO - Output received: 11:31:27 INFO - /builds/slave/test/build/application/firefox/firefox 11:31:27 INFO - Running post-action listener: _resource_record_post_action 11:31:27 INFO - [mozharness: 2017-09-03 18:31:27.900621Z] Finished install step (success) 11:31:27 INFO - [mozharness: 2017-09-03 18:31:27.900877Z] Running setup-mitmproxy step. 11:31:27 INFO - Running pre-action listener: _resource_record_pre_action 11:31:27 INFO - Running main action method: setup_mitmproxy 11:31:27 INFO - Skipping: mitmproxy is not required 11:31:27 INFO - Running post-action listener: _resource_record_post_action 11:31:27 INFO - [mozharness: 2017-09-03 18:31:27.902053Z] Finished setup-mitmproxy step (success) 11:31:27 INFO - [mozharness: 2017-09-03 18:31:27.902273Z] Running run-tests step. 11:31:27 INFO - Running pre-action listener: _resource_record_pre_action 11:31:27 INFO - Running pre-action listener: _set_gcov_prefix 11:31:27 INFO - Running main action method: run_tests 11:31:27 WARNING - Try message not found. 11:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:31:27 INFO - Python 2.7.3 11:31:27 INFO - Return code: 0 11:31:27 INFO - grabbing minidump binary from tooltool 11:31: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')]} 11:31:27 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 0x2c3e090>, '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 0x2c3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c38f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:31:27 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 11:31:27 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 11:31:27 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 11:31:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:31:28 INFO - Return code: 0 11:31:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:31:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:31:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:31:28 INFO - ENV: RUST_BACKTRACE is now full 11:31:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:31:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:31:28 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/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 11:31:28 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/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 11:31:28 INFO - Using env: {'DISPLAY': ':0', 11:31:28 INFO - 'HOME': '/home/cltbld', 11:31:28 INFO - 'LANG': 'en_US.UTF-8', 11:31:28 INFO - 'LANGUAGE': 'en_US:en', 11:31:28 INFO - 'LOGNAME': 'cltbld', 11:31:28 INFO - 'MAIL': '/var/mail/cltbld', 11:31:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:28 INFO - 'MOZ_NO_REMOTE': '1', 11:31:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:28 INFO - 'NO_EM_RESTART': '1', 11:31:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:28 INFO - 'PWD': '/builds/slave/test', 11:31:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:28 INFO - 'RUST_BACKTRACE': 'full', 11:31:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:28 INFO - 'SHELL': '/bin/bash', 11:31:28 INFO - 'SHLVL': '1', 11:31:28 INFO - 'STYLO_FORCE_ENABLED': '1', 11:31:28 INFO - 'STYLO_THREADS': '1', 11:31:28 INFO - 'TERM': 'linux', 11:31:28 INFO - 'TMOUT': '86400', 11:31:28 INFO - 'USER': 'cltbld', 11:31:28 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:28 INFO - '_': '/tools/buildbot/bin/python'} 11:31:28 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/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 11:31:28 INFO - mozversion application_buildid: 20170903173751 11:31:28 INFO - mozversion application_changeset: 0afabd3e5c27b0036517b96eecb1f8553d027179 11:31:28 INFO - mozversion application_display_name: Nightly 11:31:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:31:28 INFO - mozversion application_name: Firefox 11:31:28 INFO - mozversion application_remotingname: firefox 11:31:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:31:28 INFO - mozversion application_vendor: Mozilla 11:31:28 INFO - mozversion application_version: 57.0a1 11:31:28 INFO - mozversion platform_buildid: 20170903173751 11:31:28 INFO - mozversion platform_changeset: 0afabd3e5c27b0036517b96eecb1f8553d027179 11:31:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:31:28 INFO - mozversion platform_version: 57.0a1 11:31:28 INFO - using testdate: 1504463488 11:31:28 INFO - actual date: 1504463488 11:31:28 INFO - starting webserver on 'localhost:38997' 11:31:28 INFO - SUITE-START | Running 2 tests 11:31:28 INFO - TEST-START | dromaeo_css 11:31:28 INFO - Initialising browser for dromaeo_css test... 11:31:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38997/getInfo.html -profile /tmp/tmpm0KMJ3/profile 11:31:28 INFO - TEST-INFO | started process 26379 (/builds/slave/test/build/application/firefox/firefox http://localhost:38997/getInfo.html) 11:31:34 INFO - TEST-INFO | 26379: exit 0 11:31:34 INFO - Browser initialized. 11:31:34 INFO - Running cycle 1/1 for dromaeo_css test... 11:31:34 INFO - Using env: {'DISPLAY': ':0', 11:31:34 INFO - 'HOME': '/home/cltbld', 11:31:34 INFO - 'JSGC_DISABLE_POISONING': '1', 11:31:34 INFO - 'LANG': 'en_US.UTF-8', 11:31:34 INFO - 'LANGUAGE': 'en_US:en', 11:31:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:31:34 INFO - 'LOGNAME': 'cltbld', 11:31:34 INFO - 'MAIL': '/var/mail/cltbld', 11:31:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:34 INFO - 'MOZ_CRASHREPORTER': '1', 11:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:31:34 INFO - 'MOZ_NO_REMOTE': '1', 11:31:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:34 INFO - 'NO_EM_RESTART': '1', 11:31:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:34 INFO - 'PWD': '/builds/slave/test', 11:31:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:34 INFO - 'RUST_BACKTRACE': 'full', 11:31:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:34 INFO - 'SHELL': '/bin/bash', 11:31:34 INFO - 'SHLVL': '1', 11:31:34 INFO - 'STYLO_FORCE_ENABLED': '1', 11:31:34 INFO - 'STYLO_THREADS': '1', 11:31:34 INFO - 'TERM': 'linux', 11:31:34 INFO - 'TMOUT': '86400', 11:31:34 INFO - 'USER': 'cltbld', 11:31:34 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:34 INFO - '_': '/tools/buildbot/bin/python'} 11:31:34 INFO - TEST-INFO | started process 26560 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm0KMJ3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:35 INFO - PID 26560 | 11:31:35 INFO - PID 26560 | (/builds/slave/test/build/application/firefox/firefox:26612): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:31:35 INFO - PID 26560 | 11:31:45 INFO - PID 26560 | RSS: Main: 172134400 11:31:45 INFO - PID 26560 | 11:34:44 INFO - PID 26560 | Cycle 1(1): loaded http://localhost:38997/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38997/tests/dromaeo/cssquery-ext.html) 11:34:45 INFO - PID 26560 | RSS: Main: 174006272 11:34:45 INFO - PID 26560 | 11:37:47 INFO - PID 26560 | Cycle 1(1): loaded http://localhost:38997/tests/dromaeo/cssquery-ext.html (next: http://localhost:38997/tests/dromaeo/cssquery-jquery.html) 11:37:47 INFO - PID 26560 | RSS: Main: 168611840 11:37:47 INFO - PID 26560 | 11:40:49 INFO - PID 26560 | Cycle 1(1): loaded http://localhost:38997/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38997/tests/dromaeo/cssquery-mootools.html) 11:40:50 INFO - PID 26560 | RSS: Main: 172826624 11:40:50 INFO - PID 26560 | 11:43:51 INFO - PID 26560 | Cycle 1(1): loaded http://localhost:38997/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38997/tests/dromaeo/cssquery-prototype.html) 11:43:52 INFO - PID 26560 | RSS: Main: 171323392 11:43:52 INFO - PID 26560 | 11:46:56 INFO - PID 26560 | Cycle 1(1): loaded http://localhost:38997/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38997/tests/dromaeo/cssquery-yui.html) 11:46:56 INFO - PID 26560 | RSS: Main: 172417024 11:46:56 INFO - PID 26560 | 11:49:58 INFO - PID 26560 | Cycle 1(1): loaded http://localhost:38997/tests/dromaeo/cssquery-yui.html (next: http://localhost:38997/tests/dromaeo/cssquery-dojo.html) 11:49:58 INFO - PID 26560 | RSS: Main: 175071232 11:49:58 INFO - PID 26560 | 11:49:58 INFO - PID 26560 | __start_tp_report 11:49:58 INFO - PID 26560 | _x_x_mozilla_page_load 11:49:58 INFO - PID 26560 | _x_x_mozilla_page_load_details 11:49:58 INFO - PID 26560 | |i|pagename|runs| 11:49:58 INFO - PID 26560 | |0;dojo.html;3357.3621012007716;3372.426687463131;3377.476491143873;3404.8297585120736;3413.2320227948717;6457.838554036151;6465.9353406465925;6535.738570457182;6570.868582628356;6587.9670601646985;6395.968020159893;6460.257070436901;6469.1590093287805;6510.446612037982;6511.251206111298;5932.2584676915385;5939.020061689816;5955.07696307072;5964.970175149118;6058.454739073482;5940.584159108864;5970.402959704016;6014.097885317194;6030.276366835986;6069.484093852028;5776.1624564437925;6012.729427175744;6020.367861374536;6020.458158765732;6085.2697676278685;5897.056470964625;5939.940600593982;5946;6033.42682445167;6052.455279024909;5940.702964851784;5943.4056594340655;6024.548158888101;6035.939640603626;6038.064100064453;6331.2719037310835;6366.713497892606;6402.711877965503;6418;6575.868482630369;6870.381665650066;6875.346842050048;6887.690053947585;6925.065116209297;7004.894926576106;7075.504714669975;7102.893456598155;7109.040279562245;7113.359797618187;7171.569705817667;6446.903296450557;6519.315471875432;6571.244306904671;6609.537332386735;6614.040964060185;7217.314355136254;7255.383292420203;7310.086239220098;7328.560286382727;7342.632868356561;6597.8350541235895;6647.302033286435;6673.099131617267;6714.395704386531;6874.9656251718425;7042.859142817115;7081.150261968597;7095.254998225215;7105.89341159883;7108.786736397917;6938;6940.861182776316;6940.895886561605;7027.4729395294535;7054.400375967997;6756.763513277012;6842.965785171142;6941.583504989717;6960.895586566206;7036.9296307036275;5524.917126243109;5531.834044978657;5537.031019571559;5543.223948647193;5546.278983732089;4744.572988431058;4810.807567697253;4850.1269771441475;4860.538248866352;4881.243407271879;6055.545834062463;6087.756489740361;6091.908621370683;6154.753809847556;6180.289266734293;6803;6941.652917354213;6944.4444444444325;6977.476689248225;7101.147862256562;6062.878742425127;6064.3935606439;6067.787627433018;6074;6092.664903430265;5935.614185077956;5957.940420595825;6002.369751176151;6041.607295525777;6066.908996365058;6733.663316834139;6751.0886030386255;6846.075779769767;6849.315068493111;6857.759978400732;6863.142107236596;6971.337722916315;6994.125734283215;7091.042709234187;7098.36114749675;6660.733570657119;6770.830729231809;6824.965875170592;6840.384365407137;6844.28135045813;6832.248452670302;6960.651967401711;6976.7558135465015;6988.266232045766;6995.580265184105;7002.1947476040905;7008.194057683431;7011.404030657452;7100.857982840417;7117.1459424869345;6550.803475895731;6631.436327911989;6648.734050638114;6761.425278851353;6851.623160726208;6786.049953006484;6793.286704895915;6813.080234168522;6849.828754281183;6856.794296171123;6631.801045968628;6721.93278067213;6771.729130834711;6801.353871382211;6819.897701534382;6745.190577130776;6806.693698783665;6882.414994725505;6896.793096207121;6911.827204319932;6385.584936979081;6500.479961603155;6554.2462616797775;6662.067310576426;6664.400203981665 11:49:58 INFO - PID 26560 | |1;ext.html;817.3012074676152;837.4347315561994;854.1799872122762;885.3138817416501;891.5007595746381;40707.38938915915;41562.16875662485;41735.79132104343;41764.79117604408;41789.79105104474;37882.8105859471;38095.42856857149;38161.23677526448;38176.045598860044;38213.23573528535;6884.208316043657;7002.299770023008;7010.298970103;7011.579305241677;7014.929850701505;6922.619255940921;7018.403435707971;7020;7020.648967551628;7022.016917631536;7663.885041724378;7844.333231675286;7848.372130229596;7848.803779905519;7851.960740196291;7737;7800.141984381685;7808.804779880548;7809.453338266324;7811;7513.962430187868;7628.542287462715;7693.846123077563;7709.7301594443925;7745.380369570477;38111.61888381108;38324.80837595822;38516.229675406335;38646.420303695464;38677.613223867964;3518.0852978225844;3554.2003049313957;3573.87491437801;3575.213453040327;3577.3203091412547;31327.843360783278;31491.212719681964;31727.20681982946;31751.682483175104;31823.681763182303;5075.314832497639;5096.974515127437;5125.2312153176945;5139.10065738498;5147.588192944556;177434.11282943632;178257;186376;191184;200750.99999999854;49140.50859491431;49389;49486.257706134435;49515.25727114096;49783.751081244365;50702;50809.49190508122;50923.7453812736;51031.234531482056;51051;15930.203489825462;16139.354425822836;16158.434454794025;16158.75761863573;16285.104319262316;13441.193528388329;13459.93270033663;13462.192268463923;13469.797953030713;13475.932620336836;14149.85850141506;14208.360623771956;14222.644433889236;14244.572662820132;14377;5674.886502269931;5706.942930570641;5762.349319313079;5782.161586569924;5813.273340832396;987.1017374189597;1000.5747557288123;1007.471077684207;1009.2531526670211;1011.8279892418307;22402.663960040612;22527.211547595754;22551;22677.319680409615;22685.886570567043;1081.7782354617284;1103.0679076180616;1108.2020944919645;1110.0564520157964;1118.972025699364;11587.768244635061;12029.578964736193;12051.75896482083;12119.818202726967;12196.207246528948;246221;247151;248401;248846;248899;50738;51113.233301500506;51230.48769512332;51474.227886580986;51532.22701659478;1375.7592421326228;1410.5345236072071;1416.9433222671184;1422.267532220907;1435.6841494871112;22078.22726204575;22184;22188.334349969526;22313.888430558392;22542.887285563465;49532.75233623809;50594.49405505898;50729;50831;50854.237186441496;212108;213268;214482;214732;215113;30817.383652326378;31321.686783131878;31411.37177256488;35033.29933401267;35107.824460877535;43138.352924706785;43147;43276.13447731091;43310.78344608257;43648.56351436572;31795.682043179273;31866.522002169524;31983.680163199002;32280;32563.674363256065;31585.36829263356;32590;32597;32759.344813104082;33423.16442088967;37681;37723.81138094292;37730.62269377271;37987;38013;32638.184045399055;32791.67208327982;33864.83067584646;33961.320773584885;34416.65583344134;24241.154492714555;24251.393715156555;24444.633330499702;24478;24823.503529928937 11:49:58 INFO - PID 26560 | |2;jquery.html;578.176099058841;578.2020811280435;579.2643342954451;587.7090840034182;588.7909792023831;22291.77708222917;22379.888100559496;23423.531529369404;23441.5311693766;23450.413739656517;25764.742352576515;25949.351266218353;26151.869240653818;26190.47619047618;26339.341516462096;6458.806235812933;6616.305287944769;6629.569078009938;6639.634820084893;6639.966800165992;6000.159977603139;6494.51291153163;6509.121268628745;6533.379328963741;6619.503537234703;6468.8059358219325;6471;6475.449586785129;6475.805725828232;6480.513961452886;6459.483241340681;6481.449076828499;6486.578372405802;6498.805035848932;6499.382558656967;6037.245344331958;6056.242969628796;6062.696865156769;6063.090536419529;6063.939360606381;28723.138305850858;29559.113226603236;29670.258243543867;29924.70075299241;30097.247568810737;2011.145263263122;2039.377989713137;2041.5508588110592;2042.7446569178853;2043.7138800567932;19886.80113198864;20384.796152038438;20401.4899627509;20408.489787755276;20458.488537786525;2365.6806331145417;2412.0592968742203;2414.6498757680038;2414.7223069347074;2450.8161887858305;138808;141871;142003.28998355046;142241;142369;15044.968929194418;15377.308021139075;15417.306221220071;15440.305186266645;15521;23515.294541163792;23600.29199124029;23639;23663.29010129699;23725.52548949011;10304;10370;10434.06093451593;10518.474076296154;10532.842007369896;9814.116729494379;9831.852522212173;9913;9924.950375248078;9932.155766759906;7693.614809483809;9263.888333400035;9399.436033837992;9486.90513094874;9493.905060949302;2703.297142742901;2706;2712.443948990452;2728.822626529269;2811.943761124766;958.9232861371073;974.936629119105;979.6375341123687;981.7496538382667;989.8911119776819;19785;20132.39602811918;20416.285430010175;20448.591028179355;20635;1041.250299784154;1056.5615269663003;1058.5818601652304;1059.6079450603327;1060.931039482431;11094;11489.942550287195;11618.593349232737;13082.934585327203;14331.355089021019;447975;483553;486202;488688;491794;116627;118337;119062.404687976;119108.40445797716;119555;1222.2544248008708;1237.55448038706;1254.7490501899656;1265.411583613624;1266.0504621534033;21219;21248.15007399687;21289.893550532768;21310.467238319165;21818.018189181843;58240.4175958235;58538.7073064632;59393.40606593879;59686.70156649189;60234.39765602288;67435;67766.66116669386;67836.321636783;67981;67986;12576.182548134342;13066.477340906258;14127.505537306555;14479.348429320495;14969;22054.33836984893;22942;23070.42323941915;23232.070717171126;23557.882210588836;18039.458816235532;18287.177077031298;18402.35591754282;18420.631587368447;18441.446756597325;16514.42199523011;16755.078470684228;17099.230534625724;17298.22158002918;17301.480955571355;17304;18191.45425637233;18206.72689909679;18414.447566573024;18455.446336609923;13788.379522921694;13938.58184254474;13997.09018913808;14047.297635117999;14101.647458813612;18134.45596632103;18150.18324175389;18943;19055.237790488227;19240.326588569333 11:49:58 INFO - PID 26560 | |3;mootools.html;1317.9538716144934;1320.0495643136944;1369.4043091255305;1371.300636675296;1377.9311034448288;15005.549833504985;15283.541493755178;15554.144522051283;15612;15666.451674191405;15711;15953.361865525365;15958.84041159588;16013.039217646949;16013.679726405466;2624.6587943567356;2686.274705829435;2689.4889970905565;2690.704022557527;2700.972990270092;2650.681918169813;2659.3218729224022;2734.5214587447217;2736.3843135294596;2754.8898044078214;2760.089170573716;2801.9159425217176;2804.383035732136;2804.6914839367655;2808.326001759573;2617.803664725144;2640.2079376187066;2645.3254420122844;2646;2646.2855029142024;2630.250378642097;2635.341164708823;2646.087099950514;2649.6158057081616;2653.3234025323613;14832.555023349316;15270.923645381814;15279.923600382039;15287.694246115127;15291.617709557238;7968.282854543119;8264.793380165484;8270.710525131592;8275.213854683796;8275.213854683856;5382.93172891452;5400.189971504266;5473.31583552056;5517.5585953123655;5628.43715628438;1905.5236190952262;1942.4949513126548;1956.1099699636632;1993.8404927605757;2004.5890592428518;26776.59835102475;27009.45981080387;27052.188434346805;27093.1872043839;27104.32239194036;6762.391384775296;7093.1488221413765;7152.785416437515;7403.555786652818;7469.925300746923;17177.71167162468;17263.13684315779;17284.481465556055;17296;17337.739933901;7864.056313242333;8015.679372825021;8026.398020148512;8030.759077227693;8038.517688938682;7325.560466372035;7400.703971841173;7471.925280747231;7501.249875012564;7501.887471687929;4881.658283920163;5039.370078740158;5046.646734728533;5063.265826455144;5065.518775716375;1327.2036777933163;1369.4179973511218;1413.5123382433044;1442.163545143814;1485.472657206676;919.6137622198693;942.222666300305;948.691675205561;957.9616815327448;960.6877764726352;8569.914300856912;8884.511351875579;8924.107589241024;8928.285737141014;8968.372213945091;998.1515711645044;1001.7645853224486;1005.6429967361481;1012.3976234140674;1017.8218811707922;4495.550444955478;4710.387649605598;5054.949450505522;5370.194470829407;5590.217369568264;20990.160393584392;22067.2276470326;22132.557348852934;22193.667094993587;22264.888675556842;21053.789462105487;22102.115915363527;22207.77792222057;22217.77782222157;22219.55560888773;11608.535658573732;11734.765304694029;11762.235454695418;11763.470643820878;11770.58802941893;7709.7687069385765;8442.831143377221;8545.444546104483;8557.187067228604;8562.972443306593;18906.338278160747;18984.335548255745;19142.23431062742;19202.7119593209;19207.711884321467;19285.325013624457;19528.90235548813;19757.209711611373;19877.900610496854;19946.900265498578;12240.69398265015;12343.938280308541;12455.750884982068;12486.18839775448;12491.500339986662;14167.291635417982;14291.499797507038;14294.070885392832;14305.499307524187;14345.713085738436;11063.225574209728;11325.32048077118;11370.374629395132;11489.138314626603;11579.942100289445;10460.895391045991;10673.252872298865;10735.570577176826;10859.2398532102;10970.725731856768;10218.795624087625;10517.579296828042;10530.947345263225;10572.947135264581;10574.947125264633;7143.107111611049;7220.241874603301;7225.205227425085;7250.238724934017;7251.311125443074;6941.82645433848;7023.47323950716;7029.226785053538;7041.823954401181;7068.540544864567 11:49:58 INFO - PID 26560 | |4;prototype.html;496.726800259857;501.8569707633323;528.1997773373338;532.9467053294671;535.5902734408179;6394.712237949292;6418;6470.3529647035275;6523.347665233474;6575.9342406575915;6233.257366758623;6275.905861412082;6300.613864949715;6352.333005034463;6363.649999250039;5029.446760856321;5052.8484145475695;5138.3577052868395;5164.8708782280555;5177.9094515332845;4905.926411103818;5082.390113186423;5083.271687626207;5121.564667003309;5162.174052151657;5074.188129899217;5092.159793634046;5094.872628184288;5130.717810520438;5147.408048074463;5053.343065401493;5090.669106508084;5116.2069879168785;5124.847564023119;5164.612654050924;4558.61251793598;4565.155446242423;4589.173948689235;4616.953830461686;4635.351050852883;6320.93679063208;6349.4602958748565;6374.840628984312;6378.553501254914;6415.807525774234;5863.120531920204;5890.823275301748;5897.764089436418;5901.085331773582;5904.61619994699;6614.305497922697;6625.271220165778;6664.566803157779;6678.799636010927;6731.663416829188;4921.3984924096085;4921.926171107436;4940.950590494085;4953.628477864175;4969.453360130383;7057.553741143099;7094.184168820651;7164.820879478003;7175.31834475724;7283.308085731847;5092.9236061459105;5110.795568177231;5137.379936232548;5162;5162.97418512905;5176.378834539804;5208.583313334924;5223.190405487156;5226.477352264819;5246.737663116829;5825.2135961644635;5888.175655408247;5905.379935106838;5911.290645122613;5941.970290148522;6691.297413771581;6780.287700048419;6784.3894049535775;6852.314768523108;6882.174139103339;1685.8229885861808;1730.9134543272785;1906.560546787167;1962.6271008508338;1986.5927484865567;1431.9069260498034;1448.4061534770694;1459.5331691649824;1483.4140514496928;1491.1798510819006;5841.853953651108;5895.614530585309;5922.733476993546;5965.910511342333;6051.697415129226;6714.630695311707;6740.258571557125;6893.586384816927;6921.792346229621;6980.685869135901;5921.259842519685;5939.554533410012;5942.940570594239;5958.970205148947;5961.469283344602;3563.966449729601;3637.8181090945345;3673.9265214695556;3675.9081022974638;3703.759255648374;6607.603543787292;6637.435817955431;6650.501212409089;6686.83282917931;6745.696443660047;6974.581525108409;7060.54106483077;7075.823104422328;7083.6458177090935;7108.964455177691;6688.889666305095;6707.128073350531;6749.2238392584495;6749.595024298558;6759.2226893906845;6945.895811562729;6966.12923384577;7009.228984811769;7010.579365238102;7021;6880.208775990825;6880.415164711056;6906.548175397862;6910.585364878123;6935.375816176568;6729.158855143107;6763.86472270542;6770.8645827084165;6783.423409010092;6826.112605361272;5142.76857541419;5146.099432599355;5161.870953226199;5171.224316352577;5171.741412929414;6792.796216113525;6965.805771182061;7020;7057.576545407292;7069.469789765891;6463.192100987377;6670.699818508276;6707.664616769044;6727.394534491919;6830.487713421613;6649.60102393858;6741.292164322872;6742.8651426972165;6752.189737231563;6764.188297404343;6528.738850445929;6640.634765087966;6655.534112612264;6669.765628514502;6687.398134167948;6707.463402927657;6769.018492318684;6773.830654233487;6789.592624442549;6795.864082718417;6563.442107420923;6575.671216439255;6584;6596.637184954873;6633.336666333328 11:49:58 INFO - PID 26560 | |5;yui.html;837.4682076881182;852.8891244138262;875.662869795129;914.638717706506;932.2122806228734;18768.061596920168;18988.810111898878;20075.799242007575;20109.798902010974;20140.194392224294;19350.612987740238;19367.51581210467;19422.41732748016;19426.805731942677;19457.90271048643;708.1113202930316;725.8729722298576;935.2190920581323;976.501983988164;991.2565575818154;991.013941128578;996.4170959988415;997.5660587644362;997.9850302245472;998.3311181508407;1027.5478789332708;1028.3058935218735;1030.160419258302;1032.8192566300904;1033.2767063055853;1026.3687831983314;1026.4559783314892;1027.2911690933306;1028.1671845804922;1031.4791030529573;754.3324158120048;755.5051441305953;755.7128291249345;756.0134025097301;756.1682149635412;17402.825971740374;17569.56076098095;17657.293708251655;17700.026498542575;17976.640467190584;20125.79874201254;20191.798082019137;20562.69155962662;20588.38234852957;20616.278430254868;17098.743518847226;17387.478375648752;17470.737938930924;17495.65008699819;17593.912030439766;3720.6651401374006;3758.3798673218885;3758.3986562149926;3758.9436158457647;3761.5110035695457;122235;124557.37721311426;125361.37319313436;126178.36910815479;127204.36397818044;15337.77297816172;15801.20993950321;15939.362425503083;15963.840361596467;16026.439074632332;30545;30969.69030309713;31398.372032559222;31579.684203158133;31657.36685266328;9353.532323383804;9375.812483750424;9379.062093790566;9444.244460443148;9453.76365590852;3708.0915175782106;3859.9807000964815;4324.2648749712625;4345.34819777036;4443.955560444419;5231.163013917755;5359.812406565751;5373.489518495737;5377.838664840061;5390.245365648812;1023.9897601023895;1053.4574694032578;1065.6962765611763;1066.3814987307344;1067.7971185474735;659.3406593406594;674.9021391898245;680.5848432456198;680.8944613584903;686.1423220973783;11221.765605783357;11698.941505292589;11829.52681892732;12019.33893635841;12113.145090532124;672.277301900459;673.8012286375531;678.3149019490351;679.7179170644127;680.4726337088796;5560.972195138998;5705.087186050212;5857.648541087548;6146.416090471398;6560.835979100465;109387.45306273577;109839;110669;114763;115717.42141289239;99949;100404.49797750964;101156;102185.48907255565;106548.46725766321;86537.56731216304;87992;89318.10681893228;90692;92962.53518732499;11834.585789497327;11872.347020913932;12178.939105304416;12411.689707757378;12473.064520161206;42008.369874451295;42133.57866421419;42229;42372.36441453442;43866;55234;55376;56606.716966414904;56682.716586416806;58777;17725.556861078578;18102.90948545249;18258.90870545692;18294.451166465027;18424.723629145843;19756.604867902275;20008.39974800758;20183.39449816508;20202.29291974774;20290;18341.72487412663;18444.35444759427;18560.350387736904;18639.72040419422;18666.71999919975;16684.66630667404;16780.58048548796;16844.915775421043;16995.66008679844;17041.23314450828;17085.82914170892;17128.743068853728;17262.741058883876;17297.91351043287;17410.303587856342;11688.649340519798;11970.401479925795;12036.518539258332;12189.75620487603;12241.632751017483;13498.46006159782;13783.931080344533;13825.723485530032;13879.653008674864;14036.508722194674 11:49:58 INFO - PID 26560 | __end_tp_report 11:49:58 INFO - PID 26560 | __start_cc_report 11:49:58 INFO - PID 26560 | _x_x_mozilla_cycle_collect,376 11:49:58 INFO - PID 26560 | __end_cc_report 11:49:58 INFO - PID 26560 | __startTimestamp1504464598622__endTimestamp 11:49:58 INFO - PID 26560 | 11:49:58 INFO - PID 26560 | ------- Summary: start ------- 11:49:58 INFO - PID 26560 | Number of tests: 6 11:49:58 INFO - PID 26560 | 11:49:58 INFO - PID 26560 | [#0] dojo.html Cycles:165 Average:6425.36 Median:6647.30 stddev:755.20 (11.4%) stddev-sans-first:718.14 11:49:58 INFO - PID 26560 | Values: 3357.4 3372.4 3377.5 3404.8 3413.2 6457.8 6465.9 6535.7 6570.9 6588.0 6396.0 6460.3 6469.2 6510.4 6511.3 5932.3 5939.0 5955.1 5965.0 6058.5 5940.6 5970.4 6014.1 6030.3 6069.5 5776.2 6012.7 6020.4 6020.5 6085.3 5897.1 5939.9 5946.0 6033.4 6052.5 5940.7 5943.4 6024.5 6035.9 6038.1 6331.3 6366.7 6402.7 6418.0 6575.9 6870.4 6875.3 6887.7 6925.1 7004.9 7075.5 7102.9 7109.0 7113.4 7171.6 6446.9 6519.3 6571.2 6609.5 6614.0 7217.3 7255.4 7310.1 7328.6 7342.6 6597.8 6647.3 6673.1 6714.4 6875.0 7042.9 7081.2 7095.3 7105.9 7108.8 6938.0 6940.9 6940.9 7027.5 7054.4 6756.8 6843.0 6941.6 6960.9 7036.9 5524.9 5531.8 5537.0 5543.2 5546.3 4744.6 4810.8 4850.1 4860.5 4881.2 6055.5 6087.8 6091.9 6154.8 6180.3 6803.0 6941.7 6944.4 6977.5 7101.1 6062.9 6064.4 6067.8 6074.0 6092.7 5935.6 5957.9 6002.4 6041.6 6066.9 6733.7 6751.1 6846.1 6849.3 6857.8 6863.1 6971.3 6994.1 7091.0 7098.4 6660.7 6770.8 6825.0 6840.4 6844.3 6832.2 6960.7 6976.8 6988.3 6995.6 7002.2 7008.2 7011.4 7100.9 7117.1 6550.8 6631.4 6648.7 6761.4 6851.6 6786.0 6793.3 6813.1 6849.8 6856.8 6631.8 6721.9 6771.7 6801.4 6819.9 6745.2 6806.7 6882.4 6896.8 6911.8 6385.6 6500.5 6554.2 6662.1 6664.4 11:49:58 INFO - PID 26560 | 11:49:58 INFO - PID 26560 | [#1] ext.html Cycles:180 Average:38713.59 Median:3564.04 stddev:56542.95 (1586.5%) stddev-sans-first:56629.96 11:49:58 INFO - PID 26560 | Values: 817.3 837.4 854.2 885.3 891.5 40707.4 41562.2 41735.8 41764.8 41789.8 37882.8 38095.4 38161.2 38176.0 38213.2 6884.2 7002.3 7010.3 7011.6 7014.9 6922.6 7018.4 7020.0 7020.6 7022.0 7663.9 7844.3 7848.4 7848.8 7852.0 7737.0 7800.1 7808.8 7809.5 7811.0 7514.0 7628.5 7693.8 7709.7 7745.4 38111.6 38324.8 38516.2 38646.4 38677.6 3518.1 3554.2 3573.9 3575.2 3577.3 31327.8 31491.2 31727.2 31751.7 31823.7 5075.3 5097.0 5125.2 5139.1 5147.6 177434.1 178257.0 186376.0 191184.0 200751.0 49140.5 49389.0 49486.3 49515.3 49783.8 50702.0 50809.5 50923.7 51031.2 51051.0 15930.2 16139.4 16158.4 16158.8 16285.1 13441.2 13459.9 13462.2 13469.8 13475.9 14149.9 14208.4 14222.6 14244.6 14377.0 5674.9 5706.9 5762.3 5782.2 5813.3 987.1 1000.6 1007.5 1009.3 1011.8 22402.7 22527.2 22551.0 22677.3 22685.9 1081.8 1103.1 1108.2 1110.1 1119.0 11587.8 12029.6 12051.8 12119.8 12196.2 246221.0 247151.0 248401.0 248846.0 248899.0 50738.0 51113.2 51230.5 51474.2 51532.2 1375.8 1410.5 1416.9 1422.3 1435.7 22078.2 22184.0 22188.3 22313.9 22542.9 49532.8 50594.5 50729.0 50831.0 50854.2 212108.0 213268.0 214482.0 214732.0 215113.0 30817.4 31321.7 31411.4 35033.3 35107.8 43138.4 43147.0 43276.1 43310.8 43648.6 31795.7 31866.5 31983.7 32280.0 32563.7 31585.4 32590.0 32597.0 32759.3 33423.2 37681.0 37723.8 37730.6 37987.0 38013.0 32638.2 32791.7 33864.8 33961.3 34416.7 24241.2 24251.4 24444.6 24478.0 24823.5 11:49:58 INFO - PID 26560 | 11:49:58 INFO - PID 26560 | [#2] jquery.html Cycles:180 Average:34833.39 Median:23327.80 stddev:81166.70 (347.9%) stddev-sans-first:81353.64 11:49:58 INFO - PID 26560 | Values: 578.2 578.2 579.3 587.7 588.8 22291.8 22379.9 23423.5 23441.5 23450.4 25764.7 25949.4 26151.9 26190.5 26339.3 6458.8 6616.3 6629.6 6639.6 6640.0 6000.2 6494.5 6509.1 6533.4 6619.5 6468.8 6471.0 6475.4 6475.8 6480.5 6459.5 6481.4 6486.6 6498.8 6499.4 6037.2 6056.2 6062.7 6063.1 6063.9 28723.1 29559.1 29670.3 29924.7 30097.2 2011.1 2039.4 2041.6 2042.7 2043.7 19886.8 20384.8 20401.5 20408.5 20458.5 2365.7 2412.1 2414.6 2414.7 2450.8 138808.0 141871.0 142003.3 142241.0 142369.0 15045.0 15377.3 15417.3 15440.3 15521.0 23515.3 23600.3 23639.0 23663.3 23725.5 10304.0 10370.0 10434.1 10518.5 10532.8 9814.1 9831.9 9913.0 9925.0 9932.2 7693.6 9263.9 9399.4 9486.9 9493.9 2703.3 2706.0 2712.4 2728.8 2811.9 958.9 974.9 979.6 981.7 989.9 19785.0 20132.4 20416.3 20448.6 20635.0 1041.3 1056.6 1058.6 1059.6 1060.9 11094.0 11489.9 11618.6 13082.9 14331.4 447975.0 483553.0 486202.0 488688.0 491794.0 116627.0 118337.0 119062.4 119108.4 119555.0 1222.3 1237.6 1254.7 1265.4 1266.1 21219.0 21248.2 21289.9 21310.5 21818.0 58240.4 58538.7 59393.4 59686.7 60234.4 67435.0 67766.7 67836.3 67981.0 67986.0 12576.2 13066.5 14127.5 14479.3 14969.0 22054.3 22942.0 23070.4 23232.1 23557.9 18039.5 18287.2 18402.4 18420.6 18441.4 16514.4 16755.1 17099.2 17298.2 17301.5 17304.0 18191.5 18206.7 18414.4 18455.4 13788.4 13938.6 13997.1 14047.3 14101.6 18134.5 18150.2 18943.0 19055.2 19240.3 11:49:58 INFO - PID 26560 | 11:49:58 INFO - PID 26560 | [#3] mootools.html Cycles:180 Average:9512.35 Median:2627.45 stddev:6835.84 (260.2%) stddev-sans-first:6827.28 11:49:58 INFO - PID 26560 | Values: 1318.0 1320.0 1369.4 1371.3 1377.9 15005.5 15283.5 15554.1 15612.0 15666.5 15711.0 15953.4 15958.8 16013.0 16013.7 2624.7 2686.3 2689.5 2690.7 2701.0 2650.7 2659.3 2734.5 2736.4 2754.9 2760.1 2801.9 2804.4 2804.7 2808.3 2617.8 2640.2 2645.3 2646.0 2646.3 2630.3 2635.3 2646.1 2649.6 2653.3 14832.6 15270.9 15279.9 15287.7 15291.6 7968.3 8264.8 8270.7 8275.2 8275.2 5382.9 5400.2 5473.3 5517.6 5628.4 1905.5 1942.5 1956.1 1993.8 2004.6 26776.6 27009.5 27052.2 27093.2 27104.3 6762.4 7093.1 7152.8 7403.6 7469.9 17177.7 17263.1 17284.5 17296.0 17337.7 7864.1 8015.7 8026.4 8030.8 8038.5 7325.6 7400.7 7471.9 7501.2 7501.9 4881.7 5039.4 5046.6 5063.3 5065.5 1327.2 1369.4 1413.5 1442.2 1485.5 919.6 942.2 948.7 958.0 960.7 8569.9 8884.5 8924.1 8928.3 8968.4 998.2 1001.8 1005.6 1012.4 1017.8 4495.6 4710.4 5054.9 5370.2 5590.2 20990.2 22067.2 22132.6 22193.7 22264.9 21053.8 22102.1 22207.8 22217.8 22219.6 11608.5 11734.8 11762.2 11763.5 11770.6 7709.8 8442.8 8545.4 8557.2 8563.0 18906.3 18984.3 19142.2 19202.7 19207.7 19285.3 19528.9 19757.2 19877.9 19946.9 12240.7 12343.9 12455.8 12486.2 12491.5 14167.3 14291.5 14294.1 14305.5 14345.7 11063.2 11325.3 11370.4 11489.1 11579.9 10460.9 10673.3 10735.6 10859.2 10970.7 10218.8 10517.6 10530.9 10572.9 10574.9 7143.1 7220.2 7225.2 7250.2 7251.3 6941.8 7023.5 7029.2 7041.8 7068.5 11:49:58 INFO - PID 26560 | 11:49:58 INFO - PID 26560 | [#4] prototype.html Cycles:180 Average:5686.78 Median:6357.99 stddev:1583.23 (24.9%) stddev-sans-first:1539.00 11:49:58 INFO - PID 26560 | Values: 496.7 501.9 528.2 532.9 535.6 6394.7 6418.0 6470.4 6523.3 6575.9 6233.3 6275.9 6300.6 6352.3 6363.6 5029.4 5052.8 5138.4 5164.9 5177.9 4905.9 5082.4 5083.3 5121.6 5162.2 5074.2 5092.2 5094.9 5130.7 5147.4 5053.3 5090.7 5116.2 5124.8 5164.6 4558.6 4565.2 4589.2 4617.0 4635.4 6320.9 6349.5 6374.8 6378.6 6415.8 5863.1 5890.8 5897.8 5901.1 5904.6 6614.3 6625.3 6664.6 6678.8 6731.7 4921.4 4921.9 4941.0 4953.6 4969.5 7057.6 7094.2 7164.8 7175.3 7283.3 5092.9 5110.8 5137.4 5162.0 5163.0 5176.4 5208.6 5223.2 5226.5 5246.7 5825.2 5888.2 5905.4 5911.3 5942.0 6691.3 6780.3 6784.4 6852.3 6882.2 1685.8 1730.9 1906.6 1962.6 1986.6 1431.9 1448.4 1459.5 1483.4 1491.2 5841.9 5895.6 5922.7 5965.9 6051.7 6714.6 6740.3 6893.6 6921.8 6980.7 5921.3 5939.6 5942.9 5959.0 5961.5 3564.0 3637.8 3673.9 3675.9 3703.8 6607.6 6637.4 6650.5 6686.8 6745.7 6974.6 7060.5 7075.8 7083.6 7109.0 6688.9 6707.1 6749.2 6749.6 6759.2 6945.9 6966.1 7009.2 7010.6 7021.0 6880.2 6880.4 6906.5 6910.6 6935.4 6729.2 6763.9 6770.9 6783.4 6826.1 5142.8 5146.1 5161.9 5171.2 5171.7 6792.8 6965.8 7020.0 7057.6 7069.5 6463.2 6670.7 6707.7 6727.4 6830.5 6649.6 6741.3 6742.9 6752.2 6764.2 6528.7 6640.6 6655.5 6669.8 6687.4 6707.5 6769.0 6773.8 6789.6 6795.9 6563.4 6575.7 6584.0 6596.6 6633.3 11:49:58 INFO - PID 26560 | 11:49:58 INFO - PID 26560 | [#5] yui.html Cycles:180 Average:23515.55 Median:19607.25 stddev:32255.67 (164.5%) stddev-sans-first:32301.20 11:49:58 INFO - PID 26560 | Values: 837.5 852.9 875.7 914.6 932.2 18768.1 18988.8 20075.8 20109.8 20140.2 19350.6 19367.5 19422.4 19426.8 19457.9 708.1 725.9 935.2 976.5 991.3 991.0 996.4 997.6 998.0 998.3 1027.5 1028.3 1030.2 1032.8 1033.3 1026.4 1026.5 1027.3 1028.2 1031.5 754.3 755.5 755.7 756.0 756.2 17402.8 17569.6 17657.3 17700.0 17976.6 20125.8 20191.8 20562.7 20588.4 20616.3 17098.7 17387.5 17470.7 17495.7 17593.9 3720.7 3758.4 3758.4 3758.9 3761.5 122235.0 124557.4 125361.4 126178.4 127204.4 15337.8 15801.2 15939.4 15963.8 16026.4 30545.0 30969.7 31398.4 31579.7 31657.4 9353.5 9375.8 9379.1 9444.2 9453.8 3708.1 3860.0 4324.3 4345.3 4444.0 5231.2 5359.8 5373.5 5377.8 5390.2 1024.0 1053.5 1065.7 1066.4 1067.8 659.3 674.9 680.6 680.9 686.1 11221.8 11698.9 11829.5 12019.3 12113.1 672.3 673.8 678.3 679.7 680.5 5561.0 5705.1 5857.6 6146.4 6560.8 109387.5 109839.0 110669.0 114763.0 115717.4 99949.0 100404.5 101156.0 102185.5 106548.5 86537.6 87992.0 89318.1 90692.0 92962.5 11834.6 11872.3 12178.9 12411.7 12473.1 42008.4 42133.6 42229.0 42372.4 43866.0 55234.0 55376.0 56606.7 56682.7 58777.0 17725.6 18102.9 18258.9 18294.5 18424.7 19756.6 20008.4 20183.4 20202.3 20290.0 18341.7 18444.4 18560.4 18639.7 18666.7 16684.7 16780.6 16844.9 16995.7 17041.2 17085.8 17128.7 17262.7 17297.9 17410.3 11688.6 11970.4 12036.5 12189.8 12241.6 13498.5 13783.9 13825.7 13879.7 14036.5 11:49:58 INFO - PID 26560 | -------- Summary: end -------- 11:49:58 INFO - PID 26560 | 11:49:59 INFO - TEST-INFO | 26560: exit 0 11:49:59 INFO - TEST-OK | dromaeo_css | took 1111068ms 11:49:59 INFO - TEST-START | kraken 11:49:59 INFO - Initialising browser for kraken test... 11:49:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38997/getInfo.html -profile /tmp/tmpB0GD9H/profile 11:49:59 INFO - TEST-INFO | started process 26935 (/builds/slave/test/build/application/firefox/firefox http://localhost:38997/getInfo.html) 11:50:04 INFO - TEST-INFO | 26935: exit 0 11:50:04 INFO - Browser initialized. 11:50:04 INFO - Running cycle 1/1 for kraken test... 11:50:04 INFO - Using env: {'DISPLAY': ':0', 11:50:04 INFO - 'HOME': '/home/cltbld', 11:50:04 INFO - 'JSGC_DISABLE_POISONING': '1', 11:50:04 INFO - 'LANG': 'en_US.UTF-8', 11:50:04 INFO - 'LANGUAGE': 'en_US:en', 11:50:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:50:04 INFO - 'LOGNAME': 'cltbld', 11:50:04 INFO - 'MAIL': '/var/mail/cltbld', 11:50:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:50:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:50:04 INFO - 'MOZ_CRASHREPORTER': '1', 11:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:50:04 INFO - 'MOZ_NO_REMOTE': '1', 11:50:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:04 INFO - 'NO_EM_RESTART': '1', 11:50:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:04 INFO - 'PWD': '/builds/slave/test', 11:50:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:50:04 INFO - 'RUST_BACKTRACE': 'full', 11:50:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:50:04 INFO - 'SHELL': '/bin/bash', 11:50:04 INFO - 'SHLVL': '1', 11:50:04 INFO - 'STYLO_FORCE_ENABLED': '1', 11:50:04 INFO - 'STYLO_THREADS': '1', 11:50:04 INFO - 'TERM': 'linux', 11:50:04 INFO - 'TMOUT': '86400', 11:50:04 INFO - 'USER': 'cltbld', 11:50:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086', 11:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:04 INFO - '_': '/tools/buildbot/bin/python'} 11:50:04 INFO - TEST-INFO | started process 27117 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpB0GD9H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:50:05 INFO - PID 27117 | 11:50:05 INFO - PID 27117 | (/builds/slave/test/build/application/firefox/firefox:27169): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:50:05 INFO - PID 27117 | 11:50:16 INFO - PID 27117 | RSS: Main: 172855296 11:50:16 INFO - PID 27117 | 11:50:18 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38997/tests/kraken/driver.html?testName=audio-beat-detection) 11:50:19 INFO - PID 27117 | RSS: Main: 177696768 11:50:19 INFO - PID 27117 | 11:50:22 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38997/tests/kraken/driver.html?testName=audio-dft) 11:50:22 INFO - PID 27117 | RSS: Main: 181772288 11:50:22 INFO - PID 27117 | 11:50:25 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38997/tests/kraken/driver.html?testName=audio-fft) 11:50:26 INFO - PID 27117 | RSS: Main: 174452736 11:50:26 INFO - PID 27117 | 11:50:28 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38997/tests/kraken/driver.html?testName=audio-oscillator) 11:50:29 INFO - PID 27117 | RSS: Main: 175280128 11:50:29 INFO - PID 27117 | 11:50:31 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38997/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:50:32 INFO - PID 27117 | RSS: Main: 175714304 11:50:32 INFO - PID 27117 | 11:50:35 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38997/tests/kraken/driver.html?testName=imaging-darkroom) 11:50:35 INFO - PID 27117 | RSS: Main: 176771072 11:50:35 INFO - PID 27117 | 11:50:39 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38997/tests/kraken/driver.html?testName=imaging-desaturate) 11:50:39 INFO - PID 27117 | RSS: Main: 177106944 11:50:39 INFO - PID 27117 | 11:50:42 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38997/tests/kraken/driver.html?testName=json-parse-financial) 11:50:43 INFO - PID 27117 | RSS: Main: 178323456 11:50:43 INFO - PID 27117 | 11:50:45 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38997/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:50:45 INFO - PID 27117 | RSS: Main: 177700864 11:50:45 INFO - PID 27117 | 11:50:47 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38997/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:50:47 INFO - PID 27117 | RSS: Main: 177635328 11:50:47 INFO - PID 27117 | 11:50:50 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38997/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:50:50 INFO - PID 27117 | RSS: Main: 174297088 11:50:50 INFO - PID 27117 | 11:50:53 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38997/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:50:54 INFO - PID 27117 | RSS: Main: 174379008 11:50:54 INFO - PID 27117 | 11:50:57 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38997/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:50:57 INFO - PID 27117 | RSS: Main: 174460928 11:50:57 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | Cycle 1(1): loaded http://localhost:38997/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38997/tests/kraken/driver.html?testName=ai-astar) 11:50:59 INFO - PID 27117 | RSS: Main: 175841280 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | __start_tp_report 11:50:59 INFO - PID 27117 | _x_x_mozilla_page_load 11:50:59 INFO - PID 27117 | _x_x_mozilla_page_load_details 11:50:59 INFO - PID 27117 | |i|pagename|runs| 11:50:59 INFO - PID 27117 | |0;ai-astar;99;108;100;108;107;108;109;101;109;109 11:50:59 INFO - PID 27117 | |1;audio-beat-detection;145;120;153;181;120;142;142;117;175;118 11:50:59 INFO - PID 27117 | |2;audio-dft;163;161;160;162;161;163;170;167;161;163 11:50:59 INFO - PID 27117 | |3;audio-fft;74;74;74;195;74;75;73;89;96;74 11:50:59 INFO - PID 27117 | |4;audio-oscillator;80;79;130;82;79;79;123;79;79;130 11:50:59 INFO - PID 27117 | |5;imaging-gaussian-blur;113;117;115;113;114;114;113;114;114;116 11:50:59 INFO - PID 27117 | |6;imaging-darkroom;147;149;155;147;148;148;148;150;149;147 11:50:59 INFO - PID 27117 | |7;imaging-desaturate;93;94;94;94;94;94;95;95;94;94 11:50:59 INFO - PID 27117 | |8;json-parse-financial;51;52;55;52;51;52;51;51;51;52 11:50:59 INFO - PID 27117 | |9;json-stringify-tinderbox;37;38;38;38;38;37;38;37;39;38 11:50:59 INFO - PID 27117 | |10;stanford-crypto-aes;75;79;73;78;75;74;79;74;74;73 11:50:59 INFO - PID 27117 | |11;stanford-crypto-ccm;125;115;116;115;115;124;124;115;118;161 11:50:59 INFO - PID 27117 | |12;stanford-crypto-pbkdf2;170;180;174;172;185;175;181;179;170;170 11:50:59 INFO - PID 27117 | |13;stanford-crypto-sha256-iterative;64;63;65;62;63;64;64;73;62;62 11:50:59 INFO - PID 27117 | __end_tp_report 11:50:59 INFO - PID 27117 | __start_cc_report 11:50:59 INFO - PID 27117 | _x_x_mozilla_cycle_collect,939 11:50:59 INFO - PID 27117 | __end_cc_report 11:50:59 INFO - PID 27117 | __startTimestamp1504464659704__endTimestamp 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | ------- Summary: start ------- 11:50:59 INFO - PID 27117 | Number of tests: 14 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#0] ai-astar Cycles:10 Average:105.80 Median:108.50 stddev:4.08 (3.8%) stddev-sans-first:3.50 11:50:59 INFO - PID 27117 | Values: 99.0 108.0 100.0 108.0 107.0 108.0 109.0 101.0 109.0 109.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:143.50 stddev:23.34 (16.3%) stddev-sans-first:24.72 11:50:59 INFO - PID 27117 | Values: 145.0 120.0 153.0 181.0 120.0 142.0 142.0 117.0 175.0 118.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:3.11 (1.9%) stddev-sans-first:3.30 11:50:59 INFO - PID 27117 | Values: 163.0 161.0 160.0 162.0 161.0 163.0 170.0 167.0 161.0 163.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#3] audio-fft Cycles:10 Average:89.80 Median:74.00 stddev:37.79 (51.1%) stddev-sans-first:39.65 11:50:59 INFO - PID 27117 | Values: 74.0 74.0 74.0 195.0 74.0 75.0 73.0 89.0 96.0 74.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:79.00 stddev:23.33 (29.5%) stddev-sans-first:24.19 11:50:59 INFO - PID 27117 | Values: 80.0 79.0 130.0 82.0 79.0 79.0 123.0 79.0 79.0 130.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.34 (1.2%) stddev-sans-first:1.33 11:50:59 INFO - PID 27117 | Values: 113.0 117.0 115.0 113.0 114.0 114.0 113.0 114.0 114.0 116.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:148.50 stddev:2.39 (1.6%) stddev-sans-first:2.45 11:50:59 INFO - PID 27117 | Values: 147.0 149.0 155.0 147.0 148.0 148.0 148.0 150.0 149.0 147.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 11:50:59 INFO - PID 27117 | Values: 93.0 94.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.23 (2.4%) stddev-sans-first:1.27 11:50:59 INFO - PID 27117 | Values: 51.0 52.0 55.0 52.0 51.0 52.0 51.0 51.0 51.0 52.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.60 11:50:59 INFO - PID 27117 | Values: 37.0 38.0 38.0 38.0 38.0 37.0 38.0 37.0 39.0 38.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:75.00 stddev:2.37 (3.2%) stddev-sans-first:2.51 11:50:59 INFO - PID 27117 | Values: 75.0 79.0 73.0 78.0 75.0 74.0 79.0 74.0 74.0 73.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#11] stanford-crypto-ccm Cycles:10 Average:122.80 Median:121.00 stddev:14.06 (11.6%) stddev-sans-first:14.89 11:50:59 INFO - PID 27117 | Values: 125.0 115.0 116.0 115.0 115.0 124.0 124.0 115.0 118.0 161.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:177.00 stddev:5.36 (3.0%) stddev-sans-first:5.29 11:50:59 INFO - PID 27117 | Values: 170.0 180.0 174.0 172.0 185.0 175.0 181.0 179.0 170.0 170.0 11:50:59 INFO - PID 27117 | 11:50:59 INFO - PID 27117 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:3.26 (5.1%) stddev-sans-first:3.46 11:50:59 INFO - PID 27117 | Values: 64.0 63.0 65.0 62.0 63.0 64.0 64.0 73.0 62.0 62.0 11:50:59 INFO - PID 27117 | -------- Summary: end -------- 11:50:59 INFO - PID 27117 | 11:51:00 INFO - PID 27117 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:51:00 INFO - TEST-INFO | 27117: exit 0 11:51:00 INFO - TEST-OK | kraken | took 61097ms 11:51:00 INFO - SUITE-END | took 1172s 11:51:00 INFO - Completed test suite (00:19:32) 11:51:00 INFO - javascript benchmark 11:51:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8673.1300727634, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3357.3621012007716, 3372.426687463131, 3377.476491143873, 3404.8297585120736, 3413.2320227948717, 6457.838554036151, 6465.9353406465925, 6535.738570457182, 6570.868582628356, 6587.9670601646985, 6395.968020159893, 6460.257070436901, 6469.1590093287805, 6510.446612037982, 6511.251206111298, 5932.2584676915385, 5939.020061689816, 5955.07696307072, 5964.970175149118, 6058.454739073482, 5940.584159108864, 5970.402959704016, 6014.097885317194, 6030.276366835986, 6069.484093852028, 5776.1624564437925, 6012.729427175744, 6020.367861374536, 6020.458158765732, 6085.2697676278685, 5897.056470964625, 5939.940600593982, 5946.0, 6033.42682445167, 6052.455279024909, 5940.702964851784, 5943.4056594340655, 6024.548158888101, 6035.939640603626, 6038.064100064453, 6331.2719037310835, 6366.713497892606, 6402.711877965503, 6418.0, 6575.868482630369, 6870.381665650066, 6875.346842050048, 6887.690053947585, 6925.065116209297, 7004.894926576106, 7075.504714669975, 7102.893456598155, 7109.040279562245, 7113.359797618187, 7171.569705817667, 6446.903296450557, 6519.315471875432, 6571.244306904671, 6609.537332386735, 6614.040964060185, 7217.314355136254, 7255.383292420203, 7310.086239220098, 7328.560286382727, 7342.632868356561, 6597.8350541235895, 6647.302033286435, 6673.099131617267, 6714.395704386531, 6874.9656251718425, 7042.859142817115, 7081.150261968597, 7095.254998225215, 7105.89341159883, 7108.786736397917, 6938.0, 6940.861182776316, 6940.895886561605, 7027.4729395294535, 7054.400375967997, 6756.763513277012, 6842.965785171142, 6941.583504989717, 6960.895586566206, 7036.9296307036275, 5524.917126243109, 5531.834044978657, 5537.031019571559, 5543.223948647193, 5546.278983732089, 4744.572988431058, 4810.807567697253, 4850.1269771441475, 4860.538248866352, 4881.243407271879, 6055.545834062463, 6087.756489740361, 6091.908621370683, 6154.753809847556, 6180.289266734293, 6803.0, 6941.652917354213, 6944.4444444444325, 6977.476689248225, 7101.147862256562, 6062.878742425127, 6064.3935606439, 6067.787627433018, 6074.0, 6092.664903430265, 5935.614185077956, 5957.940420595825, 6002.369751176151, 6041.607295525777, 6066.908996365058, 6733.663316834139, 6751.0886030386255, 6846.075779769767, 6849.315068493111, 6857.759978400732, 6863.142107236596, 6971.337722916315, 6994.125734283215, 7091.042709234187, 7098.36114749675, 6660.733570657119, 6770.830729231809, 6824.965875170592, 6840.384365407137, 6844.28135045813, 6832.248452670302, 6960.651967401711, 6976.7558135465015, 6988.266232045766, 6995.580265184105, 7002.1947476040905, 7008.194057683431, 7011.404030657452, 7100.857982840417, 7117.1459424869345, 6550.803475895731, 6631.436327911989, 6648.734050638114, 6761.425278851353, 6851.623160726208, 6786.049953006484, 6793.286704895915, 6813.080234168522, 6849.828754281183, 6856.794296171123, 6631.801045968628, 6721.93278067213, 6771.729130834711, 6801.353871382211, 6819.897701534382, 6745.190577130776, 6806.693698783665, 6882.414994725505, 6896.793096207121, 6911.827204319932, 6385.584936979081, 6500.479961603155, 6554.2462616797775, 6662.067310576426, 6664.400203981665], "value": 6370.006533743387, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.3012074676152, 837.4347315561994, 854.1799872122762, 885.3138817416501, 891.5007595746381, 40707.38938915915, 41562.16875662485, 41735.79132104343, 41764.79117604408, 41789.79105104474, 37882.8105859471, 38095.42856857149, 38161.23677526448, 38176.045598860044, 38213.23573528535, 6884.208316043657, 7002.299770023008, 7010.298970103, 7011.579305241677, 7014.929850701505, 6922.619255940921, 7018.403435707971, 7020.0, 7020.648967551628, 7022.016917631536, 7663.885041724378, 7844.333231675286, 7848.372130229596, 7848.803779905519, 7851.960740196291, 7737.0, 7800.141984381685, 7808.804779880548, 7809.453338266324, 7811.0, 7513.962430187868, 7628.542287462715, 7693.846123077563, 7709.7301594443925, 7745.380369570477, 38111.61888381108, 38324.80837595822, 38516.229675406335, 38646.420303695464, 38677.613223867964, 3518.0852978225844, 3554.2003049313957, 3573.87491437801, 3575.213453040327, 3577.3203091412547, 31327.843360783278, 31491.212719681964, 31727.20681982946, 31751.682483175104, 31823.681763182303, 5075.314832497639, 5096.974515127437, 5125.2312153176945, 5139.10065738498, 5147.588192944556, 177434.11282943632, 178257.0, 186376.0, 191184.0, 200750.99999999854, 49140.50859491431, 49389.0, 49486.257706134435, 49515.25727114096, 49783.751081244365, 50702.0, 50809.49190508122, 50923.7453812736, 51031.234531482056, 51051.0, 15930.203489825462, 16139.354425822836, 16158.434454794025, 16158.75761863573, 16285.104319262316, 13441.193528388329, 13459.93270033663, 13462.192268463923, 13469.797953030713, 13475.932620336836, 14149.85850141506, 14208.360623771956, 14222.644433889236, 14244.572662820132, 14377.0, 5674.886502269931, 5706.942930570641, 5762.349319313079, 5782.161586569924, 5813.273340832396, 987.1017374189597, 1000.5747557288123, 1007.471077684207, 1009.2531526670211, 1011.8279892418307, 22402.663960040612, 22527.211547595754, 22551.0, 22677.319680409615, 22685.886570567043, 1081.7782354617284, 1103.0679076180616, 1108.2020944919645, 1110.0564520157964, 1118.972025699364, 11587.768244635061, 12029.578964736193, 12051.75896482083, 12119.818202726967, 12196.207246528948, 246221.0, 247151.0, 248401.0, 248846.0, 248899.0, 50738.0, 51113.233301500506, 51230.48769512332, 51474.227886580986, 51532.22701659478, 1375.7592421326228, 1410.5345236072071, 1416.9433222671184, 1422.267532220907, 1435.6841494871112, 22078.22726204575, 22184.0, 22188.334349969526, 22313.888430558392, 22542.887285563465, 49532.75233623809, 50594.49405505898, 50729.0, 50831.0, 50854.237186441496, 212108.0, 213268.0, 214482.0, 214732.0, 215113.0, 30817.383652326378, 31321.686783131878, 31411.37177256488, 35033.29933401267, 35107.824460877535, 43138.352924706785, 43147.0, 43276.13447731091, 43310.78344608257, 43648.56351436572, 31795.682043179273, 31866.522002169524, 31983.680163199002, 32280.0, 32563.674363256065, 31585.36829263356, 32590.0, 32597.0, 32759.344813104082, 33423.16442088967, 37681.0, 37723.81138094292, 37730.62269377271, 37987.0, 38013.0, 32638.184045399055, 32791.67208327982, 33864.83067584646, 33961.320773584885, 34416.65583344134, 24241.154492714555, 24251.393715156555, 24444.633330499702, 24478.0, 24823.503529928937], "value": 17260.671517099625, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.176099058841, 578.2020811280435, 579.2643342954451, 587.7090840034182, 588.7909792023831, 22291.77708222917, 22379.888100559496, 23423.531529369404, 23441.5311693766, 23450.413739656517, 25764.742352576515, 25949.351266218353, 26151.869240653818, 26190.47619047618, 26339.341516462096, 6458.806235812933, 6616.305287944769, 6629.569078009938, 6639.634820084893, 6639.966800165992, 6000.159977603139, 6494.51291153163, 6509.121268628745, 6533.379328963741, 6619.503537234703, 6468.8059358219325, 6471.0, 6475.449586785129, 6475.805725828232, 6480.513961452886, 6459.483241340681, 6481.449076828499, 6486.578372405802, 6498.805035848932, 6499.382558656967, 6037.245344331958, 6056.242969628796, 6062.696865156769, 6063.090536419529, 6063.939360606381, 28723.138305850858, 29559.113226603236, 29670.258243543867, 29924.70075299241, 30097.247568810737, 2011.145263263122, 2039.377989713137, 2041.5508588110592, 2042.7446569178853, 2043.7138800567932, 19886.80113198864, 20384.796152038438, 20401.4899627509, 20408.489787755276, 20458.488537786525, 2365.6806331145417, 2412.0592968742203, 2414.6498757680038, 2414.7223069347074, 2450.8161887858305, 138808.0, 141871.0, 142003.28998355046, 142241.0, 142369.0, 15044.968929194418, 15377.308021139075, 15417.306221220071, 15440.305186266645, 15521.0, 23515.294541163792, 23600.29199124029, 23639.0, 23663.29010129699, 23725.52548949011, 10304.0, 10370.0, 10434.06093451593, 10518.474076296154, 10532.842007369896, 9814.116729494379, 9831.852522212173, 9913.0, 9924.950375248078, 9932.155766759906, 7693.614809483809, 9263.888333400035, 9399.436033837992, 9486.90513094874, 9493.905060949302, 2703.297142742901, 2706.0, 2712.443948990452, 2728.822626529269, 2811.943761124766, 958.9232861371073, 974.936629119105, 979.6375341123687, 981.7496538382667, 989.8911119776819, 19785.0, 20132.39602811918, 20416.285430010175, 20448.591028179355, 20635.0, 1041.250299784154, 1056.5615269663003, 1058.5818601652304, 1059.6079450603327, 1060.931039482431, 11094.0, 11489.942550287195, 11618.593349232737, 13082.934585327203, 14331.355089021019, 447975.0, 483553.0, 486202.0, 488688.0, 491794.0, 116627.0, 118337.0, 119062.404687976, 119108.40445797716, 119555.0, 1222.2544248008708, 1237.55448038706, 1254.7490501899656, 1265.411583613624, 1266.0504621534033, 21219.0, 21248.15007399687, 21289.893550532768, 21310.467238319165, 21818.018189181843, 58240.4175958235, 58538.7073064632, 59393.40606593879, 59686.70156649189, 60234.39765602288, 67435.0, 67766.66116669386, 67836.321636783, 67981.0, 67986.0, 12576.182548134342, 13066.477340906258, 14127.505537306555, 14479.348429320495, 14969.0, 22054.33836984893, 22942.0, 23070.42323941915, 23232.070717171126, 23557.882210588836, 18039.458816235532, 18287.177077031298, 18402.35591754282, 18420.631587368447, 18441.446756597325, 16514.42199523011, 16755.078470684228, 17099.230534625724, 17298.22158002918, 17301.480955571355, 17304.0, 18191.45425637233, 18206.72689909679, 18414.447566573024, 18455.446336609923, 13788.379522921694, 13938.58184254474, 13997.09018913808, 14047.297635117999, 14101.647458813612, 18134.45596632103, 18150.18324175389, 18943.0, 19055.237790488227, 19240.326588569333], "value": 12140.968973192357, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.9538716144934, 1320.0495643136944, 1369.4043091255305, 1371.300636675296, 1377.9311034448288, 15005.549833504985, 15283.541493755178, 15554.144522051283, 15612.0, 15666.451674191405, 15711.0, 15953.361865525365, 15958.84041159588, 16013.039217646949, 16013.679726405466, 2624.6587943567356, 2686.274705829435, 2689.4889970905565, 2690.704022557527, 2700.972990270092, 2650.681918169813, 2659.3218729224022, 2734.5214587447217, 2736.3843135294596, 2754.8898044078214, 2760.089170573716, 2801.9159425217176, 2804.383035732136, 2804.6914839367655, 2808.326001759573, 2617.803664725144, 2640.2079376187066, 2645.3254420122844, 2646.0, 2646.2855029142024, 2630.250378642097, 2635.341164708823, 2646.087099950514, 2649.6158057081616, 2653.3234025323613, 14832.555023349316, 15270.923645381814, 15279.923600382039, 15287.694246115127, 15291.617709557238, 7968.282854543119, 8264.793380165484, 8270.710525131592, 8275.213854683796, 8275.213854683856, 5382.93172891452, 5400.189971504266, 5473.31583552056, 5517.5585953123655, 5628.43715628438, 1905.5236190952262, 1942.4949513126548, 1956.1099699636632, 1993.8404927605757, 2004.5890592428518, 26776.59835102475, 27009.45981080387, 27052.188434346805, 27093.1872043839, 27104.32239194036, 6762.391384775296, 7093.1488221413765, 7152.785416437515, 7403.555786652818, 7469.925300746923, 17177.71167162468, 17263.13684315779, 17284.481465556055, 17296.0, 17337.739933901, 7864.056313242333, 8015.679372825021, 8026.398020148512, 8030.759077227693, 8038.517688938682, 7325.560466372035, 7400.703971841173, 7471.925280747231, 7501.249875012564, 7501.887471687929, 4881.658283920163, 5039.370078740158, 5046.646734728533, 5063.265826455144, 5065.518775716375, 1327.2036777933163, 1369.4179973511218, 1413.5123382433044, 1442.163545143814, 1485.472657206676, 919.6137622198693, 942.222666300305, 948.691675205561, 957.9616815327448, 960.6877764726352, 8569.914300856912, 8884.511351875579, 8924.107589241024, 8928.285737141014, 8968.372213945091, 998.1515711645044, 1001.7645853224486, 1005.6429967361481, 1012.3976234140674, 1017.8218811707922, 4495.550444955478, 4710.387649605598, 5054.949450505522, 5370.194470829407, 5590.217369568264, 20990.160393584392, 22067.2276470326, 22132.557348852934, 22193.667094993587, 22264.888675556842, 21053.789462105487, 22102.115915363527, 22207.77792222057, 22217.77782222157, 22219.55560888773, 11608.535658573732, 11734.765304694029, 11762.235454695418, 11763.470643820878, 11770.58802941893, 7709.7687069385765, 8442.831143377221, 8545.444546104483, 8557.187067228604, 8562.972443306593, 18906.338278160747, 18984.335548255745, 19142.23431062742, 19202.7119593209, 19207.711884321467, 19285.325013624457, 19528.90235548813, 19757.209711611373, 19877.900610496854, 19946.900265498578, 12240.69398265015, 12343.938280308541, 12455.750884982068, 12486.18839775448, 12491.500339986662, 14167.291635417982, 14291.499797507038, 14294.070885392832, 14305.499307524187, 14345.713085738436, 11063.225574209728, 11325.32048077118, 11370.374629395132, 11489.138314626603, 11579.942100289445, 10460.895391045991, 10673.252872298865, 10735.570577176826, 10859.2398532102, 10970.725731856768, 10218.795624087625, 10517.579296828042, 10530.947345263225, 10572.947135264581, 10574.947125264633, 7143.107111611049, 7220.241874603301, 7225.205227425085, 7250.238724934017, 7251.311125443074, 6941.82645433848, 7023.47323950716, 7029.226785053538, 7041.823954401181, 7068.540544864567], "value": 6763.694120761468, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.726800259857, 501.8569707633323, 528.1997773373338, 532.9467053294671, 535.5902734408179, 6394.712237949292, 6418.0, 6470.3529647035275, 6523.347665233474, 6575.9342406575915, 6233.257366758623, 6275.905861412082, 6300.613864949715, 6352.333005034463, 6363.649999250039, 5029.446760856321, 5052.8484145475695, 5138.3577052868395, 5164.8708782280555, 5177.9094515332845, 4905.926411103818, 5082.390113186423, 5083.271687626207, 5121.564667003309, 5162.174052151657, 5074.188129899217, 5092.159793634046, 5094.872628184288, 5130.717810520438, 5147.408048074463, 5053.343065401493, 5090.669106508084, 5116.2069879168785, 5124.847564023119, 5164.612654050924, 4558.61251793598, 4565.155446242423, 4589.173948689235, 4616.953830461686, 4635.351050852883, 6320.93679063208, 6349.4602958748565, 6374.840628984312, 6378.553501254914, 6415.807525774234, 5863.120531920204, 5890.823275301748, 5897.764089436418, 5901.085331773582, 5904.61619994699, 6614.305497922697, 6625.271220165778, 6664.566803157779, 6678.799636010927, 6731.663416829188, 4921.3984924096085, 4921.926171107436, 4940.950590494085, 4953.628477864175, 4969.453360130383, 7057.553741143099, 7094.184168820651, 7164.820879478003, 7175.31834475724, 7283.308085731847, 5092.9236061459105, 5110.795568177231, 5137.379936232548, 5162.0, 5162.97418512905, 5176.378834539804, 5208.583313334924, 5223.190405487156, 5226.477352264819, 5246.737663116829, 5825.2135961644635, 5888.175655408247, 5905.379935106838, 5911.290645122613, 5941.970290148522, 6691.297413771581, 6780.287700048419, 6784.3894049535775, 6852.314768523108, 6882.174139103339, 1685.8229885861808, 1730.9134543272785, 1906.560546787167, 1962.6271008508338, 1986.5927484865567, 1431.9069260498034, 1448.4061534770694, 1459.5331691649824, 1483.4140514496928, 1491.1798510819006, 5841.853953651108, 5895.614530585309, 5922.733476993546, 5965.910511342333, 6051.697415129226, 6714.630695311707, 6740.258571557125, 6893.586384816927, 6921.792346229621, 6980.685869135901, 5921.259842519685, 5939.554533410012, 5942.940570594239, 5958.970205148947, 5961.469283344602, 3563.966449729601, 3637.8181090945345, 3673.9265214695556, 3675.9081022974638, 3703.759255648374, 6607.603543787292, 6637.435817955431, 6650.501212409089, 6686.83282917931, 6745.696443660047, 6974.581525108409, 7060.54106483077, 7075.823104422328, 7083.6458177090935, 7108.964455177691, 6688.889666305095, 6707.128073350531, 6749.2238392584495, 6749.595024298558, 6759.2226893906845, 6945.895811562729, 6966.12923384577, 7009.228984811769, 7010.579365238102, 7021.0, 6880.208775990825, 6880.415164711056, 6906.548175397862, 6910.585364878123, 6935.375816176568, 6729.158855143107, 6763.86472270542, 6770.8645827084165, 6783.423409010092, 6826.112605361272, 5142.76857541419, 5146.099432599355, 5161.870953226199, 5171.224316352577, 5171.741412929414, 6792.796216113525, 6965.805771182061, 7020.0, 7057.576545407292, 7069.469789765891, 6463.192100987377, 6670.699818508276, 6707.664616769044, 6727.394534491919, 6830.487713421613, 6649.60102393858, 6741.292164322872, 6742.8651426972165, 6752.189737231563, 6764.188297404343, 6528.738850445929, 6640.634765087966, 6655.534112612264, 6669.765628514502, 6687.398134167948, 6707.463402927657, 6769.018492318684, 6773.830654233487, 6789.592624442549, 6795.864082718417, 6563.442107420923, 6575.671216439255, 6584.0, 6596.637184954873, 6633.336666333328], "value": 5231.766147448861, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.4682076881182, 852.8891244138262, 875.662869795129, 914.638717706506, 932.2122806228734, 18768.061596920168, 18988.810111898878, 20075.799242007575, 20109.798902010974, 20140.194392224294, 19350.612987740238, 19367.51581210467, 19422.41732748016, 19426.805731942677, 19457.90271048643, 708.1113202930316, 725.8729722298576, 935.2190920581323, 976.501983988164, 991.2565575818154, 991.013941128578, 996.4170959988415, 997.5660587644362, 997.9850302245472, 998.3311181508407, 1027.5478789332708, 1028.3058935218735, 1030.160419258302, 1032.8192566300904, 1033.2767063055853, 1026.3687831983314, 1026.4559783314892, 1027.2911690933306, 1028.1671845804922, 1031.4791030529573, 754.3324158120048, 755.5051441305953, 755.7128291249345, 756.0134025097301, 756.1682149635412, 17402.825971740374, 17569.56076098095, 17657.293708251655, 17700.026498542575, 17976.640467190584, 20125.79874201254, 20191.798082019137, 20562.69155962662, 20588.38234852957, 20616.278430254868, 17098.743518847226, 17387.478375648752, 17470.737938930924, 17495.65008699819, 17593.912030439766, 3720.6651401374006, 3758.3798673218885, 3758.3986562149926, 3758.9436158457647, 3761.5110035695457, 122235.0, 124557.37721311426, 125361.37319313436, 126178.36910815479, 127204.36397818044, 15337.77297816172, 15801.20993950321, 15939.362425503083, 15963.840361596467, 16026.439074632332, 30545.0, 30969.69030309713, 31398.372032559222, 31579.684203158133, 31657.36685266328, 9353.532323383804, 9375.812483750424, 9379.062093790566, 9444.244460443148, 9453.76365590852, 3708.0915175782106, 3859.9807000964815, 4324.2648749712625, 4345.34819777036, 4443.955560444419, 5231.163013917755, 5359.812406565751, 5373.489518495737, 5377.838664840061, 5390.245365648812, 1023.9897601023895, 1053.4574694032578, 1065.6962765611763, 1066.3814987307344, 1067.7971185474735, 659.3406593406594, 674.9021391898245, 680.5848432456198, 680.8944613584903, 686.1423220973783, 11221.765605783357, 11698.941505292589, 11829.52681892732, 12019.33893635841, 12113.145090532124, 672.277301900459, 673.8012286375531, 678.3149019490351, 679.7179170644127, 680.4726337088796, 5560.972195138998, 5705.087186050212, 5857.648541087548, 6146.416090471398, 6560.835979100465, 109387.45306273577, 109839.0, 110669.0, 114763.0, 115717.42141289239, 99949.0, 100404.49797750964, 101156.0, 102185.48907255565, 106548.46725766321, 86537.56731216304, 87992.0, 89318.10681893228, 90692.0, 92962.53518732499, 11834.585789497327, 11872.347020913932, 12178.939105304416, 12411.689707757378, 12473.064520161206, 42008.369874451295, 42133.57866421419, 42229.0, 42372.36441453442, 43866.0, 55234.0, 55376.0, 56606.716966414904, 56682.716586416806, 58777.0, 17725.556861078578, 18102.90948545249, 18258.90870545692, 18294.451166465027, 18424.723629145843, 19756.604867902275, 20008.39974800758, 20183.39449816508, 20202.29291974774, 20290.0, 18341.72487412663, 18444.35444759427, 18560.350387736904, 18639.72040419422, 18666.71999919975, 16684.66630667404, 16780.58048548796, 16844.915775421043, 16995.66008679844, 17041.23314450828, 17085.82914170892, 17128.743068853728, 17262.741058883876, 17297.91351043287, 17410.303587856342, 11688.649340519798, 11970.401479925795, 12036.518539258332, 12189.75620487603, 12241.632751017483, 13498.46006159782, 13783.931080344533, 13825.723485530032, 13879.653008674864, 14036.508722194674], "value": 9010.47898696755, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 100.0, 108.0, 107.0, 108.0, 109.0, 101.0, 109.0, 109.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 120.0, 153.0, 181.0, 120.0, 142.0, 142.0, 117.0, 175.0, 118.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 160.0, 162.0, 161.0, 163.0, 170.0, 167.0, 161.0, 163.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 195.0, 74.0, 75.0, 73.0, 89.0, 96.0, 74.0], "value": 89.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 130.0, 82.0, 79.0, 79.0, 123.0, 79.0, 79.0, 130.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 115.0, 113.0, 114.0, 114.0, 113.0, 114.0, 114.0, 116.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 155.0, 147.0, 148.0, 148.0, 148.0, 150.0, 149.0, 147.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 55.0, 52.0, 51.0, 52.0, 51.0, 51.0, 51.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 38.0, 38.0, 37.0, 38.0, 37.0, 39.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 73.0, 78.0, 75.0, 74.0, 79.0, 74.0, 74.0, 73.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 115.0, 116.0, 115.0, 115.0, 124.0, 124.0, 115.0, 118.0, 161.0], "value": 122.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 180.0, 174.0, 172.0, 185.0, 175.0, 181.0, 179.0, 170.0, 170.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 62.0, 63.0, 64.0, 64.0, 73.0, 62.0, 62.0], "value": 64.2, "unit": "score"}]}]} 11:51:00 INFO - Return code: 0 11:51:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:51:00 INFO - # TBPL SUCCESS # 11:51:00 INFO - Running post-action listener: _package_coverage_data 11:51:00 INFO - Running post-action listener: _resource_record_post_action 11:51:00 INFO - [mozharness: 2017-09-03 18:51:00.980234Z] Finished run-tests step (success) 11:51:00 INFO - Running post-run listener: _resource_record_post_run 11:51:01 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 392376320; Read time: 0; Write time: 307064 11:51:01 INFO - TinderboxPrint: CPU usage
13.1% 11:51:01 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:51:01 INFO - TinderboxPrint: I/O write bytes / time
392,376,320 / 307,064 11:51:01 INFO - TinderboxPrint: CPU idle
8,237.3 (86.9%) 11:51:01 INFO - TinderboxPrint: CPU user
1,192.3 (12.6%) 11:51:01 INFO - TinderboxPrint: Swap in / out
0 / 0 11:51:01 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107257856; Read time: 0; Write time: 88352 11:51:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:51:01 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 285118464; Read time: 0; Write time: 218712 11:51:01 INFO - Running post-run listener: _upload_blobber_files 11:51:01 INFO - Blob upload gear active. 11:51:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:51:01 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 11:51:01 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'] 11:51:01 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 11:51:02 INFO - (blobuploader) - INFO - Open directory for files ... 11:51:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:51:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:51:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:02 INFO - (blobuploader) - INFO - Done attempting. 11:51:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:51:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:10 INFO - (blobuploader) - INFO - Done attempting. 11:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:11 INFO - (blobuploader) - INFO - Done attempting. 11:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:13 INFO - (blobuploader) - INFO - Done attempting. 11:51:13 INFO - (blobuploader) - INFO - Iteration through files over. 11:51:13 INFO - Return code: 0 11:51:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:51:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:51:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9c2ed1f060f9a2c33049c6125b5322173112f62142bb89011a3663a3b80286cb3deebbaa18bbc6ef447b80de6731d6bbbe97603f5a1d58fc0880a13a13d880f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50c0d98ccf500a47ad08b1ff467be6814d64e436bc73f9b29eeec80f0ef2de2ba2bcf559440a939cc91f0cd711a5b6ef4ba89eae2a0f3ef1f756d02f97cf864e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0373ac8e918f0e107cab84aac4883f4d68373b3e18311f2454260e259ba30d7cc3b60d8d584ebaa5791a3b1040b08d235d20a8ceb25cdd4f2e179129fb059c73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e513d7735bf26d2c718b633c4c1dfae5e32804e669d9b2694947d1d4b59be02d69c03ef5015f306569b45117840201967904acb551246d07e8bd8b4db4e0104d"} 11:51:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:51:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:51:13 INFO - Contents: 11:51:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9c2ed1f060f9a2c33049c6125b5322173112f62142bb89011a3663a3b80286cb3deebbaa18bbc6ef447b80de6731d6bbbe97603f5a1d58fc0880a13a13d880f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50c0d98ccf500a47ad08b1ff467be6814d64e436bc73f9b29eeec80f0ef2de2ba2bcf559440a939cc91f0cd711a5b6ef4ba89eae2a0f3ef1f756d02f97cf864e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0373ac8e918f0e107cab84aac4883f4d68373b3e18311f2454260e259ba30d7cc3b60d8d584ebaa5791a3b1040b08d235d20a8ceb25cdd4f2e179129fb059c73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e513d7735bf26d2c718b633c4c1dfae5e32804e669d9b2694947d1d4b59be02d69c03ef5015f306569b45117840201967904acb551246d07e8bd8b4db4e0104d"} 11:51:13 INFO - Running post-run listener: copy_logs_to_upload_dir 11:51:13 INFO - Copying logs to upload dir... 11:51:13 INFO - mkdir: /builds/slave/test/build/upload/logs 11:51:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.411277 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-09-03 11:51:13.251515) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 11:51:13.259249) ========= 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=db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9c2ed1f060f9a2c33049c6125b5322173112f62142bb89011a3663a3b80286cb3deebbaa18bbc6ef447b80de6731d6bbbe97603f5a1d58fc0880a13a13d880f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50c0d98ccf500a47ad08b1ff467be6814d64e436bc73f9b29eeec80f0ef2de2ba2bcf559440a939cc91f0cd711a5b6ef4ba89eae2a0f3ef1f756d02f97cf864e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0373ac8e918f0e107cab84aac4883f4d68373b3e18311f2454260e259ba30d7cc3b60d8d584ebaa5791a3b1040b08d235d20a8ceb25cdd4f2e179129fb059c73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e513d7735bf26d2c718b633c4c1dfae5e32804e669d9b2694947d1d4b59be02d69c03ef5015f306569b45117840201967904acb551246d07e8bd8b4db4e0104d"} build_url:https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011517 build_url: 'https://queue.taskcluster.net/v1/task/aRxrosGuS-CHZHHVq2qPew/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9c2ed1f060f9a2c33049c6125b5322173112f62142bb89011a3663a3b80286cb3deebbaa18bbc6ef447b80de6731d6bbbe97603f5a1d58fc0880a13a13d880f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50c0d98ccf500a47ad08b1ff467be6814d64e436bc73f9b29eeec80f0ef2de2ba2bcf559440a939cc91f0cd711a5b6ef4ba89eae2a0f3ef1f756d02f97cf864e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0373ac8e918f0e107cab84aac4883f4d68373b3e18311f2454260e259ba30d7cc3b60d8d584ebaa5791a3b1040b08d235d20a8ceb25cdd4f2e179129fb059c73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e513d7735bf26d2c718b633c4c1dfae5e32804e669d9b2694947d1d4b59be02d69c03ef5015f306569b45117840201967904acb551246d07e8bd8b4db4e0104d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 11:51:13.290099) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:51:13.290382) ========= 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=db0483c52d987ecb441a263b000001ad-1504453646.995042-1345945086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004616 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:51:13.372633) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 11:51:13.375989) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 11:51:13.376293) ========= ========= Total master_lag: 0.29 =========