Skip to content

Add more debug log info for query #3509

New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Merged
merged 2 commits into from
May 4, 2025
Merged

Add more debug log info for query #3509

merged 2 commits into from
May 4, 2025

Conversation

Jack251970
Copy link
Contributor

@Jack251970 Jack251970 commented May 4, 2025

When querying with e, we will get more debug info.

22:03:03.2597+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Start query with text: e
22:03:03.2597+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Start query with ActionKeyword <> and RawQuery <e>
22:03:03.2597+08:00 - DEBUG - MainViewModel.RemoveOldQueryResults - Remove old results
22:03:03.2762+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Valid <6> plugins: <Calculator> <Explorer> <Program> <System Commands> <URL> <Web Searches>
22:03:03.2762+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Wait for querying plugin <Calculator>
22:03:03.2762+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Wait for querying plugin <Explorer>
22:03:03.2762+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Wait for querying plugin <Program>
22:03:03.2904+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Wait for querying plugin <System Commands>
22:03:03.2904+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Wait for querying plugin <Web Searches>
22:03:03.2904+08:00 - DEBUG - PluginManager.QueryForPluginAsync - Cost for Calculator <4ms>
22:03:03.3041+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Update results for plugin <Calculator>
22:03:03.3383+08:00 - DEBUG - Http.GetStreamAsync - Url <https://www.google.com/complete/search?output=chrome&q=e>
22:03:03.3383+08:00 - DEBUG - PluginManager.QueryForPluginAsync - Cost for System Commands <47ms>
22:03:03.3383+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Update results for plugin <System Commands>
22:03:03.4933+08:00 - DEBUG - PluginManager.QueryForPluginAsync - Cost for Program <198ms>
22:03:03.4933+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Update results for plugin <Program>
22:03:03.7887+08:00 - DEBUG - PluginManager.QueryForPluginAsync - Cost for Web Searches <493ms>
22:03:03.7887+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Update results for plugin <Web Searches>
22:03:03.7887+08:00 - DEBUG - PluginManager.QueryForPluginAsync - Cost for Explorer <508ms>
22:03:03.8051+08:00 - DEBUG - MainViewModel.QueryResultsAsync - Update results for plugin <Explorer>

This can help us resolve #3508, etc.

@prlabeler prlabeler bot added the bug Something isn't working label May 4, 2025
@Jack251970 Jack251970 requested a review from Copilot May 4, 2025 14:04
@Jack251970 Jack251970 added this to the 1.20.0 milestone May 4, 2025
Copy link
Contributor

@Copilot Copilot AI left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Pull Request Overview

This PR adds additional debug logging in the query processing workflow to help trace query handling and plugin operations.

  • Added debug logs to mark the start of a query and to log query details, including text, action keyword, and raw query.
  • Included logging for valid plugins, plugin querying wait times, and updating the query results.
  • Added logging to indicate when old query results are cleared.
Comments suppressed due to low confidence (1)

Flow.Launcher/ViewModel/MainViewModel.cs:1262

  • [nitpick] While the use of angle brackets around plugin names helps emphasize the values, ensure that a consistent and clear log format is maintained throughout all log messages for better readability.
var validPluginNames = plugins.Select(x => $"<{x.Metadata.Name}>");

This comment has been minimized.

Copy link

gitstream-cm bot commented May 4, 2025

🥷 Code experts: onesounds

Jack251970, onesounds have most 👩‍💻 activity in the files.
Jack251970 has most 🧠 knowledge in the files.

See details

Flow.Launcher/ViewModel/MainViewModel.cs

Activity based on git-commit:

Jack251970 onesounds
MAY 155 additions & 74 deletions
APR 35 additions & 28 deletions
MAR 695 additions & 628 deletions 293 additions & 201 deletions
FEB 63 additions & 21 deletions 23 additions & 25 deletions
JAN 17 additions & 21 deletions
DEC 59 additions & 63 deletions

Knowledge based on git-blame:
Jack251970: 35%

To learn more about /:\ gitStream - Visit our Docs

Copy link

gitstream-cm bot commented May 4, 2025

Be a legend 🏆 by adding a before and after screenshot of the changes you made, especially if they are around UI/UX.

Copy link
Contributor

coderabbitai bot commented May 4, 2025

📝 Walkthrough

Walkthrough

This change adds comprehensive debug logging to the QueryResultsAsync method and related query handling sections within MainViewModel.cs. The new log statements provide step-by-step tracing of query processing, including the initiation of queries, plugin matching, waiting states, and result updates. No alterations were made to the control flow, error handling, or any public interfaces; the updates are strictly for improved runtime diagnostics and do not affect application behavior.

Changes

File(s) Change Summary
Flow.Launcher/ViewModel/MainViewModel.cs Added detailed debug logging throughout the QueryResultsAsync method and related query logic.

Sequence Diagram(s)

sequenceDiagram
    participant User
    participant MainViewModel
    participant App.API
    participant Plugins

    User->>MainViewModel: Initiate query
    MainViewModel->>App.API: LogDebug (start query)
    alt Query is null
        MainViewModel->>App.API: LogDebug (clear results)
        MainViewModel->>MainViewModel: Clear results
    else
        MainViewModel->>App.API: LogDebug (start with action keyword)
        MainViewModel->>Plugins: Identify valid plugins
        MainViewModel->>App.API: LogDebug (valid plugin count and names)
        loop For each plugin
            MainViewModel->>App.API: LogDebug (waiting for plugin)
            MainViewModel->>Plugins: Query plugin
            MainViewModel->>App.API: LogDebug (update results)
        end
        MainViewModel->>App.API: LogDebug (remove old results if needed)
    end
Loading

Possibly related PRs

Suggested labels

Dev branch only

Suggested reviewers

  • jjw24
  • onesounds

Poem

In the meadow of logs, the queries hop,
Each debug trace a carrot atop.
Plugins gather, results in tow,
With every log, the details grow.
No changes to flow—just insight anew,
A rabbit’s delight in the things we now view! 🐇✨

✨ Finishing Touches
  • 📝 Generate Docstrings

Thanks for using CodeRabbit! It's free for OSS, and your support helps us grow. If you like it, consider giving us a shout-out.

❤️ Share
🪧 Tips

Chat

There are 3 ways to chat with CodeRabbit:

  • Review comments: Directly reply to a review comment made by CodeRabbit. Example:
    • I pushed a fix in commit <commit_id>, please review it.
    • Generate unit testing code for this file.
    • Open a follow-up GitHub issue for this discussion.
  • Files and specific lines of code (under the "Files changed" tab): Tag @coderabbitai in a new review comment at the desired location with your query. Examples:
    • @coderabbitai generate unit testing code for this file.
    • @coderabbitai modularize this function.
  • PR comments: Tag @coderabbitai in a new PR comment to ask questions about the PR branch. For the best results, please provide a very specific query, as very limited context is provided in this mode. Examples:
    • @coderabbitai gather interesting stats about this repository and render them as a table. Additionally, render a pie chart showing the language distribution in the codebase.
    • @coderabbitai read src/utils.ts and generate unit testing code.
    • @coderabbitai read the files in the src/scheduler package and generate a class diagram using mermaid and a README in the markdown format.
    • @coderabbitai help me debug CodeRabbit configuration file.

Support

Need help? Create a ticket on our support page for assistance with any issues or questions.

Note: Be mindful of the bot's finite context window. It's strongly recommended to break down tasks such as reading entire modules into smaller chunks. For a focused discussion, use review comments to chat about specific files and their changes, instead of using the PR comments.

CodeRabbit Commands (Invoked using PR comments)

  • @coderabbitai pause to pause the reviews on a PR.
  • @coderabbitai resume to resume the paused reviews.
  • @coderabbitai review to trigger an incremental review. This is useful when automatic reviews are disabled for the repository.
  • @coderabbitai full review to do a full review from scratch and review all the files again.
  • @coderabbitai summary to regenerate the summary of the PR.
  • @coderabbitai generate docstrings to generate docstrings for this PR.
  • @coderabbitai generate sequence diagram to generate a sequence diagram of the changes in this PR.
  • @coderabbitai resolve resolve all the CodeRabbit review comments.
  • @coderabbitai configuration to show the current CodeRabbit configuration for the repository.
  • @coderabbitai help to get help.

Other keywords and placeholders

  • Add @coderabbitai ignore anywhere in the PR description to prevent this PR from being reviewed.
  • Add @coderabbitai summary to generate the high-level summary at a specific location in the PR description.
  • Add @coderabbitai anywhere in the PR title to generate the title automatically.

CodeRabbit Configuration File (.coderabbit.yaml)

  • You can programmatically configure CodeRabbit by adding a .coderabbit.yaml file to the root of your repository.
  • Please see the configuration documentation for more information.
  • If your editor has YAML language server enabled, you can add the path at the top of this file to enable auto-completion and validation: # yaml-language-server: $schema=https://coderabbit.ai/integrations/schema.v2.json

Documentation and Community

  • Visit our Documentation for detailed information on how to use CodeRabbit.
  • Join our Discord Community to get help, request features, and share feedback.
  • Follow us on X/Twitter for updates and announcements.

@Jack251970 Jack251970 requested a review from jjw24 May 4, 2025 14:06
Copy link

github-actions bot commented May 4, 2025

@check-spelling-bot Report

🔴 Please review

See the 📂 files view, the 📜action log, or 📝 job summary for details.

❌ Errors and Warnings Count
❌ forbidden-pattern 22
⚠️ non-alpha-in-dictionary 19

See ❌ Event descriptions for more information.

If the flagged items are 🤯 false positives

If items relate to a ...

  • binary file (or some other file you wouldn't want to check at all).

    Please add a file path to the excludes.txt file matching the containing file.

    File paths are Perl 5 Regular Expressions - you can test yours before committing to verify it will match your files.

    ^ refers to the file's path from the root of the repository, so ^README\.md$ would exclude README.md (on whichever branch you're using).

  • well-formed pattern.

    If you can write a pattern that would match it,
    try adding it to the patterns.txt file.

    Patterns are Perl 5 Regular Expressions - you can test yours before committing to verify it will match your lines.

    Note that patterns can't match multiline strings.

Copy link
Contributor

@coderabbitai coderabbitai bot left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Actionable comments posted: 0

♻️ Duplicate comments (2)
Flow.Launcher/ViewModel/MainViewModel.cs (2)

1333-1333: Assess log noise in per-plugin wait messages.

Repeated logs like Wait for querying plugin <{plugin.Metadata.Name}> in tight loops can generate excessive output under heavy query loads. Consider toggling this level to Trace or gating it behind a verbose flag.
[past_review_comments]


1373-1373: Evaluate necessity of per-plugin update logs.

App.API.LogDebug(ClassName, $"Update results for plugin <{plugin.Metadata.Name}>") may flood logs when many plugins return results. You might merge this with the earlier plugin list log or demote the level if fine-grained tracing isn’t always required.
[past_review_comments]

🧹 Nitpick comments (5)
Flow.Launcher/ViewModel/MainViewModel.cs (5)

1218-1218: Guard debug log interpolation to avoid unnecessary overhead.

The call App.API.LogDebug(ClassName, $"Start query with text: <{QueryText}>") unconditionally performs string interpolation even when debug level logging is disabled. To reduce runtime overhead in high-frequency code paths, consider wrapping this in a log-level check or using a lazy/log-friendly API that defers message formatting.


1244-1244: Consolidate null-query logs for clarity.

Logging "Clear query results" here duplicates part of the query lifecycle and may be streamlined. Consider combining this message with the earlier null-check log or enhancing it to include context (e.g., reason for null), so you avoid multiple disjointed debug entries.


1240-1240: Avoid redundant start-query logs.

You now log both the raw query text and then immediately log the ActionKeyword and RawQuery. These could be merged into a single log statement:

App.API.LogDebug(ClassName, $"Begin query: ActionKeyword={query.ActionKeyword}, RawQuery={query.RawQuery}");

This reduces log volume and simplifies trace reading.


1262-1263: Refine plugin count log formatting.

The message Valid <{plugins.Count}> plugins: {string.Join(" ", validPluginNames)} includes angle brackets around the count, which may confuse readers. A clearer format might be:

App.API.LogDebug(ClassName,
    $"Valid plugins ({plugins.Count}): {string.Join(", ", plugins.Select(p => p.Metadata.Name))}");

This improves readability of debug outputs.


1464-1464: Enhance old-results removal logging with context.

The log Remove old results doesn’t indicate which ActionKeyword changed. To aid troubleshooting, include both the previous and new keywords, e.g.:

-App.API.LogDebug(ClassName, "Remove old results");
+App.API.LogDebug(ClassName,
+  $"Removed old results; ActionKeyword changed from '{_lastQuery?.ActionKeyword}' to '{query.ActionKeyword}'");

This additional context improves traceability.

📜 Review details

Configuration used: CodeRabbit UI
Review profile: CHILL
Plan: Pro

📥 Commits

Reviewing files that changed from the base of the PR and between a2a8c50 and 1973163.

📒 Files selected for processing (1)
  • Flow.Launcher/ViewModel/MainViewModel.cs (6 hunks)
⏰ Context from checks skipped due to timeout of 90000ms (4)
  • GitHub Check: gitStream workflow automation
  • GitHub Check: gitStream.cm
  • GitHub Check: gitStream.cm
  • GitHub Check: build

@Jack251970 Jack251970 enabled auto-merge May 4, 2025 14:08
@Jack251970 Jack251970 merged commit e4ecea0 into dev May 4, 2025
14 checks passed
@Jack251970 Jack251970 deleted the mainvm_more_log branch May 4, 2025 22:46
@jjw24 jjw24 removed the 1 min review label May 4, 2025
@coderabbitai coderabbitai bot mentioned this pull request May 5, 2025
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
Development

Successfully merging this pull request may close these issues.

Dev branch - Programs result not longer returning for global query
2 participants