Sep 13 10:31:42 raspberrypi systemd[1]: ziti-router.service: Scheduled restart job, restart counter is at 15860.
Sep 13 10:31:42 raspberrypi systemd[1]: Stopped ziti-router.service - OpenZiti Router.
Sep 13 10:31:42 raspberrypi systemd[1]: Starting ziti-router.service - OpenZiti Router...
Sep 13 10:31:42 raspberrypi entrypoint.bash[312421]: WARN: set VERBOSE=1 or DEBUG=1 for more output
Sep 13 10:31:42 raspberrypi entrypoint.bash[312421]: WARN: see output in '/tmp/tmp.t2U8UV6oKh'
Sep 13 10:31:42 raspberrypi systemd[1]: Started ziti-router.service - OpenZiti Router.
Sep 13 10:31:42 raspberrypi ziti[312426]: {"arch":"arm64","build-date":"2024-07-16T13:15:40Z","configFile":"config.yml","file":"github.com/openziti/ziti/ziti/router/run.go:71","func":"github.com/openziti/ziti/ziti/router.run","go-version":"go1.22.5","level":"info","msg":"starting ziti router","os":"linux","revision":"94013fe4af89","routerId":"b8q4XvfJg","time":"2024-09-13T10:31:42.755Z","version":"v1.1.7"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/common/metrics/pool_metrics.go:50","func":"github.com/openziti/ziti/common/metrics.ConfigureGoroutinesPoolMetrics.GoroutinesPoolMetricsConfigF.func1.1","idleTime":30000000000,"level":"info","maxQueueSize":1000,"maxWorkers":32,"minWorkers":0,"msg":"starting goroutine pool","poolType":"pool.link.dialer","time":"2024-09-13T10:31:42.756Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/forwarder/faulter.go:78","func":"github.com/openziti/ziti/router/forwarder.(*Faulter).run","level":"info","msg":"started","time":"2024-09-13T10:31:42.756Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/forwarder/scanner.go:52","func":"github.com/openziti/ziti/router/forwarder.(*Scanner).run","level":"info","msg":"started","time":"2024-09-13T10:31:42.756Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/common/metrics/pool_metrics.go:50","func":"github.com/openziti/ziti/common/metrics.ConfigureGoroutinesPoolMetrics.GoroutinesPoolMetricsConfigF.func1.1","idleTime":30000000000,"level":"info","maxQueueSize":1000,"maxWorkers":128,"minWorkers":0,"msg":"starting goroutine pool","poolType":"pool.route.handler","time":"2024-09-13T10:31:42.756Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/common/metrics/pool_metrics.go:50","func":"github.com/openziti/ziti/common/metrics.ConfigureGoroutinesPoolMetrics.GoroutinesPoolMetricsConfigF.func1.1","idleTime":30000000000,"level":"info","maxQueueSize":1,"maxWorkers":50,"minWorkers":0,"msg":"starting goroutine pool","poolType":"pool.terminator_validation","time":"2024-09-13T10:31:42.757Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/internal/edgerouter/config.go:154","func":"github.com/openziti/ziti/router/internal/edgerouter.(*Config).LoadConfigFromMap","level":"info","msg":"cached data model file set to: config.yml.json.gzip","time":"2024-09-13T10:31:42.757Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/internal/edgerouter/config.go:171","func":"github.com/openziti/ziti/router/internal/edgerouter.(*Config).LoadConfigFromMap","level":"warning","msg":"Invalid heartbeat interval [0] (min: 60, max: 10), setting to default [60]","time":"2024-09-13T10:31:42.757Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/router.go:346","func":"github.com/openziti/ziti/router.(*Router).showOptions","level":"info","msg":"ctrl = {\"OutQueueSize\":4,\"MaxQueuedConnects\":1,\"MaxOutstandingConnects\":16,\"ConnectTimeout\":5000000000,\"DelayRxStart\":false,\"WriteTimeout\":0}","time":"2024-09-13T10:31:42.758Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/router.go:352","func":"github.com/openziti/ziti/router.(*Router).showOptions","level":"info","msg":"metrics = {\"ReportInterval\":60000000000,\"IntervalAgeThreshold\":0,\"MessageQueueSize\":10}","time":"2024-09-13T10:31:42.759Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/common/metrics/pool_metrics.go:50","func":"github.com/openziti/ziti/common/metrics.ConfigureGoroutinesPoolMetrics.GoroutinesPoolMetricsConfigF.func1.1","idleTime":30000000000,"level":"info","maxQueueSize":5000,"maxWorkers":15,"minWorkers":0,"msg":"starting goroutine pool","poolType":"pool.rate_limiter","time":"2024-09-13T10:31:42.759Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/router.go:660","func":"github.com/openziti/ziti/router.(*Router).initializeHealthChecks","level":"info","msg":"starting health check with ctrl ping initially after 15s, then every 30s, timing out after 15s","time":"2024-09-13T10:31:42.759Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/router.go:481","func":"github.com/openziti/ziti/router.(*Router).startXlinkDialers","level":"info","msg":"started Xlink dialer with binding [transport]","time":"2024-09-13T10:31:42.760Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"addr":"tls:0.0.0.0:3022","error":"no network interface found for 0.0.0.0","file":"github.com/openziti/ziti/router/xlink_transport/config.go:76","func":"github.com/openziti/ziti/router/xlink_transport.loadListenerConfig","level":"warning","msg":"unable to get interface for address","time":"2024-09-13T10:31:42.761Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/common/metrics/pool_metrics.go:50","func":"github.com/openziti/ziti/router/xlink_transport.(*listener).Listen.GoroutinesPoolMetricsConfigF.func1.1","idleTime":10000000000,"level":"info","maxQueueSize":1,"maxWorkers":16,"minWorkers":1,"msg":"starting goroutine pool","poolType":"pool.listener.link","time":"2024-09-13T10:31:42.761Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/router.go:506","func":"github.com/openziti/ziti/router.(*Router).startXlinkListeners","level":"info","msg":"started Xlink listener with binding [transport] advertising [tls:192.168.1.11:3022]","time":"2024-09-13T10:31:42.762Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"address":{},"file":"github.com/openziti/ziti/router/xgress_edge/listener.go:87","func":"github.com/openziti/ziti/router/xgress_edge.(*listener).Listen","level":"info","msg":"starting channel listener","time":"2024-09-13T10:31:42.762Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/common/metrics/pool_metrics.go:50","func":"github.com/openziti/ziti/router/xgress_edge.(*listener).Listen.GoroutinesPoolMetricsConfigF.func1.1","idleTime":10000000000,"level":"info","maxQueueSize":1,"maxWorkers":16,"minWorkers":1,"msg":"starting goroutine pool","poolType":"pool.listener.xgress_edge","time":"2024-09-13T10:31:42.763Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/router.go:544","func":"github.com/openziti/ziti/router.(*Router).startXgressListeners","level":"info","msg":"created xgress listener [edge] at [tls:0.0.0.0:3022]","time":"2024-09-13T10:31:42.763Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/router.go:544","func":"github.com/openziti/ziti/router.(*Router).startXgressListeners","level":"info","msg":"created xgress listener [tunnel] at []","time":"2024-09-13T10:31:42.763Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/router.go:722","func":"github.com/openziti/ziti/router.(*Router).getInitialCtrlEndpoints","level":"info","msg":"controller endpoints file [endpoints] doesn't exist. Using initial endpoints from config","time":"2024-09-13T10:31:42.763Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/router.go:555","func":"github.com/openziti/ziti/router.(*Router).startControlPlane","level":"info","msg":"router configured with 1 controller endpoints","time":"2024-09-13T10:31:42.763Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/xgress_edge/accept.go:126","func":"github.com/openziti/ziti/router/xgress_edge.(*Acceptor).Run","level":"info","msg":"starting","time":"2024-09-13T10:31:42.763Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"endpoint":{"tls:raspi.local:1280":{}},"file":"github.com/openziti/ziti/router/env/ctrls.go:95","func":"github.com/openziti/ziti/router/env.(*networkControllers).UpdateControllerEndpoints","level":"info","msg":"adding new ctrl endpoint","time":"2024-09-13T10:31:42.764Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"endpoint":"tls:raspi.local:1280","file":"github.com/openziti/ziti/router/env/ctrls.go:134","func":"github.com/openziti/ziti/router/env.(*networkControllers).connectToControllerWithBackoff","level":"info","msg":"starting connection attempts","time":"2024-09-13T10:31:42.764Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"endpoint":"tls:raspi.local:1280","file":"github.com/openziti/ziti/router/env/ctrls.go:140","func":"github.com/openziti/ziti/router/env.(*networkControllers).connectToControllerWithBackoff.func3","level":"info","msg":"successfully connected to controller","time":"2024-09-13T10:31:42.887Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/xgress_edge/factory.go:77","func":"github.com/openziti/ziti/router/xgress_edge.(*Factory).NotifyOfReconnect","level":"info","msg":"control channel reconnected, re-establishing hosted services","time":"2024-09-13T10:31:42.887Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/xgress_edge_tunnel/factory.go:56","func":"github.com/openziti/ziti/router/xgress_edge_tunnel.(*Factory).NotifyOfReconnect","level":"info","msg":"control channel reconnected, re-establishing hosted services","time":"2024-09-13T10:31:42.887Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"ctrlId":"NetFoundry Inc. Client y4hgZwfqy","file":"github.com/openziti/ziti/router/link/link_registry.go:306","func":"github.com/openziti/ziti/router/link.(*linkRegistryImpl).NotifyOfReconnect","level":"info","msg":"resending link states after reconnect","time":"2024-09-13T10:31:42.887Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/handler_edge_ctrl/hello.go:82","func":"github.com/openziti/ziti/router/handler_edge_ctrl.(*helloHandler).HandleReceive.func1","level":"info","msg":"received server hello, replying","time":"2024-09-13T10:31:42.889Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/state/manager.go:604","func":"github.com/openziti/ziti/router/state.(*ManagerImpl).StartHeartbeat","level":"info","msg":"heartbeat starting","time":"2024-09-13T10:31:42.928Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/xgress_edge_tunnel/tunneler.go:71","func":"github.com/openziti/ziti/router/xgress_edge_tunnel.(*tunneler).Start","level":"info","mode":"host","msg":"creating interceptor","time":"2024-09-13T10:31:42.928Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/router/xgress_edge/certchecker.go:124","func":"github.com/openziti/ziti/router/xgress_edge.(*CertExpirationChecker).Run","level":"info","msg":"waiting 8263h11m51.071376858s to renew certificates","time":"2024-09-13T10:31:42.928Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"error":"exec: \"resolvectl\": executable file not found in $PATH","file":"github.com/openziti/ziti/tunnel/dns/server.go:49","func":"github.com/openziti/ziti/tunnel/dns.flushDnsCaches","level":"warning","msg":"unable to find systemd-resolve or resolvectl in path, consider adding a dns flush to your restart process","time":"2024-09-13T10:31:42.928Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/tunnel/dns/server.go:89","func":"github.com/openziti/ziti/tunnel/dns.NewDnsServer","level":"info","msg":"starting dns server...","time":"2024-09-13T10:31:42.928Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"error":"dns server failed to start: listen udp 127.0.0.1:53: bind: permission denied","file":"github.com/openziti/ziti/router/xgress_edge_tunnel/tunneler.go:75","func":"github.com/openziti/ziti/router/xgress_edge_tunnel.(*tunneler).Start","level":"error","msg":"failed to start DNS resolver. using dummy resolver","time":"2024-09-13T10:31:42.929Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/tunnel/dns/dummy.go:37","func":"github.com/openziti/ziti/tunnel/dns.NewDummyResolver","level":"warning","msg":"dummy resolver does not store hostname/ip mappings","time":"2024-09-13T10:31:42.929Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"file":"github.com/openziti/ziti/tunnel/intercept/iputils.go:51","func":"github.com/openziti/ziti/tunnel/intercept.SetDnsInterceptIpRange","level":"info","msg":"dns intercept IP range: 100.64.0.1 - 100.127.255.255","time":"2024-09-13T10:31:42.929Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"ctrlId":"NetFoundry Inc. Client y4hgZwfqy","error":"tunneling not enabled","file":"github.com/openziti/ziti/router/xgress_edge_tunnel/fabric.go:190","func":"github.com/openziti/ziti/router/xgress_edge_tunnel.(*fabricProvider).authenticate.func1","level":"error","msg":"failed to authenticate","time":"2024-09-13T10:31:42.930Z"}
Sep 13 10:31:42 raspberrypi ziti[312426]: {"error":"tunneling not enabled","file":"github.com/openziti/ziti/router/xgress_edge_tunnel/servicepoll.go:105","func":"github.com/openziti/ziti/router/xgress_edge_tunnel.(*servicePoller).pollServices","level":"fatal","msg":"xgress_edge_tunnel unable to authenticate to controller. ensure tunneler mode is enabled for this router or disable tunnel listener. exiting ","time":"2024-09-13T10:31:42.930Z"}
Sep 13 10:31:42 raspberrypi systemd[1]: ziti-router.service: Main process exited, code=exited, status=1/FAILURE
Sep 13 10:31:42 raspberrypi systemd[1]: ziti-router.service: Failed with result 'exit-code'.