builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-020 starttime: 1507120864.8 results: success (0) revision: 933b30269777f718a14d2fc379e563a051b14618 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:04.803528) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:04.804035) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:04.804301) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:04.822572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:04.822894) ========= 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=af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-04 05:41:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2017-10-04 05:41:04 (19.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146137 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:04.986439) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:04.986754) ========= 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=af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020738 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:05.039647) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:05.039970) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 933b30269777f718a14d2fc379e563a051b14618 --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 933b30269777f718a14d2fc379e563a051b14618 --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=af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-04 05:41:05,090 truncating revision to first 12 chars 2017-10-04 05:41:05,090 Setting DEBUG logging. 2017-10-04 05:41:05,090 attempt 1/10 2017-10-04 05:41:05,090 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/933b30269777?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-04 05:41:05,600 unpacking tar archive at: mozilla-central-933b30269777/testing/mozharness/ program finished with exit code 0 elapsedTime=0.832726 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:05.889418) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:05.892745) ========= script_repo_revision: 933b30269777f718a14d2fc379e563a051b14618 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:05.893098) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:05.893353) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-04 05:41:05.907717) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-10-04 05:41:05.907979) ========= /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=af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:41:06 INFO - MultiFileLogger online at 20171004 05:41:06 in /builds/slave/test 05:41:06 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 05:41:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:41:06 INFO - {'append_to_log': False, 05:41:06 INFO - 'base_work_dir': '/builds/slave/test', 05:41:06 INFO - 'blob_upload_branch': 'Firefox', 05:41:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:41:06 INFO - 'branch': 'Firefox', 05:41:06 INFO - 'buildbot_json_path': 'buildprops.json', 05:41:06 INFO - 'code_coverage': False, 05:41:06 INFO - 'config_files': ('talos/linux_config.py',), 05:41:06 INFO - 'default_actions': ('clobber', 05:41:06 INFO - 'read-buildbot-config', 05:41:06 INFO - 'download-and-extract', 05:41:06 INFO - 'populate-webroot', 05:41:06 INFO - 'create-virtualenv', 05:41:06 INFO - 'install', 05:41:06 INFO - 'setup-mitmproxy', 05:41:06 INFO - 'run-tests'), 05:41:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:41:06 INFO - 'disable_ccov_upload': False, 05:41:06 INFO - 'disable_stylo': False, 05:41:06 INFO - 'download_minidump_stackwalk': True, 05:41:06 INFO - 'download_symbols': 'ondemand', 05:41:06 INFO - 'enable_stylo': False, 05:41:06 INFO - 'enable_webrender': False, 05:41:06 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:41:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:41:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:41:06 INFO - 'gecko_profile': False, 05:41:06 INFO - 'gecko_profile_interval': 0, 05:41:06 INFO - 'installer_path': 'installer.exe', 05:41:06 INFO - 'log_level': 'info', 05:41:06 INFO - 'log_name': 'talos', 05:41:06 INFO - 'log_to_console': True, 05:41:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:41:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:41:06 INFO - 'opt_config_files': (), 05:41:06 INFO - 'pip_index': False, 05:41:06 INFO - 'suite': 'dromaeojs-e10s', 05:41:06 INFO - 'system_bits': '32', 05:41:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:41:06 INFO - 'title': 'talos-linux64-ix-020', 05:41:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:41:06 INFO - 'use_talos_json': True, 05:41:06 INFO - 'verify': 'False', 05:41:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:41:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:41:06 INFO - 'work_dir': 'build'} 05:41:06 INFO - [mozharness: 2017-10-04 12:41:06.102642Z] Running clobber step. 05:41:06 INFO - Running pre-action listener: _resource_record_pre_action 05:41:06 INFO - Running main action method: clobber 05:41:06 INFO - rmtree: /builds/slave/test/build 05:41:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:41:07 INFO - Running post-action listener: _resource_record_post_action 05:41:07 INFO - [mozharness: 2017-10-04 12:41:07.622761Z] Finished clobber step (success) 05:41:07 INFO - [mozharness: 2017-10-04 12:41:07.622859Z] Running read-buildbot-config step. 05:41:07 INFO - Running pre-action listener: _resource_record_pre_action 05:41:07 INFO - Running main action method: read_buildbot_config 05:41:07 INFO - Using buildbot properties: 05:41:07 INFO - { 05:41:07 INFO - "project": "", 05:41:07 INFO - "product": "firefox", 05:41:07 INFO - "who": "archaeopteryx@coole-files.de", 05:41:07 INFO - "installer_path": "public/build/target.tar.bz2", 05:41:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:41:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:41:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 05:41:07 INFO - "stage_platform": "linux64-pgo", 05:41:07 INFO - "basedir": "/builds/slave/test", 05:41:07 INFO - "buildnumber": 229, 05:41:07 INFO - "platform": "ubuntu64_hw", 05:41:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:41:07 INFO - "slavebuilddir": "test", 05:41:07 INFO - "taskId": "JN-FS_UlQfa5ZEQWClISdA", 05:41:07 INFO - "branch": "mozilla-central", 05:41:07 INFO - "script_repo_revision": "production", 05:41:07 INFO - "revision": "933b30269777f718a14d2fc379e563a051b14618", 05:41:07 INFO - "slavename": "talos-linux64-ix-020", 05:41:07 INFO - "repo_path": "mozilla-central" 05:41:07 INFO - } 05:41:07 INFO - Finding installer, test and symbols from parent task. 05:41:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JN-FS_UlQfa5ZEQWClISdA'}, attempt #1 05:41:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JN-FS_UlQfa5ZEQWClISdA'}, attempt #1 05:41:08 INFO - Task dependencies: E6uNJ6CDSdC-LmwGg1i0Wg 05:41:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg'}, attempt #1 05:41:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.tar.bz2 05:41:08 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.test_packages.json 05:41:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.crashreporter-symbols.zip 05:41:08 INFO - Running post-action listener: _resource_record_post_action 05:41:08 INFO - [mozharness: 2017-10-04 12:41:08.977891Z] Finished read-buildbot-config step (success) 05:41:08 INFO - [mozharness: 2017-10-04 12:41:08.978244Z] Running download-and-extract step. 05:41:08 INFO - Running pre-action listener: _resource_record_pre_action 05:41:08 INFO - Running main action method: download_and_extract 05:41:08 INFO - mkdir: /builds/slave/test/build/tests 05:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:08 INFO - trying https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.test_packages.json 05:41:08 INFO - Downloading https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:41:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:41:09 INFO - Downloaded 989 bytes. 05:41:09 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:41:09 INFO - Using the following test package requirements: 05:41:09 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:41:09 INFO - u'common': [u'target.common.tests.zip'], 05:41:09 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:41:09 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:41:09 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:41:09 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:41:09 INFO - u'mozbase': [u'target.common.tests.zip'], 05:41:09 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:41:09 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:41:09 INFO - u'web-platform': [u'target.common.tests.zip', 05:41:09 INFO - u'target.web-platform.tests.tar.gz'], 05:41:09 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:41:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 05:41:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.common.tests.zip 05:41:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:41:09 INFO - Fetch https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.common.tests.zip into memory 05:41:10 INFO - Content-Length response header: 55882119 05:41:10 INFO - Bytes received: 55882119 05:41:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.talos.tests.zip 05:41:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:41:21 INFO - Fetch https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.talos.tests.zip into memory 05:41:22 INFO - Content-Length response header: 14533844 05:41:22 INFO - Bytes received: 14533844 05:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:22 INFO - trying https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.tar.bz2 05:41:22 INFO - Downloading https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:41:23 INFO - Downloaded 62351745 bytes. 05:41:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.tar.bz2 05:41:23 INFO - mkdir: /builds/slave/test/properties 05:41:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:41:23 INFO - Writing to file /builds/slave/test/properties/build_url 05:41:23 INFO - Contents: 05:41:23 INFO - build_url:https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.tar.bz2 05:41:23 INFO - Running post-action listener: _resource_record_post_action 05:41:23 INFO - Running post-action listener: find_tests_for_verification 05:41:23 INFO - Running post-action listener: set_extra_try_arguments 05:41:23 INFO - [mozharness: 2017-10-04 12:41:23.753102Z] Finished download-and-extract step (success) 05:41:23 INFO - [mozharness: 2017-10-04 12:41:23.753201Z] Running populate-webroot step. 05:41:23 INFO - Running pre-action listener: _resource_record_pre_action 05:41:23 INFO - Running main action method: populate_webroot 05:41:23 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:41:23 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['tresize', 05:41:23 INFO - 'tcanvasmark']}, 05:41:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:41:23 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['dromaeo_css', 05:41:23 INFO - 'kraken']}, 05:41:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:41:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:23 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:41:23 INFO - 'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:41:23 INFO - 'tests': ['damp', 'tps']}, 05:41:23 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:41:23 INFO - 'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['damp', 'tps']}, 05:41:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:41:23 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['dromaeo_dom']}, 05:41:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:41:23 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['basic_compositor_video', 05:41:23 INFO - 'glvideo']}, 05:41:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:41:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:41:23 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:41:23 INFO - 'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['ts_paint_webext', 05:41:23 INFO - 'tp5o_webext']}, 05:41:23 INFO - 'other-e10s': {'tests': ['a11yr', 05:41:23 INFO - 'ts_paint', 05:41:23 INFO - 'tpaint', 05:41:23 INFO - 'sessionrestore', 05:41:23 INFO - 'sessionrestore_many_windows', 05:41:23 INFO - 'sessionrestore_no_auto_restore', 05:41:23 INFO - 'tabpaint', 05:41:23 INFO - 'cpstartup']}, 05:41:23 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['a11yr', 05:41:23 INFO - 'ts_paint', 05:41:23 INFO - 'tpaint', 05:41:23 INFO - 'sessionrestore', 05:41:23 INFO - 'sessionrestore_many_windows', 05:41:23 INFO - 'sessionrestore_no_auto_restore', 05:41:23 INFO - 'tabpaint', 05:41:23 INFO - 'cpstartup']}, 05:41:23 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 05:41:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 05:41:23 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['perf_reftest_singletons']}, 05:41:23 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['perf_reftest']}, 05:41:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:41:23 INFO - 'tsvgr_opacity', 05:41:23 INFO - 'tart', 05:41:23 INFO - 'tscrollx', 05:41:23 INFO - 'tsvg_static']}, 05:41:23 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['tsvgx', 05:41:23 INFO - 'tsvgr_opacity', 05:41:23 INFO - 'tart', 05:41:23 INFO - 'tscrollx', 05:41:23 INFO - 'tsvg_static']}, 05:41:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:41:23 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:41:23 INFO - 'talos_options': ['--disable-stylo'], 05:41:23 INFO - 'tests': ['tp5o']}, 05:41:23 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:41:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 05:41:23 INFO - 'talos_options': ['--mitmproxy', 05:41:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:41:23 INFO - '--firstNonBlankPaint'], 05:41:23 INFO - 'tests': ['tp6_google', 05:41:23 INFO - 'tp6_youtube', 05:41:23 INFO - 'tp6_amazon', 05:41:23 INFO - 'tp6_facebook']}, 05:41:23 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:41:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 05:41:23 INFO - 'talos_options': ['--disable-stylo', 05:41:23 INFO - '--mitmproxy', 05:41:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:41:23 INFO - '--firstNonBlankPaint'], 05:41:23 INFO - 'tests': ['tp6_google', 05:41:23 INFO - 'tp6_youtube', 05:41:23 INFO - 'tp6_amazon', 05:41:23 INFO - 'tp6_facebook']}, 05:41:23 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:41:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 05:41:23 INFO - 'talos_options': ['--stylo-threads=1', 05:41:23 INFO - '--mitmproxy', 05:41:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:41:23 INFO - '--firstNonBlankPaint'], 05:41:23 INFO - 'tests': ['tp6_google', 05:41:23 INFO - 'tp6_youtube', 05:41:23 INFO - 'tp6_amazon', 05:41:23 INFO - 'tp6_facebook']}, 05:41:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:41:23 INFO - 'talos_options': ['--xperf_path', 05:41:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:23 INFO - 'tests': ['tp5n']}, 05:41:23 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:41:23 INFO - 'talos_options': ['--disable-stylo', 05:41:23 INFO - '--xperf_path', 05:41:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:23 INFO - 'tests': ['tp5n']}}} 05:41:23 INFO - Running post-action listener: _resource_record_post_action 05:41:23 INFO - [mozharness: 2017-10-04 12:41:23.771428Z] Finished populate-webroot step (success) 05:41:23 INFO - [mozharness: 2017-10-04 12:41:23.771518Z] Running create-virtualenv step. 05:41:23 INFO - Running pre-action listener: _resource_record_pre_action 05:41:23 INFO - Running main action method: create_virtualenv 05:41:23 INFO - Creating virtualenv /builds/slave/test/build/venv 05:41:23 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:41:23 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:41:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:41:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:41:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:41:24 INFO - Installing distribute.............................................................................................................................................................................................done. 05:41:24 INFO - Installing pip...............done. 05:41:24 INFO - Return code: 0 05:41:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:41:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:41:24 INFO - Reading from file tmpfile_stdout 05:41:24 INFO - Output received: 05:41:24 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 05:41:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41: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')]} 05:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41: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', 'pip>=1.5'] in /builds/slave/test/build 05:41: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 pip>=1.5 05:41:24 INFO - Using env: {'DISPLAY': ':0', 05:41:24 INFO - 'HOME': '/home/cltbld', 05:41:24 INFO - 'LANG': 'en_US.UTF-8', 05:41:24 INFO - 'LANGUAGE': 'en_US:en', 05:41:24 INFO - 'LOGNAME': 'cltbld', 05:41:24 INFO - 'MAIL': '/var/mail/cltbld', 05:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:24 INFO - 'MOZ_NO_REMOTE': '1', 05:41:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:24 INFO - 'NO_EM_RESTART': '1', 05:41:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:24 INFO - 'PWD': '/builds/slave/test', 05:41:24 INFO - 'SHELL': '/bin/bash', 05:41:24 INFO - 'SHLVL': '1', 05:41:24 INFO - 'TERM': 'linux', 05:41:24 INFO - 'TMOUT': '86400', 05:41:24 INFO - 'USER': 'cltbld', 05:41:24 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:24 INFO - '_': '/tools/buildbot/bin/python'} 05:41:27 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:41:27 INFO - Downloading/unpacking pip>=1.5 05:41:27 INFO - Running setup.py egg_info for package pip 05:41:27 INFO - warning: no files found matching 'pip/cacert.pem' 05:41:27 INFO - warning: no files found matching '*.html' under directory 'docs' 05:41:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:41:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:41:27 INFO - Installing collected packages: pip 05:41:27 INFO - Found existing installation: pip 1.1 05:41:27 INFO - Uninstalling pip: 05:41:27 INFO - Successfully uninstalled pip 05:41:27 INFO - Running setup.py install for pip 05:41:27 INFO - warning: no files found matching 'pip/cacert.pem' 05:41:27 INFO - warning: no files found matching '*.html' under directory 'docs' 05:41:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:41:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:41:27 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:41:27 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:41:27 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:41:27 INFO - Successfully installed pip 05:41:27 INFO - Cleaning up... 05:41:27 INFO - Return code: 0 05:41:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:27 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 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41:27 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 05:41:27 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 05:41:27 INFO - Using env: {'DISPLAY': ':0', 05:41:27 INFO - 'HOME': '/home/cltbld', 05:41:27 INFO - 'LANG': 'en_US.UTF-8', 05:41:27 INFO - 'LANGUAGE': 'en_US:en', 05:41:27 INFO - 'LOGNAME': 'cltbld', 05:41:27 INFO - 'MAIL': '/var/mail/cltbld', 05:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:27 INFO - 'MOZ_NO_REMOTE': '1', 05:41:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:27 INFO - 'NO_EM_RESTART': '1', 05:41:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:27 INFO - 'PWD': '/builds/slave/test', 05:41:27 INFO - 'SHELL': '/bin/bash', 05:41:27 INFO - 'SHLVL': '1', 05:41:27 INFO - 'TERM': 'linux', 05:41:27 INFO - 'TMOUT': '86400', 05:41:27 INFO - 'USER': 'cltbld', 05:41:27 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:27 INFO - '_': '/tools/buildbot/bin/python'} 05:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:27 INFO - Downloading/unpacking psutil>=3.1.1 05:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:41:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:28 INFO - Installing collected packages: psutil 05:41:28 INFO - Running setup.py install for psutil 05:41:28 INFO - building 'psutil._psutil_linux' extension 05:41:28 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 05:41:28 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 05:41:29 INFO - building 'psutil._psutil_posix' extension 05:41:29 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 05:41:29 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 05:41:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:29 INFO - Successfully installed psutil 05:41:29 INFO - Cleaning up... 05:41:29 INFO - Return code: 0 05:41:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41: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')]} 05:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:41: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 mozsystemmonitor==0.3 05:41:29 INFO - Using env: {'DISPLAY': ':0', 05:41:29 INFO - 'HOME': '/home/cltbld', 05:41:29 INFO - 'LANG': 'en_US.UTF-8', 05:41:29 INFO - 'LANGUAGE': 'en_US:en', 05:41:29 INFO - 'LOGNAME': 'cltbld', 05:41:29 INFO - 'MAIL': '/var/mail/cltbld', 05:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:29 INFO - 'MOZ_NO_REMOTE': '1', 05:41:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:29 INFO - 'NO_EM_RESTART': '1', 05:41:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:29 INFO - 'PWD': '/builds/slave/test', 05:41:29 INFO - 'SHELL': '/bin/bash', 05:41:29 INFO - 'SHLVL': '1', 05:41:29 INFO - 'TERM': 'linux', 05:41:29 INFO - 'TMOUT': '86400', 05:41:29 INFO - 'USER': 'cltbld', 05:41:29 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:29 INFO - '_': '/tools/buildbot/bin/python'} 05:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:41: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 05:41: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 05:41: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 05:41: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 05:41:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:41:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:41:30 INFO - Installing collected packages: mozsystemmonitor 05:41:30 INFO - Running setup.py install for mozsystemmonitor 05:41:31 INFO - Successfully installed mozsystemmonitor 05:41:31 INFO - Cleaning up... 05:41:33 INFO - Return code: 0 05:41:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:41:33 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')]} 05:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:33 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')]} 05:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:33 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 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41:33 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 05:41:33 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 05:41:33 INFO - Using env: {'DISPLAY': ':0', 05:41:33 INFO - 'HOME': '/home/cltbld', 05:41:33 INFO - 'LANG': 'en_US.UTF-8', 05:41:33 INFO - 'LANGUAGE': 'en_US:en', 05:41:33 INFO - 'LOGNAME': 'cltbld', 05:41:33 INFO - 'MAIL': '/var/mail/cltbld', 05:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:33 INFO - 'MOZ_NO_REMOTE': '1', 05:41:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:33 INFO - 'NO_EM_RESTART': '1', 05:41:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:33 INFO - 'PWD': '/builds/slave/test', 05:41:33 INFO - 'SHELL': '/bin/bash', 05:41:33 INFO - 'SHLVL': '1', 05:41:33 INFO - 'TERM': 'linux', 05:41:33 INFO - 'TMOUT': '86400', 05:41:33 INFO - 'USER': 'cltbld', 05:41:33 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:33 INFO - '_': '/tools/buildbot/bin/python'} 05:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:34 INFO - Downloading/unpacking jsonschema==2.5.1 05:41:34 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 05:41:34 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 05:41:34 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 05:41:34 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 05:41:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:41:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:41:35 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 05:41:35 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 05:41:35 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 05:41:35 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 05:41:35 INFO - Downloading functools32-3.2.3-2.tar.gz 05:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:41:35 INFO - warning: no files found matching '*.txt' 05:41:35 INFO - no previously-included directories found matching 'build' 05:41:35 INFO - no previously-included directories found matching 'dist' 05:41:35 INFO - no previously-included directories found matching '.git*' 05:41:35 INFO - Installing collected packages: jsonschema, functools32 05:41:36 INFO - Running setup.py install for functools32 05:41:38 INFO - warning: no files found matching '*.txt' 05:41:38 INFO - no previously-included directories found matching 'build' 05:41:38 INFO - no previously-included directories found matching 'dist' 05:41:38 INFO - no previously-included directories found matching '.git*' 05:41:38 INFO - Successfully installed jsonschema functools32 05:41:38 INFO - Cleaning up... 05:41:38 INFO - Return code: 0 05:41:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41: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')]} 05:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:38 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 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41:38 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 05:41:38 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 05:41:38 INFO - Using env: {'DISPLAY': ':0', 05:41:38 INFO - 'HOME': '/home/cltbld', 05:41:38 INFO - 'LANG': 'en_US.UTF-8', 05:41:38 INFO - 'LANGUAGE': 'en_US:en', 05:41:38 INFO - 'LOGNAME': 'cltbld', 05:41:38 INFO - 'MAIL': '/var/mail/cltbld', 05:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:38 INFO - 'MOZ_NO_REMOTE': '1', 05:41:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:38 INFO - 'NO_EM_RESTART': '1', 05:41:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:38 INFO - 'PWD': '/builds/slave/test', 05:41:38 INFO - 'SHELL': '/bin/bash', 05:41:38 INFO - 'SHLVL': '1', 05:41:38 INFO - 'TERM': 'linux', 05:41:38 INFO - 'TMOUT': '86400', 05:41:38 INFO - 'USER': 'cltbld', 05:41:38 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:38 INFO - '_': '/tools/buildbot/bin/python'} 05:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:41:38 INFO - Cleaning up... 05:41:38 INFO - Return code: 0 05:41:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41: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')]} 05:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:38 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 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41:38 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 05:41:38 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 05:41:38 INFO - Using env: {'DISPLAY': ':0', 05:41:38 INFO - 'HOME': '/home/cltbld', 05:41:38 INFO - 'LANG': 'en_US.UTF-8', 05:41:38 INFO - 'LANGUAGE': 'en_US:en', 05:41:38 INFO - 'LOGNAME': 'cltbld', 05:41:38 INFO - 'MAIL': '/var/mail/cltbld', 05:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:38 INFO - 'MOZ_NO_REMOTE': '1', 05:41:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:38 INFO - 'NO_EM_RESTART': '1', 05:41:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:38 INFO - 'PWD': '/builds/slave/test', 05:41:38 INFO - 'SHELL': '/bin/bash', 05:41:38 INFO - 'SHLVL': '1', 05:41:38 INFO - 'TERM': 'linux', 05:41:38 INFO - 'TMOUT': '86400', 05:41:38 INFO - 'USER': 'cltbld', 05:41:38 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:38 INFO - '_': '/tools/buildbot/bin/python'} 05:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:41: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 05:41: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 05:41: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 05:41: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 05:41:39 INFO - Downloading blobuploader-1.2.4.tar.gz 05:41:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:41:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:41:39 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 05:41:39 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 05:41:39 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 05:41:39 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 05:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:41:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:41:40 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 05:41:40 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 05:41:40 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 05:41:40 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 05:41:40 INFO - Downloading docopt-0.6.1.tar.gz 05:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:41:40 INFO - Installing collected packages: blobuploader, requests, docopt 05:41:40 INFO - Running setup.py install for blobuploader 05:41:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:41:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:41:40 INFO - Running setup.py install for requests 05:41:40 INFO - Running setup.py install for docopt 05:41:41 INFO - Successfully installed blobuploader requests docopt 05:41:41 INFO - Cleaning up... 05:41:41 INFO - Return code: 0 05:41:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41: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')]} 05:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:41 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 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41:41 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 05:41:41 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 05:41:41 INFO - Using env: {'DISPLAY': ':0', 05:41:41 INFO - 'HOME': '/home/cltbld', 05:41:41 INFO - 'LANG': 'en_US.UTF-8', 05:41:41 INFO - 'LANGUAGE': 'en_US:en', 05:41:41 INFO - 'LOGNAME': 'cltbld', 05:41:41 INFO - 'MAIL': '/var/mail/cltbld', 05:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:41 INFO - 'MOZ_NO_REMOTE': '1', 05:41:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:41 INFO - 'NO_EM_RESTART': '1', 05:41:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:41 INFO - 'PWD': '/builds/slave/test', 05:41:41 INFO - 'SHELL': '/bin/bash', 05:41:41 INFO - 'SHLVL': '1', 05:41:41 INFO - 'TERM': 'linux', 05:41:41 INFO - 'TMOUT': '86400', 05:41:41 INFO - 'USER': 'cltbld', 05:41:41 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:41 INFO - '_': '/tools/buildbot/bin/python'} 05:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:41 INFO - Running setup.py (path:/tmp/pip-A4ChRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:41 INFO - Running setup.py (path:/tmp/pip-X4jR6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:41 INFO - Running setup.py (path:/tmp/pip-xTsYDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:41 INFO - Running setup.py (path:/tmp/pip-VJUXrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:41 INFO - Running setup.py (path:/tmp/pip-n9CN7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:41 INFO - Running setup.py (path:/tmp/pip-_LBfZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:41 INFO - Running setup.py (path:/tmp/pip-3pC2CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:41 INFO - Running setup.py (path:/tmp/pip-cGF8aC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:41 INFO - Running setup.py (path:/tmp/pip-iNRG0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:41:41 INFO - Running setup.py (path:/tmp/pip-6wEzmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:41:41 INFO - Running setup.py (path:/tmp/pip-v7bh9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:41:42 INFO - Running setup.py (path:/tmp/pip-8fWlDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:41:42 INFO - Running setup.py (path:/tmp/pip-Q467Wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:41:42 INFO - Running setup.py (path:/tmp/pip-CnYEiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:42 INFO - Running setup.py (path:/tmp/pip-8Ij_NW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:41:42 INFO - Running setup.py (path:/tmp/pip-gf7Ifp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:41:42 INFO - Running setup.py (path:/tmp/pip-YWdXJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:41:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:41:42 INFO - Running setup.py install for manifestparser 05:41:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:41:42 INFO - Running setup.py install for mozcrash 05:41:42 INFO - Running setup.py install for mozdebug 05:41:42 INFO - Running setup.py install for mozdevice 05:41:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:41:42 INFO - Running setup.py install for mozfile 05:41:42 INFO - Running setup.py install for mozhttpd 05:41:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Running setup.py install for mozinfo 05:41:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Running setup.py install for mozInstall 05:41:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Running setup.py install for mozleak 05:41:43 INFO - Running setup.py install for mozlog 05:41:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Running setup.py install for moznetwork 05:41:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Running setup.py install for mozprocess 05:41:43 INFO - Running setup.py install for mozprofile 05:41:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Running setup.py install for mozrunner 05:41:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:41:43 INFO - Running setup.py install for mozscreenshot 05:41:44 INFO - Running setup.py install for moztest 05:41:44 INFO - Running setup.py install for mozversion 05:41:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:41:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:41:44 INFO - Cleaning up... 05:41:44 INFO - Return code: 0 05:41:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:44 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')]} 05:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:44 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')]} 05:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:44 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 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41:44 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 05:41:44 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 05:41:44 INFO - Using env: {'DISPLAY': ':0', 05:41:44 INFO - 'HOME': '/home/cltbld', 05:41:44 INFO - 'LANG': 'en_US.UTF-8', 05:41:44 INFO - 'LANGUAGE': 'en_US:en', 05:41:44 INFO - 'LOGNAME': 'cltbld', 05:41:44 INFO - 'MAIL': '/var/mail/cltbld', 05:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:44 INFO - 'MOZ_NO_REMOTE': '1', 05:41:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:44 INFO - 'NO_EM_RESTART': '1', 05:41:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:44 INFO - 'PWD': '/builds/slave/test', 05:41:44 INFO - 'SHELL': '/bin/bash', 05:41:44 INFO - 'SHLVL': '1', 05:41:44 INFO - 'TERM': 'linux', 05:41:44 INFO - 'TMOUT': '86400', 05:41:44 INFO - 'USER': 'cltbld', 05:41:44 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:44 INFO - '_': '/tools/buildbot/bin/python'} 05:41:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:44 INFO - Running setup.py (path:/tmp/pip-O4ZPyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:44 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)) 05:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:44 INFO - Running setup.py (path:/tmp/pip-CfsfSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:44 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)) 05:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:44 INFO - Running setup.py (path:/tmp/pip-Em49qb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:44 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)) 05:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:44 INFO - Running setup.py (path:/tmp/pip-TWeUEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:44 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)) 05:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:44 INFO - Running setup.py (path:/tmp/pip-6brQQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:44 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)) 05:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:44 INFO - Running setup.py (path:/tmp/pip-54WN4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:44 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)) 05:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:44 INFO - Running setup.py (path:/tmp/pip-HFOtfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:44 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)) 05:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:44 INFO - Running setup.py (path:/tmp/pip-u2eb2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:45 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)) 05:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:45 INFO - Running setup.py (path:/tmp/pip-pDTBFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:41:45 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)) 05:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:41:45 INFO - Running setup.py (path:/tmp/pip-6TZc7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:41:45 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)) 05:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:41:45 INFO - Running setup.py (path:/tmp/pip-2MjUe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:41:45 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)) 05:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:41:45 INFO - Running setup.py (path:/tmp/pip-dXHBeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:41:45 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)) 05:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:41:45 INFO - Running setup.py (path:/tmp/pip-sYkAvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:41:45 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)) 05:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:41:45 INFO - Running setup.py (path:/tmp/pip-xcDRpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:41:45 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)) 05:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:45 INFO - Running setup.py (path:/tmp/pip-TWGgtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:45 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)) 05:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:41:45 INFO - Running setup.py (path:/tmp/pip-u6GH9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:41:45 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)) 05:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:41:45 INFO - Running setup.py (path:/tmp/pip-74sQ3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:41:45 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)) 05:41:45 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)) 05:41:45 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)) 05:41:45 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)) 05:41:45 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)) 05:41:45 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)) 05:41:45 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)) 05:41:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:41:45 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 05:41:45 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 05:41:45 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 05:41:45 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 05:41:46 INFO - Downloading blessings-1.6.tar.gz 05:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:41:46 INFO - Installing collected packages: blessings 05:41:46 INFO - Running setup.py install for blessings 05:41:46 INFO - Successfully installed blessings 05:41:46 INFO - Cleaning up... 05:41:47 INFO - Return code: 0 05:41:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:41:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:47 INFO - Reading from file tmpfile_stdout 05:41:47 INFO - Current package versions: 05:41:47 INFO - argparse == 1.2.1 05:41:47 INFO - blessings == 1.6 05:41:47 INFO - blobuploader == 1.2.4 05:41:47 INFO - docopt == 0.6.1 05:41:47 INFO - functools32 == 3.2.3-2 05:41:47 INFO - jsonschema == 2.5.1 05:41:47 INFO - manifestparser == 1.1 05:41:47 INFO - mozInstall == 1.13 05:41:47 INFO - mozcrash == 1.0 05:41:47 INFO - mozdebug == 0.1 05:41:47 INFO - mozdevice == 0.51 05:41:47 INFO - mozfile == 1.2 05:41:47 INFO - mozhttpd == 0.7 05:41:47 INFO - mozinfo == 0.10 05:41:47 INFO - mozleak == 0.1 05:41:47 INFO - mozlog == 3.5 05:41:47 INFO - moznetwork == 0.27 05:41:47 INFO - mozprocess == 0.25 05:41:47 INFO - mozprofile == 0.28 05:41:47 INFO - mozrunner == 6.13 05:41:47 INFO - mozscreenshot == 0.1 05:41:47 INFO - mozsystemmonitor == 0.3 05:41:47 INFO - moztest == 0.8 05:41:47 INFO - mozversion == 1.4 05:41:47 INFO - psutil == 3.1.1 05:41:47 INFO - requests == 1.2.3 05:41:47 INFO - wsgiref == 0.1.2 05:41:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:47 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')]} 05:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:47 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')]} 05:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:47 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 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41:47 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 05:41:47 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 05:41:47 INFO - Using env: {'DISPLAY': ':0', 05:41:47 INFO - 'HOME': '/home/cltbld', 05:41:47 INFO - 'LANG': 'en_US.UTF-8', 05:41:47 INFO - 'LANGUAGE': 'en_US:en', 05:41:47 INFO - 'LOGNAME': 'cltbld', 05:41:47 INFO - 'MAIL': '/var/mail/cltbld', 05:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:47 INFO - 'MOZ_NO_REMOTE': '1', 05:41:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:47 INFO - 'NO_EM_RESTART': '1', 05:41:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:47 INFO - 'PWD': '/builds/slave/test', 05:41:47 INFO - 'SHELL': '/bin/bash', 05:41:47 INFO - 'SHLVL': '1', 05:41:47 INFO - 'TERM': 'linux', 05:41:47 INFO - 'TMOUT': '86400', 05:41:47 INFO - 'USER': 'cltbld', 05:41:47 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:47 INFO - '_': '/tools/buildbot/bin/python'} 05:41:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:47 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)) 05:41:47 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)) 05:41:47 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)) 05:41:47 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)) 05:41:47 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)) 05:41:47 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)) 05:41:47 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)) 05:41:47 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)) 05:41:47 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)) 05:41:47 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:41:47 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 05:41:47 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 05:41:47 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 05:41:47 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 05:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:41:48 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)) 05:41:48 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)) 05:41:48 INFO - Installing collected packages: simplejson 05:41:48 INFO - Running setup.py install for simplejson 05:41:48 INFO - building 'simplejson._speedups' extension 05:41:48 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 05:41:49 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 05:41:49 INFO - Successfully installed simplejson 05:41:49 INFO - Cleaning up... 05:41:49 INFO - Return code: 0 05:41:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:41:49 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')]} 05:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:49 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')]} 05:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:49 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 0x21521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x231ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, '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': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', '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 05:41:49 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 05:41:49 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 05:41:49 INFO - Using env: {'DISPLAY': ':0', 05:41:49 INFO - 'HOME': '/home/cltbld', 05:41:49 INFO - 'LANG': 'en_US.UTF-8', 05:41:49 INFO - 'LANGUAGE': 'en_US:en', 05:41:49 INFO - 'LOGNAME': 'cltbld', 05:41:49 INFO - 'MAIL': '/var/mail/cltbld', 05:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:49 INFO - 'MOZ_NO_REMOTE': '1', 05:41:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:49 INFO - 'NO_EM_RESTART': '1', 05:41:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:49 INFO - 'PWD': '/builds/slave/test', 05:41:49 INFO - 'SHELL': '/bin/bash', 05:41:49 INFO - 'SHLVL': '1', 05:41:49 INFO - 'TERM': 'linux', 05:41:49 INFO - 'TMOUT': '86400', 05:41:49 INFO - 'USER': 'cltbld', 05:41:49 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:49 INFO - '_': '/tools/buildbot/bin/python'} 05:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:41:49 INFO - Cleaning up... 05:41:49 INFO - Return code: 0 05:41:49 INFO - Running post-action listener: _resource_record_post_action 05:41:49 INFO - Running post-action listener: _start_resource_monitoring 05:41:49 INFO - Starting resource monitoring. 05:41:49 INFO - [mozharness: 2017-10-04 12:41:49.941514Z] Finished create-virtualenv step (success) 05:41:49 INFO - [mozharness: 2017-10-04 12:41:49.942048Z] Running install step. 05:41:49 INFO - Running pre-action listener: _resource_record_pre_action 05:41:49 INFO - Running main action method: install 05:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:50 INFO - Reading from file tmpfile_stdout 05:41:50 INFO - Detecting whether we're running mozinstall >=1.0... 05:41:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:41:50 INFO - Reading from file tmpfile_stdout 05:41:50 INFO - Output received: 05:41:50 INFO - Usage: mozinstall [options] installer 05:41:50 INFO - Options: 05:41:50 INFO - -h, --help show this help message and exit 05:41:50 INFO - -d DEST, --destination=DEST 05:41:50 INFO - Directory to install application into. [default: 05:41:50 INFO - "/builds/slave/test"] 05:41:50 INFO - --app=APP Application being installed. [default: firefox] 05:41:50 INFO - mkdir: /builds/slave/test/build/application 05:41:50 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'] 05:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:42:07 INFO - Reading from file tmpfile_stdout 05:42:07 INFO - Output received: 05:42:07 INFO - /builds/slave/test/build/application/firefox/firefox 05:42:07 INFO - Running post-action listener: _resource_record_post_action 05:42:07 INFO - [mozharness: 2017-10-04 12:42:07.901046Z] Finished install step (success) 05:42:07 INFO - [mozharness: 2017-10-04 12:42:07.901299Z] Running setup-mitmproxy step. 05:42:07 INFO - Running pre-action listener: _resource_record_pre_action 05:42:07 INFO - Running main action method: setup_mitmproxy 05:42:07 INFO - Skipping: mitmproxy is not required 05:42:07 INFO - Running post-action listener: _resource_record_post_action 05:42:07 INFO - [mozharness: 2017-10-04 12:42:07.902534Z] Finished setup-mitmproxy step (success) 05:42:07 INFO - [mozharness: 2017-10-04 12:42:07.902771Z] Running run-tests step. 05:42:07 INFO - Running pre-action listener: _resource_record_pre_action 05:42:07 INFO - Running pre-action listener: _set_gcov_prefix 05:42:07 INFO - Running main action method: run_tests 05:42:07 WARNING - Try message not found. 05:42:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:42:07 INFO - Python 2.7.3 05:42:07 INFO - Return code: 0 05:42:07 INFO - grabbing minidump binary from tooltool 05:42:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:07 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 0x24bcd60>, '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 0x24bda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bdef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:42:07 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 05:42:07 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 05:42:07 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 05:42:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:42:08 INFO - Return code: 0 05:42:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:42:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:42:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:42:08 INFO - ENV: RUST_BACKTRACE is now full 05:42:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:42:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:42:08 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/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 05:42:08 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/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 05:42:08 INFO - Using env: {'DISPLAY': ':0', 05:42:08 INFO - 'HOME': '/home/cltbld', 05:42:08 INFO - 'LANG': 'en_US.UTF-8', 05:42:08 INFO - 'LANGUAGE': 'en_US:en', 05:42:08 INFO - 'LOGNAME': 'cltbld', 05:42:08 INFO - 'MAIL': '/var/mail/cltbld', 05:42:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:42:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:08 INFO - 'MOZ_NO_REMOTE': '1', 05:42:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:42:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:08 INFO - 'NO_EM_RESTART': '1', 05:42:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:08 INFO - 'PWD': '/builds/slave/test', 05:42:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:42:08 INFO - 'RUST_BACKTRACE': 'full', 05:42:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:42:08 INFO - 'SHELL': '/bin/bash', 05:42:08 INFO - 'SHLVL': '1', 05:42:08 INFO - 'TERM': 'linux', 05:42:08 INFO - 'TMOUT': '86400', 05:42:08 INFO - 'USER': 'cltbld', 05:42:08 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:08 INFO - '_': '/tools/buildbot/bin/python'} 05:42:08 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/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 05:42:08 INFO - mozversion application_buildid: 20171004091107 05:42:08 INFO - mozversion application_changeset: 933b30269777f718a14d2fc379e563a051b14618 05:42:08 INFO - mozversion application_display_name: Nightly 05:42:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:42:08 INFO - mozversion application_name: Firefox 05:42:08 INFO - mozversion application_remotingname: firefox 05:42:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:42:08 INFO - mozversion application_vendor: Mozilla 05:42:08 INFO - mozversion application_version: 58.0a1 05:42:08 INFO - mozversion platform_buildid: 20171004091107 05:42:08 INFO - mozversion platform_changeset: 933b30269777f718a14d2fc379e563a051b14618 05:42:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:42:08 INFO - mozversion platform_version: 58.0a1 05:42:08 INFO - using testdate: 1507120928 05:42:08 INFO - actual date: 1507120928 05:42:08 INFO - starting webserver on 'localhost:33963' 05:42:08 INFO - SUITE-START | Running 2 tests 05:42:08 INFO - TEST-START | dromaeo_css 05:42:08 INFO - Initialising browser for dromaeo_css test... 05:42:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33963/getInfo.html -profile /tmp/tmpis1Ymo/profile 05:42:08 INFO - TEST-INFO | started process 989 (/builds/slave/test/build/application/firefox/firefox http://localhost:33963/getInfo.html) 05:42:14 INFO - TEST-INFO | 989: exit 0 05:42:14 INFO - Browser initialized. 05:42:14 INFO - Running cycle 1/1 for dromaeo_css test... 05:42:14 INFO - Using env: {'DISPLAY': ':0', 05:42:14 INFO - 'HOME': '/home/cltbld', 05:42:14 INFO - 'JSGC_DISABLE_POISONING': '1', 05:42:14 INFO - 'LANG': 'en_US.UTF-8', 05:42:14 INFO - 'LANGUAGE': 'en_US:en', 05:42:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:42:14 INFO - 'LOGNAME': 'cltbld', 05:42:14 INFO - 'MAIL': '/var/mail/cltbld', 05:42:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:42:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:42:14 INFO - 'MOZ_CRASHREPORTER': '1', 05:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:14 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:42:14 INFO - 'MOZ_NO_REMOTE': '1', 05:42:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:42:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:14 INFO - 'NO_EM_RESTART': '1', 05:42:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:14 INFO - 'PWD': '/builds/slave/test', 05:42:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:42:14 INFO - 'RUST_BACKTRACE': 'full', 05:42:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:42:14 INFO - 'SHELL': '/bin/bash', 05:42:14 INFO - 'SHLVL': '1', 05:42:14 INFO - 'TERM': 'linux', 05:42:14 INFO - 'TMOUT': '86400', 05:42:14 INFO - 'USER': 'cltbld', 05:42:14 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 05:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:14 INFO - '_': '/tools/buildbot/bin/python'} 05:42:14 INFO - TEST-INFO | started process 1345 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpis1Ymo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:15 INFO - PID 1345 | 05:42:15 INFO - PID 1345 | (/builds/slave/test/build/application/firefox/firefox:1395): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:42:15 INFO - PID 1345 | 05:42:25 INFO - PID 1345 | RSS: Main: 142172160 05:42:25 INFO - PID 1345 | 05:45:23 INFO - PID 1345 | Cycle 1(1): loaded http://localhost:33963/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33963/tests/dromaeo/cssquery-ext.html) 05:45:24 INFO - PID 1345 | RSS: Main: 143151104 05:45:24 INFO - PID 1345 | 05:48:26 INFO - PID 1345 | Cycle 1(1): loaded http://localhost:33963/tests/dromaeo/cssquery-ext.html (next: http://localhost:33963/tests/dromaeo/cssquery-jquery.html) 05:48:26 INFO - PID 1345 | RSS: Main: 141615104 05:48:26 INFO - PID 1345 | 05:51:28 INFO - PID 1345 | Cycle 1(1): loaded http://localhost:33963/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33963/tests/dromaeo/cssquery-mootools.html) 05:51:29 INFO - PID 1345 | RSS: Main: 145014784 05:51:29 INFO - PID 1345 | 05:54:30 INFO - PID 1345 | Cycle 1(1): loaded http://localhost:33963/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33963/tests/dromaeo/cssquery-prototype.html) 05:54:31 INFO - PID 1345 | RSS: Main: 144605184 05:54:31 INFO - PID 1345 | 05:57:34 INFO - PID 1345 | Cycle 1(1): loaded http://localhost:33963/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33963/tests/dromaeo/cssquery-yui.html) 05:57:34 INFO - PID 1345 | RSS: Main: 147984384 05:57:34 INFO - PID 1345 | 06:00:36 INFO - PID 1345 | Cycle 1(1): loaded http://localhost:33963/tests/dromaeo/cssquery-yui.html (next: http://localhost:33963/tests/dromaeo/cssquery-dojo.html) 06:00:36 INFO - PID 1345 | RSS: Main: 148606976 06:00:36 INFO - PID 1345 | 06:00:36 INFO - PID 1345 | __start_tp_report 06:00:36 INFO - PID 1345 | _x_x_mozilla_page_load 06:00:36 INFO - PID 1345 | _x_x_mozilla_page_load_details 06:00:36 INFO - PID 1345 | |i|pagename|runs| 06:00:36 INFO - PID 1345 | |0;dojo.html;3838.6353296436846;3857.4599556062158;3875.5155605549307;3899.3566061599845;3901.3367727486325;6286.214223222098;6314.526410519218;6330.841728956779;6347.968260158698;6426.871462570746;6329.810105696836;6330.936690633103;6352.555321127523;6353.904691429632;6389.073584330269;5589.944100559003;5678.858028549299;5693;5718.828435146952;5743.655380677173;5644.04051311276;5665.1502274658715;5672.404397538261;5706.543476521889;5746.252987111669;870.8978850988317;5533.08704063833;5641.407652196522;5707.942920570804;5755.366909639957;5524.861878453031;5533.114701647757;5591.608587398884;5636.239107720447;5679.971600142014;5601.411851755548;5603.60774745768;5664.603477756558;5685.147227915804;5696.800611978602;6221.502279817649;6252.937470625327;6253.249610046823;6275.811725648238;6306.274778400497;6737.966310168466;6770.221424536192;6811.931880681229;6857.965710171467;6875.106236189314;6684.598924064571;6753.054572359873;6774.966125169342;6836.623985680785;6869.072675188835;6271;6332.081848131995;6345.87308253837;6349.301576826545;6349.555531112824;6963.059986901805;7071.681774320168;7073.3987611053635;7074.504784665024;7102.254263302382;6701.061851340816;6727.9327206728285;6728.596284222962;6814.046033555306;6819.965900170567;6713.295104014008;6789.93210067893;6873.175218973755;6938.930610693828;6966.860662786716;6758.222804377461;6760.526763126534;6808.217055038734;6835.863282734317;6916.100906882174;6745.2242992056545;6815.114035175495;6846.110005699227;6894;6900.585964842126;4906.288588154697;4924.704517728947;4976.2286845539;4986.626003049786;4991.725455100004;4427.313766366218;4443;4476.6418686505;4494.213512635341;4500.347449619787;6222.751089956351;6238.532110091671;6251.437370636664;6270.655113968684;6295.748170073238;6767.593944363354;6771.424428923498;6789.694463749143;6863.416609588141;6866.588004719732;6173.197484327009;6190.288116866528;6201.255849298022;6253.74985000604;6258.186435763321;5464.207689885024;5503.944960550423;5527.419620939824;5531.3362396511875;5533.501984821385;7000.894986575206;7076.787696369117;7077.504574679826;7185.6047917364;7188.676509557083;6777.695003724943;6796.524243302922;6801.18385793708;6803.319668033157;6804.11546498952;7109.182444019004;7114.324139206767;7117.039199707973;7117.572945623279;7118.9644051779405;6749.8702518351265;6756.121704178622;6765.966170169315;6856.760013399507;6898.689558969959;6889.448844092361;6901.723931042702;6902.896456553055;6937.202221744362;6999.545029573062;6465.579737317059;6569.080328754074;6630.569013014138;6670.699818508082;6720.798376048726;6551;6552.475801935929;6576.210854697467;6617.404433600999;6621.172353455818;6417.96791016042;6566.4418524425955;6581.539292249497;6602.075709400784;6610.3720146586;6625.039369291522;6680;6700.4639628830555;6708.362705542965;6710.060591517289;6435.195600549931;6446.290908000023;6511.73953041873;6528.477721782151;6534.771283005072 06:00:36 INFO - PID 1345 | |1;ext.html;976.3702411944295;987.7455631348183;1002.1982414068748;1002.2182697495956;1008.4655132779627;46695.76652116739;47436;47483.28775068368;47534.762326188364;47548.28677569839;43996.120077598505;44020.55979440205;44196.11607767851;44295.335569966475;44302.55697443025;7818.257265559762;7983.321417679475;7995.800104997364;8002.919970800276;8009.679612815509;7841.921580784205;7923.445358824884;7944.404169687267;7945.205479452066;7953.880691789628;8487.44831585948;8665.090165532623;8675.219230269275;8684.56577171142;8685.913140868604;8563.486190828538;8639.308855291592;8641.481511109354;8643.049264580892;8650;8061.6372263248295;8225.300849427806;8298.294644955186;8313.002439707214;8316.209960053786;42719.35920961188;43152.784236078616;43179.78410107961;43336.783316083216;43390.349144762855;3752.3808571585655;3798.0694729791376;3801.334766415866;3801.6198380161763;3802.08749900022;34102.31795364104;34108.6589134108;34214.48678269828;34228.31543369144;34278.14304642404;5519.862003449905;5528.530074943595;5537.252470916415;5571.387147413781;5630.268065151545;213973;214314;215252;216147;216159;47806.282905756445;48834;53801.73099134479;54984;55016.44983550113;57055;57658;57714.7114264426;57847.13229301564;57853.71073144692;16302.67394652124;16543.420980265633;16573;16652.417165399154;16654.75017874733;13253.73492530144;13430;13440.462381504825;13446.126001809851;13515.594532163854;15130.319135638923;15211.315490802941;15316.770248446282;15340.693186136215;15394;6082.300535438393;6103.908441373383;6110.236220472441;6154.907676384858;6172.537059720449;1115.6597237842373;1116.3525155410005;1123.224974767408;1127.0645364347572;1132.6262333429947;25899.611505827426;26131.869340653557;26167.476650466884;26292.079777207706;26345.341366465607;1210.5036934856842;1213.9514419423124;1225.8957988570894;1228.5147366790247;1244.2783185752241;13707.314634268247;14002.929985350213;14012.789808152886;14013.649658758408;14290.499832505813;291156;291991;292314;292835;293636;61443.69278153581;61560.692196539625;61703.38296617066;61787.382126179065;62342.818001369735;1552.3790483806567;1552.9068255904908;1576.1488796050005;1577.2350410051115;1599.224376177553;25591.48817023612;25743.356416089748;25848;26042.73957260403;26068.478630426907;43581.78209108934;43635;43662.781686091366;43671;43760.562394376924;233439;235840;236312;236900;237226;37939.05152371213;38597.22805543929;40870;41151.58848411478;41240.38139427971;50894.23658645049;52193;52916;52969.735151324;53228.2015769756;34990.65009349976;35176;35442.46836297406;35660.28679426449;35720.285594288485;37223.44164837584;37529.4370584436;37553;37605.62394376021;37761.2447751049;43750.34374484449;43902.78048609736;44209;44953;45163.32255016243;36505.817470913535;36649.81675091608;36868;36976.26047479089;37606.62393376031;26846.06038788633;27666.861665691544;27753.16740497788;27860.721392785814;27919.44161116807 06:00:36 INFO - PID 1345 | |2;jquery.html;614.1310046284508;634.7175506899432;639.2201514152732;641.4099028893418;646.5215740352139;23178.652320215188;23211.3036608902;24018.639720404186;24063.879680601593;24194.758052419515;25574.488510229785;26655.86672066637;26668.466630667423;26686.19941401761;26707.599386009224;6534.0852280680865;6713.765018224363;6714.597124172541;6715.664216789165;6732.461403087766;6472.449841763456;6528.2166140063255;6773.796786096425;6785.117934668486;6792.796216113525;6779.932200678004;6783.694733736969;6785.72857085716;6789.9815027745735;6795.7961261162245;6609.900851487231;6731.394174524292;6766.086578311939;6781.321867813229;6875.3812156906115;6450.161479007699;6463.062855885917;6464.159659244315;6470.38531339522;6482.6434546099945;30443;30511.847440762875;30590.541141882888;30609.540856887164;30618.38763224723;2236.2732112063645;2269.171752310395;2271.670607761865;2283.703118594572;2283.7716228377194;20399.388018359325;21071.578568428544;21333.35999920005;21359.67960480594;21441.249556265455;2578.2136448383235;2579.3293743626605;2635.0513815026575;2646;2647.060293595784;138663.30668346593;143214;145742.27128864394;154997.22501387645;157754;15978.33040140739;16703.33186672544;16779.161041947853;16992.74510882338;17050.147492625318;26604;26648.333791655365;26755;26902.730972690413;26985.595216071775;10409;10643.52104155315;10818.404987725751;10884.61903833362;10889.618863339745;9557.166140722005;9671.226301895831;9703.9029609703;9751.268654850772;9837.950810245902;9937.403755774676;9965.35225210359;9985.251106167067;10014.39913605186;10312.484375781181;2818.168640251131;2859.885604575794;2924.795264331519;2968.9406211876076;2983.7463815575484;1016.974575635615;1032.550840384435;1039.859618951432;1052.9684109476727;1055.2191378380096;20827.06278217484;21019.78980210209;21158.47103822386;21226.150953961667;21448.4637884051;1091.1979694924225;1107.6234080412698;1108.362691452402;1109.1293334732197;1127.0251232683834;11258.268212566158;11470.827937580943;11630.100144203743;11634.825477617842;11744.255744255745;629017;640509;644959;656445;658672;126651;126977;127507;128522;130045.34977325053;1203;1207.4928530017414;1241.4103300931986;1245.1844042152404;1247.2204871955028;18406.631867362845;18552.257909684;18921.52310772279;19077.90461047686;19235.038248087818;61912;62904;63061.68469157625;63252;64590;77775.22224777906;79017;79122;79801;80194;15669.138197399252;15828.60428489251;15957.361705531648;16136.515904522883;16141.273642686343;23350.649740254248;23487.53024939526;23774.405639859142;23885.64171537463;24003.399915002265;19589.902050489658;19610.60778784445;19635;19645.803541964397;19789;16899.7465038027;17414.390496332566;17437.47687569375;17534.03562804007;17678.558036049202;18840.057997100364;20044.198232071136;20146.39560813178;20282.89858550757;20286.89856550708;15709.842901570839;15754.369825207323;15757.290921908312;15832.525024249291;15917.124558149413;19261.711074333616;19321.613567728287;19510.414687559394;19573.510662232988;19638.214471420983 06:00:36 INFO - PID 1345 | |3;mootools.html;1438.3958737330322;1495.267319013683;1502.564256365654;1506.149025800423;1511.8110236220473;16359.59101022475;16981.405650802226;16983.23575439105;16996;17000.574985625364;16590.25343859526;16681.499555013368;16769.24538395772;16804.075775832323;16812.74780878288;2856.600075989363;2961.5557666350114;2967.9554806677916;2970.153506250719;2971.881124755007;2933.8973135940246;2965.747911427531;2970.5247160454333;2977.5533669949573;2988.9402211955858;2886.0620298402996;2941.8529073546238;2960.200745798633;2964.170032390934;2968.584398184256;2814.957775633367;2816.816906901055;2817.337925587485;2824.2798086487815;2832.9511727861977;2666.9333266668486;2688.6504754382;2718.619393284942;2723.891044358223;2724.6185534025253;16037.438689645807;16160.75758863618;16293.674126517404;16315.10266935318;16393.09837958912;8770.386072974894;9031.638734450615;9060.682876099305;9097.909020909772;9119.04250053748;5730.484256416901;5809.186713860063;5853.678047707417;5957.165996760457;5976.103584462322;2063.6182306273386;2128.3082998025548;2144.6461333879893;2192.9232476863235;2199.3841724317217;26400.075997340195;26931.19206423791;28995.275118122005;30102.84948575265;30127.246818829706;7800.531968081933;7819.413543984224;7819.804504887424;8036.116027236999;8063.1130575636635;19913.402597922086;19938.800611993986;19977.101030453665;19979.700304495444;20005.199792008447;8727.301815854717;8811.911880881109;8815.867761983576;8826.51454170014;8829.16122968317;7835.804104897423;7976.7208147714555;8105.1489593593005;8163.183681631789;8166.510009399336;5867.20792692981;5879.235699359056;5922.733476993546;5977.970110149421;6033.215681961317;1629.3156874112901;1635.6401591649817;1637.656092220647;1642.5975635969264;1648.620817212048;941.4680705401427;963.720521048902;974.3228456222857;975.8633791269228;982.0572250639466;8384.958075209584;8424.915750842536;8451.873221901553;8467.449615775078;8490.957545212357;774.156169774948;791.4183075439547;807.317816445103;817.1705718695529;818.6643476174859;4801.4958429365115;5921.082232254007;6102.69486525672;6162.890679677701;6214.036824292242;23460.296191114296;23941.64087538688;23990.760092398854;24180.758192417852;24398.146064887645;23642.408939776644;23883.52232955366;23903.52192956131;23991.880040599684;24129.517409651708;12078.879211207952;12491;12676.112672113048;12733.426995785027;12745.235285882876;9271.02654221297;9503.287253456157;9547.42715437076;9578.9042109578;9724.416535007922;20090.598188035867;20479.976001199582;20769.27307544229;20835.8958205208;21088.156473741492;19951.700724489434;21050.47373815667;21147.78852211458;21303;22160.778392216518;12773.61679149627;12864.807027894776;12888.935555322163;12961.546345877849;12974.286414247306;15246.085234885943;15447.922760386125;15457.922710386376;15525.844741552892;15552.30014649321;12425.93787031059;12571.081735911703;12618.684532886751;12685.682857928625;12704.809427858405;12225.144239903122;12233.3271670059;12423.813642795547;12510.124291299522;12536.811947820972;11472.713182170513;11724.296542207494;11735.119866010255;11738.823917641414;11739.765204696028;8249.463784853966;8361.95819020901;8399.664013439395;8469.110743371857;8523.531205783742;8516.276116530164;8566.443181193203;8652.870206947027;8735.257503112307;8826.646934122564 06:00:36 INFO - PID 1345 | |4;prototype.html;534.6791924845091;557.9386267510574;567.4183961439527;568.4116938968169;569.097979702172;6208.534359923007;6286.905696414552;6288.1764288356435;6308.621482711041;6374.394432528914;6189.969050154754;6203.425578384965;6230.9688451557795;6268.874622507547;6323.810285691425;4996.625253106032;5018.623603229754;5027.296178534999;5037.773300201481;5067.797288108472;4933.7533123343865;5061.215511595708;5077.847664570068;5090.872728181788;5112.8977420451565;5028.296038554606;5038.722870242135;5069.898602027957;5074.594032477411;5100.872478188038;4961;4997.225430058329;5040.1179793535985;5056.165732654126;5078.365204349457;4650.976745116287;4667.242869490059;4668.906621867578;4674.929876051862;4682.976585117087;6177.81466556004;6217;6225.408586184305;6285.87428251437;6315.842103947392;5919.704014799243;5944.791932282393;5948.524118070587;5958.7616495340135;5965;6699.296573859772;6711.496637752188;6723.798286051427;6735.124433823572;6740.292269311701;5158.40678321994;5193.532582067594;5196.662216955886;5201.635885488017;5203.9219411708855;6982.895256571155;6984.965075174591;6986.161660600709;7002.894956575707;7012.123484564429;5322.3879253885525;5324.707141107277;5338.279332290169;5340.973295133499;5341.732913354394;5399.16428414341;5421.2681288025615;5423.511883930465;5436.537894278951;5462.617616766788;5892.292924849045;5913.379095194942;5932.567647264601;5951.880962380815;5962.582619216613;6399.520035997319;6403.807885763435;6661.633610151392;6696.598204107671;6705.1283333166375;1971.9901400492906;1978.9208431662573;1997.670384386589;2003.5492014296958;2005.508650380637;1261.5899832554273;1500.9099454032576;1504.3456096597797;1509.8414666460083;1528.740114180592;6043.244594425697;6156.26124865019;6165.414285642857;6183.165272688129;6237.220347456568;6740.224874139439;6789.524733268624;6810.761623343159;6816.522843400914;6840.794776156723;6148.4773794227995;6167.969160154171;6195.907061394083;6224.533160013018;6254.343293954069;3518.7888726676483;3541.2032292734443;3546.556680414948;3547.361474934485;3597.766145200606;7046.436285097282;7078.539894906815;7099.2545782693105;7102.751403700949;7198.424126069902;6960.338767817049;6966.12923384577;6970.337817907291;6995.755148569776;7064;7077.7168913242895;7108.537945033556;7130.112304516679;7165.570065796069;7166.426685865118;6681.933180668326;6748.460123190035;6778.93221067783;6786.728530858711;6792.320767923366;6734.3602357775935;6787.151606049244;6796.864062718817;6821.829454263485;6830.556013859084;7043;7060.435165186876;7090.574565526085;7119.430445564446;7160.248173941661;5265.315508983807;5305.65513241638;5318.707471088973;5358.705271210121;5361.544268737201;6722.193336799615;6750.6624668767345;6794.252632210354;6815.454763618799;6833.45332373399;6450.903236451363;6533.216014078151;6539.836504087436;6549.312322206099;6549.443297319781;6391.648459334781;6456.515761317826;6490;6494.740210391531;6556.704948277244;6341.90487142684;6432.807015789534;6502.219733631805;6529.249136349191;6546.18172728409;6190.876182476415;6602.372774586406;6606.306337834651;6698.296678848651;6702.151585576208;6336.239651241881;6388.425041746265;6416.422521973045;6418.775342862978;6459.644719540471 06:00:36 INFO - PID 1345 | |5;yui.html;931.3015238571076;947.6635794293026;992.2558081438921;1012.9088382045614;1018.27193556607;20335.593288134227;21272.255471058517;21553.784462155374;21568.8921555392;21576.784232157694;19208.903955480237;19333.419997400102;19469.805301947014;19487.707684384746;19505;1041.666666666667;1060.5704689600686;1061.26242261628;1061.3737894642159;1062.3997441445597;1088.29260980363;1091.6997825597944;1094.7208461842258;1095.11842966412;1095.7534554725203;1078.487718333793;1118.3234143343282;1126.4086354663825;1128.097521982415;1140.8859114088607;1114.0474893965625;1121.5401685309025;1122.068682993122;1122.5846436818388;1123.219362543033;769.2077160524668;805.9919400805976;826.6280173921759;827.8344331133737;827.9503229806231;20405;20519.692204616942;20645.4838629034;20696.551241413108;20739.066741996645;23062;23261;23854.761452385428;24110.517789644284;24144.275671729876;18342.63314733698;18760.15579298935;18868.245270189174;18888.81111188884;18962.81037189624;3985.4021896715435;4013.2976729072298;4022.1955608878343;4023.396490526415;4031.496062992126;136050;164581;164671;166832;169143.99999999878;18410.723839142425;18706.158222880003;18713.345032924055;18801.623967520572;18940.147693353832;30653.23366915845;30881;31258.687413126034;34042.148946276044;34772.478412823824;8721.956390218009;8771.429857059271;8773.605187766565;8792.47245165292;8812.16284452976;4236.5763423658;4280.16536775332;4355.912881742411;4361.8441113105055;4371.868843934687;5907.586468947218;6043.244594425697;6111.786087486917;6119.663418511936;6136.846578835475;1258.3267951645826;1292.2698675248455;1294.643972907458;1295.1775622479604;1312.520929860594;733.3619750816823;746.5110352718978;746.8655641984386;757.8408534207767;767.4972892755254;12986.415611297514;13244.867551324556;13327;13518.797218041736;13804.861951380559;651.9543631945718;658.733213048716;660.6894759463045;674.4536925090692;677.7221339250982;5599.339264035651;5768.9711551442815;5840.1239814027385;5947.256592925884;5983.401659833982;159515;160065;160123;161229;162837.18581407017;143064;143945.28027360004;144760.65718028476;146345;147002.26498867437;122288;124173.37913310557;125921.37039314744;127441;130254;10395.116415104801;10452.372857628567;10680.465976701289;10692.946535267274;10996.615118470816;54755.53600088085;56157.438425616856;56183.71908140433;56487.15269270882;56876;72489.63755181401;73563;73637.63181184059;73737;76073.85889211777;17362.826371736628;17452.563685907448;17619.119044048002;17742.112894354974;17755.556111097325;19623.70564441506;20485.18059277672;20681.689774653692;20688.17247310151;20912.16351345929;16332.020078794834;16464.917675411547;16587.50237492829;16616.50150495487;16658.250378733228;18629.161687723812;18706.719399208752;18771;18802.247910083443;18877;18765.155567999747;18795.90602046981;18811;18832.435026949213;19015.619687606446;12195.390230488263;12661.05042121826;12767.61697149087;12975.870241297844;13088.672783180496;13029.283389413673;13031.087823852618;13198.538051168163;13317.600471985468;13353 06:00:36 INFO - PID 1345 | __end_tp_report 06:00:36 INFO - PID 1345 | __start_cc_report 06:00:36 INFO - PID 1345 | _x_x_mozilla_cycle_collect,277 06:00:36 INFO - PID 1345 | __end_cc_report 06:00:36 INFO - PID 1345 | __startTimestamp1507122036531__endTimestamp 06:00:36 INFO - PID 1345 | 06:00:36 INFO - PID 1345 | ------- Summary: start ------- 06:00:36 INFO - PID 1345 | Number of tests: 6 06:00:36 INFO - PID 1345 | 06:00:36 INFO - PID 1345 | [#0] dojo.html Cycles:165 Average:6251.34 Median:6552.48 stddev:866.43 (13.2%) stddev-sans-first:848.16 06:00:36 INFO - PID 1345 | Values: 3838.6 3857.5 3875.5 3899.4 3901.3 6286.2 6314.5 6330.8 6348.0 6426.9 6329.8 6330.9 6352.6 6353.9 6389.1 5589.9 5678.9 5693.0 5718.8 5743.7 5644.0 5665.2 5672.4 5706.5 5746.3 870.9 5533.1 5641.4 5707.9 5755.4 5524.9 5533.1 5591.6 5636.2 5680.0 5601.4 5603.6 5664.6 5685.1 5696.8 6221.5 6252.9 6253.2 6275.8 6306.3 6738.0 6770.2 6811.9 6858.0 6875.1 6684.6 6753.1 6775.0 6836.6 6869.1 6271.0 6332.1 6345.9 6349.3 6349.6 6963.1 7071.7 7073.4 7074.5 7102.3 6701.1 6727.9 6728.6 6814.0 6820.0 6713.3 6789.9 6873.2 6938.9 6966.9 6758.2 6760.5 6808.2 6835.9 6916.1 6745.2 6815.1 6846.1 6894.0 6900.6 4906.3 4924.7 4976.2 4986.6 4991.7 4427.3 4443.0 4476.6 4494.2 4500.3 6222.8 6238.5 6251.4 6270.7 6295.7 6767.6 6771.4 6789.7 6863.4 6866.6 6173.2 6190.3 6201.3 6253.7 6258.2 5464.2 5503.9 5527.4 5531.3 5533.5 7000.9 7076.8 7077.5 7185.6 7188.7 6777.7 6796.5 6801.2 6803.3 6804.1 7109.2 7114.3 7117.0 7117.6 7119.0 6749.9 6756.1 6766.0 6856.8 6898.7 6889.4 6901.7 6902.9 6937.2 6999.5 6465.6 6569.1 6630.6 6670.7 6720.8 6551.0 6552.5 6576.2 6617.4 6621.2 6418.0 6566.4 6581.5 6602.1 6610.4 6625.0 6680.0 6700.5 6708.4 6710.1 6435.2 6446.3 6511.7 6528.5 6534.8 06:00:36 INFO - PID 1345 | 06:00:36 INFO - PID 1345 | [#1] ext.html Cycles:180 Average:43744.44 Median:3799.70 stddev:65017.63 (1711.1%) stddev-sans-first:65120.72 06:00:36 INFO - PID 1345 | Values: 976.4 987.7 1002.2 1002.2 1008.5 46695.8 47436.0 47483.3 47534.8 47548.3 43996.1 44020.6 44196.1 44295.3 44302.6 7818.3 7983.3 7995.8 8002.9 8009.7 7841.9 7923.4 7944.4 7945.2 7953.9 8487.4 8665.1 8675.2 8684.6 8685.9 8563.5 8639.3 8641.5 8643.0 8650.0 8061.6 8225.3 8298.3 8313.0 8316.2 42719.4 43152.8 43179.8 43336.8 43390.3 3752.4 3798.1 3801.3 3801.6 3802.1 34102.3 34108.7 34214.5 34228.3 34278.1 5519.9 5528.5 5537.3 5571.4 5630.3 213973.0 214314.0 215252.0 216147.0 216159.0 47806.3 48834.0 53801.7 54984.0 55016.4 57055.0 57658.0 57714.7 57847.1 57853.7 16302.7 16543.4 16573.0 16652.4 16654.8 13253.7 13430.0 13440.5 13446.1 13515.6 15130.3 15211.3 15316.8 15340.7 15394.0 6082.3 6103.9 6110.2 6154.9 6172.5 1115.7 1116.4 1123.2 1127.1 1132.6 25899.6 26131.9 26167.5 26292.1 26345.3 1210.5 1214.0 1225.9 1228.5 1244.3 13707.3 14002.9 14012.8 14013.6 14290.5 291156.0 291991.0 292314.0 292835.0 293636.0 61443.7 61560.7 61703.4 61787.4 62342.8 1552.4 1552.9 1576.1 1577.2 1599.2 25591.5 25743.4 25848.0 26042.7 26068.5 43581.8 43635.0 43662.8 43671.0 43760.6 233439.0 235840.0 236312.0 236900.0 237226.0 37939.1 38597.2 40870.0 41151.6 41240.4 50894.2 52193.0 52916.0 52969.7 53228.2 34990.7 35176.0 35442.5 35660.3 35720.3 37223.4 37529.4 37553.0 37605.6 37761.2 43750.3 43902.8 44209.0 44953.0 45163.3 36505.8 36649.8 36868.0 36976.3 37606.6 26846.1 27666.9 27753.2 27860.7 27919.4 06:00:36 INFO - PID 1345 | 06:00:36 INFO - PID 1345 | [#2] jquery.html Cycles:180 Average:40795.67 Median:2283.74 stddev:107496.38 (4707.0%) stddev-sans-first:107755.60 06:00:36 INFO - PID 1345 | Values: 614.1 634.7 639.2 641.4 646.5 23178.7 23211.3 24018.6 24063.9 24194.8 25574.5 26655.9 26668.5 26686.2 26707.6 6534.1 6713.8 6714.6 6715.7 6732.5 6472.4 6528.2 6773.8 6785.1 6792.8 6779.9 6783.7 6785.7 6790.0 6795.8 6609.9 6731.4 6766.1 6781.3 6875.4 6450.2 6463.1 6464.2 6470.4 6482.6 30443.0 30511.8 30590.5 30609.5 30618.4 2236.3 2269.2 2271.7 2283.7 2283.8 20399.4 21071.6 21333.4 21359.7 21441.2 2578.2 2579.3 2635.1 2646.0 2647.1 138663.3 143214.0 145742.3 154997.2 157754.0 15978.3 16703.3 16779.2 16992.7 17050.1 26604.0 26648.3 26755.0 26902.7 26985.6 10409.0 10643.5 10818.4 10884.6 10889.6 9557.2 9671.2 9703.9 9751.3 9838.0 9937.4 9965.4 9985.3 10014.4 10312.5 2818.2 2859.9 2924.8 2968.9 2983.7 1017.0 1032.6 1039.9 1053.0 1055.2 20827.1 21019.8 21158.5 21226.2 21448.5 1091.2 1107.6 1108.4 1109.1 1127.0 11258.3 11470.8 11630.1 11634.8 11744.3 629017.0 640509.0 644959.0 656445.0 658672.0 126651.0 126977.0 127507.0 128522.0 130045.3 1203.0 1207.5 1241.4 1245.2 1247.2 18406.6 18552.3 18921.5 19077.9 19235.0 61912.0 62904.0 63061.7 63252.0 64590.0 77775.2 79017.0 79122.0 79801.0 80194.0 15669.1 15828.6 15957.4 16136.5 16141.3 23350.6 23487.5 23774.4 23885.6 24003.4 19589.9 19610.6 19635.0 19645.8 19789.0 16899.7 17414.4 17437.5 17534.0 17678.6 18840.1 20044.2 20146.4 20282.9 20286.9 15709.8 15754.4 15757.3 15832.5 15917.1 19261.7 19321.6 19510.4 19573.5 19638.2 06:00:36 INFO - PID 1345 | 06:00:36 INFO - PID 1345 | [#3] mootools.html Cycles:180 Average:10372.69 Median:2724.25 stddev:7382.00 (271.0%) stddev-sans-first:7372.18 06:00:36 INFO - PID 1345 | Values: 1438.4 1495.3 1502.6 1506.1 1511.8 16359.6 16981.4 16983.2 16996.0 17000.6 16590.3 16681.5 16769.2 16804.1 16812.7 2856.6 2961.6 2968.0 2970.2 2971.9 2933.9 2965.7 2970.5 2977.6 2988.9 2886.1 2941.9 2960.2 2964.2 2968.6 2815.0 2816.8 2817.3 2824.3 2833.0 2666.9 2688.7 2718.6 2723.9 2724.6 16037.4 16160.8 16293.7 16315.1 16393.1 8770.4 9031.6 9060.7 9097.9 9119.0 5730.5 5809.2 5853.7 5957.2 5976.1 2063.6 2128.3 2144.6 2192.9 2199.4 26400.1 26931.2 28995.3 30102.8 30127.2 7800.5 7819.4 7819.8 8036.1 8063.1 19913.4 19938.8 19977.1 19979.7 20005.2 8727.3 8811.9 8815.9 8826.5 8829.2 7835.8 7976.7 8105.1 8163.2 8166.5 5867.2 5879.2 5922.7 5978.0 6033.2 1629.3 1635.6 1637.7 1642.6 1648.6 941.5 963.7 974.3 975.9 982.1 8385.0 8424.9 8451.9 8467.4 8491.0 774.2 791.4 807.3 817.2 818.7 4801.5 5921.1 6102.7 6162.9 6214.0 23460.3 23941.6 23990.8 24180.8 24398.1 23642.4 23883.5 23903.5 23991.9 24129.5 12078.9 12491.0 12676.1 12733.4 12745.2 9271.0 9503.3 9547.4 9578.9 9724.4 20090.6 20480.0 20769.3 20835.9 21088.2 19951.7 21050.5 21147.8 21303.0 22160.8 12773.6 12864.8 12888.9 12961.5 12974.3 15246.1 15447.9 15457.9 15525.8 15552.3 12425.9 12571.1 12618.7 12685.7 12704.8 12225.1 12233.3 12423.8 12510.1 12536.8 11472.7 11724.3 11735.1 11738.8 11739.8 8249.5 8362.0 8399.7 8469.1 8523.5 8516.3 8566.4 8652.9 8735.3 8826.6 06:00:36 INFO - PID 1345 | 06:00:36 INFO - PID 1345 | [#4] prototype.html Cycles:180 Average:5691.01 Median:6234.09 stddev:1556.35 (25.0%) stddev-sans-first:1511.83 06:00:36 INFO - PID 1345 | Values: 534.7 557.9 567.4 568.4 569.1 6208.5 6286.9 6288.2 6308.6 6374.4 6190.0 6203.4 6231.0 6268.9 6323.8 4996.6 5018.6 5027.3 5037.8 5067.8 4933.8 5061.2 5077.8 5090.9 5112.9 5028.3 5038.7 5069.9 5074.6 5100.9 4961.0 4997.2 5040.1 5056.2 5078.4 4651.0 4667.2 4668.9 4674.9 4683.0 6177.8 6217.0 6225.4 6285.9 6315.8 5919.7 5944.8 5948.5 5958.8 5965.0 6699.3 6711.5 6723.8 6735.1 6740.3 5158.4 5193.5 5196.7 5201.6 5203.9 6982.9 6985.0 6986.2 7002.9 7012.1 5322.4 5324.7 5338.3 5341.0 5341.7 5399.2 5421.3 5423.5 5436.5 5462.6 5892.3 5913.4 5932.6 5951.9 5962.6 6399.5 6403.8 6661.6 6696.6 6705.1 1972.0 1978.9 1997.7 2003.5 2005.5 1261.6 1500.9 1504.3 1509.8 1528.7 6043.2 6156.3 6165.4 6183.2 6237.2 6740.2 6789.5 6810.8 6816.5 6840.8 6148.5 6168.0 6195.9 6224.5 6254.3 3518.8 3541.2 3546.6 3547.4 3597.8 7046.4 7078.5 7099.3 7102.8 7198.4 6960.3 6966.1 6970.3 6995.8 7064.0 7077.7 7108.5 7130.1 7165.6 7166.4 6681.9 6748.5 6778.9 6786.7 6792.3 6734.4 6787.2 6796.9 6821.8 6830.6 7043.0 7060.4 7090.6 7119.4 7160.2 5265.3 5305.7 5318.7 5358.7 5361.5 6722.2 6750.7 6794.3 6815.5 6833.5 6450.9 6533.2 6539.8 6549.3 6549.4 6391.6 6456.5 6490.0 6494.7 6556.7 6341.9 6432.8 6502.2 6529.2 6546.2 6190.9 6602.4 6606.3 6698.3 6702.2 6336.2 6388.4 6416.4 6418.8 6459.6 06:00:36 INFO - PID 1345 | 06:00:36 INFO - PID 1345 | [#5] yui.html Cycles:180 Average:29356.17 Median:18850.34 stddev:44931.55 (238.4%) stddev-sans-first:45006.91 06:00:36 INFO - PID 1345 | Values: 931.3 947.7 992.3 1012.9 1018.3 20335.6 21272.3 21553.8 21568.9 21576.8 19208.9 19333.4 19469.8 19487.7 19505.0 1041.7 1060.6 1061.3 1061.4 1062.4 1088.3 1091.7 1094.7 1095.1 1095.8 1078.5 1118.3 1126.4 1128.1 1140.9 1114.0 1121.5 1122.1 1122.6 1123.2 769.2 806.0 826.6 827.8 828.0 20405.0 20519.7 20645.5 20696.6 20739.1 23062.0 23261.0 23854.8 24110.5 24144.3 18342.6 18760.2 18868.2 18888.8 18962.8 3985.4 4013.3 4022.2 4023.4 4031.5 136050.0 164581.0 164671.0 166832.0 169144.0 18410.7 18706.2 18713.3 18801.6 18940.1 30653.2 30881.0 31258.7 34042.1 34772.5 8722.0 8771.4 8773.6 8792.5 8812.2 4236.6 4280.2 4355.9 4361.8 4371.9 5907.6 6043.2 6111.8 6119.7 6136.8 1258.3 1292.3 1294.6 1295.2 1312.5 733.4 746.5 746.9 757.8 767.5 12986.4 13244.9 13327.0 13518.8 13804.9 652.0 658.7 660.7 674.5 677.7 5599.3 5769.0 5840.1 5947.3 5983.4 159515.0 160065.0 160123.0 161229.0 162837.2 143064.0 143945.3 144760.7 146345.0 147002.3 122288.0 124173.4 125921.4 127441.0 130254.0 10395.1 10452.4 10680.5 10692.9 10996.6 54755.5 56157.4 56183.7 56487.2 56876.0 72489.6 73563.0 73637.6 73737.0 76073.9 17362.8 17452.6 17619.1 17742.1 17755.6 19623.7 20485.2 20681.7 20688.2 20912.2 16332.0 16464.9 16587.5 16616.5 16658.3 18629.2 18706.7 18771.0 18802.2 18877.0 18765.2 18795.9 18811.0 18832.4 19015.6 12195.4 12661.1 12767.6 12975.9 13088.7 13029.3 13031.1 13198.5 13317.6 13353.0 06:00:36 INFO - PID 1345 | -------- Summary: end -------- 06:00:36 INFO - PID 1345 | 06:00:37 INFO - TEST-INFO | 1345: exit 0 06:00:37 INFO - TEST-OK | dromaeo_css | took 1108761ms 06:00:37 INFO - TEST-START | kraken 06:00:37 INFO - Initialising browser for kraken test... 06:00:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33963/getInfo.html -profile /tmp/tmpOqydAX/profile 06:00:37 INFO - TEST-INFO | started process 1748 (/builds/slave/test/build/application/firefox/firefox http://localhost:33963/getInfo.html) 06:00:42 INFO - TEST-INFO | 1748: exit 0 06:00:42 INFO - Browser initialized. 06:00:42 INFO - Running cycle 1/1 for kraken test... 06:00:42 INFO - Using env: {'DISPLAY': ':0', 06:00:42 INFO - 'HOME': '/home/cltbld', 06:00:42 INFO - 'JSGC_DISABLE_POISONING': '1', 06:00:42 INFO - 'LANG': 'en_US.UTF-8', 06:00:42 INFO - 'LANGUAGE': 'en_US:en', 06:00:42 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 06:00:42 INFO - 'LOGNAME': 'cltbld', 06:00:42 INFO - 'MAIL': '/var/mail/cltbld', 06:00:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:00:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:00:42 INFO - 'MOZ_CRASHREPORTER': '1', 06:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:42 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 06:00:42 INFO - 'MOZ_NO_REMOTE': '1', 06:00:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:00:42 INFO - 'NO_EM_RESTART': '1', 06:00:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:42 INFO - 'PWD': '/builds/slave/test', 06:00:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:00:42 INFO - 'RUST_BACKTRACE': 'full', 06:00:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:00:42 INFO - 'SHELL': '/bin/bash', 06:00:42 INFO - 'SHLVL': '1', 06:00:42 INFO - 'TERM': 'linux', 06:00:42 INFO - 'TMOUT': '86400', 06:00:42 INFO - 'USER': 'cltbld', 06:00:42 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183', 06:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:42 INFO - '_': '/tools/buildbot/bin/python'} 06:00:42 INFO - TEST-INFO | started process 2024 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOqydAX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:43 INFO - PID 2024 | 06:00:43 INFO - PID 2024 | (/builds/slave/test/build/application/firefox/firefox:2072): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:00:43 INFO - PID 2024 | 06:00:54 INFO - PID 2024 | RSS: Main: 146513920 06:00:54 INFO - PID 2024 | 06:00:56 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33963/tests/kraken/driver.html?testName=audio-beat-detection) 06:00:57 INFO - PID 2024 | RSS: Main: 151773184 06:00:57 INFO - PID 2024 | 06:01:00 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33963/tests/kraken/driver.html?testName=audio-dft) 06:01:00 INFO - PID 2024 | RSS: Main: 151687168 06:01:00 INFO - PID 2024 | 06:01:03 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33963/tests/kraken/driver.html?testName=audio-fft) 06:01:04 INFO - PID 2024 | RSS: Main: 148058112 06:01:04 INFO - PID 2024 | 06:01:06 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33963/tests/kraken/driver.html?testName=audio-oscillator) 06:01:06 INFO - PID 2024 | RSS: Main: 149164032 06:01:06 INFO - PID 2024 | 06:01:08 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33963/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:01:09 INFO - PID 2024 | RSS: Main: 148975616 06:01:09 INFO - PID 2024 | 06:01:12 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33963/tests/kraken/driver.html?testName=imaging-darkroom) 06:01:13 INFO - PID 2024 | RSS: Main: 149192704 06:01:13 INFO - PID 2024 | 06:01:16 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33963/tests/kraken/driver.html?testName=imaging-desaturate) 06:01:17 INFO - PID 2024 | RSS: Main: 150114304 06:01:17 INFO - PID 2024 | 06:01:20 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33963/tests/kraken/driver.html?testName=json-parse-financial) 06:01:20 INFO - PID 2024 | RSS: Main: 151629824 06:01:20 INFO - PID 2024 | 06:01:22 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33963/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:01:22 INFO - PID 2024 | RSS: Main: 151445504 06:01:22 INFO - PID 2024 | 06:01:24 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33963/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:01:25 INFO - PID 2024 | RSS: Main: 151076864 06:01:25 INFO - PID 2024 | 06:01:27 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33963/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:01:27 INFO - PID 2024 | RSS: Main: 148119552 06:01:27 INFO - PID 2024 | 06:01:30 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33963/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:01:30 INFO - PID 2024 | RSS: Main: 148336640 06:01:30 INFO - PID 2024 | 06:01:33 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33963/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:01:34 INFO - PID 2024 | RSS: Main: 149200896 06:01:34 INFO - PID 2024 | 06:01:35 INFO - PID 2024 | Cycle 1(1): loaded http://localhost:33963/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33963/tests/kraken/driver.html?testName=ai-astar) 06:01:36 INFO - PID 2024 | RSS: Main: 150831104 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | __start_tp_report 06:01:36 INFO - PID 2024 | _x_x_mozilla_page_load 06:01:36 INFO - PID 2024 | _x_x_mozilla_page_load_details 06:01:36 INFO - PID 2024 | |i|pagename|runs| 06:01:36 INFO - PID 2024 | |0;ai-astar;106;105;105;105;107;105;93;107;105;107 06:01:36 INFO - PID 2024 | |1;audio-beat-detection;113;116;112;113;137;118;116;113;113;116 06:01:36 INFO - PID 2024 | |2;audio-dft;163;162;162;164;161;162;164;163;166;168 06:01:36 INFO - PID 2024 | |3;audio-fft;76;74;74;73;73;74;73;73;73;73 06:01:36 INFO - PID 2024 | |4;audio-oscillator;79;77;77;78;78;82;77;79;78;81 06:01:36 INFO - PID 2024 | |5;imaging-gaussian-blur;110;114;113;114;113;114;113;117;114;114 06:01:36 INFO - PID 2024 | |6;imaging-darkroom;149;151;150;150;150;151;150;149;149;150 06:01:36 INFO - PID 2024 | |7;imaging-desaturate;95;94;93;94;95;96;94;94;94;95 06:01:36 INFO - PID 2024 | |8;json-parse-financial;44;43;45;43;44;47;46;42;42;41 06:01:36 INFO - PID 2024 | |9;json-stringify-tinderbox;36;37;40;36;37;37;37;36;36;38 06:01:36 INFO - PID 2024 | |10;stanford-crypto-aes;72;71;69;71;70;68;69;73;73;71 06:01:36 INFO - PID 2024 | |11;stanford-crypto-ccm;103;115;108;103;102;104;107;109;111;103 06:01:36 INFO - PID 2024 | |12;stanford-crypto-pbkdf2;153;149;149;150;149;155;149;149;155;147 06:01:36 INFO - PID 2024 | |13;stanford-crypto-sha256-iterative;65;62;58;58;58;61;62;57;59;64 06:01:36 INFO - PID 2024 | __end_tp_report 06:01:36 INFO - PID 2024 | __start_cc_report 06:01:36 INFO - PID 2024 | _x_x_mozilla_cycle_collect,704 06:01:36 INFO - PID 2024 | __end_cc_report 06:01:36 INFO - PID 2024 | __startTimestamp1507122096070__endTimestamp 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | ------- Summary: start ------- 06:01:36 INFO - PID 2024 | Number of tests: 14 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#0] ai-astar Cycles:10 Average:104.50 Median:106.50 stddev:4.14 (3.9%) stddev-sans-first:4.36 06:01:36 INFO - PID 2024 | Values: 106.0 105.0 105.0 105.0 107.0 105.0 93.0 107.0 105.0 107.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#1] audio-beat-detection Cycles:10 Average:116.70 Median:116.00 stddev:7.39 (6.4%) stddev-sans-first:7.72 06:01:36 INFO - PID 2024 | Values: 113.0 116.0 112.0 113.0 137.0 118.0 116.0 113.0 113.0 116.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:2.12 (1.3%) stddev-sans-first:2.24 06:01:36 INFO - PID 2024 | Values: 163.0 162.0 162.0 164.0 161.0 162.0 164.0 163.0 166.0 168.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#3] audio-fft Cycles:10 Average:73.60 Median:73.50 stddev:0.97 (1.3%) stddev-sans-first:0.50 06:01:36 INFO - PID 2024 | Values: 76.0 74.0 74.0 73.0 73.0 74.0 73.0 73.0 73.0 73.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#4] audio-oscillator Cycles:10 Average:78.60 Median:78.50 stddev:1.71 (2.2%) stddev-sans-first:1.81 06:01:36 INFO - PID 2024 | Values: 79.0 77.0 77.0 78.0 78.0 82.0 77.0 79.0 78.0 81.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:1.71 (1.5%) stddev-sans-first:1.22 06:01:36 INFO - PID 2024 | Values: 110.0 114.0 113.0 114.0 113.0 114.0 113.0 117.0 114.0 114.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:150.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 06:01:36 INFO - PID 2024 | Values: 149.0 151.0 150.0 150.0 150.0 151.0 150.0 149.0 149.0 150.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.84 (0.9%) stddev-sans-first:0.87 06:01:36 INFO - PID 2024 | Values: 95.0 94.0 93.0 94.0 95.0 96.0 94.0 94.0 94.0 95.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#8] json-parse-financial Cycles:10 Average:43.70 Median:44.00 stddev:1.89 (4.3%) stddev-sans-first:2.00 06:01:36 INFO - PID 2024 | Values: 44.0 43.0 45.0 43.0 44.0 47.0 46.0 42.0 42.0 41.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:1.25 (3.4%) stddev-sans-first:1.27 06:01:36 INFO - PID 2024 | Values: 36.0 37.0 40.0 36.0 37.0 37.0 37.0 36.0 36.0 38.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#10] stanford-crypto-aes Cycles:10 Average:70.70 Median:71.00 stddev:1.70 (2.4%) stddev-sans-first:1.74 06:01:36 INFO - PID 2024 | Values: 72.0 71.0 69.0 71.0 70.0 68.0 69.0 73.0 73.0 71.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#11] stanford-crypto-ccm Cycles:10 Average:106.50 Median:107.50 stddev:4.28 (4.0%) stddev-sans-first:4.34 06:01:36 INFO - PID 2024 | Values: 103.0 115.0 108.0 103.0 102.0 104.0 107.0 109.0 111.0 103.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.50 Median:149.50 stddev:2.80 (1.9%) stddev-sans-first:2.82 06:01:36 INFO - PID 2024 | Values: 153.0 149.0 149.0 150.0 149.0 155.0 149.0 149.0 155.0 147.0 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:61.50 stddev:2.80 (4.5%) stddev-sans-first:2.42 06:01:36 INFO - PID 2024 | Values: 65.0 62.0 58.0 58.0 58.0 61.0 62.0 57.0 59.0 64.0 06:01:36 INFO - PID 2024 | -------- Summary: end -------- 06:01:36 INFO - PID 2024 | 06:01:36 INFO - PID 2024 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:01:36 INFO - TEST-INFO | 2024: exit 0 06:01:36 INFO - TEST-OK | kraken | took 59867ms 06:01:36 INFO - SUITE-END | took 1168s 06:01:37 INFO - Completed test suite (00:19:28) 06:01:37 INFO - javascript benchmark 06:01:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9154.289168845018, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3838.6353296436846, 3857.4599556062158, 3875.5155605549307, 3899.3566061599845, 3901.3367727486325, 6286.214223222098, 6314.526410519218, 6330.841728956779, 6347.968260158698, 6426.871462570746, 6329.810105696836, 6330.936690633103, 6352.555321127523, 6353.904691429632, 6389.073584330269, 5589.944100559003, 5678.858028549299, 5693.0, 5718.828435146952, 5743.655380677173, 5644.04051311276, 5665.1502274658715, 5672.404397538261, 5706.543476521889, 5746.252987111669, 870.8978850988317, 5533.08704063833, 5641.407652196522, 5707.942920570804, 5755.366909639957, 5524.861878453031, 5533.114701647757, 5591.608587398884, 5636.239107720447, 5679.971600142014, 5601.411851755548, 5603.60774745768, 5664.603477756558, 5685.147227915804, 5696.800611978602, 6221.502279817649, 6252.937470625327, 6253.249610046823, 6275.811725648238, 6306.274778400497, 6737.966310168466, 6770.221424536192, 6811.931880681229, 6857.965710171467, 6875.106236189314, 6684.598924064571, 6753.054572359873, 6774.966125169342, 6836.623985680785, 6869.072675188835, 6271.0, 6332.081848131995, 6345.87308253837, 6349.301576826545, 6349.555531112824, 6963.059986901805, 7071.681774320168, 7073.3987611053635, 7074.504784665024, 7102.254263302382, 6701.061851340816, 6727.9327206728285, 6728.596284222962, 6814.046033555306, 6819.965900170567, 6713.295104014008, 6789.93210067893, 6873.175218973755, 6938.930610693828, 6966.860662786716, 6758.222804377461, 6760.526763126534, 6808.217055038734, 6835.863282734317, 6916.100906882174, 6745.2242992056545, 6815.114035175495, 6846.110005699227, 6894.0, 6900.585964842126, 4906.288588154697, 4924.704517728947, 4976.2286845539, 4986.626003049786, 4991.725455100004, 4427.313766366218, 4443.0, 4476.6418686505, 4494.213512635341, 4500.347449619787, 6222.751089956351, 6238.532110091671, 6251.437370636664, 6270.655113968684, 6295.748170073238, 6767.593944363354, 6771.424428923498, 6789.694463749143, 6863.416609588141, 6866.588004719732, 6173.197484327009, 6190.288116866528, 6201.255849298022, 6253.74985000604, 6258.186435763321, 5464.207689885024, 5503.944960550423, 5527.419620939824, 5531.3362396511875, 5533.501984821385, 7000.894986575206, 7076.787696369117, 7077.504574679826, 7185.6047917364, 7188.676509557083, 6777.695003724943, 6796.524243302922, 6801.18385793708, 6803.319668033157, 6804.11546498952, 7109.182444019004, 7114.324139206767, 7117.039199707973, 7117.572945623279, 7118.9644051779405, 6749.8702518351265, 6756.121704178622, 6765.966170169315, 6856.760013399507, 6898.689558969959, 6889.448844092361, 6901.723931042702, 6902.896456553055, 6937.202221744362, 6999.545029573062, 6465.579737317059, 6569.080328754074, 6630.569013014138, 6670.699818508082, 6720.798376048726, 6551.0, 6552.475801935929, 6576.210854697467, 6617.404433600999, 6621.172353455818, 6417.96791016042, 6566.4418524425955, 6581.539292249497, 6602.075709400784, 6610.3720146586, 6625.039369291522, 6680.0, 6700.4639628830555, 6708.362705542965, 6710.060591517289, 6435.195600549931, 6446.290908000023, 6511.73953041873, 6528.477721782151, 6534.771283005072], "value": 6192.976405740989, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [976.3702411944295, 987.7455631348183, 1002.1982414068748, 1002.2182697495956, 1008.4655132779627, 46695.76652116739, 47436.0, 47483.28775068368, 47534.762326188364, 47548.28677569839, 43996.120077598505, 44020.55979440205, 44196.11607767851, 44295.335569966475, 44302.55697443025, 7818.257265559762, 7983.321417679475, 7995.800104997364, 8002.919970800276, 8009.679612815509, 7841.921580784205, 7923.445358824884, 7944.404169687267, 7945.205479452066, 7953.880691789628, 8487.44831585948, 8665.090165532623, 8675.219230269275, 8684.56577171142, 8685.913140868604, 8563.486190828538, 8639.308855291592, 8641.481511109354, 8643.049264580892, 8650.0, 8061.6372263248295, 8225.300849427806, 8298.294644955186, 8313.002439707214, 8316.209960053786, 42719.35920961188, 43152.784236078616, 43179.78410107961, 43336.783316083216, 43390.349144762855, 3752.3808571585655, 3798.0694729791376, 3801.334766415866, 3801.6198380161763, 3802.08749900022, 34102.31795364104, 34108.6589134108, 34214.48678269828, 34228.31543369144, 34278.14304642404, 5519.862003449905, 5528.530074943595, 5537.252470916415, 5571.387147413781, 5630.268065151545, 213973.0, 214314.0, 215252.0, 216147.0, 216159.0, 47806.282905756445, 48834.0, 53801.73099134479, 54984.0, 55016.44983550113, 57055.0, 57658.0, 57714.7114264426, 57847.13229301564, 57853.71073144692, 16302.67394652124, 16543.420980265633, 16573.0, 16652.417165399154, 16654.75017874733, 13253.73492530144, 13430.0, 13440.462381504825, 13446.126001809851, 13515.594532163854, 15130.319135638923, 15211.315490802941, 15316.770248446282, 15340.693186136215, 15394.0, 6082.300535438393, 6103.908441373383, 6110.236220472441, 6154.907676384858, 6172.537059720449, 1115.6597237842373, 1116.3525155410005, 1123.224974767408, 1127.0645364347572, 1132.6262333429947, 25899.611505827426, 26131.869340653557, 26167.476650466884, 26292.079777207706, 26345.341366465607, 1210.5036934856842, 1213.9514419423124, 1225.8957988570894, 1228.5147366790247, 1244.2783185752241, 13707.314634268247, 14002.929985350213, 14012.789808152886, 14013.649658758408, 14290.499832505813, 291156.0, 291991.0, 292314.0, 292835.0, 293636.0, 61443.69278153581, 61560.692196539625, 61703.38296617066, 61787.382126179065, 62342.818001369735, 1552.3790483806567, 1552.9068255904908, 1576.1488796050005, 1577.2350410051115, 1599.224376177553, 25591.48817023612, 25743.356416089748, 25848.0, 26042.73957260403, 26068.478630426907, 43581.78209108934, 43635.0, 43662.781686091366, 43671.0, 43760.562394376924, 233439.0, 235840.0, 236312.0, 236900.0, 237226.0, 37939.05152371213, 38597.22805543929, 40870.0, 41151.58848411478, 41240.38139427971, 50894.23658645049, 52193.0, 52916.0, 52969.735151324, 53228.2015769756, 34990.65009349976, 35176.0, 35442.46836297406, 35660.28679426449, 35720.285594288485, 37223.44164837584, 37529.4370584436, 37553.0, 37605.62394376021, 37761.2447751049, 43750.34374484449, 43902.78048609736, 44209.0, 44953.0, 45163.32255016243, 36505.817470913535, 36649.81675091608, 36868.0, 36976.26047479089, 37606.62393376031, 26846.06038788633, 27666.861665691544, 27753.16740497788, 27860.721392785814, 27919.44161116807], "value": 19213.22753946903, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.1310046284508, 634.7175506899432, 639.2201514152732, 641.4099028893418, 646.5215740352139, 23178.652320215188, 23211.3036608902, 24018.639720404186, 24063.879680601593, 24194.758052419515, 25574.488510229785, 26655.86672066637, 26668.466630667423, 26686.19941401761, 26707.599386009224, 6534.0852280680865, 6713.765018224363, 6714.597124172541, 6715.664216789165, 6732.461403087766, 6472.449841763456, 6528.2166140063255, 6773.796786096425, 6785.117934668486, 6792.796216113525, 6779.932200678004, 6783.694733736969, 6785.72857085716, 6789.9815027745735, 6795.7961261162245, 6609.900851487231, 6731.394174524292, 6766.086578311939, 6781.321867813229, 6875.3812156906115, 6450.161479007699, 6463.062855885917, 6464.159659244315, 6470.38531339522, 6482.6434546099945, 30443.0, 30511.847440762875, 30590.541141882888, 30609.540856887164, 30618.38763224723, 2236.2732112063645, 2269.171752310395, 2271.670607761865, 2283.703118594572, 2283.7716228377194, 20399.388018359325, 21071.578568428544, 21333.35999920005, 21359.67960480594, 21441.249556265455, 2578.2136448383235, 2579.3293743626605, 2635.0513815026575, 2646.0, 2647.060293595784, 138663.30668346593, 143214.0, 145742.27128864394, 154997.22501387645, 157754.0, 15978.33040140739, 16703.33186672544, 16779.161041947853, 16992.74510882338, 17050.147492625318, 26604.0, 26648.333791655365, 26755.0, 26902.730972690413, 26985.595216071775, 10409.0, 10643.52104155315, 10818.404987725751, 10884.61903833362, 10889.618863339745, 9557.166140722005, 9671.226301895831, 9703.9029609703, 9751.268654850772, 9837.950810245902, 9937.403755774676, 9965.35225210359, 9985.251106167067, 10014.39913605186, 10312.484375781181, 2818.168640251131, 2859.885604575794, 2924.795264331519, 2968.9406211876076, 2983.7463815575484, 1016.974575635615, 1032.550840384435, 1039.859618951432, 1052.9684109476727, 1055.2191378380096, 20827.06278217484, 21019.78980210209, 21158.47103822386, 21226.150953961667, 21448.4637884051, 1091.1979694924225, 1107.6234080412698, 1108.362691452402, 1109.1293334732197, 1127.0251232683834, 11258.268212566158, 11470.827937580943, 11630.100144203743, 11634.825477617842, 11744.255744255745, 629017.0, 640509.0, 644959.0, 656445.0, 658672.0, 126651.0, 126977.0, 127507.0, 128522.0, 130045.34977325053, 1203.0, 1207.4928530017414, 1241.4103300931986, 1245.1844042152404, 1247.2204871955028, 18406.631867362845, 18552.257909684, 18921.52310772279, 19077.90461047686, 19235.038248087818, 61912.0, 62904.0, 63061.68469157625, 63252.0, 64590.0, 77775.22224777906, 79017.0, 79122.0, 79801.0, 80194.0, 15669.138197399252, 15828.60428489251, 15957.361705531648, 16136.515904522883, 16141.273642686343, 23350.649740254248, 23487.53024939526, 23774.405639859142, 23885.64171537463, 24003.399915002265, 19589.902050489658, 19610.60778784445, 19635.0, 19645.803541964397, 19789.0, 16899.7465038027, 17414.390496332566, 17437.47687569375, 17534.03562804007, 17678.558036049202, 18840.057997100364, 20044.198232071136, 20146.39560813178, 20282.89858550757, 20286.89856550708, 15709.842901570839, 15754.369825207323, 15757.290921908312, 15832.525024249291, 15917.124558149413, 19261.711074333616, 19321.613567728287, 19510.414687559394, 19573.510662232988, 19638.214471420983], "value": 12865.716186280975, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.3958737330322, 1495.267319013683, 1502.564256365654, 1506.149025800423, 1511.8110236220473, 16359.59101022475, 16981.405650802226, 16983.23575439105, 16996.0, 17000.574985625364, 16590.25343859526, 16681.499555013368, 16769.24538395772, 16804.075775832323, 16812.74780878288, 2856.600075989363, 2961.5557666350114, 2967.9554806677916, 2970.153506250719, 2971.881124755007, 2933.8973135940246, 2965.747911427531, 2970.5247160454333, 2977.5533669949573, 2988.9402211955858, 2886.0620298402996, 2941.8529073546238, 2960.200745798633, 2964.170032390934, 2968.584398184256, 2814.957775633367, 2816.816906901055, 2817.337925587485, 2824.2798086487815, 2832.9511727861977, 2666.9333266668486, 2688.6504754382, 2718.619393284942, 2723.891044358223, 2724.6185534025253, 16037.438689645807, 16160.75758863618, 16293.674126517404, 16315.10266935318, 16393.09837958912, 8770.386072974894, 9031.638734450615, 9060.682876099305, 9097.909020909772, 9119.04250053748, 5730.484256416901, 5809.186713860063, 5853.678047707417, 5957.165996760457, 5976.103584462322, 2063.6182306273386, 2128.3082998025548, 2144.6461333879893, 2192.9232476863235, 2199.3841724317217, 26400.075997340195, 26931.19206423791, 28995.275118122005, 30102.84948575265, 30127.246818829706, 7800.531968081933, 7819.413543984224, 7819.804504887424, 8036.116027236999, 8063.1130575636635, 19913.402597922086, 19938.800611993986, 19977.101030453665, 19979.700304495444, 20005.199792008447, 8727.301815854717, 8811.911880881109, 8815.867761983576, 8826.51454170014, 8829.16122968317, 7835.804104897423, 7976.7208147714555, 8105.1489593593005, 8163.183681631789, 8166.510009399336, 5867.20792692981, 5879.235699359056, 5922.733476993546, 5977.970110149421, 6033.215681961317, 1629.3156874112901, 1635.6401591649817, 1637.656092220647, 1642.5975635969264, 1648.620817212048, 941.4680705401427, 963.720521048902, 974.3228456222857, 975.8633791269228, 982.0572250639466, 8384.958075209584, 8424.915750842536, 8451.873221901553, 8467.449615775078, 8490.957545212357, 774.156169774948, 791.4183075439547, 807.317816445103, 817.1705718695529, 818.6643476174859, 4801.4958429365115, 5921.082232254007, 6102.69486525672, 6162.890679677701, 6214.036824292242, 23460.296191114296, 23941.64087538688, 23990.760092398854, 24180.758192417852, 24398.146064887645, 23642.408939776644, 23883.52232955366, 23903.52192956131, 23991.880040599684, 24129.517409651708, 12078.879211207952, 12491.0, 12676.112672113048, 12733.426995785027, 12745.235285882876, 9271.02654221297, 9503.287253456157, 9547.42715437076, 9578.9042109578, 9724.416535007922, 20090.598188035867, 20479.976001199582, 20769.27307544229, 20835.8958205208, 21088.156473741492, 19951.700724489434, 21050.47373815667, 21147.78852211458, 21303.0, 22160.778392216518, 12773.61679149627, 12864.807027894776, 12888.935555322163, 12961.546345877849, 12974.286414247306, 15246.085234885943, 15447.922760386125, 15457.922710386376, 15525.844741552892, 15552.30014649321, 12425.93787031059, 12571.081735911703, 12618.684532886751, 12685.682857928625, 12704.809427858405, 12225.144239903122, 12233.3271670059, 12423.813642795547, 12510.124291299522, 12536.811947820972, 11472.713182170513, 11724.296542207494, 11735.119866010255, 11738.823917641414, 11739.765204696028, 8249.463784853966, 8361.95819020901, 8399.664013439395, 8469.110743371857, 8523.531205783742, 8516.276116530164, 8566.443181193203, 8652.870206947027, 8735.257503112307, 8826.646934122564], "value": 7335.643533424428, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.6791924845091, 557.9386267510574, 567.4183961439527, 568.4116938968169, 569.097979702172, 6208.534359923007, 6286.905696414552, 6288.1764288356435, 6308.621482711041, 6374.394432528914, 6189.969050154754, 6203.425578384965, 6230.9688451557795, 6268.874622507547, 6323.810285691425, 4996.625253106032, 5018.623603229754, 5027.296178534999, 5037.773300201481, 5067.797288108472, 4933.7533123343865, 5061.215511595708, 5077.847664570068, 5090.872728181788, 5112.8977420451565, 5028.296038554606, 5038.722870242135, 5069.898602027957, 5074.594032477411, 5100.872478188038, 4961.0, 4997.225430058329, 5040.1179793535985, 5056.165732654126, 5078.365204349457, 4650.976745116287, 4667.242869490059, 4668.906621867578, 4674.929876051862, 4682.976585117087, 6177.81466556004, 6217.0, 6225.408586184305, 6285.87428251437, 6315.842103947392, 5919.704014799243, 5944.791932282393, 5948.524118070587, 5958.7616495340135, 5965.0, 6699.296573859772, 6711.496637752188, 6723.798286051427, 6735.124433823572, 6740.292269311701, 5158.40678321994, 5193.532582067594, 5196.662216955886, 5201.635885488017, 5203.9219411708855, 6982.895256571155, 6984.965075174591, 6986.161660600709, 7002.894956575707, 7012.123484564429, 5322.3879253885525, 5324.707141107277, 5338.279332290169, 5340.973295133499, 5341.732913354394, 5399.16428414341, 5421.2681288025615, 5423.511883930465, 5436.537894278951, 5462.617616766788, 5892.292924849045, 5913.379095194942, 5932.567647264601, 5951.880962380815, 5962.582619216613, 6399.520035997319, 6403.807885763435, 6661.633610151392, 6696.598204107671, 6705.1283333166375, 1971.9901400492906, 1978.9208431662573, 1997.670384386589, 2003.5492014296958, 2005.508650380637, 1261.5899832554273, 1500.9099454032576, 1504.3456096597797, 1509.8414666460083, 1528.740114180592, 6043.244594425697, 6156.26124865019, 6165.414285642857, 6183.165272688129, 6237.220347456568, 6740.224874139439, 6789.524733268624, 6810.761623343159, 6816.522843400914, 6840.794776156723, 6148.4773794227995, 6167.969160154171, 6195.907061394083, 6224.533160013018, 6254.343293954069, 3518.7888726676483, 3541.2032292734443, 3546.556680414948, 3547.361474934485, 3597.766145200606, 7046.436285097282, 7078.539894906815, 7099.2545782693105, 7102.751403700949, 7198.424126069902, 6960.338767817049, 6966.12923384577, 6970.337817907291, 6995.755148569776, 7064.0, 7077.7168913242895, 7108.537945033556, 7130.112304516679, 7165.570065796069, 7166.426685865118, 6681.933180668326, 6748.460123190035, 6778.93221067783, 6786.728530858711, 6792.320767923366, 6734.3602357775935, 6787.151606049244, 6796.864062718817, 6821.829454263485, 6830.556013859084, 7043.0, 7060.435165186876, 7090.574565526085, 7119.430445564446, 7160.248173941661, 5265.315508983807, 5305.65513241638, 5318.707471088973, 5358.705271210121, 5361.544268737201, 6722.193336799615, 6750.6624668767345, 6794.252632210354, 6815.454763618799, 6833.45332373399, 6450.903236451363, 6533.216014078151, 6539.836504087436, 6549.312322206099, 6549.443297319781, 6391.648459334781, 6456.515761317826, 6490.0, 6494.740210391531, 6556.704948277244, 6341.90487142684, 6432.807015789534, 6502.219733631805, 6529.249136349191, 6546.18172728409, 6190.876182476415, 6602.372774586406, 6606.306337834651, 6698.296678848651, 6702.151585576208, 6336.239651241881, 6388.425041746265, 6416.422521973045, 6418.775342862978, 6459.644719540471], "value": 5255.106011463172, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [931.3015238571076, 947.6635794293026, 992.2558081438921, 1012.9088382045614, 1018.27193556607, 20335.593288134227, 21272.255471058517, 21553.784462155374, 21568.8921555392, 21576.784232157694, 19208.903955480237, 19333.419997400102, 19469.805301947014, 19487.707684384746, 19505.0, 1041.666666666667, 1060.5704689600686, 1061.26242261628, 1061.3737894642159, 1062.3997441445597, 1088.29260980363, 1091.6997825597944, 1094.7208461842258, 1095.11842966412, 1095.7534554725203, 1078.487718333793, 1118.3234143343282, 1126.4086354663825, 1128.097521982415, 1140.8859114088607, 1114.0474893965625, 1121.5401685309025, 1122.068682993122, 1122.5846436818388, 1123.219362543033, 769.2077160524668, 805.9919400805976, 826.6280173921759, 827.8344331133737, 827.9503229806231, 20405.0, 20519.692204616942, 20645.4838629034, 20696.551241413108, 20739.066741996645, 23062.0, 23261.0, 23854.761452385428, 24110.517789644284, 24144.275671729876, 18342.63314733698, 18760.15579298935, 18868.245270189174, 18888.81111188884, 18962.81037189624, 3985.4021896715435, 4013.2976729072298, 4022.1955608878343, 4023.396490526415, 4031.496062992126, 136050.0, 164581.0, 164671.0, 166832.0, 169143.99999999878, 18410.723839142425, 18706.158222880003, 18713.345032924055, 18801.623967520572, 18940.147693353832, 30653.23366915845, 30881.0, 31258.687413126034, 34042.148946276044, 34772.478412823824, 8721.956390218009, 8771.429857059271, 8773.605187766565, 8792.47245165292, 8812.16284452976, 4236.5763423658, 4280.16536775332, 4355.912881742411, 4361.8441113105055, 4371.868843934687, 5907.586468947218, 6043.244594425697, 6111.786087486917, 6119.663418511936, 6136.846578835475, 1258.3267951645826, 1292.2698675248455, 1294.643972907458, 1295.1775622479604, 1312.520929860594, 733.3619750816823, 746.5110352718978, 746.8655641984386, 757.8408534207767, 767.4972892755254, 12986.415611297514, 13244.867551324556, 13327.0, 13518.797218041736, 13804.861951380559, 651.9543631945718, 658.733213048716, 660.6894759463045, 674.4536925090692, 677.7221339250982, 5599.339264035651, 5768.9711551442815, 5840.1239814027385, 5947.256592925884, 5983.401659833982, 159515.0, 160065.0, 160123.0, 161229.0, 162837.18581407017, 143064.0, 143945.28027360004, 144760.65718028476, 146345.0, 147002.26498867437, 122288.0, 124173.37913310557, 125921.37039314744, 127441.0, 130254.0, 10395.116415104801, 10452.372857628567, 10680.465976701289, 10692.946535267274, 10996.615118470816, 54755.53600088085, 56157.438425616856, 56183.71908140433, 56487.15269270882, 56876.0, 72489.63755181401, 73563.0, 73637.63181184059, 73737.0, 76073.85889211777, 17362.826371736628, 17452.563685907448, 17619.119044048002, 17742.112894354974, 17755.556111097325, 19623.70564441506, 20485.18059277672, 20681.689774653692, 20688.17247310151, 20912.16351345929, 16332.020078794834, 16464.917675411547, 16587.50237492829, 16616.50150495487, 16658.250378733228, 18629.161687723812, 18706.719399208752, 18771.0, 18802.247910083443, 18877.0, 18765.155567999747, 18795.90602046981, 18811.0, 18832.435026949213, 19015.619687606446, 12195.390230488263, 12661.05042121826, 12767.61697149087, 12975.870241297844, 13088.672783180496, 13029.283389413673, 13031.087823852618, 13198.538051168163, 13317.600471985468, 13353.0], "value": 9971.651990752074, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1363.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 105.0, 105.0, 107.0, 105.0, 93.0, 107.0, 105.0, 107.0], "value": 104.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 112.0, 113.0, 137.0, 118.0, 116.0, 113.0, 113.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 164.0, 161.0, 162.0, 164.0, 163.0, 166.0, 168.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 74.0, 73.0, 73.0, 74.0, 73.0, 73.0, 73.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 77.0, 78.0, 78.0, 82.0, 77.0, 79.0, 78.0, 81.0], "value": 78.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 113.0, 114.0, 113.0, 114.0, 113.0, 117.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 150.0, 150.0, 150.0, 151.0, 150.0, 149.0, 149.0, 150.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 94.0, 95.0, 96.0, 94.0, 94.0, 94.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 43.0, 45.0, 43.0, 44.0, 47.0, 46.0, 42.0, 42.0, 41.0], "value": 43.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 40.0, 36.0, 37.0, 37.0, 37.0, 36.0, 36.0, 38.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 69.0, 71.0, 70.0, 68.0, 69.0, 73.0, 73.0, 71.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 108.0, 103.0, 102.0, 104.0, 107.0, 109.0, 111.0, 103.0], "value": 106.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 149.0, 150.0, 149.0, 155.0, 149.0, 149.0, 155.0, 147.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 58.0, 58.0, 58.0, 61.0, 62.0, 57.0, 59.0, 64.0], "value": 60.4, "unit": "score"}]}]} 06:01:37 INFO - Return code: 0 06:01:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:01:37 INFO - # TBPL SUCCESS # 06:01:37 INFO - Running post-action listener: _package_coverage_data 06:01:37 INFO - Running post-action listener: _resource_record_post_action 06:01:37 INFO - [mozharness: 2017-10-04 13:01:37.332232Z] Finished run-tests step (success) 06:01:37 INFO - Running post-run listener: _resource_record_post_run 06:01:37 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 333635584; Read time: 508; Write time: 445732 06:01:37 INFO - TinderboxPrint: CPU usage
13.1% 06:01:37 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 508 06:01:37 INFO - TinderboxPrint: I/O write bytes / time
333,635,584 / 445,732 06:01:37 INFO - TinderboxPrint: CPU idle
8,223.7 (86.9%) 06:01:37 INFO - TinderboxPrint: CPU user
1,189.7 (12.6%) 06:01:37 INFO - TinderboxPrint: Swap in / out
0 / 0 06:01:37 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13471744; Read time: 0; Write time: 3300 06:01:37 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:01:37 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 8192; Write bytes: 319770624; Read time: 508; Write time: 442344 06:01:37 INFO - Running post-run listener: _upload_blobber_files 06:01:37 INFO - Blob upload gear active. 06:01:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:01:37 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 06:01:37 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'] 06:01:37 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 06:01:38 INFO - (blobuploader) - INFO - Open directory for files ... 06:01:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:01:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:01:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:45 INFO - (blobuploader) - INFO - Done attempting. 06:01:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:01:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:01:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:46 INFO - (blobuploader) - INFO - Done attempting. 06:01:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:01:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:01:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:47 INFO - (blobuploader) - INFO - Done attempting. 06:01:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:01:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:01:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:48 INFO - (blobuploader) - INFO - Done attempting. 06:01:48 INFO - (blobuploader) - INFO - Iteration through files over. 06:01:48 INFO - Return code: 0 06:01:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:01:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:01:48 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5441e82b2244e33efb4ada3d5a0a1e64335a806d8a9a4391069ce26a6ef219a7276d39810521827e5c9e0f02c73f2e6b82925270e4a7a5042c39273b2076f2a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81a057009ad86a15058403d242ccdfb128f3e2699989efa94e344a14daaab90c73f2711d24102448f544faeaeb64663e40dc5e74f3df9343ac56a2b94576c649", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a9efd9e017cad91ecddc4dc5562e0b8ff4a2e0a8c4a25ad73c0fb00b2b17f3c13dfffbfe115a59b2d0e09ab456c3519bfbbd7ccc0e3b04e22f7b33c8fc77c4d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8946fb12021428ed2e89e49111c34230ff02ae97c847a57eac9b683c4e78f57fcaa173faa33d8d496e7105d69612611c16deceeadd929bb6a2a34e3229fb9e8a"} 06:01:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:01:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:01:48 INFO - Contents: 06:01:48 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5441e82b2244e33efb4ada3d5a0a1e64335a806d8a9a4391069ce26a6ef219a7276d39810521827e5c9e0f02c73f2e6b82925270e4a7a5042c39273b2076f2a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81a057009ad86a15058403d242ccdfb128f3e2699989efa94e344a14daaab90c73f2711d24102448f544faeaeb64663e40dc5e74f3df9343ac56a2b94576c649", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a9efd9e017cad91ecddc4dc5562e0b8ff4a2e0a8c4a25ad73c0fb00b2b17f3c13dfffbfe115a59b2d0e09ab456c3519bfbbd7ccc0e3b04e22f7b33c8fc77c4d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8946fb12021428ed2e89e49111c34230ff02ae97c847a57eac9b683c4e78f57fcaa173faa33d8d496e7105d69612611c16deceeadd929bb6a2a34e3229fb9e8a"} 06:01:48 INFO - Running post-run listener: copy_logs_to_upload_dir 06:01:48 INFO - Copying logs to upload dir... 06:01:48 INFO - mkdir: /builds/slave/test/build/upload/logs 06:01:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.640878 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-10-04 06:01:48.667204) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-04 06:01:48.674809) ========= 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=af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5441e82b2244e33efb4ada3d5a0a1e64335a806d8a9a4391069ce26a6ef219a7276d39810521827e5c9e0f02c73f2e6b82925270e4a7a5042c39273b2076f2a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81a057009ad86a15058403d242ccdfb128f3e2699989efa94e344a14daaab90c73f2711d24102448f544faeaeb64663e40dc5e74f3df9343ac56a2b94576c649", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a9efd9e017cad91ecddc4dc5562e0b8ff4a2e0a8c4a25ad73c0fb00b2b17f3c13dfffbfe115a59b2d0e09ab456c3519bfbbd7ccc0e3b04e22f7b33c8fc77c4d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8946fb12021428ed2e89e49111c34230ff02ae97c847a57eac9b683c4e78f57fcaa173faa33d8d496e7105d69612611c16deceeadd929bb6a2a34e3229fb9e8a"} build_url:https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/E6uNJ6CDSdC-LmwGg1i0Wg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5441e82b2244e33efb4ada3d5a0a1e64335a806d8a9a4391069ce26a6ef219a7276d39810521827e5c9e0f02c73f2e6b82925270e4a7a5042c39273b2076f2a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81a057009ad86a15058403d242ccdfb128f3e2699989efa94e344a14daaab90c73f2711d24102448f544faeaeb64663e40dc5e74f3df9343ac56a2b94576c649", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a9efd9e017cad91ecddc4dc5562e0b8ff4a2e0a8c4a25ad73c0fb00b2b17f3c13dfffbfe115a59b2d0e09ab456c3519bfbbd7ccc0e3b04e22f7b33c8fc77c4d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8946fb12021428ed2e89e49111c34230ff02ae97c847a57eac9b683c4e78f57fcaa173faa33d8d496e7105d69612611c16deceeadd929bb6a2a34e3229fb9e8a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-04 06:01:48.705882) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 06:01:48.706163) ========= 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=af724c981e2a0f424dbabf99000001e7-1507120863.589748-1358190183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004618 ========= master_lag: 0.14 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-04 06:01:48.846338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-04 06:01:48.846651) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-04 06:01:48.846923) ========= ========= Total master_lag: 0.34 =========