~/edgex-foundry/edgex-compose-3.1.1-mqtt/compose-builder$ docker compose logs -f device-mqtt
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:44.643897477Z app=device-mqtt source=variables.go:462 msg="Variables override of 'Startup Duration' by environment variable: EDGEX_STARTUP_DURATION=120"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:44.644077544Z app=device-mqtt source=config.go:718 msg="Using Configuration provider (consul) from: http://edgex-core-consul:8500 with base path of edgex/v3/core-common-config-bootstrapper/all-services"
edgex-device-mqtt  | level=WARN ts=2024-09-03T12:09:49.701802549Z app=device-mqtt source=config.go:1056 msg="did not get boolean from config provider for edgex/v3/core-common-config-bootstrapper/IsCommonConfigReady: strconv.ParseBool: parsing \"\": invalid syntax"
edgex-device-mqtt  | level=WARN ts=2024-09-03T12:09:49.701923253Z app=device-mqtt source=config.go:1064 msg="waiting for Common Configuration to be available from config provider"
edgex-device-mqtt  | level=WARN ts=2024-09-03T12:09:50.70310004Z app=device-mqtt source=config.go:1056 msg="did not get boolean from config provider for edgex/v3/core-common-config-bootstrapper/IsCommonConfigReady: strconv.ParseBool: parsing \"\": invalid syntax"
edgex-device-mqtt  | level=WARN ts=2024-09-03T12:09:50.703151127Z app=device-mqtt source=config.go:1064 msg="waiting for Common Configuration to be available from config provider"
edgex-device-mqtt  | level=WARN ts=2024-09-03T12:09:51.704094698Z app=device-mqtt source=config.go:1056 msg="did not get boolean from config provider for edgex/v3/core-common-config-bootstrapper/IsCommonConfigReady: strconv.ParseBool: parsing \"\": invalid syntax"
edgex-device-mqtt  | level=WARN ts=2024-09-03T12:09:51.704157879Z app=device-mqtt source=config.go:1064 msg="waiting for Common Configuration to be available from config provider"
edgex-device-mqtt  | level=WARN ts=2024-09-03T12:09:52.705815778Z app=device-mqtt source=config.go:1064 msg="waiting for Common Configuration to be available from config provider"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.71294468Z app=device-mqtt source=config.go:442 msg="loading the common configuration for service type device-service"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.713183174Z app=device-mqtt source=config.go:718 msg="Using Configuration provider (consul) from: http://edgex-core-consul:8500 with base path of edgex/v3/core-common-config-bootstrapper/device-services"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.71809474Z app=device-mqtt source=config.go:173 msg="Common configuration loaded from the Configuration Provider. No overrides applied"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.718131025Z app=device-mqtt source=config.go:718 msg="Using Configuration provider (consul) from: http://edgex-core-consul:8500 with base path of edgex/v3/device-mqtt"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.719070559Z app=device-mqtt source=config.go:731 msg="Loading configuration file from res/configuration.yaml"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.719388668Z app=device-mqtt source=variables.go:462 msg="Variables override of 'MQTTBrokerInfo/Host' by environment variable: MQTTBROKERINFO_HOST=edgex-mqtt-broker"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.719416918Z app=device-mqtt source=variables.go:462 msg="Variables override of 'MQTTBrokerInfo/Port' by environment variable: MQTTBROKERINFO_PORT=1883"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.719422736Z app=device-mqtt source=variables.go:462 msg="Variables override of 'Service/Host' by environment variable: SERVICE_HOST=edgex-device-mqtt"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.719426921Z app=device-mqtt source=config.go:251 msg="Private configuration loaded from file with 3 overrides applied"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.80137053Z app=device-mqtt source=config.go:262 msg="Private configuration has been pushed to into Configuration Provider with overrides applied"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.801440843Z app=device-mqtt source=config.go:269 msg="listening for private config changes"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.801452454Z app=device-mqtt source=config.go:271 msg="listening for all services common config changes"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.801462272Z app=device-mqtt source=config.go:278 msg="listening for device service common config changes"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.801541147Z app=device-mqtt source=registry.go:61 msg="Using Registry access token of length 0"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.801604394Z app=device-mqtt source=registry.go:89 msg="Using Registry (consul) from http://edgex-core-consul:8500"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.865010868Z app=device-mqtt source=httpserver.go:149 msg="Web server starting (edgex-device-mqtt:59982)"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.866549453Z app=device-mqtt source=messaging.go:104 msg="Connected to mqtt Message Bus @ tcp://edgex-mqtt-broker:1883 with AuthMode='none'"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.866624066Z app=device-mqtt source=command.go:36 msg="Subscribing to command requests on topic: edgex/device/command/request/device-mqtt/#"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.866635696Z app=device-mqtt source=command.go:40 msg="Responses to command requests will be published on topic: edgex/response/device-mqtt/<requestId>"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.867092704Z app=device-mqtt source=callback.go:34 msg="Subscribing to System Events on topic: edgex/system-events/core-metadata/+/+/device-mqtt/#"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.867334805Z app=device-mqtt source=validation.go:31 msg="Subscribing to device validation requests on topic: edgex/device-mqtt/validate/device"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.867347262Z app=device-mqtt source=validation.go:35 msg="Responses to device validation requests will be published on topic: edgex/response/device-mqtt/<requestId>"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.867651441Z app=device-mqtt source=manager.go:128 msg="Metrics Manager started with a report interval of 30s"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.868607792Z app=device-mqtt source=clients.go:190 msg="Using registry for URL for 'core-metadata': http://edgex-core-metadata:59881"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.868705515Z app=device-mqtt source=restrouter.go:56 msg="Registering v2 routes..."
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.87210031Z app=device-mqtt source=config.go:576 msg="Checking if custom configuration ('MQTTBrokerInfo') exists in Configuration Provider"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.873840769Z app=device-mqtt source=config.go:597 msg="Loaded custom configuration from Configuration Provider, no overrides applied"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.873889733Z app=device-mqtt source=config.go:690 msg="Watching for custom configuration changes has started for `MQTTBrokerInfo/Writable`"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.873898288Z app=device-mqtt source=driver.go:425 msg="Create MQTT client and connection: hostname=edgex-mqtt-broker clientID=device-mqtt "
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.876183477Z app=device-mqtt source=driver.go:457 msg="Subscribed to topic 'incoming/data/#' for receiving the async reading"
edgex-device-mqtt  | level=ERROR ts=2024-09-03T12:09:53.876377781Z app=device-mqtt source=driver.go:462 msg="could not subscribe to topic 'command/response/#': connection lost before Subscribe completed"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.876448331Z app=device-mqtt source=service.go:285 msg="device service device-mqtt doesn't exist, creating a new one"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.877833237Z app=device-mqtt source=profiles.go:89 msg="Loading pre-defined Device Profiles from /res/profiles(1 files found)"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.878800575Z app=device-mqtt source=profiles.go:180 msg="Device Profile Test-Device-MQTT-Profile not found in Metadata, adding it ..."
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.880190769Z app=device-mqtt source=devices.go:107 msg="Loading pre-defined Devices from /res/devices(1 files found)"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:53.880336869Z app=device-mqtt source=devices.go:189 msg="Device MQTT-test-device not found in Metadata, adding it ..."
edgex-device-mqtt  | level=ERROR ts=2024-09-03T12:09:58.882289648Z app=device-mqtt source=init.go:104 msg="Failed to load devices: request failed, status code: 503, err: <html><head><title>Timeout</title></head><body><h1>Timeout</h1></body></html>"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882431924Z app=device-mqtt source=bootstrap.go:146 msg="Un-Registering service from the Registry"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882497731Z app=device-mqtt source=command.go:61 msg="Exiting waiting for MessageBus 'edgex/device/command/request/device-mqtt/#' topic messages"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882565217Z app=device-mqtt source=config.go:852 msg="Watching for 'Writable' configuration changes has stopped"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882564656Z app=device-mqtt source=validation.go:56 msg="Exiting waiting for MessageBus 'edgex/device-mqtt/validate/device' topic messages"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.88261066Z app=device-mqtt source=callback.go:73 msg="Exiting waiting for MessageBus 'edgex/system-events/core-metadata/+/+/device-mqtt/#' topic messages"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882639169Z app=device-mqtt source=config.go:852 msg="Watching for 'Writable' configuration changes has stopped"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882654556Z app=device-mqtt source=config.go:790 msg="Watching for 'Writable' configuration changes has stopped"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882685348Z app=device-mqtt source=httpserver.go:174 msg="Web server stopped"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882720582Z app=device-mqtt source=config.go:669 msg="Watching for 'MQTTBrokerInfo/Writable' configuration changes has stopped"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882784697Z app=device-mqtt source=manager.go:110 msg="Exited Metrics Manager Run..."
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882793742Z app=device-mqtt source=httpserver.go:146 msg="Web server shut down"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.882778169Z app=device-mqtt source=messaging.go:95 msg="Disconnected from MessageBus"
edgex-device-mqtt  | level=INFO ts=2024-09-03T12:09:58.888193065Z app=device-mqtt source=driver.go:223 msg="driver is stopping, disconnect the MQTT conn"
edgex-device-mqtt  | level=ERROR ts=2024-09-03T12:10:03.888480134Z app=device-mqtt source=bootstrap.go:27 msg="Device Service bootstrapping failed"
edgex-device-mqtt exited with code 0