Why are some data not being transferred to the agent? (0.4.1 of autogen)

[vincent-herlemont]

Hello, I am trying to run a test with agents (I am using version 0.4.1 of autogen)

Simple scenario with 4 agents:

• Router agent (takes user input and hands it off to agents with the tools)
• Day agent (returns the day, this agent calls a Python function)
• Time agent (returns the time, this agent calls a Python function)
• Formatter agent (formats the response for the user, based on what the agent

            AssistantAgent(
                name="router",
                system_message="""You are a router agent that analyzes user questions and directs them to the appropriate agent.
                
                Your role is to:
                1. Analyze the user's input
                2. Determine if they're asking about:
                   - handoff to time_agent for questions about time
                   - handoff to day_agent for questions about date
                """,
                model_client=model_client,
                handoffs=["day_agent", "time_agent"],
            )
            ...
            AssistantAgent(
                name="time_agent",
                system_message="""You are a time agent that provides the current time. Send the time information to the formatter agent.""",
                model_client=model_client,
                tools=[execute_git_current_time],
                handoffs=["formatter"],
            )
            ...
            AssistantAgent(
                name="day_agent",
                system_message="""You are a day agent that provides the current day. Send the day information to the formatter agent.""",
                model_client=model_client,
                tools=[get_current_day],
                handoffs=["formatter"],
            )
            ...
            AssistantAgent(
                name="formatter",
                system_message="""You are a formatter agent that formats time and day information. Send the formatted information to the user.""",
                model_client=model_client,
                handoffs=["user"],
            )
            ...
 # INIT
    router = RouterAgent(model_client=model_client)
    time_agent = TimeAgent(model_client=model_client)
    day_agent = DayAgent(model_client=model_client)
    formatter = FormatterAgent(model_client=model_client)

    # Create a team with all agents
    handoff_termination = HandoffTermination(target="user")
    max_messages_termination = MaxMessageTermination(max_messages=args.max_messages)
    termination = handoff_termination | max_messages_termination
    
    team = Swarm(
        participants=[router, time_agent, day_agent, formatter],
        termination_condition=termination,
    )
# RUN
result = await team.run(
   task=HandoffMessage(
        source="user",
        target=router.name,
        content=question or "What time and day is it?",  # Default question if empty
   )
)
...

full logs

2025-01-14 12:02:13 INFO     autogen_logger_agent_wrapper.tools.telemetry init_telemetry Initialized OpenTelemetry with OTLP exporter to http://localhost:4317
2025-01-14 12:02:13 WARNING  opentelemetry.trace _set_tracer_provider Overriding of current TracerProvider is not allowed
2025-01-14 12:02:13 INFO     autogen_logger_agent_wrapper.tools.telemetry init_telemetry Initialized OpenTelemetry with OTLP exporter to http://localhost:4317
2025-01-14 12:02:13 WARNING  opentelemetry.trace _set_tracer_provider Overriding of current TracerProvider is not allowed
2025-01-14 12:02:13 INFO     autogen_logger_agent_wrapper.tools.telemetry init_telemetry Initialized OpenTelemetry with OTLP exporter to http://localhost:4317
2025-01-14 12:02:13 WARNING  opentelemetry.trace _set_tracer_provider Overriding of current TracerProvider is not allowed
2025-01-14 12:02:13 INFO     autogen_logger_agent_wrapper.tools.telemetry init_telemetry Initialized OpenTelemetry with OTLP exporter to http://localhost:4317
2025-01-14 12:02:13 WARNING  opentelemetry.trace _set_tracer_provider Overriding of current TracerProvider is not allowed
2025-01-14 12:02:13 INFO     autogen_logger_agent_wrapper.tools.telemetry init_telemetry Initialized OpenTelemetry with OTLP exporter to http://localhost:4317
Ask about time or date (or press Enter for both, 'exit' to quit): What time is it     
2025-01-14 12:02:23 INFO     autogen_core.events send_message {"payload": "{\"messages\":[{\"source\":\"user\",\"models_usage\":null,\"target\":\"router\",\"content\":\"What time is it\",\"type\":\"HandoffMessage\"}]}", "sender": null, "receiver": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.DIRECT", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core send_message Sending message of type GroupChatStart to group_chat_manager: {'messages': [HandoffMessage(source='user', models_usage=None, target='router', content='What time is it', type='HandoffMessage')]}
2025-01-14 12:02:23 INFO     autogen_core _process_send Calling message handler for group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a with message type GroupChatStart sent by Unknown
2025-01-14 12:02:23 INFO     autogen_core.events _process_send {"payload": "{\"messages\":[{\"source\":\"user\",\"models_usage\":null,\"target\":\"router\",\"content\":\"What time is it\",\"type\":\"HandoffMessage\"}]}", "sender": null, "receiver": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.DIRECT", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core publish_message Publishing message of type GroupChatStart to all subscribers: {'messages': [HandoffMessage(source='user', models_usage=None, target='router', content='What time is it', type='HandoffMessage')]}
2025-01-14 12:02:23 INFO     autogen_core.events publish_message {"payload": "{\"messages\":[{\"source\":\"user\",\"models_usage\":null,\"target\":\"router\",\"content\":\"What time is it\",\"type\":\"HandoffMessage\"}]}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core publish_message Publishing message of type GroupChatStart to all subscribers: {'messages': [HandoffMessage(source='user', models_usage=None, target='router', content='What time is it', type='HandoffMessage')]}
2025-01-14 12:02:23 INFO     autogen_core.events publish_message {"payload": "{\"messages\":[{\"source\":\"user\",\"models_usage\":null,\"target\":\"router\",\"content\":\"What time is it\",\"type\":\"HandoffMessage\"}]}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "group_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatStart published by group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:23 INFO     autogen_core.events _process_publish {"payload": "{\"messages\":[{\"source\":\"user\",\"models_usage\":null,\"target\":\"router\",\"content\":\"What time is it\",\"type\":\"HandoffMessage\"}]}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_agentchat.events collect_output_messages source='user' models_usage=None target='router' content='What time is it' type='HandoffMessage'
2025-01-14 12:02:23 INFO     autogen_core _process_publish Calling message handler for router with message type GroupChatStart published by group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:23 INFO     autogen_core.events _process_publish {"payload": "{\"messages\":[{\"source\":\"user\",\"models_usage\":null,\"target\":\"router\",\"content\":\"What time is it\",\"type\":\"HandoffMessage\"}]}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core _process_publish Calling message handler for time_agent with message type GroupChatStart published by group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:23 INFO     autogen_core.events _process_publish {"payload": "{\"messages\":[{\"source\":\"user\",\"models_usage\":null,\"target\":\"router\",\"content\":\"What time is it\",\"type\":\"HandoffMessage\"}]}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core _process_publish Calling message handler for day_agent with message type GroupChatStart published by group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:23 INFO     autogen_core.events _process_publish {"payload": "{\"messages\":[{\"source\":\"user\",\"models_usage\":null,\"target\":\"router\",\"content\":\"What time is it\",\"type\":\"HandoffMessage\"}]}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core _process_publish Calling message handler for formatter with message type GroupChatStart published by group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:23 INFO     autogen_core.events _process_publish {"payload": "{\"messages\":[{\"source\":\"user\",\"models_usage\":null,\"target\":\"router\",\"content\":\"What time is it\",\"type\":\"HandoffMessage\"}]}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core publish_message Publishing message of type GroupChatRequestPublish to all subscribers: {}
2025-01-14 12:02:23 INFO     autogen_core.events publish_message {"payload": "{}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core.events _process_send {"payload": "Message could not be serialized", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.RESPOND", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_core _process_publish Calling message handler for router with message type GroupChatRequestPublish published by group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:23 INFO     autogen_core.events _process_publish {"payload": "{}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:23 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [router] System messages: [SystemMessage(content="You are a router agent that analyzes user questions and directs them to the appropriate agent.\n                \n                Your role is to:\n                1. Analyze the user's input\n                2. Determine if they're asking about:\n                   - handoff to time_agent for questions about time\n                   - handoff to day_agent for questions about date\n                   - handoff to time_agent and day_agent for questions about both time and date\n                \n                You **MUST** do a handoff to either time_agent, day_agent, or both.\n                ", type='SystemMessage')]
2025-01-14 12:02:23 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [router] New messages: [HandoffMessage(source='user', models_usage=None, target='router', content='What time is it', type='HandoffMessage')]
2025-01-14 12:02:23 INFO     autogen_core _process_response Resolving response with message type NoneType for recipient None from group_chat_manager: None
2025-01-14 12:02:23 INFO     autogen_core.events _process_response {"payload": "Message could not be serialized", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.RESPOND", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:24 INFO     httpx _send_single_request HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2025-01-14 12:02:24 INFO     autogen_core.events create {"type": "LLMCall", "messages": [{"content": "You are a router agent that analyzes user questions and directs them to the appropriate agent.\n                \n                Your role is to:\n                1. Analyze the user's input\n                2. Determine if they're asking about:\n                   - handoff to time_agent for questions about time\n                   - handoff to day_agent for questions about date\n                   - handoff to time_agent and day_agent for questions about both time and date\n                \n                You **MUST** do a handoff to either time_agent, day_agent, or both.\n                ", "role": "system"}, {"content": "What time is it", "role": "user", "name": "user"}], "response": {"id": "chatcmpl-ApYydBxek6lllui1cfoRQAAlP6FjV", "choices": [{"finish_reason": "tool_calls", "index": 0, "logprobs": null, "message": {"content": null, "refusal": null, "role": "assistant", "audio": null, "function_call": null, "tool_calls": [{"id": "call_ZvR1DFJbXo6oyn3nu13HaGMn", "function": {"arguments": "{}", "name": "transfer_to_time_agent"}, "type": "function"}]}}], "created": 1736852543, "model": "gpt-4o-2024-08-06", "object": "chat.completion", "service_tier": "default", "system_fingerprint": "fp_b7d65f1a5b", "usage": {"completion_tokens": 13, "prompt_tokens": 171, "total_tokens": 184, "completion_tokens_details": {"accepted_prediction_tokens": 0, "audio_tokens": 0, "reasoning_tokens": 0, "rejected_prediction_tokens": 0}, "prompt_tokens_details": {"audio_tokens": 0, "cached_tokens": 0}}}, "prompt_tokens": 171, "completion_tokens": 13, "agent_id": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a"}
2025-01-14 12:02:24 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [router] Response type: ToolCallRequestEvent, Response: source='router' models_usage=RequestUsage(prompt_tokens=171, completion_tokens=13) content=[FunctionCall(id='call_ZvR1DFJbXo6oyn3nu13HaGMn', arguments='{}', name='transfer_to_time_agent')] type='ToolCallRequestEvent'
2025-01-14 12:02:24 INFO     autogen_core publish_message Publishing message of type GroupChatMessage to all subscribers: {'message': ToolCallRequestEvent(source='router', models_usage=RequestUsage(prompt_tokens=171, completion_tokens=13), content=[FunctionCall(id='call_ZvR1DFJbXo6oyn3nu13HaGMn', arguments='{}', name='transfer_to_time_agent')], type='ToolCallRequestEvent')}
2025-01-14 12:02:24 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"router\",\"models_usage\":{\"prompt_tokens\":171,\"completion_tokens\":13},\"content\":[{\"id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\",\"arguments\":\"{}\",\"name\":\"transfer_to_time_agent\"}],\"type\":\"ToolCallRequestEvent\"}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatMessage published by router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:24 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"router\",\"models_usage\":{\"prompt_tokens\":171,\"completion_tokens\":13},\"content\":[{\"id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\",\"arguments\":\"{}\",\"name\":\"transfer_to_time_agent\"}],\"type\":\"ToolCallRequestEvent\"}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_agentchat.events collect_output_messages source='router' models_usage=RequestUsage(prompt_tokens=171, completion_tokens=13) content=[FunctionCall(id='call_ZvR1DFJbXo6oyn3nu13HaGMn', arguments='{}', name='transfer_to_time_agent')] type='ToolCallRequestEvent'
2025-01-14 12:02:24 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [router] Response type: ToolCallExecutionEvent, Response: source='router' models_usage=None content=[FunctionExecutionResult(content='What is the current time?', call_id='call_ZvR1DFJbXo6oyn3nu13HaGMn')] type='ToolCallExecutionEvent'
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'prompt_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'completion_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:24 INFO     autogen_core publish_message Publishing message of type GroupChatMessage to all subscribers: {'message': ToolCallExecutionEvent(source='router', models_usage=None, content=[FunctionExecutionResult(content='What is the current time?', call_id='call_ZvR1DFJbXo6oyn3nu13HaGMn')], type='ToolCallExecutionEvent')}
2025-01-14 12:02:24 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"router\",\"models_usage\":null,\"content\":[{\"content\":\"What is the current time?\",\"call_id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\"}],\"type\":\"ToolCallExecutionEvent\"}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [router] Response type: Response, Response: Response(chat_message=HandoffMessage(source='router', models_usage=None, target='time_agent', content='What is the current time?', type='HandoffMessage'), inner_messages=[ToolCallRequestEvent(source='router', models_usage=RequestUsage(prompt_tokens=171, completion_tokens=13), content=[FunctionCall(id='call_ZvR1DFJbXo6oyn3nu13HaGMn', arguments='{}', name='transfer_to_time_agent')], type='ToolCallRequestEvent'), ToolCallExecutionEvent(source='router', models_usage=None, content=[FunctionExecutionResult(content='What is the current time?', call_id='call_ZvR1DFJbXo6oyn3nu13HaGMn')], type='ToolCallExecutionEvent')])
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'source' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'prompt_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'completion_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'content.type' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'chat_message.prompt_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'chat_message.completion_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type FunctionCall in attribute 'inner_messages.0.content' value sequence. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or None
2025-01-14 12:02:24 WARNING  opentelemetry.attributes _clean_attribute Invalid type FunctionExecutionResult in attribute 'inner_messages.1.content' value sequence. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or None
2025-01-14 12:02:24 INFO     autogen_core publish_message Publishing message of type GroupChatMessage to all subscribers: {'message': HandoffMessage(source='router', models_usage=None, target='time_agent', content='What is the current time?', type='HandoffMessage')}
2025-01-14 12:02:24 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"router\",\"models_usage\":null,\"target\":\"time_agent\",\"content\":\"What is the current time?\",\"type\":\"HandoffMessage\"}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_core publish_message Publishing message of type GroupChatAgentResponse to all subscribers: {'agent_response': Response(chat_message=HandoffMessage(source='router', models_usage=None, target='time_agent', content='What is the current time?', type='HandoffMessage'), inner_messages=[ToolCallRequestEvent(source='router', models_usage=RequestUsage(prompt_tokens=171, completion_tokens=13), content=[FunctionCall(id='call_ZvR1DFJbXo6oyn3nu13HaGMn', arguments='{}', name='transfer_to_time_agent')], type='ToolCallRequestEvent'), ToolCallExecutionEvent(source='router', models_usage=None, content=[FunctionExecutionResult(content='What is the current time?', call_id='call_ZvR1DFJbXo6oyn3nu13HaGMn')], type='ToolCallExecutionEvent')])}
2025-01-14 12:02:24 INFO     autogen_core.events publish_message {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"router\",\"models_usage\":null,\"target\":\"time_agent\",\"content\":\"What is the current time?\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"router\",\"models_usage\":{\"prompt_tokens\":171,\"completion_tokens\":13},\"content\":[{\"id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\",\"arguments\":\"{}\",\"name\":\"transfer_to_time_agent\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"router\",\"models_usage\":null,\"content\":[{\"content\":\"What is the current time?\",\"call_id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "group_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatMessage published by router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:24 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"router\",\"models_usage\":null,\"content\":[{\"content\":\"What is the current time?\",\"call_id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\"}],\"type\":\"ToolCallExecutionEvent\"}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_agentchat.events collect_output_messages source='router' models_usage=None content=[FunctionExecutionResult(content='What is the current time?', call_id='call_ZvR1DFJbXo6oyn3nu13HaGMn')] type='ToolCallExecutionEvent'
2025-01-14 12:02:24 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatMessage published by router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:24 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"router\",\"models_usage\":null,\"target\":\"time_agent\",\"content\":\"What is the current time?\",\"type\":\"HandoffMessage\"}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_agentchat.events collect_output_messages source='router' models_usage=None target='time_agent' content='What is the current time?' type='HandoffMessage'
2025-01-14 12:02:24 INFO     autogen_core _process_publish Calling message handler for time_agent with message type GroupChatAgentResponse published by router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:24 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"router\",\"models_usage\":null,\"target\":\"time_agent\",\"content\":\"What is the current time?\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"router\",\"models_usage\":{\"prompt_tokens\":171,\"completion_tokens\":13},\"content\":[{\"id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\",\"arguments\":\"{}\",\"name\":\"transfer_to_time_agent\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"router\",\"models_usage\":null,\"content\":[{\"content\":\"What is the current time?\",\"call_id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_core _process_publish Calling message handler for day_agent with message type GroupChatAgentResponse published by router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:24 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"router\",\"models_usage\":null,\"target\":\"time_agent\",\"content\":\"What is the current time?\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"router\",\"models_usage\":{\"prompt_tokens\":171,\"completion_tokens\":13},\"content\":[{\"id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\",\"arguments\":\"{}\",\"name\":\"transfer_to_time_agent\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"router\",\"models_usage\":null,\"content\":[{\"content\":\"What is the current time?\",\"call_id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_core _process_publish Calling message handler for formatter with message type GroupChatAgentResponse published by router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:24 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"router\",\"models_usage\":null,\"target\":\"time_agent\",\"content\":\"What is the current time?\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"router\",\"models_usage\":{\"prompt_tokens\":171,\"completion_tokens\":13},\"content\":[{\"id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\",\"arguments\":\"{}\",\"name\":\"transfer_to_time_agent\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"router\",\"models_usage\":null,\"content\":[{\"content\":\"What is the current time?\",\"call_id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_core _process_publish Calling message handler for group_chat_manager with message type GroupChatAgentResponse published by router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:24 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"router\",\"models_usage\":null,\"target\":\"time_agent\",\"content\":\"What is the current time?\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"router\",\"models_usage\":{\"prompt_tokens\":171,\"completion_tokens\":13},\"content\":[{\"id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\",\"arguments\":\"{}\",\"name\":\"transfer_to_time_agent\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"router\",\"models_usage\":null,\"content\":[{\"content\":\"What is the current time?\",\"call_id\":\"call_ZvR1DFJbXo6oyn3nu13HaGMn\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "router/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_core publish_message Publishing message of type GroupChatRequestPublish to all subscribers: {}
2025-01-14 12:02:24 INFO     autogen_core.events publish_message {"payload": "{}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_core _process_publish Calling message handler for time_agent with message type GroupChatRequestPublish published by group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:24 INFO     autogen_core.events _process_publish {"payload": "{}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:24 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [time_agent] System messages: [SystemMessage(content='You are a time agent that provides the current time. Send the time information to the formatter agent.', type='SystemMessage')]
2025-01-14 12:02:24 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [time_agent] New messages: [HandoffMessage(source='user', models_usage=None, target='router', content='What time is it', type='HandoffMessage'), HandoffMessage(source='router', models_usage=None, target='time_agent', content='What is the current time?', type='HandoffMessage')]
2025-01-14 12:02:26 INFO     httpx _send_single_request HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2025-01-14 12:02:26 INFO     autogen_core.events create {"type": "LLMCall", "messages": [{"content": "You are a time agent that provides the current time. Send the time information to the formatter agent.", "role": "system"}, {"content": "What time is it", "role": "user", "name": "user"}, {"content": "What is the current time?", "role": "user", "name": "router"}], "response": {"id": "chatcmpl-ApYyegjZxBW6Vo9i1nVGsaphim5rz", "choices": [{"finish_reason": "tool_calls", "index": 0, "logprobs": null, "message": {"content": null, "refusal": null, "role": "assistant", "audio": null, "function_call": null, "tool_calls": [{"id": "call_0MaKYPBLVpAGaUAymyS5eLIp", "function": {"arguments": "{}", "name": "get_current_time"}, "type": "function"}, {"id": "call_1g2yDe51rbeeHz55Fx8e043W", "function": {"arguments": "{}", "name": "transfer_to_formatter"}, "type": "function"}]}}], "created": 1736852544, "model": "gpt-4o-2024-08-06", "object": "chat.completion", "service_tier": "default", "system_fingerprint": "fp_b7d65f1a5b", "usage": {"completion_tokens": 42, "prompt_tokens": 88, "total_tokens": 130, "completion_tokens_details": {"accepted_prediction_tokens": 0, "audio_tokens": 0, "reasoning_tokens": 0, "rejected_prediction_tokens": 0}, "prompt_tokens_details": {"audio_tokens": 0, "cached_tokens": 0}}}, "prompt_tokens": 88, "completion_tokens": 42, "agent_id": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a"}
2025-01-14 12:02:26 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [time_agent] Response type: ToolCallRequestEvent, Response: source='time_agent' models_usage=RequestUsage(prompt_tokens=88, completion_tokens=42) content=[FunctionCall(id='call_0MaKYPBLVpAGaUAymyS5eLIp', arguments='{}', name='get_current_time'), FunctionCall(id='call_1g2yDe51rbeeHz55Fx8e043W', arguments='{}', name='transfer_to_formatter')] type='ToolCallRequestEvent'
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatMessage to all subscribers: {'message': ToolCallRequestEvent(source='time_agent', models_usage=RequestUsage(prompt_tokens=88, completion_tokens=42), content=[FunctionCall(id='call_0MaKYPBLVpAGaUAymyS5eLIp', arguments='{}', name='get_current_time'), FunctionCall(id='call_1g2yDe51rbeeHz55Fx8e043W', arguments='{}', name='transfer_to_formatter')], type='ToolCallRequestEvent')}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"time_agent\",\"models_usage\":{\"prompt_tokens\":88,\"completion_tokens\":42},\"content\":[{\"id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\",\"arguments\":\"{}\",\"name\":\"get_current_time\"},{\"id\":\"call_1g2yDe51rbeeHz55Fx8e043W\",\"arguments\":\"{}\",\"name\":\"transfer_to_formatter\"}],\"type\":\"ToolCallRequestEvent\"}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatMessage published by time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"time_agent\",\"models_usage\":{\"prompt_tokens\":88,\"completion_tokens\":42},\"content\":[{\"id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\",\"arguments\":\"{}\",\"name\":\"get_current_time\"},{\"id\":\"call_1g2yDe51rbeeHz55Fx8e043W\",\"arguments\":\"{}\",\"name\":\"transfer_to_formatter\"}],\"type\":\"ToolCallRequestEvent\"}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_agentchat.events collect_output_messages source='time_agent' models_usage=RequestUsage(prompt_tokens=88, completion_tokens=42) content=[FunctionCall(id='call_0MaKYPBLVpAGaUAymyS5eLIp', arguments='{}', name='get_current_time'), FunctionCall(id='call_1g2yDe51rbeeHz55Fx8e043W', arguments='{}', name='transfer_to_formatter')] type='ToolCallRequestEvent'
2025-01-14 12:02:26 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [time_agent] Response type: ToolCallExecutionEvent, Response: source='time_agent' models_usage=None content=[FunctionExecutionResult(content='The current time is 12:02 PM.', call_id='call_0MaKYPBLVpAGaUAymyS5eLIp'), FunctionExecutionResult(content='Transferred to formatter, adopting the role of formatter immediately.', call_id='call_1g2yDe51rbeeHz55Fx8e043W')] type='ToolCallExecutionEvent'
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'prompt_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'completion_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatMessage to all subscribers: {'message': ToolCallExecutionEvent(source='time_agent', models_usage=None, content=[FunctionExecutionResult(content='The current time is 12:02 PM.', call_id='call_0MaKYPBLVpAGaUAymyS5eLIp'), FunctionExecutionResult(content='Transferred to formatter, adopting the role of formatter immediately.', call_id='call_1g2yDe51rbeeHz55Fx8e043W')], type='ToolCallExecutionEvent')}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"time_agent\",\"models_usage\":null,\"content\":[{\"content\":\"The current time is 12:02 PM.\",\"call_id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\"},{\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"call_id\":\"call_1g2yDe51rbeeHz55Fx8e043W\"}],\"type\":\"ToolCallExecutionEvent\"}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [time_agent] Response type: Response, Response: Response(chat_message=HandoffMessage(source='time_agent', models_usage=None, target='formatter', content='Transferred to formatter, adopting the role of formatter immediately.', type='HandoffMessage'), inner_messages=[ToolCallRequestEvent(source='time_agent', models_usage=RequestUsage(prompt_tokens=88, completion_tokens=42), content=[FunctionCall(id='call_0MaKYPBLVpAGaUAymyS5eLIp', arguments='{}', name='get_current_time'), FunctionCall(id='call_1g2yDe51rbeeHz55Fx8e043W', arguments='{}', name='transfer_to_formatter')], type='ToolCallRequestEvent'), ToolCallExecutionEvent(source='time_agent', models_usage=None, content=[FunctionExecutionResult(content='The current time is 12:02 PM.', call_id='call_0MaKYPBLVpAGaUAymyS5eLIp'), FunctionExecutionResult(content='Transferred to formatter, adopting the role of formatter immediately.', call_id='call_1g2yDe51rbeeHz55Fx8e043W')], type='ToolCallExecutionEvent')])
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'source' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'prompt_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'completion_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'content.type' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'chat_message.prompt_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'chat_message.completion_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type FunctionCall in attribute 'inner_messages.0.content' value sequence. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or None
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type FunctionExecutionResult in attribute 'inner_messages.1.content' value sequence. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or None
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatMessage to all subscribers: {'message': HandoffMessage(source='time_agent', models_usage=None, target='formatter', content='Transferred to formatter, adopting the role of formatter immediately.', type='HandoffMessage')}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"time_agent\",\"models_usage\":null,\"target\":\"formatter\",\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"type\":\"HandoffMessage\"}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatAgentResponse to all subscribers: {'agent_response': Response(chat_message=HandoffMessage(source='time_agent', models_usage=None, target='formatter', content='Transferred to formatter, adopting the role of formatter immediately.', type='HandoffMessage'), inner_messages=[ToolCallRequestEvent(source='time_agent', models_usage=RequestUsage(prompt_tokens=88, completion_tokens=42), content=[FunctionCall(id='call_0MaKYPBLVpAGaUAymyS5eLIp', arguments='{}', name='get_current_time'), FunctionCall(id='call_1g2yDe51rbeeHz55Fx8e043W', arguments='{}', name='transfer_to_formatter')], type='ToolCallRequestEvent'), ToolCallExecutionEvent(source='time_agent', models_usage=None, content=[FunctionExecutionResult(content='The current time is 12:02 PM.', call_id='call_0MaKYPBLVpAGaUAymyS5eLIp'), FunctionExecutionResult(content='Transferred to formatter, adopting the role of formatter immediately.', call_id='call_1g2yDe51rbeeHz55Fx8e043W')], type='ToolCallExecutionEvent')])}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"time_agent\",\"models_usage\":null,\"target\":\"formatter\",\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"time_agent\",\"models_usage\":{\"prompt_tokens\":88,\"completion_tokens\":42},\"content\":[{\"id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\",\"arguments\":\"{}\",\"name\":\"get_current_time\"},{\"id\":\"call_1g2yDe51rbeeHz55Fx8e043W\",\"arguments\":\"{}\",\"name\":\"transfer_to_formatter\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"time_agent\",\"models_usage\":null,\"content\":[{\"content\":\"The current time is 12:02 PM.\",\"call_id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\"},{\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"call_id\":\"call_1g2yDe51rbeeHz55Fx8e043W\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "group_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatMessage published by time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"time_agent\",\"models_usage\":null,\"content\":[{\"content\":\"The current time is 12:02 PM.\",\"call_id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\"},{\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"call_id\":\"call_1g2yDe51rbeeHz55Fx8e043W\"}],\"type\":\"ToolCallExecutionEvent\"}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_agentchat.events collect_output_messages source='time_agent' models_usage=None content=[FunctionExecutionResult(content='The current time is 12:02 PM.', call_id='call_0MaKYPBLVpAGaUAymyS5eLIp'), FunctionExecutionResult(content='Transferred to formatter, adopting the role of formatter immediately.', call_id='call_1g2yDe51rbeeHz55Fx8e043W')] type='ToolCallExecutionEvent'
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatMessage published by time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"time_agent\",\"models_usage\":null,\"target\":\"formatter\",\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"type\":\"HandoffMessage\"}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_agentchat.events collect_output_messages source='time_agent' models_usage=None target='formatter' content='Transferred to formatter, adopting the role of formatter immediately.' type='HandoffMessage'
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for router with message type GroupChatAgentResponse published by time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"time_agent\",\"models_usage\":null,\"target\":\"formatter\",\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"time_agent\",\"models_usage\":{\"prompt_tokens\":88,\"completion_tokens\":42},\"content\":[{\"id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\",\"arguments\":\"{}\",\"name\":\"get_current_time\"},{\"id\":\"call_1g2yDe51rbeeHz55Fx8e043W\",\"arguments\":\"{}\",\"name\":\"transfer_to_formatter\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"time_agent\",\"models_usage\":null,\"content\":[{\"content\":\"The current time is 12:02 PM.\",\"call_id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\"},{\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"call_id\":\"call_1g2yDe51rbeeHz55Fx8e043W\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for day_agent with message type GroupChatAgentResponse published by time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"time_agent\",\"models_usage\":null,\"target\":\"formatter\",\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"time_agent\",\"models_usage\":{\"prompt_tokens\":88,\"completion_tokens\":42},\"content\":[{\"id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\",\"arguments\":\"{}\",\"name\":\"get_current_time\"},{\"id\":\"call_1g2yDe51rbeeHz55Fx8e043W\",\"arguments\":\"{}\",\"name\":\"transfer_to_formatter\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"time_agent\",\"models_usage\":null,\"content\":[{\"content\":\"The current time is 12:02 PM.\",\"call_id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\"},{\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"call_id\":\"call_1g2yDe51rbeeHz55Fx8e043W\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for formatter with message type GroupChatAgentResponse published by time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"time_agent\",\"models_usage\":null,\"target\":\"formatter\",\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"time_agent\",\"models_usage\":{\"prompt_tokens\":88,\"completion_tokens\":42},\"content\":[{\"id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\",\"arguments\":\"{}\",\"name\":\"get_current_time\"},{\"id\":\"call_1g2yDe51rbeeHz55Fx8e043W\",\"arguments\":\"{}\",\"name\":\"transfer_to_formatter\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"time_agent\",\"models_usage\":null,\"content\":[{\"content\":\"The current time is 12:02 PM.\",\"call_id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\"},{\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"call_id\":\"call_1g2yDe51rbeeHz55Fx8e043W\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for group_chat_manager with message type GroupChatAgentResponse published by time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"time_agent\",\"models_usage\":null,\"target\":\"formatter\",\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"time_agent\",\"models_usage\":{\"prompt_tokens\":88,\"completion_tokens\":42},\"content\":[{\"id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\",\"arguments\":\"{}\",\"name\":\"get_current_time\"},{\"id\":\"call_1g2yDe51rbeeHz55Fx8e043W\",\"arguments\":\"{}\",\"name\":\"transfer_to_formatter\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"time_agent\",\"models_usage\":null,\"content\":[{\"content\":\"The current time is 12:02 PM.\",\"call_id\":\"call_0MaKYPBLVpAGaUAymyS5eLIp\"},{\"content\":\"Transferred to formatter, adopting the role of formatter immediately.\",\"call_id\":\"call_1g2yDe51rbeeHz55Fx8e043W\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "time_agent/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatRequestPublish to all subscribers: {}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for formatter with message type GroupChatRequestPublish published by group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [formatter] System messages: [SystemMessage(content='You are a formatter agent that formats time and day information.\n                \n                You **MUST** do a handoff to the user when done.\n                ', type='SystemMessage')]
2025-01-14 12:02:26 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [formatter] New messages: [HandoffMessage(source='user', models_usage=None, target='router', content='What time is it', type='HandoffMessage'), HandoffMessage(source='router', models_usage=None, target='time_agent', content='What is the current time?', type='HandoffMessage'), HandoffMessage(source='time_agent', models_usage=None, target='formatter', content='Transferred to formatter, adopting the role of formatter immediately.', type='HandoffMessage')]
2025-01-14 12:02:26 INFO     httpx _send_single_request HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2025-01-14 12:02:26 INFO     autogen_core.events create {"type": "LLMCall", "messages": [{"content": "You are a formatter agent that formats time and day information.\n                \n                You **MUST** do a handoff to the user when done.\n                ", "role": "system"}, {"content": "What time is it", "role": "user", "name": "user"}, {"content": "What is the current time?", "role": "user", "name": "router"}, {"content": "Transferred to formatter, adopting the role of formatter immediately.", "role": "user", "name": "time_agent"}], "response": {"id": "chatcmpl-ApYygGmlfYV3jmKhgw4RQAr4wgwOz", "choices": [{"finish_reason": "tool_calls", "index": 0, "logprobs": null, "message": {"content": null, "refusal": null, "role": "assistant", "audio": null, "function_call": null, "tool_calls": [{"id": "call_gLyrWo94WEPd2W0Jnqcr0wao", "function": {"arguments": "{}", "name": "transfer_to_user"}, "type": "function"}]}}], "created": 1736852546, "model": "gpt-4o-2024-08-06", "object": "chat.completion", "service_tier": "default", "system_fingerprint": "fp_4691090a87", "usage": {"completion_tokens": 12, "prompt_tokens": 102, "total_tokens": 114, "completion_tokens_details": {"accepted_prediction_tokens": 0, "audio_tokens": 0, "reasoning_tokens": 0, "rejected_prediction_tokens": 0}, "prompt_tokens_details": {"audio_tokens": 0, "cached_tokens": 0}}}, "prompt_tokens": 102, "completion_tokens": 12, "agent_id": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a"}
2025-01-14 12:02:26 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [formatter] Response type: ToolCallRequestEvent, Response: source='formatter' models_usage=RequestUsage(prompt_tokens=102, completion_tokens=12) content=[FunctionCall(id='call_gLyrWo94WEPd2W0Jnqcr0wao', arguments='{}', name='transfer_to_user')] type='ToolCallRequestEvent'
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatMessage to all subscribers: {'message': ToolCallRequestEvent(source='formatter', models_usage=RequestUsage(prompt_tokens=102, completion_tokens=12), content=[FunctionCall(id='call_gLyrWo94WEPd2W0Jnqcr0wao', arguments='{}', name='transfer_to_user')], type='ToolCallRequestEvent')}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"formatter\",\"models_usage\":{\"prompt_tokens\":102,\"completion_tokens\":12},\"content\":[{\"id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\",\"arguments\":\"{}\",\"name\":\"transfer_to_user\"}],\"type\":\"ToolCallRequestEvent\"}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatMessage published by formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"formatter\",\"models_usage\":{\"prompt_tokens\":102,\"completion_tokens\":12},\"content\":[{\"id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\",\"arguments\":\"{}\",\"name\":\"transfer_to_user\"}],\"type\":\"ToolCallRequestEvent\"}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_agentchat.events collect_output_messages source='formatter' models_usage=RequestUsage(prompt_tokens=102, completion_tokens=12) content=[FunctionCall(id='call_gLyrWo94WEPd2W0Jnqcr0wao', arguments='{}', name='transfer_to_user')] type='ToolCallRequestEvent'
2025-01-14 12:02:26 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [formatter] Response type: ToolCallExecutionEvent, Response: source='formatter' models_usage=None content=[FunctionExecutionResult(content='Transferred to user, adopting the role of user immediately.', call_id='call_gLyrWo94WEPd2W0Jnqcr0wao')] type='ToolCallExecutionEvent'
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'prompt_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'completion_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatMessage to all subscribers: {'message': ToolCallExecutionEvent(source='formatter', models_usage=None, content=[FunctionExecutionResult(content='Transferred to user, adopting the role of user immediately.', call_id='call_gLyrWo94WEPd2W0Jnqcr0wao')], type='ToolCallExecutionEvent')}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"formatter\",\"models_usage\":null,\"content\":[{\"content\":\"Transferred to user, adopting the role of user immediately.\",\"call_id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\"}],\"type\":\"ToolCallExecutionEvent\"}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_logger_agent_wrapper.tools.logger_agent_wrapper on_messages_stream [formatter] Response type: Response, Response: Response(chat_message=HandoffMessage(source='formatter', models_usage=None, target='user', content='Transferred to user, adopting the role of user immediately.', type='HandoffMessage'), inner_messages=[ToolCallRequestEvent(source='formatter', models_usage=RequestUsage(prompt_tokens=102, completion_tokens=12), content=[FunctionCall(id='call_gLyrWo94WEPd2W0Jnqcr0wao', arguments='{}', name='transfer_to_user')], type='ToolCallRequestEvent'), ToolCallExecutionEvent(source='formatter', models_usage=None, content=[FunctionExecutionResult(content='Transferred to user, adopting the role of user immediately.', call_id='call_gLyrWo94WEPd2W0Jnqcr0wao')], type='ToolCallExecutionEvent')])
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'source' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'prompt_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'completion_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'content.type' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'chat_message.prompt_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type NoneType for attribute 'chat_message.completion_tokens' value. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or a sequence of those types
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type FunctionCall in attribute 'inner_messages.0.content' value sequence. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or None
2025-01-14 12:02:26 WARNING  opentelemetry.attributes _clean_attribute Invalid type FunctionExecutionResult in attribute 'inner_messages.1.content' value sequence. Expected one of ['bool', 'str', 'bytes', 'int', 'float'] or None
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatMessage to all subscribers: {'message': HandoffMessage(source='formatter', models_usage=None, target='user', content='Transferred to user, adopting the role of user immediately.', type='HandoffMessage')}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"formatter\",\"models_usage\":null,\"target\":\"user\",\"content\":\"Transferred to user, adopting the role of user immediately.\",\"type\":\"HandoffMessage\"}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatAgentResponse to all subscribers: {'agent_response': Response(chat_message=HandoffMessage(source='formatter', models_usage=None, target='user', content='Transferred to user, adopting the role of user immediately.', type='HandoffMessage'), inner_messages=[ToolCallRequestEvent(source='formatter', models_usage=RequestUsage(prompt_tokens=102, completion_tokens=12), content=[FunctionCall(id='call_gLyrWo94WEPd2W0Jnqcr0wao', arguments='{}', name='transfer_to_user')], type='ToolCallRequestEvent'), ToolCallExecutionEvent(source='formatter', models_usage=None, content=[FunctionExecutionResult(content='Transferred to user, adopting the role of user immediately.', call_id='call_gLyrWo94WEPd2W0Jnqcr0wao')], type='ToolCallExecutionEvent')])}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"formatter\",\"models_usage\":null,\"target\":\"user\",\"content\":\"Transferred to user, adopting the role of user immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"formatter\",\"models_usage\":{\"prompt_tokens\":102,\"completion_tokens\":12},\"content\":[{\"id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\",\"arguments\":\"{}\",\"name\":\"transfer_to_user\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"formatter\",\"models_usage\":null,\"content\":[{\"content\":\"Transferred to user, adopting the role of user immediately.\",\"call_id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "group_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatMessage published by formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"formatter\",\"models_usage\":null,\"content\":[{\"content\":\"Transferred to user, adopting the role of user immediately.\",\"call_id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\"}],\"type\":\"ToolCallExecutionEvent\"}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_agentchat.events collect_output_messages source='formatter' models_usage=None content=[FunctionExecutionResult(content='Transferred to user, adopting the role of user immediately.', call_id='call_gLyrWo94WEPd2W0Jnqcr0wao')] type='ToolCallExecutionEvent'
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatMessage published by formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"formatter\",\"models_usage\":null,\"target\":\"user\",\"content\":\"Transferred to user, adopting the role of user immediately.\",\"type\":\"HandoffMessage\"}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_agentchat.events collect_output_messages source='formatter' models_usage=None target='user' content='Transferred to user, adopting the role of user immediately.' type='HandoffMessage'
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for router with message type GroupChatAgentResponse published by formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"formatter\",\"models_usage\":null,\"target\":\"user\",\"content\":\"Transferred to user, adopting the role of user immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"formatter\",\"models_usage\":{\"prompt_tokens\":102,\"completion_tokens\":12},\"content\":[{\"id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\",\"arguments\":\"{}\",\"name\":\"transfer_to_user\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"formatter\",\"models_usage\":null,\"content\":[{\"content\":\"Transferred to user, adopting the role of user immediately.\",\"call_id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for time_agent with message type GroupChatAgentResponse published by formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"formatter\",\"models_usage\":null,\"target\":\"user\",\"content\":\"Transferred to user, adopting the role of user immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"formatter\",\"models_usage\":{\"prompt_tokens\":102,\"completion_tokens\":12},\"content\":[{\"id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\",\"arguments\":\"{}\",\"name\":\"transfer_to_user\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"formatter\",\"models_usage\":null,\"content\":[{\"content\":\"Transferred to user, adopting the role of user immediately.\",\"call_id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for day_agent with message type GroupChatAgentResponse published by formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"formatter\",\"models_usage\":null,\"target\":\"user\",\"content\":\"Transferred to user, adopting the role of user immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"formatter\",\"models_usage\":{\"prompt_tokens\":102,\"completion_tokens\":12},\"content\":[{\"id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\",\"arguments\":\"{}\",\"name\":\"transfer_to_user\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"formatter\",\"models_usage\":null,\"content\":[{\"content\":\"Transferred to user, adopting the role of user immediately.\",\"call_id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for group_chat_manager with message type GroupChatAgentResponse published by formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"agent_response\":{\"chat_message\":{\"source\":\"formatter\",\"models_usage\":null,\"target\":\"user\",\"content\":\"Transferred to user, adopting the role of user immediately.\",\"type\":\"HandoffMessage\"},\"inner_messages\":[{\"source\":\"formatter\",\"models_usage\":{\"prompt_tokens\":102,\"completion_tokens\":12},\"content\":[{\"id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\",\"arguments\":\"{}\",\"name\":\"transfer_to_user\"}],\"type\":\"ToolCallRequestEvent\"},{\"source\":\"formatter\",\"models_usage\":null,\"content\":[{\"content\":\"Transferred to user, adopting the role of user immediately.\",\"call_id\":\"call_gLyrWo94WEPd2W0Jnqcr0wao\"}],\"type\":\"ToolCallExecutionEvent\"}]}}", "sender": "formatter/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core publish_message Publishing message of type GroupChatTermination to all subscribers: {'message': StopMessage(source='HandoffTermination', models_usage=None, content='Handoff to user from formatter detected.', type='StopMessage')}
2025-01-14 12:02:26 INFO     autogen_core.events publish_message {"payload": "{\"message\":{\"source\":\"HandoffTermination\",\"models_usage\":null,\"content\":\"Handoff to user from formatter detected.\",\"type\":\"StopMessage\"}}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": "output_topic/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.SEND", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_core _process_publish Calling message handler for collect_output_messages with message type GroupChatTermination published by group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a
2025-01-14 12:02:26 INFO     autogen_core.events _process_publish {"payload": "{\"message\":{\"source\":\"HandoffTermination\",\"models_usage\":null,\"content\":\"Handoff to user from formatter detected.\",\"type\":\"StopMessage\"}}", "sender": "group_chat_manager/4fe4de02-eaaa-4bfb-8827-d629acf7de0a", "receiver": null, "kind": "MessageKind.PUBLISH", "delivery_stage": "DeliveryStage.DELIVER", "type": "Message"}
2025-01-14 12:02:26 INFO     autogen_agentchat.events collect_output_messages source='HandoffTermination' models_usage=None content='Handoff to user from formatter detected.' type='StopMessage'
2025-01-14 12:02:26 INFO     wapi_talky process_question Result: TaskResult(messages=[HandoffMessage(source='user', models_usage=None, target='router', content='What time is it', type='HandoffMessage'), ToolCallRequestEvent(source='router', models_usage=RequestUsage(prompt_tokens=171, completion_tokens=13), content=[FunctionCall(id='call_ZvR1DFJbXo6oyn3nu13HaGMn', arguments='{}', name='transfer_to_time_agent')], type='ToolCallRequestEvent'), ToolCallExecutionEvent(source='router', models_usage=None, content=[FunctionExecutionResult(content='What is the current time?', call_id='call_ZvR1DFJbXo6oyn3nu13HaGMn')], type='ToolCallExecutionEvent'), HandoffMessage(source='router', models_usage=None, target='time_agent', content='What is the current time?', type='HandoffMessage'), ToolCallRequestEvent(source='time_agent', models_usage=RequestUsage(prompt_tokens=88, completion_tokens=42), content=[FunctionCall(id='call_0MaKYPBLVpAGaUAymyS5eLIp', arguments='{}', name='get_current_time'), FunctionCall(id='call_1g2yDe51rbeeHz55Fx8e043W', arguments='{}', name='transfer_to_formatter')], type='ToolCallRequestEvent'), ToolCallExecutionEvent(source='time_agent', models_usage=None, content=[FunctionExecutionResult(content='The current time is 12:02 PM.', call_id='call_0MaKYPBLVpAGaUAymyS5eLIp'), FunctionExecutionResult(content='Transferred to formatter, adopting the role of formatter immediately.', call_id='call_1g2yDe51rbeeHz55Fx8e043W')], type='ToolCallExecutionEvent'), HandoffMessage(source='time_agent', models_usage=None, target='formatter', content='Transferred to formatter, adopting the role of formatter immediately.', type='HandoffMessage'), ToolCallRequestEvent(source='formatter', models_usage=RequestUsage(prompt_tokens=102, completion_tokens=12), content=[FunctionCall(id='call_gLyrWo94WEPd2W0Jnqcr0wao', arguments='{}', name='transfer_to_user')], type='ToolCallRequestEvent'), ToolCallExecutionEvent(source='formatter', models_usage=None, content=[FunctionExecutionResult(content='Transferred to user, adopting the role of user immediately.', call_id='call_gLyrWo94WEPd2W0Jnqcr0wao')], type='ToolCallExecutionEvent'), HandoffMessage(source='formatter', models_usage=None, target='user', content='Transferred to user, adopting the role of user immediately.', type='HandoffMessage')], stop_reason='Handoff to user from formatter detected.')

1. Time agent: content.result
   • "The current time is 12:02 PM."
   • "Transferred to formatter, adopting the role of formatter immediately."
2. Formatter agent: messages:
   • "What time is it?"
   • "What is the current time?" (router agent reformats the response)
   • "Transferred to formatter, adopting the role of formatter immediately." (time agent response)

We can see that "The current time is 10:34 AM." does not appear in the messages from the formatter agents. Only the last content "Transferred to formatter, adopting the role of formatter immediately." appears.

As a result, the formatter returns an empty response.

Do you have any suggestions?

[ekzhu]

Thanks @vincent-herlemont, this is a bug.
Created issue: #5045