builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-069 starttime: 1506687925.49 results: success (0) revision: cd9c8c48e4b3ded47a776f757008f3dcf570c59c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:25.492548) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:25.493409) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:25.497942) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:26.102169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:26.102986) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-29 05:25:26-- 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% 23.3M=0s 2017-09-29 05:25:26 (23.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127779 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:26.385466) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:26.385894) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018463 ========= master_lag: 0.18 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:26.589351) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-29 05:25:26.593417) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cd9c8c48e4b3ded47a776f757008f3dcf570c59c --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 cd9c8c48e4b3ded47a776f757008f3dcf570c59c --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=05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-29 05:25:26,676 truncating revision to first 12 chars 2017-09-29 05:25:26,676 Setting DEBUG logging. 2017-09-29 05:25:26,676 attempt 1/10 2017-09-29 05:25:26,676 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cd9c8c48e4b3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-29 05:25:27,623 unpacking tar archive at: mozilla-central-cd9c8c48e4b3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.288811 ========= master_lag: 0.86 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-29 05:25:28.741211) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:28.807510) ========= script_repo_revision: cd9c8c48e4b3ded47a776f757008f3dcf570c59c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:28.843346) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:28.846563) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-29 05:25:29.145022) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-29 05:25:29.145363) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:25:29 INFO - MultiFileLogger online at 20170929 05:25:29 in /builds/slave/test 05:25:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:25:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:25:29 INFO - {'append_to_log': False, 05:25:29 INFO - 'base_work_dir': '/builds/slave/test', 05:25:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:25:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:25:29 INFO - 'branch': 'Firefox-Non-PGO', 05:25:29 INFO - 'buildbot_json_path': 'buildprops.json', 05:25:29 INFO - 'code_coverage': False, 05:25:29 INFO - 'config_files': ('talos/linux_config.py',), 05:25:29 INFO - 'default_actions': ('clobber', 05:25:29 INFO - 'read-buildbot-config', 05:25:29 INFO - 'download-and-extract', 05:25:29 INFO - 'populate-webroot', 05:25:29 INFO - 'create-virtualenv', 05:25:29 INFO - 'install', 05:25:29 INFO - 'setup-mitmproxy', 05:25:29 INFO - 'run-tests'), 05:25:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:25:29 INFO - 'disable_ccov_upload': False, 05:25:29 INFO - 'disable_stylo': False, 05:25:29 INFO - 'download_minidump_stackwalk': True, 05:25:29 INFO - 'download_symbols': 'ondemand', 05:25:29 INFO - 'enable_stylo': False, 05:25:29 INFO - 'enable_webrender': False, 05:25:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:25:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:25:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:25:29 INFO - 'gecko_profile': False, 05:25:29 INFO - 'gecko_profile_interval': 0, 05:25:29 INFO - 'installer_path': 'installer.exe', 05:25:29 INFO - 'log_level': 'info', 05:25:29 INFO - 'log_name': 'talos', 05:25:29 INFO - 'log_to_console': True, 05:25:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:25:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:25:29 INFO - 'opt_config_files': (), 05:25:29 INFO - 'pip_index': False, 05:25:29 INFO - 'suite': 'dromaeojs-e10s', 05:25:29 INFO - 'system_bits': '32', 05:25:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:25:29 INFO - 'title': 'talos-linux64-ix-069', 05:25:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:25:29 INFO - 'use_talos_json': True, 05:25:29 INFO - 'verify': 'False', 05:25:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:25:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:25:29 INFO - 'work_dir': 'build'} 05:25:29 INFO - [mozharness: 2017-09-29 12:25:29.500842Z] Running clobber step. 05:25:29 INFO - Running pre-action listener: _resource_record_pre_action 05:25:29 INFO - Running main action method: clobber 05:25:29 INFO - rmtree: /builds/slave/test/build 05:25:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:25:30 INFO - Running post-action listener: _resource_record_post_action 05:25:30 INFO - [mozharness: 2017-09-29 12:25:30.659346Z] Finished clobber step (success) 05:25:30 INFO - [mozharness: 2017-09-29 12:25:30.659443Z] Running read-buildbot-config step. 05:25:30 INFO - Running pre-action listener: _resource_record_pre_action 05:25:30 INFO - Running main action method: read_buildbot_config 05:25:30 INFO - Using buildbot properties: 05:25:30 INFO - { 05:25:30 INFO - "project": "", 05:25:30 INFO - "product": "firefox", 05:25:30 INFO - "who": "archaeopteryx@coole-files.de", 05:25:30 INFO - "installer_path": "public/build/target.tar.bz2", 05:25:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:25:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:25:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:25:30 INFO - "stage_platform": "linux64", 05:25:30 INFO - "basedir": "/builds/slave/test", 05:25:30 INFO - "buildnumber": 881, 05:25:30 INFO - "platform": "ubuntu64_hw", 05:25:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:25:30 INFO - "slavebuilddir": "test", 05:25:30 INFO - "taskId": "RQD3iIs8TEG3eqBNEcIP4g", 05:25:30 INFO - "branch": "mozilla-central", 05:25:30 INFO - "script_repo_revision": "production", 05:25:30 INFO - "revision": "cd9c8c48e4b3ded47a776f757008f3dcf570c59c", 05:25:30 INFO - "slavename": "talos-linux64-ix-069", 05:25:30 INFO - "repo_path": "mozilla-central" 05:25:30 INFO - } 05:25:30 INFO - Finding installer, test and symbols from parent task. 05:25:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RQD3iIs8TEG3eqBNEcIP4g'}, attempt #1 05:25:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RQD3iIs8TEG3eqBNEcIP4g'}, attempt #1 05:25:31 INFO - Task dependencies: IH85rkEeSoiGVybgA5cHLA 05:25:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA'}, attempt #1 05:25:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.tar.bz2 05:25:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.test_packages.json 05:25:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.crashreporter-symbols.zip 05:25:32 INFO - Running post-action listener: _resource_record_post_action 05:25:32 INFO - [mozharness: 2017-09-29 12:25:32.121471Z] Finished read-buildbot-config step (success) 05:25:32 INFO - [mozharness: 2017-09-29 12:25:32.121689Z] Running download-and-extract step. 05:25:32 INFO - Running pre-action listener: _resource_record_pre_action 05:25:32 INFO - Running main action method: download_and_extract 05:25:32 INFO - mkdir: /builds/slave/test/build/tests 05:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:32 INFO - trying https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.test_packages.json 05:25:32 INFO - Downloading https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:25:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:25:32 INFO - Downloaded 989 bytes. 05:25:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:25:32 INFO - Using the following test package requirements: 05:25:32 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:25:32 INFO - u'common': [u'target.common.tests.zip'], 05:25:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:25:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:25:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:25:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:25:32 INFO - u'mozbase': [u'target.common.tests.zip'], 05:25:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:25:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:25:32 INFO - u'web-platform': [u'target.common.tests.zip', 05:25:32 INFO - u'target.web-platform.tests.tar.gz'], 05:25:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:25:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 05:25:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.common.tests.zip 05:25:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:25:32 INFO - Fetch https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.common.tests.zip into memory 05:25:33 INFO - Content-Length response header: 55366218 05:25:33 INFO - Bytes received: 55366218 05:25:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.talos.tests.zip 05:25:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:25:44 INFO - Fetch https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.talos.tests.zip into memory 05:25:45 INFO - Content-Length response header: 14521355 05:25:45 INFO - Bytes received: 14521355 05:25:45 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:25:45 INFO - trying https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.tar.bz2 05:25:45 INFO - Downloading https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:25:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:25:46 INFO - Downloaded 61539289 bytes. 05:25:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.tar.bz2 05:25:46 INFO - mkdir: /builds/slave/test/properties 05:25:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:25:46 INFO - Writing to file /builds/slave/test/properties/build_url 05:25:46 INFO - Contents: 05:25:46 INFO - build_url:https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.tar.bz2 05:25:46 INFO - Running post-action listener: _resource_record_post_action 05:25:46 INFO - Running post-action listener: find_tests_for_verification 05:25:46 INFO - Running post-action listener: set_extra_try_arguments 05:25:46 INFO - [mozharness: 2017-09-29 12:25:46.956807Z] Finished download-and-extract step (success) 05:25:46 INFO - [mozharness: 2017-09-29 12:25:46.956906Z] Running populate-webroot step. 05:25:46 INFO - Running pre-action listener: _resource_record_pre_action 05:25:46 INFO - Running main action method: populate_webroot 05:25:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:25:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:25:46 INFO - 'tests': ['tresize', 05:25:46 INFO - 'tcanvasmark']}, 05:25:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:25:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:25:46 INFO - 'tests': ['dromaeo_css', 05:25:46 INFO - 'kraken']}, 05:25:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:25:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:25:46 INFO - 'talos_options': ['--disable-stylo'], 05:25:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:25:46 INFO - 'tests': ['damp', 'tps']}, 05:25:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:25:46 INFO - 'talos_options': ['--disable-stylo'], 05:25:46 INFO - 'tests': ['damp', 'tps']}, 05:25:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:25:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:25:46 INFO - 'tests': ['dromaeo_dom']}, 05:25:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:25:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:25:46 INFO - 'tests': ['basic_compositor_video', 05:25:46 INFO - 'glvideo']}, 05:25:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:25:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:25:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:25:47 INFO - 'talos_options': ['--disable-stylo'], 05:25:47 INFO - 'tests': ['ts_paint_webext', 05:25:47 INFO - 'tp5o_webext']}, 05:25:47 INFO - 'other-e10s': {'tests': ['a11yr', 05:25:47 INFO - 'ts_paint', 05:25:47 INFO - 'tpaint', 05:25:47 INFO - 'sessionrestore', 05:25:47 INFO - 'sessionrestore_many_windows', 05:25:47 INFO - 'sessionrestore_no_auto_restore', 05:25:47 INFO - 'tabpaint', 05:25:47 INFO - 'cpstartup']}, 05:25:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:25:47 INFO - 'tests': ['a11yr', 05:25:47 INFO - 'ts_paint', 05:25:47 INFO - 'tpaint', 05:25:47 INFO - 'sessionrestore', 05:25:47 INFO - 'sessionrestore_many_windows', 05:25:47 INFO - 'sessionrestore_no_auto_restore', 05:25:47 INFO - 'tabpaint', 05:25:47 INFO - 'cpstartup']}, 05:25:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 05:25:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 05:25:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:25:47 INFO - 'tests': ['perf_reftest_singletons']}, 05:25:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:25:47 INFO - 'tests': ['perf_reftest']}, 05:25:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:25:47 INFO - 'tsvgr_opacity', 05:25:47 INFO - 'tart', 05:25:47 INFO - 'tscrollx', 05:25:47 INFO - 'tsvg_static']}, 05:25:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:25:47 INFO - 'tests': ['tsvgx', 05:25:47 INFO - 'tsvgr_opacity', 05:25:47 INFO - 'tart', 05:25:47 INFO - 'tscrollx', 05:25:47 INFO - 'tsvg_static']}, 05:25:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:25:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:25:47 INFO - 'talos_options': ['--disable-stylo'], 05:25:47 INFO - 'tests': ['tp5o']}, 05:25:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:25:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 05:25:47 INFO - 'talos_options': ['--mitmproxy', 05:25:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:25:47 INFO - '--firstNonBlankPaint'], 05:25:47 INFO - 'tests': ['tp6_google', 05:25:47 INFO - 'tp6_youtube', 05:25:47 INFO - 'tp6_amazon', 05:25:47 INFO - 'tp6_facebook']}, 05:25:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:25:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 05:25:47 INFO - 'talos_options': ['--disable-stylo', 05:25:47 INFO - '--mitmproxy', 05:25:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:25:47 INFO - '--firstNonBlankPaint'], 05:25:47 INFO - 'tests': ['tp6_google', 05:25:47 INFO - 'tp6_youtube', 05:25:47 INFO - 'tp6_amazon', 05:25:47 INFO - 'tp6_facebook']}, 05:25:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:25:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 05:25:47 INFO - 'talos_options': ['--stylo-threads=1', 05:25:47 INFO - '--mitmproxy', 05:25:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:25:47 INFO - '--firstNonBlankPaint'], 05:25:47 INFO - 'tests': ['tp6_google', 05:25:47 INFO - 'tp6_youtube', 05:25:47 INFO - 'tp6_amazon', 05:25:47 INFO - 'tp6_facebook']}, 05:25:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:25:47 INFO - 'talos_options': ['--xperf_path', 05:25:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:47 INFO - 'tests': ['tp5n']}, 05:25:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:25:47 INFO - 'talos_options': ['--disable-stylo', 05:25:47 INFO - '--xperf_path', 05:25:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:47 INFO - 'tests': ['tp5n']}}} 05:25:47 INFO - Running post-action listener: _resource_record_post_action 05:25:47 INFO - [mozharness: 2017-09-29 12:25:47.230251Z] Finished populate-webroot step (success) 05:25:47 INFO - [mozharness: 2017-09-29 12:25:47.230474Z] Running create-virtualenv step. 05:25:47 INFO - Running pre-action listener: _resource_record_pre_action 05:25:47 INFO - Running main action method: create_virtualenv 05:25:47 INFO - Creating virtualenv /builds/slave/test/build/venv 05:25:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:25:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:25:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:25:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:25:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:25:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:25:48 INFO - Installing pip...............done. 05:25:48 INFO - Return code: 0 05:25:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:25:48 INFO - Reading from file tmpfile_stdout 05:25:48 INFO - Output received: 05:25:48 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:25:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:48 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 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:25:48 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:25:48 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:25:48 INFO - Using env: {'DISPLAY': ':0', 05:25:48 INFO - 'HOME': '/home/cltbld', 05:25:48 INFO - 'LANG': 'en_US.UTF-8', 05:25:48 INFO - 'LANGUAGE': 'en_US:en', 05:25:48 INFO - 'LOGNAME': 'cltbld', 05:25:48 INFO - 'MAIL': '/var/mail/cltbld', 05:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:48 INFO - 'MOZ_NO_REMOTE': '1', 05:25:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:48 INFO - 'NO_EM_RESTART': '1', 05:25:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:48 INFO - 'PWD': '/builds/slave/test', 05:25:48 INFO - 'SHELL': '/bin/bash', 05:25:48 INFO - 'SHLVL': '1', 05:25:48 INFO - 'TERM': 'linux', 05:25:48 INFO - 'TMOUT': '86400', 05:25:48 INFO - 'USER': 'cltbld', 05:25:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:48 INFO - '_': '/tools/buildbot/bin/python'} 05:25:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:25:49 INFO - Downloading/unpacking pip>=1.5 05:25:49 INFO - Running setup.py egg_info for package pip 05:25:49 INFO - warning: no files found matching 'pip/cacert.pem' 05:25:49 INFO - warning: no files found matching '*.html' under directory 'docs' 05:25:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:25:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:25:49 INFO - Installing collected packages: pip 05:25:49 INFO - Found existing installation: pip 1.1 05:25:49 INFO - Uninstalling pip: 05:25:49 INFO - Successfully uninstalled pip 05:25:49 INFO - Running setup.py install for pip 05:25:49 INFO - warning: no files found matching 'pip/cacert.pem' 05:25:49 INFO - warning: no files found matching '*.html' under directory 'docs' 05:25:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:25:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:25:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:25:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:25:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:25:49 INFO - Successfully installed pip 05:25:49 INFO - Cleaning up... 05:25:49 INFO - Return code: 0 05:25:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:25: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:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25: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:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25: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', '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 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:25: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:25: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 psutil>=3.1.1 05:25:49 INFO - Using env: {'DISPLAY': ':0', 05:25:49 INFO - 'HOME': '/home/cltbld', 05:25:49 INFO - 'LANG': 'en_US.UTF-8', 05:25:49 INFO - 'LANGUAGE': 'en_US:en', 05:25:49 INFO - 'LOGNAME': 'cltbld', 05:25:49 INFO - 'MAIL': '/var/mail/cltbld', 05:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:49 INFO - 'MOZ_NO_REMOTE': '1', 05:25:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:49 INFO - 'NO_EM_RESTART': '1', 05:25:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:49 INFO - 'PWD': '/builds/slave/test', 05:25:49 INFO - 'SHELL': '/bin/bash', 05:25:49 INFO - 'SHLVL': '1', 05:25:49 INFO - 'TERM': 'linux', 05:25:49 INFO - 'TMOUT': '86400', 05:25:49 INFO - 'USER': 'cltbld', 05:25:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:49 INFO - '_': '/tools/buildbot/bin/python'} 05:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:49 INFO - Downloading/unpacking psutil>=3.1.1 05:25:49 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:25:49 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:25:49 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:25:49 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:25:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:25:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:51 INFO - Installing collected packages: psutil 05:25:51 INFO - Running setup.py install for psutil 05:25:51 INFO - building 'psutil._psutil_linux' extension 05:25:51 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:25:51 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:25:51 INFO - building 'psutil._psutil_posix' extension 05:25:51 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:25:51 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:25:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:51 INFO - Successfully installed psutil 05:25:51 INFO - Cleaning up... 05:25:51 INFO - Return code: 0 05:25:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:25:51 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:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:51 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:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:51 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 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:25:51 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:25:51 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:25:51 INFO - Using env: {'DISPLAY': ':0', 05:25:51 INFO - 'HOME': '/home/cltbld', 05:25:51 INFO - 'LANG': 'en_US.UTF-8', 05:25:51 INFO - 'LANGUAGE': 'en_US:en', 05:25:51 INFO - 'LOGNAME': 'cltbld', 05:25:51 INFO - 'MAIL': '/var/mail/cltbld', 05:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:51 INFO - 'MOZ_NO_REMOTE': '1', 05:25:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:51 INFO - 'NO_EM_RESTART': '1', 05:25:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:51 INFO - 'PWD': '/builds/slave/test', 05:25:51 INFO - 'SHELL': '/bin/bash', 05:25:51 INFO - 'SHLVL': '1', 05:25:51 INFO - 'TERM': 'linux', 05:25:51 INFO - 'TMOUT': '86400', 05:25:51 INFO - 'USER': 'cltbld', 05:25:51 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:51 INFO - '_': '/tools/buildbot/bin/python'} 05:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:25:51 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:25:51 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:25:51 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:25:51 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:25:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:25:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:25:52 INFO - Installing collected packages: mozsystemmonitor 05:25:52 INFO - Running setup.py install for mozsystemmonitor 05:25:53 INFO - Successfully installed mozsystemmonitor 05:25:53 INFO - Cleaning up... 05:25:54 INFO - Return code: 0 05:25:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:25:54 INFO - Using env: {'DISPLAY': ':0', 05:25:54 INFO - 'HOME': '/home/cltbld', 05:25:54 INFO - 'LANG': 'en_US.UTF-8', 05:25:54 INFO - 'LANGUAGE': 'en_US:en', 05:25:54 INFO - 'LOGNAME': 'cltbld', 05:25:54 INFO - 'MAIL': '/var/mail/cltbld', 05:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:54 INFO - 'MOZ_NO_REMOTE': '1', 05:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:54 INFO - 'NO_EM_RESTART': '1', 05:25:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:54 INFO - 'PWD': '/builds/slave/test', 05:25:54 INFO - 'SHELL': '/bin/bash', 05:25:54 INFO - 'SHLVL': '1', 05:25:54 INFO - 'TERM': 'linux', 05:25:54 INFO - 'TMOUT': '86400', 05:25:54 INFO - 'USER': 'cltbld', 05:25:54 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:54 INFO - '_': '/tools/buildbot/bin/python'} 05:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:54 INFO - Downloading/unpacking jsonschema==2.5.1 05:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:25:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:25:55 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:25:55 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:25:55 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:25:55 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:25:55 INFO - Downloading functools32-3.2.3-2.tar.gz 05:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:25:55 INFO - warning: no files found matching '*.txt' 05:25:55 INFO - no previously-included directories found matching 'build' 05:25:55 INFO - no previously-included directories found matching 'dist' 05:25:55 INFO - no previously-included directories found matching '.git*' 05:25:55 INFO - Installing collected packages: jsonschema, functools32 05:25:56 INFO - Running setup.py install for functools32 05:25:57 INFO - warning: no files found matching '*.txt' 05:25:57 INFO - no previously-included directories found matching 'build' 05:25:57 INFO - no previously-included directories found matching 'dist' 05:25:57 INFO - no previously-included directories found matching '.git*' 05:25:57 INFO - Successfully installed jsonschema functools32 05:25:57 INFO - Cleaning up... 05:25:57 INFO - Return code: 0 05:25:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:25:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:25:57 INFO - Using env: {'DISPLAY': ':0', 05:25:57 INFO - 'HOME': '/home/cltbld', 05:25:57 INFO - 'LANG': 'en_US.UTF-8', 05:25:57 INFO - 'LANGUAGE': 'en_US:en', 05:25:57 INFO - 'LOGNAME': 'cltbld', 05:25:57 INFO - 'MAIL': '/var/mail/cltbld', 05:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:57 INFO - 'MOZ_NO_REMOTE': '1', 05:25:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:57 INFO - 'NO_EM_RESTART': '1', 05:25:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:57 INFO - 'PWD': '/builds/slave/test', 05:25:57 INFO - 'SHELL': '/bin/bash', 05:25:57 INFO - 'SHLVL': '1', 05:25:57 INFO - 'TERM': 'linux', 05:25:57 INFO - 'TMOUT': '86400', 05:25:57 INFO - 'USER': 'cltbld', 05:25:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:57 INFO - '_': '/tools/buildbot/bin/python'} 05:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:25:57 INFO - Cleaning up... 05:25:57 INFO - Return code: 0 05:25:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:25:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:25:57 INFO - Using env: {'DISPLAY': ':0', 05:25:57 INFO - 'HOME': '/home/cltbld', 05:25:57 INFO - 'LANG': 'en_US.UTF-8', 05:25:57 INFO - 'LANGUAGE': 'en_US:en', 05:25:57 INFO - 'LOGNAME': 'cltbld', 05:25:57 INFO - 'MAIL': '/var/mail/cltbld', 05:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:57 INFO - 'MOZ_NO_REMOTE': '1', 05:25:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:25:57 INFO - 'NO_EM_RESTART': '1', 05:25:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:57 INFO - 'PWD': '/builds/slave/test', 05:25:57 INFO - 'SHELL': '/bin/bash', 05:25:57 INFO - 'SHLVL': '1', 05:25:57 INFO - 'TERM': 'linux', 05:25:57 INFO - 'TMOUT': '86400', 05:25:57 INFO - 'USER': 'cltbld', 05:25:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:57 INFO - '_': '/tools/buildbot/bin/python'} 05:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:57 INFO - Downloading/unpacking blobuploader==1.2.4 05:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:58 INFO - Downloading blobuploader-1.2.4.tar.gz 05:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:25:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:25:58 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:25:58 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:25:58 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:25:58 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:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:25:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:59 INFO - Downloading docopt-0.6.1.tar.gz 05:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:25:59 INFO - Installing collected packages: blobuploader, requests, docopt 05:25:59 INFO - Running setup.py install for blobuploader 05:25:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:25:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:25:59 INFO - Running setup.py install for requests 05:25:59 INFO - Running setup.py install for docopt 05:25:59 INFO - Successfully installed blobuploader requests docopt 05:25:59 INFO - Cleaning up... 05:26:00 INFO - Return code: 0 05:26:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:26:00 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:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:00 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:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:00 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 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:26:00 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:26:00 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:26:00 INFO - Using env: {'DISPLAY': ':0', 05:26:00 INFO - 'HOME': '/home/cltbld', 05:26:00 INFO - 'LANG': 'en_US.UTF-8', 05:26:00 INFO - 'LANGUAGE': 'en_US:en', 05:26:00 INFO - 'LOGNAME': 'cltbld', 05:26:00 INFO - 'MAIL': '/var/mail/cltbld', 05:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:00 INFO - 'MOZ_NO_REMOTE': '1', 05:26:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:00 INFO - 'NO_EM_RESTART': '1', 05:26:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:00 INFO - 'PWD': '/builds/slave/test', 05:26:00 INFO - 'SHELL': '/bin/bash', 05:26:00 INFO - 'SHLVL': '1', 05:26:00 INFO - 'TERM': 'linux', 05:26:00 INFO - 'TMOUT': '86400', 05:26:00 INFO - 'USER': 'cltbld', 05:26:00 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:00 INFO - '_': '/tools/buildbot/bin/python'} 05:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:26:00 INFO - Running setup.py (path:/tmp/pip-a5y6c5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:26:00 INFO - Running setup.py (path:/tmp/pip-bDp17x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:26:00 INFO - Running setup.py (path:/tmp/pip-UjaRkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:26:00 INFO - Running setup.py (path:/tmp/pip-VRro3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:26:00 INFO - Running setup.py (path:/tmp/pip-5cr6Q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:26:00 INFO - Running setup.py (path:/tmp/pip-fheIyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:26:00 INFO - Running setup.py (path:/tmp/pip-S5VoA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:26:00 INFO - Running setup.py (path:/tmp/pip-0fE74z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:26:00 INFO - Running setup.py (path:/tmp/pip-73yI0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:26:00 INFO - Running setup.py (path:/tmp/pip-lKes7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:26:00 INFO - Running setup.py (path:/tmp/pip-HJLsXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:26:01 INFO - Running setup.py (path:/tmp/pip-6tBO5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:26:01 INFO - Running setup.py (path:/tmp/pip-lItLzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:26:01 INFO - Running setup.py (path:/tmp/pip-pD5u_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:01 INFO - Running setup.py (path:/tmp/pip-QVlQUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:26:01 INFO - Running setup.py (path:/tmp/pip-2xVT_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:26:01 INFO - Running setup.py (path:/tmp/pip-WnqlaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:26:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:26:01 INFO - Running setup.py install for manifestparser 05:26:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:26:01 INFO - Running setup.py install for mozcrash 05:26:01 INFO - Running setup.py install for mozdebug 05:26:01 INFO - Running setup.py install for mozdevice 05:26:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:26:01 INFO - Running setup.py install for mozfile 05:26:01 INFO - Running setup.py install for mozhttpd 05:26:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Running setup.py install for mozinfo 05:26:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Running setup.py install for mozInstall 05:26:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Running setup.py install for mozleak 05:26:02 INFO - Running setup.py install for mozlog 05:26:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Running setup.py install for moznetwork 05:26:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Running setup.py install for mozprocess 05:26:02 INFO - Running setup.py install for mozprofile 05:26:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Running setup.py install for mozrunner 05:26:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:26:02 INFO - Running setup.py install for mozscreenshot 05:26:02 INFO - Running setup.py install for moztest 05:26:03 INFO - Running setup.py install for mozversion 05:26:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:26:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:26:03 INFO - Cleaning up... 05:26:03 INFO - Return code: 0 05:26:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:26:03 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:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:03 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:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:03 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 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:26:03 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:26:03 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:26:03 INFO - Using env: {'DISPLAY': ':0', 05:26:03 INFO - 'HOME': '/home/cltbld', 05:26:03 INFO - 'LANG': 'en_US.UTF-8', 05:26:03 INFO - 'LANGUAGE': 'en_US:en', 05:26:03 INFO - 'LOGNAME': 'cltbld', 05:26:03 INFO - 'MAIL': '/var/mail/cltbld', 05:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:03 INFO - 'MOZ_NO_REMOTE': '1', 05:26:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:03 INFO - 'NO_EM_RESTART': '1', 05:26:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:03 INFO - 'PWD': '/builds/slave/test', 05:26:03 INFO - 'SHELL': '/bin/bash', 05:26:03 INFO - 'SHLVL': '1', 05:26:03 INFO - 'TERM': 'linux', 05:26:03 INFO - 'TMOUT': '86400', 05:26:03 INFO - 'USER': 'cltbld', 05:26:03 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:03 INFO - '_': '/tools/buildbot/bin/python'} 05:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:26:03 INFO - Running setup.py (path:/tmp/pip-JFZzKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:26:03 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:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:26:03 INFO - Running setup.py (path:/tmp/pip-rY0qG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:26:03 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:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:26:03 INFO - Running setup.py (path:/tmp/pip-seh5AY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:26:03 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:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:26:03 INFO - Running setup.py (path:/tmp/pip-hAIgni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:26:03 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:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:26:03 INFO - Running setup.py (path:/tmp/pip-u8o5qB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:26:03 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:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:26:03 INFO - Running setup.py (path:/tmp/pip-OWYHo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:26:03 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:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:26:03 INFO - Running setup.py (path:/tmp/pip-JHDedZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:26:03 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:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:26:03 INFO - Running setup.py (path:/tmp/pip-Lry9or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:26:03 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:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:26:03 INFO - Running setup.py (path:/tmp/pip-MfDuZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:26:04 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:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:26:04 INFO - Running setup.py (path:/tmp/pip-J88gLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:26:04 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:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:26:04 INFO - Running setup.py (path:/tmp/pip-KkG0DP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:26:04 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:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:26:04 INFO - Running setup.py (path:/tmp/pip-IwcAOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:26:04 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:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:26:04 INFO - Running setup.py (path:/tmp/pip-RSjqgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:26:04 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:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:26:04 INFO - Running setup.py (path:/tmp/pip-963Lrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:26:04 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:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:04 INFO - Running setup.py (path:/tmp/pip-4FKsWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:04 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:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:26:04 INFO - Running setup.py (path:/tmp/pip-FGuQ1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:26:04 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:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:26:04 INFO - Running setup.py (path:/tmp/pip-RKZpgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:26:04 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:26:04 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:26:04 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:26:04 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:26:04 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:26:04 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:26:04 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:26:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:26:04 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:26:04 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:26:04 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:26:04 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:26:05 INFO - Downloading blessings-1.6.tar.gz 05:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:26:05 INFO - Installing collected packages: blessings 05:26:05 INFO - Running setup.py install for blessings 05:26:05 INFO - Successfully installed blessings 05:26:05 INFO - Cleaning up... 05:26:05 INFO - Return code: 0 05:26:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:26:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:26:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:26:06 INFO - Reading from file tmpfile_stdout 05:26:06 INFO - Current package versions: 05:26:06 INFO - argparse == 1.2.1 05:26:06 INFO - blessings == 1.6 05:26:06 INFO - blobuploader == 1.2.4 05:26:06 INFO - docopt == 0.6.1 05:26:06 INFO - functools32 == 3.2.3-2 05:26:06 INFO - jsonschema == 2.5.1 05:26:06 INFO - manifestparser == 1.1 05:26:06 INFO - mozInstall == 1.13 05:26:06 INFO - mozcrash == 1.0 05:26:06 INFO - mozdebug == 0.1 05:26:06 INFO - mozdevice == 0.51 05:26:06 INFO - mozfile == 1.2 05:26:06 INFO - mozhttpd == 0.7 05:26:06 INFO - mozinfo == 0.10 05:26:06 INFO - mozleak == 0.1 05:26:06 INFO - mozlog == 3.5 05:26:06 INFO - moznetwork == 0.27 05:26:06 INFO - mozprocess == 0.25 05:26:06 INFO - mozprofile == 0.28 05:26:06 INFO - mozrunner == 6.13 05:26:06 INFO - mozscreenshot == 0.1 05:26:06 INFO - mozsystemmonitor == 0.3 05:26:06 INFO - moztest == 0.8 05:26:06 INFO - mozversion == 1.4 05:26:06 INFO - psutil == 3.1.1 05:26:06 INFO - requests == 1.2.3 05:26:06 INFO - wsgiref == 0.1.2 05:26:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:06 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 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:26:06 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:26:06 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:26:06 INFO - Using env: {'DISPLAY': ':0', 05:26:06 INFO - 'HOME': '/home/cltbld', 05:26:06 INFO - 'LANG': 'en_US.UTF-8', 05:26:06 INFO - 'LANGUAGE': 'en_US:en', 05:26:06 INFO - 'LOGNAME': 'cltbld', 05:26:06 INFO - 'MAIL': '/var/mail/cltbld', 05:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:06 INFO - 'MOZ_NO_REMOTE': '1', 05:26:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:06 INFO - 'NO_EM_RESTART': '1', 05:26:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:06 INFO - 'PWD': '/builds/slave/test', 05:26:06 INFO - 'SHELL': '/bin/bash', 05:26:06 INFO - 'SHLVL': '1', 05:26:06 INFO - 'TERM': 'linux', 05:26:06 INFO - 'TMOUT': '86400', 05:26:06 INFO - 'USER': 'cltbld', 05:26:06 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:06 INFO - '_': '/tools/buildbot/bin/python'} 05:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:06 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:26:06 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:26:06 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:26:06 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:26:06 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:26:06 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:26:06 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:26:06 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:26:06 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:26:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:26:07 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:26:07 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:26:07 INFO - Installing collected packages: simplejson 05:26:07 INFO - Running setup.py install for simplejson 05:26:07 INFO - building 'simplejson._speedups' extension 05:26:07 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:26:08 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:26:08 INFO - Successfully installed simplejson 05:26:08 INFO - Cleaning up... 05:26:08 INFO - Return code: 0 05:26:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:26: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:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26: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:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', '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:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:26:08 INFO - Using env: {'DISPLAY': ':0', 05:26:08 INFO - 'HOME': '/home/cltbld', 05:26:08 INFO - 'LANG': 'en_US.UTF-8', 05:26:08 INFO - 'LANGUAGE': 'en_US:en', 05:26:08 INFO - 'LOGNAME': 'cltbld', 05:26:08 INFO - 'MAIL': '/var/mail/cltbld', 05:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:08 INFO - 'MOZ_NO_REMOTE': '1', 05:26:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:08 INFO - 'NO_EM_RESTART': '1', 05:26:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:08 INFO - 'PWD': '/builds/slave/test', 05:26:08 INFO - 'SHELL': '/bin/bash', 05:26:08 INFO - 'SHLVL': '1', 05:26:08 INFO - 'TERM': 'linux', 05:26:08 INFO - 'TMOUT': '86400', 05:26:08 INFO - 'USER': 'cltbld', 05:26:08 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:08 INFO - '_': '/tools/buildbot/bin/python'} 05:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:26:08 INFO - Cleaning up... 05:26:08 INFO - Return code: 0 05:26:08 INFO - Running post-action listener: _resource_record_post_action 05:26:08 INFO - Running post-action listener: _start_resource_monitoring 05:26:08 INFO - Starting resource monitoring. 05:26:08 INFO - [mozharness: 2017-09-29 12:26:08.895132Z] Finished create-virtualenv step (success) 05:26:08 INFO - [mozharness: 2017-09-29 12:26:08.895651Z] Running install step. 05:26:08 INFO - Running pre-action listener: _resource_record_pre_action 05:26:08 INFO - Running main action method: install 05:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:26:09 INFO - Reading from file tmpfile_stdout 05:26:09 INFO - Detecting whether we're running mozinstall >=1.0... 05:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:26:09 INFO - Reading from file tmpfile_stdout 05:26:09 INFO - Output received: 05:26:09 INFO - Usage: mozinstall [options] installer 05:26:09 INFO - Options: 05:26:09 INFO - -h, --help show this help message and exit 05:26:09 INFO - -d DEST, --destination=DEST 05:26:09 INFO - Directory to install application into. [default: 05:26:09 INFO - "/builds/slave/test"] 05:26:09 INFO - --app=APP Application being installed. [default: firefox] 05:26:09 INFO - mkdir: /builds/slave/test/build/application 05:26:09 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:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:26:27 INFO - Reading from file tmpfile_stdout 05:26:27 INFO - Output received: 05:26:27 INFO - /builds/slave/test/build/application/firefox/firefox 05:26:27 INFO - Running post-action listener: _resource_record_post_action 05:26:27 INFO - [mozharness: 2017-09-29 12:26:27.071612Z] Finished install step (success) 05:26:27 INFO - [mozharness: 2017-09-29 12:26:27.071865Z] Running setup-mitmproxy step. 05:26:27 INFO - Running pre-action listener: _resource_record_pre_action 05:26:27 INFO - Running main action method: setup_mitmproxy 05:26:27 INFO - Skipping: mitmproxy is not required 05:26:27 INFO - Running post-action listener: _resource_record_post_action 05:26:27 INFO - [mozharness: 2017-09-29 12:26:27.072994Z] Finished setup-mitmproxy step (success) 05:26:27 INFO - [mozharness: 2017-09-29 12:26:27.073229Z] Running run-tests step. 05:26:27 INFO - Running pre-action listener: _resource_record_pre_action 05:26:27 INFO - Running pre-action listener: _set_gcov_prefix 05:26:27 INFO - Running main action method: run_tests 05:26:27 WARNING - Try message not found. 05:26:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:26:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:26:27 INFO - Python 2.7.3 05:26:27 INFO - Return code: 0 05:26:27 INFO - grabbing minidump binary from tooltool 05:26: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:26:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2942d60>, '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 0x2943a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2943ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:26:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:26:27 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:26:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:26:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:26:27 INFO - Return code: 0 05:26:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:26:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:26:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:26:27 INFO - ENV: RUST_BACKTRACE is now full 05:26:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:26:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:26:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:26:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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:26:27 INFO - Using env: {'DISPLAY': ':0', 05:26:27 INFO - 'HOME': '/home/cltbld', 05:26:27 INFO - 'LANG': 'en_US.UTF-8', 05:26:27 INFO - 'LANGUAGE': 'en_US:en', 05:26:27 INFO - 'LOGNAME': 'cltbld', 05:26:27 INFO - 'MAIL': '/var/mail/cltbld', 05:26:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:26:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:26:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:27 INFO - 'MOZ_NO_REMOTE': '1', 05:26:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:26:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:27 INFO - 'NO_EM_RESTART': '1', 05:26:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:27 INFO - 'PWD': '/builds/slave/test', 05:26:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:26:27 INFO - 'RUST_BACKTRACE': 'full', 05:26:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:26:27 INFO - 'SHELL': '/bin/bash', 05:26:27 INFO - 'SHLVL': '1', 05:26:27 INFO - 'TERM': 'linux', 05:26:27 INFO - 'TMOUT': '86400', 05:26:27 INFO - 'USER': 'cltbld', 05:26:27 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:26:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:27 INFO - '_': '/tools/buildbot/bin/python'} 05:26:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:26:27 INFO - mozversion application_buildid: 20170929113110 05:26:27 INFO - mozversion application_changeset: cd9c8c48e4b3ded47a776f757008f3dcf570c59c 05:26:27 INFO - mozversion application_display_name: Nightly 05:26:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:26:27 INFO - mozversion application_name: Firefox 05:26:27 INFO - mozversion application_remotingname: firefox 05:26:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:26:27 INFO - mozversion application_vendor: Mozilla 05:26:27 INFO - mozversion application_version: 58.0a1 05:26:27 INFO - mozversion platform_buildid: 20170929113110 05:26:27 INFO - mozversion platform_changeset: cd9c8c48e4b3ded47a776f757008f3dcf570c59c 05:26:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:26:27 INFO - mozversion platform_version: 58.0a1 05:26:27 INFO - using testdate: 1506687987 05:26:27 INFO - actual date: 1506687987 05:26:27 INFO - starting webserver on 'localhost:43096' 05:26:27 INFO - SUITE-START | Running 2 tests 05:26:27 INFO - TEST-START | dromaeo_css 05:26:27 INFO - Initialising browser for dromaeo_css test... 05:26:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43096/getInfo.html -profile /tmp/tmpogDpgB/profile 05:26:27 INFO - TEST-INFO | started process 2107 (/builds/slave/test/build/application/firefox/firefox http://localhost:43096/getInfo.html) 05:26:33 INFO - TEST-INFO | 2107: exit 0 05:26:33 INFO - Browser initialized. 05:26:33 INFO - Running cycle 1/1 for dromaeo_css test... 05:26:33 INFO - Using env: {'DISPLAY': ':0', 05:26:33 INFO - 'HOME': '/home/cltbld', 05:26:33 INFO - 'JSGC_DISABLE_POISONING': '1', 05:26:33 INFO - 'LANG': 'en_US.UTF-8', 05:26:33 INFO - 'LANGUAGE': 'en_US:en', 05:26:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:26:33 INFO - 'LOGNAME': 'cltbld', 05:26:33 INFO - 'MAIL': '/var/mail/cltbld', 05:26:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:26:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:26:33 INFO - 'MOZ_CRASHREPORTER': '1', 05:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:26:33 INFO - 'MOZ_NO_REMOTE': '1', 05:26:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:26:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:33 INFO - 'NO_EM_RESTART': '1', 05:26:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:33 INFO - 'PWD': '/builds/slave/test', 05:26:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:26:33 INFO - 'RUST_BACKTRACE': 'full', 05:26:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:26:33 INFO - 'SHELL': '/bin/bash', 05:26:33 INFO - 'SHLVL': '1', 05:26:33 INFO - 'TERM': 'linux', 05:26:33 INFO - 'TMOUT': '86400', 05:26:33 INFO - 'USER': 'cltbld', 05:26:33 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:33 INFO - '_': '/tools/buildbot/bin/python'} 05:26:33 INFO - TEST-INFO | started process 2346 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpogDpgB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:34 INFO - PID 2346 | 05:26:34 INFO - PID 2346 | (/builds/slave/test/build/application/firefox/firefox:2395): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:26:34 INFO - PID 2346 | 05:26:44 INFO - PID 2346 | RSS: Main: 147554304 05:26:44 INFO - PID 2346 | 05:29:42 INFO - PID 2346 | Cycle 1(1): loaded http://localhost:43096/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43096/tests/dromaeo/cssquery-ext.html) 05:29:43 INFO - PID 2346 | RSS: Main: 148631552 05:29:43 INFO - PID 2346 | 05:32:45 INFO - PID 2346 | Cycle 1(1): loaded http://localhost:43096/tests/dromaeo/cssquery-ext.html (next: http://localhost:43096/tests/dromaeo/cssquery-jquery.html) 05:32:46 INFO - PID 2346 | RSS: Main: 148217856 05:32:46 INFO - PID 2346 | 05:35:48 INFO - PID 2346 | Cycle 1(1): loaded http://localhost:43096/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43096/tests/dromaeo/cssquery-mootools.html) 05:35:48 INFO - PID 2346 | RSS: Main: 151437312 05:35:48 INFO - PID 2346 | 05:38:50 INFO - PID 2346 | Cycle 1(1): loaded http://localhost:43096/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43096/tests/dromaeo/cssquery-prototype.html) 05:38:50 INFO - PID 2346 | RSS: Main: 151437312 05:38:50 INFO - PID 2346 | 05:41:54 INFO - PID 2346 | Cycle 1(1): loaded http://localhost:43096/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43096/tests/dromaeo/cssquery-yui.html) 05:41:54 INFO - PID 2346 | RSS: Main: 153403392 05:41:54 INFO - PID 2346 | 05:44:56 INFO - PID 2346 | Cycle 1(1): loaded http://localhost:43096/tests/dromaeo/cssquery-yui.html (next: http://localhost:43096/tests/dromaeo/cssquery-dojo.html) 05:44:56 INFO - PID 2346 | RSS: Main: 153231360 05:44:56 INFO - PID 2346 | 05:44:56 INFO - PID 2346 | __start_tp_report 05:44:56 INFO - PID 2346 | _x_x_mozilla_page_load 05:44:56 INFO - PID 2346 | _x_x_mozilla_page_load_details 05:44:56 INFO - PID 2346 | |i|pagename|runs| 05:44:56 INFO - PID 2346 | |0;dojo.html;3179.189306726785;3206.230504678877;3206.615206175259;3210.8715651373946;3218.517222416637;6451.548391612589;6494.123293355396;6509.349065093488;6540.345965403457;6553.83615409615;6230.096635987779;6364.618122912617;6368.267649220341;6387.744490220397;6392.552521323498;5867.207926929853;5947.821565353046;5964.476499723188;5988.101784732303;6024.8493787655225;5911.5270778337845;5926.911096333559;5954.4640982311585;5967.98544247479;5999.400059994009;5773.971130144364;5793.304803423595;5822.388649191837;5853.7365818538265;5888.146218798293;5854.356020837705;5919.585629005971;5926.940730592682;5934.910976335358;5936.1392598073035;5714.342850572196;5799.33307669615;5814.912776308359;5870.765169393219;5876.794312199053;6308.589941653824;6323.588966717149;6331.303556608769;6387.1377364055725;6410.326915673833;6886.070380498619;6903.896441553381;6908.930910690879;6910.792676219721;6919.584824910471;6916.377526022682;6999.020137180799;7003.264657210971;7003.404710599605;7005.439564834801;6378.202724659418;6379.330170332141;6413.551051426355;6473.676316184172;6486.772962946368;6877.037214789933;6945.965270173617;6992.650367481606;6992.79021629352;7084.079069720903;6775.525713200127;6775.559588626724;6781.355771201728;6807.591544507345;6814.182298124158;6830.385265326046;6897.137857767779;6917.515773895779;6925.203601585882;6931.133608298963;6796.490263230277;6813.523053386314;6822.829429264308;6910.136232970879;6911.5853048817235;6682.398584127452;6843.486738494533;6852.417544508673;6853.314668533207;6853.897191542131;5320.12217984036;5321.813736519281;5321.840344789663;5323.707196104251;5327.706976116273;4649.465311489154;4685.156671799109;4718.079974405026;4756.381670382828;4758.833440829623;5675.0636145035605;5786.132080188006;5854.297484301911;5863.70681465925;5869.354371019184;6778.72885084601;6801.931980680229;6879.071325371111;6889.104416425833;6902.827429314207;5665.235193248858;5714.085746280575;5745.770169193269;5756.481916627524;5776.740046697909;5766.192733017381;5828.708564571754;5835.533157347487;5843.065109582446;5866.032104702762;6532.444742196966;6545.181852268466;6571.178602674666;6576.408123268929;6589.637569933604;6791.151106111736;6809.182898052166;6870.381665650116;6894.793156205322;6910.170779506491;6406.775762848278;6461.515386345949;6467.611943283418;6520.217573890974;6563.868722625616;6669.266380698216;6715.4963377747845;6718.992151177363;6722.563033402813;6743.089682892943;6884.862302753817;6955.965220174069;6963.825904352431;6979.162500499972;6998.16022077354;6519.891618424974;6600;6603.339666033358;6606.372394622503;6606.53754237218;6557.803265902031;6560.40956313934;6638.800835974928;6645.1361323027695;6654.700538475683;6384.744610215539;6483.481321494363;6497.707603157774;6498.057781621544;6500.804975850732;6604.933950660432;6628.237752658506;6648.434883034996;6722.2605513394465;6762.222344430454;6418.679066046586;6454.612723236621;6499.382558657109;6530.249021362604;6530.869382612226 05:44:56 INFO - PID 2346 | |1;ext.html;855.2858363266672;881.982360352793;882.7087061269782;882.9823403531927;883.4037025008118;38545.42181867271;42108;42161.57838421623;42195.15609687805;42196;38867.22265554687;39080.21839563207;39091.80454097725;39138.21723565527;39188.41217381734;7185.389241914443;7299.635018249093;7320;7885.1326354101;8076.71731489398;7973.840523189562;8030.156833532483;8038.557879316635;8093.271605555499;8106.229908158745;8021.077576078767;8098.797530061766;8106.837863242732;8135.837283254331;8142.796430089265;8042.919570804304;8059.23437273464;8059.838803223903;8092.109867914524;8117.025956885212;7860.960695196544;7902;7947.721829735931;7954.443188976774;7970.163132871081;37460.81269593662;37700.057498562484;37774;37803.81098094519;37858;3465.930681386383;3508.491268766015;3509.2279698466295;3510.666486683761;3511.578610566723;30772.53841192384;30836.074917752503;30913.38173236545;30930.53604195939;30967.690323096707;5145.897082058375;5186.50728180826;5188.584913206934;5208.531232189083;5227.320448341691;185849;186704.06647966808;187771;187993;188449;43170.704878853685;43882.56117438849;48680.756596216794;48891;49053.50946490489;50884;51962.48037519652;52013;52069;52071;16165.35338586467;16384.75422868658;16411.261493232832;16417.343306267616;16447.177641118134;13446.32768361578;13446.663833404047;13453.932730336286;13502.852257558217;13549.86450135486;14051.929740351437;14216.644583885485;14251.714965700628;14259.64350891236;14380.28098595087;5859.94140058594;5888.941110588839;5981.491573216325;5996.2504686914135;6020.909686354708;1013.6046941692746;1030.5568605499707;1039.859618951432;1044.9529771160317;1044.984325235122;22783.31650050468;23401.180958666366;23582.646260306108;23582.81853185427;23699.644505332435;1120.6918097523246;1122.3770807202081;1124.3759713359163;1125.5779082843933;1134.9659510214542;12158.635240942785;12164.452599633038;12182.939085304693;12380.504779808887;12568.874311256954;226326;226572;226863;227786;227915;52724;52763.73618131885;52803.47196527985;52942.735286323325;53297.46702533003;1447.6970726346372;1461.9122852628877;1470.8382077971414;1483.5697647682266;1485.1534625263712;22690.319290421314;22785.20251791179;22913;23220.76779232186;23233.535329292983;36756.81621591875;36846.44730329101;36900;36954;36995.63004369922;199090;199654;199860;200013;200865;33317;35093.29813403769;35979;35989.28021439504;36021.63978360288;44369.55630443654;45350.319745204506;45783.313250300605;45816.312755309365;46092;32981.67018329882;33265.168370790925;33323;33509.32981340311;33799.32401352008;33605.49591756175;33631.495527566614;33739.493907591896;34063.31873362568;34215.144621384665;37827.24345513129;37849.24301513899;37880.62119378771;37978.240435190586;37991;32154.19614509656;32406.67593324131;32531.674683253812;32692.34615307728;33079.50380744242;24461.266162015167;24544.754552454247;24858.50282994294;25093.498130037664;25236.24291271265 05:44:56 INFO - PID 2346 | |2;jquery.html;565.3272605599336;570.5863249144368;582.6009183709157;596.2546816479401;606.6602702486608;22805.885970570143;23480.530389392246;23524;23544.411389715224;23547.76452235477;24331.270061898173;25241;26029.47941041178;26297.211083667473;26313;6668.199816022084;6876.381125698686;6883.724651013953;6888.4144847688;6889.827754306158;6187.412195841388;6483.22201335838;6507.707153178095;6795.082663840368;6905.551139175963;6416.711247993828;6456.386643268906;6463.806085817433;6466.547341686084;6468.2561505426775;6459.580127291734;6462.095306657072;6472.190976127984;6478.902816457757;6992.5105242633035;7130.6078165700865;7145.499815012951;7173.175084865203;7179.1026121734785;7197.532160409609;31616.36767264642;32144.357112857844;32157.356852863046;32248.193795155075;32381.6761832381;1969.3993332033724;2007.7289565908707;2016.2439085342996;2019.3134334326253;2025.463252238158;19339.226430942887;19689.11398987049;19811;19853.305134320228;20241.291554795658;2528.911488097926;2538.0862889359814;2570.832895861763;2583.354161459635;2583.534963706532;124327;129716.35141824231;130301.34849325787;145981;150499;21924.780752192273;22459.663105053438;22482.196846655635;22915.541689166123;23331;44780;44832.3275150873;44925.77537112294;44929.55070449254;45177.774111129234;11743.765124697458;11801.645950621494;12049.759004819854;12054.337011464278;12086.758264834654;10088.596456141819;10098.596056157672;10154.796904061877;10245.385276883411;10291.176705863512;11210.775784484265;11452.94273528627;11625.302481851117;11635.360055197045;11773.352465614302;3639.3771622842773;3660.194757153422;3903.941440878389;4094.385842123705;4339.609435150852;941.8210539997378;962.8940816510178;965.3242730088966;967.3083745122271;977.1889331854544;20019;20335;20392;20770.1691932321;20842.583148336947;1009.5860697114294;1014;1027.4811220333684;1029.3875144289286;1034.829253173218;11005.339679619248;11040.33757974508;11188.832167517494;11300.265482743594;11427.657170284905;504789;519966;528296;532865;535888;115238.42380788042;116054.41972790082;117022;118600.40699796446;118707;1136.1422126294594;1142.280363371071;1179.9233049851905;1180.7048237940514;1185.8932696057395;17778.5555361117;17928.372506962714;18059.187336569623;18142.637147257246;18610.069496524848;57657.423425765206;57909.13136302875;58516.70741646265;58652.4134758647;59388;70528.64735676289;70690;71455.64272178606;72147;73233;21013.264535741175;21023.8948805255;21139;21298.402619803266;21321.78678213198;23725.406864827826;23755.881220593787;23785.64321535213;23945.760542394353;24061.639075413532;18824;18828.52928676794;19024.42926712201;19154.138063787443;19166.90416547908;16764.413245536347;16964;17457.73813392775;17512.91243543774;17838.732419013464;17998.73001904999;19262.22951081941;19548.413547593595;19702.901485492483;19825.107870146167;20944.37166884996;21026.2640807571;21027;21044.473888152304;21128.15487380549;18846.43460696181;18969.336073237922;18995.525111872314;19261.614767704847;19286 05:44:56 INFO - PID 2346 | |3;mootools.html;1316.1313533068178;1372.4441601151532;1389.1387339849296;1398.147130250547;1399.3842709207947;15262.542123736292;16465.83534164661;16494.505164845043;16507.17464126792;16541.503754887344;15918.52244432666;16265.268062937166;16270.186490675478;16280.104594247312;16318.184090795443;2669.159214847317;2793.064323451646;2868.0678779396676;2877.30944573303;2881.121258016304;2803.355228297493;2834.1922552072665;2836.2342167614734;2836.588694639276;2837.1914004508717;2726.3047922779665;2778.569321755131;2780.304923769058;2796.189105159501;2822.520171570838;2682.9463410731773;2694.407230409307;2745.6018877262686;2759.599858020586;2763.2539214412195;2650.4566563854555;2682.906098286551;2722.9591556126675;2724.850133242671;2748.3541367778557;14868.330925108288;14946.103233805898;15023.849761502355;15054.32255548492;15092.245387730569;8097.109317975018;8364.916350836475;8382.832343353099;8386.538740369277;8395.412321137523;5350.464953504657;5587.38538760736;5737.770489180428;5756.30924289088;5761.53907687384;1972.5956178983274;1982.5043739065234;1996.1815655581288;2037.6128535578341;2062.0823733438624;26623.733762662512;26931.730682693313;26981.190564283104;27010.5948410774;27035.324116897038;6751.223609284897;6827.69275382609;6861.245263021064;6955.7217711292;6998.4751143664425;17350.219240134225;17441.56396090108;17451.214695338742;17463.301467941394;17470.563235919202;7801.414893882981;8087.3125784308995;8093;8097.068837083693;8098.271155596025;6075.908861367083;6111.388861113853;6569.901451478232;7580.976568163338;7587.127480339721;5175.353080864892;5291.603129765206;5366.6779993200535;5392.056390131711;5393.946060539422;1517.4006267524487;1526.1529850932618;1527.7402841516969;1533.1950725869008;1541.3834466213605;902.4765635931144;904.0913881549133;907.6414816147585;922.9616681233612;926.9768255793659;7565.6217189140325;7657.349125324298;7691.923080769233;7734.071911370672;7813.335866451302;704.7709494414336;731.9386889010956;746.9327760501473;748.8389996150854;755.0938873352;5219.217117432339;5291.391489978625;5329.413764485904;5451.318585176853;5529.695866727288;21163;21217.045232964552;21751.238706645192;21818.127274909144;22150.556988860455;21589.56820863574;21698.566028679652;21745.782542174376;21834.23580174686;22040.88979555092;10098.141657959155;10163.136133428594;10178.23663225276;10185.490725463696;10261.846072308772;8480.703175388831;8510.78723031929;8533.232009118961;8600;8676.826463470821;18072.81927180764;18135.36526221576;18254.543636409195;18594.628107437504;18851.15169817334;19596.510087247934;19940.70088948638;19947.700784488534;20047.897365644447;20137.093830777358;10955.34267943926;11301.71745706364;11367.772644547229;11403.543858245908;11406.885931140583;13871.514496992557;13884.444622214998;13899.305034748424;14026.649333766738;14128.43486260538;11220.831687524531;11438.199326047097;11577.247478913843;11703.23928944616;11800.586979455678;11033;11233.157513186381;11435.371054592077;11539.596114135966;11787.057035436974;10016.248781341283;10278.280520363503;10280.280380373302;10287.43419111956;10293.228007899586;7312.049433573601;7317.817054573678;7393.482456228013;7406.666699998405;7500.587467689329;7439.739609113655;7448.366888814511;7492.925070749223;7550.395968322631;7576.507527010726 05:44:56 INFO - PID 2346 | |4;prototype.html;511.93344865167523;516.5996352826559;542.1731858915157;543.6602123672704;553.0901666758181;6293.6853157342175;6306.558540902139;6318.462930650894;6318.810435686937;6405.231372235333;6185.536084793636;6188.164597779299;6190.969045154779;6277.843053923642;6293.339199384056;5085.4660260672745;5123.615728820335;5132.40977287613;5163.63854530183;5169.87075323118;5010.624203184776;5145.6398052136365;5157.896842063156;5161.225816127573;5166.560842328407;5042.445331013586;5069.873253168664;5106.591472682177;5114.744262786864;5148.5881129509735;5015.5736762375245;5088.2622019807295;5107.668001579886;5116.565091967187;5122.129238029525;4590.449146102455;4634.86095417138;4637.304404339376;4647.976760116211;4653.813847446098;6230.626162430269;6269.843253918643;6270.9372906271265;6298.181236439279;6323.494120470351;5717.571182161355;5796.1595568642315;5832.929310094898;5844.035734103868;5860.7948721794955;6667.3999340059145;6673.833154171185;6681.732730690766;6686.4985126115735;6715.529912906099;5025.648204625678;5026.924596131061;5027.145385284474;5034.320366750479;5035.62232832539;6953.548019378724;6968.338007889242;7014.859702805967;7018;7029.5430796998035;5129.589632829365;5132.871678208075;5153.943441594464;5160.767765450564;5162.354705661793;5190.403103643054;5231.659942103751;5241.187615919539;5241.9213711794355;5247.317848679647;5780.132980053026;5786.797462088806;5816.243888294494;5819.243498345188;5832.154337621021;6403.519736019724;6419.422251997343;6607.933920660828;6753.15585551806;6768.7292508299115;1752.658231644817;1761.5731257681064;1779.6796576616334;1785.2950432337086;1788.1238193285458;1061.3313612424124;1225.9877401226051;1228.961527509271;1230.975380492385;1252.5303011370736;5652.886942261214;5675.347335056439;5691.146328050825;5718.914216286759;5796.101604251347;6728.259891411941;6782.830429239309;6799.3200679931615;6804.196887824731;6842.281560436182;5675.1487276907965;5696.430356964353;5744.626599271034;5761.8559536011935;5792.9420705793245;3566.5185199998186;3566.6433356664124;3574;3592.7305452091196;3603.675669189733;6456.677166141674;6528.543001989815;6537.803865884031;6556;6581.80254592363;6888.483363747739;6941.132358455193;6964.965175174092;6968.477364197604;6976.965115174392;6394.648294343856;6436.356364363526;6473.8705225896165;6474.12599299098;6493.448056915215;6710.362515561014;6716.664166791543;6748.325167483213;6811.182658081062;6831.2144103428745;6697.3637504437;6746.595204287759;6759.831004224934;6802.01370801241;6810.387065163961;6513.576617519846;6520.771772987922;6576.309487503743;6589.934100658932;6645.368689974444;5009.348784657971;5036;5041.899162016666;5062.190049592047;5063.772130254221;6710.101625813086;6712.838040414974;6773.153355830521;6826.146731658543;6828.692708828214;6538.044483087663;6578.80263592093;6635;6650.869352210039;6679.165104361955;6585.5719378240265;6586.70359833799;6591;6610.933890661224;6657.234418041793;6292.2314181759875;6313.43179113882;6466;6472.673102013981;6553.967230163818;6321.7769743886765;6509.902351464637;6556.3443655634055;6595.327144804567;6599.208095028627;6197.5351848610635;6308.211473565804;6312.779052733133;6316.62100273985;6349.5872768271765 05:44:56 INFO - PID 2346 | |5;yui.html;895.1764376773372;900.2572877376165;919.7194855569048;920.4017388697346;921.7373048681123;20010.49973750657;20730.792692073075;20735.585288294245;20741.688874666874;20745.377638670827;18954.715679264787;19068.141933612987;19070.141843617035;19177.616447671036;19186.520336991547;974.7758015656385;995.1143482300758;997.2620261006839;999.6950929966357;1002.7142264454632;980.0787259975635;982.8280050991084;984.3306551544947;985.3496692183161;987.6395115782724;1021.0963297481708;1047.0994944347856;1050.4117694091294;1053.4522048534757;1068.3483075324045;1047.7537778622018;1051.069803224152;1051.7948999945013;1053.0522529723235;1057.751428414318;728.4318231779221;741;748.1807420874134;750.9399248060142;755.0750330844726;19939.601207975906;20050.1979920803;20100.497487562927;20184.899075504676;20281;21439.67840482394;21449.463763405885;22061.448463788533;22403.215887444025;22405.43986400337;17644.558886027822;17889.552761180945;17932.462026139237;17936.013519256434;17945.551361216076;3607.765495242827;3691.50164727761;3694.7228957828;3694.852205911787;3694.9815250923575;105809;137590;140641.29679351742;142723;143158;13025.674358140932;13371.799423008662;13410.664733381744;13444.596662100153;13490.595282141552;30252;30378.696213038027;30548.54177187344;31390.529142062886;31431.214219644233;8618.698345558001;8647.913520864711;8675.566221689016;8683.696070637498;8696.782580435412;3797.5883418228204;3884.106655469258;3898.3957486589625;3912.4326972588815;3923.725339226227;5437.265969094201;5461.781528738806;5533.3083364579425;5541.362743284493;5557.138643510262;1078.1213311151387;1096.17786660005;1103.2773533335678;1107.06453047175;1112.2825777373548;679.4632240530025;696.6864910790165;706.6643344411364;706.780897921646;719.9928000720031;11609.709757256136;12286;12763.617091487271;13520.93239533796;13543.322833858268;690.730615060127;699.2587856871733;703.6938931564786;705.6224919668056;717.1716667249335;5684.744186511617;5689.34572524165;5724.893706209939;5757.510611598061;5796.565257605696;117260;123072.38463807803;123914.38042809728;124111;127274.36362818311;105749;107970;110256;113837.4308128454;113841.43079284551;91657;93575;95006;96339.51830240803;98309.50845245727;10501.474926253504;10525.315854469434;10820.5130769114;10838.349699018085;10869.402182880018;44361.33457998197;44459;45062.54937450714;45328.77335613433;45464.31803522884;58599.70700146472;59351.40648593631;59847;60143.39856601378;60150.39849601448;16208.594785130466;16241.756373654642;16348.836511634732;16368.09975451314;16423;17602.207900644757;17668.558286042953;17771.555711107325;17980;18035.549111271797;16554.668906622042;16745.33018679288;16759.91620041892;16839.073850937824;16936;16671.24979375955;16796.832031680016;16822.831771682126;16989.40537081196;17087.401940932006;15995.600109997344;16265.593360165618;16459.34162633481;16524.339026438807;16720.832791671928;11460.369679667698;11745.647630571097;11886.881131188577;12024;12040.638780836589;12468;12548.121631485708;12560.685982850136;12681.682957926125;12683.302418367079 05:44:56 INFO - PID 2346 | __end_tp_report 05:44:56 INFO - PID 2346 | __start_cc_report 05:44:56 INFO - PID 2346 | _x_x_mozilla_cycle_collect,291 05:44:56 INFO - PID 2346 | __end_cc_report 05:44:56 INFO - PID 2346 | __startTimestamp1506689096732__endTimestamp 05:44:56 INFO - PID 2346 | 05:44:56 INFO - PID 2346 | ------- Summary: start ------- 05:44:56 INFO - PID 2346 | Number of tests: 6 05:44:56 INFO - PID 2346 | 05:44:56 INFO - PID 2346 | [#0] dojo.html Cycles:165 Average:6278.69 Median:6500.80 stddev:760.49 (11.7%) stddev-sans-first:722.90 05:44:56 INFO - PID 2346 | Values: 3179.2 3206.2 3206.6 3210.9 3218.5 6451.5 6494.1 6509.3 6540.3 6553.8 6230.1 6364.6 6368.3 6387.7 6392.6 5867.2 5947.8 5964.5 5988.1 6024.8 5911.5 5926.9 5954.5 5968.0 5999.4 5774.0 5793.3 5822.4 5853.7 5888.1 5854.4 5919.6 5926.9 5934.9 5936.1 5714.3 5799.3 5814.9 5870.8 5876.8 6308.6 6323.6 6331.3 6387.1 6410.3 6886.1 6903.9 6908.9 6910.8 6919.6 6916.4 6999.0 7003.3 7003.4 7005.4 6378.2 6379.3 6413.6 6473.7 6486.8 6877.0 6946.0 6992.7 6992.8 7084.1 6775.5 6775.6 6781.4 6807.6 6814.2 6830.4 6897.1 6917.5 6925.2 6931.1 6796.5 6813.5 6822.8 6910.1 6911.6 6682.4 6843.5 6852.4 6853.3 6853.9 5320.1 5321.8 5321.8 5323.7 5327.7 4649.5 4685.2 4718.1 4756.4 4758.8 5675.1 5786.1 5854.3 5863.7 5869.4 6778.7 6801.9 6879.1 6889.1 6902.8 5665.2 5714.1 5745.8 5756.5 5776.7 5766.2 5828.7 5835.5 5843.1 5866.0 6532.4 6545.2 6571.2 6576.4 6589.6 6791.2 6809.2 6870.4 6894.8 6910.2 6406.8 6461.5 6467.6 6520.2 6563.9 6669.3 6715.5 6719.0 6722.6 6743.1 6884.9 6956.0 6963.8 6979.2 6998.2 6519.9 6600.0 6603.3 6606.4 6606.5 6557.8 6560.4 6638.8 6645.1 6654.7 6384.7 6483.5 6497.7 6498.1 6500.8 6604.9 6628.2 6648.4 6722.3 6762.2 6418.7 6454.6 6499.4 6530.2 6530.9 05:44:56 INFO - PID 2346 | 05:44:56 INFO - PID 2346 | [#1] ext.html Cycles:180 Average:37686.08 Median:3511.12 stddev:53148.18 (1513.7%) stddev-sans-first:53225.32 05:44:56 INFO - PID 2346 | Values: 855.3 882.0 882.7 883.0 883.4 38545.4 42108.0 42161.6 42195.2 42196.0 38867.2 39080.2 39091.8 39138.2 39188.4 7185.4 7299.6 7320.0 7885.1 8076.7 7973.8 8030.2 8038.6 8093.3 8106.2 8021.1 8098.8 8106.8 8135.8 8142.8 8042.9 8059.2 8059.8 8092.1 8117.0 7861.0 7902.0 7947.7 7954.4 7970.2 37460.8 37700.1 37774.0 37803.8 37858.0 3465.9 3508.5 3509.2 3510.7 3511.6 30772.5 30836.1 30913.4 30930.5 30967.7 5145.9 5186.5 5188.6 5208.5 5227.3 185849.0 186704.1 187771.0 187993.0 188449.0 43170.7 43882.6 48680.8 48891.0 49053.5 50884.0 51962.5 52013.0 52069.0 52071.0 16165.4 16384.8 16411.3 16417.3 16447.2 13446.3 13446.7 13453.9 13502.9 13549.9 14051.9 14216.6 14251.7 14259.6 14380.3 5859.9 5888.9 5981.5 5996.3 6020.9 1013.6 1030.6 1039.9 1045.0 1045.0 22783.3 23401.2 23582.6 23582.8 23699.6 1120.7 1122.4 1124.4 1125.6 1135.0 12158.6 12164.5 12182.9 12380.5 12568.9 226326.0 226572.0 226863.0 227786.0 227915.0 52724.0 52763.7 52803.5 52942.7 53297.5 1447.7 1461.9 1470.8 1483.6 1485.2 22690.3 22785.2 22913.0 23220.8 23233.5 36756.8 36846.4 36900.0 36954.0 36995.6 199090.0 199654.0 199860.0 200013.0 200865.0 33317.0 35093.3 35979.0 35989.3 36021.6 44369.6 45350.3 45783.3 45816.3 46092.0 32981.7 33265.2 33323.0 33509.3 33799.3 33605.5 33631.5 33739.5 34063.3 34215.1 37827.2 37849.2 37880.6 37978.2 37991.0 32154.2 32406.7 32531.7 32692.3 33079.5 24461.3 24544.8 24858.5 25093.5 25236.2 05:44:56 INFO - PID 2346 | 05:44:56 INFO - PID 2346 | [#2] jquery.html Cycles:180 Average:37392.30 Median:22192.22 stddev:87672.12 (395.1%) stddev-sans-first:87874.46 05:44:56 INFO - PID 2346 | Values: 565.3 570.6 582.6 596.3 606.7 22805.9 23480.5 23524.0 23544.4 23547.8 24331.3 25241.0 26029.5 26297.2 26313.0 6668.2 6876.4 6883.7 6888.4 6889.8 6187.4 6483.2 6507.7 6795.1 6905.6 6416.7 6456.4 6463.8 6466.5 6468.3 6459.6 6462.1 6472.2 6478.9 6992.5 7130.6 7145.5 7173.2 7179.1 7197.5 31616.4 32144.4 32157.4 32248.2 32381.7 1969.4 2007.7 2016.2 2019.3 2025.5 19339.2 19689.1 19811.0 19853.3 20241.3 2528.9 2538.1 2570.8 2583.4 2583.5 124327.0 129716.4 130301.3 145981.0 150499.0 21924.8 22459.7 22482.2 22915.5 23331.0 44780.0 44832.3 44925.8 44929.6 45177.8 11743.8 11801.6 12049.8 12054.3 12086.8 10088.6 10098.6 10154.8 10245.4 10291.2 11210.8 11452.9 11625.3 11635.4 11773.4 3639.4 3660.2 3903.9 4094.4 4339.6 941.8 962.9 965.3 967.3 977.2 20019.0 20335.0 20392.0 20770.2 20842.6 1009.6 1014.0 1027.5 1029.4 1034.8 11005.3 11040.3 11188.8 11300.3 11427.7 504789.0 519966.0 528296.0 532865.0 535888.0 115238.4 116054.4 117022.0 118600.4 118707.0 1136.1 1142.3 1179.9 1180.7 1185.9 17778.6 17928.4 18059.2 18142.6 18610.1 57657.4 57909.1 58516.7 58652.4 59388.0 70528.6 70690.0 71455.6 72147.0 73233.0 21013.3 21023.9 21139.0 21298.4 21321.8 23725.4 23755.9 23785.6 23945.8 24061.6 18824.0 18828.5 19024.4 19154.1 19166.9 16764.4 16964.0 17457.7 17512.9 17838.7 17998.7 19262.2 19548.4 19702.9 19825.1 20944.4 21026.3 21027.0 21044.5 21128.2 18846.4 18969.3 18995.5 19261.6 19286.0 05:44:56 INFO - PID 2346 | 05:44:56 INFO - PID 2346 | [#3] mootools.html Cycles:180 Average:9446.17 Median:14813.07 stddev:6784.99 (45.8%) stddev-sans-first:6776.53 05:44:56 INFO - PID 2346 | Values: 1316.1 1372.4 1389.1 1398.1 1399.4 15262.5 16465.8 16494.5 16507.2 16541.5 15918.5 16265.3 16270.2 16280.1 16318.2 2669.2 2793.1 2868.1 2877.3 2881.1 2803.4 2834.2 2836.2 2836.6 2837.2 2726.3 2778.6 2780.3 2796.2 2822.5 2682.9 2694.4 2745.6 2759.6 2763.3 2650.5 2682.9 2723.0 2724.9 2748.4 14868.3 14946.1 15023.8 15054.3 15092.2 8097.1 8364.9 8382.8 8386.5 8395.4 5350.5 5587.4 5737.8 5756.3 5761.5 1972.6 1982.5 1996.2 2037.6 2062.1 26623.7 26931.7 26981.2 27010.6 27035.3 6751.2 6827.7 6861.2 6955.7 6998.5 17350.2 17441.6 17451.2 17463.3 17470.6 7801.4 8087.3 8093.0 8097.1 8098.3 6075.9 6111.4 6569.9 7581.0 7587.1 5175.4 5291.6 5366.7 5392.1 5393.9 1517.4 1526.2 1527.7 1533.2 1541.4 902.5 904.1 907.6 923.0 927.0 7565.6 7657.3 7691.9 7734.1 7813.3 704.8 731.9 746.9 748.8 755.1 5219.2 5291.4 5329.4 5451.3 5529.7 21163.0 21217.0 21751.2 21818.1 22150.6 21589.6 21698.6 21745.8 21834.2 22040.9 10098.1 10163.1 10178.2 10185.5 10261.8 8480.7 8510.8 8533.2 8600.0 8676.8 18072.8 18135.4 18254.5 18594.6 18851.2 19596.5 19940.7 19947.7 20047.9 20137.1 10955.3 11301.7 11367.8 11403.5 11406.9 13871.5 13884.4 13899.3 14026.6 14128.4 11220.8 11438.2 11577.2 11703.2 11800.6 11033.0 11233.2 11435.4 11539.6 11787.1 10016.2 10278.3 10280.3 10287.4 10293.2 7312.0 7317.8 7393.5 7406.7 7500.6 7439.7 7448.4 7492.9 7550.4 7576.5 05:44:56 INFO - PID 2346 | 05:44:56 INFO - PID 2346 | [#4] prototype.html Cycles:180 Average:5578.73 Median:6270.39 stddev:1551.68 (24.7%) stddev-sans-first:1508.71 05:44:56 INFO - PID 2346 | Values: 511.9 516.6 542.2 543.7 553.1 6293.7 6306.6 6318.5 6318.8 6405.2 6185.5 6188.2 6191.0 6277.8 6293.3 5085.5 5123.6 5132.4 5163.6 5169.9 5010.6 5145.6 5157.9 5161.2 5166.6 5042.4 5069.9 5106.6 5114.7 5148.6 5015.6 5088.3 5107.7 5116.6 5122.1 4590.4 4634.9 4637.3 4648.0 4653.8 6230.6 6269.8 6270.9 6298.2 6323.5 5717.6 5796.2 5832.9 5844.0 5860.8 6667.4 6673.8 6681.7 6686.5 6715.5 5025.6 5026.9 5027.1 5034.3 5035.6 6953.5 6968.3 7014.9 7018.0 7029.5 5129.6 5132.9 5153.9 5160.8 5162.4 5190.4 5231.7 5241.2 5241.9 5247.3 5780.1 5786.8 5816.2 5819.2 5832.2 6403.5 6419.4 6607.9 6753.2 6768.7 1752.7 1761.6 1779.7 1785.3 1788.1 1061.3 1226.0 1229.0 1231.0 1252.5 5652.9 5675.3 5691.1 5718.9 5796.1 6728.3 6782.8 6799.3 6804.2 6842.3 5675.1 5696.4 5744.6 5761.9 5792.9 3566.5 3566.6 3574.0 3592.7 3603.7 6456.7 6528.5 6537.8 6556.0 6581.8 6888.5 6941.1 6965.0 6968.5 6977.0 6394.6 6436.4 6473.9 6474.1 6493.4 6710.4 6716.7 6748.3 6811.2 6831.2 6697.4 6746.6 6759.8 6802.0 6810.4 6513.6 6520.8 6576.3 6589.9 6645.4 5009.3 5036.0 5041.9 5062.2 5063.8 6710.1 6712.8 6773.2 6826.1 6828.7 6538.0 6578.8 6635.0 6650.9 6679.2 6585.6 6586.7 6591.0 6610.9 6657.2 6292.2 6313.4 6466.0 6472.7 6554.0 6321.8 6509.9 6556.3 6595.3 6599.2 6197.5 6308.2 6312.8 6316.6 6349.6 05:44:56 INFO - PID 2346 | 05:44:56 INFO - PID 2346 | [#5] yui.html Cycles:180 Average:24402.15 Median:19563.06 stddev:35042.64 (179.1%) stddev-sans-first:35096.49 05:44:56 INFO - PID 2346 | Values: 895.2 900.3 919.7 920.4 921.7 20010.5 20730.8 20735.6 20741.7 20745.4 18954.7 19068.1 19070.1 19177.6 19186.5 974.8 995.1 997.3 999.7 1002.7 980.1 982.8 984.3 985.3 987.6 1021.1 1047.1 1050.4 1053.5 1068.3 1047.8 1051.1 1051.8 1053.1 1057.8 728.4 741.0 748.2 750.9 755.1 19939.6 20050.2 20100.5 20184.9 20281.0 21439.7 21449.5 22061.4 22403.2 22405.4 17644.6 17889.6 17932.5 17936.0 17945.6 3607.8 3691.5 3694.7 3694.9 3695.0 105809.0 137590.0 140641.3 142723.0 143158.0 13025.7 13371.8 13410.7 13444.6 13490.6 30252.0 30378.7 30548.5 31390.5 31431.2 8618.7 8647.9 8675.6 8683.7 8696.8 3797.6 3884.1 3898.4 3912.4 3923.7 5437.3 5461.8 5533.3 5541.4 5557.1 1078.1 1096.2 1103.3 1107.1 1112.3 679.5 696.7 706.7 706.8 720.0 11609.7 12286.0 12763.6 13520.9 13543.3 690.7 699.3 703.7 705.6 717.2 5684.7 5689.3 5724.9 5757.5 5796.6 117260.0 123072.4 123914.4 124111.0 127274.4 105749.0 107970.0 110256.0 113837.4 113841.4 91657.0 93575.0 95006.0 96339.5 98309.5 10501.5 10525.3 10820.5 10838.3 10869.4 44361.3 44459.0 45062.5 45328.8 45464.3 58599.7 59351.4 59847.0 60143.4 60150.4 16208.6 16241.8 16348.8 16368.1 16423.0 17602.2 17668.6 17771.6 17980.0 18035.5 16554.7 16745.3 16759.9 16839.1 16936.0 16671.2 16796.8 16822.8 16989.4 17087.4 15995.6 16265.6 16459.3 16524.3 16720.8 11460.4 11745.6 11886.9 12024.0 12040.6 12468.0 12548.1 12560.7 12681.7 12683.3 05:44:56 INFO - PID 2346 | -------- Summary: end -------- 05:44:56 INFO - PID 2346 | 05:44:57 INFO - TEST-INFO | 2346: exit 0 05:44:57 INFO - TEST-OK | dromaeo_css | took 1109820ms 05:44:57 INFO - TEST-START | kraken 05:44:57 INFO - Initialising browser for kraken test... 05:44:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43096/getInfo.html -profile /tmp/tmpkf5fkK/profile 05:44:57 INFO - TEST-INFO | started process 2805 (/builds/slave/test/build/application/firefox/firefox http://localhost:43096/getInfo.html) 05:45:02 INFO - TEST-INFO | 2805: exit 0 05:45:02 INFO - Browser initialized. 05:45:02 INFO - Running cycle 1/1 for kraken test... 05:45:02 INFO - Using env: {'DISPLAY': ':0', 05:45:02 INFO - 'HOME': '/home/cltbld', 05:45:02 INFO - 'JSGC_DISABLE_POISONING': '1', 05:45:02 INFO - 'LANG': 'en_US.UTF-8', 05:45:02 INFO - 'LANGUAGE': 'en_US:en', 05:45:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:45:02 INFO - 'LOGNAME': 'cltbld', 05:45:02 INFO - 'MAIL': '/var/mail/cltbld', 05:45:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:45:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:45:02 INFO - 'MOZ_CRASHREPORTER': '1', 05:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:45:02 INFO - 'MOZ_NO_REMOTE': '1', 05:45:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:45:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:02 INFO - 'NO_EM_RESTART': '1', 05:45:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:02 INFO - 'PWD': '/builds/slave/test', 05:45:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:45:02 INFO - 'RUST_BACKTRACE': 'full', 05:45:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:45:02 INFO - 'SHELL': '/bin/bash', 05:45:02 INFO - 'SHLVL': '1', 05:45:02 INFO - 'TERM': 'linux', 05:45:02 INFO - 'TMOUT': '86400', 05:45:02 INFO - 'USER': 'cltbld', 05:45:02 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808', 05:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:02 INFO - '_': '/tools/buildbot/bin/python'} 05:45:02 INFO - TEST-INFO | started process 3127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkf5fkK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:45:03 INFO - PID 3127 | 05:45:03 INFO - PID 3127 | (/builds/slave/test/build/application/firefox/firefox:3191): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:45:03 INFO - PID 3127 | 05:45:13 INFO - PID 3127 | RSS: Main: 151584768 05:45:13 INFO - PID 3127 | 05:45:16 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43096/tests/kraken/driver.html?testName=audio-beat-detection) 05:45:17 INFO - PID 3127 | RSS: Main: 158007296 05:45:17 INFO - PID 3127 | 05:45:20 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43096/tests/kraken/driver.html?testName=audio-dft) 05:45:20 INFO - PID 3127 | RSS: Main: 158461952 05:45:20 INFO - PID 3127 | 05:45:23 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43096/tests/kraken/driver.html?testName=audio-fft) 05:45:24 INFO - PID 3127 | RSS: Main: 154042368 05:45:24 INFO - PID 3127 | 05:45:26 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43096/tests/kraken/driver.html?testName=audio-oscillator) 05:45:26 INFO - PID 3127 | RSS: Main: 155000832 05:45:26 INFO - PID 3127 | 05:45:29 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43096/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:45:29 INFO - PID 3127 | RSS: Main: 155086848 05:45:29 INFO - PID 3127 | 05:45:32 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43096/tests/kraken/driver.html?testName=imaging-darkroom) 05:45:33 INFO - PID 3127 | RSS: Main: 157179904 05:45:33 INFO - PID 3127 | 05:45:37 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43096/tests/kraken/driver.html?testName=imaging-desaturate) 05:45:37 INFO - PID 3127 | RSS: Main: 157872128 05:45:37 INFO - PID 3127 | 05:45:40 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43096/tests/kraken/driver.html?testName=json-parse-financial) 05:45:41 INFO - PID 3127 | RSS: Main: 158146560 05:45:41 INFO - PID 3127 | 05:45:43 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43096/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:45:43 INFO - PID 3127 | RSS: Main: 157827072 05:45:43 INFO - PID 3127 | 05:45:45 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43096/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:45:45 INFO - PID 3127 | RSS: Main: 154947584 05:45:45 INFO - PID 3127 | 05:45:48 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43096/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:45:48 INFO - PID 3127 | RSS: Main: 154636288 05:45:48 INFO - PID 3127 | 05:45:51 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43096/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:45:51 INFO - PID 3127 | RSS: Main: 155582464 05:45:51 INFO - PID 3127 | 05:45:54 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43096/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:45:55 INFO - PID 3127 | RSS: Main: 155475968 05:45:55 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | Cycle 1(1): loaded http://localhost:43096/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43096/tests/kraken/driver.html?testName=ai-astar) 05:45:57 INFO - PID 3127 | RSS: Main: 156745728 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | __start_tp_report 05:45:57 INFO - PID 3127 | _x_x_mozilla_page_load 05:45:57 INFO - PID 3127 | _x_x_mozilla_page_load_details 05:45:57 INFO - PID 3127 | |i|pagename|runs| 05:45:57 INFO - PID 3127 | |0;ai-astar;107;107;109;108;108;108;107;110;109;106 05:45:57 INFO - PID 3127 | |1;audio-beat-detection;118;120;115;116;140;120;116;119;121;118 05:45:57 INFO - PID 3127 | |2;audio-dft;164;166;165;164;163;165;165;165;168;166 05:45:57 INFO - PID 3127 | |3;audio-fft;74;76;74;73;73;74;76;74;76;76 05:45:57 INFO - PID 3127 | |4;audio-oscillator;80;79;80;79;78;79;79;78;80;123 05:45:57 INFO - PID 3127 | |5;imaging-gaussian-blur;111;111;113;112;112;112;112;112;112;113 05:45:57 INFO - PID 3127 | |6;imaging-darkroom;149;148;148;149;148;149;151;148;148;149 05:45:57 INFO - PID 3127 | |7;imaging-desaturate;104;94;94;94;94;95;93;94;96;95 05:45:57 INFO - PID 3127 | |8;json-parse-financial;52;52;53;53;51;55;53;51;51;51 05:45:57 INFO - PID 3127 | |9;json-stringify-tinderbox;41;37;37;37;37;37;38;38;37;38 05:45:57 INFO - PID 3127 | |10;stanford-crypto-aes;78;75;76;73;73;74;74;76;73;75 05:45:57 INFO - PID 3127 | |11;stanford-crypto-ccm;115;111;112;108;119;109;115;116;114;107 05:45:57 INFO - PID 3127 | |12;stanford-crypto-pbkdf2;161;162;171;159;159;170;164;160;164;165 05:45:57 INFO - PID 3127 | |13;stanford-crypto-sha256-iterative;63;65;63;64;65;69;65;67;67;66 05:45:57 INFO - PID 3127 | __end_tp_report 05:45:57 INFO - PID 3127 | __start_cc_report 05:45:57 INFO - PID 3127 | _x_x_mozilla_cycle_collect,800 05:45:57 INFO - PID 3127 | __end_cc_report 05:45:57 INFO - PID 3127 | __startTimestamp1506689157358__endTimestamp 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | ------- Summary: start ------- 05:45:57 INFO - PID 3127 | Number of tests: 14 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#0] ai-astar Cycles:10 Average:107.90 Median:108.00 stddev:1.20 (1.1%) stddev-sans-first:1.22 05:45:57 INFO - PID 3127 | Values: 107.0 107.0 109.0 108.0 108.0 108.0 107.0 110.0 109.0 106.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#1] audio-beat-detection Cycles:10 Average:120.30 Median:119.50 stddev:7.20 (6.0%) stddev-sans-first:7.58 05:45:57 INFO - PID 3127 | Values: 118.0 120.0 115.0 116.0 140.0 120.0 116.0 119.0 121.0 118.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#2] audio-dft Cycles:10 Average:165.10 Median:165.00 stddev:1.37 (0.8%) stddev-sans-first:1.39 05:45:57 INFO - PID 3127 | Values: 164.0 166.0 165.0 164.0 163.0 165.0 165.0 165.0 168.0 166.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#3] audio-fft Cycles:10 Average:74.60 Median:75.00 stddev:1.26 (1.7%) stddev-sans-first:1.32 05:45:57 INFO - PID 3127 | Values: 74.0 76.0 74.0 73.0 73.0 74.0 76.0 74.0 76.0 76.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:79.00 stddev:13.90 (17.6%) stddev-sans-first:14.68 05:45:57 INFO - PID 3127 | Values: 80.0 79.0 80.0 79.0 78.0 79.0 79.0 78.0 80.0 123.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#5] imaging-gaussian-blur Cycles:10 Average:112.00 Median:112.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 05:45:57 INFO - PID 3127 | Values: 111.0 111.0 113.0 112.0 112.0 112.0 112.0 112.0 112.0 113.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 05:45:57 INFO - PID 3127 | Values: 149.0 148.0 148.0 149.0 148.0 149.0 151.0 148.0 148.0 149.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:94.00 stddev:3.16 (3.4%) stddev-sans-first:0.87 05:45:57 INFO - PID 3127 | Values: 104.0 94.0 94.0 94.0 94.0 95.0 93.0 94.0 96.0 95.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.50 stddev:1.32 (2.5%) stddev-sans-first:1.39 05:45:57 INFO - PID 3127 | Values: 52.0 52.0 53.0 53.0 51.0 55.0 53.0 51.0 51.0 51.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#9] json-stringify-tinderbox Cycles:10 Average:37.70 Median:37.50 stddev:1.25 (3.3%) stddev-sans-first:0.50 05:45:57 INFO - PID 3127 | Values: 41.0 37.0 37.0 37.0 37.0 37.0 38.0 38.0 37.0 38.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:75.00 stddev:1.64 (2.2%) stddev-sans-first:1.22 05:45:57 INFO - PID 3127 | Values: 78.0 75.0 76.0 73.0 73.0 74.0 74.0 76.0 73.0 75.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#11] stanford-crypto-ccm Cycles:10 Average:112.60 Median:114.50 stddev:3.86 (3.4%) stddev-sans-first:4.00 05:45:57 INFO - PID 3127 | Values: 115.0 111.0 112.0 108.0 119.0 109.0 115.0 116.0 114.0 107.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:164.00 stddev:4.25 (2.6%) stddev-sans-first:4.41 05:45:57 INFO - PID 3127 | Values: 161.0 162.0 171.0 159.0 159.0 170.0 164.0 160.0 164.0 165.0 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:1.90 (2.9%) stddev-sans-first:1.80 05:45:57 INFO - PID 3127 | Values: 63.0 65.0 63.0 64.0 65.0 69.0 65.0 67.0 67.0 66.0 05:45:57 INFO - PID 3127 | -------- Summary: end -------- 05:45:57 INFO - PID 3127 | 05:45:57 INFO - PID 3127 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:45:58 INFO - TEST-INFO | 3127: exit 0 05:45:58 INFO - TEST-OK | kraken | took 60995ms 05:45:58 INFO - SUITE-END | took 1170s 05:45:58 INFO - Completed test suite (00:19:31) 05:45:58 INFO - javascript benchmark 05:45:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8726.391850832932, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3179.189306726785, 3206.230504678877, 3206.615206175259, 3210.8715651373946, 3218.517222416637, 6451.548391612589, 6494.123293355396, 6509.349065093488, 6540.345965403457, 6553.83615409615, 6230.096635987779, 6364.618122912617, 6368.267649220341, 6387.744490220397, 6392.552521323498, 5867.207926929853, 5947.821565353046, 5964.476499723188, 5988.101784732303, 6024.8493787655225, 5911.5270778337845, 5926.911096333559, 5954.4640982311585, 5967.98544247479, 5999.400059994009, 5773.971130144364, 5793.304803423595, 5822.388649191837, 5853.7365818538265, 5888.146218798293, 5854.356020837705, 5919.585629005971, 5926.940730592682, 5934.910976335358, 5936.1392598073035, 5714.342850572196, 5799.33307669615, 5814.912776308359, 5870.765169393219, 5876.794312199053, 6308.589941653824, 6323.588966717149, 6331.303556608769, 6387.1377364055725, 6410.326915673833, 6886.070380498619, 6903.896441553381, 6908.930910690879, 6910.792676219721, 6919.584824910471, 6916.377526022682, 6999.020137180799, 7003.264657210971, 7003.404710599605, 7005.439564834801, 6378.202724659418, 6379.330170332141, 6413.551051426355, 6473.676316184172, 6486.772962946368, 6877.037214789933, 6945.965270173617, 6992.650367481606, 6992.79021629352, 7084.079069720903, 6775.525713200127, 6775.559588626724, 6781.355771201728, 6807.591544507345, 6814.182298124158, 6830.385265326046, 6897.137857767779, 6917.515773895779, 6925.203601585882, 6931.133608298963, 6796.490263230277, 6813.523053386314, 6822.829429264308, 6910.136232970879, 6911.5853048817235, 6682.398584127452, 6843.486738494533, 6852.417544508673, 6853.314668533207, 6853.897191542131, 5320.12217984036, 5321.813736519281, 5321.840344789663, 5323.707196104251, 5327.706976116273, 4649.465311489154, 4685.156671799109, 4718.079974405026, 4756.381670382828, 4758.833440829623, 5675.0636145035605, 5786.132080188006, 5854.297484301911, 5863.70681465925, 5869.354371019184, 6778.72885084601, 6801.931980680229, 6879.071325371111, 6889.104416425833, 6902.827429314207, 5665.235193248858, 5714.085746280575, 5745.770169193269, 5756.481916627524, 5776.740046697909, 5766.192733017381, 5828.708564571754, 5835.533157347487, 5843.065109582446, 5866.032104702762, 6532.444742196966, 6545.181852268466, 6571.178602674666, 6576.408123268929, 6589.637569933604, 6791.151106111736, 6809.182898052166, 6870.381665650116, 6894.793156205322, 6910.170779506491, 6406.775762848278, 6461.515386345949, 6467.611943283418, 6520.217573890974, 6563.868722625616, 6669.266380698216, 6715.4963377747845, 6718.992151177363, 6722.563033402813, 6743.089682892943, 6884.862302753817, 6955.965220174069, 6963.825904352431, 6979.162500499972, 6998.16022077354, 6519.891618424974, 6600.0, 6603.339666033358, 6606.372394622503, 6606.53754237218, 6557.803265902031, 6560.40956313934, 6638.800835974928, 6645.1361323027695, 6654.700538475683, 6384.744610215539, 6483.481321494363, 6497.707603157774, 6498.057781621544, 6500.804975850732, 6604.933950660432, 6628.237752658506, 6648.434883034996, 6722.2605513394465, 6762.222344430454, 6418.679066046586, 6454.612723236621, 6499.382558657109, 6530.249021362604, 6530.869382612226], "value": 6220.326037187512, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [855.2858363266672, 881.982360352793, 882.7087061269782, 882.9823403531927, 883.4037025008118, 38545.42181867271, 42108.0, 42161.57838421623, 42195.15609687805, 42196.0, 38867.22265554687, 39080.21839563207, 39091.80454097725, 39138.21723565527, 39188.41217381734, 7185.389241914443, 7299.635018249093, 7320.0, 7885.1326354101, 8076.71731489398, 7973.840523189562, 8030.156833532483, 8038.557879316635, 8093.271605555499, 8106.229908158745, 8021.077576078767, 8098.797530061766, 8106.837863242732, 8135.837283254331, 8142.796430089265, 8042.919570804304, 8059.23437273464, 8059.838803223903, 8092.109867914524, 8117.025956885212, 7860.960695196544, 7902.0, 7947.721829735931, 7954.443188976774, 7970.163132871081, 37460.81269593662, 37700.057498562484, 37774.0, 37803.81098094519, 37858.0, 3465.930681386383, 3508.491268766015, 3509.2279698466295, 3510.666486683761, 3511.578610566723, 30772.53841192384, 30836.074917752503, 30913.38173236545, 30930.53604195939, 30967.690323096707, 5145.897082058375, 5186.50728180826, 5188.584913206934, 5208.531232189083, 5227.320448341691, 185849.0, 186704.06647966808, 187771.0, 187993.0, 188449.0, 43170.704878853685, 43882.56117438849, 48680.756596216794, 48891.0, 49053.50946490489, 50884.0, 51962.48037519652, 52013.0, 52069.0, 52071.0, 16165.35338586467, 16384.75422868658, 16411.261493232832, 16417.343306267616, 16447.177641118134, 13446.32768361578, 13446.663833404047, 13453.932730336286, 13502.852257558217, 13549.86450135486, 14051.929740351437, 14216.644583885485, 14251.714965700628, 14259.64350891236, 14380.28098595087, 5859.94140058594, 5888.941110588839, 5981.491573216325, 5996.2504686914135, 6020.909686354708, 1013.6046941692746, 1030.5568605499707, 1039.859618951432, 1044.9529771160317, 1044.984325235122, 22783.31650050468, 23401.180958666366, 23582.646260306108, 23582.81853185427, 23699.644505332435, 1120.6918097523246, 1122.3770807202081, 1124.3759713359163, 1125.5779082843933, 1134.9659510214542, 12158.635240942785, 12164.452599633038, 12182.939085304693, 12380.504779808887, 12568.874311256954, 226326.0, 226572.0, 226863.0, 227786.0, 227915.0, 52724.0, 52763.73618131885, 52803.47196527985, 52942.735286323325, 53297.46702533003, 1447.6970726346372, 1461.9122852628877, 1470.8382077971414, 1483.5697647682266, 1485.1534625263712, 22690.319290421314, 22785.20251791179, 22913.0, 23220.76779232186, 23233.535329292983, 36756.81621591875, 36846.44730329101, 36900.0, 36954.0, 36995.63004369922, 199090.0, 199654.0, 199860.0, 200013.0, 200865.0, 33317.0, 35093.29813403769, 35979.0, 35989.28021439504, 36021.63978360288, 44369.55630443654, 45350.319745204506, 45783.313250300605, 45816.312755309365, 46092.0, 32981.67018329882, 33265.168370790925, 33323.0, 33509.32981340311, 33799.32401352008, 33605.49591756175, 33631.495527566614, 33739.493907591896, 34063.31873362568, 34215.144621384665, 37827.24345513129, 37849.24301513899, 37880.62119378771, 37978.240435190586, 37991.0, 32154.19614509656, 32406.67593324131, 32531.674683253812, 32692.34615307728, 33079.50380744242, 24461.266162015167, 24544.754552454247, 24858.50282994294, 25093.498130037664, 25236.24291271265], "value": 17391.969152659232, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.3272605599336, 570.5863249144368, 582.6009183709157, 596.2546816479401, 606.6602702486608, 22805.885970570143, 23480.530389392246, 23524.0, 23544.411389715224, 23547.76452235477, 24331.270061898173, 25241.0, 26029.47941041178, 26297.211083667473, 26313.0, 6668.199816022084, 6876.381125698686, 6883.724651013953, 6888.4144847688, 6889.827754306158, 6187.412195841388, 6483.22201335838, 6507.707153178095, 6795.082663840368, 6905.551139175963, 6416.711247993828, 6456.386643268906, 6463.806085817433, 6466.547341686084, 6468.2561505426775, 6459.580127291734, 6462.095306657072, 6472.190976127984, 6478.902816457757, 6992.5105242633035, 7130.6078165700865, 7145.499815012951, 7173.175084865203, 7179.1026121734785, 7197.532160409609, 31616.36767264642, 32144.357112857844, 32157.356852863046, 32248.193795155075, 32381.6761832381, 1969.3993332033724, 2007.7289565908707, 2016.2439085342996, 2019.3134334326253, 2025.463252238158, 19339.226430942887, 19689.11398987049, 19811.0, 19853.305134320228, 20241.291554795658, 2528.911488097926, 2538.0862889359814, 2570.832895861763, 2583.354161459635, 2583.534963706532, 124327.0, 129716.35141824231, 130301.34849325787, 145981.0, 150499.0, 21924.780752192273, 22459.663105053438, 22482.196846655635, 22915.541689166123, 23331.0, 44780.0, 44832.3275150873, 44925.77537112294, 44929.55070449254, 45177.774111129234, 11743.765124697458, 11801.645950621494, 12049.759004819854, 12054.337011464278, 12086.758264834654, 10088.596456141819, 10098.596056157672, 10154.796904061877, 10245.385276883411, 10291.176705863512, 11210.775784484265, 11452.94273528627, 11625.302481851117, 11635.360055197045, 11773.352465614302, 3639.3771622842773, 3660.194757153422, 3903.941440878389, 4094.385842123705, 4339.609435150852, 941.8210539997378, 962.8940816510178, 965.3242730088966, 967.3083745122271, 977.1889331854544, 20019.0, 20335.0, 20392.0, 20770.1691932321, 20842.583148336947, 1009.5860697114294, 1014.0, 1027.4811220333684, 1029.3875144289286, 1034.829253173218, 11005.339679619248, 11040.33757974508, 11188.832167517494, 11300.265482743594, 11427.657170284905, 504789.0, 519966.0, 528296.0, 532865.0, 535888.0, 115238.42380788042, 116054.41972790082, 117022.0, 118600.40699796446, 118707.0, 1136.1422126294594, 1142.280363371071, 1179.9233049851905, 1180.7048237940514, 1185.8932696057395, 17778.5555361117, 17928.372506962714, 18059.187336569623, 18142.637147257246, 18610.069496524848, 57657.423425765206, 57909.13136302875, 58516.70741646265, 58652.4134758647, 59388.0, 70528.64735676289, 70690.0, 71455.64272178606, 72147.0, 73233.0, 21013.264535741175, 21023.8948805255, 21139.0, 21298.402619803266, 21321.78678213198, 23725.406864827826, 23755.881220593787, 23785.64321535213, 23945.760542394353, 24061.639075413532, 18824.0, 18828.52928676794, 19024.42926712201, 19154.138063787443, 19166.90416547908, 16764.413245536347, 16964.0, 17457.73813392775, 17512.91243543774, 17838.732419013464, 17998.73001904999, 19262.22951081941, 19548.413547593595, 19702.901485492483, 19825.107870146167, 20944.37166884996, 21026.2640807571, 21027.0, 21044.473888152304, 21128.15487380549, 18846.43460696181, 18969.336073237922, 18995.525111872314, 19261.614767704847, 19286.0], "value": 13144.294410223603, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.1313533068178, 1372.4441601151532, 1389.1387339849296, 1398.147130250547, 1399.3842709207947, 15262.542123736292, 16465.83534164661, 16494.505164845043, 16507.17464126792, 16541.503754887344, 15918.52244432666, 16265.268062937166, 16270.186490675478, 16280.104594247312, 16318.184090795443, 2669.159214847317, 2793.064323451646, 2868.0678779396676, 2877.30944573303, 2881.121258016304, 2803.355228297493, 2834.1922552072665, 2836.2342167614734, 2836.588694639276, 2837.1914004508717, 2726.3047922779665, 2778.569321755131, 2780.304923769058, 2796.189105159501, 2822.520171570838, 2682.9463410731773, 2694.407230409307, 2745.6018877262686, 2759.599858020586, 2763.2539214412195, 2650.4566563854555, 2682.906098286551, 2722.9591556126675, 2724.850133242671, 2748.3541367778557, 14868.330925108288, 14946.103233805898, 15023.849761502355, 15054.32255548492, 15092.245387730569, 8097.109317975018, 8364.916350836475, 8382.832343353099, 8386.538740369277, 8395.412321137523, 5350.464953504657, 5587.38538760736, 5737.770489180428, 5756.30924289088, 5761.53907687384, 1972.5956178983274, 1982.5043739065234, 1996.1815655581288, 2037.6128535578341, 2062.0823733438624, 26623.733762662512, 26931.730682693313, 26981.190564283104, 27010.5948410774, 27035.324116897038, 6751.223609284897, 6827.69275382609, 6861.245263021064, 6955.7217711292, 6998.4751143664425, 17350.219240134225, 17441.56396090108, 17451.214695338742, 17463.301467941394, 17470.563235919202, 7801.414893882981, 8087.3125784308995, 8093.0, 8097.068837083693, 8098.271155596025, 6075.908861367083, 6111.388861113853, 6569.901451478232, 7580.976568163338, 7587.127480339721, 5175.353080864892, 5291.603129765206, 5366.6779993200535, 5392.056390131711, 5393.946060539422, 1517.4006267524487, 1526.1529850932618, 1527.7402841516969, 1533.1950725869008, 1541.3834466213605, 902.4765635931144, 904.0913881549133, 907.6414816147585, 922.9616681233612, 926.9768255793659, 7565.6217189140325, 7657.349125324298, 7691.923080769233, 7734.071911370672, 7813.335866451302, 704.7709494414336, 731.9386889010956, 746.9327760501473, 748.8389996150854, 755.0938873352, 5219.217117432339, 5291.391489978625, 5329.413764485904, 5451.318585176853, 5529.695866727288, 21163.0, 21217.045232964552, 21751.238706645192, 21818.127274909144, 22150.556988860455, 21589.56820863574, 21698.566028679652, 21745.782542174376, 21834.23580174686, 22040.88979555092, 10098.141657959155, 10163.136133428594, 10178.23663225276, 10185.490725463696, 10261.846072308772, 8480.703175388831, 8510.78723031929, 8533.232009118961, 8600.0, 8676.826463470821, 18072.81927180764, 18135.36526221576, 18254.543636409195, 18594.628107437504, 18851.15169817334, 19596.510087247934, 19940.70088948638, 19947.700784488534, 20047.897365644447, 20137.093830777358, 10955.34267943926, 11301.71745706364, 11367.772644547229, 11403.543858245908, 11406.885931140583, 13871.514496992557, 13884.444622214998, 13899.305034748424, 14026.649333766738, 14128.43486260538, 11220.831687524531, 11438.199326047097, 11577.247478913843, 11703.23928944616, 11800.586979455678, 11033.0, 11233.157513186381, 11435.371054592077, 11539.596114135966, 11787.057035436974, 10016.248781341283, 10278.280520363503, 10280.280380373302, 10287.43419111956, 10293.228007899586, 7312.049433573601, 7317.817054573678, 7393.482456228013, 7406.666699998405, 7500.587467689329, 7439.739609113655, 7448.366888814511, 7492.925070749223, 7550.395968322631, 7576.507527010726], "value": 6714.782692757541, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.93344865167523, 516.5996352826559, 542.1731858915157, 543.6602123672704, 553.0901666758181, 6293.6853157342175, 6306.558540902139, 6318.462930650894, 6318.810435686937, 6405.231372235333, 6185.536084793636, 6188.164597779299, 6190.969045154779, 6277.843053923642, 6293.339199384056, 5085.4660260672745, 5123.615728820335, 5132.40977287613, 5163.63854530183, 5169.87075323118, 5010.624203184776, 5145.6398052136365, 5157.896842063156, 5161.225816127573, 5166.560842328407, 5042.445331013586, 5069.873253168664, 5106.591472682177, 5114.744262786864, 5148.5881129509735, 5015.5736762375245, 5088.2622019807295, 5107.668001579886, 5116.565091967187, 5122.129238029525, 4590.449146102455, 4634.86095417138, 4637.304404339376, 4647.976760116211, 4653.813847446098, 6230.626162430269, 6269.843253918643, 6270.9372906271265, 6298.181236439279, 6323.494120470351, 5717.571182161355, 5796.1595568642315, 5832.929310094898, 5844.035734103868, 5860.7948721794955, 6667.3999340059145, 6673.833154171185, 6681.732730690766, 6686.4985126115735, 6715.529912906099, 5025.648204625678, 5026.924596131061, 5027.145385284474, 5034.320366750479, 5035.62232832539, 6953.548019378724, 6968.338007889242, 7014.859702805967, 7018.0, 7029.5430796998035, 5129.589632829365, 5132.871678208075, 5153.943441594464, 5160.767765450564, 5162.354705661793, 5190.403103643054, 5231.659942103751, 5241.187615919539, 5241.9213711794355, 5247.317848679647, 5780.132980053026, 5786.797462088806, 5816.243888294494, 5819.243498345188, 5832.154337621021, 6403.519736019724, 6419.422251997343, 6607.933920660828, 6753.15585551806, 6768.7292508299115, 1752.658231644817, 1761.5731257681064, 1779.6796576616334, 1785.2950432337086, 1788.1238193285458, 1061.3313612424124, 1225.9877401226051, 1228.961527509271, 1230.975380492385, 1252.5303011370736, 5652.886942261214, 5675.347335056439, 5691.146328050825, 5718.914216286759, 5796.101604251347, 6728.259891411941, 6782.830429239309, 6799.3200679931615, 6804.196887824731, 6842.281560436182, 5675.1487276907965, 5696.430356964353, 5744.626599271034, 5761.8559536011935, 5792.9420705793245, 3566.5185199998186, 3566.6433356664124, 3574.0, 3592.7305452091196, 3603.675669189733, 6456.677166141674, 6528.543001989815, 6537.803865884031, 6556.0, 6581.80254592363, 6888.483363747739, 6941.132358455193, 6964.965175174092, 6968.477364197604, 6976.965115174392, 6394.648294343856, 6436.356364363526, 6473.8705225896165, 6474.12599299098, 6493.448056915215, 6710.362515561014, 6716.664166791543, 6748.325167483213, 6811.182658081062, 6831.2144103428745, 6697.3637504437, 6746.595204287759, 6759.831004224934, 6802.01370801241, 6810.387065163961, 6513.576617519846, 6520.771772987922, 6576.309487503743, 6589.934100658932, 6645.368689974444, 5009.348784657971, 5036.0, 5041.899162016666, 5062.190049592047, 5063.772130254221, 6710.101625813086, 6712.838040414974, 6773.153355830521, 6826.146731658543, 6828.692708828214, 6538.044483087663, 6578.80263592093, 6635.0, 6650.869352210039, 6679.165104361955, 6585.5719378240265, 6586.70359833799, 6591.0, 6610.933890661224, 6657.234418041793, 6292.2314181759875, 6313.43179113882, 6466.0, 6472.673102013981, 6553.967230163818, 6321.7769743886765, 6509.902351464637, 6556.3443655634055, 6595.327144804567, 6599.208095028627, 6197.5351848610635, 6308.211473565804, 6312.779052733133, 6316.62100273985, 6349.5872768271765], "value": 5121.557142201393, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [895.1764376773372, 900.2572877376165, 919.7194855569048, 920.4017388697346, 921.7373048681123, 20010.49973750657, 20730.792692073075, 20735.585288294245, 20741.688874666874, 20745.377638670827, 18954.715679264787, 19068.141933612987, 19070.141843617035, 19177.616447671036, 19186.520336991547, 974.7758015656385, 995.1143482300758, 997.2620261006839, 999.6950929966357, 1002.7142264454632, 980.0787259975635, 982.8280050991084, 984.3306551544947, 985.3496692183161, 987.6395115782724, 1021.0963297481708, 1047.0994944347856, 1050.4117694091294, 1053.4522048534757, 1068.3483075324045, 1047.7537778622018, 1051.069803224152, 1051.7948999945013, 1053.0522529723235, 1057.751428414318, 728.4318231779221, 741.0, 748.1807420874134, 750.9399248060142, 755.0750330844726, 19939.601207975906, 20050.1979920803, 20100.497487562927, 20184.899075504676, 20281.0, 21439.67840482394, 21449.463763405885, 22061.448463788533, 22403.215887444025, 22405.43986400337, 17644.558886027822, 17889.552761180945, 17932.462026139237, 17936.013519256434, 17945.551361216076, 3607.765495242827, 3691.50164727761, 3694.7228957828, 3694.852205911787, 3694.9815250923575, 105809.0, 137590.0, 140641.29679351742, 142723.0, 143158.0, 13025.674358140932, 13371.799423008662, 13410.664733381744, 13444.596662100153, 13490.595282141552, 30252.0, 30378.696213038027, 30548.54177187344, 31390.529142062886, 31431.214219644233, 8618.698345558001, 8647.913520864711, 8675.566221689016, 8683.696070637498, 8696.782580435412, 3797.5883418228204, 3884.106655469258, 3898.3957486589625, 3912.4326972588815, 3923.725339226227, 5437.265969094201, 5461.781528738806, 5533.3083364579425, 5541.362743284493, 5557.138643510262, 1078.1213311151387, 1096.17786660005, 1103.2773533335678, 1107.06453047175, 1112.2825777373548, 679.4632240530025, 696.6864910790165, 706.6643344411364, 706.780897921646, 719.9928000720031, 11609.709757256136, 12286.0, 12763.617091487271, 13520.93239533796, 13543.322833858268, 690.730615060127, 699.2587856871733, 703.6938931564786, 705.6224919668056, 717.1716667249335, 5684.744186511617, 5689.34572524165, 5724.893706209939, 5757.510611598061, 5796.565257605696, 117260.0, 123072.38463807803, 123914.38042809728, 124111.0, 127274.36362818311, 105749.0, 107970.0, 110256.0, 113837.4308128454, 113841.43079284551, 91657.0, 93575.0, 95006.0, 96339.51830240803, 98309.50845245727, 10501.474926253504, 10525.315854469434, 10820.5130769114, 10838.349699018085, 10869.402182880018, 44361.33457998197, 44459.0, 45062.54937450714, 45328.77335613433, 45464.31803522884, 58599.70700146472, 59351.40648593631, 59847.0, 60143.39856601378, 60150.39849601448, 16208.594785130466, 16241.756373654642, 16348.836511634732, 16368.09975451314, 16423.0, 17602.207900644757, 17668.558286042953, 17771.555711107325, 17980.0, 18035.549111271797, 16554.668906622042, 16745.33018679288, 16759.91620041892, 16839.073850937824, 16936.0, 16671.24979375955, 16796.832031680016, 16822.831771682126, 16989.40537081196, 17087.401940932006, 15995.600109997344, 16265.593360165618, 16459.34162633481, 16524.339026438807, 16720.832791671928, 11460.369679667698, 11745.647630571097, 11886.881131188577, 12024.0, 12040.638780836589, 12468.0, 12548.121631485708, 12560.685982850136, 12681.682957926125, 12683.302418367079], "value": 9029.179897483602, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1413.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 109.0, 108.0, 108.0, 108.0, 107.0, 110.0, 109.0, 106.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 120.0, 115.0, 116.0, 140.0, 120.0, 116.0, 119.0, 121.0, 118.0], "value": 120.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 165.0, 164.0, 163.0, 165.0, 165.0, 165.0, 168.0, 166.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 73.0, 73.0, 74.0, 76.0, 74.0, 76.0, 76.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 80.0, 79.0, 78.0, 79.0, 79.0, 78.0, 80.0, 123.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 113.0], "value": 112.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 149.0, 148.0, 149.0, 151.0, 148.0, 148.0, 149.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 53.0, 51.0, 55.0, 53.0, 51.0, 51.0, 51.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [41.0, 37.0, 37.0, 37.0, 37.0, 37.0, 38.0, 38.0, 37.0, 38.0], "value": 37.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 76.0, 73.0, 73.0, 74.0, 74.0, 76.0, 73.0, 75.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 111.0, 112.0, 108.0, 119.0, 109.0, 115.0, 116.0, 114.0, 107.0], "value": 112.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 171.0, 159.0, 159.0, 170.0, 164.0, 160.0, 164.0, 165.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 63.0, 64.0, 65.0, 69.0, 65.0, 67.0, 67.0, 66.0], "value": 65.4, "unit": "score"}]}]} 05:45:58 INFO - Return code: 0 05:45:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:45:58 INFO - # TBPL SUCCESS # 05:45:58 INFO - Running post-action listener: _package_coverage_data 05:45:58 INFO - Running post-action listener: _resource_record_post_action 05:45:58 INFO - [mozharness: 2017-09-29 12:45:58.658492Z] Finished run-tests step (success) 05:45:58 INFO - Running post-run listener: _resource_record_post_run 05:45:58 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 349208576; Read time: 12; Write time: 274556 05:45:58 INFO - TinderboxPrint: CPU usage
13.1% 05:45:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:45:58 INFO - TinderboxPrint: I/O write bytes / time
349,208,576 / 274,556 05:45:58 INFO - TinderboxPrint: CPU idle
8,234.8 (86.9%) 05:45:58 INFO - TinderboxPrint: CPU user
1,193.9 (12.6%) 05:45:58 INFO - TinderboxPrint: Swap in / out
0 / 0 05:45:58 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13668352; Read time: 0; Write time: 1744 05:45:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:45:58 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 335540224; Read time: 0; Write time: 272812 05:45:59 INFO - Running post-run listener: _upload_blobber_files 05:45:59 INFO - Blob upload gear active. 05:45:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:45:59 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 05:45:59 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:45:59 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:45:59 INFO - (blobuploader) - INFO - Open directory for files ... 05:45:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:45:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:46:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:00 INFO - (blobuploader) - INFO - Done attempting. 05:46:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:46:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:46:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:04 INFO - (blobuploader) - INFO - Done attempting. 05:46:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:46:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:46:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:05 INFO - (blobuploader) - INFO - Done attempting. 05:46:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:46:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:46:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:07 INFO - (blobuploader) - INFO - Done attempting. 05:46:07 INFO - (blobuploader) - INFO - Iteration through files over. 05:46:07 INFO - Return code: 0 05:46:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:46:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:46:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1c0ece56dbc5f9230cd36682eea88a39c076970cfe460de4844ae81c4bf763d3b978b58d1b73823ab879b7eef70942d1e7c7a5618104f8fb568f86e9c9a5d1a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ce51920bd4e77cb0d557c000cf6ca57fc625532a783500779864d56be4bdade31efdf7ec01141c51dfe7e3d0f4432ac471b01518e640b311026523f7d8f8d223", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e302b6cb724cf0096a2e594f7891d3b1b5eb2a0998e0c8f4455f644701a7dd61358ca0cc458299819203f84f9698d3ca01502013610076e4a1663a38c5d4784", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd57225aa4f8e839fde3aab69ffc94c16e02a765db55958a04374040a83e126a2a0ae892a45f42def6b2065cfe347421407b1cac80af75006e728a8c7a577d34"} 05:46:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:46:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:46:07 INFO - Contents: 05:46:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1c0ece56dbc5f9230cd36682eea88a39c076970cfe460de4844ae81c4bf763d3b978b58d1b73823ab879b7eef70942d1e7c7a5618104f8fb568f86e9c9a5d1a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ce51920bd4e77cb0d557c000cf6ca57fc625532a783500779864d56be4bdade31efdf7ec01141c51dfe7e3d0f4432ac471b01518e640b311026523f7d8f8d223", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e302b6cb724cf0096a2e594f7891d3b1b5eb2a0998e0c8f4455f644701a7dd61358ca0cc458299819203f84f9698d3ca01502013610076e4a1663a38c5d4784", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd57225aa4f8e839fde3aab69ffc94c16e02a765db55958a04374040a83e126a2a0ae892a45f42def6b2065cfe347421407b1cac80af75006e728a8c7a577d34"} 05:46:07 INFO - Running post-run listener: copy_logs_to_upload_dir 05:46:07 INFO - Copying logs to upload dir... 05:46:07 INFO - mkdir: /builds/slave/test/build/upload/logs 05:46:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.310506 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-29 05:46:07.545020) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-29 05:46:07.549254) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1c0ece56dbc5f9230cd36682eea88a39c076970cfe460de4844ae81c4bf763d3b978b58d1b73823ab879b7eef70942d1e7c7a5618104f8fb568f86e9c9a5d1a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ce51920bd4e77cb0d557c000cf6ca57fc625532a783500779864d56be4bdade31efdf7ec01141c51dfe7e3d0f4432ac471b01518e640b311026523f7d8f8d223", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e302b6cb724cf0096a2e594f7891d3b1b5eb2a0998e0c8f4455f644701a7dd61358ca0cc458299819203f84f9698d3ca01502013610076e4a1663a38c5d4784", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd57225aa4f8e839fde3aab69ffc94c16e02a765db55958a04374040a83e126a2a0ae892a45f42def6b2065cfe347421407b1cac80af75006e728a8c7a577d34"} build_url:https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/IH85rkEeSoiGVybgA5cHLA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1c0ece56dbc5f9230cd36682eea88a39c076970cfe460de4844ae81c4bf763d3b978b58d1b73823ab879b7eef70942d1e7c7a5618104f8fb568f86e9c9a5d1a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ce51920bd4e77cb0d557c000cf6ca57fc625532a783500779864d56be4bdade31efdf7ec01141c51dfe7e3d0f4432ac471b01518e640b311026523f7d8f8d223", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e302b6cb724cf0096a2e594f7891d3b1b5eb2a0998e0c8f4455f644701a7dd61358ca0cc458299819203f84f9698d3ca01502013610076e4a1663a38c5d4784", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd57225aa4f8e839fde3aab69ffc94c16e02a765db55958a04374040a83e126a2a0ae892a45f42def6b2065cfe347421407b1cac80af75006e728a8c7a577d34"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-29 05:46:07.580985) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 05:46:07.581388) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506684274.366040-1158570808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004520 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 05:46:07.663737) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-29 05:46:07.664064) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-29 05:46:07.664378) ========= ========= Total master_lag: 1.39 =========