builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-061 starttime: 1503624289.83 results: success (0) revision: 3199bacd6b3827d711155a9e81c081434cf7cd8f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:49.826613) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:49.827027) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:49.827323) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:49.843150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:49.843459) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-24 18:24:50-- 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% 46.8M=0s 2017-08-24 18:24:50 (46.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126941 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:49.986103) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:49.986443) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015454 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:50.035844) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-24 18:24:50.036153) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3199bacd6b3827d711155a9e81c081434cf7cd8f --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 3199bacd6b3827d711155a9e81c081434cf7cd8f --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=516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-24 18:24:50,265 truncating revision to first 12 chars 2017-08-24 18:24:50,265 Setting DEBUG logging. 2017-08-24 18:24:50,265 attempt 1/10 2017-08-24 18:24:50,265 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3199bacd6b38?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-24 18:24:51,755 unpacking tar archive at: mozilla-central-3199bacd6b38/testing/mozharness/ program finished with exit code 0 elapsedTime=1.832905 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-24 18:24:51.886788) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:51.887117) ========= script_repo_revision: 3199bacd6b3827d711155a9e81c081434cf7cd8f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:51.887503) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:51.887771) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:51.899212) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-08-24 18:24:51.899482) ========= /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=516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:24:52 INFO - MultiFileLogger online at 20170824 18:24:52 in /builds/slave/test 18:24:52 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 18:24:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:24:52 INFO - {'append_to_log': False, 18:24:52 INFO - 'base_work_dir': '/builds/slave/test', 18:24:52 INFO - 'blob_upload_branch': 'Firefox', 18:24:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:24:52 INFO - 'branch': 'Firefox', 18:24:52 INFO - 'buildbot_json_path': 'buildprops.json', 18:24:52 INFO - 'code_coverage': False, 18:24:52 INFO - 'config_files': ('talos/linux_config.py',), 18:24:52 INFO - 'default_actions': ('clobber', 18:24:52 INFO - 'read-buildbot-config', 18:24:52 INFO - 'download-and-extract', 18:24:52 INFO - 'populate-webroot', 18:24:52 INFO - 'create-virtualenv', 18:24:52 INFO - 'install', 18:24:52 INFO - 'setup-mitmproxy', 18:24:52 INFO - 'run-tests'), 18:24:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:24:52 INFO - 'disable_ccov_upload': False, 18:24:52 INFO - 'download_minidump_stackwalk': True, 18:24:52 INFO - 'download_symbols': 'ondemand', 18:24:52 INFO - 'enable_stylo': False, 18:24:52 INFO - 'enable_webrender': False, 18:24:52 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:24:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:24:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:24:52 INFO - 'gecko_profile': False, 18:24:52 INFO - 'gecko_profile_interval': 0, 18:24:52 INFO - 'installer_path': 'installer.exe', 18:24:52 INFO - 'log_level': 'info', 18:24:52 INFO - 'log_name': 'talos', 18:24:52 INFO - 'log_to_console': True, 18:24:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:24:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:24:52 INFO - 'opt_config_files': (), 18:24:52 INFO - 'pip_index': False, 18:24:52 INFO - 'suite': 'dromaeojs-e10s', 18:24:52 INFO - 'system_bits': '32', 18:24:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:24:52 INFO - 'title': 'talos-linux64-ix-061', 18:24:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:24:52 INFO - 'use_talos_json': True, 18:24:52 INFO - 'verify': 'False', 18:24:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:24:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:24:52 INFO - 'work_dir': 'build'} 18:24:52 INFO - [mozharness: 2017-08-25 01:24:52.273498Z] Running clobber step. 18:24:52 INFO - Running pre-action listener: _resource_record_pre_action 18:24:52 INFO - Running main action method: clobber 18:24:52 INFO - rmtree: /builds/slave/test/build 18:24:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:24:53 INFO - Running post-action listener: _resource_record_post_action 18:24:53 INFO - [mozharness: 2017-08-25 01:24:53.290961Z] Finished clobber step (success) 18:24:53 INFO - [mozharness: 2017-08-25 01:24:53.291059Z] Running read-buildbot-config step. 18:24:53 INFO - Running pre-action listener: _resource_record_pre_action 18:24:53 INFO - Running main action method: read_buildbot_config 18:24:53 INFO - Using buildbot properties: 18:24:53 INFO - { 18:24:53 INFO - "project": "", 18:24:53 INFO - "product": "firefox", 18:24:53 INFO - "who": "ryanvm@gmail.com", 18:24:53 INFO - "installer_path": "public/build/target.tar.bz2", 18:24:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:24:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:24:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:24:53 INFO - "stage_platform": "linux64-pgo", 18:24:53 INFO - "basedir": "/builds/slave/test", 18:24:53 INFO - "buildnumber": 140, 18:24:53 INFO - "platform": "ubuntu64_hw", 18:24:53 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:24:53 INFO - "slavebuilddir": "test", 18:24:53 INFO - "taskId": "TDDilOpSTty-7VGD-b4uzw", 18:24:53 INFO - "branch": "mozilla-central", 18:24:53 INFO - "script_repo_revision": "production", 18:24:53 INFO - "revision": "3199bacd6b3827d711155a9e81c081434cf7cd8f", 18:24:53 INFO - "slavename": "talos-linux64-ix-061", 18:24:53 INFO - "repo_path": "mozilla-central" 18:24:53 INFO - } 18:24:53 INFO - Finding installer, test and symbols from parent task. 18:24:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TDDilOpSTty-7VGD-b4uzw'}, attempt #1 18:24:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TDDilOpSTty-7VGD-b4uzw'}, attempt #1 18:24:54 INFO - Task dependencies: TjzgtrtbQIeWPDtRo_N7qA 18:24:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA'}, attempt #1 18:24:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.tar.bz2 18:24:54 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.test_packages.json 18:24:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.crashreporter-symbols.zip 18:24:54 INFO - Running post-action listener: _resource_record_post_action 18:24:54 INFO - [mozharness: 2017-08-25 01:24:54.662023Z] Finished read-buildbot-config step (success) 18:24:54 INFO - [mozharness: 2017-08-25 01:24:54.662239Z] Running download-and-extract step. 18:24:54 INFO - Running pre-action listener: _resource_record_pre_action 18:24:54 INFO - Running main action method: download_and_extract 18:24:54 INFO - mkdir: /builds/slave/test/build/tests 18:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:54 INFO - trying https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.test_packages.json 18:24:54 INFO - Downloading https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:24:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:24:55 INFO - Downloaded 989 bytes. 18:24:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:24:55 INFO - Using the following test package requirements: 18:24:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:24:55 INFO - u'common': [u'target.common.tests.zip'], 18:24:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:24:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:24:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:24:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:24:55 INFO - u'mozbase': [u'target.common.tests.zip'], 18:24:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:24:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:24:55 INFO - u'web-platform': [u'target.common.tests.zip', 18:24:55 INFO - u'target.web-platform.tests.tar.gz'], 18:24:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:24:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:24:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.common.tests.zip 18:24:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:24:55 INFO - Fetch https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.common.tests.zip into memory 18:24:56 INFO - Content-Length response header: 55730797 18:24:56 INFO - Bytes received: 55730797 18:25:07 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:25:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.common.tests.zip 18:25:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:25:07 INFO - Fetch https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.common.tests.zip into memory 18:25:08 INFO - Content-Length response header: 55730797 18:25:08 INFO - Bytes received: 55730797 18:25:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.talos.tests.zip 18:25:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:25:20 INFO - Fetch https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.talos.tests.zip into memory 18:25:20 INFO - Content-Length response header: 14496555 18:25:20 INFO - Bytes received: 14496555 18:25:21 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')]} 18:25:21 INFO - trying https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.tar.bz2 18:25:21 INFO - Downloading https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:25:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:25:22 INFO - Downloaded 62321753 bytes. 18:25:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.tar.bz2 18:25:22 INFO - mkdir: /builds/slave/test/properties 18:25:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:25:22 INFO - Writing to file /builds/slave/test/properties/build_url 18:25:22 INFO - Contents: 18:25:22 INFO - build_url:https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.tar.bz2 18:25:22 INFO - Running post-action listener: _resource_record_post_action 18:25:22 INFO - Running post-action listener: find_tests_for_verification 18:25:22 INFO - Running post-action listener: set_extra_try_arguments 18:25:22 INFO - [mozharness: 2017-08-25 01:25:22.375388Z] Finished download-and-extract step (success) 18:25:22 INFO - [mozharness: 2017-08-25 01:25:22.375485Z] Running populate-webroot step. 18:25:22 INFO - Running pre-action listener: _resource_record_pre_action 18:25:22 INFO - Running main action method: populate_webroot 18:25:22 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:25:22 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:25:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:25:22 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:25:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:22 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:25:22 INFO - 'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:25:22 INFO - 'tests': ['damp', 'tps']}, 18:25:22 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:25:22 INFO - 'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['damp', 'tps']}, 18:25:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:25:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:25:22 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:25:22 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:25:22 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:25:22 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:25:22 INFO - 'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:25:22 INFO - 'other-e10s': {'tests': ['a11yr', 18:25:22 INFO - 'ts_paint', 18:25:22 INFO - 'tpaint', 18:25:22 INFO - 'sessionrestore', 18:25:22 INFO - 'sessionrestore_many_windows', 18:25:22 INFO - 'sessionrestore_no_auto_restore', 18:25:22 INFO - 'tabpaint']}, 18:25:22 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['a11yr', 18:25:22 INFO - 'ts_paint', 18:25:22 INFO - 'tpaint', 18:25:22 INFO - 'sessionrestore', 18:25:22 INFO - 'sessionrestore_many_windows', 18:25:22 INFO - 'sessionrestore_no_auto_restore', 18:25:22 INFO - 'tabpaint']}, 18:25:22 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:25:22 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:25:22 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['bloom_basic_singleton']}, 18:25:22 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['bloom_basic']}, 18:25:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:25:22 INFO - 'tsvgr_opacity', 18:25:22 INFO - 'tart', 18:25:22 INFO - 'tscrollx', 18:25:22 INFO - 'tsvg_static']}, 18:25:22 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['tsvgx', 18:25:22 INFO - 'tsvgr_opacity', 18:25:22 INFO - 'tart', 18:25:22 INFO - 'tscrollx', 18:25:22 INFO - 'tsvg_static']}, 18:25:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:25:22 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:25:22 INFO - 'talos_options': ['--stylo'], 18:25:22 INFO - 'tests': ['tp5o']}, 18:25:22 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:25:22 INFO - 'talos_options': ['--mitmproxy', 18:25:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:25:22 INFO - '--firstNonBlankPaint'], 18:25:22 INFO - 'tests': ['tp6_google', 18:25:22 INFO - 'tp6_youtube', 18:25:22 INFO - 'tp6_amazon', 18:25:22 INFO - 'tp6_facebook']}, 18:25:22 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:25:22 INFO - 'talos_options': ['--stylo', 18:25:22 INFO - '--mitmproxy', 18:25:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:25:22 INFO - '--firstNonBlankPaint'], 18:25:22 INFO - 'tests': ['tp6_google', 18:25:22 INFO - 'tp6_youtube', 18:25:22 INFO - 'tp6_amazon', 18:25:22 INFO - 'tp6_facebook']}, 18:25:22 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:25:22 INFO - 'talos_options': ['--stylo', 18:25:22 INFO - '--stylo-threads=1', 18:25:22 INFO - '--mitmproxy', 18:25:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:25:22 INFO - '--firstNonBlankPaint'], 18:25:22 INFO - 'tests': ['tp6_google', 18:25:22 INFO - 'tp6_youtube', 18:25:22 INFO - 'tp6_amazon', 18:25:22 INFO - 'tp6_facebook']}, 18:25:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:25:22 INFO - 'talos_options': ['--xperf_path', 18:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:22 INFO - 'tests': ['tp5n']}, 18:25:22 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:25:22 INFO - 'talos_options': ['--stylo', 18:25:22 INFO - '--xperf_path', 18:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:22 INFO - 'tests': ['tp5n']}}} 18:25:22 INFO - Running post-action listener: _resource_record_post_action 18:25:22 INFO - [mozharness: 2017-08-25 01:25:22.389536Z] Finished populate-webroot step (success) 18:25:22 INFO - [mozharness: 2017-08-25 01:25:22.389624Z] Running create-virtualenv step. 18:25:22 INFO - Running pre-action listener: _resource_record_pre_action 18:25:22 INFO - Running main action method: create_virtualenv 18:25:22 INFO - Creating virtualenv /builds/slave/test/build/venv 18:25:22 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:25:22 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:25:22 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:25:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:25:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:25:23 INFO - Installing distribute.............................................................................................................................................................................................done. 18:25:23 INFO - Installing pip...............done. 18:25:23 INFO - Return code: 0 18:25:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:25:23 INFO - Reading from file tmpfile_stdout 18:25:23 INFO - Output received: 18:25:23 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:25:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:25:23 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')]} 18:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:23 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')]} 18:25:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:23 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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25:23 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 18:25:23 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 18:25:23 INFO - Using env: {'DISPLAY': ':0', 18:25:23 INFO - 'HOME': '/home/cltbld', 18:25:23 INFO - 'LANG': 'en_US.UTF-8', 18:25:23 INFO - 'LANGUAGE': 'en_US:en', 18:25:23 INFO - 'LOGNAME': 'cltbld', 18:25:23 INFO - 'MAIL': '/var/mail/cltbld', 18:25:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:23 INFO - 'MOZ_NO_REMOTE': '1', 18:25:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:23 INFO - 'NO_EM_RESTART': '1', 18:25:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:23 INFO - 'PWD': '/builds/slave/test', 18:25:23 INFO - 'SHELL': '/bin/bash', 18:25:23 INFO - 'SHLVL': '1', 18:25:23 INFO - 'TERM': 'linux', 18:25:23 INFO - 'TMOUT': '86400', 18:25:23 INFO - 'USER': 'cltbld', 18:25:23 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:23 INFO - '_': '/tools/buildbot/bin/python'} 18:25:24 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:25:24 INFO - Downloading/unpacking pip>=1.5 18:25:24 INFO - Running setup.py egg_info for package pip 18:25:24 INFO - warning: no files found matching 'pip/cacert.pem' 18:25:24 INFO - warning: no files found matching '*.html' under directory 'docs' 18:25:24 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:25:24 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:25:24 INFO - Installing collected packages: pip 18:25:24 INFO - Found existing installation: pip 1.1 18:25:24 INFO - Uninstalling pip: 18:25:24 INFO - Successfully uninstalled pip 18:25:24 INFO - Running setup.py install for pip 18:25:24 INFO - warning: no files found matching 'pip/cacert.pem' 18:25:24 INFO - warning: no files found matching '*.html' under directory 'docs' 18:25:24 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:25:24 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:25:24 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Successfully installed pip 18:25:24 INFO - Cleaning up... 18:25:24 INFO - Return code: 0 18:25:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25: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')]} 18:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25: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', '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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:25: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 psutil>=3.1.1 18:25:24 INFO - Using env: {'DISPLAY': ':0', 18:25:24 INFO - 'HOME': '/home/cltbld', 18:25:24 INFO - 'LANG': 'en_US.UTF-8', 18:25:24 INFO - 'LANGUAGE': 'en_US:en', 18:25:24 INFO - 'LOGNAME': 'cltbld', 18:25:24 INFO - 'MAIL': '/var/mail/cltbld', 18:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:24 INFO - 'MOZ_NO_REMOTE': '1', 18:25:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:24 INFO - 'NO_EM_RESTART': '1', 18:25:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:24 INFO - 'PWD': '/builds/slave/test', 18:25:24 INFO - 'SHELL': '/bin/bash', 18:25:24 INFO - 'SHLVL': '1', 18:25:24 INFO - 'TERM': 'linux', 18:25:24 INFO - 'TMOUT': '86400', 18:25:24 INFO - 'USER': 'cltbld', 18:25:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:24 INFO - '_': '/tools/buildbot/bin/python'} 18:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:24 INFO - Downloading/unpacking psutil>=3.1.1 18:25:24 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 18:25:24 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 18:25:24 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 18:25:24 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 18:25:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:25:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:26 INFO - Installing collected packages: psutil 18:25:26 INFO - Running setup.py install for psutil 18:25:26 INFO - building 'psutil._psutil_linux' extension 18:25:26 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 18:25:26 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 18:25:26 INFO - building 'psutil._psutil_posix' extension 18:25:26 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 18:25:26 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 18:25:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:26 INFO - Successfully installed psutil 18:25:26 INFO - Cleaning up... 18:25:26 INFO - Return code: 0 18:25:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:25:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:26 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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25:26 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 18:25:26 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 18:25:26 INFO - Using env: {'DISPLAY': ':0', 18:25:26 INFO - 'HOME': '/home/cltbld', 18:25:26 INFO - 'LANG': 'en_US.UTF-8', 18:25:26 INFO - 'LANGUAGE': 'en_US:en', 18:25:26 INFO - 'LOGNAME': 'cltbld', 18:25:26 INFO - 'MAIL': '/var/mail/cltbld', 18:25:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:26 INFO - 'MOZ_NO_REMOTE': '1', 18:25:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:26 INFO - 'NO_EM_RESTART': '1', 18:25:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:26 INFO - 'PWD': '/builds/slave/test', 18:25:26 INFO - 'SHELL': '/bin/bash', 18:25:26 INFO - 'SHLVL': '1', 18:25:26 INFO - 'TERM': 'linux', 18:25:26 INFO - 'TMOUT': '86400', 18:25:26 INFO - 'USER': 'cltbld', 18:25:26 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:26 INFO - '_': '/tools/buildbot/bin/python'} 18:25:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:25:26 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 18:25:26 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 18:25:26 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 18:25:26 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 18:25:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:25:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:25:27 INFO - Installing collected packages: mozsystemmonitor 18:25:27 INFO - Running setup.py install for mozsystemmonitor 18:25:27 INFO - Successfully installed mozsystemmonitor 18:25:27 INFO - Cleaning up... 18:25:28 INFO - Return code: 0 18:25:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:25:28 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')]} 18:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:28 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')]} 18:25:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:28 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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25:28 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 18:25:28 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 18:25:28 INFO - Using env: {'DISPLAY': ':0', 18:25:28 INFO - 'HOME': '/home/cltbld', 18:25:28 INFO - 'LANG': 'en_US.UTF-8', 18:25:28 INFO - 'LANGUAGE': 'en_US:en', 18:25:28 INFO - 'LOGNAME': 'cltbld', 18:25:28 INFO - 'MAIL': '/var/mail/cltbld', 18:25:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:28 INFO - 'MOZ_NO_REMOTE': '1', 18:25:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:28 INFO - 'NO_EM_RESTART': '1', 18:25:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:28 INFO - 'PWD': '/builds/slave/test', 18:25:28 INFO - 'SHELL': '/bin/bash', 18:25:28 INFO - 'SHLVL': '1', 18:25:28 INFO - 'TERM': 'linux', 18:25:28 INFO - 'TMOUT': '86400', 18:25:28 INFO - 'USER': 'cltbld', 18:25:28 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:28 INFO - '_': '/tools/buildbot/bin/python'} 18:25:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:28 INFO - Downloading/unpacking jsonschema==2.5.1 18:25:28 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 18:25:28 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 18:25:28 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 18:25:28 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 18:25:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:25:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:25:29 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 18:25:29 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 18:25:29 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 18:25:29 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 18:25:29 INFO - Downloading functools32-3.2.3-2.tar.gz 18:25:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:25:29 INFO - warning: no files found matching '*.txt' 18:25:29 INFO - no previously-included directories found matching 'build' 18:25:29 INFO - no previously-included directories found matching 'dist' 18:25:29 INFO - no previously-included directories found matching '.git*' 18:25:29 INFO - Installing collected packages: jsonschema, functools32 18:25:29 INFO - Running setup.py install for functools32 18:25:29 INFO - warning: no files found matching '*.txt' 18:25:29 INFO - no previously-included directories found matching 'build' 18:25:29 INFO - no previously-included directories found matching 'dist' 18:25:29 INFO - no previously-included directories found matching '.git*' 18:25:29 INFO - Successfully installed jsonschema functools32 18:25:29 INFO - Cleaning up... 18:25:29 INFO - Return code: 0 18:25:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:25:29 INFO - Using env: {'DISPLAY': ':0', 18:25:29 INFO - 'HOME': '/home/cltbld', 18:25:29 INFO - 'LANG': 'en_US.UTF-8', 18:25:29 INFO - 'LANGUAGE': 'en_US:en', 18:25:29 INFO - 'LOGNAME': 'cltbld', 18:25:29 INFO - 'MAIL': '/var/mail/cltbld', 18:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:29 INFO - 'MOZ_NO_REMOTE': '1', 18:25:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:29 INFO - 'NO_EM_RESTART': '1', 18:25:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:29 INFO - 'PWD': '/builds/slave/test', 18:25:29 INFO - 'SHELL': '/bin/bash', 18:25:29 INFO - 'SHLVL': '1', 18:25:29 INFO - 'TERM': 'linux', 18:25:29 INFO - 'TMOUT': '86400', 18:25:29 INFO - 'USER': 'cltbld', 18:25:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:29 INFO - '_': '/tools/buildbot/bin/python'} 18:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:25:29 INFO - Cleaning up... 18:25:29 INFO - Return code: 0 18:25:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:25:29 INFO - Using env: {'DISPLAY': ':0', 18:25:29 INFO - 'HOME': '/home/cltbld', 18:25:29 INFO - 'LANG': 'en_US.UTF-8', 18:25:29 INFO - 'LANGUAGE': 'en_US:en', 18:25:29 INFO - 'LOGNAME': 'cltbld', 18:25:29 INFO - 'MAIL': '/var/mail/cltbld', 18:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:29 INFO - 'MOZ_NO_REMOTE': '1', 18:25:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:29 INFO - 'NO_EM_RESTART': '1', 18:25:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:29 INFO - 'PWD': '/builds/slave/test', 18:25:29 INFO - 'SHELL': '/bin/bash', 18:25:29 INFO - 'SHLVL': '1', 18:25:29 INFO - 'TERM': 'linux', 18:25:29 INFO - 'TMOUT': '86400', 18:25:29 INFO - 'USER': 'cltbld', 18:25:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:29 INFO - '_': '/tools/buildbot/bin/python'} 18:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:30 INFO - Downloading/unpacking blobuploader==1.2.4 18:25:30 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 18:25:30 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 18:25:30 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 18:25:30 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 18:25:31 INFO - Downloading blobuploader-1.2.4.tar.gz 18:25:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:25:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:25:31 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 18:25:31 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 18:25:31 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 18:25:31 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 18:25:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:25:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:25:31 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 18:25:31 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 18:25:31 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 18:25:31 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 18:25:31 INFO - Downloading docopt-0.6.1.tar.gz 18:25:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:25:31 INFO - Installing collected packages: blobuploader, requests, docopt 18:25:31 INFO - Running setup.py install for blobuploader 18:25:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:25:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:25:31 INFO - Running setup.py install for requests 18:25:32 INFO - Running setup.py install for docopt 18:25:32 INFO - Successfully installed blobuploader requests docopt 18:25:32 INFO - Cleaning up... 18:25:32 INFO - Return code: 0 18:25:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:32 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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25:32 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 18:25:32 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 18:25:32 INFO - Using env: {'DISPLAY': ':0', 18:25:32 INFO - 'HOME': '/home/cltbld', 18:25:32 INFO - 'LANG': 'en_US.UTF-8', 18:25:32 INFO - 'LANGUAGE': 'en_US:en', 18:25:32 INFO - 'LOGNAME': 'cltbld', 18:25:32 INFO - 'MAIL': '/var/mail/cltbld', 18:25:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:32 INFO - 'MOZ_NO_REMOTE': '1', 18:25:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:32 INFO - 'NO_EM_RESTART': '1', 18:25:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:32 INFO - 'PWD': '/builds/slave/test', 18:25:32 INFO - 'SHELL': '/bin/bash', 18:25:32 INFO - 'SHLVL': '1', 18:25:32 INFO - 'TERM': 'linux', 18:25:32 INFO - 'TMOUT': '86400', 18:25:32 INFO - 'USER': 'cltbld', 18:25:32 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:32 INFO - '_': '/tools/buildbot/bin/python'} 18:25:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:25:32 INFO - Running setup.py (path:/tmp/pip-MCwtQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:25:32 INFO - Running setup.py (path:/tmp/pip-GiHg_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:25:32 INFO - Running setup.py (path:/tmp/pip-OXLBYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:25:32 INFO - Running setup.py (path:/tmp/pip-BBwzbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:25:32 INFO - Running setup.py (path:/tmp/pip-IuP6ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:25:32 INFO - Running setup.py (path:/tmp/pip-Z8iNlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:25:33 INFO - Running setup.py (path:/tmp/pip-Ovmb7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:25:33 INFO - Running setup.py (path:/tmp/pip-OJhx90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:25:33 INFO - Running setup.py (path:/tmp/pip-euQIEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:25:33 INFO - Running setup.py (path:/tmp/pip-7wQgVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:25:33 INFO - Running setup.py (path:/tmp/pip-wBadwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:25:33 INFO - Running setup.py (path:/tmp/pip-u1y4dD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:25:33 INFO - Running setup.py (path:/tmp/pip-d5RUYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:25:33 INFO - Running setup.py (path:/tmp/pip-8wMTl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:33 INFO - Running setup.py (path:/tmp/pip-64GBhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:25:33 INFO - Running setup.py (path:/tmp/pip-eSZ2Wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:25:33 INFO - Running setup.py (path:/tmp/pip-oMk859-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:25:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:25:33 INFO - Running setup.py install for manifestparser 18:25:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:25:33 INFO - Running setup.py install for mozcrash 18:25:34 INFO - Running setup.py install for mozdebug 18:25:34 INFO - Running setup.py install for mozdevice 18:25:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:25:34 INFO - Running setup.py install for mozfile 18:25:34 INFO - Running setup.py install for mozhttpd 18:25:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:25:34 INFO - Running setup.py install for mozinfo 18:25:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:25:34 INFO - Running setup.py install for mozInstall 18:25:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:25:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:25:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:25:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:25:34 INFO - Running setup.py install for mozleak 18:25:34 INFO - Running setup.py install for mozlog 18:25:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:25:34 INFO - Running setup.py install for moznetwork 18:25:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:25:34 INFO - Running setup.py install for mozprocess 18:25:35 INFO - Running setup.py install for mozprofile 18:25:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:25:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:25:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:25:35 INFO - Running setup.py install for mozrunner 18:25:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:25:35 INFO - Running setup.py install for mozscreenshot 18:25:35 INFO - Running setup.py install for moztest 18:25:35 INFO - Running setup.py install for mozversion 18:25:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:25:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:25:35 INFO - Cleaning up... 18:25:35 INFO - Return code: 0 18:25:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25: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')]} 18:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:35 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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25:35 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 18:25:35 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 18:25:35 INFO - Using env: {'DISPLAY': ':0', 18:25:35 INFO - 'HOME': '/home/cltbld', 18:25:35 INFO - 'LANG': 'en_US.UTF-8', 18:25:35 INFO - 'LANGUAGE': 'en_US:en', 18:25:35 INFO - 'LOGNAME': 'cltbld', 18:25:35 INFO - 'MAIL': '/var/mail/cltbld', 18:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:35 INFO - 'MOZ_NO_REMOTE': '1', 18:25:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:35 INFO - 'NO_EM_RESTART': '1', 18:25:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:35 INFO - 'PWD': '/builds/slave/test', 18:25:35 INFO - 'SHELL': '/bin/bash', 18:25:35 INFO - 'SHLVL': '1', 18:25:35 INFO - 'TERM': 'linux', 18:25:35 INFO - 'TMOUT': '86400', 18:25:35 INFO - 'USER': 'cltbld', 18:25:35 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:35 INFO - '_': '/tools/buildbot/bin/python'} 18:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:25:35 INFO - Running setup.py (path:/tmp/pip-XM5FrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:25:35 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)) 18:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:25:35 INFO - Running setup.py (path:/tmp/pip-R7elmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:25:35 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)) 18:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:25:35 INFO - Running setup.py (path:/tmp/pip-OoNC6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:25:36 INFO - Running setup.py (path:/tmp/pip-fd6WEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:25:36 INFO - Running setup.py (path:/tmp/pip-9iEkVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:25:36 INFO - Running setup.py (path:/tmp/pip-YtX3jG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:25:36 INFO - Running setup.py (path:/tmp/pip-WG_tz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:25:36 INFO - Running setup.py (path:/tmp/pip-52XM6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:25:36 INFO - Running setup.py (path:/tmp/pip-PDv4Ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:25:36 INFO - Running setup.py (path:/tmp/pip-pu8atx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:25:36 INFO - Running setup.py (path:/tmp/pip-pkMArY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:25:36 INFO - Running setup.py (path:/tmp/pip-e5gT3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:25:36 INFO - Running setup.py (path:/tmp/pip-836WGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:25:36 INFO - Running setup.py (path:/tmp/pip-JuLaY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:36 INFO - Running setup.py (path:/tmp/pip-6l_0cR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:25:36 INFO - Running setup.py (path:/tmp/pip-gKNp8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:25:36 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)) 18:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:25:36 INFO - Running setup.py (path:/tmp/pip-6u98vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:25:37 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)) 18:25:37 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)) 18:25:37 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)) 18:25:37 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)) 18:25:37 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)) 18:25:37 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)) 18:25:37 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)) 18:25:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:25:37 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 18:25:37 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 18:25:37 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 18:25:37 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 18:25:38 INFO - Downloading blessings-1.6.tar.gz 18:25:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:25:38 INFO - Installing collected packages: blessings 18:25:38 INFO - Running setup.py install for blessings 18:25:38 INFO - Successfully installed blessings 18:25:38 INFO - Cleaning up... 18:25:38 INFO - Return code: 0 18:25:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:25:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:25:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:25:38 INFO - Reading from file tmpfile_stdout 18:25:38 INFO - Current package versions: 18:25:38 INFO - argparse == 1.2.1 18:25:38 INFO - blessings == 1.6 18:25:38 INFO - blobuploader == 1.2.4 18:25:38 INFO - docopt == 0.6.1 18:25:38 INFO - functools32 == 3.2.3-2 18:25:38 INFO - jsonschema == 2.5.1 18:25:38 INFO - manifestparser == 1.1 18:25:38 INFO - mozInstall == 1.13 18:25:38 INFO - mozcrash == 1.0 18:25:38 INFO - mozdebug == 0.1 18:25:38 INFO - mozdevice == 0.51 18:25:38 INFO - mozfile == 1.2 18:25:38 INFO - mozhttpd == 0.7 18:25:38 INFO - mozinfo == 0.10 18:25:38 INFO - mozleak == 0.1 18:25:38 INFO - mozlog == 3.5 18:25:38 INFO - moznetwork == 0.27 18:25:38 INFO - mozprocess == 0.25 18:25:38 INFO - mozprofile == 0.28 18:25:38 INFO - mozrunner == 6.13 18:25:38 INFO - mozscreenshot == 0.1 18:25:38 INFO - mozsystemmonitor == 0.3 18:25:38 INFO - moztest == 0.8 18:25:38 INFO - mozversion == 1.4 18:25:38 INFO - psutil == 3.1.1 18:25:38 INFO - requests == 1.2.3 18:25:38 INFO - wsgiref == 0.1.2 18:25:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:38 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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25:38 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 18:25:38 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 18:25:38 INFO - Using env: {'DISPLAY': ':0', 18:25:38 INFO - 'HOME': '/home/cltbld', 18:25:38 INFO - 'LANG': 'en_US.UTF-8', 18:25:38 INFO - 'LANGUAGE': 'en_US:en', 18:25:38 INFO - 'LOGNAME': 'cltbld', 18:25:38 INFO - 'MAIL': '/var/mail/cltbld', 18:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:38 INFO - 'MOZ_NO_REMOTE': '1', 18:25:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:38 INFO - 'NO_EM_RESTART': '1', 18:25:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:38 INFO - 'PWD': '/builds/slave/test', 18:25:38 INFO - 'SHELL': '/bin/bash', 18:25:38 INFO - 'SHLVL': '1', 18:25:38 INFO - 'TERM': 'linux', 18:25:38 INFO - 'TMOUT': '86400', 18:25:38 INFO - 'USER': 'cltbld', 18:25:38 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:38 INFO - '_': '/tools/buildbot/bin/python'} 18:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:38 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)) 18:25:38 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)) 18:25:38 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)) 18:25:38 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)) 18:25:38 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)) 18:25:38 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)) 18:25:38 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)) 18:25:38 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)) 18:25:38 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)) 18:25:38 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:25:39 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)) 18:25:39 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)) 18:25:39 INFO - Installing collected packages: simplejson 18:25:39 INFO - Running setup.py install for simplejson 18:25:40 INFO - building 'simplejson._speedups' extension 18:25:40 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 18:25:40 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 18:25:40 INFO - Successfully installed simplejson 18:25:40 INFO - Cleaning up... 18:25:41 INFO - Return code: 0 18:25:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25: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')]} 18:25:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:41 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 0x19681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b33a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', '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 18:25:41 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 18:25:41 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 18:25:41 INFO - Using env: {'DISPLAY': ':0', 18:25:41 INFO - 'HOME': '/home/cltbld', 18:25:41 INFO - 'LANG': 'en_US.UTF-8', 18:25:41 INFO - 'LANGUAGE': 'en_US:en', 18:25:41 INFO - 'LOGNAME': 'cltbld', 18:25:41 INFO - 'MAIL': '/var/mail/cltbld', 18:25:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:41 INFO - 'MOZ_NO_REMOTE': '1', 18:25:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:41 INFO - 'NO_EM_RESTART': '1', 18:25:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:41 INFO - 'PWD': '/builds/slave/test', 18:25:41 INFO - 'SHELL': '/bin/bash', 18:25:41 INFO - 'SHLVL': '1', 18:25:41 INFO - 'TERM': 'linux', 18:25:41 INFO - 'TMOUT': '86400', 18:25:41 INFO - 'USER': 'cltbld', 18:25:41 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:41 INFO - '_': '/tools/buildbot/bin/python'} 18:25:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:25:41 INFO - Cleaning up... 18:25:41 INFO - Return code: 0 18:25:41 INFO - Running post-action listener: _resource_record_post_action 18:25:41 INFO - Running post-action listener: _start_resource_monitoring 18:25:41 INFO - Starting resource monitoring. 18:25:41 INFO - [mozharness: 2017-08-25 01:25:41.304808Z] Finished create-virtualenv step (success) 18:25:41 INFO - [mozharness: 2017-08-25 01:25:41.305328Z] Running install step. 18:25:41 INFO - Running pre-action listener: _resource_record_pre_action 18:25:41 INFO - Running main action method: install 18:25:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:25:41 INFO - Reading from file tmpfile_stdout 18:25:41 INFO - Detecting whether we're running mozinstall >=1.0... 18:25:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:25:41 INFO - Reading from file tmpfile_stdout 18:25:41 INFO - Output received: 18:25:41 INFO - Usage: mozinstall [options] installer 18:25:41 INFO - Options: 18:25:41 INFO - -h, --help show this help message and exit 18:25:41 INFO - -d DEST, --destination=DEST 18:25:41 INFO - Directory to install application into. [default: 18:25:41 INFO - "/builds/slave/test"] 18:25:41 INFO - --app=APP Application being installed. [default: firefox] 18:25:41 INFO - mkdir: /builds/slave/test/build/application 18:25:41 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'] 18:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:25:59 INFO - Reading from file tmpfile_stdout 18:25:59 INFO - Output received: 18:25:59 INFO - /builds/slave/test/build/application/firefox/firefox 18:25:59 INFO - Running post-action listener: _resource_record_post_action 18:25:59 INFO - [mozharness: 2017-08-25 01:25:59.285043Z] Finished install step (success) 18:25:59 INFO - [mozharness: 2017-08-25 01:25:59.285326Z] Running setup-mitmproxy step. 18:25:59 INFO - Running pre-action listener: _resource_record_pre_action 18:25:59 INFO - Running main action method: setup_mitmproxy 18:25:59 INFO - Skipping: mitmproxy is not required 18:25:59 INFO - Running post-action listener: _resource_record_post_action 18:25:59 INFO - [mozharness: 2017-08-25 01:25:59.286450Z] Finished setup-mitmproxy step (success) 18:25:59 INFO - [mozharness: 2017-08-25 01:25:59.286669Z] Running run-tests step. 18:25:59 INFO - Running pre-action listener: _resource_record_pre_action 18:25:59 INFO - Running pre-action listener: _set_gcov_prefix 18:25:59 INFO - Running main action method: run_tests 18:25:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:25:59 INFO - Python 2.7.3 18:25:59 INFO - Return code: 0 18:25:59 INFO - grabbing minidump binary from tooltool 18:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:59 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 0x1cd4130>, '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 0x1cd5030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd54c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:25:59 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 18:25:59 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 18:25:59 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 18:25:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:25:59 INFO - Return code: 0 18:25:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:25:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:25:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:25:59 INFO - ENV: RUST_BACKTRACE is now full 18:25:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:25:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:25:59 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/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 18:25:59 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/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-061 --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 18:25:59 INFO - Using env: {'DISPLAY': ':0', 18:25:59 INFO - 'HOME': '/home/cltbld', 18:25:59 INFO - 'LANG': 'en_US.UTF-8', 18:25:59 INFO - 'LANGUAGE': 'en_US:en', 18:25:59 INFO - 'LOGNAME': 'cltbld', 18:25:59 INFO - 'MAIL': '/var/mail/cltbld', 18:25:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:25:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:59 INFO - 'MOZ_NO_REMOTE': '1', 18:25:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:25:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:59 INFO - 'NO_EM_RESTART': '1', 18:25:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:59 INFO - 'PWD': '/builds/slave/test', 18:25:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:25:59 INFO - 'RUST_BACKTRACE': 'full', 18:25:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:25:59 INFO - 'SHELL': '/bin/bash', 18:25:59 INFO - 'SHLVL': '1', 18:25:59 INFO - 'TERM': 'linux', 18:25:59 INFO - 'TMOUT': '86400', 18:25:59 INFO - 'USER': 'cltbld', 18:25:59 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592', 18:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:59 INFO - '_': '/tools/buildbot/bin/python'} 18:25:59 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/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 18:25:59 INFO - mozversion application_buildid: 20170825001342 18:25:59 INFO - mozversion application_changeset: 3199bacd6b3827d711155a9e81c081434cf7cd8f 18:25:59 INFO - mozversion application_display_name: Nightly 18:25:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:25:59 INFO - mozversion application_name: Firefox 18:25:59 INFO - mozversion application_remotingname: firefox 18:25:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:25:59 INFO - mozversion application_vendor: Mozilla 18:25:59 INFO - mozversion application_version: 57.0a1 18:25:59 INFO - mozversion platform_buildid: 20170825001342 18:25:59 INFO - mozversion platform_changeset: 3199bacd6b3827d711155a9e81c081434cf7cd8f 18:25:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:25:59 INFO - mozversion platform_version: 57.0a1 18:25:59 INFO - using testdate: 1503624359 18:25:59 INFO - actual date: 1503624359 18:25:59 INFO - starting webserver on 'localhost:59438' 18:25:59 INFO - SUITE-START | Running 2 tests 18:25:59 INFO - TEST-START | dromaeo_css 18:25:59 INFO - Initialising browser for dromaeo_css test... 18:25:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59438/getInfo.html -profile /tmp/tmpShrlC2/profile 18:25:59 INFO - TEST-INFO | started process 17512 (/builds/slave/test/build/application/firefox/firefox http://localhost:59438/getInfo.html) 18:26:05 INFO - TEST-INFO | 17512: exit 0 18:26:05 INFO - Browser initialized. 18:26:05 INFO - Running cycle 1/1 for dromaeo_css test... 18:26:05 INFO - TEST-INFO | started process 17689 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpShrlC2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:26:06 INFO - PID 17689 | 18:26:06 INFO - PID 17689 | (/builds/slave/test/build/application/firefox/firefox:17740): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:26:06 INFO - PID 17689 | 18:26:07 INFO - PID 17689 | 18:26:07 INFO - PID 17689 | (/builds/slave/test/build/application/firefox/firefox:17798): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:26:07 INFO - PID 17689 | 18:26:07 INFO - PID 17689 | __metrics Screen width/height:1600/1200 18:26:07 INFO - PID 17689 | colorDepth:24 18:26:07 INFO - PID 17689 | Browser inner width/height: 1024/768 18:26:07 INFO - PID 17689 | __metrics 18:26:17 INFO - PID 17689 | RSS: Main: 165781504 18:26:17 INFO - PID 17689 | 18:29:14 INFO - PID 17689 | Cycle 1(1): loaded http://localhost:59438/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59438/tests/dromaeo/cssquery-ext.html) 18:29:16 INFO - PID 17689 | RSS: Main: 169943040 18:29:16 INFO - PID 17689 | 18:32:18 INFO - PID 17689 | Cycle 1(1): loaded http://localhost:59438/tests/dromaeo/cssquery-ext.html (next: http://localhost:59438/tests/dromaeo/cssquery-jquery.html) 18:32:19 INFO - PID 17689 | RSS: Main: 164667392 18:32:19 INFO - PID 17689 | 18:35:20 INFO - PID 17689 | Cycle 1(1): loaded http://localhost:59438/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59438/tests/dromaeo/cssquery-mootools.html) 18:35:21 INFO - PID 17689 | RSS: Main: 168943616 18:35:21 INFO - PID 17689 | 18:38:22 INFO - PID 17689 | Cycle 1(1): loaded http://localhost:59438/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59438/tests/dromaeo/cssquery-prototype.html) 18:38:23 INFO - PID 17689 | RSS: Main: 167436288 18:38:23 INFO - PID 17689 | 18:41:26 INFO - PID 17689 | Cycle 1(1): loaded http://localhost:59438/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59438/tests/dromaeo/cssquery-yui.html) 18:41:27 INFO - PID 17689 | RSS: Main: 170541056 18:41:27 INFO - PID 17689 | 18:44:29 INFO - PID 17689 | Cycle 1(1): loaded http://localhost:59438/tests/dromaeo/cssquery-yui.html (next: http://localhost:59438/tests/dromaeo/cssquery-dojo.html) 18:44:29 INFO - PID 17689 | RSS: Main: 169316352 18:44:29 INFO - PID 17689 | 18:44:29 INFO - PID 17689 | __start_tp_report 18:44:29 INFO - PID 17689 | _x_x_mozilla_page_load 18:44:29 INFO - PID 17689 | _x_x_mozilla_page_load_details 18:44:29 INFO - PID 17689 | |i|pagename|runs| 18:44:29 INFO - PID 17689 | |0;dojo.html;3855.383138697809;3900.2979463696524;3902.7072969527285;3909.7654140751556;3920.6275403836644;6024.427679370453;6154.384561543855;6175.536834737396;6175.66033868137;6194.659293738849;6091.299500557438;6167.845803854894;6181.3200547939805;6184.041473571592;6184.69076546172;5560.193771903072;5578.386377498472;5616.129499927518;5631.211630371751;5647.774089036434;5617.662940223599;5657.20799088128;5676.063449530822;5691.117876729093;5704.942950570504;5453.045716999509;5509.173623956399;5620.269364982567;5688.658680479164;5693;5500.834974950758;5606.579506536985;5610.663360198401;5621.550275977912;5643.266375371176;5528.723563821793;5535.557155427556;5589.217509548666;5598.720063996825;5613.045782216992;6031.698415079272;6067.18093057436;6079.118527813486;6130.969345153246;6171.228596425447;6541.738330466776;6620.735170593171;6678.398944095006;6686.966565167143;6689.531732778707;6602.570832895846;6681.298463661295;6689.6989635466525;6699.229588597325;6702.463802895757;6128.877422451526;6133;6234.501239900797;6238.220222472191;6245.344238854946;6546.410823025951;6620.5365624406795;6621.834454138685;6714.093597364391;6800.251972283045;6432.195975503062;6514;6516.218053833571;6520;6544.738210471528;6651.201855777338;6685.465162786965;6733.360330768667;6736.966315168393;6757.526973111835;6581.2760596334365;6676.298988606223;6678.198616166092;6683.43190828785;6698.933010669928;6583.177102862142;6585.73657053722;6595.0766892635065;6677.165354330708;6689;4946.505349465025;4980.203167493184;5024.447310795809;5033.521815427529;5069.986002799455;4596.954030459653;4652.046330502239;4682.321063445781;4689.20283551797;4690.085433340466;6211.534134939898;6212;6298.8425289367215;6302.65335406557;6325.873482530324;6474.287828338879;6616.702248398834;6648.8670226595195;6724.529282950147;6732.663366831639;6140.048292514667;6146.877062458815;6149.3235744068115;6153.661548614869;6202.255729312511;5585.776568937278;5594.748236329375;5595.496405323541;5596.944030559724;5602.907433050516;6923.9653801730665;6965;6973.12835895513;7044.471664625071;7063.788086357417;6631.900521492278;6649.73401063952;6730.46156307504;6733.730650773914;6742.359475849885;6837.760678376432;6914.412274956484;6976.19773726028;6976.616286104313;6993.370596646327;6546.803595892131;6621.768238111643;6630.138082049302;6659.067730517634;6667.43326817226;6736.225334086643;6744.156980377453;6745.5952642841585;6759.019942108268;6773.390394864486;6427.582207156707;6431.614103153825;6520.869582608416;6524.706388212637;6529.706163222571;6411.807645770635;6416.807495775134;6511.706973186123;6514.73941042353;6522.15212022434;6320.462760665395;6375;6389.329120442287;6404.967975160094;6481.222253329631;6327.620342779263;6415.422611964946;6434.871302574016;6436.034594810816;6494.220693516808;6266;6267.467265282502;6292.685365731787;6298.338674439118;6303.212098487689 18:44:29 INFO - PID 17689 | |1;ext.html;859.0226664844495;921.8571121476169;948.4356807699419;975.5902520941204;978.2907392140698;40942;41723.58276417235;41738;41745.79127104368;41767.37348939761;37394.439083413774;37723.62276377236;37761;37800.621993780056;37803;7022.683979220935;7129.89305160423;7137.500374973754;7140.214576396592;7147.642617869112;6987.755428560002;7081.291870812929;7087.043249161375;7091.5390499617615;7121.145462544502;7526.322630963212;7690.807729806772;7691.346235569983;7691.538507689528;7694.384449244047;7592.392608591327;7627.61861906908;7628.160902300742;7662.348700360476;7686.500377475474;7425.591592462412;7627.5042122261875;7629.54222746637;7633.198514155989;7636.732714354971;37030.259394811954;37324.44013339802;37335.25329493395;37386;37393.81303093494;3574.4995700601935;3627.7097832173363;3630.310241054218;3631.6186800385844;3637.108908317453;29726.25684357908;29732.8513357434;29740.70259297401;30025.249368765737;30084.24789380283;5212.739363031833;5234.0840352938085;5248;5265.131253343194;5290.444503327172;198981;201065;205614;206585;220378.89810551004;51580.96838063218;51806;52028.47971520236;52082.47917520852;52129.47870521246;53045.204321935205;53080.73459632677;53120;53126.46873531293;53147.46852531425;15183.848161518465;15307.61730956735;15319.31063102163;15319.92340038315;15421.306041228172;12375.566855160027;12453.937730311472;12461;12463.127581069419;12466.127371084118;14924;14941.850581494045;14979.550613481613;15001.774973375408;15120.168390738396;5816.79641212564;5917.881642367129;5920.822375328661;5994.340622531518;6020.4280593343565;1028.2545154762738;1046.0219694585587;1061.4321338084087;1067.3435836960214;1070.7269646240159;30117.849410752806;31219.531707024413;31288.68711312903;31411.528827067614;32920.341593168;1117.4189421500773;1136.9658910232706;1136.9658910232706;1143.5540139345662;1159.3217967489113;12610.495580176874;13171.341432928315;13293.335333233299;13353.666158345924;13446.46214151423;272637;273417;274533;275137;276196;55880;56374;56394.43605563974;56527;56550.151747723816;1492.2389581313391;1496.7156240314523;1529.7246495630893;1538.907665540071;1542.105578764314;23637.290881273588;24118.39704007414;24121.758782412653;24167.395815104763;24205.394865127808;39397.2120557593;39699.40450893297;39740.40389394104;39759.80120099381;39779.60220397875;245796;246210;246254;247230;248363;28866;31630.209244769067;34482.31035379228;34784.130396740286;34836.303273934886;44191.33712994243;44384.55615443804;44474;44852;45070.77464612787;32341.676583233868;32537.186570334983;32633.673663264013;33168;33227.16932076621;32522.674773251965;32583.674163258063;32642.51036234411;32897.34205315928;33599.3280134391;35940.64059359373;36558;36673.81663091668;36880.44679329758;37036.6296337044;31246.843765781025;31866.20334491656;31888.68111318857;31890.36219275555;32419.837900810344;23219.88390058039;24624.753752462962;24957.87521062383;26979.730202697723;27095.72904270932 18:44:29 INFO - PID 17689 | |2;jquery.html;559.4657102467145;566.3914482868657;569.5614376929764;575.7984705353126;578.6701580099345;23026.078956841728;23664.881675591598;23676.526469470602;23689.644655330183;23826;25804;26361.73638263617;26403.339916502096;26562.73437265627;26605.334866628345;6224.688765561715;6383.106365108865;6386.808395748112;6387.361263873622;6387.425131738142;6036.486898613623;6240.282367527724;6241;6253.28087269963;6280.748770049193;6196.81409557714;6203.069539569078;6210.09953556734;6211.9068213976825;6240.157578726882;6197.81406557804;6199.969000155015;6200.472959798422;6204.007358822567;6218.31598524162;5794.971025144847;5797.043487824504;5800.593958422912;5806.970965145189;5814.215080964058;30321;30835.074947751604;31360.37279254402;32036.359272814414;32131.83934080338;2216.900239489211;2244.9551008979893;2248.786365295294;2250.3698964290024;2250.763669814662;19825.405237842886;20308.593828123503;20398.69401958972;20420.285290014926;20426.38720838377;2328.5924963131383;2349.295211436562;2352.2002519143402;2389.7371289158177;2476.578981573137;155236;157726;157929;157993;158094.20952895161;14876.553703388916;15545.378184872705;15852.44516441919;16050.518484445485;16508.83491165073;24652.383690407525;24669.753302467103;24706.629400559006;24740.134095306577;24750.752492475203;10624.575016999388;10810.189235807346;10831.891681083245;10859.40273284961;10873.238873278953;8493.660253589911;8856.335774816915;10028.398296102112;10042.74893127663;10136.54385552652;9301.813963720688;9726.221902247944;9740.512974351253;9833.459159746282;10189;2785.331520435081;2844.33158207821;2845;2866.613007244037;2880.1071667783053;998.3011891675856;1013.4729940430934;1025.3386565665253;1030.5414090729553;1036.7563622548696;21021.789782101983;25112.74887251104;27003.1899043029;27389.589156162674;27732.168034958984;1022.6369638778275;1089.515165751233;1095.802755504001;1095.8739744929276;1104.1994553948323;11944.044476441864;12108.697282568006;12181.147319687538;12216.755664886652;12297.938510307391;644896;657991;663851;668336;671728;137399.31300343433;138251;140276;140542;142112;1272.4337669736879;1325.1982550556968;1339.437436276766;1343.5029039255537;1352.918824870511;22865;22986.54026919419;22990.655140172577;23089.307320780405;23181.304560863202;64444.67777661081;65466.67266663827;66275.66862165659;67009;68585.3141468579;85401.14598853931;86084.1391586076;86965;87220;87528.56235719036;13112.934435327763;14613.85386146125;15287.77068343998;15592.298346574204;15642.374305027672;24731.50536989214;24839.751602483742;24864.37839054038;25022;25122.49755004853;19602.50993725168;19673;19721.70417443711;20062.899685501478;20070.49823754418;17204.91397543004;17526.82473175303;17777;17958.371456998942;18565.35021274249;20069.899650501655;20251.18995240174;20474.488137796674;21170.470738231666;21617.351479455643;15553.688926221186;15599.376024958874;15603.765943511084;15762.133082680562;15793.921030394775;19802;20260.89869550643;20314.695279570522;20367.694484582447;20423.69364459564 18:44:29 INFO - PID 17689 | |3;mootools.html;1408.7605107131787;1460.3282490054573;1466.9413223471063;1467.6917847252078;1476.0995792566532;15631.218439078044;15925.840741592581;16233.350665973376;16270.7559386609;16285.755713664304;16527.173641317917;16763.580910477245;16808.159592020413;16834;16849.241784119713;2710.159850446363;2759.8206116602355;2761.903333383332;2762.2541913683294;2776.430831679511;2763.405867738436;2850.4156647887135;2863.8568071596337;2864.455753406846;2864.4987127252753;2739.205630367201;2762.654668166479;2774.3480282133683;2789.734975177355;2792.190264823199;2773.459175460786;2785.7492825645686;2805.130409573039;2805.4809860175833;2805.5090359187266;2625.18619228038;2637.1956553251252;2640.749128832758;2644.9471010579873;2656.3624730064644;15728.449504267295;16090.83909160905;16114.274857631435;16117.919410402874;16122.919385403115;8307.0862205158;8583.914160858374;8596.656133754643;8602.483850968962;8622.749701293278;5569.192467092249;5759.366469688331;5771.307443106812;5859.384764599698;5859.619124756877;1940.6409814184422;1952.541152829084;1952.8437724981968;1979.3468155508647;1997.850161237913;28415.85792071047;28646.283842904093;28700.13899583016;28703.56944645832;28717.42565148706;6964.79105626832;7051.682674279669;7093.89359159613;7156.926461030783;7261.237570055174;18378.63242735138;18411.355602553846;18414.079296035146;18423.907880460512;18425.907870460564;8012.27889489937;8161.510309381338;8167.305779008732;8174.427790054758;8177.836443271101;7459.701611935462;7478.326950574475;7567;7602.543847369065;7611.543307401574;5193.428722840485;5403.675779453245;5411.161270003077;5452.345718513724;5473.507384335349;1535.616095976006;1535.7773122897117;1542.583502454331;1555.574390858007;1569.6939096876;931.2363861633396;967.3035414501547;967.9981219437989;974.1232890398697;974.1914211204825;8439.113893041265;8464.873026904601;8509.829803403898;8548.743537693756;8616.741497755078;750.3996802557932;769.2768797330604;771.4753967302177;781.1797612506845;792.4928046050417;5210.505002024802;5906.704664766745;5984.521238300926;6008.909866352008;6043.576949613484;22136;22572.774272257066;23287;23497.7650223499;23752.049918003086;21839;22948.770512294996;23372.883135584212;23479.295621131394;23492.76507234906;11169.44152792357;11642.70893227676;11678.0657547396;11682;12299.508019679291;9016.909830901606;9200.585973631336;9216.308776841897;9246.121618446237;9254.25965922738;19543.12055957457;19548.120334585263;20211.595768084262;20475.283365082152;20683.069261883553;20275.59448811045;20441;20492.07785649679;20535.58928821445;20609;11491.253068550517;12352.320622365856;12735.936320318338;12760.489580416679;12776.425060872098;14308.356123974238;14341.92829035848;14445;14466.421343146156;14529.709405812036;11904.345261010727;11966.22219555726;12060;12142.514299427923;12144.271343719405;11096.167787416138;11310.377929213972;11382.829257560978;11514.712132196762;11538;10332.638357657446;10562.630307939186;10590.099841513558;10674.252802303765;10682.73293167677;7170.207448137966;7255.56466612005;7982.920170798217;7994.5203287802915;8150.144234855255;8335.666573337;8506.574671266535;8612.138786121337;8666.826663466569;8734.912650873664 18:44:29 INFO - PID 17689 | |4;prototype.html;515.13200257566;523.3222976245761;544.276112770016;555.4084899581948;557.5873853348522;6116.847078823032;6129.601575897569;6166.6300021998595;6171.889059969204;6251.187345645071;5918.348981612019;6042.274927008754;6056.545759068065;6066.4236897494775;6081.239845019372;4918.385201849769;4939.580135688462;4944.826931057413;4950.455449900508;4952.5047495250365;4907.1657818171;4912.508749125094;4922.827701030465;4928.457869634337;4935.555799978001;4800;4892.608591312686;4903.215485522317;4916.778744956468;4924;4892.510748925115;4901.701049221959;4912.606991440676;4925.408950925894;4938.851834444972;4397.186520493687;4397.494288156871;4424.84513042042;4428.424304840382;4430.224710675619;6022.096685497167;6036.336003039706;6061;6066.575339726177;6109.969450152766;5703.885922281531;5708.77164913403;5713.771449142029;5717.199592057115;5728.570357223183;6446.9355306446805;6449.4840412767335;6449.967750161266;6508.54440189187;6514.120593719882;4846.29728689338;4846.61227101831;4858.076965376603;4870;4879.63402744792;6730.125083739083;6758.391744742948;6772.35662612056;6782.287859774751;6804.285550017276;5013.66591216844;5017.67385119966;5023.09584274834;5027.120253955543;5040.823571174991;5078.070333273333;5089.262057001788;5092.490750924952;5092.777733343971;5110;5636.520895723909;5652.682400215892;5673.631213971161;5726.226959360516;5729.8567535811935;6457.225132983978;6463.353664633499;6474.802161600119;6487.437462202403;6520.771772987922;1676.2624445244053;1710.45265515034;1739.4607671621839;1744.337151882302;1758.9560260993321;1188.8811118888216;1220.8473940757406;1227.147132742753;1242.7328124453286;1258.7608354412632;5954.851128721816;5987.520998320124;6003.219581454383;6027.011086341497;6074;6389.904151437732;6407.967960160169;6469.159009328851;6551.80344589663;6556.377144171296;5955.67243801595;5994.250718660168;6018.608790428608;6159.599626024294;6174.197354343996;3430.2624935638955;3474.774139680913;3483.686468217822;3495.580530336376;3514.3674138655288;6785.355391237825;6809.706155830377;6855.4917918057945;6945.965270173718;6947.513674042769;6693.765718199839;6720.798376048726;6723.529352945345;6817.931820681829;6825.897611535831;6858.554193977276;6862.519623626298;6921.958022383409;6979.581225126509;6997;6572.80281591553;6627.867442651023;6641.568298060611;6662.800115996528;6694.933050669431;6484.254310754323;6545.639989800607;6567.343265673395;6588.604683718992;6594.835129121619;6972.6403351346;6980.616066116209;6986.266442023514;7016.22821489646;7029.402500787491;4977.427595826526;5029.497050294941;5034.256330300069;5041.16820724591;5045.747712614428;6619.867602647823;6635.668216589054;6635.668216589248;6636.369544893228;6646.1452357633425;6355.777547785805;6398.176519692051;6428.457170279005;6431.842268391734;6452.419282264431;6290.175849003921;6310.936890631218;6310.968445157745;6317.526185536195;6353;6137.907931380943;6282.183316168869;6288;6316.684165791784;6332.905006425;6154.522914500577;6316.6841657916;6349.8730025400155;6359.745610175726;6480.287168411565;6151.630902145886;6187.752489900354;6220.502359811295;6229.408206220402;6263.592866463665 18:44:29 INFO - PID 17689 | |5;yui.html;911.5351170902842;941.0569712902936;953.1040821627673;956.077385323163;969.5249327829364;18862.339818106368;19852;19887.105080271387;19894.50263743407;22206.555868882653;21153.788462115375;21437.57124857502;21954.121835126574;22023.22918697846;22054.33836984889;1003.3077176748038;1029.7683021320217;1032.654060889603;1033.3851358441716;1036.1710631494811;1020.209337763232;1033.4987531047436;1078.2344535379889;1080.1952545353683;1084.598698481563;1072.3940973350032;1081.2269227502334;1084.7776205877815;1087.7171935296801;1088.5319312695538;1076.8869268726748;1079.023483747206;1084.5932775209296;1085.1915323084274;1085.766560189555;804.5333706450303;804.9235322644339;805.2712295372697;805.3074356053789;805.5609692717483;19937.401877943685;20461.590768184702;20719.27482538119;20912.895435522878;20950.37148885536;22406.32781016572;22413.103475860946;22767.316980490446;22878.42803929915;23066.42333941648;17522.335378139058;17855.46433606981;17856.642867142586;17918.552036199068;17989.7301540477;3844.9807750961068;3862.1503269280715;3873.9806300968316;3877.418387241908;3881.825317860703;144498;146305.26847365696;146767.99999999892;147839;147969.2601536996;17126;17188.742168867477;17396.39112631052;17440;17534.473965781046;29253.85373073121;29733.405331893242;30007.099787006424;30050;30337.696623033487;7673.731419400406;7990.520568765893;8429.915700843036;8496.66013359471;8724.956375218084;4095.2014357200655;4147.958520414818;4154.5222299435345;4159.147374788161;4175.62419382257;5451.672899625956;5544.417836127229;5544.584156188302;5588.469095435927;5600.215969764236;1116.20749268019;1135.0975974100609;1150.1833698074295;1150.3098141115431;1155.6764106050318;707.3351050012972;721.2066726600697;722.0901663903519;726.4333819620705;750.7372419653078;12647.424347539782;12700.616205095508;13164.40760165795;13304.733905322033;13911.930440347733;729.0740759235741;733.6771820399007;736.9078865141857;739.9112106547141;741.907261592301;5961;6092;6156.107364432132;6161.075838624242;6234.189555357865;124689.37655311846;125667.854739414;128771.71228287785;129648;131848;110224.4488777567;110397;111372.44313778379;114585;120558;99434.50282748685;100909;102236;102629;102726.48636756917;14168.574942751733;14201.786973195616;14290.928545357207;14369.64075898111;14621.780673290123;46726.766366167954;47679;47807.5219247803;48755.75622121867;49993.50006499889;59489.405105950114;60197;60338.69830650819;60719;60776.69611651913;19022.524436889187;19427.805721943165;19499.902500487406;19626.90186549058;19649.410517684493;20478;20496.897515512326;20586.588268234253;20707.17171313191;20717.585648286647;18932.526686832938;19116.617667646846;19139.617207656047;19190.904045479685;19335.226590936207;19327.806721933164;19330.903345483184;19406;19488.902555487133;19805.40583782489;18541.44375668732;18653.347132850286;18828.717569236745;18863.811361886204;19017.809821902156;14661.413543458142;14842.851571484578;14978.625534361729;15095.54713358601;15110;14601.342939567532;15496.380144794082;15986.920065399598;16054.839451605334;16139.193040348171 18:44:29 INFO - PID 17689 | __end_tp_report 18:44:29 INFO - PID 17689 | __start_cc_report 18:44:29 INFO - PID 17689 | _x_x_mozilla_cycle_collect,341 18:44:29 INFO - PID 17689 | __end_cc_report 18:44:29 INFO - PID 17689 | __startTimestamp1503625469275__endTimestamp 18:44:29 INFO - PID 17689 | 18:44:29 INFO - PID 17689 | ------- Summary: start ------- 18:44:29 INFO - PID 17689 | Number of tests: 6 18:44:29 INFO - PID 17689 | 18:44:29 INFO - PID 17689 | [#0] dojo.html Cycles:165 Average:6164.56 Median:6375.00 stddev:676.44 (10.6%) stddev-sans-first:653.81 18:44:29 INFO - PID 17689 | Values: 3855.4 3900.3 3902.7 3909.8 3920.6 6024.4 6154.4 6175.5 6175.7 6194.7 6091.3 6167.8 6181.3 6184.0 6184.7 5560.2 5578.4 5616.1 5631.2 5647.8 5617.7 5657.2 5676.1 5691.1 5704.9 5453.0 5509.2 5620.3 5688.7 5693.0 5500.8 5606.6 5610.7 5621.6 5643.3 5528.7 5535.6 5589.2 5598.7 5613.0 6031.7 6067.2 6079.1 6131.0 6171.2 6541.7 6620.7 6678.4 6687.0 6689.5 6602.6 6681.3 6689.7 6699.2 6702.5 6128.9 6133.0 6234.5 6238.2 6245.3 6546.4 6620.5 6621.8 6714.1 6800.3 6432.2 6514.0 6516.2 6520.0 6544.7 6651.2 6685.5 6733.4 6737.0 6757.5 6581.3 6676.3 6678.2 6683.4 6698.9 6583.2 6585.7 6595.1 6677.2 6689.0 4946.5 4980.2 5024.4 5033.5 5070.0 4597.0 4652.0 4682.3 4689.2 4690.1 6211.5 6212.0 6298.8 6302.7 6325.9 6474.3 6616.7 6648.9 6724.5 6732.7 6140.0 6146.9 6149.3 6153.7 6202.3 5585.8 5594.7 5595.5 5596.9 5602.9 6924.0 6965.0 6973.1 7044.5 7063.8 6631.9 6649.7 6730.5 6733.7 6742.4 6837.8 6914.4 6976.2 6976.6 6993.4 6546.8 6621.8 6630.1 6659.1 6667.4 6736.2 6744.2 6745.6 6759.0 6773.4 6427.6 6431.6 6520.9 6524.7 6529.7 6411.8 6416.8 6511.7 6514.7 6522.2 6320.5 6375.0 6389.3 6405.0 6481.2 6327.6 6415.4 6434.9 6436.0 6494.2 6266.0 6267.5 6292.7 6298.3 6303.2 18:44:29 INFO - PID 17689 | 18:44:29 INFO - PID 17689 | [#1] ext.html Cycles:180 Average:41112.86 Median:19757.57 stddev:63572.74 (321.8%) stddev-sans-first:63679.23 18:44:29 INFO - PID 17689 | Values: 859.0 921.9 948.4 975.6 978.3 40942.0 41723.6 41738.0 41745.8 41767.4 37394.4 37723.6 37761.0 37800.6 37803.0 7022.7 7129.9 7137.5 7140.2 7147.6 6987.8 7081.3 7087.0 7091.5 7121.1 7526.3 7690.8 7691.3 7691.5 7694.4 7592.4 7627.6 7628.2 7662.3 7686.5 7425.6 7627.5 7629.5 7633.2 7636.7 37030.3 37324.4 37335.3 37386.0 37393.8 3574.5 3627.7 3630.3 3631.6 3637.1 29726.3 29732.9 29740.7 30025.2 30084.2 5212.7 5234.1 5248.0 5265.1 5290.4 198981.0 201065.0 205614.0 206585.0 220378.9 51581.0 51806.0 52028.5 52082.5 52129.5 53045.2 53080.7 53120.0 53126.5 53147.5 15183.8 15307.6 15319.3 15319.9 15421.3 12375.6 12453.9 12461.0 12463.1 12466.1 14924.0 14941.9 14979.6 15001.8 15120.2 5816.8 5917.9 5920.8 5994.3 6020.4 1028.3 1046.0 1061.4 1067.3 1070.7 30117.8 31219.5 31288.7 31411.5 32920.3 1117.4 1137.0 1137.0 1143.6 1159.3 12610.5 13171.3 13293.3 13353.7 13446.5 272637.0 273417.0 274533.0 275137.0 276196.0 55880.0 56374.0 56394.4 56527.0 56550.2 1492.2 1496.7 1529.7 1538.9 1542.1 23637.3 24118.4 24121.8 24167.4 24205.4 39397.2 39699.4 39740.4 39759.8 39779.6 245796.0 246210.0 246254.0 247230.0 248363.0 28866.0 31630.2 34482.3 34784.1 34836.3 44191.3 44384.6 44474.0 44852.0 45070.8 32341.7 32537.2 32633.7 33168.0 33227.2 32522.7 32583.7 32642.5 32897.3 33599.3 35940.6 36558.0 36673.8 36880.4 37036.6 31246.8 31866.2 31888.7 31890.4 32419.8 23219.9 24624.8 24957.9 26979.7 27095.7 18:44:29 INFO - PID 17689 | 18:44:29 INFO - PID 17689 | [#2] jquery.html Cycles:180 Average:42239.71 Median:2338.94 stddev:110542.50 (4726.2%) stddev-sans-first:110808.30 18:44:29 INFO - PID 17689 | Values: 559.5 566.4 569.6 575.8 578.7 23026.1 23664.9 23676.5 23689.6 23826.0 25804.0 26361.7 26403.3 26562.7 26605.3 6224.7 6383.1 6386.8 6387.4 6387.4 6036.5 6240.3 6241.0 6253.3 6280.7 6196.8 6203.1 6210.1 6211.9 6240.2 6197.8 6200.0 6200.5 6204.0 6218.3 5795.0 5797.0 5800.6 5807.0 5814.2 30321.0 30835.1 31360.4 32036.4 32131.8 2216.9 2245.0 2248.8 2250.4 2250.8 19825.4 20308.6 20398.7 20420.3 20426.4 2328.6 2349.3 2352.2 2389.7 2476.6 155236.0 157726.0 157929.0 157993.0 158094.2 14876.6 15545.4 15852.4 16050.5 16508.8 24652.4 24669.8 24706.6 24740.1 24750.8 10624.6 10810.2 10831.9 10859.4 10873.2 8493.7 8856.3 10028.4 10042.7 10136.5 9301.8 9726.2 9740.5 9833.5 10189.0 2785.3 2844.3 2845.0 2866.6 2880.1 998.3 1013.5 1025.3 1030.5 1036.8 21021.8 25112.7 27003.2 27389.6 27732.2 1022.6 1089.5 1095.8 1095.9 1104.2 11944.0 12108.7 12181.1 12216.8 12297.9 644896.0 657991.0 663851.0 668336.0 671728.0 137399.3 138251.0 140276.0 140542.0 142112.0 1272.4 1325.2 1339.4 1343.5 1352.9 22865.0 22986.5 22990.7 23089.3 23181.3 64444.7 65466.7 66275.7 67009.0 68585.3 85401.1 86084.1 86965.0 87220.0 87528.6 13112.9 14613.9 15287.8 15592.3 15642.4 24731.5 24839.8 24864.4 25022.0 25122.5 19602.5 19673.0 19721.7 20062.9 20070.5 17204.9 17526.8 17777.0 17958.4 18565.4 20069.9 20251.2 20474.5 21170.5 21617.4 15553.7 15599.4 15603.8 15762.1 15793.9 19802.0 20260.9 20314.7 20367.7 20423.7 18:44:29 INFO - PID 17689 | 18:44:29 INFO - PID 17689 | [#3] mootools.html Cycles:180 Average:9940.75 Median:2724.68 stddev:7187.55 (263.8%) stddev-sans-first:7179.13 18:44:29 INFO - PID 17689 | Values: 1408.8 1460.3 1466.9 1467.7 1476.1 15631.2 15925.8 16233.4 16270.8 16285.8 16527.2 16763.6 16808.2 16834.0 16849.2 2710.2 2759.8 2761.9 2762.3 2776.4 2763.4 2850.4 2863.9 2864.5 2864.5 2739.2 2762.7 2774.3 2789.7 2792.2 2773.5 2785.7 2805.1 2805.5 2805.5 2625.2 2637.2 2640.7 2644.9 2656.4 15728.4 16090.8 16114.3 16117.9 16122.9 8307.1 8583.9 8596.7 8602.5 8622.7 5569.2 5759.4 5771.3 5859.4 5859.6 1940.6 1952.5 1952.8 1979.3 1997.9 28415.9 28646.3 28700.1 28703.6 28717.4 6964.8 7051.7 7093.9 7156.9 7261.2 18378.6 18411.4 18414.1 18423.9 18425.9 8012.3 8161.5 8167.3 8174.4 8177.8 7459.7 7478.3 7567.0 7602.5 7611.5 5193.4 5403.7 5411.2 5452.3 5473.5 1535.6 1535.8 1542.6 1555.6 1569.7 931.2 967.3 968.0 974.1 974.2 8439.1 8464.9 8509.8 8548.7 8616.7 750.4 769.3 771.5 781.2 792.5 5210.5 5906.7 5984.5 6008.9 6043.6 22136.0 22572.8 23287.0 23497.8 23752.0 21839.0 22948.8 23372.9 23479.3 23492.8 11169.4 11642.7 11678.1 11682.0 12299.5 9016.9 9200.6 9216.3 9246.1 9254.3 19543.1 19548.1 20211.6 20475.3 20683.1 20275.6 20441.0 20492.1 20535.6 20609.0 11491.3 12352.3 12735.9 12760.5 12776.4 14308.4 14341.9 14445.0 14466.4 14529.7 11904.3 11966.2 12060.0 12142.5 12144.3 11096.2 11310.4 11382.8 11514.7 11538.0 10332.6 10562.6 10590.1 10674.3 10682.7 7170.2 7255.6 7982.9 7994.5 8150.1 8335.7 8506.6 8612.1 8666.8 8734.9 18:44:29 INFO - PID 17689 | 18:44:29 INFO - PID 17689 | [#4] prototype.html Cycles:180 Average:5497.29 Median:6077.62 stddev:1547.25 (25.5%) stddev-sans-first:1505.73 18:44:29 INFO - PID 17689 | Values: 515.1 523.3 544.3 555.4 557.6 6116.8 6129.6 6166.6 6171.9 6251.2 5918.3 6042.3 6056.5 6066.4 6081.2 4918.4 4939.6 4944.8 4950.5 4952.5 4907.2 4912.5 4922.8 4928.5 4935.6 4800.0 4892.6 4903.2 4916.8 4924.0 4892.5 4901.7 4912.6 4925.4 4938.9 4397.2 4397.5 4424.8 4428.4 4430.2 6022.1 6036.3 6061.0 6066.6 6110.0 5703.9 5708.8 5713.8 5717.2 5728.6 6446.9 6449.5 6450.0 6508.5 6514.1 4846.3 4846.6 4858.1 4870.0 4879.6 6730.1 6758.4 6772.4 6782.3 6804.3 5013.7 5017.7 5023.1 5027.1 5040.8 5078.1 5089.3 5092.5 5092.8 5110.0 5636.5 5652.7 5673.6 5726.2 5729.9 6457.2 6463.4 6474.8 6487.4 6520.8 1676.3 1710.5 1739.5 1744.3 1759.0 1188.9 1220.8 1227.1 1242.7 1258.8 5954.9 5987.5 6003.2 6027.0 6074.0 6389.9 6408.0 6469.2 6551.8 6556.4 5955.7 5994.3 6018.6 6159.6 6174.2 3430.3 3474.8 3483.7 3495.6 3514.4 6785.4 6809.7 6855.5 6946.0 6947.5 6693.8 6720.8 6723.5 6817.9 6825.9 6858.6 6862.5 6922.0 6979.6 6997.0 6572.8 6627.9 6641.6 6662.8 6694.9 6484.3 6545.6 6567.3 6588.6 6594.8 6972.6 6980.6 6986.3 7016.2 7029.4 4977.4 5029.5 5034.3 5041.2 5045.7 6619.9 6635.7 6635.7 6636.4 6646.1 6355.8 6398.2 6428.5 6431.8 6452.4 6290.2 6310.9 6311.0 6317.5 6353.0 6137.9 6282.2 6288.0 6316.7 6332.9 6154.5 6316.7 6349.9 6359.7 6480.3 6151.6 6187.8 6220.5 6229.4 6263.6 18:44:29 INFO - PID 17689 | 18:44:29 INFO - PID 17689 | [#5] yui.html Cycles:180 Average:26127.24 Median:19494.40 stddev:36913.67 (189.4%) stddev-sans-first:36968.67 18:44:29 INFO - PID 17689 | Values: 911.5 941.1 953.1 956.1 969.5 18862.3 19852.0 19887.1 19894.5 22206.6 21153.8 21437.6 21954.1 22023.2 22054.3 1003.3 1029.8 1032.7 1033.4 1036.2 1020.2 1033.5 1078.2 1080.2 1084.6 1072.4 1081.2 1084.8 1087.7 1088.5 1076.9 1079.0 1084.6 1085.2 1085.8 804.5 804.9 805.3 805.3 805.6 19937.4 20461.6 20719.3 20912.9 20950.4 22406.3 22413.1 22767.3 22878.4 23066.4 17522.3 17855.5 17856.6 17918.6 17989.7 3845.0 3862.2 3874.0 3877.4 3881.8 144498.0 146305.3 146768.0 147839.0 147969.3 17126.0 17188.7 17396.4 17440.0 17534.5 29253.9 29733.4 30007.1 30050.0 30337.7 7673.7 7990.5 8429.9 8496.7 8725.0 4095.2 4148.0 4154.5 4159.1 4175.6 5451.7 5544.4 5544.6 5588.5 5600.2 1116.2 1135.1 1150.2 1150.3 1155.7 707.3 721.2 722.1 726.4 750.7 12647.4 12700.6 13164.4 13304.7 13911.9 729.1 733.7 736.9 739.9 741.9 5961.0 6092.0 6156.1 6161.1 6234.2 124689.4 125667.9 128771.7 129648.0 131848.0 110224.4 110397.0 111372.4 114585.0 120558.0 99434.5 100909.0 102236.0 102629.0 102726.5 14168.6 14201.8 14290.9 14369.6 14621.8 46726.8 47679.0 47807.5 48755.8 49993.5 59489.4 60197.0 60338.7 60719.0 60776.7 19022.5 19427.8 19499.9 19626.9 19649.4 20478.0 20496.9 20586.6 20707.2 20717.6 18932.5 19116.6 19139.6 19190.9 19335.2 19327.8 19330.9 19406.0 19488.9 19805.4 18541.4 18653.3 18828.7 18863.8 19017.8 14661.4 14842.9 14978.6 15095.5 15110.0 14601.3 15496.4 15986.9 16054.8 16139.2 18:44:29 INFO - PID 17689 | -------- Summary: end -------- 18:44:29 INFO - PID 17689 | 18:44:29 INFO - TEST-INFO | 17689: exit 0 18:44:30 INFO - TEST-OK | dromaeo_css | took 1110357ms 18:44:30 INFO - TEST-START | kraken 18:44:30 INFO - Initialising browser for kraken test... 18:44:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59438/getInfo.html -profile /tmp/tmpKXQ6DG/profile 18:44:30 INFO - TEST-INFO | started process 18103 (/builds/slave/test/build/application/firefox/firefox http://localhost:59438/getInfo.html) 18:44:35 INFO - TEST-INFO | 18103: exit 0 18:44:36 INFO - Browser initialized. 18:44:36 INFO - Running cycle 1/1 for kraken test... 18:44:36 INFO - TEST-INFO | started process 18279 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKXQ6DG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:44:36 INFO - PID 18279 | 18:44:36 INFO - PID 18279 | (/builds/slave/test/build/application/firefox/firefox:18330): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:44:36 INFO - PID 18279 | 18:44:37 INFO - PID 18279 | 18:44:37 INFO - PID 18279 | (/builds/slave/test/build/application/firefox/firefox:18386): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:44:37 INFO - PID 18279 | 18:44:37 INFO - PID 18279 | __metrics Screen width/height:1600/1200 18:44:37 INFO - PID 18279 | colorDepth:24 18:44:37 INFO - PID 18279 | Browser inner width/height: 1024/768 18:44:37 INFO - PID 18279 | __metrics 18:44:47 INFO - PID 18279 | RSS: Main: 165900288 18:44:47 INFO - PID 18279 | 18:44:49 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59438/tests/kraken/driver.html?testName=audio-beat-detection) 18:44:50 INFO - PID 18279 | RSS: Main: 170479616 18:44:50 INFO - PID 18279 | 18:44:53 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59438/tests/kraken/driver.html?testName=audio-dft) 18:44:53 INFO - PID 18279 | RSS: Main: 172408832 18:44:53 INFO - PID 18279 | 18:44:56 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59438/tests/kraken/driver.html?testName=audio-fft) 18:44:57 INFO - PID 18279 | RSS: Main: 165945344 18:44:57 INFO - PID 18279 | 18:44:59 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59438/tests/kraken/driver.html?testName=audio-oscillator) 18:45:00 INFO - PID 18279 | RSS: Main: 166801408 18:45:00 INFO - PID 18279 | 18:45:02 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59438/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:45:02 INFO - PID 18279 | RSS: Main: 166969344 18:45:02 INFO - PID 18279 | 18:45:05 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59438/tests/kraken/driver.html?testName=imaging-darkroom) 18:45:06 INFO - PID 18279 | RSS: Main: 166842368 18:45:06 INFO - PID 18279 | 18:45:09 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59438/tests/kraken/driver.html?testName=imaging-desaturate) 18:45:10 INFO - PID 18279 | RSS: Main: 170508288 18:45:10 INFO - PID 18279 | 18:45:13 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59438/tests/kraken/driver.html?testName=json-parse-financial) 18:45:13 INFO - PID 18279 | RSS: Main: 169304064 18:45:13 INFO - PID 18279 | 18:45:15 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59438/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:45:15 INFO - PID 18279 | RSS: Main: 168898560 18:45:15 INFO - PID 18279 | 18:45:17 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59438/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:45:18 INFO - PID 18279 | RSS: Main: 169000960 18:45:18 INFO - PID 18279 | 18:45:20 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59438/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:45:20 INFO - PID 18279 | RSS: Main: 168820736 18:45:20 INFO - PID 18279 | 18:45:23 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:45:24 INFO - PID 18279 | RSS: Main: 168726528 18:45:24 INFO - PID 18279 | 18:45:27 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:45:27 INFO - PID 18279 | RSS: Main: 169373696 18:45:27 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | Cycle 1(1): loaded http://localhost:59438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59438/tests/kraken/driver.html?testName=ai-astar) 18:45:29 INFO - PID 18279 | RSS: Main: 170987520 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | __start_tp_report 18:45:29 INFO - PID 18279 | _x_x_mozilla_page_load 18:45:29 INFO - PID 18279 | _x_x_mozilla_page_load_details 18:45:29 INFO - PID 18279 | |i|pagename|runs| 18:45:29 INFO - PID 18279 | |0;ai-astar;97;108;98;107;108;108;107;98;106;110 18:45:29 INFO - PID 18279 | |1;audio-beat-detection;144;117;146;180;122;142;145;115;165;120 18:45:29 INFO - PID 18279 | |2;audio-dft;162;163;160;161;162;163;164;163;168;166 18:45:29 INFO - PID 18279 | |3;audio-fft;73;74;73;173;74;74;86;87;94;74 18:45:29 INFO - PID 18279 | |4;audio-oscillator;82;80;126;81;78;79;133;79;79;127 18:45:29 INFO - PID 18279 | |5;imaging-gaussian-blur;114;114;114;114;114;113;114;115;115;114 18:45:29 INFO - PID 18279 | |6;imaging-darkroom;150;151;150;151;151;151;152;150;151;150 18:45:29 INFO - PID 18279 | |7;imaging-desaturate;94;95;94;94;94;95;95;93;95;94 18:45:29 INFO - PID 18279 | |8;json-parse-financial;47;53;50;46;46;46;52;45;46;46 18:45:29 INFO - PID 18279 | |9;json-stringify-tinderbox;52;51;51;51;52;50;51;51;51;51 18:45:29 INFO - PID 18279 | |10;stanford-crypto-aes;77;72;74;73;77;72;73;73;72;72 18:45:29 INFO - PID 18279 | |11;stanford-crypto-ccm;120;113;122;110;143;122;126;123;113;152 18:45:29 INFO - PID 18279 | |12;stanford-crypto-pbkdf2;165;169;170;168;169;164;167;168;166;169 18:45:29 INFO - PID 18279 | |13;stanford-crypto-sha256-iterative;61;60;60;60;60;62;67;61;60;62 18:45:29 INFO - PID 18279 | __end_tp_report 18:45:29 INFO - PID 18279 | __start_cc_report 18:45:29 INFO - PID 18279 | _x_x_mozilla_cycle_collect,889 18:45:29 INFO - PID 18279 | __end_cc_report 18:45:29 INFO - PID 18279 | __startTimestamp1503625529572__endTimestamp 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | ------- Summary: start ------- 18:45:29 INFO - PID 18279 | Number of tests: 14 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#0] ai-astar Cycles:10 Average:104.70 Median:109.00 stddev:4.97 (4.6%) stddev-sans-first:4.42 18:45:29 INFO - PID 18279 | Values: 97.0 108.0 98.0 107.0 108.0 108.0 107.0 98.0 106.0 110.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:144.50 stddev:21.56 (14.9%) stddev-sans-first:22.81 18:45:29 INFO - PID 18279 | Values: 144.0 117.0 146.0 180.0 122.0 142.0 145.0 115.0 165.0 120.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:2.35 (1.4%) stddev-sans-first:2.45 18:45:29 INFO - PID 18279 | Values: 162.0 163.0 160.0 161.0 162.0 163.0 164.0 163.0 168.0 166.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#3] audio-fft Cycles:10 Average:88.20 Median:74.00 stddev:30.73 (41.5%) stddev-sans-first:32.10 18:45:29 INFO - PID 18279 | Values: 73.0 74.0 73.0 173.0 74.0 74.0 86.0 87.0 94.0 74.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.00 stddev:23.74 (30.1%) stddev-sans-first:24.75 18:45:29 INFO - PID 18279 | Values: 82.0 80.0 126.0 81.0 78.0 79.0 133.0 79.0 79.0 127.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:45:29 INFO - PID 18279 | Values: 114.0 114.0 114.0 114.0 114.0 113.0 114.0 115.0 115.0 114.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:151.00 stddev:0.67 (0.4%) stddev-sans-first:0.67 18:45:29 INFO - PID 18279 | Values: 150.0 151.0 150.0 151.0 151.0 151.0 152.0 150.0 151.0 150.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 18:45:29 INFO - PID 18279 | Values: 94.0 95.0 94.0 94.0 94.0 95.0 95.0 93.0 95.0 94.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#8] json-parse-financial Cycles:10 Average:47.70 Median:46.50 stddev:2.87 (6.2%) stddev-sans-first:3.03 18:45:29 INFO - PID 18279 | Values: 47.0 53.0 50.0 46.0 46.0 46.0 52.0 45.0 46.0 46.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#9] json-stringify-tinderbox Cycles:10 Average:51.10 Median:51.00 stddev:0.57 (1.1%) stddev-sans-first:0.50 18:45:29 INFO - PID 18279 | Values: 52.0 51.0 51.0 51.0 52.0 50.0 51.0 51.0 51.0 51.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:73.00 stddev:1.96 (2.7%) stddev-sans-first:1.62 18:45:29 INFO - PID 18279 | Values: 77.0 72.0 74.0 73.0 77.0 72.0 73.0 73.0 72.0 72.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:122.50 stddev:13.38 (10.9%) stddev-sans-first:14.09 18:45:29 INFO - PID 18279 | Values: 120.0 113.0 122.0 110.0 143.0 122.0 126.0 123.0 113.0 152.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:168.50 stddev:1.96 (1.2%) stddev-sans-first:1.86 18:45:29 INFO - PID 18279 | Values: 165.0 169.0 170.0 168.0 169.0 164.0 167.0 168.0 166.0 169.0 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.00 stddev:2.16 (3.5%) stddev-sans-first:2.29 18:45:29 INFO - PID 18279 | Values: 61.0 60.0 60.0 60.0 60.0 62.0 67.0 61.0 60.0 62.0 18:45:29 INFO - PID 18279 | -------- Summary: end -------- 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | 18:45:29 INFO - PID 18279 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:45:29 INFO - PID 18279 | 18:45:30 INFO - PID 18279 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:45:30 INFO - TEST-INFO | 18279: exit 0 18:45:30 INFO - TEST-OK | kraken | took 60343ms 18:45:30 INFO - SUITE-END | took 1170s 18:45:30 INFO - Completed test suite (00:19:31) 18:45:30 INFO - javascript benchmark 18:45:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8852.679598454417, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3855.383138697809, 3900.2979463696524, 3902.7072969527285, 3909.7654140751556, 3920.6275403836644, 6024.427679370453, 6154.384561543855, 6175.536834737396, 6175.66033868137, 6194.659293738849, 6091.299500557438, 6167.845803854894, 6181.3200547939805, 6184.041473571592, 6184.69076546172, 5560.193771903072, 5578.386377498472, 5616.129499927518, 5631.211630371751, 5647.774089036434, 5617.662940223599, 5657.20799088128, 5676.063449530822, 5691.117876729093, 5704.942950570504, 5453.045716999509, 5509.173623956399, 5620.269364982567, 5688.658680479164, 5693.0, 5500.834974950758, 5606.579506536985, 5610.663360198401, 5621.550275977912, 5643.266375371176, 5528.723563821793, 5535.557155427556, 5589.217509548666, 5598.720063996825, 5613.045782216992, 6031.698415079272, 6067.18093057436, 6079.118527813486, 6130.969345153246, 6171.228596425447, 6541.738330466776, 6620.735170593171, 6678.398944095006, 6686.966565167143, 6689.531732778707, 6602.570832895846, 6681.298463661295, 6689.6989635466525, 6699.229588597325, 6702.463802895757, 6128.877422451526, 6133.0, 6234.501239900797, 6238.220222472191, 6245.344238854946, 6546.410823025951, 6620.5365624406795, 6621.834454138685, 6714.093597364391, 6800.251972283045, 6432.195975503062, 6514.0, 6516.218053833571, 6520.0, 6544.738210471528, 6651.201855777338, 6685.465162786965, 6733.360330768667, 6736.966315168393, 6757.526973111835, 6581.2760596334365, 6676.298988606223, 6678.198616166092, 6683.43190828785, 6698.933010669928, 6583.177102862142, 6585.73657053722, 6595.0766892635065, 6677.165354330708, 6689.0, 4946.505349465025, 4980.203167493184, 5024.447310795809, 5033.521815427529, 5069.986002799455, 4596.954030459653, 4652.046330502239, 4682.321063445781, 4689.20283551797, 4690.085433340466, 6211.534134939898, 6212.0, 6298.8425289367215, 6302.65335406557, 6325.873482530324, 6474.287828338879, 6616.702248398834, 6648.8670226595195, 6724.529282950147, 6732.663366831639, 6140.048292514667, 6146.877062458815, 6149.3235744068115, 6153.661548614869, 6202.255729312511, 5585.776568937278, 5594.748236329375, 5595.496405323541, 5596.944030559724, 5602.907433050516, 6923.9653801730665, 6965.0, 6973.12835895513, 7044.471664625071, 7063.788086357417, 6631.900521492278, 6649.73401063952, 6730.46156307504, 6733.730650773914, 6742.359475849885, 6837.760678376432, 6914.412274956484, 6976.19773726028, 6976.616286104313, 6993.370596646327, 6546.803595892131, 6621.768238111643, 6630.138082049302, 6659.067730517634, 6667.43326817226, 6736.225334086643, 6744.156980377453, 6745.5952642841585, 6759.019942108268, 6773.390394864486, 6427.582207156707, 6431.614103153825, 6520.869582608416, 6524.706388212637, 6529.706163222571, 6411.807645770635, 6416.807495775134, 6511.706973186123, 6514.73941042353, 6522.15212022434, 6320.462760665395, 6375.0, 6389.329120442287, 6404.967975160094, 6481.222253329631, 6327.620342779263, 6415.422611964946, 6434.871302574016, 6436.034594810816, 6494.220693516808, 6266.0, 6267.467265282502, 6292.685365731787, 6298.338674439118, 6303.212098487689], "value": 6122.434487420573, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [859.0226664844495, 921.8571121476169, 948.4356807699419, 975.5902520941204, 978.2907392140698, 40942.0, 41723.58276417235, 41738.0, 41745.79127104368, 41767.37348939761, 37394.439083413774, 37723.62276377236, 37761.0, 37800.621993780056, 37803.0, 7022.683979220935, 7129.89305160423, 7137.500374973754, 7140.214576396592, 7147.642617869112, 6987.755428560002, 7081.291870812929, 7087.043249161375, 7091.5390499617615, 7121.145462544502, 7526.322630963212, 7690.807729806772, 7691.346235569983, 7691.538507689528, 7694.384449244047, 7592.392608591327, 7627.61861906908, 7628.160902300742, 7662.348700360476, 7686.500377475474, 7425.591592462412, 7627.5042122261875, 7629.54222746637, 7633.198514155989, 7636.732714354971, 37030.259394811954, 37324.44013339802, 37335.25329493395, 37386.0, 37393.81303093494, 3574.4995700601935, 3627.7097832173363, 3630.310241054218, 3631.6186800385844, 3637.108908317453, 29726.25684357908, 29732.8513357434, 29740.70259297401, 30025.249368765737, 30084.24789380283, 5212.739363031833, 5234.0840352938085, 5248.0, 5265.131253343194, 5290.444503327172, 198981.0, 201065.0, 205614.0, 206585.0, 220378.89810551004, 51580.96838063218, 51806.0, 52028.47971520236, 52082.47917520852, 52129.47870521246, 53045.204321935205, 53080.73459632677, 53120.0, 53126.46873531293, 53147.46852531425, 15183.848161518465, 15307.61730956735, 15319.31063102163, 15319.92340038315, 15421.306041228172, 12375.566855160027, 12453.937730311472, 12461.0, 12463.127581069419, 12466.127371084118, 14924.0, 14941.850581494045, 14979.550613481613, 15001.774973375408, 15120.168390738396, 5816.79641212564, 5917.881642367129, 5920.822375328661, 5994.340622531518, 6020.4280593343565, 1028.2545154762738, 1046.0219694585587, 1061.4321338084087, 1067.3435836960214, 1070.7269646240159, 30117.849410752806, 31219.531707024413, 31288.68711312903, 31411.528827067614, 32920.341593168, 1117.4189421500773, 1136.9658910232706, 1136.9658910232706, 1143.5540139345662, 1159.3217967489113, 12610.495580176874, 13171.341432928315, 13293.335333233299, 13353.666158345924, 13446.46214151423, 272637.0, 273417.0, 274533.0, 275137.0, 276196.0, 55880.0, 56374.0, 56394.43605563974, 56527.0, 56550.151747723816, 1492.2389581313391, 1496.7156240314523, 1529.7246495630893, 1538.907665540071, 1542.105578764314, 23637.290881273588, 24118.39704007414, 24121.758782412653, 24167.395815104763, 24205.394865127808, 39397.2120557593, 39699.40450893297, 39740.40389394104, 39759.80120099381, 39779.60220397875, 245796.0, 246210.0, 246254.0, 247230.0, 248363.0, 28866.0, 31630.209244769067, 34482.31035379228, 34784.130396740286, 34836.303273934886, 44191.33712994243, 44384.55615443804, 44474.0, 44852.0, 45070.77464612787, 32341.676583233868, 32537.186570334983, 32633.673663264013, 33168.0, 33227.16932076621, 32522.674773251965, 32583.674163258063, 32642.51036234411, 32897.34205315928, 33599.3280134391, 35940.64059359373, 36558.0, 36673.81663091668, 36880.44679329758, 37036.6296337044, 31246.843765781025, 31866.20334491656, 31888.68111318857, 31890.36219275555, 32419.837900810344, 23219.88390058039, 24624.753752462962, 24957.87521062383, 26979.730202697723, 27095.72904270932], "value": 17711.833311068098, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.4657102467145, 566.3914482868657, 569.5614376929764, 575.7984705353126, 578.6701580099345, 23026.078956841728, 23664.881675591598, 23676.526469470602, 23689.644655330183, 23826.0, 25804.0, 26361.73638263617, 26403.339916502096, 26562.73437265627, 26605.334866628345, 6224.688765561715, 6383.106365108865, 6386.808395748112, 6387.361263873622, 6387.425131738142, 6036.486898613623, 6240.282367527724, 6241.0, 6253.28087269963, 6280.748770049193, 6196.81409557714, 6203.069539569078, 6210.09953556734, 6211.9068213976825, 6240.157578726882, 6197.81406557804, 6199.969000155015, 6200.472959798422, 6204.007358822567, 6218.31598524162, 5794.971025144847, 5797.043487824504, 5800.593958422912, 5806.970965145189, 5814.215080964058, 30321.0, 30835.074947751604, 31360.37279254402, 32036.359272814414, 32131.83934080338, 2216.900239489211, 2244.9551008979893, 2248.786365295294, 2250.3698964290024, 2250.763669814662, 19825.405237842886, 20308.593828123503, 20398.69401958972, 20420.285290014926, 20426.38720838377, 2328.5924963131383, 2349.295211436562, 2352.2002519143402, 2389.7371289158177, 2476.578981573137, 155236.0, 157726.0, 157929.0, 157993.0, 158094.20952895161, 14876.553703388916, 15545.378184872705, 15852.44516441919, 16050.518484445485, 16508.83491165073, 24652.383690407525, 24669.753302467103, 24706.629400559006, 24740.134095306577, 24750.752492475203, 10624.575016999388, 10810.189235807346, 10831.891681083245, 10859.40273284961, 10873.238873278953, 8493.660253589911, 8856.335774816915, 10028.398296102112, 10042.74893127663, 10136.54385552652, 9301.813963720688, 9726.221902247944, 9740.512974351253, 9833.459159746282, 10189.0, 2785.331520435081, 2844.33158207821, 2845.0, 2866.613007244037, 2880.1071667783053, 998.3011891675856, 1013.4729940430934, 1025.3386565665253, 1030.5414090729553, 1036.7563622548696, 21021.789782101983, 25112.74887251104, 27003.1899043029, 27389.589156162674, 27732.168034958984, 1022.6369638778275, 1089.515165751233, 1095.802755504001, 1095.8739744929276, 1104.1994553948323, 11944.044476441864, 12108.697282568006, 12181.147319687538, 12216.755664886652, 12297.938510307391, 644896.0, 657991.0, 663851.0, 668336.0, 671728.0, 137399.31300343433, 138251.0, 140276.0, 140542.0, 142112.0, 1272.4337669736879, 1325.1982550556968, 1339.437436276766, 1343.5029039255537, 1352.918824870511, 22865.0, 22986.54026919419, 22990.655140172577, 23089.307320780405, 23181.304560863202, 64444.67777661081, 65466.67266663827, 66275.66862165659, 67009.0, 68585.3141468579, 85401.14598853931, 86084.1391586076, 86965.0, 87220.0, 87528.56235719036, 13112.934435327763, 14613.85386146125, 15287.77068343998, 15592.298346574204, 15642.374305027672, 24731.50536989214, 24839.751602483742, 24864.37839054038, 25022.0, 25122.49755004853, 19602.50993725168, 19673.0, 19721.70417443711, 20062.899685501478, 20070.49823754418, 17204.91397543004, 17526.82473175303, 17777.0, 17958.371456998942, 18565.35021274249, 20069.899650501655, 20251.18995240174, 20474.488137796674, 21170.470738231666, 21617.351479455643, 15553.688926221186, 15599.376024958874, 15603.765943511084, 15762.133082680562, 15793.921030394775, 19802.0, 20260.89869550643, 20314.695279570522, 20367.694484582447, 20423.69364459564], "value": 12874.096499303203, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.7605107131787, 1460.3282490054573, 1466.9413223471063, 1467.6917847252078, 1476.0995792566532, 15631.218439078044, 15925.840741592581, 16233.350665973376, 16270.7559386609, 16285.755713664304, 16527.173641317917, 16763.580910477245, 16808.159592020413, 16834.0, 16849.241784119713, 2710.159850446363, 2759.8206116602355, 2761.903333383332, 2762.2541913683294, 2776.430831679511, 2763.405867738436, 2850.4156647887135, 2863.8568071596337, 2864.455753406846, 2864.4987127252753, 2739.205630367201, 2762.654668166479, 2774.3480282133683, 2789.734975177355, 2792.190264823199, 2773.459175460786, 2785.7492825645686, 2805.130409573039, 2805.4809860175833, 2805.5090359187266, 2625.18619228038, 2637.1956553251252, 2640.749128832758, 2644.9471010579873, 2656.3624730064644, 15728.449504267295, 16090.83909160905, 16114.274857631435, 16117.919410402874, 16122.919385403115, 8307.0862205158, 8583.914160858374, 8596.656133754643, 8602.483850968962, 8622.749701293278, 5569.192467092249, 5759.366469688331, 5771.307443106812, 5859.384764599698, 5859.619124756877, 1940.6409814184422, 1952.541152829084, 1952.8437724981968, 1979.3468155508647, 1997.850161237913, 28415.85792071047, 28646.283842904093, 28700.13899583016, 28703.56944645832, 28717.42565148706, 6964.79105626832, 7051.682674279669, 7093.89359159613, 7156.926461030783, 7261.237570055174, 18378.63242735138, 18411.355602553846, 18414.079296035146, 18423.907880460512, 18425.907870460564, 8012.27889489937, 8161.510309381338, 8167.305779008732, 8174.427790054758, 8177.836443271101, 7459.701611935462, 7478.326950574475, 7567.0, 7602.543847369065, 7611.543307401574, 5193.428722840485, 5403.675779453245, 5411.161270003077, 5452.345718513724, 5473.507384335349, 1535.616095976006, 1535.7773122897117, 1542.583502454331, 1555.574390858007, 1569.6939096876, 931.2363861633396, 967.3035414501547, 967.9981219437989, 974.1232890398697, 974.1914211204825, 8439.113893041265, 8464.873026904601, 8509.829803403898, 8548.743537693756, 8616.741497755078, 750.3996802557932, 769.2768797330604, 771.4753967302177, 781.1797612506845, 792.4928046050417, 5210.505002024802, 5906.704664766745, 5984.521238300926, 6008.909866352008, 6043.576949613484, 22136.0, 22572.774272257066, 23287.0, 23497.7650223499, 23752.049918003086, 21839.0, 22948.770512294996, 23372.883135584212, 23479.295621131394, 23492.76507234906, 11169.44152792357, 11642.70893227676, 11678.0657547396, 11682.0, 12299.508019679291, 9016.909830901606, 9200.585973631336, 9216.308776841897, 9246.121618446237, 9254.25965922738, 19543.12055957457, 19548.120334585263, 20211.595768084262, 20475.283365082152, 20683.069261883553, 20275.59448811045, 20441.0, 20492.07785649679, 20535.58928821445, 20609.0, 11491.253068550517, 12352.320622365856, 12735.936320318338, 12760.489580416679, 12776.425060872098, 14308.356123974238, 14341.92829035848, 14445.0, 14466.421343146156, 14529.709405812036, 11904.345261010727, 11966.22219555726, 12060.0, 12142.514299427923, 12144.271343719405, 11096.167787416138, 11310.377929213972, 11382.829257560978, 11514.712132196762, 11538.0, 10332.638357657446, 10562.630307939186, 10590.099841513558, 10674.252802303765, 10682.73293167677, 7170.207448137966, 7255.56466612005, 7982.920170798217, 7994.5203287802915, 8150.144234855255, 8335.666573337, 8506.574671266535, 8612.138786121337, 8666.826663466569, 8734.912650873664], "value": 7009.294626477541, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.13200257566, 523.3222976245761, 544.276112770016, 555.4084899581948, 557.5873853348522, 6116.847078823032, 6129.601575897569, 6166.6300021998595, 6171.889059969204, 6251.187345645071, 5918.348981612019, 6042.274927008754, 6056.545759068065, 6066.4236897494775, 6081.239845019372, 4918.385201849769, 4939.580135688462, 4944.826931057413, 4950.455449900508, 4952.5047495250365, 4907.1657818171, 4912.508749125094, 4922.827701030465, 4928.457869634337, 4935.555799978001, 4800.0, 4892.608591312686, 4903.215485522317, 4916.778744956468, 4924.0, 4892.510748925115, 4901.701049221959, 4912.606991440676, 4925.408950925894, 4938.851834444972, 4397.186520493687, 4397.494288156871, 4424.84513042042, 4428.424304840382, 4430.224710675619, 6022.096685497167, 6036.336003039706, 6061.0, 6066.575339726177, 6109.969450152766, 5703.885922281531, 5708.77164913403, 5713.771449142029, 5717.199592057115, 5728.570357223183, 6446.9355306446805, 6449.4840412767335, 6449.967750161266, 6508.54440189187, 6514.120593719882, 4846.29728689338, 4846.61227101831, 4858.076965376603, 4870.0, 4879.63402744792, 6730.125083739083, 6758.391744742948, 6772.35662612056, 6782.287859774751, 6804.285550017276, 5013.66591216844, 5017.67385119966, 5023.09584274834, 5027.120253955543, 5040.823571174991, 5078.070333273333, 5089.262057001788, 5092.490750924952, 5092.777733343971, 5110.0, 5636.520895723909, 5652.682400215892, 5673.631213971161, 5726.226959360516, 5729.8567535811935, 6457.225132983978, 6463.353664633499, 6474.802161600119, 6487.437462202403, 6520.771772987922, 1676.2624445244053, 1710.45265515034, 1739.4607671621839, 1744.337151882302, 1758.9560260993321, 1188.8811118888216, 1220.8473940757406, 1227.147132742753, 1242.7328124453286, 1258.7608354412632, 5954.851128721816, 5987.520998320124, 6003.219581454383, 6027.011086341497, 6074.0, 6389.904151437732, 6407.967960160169, 6469.159009328851, 6551.80344589663, 6556.377144171296, 5955.67243801595, 5994.250718660168, 6018.608790428608, 6159.599626024294, 6174.197354343996, 3430.2624935638955, 3474.774139680913, 3483.686468217822, 3495.580530336376, 3514.3674138655288, 6785.355391237825, 6809.706155830377, 6855.4917918057945, 6945.965270173718, 6947.513674042769, 6693.765718199839, 6720.798376048726, 6723.529352945345, 6817.931820681829, 6825.897611535831, 6858.554193977276, 6862.519623626298, 6921.958022383409, 6979.581225126509, 6997.0, 6572.80281591553, 6627.867442651023, 6641.568298060611, 6662.800115996528, 6694.933050669431, 6484.254310754323, 6545.639989800607, 6567.343265673395, 6588.604683718992, 6594.835129121619, 6972.6403351346, 6980.616066116209, 6986.266442023514, 7016.22821489646, 7029.402500787491, 4977.427595826526, 5029.497050294941, 5034.256330300069, 5041.16820724591, 5045.747712614428, 6619.867602647823, 6635.668216589054, 6635.668216589248, 6636.369544893228, 6646.1452357633425, 6355.777547785805, 6398.176519692051, 6428.457170279005, 6431.842268391734, 6452.419282264431, 6290.175849003921, 6310.936890631218, 6310.968445157745, 6317.526185536195, 6353.0, 6137.907931380943, 6282.183316168869, 6288.0, 6316.684165791784, 6332.905006425, 6154.522914500577, 6316.6841657916, 6349.8730025400155, 6359.745610175726, 6480.287168411565, 6151.630902145886, 6187.752489900354, 6220.502359811295, 6229.408206220402, 6263.592866463665], "value": 5047.299696928967, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [911.5351170902842, 941.0569712902936, 953.1040821627673, 956.077385323163, 969.5249327829364, 18862.339818106368, 19852.0, 19887.105080271387, 19894.50263743407, 22206.555868882653, 21153.788462115375, 21437.57124857502, 21954.121835126574, 22023.22918697846, 22054.33836984889, 1003.3077176748038, 1029.7683021320217, 1032.654060889603, 1033.3851358441716, 1036.1710631494811, 1020.209337763232, 1033.4987531047436, 1078.2344535379889, 1080.1952545353683, 1084.598698481563, 1072.3940973350032, 1081.2269227502334, 1084.7776205877815, 1087.7171935296801, 1088.5319312695538, 1076.8869268726748, 1079.023483747206, 1084.5932775209296, 1085.1915323084274, 1085.766560189555, 804.5333706450303, 804.9235322644339, 805.2712295372697, 805.3074356053789, 805.5609692717483, 19937.401877943685, 20461.590768184702, 20719.27482538119, 20912.895435522878, 20950.37148885536, 22406.32781016572, 22413.103475860946, 22767.316980490446, 22878.42803929915, 23066.42333941648, 17522.335378139058, 17855.46433606981, 17856.642867142586, 17918.552036199068, 17989.7301540477, 3844.9807750961068, 3862.1503269280715, 3873.9806300968316, 3877.418387241908, 3881.825317860703, 144498.0, 146305.26847365696, 146767.99999999892, 147839.0, 147969.2601536996, 17126.0, 17188.742168867477, 17396.39112631052, 17440.0, 17534.473965781046, 29253.85373073121, 29733.405331893242, 30007.099787006424, 30050.0, 30337.696623033487, 7673.731419400406, 7990.520568765893, 8429.915700843036, 8496.66013359471, 8724.956375218084, 4095.2014357200655, 4147.958520414818, 4154.5222299435345, 4159.147374788161, 4175.62419382257, 5451.672899625956, 5544.417836127229, 5544.584156188302, 5588.469095435927, 5600.215969764236, 1116.20749268019, 1135.0975974100609, 1150.1833698074295, 1150.3098141115431, 1155.6764106050318, 707.3351050012972, 721.2066726600697, 722.0901663903519, 726.4333819620705, 750.7372419653078, 12647.424347539782, 12700.616205095508, 13164.40760165795, 13304.733905322033, 13911.930440347733, 729.0740759235741, 733.6771820399007, 736.9078865141857, 739.9112106547141, 741.907261592301, 5961.0, 6092.0, 6156.107364432132, 6161.075838624242, 6234.189555357865, 124689.37655311846, 125667.854739414, 128771.71228287785, 129648.0, 131848.0, 110224.4488777567, 110397.0, 111372.44313778379, 114585.0, 120558.0, 99434.50282748685, 100909.0, 102236.0, 102629.0, 102726.48636756917, 14168.574942751733, 14201.786973195616, 14290.928545357207, 14369.64075898111, 14621.780673290123, 46726.766366167954, 47679.0, 47807.5219247803, 48755.75622121867, 49993.50006499889, 59489.405105950114, 60197.0, 60338.69830650819, 60719.0, 60776.69611651913, 19022.524436889187, 19427.805721943165, 19499.902500487406, 19626.90186549058, 19649.410517684493, 20478.0, 20496.897515512326, 20586.588268234253, 20707.17171313191, 20717.585648286647, 18932.526686832938, 19116.617667646846, 19139.617207656047, 19190.904045479685, 19335.226590936207, 19327.806721933164, 19330.903345483184, 19406.0, 19488.902555487133, 19805.40583782489, 18541.44375668732, 18653.347132850286, 18828.717569236745, 18863.811361886204, 19017.809821902156, 14661.413543458142, 14842.851571484578, 14978.625534361729, 15095.54713358601, 15110.0, 14601.342939567532, 15496.380144794082, 15986.920065399598, 16054.839451605334, 16139.193040348171], "value": 9745.031358351394, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1474.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 108.0, 98.0, 107.0, 108.0, 108.0, 107.0, 98.0, 106.0, 110.0], "value": 104.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 146.0, 180.0, 122.0, 142.0, 145.0, 115.0, 165.0, 120.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 160.0, 161.0, 162.0, 163.0, 164.0, 163.0, 168.0, 166.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 173.0, 74.0, 74.0, 86.0, 87.0, 94.0, 74.0], "value": 88.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 126.0, 81.0, 78.0, 79.0, 133.0, 79.0, 79.0, 127.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 115.0, 115.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 150.0, 151.0, 151.0, 151.0, 152.0, 150.0, 151.0, 150.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 93.0, 95.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 53.0, 50.0, 46.0, 46.0, 46.0, 52.0, 45.0, 46.0, 46.0], "value": 47.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 51.0, 52.0, 50.0, 51.0, 51.0, 51.0, 51.0], "value": 51.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 74.0, 73.0, 77.0, 72.0, 73.0, 73.0, 72.0, 72.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 113.0, 122.0, 110.0, 143.0, 122.0, 126.0, 123.0, 113.0, 152.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 170.0, 168.0, 169.0, 164.0, 167.0, 168.0, 166.0, 169.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 60.0, 60.0, 62.0, 67.0, 61.0, 60.0, 62.0], "value": 61.3, "unit": "score"}]}]} 18:45:30 INFO - Return code: 0 18:45:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:45:30 INFO - # TBPL SUCCESS # 18:45:30 INFO - Running post-action listener: _package_coverage_data 18:45:30 INFO - Running post-action listener: _resource_record_post_action 18:45:30 INFO - [mozharness: 2017-08-25 01:45:30.868863Z] Finished run-tests step (success) 18:45:30 INFO - Running post-run listener: _resource_record_post_run 18:45:30 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 12288; Write bytes: 426147840; Read time: 284; Write time: 352160 18:45:30 INFO - TinderboxPrint: CPU usage
13.1% 18:45:30 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 284 18:45:30 INFO - TinderboxPrint: I/O write bytes / time
426,147,840 / 352,160 18:45:30 INFO - TinderboxPrint: CPU idle
8,239.4 (86.9%) 18:45:30 INFO - TinderboxPrint: CPU user
1,190.5 (12.6%) 18:45:30 INFO - TinderboxPrint: Swap in / out
0 / 0 18:45:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106303488; Read time: 0; Write time: 121584 18:45:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:45:30 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 12288; Write bytes: 319844352; Read time: 284; Write time: 230576 18:45:31 INFO - Running post-run listener: _upload_blobber_files 18:45:31 INFO - Blob upload gear active. 18:45:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:45:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:45:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:45:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:45:31 INFO - (blobuploader) - INFO - Open directory for files ... 18:45:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:45:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:45:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:32 INFO - (blobuploader) - INFO - Done attempting. 18:45:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:45:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:45:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:33 INFO - (blobuploader) - INFO - Done attempting. 18:45:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:45:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:45:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:37 INFO - (blobuploader) - INFO - Done attempting. 18:45:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:45:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:45:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:38 INFO - (blobuploader) - INFO - Done attempting. 18:45:38 INFO - (blobuploader) - INFO - Iteration through files over. 18:45:38 INFO - Return code: 0 18:45:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:45:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:45:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ed3445bc520461c93e4d009875dc631fd24e88919147c9164e7ba66472b67af2151c25e9549e9ac75c363af47d712f55bd5511f0ee846ef90620cc391e15cb1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa5163b9a1766a456b68aaa0c2dc4e41d44752a1f90d1c65a41b2de12c8717305775981fe8e097816b7e3a169487b9df50a0cb5db2eaf4c80a6ff0166fa6c19", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/142b6e26d92f76dfc7c19b6b91921a5048df2dbfb5e88f29d86dbeafa0b89d71b15653e3e3e6f0bac9fe54a20b1d17b8db2822f29b00f2550559b896eaa04840", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a78ff9eb692d793561e4d13432af5dd5ead2c1f76aabd4156bbaaddf81dba268458579be03759b3a2d698574053fb33b81835adb316a73153e09ae3bfb17df4a"} 18:45:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:45:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:45:38 INFO - Contents: 18:45:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ed3445bc520461c93e4d009875dc631fd24e88919147c9164e7ba66472b67af2151c25e9549e9ac75c363af47d712f55bd5511f0ee846ef90620cc391e15cb1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa5163b9a1766a456b68aaa0c2dc4e41d44752a1f90d1c65a41b2de12c8717305775981fe8e097816b7e3a169487b9df50a0cb5db2eaf4c80a6ff0166fa6c19", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/142b6e26d92f76dfc7c19b6b91921a5048df2dbfb5e88f29d86dbeafa0b89d71b15653e3e3e6f0bac9fe54a20b1d17b8db2822f29b00f2550559b896eaa04840", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a78ff9eb692d793561e4d13432af5dd5ead2c1f76aabd4156bbaaddf81dba268458579be03759b3a2d698574053fb33b81835adb316a73153e09ae3bfb17df4a"} 18:45:38 INFO - Running post-run listener: copy_logs_to_upload_dir 18:45:38 INFO - Copying logs to upload dir... 18:45:38 INFO - mkdir: /builds/slave/test/build/upload/logs 18:45:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.298230 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-08-24 18:45:38.393849) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:45:38.397000) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ed3445bc520461c93e4d009875dc631fd24e88919147c9164e7ba66472b67af2151c25e9549e9ac75c363af47d712f55bd5511f0ee846ef90620cc391e15cb1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa5163b9a1766a456b68aaa0c2dc4e41d44752a1f90d1c65a41b2de12c8717305775981fe8e097816b7e3a169487b9df50a0cb5db2eaf4c80a6ff0166fa6c19", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/142b6e26d92f76dfc7c19b6b91921a5048df2dbfb5e88f29d86dbeafa0b89d71b15653e3e3e6f0bac9fe54a20b1d17b8db2822f29b00f2550559b896eaa04840", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a78ff9eb692d793561e4d13432af5dd5ead2c1f76aabd4156bbaaddf81dba268458579be03759b3a2d698574053fb33b81835adb316a73153e09ae3bfb17df4a"} build_url:https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011388 build_url: 'https://queue.taskcluster.net/v1/task/TjzgtrtbQIeWPDtRo_N7qA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ed3445bc520461c93e4d009875dc631fd24e88919147c9164e7ba66472b67af2151c25e9549e9ac75c363af47d712f55bd5511f0ee846ef90620cc391e15cb1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa5163b9a1766a456b68aaa0c2dc4e41d44752a1f90d1c65a41b2de12c8717305775981fe8e097816b7e3a169487b9df50a0cb5db2eaf4c80a6ff0166fa6c19", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/142b6e26d92f76dfc7c19b6b91921a5048df2dbfb5e88f29d86dbeafa0b89d71b15653e3e3e6f0bac9fe54a20b1d17b8db2822f29b00f2550559b896eaa04840", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a78ff9eb692d793561e4d13432af5dd5ead2c1f76aabd4156bbaaddf81dba268458579be03759b3a2d698574053fb33b81835adb316a73153e09ae3bfb17df4a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:45:38.423585) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:45:38.423931) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1503624288.842859-1535059592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:45:38.506485) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:45:38.509858) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:45:38.510173) ========= ========= Total master_lag: 0.36 =========