builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-039 starttime: 1504808911.21 results: success (0) revision: 3c96d611ebd67fc219d22bcb476a72412c76f6c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:31.210185) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:31.210637) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:31.210924) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:31.228683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:31.229017) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 11:28:31-- 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% 42.0M=0s 2017-09-07 11:28:31 (42.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.195771 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:31.443735) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:31.444063) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019309 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:31.493900) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 11:28:31.494203) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3c96d611ebd67fc219d22bcb476a72412c76f6c7 --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 3c96d611ebd67fc219d22bcb476a72412c76f6c7 --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=5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 11:28:31,553 truncating revision to first 12 chars 2017-09-07 11:28:31,553 Setting DEBUG logging. 2017-09-07 11:28:31,553 attempt 1/10 2017-09-07 11:28:31,553 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3c96d611ebd6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 11:28:32,395 unpacking tar archive at: mozilla-central-3c96d611ebd6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.132792 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 11:28:32.649369) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:32.649685) ========= script_repo_revision: 3c96d611ebd67fc219d22bcb476a72412c76f6c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:32.650051) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:32.650308) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 11:28:32.663503) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-07 11:28:32.663821) ========= /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=5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:28:32 INFO - MultiFileLogger online at 20170907 11:28:32 in /builds/slave/test 11:28:32 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:28:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:28:32 INFO - {'append_to_log': False, 11:28:32 INFO - 'base_work_dir': '/builds/slave/test', 11:28:32 INFO - 'blob_upload_branch': 'Firefox', 11:28:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:28:32 INFO - 'branch': 'Firefox', 11:28:32 INFO - 'buildbot_json_path': 'buildprops.json', 11:28:32 INFO - 'code_coverage': False, 11:28:32 INFO - 'config_files': ('talos/linux_config.py',), 11:28:32 INFO - 'default_actions': ('clobber', 11:28:32 INFO - 'read-buildbot-config', 11:28:32 INFO - 'download-and-extract', 11:28:32 INFO - 'populate-webroot', 11:28:32 INFO - 'create-virtualenv', 11:28:32 INFO - 'install', 11:28:32 INFO - 'setup-mitmproxy', 11:28:32 INFO - 'run-tests'), 11:28:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:28:32 INFO - 'disable_ccov_upload': False, 11:28:32 INFO - 'disable_stylo': False, 11:28:32 INFO - 'download_minidump_stackwalk': True, 11:28:32 INFO - 'download_symbols': 'ondemand', 11:28:32 INFO - 'enable_stylo': False, 11:28:32 INFO - 'enable_webrender': False, 11:28:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:28:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:28:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:28:32 INFO - 'gecko_profile': False, 11:28:32 INFO - 'gecko_profile_interval': 0, 11:28:32 INFO - 'installer_path': 'installer.exe', 11:28:32 INFO - 'log_level': 'info', 11:28:32 INFO - 'log_name': 'talos', 11:28:32 INFO - 'log_to_console': True, 11:28:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:28:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:28:32 INFO - 'opt_config_files': (), 11:28:32 INFO - 'pip_index': False, 11:28:32 INFO - 'suite': 'dromaeojs-e10s', 11:28:32 INFO - 'system_bits': '32', 11:28:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:28:32 INFO - 'title': 'talos-linux64-ix-039', 11:28:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:28:32 INFO - 'use_talos_json': True, 11:28:32 INFO - 'verify': 'False', 11:28:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:28:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:28:32 INFO - 'work_dir': 'build'} 11:28:32 INFO - [mozharness: 2017-09-07 18:28:32.864893Z] Running clobber step. 11:28:32 INFO - Running pre-action listener: _resource_record_pre_action 11:28:32 INFO - Running main action method: clobber 11:28:32 INFO - rmtree: /builds/slave/test/build 11:28:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:28:34 INFO - Running post-action listener: _resource_record_post_action 11:28:34 INFO - [mozharness: 2017-09-07 18:28:34.390919Z] Finished clobber step (success) 11:28:34 INFO - [mozharness: 2017-09-07 18:28:34.391016Z] Running read-buildbot-config step. 11:28:34 INFO - Running pre-action listener: _resource_record_pre_action 11:28:34 INFO - Running main action method: read_buildbot_config 11:28:34 INFO - Using buildbot properties: 11:28:34 INFO - { 11:28:34 INFO - "project": "", 11:28:34 INFO - "product": "firefox", 11:28:34 INFO - "who": "ffxbld@noreply.mozilla.org", 11:28:34 INFO - "installer_path": "public/build/target.tar.bz2", 11:28:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:28:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:28:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 11:28:34 INFO - "stage_platform": "linux64-pgo", 11:28:34 INFO - "basedir": "/builds/slave/test", 11:28:34 INFO - "buildnumber": 144, 11:28:34 INFO - "platform": "ubuntu64_hw", 11:28:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:28:34 INFO - "slavebuilddir": "test", 11:28:34 INFO - "taskId": "F9LTOpn3RCiXdqFybPJW_A", 11:28:34 INFO - "branch": "mozilla-central", 11:28:34 INFO - "script_repo_revision": "production", 11:28:34 INFO - "revision": "3c96d611ebd67fc219d22bcb476a72412c76f6c7", 11:28:34 INFO - "slavename": "talos-linux64-ix-039", 11:28:34 INFO - "repo_path": "mozilla-central" 11:28:34 INFO - } 11:28:34 INFO - Finding installer, test and symbols from parent task. 11:28:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F9LTOpn3RCiXdqFybPJW_A'}, attempt #1 11:28:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F9LTOpn3RCiXdqFybPJW_A'}, attempt #1 11:28:35 INFO - Task dependencies: IGNMaOSuRLejLW-8IRhPzg 11:28:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg'}, attempt #1 11:28:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.tar.bz2 11:28:35 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.test_packages.json 11:28:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.crashreporter-symbols.zip 11:28:35 INFO - Running post-action listener: _resource_record_post_action 11:28:35 INFO - [mozharness: 2017-09-07 18:28:35.736634Z] Finished read-buildbot-config step (success) 11:28:35 INFO - [mozharness: 2017-09-07 18:28:35.736851Z] Running download-and-extract step. 11:28:35 INFO - Running pre-action listener: _resource_record_pre_action 11:28:35 INFO - Running main action method: download_and_extract 11:28:35 INFO - mkdir: /builds/slave/test/build/tests 11:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:35 INFO - trying https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.test_packages.json 11:28:35 INFO - Downloading https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:28:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:28:36 INFO - Downloaded 989 bytes. 11:28:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:28:36 INFO - Using the following test package requirements: 11:28:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:28:36 INFO - u'common': [u'target.common.tests.zip'], 11:28:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:28:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:28:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:28:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:28:36 INFO - u'mozbase': [u'target.common.tests.zip'], 11:28:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:28:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:28:36 INFO - u'web-platform': [u'target.common.tests.zip', 11:28:36 INFO - u'target.web-platform.tests.tar.gz'], 11:28:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:28:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:28:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.common.tests.zip 11:28:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:28:36 INFO - Fetch https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.common.tests.zip into memory 11:28:37 INFO - Content-Length response header: 55690701 11:28:37 INFO - Bytes received: 55690701 11:28:48 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:28:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.common.tests.zip 11:28:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:28:48 INFO - Fetch https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.common.tests.zip into memory 11:28:49 INFO - Content-Length response header: 55690701 11:28:49 INFO - Bytes received: 55690701 11:29:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.talos.tests.zip 11:29:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:29:03 INFO - Fetch https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.talos.tests.zip into memory 11:29:04 INFO - Content-Length response header: 14496665 11:29:04 INFO - Bytes received: 14496665 11:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:04 INFO - trying https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.tar.bz2 11:29:04 INFO - Downloading https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:29:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:29:05 INFO - Downloaded 63586817 bytes. 11:29:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.tar.bz2 11:29:05 INFO - mkdir: /builds/slave/test/properties 11:29:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:29:05 INFO - Writing to file /builds/slave/test/properties/build_url 11:29:05 INFO - Contents: 11:29:05 INFO - build_url:https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.tar.bz2 11:29:05 INFO - Running post-action listener: _resource_record_post_action 11:29:05 INFO - Running post-action listener: find_tests_for_verification 11:29:05 INFO - Running post-action listener: set_extra_try_arguments 11:29:05 INFO - [mozharness: 2017-09-07 18:29:05.867642Z] Finished download-and-extract step (success) 11:29:05 INFO - [mozharness: 2017-09-07 18:29:05.867737Z] Running populate-webroot step. 11:29:05 INFO - Running pre-action listener: _resource_record_pre_action 11:29:05 INFO - Running main action method: populate_webroot 11:29:05 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:29:05 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['tresize', 11:29:05 INFO - 'tcanvasmark']}, 11:29:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:29:05 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['dromaeo_css', 11:29:05 INFO - 'kraken']}, 11:29:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:29:05 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:29:05 INFO - 'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:29:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:29:05 INFO - 'tests': ['damp', 'tps']}, 11:29:05 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:29:05 INFO - 'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['damp', 'tps']}, 11:29:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:29:05 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['dromaeo_dom']}, 11:29:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:29:05 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['basic_compositor_video', 11:29:05 INFO - 'glvideo']}, 11:29:05 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:29:05 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:29:05 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:29:05 INFO - 'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['ts_paint_webext', 11:29:05 INFO - 'tp5o_webext']}, 11:29:05 INFO - 'other-e10s': {'tests': ['a11yr', 11:29:05 INFO - 'ts_paint', 11:29:05 INFO - 'tpaint', 11:29:05 INFO - 'sessionrestore', 11:29:05 INFO - 'sessionrestore_many_windows', 11:29:05 INFO - 'sessionrestore_no_auto_restore', 11:29:05 INFO - 'tabpaint']}, 11:29:05 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['a11yr', 11:29:05 INFO - 'ts_paint', 11:29:05 INFO - 'tpaint', 11:29:05 INFO - 'sessionrestore', 11:29:05 INFO - 'sessionrestore_many_windows', 11:29:05 INFO - 'sessionrestore_no_auto_restore', 11:29:05 INFO - 'tabpaint']}, 11:29:05 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:29:05 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:29:05 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['bloom_basic_singleton']}, 11:29:05 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['bloom_basic']}, 11:29:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:29:05 INFO - 'tsvgr_opacity', 11:29:05 INFO - 'tart', 11:29:05 INFO - 'tscrollx', 11:29:05 INFO - 'tsvg_static']}, 11:29:05 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['tsvgx', 11:29:05 INFO - 'tsvgr_opacity', 11:29:05 INFO - 'tart', 11:29:05 INFO - 'tscrollx', 11:29:05 INFO - 'tsvg_static']}, 11:29:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:29:05 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:29:05 INFO - 'talos_options': ['--disable-stylo'], 11:29:05 INFO - 'tests': ['tp5o']}, 11:29:05 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:29:05 INFO - 'talos_options': ['--mitmproxy', 11:29:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:29:05 INFO - '--firstNonBlankPaint'], 11:29:05 INFO - 'tests': ['tp6_google', 11:29:05 INFO - 'tp6_youtube', 11:29:05 INFO - 'tp6_amazon', 11:29:05 INFO - 'tp6_facebook']}, 11:29:05 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:29:05 INFO - 'talos_options': ['--disable-stylo', 11:29:05 INFO - '--mitmproxy', 11:29:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:29:05 INFO - '--firstNonBlankPaint'], 11:29:05 INFO - 'tests': ['tp6_google', 11:29:05 INFO - 'tp6_youtube', 11:29:05 INFO - 'tp6_amazon', 11:29:05 INFO - 'tp6_facebook']}, 11:29:05 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:29:05 INFO - 'talos_options': ['--stylo-threads=1', 11:29:05 INFO - '--mitmproxy', 11:29:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:29:05 INFO - '--firstNonBlankPaint'], 11:29:05 INFO - 'tests': ['tp6_google', 11:29:05 INFO - 'tp6_youtube', 11:29:05 INFO - 'tp6_amazon', 11:29:05 INFO - 'tp6_facebook']}, 11:29:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:29:05 INFO - 'talos_options': ['--xperf_path', 11:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:29:05 INFO - 'tests': ['tp5n']}, 11:29:05 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:29:05 INFO - 'talos_options': ['--disable-stylo', 11:29:05 INFO - '--xperf_path', 11:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:29:05 INFO - 'tests': ['tp5n']}}} 11:29:05 INFO - Running post-action listener: _resource_record_post_action 11:29:05 INFO - [mozharness: 2017-09-07 18:29:05.882618Z] Finished populate-webroot step (success) 11:29:05 INFO - [mozharness: 2017-09-07 18:29:05.882707Z] Running create-virtualenv step. 11:29:05 INFO - Running pre-action listener: _resource_record_pre_action 11:29:05 INFO - Running main action method: create_virtualenv 11:29:05 INFO - Creating virtualenv /builds/slave/test/build/venv 11:29:05 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:29:05 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:29:05 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:29:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:29:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:29:06 INFO - Installing distribute.............................................................................................................................................................................................done. 11:29:06 INFO - Installing pip...............done. 11:29:06 INFO - Return code: 0 11:29:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:29:06 INFO - Reading from file tmpfile_stdout 11:29:06 INFO - Output received: 11:29:06 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:29:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:29:06 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:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:06 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:29:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:06 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 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29:06 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:29:06 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:29:06 INFO - Using env: {'DISPLAY': ':0', 11:29:06 INFO - 'HOME': '/home/cltbld', 11:29:06 INFO - 'LANG': 'en_US.UTF-8', 11:29:06 INFO - 'LANGUAGE': 'en_US:en', 11:29:06 INFO - 'LOGNAME': 'cltbld', 11:29:06 INFO - 'MAIL': '/var/mail/cltbld', 11:29:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:06 INFO - 'MOZ_NO_REMOTE': '1', 11:29:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:06 INFO - 'NO_EM_RESTART': '1', 11:29:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:06 INFO - 'PWD': '/builds/slave/test', 11:29:06 INFO - 'SHELL': '/bin/bash', 11:29:06 INFO - 'SHLVL': '1', 11:29:06 INFO - 'TERM': 'linux', 11:29:06 INFO - 'TMOUT': '86400', 11:29:06 INFO - 'USER': 'cltbld', 11:29:06 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:06 INFO - '_': '/tools/buildbot/bin/python'} 11:29:08 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:29:08 INFO - Downloading/unpacking pip>=1.5 11:29:08 INFO - Running setup.py egg_info for package pip 11:29:08 INFO - warning: no files found matching 'pip/cacert.pem' 11:29:08 INFO - warning: no files found matching '*.html' under directory 'docs' 11:29:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:29:08 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:29:08 INFO - Installing collected packages: pip 11:29:08 INFO - Found existing installation: pip 1.1 11:29:08 INFO - Uninstalling pip: 11:29:08 INFO - Successfully uninstalled pip 11:29:08 INFO - Running setup.py install for pip 11:29:08 INFO - warning: no files found matching 'pip/cacert.pem' 11:29:08 INFO - warning: no files found matching '*.html' under directory 'docs' 11:29:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:29:08 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:29:08 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:29:08 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:29:08 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:29:08 INFO - Successfully installed pip 11:29:08 INFO - Cleaning up... 11:29:08 INFO - Return code: 0 11:29:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:29: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:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29: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:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:08 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 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29:08 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:29:08 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:29:08 INFO - Using env: {'DISPLAY': ':0', 11:29:08 INFO - 'HOME': '/home/cltbld', 11:29:08 INFO - 'LANG': 'en_US.UTF-8', 11:29:08 INFO - 'LANGUAGE': 'en_US:en', 11:29:08 INFO - 'LOGNAME': 'cltbld', 11:29:08 INFO - 'MAIL': '/var/mail/cltbld', 11:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:08 INFO - 'MOZ_NO_REMOTE': '1', 11:29:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:08 INFO - 'NO_EM_RESTART': '1', 11:29:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:08 INFO - 'PWD': '/builds/slave/test', 11:29:08 INFO - 'SHELL': '/bin/bash', 11:29:08 INFO - 'SHLVL': '1', 11:29:08 INFO - 'TERM': 'linux', 11:29:08 INFO - 'TMOUT': '86400', 11:29:08 INFO - 'USER': 'cltbld', 11:29:08 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:08 INFO - '_': '/tools/buildbot/bin/python'} 11:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:08 INFO - Downloading/unpacking psutil>=3.1.1 11:29: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:29: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:29: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:29: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:29:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:29:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:29:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:29:09 INFO - Installing collected packages: psutil 11:29:09 INFO - Running setup.py install for psutil 11:29:09 INFO - building 'psutil._psutil_linux' extension 11:29:09 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:29:09 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:29:09 INFO - building 'psutil._psutil_posix' extension 11:29:09 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:29:09 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:29:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:29:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:29:10 INFO - Successfully installed psutil 11:29:10 INFO - Cleaning up... 11:29:10 INFO - Return code: 0 11:29:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:29: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:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29: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:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:29: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 mozsystemmonitor==0.3 11:29:10 INFO - Using env: {'DISPLAY': ':0', 11:29:10 INFO - 'HOME': '/home/cltbld', 11:29:10 INFO - 'LANG': 'en_US.UTF-8', 11:29:10 INFO - 'LANGUAGE': 'en_US:en', 11:29:10 INFO - 'LOGNAME': 'cltbld', 11:29:10 INFO - 'MAIL': '/var/mail/cltbld', 11:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:10 INFO - 'MOZ_NO_REMOTE': '1', 11:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:10 INFO - 'NO_EM_RESTART': '1', 11:29:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:10 INFO - 'PWD': '/builds/slave/test', 11:29:10 INFO - 'SHELL': '/bin/bash', 11:29:10 INFO - 'SHLVL': '1', 11:29:10 INFO - 'TERM': 'linux', 11:29:10 INFO - 'TMOUT': '86400', 11:29:10 INFO - 'USER': 'cltbld', 11:29:10 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:10 INFO - '_': '/tools/buildbot/bin/python'} 11:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:29:10 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:29:10 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:29:10 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:29:10 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:29:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:29:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:29:11 INFO - Installing collected packages: mozsystemmonitor 11:29:11 INFO - Running setup.py install for mozsystemmonitor 11:29:11 INFO - Successfully installed mozsystemmonitor 11:29:11 INFO - Cleaning up... 11:29:11 INFO - Return code: 0 11:29:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:29:11 INFO - Using env: {'DISPLAY': ':0', 11:29:11 INFO - 'HOME': '/home/cltbld', 11:29:11 INFO - 'LANG': 'en_US.UTF-8', 11:29:11 INFO - 'LANGUAGE': 'en_US:en', 11:29:11 INFO - 'LOGNAME': 'cltbld', 11:29:11 INFO - 'MAIL': '/var/mail/cltbld', 11:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:11 INFO - 'MOZ_NO_REMOTE': '1', 11:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:11 INFO - 'NO_EM_RESTART': '1', 11:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:11 INFO - 'PWD': '/builds/slave/test', 11:29:11 INFO - 'SHELL': '/bin/bash', 11:29:11 INFO - 'SHLVL': '1', 11:29:11 INFO - 'TERM': 'linux', 11:29:11 INFO - 'TMOUT': '86400', 11:29:11 INFO - 'USER': 'cltbld', 11:29:11 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:11 INFO - '_': '/tools/buildbot/bin/python'} 11:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:11 INFO - Downloading/unpacking jsonschema==2.5.1 11:29:11 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:29:11 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:29:11 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:29:11 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:29:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:29:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:29:12 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:29:12 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:29:12 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:29:12 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:29:13 INFO - Downloading functools32-3.2.3-2.tar.gz 11:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:29:13 INFO - warning: no files found matching '*.txt' 11:29:13 INFO - no previously-included directories found matching 'build' 11:29:13 INFO - no previously-included directories found matching 'dist' 11:29:13 INFO - no previously-included directories found matching '.git*' 11:29:13 INFO - Installing collected packages: jsonschema, functools32 11:29:13 INFO - Running setup.py install for functools32 11:29:13 INFO - warning: no files found matching '*.txt' 11:29:13 INFO - no previously-included directories found matching 'build' 11:29:13 INFO - no previously-included directories found matching 'dist' 11:29:13 INFO - no previously-included directories found matching '.git*' 11:29:13 INFO - Successfully installed jsonschema functools32 11:29:13 INFO - Cleaning up... 11:29:13 INFO - Return code: 0 11:29:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:13 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 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29:13 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:29:13 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:29:13 INFO - Using env: {'DISPLAY': ':0', 11:29:13 INFO - 'HOME': '/home/cltbld', 11:29:13 INFO - 'LANG': 'en_US.UTF-8', 11:29:13 INFO - 'LANGUAGE': 'en_US:en', 11:29:13 INFO - 'LOGNAME': 'cltbld', 11:29:13 INFO - 'MAIL': '/var/mail/cltbld', 11:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:13 INFO - 'MOZ_NO_REMOTE': '1', 11:29:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:13 INFO - 'NO_EM_RESTART': '1', 11:29:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:13 INFO - 'PWD': '/builds/slave/test', 11:29:13 INFO - 'SHELL': '/bin/bash', 11:29:13 INFO - 'SHLVL': '1', 11:29:13 INFO - 'TERM': 'linux', 11:29:13 INFO - 'TMOUT': '86400', 11:29:13 INFO - 'USER': 'cltbld', 11:29:13 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:13 INFO - '_': '/tools/buildbot/bin/python'} 11:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:29:13 INFO - Cleaning up... 11:29:13 INFO - Return code: 0 11:29:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:13 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 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29:13 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:29:13 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:29:13 INFO - Using env: {'DISPLAY': ':0', 11:29:13 INFO - 'HOME': '/home/cltbld', 11:29:13 INFO - 'LANG': 'en_US.UTF-8', 11:29:13 INFO - 'LANGUAGE': 'en_US:en', 11:29:13 INFO - 'LOGNAME': 'cltbld', 11:29:13 INFO - 'MAIL': '/var/mail/cltbld', 11:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:13 INFO - 'MOZ_NO_REMOTE': '1', 11:29:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:13 INFO - 'NO_EM_RESTART': '1', 11:29:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:13 INFO - 'PWD': '/builds/slave/test', 11:29:13 INFO - 'SHELL': '/bin/bash', 11:29:13 INFO - 'SHLVL': '1', 11:29:13 INFO - 'TERM': 'linux', 11:29:13 INFO - 'TMOUT': '86400', 11:29:13 INFO - 'USER': 'cltbld', 11:29:13 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:13 INFO - '_': '/tools/buildbot/bin/python'} 11:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:13 INFO - Downloading/unpacking blobuploader==1.2.4 11:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:14 INFO - Downloading blobuploader-1.2.4.tar.gz 11:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:29:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:29:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:15 INFO - Downloading docopt-0.6.1.tar.gz 11:29:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:29:15 INFO - Installing collected packages: blobuploader, requests, docopt 11:29:15 INFO - Running setup.py install for blobuploader 11:29:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:29:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:29:15 INFO - Running setup.py install for requests 11:29:15 INFO - Running setup.py install for docopt 11:29:15 INFO - Successfully installed blobuploader requests docopt 11:29:15 INFO - Cleaning up... 11:29:15 INFO - Return code: 0 11:29:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29:15 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:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:15 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:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:15 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 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29:15 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:29:15 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:29:15 INFO - Using env: {'DISPLAY': ':0', 11:29:15 INFO - 'HOME': '/home/cltbld', 11:29:15 INFO - 'LANG': 'en_US.UTF-8', 11:29:15 INFO - 'LANGUAGE': 'en_US:en', 11:29:15 INFO - 'LOGNAME': 'cltbld', 11:29:15 INFO - 'MAIL': '/var/mail/cltbld', 11:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:15 INFO - 'MOZ_NO_REMOTE': '1', 11:29:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:15 INFO - 'NO_EM_RESTART': '1', 11:29:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:15 INFO - 'PWD': '/builds/slave/test', 11:29:15 INFO - 'SHELL': '/bin/bash', 11:29:15 INFO - 'SHLVL': '1', 11:29:15 INFO - 'TERM': 'linux', 11:29:15 INFO - 'TMOUT': '86400', 11:29:15 INFO - 'USER': 'cltbld', 11:29:15 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:15 INFO - '_': '/tools/buildbot/bin/python'} 11:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:29:16 INFO - Running setup.py (path:/tmp/pip-j07vWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:29:16 INFO - Running setup.py (path:/tmp/pip-I6FACy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:29:16 INFO - Running setup.py (path:/tmp/pip-BbaoYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:29:16 INFO - Running setup.py (path:/tmp/pip-FSK40b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:29:16 INFO - Running setup.py (path:/tmp/pip-9ipAHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:29:16 INFO - Running setup.py (path:/tmp/pip-2Sn7Wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:29:16 INFO - Running setup.py (path:/tmp/pip-y42JvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:29:16 INFO - Running setup.py (path:/tmp/pip-nrOugr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:29:16 INFO - Running setup.py (path:/tmp/pip-DxcYRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:29:16 INFO - Running setup.py (path:/tmp/pip-cpPdeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:29:16 INFO - Running setup.py (path:/tmp/pip-ZIEEIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:29:16 INFO - Running setup.py (path:/tmp/pip-mOu3fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:29:17 INFO - Running setup.py (path:/tmp/pip-v1qCYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:29:17 INFO - Running setup.py (path:/tmp/pip-7SsXwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:17 INFO - Running setup.py (path:/tmp/pip-sx6QkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:29:17 INFO - Running setup.py (path:/tmp/pip-DiYeBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:29:17 INFO - Running setup.py (path:/tmp/pip-BPM5uH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:29:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:29:17 INFO - Running setup.py install for manifestparser 11:29:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:29:17 INFO - Running setup.py install for mozcrash 11:29:17 INFO - Running setup.py install for mozdebug 11:29:17 INFO - Running setup.py install for mozdevice 11:29:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:29:17 INFO - Running setup.py install for mozfile 11:29:17 INFO - Running setup.py install for mozhttpd 11:29:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:29:17 INFO - Running setup.py install for mozinfo 11:29:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Running setup.py install for mozInstall 11:29:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Running setup.py install for mozleak 11:29:18 INFO - Running setup.py install for mozlog 11:29:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Running setup.py install for moznetwork 11:29:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Running setup.py install for mozprocess 11:29:18 INFO - Running setup.py install for mozprofile 11:29:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Running setup.py install for mozrunner 11:29:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:29:18 INFO - Running setup.py install for mozscreenshot 11:29:18 INFO - Running setup.py install for moztest 11:29:18 INFO - Running setup.py install for mozversion 11:29:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:29:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:29:19 INFO - Cleaning up... 11:29:19 INFO - Return code: 0 11:29:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29:19 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:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:19 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:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:19 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 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29:19 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:29:19 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:29:19 INFO - Using env: {'DISPLAY': ':0', 11:29:19 INFO - 'HOME': '/home/cltbld', 11:29:19 INFO - 'LANG': 'en_US.UTF-8', 11:29:19 INFO - 'LANGUAGE': 'en_US:en', 11:29:19 INFO - 'LOGNAME': 'cltbld', 11:29:19 INFO - 'MAIL': '/var/mail/cltbld', 11:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:19 INFO - 'MOZ_NO_REMOTE': '1', 11:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:19 INFO - 'NO_EM_RESTART': '1', 11:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:19 INFO - 'PWD': '/builds/slave/test', 11:29:19 INFO - 'SHELL': '/bin/bash', 11:29:19 INFO - 'SHLVL': '1', 11:29:19 INFO - 'TERM': 'linux', 11:29:19 INFO - 'TMOUT': '86400', 11:29:19 INFO - 'USER': 'cltbld', 11:29:19 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:19 INFO - '_': '/tools/buildbot/bin/python'} 11:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:29:19 INFO - Running setup.py (path:/tmp/pip-gew10p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:29:19 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:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:29:19 INFO - Running setup.py (path:/tmp/pip-sFCF8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:29:19 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:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:29:19 INFO - Running setup.py (path:/tmp/pip-mRlCuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:29:19 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:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:29:19 INFO - Running setup.py (path:/tmp/pip-KaDTYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:29:19 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:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:29:19 INFO - Running setup.py (path:/tmp/pip-M2vFcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:29:19 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:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:29:19 INFO - Running setup.py (path:/tmp/pip-BFjeSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:29:19 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:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:29:19 INFO - Running setup.py (path:/tmp/pip-b7B5rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:29:19 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:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:29:19 INFO - Running setup.py (path:/tmp/pip-S31V3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:29:19 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:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:29:19 INFO - Running setup.py (path:/tmp/pip-eQju63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:29:19 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:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:29:19 INFO - Running setup.py (path:/tmp/pip-RdQ2PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:29:20 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:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:29:20 INFO - Running setup.py (path:/tmp/pip-XTvY1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:29:20 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:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:29:20 INFO - Running setup.py (path:/tmp/pip-K8Wxwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:29:20 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:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:29:20 INFO - Running setup.py (path:/tmp/pip-xLvYBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:29:20 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:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:29:20 INFO - Running setup.py (path:/tmp/pip-iLVb27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:29:20 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:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:20 INFO - Running setup.py (path:/tmp/pip-6SWSxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:20 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:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:29:20 INFO - Running setup.py (path:/tmp/pip-1gFNLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:29:20 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:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:29:20 INFO - Running setup.py (path:/tmp/pip-wenqqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:29:20 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:29:20 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:29:20 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:29:20 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:29:20 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:29:20 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:29:20 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:29:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:21 INFO - Downloading blessings-1.6.tar.gz 11:29:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:29:21 INFO - Installing collected packages: blessings 11:29:21 INFO - Running setup.py install for blessings 11:29:21 INFO - Successfully installed blessings 11:29:21 INFO - Cleaning up... 11:29:21 INFO - Return code: 0 11:29:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:29:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:29:22 INFO - Reading from file tmpfile_stdout 11:29:22 INFO - Current package versions: 11:29:22 INFO - argparse == 1.2.1 11:29:22 INFO - blessings == 1.6 11:29:22 INFO - blobuploader == 1.2.4 11:29:22 INFO - docopt == 0.6.1 11:29:22 INFO - functools32 == 3.2.3-2 11:29:22 INFO - jsonschema == 2.5.1 11:29:22 INFO - manifestparser == 1.1 11:29:22 INFO - mozInstall == 1.13 11:29:22 INFO - mozcrash == 1.0 11:29:22 INFO - mozdebug == 0.1 11:29:22 INFO - mozdevice == 0.51 11:29:22 INFO - mozfile == 1.2 11:29:22 INFO - mozhttpd == 0.7 11:29:22 INFO - mozinfo == 0.10 11:29:22 INFO - mozleak == 0.1 11:29:22 INFO - mozlog == 3.5 11:29:22 INFO - moznetwork == 0.27 11:29:22 INFO - mozprocess == 0.25 11:29:22 INFO - mozprofile == 0.28 11:29:22 INFO - mozrunner == 6.13 11:29:22 INFO - mozscreenshot == 0.1 11:29:22 INFO - mozsystemmonitor == 0.3 11:29:22 INFO - moztest == 0.8 11:29:22 INFO - mozversion == 1.4 11:29:22 INFO - psutil == 3.1.1 11:29:22 INFO - requests == 1.2.3 11:29:22 INFO - wsgiref == 0.1.2 11:29:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29:22 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:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:22 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:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:22 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 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29:22 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:29:22 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:29:22 INFO - Using env: {'DISPLAY': ':0', 11:29:22 INFO - 'HOME': '/home/cltbld', 11:29:22 INFO - 'LANG': 'en_US.UTF-8', 11:29:22 INFO - 'LANGUAGE': 'en_US:en', 11:29:22 INFO - 'LOGNAME': 'cltbld', 11:29:22 INFO - 'MAIL': '/var/mail/cltbld', 11:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:22 INFO - 'MOZ_NO_REMOTE': '1', 11:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:22 INFO - 'NO_EM_RESTART': '1', 11:29:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:22 INFO - 'PWD': '/builds/slave/test', 11:29:22 INFO - 'SHELL': '/bin/bash', 11:29:22 INFO - 'SHLVL': '1', 11:29:22 INFO - 'TERM': 'linux', 11:29:22 INFO - 'TMOUT': '86400', 11:29:22 INFO - 'USER': 'cltbld', 11:29:22 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:22 INFO - '_': '/tools/buildbot/bin/python'} 11:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:29:22 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:29:22 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:29:22 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:29:22 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:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:29:23 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:29:23 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:29:23 INFO - Installing collected packages: simplejson 11:29:23 INFO - Running setup.py install for simplejson 11:29:23 INFO - building 'simplejson._speedups' extension 11:29:23 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:29:24 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:29:24 INFO - Successfully installed simplejson 11:29:24 INFO - Cleaning up... 11:29:24 INFO - Return code: 0 11:29:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:24 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 0xd1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', '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:29:24 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:29:24 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:29:24 INFO - Using env: {'DISPLAY': ':0', 11:29:24 INFO - 'HOME': '/home/cltbld', 11:29:24 INFO - 'LANG': 'en_US.UTF-8', 11:29:24 INFO - 'LANGUAGE': 'en_US:en', 11:29:24 INFO - 'LOGNAME': 'cltbld', 11:29:24 INFO - 'MAIL': '/var/mail/cltbld', 11:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:24 INFO - 'MOZ_NO_REMOTE': '1', 11:29:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:24 INFO - 'NO_EM_RESTART': '1', 11:29:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:24 INFO - 'PWD': '/builds/slave/test', 11:29:24 INFO - 'SHELL': '/bin/bash', 11:29:24 INFO - 'SHLVL': '1', 11:29:24 INFO - 'TERM': 'linux', 11:29:24 INFO - 'TMOUT': '86400', 11:29:24 INFO - 'USER': 'cltbld', 11:29:24 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:24 INFO - '_': '/tools/buildbot/bin/python'} 11:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:29:24 INFO - Cleaning up... 11:29:24 INFO - Return code: 0 11:29:24 INFO - Running post-action listener: _resource_record_post_action 11:29:24 INFO - Running post-action listener: _start_resource_monitoring 11:29:24 INFO - Starting resource monitoring. 11:29:24 INFO - [mozharness: 2017-09-07 18:29:24.837990Z] Finished create-virtualenv step (success) 11:29:24 INFO - [mozharness: 2017-09-07 18:29:24.838500Z] Running install step. 11:29:24 INFO - Running pre-action listener: _resource_record_pre_action 11:29:24 INFO - Running main action method: install 11:29:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:29:25 INFO - Reading from file tmpfile_stdout 11:29:25 INFO - Detecting whether we're running mozinstall >=1.0... 11:29:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:29:25 INFO - Reading from file tmpfile_stdout 11:29:25 INFO - Output received: 11:29:25 INFO - Usage: mozinstall [options] installer 11:29:25 INFO - Options: 11:29:25 INFO - -h, --help show this help message and exit 11:29:25 INFO - -d DEST, --destination=DEST 11:29:25 INFO - Directory to install application into. [default: 11:29:25 INFO - "/builds/slave/test"] 11:29:25 INFO - --app=APP Application being installed. [default: firefox] 11:29:25 INFO - mkdir: /builds/slave/test/build/application 11:29:25 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:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:29:41 INFO - Reading from file tmpfile_stdout 11:29:41 INFO - Output received: 11:29:41 INFO - /builds/slave/test/build/application/firefox/firefox 11:29:41 INFO - Running post-action listener: _resource_record_post_action 11:29:41 INFO - [mozharness: 2017-09-07 18:29:41.613860Z] Finished install step (success) 11:29:41 INFO - [mozharness: 2017-09-07 18:29:41.614110Z] Running setup-mitmproxy step. 11:29:41 INFO - Running pre-action listener: _resource_record_pre_action 11:29:41 INFO - Running main action method: setup_mitmproxy 11:29:41 INFO - Skipping: mitmproxy is not required 11:29:41 INFO - Running post-action listener: _resource_record_post_action 11:29:41 INFO - [mozharness: 2017-09-07 18:29:41.615241Z] Finished setup-mitmproxy step (success) 11:29:41 INFO - [mozharness: 2017-09-07 18:29:41.615463Z] Running run-tests step. 11:29:41 INFO - Running pre-action listener: _resource_record_pre_action 11:29:41 INFO - Running pre-action listener: _set_gcov_prefix 11:29:41 INFO - Running main action method: run_tests 11:29:41 WARNING - Try message not found. 11:29:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:29:41 INFO - Python 2.7.3 11:29:41 INFO - Return code: 0 11:29:41 INFO - grabbing minidump binary from tooltool 11:29:41 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:29:41 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 0x108a090>, '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 0x108ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:29:41 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:29:41 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:29:41 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:29:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:29:41 INFO - Return code: 0 11:29:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:29:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:29:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:29:41 INFO - ENV: RUST_BACKTRACE is now full 11:29:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:29:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:29:41 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/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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:29:41 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/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-039 --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:29:41 INFO - Using env: {'DISPLAY': ':0', 11:29:41 INFO - 'HOME': '/home/cltbld', 11:29:41 INFO - 'LANG': 'en_US.UTF-8', 11:29:41 INFO - 'LANGUAGE': 'en_US:en', 11:29:41 INFO - 'LOGNAME': 'cltbld', 11:29:41 INFO - 'MAIL': '/var/mail/cltbld', 11:29:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:29:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:41 INFO - 'MOZ_NO_REMOTE': '1', 11:29:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:29:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:41 INFO - 'NO_EM_RESTART': '1', 11:29:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:41 INFO - 'PWD': '/builds/slave/test', 11:29:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:29:41 INFO - 'RUST_BACKTRACE': 'full', 11:29:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:29:41 INFO - 'SHELL': '/bin/bash', 11:29:41 INFO - 'SHLVL': '1', 11:29:41 INFO - 'TERM': 'linux', 11:29:41 INFO - 'TMOUT': '86400', 11:29:41 INFO - 'USER': 'cltbld', 11:29:41 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:41 INFO - '_': '/tools/buildbot/bin/python'} 11:29:41 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/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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:29:41 INFO - mozversion application_buildid: 20170907171306 11:29:41 INFO - mozversion application_changeset: 3c96d611ebd67fc219d22bcb476a72412c76f6c7 11:29:41 INFO - mozversion application_display_name: Nightly 11:29:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:29:41 INFO - mozversion application_name: Firefox 11:29:41 INFO - mozversion application_remotingname: firefox 11:29:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:29:41 INFO - mozversion application_vendor: Mozilla 11:29:41 INFO - mozversion application_version: 57.0a1 11:29:41 INFO - mozversion platform_buildid: 20170907171306 11:29:41 INFO - mozversion platform_changeset: 3c96d611ebd67fc219d22bcb476a72412c76f6c7 11:29:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:29:41 INFO - mozversion platform_version: 57.0a1 11:29:41 INFO - using testdate: 1504808981 11:29:41 INFO - actual date: 1504808981 11:29:41 INFO - starting webserver on 'localhost:34744' 11:29:41 INFO - SUITE-START | Running 2 tests 11:29:41 INFO - TEST-START | dromaeo_css 11:29:41 INFO - Initialising browser for dromaeo_css test... 11:29:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34744/getInfo.html -profile /tmp/tmptZNVXv/profile 11:29:41 INFO - TEST-INFO | started process 11024 (/builds/slave/test/build/application/firefox/firefox http://localhost:34744/getInfo.html) 11:29:47 INFO - TEST-INFO | 11024: exit 0 11:29:47 INFO - Browser initialized. 11:29:47 INFO - Running cycle 1/1 for dromaeo_css test... 11:29:47 INFO - Using env: {'DISPLAY': ':0', 11:29:47 INFO - 'HOME': '/home/cltbld', 11:29:47 INFO - 'JSGC_DISABLE_POISONING': '1', 11:29:47 INFO - 'LANG': 'en_US.UTF-8', 11:29:47 INFO - 'LANGUAGE': 'en_US:en', 11:29:47 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:29:47 INFO - 'LOGNAME': 'cltbld', 11:29:47 INFO - 'MAIL': '/var/mail/cltbld', 11:29:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:29:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:29:47 INFO - 'MOZ_CRASHREPORTER': '1', 11:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:47 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:29:47 INFO - 'MOZ_NO_REMOTE': '1', 11:29:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:47 INFO - 'NO_EM_RESTART': '1', 11:29:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:47 INFO - 'PWD': '/builds/slave/test', 11:29:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:29:47 INFO - 'RUST_BACKTRACE': 'full', 11:29:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:29:47 INFO - 'SHELL': '/bin/bash', 11:29:47 INFO - 'SHLVL': '1', 11:29:47 INFO - 'TERM': 'linux', 11:29:47 INFO - 'TMOUT': '86400', 11:29:47 INFO - 'USER': 'cltbld', 11:29:47 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:47 INFO - '_': '/tools/buildbot/bin/python'} 11:29:47 INFO - TEST-INFO | started process 11227 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptZNVXv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:48 INFO - PID 11227 | 11:29:48 INFO - PID 11227 | (/builds/slave/test/build/application/firefox/firefox:11278): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:29:48 INFO - PID 11227 | 11:29:59 INFO - PID 11227 | RSS: Main: 163229696 11:29:59 INFO - PID 11227 | 11:32:56 INFO - PID 11227 | Cycle 1(1): loaded http://localhost:34744/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34744/tests/dromaeo/cssquery-ext.html) 11:32:58 INFO - PID 11227 | RSS: Main: 166850560 11:32:58 INFO - PID 11227 | 11:35:59 INFO - PID 11227 | Cycle 1(1): loaded http://localhost:34744/tests/dromaeo/cssquery-ext.html (next: http://localhost:34744/tests/dromaeo/cssquery-jquery.html) 11:36:00 INFO - PID 11227 | RSS: Main: 163422208 11:36:00 INFO - PID 11227 | 11:39:02 INFO - PID 11227 | Cycle 1(1): loaded http://localhost:34744/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34744/tests/dromaeo/cssquery-mootools.html) 11:39:02 INFO - PID 11227 | RSS: Main: 165367808 11:39:02 INFO - PID 11227 | 11:42:04 INFO - PID 11227 | Cycle 1(1): loaded http://localhost:34744/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34744/tests/dromaeo/cssquery-prototype.html) 11:42:04 INFO - PID 11227 | RSS: Main: 164397056 11:42:04 INFO - PID 11227 | 11:45:07 INFO - PID 11227 | Cycle 1(1): loaded http://localhost:34744/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34744/tests/dromaeo/cssquery-yui.html) 11:45:08 INFO - PID 11227 | RSS: Main: 163893248 11:45:08 INFO - PID 11227 | 11:48:10 INFO - PID 11227 | Cycle 1(1): loaded http://localhost:34744/tests/dromaeo/cssquery-yui.html (next: http://localhost:34744/tests/dromaeo/cssquery-dojo.html) 11:48:10 INFO - PID 11227 | RSS: Main: 168534016 11:48:10 INFO - PID 11227 | 11:48:10 INFO - PID 11227 | __start_tp_report 11:48:10 INFO - PID 11227 | _x_x_mozilla_page_load 11:48:10 INFO - PID 11227 | _x_x_mozilla_page_load_details 11:48:10 INFO - PID 11227 | |i|pagename|runs| 11:48:10 INFO - PID 11227 | |0;dojo.html;3841.404582289746;3895.532536095668;3896.629820167084;3896.668783153432;3933.8229779659914;6212.285587157478;6356.39614236648;6395.520335974803;6399.8080057598345;6423.678816059201;6325.6837158142025;6335.398137176972;6393.552451328409;6419.165508483902;6472.51456140789;5600.355959064698;5655.1517272409055;5723.8569035774235;5728.971355143219;5737.340205876336;5501.037318469272;5706.800261990831;5749.971250143764;5781.913271300934;5809.419058094178;5614.29821272341;5650.237217975582;5666.065099258617;5683.3464151622675;5703.714814259291;5612.186232996192;5653.321601407816;5684.687342196177;5729.140628905656;5759.798407055775;5659.236003139565;5687.516561092312;5733.942660573382;5738.684372359518;5745.224394706703;6216.036514340284;6234;6247.062940558907;6360.173177486897;6407.070974708641;6809.455243580551;6828.829279268009;6833.180018397775;6849.04113424121;6872.140982377203;6851.417629501449;6876.518643694943;6922.619255940921;6959.408450281732;6959.408450281732;6284.842878928018;6383.68081595923;6385.584936979081;6482.805515834485;6496.805095847132;7037.788866334018;7040;7050.647467626701;7068.611226382598;7080.504364694422;6731.2932142125355;6776.864462710817;6850.588964662136;6870.965645171742;6871.072405225231;6878.896816547755;6906.102206713095;6968.895466568006;6975.162980442278;6997.440204783707;6752.83117922046;6799.524033317621;6818.045473633695;6866.6566671667215;6884.242733299333;6820.215675197316;6840.144981877265;6883.586984780929;6921.342472465108;6923.134608173978;4950.653454258239;5001.974990125026;5004.074246264453;5010.098182327216;5017.071841709296;4490.034642551802;4527;4531.161735079021;4573.291139873325;4579.152856721517;6268.310485846553;6286.622802631857;6316.084167795736;6341;6347.55567110307;6877.2091209510545;6908.861822763517;6921.653917304115;6927;6951.2353641099435;6374.808755737336;6386.457151142205;6401.199850018747;6404.103425520431;6418.775342862978;5477.3427188737605;5569.443055694479;5625.071863142617;5649.378568357477;5655.434456554394;7023.824404389931;7089.22018577956;7112.25321341262;7150.856982860314;7220.097487814023;6870.484713646397;6881.208661003949;6934.757283495255;6949.200841903245;6954.235034146139;7110.7511237106455;7127.10911136108;7130.714771409085;7221.711131554889;7231.566106033444;6722.529422940347;6810.250872404131;6831.146106736658;6906.171259448898;6915.757948471779;6887.5178737487895;6914.274001229798;6963.199232088172;6967.75612853568;6968.233494315723;6690.933090669226;6714.597124172566;6736.02327662496;6759.898601521079;6775.525713200029;6543.280239173782;6654.667266636746;6655.168103987002;6656.068150459037;6659.733610655519;6675.699593518206;6677.732890684319;6684.131062961784;6698.765543205965;6704.564203326768;6720.495962802712;6731.663416829041;6754.324567543206;6771.424428923597;6839.452843772586;6381.904271435839;6504.707288171949;6506.902396464152;6516.967415162894;6617.536772426077 11:48:10 INFO - PID 11227 | |1;ext.html;930.3812964378689;951.167728237792;981.3964411886692;987.9802403951921;997.3666721631762;42494.78752606236;42704.14591708164;43329.35005974909;43395;43466.78266608666;39856.60143398565;40250.59749402505;40360.59639403605;40389.39415908756;40527.59472405275;7156.463265255101;7304.3426091651745;7340.229275926031;7341.119065712095;7344.742933997311;7191.964040179818;7241.312075352833;7246.492745507817;7248.456365772562;7279.162896266917;7930.603469826486;8120.918790812076;8128.4310098293145;8133.308668763133;8139.38954578406;7999.1600881907725;8020.719274825382;8038.155993620702;8093.190680931919;8096.473729207582;7830.804229894241;8013.2387423194705;8071.112177660452;8075.475094118863;8085.716999904975;39780.403293950614;39785;39974.80012599948;40012.799936000134;40035.59964400348;3551.804650744208;3605.152789094561;3606.296772129436;3606.765560238576;3616.945745813815;32608.184795380068;32783;32784;32870.178245543815;32922.6707732922;5135.332406787131;5174.09453345663;5178.948210517884;5187.325647665779;5187.559057480119;200904;204680;211642.94178529162;212008;212592;54010.7299463508;54607.453925460235;54724.179137312974;54726.72636636791;54853.434526755635;55701.44298557044;55743.44256557464;55783.442165578635;55993;55995.440045599025;15992.680146397008;16091.919540402223;16115.516534503984;16123.516294511184;16128.75806862898;13155.473781048842;13218.339083045808;13230.801537976939;13261.535846245335;13269.203847769164;15791;15828.44600438979;15829.762553561706;15874.285657145456;15874.285657145456;6061.454469097803;6094.11635312886;6134.447899689049;6134.877302453926;6211.9068213976825;1107.0811226681835;1109.5506319940437;1120.4341807387218;1127.0926903842485;1129.8418221449003;25052.498950020898;25133.371665708502;25340.11309604155;25382.365440864127;25468.74531254701;1208.1965492947268;1209.879012098783;1217.8599461062092;1229.7970834812156;1237.2267332916927;13381.531646392525;13433.261170635513;13435.798463023062;13485;13486.258255795829;258324;259277;259464;259665;259686;55465.168022479695;55947.72026139925;55988.16017759737;56217.71891140518;56270.43729562652;1486.4932092930658;1505.759078547449;1512.1305249481372;1514.5691165679148;1565.7416526273037;24748.38129046788;24756.1335353269;24960.251192464257;30909.227269317547;31033.534496982113;54976.72511637576;55611.44388556063;55624.72187639036;55692.44307556873;55718.16422753581;240196;244099;244496;244647;245069;33187;33636.32727345488;36379.818100909324;36669;37542.43686344652;48766;49013;49411.75294123506;49848;50223.24665129953;36082.8195859019;36261;36300;36467.45298820573;36911.26177476489;35352.11619709528;35387.82306088453;35551.11122221862;35645;35779.28441431105;40962.385564217155;41990;42124;42765.35851962285;42800.78599606982;34754.30491390209;35308.47037294494;35746;35831.46252806262;36186;25966.870165649052;26961.46077078408;27000;27196.864015679796;27879 11:48:10 INFO - PID 11227 | |2;jquery.html;580.2485780913717;609.2171559545984;617.607819034913;630.1492984470965;647.0972992675216;22344.66483002754;22971;23033.309000729983;23058.538829223406;23146.42133946652;22688.8865555672;23503.76496235037;23579.29262122135;23634.64548031781;23733.5253294934;6617.272100068976;6804.08144900437;6846;6857.691403886837;6861.108055952719;6362.459190968773;6505.739770409178;6544;6564.081028655992;6623.701933413008;6602.405783479486;6605.306442823482;6609.272979972199;6630.138082049327;6632.900506492407;6604.834879128012;6607.075009498674;6615.5369124161325;6620.271770105261;6627.304133066055;6101.145839582462;6105.595712986038;6153.138560601519;6159.076138579204;6182.412670796267;27342.5898611519;27734.5839812403;27946.441071178666;28348.858255708798;28973.130806075853;2305.6656784766283;2348.5537747827857;2354.2701762453694;2355.2463211772238;2356.8350215484925;20133.39599812008;20456.488587785276;20487.180512779472;20752.896235518878;20771.376858694264;2460.5447992121517;2481.8759062047006;2557.4117952870756;2645.0477827981913;2646.4707058588165;134059;139842;150260.2486987569;152094;159532;15308.234588270541;16124.919375403048;16431.671366572602;16473.83526164747;16705.415310464075;25682.614760778604;25709.614355784677;25727.356816079748;25784.226473205457;25806.09678661238;10475.580976760997;10511.159107271553;10680.51937662807;10693.82367939526;10726.302790318605;9503.857442138235;9528.285378596494;9613.134817866287;9701;9716.368436051634;9497.90502094984;9531.65239264532;9733.805323893483;9822.852657210147;9959.80080398388;2983.895563655262;2988.4770165221435;2991.551267309921;3009.849507524615;3058.663547009827;1044.7492601775518;1050;1068.108129711684;1080.6920027792041;1082.8429877667852;24534.30122686503;25858;26187.345316367242;26568;26657.866710666323;1077.2136340471343;1095.8520599718936;1111.9110471162448;1116.447358557503;1131.0838221040983;11702.648920532398;11839.585614503623;11856.644300670994;11917.821232681517;11980.880191197977;612955;627457;629445;634347;655335;152863;153784;155377;155732;158446;1249.5314257153568;1290.8773666501668;1303.9282839443922;1318.6044186743939;1340.7921772125337;21816.45458863541;22542.887285563465;22917.31248062561;23192;23358.299251022498;67390;71903.28096719175;72609;73894;74121.88817167639;82947.5852620733;83303.5834820822;83828;84720.57639711762;85357.57321213355;21362.786372136703;21919;21984.45038874041;22107.66838497456;22207.1117155312;24372.51254974855;24442.87778561096;24445.26664200077;24591.262262132164;24607.38481537976;19419.8058019418;19652.803471965097;19717.507062323555;19783;19797;16095.275712593195;16374.918125409296;16646.334146634;18170.27318907229;18207.362742303954;20881.373558792653;21221.575568488854;21357.466063347918;21491.570168596227;21719.674204887255;21484.462888427915;21804.221881201178;21952.121915123214;22042;22183.667244991662;19991.600587958703;20055.0975206119;20157.294494692615;20222.292219772236;20443.080061396977 11:48:10 INFO - PID 11227 | |3;mootools.html;1344.9529266475672;1417.3834382043813;1418.5673369622261;1428.0788891165198;1448.3844366144385;16608.25262863171;17232.569185770346;17240.999999999985;17271.913640431812;17273.13634318282;16761.7485737714;16811.74782378262;16872.746908796347;16910.23903924323;16922.323107075732;2830.1368082734707;2846.5872448494956;2869.4691482075777;2875.7986940914143;2877.9856100719467;2857.4999375109273;2886.350571121491;2901.956470652942;2903.071017274473;2906.2879594499377;2839.957400638992;2858.699836517167;2949.7492713119304;2950.2034450698297;2952.763778897683;2889.0466146171816;2895.580140879571;2919.4453053919688;2920.7371336579704;2922.4009078139143;2759.1722483255144;2761.737634924679;2775.9167224983285;2780.7497325240824;2783.262435454586;16610.086445245506;16911.06989115598;17010.659786804194;17019.063951482665;17029.06340151291;8340.582970851434;8571.571421428904;8613.61238744258;8617.65529378824;8658.090900455425;5200.29795977542;5414.40441551429;5424.349078110652;5512.476314750133;5534.252875861787;2026.128764631208;2084.833213342929;2088.8642238254615;2146.399008277685;2165.382865883216;29945;29966.101016969526;30066;30096.54855177196;30134;7051.717931282691;7098.148222213367;7120.145582530129;7206.675699593531;7431.702731890772;18937;19223.423297301102;19305.903470482557;19477.707834382494;19587;8307.543085130255;8529.488230706178;8561.400701950804;8571.057183709787;8573.485590864444;7593.582352970529;7753.302202801662;7782.338501227459;7799.922000780033;7884.408669349822;5725.427457254241;5916.171735956969;5941.524678025748;5941.821745347646;6123.510119190454;1663.7005339038847;1685.073209734641;1701.5661006443277;1703.4634090261527;1711.9572010699583;938.7653086728318;979.0992287095889;980.4313498171186;991.4101109839634;1022.7187523430968;9923;9951.25365597583;10098.444585547866;10251.179905607534;10257;1047.7171163785888;1072.1476426241156;1082.3397727386289;1091.7379828841022;1099.1371773158032;5890.67601281925;5989.161517387569;6053.697315134314;6096.329403765582;6416.05610059003;24240;25254.494910101694;25439.36401589975;25630.359241018752;25635.487290254092;24348.634770478457;25040.499190016097;25049.62425563618;25077.87461062683;25455;11775.705607359712;11906;11992.220505667105;11999.100067495147;12026.879731202576;9592.280578956466;10025.849612255968;10032.498375081363;10054.19566434698;10563.419011954416;21385.572288554453;21743.34769956904;21818.127274908828;22196.223132190295;22260.554788903806;21974.121035158416;22023.22918697838;22069;22146.44633884166;22517.211897584162;12859.678508037;13055.151415157985;13134.802977955147;13141.737165256833;13147.276899770604;15170.317335719697;15206.771898421312;15326.693466130839;15336.76994845056;15354.539363819104;12511.812322814983;12558.560450384559;12563.623091307276;12658.620241392773;12826.422810973716;12300.323482208565;12311.44598493052;12417.875821241672;12491.625251242478;12502.937250333824;10854.945725271324;10905;11108.111351091731;11115.277506962022;11124.666260012213;8170.877436838571;8208.179182081743;8286.337093032664;8346.540940248344;8443.662253510036;8174.223448772357;8176.345892328718;8278.213569710868;8323.625436855476;8461.06928237881 11:48:10 INFO - PID 11227 | |4;prototype.html;551.9641223320484;570.6519023395729;580.8489792653909;593.2850914647847;593.7031484257873;6386.584871983329;6411.903821442682;6433.485321174307;6445.3554644535525;6455.515836312277;6140.232470941132;6212.130301757747;6272.623642581449;6315.684215789215;6336.366363363662;5132.923006154911;5146.305248791412;5149.8155424252445;5171.87070323243;5177.792888284464;4892;5095.6178286628465;5122.769475373599;5150.793968241266;5194.428612852583;4898.338724272231;4913.641452102993;4930.630202734791;4950.702957822524;4964.255361695757;4890.168671325883;4918.631102667278;4930.950690493085;4965.677230979974;4988.201887697951;4396.054848207651;4468.240399132155;4498.325251212312;4499.460064792246;4526;6054.939450605481;6118.908216376758;6141.723622437001;6163.691815409212;6169.475594574466;5799.681017544033;5815.941840581625;5836.824895253149;5855.531557475391;5863.413658634145;6612.801615951529;6614.570052946543;6655.401013908771;6686.489592963684;6727.192736871558;4983.72589507577;5048.1418158913075;5050.621203409759;5065.721385323805;5077.543021128116;6681.8663626727675;6771.9661401693165;6845.383915447584;6866.455047614297;6989;5154.381474223117;5178.741062946838;5204.8959020819375;5204.947950520522;5216;5247.2653828464045;5279.785649300682;5286.312779338661;5296.126139186991;5296.947030529645;5862.296524417012;5874.1776151338845;5883.61756485827;5905.970470147622;5912.615679980788;6738.494612904052;6745.3591908768585;6762.594244345355;6791.762288319885;6798.354156355138;1837.5130590393555;1845.0221382667207;1846.8245516675895;1852.8610354223488;1852.8702990790784;1222.9449674764658;1280.4878048780377;1284.9678758031123;1290.1807352331336;1293.3080801771196;6110;6135.662538560333;6142.293636231801;6176.413240742122;6189.381061893775;6539.280679125292;6562.2453417856605;6651.733930642817;6753.940048285222;6789.830254243585;5956.315023772235;6033.849153771103;6075.787347442907;6098.969505152446;6102.511799056065;3496.230829217568;3509.701675357572;3537;3599.38810402227;3613.837377318027;6970.930290697028;7002.754903578452;7038.824029399306;7099.3965512930945;7151.856962860714;6856.657167141623;6857.73131970587;6914.757983470655;6983.441324694012;7069.434445244368;6956.582605043714;7019.718591457797;7094.219635840053;7115.893261600977;7122.430205583541;6614.933850661624;6621.205455345389;6690.531662783558;6802.82992925161;6861.313868613099;6680.6993685283305;6742.213313472032;6773.89839152423;6784.525083244125;6840;6983.720651173896;7040.401565866754;7059.399951004222;7135.393491553276;7141.428685705235;5119.28330033803;5128.102582048201;5165.199394093921;5169;5181.611379146504;6572.605643661204;6718.8320291993095;6721.193456785021;6761.106890070788;6783.898241526283;6455.580387274812;6559.639219842764;6585.901211481927;6596.901046484402;6630.905900526359;6557.2131344239;6561.671916404065;6578.7368505261165;6580.703868325841;6618.8345291368105;6550.593691280603;6576.342365763386;6594.604323740591;6600.438962688226;6608.636524991171;6635.933640663725;6645.667716614246;6668.433183179484;6678.632675202716;6687.130673012672;6278.717457714505;6302.716377763103;6328.557001009885;6453.741850326122;6453.774117905851 11:48:10 INFO - PID 11227 | |5;yui.html;962.74006018375;988.7083310423425;1002.6190047781841;1007.4005966449964;1018.6129270877068;17319;17686.46940591781;17694.115294235286;17717.911410442928;17739.467815965527;17747.46757597274;17932.820671793277;18081.367152149644;18304.450866473995;18307.816921830778;1063.1707268330715;1093.8468614393992;1094.4801219420751;1095.4303762043733;1096.6326280695937;1019.6737044145876;1060.809054370213;1063.261033581661;1079.5196137718717;1081.7728277061826;1121.2991880074953;1125.5947858770835;1129.836173754805;1132.1339175530725;1133.4389477208792;1128.6388355726172;1128.6952522818833;1129.0854407929562;1129.9378534180616;1132.7507948251414;824.4435006370676;828.2173346187855;831.9126491718343;832.8917240758663;833.1002517281382;16623.91688041564;18477;18680;18694.81305186944;18704.158312875952;20816.08397545051;21303.573928521342;21825.67261491079;21830.126794928343;21852;16996.49010529686;17000.3199872005;17013.91493042539;17119.48641540756;17163.656726865393;3965.643092121723;3994.460747799039;3995.840166393341;3998.4402183694306;3999.2201520703475;153092;154536;155607;158251;158634.20682896627;14525.983181177427;14621.122732636077;14820.777688334683;14857.034292771152;14918.552443426715;27696.723032769816;27725;27730.72269277322;27741.167764966678;27747.445051098864;8725.345599079967;9087.36388452802;9093.4998575079;9093.636254549876;9096.317776166814;4253.659707223476;4320.265554855682;4334;4344.717593356421;4361.193179261847;5169.405518365361;5171.741412929338;5188.610854185876;5210.557102646242;5261.76322065508;1170.7014711248578;1190.36315571169;1208.2448469706433;1209.4557449147742;1211.3156066822216;742.483973638327;746.5856449670486;754.0762565856782;770.7995921060452;777.5917643237345;12073.094517911191;12349.32078735678;12753.487164638118;12855.421506032251;13641.522546710818;723.9384652304614;727.8507905879283;730.3791776989623;735.8197241675716;737.0860133434503;5829.967589129893;5855.531557475477;5909.852253693692;6049.122877182784;6117.541184411187;153578;155249;155336;156034.21982890012;158081.2095939536;132330.33834830957;133753;134698;135859;141464;112105;113242;113622;115780.42109789397;116217.8378216224;11107.38909359993;11161.162912781412;11408.771824563297;11438.25651332694;11534.365609891536;54328.456715433924;54358;54507.72746136244;56114.719426402604;56938.715306423204;94091.52954235185;96885;98089;98480;101577.43189496653;17488.300467981648;17806.287748490435;17923.551911202325;17967.910160449115;18122.72815907736;20844.374668759963;20960.580788384454;21095;21377.46556336042;21755.34733957984;18882.52793680169;19041.143148558625;19585.51036224049;19970.30103946413;20230;16171.514854554382;17803.910980445533;19042.7143592849;19448.90275548613;19681.803181967996;18477.9076104624;18514.259429622663;18836.24655013784;19901.60196796085;20150.496237594176;14376.35306411193;14555.854441455873;14556.63608409756;14601.780973285622;14647.853521464649;14935.402583896523;15116.244187790786;15136.470223542123;15307;15396.769048464057 11:48:10 INFO - PID 11227 | __end_tp_report 11:48:10 INFO - PID 11227 | __start_cc_report 11:48:10 INFO - PID 11227 | _x_x_mozilla_cycle_collect,333 11:48:10 INFO - PID 11227 | __end_cc_report 11:48:10 INFO - PID 11227 | __startTimestamp1504810090356__endTimestamp 11:48:10 INFO - PID 11227 | 11:48:10 INFO - PID 11227 | ------- Summary: start ------- 11:48:10 INFO - PID 11227 | Number of tests: 6 11:48:10 INFO - PID 11227 | 11:48:10 INFO - PID 11227 | [#0] dojo.html Cycles:165 Average:6337.62 Median:6655.17 stddev:763.60 (11.5%) stddev-sans-first:740.40 11:48:10 INFO - PID 11227 | Values: 3841.4 3895.5 3896.6 3896.7 3933.8 6212.3 6356.4 6395.5 6399.8 6423.7 6325.7 6335.4 6393.6 6419.2 6472.5 5600.4 5655.2 5723.9 5729.0 5737.3 5501.0 5706.8 5750.0 5781.9 5809.4 5614.3 5650.2 5666.1 5683.3 5703.7 5612.2 5653.3 5684.7 5729.1 5759.8 5659.2 5687.5 5733.9 5738.7 5745.2 6216.0 6234.0 6247.1 6360.2 6407.1 6809.5 6828.8 6833.2 6849.0 6872.1 6851.4 6876.5 6922.6 6959.4 6959.4 6284.8 6383.7 6385.6 6482.8 6496.8 7037.8 7040.0 7050.6 7068.6 7080.5 6731.3 6776.9 6850.6 6871.0 6871.1 6878.9 6906.1 6968.9 6975.2 6997.4 6752.8 6799.5 6818.0 6866.7 6884.2 6820.2 6840.1 6883.6 6921.3 6923.1 4950.7 5002.0 5004.1 5010.1 5017.1 4490.0 4527.0 4531.2 4573.3 4579.2 6268.3 6286.6 6316.1 6341.0 6347.6 6877.2 6908.9 6921.7 6927.0 6951.2 6374.8 6386.5 6401.2 6404.1 6418.8 5477.3 5569.4 5625.1 5649.4 5655.4 7023.8 7089.2 7112.3 7150.9 7220.1 6870.5 6881.2 6934.8 6949.2 6954.2 7110.8 7127.1 7130.7 7221.7 7231.6 6722.5 6810.3 6831.1 6906.2 6915.8 6887.5 6914.3 6963.2 6967.8 6968.2 6690.9 6714.6 6736.0 6759.9 6775.5 6543.3 6654.7 6655.2 6656.1 6659.7 6675.7 6677.7 6684.1 6698.8 6704.6 6720.5 6731.7 6754.3 6771.4 6839.5 6381.9 6504.7 6506.9 6517.0 6617.5 11:48:10 INFO - PID 11227 | 11:48:10 INFO - PID 11227 | [#1] ext.html Cycles:180 Average:42236.12 Median:36423.64 stddev:61863.35 (169.8%) stddev-sans-first:61959.14 11:48:10 INFO - PID 11227 | Values: 930.4 951.2 981.4 988.0 997.4 42494.8 42704.1 43329.4 43395.0 43466.8 39856.6 40250.6 40360.6 40389.4 40527.6 7156.5 7304.3 7340.2 7341.1 7344.7 7192.0 7241.3 7246.5 7248.5 7279.2 7930.6 8120.9 8128.4 8133.3 8139.4 7999.2 8020.7 8038.2 8093.2 8096.5 7830.8 8013.2 8071.1 8075.5 8085.7 39780.4 39785.0 39974.8 40012.8 40035.6 3551.8 3605.2 3606.3 3606.8 3616.9 32608.2 32783.0 32784.0 32870.2 32922.7 5135.3 5174.1 5178.9 5187.3 5187.6 200904.0 204680.0 211642.9 212008.0 212592.0 54010.7 54607.5 54724.2 54726.7 54853.4 55701.4 55743.4 55783.4 55993.0 55995.4 15992.7 16091.9 16115.5 16123.5 16128.8 13155.5 13218.3 13230.8 13261.5 13269.2 15791.0 15828.4 15829.8 15874.3 15874.3 6061.5 6094.1 6134.4 6134.9 6211.9 1107.1 1109.6 1120.4 1127.1 1129.8 25052.5 25133.4 25340.1 25382.4 25468.7 1208.2 1209.9 1217.9 1229.8 1237.2 13381.5 13433.3 13435.8 13485.0 13486.3 258324.0 259277.0 259464.0 259665.0 259686.0 55465.2 55947.7 55988.2 56217.7 56270.4 1486.5 1505.8 1512.1 1514.6 1565.7 24748.4 24756.1 24960.3 30909.2 31033.5 54976.7 55611.4 55624.7 55692.4 55718.2 240196.0 244099.0 244496.0 244647.0 245069.0 33187.0 33636.3 36379.8 36669.0 37542.4 48766.0 49013.0 49411.8 49848.0 50223.2 36082.8 36261.0 36300.0 36467.5 36911.3 35352.1 35387.8 35551.1 35645.0 35779.3 40962.4 41990.0 42124.0 42765.4 42800.8 34754.3 35308.5 35746.0 35831.5 36186.0 25966.9 26961.5 27000.0 27196.9 27879.0 11:48:10 INFO - PID 11227 | 11:48:10 INFO - PID 11227 | [#2] jquery.html Cycles:180 Average:41916.50 Median:2356.04 stddev:106035.49 (4500.6%) stddev-sans-first:106287.52 11:48:10 INFO - PID 11227 | Values: 580.2 609.2 617.6 630.1 647.1 22344.7 22971.0 23033.3 23058.5 23146.4 22688.9 23503.8 23579.3 23634.6 23733.5 6617.3 6804.1 6846.0 6857.7 6861.1 6362.5 6505.7 6544.0 6564.1 6623.7 6602.4 6605.3 6609.3 6630.1 6632.9 6604.8 6607.1 6615.5 6620.3 6627.3 6101.1 6105.6 6153.1 6159.1 6182.4 27342.6 27734.6 27946.4 28348.9 28973.1 2305.7 2348.6 2354.3 2355.2 2356.8 20133.4 20456.5 20487.2 20752.9 20771.4 2460.5 2481.9 2557.4 2645.0 2646.5 134059.0 139842.0 150260.2 152094.0 159532.0 15308.2 16124.9 16431.7 16473.8 16705.4 25682.6 25709.6 25727.4 25784.2 25806.1 10475.6 10511.2 10680.5 10693.8 10726.3 9503.9 9528.3 9613.1 9701.0 9716.4 9497.9 9531.7 9733.8 9822.9 9959.8 2983.9 2988.5 2991.6 3009.8 3058.7 1044.7 1050.0 1068.1 1080.7 1082.8 24534.3 25858.0 26187.3 26568.0 26657.9 1077.2 1095.9 1111.9 1116.4 1131.1 11702.6 11839.6 11856.6 11917.8 11980.9 612955.0 627457.0 629445.0 634347.0 655335.0 152863.0 153784.0 155377.0 155732.0 158446.0 1249.5 1290.9 1303.9 1318.6 1340.8 21816.5 22542.9 22917.3 23192.0 23358.3 67390.0 71903.3 72609.0 73894.0 74121.9 82947.6 83303.6 83828.0 84720.6 85357.6 21362.8 21919.0 21984.5 22107.7 22207.1 24372.5 24442.9 24445.3 24591.3 24607.4 19419.8 19652.8 19717.5 19783.0 19797.0 16095.3 16374.9 16646.3 18170.3 18207.4 20881.4 21221.6 21357.5 21491.6 21719.7 21484.5 21804.2 21952.1 22042.0 22183.7 19991.6 20055.1 20157.3 20222.3 20443.1 11:48:10 INFO - PID 11227 | 11:48:10 INFO - PID 11227 | [#3] mootools.html Cycles:180 Average:10517.41 Median:25045.06 stddev:7699.11 (30.7%) stddev-sans-first:7689.87 11:48:10 INFO - PID 11227 | Values: 1345.0 1417.4 1418.6 1428.1 1448.4 16608.3 17232.6 17241.0 17271.9 17273.1 16761.7 16811.7 16872.7 16910.2 16922.3 2830.1 2846.6 2869.5 2875.8 2878.0 2857.5 2886.4 2902.0 2903.1 2906.3 2840.0 2858.7 2949.7 2950.2 2952.8 2889.0 2895.6 2919.4 2920.7 2922.4 2759.2 2761.7 2775.9 2780.7 2783.3 16610.1 16911.1 17010.7 17019.1 17029.1 8340.6 8571.6 8613.6 8617.7 8658.1 5200.3 5414.4 5424.3 5512.5 5534.3 2026.1 2084.8 2088.9 2146.4 2165.4 29945.0 29966.1 30066.0 30096.5 30134.0 7051.7 7098.1 7120.1 7206.7 7431.7 18937.0 19223.4 19305.9 19477.7 19587.0 8307.5 8529.5 8561.4 8571.1 8573.5 7593.6 7753.3 7782.3 7799.9 7884.4 5725.4 5916.2 5941.5 5941.8 6123.5 1663.7 1685.1 1701.6 1703.5 1712.0 938.8 979.1 980.4 991.4 1022.7 9923.0 9951.3 10098.4 10251.2 10257.0 1047.7 1072.1 1082.3 1091.7 1099.1 5890.7 5989.2 6053.7 6096.3 6416.1 24240.0 25254.5 25439.4 25630.4 25635.5 24348.6 25040.5 25049.6 25077.9 25455.0 11775.7 11906.0 11992.2 11999.1 12026.9 9592.3 10025.8 10032.5 10054.2 10563.4 21385.6 21743.3 21818.1 22196.2 22260.6 21974.1 22023.2 22069.0 22146.4 22517.2 12859.7 13055.2 13134.8 13141.7 13147.3 15170.3 15206.8 15326.7 15336.8 15354.5 12511.8 12558.6 12563.6 12658.6 12826.4 12300.3 12311.4 12417.9 12491.6 12502.9 10854.9 10905.0 11108.1 11115.3 11124.7 8170.9 8208.2 8286.3 8346.5 8443.7 8174.2 8176.3 8278.2 8323.6 8461.1 11:48:10 INFO - PID 11227 | 11:48:10 INFO - PID 11227 | [#4] prototype.html Cycles:180 Average:5645.73 Median:6200.76 stddev:1580.24 (25.5%) stddev-sans-first:1537.73 11:48:10 INFO - PID 11227 | Values: 552.0 570.7 580.8 593.3 593.7 6386.6 6411.9 6433.5 6445.4 6455.5 6140.2 6212.1 6272.6 6315.7 6336.4 5132.9 5146.3 5149.8 5171.9 5177.8 4892.0 5095.6 5122.8 5150.8 5194.4 4898.3 4913.6 4930.6 4950.7 4964.3 4890.2 4918.6 4931.0 4965.7 4988.2 4396.1 4468.2 4498.3 4499.5 4526.0 6054.9 6118.9 6141.7 6163.7 6169.5 5799.7 5815.9 5836.8 5855.5 5863.4 6612.8 6614.6 6655.4 6686.5 6727.2 4983.7 5048.1 5050.6 5065.7 5077.5 6681.9 6772.0 6845.4 6866.5 6989.0 5154.4 5178.7 5204.9 5204.9 5216.0 5247.3 5279.8 5286.3 5296.1 5296.9 5862.3 5874.2 5883.6 5906.0 5912.6 6738.5 6745.4 6762.6 6791.8 6798.4 1837.5 1845.0 1846.8 1852.9 1852.9 1222.9 1280.5 1285.0 1290.2 1293.3 6110.0 6135.7 6142.3 6176.4 6189.4 6539.3 6562.2 6651.7 6753.9 6789.8 5956.3 6033.8 6075.8 6099.0 6102.5 3496.2 3509.7 3537.0 3599.4 3613.8 6970.9 7002.8 7038.8 7099.4 7151.9 6856.7 6857.7 6914.8 6983.4 7069.4 6956.6 7019.7 7094.2 7115.9 7122.4 6614.9 6621.2 6690.5 6802.8 6861.3 6680.7 6742.2 6773.9 6784.5 6840.0 6983.7 7040.4 7059.4 7135.4 7141.4 5119.3 5128.1 5165.2 5169.0 5181.6 6572.6 6718.8 6721.2 6761.1 6783.9 6455.6 6559.6 6585.9 6596.9 6630.9 6557.2 6561.7 6578.7 6580.7 6618.8 6550.6 6576.3 6594.6 6600.4 6608.6 6635.9 6645.7 6668.4 6678.6 6687.1 6278.7 6302.7 6328.6 6453.7 6453.8 11:48:10 INFO - PID 11227 | 11:48:10 INFO - PID 11227 | [#5] yui.html Cycles:180 Average:28805.14 Median:17928.19 stddev:43670.91 (243.6%) stddev-sans-first:43743.38 11:48:10 INFO - PID 11227 | Values: 962.7 988.7 1002.6 1007.4 1018.6 17319.0 17686.5 17694.1 17717.9 17739.5 17747.5 17932.8 18081.4 18304.5 18307.8 1063.2 1093.8 1094.5 1095.4 1096.6 1019.7 1060.8 1063.3 1079.5 1081.8 1121.3 1125.6 1129.8 1132.1 1133.4 1128.6 1128.7 1129.1 1129.9 1132.8 824.4 828.2 831.9 832.9 833.1 16623.9 18477.0 18680.0 18694.8 18704.2 20816.1 21303.6 21825.7 21830.1 21852.0 16996.5 17000.3 17013.9 17119.5 17163.7 3965.6 3994.5 3995.8 3998.4 3999.2 153092.0 154536.0 155607.0 158251.0 158634.2 14526.0 14621.1 14820.8 14857.0 14918.6 27696.7 27725.0 27730.7 27741.2 27747.4 8725.3 9087.4 9093.5 9093.6 9096.3 4253.7 4320.3 4334.0 4344.7 4361.2 5169.4 5171.7 5188.6 5210.6 5261.8 1170.7 1190.4 1208.2 1209.5 1211.3 742.5 746.6 754.1 770.8 777.6 12073.1 12349.3 12753.5 12855.4 13641.5 723.9 727.9 730.4 735.8 737.1 5830.0 5855.5 5909.9 6049.1 6117.5 153578.0 155249.0 155336.0 156034.2 158081.2 132330.3 133753.0 134698.0 135859.0 141464.0 112105.0 113242.0 113622.0 115780.4 116217.8 11107.4 11161.2 11408.8 11438.3 11534.4 54328.5 54358.0 54507.7 56114.7 56938.7 94091.5 96885.0 98089.0 98480.0 101577.4 17488.3 17806.3 17923.6 17967.9 18122.7 20844.4 20960.6 21095.0 21377.5 21755.3 18882.5 19041.1 19585.5 19970.3 20230.0 16171.5 17803.9 19042.7 19448.9 19681.8 18477.9 18514.3 18836.2 19901.6 20150.5 14376.4 14555.9 14556.6 14601.8 14647.9 14935.4 15116.2 15136.5 15307.0 15396.8 11:48:10 INFO - PID 11227 | -------- Summary: end -------- 11:48:10 INFO - PID 11227 | 11:48:11 INFO - TEST-INFO | 11227: exit 0 11:48:11 INFO - TEST-OK | dromaeo_css | took 1109069ms 11:48:11 INFO - TEST-START | kraken 11:48:11 INFO - Initialising browser for kraken test... 11:48:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34744/getInfo.html -profile /tmp/tmpicK7oF/profile 11:48:11 INFO - TEST-INFO | started process 11610 (/builds/slave/test/build/application/firefox/firefox http://localhost:34744/getInfo.html) 11:48:17 INFO - TEST-INFO | 11610: exit 0 11:48:17 INFO - Browser initialized. 11:48:17 INFO - Running cycle 1/1 for kraken test... 11:48:17 INFO - Using env: {'DISPLAY': ':0', 11:48:17 INFO - 'HOME': '/home/cltbld', 11:48:17 INFO - 'JSGC_DISABLE_POISONING': '1', 11:48:17 INFO - 'LANG': 'en_US.UTF-8', 11:48:17 INFO - 'LANGUAGE': 'en_US:en', 11:48:17 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:48:17 INFO - 'LOGNAME': 'cltbld', 11:48:17 INFO - 'MAIL': '/var/mail/cltbld', 11:48:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:48:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:48:17 INFO - 'MOZ_CRASHREPORTER': '1', 11:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:17 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:48:17 INFO - 'MOZ_NO_REMOTE': '1', 11:48:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:48:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:17 INFO - 'NO_EM_RESTART': '1', 11:48:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:17 INFO - 'PWD': '/builds/slave/test', 11:48:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:48:17 INFO - 'RUST_BACKTRACE': 'full', 11:48:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:48:17 INFO - 'SHELL': '/bin/bash', 11:48:17 INFO - 'SHLVL': '1', 11:48:17 INFO - 'TERM': 'linux', 11:48:17 INFO - 'TMOUT': '86400', 11:48:17 INFO - 'USER': 'cltbld', 11:48:17 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977', 11:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:17 INFO - '_': '/tools/buildbot/bin/python'} 11:48:17 INFO - TEST-INFO | started process 11806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpicK7oF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:48:18 INFO - PID 11806 | 11:48:18 INFO - PID 11806 | (/builds/slave/test/build/application/firefox/firefox:11857): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:48:18 INFO - PID 11806 | 11:48:28 INFO - PID 11806 | RSS: Main: 164073472 11:48:28 INFO - PID 11806 | 11:48:30 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34744/tests/kraken/driver.html?testName=audio-beat-detection) 11:48:31 INFO - PID 11806 | RSS: Main: 169783296 11:48:31 INFO - PID 11806 | 11:48:34 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34744/tests/kraken/driver.html?testName=audio-dft) 11:48:34 INFO - PID 11806 | RSS: Main: 170401792 11:48:34 INFO - PID 11806 | 11:48:37 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34744/tests/kraken/driver.html?testName=audio-fft) 11:48:38 INFO - PID 11806 | RSS: Main: 164528128 11:48:38 INFO - PID 11806 | 11:48:40 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34744/tests/kraken/driver.html?testName=audio-oscillator) 11:48:41 INFO - PID 11806 | RSS: Main: 166359040 11:48:41 INFO - PID 11806 | 11:48:43 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34744/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:48:43 INFO - PID 11806 | RSS: Main: 166526976 11:48:43 INFO - PID 11806 | 11:48:46 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34744/tests/kraken/driver.html?testName=imaging-darkroom) 11:48:47 INFO - PID 11806 | RSS: Main: 166359040 11:48:47 INFO - PID 11806 | 11:48:51 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34744/tests/kraken/driver.html?testName=imaging-desaturate) 11:48:51 INFO - PID 11806 | RSS: Main: 168849408 11:48:51 INFO - PID 11806 | 11:48:54 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34744/tests/kraken/driver.html?testName=json-parse-financial) 11:48:55 INFO - PID 11806 | RSS: Main: 169041920 11:48:55 INFO - PID 11806 | 11:48:56 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34744/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:48:57 INFO - PID 11806 | RSS: Main: 168812544 11:48:57 INFO - PID 11806 | 11:48:58 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34744/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:48:59 INFO - PID 11806 | RSS: Main: 169046016 11:48:59 INFO - PID 11806 | 11:49:01 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34744/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:49:02 INFO - PID 11806 | RSS: Main: 166727680 11:49:02 INFO - PID 11806 | 11:49:04 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34744/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:49:05 INFO - PID 11806 | RSS: Main: 166699008 11:49:05 INFO - PID 11806 | 11:49:07 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34744/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:49:08 INFO - PID 11806 | RSS: Main: 166825984 11:49:08 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | Cycle 1(1): loaded http://localhost:34744/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34744/tests/kraken/driver.html?testName=ai-astar) 11:49:10 INFO - PID 11806 | RSS: Main: 168931328 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | __start_tp_report 11:49:10 INFO - PID 11806 | _x_x_mozilla_page_load 11:49:10 INFO - PID 11806 | _x_x_mozilla_page_load_details 11:49:10 INFO - PID 11806 | |i|pagename|runs| 11:49:10 INFO - PID 11806 | |0;ai-astar;104;104;104;105;105;107;107;105;105;106 11:49:10 INFO - PID 11806 | |1;audio-beat-detection;113;118;112;111;112;111;149;116;115;113 11:49:10 INFO - PID 11806 | |2;audio-dft;163;163;163;162;161;162;161;163;163;166 11:49:10 INFO - PID 11806 | |3;audio-fft;72;72;73;73;73;74;75;75;74;72 11:49:10 INFO - PID 11806 | |4;audio-oscillator;78;79;81;78;78;77;78;77;79;78 11:49:10 INFO - PID 11806 | |5;imaging-gaussian-blur;116;113;113;115;113;114;112;114;113;114 11:49:10 INFO - PID 11806 | |6;imaging-darkroom;149;151;151;149;150;150;148;149;150;149 11:49:10 INFO - PID 11806 | |7;imaging-desaturate;94;94;93;93;93;94;95;94;95;95 11:49:10 INFO - PID 11806 | |8;json-parse-financial;47;47;47;44;43;44;43;42;47;43 11:49:10 INFO - PID 11806 | |9;json-stringify-tinderbox;38;38;38;39;39;39;38;38;39;39 11:49:10 INFO - PID 11806 | |10;stanford-crypto-aes;74;73;73;74;72;71;72;73;72;73 11:49:10 INFO - PID 11806 | |11;stanford-crypto-ccm;113;113;113;115;128;126;111;115;111;116 11:49:10 INFO - PID 11806 | |12;stanford-crypto-pbkdf2;157;156;154;154;150;154;151;159;153;160 11:49:10 INFO - PID 11806 | |13;stanford-crypto-sha256-iterative;64;58;58;59;59;59;68;61;61;56 11:49:10 INFO - PID 11806 | __end_tp_report 11:49:10 INFO - PID 11806 | __start_cc_report 11:49:10 INFO - PID 11806 | _x_x_mozilla_cycle_collect,903 11:49:10 INFO - PID 11806 | __end_cc_report 11:49:10 INFO - PID 11806 | __startTimestamp1504810150554__endTimestamp 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | ------- Summary: start ------- 11:49:10 INFO - PID 11806 | Number of tests: 14 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#0] ai-astar Cycles:10 Average:105.20 Median:105.00 stddev:1.14 (1.1%) stddev-sans-first:1.12 11:49:10 INFO - PID 11806 | Values: 104.0 104.0 104.0 105.0 105.0 107.0 107.0 105.0 105.0 106.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#1] audio-beat-detection Cycles:10 Average:117.00 Median:114.00 stddev:11.47 (10.1%) stddev-sans-first:12.07 11:49:10 INFO - PID 11806 | Values: 113.0 118.0 112.0 111.0 112.0 111.0 149.0 116.0 115.0 113.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.42 (0.9%) stddev-sans-first:1.50 11:49:10 INFO - PID 11806 | Values: 163.0 163.0 163.0 162.0 161.0 162.0 161.0 163.0 163.0 166.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#3] audio-fft Cycles:10 Average:73.30 Median:73.50 stddev:1.16 (1.6%) stddev-sans-first:1.13 11:49:10 INFO - PID 11806 | Values: 72.0 72.0 73.0 73.0 73.0 74.0 75.0 75.0 74.0 72.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:78.00 stddev:1.16 (1.5%) stddev-sans-first:1.22 11:49:10 INFO - PID 11806 | Values: 78.0 79.0 81.0 78.0 78.0 77.0 78.0 77.0 79.0 78.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:1.16 (1.0%) stddev-sans-first:0.88 11:49:10 INFO - PID 11806 | Values: 116.0 113.0 113.0 115.0 113.0 114.0 112.0 114.0 113.0 114.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:150.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 11:49:10 INFO - PID 11806 | Values: 149.0 151.0 151.0 149.0 150.0 150.0 148.0 149.0 150.0 149.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 11:49:10 INFO - PID 11806 | Values: 94.0 94.0 93.0 93.0 93.0 94.0 95.0 94.0 95.0 95.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#8] json-parse-financial Cycles:10 Average:44.70 Median:45.50 stddev:2.06 (4.5%) stddev-sans-first:2.01 11:49:10 INFO - PID 11806 | Values: 47.0 47.0 47.0 44.0 43.0 44.0 43.0 42.0 47.0 43.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:39.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 11:49:10 INFO - PID 11806 | Values: 38.0 38.0 38.0 39.0 39.0 39.0 38.0 38.0 39.0 39.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#10] stanford-crypto-aes Cycles:10 Average:72.70 Median:73.00 stddev:0.95 (1.3%) stddev-sans-first:0.88 11:49:10 INFO - PID 11806 | Values: 74.0 73.0 73.0 74.0 72.0 71.0 72.0 73.0 72.0 73.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#11] stanford-crypto-ccm Cycles:10 Average:116.10 Median:115.00 stddev:5.99 (5.2%) stddev-sans-first:6.25 11:49:10 INFO - PID 11806 | Values: 113.0 113.0 113.0 115.0 128.0 126.0 111.0 115.0 111.0 116.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.80 Median:155.00 stddev:3.22 (2.1%) stddev-sans-first:3.32 11:49:10 INFO - PID 11806 | Values: 157.0 156.0 154.0 154.0 150.0 154.0 151.0 159.0 153.0 160.0 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.30 Median:60.00 stddev:3.47 (5.8%) stddev-sans-first:3.41 11:49:10 INFO - PID 11806 | Values: 64.0 58.0 58.0 59.0 59.0 59.0 68.0 61.0 61.0 56.0 11:49:10 INFO - PID 11806 | -------- Summary: end -------- 11:49:10 INFO - PID 11806 | 11:49:10 INFO - PID 11806 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:49:11 INFO - TEST-INFO | 11806: exit 0 11:49:11 INFO - TEST-OK | kraken | took 60292ms 11:49:11 INFO - SUITE-END | took 1169s 11:49:11 INFO - Completed test suite (00:19:29) 11:49:11 INFO - javascript benchmark 11:49:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9155.0214586841, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3841.404582289746, 3895.532536095668, 3896.629820167084, 3896.668783153432, 3933.8229779659914, 6212.285587157478, 6356.39614236648, 6395.520335974803, 6399.8080057598345, 6423.678816059201, 6325.6837158142025, 6335.398137176972, 6393.552451328409, 6419.165508483902, 6472.51456140789, 5600.355959064698, 5655.1517272409055, 5723.8569035774235, 5728.971355143219, 5737.340205876336, 5501.037318469272, 5706.800261990831, 5749.971250143764, 5781.913271300934, 5809.419058094178, 5614.29821272341, 5650.237217975582, 5666.065099258617, 5683.3464151622675, 5703.714814259291, 5612.186232996192, 5653.321601407816, 5684.687342196177, 5729.140628905656, 5759.798407055775, 5659.236003139565, 5687.516561092312, 5733.942660573382, 5738.684372359518, 5745.224394706703, 6216.036514340284, 6234.0, 6247.062940558907, 6360.173177486897, 6407.070974708641, 6809.455243580551, 6828.829279268009, 6833.180018397775, 6849.04113424121, 6872.140982377203, 6851.417629501449, 6876.518643694943, 6922.619255940921, 6959.408450281732, 6959.408450281732, 6284.842878928018, 6383.68081595923, 6385.584936979081, 6482.805515834485, 6496.805095847132, 7037.788866334018, 7040.0, 7050.647467626701, 7068.611226382598, 7080.504364694422, 6731.2932142125355, 6776.864462710817, 6850.588964662136, 6870.965645171742, 6871.072405225231, 6878.896816547755, 6906.102206713095, 6968.895466568006, 6975.162980442278, 6997.440204783707, 6752.83117922046, 6799.524033317621, 6818.045473633695, 6866.6566671667215, 6884.242733299333, 6820.215675197316, 6840.144981877265, 6883.586984780929, 6921.342472465108, 6923.134608173978, 4950.653454258239, 5001.974990125026, 5004.074246264453, 5010.098182327216, 5017.071841709296, 4490.034642551802, 4527.0, 4531.161735079021, 4573.291139873325, 4579.152856721517, 6268.310485846553, 6286.622802631857, 6316.084167795736, 6341.0, 6347.55567110307, 6877.2091209510545, 6908.861822763517, 6921.653917304115, 6927.0, 6951.2353641099435, 6374.808755737336, 6386.457151142205, 6401.199850018747, 6404.103425520431, 6418.775342862978, 5477.3427188737605, 5569.443055694479, 5625.071863142617, 5649.378568357477, 5655.434456554394, 7023.824404389931, 7089.22018577956, 7112.25321341262, 7150.856982860314, 7220.097487814023, 6870.484713646397, 6881.208661003949, 6934.757283495255, 6949.200841903245, 6954.235034146139, 7110.7511237106455, 7127.10911136108, 7130.714771409085, 7221.711131554889, 7231.566106033444, 6722.529422940347, 6810.250872404131, 6831.146106736658, 6906.171259448898, 6915.757948471779, 6887.5178737487895, 6914.274001229798, 6963.199232088172, 6967.75612853568, 6968.233494315723, 6690.933090669226, 6714.597124172566, 6736.02327662496, 6759.898601521079, 6775.525713200029, 6543.280239173782, 6654.667266636746, 6655.168103987002, 6656.068150459037, 6659.733610655519, 6675.699593518206, 6677.732890684319, 6684.131062961784, 6698.765543205965, 6704.564203326768, 6720.495962802712, 6731.663416829041, 6754.324567543206, 6771.424428923597, 6839.452843772586, 6381.904271435839, 6504.707288171949, 6506.902396464152, 6516.967415162894, 6617.536772426077], "value": 6284.815642242174, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [930.3812964378689, 951.167728237792, 981.3964411886692, 987.9802403951921, 997.3666721631762, 42494.78752606236, 42704.14591708164, 43329.35005974909, 43395.0, 43466.78266608666, 39856.60143398565, 40250.59749402505, 40360.59639403605, 40389.39415908756, 40527.59472405275, 7156.463265255101, 7304.3426091651745, 7340.229275926031, 7341.119065712095, 7344.742933997311, 7191.964040179818, 7241.312075352833, 7246.492745507817, 7248.456365772562, 7279.162896266917, 7930.603469826486, 8120.918790812076, 8128.4310098293145, 8133.308668763133, 8139.38954578406, 7999.1600881907725, 8020.719274825382, 8038.155993620702, 8093.190680931919, 8096.473729207582, 7830.804229894241, 8013.2387423194705, 8071.112177660452, 8075.475094118863, 8085.716999904975, 39780.403293950614, 39785.0, 39974.80012599948, 40012.799936000134, 40035.59964400348, 3551.804650744208, 3605.152789094561, 3606.296772129436, 3606.765560238576, 3616.945745813815, 32608.184795380068, 32783.0, 32784.0, 32870.178245543815, 32922.6707732922, 5135.332406787131, 5174.09453345663, 5178.948210517884, 5187.325647665779, 5187.559057480119, 200904.0, 204680.0, 211642.94178529162, 212008.0, 212592.0, 54010.7299463508, 54607.453925460235, 54724.179137312974, 54726.72636636791, 54853.434526755635, 55701.44298557044, 55743.44256557464, 55783.442165578635, 55993.0, 55995.440045599025, 15992.680146397008, 16091.919540402223, 16115.516534503984, 16123.516294511184, 16128.75806862898, 13155.473781048842, 13218.339083045808, 13230.801537976939, 13261.535846245335, 13269.203847769164, 15791.0, 15828.44600438979, 15829.762553561706, 15874.285657145456, 15874.285657145456, 6061.454469097803, 6094.11635312886, 6134.447899689049, 6134.877302453926, 6211.9068213976825, 1107.0811226681835, 1109.5506319940437, 1120.4341807387218, 1127.0926903842485, 1129.8418221449003, 25052.498950020898, 25133.371665708502, 25340.11309604155, 25382.365440864127, 25468.74531254701, 1208.1965492947268, 1209.879012098783, 1217.8599461062092, 1229.7970834812156, 1237.2267332916927, 13381.531646392525, 13433.261170635513, 13435.798463023062, 13485.0, 13486.258255795829, 258324.0, 259277.0, 259464.0, 259665.0, 259686.0, 55465.168022479695, 55947.72026139925, 55988.16017759737, 56217.71891140518, 56270.43729562652, 1486.4932092930658, 1505.759078547449, 1512.1305249481372, 1514.5691165679148, 1565.7416526273037, 24748.38129046788, 24756.1335353269, 24960.251192464257, 30909.227269317547, 31033.534496982113, 54976.72511637576, 55611.44388556063, 55624.72187639036, 55692.44307556873, 55718.16422753581, 240196.0, 244099.0, 244496.0, 244647.0, 245069.0, 33187.0, 33636.32727345488, 36379.818100909324, 36669.0, 37542.43686344652, 48766.0, 49013.0, 49411.75294123506, 49848.0, 50223.24665129953, 36082.8195859019, 36261.0, 36300.0, 36467.45298820573, 36911.26177476489, 35352.11619709528, 35387.82306088453, 35551.11122221862, 35645.0, 35779.28441431105, 40962.385564217155, 41990.0, 42124.0, 42765.35851962285, 42800.78599606982, 34754.30491390209, 35308.47037294494, 35746.0, 35831.46252806262, 36186.0, 25966.870165649052, 26961.46077078408, 27000.0, 27196.864015679796, 27879.0], "value": 18611.708101080927, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.2485780913717, 609.2171559545984, 617.607819034913, 630.1492984470965, 647.0972992675216, 22344.66483002754, 22971.0, 23033.309000729983, 23058.538829223406, 23146.42133946652, 22688.8865555672, 23503.76496235037, 23579.29262122135, 23634.64548031781, 23733.5253294934, 6617.272100068976, 6804.08144900437, 6846.0, 6857.691403886837, 6861.108055952719, 6362.459190968773, 6505.739770409178, 6544.0, 6564.081028655992, 6623.701933413008, 6602.405783479486, 6605.306442823482, 6609.272979972199, 6630.138082049327, 6632.900506492407, 6604.834879128012, 6607.075009498674, 6615.5369124161325, 6620.271770105261, 6627.304133066055, 6101.145839582462, 6105.595712986038, 6153.138560601519, 6159.076138579204, 6182.412670796267, 27342.5898611519, 27734.5839812403, 27946.441071178666, 28348.858255708798, 28973.130806075853, 2305.6656784766283, 2348.5537747827857, 2354.2701762453694, 2355.2463211772238, 2356.8350215484925, 20133.39599812008, 20456.488587785276, 20487.180512779472, 20752.896235518878, 20771.376858694264, 2460.5447992121517, 2481.8759062047006, 2557.4117952870756, 2645.0477827981913, 2646.4707058588165, 134059.0, 139842.0, 150260.2486987569, 152094.0, 159532.0, 15308.234588270541, 16124.919375403048, 16431.671366572602, 16473.83526164747, 16705.415310464075, 25682.614760778604, 25709.614355784677, 25727.356816079748, 25784.226473205457, 25806.09678661238, 10475.580976760997, 10511.159107271553, 10680.51937662807, 10693.82367939526, 10726.302790318605, 9503.857442138235, 9528.285378596494, 9613.134817866287, 9701.0, 9716.368436051634, 9497.90502094984, 9531.65239264532, 9733.805323893483, 9822.852657210147, 9959.80080398388, 2983.895563655262, 2988.4770165221435, 2991.551267309921, 3009.849507524615, 3058.663547009827, 1044.7492601775518, 1050.0, 1068.108129711684, 1080.6920027792041, 1082.8429877667852, 24534.30122686503, 25858.0, 26187.345316367242, 26568.0, 26657.866710666323, 1077.2136340471343, 1095.8520599718936, 1111.9110471162448, 1116.447358557503, 1131.0838221040983, 11702.648920532398, 11839.585614503623, 11856.644300670994, 11917.821232681517, 11980.880191197977, 612955.0, 627457.0, 629445.0, 634347.0, 655335.0, 152863.0, 153784.0, 155377.0, 155732.0, 158446.0, 1249.5314257153568, 1290.8773666501668, 1303.9282839443922, 1318.6044186743939, 1340.7921772125337, 21816.45458863541, 22542.887285563465, 22917.31248062561, 23192.0, 23358.299251022498, 67390.0, 71903.28096719175, 72609.0, 73894.0, 74121.88817167639, 82947.5852620733, 83303.5834820822, 83828.0, 84720.57639711762, 85357.57321213355, 21362.786372136703, 21919.0, 21984.45038874041, 22107.66838497456, 22207.1117155312, 24372.51254974855, 24442.87778561096, 24445.26664200077, 24591.262262132164, 24607.38481537976, 19419.8058019418, 19652.803471965097, 19717.507062323555, 19783.0, 19797.0, 16095.275712593195, 16374.918125409296, 16646.334146634, 18170.27318907229, 18207.362742303954, 20881.373558792653, 21221.575568488854, 21357.466063347918, 21491.570168596227, 21719.674204887255, 21484.462888427915, 21804.221881201178, 21952.121915123214, 22042.0, 22183.667244991662, 19991.600587958703, 20055.0975206119, 20157.294494692615, 20222.292219772236, 20443.080061396977], "value": 13278.940343518536, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.9529266475672, 1417.3834382043813, 1418.5673369622261, 1428.0788891165198, 1448.3844366144385, 16608.25262863171, 17232.569185770346, 17240.999999999985, 17271.913640431812, 17273.13634318282, 16761.7485737714, 16811.74782378262, 16872.746908796347, 16910.23903924323, 16922.323107075732, 2830.1368082734707, 2846.5872448494956, 2869.4691482075777, 2875.7986940914143, 2877.9856100719467, 2857.4999375109273, 2886.350571121491, 2901.956470652942, 2903.071017274473, 2906.2879594499377, 2839.957400638992, 2858.699836517167, 2949.7492713119304, 2950.2034450698297, 2952.763778897683, 2889.0466146171816, 2895.580140879571, 2919.4453053919688, 2920.7371336579704, 2922.4009078139143, 2759.1722483255144, 2761.737634924679, 2775.9167224983285, 2780.7497325240824, 2783.262435454586, 16610.086445245506, 16911.06989115598, 17010.659786804194, 17019.063951482665, 17029.06340151291, 8340.582970851434, 8571.571421428904, 8613.61238744258, 8617.65529378824, 8658.090900455425, 5200.29795977542, 5414.40441551429, 5424.349078110652, 5512.476314750133, 5534.252875861787, 2026.128764631208, 2084.833213342929, 2088.8642238254615, 2146.399008277685, 2165.382865883216, 29945.0, 29966.101016969526, 30066.0, 30096.54855177196, 30134.0, 7051.717931282691, 7098.148222213367, 7120.145582530129, 7206.675699593531, 7431.702731890772, 18937.0, 19223.423297301102, 19305.903470482557, 19477.707834382494, 19587.0, 8307.543085130255, 8529.488230706178, 8561.400701950804, 8571.057183709787, 8573.485590864444, 7593.582352970529, 7753.302202801662, 7782.338501227459, 7799.922000780033, 7884.408669349822, 5725.427457254241, 5916.171735956969, 5941.524678025748, 5941.821745347646, 6123.510119190454, 1663.7005339038847, 1685.073209734641, 1701.5661006443277, 1703.4634090261527, 1711.9572010699583, 938.7653086728318, 979.0992287095889, 980.4313498171186, 991.4101109839634, 1022.7187523430968, 9923.0, 9951.25365597583, 10098.444585547866, 10251.179905607534, 10257.0, 1047.7171163785888, 1072.1476426241156, 1082.3397727386289, 1091.7379828841022, 1099.1371773158032, 5890.67601281925, 5989.161517387569, 6053.697315134314, 6096.329403765582, 6416.05610059003, 24240.0, 25254.494910101694, 25439.36401589975, 25630.359241018752, 25635.487290254092, 24348.634770478457, 25040.499190016097, 25049.62425563618, 25077.87461062683, 25455.0, 11775.705607359712, 11906.0, 11992.220505667105, 11999.100067495147, 12026.879731202576, 9592.280578956466, 10025.849612255968, 10032.498375081363, 10054.19566434698, 10563.419011954416, 21385.572288554453, 21743.34769956904, 21818.127274908828, 22196.223132190295, 22260.554788903806, 21974.121035158416, 22023.22918697838, 22069.0, 22146.44633884166, 22517.211897584162, 12859.678508037, 13055.151415157985, 13134.802977955147, 13141.737165256833, 13147.276899770604, 15170.317335719697, 15206.771898421312, 15326.693466130839, 15336.76994845056, 15354.539363819104, 12511.812322814983, 12558.560450384559, 12563.623091307276, 12658.620241392773, 12826.422810973716, 12300.323482208565, 12311.44598493052, 12417.875821241672, 12491.625251242478, 12502.937250333824, 10854.945725271324, 10905.0, 11108.111351091731, 11115.277506962022, 11124.666260012213, 8170.877436838571, 8208.179182081743, 8286.337093032664, 8346.540940248344, 8443.662253510036, 8174.223448772357, 8176.345892328718, 8278.213569710868, 8323.625436855476, 8461.06928237881], "value": 7408.582634299609, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.9641223320484, 570.6519023395729, 580.8489792653909, 593.2850914647847, 593.7031484257873, 6386.584871983329, 6411.903821442682, 6433.485321174307, 6445.3554644535525, 6455.515836312277, 6140.232470941132, 6212.130301757747, 6272.623642581449, 6315.684215789215, 6336.366363363662, 5132.923006154911, 5146.305248791412, 5149.8155424252445, 5171.87070323243, 5177.792888284464, 4892.0, 5095.6178286628465, 5122.769475373599, 5150.793968241266, 5194.428612852583, 4898.338724272231, 4913.641452102993, 4930.630202734791, 4950.702957822524, 4964.255361695757, 4890.168671325883, 4918.631102667278, 4930.950690493085, 4965.677230979974, 4988.201887697951, 4396.054848207651, 4468.240399132155, 4498.325251212312, 4499.460064792246, 4526.0, 6054.939450605481, 6118.908216376758, 6141.723622437001, 6163.691815409212, 6169.475594574466, 5799.681017544033, 5815.941840581625, 5836.824895253149, 5855.531557475391, 5863.413658634145, 6612.801615951529, 6614.570052946543, 6655.401013908771, 6686.489592963684, 6727.192736871558, 4983.72589507577, 5048.1418158913075, 5050.621203409759, 5065.721385323805, 5077.543021128116, 6681.8663626727675, 6771.9661401693165, 6845.383915447584, 6866.455047614297, 6989.0, 5154.381474223117, 5178.741062946838, 5204.8959020819375, 5204.947950520522, 5216.0, 5247.2653828464045, 5279.785649300682, 5286.312779338661, 5296.126139186991, 5296.947030529645, 5862.296524417012, 5874.1776151338845, 5883.61756485827, 5905.970470147622, 5912.615679980788, 6738.494612904052, 6745.3591908768585, 6762.594244345355, 6791.762288319885, 6798.354156355138, 1837.5130590393555, 1845.0221382667207, 1846.8245516675895, 1852.8610354223488, 1852.8702990790784, 1222.9449674764658, 1280.4878048780377, 1284.9678758031123, 1290.1807352331336, 1293.3080801771196, 6110.0, 6135.662538560333, 6142.293636231801, 6176.413240742122, 6189.381061893775, 6539.280679125292, 6562.2453417856605, 6651.733930642817, 6753.940048285222, 6789.830254243585, 5956.315023772235, 6033.849153771103, 6075.787347442907, 6098.969505152446, 6102.511799056065, 3496.230829217568, 3509.701675357572, 3537.0, 3599.38810402227, 3613.837377318027, 6970.930290697028, 7002.754903578452, 7038.824029399306, 7099.3965512930945, 7151.856962860714, 6856.657167141623, 6857.73131970587, 6914.757983470655, 6983.441324694012, 7069.434445244368, 6956.582605043714, 7019.718591457797, 7094.219635840053, 7115.893261600977, 7122.430205583541, 6614.933850661624, 6621.205455345389, 6690.531662783558, 6802.82992925161, 6861.313868613099, 6680.6993685283305, 6742.213313472032, 6773.89839152423, 6784.525083244125, 6840.0, 6983.720651173896, 7040.401565866754, 7059.399951004222, 7135.393491553276, 7141.428685705235, 5119.28330033803, 5128.102582048201, 5165.199394093921, 5169.0, 5181.611379146504, 6572.605643661204, 6718.8320291993095, 6721.193456785021, 6761.106890070788, 6783.898241526283, 6455.580387274812, 6559.639219842764, 6585.901211481927, 6596.901046484402, 6630.905900526359, 6557.2131344239, 6561.671916404065, 6578.7368505261165, 6580.703868325841, 6618.8345291368105, 6550.593691280603, 6576.342365763386, 6594.604323740591, 6600.438962688226, 6608.636524991171, 6635.933640663725, 6645.667716614246, 6668.433183179484, 6678.632675202716, 6687.130673012672, 6278.717457714505, 6302.716377763103, 6328.557001009885, 6453.741850326122, 6453.774117905851], "value": 5194.186588244944, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [962.74006018375, 988.7083310423425, 1002.6190047781841, 1007.4005966449964, 1018.6129270877068, 17319.0, 17686.46940591781, 17694.115294235286, 17717.911410442928, 17739.467815965527, 17747.46757597274, 17932.820671793277, 18081.367152149644, 18304.450866473995, 18307.816921830778, 1063.1707268330715, 1093.8468614393992, 1094.4801219420751, 1095.4303762043733, 1096.6326280695937, 1019.6737044145876, 1060.809054370213, 1063.261033581661, 1079.5196137718717, 1081.7728277061826, 1121.2991880074953, 1125.5947858770835, 1129.836173754805, 1132.1339175530725, 1133.4389477208792, 1128.6388355726172, 1128.6952522818833, 1129.0854407929562, 1129.9378534180616, 1132.7507948251414, 824.4435006370676, 828.2173346187855, 831.9126491718343, 832.8917240758663, 833.1002517281382, 16623.91688041564, 18477.0, 18680.0, 18694.81305186944, 18704.158312875952, 20816.08397545051, 21303.573928521342, 21825.67261491079, 21830.126794928343, 21852.0, 16996.49010529686, 17000.3199872005, 17013.91493042539, 17119.48641540756, 17163.656726865393, 3965.643092121723, 3994.460747799039, 3995.840166393341, 3998.4402183694306, 3999.2201520703475, 153092.0, 154536.0, 155607.0, 158251.0, 158634.20682896627, 14525.983181177427, 14621.122732636077, 14820.777688334683, 14857.034292771152, 14918.552443426715, 27696.723032769816, 27725.0, 27730.72269277322, 27741.167764966678, 27747.445051098864, 8725.345599079967, 9087.36388452802, 9093.4998575079, 9093.636254549876, 9096.317776166814, 4253.659707223476, 4320.265554855682, 4334.0, 4344.717593356421, 4361.193179261847, 5169.405518365361, 5171.741412929338, 5188.610854185876, 5210.557102646242, 5261.76322065508, 1170.7014711248578, 1190.36315571169, 1208.2448469706433, 1209.4557449147742, 1211.3156066822216, 742.483973638327, 746.5856449670486, 754.0762565856782, 770.7995921060452, 777.5917643237345, 12073.094517911191, 12349.32078735678, 12753.487164638118, 12855.421506032251, 13641.522546710818, 723.9384652304614, 727.8507905879283, 730.3791776989623, 735.8197241675716, 737.0860133434503, 5829.967589129893, 5855.531557475477, 5909.852253693692, 6049.122877182784, 6117.541184411187, 153578.0, 155249.0, 155336.0, 156034.21982890012, 158081.2095939536, 132330.33834830957, 133753.0, 134698.0, 135859.0, 141464.0, 112105.0, 113242.0, 113622.0, 115780.42109789397, 116217.8378216224, 11107.38909359993, 11161.162912781412, 11408.771824563297, 11438.25651332694, 11534.365609891536, 54328.456715433924, 54358.0, 54507.72746136244, 56114.719426402604, 56938.715306423204, 94091.52954235185, 96885.0, 98089.0, 98480.0, 101577.43189496653, 17488.300467981648, 17806.287748490435, 17923.551911202325, 17967.910160449115, 18122.72815907736, 20844.374668759963, 20960.580788384454, 21095.0, 21377.46556336042, 21755.34733957984, 18882.52793680169, 19041.143148558625, 19585.51036224049, 19970.30103946413, 20230.0, 16171.514854554382, 17803.910980445533, 19042.7143592849, 19448.90275548613, 19681.803181967996, 18477.9076104624, 18514.259429622663, 18836.24655013784, 19901.60196796085, 20150.496237594176, 14376.35306411193, 14555.854441455873, 14556.63608409756, 14601.780973285622, 14647.853521464649, 14935.402583896523, 15116.244187790786, 15136.470223542123, 15307.0, 15396.769048464057], "value": 9849.960671977247, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1380.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 104.0, 104.0, 105.0, 105.0, 107.0, 107.0, 105.0, 105.0, 106.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 118.0, 112.0, 111.0, 112.0, 111.0, 149.0, 116.0, 115.0, 113.0], "value": 117.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 162.0, 161.0, 162.0, 161.0, 163.0, 163.0, 166.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 73.0, 73.0, 74.0, 75.0, 75.0, 74.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 81.0, 78.0, 78.0, 77.0, 78.0, 77.0, 79.0, 78.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 113.0, 115.0, 113.0, 114.0, 112.0, 114.0, 113.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 151.0, 149.0, 150.0, 150.0, 148.0, 149.0, 150.0, 149.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 93.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 47.0, 47.0, 44.0, 43.0, 44.0, 43.0, 42.0, 47.0, 43.0], "value": 44.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 39.0, 39.0, 39.0, 38.0, 38.0, 39.0, 39.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 74.0, 72.0, 71.0, 72.0, 73.0, 72.0, 73.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 115.0, 128.0, 126.0, 111.0, 115.0, 111.0, 116.0], "value": 116.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 154.0, 154.0, 150.0, 154.0, 151.0, 159.0, 153.0, 160.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 58.0, 58.0, 59.0, 59.0, 59.0, 68.0, 61.0, 61.0, 56.0], "value": 60.3, "unit": "score"}]}]} 11:49:11 INFO - Return code: 0 11:49:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:49:11 INFO - # TBPL SUCCESS # 11:49:11 INFO - Running post-action listener: _package_coverage_data 11:49:11 INFO - Running post-action listener: _resource_record_post_action 11:49:11 INFO - [mozharness: 2017-09-07 18:49:11.853825Z] Finished run-tests step (success) 11:49:11 INFO - Running post-run listener: _resource_record_post_run 11:49:11 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 16384; Write bytes: 396955648; Read time: 368; Write time: 334044 11:49:11 INFO - TinderboxPrint: CPU usage
13.1% 11:49:11 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 368 11:49:11 INFO - TinderboxPrint: I/O write bytes / time
396,955,648 / 334,044 11:49:11 INFO - TinderboxPrint: CPU idle
8,217.6 (86.9%) 11:49:11 INFO - TinderboxPrint: CPU user
1,187.4 (12.6%) 11:49:11 INFO - TinderboxPrint: Swap in / out
0 / 0 11:49:11 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 118751232; Read time: 184; Write time: 113936 11:49:11 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:49:11 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 12288; Write bytes: 278204416; Read time: 184; Write time: 220108 11:49:12 INFO - Running post-run listener: _upload_blobber_files 11:49:12 INFO - Blob upload gear active. 11:49:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:49:12 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:49:12 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:49:12 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:49:12 INFO - (blobuploader) - INFO - Open directory for files ... 11:49:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:49:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:49:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:14 INFO - (blobuploader) - INFO - Done attempting. 11:49:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:49:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:49:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:17 INFO - (blobuploader) - INFO - Done attempting. 11:49:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:49:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:49:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:18 INFO - (blobuploader) - INFO - Done attempting. 11:49:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:49:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:49:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:20 INFO - (blobuploader) - INFO - Done attempting. 11:49:20 INFO - (blobuploader) - INFO - Iteration through files over. 11:49:20 INFO - Return code: 0 11:49:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:49:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:49:20 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9ce45ead944573f772aa646795eb75db9f45feebaf9d1c3134f05d9420f0ad8e998f1f4ef25a091a0a0d091c0f5b9de1298a8a069f3001857016952cc83ace3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65289bf7ce30e2a61def621a2e8528455f766cd2824363514f96aa7171fe88d7b9ed3c0e8e4f7d26289c71d8e099a418d648419299cbc9a2fd3d522fcd5adf0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65277ca6e1e9941934f3a09583c31cd4d149b993aff735ec6b5846fdc47bb477c39e789ac2edc290232b4bf2a421ae3b11a67c48463db22178fd2259a1a14afb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5370e76da72bcd0f8dd9a0fa0327916f3a1d134d5e7da49fa6a58a72f89ceadac27087eb3d39bb31c6c7aa34cac42cfe87802f4fbf068c0810bbe08f88513fbd"} 11:49:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:49:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:49:20 INFO - Contents: 11:49:20 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9ce45ead944573f772aa646795eb75db9f45feebaf9d1c3134f05d9420f0ad8e998f1f4ef25a091a0a0d091c0f5b9de1298a8a069f3001857016952cc83ace3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65289bf7ce30e2a61def621a2e8528455f766cd2824363514f96aa7171fe88d7b9ed3c0e8e4f7d26289c71d8e099a418d648419299cbc9a2fd3d522fcd5adf0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65277ca6e1e9941934f3a09583c31cd4d149b993aff735ec6b5846fdc47bb477c39e789ac2edc290232b4bf2a421ae3b11a67c48463db22178fd2259a1a14afb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5370e76da72bcd0f8dd9a0fa0327916f3a1d134d5e7da49fa6a58a72f89ceadac27087eb3d39bb31c6c7aa34cac42cfe87802f4fbf068c0810bbe08f88513fbd"} 11:49:20 INFO - Running post-run listener: copy_logs_to_upload_dir 11:49:20 INFO - Copying logs to upload dir... 11:49:20 INFO - mkdir: /builds/slave/test/build/upload/logs 11:49:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.441113 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-07 11:49:20.224371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 11:49:20.231959) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9ce45ead944573f772aa646795eb75db9f45feebaf9d1c3134f05d9420f0ad8e998f1f4ef25a091a0a0d091c0f5b9de1298a8a069f3001857016952cc83ace3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65289bf7ce30e2a61def621a2e8528455f766cd2824363514f96aa7171fe88d7b9ed3c0e8e4f7d26289c71d8e099a418d648419299cbc9a2fd3d522fcd5adf0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65277ca6e1e9941934f3a09583c31cd4d149b993aff735ec6b5846fdc47bb477c39e789ac2edc290232b4bf2a421ae3b11a67c48463db22178fd2259a1a14afb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5370e76da72bcd0f8dd9a0fa0327916f3a1d134d5e7da49fa6a58a72f89ceadac27087eb3d39bb31c6c7aa34cac42cfe87802f4fbf068c0810bbe08f88513fbd"} build_url:https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011436 build_url: 'https://queue.taskcluster.net/v1/task/IGNMaOSuRLejLW-8IRhPzg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9ce45ead944573f772aa646795eb75db9f45feebaf9d1c3134f05d9420f0ad8e998f1f4ef25a091a0a0d091c0f5b9de1298a8a069f3001857016952cc83ace3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65289bf7ce30e2a61def621a2e8528455f766cd2824363514f96aa7171fe88d7b9ed3c0e8e4f7d26289c71d8e099a418d648419299cbc9a2fd3d522fcd5adf0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65277ca6e1e9941934f3a09583c31cd4d149b993aff735ec6b5846fdc47bb477c39e789ac2edc290232b4bf2a421ae3b11a67c48463db22178fd2259a1a14afb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5370e76da72bcd0f8dd9a0fa0327916f3a1d134d5e7da49fa6a58a72f89ceadac27087eb3d39bb31c6c7aa34cac42cfe87802f4fbf068c0810bbe08f88513fbd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 11:49:20.266283) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 11:49:20.266665) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504808909.489335-229664977 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 11:49:20.350028) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 11:49:20.350384) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 11:49:20.350716) ========= ========= Total master_lag: 0.29 =========