Skip to content

[Bug]: Investigate timestamp mismatches #388

@pregnor

Description

@pregnor

Bug Description

Investigate why timestamps are in different format and also have a drift in the middle.

recruiter-supervisor | 2026-02-27 14:34:08 [lungo.recruiter.supervisor.agent] [INFO] [<module>:52] Using LiteLLM Proxy: https://litellm.prod.outshift.ai/v1/
||
V
lungo-nats | [1] 2026/02/27 14:22:25.067696 [INF] Starting nats-server
||
V
recruiter-supervisor | 2026-02-27 14:34:26 [agntcy_app_sdk.factory] [INFO] [__init__:93] Tracing enabled for lungo.recruiter_supervisor via ioa_observe.sdk

# timestamp mismatch
pregnor _ 3158 : - @ - . /Users/pregnor/development/src/github.com/agntcy/coffeeAgntcy/coffeeAGNTCY/coffee_agents/lungo (main %|u=) [ microk8s / default ]
-> docker compose logs --follow

# Begin: 2026-02-27T15:40:58.493043000+01:00

WARN[0000] The "OPENAI_ENDPOINT" variable is not set. Defaulting to a blank string.
WARN[0000] The "OPENAI_MODEL_NAME" variable is not set. Defaulting to a blank string.
WARN[0000] The "OPENAI_API_KEY" variable is not set. Defaulting to a blank string.
recruiter  |    Building agent-recruiter @ file:///app
recruiter  |       Built agent-recruiter @ file:///app
recruiter  | Uninstalled 1 package in 10ms
recruiter  | Installed 1 package in 2ms
recruiter  | 2026-02-27 14:33:20.220 | INFO     | agent_recruiter.common.llm:configure_llm:28 - Using LiteLLM Proxy: https://litellm.prod.outshift.ai/v1/
recruiter  | 2026-02-27 14:33:20.221 | INFO     | agent_recruiter.common.llm:configure_llm:28 - Using LiteLLM Proxy: https://litellm.prod.outshift.ai/v1/
recruiter  | 2026-02-27 14:33:20.264 | INFO     | agent_recruiter.common.llm:configure_llm:28 - Using LiteLLM Proxy: https://litellm.prod.outshift.ai/v1/
recruiter  | 2026-02-27 14:33:20.275 | INFO     | __main__:<module>:91 - Starting RecruiterAgent server...
recruiter  | 2026-02-27 14:33:20.277 | INFO     | agent_recruiter.plugins.cache_config:load_cache_config:124 - Cache config loaded: mode=tool, tool_enabled=True
recruiter  | 2026-02-27 14:33:20.277 | INFO     | agent_recruiter.agent_registries.registry_search_agent:create_mcp_toolset:133 - MCP connection mode: binary
recruiter  | 2026-02-27 14:33:20.292 | INFO     | agent_recruiter.agent_registries.registry_search_agent:_check_dirctl_binary:47 - Found dirctl binary at: /usr/local/bin/dirctl
recruiter  | 2026-02-27 14:33:20.292 | INFO     | agent_recruiter.agent_registries.registry_search_agent:create_mcp_toolset:162 - Successfully created MCP toolset using binary mode (dirctl at /usr/local/bin/dirctl)
recruiter  | 2026-02-27 14:33:20.293 | INFO     | agent_recruiter.recruiter.recruiter:__init__:205 - Tool cache enabled (ttl=600s, max_entries=500, excluded_tools={'store_search_results'})
recruiter  | 2026-02-27 14:33:20.293 | INFO     | agent_recruiter.recruiter.recruiter:__init__:214 - Total plugins enabled: 1
recruiter  | 2026-02-27 14:33:20 [google_adk.google.adk.plugins.plugin_manager] [INFO] [register_plugin:104] Plugin 'tool_cache' registered.
recruiter  | 2026-02-27 14:33:20.299 | WARNING  | __main__:main:77 - MESSAGE_TRANSPORT or TRANSPORT_SERVER_ENDPOINT not set. Fallback to http only.
recruiter  | INFO:     Started server process [26]
recruiter  | INFO:     Waiting for application startup.
recruiter  | INFO:     Application startup complete.
recruiter  | INFO:     Uvicorn running on http://0.0.0.0:8881 (Press CTRL+C to quit)
lungo-ui   |
lungo-ui   | > frontend@0.0.0 build
lungo-ui   | > vite build
lungo-ui   |
lungo-ui   | vite v6.4.1 building for production...
lungo-ui              | transforming...
lungo-ui              | ✓ 2016 modules transformed.
lungo-ui              | rendering chunks...
lungo-ui              | computing gzip size...
lungo-ui              | dist/index.html                                 1.44 kB │ gzip:   0.80 kB
lungo-ui              | dist/assets/identity_badge-DTXxA1o1.svg         4.59 kB │ gzip:   3.41 kB
lungo-ui              | dist/assets/Coffee_Icon-DHy5u6V_.svg            5.69 kB │ gzip:   2.12 kB
lungo-ui              | dist/assets/supervisor-DDJ3JMgW.png            28.56 kB
lungo-ui              | dist/assets/coffeeAGNTCY_logo-BKPoL6Z8.svg     43.54 kB │ gzip:  29.58 kB
lungo-ui              | dist/assets/Grader-Agent-B6_cDdg4.png       1,453.38 kB
recruiter-supervisor  | warning: Ignoring existing virtual environment linked to non-existent Python interpreter: .venv/bin/python3 -> python
recruiter-supervisor  | Using CPython 3.13.11 interpreter at: /usr/local/bin/python3
recruiter-supervisor  | Removed virtual environment at: .venv
recruiter-supervisor  | Creating virtual environment at: .venv
recruiter-supervisor  |    Building lungo @ file:///app
recruiter-supervisor  |       Built lungo @ file:///app
recruiter-supervisor  | Installed 252 packages in 35.53s
recruiter-supervisor  | 2026-02-27 14:34:08 [agntcy_app_sdk.factory] [INFO] [__init__:93] Tracing enabled for lungo.recruiter_supervisor via ioa_observe.sdk
recruiter-supervisor  | 2026-02-27 14:34:08 [agntcy_app_sdk.transport.nats.transport] [INFO] [__init__:53] NatsTransport initialized with tracing enabled
recruiter-supervisor  | 2026-02-27 14:34:08 [lungo.recruiter.supervisor.agent] [INFO] [<module>:52] Using LiteLLM Proxy: https://litellm.prod.outshift.ai/v1/
recruiter-supervisor  | INFO:     Will watch for changes in these directories: ['/app']
recruiter-supervisor  | INFO:     Uvicorn running on http://0.0.0.0:8882 (Press CTRL+C to quit)
recruiter-supervisor  | INFO:     Started reloader process [28] using StatReload
recruiter-supervisor  |
lungo-nats            | [1] 2026/02/27 14:22:25.067696 [INF] Starting nats-server
lungo-nats            | [1] 2026/02/27 14:22:25.067729 [INF]   Version:  2.12.4
lungo-nats            | [1] 2026/02/27 14:22:25.067731 [INF]   Git:      [34894c1]
lungo-nats            | [1] 2026/02/27 14:22:25.067732 [INF]   Cluster:  my_cluster
lungo-nats            | [1] 2026/02/27 14:22:25.067733 [INF]   Name:     NBBLLSOROOJM6XGIOFKIG4BXJOFWAKR3AX2OGSKI3P7UKOJSOLLBSWL5
lungo-nats            | [1] 2026/02/27 14:22:25.067734 [INF]   ID:       NBBLLSOROOJM6XGIOFKIG4BXJOFWAKR3AX2OGSKI3P7UKOJSOLLBSWL5
lungo-nats            | [1] 2026/02/27 14:22:25.068185 [INF] Using configuration file: nats-server.conf (sha256:c7aaa34d64beb74e7c700fd3504b40b01deb1275f6567fb81ed3809ff03a5fe4)
lungo-nats            | [1] 2026/02/27 14:22:25.116990 [INF] Starting http monitor on 0.0.0.0:8222
lungo-nats            | [1] 2026/02/27 14:22:25.119284 [INF] Listening for client connections on 0.0.0.0:4222
lungo-nats            | [1] 2026/02/27 14:22:25.124563 [INF] Server is ready
lungo-nats            | [1] 2026/02/27 14:22:25.125059 [INF] Cluster name is my_cluster
lungo-nats            | [1] 2026/02/27 14:22:25.133938 [INF] Listening for route connections on 0.0.0.0:6222
recruiter-supervisor  | 2026-02-27 14:34:26 [agntcy_app_sdk.factory] [INFO] [__init__:93] Tracing enabled for lungo.recruiter_supervisor via ioa_observe.sdk
recruiter-supervisor  | 2026-02-27 14:34:26 [agntcy_app_sdk.transport.nats.transport] [INFO] [__init__:53] NatsTransport initialized with tracing enabled
recruiter-supervisor  | 2026-02-27 14:34:26 [lungo.recruiter.supervisor.agent] [INFO] [<module>:52] Using LiteLLM Proxy: https://litellm.prod.outshift.ai/v1/
lungo-ui              | dist/assets/index-DCcjHop8.css                 53.22 kB │ gzip:  12.83 kB

Expected Behavior

  1. Timestamps should be uniform in format.
  2. Timestamps should be monotonous/chronological in the log.

Affected Version

v1.0.0

Steps to Reproduce

  1. Set up lungo.
  2. start the recruiter.
  3. Observe the timestamp mismatch.

Checklist

Metadata

Metadata

Assignees

Labels

bugSomething isn't working

Type

Projects

Status

Todo

Milestone

No milestone

Relationships

None yet

Development

No branches or pull requests

Issue actions