{"level":"warn","ts":"2026-04-15T04:13:53.217142Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T04:13:53.217216Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-15T04:13:53.217283Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T04:13:53.217296Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-15T04:13:53.21731Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-15T04:13:53.217894Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-04-15T04:13:53.217966Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-15T04:13:53.221082Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.90184ms"} {"level":"info","ts":"2026-04-15T04:13:53.232952Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-04-15T04:13:53.233045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T04:13:53.233073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-04-15T04:13:53.233089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T04:13:53.233105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-04-15T04:13:53.233141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-04-15T04:13:53.240113Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T04:13:53.241862Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T04:13:53.244446Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-15T04:13:53.248212Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T04:13:53.248392Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T04:13:53.248478Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-15T04:13:53.24857Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-15T04:13:53.248583Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-15T04:13:53.250318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-04-15T04:13:53.250741Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-15T04:13:53.252473Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-15T04:13:53.252622Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-15T04:13:53.252671Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-15T04:13:53.252806Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T04:13:53.25285Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T04:13:53.333507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T04:13:53.333558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T04:13:53.333599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-04-15T04:13:53.333611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-04-15T04:13:53.333617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-15T04:13:53.333648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-04-15T04:13:53.333681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-15T04:13:53.342655Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T04:13:53.343374Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T04:13:53.34368Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T04:13:53.343769Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T04:13:53.343793Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T04:13:53.343807Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T04:13:53.344058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T04:13:53.345349Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-04-15T04:13:53.345521Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T04:13:53.345567Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T04:13:53.346425Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-15T04:14:16.31075Z","caller":"traceutil/trace.go:171","msg":"trace[1018901209] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"104.961808ms","start":"2026-04-15T04:14:16.205726Z","end":"2026-04-15T04:14:16.310688Z","steps":["trace[1018901209] 'process raft request' (duration: 104.290892ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:14:16.320618Z","caller":"traceutil/trace.go:171","msg":"trace[860310625] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"108.830998ms","start":"2026-04-15T04:14:16.21176Z","end":"2026-04-15T04:14:16.320591Z","steps":["trace[860310625] 'process raft request' (duration: 108.753048ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:14:16.320593Z","caller":"traceutil/trace.go:171","msg":"trace[1364565782] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"108.891648ms","start":"2026-04-15T04:14:16.211678Z","end":"2026-04-15T04:14:16.320569Z","steps":["trace[1364565782] 'process raft request' (duration: 108.773427ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:14:16.864822Z","caller":"traceutil/trace.go:171","msg":"trace[569522132] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"128.27153ms","start":"2026-04-15T04:14:16.736511Z","end":"2026-04-15T04:14:16.864783Z","steps":["trace[569522132] 'process raft request' (duration: 76.418812ms)","trace[569522132] 'compare' (duration: 51.098652ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:14:16.8653Z","caller":"traceutil/trace.go:171","msg":"trace[850287792] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:521; }","duration":"105.421892ms","start":"2026-04-15T04:14:16.75986Z","end":"2026-04-15T04:14:16.865282Z","steps":["trace[850287792] 'read index received' (duration: 29.792416ms)","trace[850287792] 'applied index is now lower than readState.Index' (duration: 75.628146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:14:16.865544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.629373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-15T04:14:16.865634Z","caller":"traceutil/trace.go:171","msg":"trace[795486320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"105.836294ms","start":"2026-04-15T04:14:16.759784Z","end":"2026-04-15T04:14:16.86562Z","steps":["trace[795486320] 'agreement among raft nodes before linearized reading' (duration: 105.598052ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:14:16.865955Z","caller":"traceutil/trace.go:171","msg":"trace[379835569] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"116.248616ms","start":"2026-04-15T04:14:16.749672Z","end":"2026-04-15T04:14:16.865921Z","steps":["trace[379835569] 'process raft request' (duration: 114.926935ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:14:17.225449Z","caller":"traceutil/trace.go:171","msg":"trace[1235029846] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"111.708013ms","start":"2026-04-15T04:14:17.113707Z","end":"2026-04-15T04:14:17.225415Z","steps":["trace[1235029846] 'process raft request' (duration: 111.472701ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:14:22.606128Z","caller":"traceutil/trace.go:171","msg":"trace[1996771234] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"136.22192ms","start":"2026-04-15T04:14:22.469775Z","end":"2026-04-15T04:14:22.605997Z","steps":["trace[1996771234] 'read index received' (duration: 132.28531ms)","trace[1996771234] 'applied index is now lower than readState.Index' (duration: 3.93478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:14:22.606724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.844654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rwsfc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-15T04:14:22.606754Z","caller":"traceutil/trace.go:171","msg":"trace[431863028] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rwsfc; range_end:; response_count:1; response_revision:556; }","duration":"137.018875ms","start":"2026-04-15T04:14:22.469724Z","end":"2026-04-15T04:14:22.606743Z","steps":["trace[431863028] 'agreement among raft nodes before linearized reading' (duration: 136.599532ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:14:22.60745Z","caller":"traceutil/trace.go:171","msg":"trace[509425387] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"398.266081ms","start":"2026-04-15T04:14:22.209146Z","end":"2026-04-15T04:14:22.607412Z","steps":["trace[509425387] 'process raft request' (duration: 393.573307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:14:22.609492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:14:22.209119Z","time spent":"398.450002ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:14:23.402267Z","caller":"traceutil/trace.go:171","msg":"trace[1508203236] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"131.493397ms","start":"2026-04-15T04:14:23.270747Z","end":"2026-04-15T04:14:23.40224Z","steps":["trace[1508203236] 'read index received' (duration: 128.422574ms)","trace[1508203236] 'applied index is now lower than readState.Index' (duration: 3.069273ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:14:23.402368Z","caller":"traceutil/trace.go:171","msg":"trace[1472592879] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"284.199991ms","start":"2026-04-15T04:14:23.118106Z","end":"2026-04-15T04:14:23.402306Z","steps":["trace[1472592879] 'process raft request' (duration: 281.43261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:14:23.402459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.694708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-15T04:14:23.402533Z","caller":"traceutil/trace.go:171","msg":"trace[721211415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"131.787779ms","start":"2026-04-15T04:14:23.270702Z","end":"2026-04-15T04:14:23.40249Z","steps":["trace[721211415] 'agreement among raft nodes before linearized reading' (duration: 131.634958ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:14:37.678698Z","caller":"traceutil/trace.go:171","msg":"trace[439047839] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"122.188137ms","start":"2026-04-15T04:14:37.556486Z","end":"2026-04-15T04:14:37.678674Z","steps":["trace[439047839] 'read index received' (duration: 122.147647ms)","trace[439047839] 'applied index is now lower than readState.Index' (duration: 39.39µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:14:37.678814Z","caller":"traceutil/trace.go:171","msg":"trace[1443007829] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"177.678794ms","start":"2026-04-15T04:14:37.501113Z","end":"2026-04-15T04:14:37.678792Z","steps":["trace[1443007829] 'process raft request' (duration: 177.416612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:14:37.678951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.449099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5pv2s\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-15T04:14:37.679047Z","caller":"traceutil/trace.go:171","msg":"trace[887748881] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5pv2s; range_end:; response_count:1; response_revision:722; }","duration":"122.592159ms","start":"2026-04-15T04:14:37.556438Z","end":"2026-04-15T04:14:37.679031Z","steps":["trace[887748881] 'agreement among raft nodes before linearized reading' (duration: 122.338748ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:36.721133Z","caller":"traceutil/trace.go:171","msg":"trace[1445807668] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"121.300181ms","start":"2026-04-15T04:16:36.59981Z","end":"2026-04-15T04:16:36.72111Z","steps":["trace[1445807668] 'process raft request' (duration: 69.052951ms)","trace[1445807668] 'compare' (duration: 52.164199ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:16:36.779898Z","caller":"traceutil/trace.go:171","msg":"trace[947073341] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"178.832121ms","start":"2026-04-15T04:16:36.601046Z","end":"2026-04-15T04:16:36.779878Z","steps":["trace[947073341] 'process raft request' (duration: 178.75031ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:38.223165Z","caller":"traceutil/trace.go:171","msg":"trace[1755705339] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"137.588523ms","start":"2026-04-15T04:16:38.085534Z","end":"2026-04-15T04:16:38.223122Z","steps":["trace[1755705339] 'process raft request' (duration: 88.269183ms)","trace[1755705339] 'compare' (duration: 48.976126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:16:58.012868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.203632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:16:58.01285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.942959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.012929Z","caller":"traceutil/trace.go:171","msg":"trace[527666842] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2143; }","duration":"150.278162ms","start":"2026-04-15T04:16:57.862638Z","end":"2026-04-15T04:16:58.012917Z","steps":["trace[527666842] 'range keys from in-memory index tree' (duration: 150.130731ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:58.012958Z","caller":"traceutil/trace.go:171","msg":"trace[874870453] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2143; }","duration":"151.09926ms","start":"2026-04-15T04:16:57.861836Z","end":"2026-04-15T04:16:58.012935Z","steps":["trace[874870453] 'count revisions from in-memory index tree' (duration: 150.849788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.012972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.769388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:16:58.013069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.733289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.013081Z","caller":"traceutil/trace.go:171","msg":"trace[1993250284] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2143; }","duration":"193.88246ms","start":"2026-04-15T04:16:57.819153Z","end":"2026-04-15T04:16:58.013035Z","steps":["trace[1993250284] 'count revisions from in-memory index tree' (duration: 193.690067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.013215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.109473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:16:58.013331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.753094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.013363Z","caller":"traceutil/trace.go:171","msg":"trace[73402112] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2143; }","duration":"208.800415ms","start":"2026-04-15T04:16:57.804555Z","end":"2026-04-15T04:16:58.013356Z","steps":["trace[73402112] 'count revisions from in-memory index tree' (duration: 208.678463ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:58.013412Z","caller":"traceutil/trace.go:171","msg":"trace[32568652] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2143; }","duration":"110.284064ms","start":"2026-04-15T04:16:57.903045Z","end":"2026-04-15T04:16:58.013329Z","steps":["trace[32568652] 'range keys from in-memory index tree' (duration: 109.948831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.013481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.891196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.013509Z","caller":"traceutil/trace.go:171","msg":"trace[2003425317] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2143; }","duration":"208.931196ms","start":"2026-04-15T04:16:57.804572Z","end":"2026-04-15T04:16:58.013503Z","steps":["trace[2003425317] 'range keys from in-memory index tree' (duration: 208.844696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.013522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.336847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:16:58.013643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.893704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-15T04:16:58.013669Z","caller":"traceutil/trace.go:171","msg":"trace[1151026928] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2143; }","duration":"178.549209ms","start":"2026-04-15T04:16:57.835088Z","end":"2026-04-15T04:16:58.013637Z","steps":["trace[1151026928] 'count revisions from in-memory index tree' (duration: 178.268675ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:58.01371Z","caller":"traceutil/trace.go:171","msg":"trace[736582154] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2143; }","duration":"207.977785ms","start":"2026-04-15T04:16:57.805715Z","end":"2026-04-15T04:16:58.013693Z","steps":["trace[736582154] 'range keys from in-memory index tree' (duration: 207.718013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.013717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.677543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.013748Z","caller":"traceutil/trace.go:171","msg":"trace[776759249] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2143; }","duration":"208.721895ms","start":"2026-04-15T04:16:57.805019Z","end":"2026-04-15T04:16:58.013741Z","steps":["trace[776759249] 'range keys from in-memory index tree' (duration: 208.619303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.013801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.76965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.013912Z","caller":"traceutil/trace.go:171","msg":"trace[1872493014] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2143; }","duration":"178.877701ms","start":"2026-04-15T04:16:57.835019Z","end":"2026-04-15T04:16:58.013897Z","steps":["trace[1872493014] 'range keys from in-memory index tree' (duration: 178.7278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.013927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.985072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.013951Z","caller":"traceutil/trace.go:171","msg":"trace[852137325] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2143; }","duration":"179.010593ms","start":"2026-04-15T04:16:57.834934Z","end":"2026-04-15T04:16:58.013944Z","steps":["trace[852137325] 'range keys from in-memory index tree' (duration: 178.7875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.014015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.733468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.014036Z","caller":"traceutil/trace.go:171","msg":"trace[85010732] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2143; }","duration":"194.764309ms","start":"2026-04-15T04:16:57.819267Z","end":"2026-04-15T04:16:58.014031Z","steps":["trace[85010732] 'range keys from in-memory index tree' (duration: 194.694567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.014031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.286081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.014065Z","caller":"traceutil/trace.go:171","msg":"trace[115961809] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2143; }","duration":"124.319661ms","start":"2026-04-15T04:16:57.889734Z","end":"2026-04-15T04:16:58.014054Z","steps":["trace[115961809] 'range keys from in-memory index tree' (duration: 124.180898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.014115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.377667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:16:58.014092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.111459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:16:58.013584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.385004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.014169Z","caller":"traceutil/trace.go:171","msg":"trace[723493492] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2143; }","duration":"138.443558ms","start":"2026-04-15T04:16:57.875709Z","end":"2026-04-15T04:16:58.014152Z","steps":["trace[723493492] 'count revisions from in-memory index tree' (duration: 138.338457ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:58.0142Z","caller":"traceutil/trace.go:171","msg":"trace[1160831355] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2143; }","duration":"195.014591ms","start":"2026-04-15T04:16:57.81917Z","end":"2026-04-15T04:16:58.014185Z","steps":["trace[1160831355] 'range keys from in-memory index tree' (duration: 194.346804ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:58.014196Z","caller":"traceutil/trace.go:171","msg":"trace[1982403009] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2143; }","duration":"124.25095ms","start":"2026-04-15T04:16:57.889922Z","end":"2026-04-15T04:16:58.014173Z","steps":["trace[1982403009] 'range keys from in-memory index tree' (duration: 124.070048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.014232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.688541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.014263Z","caller":"traceutil/trace.go:171","msg":"trace[1103384134] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2143; }","duration":"138.742001ms","start":"2026-04-15T04:16:57.875514Z","end":"2026-04-15T04:16:58.014256Z","steps":["trace[1103384134] 'range keys from in-memory index tree' (duration: 138.61479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.014296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.013592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:16:58.014367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.424742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.01441Z","caller":"traceutil/trace.go:171","msg":"trace[2015615877] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2143; }","duration":"168.475682ms","start":"2026-04-15T04:16:57.845921Z","end":"2026-04-15T04:16:58.014397Z","steps":["trace[2015615877] 'count revisions from in-memory index tree' (duration: 168.380241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.014427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.286771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.0131Z","caller":"traceutil/trace.go:171","msg":"trace[1385593792] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2143; }","duration":"109.777719ms","start":"2026-04-15T04:16:57.903313Z","end":"2026-04-15T04:16:58.013091Z","steps":["trace[1385593792] 'range keys from in-memory index tree' (duration: 109.696459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.012854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.000399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.014545Z","caller":"traceutil/trace.go:171","msg":"trace[1389542641] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2143; }","duration":"168.465883ms","start":"2026-04-15T04:16:57.846071Z","end":"2026-04-15T04:16:58.014537Z","steps":["trace[1389542641] 'range keys from in-memory index tree' (duration: 168.242239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.014415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.2888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:16:58.014675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.285626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.014654Z","caller":"traceutil/trace.go:171","msg":"trace[393069420] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2143; }","duration":"152.771798ms","start":"2026-04-15T04:16:57.861805Z","end":"2026-04-15T04:16:58.014577Z","steps":["trace[393069420] 'range keys from in-memory index tree' (duration: 150.886828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:16:58.014329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.268121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:16:58.014784Z","caller":"traceutil/trace.go:171","msg":"trace[1475613029] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2143; }","duration":"168.772185ms","start":"2026-04-15T04:16:57.846004Z","end":"2026-04-15T04:16:58.014776Z","steps":["trace[1475613029] 'range keys from in-memory index tree' (duration: 168.110328ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:58.014712Z","caller":"traceutil/trace.go:171","msg":"trace[1358554051] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2143; }","duration":"138.336367ms","start":"2026-04-15T04:16:57.876364Z","end":"2026-04-15T04:16:58.0147Z","steps":["trace[1358554051] 'range keys from in-memory index tree' (duration: 138.251996ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:58.014375Z","caller":"traceutil/trace.go:171","msg":"trace[984100530] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2143; }","duration":"111.245574ms","start":"2026-04-15T04:16:57.903114Z","end":"2026-04-15T04:16:58.01436Z","steps":["trace[984100530] 'count revisions from in-memory index tree' (duration: 110.972772ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:58.014682Z","caller":"traceutil/trace.go:171","msg":"trace[375743275] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2143; }","duration":"124.884876ms","start":"2026-04-15T04:16:57.88978Z","end":"2026-04-15T04:16:58.014665Z","steps":["trace[375743275] 'count revisions from in-memory index tree' (duration: 123.444942ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:16:58.278786Z","caller":"traceutil/trace.go:171","msg":"trace[1234194635] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"166.91904ms","start":"2026-04-15T04:16:58.111833Z","end":"2026-04-15T04:16:58.278753Z","steps":["trace[1234194635] 'process raft request' (duration: 166.508316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:28.731732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.823341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T04:17:28.731996Z","caller":"traceutil/trace.go:171","msg":"trace[948885833] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"250.12728ms","start":"2026-04-15T04:17:28.481826Z","end":"2026-04-15T04:17:28.731953Z","steps":["trace[948885833] 'process raft request' (duration: 121.739984ms)","trace[948885833] 'compare' (duration: 127.684709ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:17:28.73204Z","caller":"traceutil/trace.go:171","msg":"trace[576685363] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"227.350394ms","start":"2026-04-15T04:17:28.504671Z","end":"2026-04-15T04:17:28.732022Z","steps":["trace[576685363] 'process raft request' (duration: 227.188823ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:28.732219Z","caller":"traceutil/trace.go:171","msg":"trace[1577350063] linearizableReadLoop","detail":"{readStateIndex:2487; appliedIndex:2484; }","duration":"194.266071ms","start":"2026-04-15T04:17:28.537943Z","end":"2026-04-15T04:17:28.732209Z","steps":["trace[1577350063] 'read index received' (duration: 65.637893ms)","trace[1577350063] 'applied index is now lower than readState.Index' (duration: 128.627108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:17:28.732403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.448983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-15T04:17:28.732431Z","caller":"traceutil/trace.go:171","msg":"trace[1625051926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2393; }","duration":"194.529643ms","start":"2026-04-15T04:17:28.537895Z","end":"2026-04-15T04:17:28.732425Z","steps":["trace[1625051926] 'agreement among raft nodes before linearized reading' (duration: 194.348262ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:28.732478Z","caller":"traceutil/trace.go:171","msg":"trace[2039439195] transaction","detail":"{read_only:false; response_revision:2393; number_of_response:1; }","duration":"202.852972ms","start":"2026-04-15T04:17:28.5296Z","end":"2026-04-15T04:17:28.732453Z","steps":["trace[2039439195] 'process raft request' (duration: 202.379557ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:28.928926Z","caller":"traceutil/trace.go:171","msg":"trace[607027036] transaction","detail":"{read_only:false; response_revision:2395; number_of_response:1; }","duration":"193.164241ms","start":"2026-04-15T04:17:28.735729Z","end":"2026-04-15T04:17:28.928893Z","steps":["trace[607027036] 'process raft request' (duration: 115.375723ms)","trace[607027036] 'compare' (duration: 77.620776ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:17:29.133782Z","caller":"traceutil/trace.go:171","msg":"trace[674331255] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"127.468815ms","start":"2026-04-15T04:17:29.006284Z","end":"2026-04-15T04:17:29.133753Z","steps":["trace[674331255] 'process raft request' (duration: 127.293574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:29.456861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.895033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-15T04:17:29.456936Z","caller":"traceutil/trace.go:171","msg":"trace[1786740076] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2397; }","duration":"174.026114ms","start":"2026-04-15T04:17:29.282891Z","end":"2026-04-15T04:17:29.456917Z","steps":["trace[1786740076] 'range keys from in-memory index tree' (duration: 173.692341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:29.456966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.449873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-04-15T04:17:29.457049Z","caller":"traceutil/trace.go:171","msg":"trace[1978969626] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2397; }","duration":"111.561324ms","start":"2026-04-15T04:17:29.345468Z","end":"2026-04-15T04:17:29.457029Z","steps":["trace[1978969626] 'range keys from in-memory index tree' (duration: 111.291472ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:29.971309Z","caller":"traceutil/trace.go:171","msg":"trace[1327636679] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"253.89362ms","start":"2026-04-15T04:17:29.717398Z","end":"2026-04-15T04:17:29.971291Z","steps":["trace[1327636679] 'process raft request' (duration: 253.763719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:30.260491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.156374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T04:17:30.260645Z","caller":"traceutil/trace.go:171","msg":"trace[1064940219] linearizableReadLoop","detail":"{readStateIndex:2497; appliedIndex:2495; }","duration":"305.177578ms","start":"2026-04-15T04:17:29.955443Z","end":"2026-04-15T04:17:30.260621Z","steps":["trace[1064940219] 'read index received' (duration: 15.763909ms)","trace[1064940219] 'applied index is now lower than readState.Index' (duration: 289.411959ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:17:30.260774Z","caller":"traceutil/trace.go:171","msg":"trace[1338024973] transaction","detail":"{read_only:false; response_revision:2403; number_of_response:1; }","duration":"541.34015ms","start":"2026-04-15T04:17:29.719414Z","end":"2026-04-15T04:17:30.260754Z","steps":["trace[1338024973] 'process raft request' (duration: 317.786862ms)","trace[1338024973] 'compare' (duration: 222.935322ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:17:30.260857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:17:29.719377Z","time spent":"541.440811ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:17:30.261203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.409009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:17:30.261267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.808523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-15T04:17:30.26142Z","caller":"traceutil/trace.go:171","msg":"trace[1656376856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2403; }","duration":"305.984076ms","start":"2026-04-15T04:17:29.955396Z","end":"2026-04-15T04:17:30.26138Z","steps":["trace[1656376856] 'agreement among raft nodes before linearized reading' (duration: 305.721463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:30.261463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:17:29.955357Z","time spent":"306.098246ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-15T04:17:30.261489Z","caller":"traceutil/trace.go:171","msg":"trace[974970999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2403; }","duration":"289.63028ms","start":"2026-04-15T04:17:29.97171Z","end":"2026-04-15T04:17:30.26134Z","steps":["trace[974970999] 'agreement among raft nodes before linearized reading' (duration: 289.391408ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:19:24.566743Z","caller":"traceutil/trace.go:171","msg":"trace[1606119259] linearizableReadLoop","detail":"{readStateIndex:3212; appliedIndex:3211; }","duration":"105.943455ms","start":"2026-04-15T04:19:24.460774Z","end":"2026-04-15T04:19:24.566718Z","steps":["trace[1606119259] 'read index received' (duration: 105.700373ms)","trace[1606119259] 'applied index is now lower than readState.Index' (duration: 242.302µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:19:24.566996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.202367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-04-15T04:19:24.566846Z","caller":"traceutil/trace.go:171","msg":"trace[387540449] transaction","detail":"{read_only:false; response_revision:3094; number_of_response:1; }","duration":"109.560933ms","start":"2026-04-15T04:19:24.457244Z","end":"2026-04-15T04:19:24.566805Z","steps":["trace[387540449] 'process raft request' (duration: 109.341702ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:19:24.567035Z","caller":"traceutil/trace.go:171","msg":"trace[754934194] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3094; }","duration":"106.312097ms","start":"2026-04-15T04:19:24.460713Z","end":"2026-04-15T04:19:24.567025Z","steps":["trace[754934194] 'agreement among raft nodes before linearized reading' (duration: 106.125846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:19:24.567042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.989998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T04:19:24.567136Z","caller":"traceutil/trace.go:171","msg":"trace[214540885] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:3094; }","duration":"104.131609ms","start":"2026-04-15T04:19:24.462982Z","end":"2026-04-15T04:19:24.567113Z","steps":["trace[214540885] 'agreement among raft nodes before linearized reading' (duration: 103.935277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:19:24.839443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.162896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T04:19:24.839594Z","caller":"traceutil/trace.go:171","msg":"trace[1285178950] transaction","detail":"{read_only:false; response_revision:3095; number_of_response:1; }","duration":"143.375939ms","start":"2026-04-15T04:19:24.696193Z","end":"2026-04-15T04:19:24.839569Z","steps":["trace[1285178950] 'process raft request' (duration: 25.971461ms)","trace[1285178950] 'compare' (duration: 116.965704ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:21:31.316376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696104357229439,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T04:21:31.438131Z","caller":"traceutil/trace.go:171","msg":"trace[1378784655] transaction","detail":"{read_only:false; response_revision:3943; number_of_response:1; }","duration":"810.727136ms","start":"2026-04-15T04:21:30.627374Z","end":"2026-04-15T04:21:31.438101Z","steps":["trace[1378784655] 'process raft request' (duration: 810.546415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:21:31.438309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:21:30.627342Z","time spent":"810.863647ms","remote":"127.0.0.1:55530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:21:31.440303Z","caller":"traceutil/trace.go:171","msg":"trace[19786306] linearizableReadLoop","detail":"{readStateIndex:4093; appliedIndex:4090; }","duration":"624.971711ms","start":"2026-04-15T04:21:30.815309Z","end":"2026-04-15T04:21:31.440281Z","steps":["trace[19786306] 'read index received' (duration: 622.685393ms)","trace[19786306] 'applied index is now lower than readState.Index' (duration: 2.284928ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:21:31.441024Z","caller":"traceutil/trace.go:171","msg":"trace[2020854682] transaction","detail":"{read_only:false; response_revision:3944; number_of_response:1; }","duration":"421.801249ms","start":"2026-04-15T04:21:31.019178Z","end":"2026-04-15T04:21:31.44098Z","steps":["trace[2020854682] 'process raft request' (duration: 420.928762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:21:31.444025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.548013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:21:31.444137Z","caller":"traceutil/trace.go:171","msg":"trace[1235723683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3945; }","duration":"471.616403ms","start":"2026-04-15T04:21:30.972461Z","end":"2026-04-15T04:21:31.444078Z","steps":["trace[1235723683] 'agreement among raft nodes before linearized reading' (duration: 471.520343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:21:31.444202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:21:31.019146Z","time spent":"424.652461ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:21:31.444227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:21:30.972379Z","time spent":"471.836325ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T04:21:31.441677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.294711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T04:21:31.447567Z","caller":"traceutil/trace.go:171","msg":"trace[379683064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3945; }","duration":"632.292978ms","start":"2026-04-15T04:21:30.815258Z","end":"2026-04-15T04:21:31.447551Z","steps":["trace[379683064] 'agreement among raft nodes before linearized reading' (duration: 625.680486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:21:31.447627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:21:30.815239Z","time spent":"632.36797ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T04:21:31.444321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.508821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:21:31.4477Z","caller":"traceutil/trace.go:171","msg":"trace[1100556698] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:3945; }","duration":"349.934678ms","start":"2026-04-15T04:21:31.097746Z","end":"2026-04-15T04:21:31.44768Z","steps":["trace[1100556698] 'agreement among raft nodes before linearized reading' (duration: 346.511172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:21:31.447767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:21:31.097683Z","time spent":"350.067349ms","remote":"127.0.0.1:55406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-04-15T04:21:31.441405Z","caller":"traceutil/trace.go:171","msg":"trace[55162376] transaction","detail":"{read_only:false; response_revision:3945; number_of_response:1; }","duration":"156.788955ms","start":"2026-04-15T04:21:31.284591Z","end":"2026-04-15T04:21:31.44138Z","steps":["trace[55162376] 'process raft request' (duration: 155.634346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:21:31.444496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.190562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:21:31.448005Z","caller":"traceutil/trace.go:171","msg":"trace[1430770305] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:3945; }","duration":"289.73316ms","start":"2026-04-15T04:21:31.158256Z","end":"2026-04-15T04:21:31.447989Z","steps":["trace[1430770305] 'agreement among raft nodes before linearized reading' (duration: 286.007011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:21:31.446042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.609353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-15T04:21:31.448257Z","caller":"traceutil/trace.go:171","msg":"trace[1761958669] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3945; }","duration":"272.534664ms","start":"2026-04-15T04:21:31.175673Z","end":"2026-04-15T04:21:31.448208Z","steps":["trace[1761958669] 'agreement among raft nodes before linearized reading' (duration: 268.341341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:10.421192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.999259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T04:22:10.421287Z","caller":"traceutil/trace.go:171","msg":"trace[880019155] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:4289; }","duration":"395.170061ms","start":"2026-04-15T04:22:10.026098Z","end":"2026-04-15T04:22:10.421268Z","steps":["trace[880019155] 'count revisions from in-memory index tree' (duration: 394.917979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:10.42133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:10.026076Z","time spent":"395.243732ms","remote":"127.0.0.1:55716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-15T04:22:16.080381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696104357230416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T04:22:16.581114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696104357230416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T04:22:17.081879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696104357230416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T04:22:17.583392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696104357230416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T04:22:17.710728Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.343000959s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T04:22:17.711218Z","caller":"traceutil/trace.go:171","msg":"trace[1679107314] transaction","detail":"{read_only:false; response_revision:4318; number_of_response:1; }","duration":"2.343576974s","start":"2026-04-15T04:22:15.367606Z","end":"2026-04-15T04:22:17.711183Z","steps":["trace[1679107314] 'process raft request' (duration: 2.343353542s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.711425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.36758Z","time spent":"2.343751815s","remote":"127.0.0.1:48266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2358,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T04:22:17.782014Z","caller":"traceutil/trace.go:171","msg":"trace[1468508418] transaction","detail":"{read_only:false; response_revision:4321; number_of_response:1; }","duration":"2.169638825s","start":"2026-04-15T04:22:15.612348Z","end":"2026-04-15T04:22:17.781987Z","steps":["trace[1468508418] 'process raft request' (duration: 2.169565875s)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:17.782033Z","caller":"traceutil/trace.go:171","msg":"trace[1268395628] transaction","detail":"{read_only:false; response_revision:4319; number_of_response:1; }","duration":"2.316091556s","start":"2026-04-15T04:22:15.465904Z","end":"2026-04-15T04:22:17.781996Z","steps":["trace[1268395628] 'process raft request' (duration: 2.315805623s)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:17.78209Z","caller":"traceutil/trace.go:171","msg":"trace[982459274] transaction","detail":"{read_only:false; response_revision:4320; number_of_response:1; }","duration":"2.169914858s","start":"2026-04-15T04:22:15.612127Z","end":"2026-04-15T04:22:17.782042Z","steps":["trace[982459274] 'process raft request' (duration: 2.169738817s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.78219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.612309Z","time spent":"2.169769227s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:22:17.782254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.465881Z","time spent":"2.316226857s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:22:17.782288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.612104Z","time spent":"2.17008371s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:22:17.782314Z","caller":"traceutil/trace.go:171","msg":"trace[1710728217] transaction","detail":"{read_only:false; response_revision:4322; number_of_response:1; }","duration":"1.858108699s","start":"2026-04-15T04:22:15.924178Z","end":"2026-04-15T04:22:17.782286Z","steps":["trace[1710728217] 'process raft request' (duration: 1.857768675s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.782431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.924144Z","time spent":"1.85823139s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:22:17.782711Z","caller":"traceutil/trace.go:171","msg":"trace[2121564366] linearizableReadLoop","detail":"{readStateIndex:4482; appliedIndex:4476; }","duration":"2.202710009s","start":"2026-04-15T04:22:15.579973Z","end":"2026-04-15T04:22:17.782683Z","steps":["trace[2121564366] 'read index received' (duration: 2.131057311s)","trace[2121564366] 'applied index is now lower than readState.Index' (duration: 71.644008ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:22:17.782785Z","caller":"traceutil/trace.go:171","msg":"trace[1670785655] transaction","detail":"{read_only:false; response_revision:4323; number_of_response:1; }","duration":"1.181297957s","start":"2026-04-15T04:22:16.601474Z","end":"2026-04-15T04:22:17.782772Z","steps":["trace[1670785655] 'process raft request' (duration: 1.180673263s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.782849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.601445Z","time spent":"1.181371208s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:22:17.782908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.20293958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-15T04:22:17.782934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.893542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:22:17.782964Z","caller":"traceutil/trace.go:171","msg":"trace[1086186886] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4323; }","duration":"2.203037842s","start":"2026-04-15T04:22:15.57991Z","end":"2026-04-15T04:22:17.782948Z","steps":["trace[1086186886] 'agreement among raft nodes before linearized reading' (duration: 2.20286879s)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:17.782985Z","caller":"traceutil/trace.go:171","msg":"trace[1509612619] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:4323; }","duration":"825.977613ms","start":"2026-04-15T04:22:16.956991Z","end":"2026-04-15T04:22:17.782969Z","steps":["trace[1509612619] 'agreement among raft nodes before linearized reading' (duration: 825.906832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.783004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.579885Z","time spent":"2.203109792s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.956972Z","time spent":"826.039223ms","remote":"127.0.0.1:55580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-04-15T04:22:17.78302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.811232087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:22:17.783058Z","caller":"traceutil/trace.go:171","msg":"trace[518467737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4323; }","duration":"1.811313508s","start":"2026-04-15T04:22:15.971735Z","end":"2026-04-15T04:22:17.783048Z","steps":["trace[518467737] 'agreement among raft nodes before linearized reading' (duration: 1.811254308s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.783098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.971718Z","time spent":"1.811375559s","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.854623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-15T04:22:17.78317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.109736472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T04:22:17.783278Z","caller":"traceutil/trace.go:171","msg":"trace[281338285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4323; }","duration":"2.109944323s","start":"2026-04-15T04:22:15.673321Z","end":"2026-04-15T04:22:17.783265Z","steps":["trace[281338285] 'agreement among raft nodes before linearized reading' (duration: 2.10958813s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.783297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328627643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-15T04:22:17.78333Z","caller":"traceutil/trace.go:171","msg":"trace[842021113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4323; }","duration":"1.328700835s","start":"2026-04-15T04:22:16.454621Z","end":"2026-04-15T04:22:17.783321Z","steps":["trace[842021113] 'agreement among raft nodes before linearized reading' (duration: 1.328592864s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.783351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.673307Z","time spent":"2.110031544s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.454595Z","time spent":"1.328762845s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.044302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6084"} {"level":"info","ts":"2026-04-15T04:22:17.783419Z","caller":"traceutil/trace.go:171","msg":"trace[1593328182] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:4323; }","duration":"812.202343ms","start":"2026-04-15T04:22:16.971203Z","end":"2026-04-15T04:22:17.783405Z","steps":["trace[1593328182] 'agreement among raft nodes before linearized reading' (duration: 812.027662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.783461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.971175Z","time spent":"812.275694ms","remote":"127.0.0.1:55580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6106,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.030191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:22:17.783659Z","caller":"traceutil/trace.go:171","msg":"trace[1729215147] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:4323; }","duration":"822.100421ms","start":"2026-04-15T04:22:16.961546Z","end":"2026-04-15T04:22:17.783647Z","steps":["trace[1729215147] 'agreement among raft nodes before linearized reading' (duration: 822.044641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.783686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.961531Z","time spent":"822.149951ms","remote":"127.0.0.1:55580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11096987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-15T04:22:17.783882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.398532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-data-valkey-node-1.18a66ce80ce3cc8a\" ","response":"range_response_count:1 size:764"} {"level":"warn","ts":"2026-04-15T04:22:17.783894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141498242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T04:22:17.78318Z","caller":"traceutil/trace.go:171","msg":"trace[592981386] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4323; }","duration":"483.950823ms","start":"2026-04-15T04:22:17.299216Z","end":"2026-04-15T04:22:17.783167Z","steps":["trace[592981386] 'agreement among raft nodes before linearized reading' (duration: 483.790722ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:17.783903Z","caller":"traceutil/trace.go:171","msg":"trace[148957077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4323; }","duration":"1.11105133s","start":"2026-04-15T04:22:16.67284Z","end":"2026-04-15T04:22:17.783892Z","steps":["trace[148957077] 'agreement among raft nodes before linearized reading' (duration: 1.110920699s)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:17.783917Z","caller":"traceutil/trace.go:171","msg":"trace[2047140098] range","detail":"{range_begin:/registry/events/openstack/valkey-data-valkey-node-1.18a66ce80ce3cc8a; range_end:; response_count:1; response_revision:4323; }","duration":"828.473932ms","start":"2026-04-15T04:22:16.955432Z","end":"2026-04-15T04:22:17.783906Z","steps":["trace[2047140098] 'agreement among raft nodes before linearized reading' (duration: 828.368551ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:17.783932Z","caller":"traceutil/trace.go:171","msg":"trace[1381329779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4323; }","duration":"1.141562022s","start":"2026-04-15T04:22:16.642357Z","end":"2026-04-15T04:22:17.783919Z","steps":["trace[1381329779] 'agreement among raft nodes before linearized reading' (duration: 1.141452141s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.783948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.95541Z","time spent":"828.532403ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":786,"request content":"key:\"/registry/events/openstack/valkey-data-valkey-node-1.18a66ce80ce3cc8a\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:17.299196Z","time spent":"484.739289ms","remote":"127.0.0.1:55530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.642345Z","time spent":"1.141615032s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.004013844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-15T04:22:17.783855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911707423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-4wtzv\" ","response":"range_response_count:1 size:7842"} {"level":"info","ts":"2026-04-15T04:22:17.784038Z","caller":"traceutil/trace.go:171","msg":"trace[878256693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4323; }","duration":"2.004100335s","start":"2026-04-15T04:22:15.779922Z","end":"2026-04-15T04:22:17.784022Z","steps":["trace[878256693] 'agreement among raft nodes before linearized reading' (duration: 2.003983014s)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:17.784101Z","caller":"traceutil/trace.go:171","msg":"trace[168755328] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-4wtzv; range_end:; response_count:1; response_revision:4323; }","duration":"1.912007244s","start":"2026-04-15T04:22:15.872081Z","end":"2026-04-15T04:22:17.784088Z","steps":["trace[168755328] 'agreement among raft nodes before linearized reading' (duration: 1.911652402s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.784107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.7799Z","time spent":"2.004198355s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T04:22:17.784138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:15.872057Z","time spent":"1.912069526s","remote":"127.0.0.1:55530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":7864,"request content":"key:\"/registry/pods/openstack/keystone-credential-setup-4wtzv\" "} {"level":"warn","ts":"2026-04-15T04:22:17.78395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.672823Z","time spent":"1.11111978s","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T04:22:17.783204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.547792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T04:22:17.784217Z","caller":"traceutil/trace.go:171","msg":"trace[1398276823] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4323; }","duration":"801.669159ms","start":"2026-04-15T04:22:16.982537Z","end":"2026-04-15T04:22:17.784206Z","steps":["trace[1398276823] 'agreement among raft nodes before linearized reading' (duration: 800.627711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.784255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.982478Z","time spent":"801.765041ms","remote":"127.0.0.1:55706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T04:22:17.784296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255494925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-15T04:22:17.784333Z","caller":"traceutil/trace.go:171","msg":"trace[1764919124] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:4323; }","duration":"1.255574545s","start":"2026-04-15T04:22:16.528748Z","end":"2026-04-15T04:22:17.784322Z","steps":["trace[1764919124] 'agreement among raft nodes before linearized reading' (duration: 1.254171515s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:17.784363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:22:16.528722Z","time spent":"1.255633505s","remote":"127.0.0.1:55356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.155\" "} {"level":"warn","ts":"2026-04-15T04:22:18.029287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.331692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T04:22:18.02953Z","caller":"traceutil/trace.go:171","msg":"trace[1483034549] transaction","detail":"{read_only:false; response_revision:4324; number_of_response:1; }","duration":"239.528488ms","start":"2026-04-15T04:22:17.789976Z","end":"2026-04-15T04:22:18.029505Z","steps":["trace[1483034549] 'process raft request' (duration: 132.757682ms)","trace[1483034549] 'compare' (duration: 106.145621ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:22:18.030201Z","caller":"traceutil/trace.go:171","msg":"trace[264336985] transaction","detail":"{read_only:false; response_revision:4325; number_of_response:1; }","duration":"238.851152ms","start":"2026-04-15T04:22:17.791317Z","end":"2026-04-15T04:22:18.030168Z","steps":["trace[264336985] 'process raft request' (duration: 238.106126ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.031029Z","caller":"traceutil/trace.go:171","msg":"trace[2060456312] transaction","detail":"{read_only:false; response_revision:4327; number_of_response:1; }","duration":"238.827761ms","start":"2026-04-15T04:22:17.792186Z","end":"2026-04-15T04:22:18.031014Z","steps":["trace[2060456312] 'process raft request' (duration: 238.73804ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.031052Z","caller":"traceutil/trace.go:171","msg":"trace[1256440798] transaction","detail":"{read_only:false; response_revision:4326; number_of_response:1; }","duration":"239.183074ms","start":"2026-04-15T04:22:17.791847Z","end":"2026-04-15T04:22:18.03103Z","steps":["trace[1256440798] 'process raft request' (duration: 238.251877ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.031326Z","caller":"traceutil/trace.go:171","msg":"trace[368389237] transaction","detail":"{read_only:false; response_revision:4328; number_of_response:1; }","duration":"237.960583ms","start":"2026-04-15T04:22:17.793345Z","end":"2026-04-15T04:22:18.031305Z","steps":["trace[368389237] 'process raft request' (duration: 237.622871ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.032164Z","caller":"traceutil/trace.go:171","msg":"trace[1914597493] transaction","detail":"{read_only:false; response_revision:4330; number_of_response:1; }","duration":"237.25412ms","start":"2026-04-15T04:22:17.794899Z","end":"2026-04-15T04:22:18.032153Z","steps":["trace[1914597493] 'process raft request' (duration: 237.121129ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.032275Z","caller":"traceutil/trace.go:171","msg":"trace[1099947701] transaction","detail":"{read_only:false; response_revision:4331; number_of_response:1; }","duration":"235.864648ms","start":"2026-04-15T04:22:17.796388Z","end":"2026-04-15T04:22:18.032253Z","steps":["trace[1099947701] 'process raft request' (duration: 235.693736ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.032316Z","caller":"traceutil/trace.go:171","msg":"trace[1670615370] transaction","detail":"{read_only:false; response_revision:4329; number_of_response:1; }","duration":"237.859604ms","start":"2026-04-15T04:22:17.794444Z","end":"2026-04-15T04:22:18.032304Z","steps":["trace[1670615370] 'process raft request' (duration: 236.764525ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.032239Z","caller":"traceutil/trace.go:171","msg":"trace[976929940] linearizableReadLoop","detail":"{readStateIndex:4491; appliedIndex:4483; }","duration":"227.997136ms","start":"2026-04-15T04:22:17.804209Z","end":"2026-04-15T04:22:18.032206Z","steps":["trace[976929940] 'read index received' (duration: 118.542939ms)","trace[976929940] 'applied index is now lower than readState.Index' (duration: 109.452477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:22:18.032629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.454791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-15T04:22:18.032688Z","caller":"traceutil/trace.go:171","msg":"trace[1545868443] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4332; }","duration":"242.576332ms","start":"2026-04-15T04:22:17.790099Z","end":"2026-04-15T04:22:18.032676Z","steps":["trace[1545868443] 'agreement among raft nodes before linearized reading' (duration: 242.209539ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.032674Z","caller":"traceutil/trace.go:171","msg":"trace[447408945] transaction","detail":"{read_only:false; response_revision:4332; number_of_response:1; }","duration":"227.518831ms","start":"2026-04-15T04:22:17.80498Z","end":"2026-04-15T04:22:18.032499Z","steps":["trace[447408945] 'process raft request' (duration: 227.177969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:18.032831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.227321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:22:18.032981Z","caller":"traceutil/trace.go:171","msg":"trace[1742840803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4333; }","duration":"231.459134ms","start":"2026-04-15T04:22:17.801506Z","end":"2026-04-15T04:22:18.032965Z","steps":["trace[1742840803] 'agreement among raft nodes before linearized reading' (duration: 231.034341ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.032841Z","caller":"traceutil/trace.go:171","msg":"trace[1857747307] transaction","detail":"{read_only:false; response_revision:4334; number_of_response:1; }","duration":"103.972403ms","start":"2026-04-15T04:22:17.928855Z","end":"2026-04-15T04:22:18.032827Z","steps":["trace[1857747307] 'process raft request' (duration: 103.889342ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:18.032847Z","caller":"traceutil/trace.go:171","msg":"trace[1346476685] transaction","detail":"{read_only:false; response_revision:4333; number_of_response:1; }","duration":"225.487256ms","start":"2026-04-15T04:22:17.80734Z","end":"2026-04-15T04:22:18.032827Z","steps":["trace[1346476685] 'process raft request' (duration: 224.930552ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:19.291177Z","caller":"traceutil/trace.go:171","msg":"trace[1147653654] linearizableReadLoop","detail":"{readStateIndex:4498; appliedIndex:4497; }","duration":"171.359938ms","start":"2026-04-15T04:22:19.11979Z","end":"2026-04-15T04:22:19.29115Z","steps":["trace[1147653654] 'read index received' (duration: 96.726067ms)","trace[1147653654] 'applied index is now lower than readState.Index' (duration: 74.632381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:22:19.291551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.74096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-15T04:22:19.291595Z","caller":"traceutil/trace.go:171","msg":"trace[1359203201] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4338; }","duration":"171.902271ms","start":"2026-04-15T04:22:19.119682Z","end":"2026-04-15T04:22:19.291584Z","steps":["trace[1359203201] 'agreement among raft nodes before linearized reading' (duration: 171.572548ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:19.291581Z","caller":"traceutil/trace.go:171","msg":"trace[773072243] transaction","detail":"{read_only:false; response_revision:4338; number_of_response:1; }","duration":"171.909622ms","start":"2026-04-15T04:22:19.119631Z","end":"2026-04-15T04:22:19.29154Z","steps":["trace[773072243] 'process raft request' (duration: 96.928528ms)","trace[773072243] 'compare' (duration: 74.45568ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:22:19.664014Z","caller":"traceutil/trace.go:171","msg":"trace[548094084] transaction","detail":"{read_only:false; response_revision:4342; number_of_response:1; }","duration":"174.736963ms","start":"2026-04-15T04:22:19.489247Z","end":"2026-04-15T04:22:19.663984Z","steps":["trace[548094084] 'process raft request' (duration: 110.311683ms)","trace[548094084] 'compare' (duration: 64.184299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:22:20.017068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.213556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-15T04:22:20.017193Z","caller":"traceutil/trace.go:171","msg":"trace[1278236186] linearizableReadLoop","detail":"{readStateIndex:4504; appliedIndex:4503; }","duration":"226.233501ms","start":"2026-04-15T04:22:19.790939Z","end":"2026-04-15T04:22:20.017173Z","steps":["trace[1278236186] 'read index received' (duration: 59.987414ms)","trace[1278236186] 'applied index is now lower than readState.Index' (duration: 166.244807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:22:20.017319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.372782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:22:20.017296Z","caller":"traceutil/trace.go:171","msg":"trace[1255894101] transaction","detail":"{read_only:false; response_revision:4344; number_of_response:1; }","duration":"280.706314ms","start":"2026-04-15T04:22:19.736545Z","end":"2026-04-15T04:22:20.017251Z","steps":["trace[1255894101] 'process raft request' (duration: 114.230785ms)","trace[1255894101] 'compare' (duration: 166.021385ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:22:20.017357Z","caller":"traceutil/trace.go:171","msg":"trace[691740839] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:4344; }","duration":"226.463833ms","start":"2026-04-15T04:22:19.790882Z","end":"2026-04-15T04:22:20.017346Z","steps":["trace[691740839] 'agreement among raft nodes before linearized reading' (duration: 226.373643ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.067592Z","caller":"traceutil/trace.go:171","msg":"trace[1244722421] transaction","detail":"{read_only:false; response_revision:4346; number_of_response:1; }","duration":"274.886177ms","start":"2026-04-15T04:22:19.79267Z","end":"2026-04-15T04:22:20.067556Z","steps":["trace[1244722421] 'process raft request' (duration: 274.800726ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.067952Z","caller":"traceutil/trace.go:171","msg":"trace[810115001] transaction","detail":"{read_only:false; response_revision:4347; number_of_response:1; }","duration":"275.154449ms","start":"2026-04-15T04:22:19.792768Z","end":"2026-04-15T04:22:20.067923Z","steps":["trace[810115001] 'process raft request' (duration: 274.744626ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.068395Z","caller":"traceutil/trace.go:171","msg":"trace[1396205564] transaction","detail":"{read_only:false; response_revision:4345; number_of_response:1; }","duration":"276.195378ms","start":"2026-04-15T04:22:19.791932Z","end":"2026-04-15T04:22:20.068128Z","steps":["trace[1396205564] 'process raft request' (duration: 275.31106ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.068533Z","caller":"traceutil/trace.go:171","msg":"trace[1552968052] transaction","detail":"{read_only:false; response_revision:4348; number_of_response:1; }","duration":"275.270361ms","start":"2026-04-15T04:22:19.793196Z","end":"2026-04-15T04:22:20.068467Z","steps":["trace[1552968052] 'process raft request' (duration: 274.624575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:20.068578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.898543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:22:20.068645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.224528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6181"} {"level":"info","ts":"2026-04-15T04:22:20.068661Z","caller":"traceutil/trace.go:171","msg":"trace[167551575] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:4348; }","duration":"277.018484ms","start":"2026-04-15T04:22:19.791625Z","end":"2026-04-15T04:22:20.068643Z","steps":["trace[167551575] 'agreement among raft nodes before linearized reading' (duration: 276.877853ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.068705Z","caller":"traceutil/trace.go:171","msg":"trace[749657395] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:4348; }","duration":"276.352059ms","start":"2026-04-15T04:22:19.792338Z","end":"2026-04-15T04:22:20.06869Z","steps":["trace[749657395] 'agreement among raft nodes before linearized reading' (duration: 276.159988ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.264471Z","caller":"traceutil/trace.go:171","msg":"trace[634179939] linearizableReadLoop","detail":"{readStateIndex:4511; appliedIndex:4508; }","duration":"196.063683ms","start":"2026-04-15T04:22:20.068391Z","end":"2026-04-15T04:22:20.264455Z","steps":["trace[634179939] 'read index received' (duration: 125.590864ms)","trace[634179939] 'applied index is now lower than readState.Index' (duration: 70.471389ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:22:20.264793Z","caller":"traceutil/trace.go:171","msg":"trace[1519544782] transaction","detail":"{read_only:false; response_revision:4350; number_of_response:1; }","duration":"241.623604ms","start":"2026-04-15T04:22:20.023155Z","end":"2026-04-15T04:22:20.264778Z","steps":["trace[1519544782] 'process raft request' (duration: 241.18497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:20.26494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.397166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-15T04:22:20.264997Z","caller":"traceutil/trace.go:171","msg":"trace[1489486987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4351; }","duration":"225.486776ms","start":"2026-04-15T04:22:20.039498Z","end":"2026-04-15T04:22:20.264984Z","steps":["trace[1489486987] 'agreement among raft nodes before linearized reading' (duration: 225.324565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:20.265265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.802688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T04:22:20.266175Z","caller":"traceutil/trace.go:171","msg":"trace[1687100450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4351; }","duration":"231.749005ms","start":"2026-04-15T04:22:20.034406Z","end":"2026-04-15T04:22:20.266155Z","steps":["trace[1687100450] 'agreement among raft nodes before linearized reading' (duration: 230.693297ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.265329Z","caller":"traceutil/trace.go:171","msg":"trace[28850067] transaction","detail":"{read_only:false; response_revision:4351; number_of_response:1; }","duration":"240.537755ms","start":"2026-04-15T04:22:20.024779Z","end":"2026-04-15T04:22:20.265316Z","steps":["trace[28850067] 'process raft request' (duration: 239.617088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:20.265413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.552528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T04:22:20.266468Z","caller":"traceutil/trace.go:171","msg":"trace[352071836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4351; }","duration":"225.627576ms","start":"2026-04-15T04:22:20.040831Z","end":"2026-04-15T04:22:20.266458Z","steps":["trace[352071836] 'agreement among raft nodes before linearized reading' (duration: 224.520118ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.264507Z","caller":"traceutil/trace.go:171","msg":"trace[245896364] transaction","detail":"{read_only:false; response_revision:4349; number_of_response:1; }","duration":"242.520241ms","start":"2026-04-15T04:22:20.021933Z","end":"2026-04-15T04:22:20.264453Z","steps":["trace[245896364] 'process raft request' (duration: 172.068862ms)","trace[245896364] 'compare' (duration: 70.237757ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:22:20.265683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.942452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:22:20.266765Z","caller":"traceutil/trace.go:171","msg":"trace[1002664949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4351; }","duration":"197.050271ms","start":"2026-04-15T04:22:20.069704Z","end":"2026-04-15T04:22:20.266755Z","steps":["trace[1002664949] 'agreement among raft nodes before linearized reading' (duration: 195.941472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:20.265663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.563066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-15T04:22:20.267029Z","caller":"traceutil/trace.go:171","msg":"trace[531645375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4351; }","duration":"227.022479ms","start":"2026-04-15T04:22:20.039998Z","end":"2026-04-15T04:22:20.267021Z","steps":["trace[531645375] 'agreement among raft nodes before linearized reading' (duration: 225.431996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:20.26581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.254403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-15T04:22:20.268119Z","caller":"traceutil/trace.go:171","msg":"trace[1444091507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4351; }","duration":"227.577092ms","start":"2026-04-15T04:22:20.040532Z","end":"2026-04-15T04:22:20.268109Z","steps":["trace[1444091507] 'agreement among raft nodes before linearized reading' (duration: 225.198633ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.438786Z","caller":"traceutil/trace.go:171","msg":"trace[1920682740] transaction","detail":"{read_only:false; response_revision:4353; number_of_response:1; }","duration":"165.42874ms","start":"2026-04-15T04:22:20.273305Z","end":"2026-04-15T04:22:20.438733Z","steps":["trace[1920682740] 'process raft request' (duration: 94.090195ms)","trace[1920682740] 'compare' (duration: 71.086223ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:22:20.438841Z","caller":"traceutil/trace.go:171","msg":"trace[345854137] transaction","detail":"{read_only:false; response_revision:4354; number_of_response:1; }","duration":"165.39622ms","start":"2026-04-15T04:22:20.27341Z","end":"2026-04-15T04:22:20.438807Z","steps":["trace[345854137] 'process raft request' (duration: 165.259459ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.438938Z","caller":"traceutil/trace.go:171","msg":"trace[374742547] transaction","detail":"{read_only:false; response_revision:4355; number_of_response:1; }","duration":"165.289239ms","start":"2026-04-15T04:22:20.27362Z","end":"2026-04-15T04:22:20.438909Z","steps":["trace[374742547] 'process raft request' (duration: 165.116117ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.439111Z","caller":"traceutil/trace.go:171","msg":"trace[818162998] transaction","detail":"{read_only:false; response_revision:4356; number_of_response:1; }","duration":"165.40329ms","start":"2026-04-15T04:22:20.273666Z","end":"2026-04-15T04:22:20.439069Z","steps":["trace[818162998] 'process raft request' (duration: 165.110998ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.439111Z","caller":"traceutil/trace.go:171","msg":"trace[2044390464] transaction","detail":"{read_only:false; response_revision:4357; number_of_response:1; }","duration":"164.706665ms","start":"2026-04-15T04:22:20.274371Z","end":"2026-04-15T04:22:20.439077Z","steps":["trace[2044390464] 'process raft request' (duration: 164.447843ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:20.454227Z","caller":"traceutil/trace.go:171","msg":"trace[984397944] transaction","detail":"{read_only:false; response_revision:4358; number_of_response:1; }","duration":"154.329232ms","start":"2026-04-15T04:22:20.299849Z","end":"2026-04-15T04:22:20.454178Z","steps":["trace[984397944] 'process raft request' (duration: 154.161921ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:48.209831Z","caller":"traceutil/trace.go:171","msg":"trace[1097987327] linearizableReadLoop","detail":"{readStateIndex:4807; appliedIndex:4806; }","duration":"117.396029ms","start":"2026-04-15T04:22:48.092409Z","end":"2026-04-15T04:22:48.209805Z","steps":["trace[1097987327] 'read index received' (duration: 77.414483ms)","trace[1097987327] 'applied index is now lower than readState.Index' (duration: 39.980496ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:22:48.210101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.666121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8242"} {"level":"info","ts":"2026-04-15T04:22:48.210146Z","caller":"traceutil/trace.go:171","msg":"trace[1388981871] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:4638; }","duration":"117.751513ms","start":"2026-04-15T04:22:48.092384Z","end":"2026-04-15T04:22:48.210135Z","steps":["trace[1388981871] 'agreement among raft nodes before linearized reading' (duration: 117.528701ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:48.210157Z","caller":"traceutil/trace.go:171","msg":"trace[1666731163] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"145.188609ms","start":"2026-04-15T04:22:48.064937Z","end":"2026-04-15T04:22:48.210126Z","steps":["trace[1666731163] 'process raft request' (duration: 104.9333ms)","trace[1666731163] 'compare' (duration: 39.813055ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:22:48.255373Z","caller":"traceutil/trace.go:171","msg":"trace[1209424074] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"141.250948ms","start":"2026-04-15T04:22:48.114089Z","end":"2026-04-15T04:22:48.25534Z","steps":["trace[1209424074] 'process raft request' (duration: 141.073667ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:48.34022Z","caller":"traceutil/trace.go:171","msg":"trace[539197996] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"139.560245ms","start":"2026-04-15T04:22:48.200604Z","end":"2026-04-15T04:22:48.340164Z","steps":["trace[539197996] 'process raft request' (duration: 139.520035ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:48.340274Z","caller":"traceutil/trace.go:171","msg":"trace[677146813] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"150.41277ms","start":"2026-04-15T04:22:48.189817Z","end":"2026-04-15T04:22:48.340229Z","steps":["trace[677146813] 'process raft request' (duration: 149.992277ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:48.340616Z","caller":"traceutil/trace.go:171","msg":"trace[526034099] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"147.79205ms","start":"2026-04-15T04:22:48.19281Z","end":"2026-04-15T04:22:48.340602Z","steps":["trace[526034099] 'process raft request' (duration: 147.221256ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:48.340795Z","caller":"traceutil/trace.go:171","msg":"trace[529669345] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"146.713461ms","start":"2026-04-15T04:22:48.194068Z","end":"2026-04-15T04:22:48.340782Z","steps":["trace[529669345] 'process raft request' (duration: 146.020605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:23:31.275002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.778889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:23:31.275165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.03236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-15T04:23:31.275256Z","caller":"traceutil/trace.go:171","msg":"trace[1865447925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5494; }","duration":"149.174031ms","start":"2026-04-15T04:23:31.126062Z","end":"2026-04-15T04:23:31.275236Z","steps":["trace[1865447925] 'range keys from in-memory index tree' (duration: 148.833538ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:31.275125Z","caller":"traceutil/trace.go:171","msg":"trace[1190804194] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:5494; }","duration":"137.951371ms","start":"2026-04-15T04:23:31.137139Z","end":"2026-04-15T04:23:31.275091Z","steps":["trace[1190804194] 'count revisions from in-memory index tree' (duration: 137.694559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:23:31.275144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.007143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-15T04:23:31.275479Z","caller":"traceutil/trace.go:171","msg":"trace[704708726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5494; }","duration":"142.364206ms","start":"2026-04-15T04:23:31.133056Z","end":"2026-04-15T04:23:31.27542Z","steps":["trace[704708726] 'range keys from in-memory index tree' (duration: 141.788801ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:31.28956Z","caller":"traceutil/trace.go:171","msg":"trace[1308213864] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"132.587889ms","start":"2026-04-15T04:23:31.156949Z","end":"2026-04-15T04:23:31.289537Z","steps":["trace[1308213864] 'process raft request' (duration: 132.365048ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:53.570551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2921} {"level":"info","ts":"2026-04-15T04:23:53.721656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2921,"took":"149.056679ms","hash":1245815431,"current-db-size-bytes":54579200,"current-db-size":"55 MB","current-db-size-in-use-bytes":30904320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-15T04:23:53.721722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1245815431,"revision":2921,"compact-revision":-1}