mission-control-v2/backend/tests/test_waechter.py

"""Tests für die reinen Auswertungen des Wächters — mit echten Zeilen von der Box (23.09.2026)."""

from services import waechter

PROJEKTE_SYNC_LOG = [
    "2026-09-23 21:02:44 === Sync-Lauf (14 Repos gemeldet) ===",
    "2026-09-23 21:02:45   = NerdQuiz-next: aktuell (a1b2c3d)",
    "2026-09-23 21:02:46   ! TTT2-Toolbox_ClaudeEdition: Pull fehlgeschlagen — fatal: no such ref was fetched",
    "2026-09-23 21:02:46 === Ende (Fehler: 1) ===",
]

ERRORS_LOG = [
    ('2026-09-23 07:02:41,584 WARNING [cron_195e479e8e30_20260923_070017] agent.tool_executor: '
     'Tool write_file returned error (0.00s): {"error": "Refusing to overwrite /tmp/news-text.md: '
     '/tmp/news-text.md exists"}'),
    ('2026-09-23 07:02:58,516 WARNING [cron_195e479e8e30_20260923_070017] agent.tool_executor: '
     'Tool write_file returned error (0.00s): {"error": "Refusing to overwrite /tmp/news-text.md"}'),
    "2026-09-23 07:03:39,596 WARNING gateway.mirror: Mirror: no session found for telegram:1 thread=None",
    ('2026-09-22 07:01:10,000 WARNING [cron_195e479e8e30_20260922_070017] agent.tool_executor: '
     'Tool web_extract returned error (0.10s): {"error": "timeout"}'),
]


def test_fehlerzeile_nimmt_die_letzte_fehlerzeile_ohne_zeitstempel():
    zeile = waechter.waehle_fehlerzeile(PROJEKTE_SYNC_LOG)
    assert zeile.startswith("! TTT2-Toolbox_ClaudeEdition: Pull fehlgeschlagen")
    assert "2026-09-23" not in zeile


def test_fehlerzeile_uebergeht_systemd_rahmenzeilen():
    """Echtes Journal vom 23.09.: systemd schreibt NACH der Skriptzeile noch „Failed to start …“."""
    zeilen = PROJEKTE_SYNC_LOG + [
        "projekte-sync.service: Main process exited, code=exited, status=1/FAILURE",
        "projekte-sync.service: Failed with result 'exit-code'.",
        "Failed to start projekte-sync.service - Projekte-Sync — haelt ~/projekte mit Gitea deckungsgleich.",
    ]
    assert waechter.waehle_fehlerzeile(zeilen).startswith("! TTT2-Toolbox_ClaudeEdition")


def test_fehlerzeile_leer_wenn_nichts_nach_fehler_aussieht():
    assert waechter.waehle_fehlerzeile(["alles gut", "=== Ende (Fehler: 0) ==="]) == ""


def test_werkzeugfehler_zaehlt_nur_den_lauf_des_tages():
    anzahl, beispiel = waechter.werkzeugfehler_im_lauf(
        ERRORS_LOG, "195e479e8e30", "2026-09-23T07:00:17.675474+02:00")
    assert anzahl == 2
    assert beispiel.startswith("write_file: Refusing to overwrite /tmp/news-text.md")


def test_gelesene_kurze_seite_ist_kein_werkzeugfehler():
    """Echte Zeilen vom 24.09.: Hermes loggt ein erfolgreiches web_extract als Fehler, weil sein
    leeres "error"-Feld bei kurzen Seiten in den ersten 500 Zeichen landet."""
    zeilen = [
        ('2026-09-24 07:01:03,291 WARNING [cron_195e479e8e30_20260924_070027] agent.tool_executor: '
         'Tool web_extract returned error (0.56s): {'),
        '  "results": [',
        "    {",
        '      "url": "https://example.org",',
        ('2026-09-24 07:01:38,983 WARNING [cron_195e479e8e30_20260924_070027] agent.tool_executor: '
         'Tool web_extract returned error (0.26s): {"success": false, "error": "DuckDuckGo (ddgs) is a '
         'search-only backend and cannot extract URL content."}'),
    ]
    anzahl, beispiel = waechter.werkzeugfehler_im_lauf(zeilen, "195e479e8e30", "2026-09-24T07:00:27+02:00")
    assert anzahl == 1 and beispiel.startswith("web_extract: DuckDuckGo (ddgs) is a search-only backend")


def test_werkzeugfehler_null_fuer_anderen_job_oder_kaputtes_datum():
    assert waechter.werkzeugfehler_im_lauf(ERRORS_LOG, "ca7d4ed207c2", "2026-09-23T08:00:00+02:00") == (0, "")
    assert waechter.werkzeugfehler_im_lauf(ERRORS_LOG, "195e479e8e30", "kein-datum") == (0, "")


def test_befunde_werden_erst_nach_fail_after_takten_zum_hinweis(monkeypatch, tmp_path):
    """Flankenlogik: ein kurzer Aussetzer (1 Takt) erzeugt keinen Hinweis, ein dauerhafter schon.
    Rote Hinweise gehen an Telegram, Erledigtes verschwindet und landet im Verlauf."""
    monkeypatch.setattr(waechter, "STORE_PATH", tmp_path / "waechter.json")
    monkeypatch.setattr(waechter, "_stand", {"hinweise": {}, "kandidaten": {}, "verlauf": [], "auto": {}, "stand": 0.0})
    monkeypatch.setattr(waechter, "_update_laeuft", lambda: False)
    meldungen: list[str] = []
    monkeypatch.setattr(waechter, "_telegram", lambda betreff, text: meldungen.append(betreff))
    defekt = [True]

    def pruefung():
        if not defekt[0]:
            return []
        return [waechter.Befund(id="kern:engine", stufe="rot", titel="Der Motor antwortet nicht",
                                text="…", quelle="engine")]

    monkeypatch.setattr(waechter, "PRUEFUNGEN", (pruefung,))
    monkeypatch.setattr(waechter, "FAIL_AFTER", 2)

    waechter.takt()
    assert waechter._stand["hinweise"] == {}          # erster Takt: nur Kandidat
    waechter.takt()
    assert "kern:engine" in waechter._stand["hinweise"]
    assert meldungen == ["[Box-Problem]"]

    defekt[0] = False
    waechter.takt()
    assert waechter._stand["hinweise"] == {}
    assert meldungen == ["[Box-Problem]", "[Box wieder ok]"]
    arten = [v["art"] for v in waechter._stand["verlauf"]]
    assert arten == ["neu", "erledigt"]

    stand = waechter.lese_stand()
    assert stand["aktiv"] is True and stand["hinweise"] == []


def test_schlafende_dienste_sind_kein_befund(monkeypatch):
    """24.09.2026: Spracherkennung und Konsole sind bewusst abgeschaltet (disable --now) — kein Alarm.
    Ein gestoppter, aber noch eingetragener Dienst bleibt dagegen ein Hinweis."""
    zustaende = {
        "voice-service": {"LoadState": "loaded", "ActiveState": "inactive", "UnitFileState": "disabled"},
        "lucy-stimme": {"LoadState": "loaded", "ActiveState": "inactive", "UnitFileState": "enabled"},
    }
    monkeypatch.setattr(waechter, "_systemctl_show",
                        lambda name, system: zustaende.get(name, {"LoadState": "loaded", "ActiveState": "active"}))
    ids = {b.id for b in waechter.pruefe_dienste()}
    assert "dienst:voice-service" not in ids
    assert "dienst:lucy-stimme" in ids
    assert waechter.schlaeft(zustaende["voice-service"]) is True
    assert waechter.schlaeft({"ActiveState": "failed", "UnitFileState": "disabled"}) is False


def test_abgestuerzte_pruefung_wird_ein_gelber_befund(monkeypatch, tmp_path):
    """24.09.2026: Eine Prüfung, die abstürzt, darf nicht still verschwinden (Cockpit blieb grün)."""
    def kaputt():
        raise ValueError("jobs.json hat ein neues Format")

    kaputt.__name__ = "pruefe_hermes_jobs"
    monkeypatch.setattr(waechter, "PRUEFUNGEN", (kaputt,))
    monkeypatch.setattr(waechter, "STORE_PATH", tmp_path / "mc2-waechter.json")
    monkeypatch.setattr(waechter, "_update_laeuft", lambda: False)
    monkeypatch.setattr(waechter, "_telegram", lambda betreff, text: None)
    monkeypatch.setattr(waechter, "FAIL_AFTER", 1)
    monkeypatch.setattr(waechter, "_stand", {"hinweise": {}, "kandidaten": {}, "verlauf": [], "stand": None,
                                             "update_laeuft": False})
    waechter.takt()
    hinweise = waechter._stand["hinweise"]
    assert "pruefung:pruefe_hermes_jobs" in hinweise
    assert "neues Format" in hinweise["pruefung:pruefe_hermes_jobs"]["text"]


def test_ausblenden_gilt_bis_zum_naechsten_lauf(monkeypatch, tmp_path):
    """24.09.2026: Ein behobener Werkzeugfehler (z. B. web_extract im News-Job) soll sich
    ausblenden lassen — kommt aber wieder, sobald ein neuer Lauf erneut Fehler hat."""
    from datetime import datetime, timedelta

    monkeypatch.setattr(waechter, "QUITTIERT_PATH", tmp_path / "mc2-quittiert.json")
    heute = (datetime.now() - timedelta(hours=2)).isoformat(timespec="seconds")
    lauf = {"started_at": heute}
    monkeypatch.setattr(waechter, "_hermes_jobs", lambda: [{"id": "news", "name": "Daily News Report",
                                                             "enabled": True, "last_status": "ok"}])
    monkeypatch.setattr(waechter, "_letzter_lauf", lambda jid: lauf)
    monkeypatch.setattr(waechter, "_errors_log_ende", lambda: [])
    monkeypatch.setattr(waechter, "werkzeugfehler_im_lauf", lambda zeilen, jid, start: (2, "web_extract: Fehler"))

    befunde = waechter.pruefe_hermes_jobs()
    assert [b.id for b in befunde] == ["job:news:werkzeug"]
    assert any(a["id"] == "ausblenden" and a["lauf"] == heute for a in befunde[0].aktionen)

    waechter.quittieren("job:news:werkzeug", heute)
    assert waechter.pruefe_hermes_jobs() == []

    lauf["started_at"] = (datetime.now() - timedelta(minutes=5)).isoformat(timespec="seconds")
    assert [b.id for b in waechter.pruefe_hermes_jobs()] == ["job:news:werkzeug"]