{"level":"warn","ts":"2026-07-08T15:22:01.536973Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T15:22:01.537074Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.223: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.19.213.223:2380","--initial-cluster=instance=https://199.19.213.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.223: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-07-08T15:22:01.537141Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T15:22:01.537158Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-07-08T15:22:01.537181Z","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-07-08T15:22:01.53781Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"]} {"level":"info","ts":"2026-07-08T15:22:01.537892Z","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":16,"max-cpu-available":16,"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.19.213.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.223: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-07-08T15:22:01.542575Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.473715ms"} {"level":"info","ts":"2026-07-08T15:22:01.549411Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"12dd93b2fab6fcb","cluster-id":"8ac3bdb9c2c26b53"} {"level":"info","ts":"2026-07-08T15:22:01.549616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T15:22:01.549672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 0"} {"level":"info","ts":"2026-07-08T15:22:01.5497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 12dd93b2fab6fcb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T15:22:01.549732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 1"} {"level":"info","ts":"2026-07-08T15:22:01.549802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"warn","ts":"2026-07-08T15:22:01.55632Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T15:22:01.558068Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T15:22:01.559118Z","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-07-08T15:22:01.560962Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"12dd93b2fab6fcb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T15:22:01.561255Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"12dd93b2fab6fcb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T15:22:01.561345Z","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-07-08T15:22:01.561674Z","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-07-08T15:22:01.561711Z","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-07-08T15:22:01.56258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"info","ts":"2026-07-08T15:22:01.563507Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","added-peer-id":"12dd93b2fab6fcb","added-peer-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-07-08T15:22:01.565323Z","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-07-08T15:22:01.565442Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-07-08T15:22:01.56551Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-07-08T15:22:01.565801Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"12dd93b2fab6fcb","initial-advertise-peer-urls":["https://199.19.213.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T15:22:01.565875Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T15:22:01.951109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T15:22:01.9512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T15:22:01.951247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgPreVoteResp from 12dd93b2fab6fcb at term 1"} {"level":"info","ts":"2026-07-08T15:22:01.951273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became candidate at term 2"} {"level":"info","ts":"2026-07-08T15:22:01.951296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgVoteResp from 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-07-08T15:22:01.951313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became leader at term 2"} {"level":"info","ts":"2026-07-08T15:22:01.951338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 12dd93b2fab6fcb elected leader 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-07-08T15:22:01.952566Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"12dd93b2fab6fcb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.223:2379]}","request-path":"/0/members/12dd93b2fab6fcb/attributes","cluster-id":"8ac3bdb9c2c26b53","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T15:22:01.952623Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:22:01.952639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T15:22:01.952674Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T15:22:01.952914Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T15:22:01.952991Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T15:22:01.953483Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:22:01.953577Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:22:01.953607Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:22:01.95489Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.223:2379"} {"level":"info","ts":"2026-07-08T15:22:01.956208Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T15:22:54.867346Z","caller":"traceutil/trace.go:171","msg":"trace[360521932] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"138.337403ms","start":"2026-07-08T15:22:54.728984Z","end":"2026-07-08T15:22:54.867321Z","steps":["trace[360521932] 'read index received' (duration: 137.901164ms)","trace[360521932] 'applied index is now lower than readState.Index' (duration: 435.499µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:22:54.867888Z","caller":"traceutil/trace.go:171","msg":"trace[493561362] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"168.740095ms","start":"2026-07-08T15:22:54.699129Z","end":"2026-07-08T15:22:54.867869Z","steps":["trace[493561362] 'process raft request' (duration: 167.93001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:22:54.868461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.947017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:22:54.868544Z","caller":"traceutil/trace.go:171","msg":"trace[802175851] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:669; }","duration":"102.09518ms","start":"2026-07-08T15:22:54.766431Z","end":"2026-07-08T15:22:54.868526Z","steps":["trace[802175851] 'agreement among raft nodes before linearized reading' (duration: 101.939498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:22:54.870179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.189376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-07-08T15:22:54.870209Z","caller":"traceutil/trace.go:171","msg":"trace[936214602] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:669; }","duration":"141.246758ms","start":"2026-07-08T15:22:54.728953Z","end":"2026-07-08T15:22:54.8702Z","steps":["trace[936214602] 'agreement among raft nodes before linearized reading' (duration: 139.166468ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:22:55.04739Z","caller":"traceutil/trace.go:171","msg":"trace[1949011800] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"153.988007ms","start":"2026-07-08T15:22:54.893374Z","end":"2026-07-08T15:22:55.047362Z","steps":["trace[1949011800] 'read index received' (duration: 153.834115ms)","trace[1949011800] 'applied index is now lower than readState.Index' (duration: 152.652µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:22:55.04752Z","caller":"traceutil/trace.go:171","msg":"trace[1242340536] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"154.494817ms","start":"2026-07-08T15:22:54.892997Z","end":"2026-07-08T15:22:55.047492Z","steps":["trace[1242340536] 'process raft request' (duration: 154.202711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:22:55.047684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.272593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:18056"} {"level":"info","ts":"2026-07-08T15:22:55.047757Z","caller":"traceutil/trace.go:171","msg":"trace[668204199] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:670; }","duration":"154.381874ms","start":"2026-07-08T15:22:54.893352Z","end":"2026-07-08T15:22:55.047734Z","steps":["trace[668204199] 'agreement among raft nodes before linearized reading' (duration: 154.16521ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:23:00.437116Z","caller":"traceutil/trace.go:171","msg":"trace[182708872] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"106.365691ms","start":"2026-07-08T15:23:00.330718Z","end":"2026-07-08T15:23:00.437084Z","steps":["trace[182708872] 'process raft request' (duration: 105.702498ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:23:00.823022Z","caller":"traceutil/trace.go:171","msg":"trace[1287261786] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"233.653326ms","start":"2026-07-08T15:23:00.589344Z","end":"2026-07-08T15:23:00.822998Z","steps":["trace[1287261786] 'process raft request' (duration: 185.640293ms)","trace[1287261786] 'compare' (duration: 47.870651ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:23:01.333785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.795847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rmtzz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-08T15:23:01.333857Z","caller":"traceutil/trace.go:171","msg":"trace[472036402] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rmtzz; range_end:; response_count:1; response_revision:727; }","duration":"260.944299ms","start":"2026-07-08T15:23:01.072897Z","end":"2026-07-08T15:23:01.333842Z","steps":["trace[472036402] 'range keys from in-memory index tree' (duration: 260.528992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:23:46.087909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.054762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T15:23:46.088085Z","caller":"traceutil/trace.go:171","msg":"trace[1225586167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"176.316556ms","start":"2026-07-08T15:23:45.911736Z","end":"2026-07-08T15:23:46.088052Z","steps":["trace[1225586167] 'range keys from in-memory index tree' (duration: 175.778387ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:24:07.634659Z","caller":"traceutil/trace.go:171","msg":"trace[658426548] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"100.898098ms","start":"2026-07-08T15:24:07.533733Z","end":"2026-07-08T15:24:07.634631Z","steps":["trace[658426548] 'process raft request' (duration: 100.699094ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:24:31.738666Z","caller":"traceutil/trace.go:171","msg":"trace[1026405187] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"160.746363ms","start":"2026-07-08T15:24:31.577891Z","end":"2026-07-08T15:24:31.738637Z","steps":["trace[1026405187] 'process raft request' (duration: 160.479828ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:24:32.15412Z","caller":"traceutil/trace.go:171","msg":"trace[167630185] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"245.85005ms","start":"2026-07-08T15:24:31.908232Z","end":"2026-07-08T15:24:32.154082Z","steps":["trace[167630185] 'process raft request' (duration: 245.632445ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:24:32.155324Z","caller":"traceutil/trace.go:171","msg":"trace[648036286] linearizableReadLoop","detail":"{readStateIndex:1996; appliedIndex:1993; }","duration":"117.424879ms","start":"2026-07-08T15:24:32.037872Z","end":"2026-07-08T15:24:32.155297Z","steps":["trace[648036286] 'read index received' (duration: 115.954411ms)","trace[648036286] 'applied index is now lower than readState.Index' (duration: 1.469378ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:24:32.155626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.741117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-08T15:24:32.155643Z","caller":"traceutil/trace.go:171","msg":"trace[707820204] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"127.349229ms","start":"2026-07-08T15:24:32.028275Z","end":"2026-07-08T15:24:32.155624Z","steps":["trace[707820204] 'process raft request' (duration: 126.957841ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:24:32.155673Z","caller":"traceutil/trace.go:171","msg":"trace[542167717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1918; }","duration":"117.843309ms","start":"2026-07-08T15:24:32.037815Z","end":"2026-07-08T15:24:32.155658Z","steps":["trace[542167717] 'agreement among raft nodes before linearized reading' (duration: 117.587403ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:24:32.15565Z","caller":"traceutil/trace.go:171","msg":"trace[593062490] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"246.42826ms","start":"2026-07-08T15:24:31.909034Z","end":"2026-07-08T15:24:32.155462Z","steps":["trace[593062490] 'process raft request' (duration: 246.055093ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:24:44.641392Z","caller":"traceutil/trace.go:171","msg":"trace[462756836] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"162.169511ms","start":"2026-07-08T15:24:44.479193Z","end":"2026-07-08T15:24:44.641363Z","steps":["trace[462756836] 'process raft request' (duration: 162.005008ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:25:22.259098Z","caller":"traceutil/trace.go:171","msg":"trace[48166305] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"176.356979ms","start":"2026-07-08T15:25:22.08271Z","end":"2026-07-08T15:25:22.259067Z","steps":["trace[48166305] 'process raft request' (duration: 173.565766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:25:22.524304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.133595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:25:22.52457Z","caller":"traceutil/trace.go:171","msg":"trace[916880806] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"150.374365ms","start":"2026-07-08T15:25:22.374144Z","end":"2026-07-08T15:25:22.524518Z","steps":["trace[916880806] 'process raft request' (duration: 33.332812ms)","trace[916880806] 'compare' (duration: 115.909142ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:26:11.43593Z","caller":"traceutil/trace.go:171","msg":"trace[2138639443] linearizableReadLoop","detail":"{readStateIndex:2786; appliedIndex:2785; }","duration":"132.323103ms","start":"2026-07-08T15:26:11.303582Z","end":"2026-07-08T15:26:11.435905Z","steps":["trace[2138639443] 'read index received' (duration: 132.056238ms)","trace[2138639443] 'applied index is now lower than readState.Index' (duration: 266.015µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:26:11.436045Z","caller":"traceutil/trace.go:171","msg":"trace[1234703472] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"193.308352ms","start":"2026-07-08T15:26:11.242693Z","end":"2026-07-08T15:26:11.436001Z","steps":["trace[1234703472] 'process raft request' (duration: 193.025457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:26:11.436095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.489306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:26:11.436146Z","caller":"traceutil/trace.go:171","msg":"trace[1896508394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2680; }","duration":"132.557368ms","start":"2026-07-08T15:26:11.303575Z","end":"2026-07-08T15:26:11.436133Z","steps":["trace[1896508394] 'agreement among raft nodes before linearized reading' (duration: 132.468536ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:26:44.525643Z","caller":"traceutil/trace.go:171","msg":"trace[806843561] transaction","detail":"{read_only:false; response_revision:2877; number_of_response:1; }","duration":"114.16999ms","start":"2026-07-08T15:26:44.411429Z","end":"2026-07-08T15:26:44.525599Z","steps":["trace[806843561] 'process raft request' (duration: 113.828503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:26:44.88876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.366302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:26:44.88885Z","caller":"traceutil/trace.go:171","msg":"trace[115107733] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2877; }","duration":"237.515595ms","start":"2026-07-08T15:26:44.651315Z","end":"2026-07-08T15:26:44.888831Z","steps":["trace[115107733] 'count revisions from in-memory index tree' (duration: 237.284041ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:26:55.896393Z","caller":"traceutil/trace.go:171","msg":"trace[1068449074] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"134.335744ms","start":"2026-07-08T15:26:55.762029Z","end":"2026-07-08T15:26:55.896364Z","steps":["trace[1068449074] 'process raft request' (duration: 134.158941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:26.445726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.036139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:29:26.445835Z","caller":"traceutil/trace.go:171","msg":"trace[269991396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3933; }","duration":"142.221843ms","start":"2026-07-08T15:29:26.303591Z","end":"2026-07-08T15:29:26.445812Z","steps":["trace[269991396] 'range keys from in-memory index tree' (duration: 141.913046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:29:26.445877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.261267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:29:26.446026Z","caller":"traceutil/trace.go:171","msg":"trace[202061235] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3933; }","duration":"138.46716ms","start":"2026-07-08T15:29:26.307527Z","end":"2026-07-08T15:29:26.445994Z","steps":["trace[202061235] 'range keys from in-memory index tree' (duration: 138.189735ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:30:56.358462Z","caller":"traceutil/trace.go:171","msg":"trace[967173056] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"116.633228ms","start":"2026-07-08T15:30:56.241794Z","end":"2026-07-08T15:30:56.358428Z","steps":["trace[967173056] 'process raft request' (duration: 46.657627ms)","trace[967173056] 'compare' (duration: 69.513033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:30:58.043307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.821878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:30:58.043417Z","caller":"traceutil/trace.go:171","msg":"trace[926424895] linearizableReadLoop","detail":"{readStateIndex:5349; appliedIndex:5348; }","duration":"204.062142ms","start":"2026-07-08T15:30:57.839337Z","end":"2026-07-08T15:30:58.043399Z","steps":["trace[926424895] 'read index received' (duration: 20.091312ms)","trace[926424895] 'applied index is now lower than readState.Index' (duration: 183.96944ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:30:58.043444Z","caller":"traceutil/trace.go:171","msg":"trace[1368765511] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"222.518683ms","start":"2026-07-08T15:30:57.820889Z","end":"2026-07-08T15:30:58.043408Z","steps":["trace[1368765511] 'process raft request' (duration: 38.502233ms)","trace[1368765511] 'compare' (duration: 183.616823ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:30:58.043528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.197555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:30:58.043592Z","caller":"traceutil/trace.go:171","msg":"trace[1111365072] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5153; }","duration":"204.324497ms","start":"2026-07-08T15:30:57.839255Z","end":"2026-07-08T15:30:58.043579Z","steps":["trace[1111365072] 'agreement among raft nodes before linearized reading' (duration: 204.226136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:10.605442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.74656ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T15:31:10.606128Z","caller":"traceutil/trace.go:171","msg":"trace[380208998] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"337.046213ms","start":"2026-07-08T15:31:10.269051Z","end":"2026-07-08T15:31:10.606097Z","steps":["trace[380208998] 'process raft request' (duration: 336.551044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:10.606279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:10.269024Z","time spent":"337.190556ms","remote":"127.0.0.1:57662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1005,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:31:10.609444Z","caller":"traceutil/trace.go:171","msg":"trace[699336590] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"196.58243ms","start":"2026-07-08T15:31:10.412846Z","end":"2026-07-08T15:31:10.609428Z","steps":["trace[699336590] 'process raft request' (duration: 196.489778ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:10.609615Z","caller":"traceutil/trace.go:171","msg":"trace[1201440949] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"339.949487ms","start":"2026-07-08T15:31:10.269634Z","end":"2026-07-08T15:31:10.609583Z","steps":["trace[1201440949] 'process raft request' (duration: 336.44838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:10.609725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:10.269596Z","time spent":"340.08405ms","remote":"127.0.0.1:57550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":524,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:31:10.610115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.52087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:31:10.610154Z","caller":"traceutil/trace.go:171","msg":"trace[1192899360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5392; }","duration":"298.618682ms","start":"2026-07-08T15:31:10.311525Z","end":"2026-07-08T15:31:10.610144Z","steps":["trace[1192899360] 'agreement among raft nodes before linearized reading' (duration: 298.53567ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:10.610114Z","caller":"traceutil/trace.go:171","msg":"trace[1134587117] linearizableReadLoop","detail":"{readStateIndex:5596; appliedIndex:5593; }","duration":"298.390618ms","start":"2026-07-08T15:31:10.311579Z","end":"2026-07-08T15:31:10.60997Z","steps":["trace[1134587117] 'read index received' (duration: 165.587481ms)","trace[1134587117] 'applied index is now lower than readState.Index' (duration: 132.797617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:10.610404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.488601ms","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-07-08T15:31:10.610464Z","caller":"traceutil/trace.go:171","msg":"trace[1017199294] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:5392; }","duration":"245.566702ms","start":"2026-07-08T15:31:10.364872Z","end":"2026-07-08T15:31:10.610438Z","steps":["trace[1017199294] 'agreement among raft nodes before linearized reading' (duration: 245.43476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:10.611176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.447368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2sbd4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-08T15:31:10.611209Z","caller":"traceutil/trace.go:171","msg":"trace[1465851801] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2sbd4; range_end:; response_count:1; response_revision:5392; }","duration":"104.535759ms","start":"2026-07-08T15:31:10.506667Z","end":"2026-07-08T15:31:10.611203Z","steps":["trace[1465851801] 'agreement among raft nodes before linearized reading' (duration: 104.454468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:10.611167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.549571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:31:10.611338Z","caller":"traceutil/trace.go:171","msg":"trace[294194280] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5392; }","duration":"134.819495ms","start":"2026-07-08T15:31:10.476504Z","end":"2026-07-08T15:31:10.611323Z","steps":["trace[294194280] 'agreement among raft nodes before linearized reading' (duration: 134.593921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:18.747945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.670743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6a87af89-6e91-410f-9104-f30fd8ac9082\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-08T15:31:18.748071Z","caller":"traceutil/trace.go:171","msg":"trace[603455502] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6a87af89-6e91-410f-9104-f30fd8ac9082; range_end:; response_count:1; response_revision:5589; }","duration":"154.859497ms","start":"2026-07-08T15:31:18.593187Z","end":"2026-07-08T15:31:18.748046Z","steps":["trace[603455502] 'range keys from in-memory index tree' (duration: 154.414198ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:18.748199Z","caller":"traceutil/trace.go:171","msg":"trace[571045052] transaction","detail":"{read_only:false; response_revision:5590; number_of_response:1; }","duration":"108.131637ms","start":"2026-07-08T15:31:18.640026Z","end":"2026-07-08T15:31:18.748158Z","steps":["trace[571045052] 'process raft request' (duration: 52.238364ms)","trace[571045052] 'compare' (duration: 55.72699ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:18.781239Z","caller":"traceutil/trace.go:171","msg":"trace[2066823255] linearizableReadLoop","detail":"{readStateIndex:5800; appliedIndex:5798; }","duration":"126.350554ms","start":"2026-07-08T15:31:18.654857Z","end":"2026-07-08T15:31:18.781207Z","steps":["trace[2066823255] 'read index received' (duration: 37.407881ms)","trace[2066823255] 'applied index is now lower than readState.Index' (duration: 88.941762ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:18.781376Z","caller":"traceutil/trace.go:171","msg":"trace[1799860914] transaction","detail":"{read_only:false; response_revision:5591; number_of_response:1; }","duration":"140.82582ms","start":"2026-07-08T15:31:18.640539Z","end":"2026-07-08T15:31:18.781365Z","steps":["trace[1799860914] 'process raft request' (duration: 140.527245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:18.781678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.805253ms","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-07-08T15:31:18.781716Z","caller":"traceutil/trace.go:171","msg":"trace[181003162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5591; }","duration":"126.908394ms","start":"2026-07-08T15:31:18.654798Z","end":"2026-07-08T15:31:18.781707Z","steps":["trace[181003162] 'agreement among raft nodes before linearized reading' (duration: 126.73295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:18.781791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.650797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-08T15:31:18.781946Z","caller":"traceutil/trace.go:171","msg":"trace[751467298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5591; }","duration":"119.86921ms","start":"2026-07-08T15:31:18.66204Z","end":"2026-07-08T15:31:18.781909Z","steps":["trace[751467298] 'agreement among raft nodes before linearized reading' (duration: 119.555415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:18.782055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.060669ms","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-07-08T15:31:18.782141Z","caller":"traceutil/trace.go:171","msg":"trace[1097041497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5591; }","duration":"115.212582ms","start":"2026-07-08T15:31:18.666908Z","end":"2026-07-08T15:31:18.782121Z","steps":["trace[1097041497] 'agreement among raft nodes before linearized reading' (duration: 114.816305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:18.782137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.603721ms","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-07-08T15:31:18.782201Z","caller":"traceutil/trace.go:171","msg":"trace[554476853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5591; }","duration":"113.729013ms","start":"2026-07-08T15:31:18.668454Z","end":"2026-07-08T15:31:18.782183Z","steps":["trace[554476853] 'agreement among raft nodes before linearized reading' (duration: 113.243834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:18.782231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.321655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2sbd4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-08T15:31:18.782319Z","caller":"traceutil/trace.go:171","msg":"trace[1442861006] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2sbd4; range_end:; response_count:1; response_revision:5591; }","duration":"113.448698ms","start":"2026-07-08T15:31:18.668852Z","end":"2026-07-08T15:31:18.7823Z","steps":["trace[1442861006] 'agreement among raft nodes before linearized reading' (duration: 112.747835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:18.970266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.015517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:31:18.970729Z","caller":"traceutil/trace.go:171","msg":"trace[320461046] linearizableReadLoop","detail":"{readStateIndex:5803; appliedIndex:5800; }","duration":"183.894089ms","start":"2026-07-08T15:31:18.786812Z","end":"2026-07-08T15:31:18.970706Z","steps":["trace[320461046] 'read index received' (duration: 66.25837ms)","trace[320461046] 'applied index is now lower than readState.Index' (duration: 117.634229ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:18.970748Z","caller":"traceutil/trace.go:171","msg":"trace[833612489] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"184.187165ms","start":"2026-07-08T15:31:18.786517Z","end":"2026-07-08T15:31:18.970704Z","steps":["trace[833612489] 'process raft request' (duration: 184.013671ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:18.970842Z","caller":"traceutil/trace.go:171","msg":"trace[512174294] transaction","detail":"{read_only:false; response_revision:5595; number_of_response:1; }","duration":"183.284478ms","start":"2026-07-08T15:31:18.787549Z","end":"2026-07-08T15:31:18.970833Z","steps":["trace[512174294] 'process raft request' (duration: 183.102915ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:18.970823Z","caller":"traceutil/trace.go:171","msg":"trace[1518620875] transaction","detail":"{read_only:false; response_revision:5594; number_of_response:1; }","duration":"184.159094ms","start":"2026-07-08T15:31:18.786601Z","end":"2026-07-08T15:31:18.97076Z","steps":["trace[1518620875] 'process raft request' (duration: 184.001171ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:18.970717Z","caller":"traceutil/trace.go:171","msg":"trace[166372298] transaction","detail":"{read_only:false; response_revision:5592; number_of_response:1; }","duration":"184.760376ms","start":"2026-07-08T15:31:18.785895Z","end":"2026-07-08T15:31:18.970655Z","steps":["trace[166372298] 'process raft request' (duration: 67.417143ms)","trace[166372298] 'compare' (duration: 116.63127ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:18.971163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.342567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18310"} {"level":"info","ts":"2026-07-08T15:31:18.971253Z","caller":"traceutil/trace.go:171","msg":"trace[698146619] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5595; }","duration":"184.45178ms","start":"2026-07-08T15:31:18.786784Z","end":"2026-07-08T15:31:18.971236Z","steps":["trace[698146619] 'agreement among raft nodes before linearized reading' (duration: 184.182394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:18.971434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.372318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80203"} {"level":"info","ts":"2026-07-08T15:31:18.971503Z","caller":"traceutil/trace.go:171","msg":"trace[629439080] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5595; }","duration":"184.513711ms","start":"2026-07-08T15:31:18.786973Z","end":"2026-07-08T15:31:18.971487Z","steps":["trace[629439080] 'agreement among raft nodes before linearized reading' (duration: 184.115153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:21.168574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.925395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:31:21.168711Z","caller":"traceutil/trace.go:171","msg":"trace[1526023752] linearizableReadLoop","detail":"{readStateIndex:5827; appliedIndex:5826; }","duration":"100.721187ms","start":"2026-07-08T15:31:21.067971Z","end":"2026-07-08T15:31:21.168692Z","steps":["trace[1526023752] 'read index received' (duration: 64.832µs)","trace[1526023752] 'applied index is now lower than readState.Index' (duration: 100.652025ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:21.168862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.90562ms","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-07-08T15:31:21.168827Z","caller":"traceutil/trace.go:171","msg":"trace[13329875] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"152.835078ms","start":"2026-07-08T15:31:21.015929Z","end":"2026-07-08T15:31:21.168764Z","steps":["trace[13329875] 'process raft request' (duration: 14.368923ms)","trace[13329875] 'compare' (duration: 137.766732ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:21.168899Z","caller":"traceutil/trace.go:171","msg":"trace[555098616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5617; }","duration":"101.001651ms","start":"2026-07-08T15:31:21.067886Z","end":"2026-07-08T15:31:21.168888Z","steps":["trace[555098616] 'agreement among raft nodes before linearized reading' (duration: 100.855659ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:02.020905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-07-08T15:32:02.097519Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"74.457964ms","hash":1557980936,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":33861632,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-08T15:32:02.097614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1557980936,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-07-08T15:37:02.028994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6013} {"level":"info","ts":"2026-07-08T15:37:02.136419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6013,"took":"104.991288ms","hash":4234167653,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":21897216,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-08T15:37:02.136544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4234167653,"revision":6013,"compact-revision":2974} {"level":"info","ts":"2026-07-08T15:37:57.4866Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022a9.wal"} {"level":"info","ts":"2026-07-08T15:38:04.113295Z","caller":"traceutil/trace.go:171","msg":"trace[426573928] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"136.070954ms","start":"2026-07-08T15:38:03.977171Z","end":"2026-07-08T15:38:04.113242Z","steps":["trace[426573928] 'process raft request' (duration: 132.356702ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:32.431527Z","caller":"traceutil/trace.go:171","msg":"trace[1452229279] linearizableReadLoop","detail":"{readStateIndex:9118; appliedIndex:9117; }","duration":"129.838424ms","start":"2026-07-08T15:38:32.301654Z","end":"2026-07-08T15:38:32.431493Z","steps":["trace[1452229279] 'read index received' (duration: 129.611839ms)","trace[1452229279] 'applied index is now lower than readState.Index' (duration: 225.495µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:38:32.432088Z","caller":"traceutil/trace.go:171","msg":"trace[1695635826] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"218.313987ms","start":"2026-07-08T15:38:32.213759Z","end":"2026-07-08T15:38:32.432073Z","steps":["trace[1695635826] 'process raft request' (duration: 217.536532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:32.432332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.646149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:38:32.432375Z","caller":"traceutil/trace.go:171","msg":"trace[1991911034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8801; }","duration":"130.761102ms","start":"2026-07-08T15:38:32.301604Z","end":"2026-07-08T15:38:32.432365Z","steps":["trace[1991911034] 'agreement among raft nodes before linearized reading' (duration: 130.6575ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:37.563023Z","caller":"traceutil/trace.go:171","msg":"trace[594332673] transaction","detail":"{read_only:false; response_revision:8824; number_of_response:1; }","duration":"125.228826ms","start":"2026-07-08T15:38:37.437759Z","end":"2026-07-08T15:38:37.562988Z","steps":["trace[594332673] 'process raft request' (duration: 125.007042ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:37.603964Z","caller":"traceutil/trace.go:171","msg":"trace[49168110] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"100.861219ms","start":"2026-07-08T15:38:37.503077Z","end":"2026-07-08T15:38:37.603938Z","steps":["trace[49168110] 'process raft request' (duration: 100.289718ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:37.858447Z","caller":"traceutil/trace.go:171","msg":"trace[2107320640] transaction","detail":"{read_only:false; response_revision:8826; number_of_response:1; }","duration":"250.301638ms","start":"2026-07-08T15:38:37.6081Z","end":"2026-07-08T15:38:37.858401Z","steps":["trace[2107320640] 'process raft request' (duration: 250.086944ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:37.866973Z","caller":"traceutil/trace.go:171","msg":"trace[1485988294] linearizableReadLoop","detail":"{readStateIndex:9145; appliedIndex:9143; }","duration":"221.206033ms","start":"2026-07-08T15:38:37.645737Z","end":"2026-07-08T15:38:37.866943Z","steps":["trace[1485988294] 'read index received' (duration: 212.334102ms)","trace[1485988294] 'applied index is now lower than readState.Index' (duration: 8.870911ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:38:37.867067Z","caller":"traceutil/trace.go:171","msg":"trace[1267344601] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"258.448355ms","start":"2026-07-08T15:38:37.608572Z","end":"2026-07-08T15:38:37.86702Z","steps":["trace[1267344601] 'process raft request' (duration: 258.20713ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:37.867131Z","caller":"traceutil/trace.go:171","msg":"trace[1234302917] transaction","detail":"{read_only:false; response_revision:8828; number_of_response:1; }","duration":"176.195421ms","start":"2026-07-08T15:38:37.690888Z","end":"2026-07-08T15:38:37.867083Z","steps":["trace[1234302917] 'process raft request' (duration: 176.021248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:37.867211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.450317ms","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-07-08T15:38:37.867241Z","caller":"traceutil/trace.go:171","msg":"trace[1872771887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8828; }","duration":"221.531698ms","start":"2026-07-08T15:38:37.645701Z","end":"2026-07-08T15:38:37.867233Z","steps":["trace[1872771887] 'agreement among raft nodes before linearized reading' (duration: 221.368715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:37.867398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.04618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-08T15:38:37.867508Z","caller":"traceutil/trace.go:171","msg":"trace[344133234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8828; }","duration":"185.207804ms","start":"2026-07-08T15:38:37.682279Z","end":"2026-07-08T15:38:37.867487Z","steps":["trace[344133234] 'agreement among raft nodes before linearized reading' (duration: 184.870037ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:46.833778Z","caller":"traceutil/trace.go:171","msg":"trace[988738125] transaction","detail":"{read_only:false; response_revision:8915; number_of_response:1; }","duration":"252.517562ms","start":"2026-07-08T15:38:46.581233Z","end":"2026-07-08T15:38:46.83375Z","steps":["trace[988738125] 'process raft request' (duration: 207.272817ms)","trace[988738125] 'compare' (duration: 45.00107ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:38:46.839249Z","caller":"traceutil/trace.go:171","msg":"trace[1534483168] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8916; }","duration":"255.759403ms","start":"2026-07-08T15:38:46.583453Z","end":"2026-07-08T15:38:46.839213Z","steps":["trace[1534483168] 'process raft request' (duration: 255.447098ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:47.789876Z","caller":"traceutil/trace.go:171","msg":"trace[643020985] linearizableReadLoop","detail":"{readStateIndex:9243; appliedIndex:9242; }","duration":"178.299532ms","start":"2026-07-08T15:38:47.61154Z","end":"2026-07-08T15:38:47.789839Z","steps":["trace[643020985] 'read index received' (duration: 177.958145ms)","trace[643020985] 'applied index is now lower than readState.Index' (duration: 339.457µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:38:47.789947Z","caller":"traceutil/trace.go:171","msg":"trace[2033875630] transaction","detail":"{read_only:false; response_revision:8920; number_of_response:1; }","duration":"229.985851ms","start":"2026-07-08T15:38:47.559901Z","end":"2026-07-08T15:38:47.789887Z","steps":["trace[2033875630] 'process raft request' (duration: 229.598533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:47.790408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.824902ms","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-07-08T15:38:47.79053Z","caller":"traceutil/trace.go:171","msg":"trace[356393179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8920; }","duration":"179.018015ms","start":"2026-07-08T15:38:47.611487Z","end":"2026-07-08T15:38:47.790505Z","steps":["trace[356393179] 'agreement among raft nodes before linearized reading' (duration: 178.512496ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:47.932869Z","caller":"traceutil/trace.go:171","msg":"trace[1857253060] transaction","detail":"{read_only:false; response_revision:8921; number_of_response:1; }","duration":"293.20507ms","start":"2026-07-08T15:38:47.639593Z","end":"2026-07-08T15:38:47.932798Z","steps":["trace[1857253060] 'process raft request' (duration: 274.848899ms)","trace[1857253060] 'compare' (duration: 18.158217ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:38:47.935189Z","caller":"traceutil/trace.go:171","msg":"trace[1845120857] transaction","detail":"{read_only:false; response_revision:8922; number_of_response:1; }","duration":"139.698263ms","start":"2026-07-08T15:38:47.795403Z","end":"2026-07-08T15:38:47.935101Z","steps":["trace[1845120857] 'process raft request' (duration: 139.198133ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:40:02.323626Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"12dd93b2fab6fcb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T15:40:02.331459Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-08T15:40:02.331556Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-08T15:42:02.036945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8090} {"level":"info","ts":"2026-07-08T15:42:02.104728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8090,"took":"65.276289ms","hash":2137593475,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":28237824,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-08T15:42:02.104813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2137593475,"revision":8090,"compact-revision":6013} {"level":"info","ts":"2026-07-08T15:44:24.081081Z","caller":"traceutil/trace.go:171","msg":"trace[263325335] transaction","detail":"{read_only:false; response_revision:11332; number_of_response:1; }","duration":"134.687016ms","start":"2026-07-08T15:44:23.946345Z","end":"2026-07-08T15:44:24.081032Z","steps":["trace[263325335] 'process raft request' (duration: 106.464946ms)","trace[263325335] 'compare' (duration: 27.990185ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:45:49.814636Z","caller":"traceutil/trace.go:171","msg":"trace[1353185429] transaction","detail":"{read_only:false; response_revision:12139; number_of_response:1; }","duration":"103.995259ms","start":"2026-07-08T15:45:49.710605Z","end":"2026-07-08T15:45:49.8146Z","steps":["trace[1353185429] 'process raft request' (duration: 103.792495ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:47:02.044107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10466} {"level":"info","ts":"2026-07-08T15:47:02.132141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10466,"took":"84.73159ms","hash":3671468514,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":26804224,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-08T15:47:02.132207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3671468514,"revision":10466,"compact-revision":8090} {"level":"info","ts":"2026-07-08T15:52:01.86399Z","caller":"traceutil/trace.go:171","msg":"trace[1041303773] transaction","detail":"{read_only:false; response_revision:14645; number_of_response:1; }","duration":"141.040146ms","start":"2026-07-08T15:52:01.722914Z","end":"2026-07-08T15:52:01.863955Z","steps":["trace[1041303773] 'process raft request' (duration: 140.793562ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:52:02.053701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12628} {"level":"info","ts":"2026-07-08T15:52:02.134177Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12628,"took":"77.180855ms","hash":45027010,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":27148288,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-08T15:52:02.134239Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":45027010,"revision":12628,"compact-revision":10466} {"level":"warn","ts":"2026-07-08T15:52:27.267087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.209678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:52:27.267198Z","caller":"traceutil/trace.go:171","msg":"trace[1159437389] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:14784; }","duration":"178.493284ms","start":"2026-07-08T15:52:27.088678Z","end":"2026-07-08T15:52:27.267171Z","steps":["trace[1159437389] 'count revisions from in-memory index tree' (duration: 178.066906ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:53:07.834702Z","caller":"traceutil/trace.go:171","msg":"trace[948883378] transaction","detail":"{read_only:false; response_revision:15201; number_of_response:1; }","duration":"276.045154ms","start":"2026-07-08T15:53:07.558624Z","end":"2026-07-08T15:53:07.834669Z","steps":["trace[948883378] 'process raft request' (duration: 275.83068ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:53:07.894687Z","caller":"traceutil/trace.go:171","msg":"trace[1363381717] transaction","detail":"{read_only:false; response_revision:15202; number_of_response:1; }","duration":"322.971153ms","start":"2026-07-08T15:53:07.571692Z","end":"2026-07-08T15:53:07.894663Z","steps":["trace[1363381717] 'process raft request' (duration: 322.766959ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:53:07.895026Z","caller":"traceutil/trace.go:171","msg":"trace[1341901145] linearizableReadLoop","detail":"{readStateIndex:15736; appliedIndex:15734; }","duration":"322.617235ms","start":"2026-07-08T15:53:07.57238Z","end":"2026-07-08T15:53:07.894997Z","steps":["trace[1341901145] 'read index received' (duration: 262.025265ms)","trace[1341901145] 'applied index is now lower than readState.Index' (duration: 60.59024ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:53:07.895092Z","caller":"traceutil/trace.go:171","msg":"trace[2053071514] transaction","detail":"{read_only:false; response_revision:15204; number_of_response:1; }","duration":"321.588325ms","start":"2026-07-08T15:53:07.573488Z","end":"2026-07-08T15:53:07.895076Z","steps":["trace[2053071514] 'process raft request' (duration: 321.371391ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:53:07.895107Z","caller":"traceutil/trace.go:171","msg":"trace[1575528215] transaction","detail":"{read_only:false; response_revision:15203; number_of_response:1; }","duration":"321.83363ms","start":"2026-07-08T15:53:07.573239Z","end":"2026-07-08T15:53:07.895073Z","steps":["trace[1575528215] 'process raft request' (duration: 321.361261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:53:07.895224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:53:07.571643Z","time spent":"323.122555ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:53:07.895223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.30619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:53:07.895164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:53:07.57344Z","time spent":"321.680077ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:53:07.895277Z","caller":"traceutil/trace.go:171","msg":"trace[1382947672] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:15204; }","duration":"216.387911ms","start":"2026-07-08T15:53:07.678872Z","end":"2026-07-08T15:53:07.89526Z","steps":["trace[1382947672] 'agreement among raft nodes before linearized reading' (duration: 216.276439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:53:07.895297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:53:07.573233Z","time spent":"321.977443ms","remote":"127.0.0.1:51586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7680,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:53:07.895527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.114754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-08T15:53:07.896421Z","caller":"traceutil/trace.go:171","msg":"trace[1277823993] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15204; }","duration":"323.268646ms","start":"2026-07-08T15:53:07.572365Z","end":"2026-07-08T15:53:07.895634Z","steps":["trace[1277823993] 'agreement among raft nodes before linearized reading' (duration: 322.867809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:53:07.896606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:53:07.572333Z","time spent":"324.210986ms","remote":"127.0.0.1:57572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-08T15:54:34.644606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.923227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737310"} {"level":"info","ts":"2026-07-08T15:54:34.644724Z","caller":"traceutil/trace.go:171","msg":"trace[945448982] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16013; }","duration":"106.05918ms","start":"2026-07-08T15:54:34.53864Z","end":"2026-07-08T15:54:34.644699Z","steps":["trace[945448982] 'range keys from in-memory index tree' (duration: 105.164993ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:54:35.012358Z","caller":"traceutil/trace.go:171","msg":"trace[912589894] linearizableReadLoop","detail":"{readStateIndex:16571; appliedIndex:16570; }","duration":"229.274579ms","start":"2026-07-08T15:54:34.783055Z","end":"2026-07-08T15:54:35.012329Z","steps":["trace[912589894] 'read index received' (duration: 229.017573ms)","trace[912589894] 'applied index is now lower than readState.Index' (duration: 255.926µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:54:35.012732Z","caller":"traceutil/trace.go:171","msg":"trace[1042718044] transaction","detail":"{read_only:false; response_revision:16014; number_of_response:1; }","duration":"238.285091ms","start":"2026-07-08T15:54:34.774423Z","end":"2026-07-08T15:54:35.012708Z","steps":["trace[1042718044] 'process raft request' (duration: 237.71455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:54:35.013068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.010412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:54:35.013123Z","caller":"traceutil/trace.go:171","msg":"trace[304485127] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:16014; }","duration":"230.131914ms","start":"2026-07-08T15:54:34.782977Z","end":"2026-07-08T15:54:35.013109Z","steps":["trace[304485127] 'agreement among raft nodes before linearized reading' (duration: 230.021372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:54:35.013334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.666345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-08T15:54:35.013397Z","caller":"traceutil/trace.go:171","msg":"trace[623466154] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16014; }","duration":"207.791587ms","start":"2026-07-08T15:54:34.805591Z","end":"2026-07-08T15:54:35.013382Z","steps":["trace[623466154] 'agreement among raft nodes before linearized reading' (duration: 207.666775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:54:35.013786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.102499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-07-08T15:54:35.013833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.235091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-07-08T15:54:35.013868Z","caller":"traceutil/trace.go:171","msg":"trace[1270765740] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16014; }","duration":"120.209091ms","start":"2026-07-08T15:54:34.893647Z","end":"2026-07-08T15:54:35.013856Z","steps":["trace[1270765740] 'agreement among raft nodes before linearized reading' (duration: 119.978747ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:54:35.013878Z","caller":"traceutil/trace.go:171","msg":"trace[2118697904] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16014; }","duration":"120.277632ms","start":"2026-07-08T15:54:34.893585Z","end":"2026-07-08T15:54:35.013862Z","steps":["trace[2118697904] 'agreement among raft nodes before linearized reading' (duration: 120.16867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:54:35.013972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.807372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:54:35.014008Z","caller":"traceutil/trace.go:171","msg":"trace[1195364657] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16014; }","duration":"120.878014ms","start":"2026-07-08T15:54:34.893121Z","end":"2026-07-08T15:54:35.013999Z","steps":["trace[1195364657] 'agreement among raft nodes before linearized reading' (duration: 120.825783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:54:35.014138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.716998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-08T15:54:35.014191Z","caller":"traceutil/trace.go:171","msg":"trace[203812360] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16014; }","duration":"112.821ms","start":"2026-07-08T15:54:34.901355Z","end":"2026-07-08T15:54:35.014176Z","steps":["trace[203812360] 'agreement among raft nodes before linearized reading' (duration: 112.680867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:54:35.014339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.648379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:54:35.014409Z","caller":"traceutil/trace.go:171","msg":"trace[1537157114] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16014; }","duration":"120.771832ms","start":"2026-07-08T15:54:34.893617Z","end":"2026-07-08T15:54:35.014389Z","steps":["trace[1537157114] 'agreement among raft nodes before linearized reading' (duration: 120.69056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:54:35.435492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.932426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:54:35.435636Z","caller":"traceutil/trace.go:171","msg":"trace[1370591987] linearizableReadLoop","detail":"{readStateIndex:16573; appliedIndex:16572; }","duration":"166.760882ms","start":"2026-07-08T15:54:35.268854Z","end":"2026-07-08T15:54:35.435615Z","steps":["trace[1370591987] 'read index received' (duration: 47.508169ms)","trace[1370591987] 'applied index is now lower than readState.Index' (duration: 119.251053ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:54:35.43572Z","caller":"traceutil/trace.go:171","msg":"trace[658601961] transaction","detail":"{read_only:false; response_revision:16015; number_of_response:1; }","duration":"171.028924ms","start":"2026-07-08T15:54:35.264646Z","end":"2026-07-08T15:54:35.435675Z","steps":["trace[658601961] 'process raft request' (duration: 51.805292ms)","trace[658601961] 'compare' (duration: 118.779684ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:54:35.435985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.033222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wdhd7\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-07-08T15:54:35.436024Z","caller":"traceutil/trace.go:171","msg":"trace[616628578] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wdhd7; range_end:; response_count:1; response_revision:16015; }","duration":"148.074013ms","start":"2026-07-08T15:54:35.287939Z","end":"2026-07-08T15:54:35.436013Z","steps":["trace[616628578] 'agreement among raft nodes before linearized reading' (duration: 147.797448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:54:35.436216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.048088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:54:35.436329Z","caller":"traceutil/trace.go:171","msg":"trace[405396391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16015; }","duration":"131.239262ms","start":"2026-07-08T15:54:35.305065Z","end":"2026-07-08T15:54:35.436304Z","steps":["trace[405396391] 'agreement among raft nodes before linearized reading' (duration: 131.014587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:54:35.436987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.103968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737310"} {"level":"info","ts":"2026-07-08T15:54:35.43707Z","caller":"traceutil/trace.go:171","msg":"trace[1363762492] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16015; }","duration":"168.22589ms","start":"2026-07-08T15:54:35.268824Z","end":"2026-07-08T15:54:35.43705Z","steps":["trace[1363762492] 'agreement among raft nodes before linearized reading' (duration: 166.896565ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:57:02.059278Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14646} {"level":"info","ts":"2026-07-08T15:57:02.151015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14646,"took":"88.828091ms","hash":1250825266,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":30994432,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T15:57:02.1511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1250825266,"revision":14646,"compact-revision":12628} {"level":"info","ts":"2026-07-08T16:01:26.482576Z","caller":"traceutil/trace.go:171","msg":"trace[1444013738] transaction","detail":"{read_only:false; response_revision:19057; number_of_response:1; }","duration":"526.666849ms","start":"2026-07-08T16:01:25.955874Z","end":"2026-07-08T16:01:26.482541Z","steps":["trace[1444013738] 'process raft request' (duration: 526.478226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:01:26.482803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:01:25.955847Z","time spent":"526.842362ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:01:26.503054Z","caller":"traceutil/trace.go:171","msg":"trace[1228768602] linearizableReadLoop","detail":"{readStateIndex:19716; appliedIndex:19713; }","duration":"466.240883ms","start":"2026-07-08T16:01:26.036757Z","end":"2026-07-08T16:01:26.502998Z","steps":["trace[1228768602] 'read index received' (duration: 445.273022ms)","trace[1228768602] 'applied index is now lower than readState.Index' (duration: 20.966781ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:01:26.503825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.038708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-08T16:01:26.503886Z","caller":"traceutil/trace.go:171","msg":"trace[389227727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19060; }","duration":"467.17828ms","start":"2026-07-08T16:01:26.036691Z","end":"2026-07-08T16:01:26.503869Z","steps":["trace[389227727] 'agreement among raft nodes before linearized reading' (duration: 466.957116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:01:26.503926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:01:26.036666Z","time spent":"467.248482ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T16:01:26.504029Z","caller":"traceutil/trace.go:171","msg":"trace[2094734485] transaction","detail":"{read_only:false; response_revision:19059; number_of_response:1; }","duration":"533.997228ms","start":"2026-07-08T16:01:25.969993Z","end":"2026-07-08T16:01:26.50399Z","steps":["trace[2094734485] 'process raft request' (duration: 532.893937ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:01:26.504145Z","caller":"traceutil/trace.go:171","msg":"trace[1965097515] transaction","detail":"{read_only:false; response_revision:19060; number_of_response:1; }","duration":"360.172302ms","start":"2026-07-08T16:01:26.143962Z","end":"2026-07-08T16:01:26.504134Z","steps":["trace[1965097515] 'process raft request' (duration: 358.9665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:01:26.504238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:01:26.143925Z","time spent":"360.250884ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:01:26.504254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:01:25.969979Z","time spent":"534.152902ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:01:26.503996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.760893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T16:01:26.504583Z","caller":"traceutil/trace.go:171","msg":"trace[1810769041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19060; }","duration":"467.399765ms","start":"2026-07-08T16:01:26.03717Z","end":"2026-07-08T16:01:26.50457Z","steps":["trace[1810769041] 'agreement among raft nodes before linearized reading' (duration: 466.584999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:01:26.504625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:01:26.037164Z","time spent":"467.448886ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T16:01:26.504702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.992217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:01:26.504794Z","caller":"traceutil/trace.go:171","msg":"trace[772816976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19060; }","duration":"201.12771ms","start":"2026-07-08T16:01:26.303636Z","end":"2026-07-08T16:01:26.504764Z","steps":["trace[772816976] 'agreement among raft nodes before linearized reading' (duration: 201.017898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:01:26.504829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.367981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-08T16:01:26.504869Z","caller":"traceutil/trace.go:171","msg":"trace[300595279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19060; }","duration":"460.427351ms","start":"2026-07-08T16:01:26.044429Z","end":"2026-07-08T16:01:26.504857Z","steps":["trace[300595279] 'agreement among raft nodes before linearized reading' (duration: 460.326329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:01:26.504907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:01:26.044419Z","time spent":"460.477472ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T16:01:26.504723Z","caller":"traceutil/trace.go:171","msg":"trace[172291179] transaction","detail":"{read_only:false; response_revision:19058; number_of_response:1; }","duration":"540.420413ms","start":"2026-07-08T16:01:25.96429Z","end":"2026-07-08T16:01:26.50471Z","steps":["trace[172291179] 'process raft request' (duration: 538.345843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:01:26.505134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.175235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-08T16:01:26.505205Z","caller":"traceutil/trace.go:171","msg":"trace[242093048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19060; }","duration":"461.261216ms","start":"2026-07-08T16:01:26.043927Z","end":"2026-07-08T16:01:26.505188Z","steps":["trace[242093048] 'agreement among raft nodes before linearized reading' (duration: 461.086043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:01:26.505263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:01:26.043919Z","time spent":"461.330169ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T16:01:26.505272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.056344ms","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-07-08T16:01:26.505332Z","caller":"traceutil/trace.go:171","msg":"trace[2100660096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19060; }","duration":"460.136125ms","start":"2026-07-08T16:01:26.045179Z","end":"2026-07-08T16:01:26.505315Z","steps":["trace[2100660096] 'agreement among raft nodes before linearized reading' (duration: 459.963162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:01:26.505397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:01:26.045169Z","time spent":"460.208087ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T16:01:26.505411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:01:25.964261Z","time spent":"540.877351ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:01:47.637215Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"12dd93b2fab6fcb","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T16:01:47.65434Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-07-08T16:01:47.654482Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-07-08T16:02:02.071678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17005} {"level":"info","ts":"2026-07-08T16:02:02.168212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17005,"took":"93.193003ms","hash":1128939376,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":33685504,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-08T16:02:02.168287Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1128939376,"revision":17005,"compact-revision":14646} {"level":"info","ts":"2026-07-08T16:07:02.079968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19519} {"level":"info","ts":"2026-07-08T16:07:02.210166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19519,"took":"126.037554ms","hash":764396581,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":30236672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-08T16:07:02.210267Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":764396581,"revision":19519,"compact-revision":17005} {"level":"warn","ts":"2026-07-08T16:07:12.813035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.700588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:07:12.813167Z","caller":"traceutil/trace.go:171","msg":"trace[12203435] transaction","detail":"{read_only:false; response_revision:21711; number_of_response:1; }","duration":"146.156669ms","start":"2026-07-08T16:07:12.666989Z","end":"2026-07-08T16:07:12.813145Z","steps":["trace[12203435] 'process raft request' (duration: 21.145005ms)","trace[12203435] 'compare' (duration: 124.375002ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:07:12.878822Z","caller":"traceutil/trace.go:171","msg":"trace[1329923614] transaction","detail":"{read_only:false; response_revision:21713; number_of_response:1; }","duration":"202.958906ms","start":"2026-07-08T16:07:12.675805Z","end":"2026-07-08T16:07:12.878764Z","steps":["trace[1329923614] 'process raft request' (duration: 202.702551ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:07:12.878597Z","caller":"traceutil/trace.go:171","msg":"trace[2125975768] transaction","detail":"{read_only:false; response_revision:21712; number_of_response:1; }","duration":"203.046127ms","start":"2026-07-08T16:07:12.675513Z","end":"2026-07-08T16:07:12.878559Z","steps":["trace[2125975768] 'process raft request' (duration: 202.850703ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:07:29.026814Z","caller":"traceutil/trace.go:171","msg":"trace[1103142998] transaction","detail":"{read_only:false; response_revision:21793; number_of_response:1; }","duration":"277.36034ms","start":"2026-07-08T16:07:28.749416Z","end":"2026-07-08T16:07:29.026776Z","steps":["trace[1103142998] 'process raft request' (duration: 277.148356ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:07:29.232767Z","caller":"traceutil/trace.go:171","msg":"trace[812051492] linearizableReadLoop","detail":"{readStateIndex:22544; appliedIndex:22542; }","duration":"439.520385ms","start":"2026-07-08T16:07:28.793215Z","end":"2026-07-08T16:07:29.232735Z","steps":["trace[812051492] 'read index received' (duration: 233.371047ms)","trace[812051492] 'applied index is now lower than readState.Index' (duration: 206.147968ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:07:29.232912Z","caller":"traceutil/trace.go:171","msg":"trace[1000595642] transaction","detail":"{read_only:false; response_revision:21794; number_of_response:1; }","duration":"481.321145ms","start":"2026-07-08T16:07:28.751576Z","end":"2026-07-08T16:07:29.232897Z","steps":["trace[1000595642] 'process raft request' (duration: 427.719278ms)","trace[1000595642] 'compare' (duration: 53.159448ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:07:29.233045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:07:28.751565Z","time spent":"481.375517ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:07:29.233257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.982926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-08T16:07:29.233401Z","caller":"traceutil/trace.go:171","msg":"trace[1365719881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21794; }","duration":"332.16986ms","start":"2026-07-08T16:07:28.901205Z","end":"2026-07-08T16:07:29.233374Z","steps":["trace[1365719881] 'agreement among raft nodes before linearized reading' (duration: 331.839064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:07:29.233551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:07:28.901188Z","time spent":"332.346493ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T16:07:29.233722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.496843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T16:07:29.233776Z","caller":"traceutil/trace.go:171","msg":"trace[1086778704] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:21794; }","duration":"440.590375ms","start":"2026-07-08T16:07:28.793174Z","end":"2026-07-08T16:07:29.233764Z","steps":["trace[1086778704] 'agreement among raft nodes before linearized reading' (duration: 440.423042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:07:29.233809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:07:28.793158Z","time spent":"440.643955ms","remote":"127.0.0.1:57736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":115,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-08T16:07:29.23395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.068014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T16:07:29.234034Z","caller":"traceutil/trace.go:171","msg":"trace[674102560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21794; }","duration":"265.172767ms","start":"2026-07-08T16:07:28.96885Z","end":"2026-07-08T16:07:29.234022Z","steps":["trace[674102560] 'agreement among raft nodes before linearized reading' (duration: 265.013204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:07:29.234273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.743625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-08T16:07:29.234317Z","caller":"traceutil/trace.go:171","msg":"trace[941359998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21794; }","duration":"268.866778ms","start":"2026-07-08T16:07:28.965439Z","end":"2026-07-08T16:07:29.234306Z","steps":["trace[941359998] 'agreement among raft nodes before linearized reading' (duration: 268.703185ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:07:29.442341Z","caller":"traceutil/trace.go:171","msg":"trace[1340459235] linearizableReadLoop","detail":"{readStateIndex:22547; appliedIndex:22546; }","duration":"137.828318ms","start":"2026-07-08T16:07:29.304477Z","end":"2026-07-08T16:07:29.442305Z","steps":["trace[1340459235] 'read index received' (duration: 98.899573ms)","trace[1340459235] 'applied index is now lower than readState.Index' (duration: 38.927415ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:07:29.442389Z","caller":"traceutil/trace.go:171","msg":"trace[534651540] transaction","detail":"{read_only:false; response_revision:21797; number_of_response:1; }","duration":"195.527313ms","start":"2026-07-08T16:07:29.246808Z","end":"2026-07-08T16:07:29.442336Z","steps":["trace[534651540] 'process raft request' (duration: 156.545707ms)","trace[534651540] 'compare' (duration: 38.796073ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:07:29.442561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.056612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:07:29.442713Z","caller":"traceutil/trace.go:171","msg":"trace[1328355531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21797; }","duration":"138.255716ms","start":"2026-07-08T16:07:29.304428Z","end":"2026-07-08T16:07:29.442683Z","steps":["trace[1328355531] 'agreement among raft nodes before linearized reading' (duration: 138.032972ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:07:29.530177Z","caller":"traceutil/trace.go:171","msg":"trace[30670309] transaction","detail":"{read_only:false; response_revision:21798; number_of_response:1; }","duration":"168.299593ms","start":"2026-07-08T16:07:29.361836Z","end":"2026-07-08T16:07:29.530136Z","steps":["trace[30670309] 'process raft request' (duration: 168.083049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:07:29.699045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.385694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:07:29.699166Z","caller":"traceutil/trace.go:171","msg":"trace[1861723049] linearizableReadLoop","detail":"{readStateIndex:22549; appliedIndex:22547; }","duration":"256.717186ms","start":"2026-07-08T16:07:29.442431Z","end":"2026-07-08T16:07:29.699148Z","steps":["trace[1861723049] 'read index received' (duration: 87.516056ms)","trace[1861723049] 'applied index is now lower than readState.Index' (duration: 169.2005ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:07:29.69926Z","caller":"traceutil/trace.go:171","msg":"trace[167684971] transaction","detail":"{read_only:false; response_revision:21799; number_of_response:1; }","duration":"272.866365ms","start":"2026-07-08T16:07:29.426385Z","end":"2026-07-08T16:07:29.699251Z","steps":["trace[167684971] 'process raft request' (duration: 116.161894ms)","trace[167684971] 'compare' (duration: 156.19762ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:07:29.699461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.386381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:07:29.699574Z","caller":"traceutil/trace.go:171","msg":"trace[13007553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21799; }","duration":"255.533523ms","start":"2026-07-08T16:07:29.444012Z","end":"2026-07-08T16:07:29.699546Z","steps":["trace[13007553] 'agreement among raft nodes before linearized reading' (duration: 255.34223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:07:29.699556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.498596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T16:07:29.699616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.221131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:07:29.699666Z","caller":"traceutil/trace.go:171","msg":"trace[323822631] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:21799; }","duration":"121.6886ms","start":"2026-07-08T16:07:29.577946Z","end":"2026-07-08T16:07:29.699635Z","steps":["trace[323822631] 'agreement among raft nodes before linearized reading' (duration: 121.484896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:07:29.699667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.262451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-pk9vk.18c05bed64ce8fba\" ","response":"range_response_count:1 size:1387"} {"level":"info","ts":"2026-07-08T16:07:29.699727Z","caller":"traceutil/trace.go:171","msg":"trace[424126550] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:21799; }","duration":"250.379984ms","start":"2026-07-08T16:07:29.449318Z","end":"2026-07-08T16:07:29.699698Z","steps":["trace[424126550] 'agreement among raft nodes before linearized reading' (duration: 250.1729ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:07:29.699739Z","caller":"traceutil/trace.go:171","msg":"trace[1973814873] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-pk9vk.18c05bed64ce8fba; range_end:; response_count:1; response_revision:21799; }","duration":"274.388653ms","start":"2026-07-08T16:07:29.425329Z","end":"2026-07-08T16:07:29.699717Z","steps":["trace[1973814873] 'agreement among raft nodes before linearized reading' (duration: 274.108008ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:07:30.032222Z","caller":"traceutil/trace.go:171","msg":"trace[1203044617] transaction","detail":"{read_only:false; response_revision:21800; number_of_response:1; }","duration":"329.352636ms","start":"2026-07-08T16:07:29.702838Z","end":"2026-07-08T16:07:30.032191Z","steps":["trace[1203044617] 'process raft request' (duration: 329.153071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:07:30.03257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:07:29.702817Z","time spent":"329.499229ms","remote":"127.0.0.1:57468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1369,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:11:41.978346Z","caller":"traceutil/trace.go:171","msg":"trace[1617954720] transaction","detail":"{read_only:false; response_revision:24361; number_of_response:1; }","duration":"161.624976ms","start":"2026-07-08T16:11:41.816697Z","end":"2026-07-08T16:11:41.978322Z","steps":["trace[1617954720] 'process raft request' (duration: 161.415112ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:12:02.087224Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21657} {"level":"info","ts":"2026-07-08T16:12:02.207636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21657,"took":"114.736427ms","hash":968118780,"current-db-size-bytes":59039744,"current-db-size":"59 MB","current-db-size-in-use-bytes":42385408,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-08T16:12:02.207715Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":968118780,"revision":21657,"compact-revision":19519} {"level":"info","ts":"2026-07-08T16:13:57.506354Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067d7.wal"} {"level":"info","ts":"2026-07-08T16:15:20.447974Z","caller":"traceutil/trace.go:171","msg":"trace[332195219] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"133.7047ms","start":"2026-07-08T16:15:20.314237Z","end":"2026-07-08T16:15:20.447942Z","steps":["trace[332195219] 'process raft request' (duration: 133.519517ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:20.448554Z","caller":"traceutil/trace.go:171","msg":"trace[96550177] linearizableReadLoop","detail":"{readStateIndex:27430; appliedIndex:27430; }","duration":"102.328899ms","start":"2026-07-08T16:15:20.346209Z","end":"2026-07-08T16:15:20.448538Z","steps":["trace[96550177] 'read index received' (duration: 102.323679ms)","trace[96550177] 'applied index is now lower than readState.Index' (duration: 4.52µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:20.448678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.450141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T16:15:20.448717Z","caller":"traceutil/trace.go:171","msg":"trace[492096155] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:26549; }","duration":"102.562213ms","start":"2026-07-08T16:15:20.346144Z","end":"2026-07-08T16:15:20.448706Z","steps":["trace[492096155] 'agreement among raft nodes before linearized reading' (duration: 102.471952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:20.492023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.872952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-pk9vk.18c05bed64ce8fba\" ","response":"range_response_count:1 size:1387"} {"level":"info","ts":"2026-07-08T16:15:20.492094Z","caller":"traceutil/trace.go:171","msg":"trace[466497811] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-pk9vk.18c05bed64ce8fba; range_end:; response_count:1; response_revision:26550; }","duration":"111.007565ms","start":"2026-07-08T16:15:20.381069Z","end":"2026-07-08T16:15:20.492077Z","steps":["trace[466497811] 'agreement among raft nodes before linearized reading' (duration: 110.811161ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:20.492235Z","caller":"traceutil/trace.go:171","msg":"trace[1120093506] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"127.530351ms","start":"2026-07-08T16:15:20.364645Z","end":"2026-07-08T16:15:20.492175Z","steps":["trace[1120093506] 'process raft request' (duration: 127.101804ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:20.596164Z","caller":"traceutil/trace.go:171","msg":"trace[928968511] transaction","detail":"{read_only:false; response_revision:26551; number_of_response:1; }","duration":"101.082146ms","start":"2026-07-08T16:15:20.495041Z","end":"2026-07-08T16:15:20.596124Z","steps":["trace[928968511] 'process raft request' (duration: 78.960583ms)","trace[928968511] 'compare' (duration: 21.586862ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:20.751533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.933252ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-07-08T16:15:26.138987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.064819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-08T16:15:26.139127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.354173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:26.139251Z","caller":"traceutil/trace.go:171","msg":"trace[86479065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26599; }","duration":"202.335564ms","start":"2026-07-08T16:15:25.936849Z","end":"2026-07-08T16:15:26.139185Z","steps":["trace[86479065] 'range keys from in-memory index tree' (duration: 201.884335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.139572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.932835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T16:15:26.139644Z","caller":"traceutil/trace.go:171","msg":"trace[292908616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26599; }","duration":"204.049827ms","start":"2026-07-08T16:15:25.935581Z","end":"2026-07-08T16:15:26.139631Z","steps":["trace[292908616] 'range keys from in-memory index tree' (duration: 203.806622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.139045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.760656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:26.140439Z","caller":"traceutil/trace.go:171","msg":"trace[282365456] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26599; }","duration":"305.213754ms","start":"2026-07-08T16:15:25.835191Z","end":"2026-07-08T16:15:26.140405Z","steps":["trace[282365456] 'count revisions from in-memory index tree' (duration: 303.662584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.140506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:25.835166Z","time spent":"305.318755ms","remote":"127.0.0.1:52496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"info","ts":"2026-07-08T16:15:26.140939Z","caller":"traceutil/trace.go:171","msg":"trace[471495466] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26599; }","duration":"286.641087ms","start":"2026-07-08T16:15:25.853715Z","end":"2026-07-08T16:15:26.140356Z","steps":["trace[471495466] 'count revisions from in-memory index tree' (duration: 285.305831ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:26.374762Z","caller":"traceutil/trace.go:171","msg":"trace[931045815] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"301.345179ms","start":"2026-07-08T16:15:26.07339Z","end":"2026-07-08T16:15:26.374735Z","steps":["trace[931045815] 'process raft request' (duration: 301.189506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.374931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.073354Z","time spent":"301.484053ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:15:26.685551Z","caller":"traceutil/trace.go:171","msg":"trace[651933843] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"540.313985ms","start":"2026-07-08T16:15:26.145197Z","end":"2026-07-08T16:15:26.685511Z","steps":["trace[651933843] 'process raft request' (duration: 540.167262ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:26.685537Z","caller":"traceutil/trace.go:171","msg":"trace[1156518954] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"540.295425ms","start":"2026-07-08T16:15:26.145146Z","end":"2026-07-08T16:15:26.685441Z","steps":["trace[1156518954] 'process raft request' (duration: 540.167542ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:26.685629Z","caller":"traceutil/trace.go:171","msg":"trace[10915553] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"539.305776ms","start":"2026-07-08T16:15:26.146297Z","end":"2026-07-08T16:15:26.685603Z","steps":["trace[10915553] 'process raft request' (duration: 539.109272ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:26.685686Z","caller":"traceutil/trace.go:171","msg":"trace[727434668] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"503.365337ms","start":"2026-07-08T16:15:26.182271Z","end":"2026-07-08T16:15:26.685636Z","steps":["trace[727434668] 'process raft request' (duration: 503.177124ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:26.685677Z","caller":"traceutil/trace.go:171","msg":"trace[492771088] linearizableReadLoop","detail":"{readStateIndex:27484; appliedIndex:27483; }","duration":"568.81196ms","start":"2026-07-08T16:15:26.116829Z","end":"2026-07-08T16:15:26.685641Z","steps":["trace[492771088] 'read index received' (duration: 258.529769ms)","trace[492771088] 'applied index is now lower than readState.Index' (duration: 310.280861ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:26.685775Z","caller":"traceutil/trace.go:171","msg":"trace[386078201] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"499.263599ms","start":"2026-07-08T16:15:26.186505Z","end":"2026-07-08T16:15:26.685769Z","steps":["trace[386078201] 'process raft request' (duration: 499.012054ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:26.68573Z","caller":"traceutil/trace.go:171","msg":"trace[286952242] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"393.722598ms","start":"2026-07-08T16:15:26.291958Z","end":"2026-07-08T16:15:26.68568Z","steps":["trace[286952242] 'process raft request' (duration: 393.639817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.685767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.145104Z","time spent":"540.55864ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:26.685811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.186489Z","time spent":"499.30637ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:26.68581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.146276Z","time spent":"539.418807ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:26.685868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.18225Z","time spent":"503.515981ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:26.685733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.145164Z","time spent":"540.482839ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:26.685914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.291934Z","time spent":"393.883861ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:26.685994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.143626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:26.685446Z","caller":"traceutil/trace.go:171","msg":"trace[485143058] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"595.808148ms","start":"2026-07-08T16:15:26.089591Z","end":"2026-07-08T16:15:26.685399Z","steps":["trace[485143058] 'process raft request' (duration: 595.506311ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:26.686039Z","caller":"traceutil/trace.go:171","msg":"trace[1442003852] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26608; }","duration":"569.253979ms","start":"2026-07-08T16:15:26.116771Z","end":"2026-07-08T16:15:26.686025Z","steps":["trace[1442003852] 'agreement among raft nodes before linearized reading' (duration: 569.038165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.686081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.116746Z","time spent":"569.32435ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "} {"level":"warn","ts":"2026-07-08T16:15:26.686082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.089535Z","time spent":"596.505091ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:15:26.686583Z","caller":"traceutil/trace.go:171","msg":"trace[1252061942] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"496.279751ms","start":"2026-07-08T16:15:26.190282Z","end":"2026-07-08T16:15:26.686562Z","steps":["trace[1252061942] 'process raft request' (duration: 495.277803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.686647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.190271Z","time spent":"496.342992ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:15:26.837577Z","caller":"traceutil/trace.go:171","msg":"trace[1501464585] linearizableReadLoop","detail":"{readStateIndex:27493; appliedIndex:27491; }","duration":"151.675953ms","start":"2026-07-08T16:15:26.685769Z","end":"2026-07-08T16:15:26.837444Z","steps":["trace[1501464585] 'read index received' (duration: 147.877432ms)","trace[1501464585] 'applied index is now lower than readState.Index' (duration: 3.797521ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:26.838116Z","caller":"traceutil/trace.go:171","msg":"trace[589000588] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"430.031023ms","start":"2026-07-08T16:15:26.408065Z","end":"2026-07-08T16:15:26.838096Z","steps":["trace[589000588] 'process raft request' (duration: 425.66044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.838342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.408039Z","time spent":"430.138635ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:26.838635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.569474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-cc669c96b-xlq87.18c05b353f38243d\" ","response":"range_response_count:1 size:1178"} {"level":"info","ts":"2026-07-08T16:15:26.838729Z","caller":"traceutil/trace.go:171","msg":"trace[1597526077] range","detail":"{range_begin:/registry/events/openstack/cinder-api-cc669c96b-xlq87.18c05b353f38243d; range_end:; response_count:1; response_revision:26610; }","duration":"137.683425ms","start":"2026-07-08T16:15:26.701019Z","end":"2026-07-08T16:15:26.838703Z","steps":["trace[1597526077] 'agreement among raft nodes before linearized reading' (duration: 137.40843ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:26.838983Z","caller":"traceutil/trace.go:171","msg":"trace[2049859651] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"323.168117ms","start":"2026-07-08T16:15:26.515785Z","end":"2026-07-08T16:15:26.838954Z","steps":["trace[2049859651] 'process raft request' (duration: 321.581347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.839094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.515758Z","time spent":"323.262269ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:26.83931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.328409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T16:15:26.83935Z","caller":"traceutil/trace.go:171","msg":"trace[1407059095] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:26610; }","duration":"477.368929ms","start":"2026-07-08T16:15:26.36197Z","end":"2026-07-08T16:15:26.839339Z","steps":["trace[1407059095] 'agreement among raft nodes before linearized reading' (duration: 477.300728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:26.83938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.361911Z","time spent":"477.461111ms","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-07-08T16:15:26.839594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.012992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-08T16:15:26.839634Z","caller":"traceutil/trace.go:171","msg":"trace[1768200620] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26610; }","duration":"115.105424ms","start":"2026-07-08T16:15:26.72452Z","end":"2026-07-08T16:15:26.839625Z","steps":["trace[1768200620] 'agreement among raft nodes before linearized reading' (duration: 114.996312ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:27.251023Z","caller":"traceutil/trace.go:171","msg":"trace[1560120444] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"409.212126ms","start":"2026-07-08T16:15:26.84178Z","end":"2026-07-08T16:15:27.250992Z","steps":["trace[1560120444] 'process raft request' (duration: 408.97423ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:27.251149Z","caller":"traceutil/trace.go:171","msg":"trace[1017676657] linearizableReadLoop","detail":"{readStateIndex:27494; appliedIndex:27494; }","duration":"306.950867ms","start":"2026-07-08T16:15:26.94414Z","end":"2026-07-08T16:15:27.251091Z","steps":["trace[1017676657] 'read index received' (duration: 306.929387ms)","trace[1017676657] 'applied index is now lower than readState.Index' (duration: 16.34µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:27.251274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.841758Z","time spent":"409.354028ms","remote":"127.0.0.1:57468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1161,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:27.251481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.295964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-07-08T16:15:27.2516Z","caller":"traceutil/trace.go:171","msg":"trace[507933712] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26611; }","duration":"307.465827ms","start":"2026-07-08T16:15:26.944099Z","end":"2026-07-08T16:15:27.251564Z","steps":["trace[507933712] 'agreement among raft nodes before linearized reading' (duration: 307.137771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:27.251655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.944079Z","time spent":"307.565949ms","remote":"127.0.0.1:57548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-07-08T16:15:27.452944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.49431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:15:27.453087Z","caller":"traceutil/trace.go:171","msg":"trace[1577186575] linearizableReadLoop","detail":"{readStateIndex:27495; appliedIndex:27494; }","duration":"197.263707ms","start":"2026-07-08T16:15:27.255803Z","end":"2026-07-08T16:15:27.453067Z","steps":["trace[1577186575] 'read index received' (duration: 32.518053ms)","trace[1577186575] 'applied index is now lower than readState.Index' (duration: 164.744184ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:27.453211Z","caller":"traceutil/trace.go:171","msg":"trace[786018762] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"467.032792ms","start":"2026-07-08T16:15:26.986162Z","end":"2026-07-08T16:15:27.453195Z","steps":["trace[786018762] 'process raft request' (duration: 302.172566ms)","trace[786018762] 'compare' (duration: 164.332977ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:27.453284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:26.986139Z","time spent":"467.108523ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:15:27.453799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.9962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e9b0ef0c-cb1b-474e-bffc-5717fe4ea02f\" ","response":"range_response_count:1 size:1276"} {"level":"warn","ts":"2026-07-08T16:15:27.453764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.618301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:27.453845Z","caller":"traceutil/trace.go:171","msg":"trace[1227707956] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e9b0ef0c-cb1b-474e-bffc-5717fe4ea02f; range_end:; response_count:1; response_revision:26612; }","duration":"198.071552ms","start":"2026-07-08T16:15:27.255762Z","end":"2026-07-08T16:15:27.453833Z","steps":["trace[1227707956] 'agreement among raft nodes before linearized reading' (duration: 197.887999ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:27.453874Z","caller":"traceutil/trace.go:171","msg":"trace[82498964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26612; }","duration":"152.880056ms","start":"2026-07-08T16:15:27.30097Z","end":"2026-07-08T16:15:27.45385Z","steps":["trace[82498964] 'agreement among raft nodes before linearized reading' (duration: 152.383167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:27.97481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.181236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:15:27.975214Z","caller":"traceutil/trace.go:171","msg":"trace[1211208567] transaction","detail":"{read_only:false; response_revision:26615; number_of_response:1; }","duration":"199.575602ms","start":"2026-07-08T16:15:27.775608Z","end":"2026-07-08T16:15:27.975183Z","steps":["trace[1211208567] 'process raft request' (duration: 199.364778ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:27.975284Z","caller":"traceutil/trace.go:171","msg":"trace[1469407471] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"202.728952ms","start":"2026-07-08T16:15:27.772511Z","end":"2026-07-08T16:15:27.97524Z","steps":["trace[1469407471] 'process raft request' (duration: 87.983875ms)","trace[1469407471] 'compare' (duration: 113.761188ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:28.812786Z","caller":"traceutil/trace.go:171","msg":"trace[1638728470] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"117.804615ms","start":"2026-07-08T16:15:28.694948Z","end":"2026-07-08T16:15:28.812752Z","steps":["trace[1638728470] 'process raft request' (duration: 117.239185ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:28.812816Z","caller":"traceutil/trace.go:171","msg":"trace[390154999] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"117.837626ms","start":"2026-07-08T16:15:28.694943Z","end":"2026-07-08T16:15:28.81278Z","steps":["trace[390154999] 'process raft request' (duration: 117.722344ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:28.813073Z","caller":"traceutil/trace.go:171","msg":"trace[1681695308] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"114.94496ms","start":"2026-07-08T16:15:28.698098Z","end":"2026-07-08T16:15:28.813043Z","steps":["trace[1681695308] 'process raft request' (duration: 114.639765ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:28.813177Z","caller":"traceutil/trace.go:171","msg":"trace[538851004] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"113.816709ms","start":"2026-07-08T16:15:28.699321Z","end":"2026-07-08T16:15:28.813138Z","steps":["trace[538851004] 'process raft request' (duration: 113.669127ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:28.813353Z","caller":"traceutil/trace.go:171","msg":"trace[1975058156] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"113.945091ms","start":"2026-07-08T16:15:28.699392Z","end":"2026-07-08T16:15:28.813337Z","steps":["trace[1975058156] 'process raft request' (duration: 113.699266ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:28.813597Z","caller":"traceutil/trace.go:171","msg":"trace[990997928] transaction","detail":"{read_only:false; response_revision:26623; number_of_response:1; }","duration":"113.234828ms","start":"2026-07-08T16:15:28.700282Z","end":"2026-07-08T16:15:28.813517Z","steps":["trace[990997928] 'process raft request' (duration: 112.962052ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:29.002439Z","caller":"traceutil/trace.go:171","msg":"trace[418224859] linearizableReadLoop","detail":"{readStateIndex:27507; appliedIndex:27506; }","duration":"117.947248ms","start":"2026-07-08T16:15:28.884461Z","end":"2026-07-08T16:15:29.002408Z","steps":["trace[418224859] 'read index received' (duration: 111.161859ms)","trace[418224859] 'applied index is now lower than readState.Index' (duration: 6.783439ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:29.00259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.100481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:29.002624Z","caller":"traceutil/trace.go:171","msg":"trace[1029814292] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26624; }","duration":"118.215084ms","start":"2026-07-08T16:15:28.8844Z","end":"2026-07-08T16:15:29.002615Z","steps":["trace[1029814292] 'agreement among raft nodes before linearized reading' (duration: 118.107042ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:29.002652Z","caller":"traceutil/trace.go:171","msg":"trace[1642723897] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"157.866763ms","start":"2026-07-08T16:15:28.844748Z","end":"2026-07-08T16:15:29.002614Z","steps":["trace[1642723897] 'process raft request' (duration: 150.899929ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:35.070698Z","caller":"traceutil/trace.go:171","msg":"trace[2057348362] transaction","detail":"{read_only:false; response_revision:26672; number_of_response:1; }","duration":"273.169051ms","start":"2026-07-08T16:15:34.797506Z","end":"2026-07-08T16:15:35.070675Z","steps":["trace[2057348362] 'process raft request' (duration: 272.936626ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:35.090579Z","caller":"traceutil/trace.go:171","msg":"trace[181301276] transaction","detail":"{read_only:false; response_revision:26673; number_of_response:1; }","duration":"258.125203ms","start":"2026-07-08T16:15:34.832441Z","end":"2026-07-08T16:15:35.090567Z","steps":["trace[181301276] 'process raft request' (duration: 257.834837ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:35.090602Z","caller":"traceutil/trace.go:171","msg":"trace[1200768397] transaction","detail":"{read_only:false; response_revision:26675; number_of_response:1; }","duration":"251.788781ms","start":"2026-07-08T16:15:34.838783Z","end":"2026-07-08T16:15:35.090572Z","steps":["trace[1200768397] 'process raft request' (duration: 251.715789ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:35.09067Z","caller":"traceutil/trace.go:171","msg":"trace[2036787932] transaction","detail":"{read_only:false; response_revision:26674; number_of_response:1; }","duration":"253.222748ms","start":"2026-07-08T16:15:34.837439Z","end":"2026-07-08T16:15:35.090662Z","steps":["trace[2036787932] 'process raft request' (duration: 253.028265ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:35.090772Z","caller":"traceutil/trace.go:171","msg":"trace[318725731] linearizableReadLoop","detail":"{readStateIndex:27559; appliedIndex:27555; }","duration":"249.562278ms","start":"2026-07-08T16:15:34.841191Z","end":"2026-07-08T16:15:35.090753Z","steps":["trace[318725731] 'read index received' (duration: 229.442903ms)","trace[318725731] 'applied index is now lower than readState.Index' (duration: 20.118095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:35.091007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.799522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-08T16:15:35.091048Z","caller":"traceutil/trace.go:171","msg":"trace[188266396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26676; }","duration":"249.916774ms","start":"2026-07-08T16:15:34.841118Z","end":"2026-07-08T16:15:35.091034Z","steps":["trace[188266396] 'agreement among raft nodes before linearized reading' (duration: 249.768791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:35.091138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.349364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T16:15:35.091173Z","caller":"traceutil/trace.go:171","msg":"trace[294218618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26676; }","duration":"249.430476ms","start":"2026-07-08T16:15:34.841733Z","end":"2026-07-08T16:15:35.091163Z","steps":["trace[294218618] 'agreement among raft nodes before linearized reading' (duration: 249.273283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:35.091185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.393481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-07-08T16:15:35.091221Z","caller":"traceutil/trace.go:171","msg":"trace[1913128390] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26676; }","duration":"102.479281ms","start":"2026-07-08T16:15:34.988731Z","end":"2026-07-08T16:15:35.09121Z","steps":["trace[1913128390] 'agreement among raft nodes before linearized reading' (duration: 102.40939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:35.091434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.762428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-08T16:15:35.091525Z","caller":"traceutil/trace.go:171","msg":"trace[1224618990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26676; }","duration":"230.930632ms","start":"2026-07-08T16:15:34.860565Z","end":"2026-07-08T16:15:35.091495Z","steps":["trace[1224618990] 'agreement among raft nodes before linearized reading' (duration: 230.599046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:35.288857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.125139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:15:35.28904Z","caller":"traceutil/trace.go:171","msg":"trace[2024047448] transaction","detail":"{read_only:false; response_revision:26677; number_of_response:1; }","duration":"191.899064ms","start":"2026-07-08T16:15:35.097089Z","end":"2026-07-08T16:15:35.288988Z","steps":["trace[2024047448] 'process raft request' (duration: 59.52343ms)","trace[2024047448] 'compare' (duration: 131.510568ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:35.302945Z","caller":"traceutil/trace.go:171","msg":"trace[424309854] transaction","detail":"{read_only:false; response_revision:26678; number_of_response:1; }","duration":"205.79152ms","start":"2026-07-08T16:15:35.097134Z","end":"2026-07-08T16:15:35.302926Z","steps":["trace[424309854] 'process raft request' (duration: 205.528345ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:35.303044Z","caller":"traceutil/trace.go:171","msg":"trace[1664422141] transaction","detail":"{read_only:false; response_revision:26679; number_of_response:1; }","duration":"205.865591ms","start":"2026-07-08T16:15:35.097135Z","end":"2026-07-08T16:15:35.303Z","steps":["trace[1664422141] 'process raft request' (duration: 205.669107ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:36.166284Z","caller":"traceutil/trace.go:171","msg":"trace[1913645414] linearizableReadLoop","detail":"{readStateIndex:27568; appliedIndex:27567; }","duration":"147.239759ms","start":"2026-07-08T16:15:36.019016Z","end":"2026-07-08T16:15:36.166255Z","steps":["trace[1913645414] 'read index received' (duration: 147.010895ms)","trace[1913645414] 'applied index is now lower than readState.Index' (duration: 227.904µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:36.166371Z","caller":"traceutil/trace.go:171","msg":"trace[1862483114] transaction","detail":"{read_only:false; response_revision:26683; number_of_response:1; }","duration":"151.086213ms","start":"2026-07-08T16:15:36.015221Z","end":"2026-07-08T16:15:36.166307Z","steps":["trace[1862483114] 'process raft request' (duration: 150.848329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:36.16653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.491613ms","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-07-08T16:15:36.166568Z","caller":"traceutil/trace.go:171","msg":"trace[139079621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26683; }","duration":"147.584736ms","start":"2026-07-08T16:15:36.018973Z","end":"2026-07-08T16:15:36.166557Z","steps":["trace[139079621] 'agreement among raft nodes before linearized reading' (duration: 147.394972ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:46.552091Z","caller":"traceutil/trace.go:171","msg":"trace[616991199] linearizableReadLoop","detail":"{readStateIndex:27712; appliedIndex:27711; }","duration":"232.481551ms","start":"2026-07-08T16:15:46.319575Z","end":"2026-07-08T16:15:46.552057Z","steps":["trace[616991199] 'read index received' (duration: 232.218166ms)","trace[616991199] 'applied index is now lower than readState.Index' (duration: 261.765µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:46.55224Z","caller":"traceutil/trace.go:171","msg":"trace[982599492] transaction","detail":"{read_only:false; response_revision:26824; number_of_response:1; }","duration":"235.623891ms","start":"2026-07-08T16:15:46.316551Z","end":"2026-07-08T16:15:46.552175Z","steps":["trace[982599492] 'process raft request' (duration: 235.302815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:46.552326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.724725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T16:15:46.55242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.631826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-08T16:15:46.552465Z","caller":"traceutil/trace.go:171","msg":"trace[1657489658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26824; }","duration":"229.715478ms","start":"2026-07-08T16:15:46.322739Z","end":"2026-07-08T16:15:46.552455Z","steps":["trace[1657489658] 'agreement among raft nodes before linearized reading' (duration: 229.508124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:46.552678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.303635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:46.552421Z","caller":"traceutil/trace.go:171","msg":"trace[353206488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26824; }","duration":"232.872978ms","start":"2026-07-08T16:15:46.319527Z","end":"2026-07-08T16:15:46.5524Z","steps":["trace[353206488] 'agreement among raft nodes before linearized reading' (duration: 232.668604ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:46.552734Z","caller":"traceutil/trace.go:171","msg":"trace[1600527863] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26824; }","duration":"153.441087ms","start":"2026-07-08T16:15:46.399282Z","end":"2026-07-08T16:15:46.552723Z","steps":["trace[1600527863] 'agreement among raft nodes before linearized reading' (duration: 153.297575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:46.553697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.559265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224964"} {"level":"info","ts":"2026-07-08T16:15:46.553788Z","caller":"traceutil/trace.go:171","msg":"trace[1024788810] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:26824; }","duration":"147.685208ms","start":"2026-07-08T16:15:46.406085Z","end":"2026-07-08T16:15:46.55377Z","steps":["trace[1024788810] 'agreement among raft nodes before linearized reading' (duration: 146.420614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:46.799198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.363476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:15:46.799379Z","caller":"traceutil/trace.go:171","msg":"trace[2085772854] transaction","detail":"{read_only:false; response_revision:26825; number_of_response:1; }","duration":"237.074348ms","start":"2026-07-08T16:15:46.562264Z","end":"2026-07-08T16:15:46.799338Z","steps":["trace[2085772854] 'process raft request' (duration: 95.449667ms)","trace[2085772854] 'compare' (duration: 141.076481ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:46.799475Z","caller":"traceutil/trace.go:171","msg":"trace[76684805] transaction","detail":"{read_only:false; response_revision:26826; number_of_response:1; }","duration":"172.952751ms","start":"2026-07-08T16:15:46.626501Z","end":"2026-07-08T16:15:46.799454Z","steps":["trace[76684805] 'process raft request' (duration: 172.856689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:46.799706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.065412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-07-08T16:15:46.799464Z","caller":"traceutil/trace.go:171","msg":"trace[198163556] linearizableReadLoop","detail":"{readStateIndex:27713; appliedIndex:27712; }","duration":"209.798146ms","start":"2026-07-08T16:15:46.589631Z","end":"2026-07-08T16:15:46.799429Z","steps":["trace[198163556] 'read index received' (duration: 68.102084ms)","trace[198163556] 'applied index is now lower than readState.Index' (duration: 141.691852ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:46.79977Z","caller":"traceutil/trace.go:171","msg":"trace[1270535773] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:26826; }","duration":"210.161964ms","start":"2026-07-08T16:15:46.58959Z","end":"2026-07-08T16:15:46.799752Z","steps":["trace[1270535773] 'agreement among raft nodes before linearized reading' (duration: 209.918959ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:46.960031Z","caller":"traceutil/trace.go:171","msg":"trace[798700821] linearizableReadLoop","detail":"{readStateIndex:27715; appliedIndex:27714; }","duration":"160.229537ms","start":"2026-07-08T16:15:46.799775Z","end":"2026-07-08T16:15:46.960004Z","steps":["trace[798700821] 'read index received' (duration: 158.202709ms)","trace[798700821] 'applied index is now lower than readState.Index' (duration: 2.025468ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:46.960377Z","caller":"traceutil/trace.go:171","msg":"trace[1021066173] transaction","detail":"{read_only:false; response_revision:26827; number_of_response:1; }","duration":"238.908524ms","start":"2026-07-08T16:15:46.721454Z","end":"2026-07-08T16:15:46.960363Z","steps":["trace[1021066173] 'process raft request' (duration: 236.60234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:46.960694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.81072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:46.960744Z","caller":"traceutil/trace.go:171","msg":"trace[952578853] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:26827; }","duration":"265.959022ms","start":"2026-07-08T16:15:46.694773Z","end":"2026-07-08T16:15:46.960732Z","steps":["trace[952578853] 'agreement among raft nodes before linearized reading' (duration: 265.84481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:46.961065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.525425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-07-08T16:15:46.961113Z","caller":"traceutil/trace.go:171","msg":"trace[1453974068] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:26827; }","duration":"134.624827ms","start":"2026-07-08T16:15:46.826478Z","end":"2026-07-08T16:15:46.961102Z","steps":["trace[1453974068] 'agreement among raft nodes before linearized reading' (duration: 134.466124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:46.961486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.673973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-695c38c8-8419-44c9-b4be-cfa0cd288e1e\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-08T16:15:46.96155Z","caller":"traceutil/trace.go:171","msg":"trace[1613098229] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-695c38c8-8419-44c9-b4be-cfa0cd288e1e; range_end:; response_count:1; response_revision:26827; }","duration":"117.779435ms","start":"2026-07-08T16:15:46.843761Z","end":"2026-07-08T16:15:46.96154Z","steps":["trace[1613098229] 'agreement among raft nodes before linearized reading' (duration: 117.609962ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:47.199205Z","caller":"traceutil/trace.go:171","msg":"trace[1888577665] transaction","detail":"{read_only:false; response_revision:26828; number_of_response:1; }","duration":"176.399767ms","start":"2026-07-08T16:15:47.022778Z","end":"2026-07-08T16:15:47.199178Z","steps":["trace[1888577665] 'process raft request' (duration: 163.229135ms)","trace[1888577665] 'compare' (duration: 13.03857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:47.482755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.874224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:47.482869Z","caller":"traceutil/trace.go:171","msg":"trace[1601596280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26833; }","duration":"179.072359ms","start":"2026-07-08T16:15:47.303772Z","end":"2026-07-08T16:15:47.482844Z","steps":["trace[1601596280] 'range keys from in-memory index tree' (duration: 178.748392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:47.483659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.085437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-08T16:15:47.483759Z","caller":"traceutil/trace.go:171","msg":"trace[936531001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26833; }","duration":"123.25173ms","start":"2026-07-08T16:15:47.360486Z","end":"2026-07-08T16:15:47.483738Z","steps":["trace[936531001] 'range keys from in-memory index tree' (duration: 122.866693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:47.484811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.781444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-08T16:15:47.484898Z","caller":"traceutil/trace.go:171","msg":"trace[638087423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26833; }","duration":"119.870196ms","start":"2026-07-08T16:15:47.365021Z","end":"2026-07-08T16:15:47.484891Z","steps":["trace[638087423] 'range keys from in-memory index tree' (duration: 118.906676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:47.485096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.631663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T16:15:47.485166Z","caller":"traceutil/trace.go:171","msg":"trace[536842710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26833; }","duration":"115.736315ms","start":"2026-07-08T16:15:47.369412Z","end":"2026-07-08T16:15:47.485149Z","steps":["trace[536842710] 'range keys from in-memory index tree' (duration: 114.968921ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:48.100078Z","caller":"traceutil/trace.go:171","msg":"trace[1877367597] linearizableReadLoop","detail":"{readStateIndex:27728; appliedIndex:27727; }","duration":"186.560102ms","start":"2026-07-08T16:15:47.913484Z","end":"2026-07-08T16:15:48.100044Z","steps":["trace[1877367597] 'read index received' (duration: 133.236391ms)","trace[1877367597] 'applied index is now lower than readState.Index' (duration: 53.321961ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:48.100174Z","caller":"traceutil/trace.go:171","msg":"trace[1811455770] transaction","detail":"{read_only:false; response_revision:26840; number_of_response:1; }","duration":"186.678714ms","start":"2026-07-08T16:15:47.913433Z","end":"2026-07-08T16:15:48.100112Z","steps":["trace[1811455770] 'process raft request' (duration: 133.475145ms)","trace[1811455770] 'compare' (duration: 52.793651ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:48.1004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.738176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"warn","ts":"2026-07-08T16:15:48.100447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.075935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-cluster-api-proxy-shjvr\" ","response":"range_response_count:1 size:5276"} {"level":"info","ts":"2026-07-08T16:15:48.101202Z","caller":"traceutil/trace.go:171","msg":"trace[1556802111] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26840; }","duration":"187.548531ms","start":"2026-07-08T16:15:47.913612Z","end":"2026-07-08T16:15:48.10116Z","steps":["trace[1556802111] 'agreement among raft nodes before linearized reading' (duration: 186.694105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:48.10123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.14636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-695c38c8-8419-44c9-b4be-cfa0cd288e1e\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-07-08T16:15:48.101264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.886681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-cluster-api-proxy-shjvr.18c05ca5e8c1c470\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-07-08T16:15:48.101292Z","caller":"traceutil/trace.go:171","msg":"trace[86970749] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-695c38c8-8419-44c9-b4be-cfa0cd288e1e; range_end:; response_count:1; response_revision:26840; }","duration":"133.455915ms","start":"2026-07-08T16:15:47.967816Z","end":"2026-07-08T16:15:48.101272Z","steps":["trace[86970749] 'agreement among raft nodes before linearized reading' (duration: 132.694381ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:48.101329Z","caller":"traceutil/trace.go:171","msg":"trace[352927731] range","detail":"{range_begin:/registry/events/openstack/magnum-cluster-api-proxy-shjvr.18c05ca5e8c1c470; range_end:; response_count:1; response_revision:26840; }","duration":"184.014433ms","start":"2026-07-08T16:15:47.917292Z","end":"2026-07-08T16:15:48.101306Z","steps":["trace[352927731] 'agreement among raft nodes before linearized reading' (duration: 183.354551ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:48.101252Z","caller":"traceutil/trace.go:171","msg":"trace[180418367] range","detail":"{range_begin:/registry/pods/openstack/magnum-cluster-api-proxy-shjvr; range_end:; response_count:1; response_revision:26840; }","duration":"183.906701ms","start":"2026-07-08T16:15:47.917323Z","end":"2026-07-08T16:15:48.10123Z","steps":["trace[180418367] 'agreement among raft nodes before linearized reading' (duration: 182.988344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:48.102966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.93657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:1137"} {"level":"info","ts":"2026-07-08T16:15:48.103057Z","caller":"traceutil/trace.go:171","msg":"trace[556741542] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:26840; }","duration":"189.587249ms","start":"2026-07-08T16:15:47.913421Z","end":"2026-07-08T16:15:48.103009Z","steps":["trace[556741542] 'agreement among raft nodes before linearized reading' (duration: 186.810256ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:48.285712Z","caller":"traceutil/trace.go:171","msg":"trace[659681612] linearizableReadLoop","detail":"{readStateIndex:27730; appliedIndex:27729; }","duration":"162.201865ms","start":"2026-07-08T16:15:48.123483Z","end":"2026-07-08T16:15:48.285685Z","steps":["trace[659681612] 'read index received' (duration: 137.960511ms)","trace[659681612] 'applied index is now lower than readState.Index' (duration: 24.238164ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:48.285738Z","caller":"traceutil/trace.go:171","msg":"trace[610602844] transaction","detail":"{read_only:false; response_revision:26843; number_of_response:1; }","duration":"160.044634ms","start":"2026-07-08T16:15:48.12567Z","end":"2026-07-08T16:15:48.285715Z","steps":["trace[610602844] 'process raft request' (duration: 159.726628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:48.285935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.241031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-07-08T16:15:48.285968Z","caller":"traceutil/trace.go:171","msg":"trace[1722696497] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26844; }","duration":"180.306132ms","start":"2026-07-08T16:15:48.105649Z","end":"2026-07-08T16:15:48.285955Z","steps":["trace[1722696497] 'agreement among raft nodes before linearized reading' (duration: 180.122949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:48.286048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.739143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:48.286083Z","caller":"traceutil/trace.go:171","msg":"trace[1619707817] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:26844; }","duration":"177.816944ms","start":"2026-07-08T16:15:48.108255Z","end":"2026-07-08T16:15:48.286072Z","steps":["trace[1619707817] 'agreement among raft nodes before linearized reading' (duration: 177.740313ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:48.286117Z","caller":"traceutil/trace.go:171","msg":"trace[1685355680] transaction","detail":"{read_only:false; response_revision:26842; number_of_response:1; }","duration":"176.858646ms","start":"2026-07-08T16:15:48.109177Z","end":"2026-07-08T16:15:48.286035Z","steps":["trace[1685355680] 'process raft request' (duration: 152.242875ms)","trace[1685355680] 'compare' (duration: 23.762465ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:48.46332Z","caller":"traceutil/trace.go:171","msg":"trace[1954726657] linearizableReadLoop","detail":"{readStateIndex:27733; appliedIndex:27732; }","duration":"140.593643ms","start":"2026-07-08T16:15:48.322699Z","end":"2026-07-08T16:15:48.463293Z","steps":["trace[1954726657] 'read index received' (duration: 130.827566ms)","trace[1954726657] 'applied index is now lower than readState.Index' (duration: 9.764887ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:48.463437Z","caller":"traceutil/trace.go:171","msg":"trace[1204871561] transaction","detail":"{read_only:false; response_revision:26845; number_of_response:1; }","duration":"143.253792ms","start":"2026-07-08T16:15:48.320156Z","end":"2026-07-08T16:15:48.46341Z","steps":["trace[1204871561] 'process raft request' (duration: 133.382963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:48.463667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.910268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-08T16:15:48.463781Z","caller":"traceutil/trace.go:171","msg":"trace[304488225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26845; }","duration":"141.079691ms","start":"2026-07-08T16:15:48.322671Z","end":"2026-07-08T16:15:48.463751Z","steps":["trace[304488225] 'agreement among raft nodes before linearized reading' (duration: 140.813496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:48.776364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.368055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:15:48.776539Z","caller":"traceutil/trace.go:171","msg":"trace[725058124] transaction","detail":"{read_only:false; response_revision:26846; number_of_response:1; }","duration":"307.309273ms","start":"2026-07-08T16:15:48.469196Z","end":"2026-07-08T16:15:48.776506Z","steps":["trace[725058124] 'process raft request' (duration: 128.632032ms)","trace[725058124] 'compare' (duration: 178.182282ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:48.776645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:15:48.469149Z","time spent":"307.447456ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:15:48.824006Z","caller":"traceutil/trace.go:171","msg":"trace[455602645] transaction","detail":"{read_only:false; response_revision:26847; number_of_response:1; }","duration":"251.871341ms","start":"2026-07-08T16:15:48.572113Z","end":"2026-07-08T16:15:48.823984Z","steps":["trace[455602645] 'process raft request' (duration: 251.676367ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:48.945843Z","caller":"traceutil/trace.go:171","msg":"trace[112670071] linearizableReadLoop","detail":"{readStateIndex:27736; appliedIndex:27735; }","duration":"121.852983ms","start":"2026-07-08T16:15:48.823967Z","end":"2026-07-08T16:15:48.94582Z","steps":["trace[112670071] 'read index received' (duration: 118.405088ms)","trace[112670071] 'applied index is now lower than readState.Index' (duration: 3.446395ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:48.94586Z","caller":"traceutil/trace.go:171","msg":"trace[1626168901] transaction","detail":"{read_only:false; response_revision:26848; number_of_response:1; }","duration":"136.003423ms","start":"2026-07-08T16:15:48.809812Z","end":"2026-07-08T16:15:48.945815Z","steps":["trace[1626168901] 'process raft request' (duration: 132.541847ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:48.945983Z","caller":"traceutil/trace.go:171","msg":"trace[58181905] transaction","detail":"{read_only:false; response_revision:26849; number_of_response:1; }","duration":"118.070731ms","start":"2026-07-08T16:15:48.827897Z","end":"2026-07-08T16:15:48.945967Z","steps":["trace[58181905] 'process raft request' (duration: 117.876437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:48.946285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.787395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-695c38c8-8419-44c9-b4be-cfa0cd288e1e\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-08T16:15:48.946438Z","caller":"traceutil/trace.go:171","msg":"trace[1071695305] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-695c38c8-8419-44c9-b4be-cfa0cd288e1e; range_end:; response_count:1; response_revision:26849; }","duration":"129.998039ms","start":"2026-07-08T16:15:48.816406Z","end":"2026-07-08T16:15:48.946404Z","steps":["trace[1071695305] 'agreement among raft nodes before linearized reading' (duration: 129.523359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:48.946536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.67403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:48.946603Z","caller":"traceutil/trace.go:171","msg":"trace[2044962718] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:26849; }","duration":"132.811813ms","start":"2026-07-08T16:15:48.813775Z","end":"2026-07-08T16:15:48.946587Z","steps":["trace[2044962718] 'agreement among raft nodes before linearized reading' (duration: 132.135879ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:49.146288Z","caller":"traceutil/trace.go:171","msg":"trace[723999407] linearizableReadLoop","detail":"{readStateIndex:27738; appliedIndex:27737; }","duration":"187.857638ms","start":"2026-07-08T16:15:48.958406Z","end":"2026-07-08T16:15:49.146263Z","steps":["trace[723999407] 'read index received' (duration: 187.553911ms)","trace[723999407] 'applied index is now lower than readState.Index' (duration: 302.117µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:49.146408Z","caller":"traceutil/trace.go:171","msg":"trace[1215809834] transaction","detail":"{read_only:false; response_revision:26850; number_of_response:1; }","duration":"189.933417ms","start":"2026-07-08T16:15:48.956434Z","end":"2026-07-08T16:15:49.146367Z","steps":["trace[1215809834] 'process raft request' (duration: 189.60512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:49.146478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.049491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-08T16:15:49.146821Z","caller":"traceutil/trace.go:171","msg":"trace[1151533950] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:26850; }","duration":"188.378847ms","start":"2026-07-08T16:15:48.958378Z","end":"2026-07-08T16:15:49.146757Z","steps":["trace[1151533950] 'agreement among raft nodes before linearized reading' (duration: 188.01228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:49.146872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.777947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:49.14694Z","caller":"traceutil/trace.go:171","msg":"trace[1652673674] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:26850; }","duration":"174.871288ms","start":"2026-07-08T16:15:48.972053Z","end":"2026-07-08T16:15:49.146924Z","steps":["trace[1652673674] 'agreement among raft nodes before linearized reading' (duration: 174.777496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:49.147015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.456116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:49.14707Z","caller":"traceutil/trace.go:171","msg":"trace[1832809076] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26850; }","duration":"108.578958ms","start":"2026-07-08T16:15:49.038474Z","end":"2026-07-08T16:15:49.147053Z","steps":["trace[1832809076] 'agreement among raft nodes before linearized reading' (duration: 108.411405ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:49.388328Z","caller":"traceutil/trace.go:171","msg":"trace[1954289517] transaction","detail":"{read_only:false; response_revision:26851; number_of_response:1; }","duration":"170.476244ms","start":"2026-07-08T16:15:49.217789Z","end":"2026-07-08T16:15:49.388266Z","steps":["trace[1954289517] 'process raft request' (duration: 147.067126ms)","trace[1954289517] 'compare' (duration: 23.223284ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:49.474302Z","caller":"traceutil/trace.go:171","msg":"trace[1303823725] transaction","detail":"{read_only:false; response_revision:26852; number_of_response:1; }","duration":"256.103173ms","start":"2026-07-08T16:15:49.218172Z","end":"2026-07-08T16:15:49.474275Z","steps":["trace[1303823725] 'process raft request' (duration: 255.802118ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:49.474487Z","caller":"traceutil/trace.go:171","msg":"trace[417898082] transaction","detail":"{read_only:false; response_revision:26854; number_of_response:1; }","duration":"253.32027ms","start":"2026-07-08T16:15:49.221152Z","end":"2026-07-08T16:15:49.474472Z","steps":["trace[417898082] 'process raft request' (duration: 253.081026ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:49.474489Z","caller":"traceutil/trace.go:171","msg":"trace[1680170954] linearizableReadLoop","detail":"{readStateIndex:27743; appliedIndex:27738; }","duration":"170.721798ms","start":"2026-07-08T16:15:49.303747Z","end":"2026-07-08T16:15:49.474468Z","steps":["trace[1680170954] 'read index received' (duration: 61.151721ms)","trace[1680170954] 'applied index is now lower than readState.Index' (duration: 109.569047ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:49.474306Z","caller":"traceutil/trace.go:171","msg":"trace[1516179708] transaction","detail":"{read_only:false; response_revision:26853; number_of_response:1; }","duration":"254.36744ms","start":"2026-07-08T16:15:49.21991Z","end":"2026-07-08T16:15:49.474277Z","steps":["trace[1516179708] 'process raft request' (duration: 254.255968ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:49.474591Z","caller":"traceutil/trace.go:171","msg":"trace[1420936677] transaction","detail":"{read_only:false; response_revision:26855; number_of_response:1; }","duration":"252.029245ms","start":"2026-07-08T16:15:49.222551Z","end":"2026-07-08T16:15:49.47458Z","steps":["trace[1420936677] 'process raft request' (duration: 251.818431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:49.474756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.992584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:49.474854Z","caller":"traceutil/trace.go:171","msg":"trace[1640703220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26855; }","duration":"171.179877ms","start":"2026-07-08T16:15:49.303651Z","end":"2026-07-08T16:15:49.474831Z","steps":["trace[1640703220] 'agreement among raft nodes before linearized reading' (duration: 170.993583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:49.736444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.276988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-08T16:15:49.736554Z","caller":"traceutil/trace.go:171","msg":"trace[1600077398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26855; }","duration":"172.432701ms","start":"2026-07-08T16:15:49.564097Z","end":"2026-07-08T16:15:49.73653Z","steps":["trace[1600077398] 'range keys from in-memory index tree' (duration: 172.049014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:49.736627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.056489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:15:49.736738Z","caller":"traceutil/trace.go:171","msg":"trace[1967306119] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26855; }","duration":"189.249493ms","start":"2026-07-08T16:15:49.547464Z","end":"2026-07-08T16:15:49.736714Z","steps":["trace[1967306119] 'range keys from in-memory index tree' (duration: 188.909067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:49.736807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.898119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-08T16:15:49.73691Z","caller":"traceutil/trace.go:171","msg":"trace[1038171536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26855; }","duration":"174.047032ms","start":"2026-07-08T16:15:49.562836Z","end":"2026-07-08T16:15:49.736883Z","steps":["trace[1038171536] 'range keys from in-memory index tree' (duration: 173.642164ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:49.737024Z","caller":"traceutil/trace.go:171","msg":"trace[1283748500] transaction","detail":"{read_only:false; response_revision:26856; number_of_response:1; }","duration":"172.567403ms","start":"2026-07-08T16:15:49.564397Z","end":"2026-07-08T16:15:49.736965Z","steps":["trace[1283748500] 'process raft request' (duration: 171.485013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:49.73707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.934919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T16:15:49.737232Z","caller":"traceutil/trace.go:171","msg":"trace[778742972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26855; }","duration":"174.154495ms","start":"2026-07-08T16:15:49.563026Z","end":"2026-07-08T16:15:49.73718Z","steps":["trace[778742972] 'range keys from in-memory index tree' (duration: 173.655195ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:49.897302Z","caller":"traceutil/trace.go:171","msg":"trace[99322199] linearizableReadLoop","detail":"{readStateIndex:27747; appliedIndex:27744; }","duration":"152.882797ms","start":"2026-07-08T16:15:49.744384Z","end":"2026-07-08T16:15:49.897267Z","steps":["trace[99322199] 'read index received' (duration: 14.248643ms)","trace[99322199] 'applied index is now lower than readState.Index' (duration: 138.632594ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:49.89737Z","caller":"traceutil/trace.go:171","msg":"trace[917363845] transaction","detail":"{read_only:false; response_revision:26858; number_of_response:1; }","duration":"154.500148ms","start":"2026-07-08T16:15:49.742817Z","end":"2026-07-08T16:15:49.897317Z","steps":["trace[917363845] 'process raft request' (duration: 112.362471ms)","trace[917363845] 'compare' (duration: 41.856282ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:49.897543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.137291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-08T16:15:49.897611Z","caller":"traceutil/trace.go:171","msg":"trace[1088214990] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26859; }","duration":"153.263404ms","start":"2026-07-08T16:15:49.744334Z","end":"2026-07-08T16:15:49.897597Z","steps":["trace[1088214990] 'agreement among raft nodes before linearized reading' (duration: 153.072801ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:49.897624Z","caller":"traceutil/trace.go:171","msg":"trace[715550028] transaction","detail":"{read_only:false; response_revision:26859; number_of_response:1; }","duration":"154.150172ms","start":"2026-07-08T16:15:49.743206Z","end":"2026-07-08T16:15:49.897357Z","steps":["trace[715550028] 'process raft request' (duration: 153.996459ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:56.96572Z","caller":"traceutil/trace.go:171","msg":"trace[1946629418] transaction","detail":"{read_only:false; response_revision:26936; number_of_response:1; }","duration":"112.914361ms","start":"2026-07-08T16:15:56.852747Z","end":"2026-07-08T16:15:56.965662Z","steps":["trace[1946629418] 'process raft request' (duration: 111.382062ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:57.2085Z","caller":"traceutil/trace.go:171","msg":"trace[419504385] transaction","detail":"{read_only:false; response_revision:26938; number_of_response:1; }","duration":"224.468609ms","start":"2026-07-08T16:15:56.984Z","end":"2026-07-08T16:15:57.208469Z","steps":["trace[419504385] 'process raft request' (duration: 224.399407ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:57.208623Z","caller":"traceutil/trace.go:171","msg":"trace[269553810] transaction","detail":"{read_only:false; response_revision:26937; number_of_response:1; }","duration":"233.536802ms","start":"2026-07-08T16:15:56.975058Z","end":"2026-07-08T16:15:57.208595Z","steps":["trace[269553810] 'process raft request' (duration: 135.25103ms)","trace[269553810] 'compare' (duration: 97.880864ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:15:57.208779Z","caller":"traceutil/trace.go:171","msg":"trace[1542169311] linearizableReadLoop","detail":"{readStateIndex:27828; appliedIndex:27827; }","duration":"229.500534ms","start":"2026-07-08T16:15:56.979272Z","end":"2026-07-08T16:15:57.208773Z","steps":["trace[1542169311] 'read index received' (duration: 131.052439ms)","trace[1542169311] 'applied index is now lower than readState.Index' (duration: 98.447265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:15:57.209256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.954882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-08T16:15:57.209315Z","caller":"traceutil/trace.go:171","msg":"trace[323462735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26938; }","duration":"230.096185ms","start":"2026-07-08T16:15:56.979205Z","end":"2026-07-08T16:15:57.209301Z","steps":["trace[323462735] 'agreement among raft nodes before linearized reading' (duration: 229.902711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:15:57.210225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.909256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418324"} {"level":"info","ts":"2026-07-08T16:15:57.210263Z","caller":"traceutil/trace.go:171","msg":"trace[1231999246] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26938; }","duration":"107.041259ms","start":"2026-07-08T16:15:57.103213Z","end":"2026-07-08T16:15:57.210255Z","steps":["trace[1231999246] 'agreement among raft nodes before linearized reading' (duration: 106.754204ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:15:57.365539Z","caller":"traceutil/trace.go:171","msg":"trace[475209553] transaction","detail":"{read_only:false; response_revision:26939; number_of_response:1; }","duration":"149.38359ms","start":"2026-07-08T16:15:57.216131Z","end":"2026-07-08T16:15:57.365515Z","steps":["trace[475209553] 'process raft request' (duration: 149.216267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:04.13642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.334724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:16:04.136619Z","caller":"traceutil/trace.go:171","msg":"trace[1788321908] linearizableReadLoop","detail":"{readStateIndex:27897; appliedIndex:27895; }","duration":"220.548123ms","start":"2026-07-08T16:16:03.91605Z","end":"2026-07-08T16:16:04.136598Z","steps":["trace[1788321908] 'read index received' (duration: 51.951206ms)","trace[1788321908] 'applied index is now lower than readState.Index' (duration: 168.595627ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:04.136696Z","caller":"traceutil/trace.go:171","msg":"trace[1012381202] transaction","detail":"{read_only:false; response_revision:27005; number_of_response:1; }","duration":"278.761117ms","start":"2026-07-08T16:16:03.857924Z","end":"2026-07-08T16:16:04.136685Z","steps":["trace[1012381202] 'process raft request' (duration: 278.619084ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:04.136741Z","caller":"traceutil/trace.go:171","msg":"trace[1125870306] transaction","detail":"{read_only:false; response_revision:27004; number_of_response:1; }","duration":"293.554971ms","start":"2026-07-08T16:16:03.843126Z","end":"2026-07-08T16:16:04.136681Z","steps":["trace[1125870306] 'process raft request' (duration: 124.859201ms)","trace[1125870306] 'compare' (duration: 167.889004ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:04.136813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.741177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:16:04.13688Z","caller":"traceutil/trace.go:171","msg":"trace[1482259206] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:27005; }","duration":"220.882089ms","start":"2026-07-08T16:16:03.915981Z","end":"2026-07-08T16:16:04.136863Z","steps":["trace[1482259206] 'agreement among raft nodes before linearized reading' (duration: 220.763217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:04.166057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.763903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-conductor-0\" ","response":"range_response_count:1 size:10441"} {"level":"info","ts":"2026-07-08T16:16:04.166144Z","caller":"traceutil/trace.go:171","msg":"trace[1061648878] transaction","detail":"{read_only:false; response_revision:27006; number_of_response:1; }","duration":"197.755196ms","start":"2026-07-08T16:16:03.968369Z","end":"2026-07-08T16:16:04.166124Z","steps":["trace[1061648878] 'process raft request' (duration: 197.150645ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:04.166152Z","caller":"traceutil/trace.go:171","msg":"trace[315200259] range","detail":"{range_begin:/registry/pods/openstack/magnum-conductor-0; range_end:; response_count:1; response_revision:27006; }","duration":"248.910565ms","start":"2026-07-08T16:16:03.917218Z","end":"2026-07-08T16:16:04.166129Z","steps":["trace[315200259] 'agreement among raft nodes before linearized reading' (duration: 248.61008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:04.166124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.478991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-08T16:16:04.166247Z","caller":"traceutil/trace.go:171","msg":"trace[910686719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27006; }","duration":"196.654654ms","start":"2026-07-08T16:16:03.969568Z","end":"2026-07-08T16:16:04.166222Z","steps":["trace[910686719] 'agreement among raft nodes before linearized reading' (duration: 196.316328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:04.166354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.737003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-conductor-0.18c05ca8114d4309\" ","response":"range_response_count:1 size:830"} {"level":"info","ts":"2026-07-08T16:16:04.166409Z","caller":"traceutil/trace.go:171","msg":"trace[1327585155] range","detail":"{range_begin:/registry/events/openstack/magnum-conductor-0.18c05ca8114d4309; range_end:; response_count:1; response_revision:27006; }","duration":"247.844595ms","start":"2026-07-08T16:16:03.91855Z","end":"2026-07-08T16:16:04.166395Z","steps":["trace[1327585155] 'agreement among raft nodes before linearized reading' (duration: 247.386687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:04.166535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.881169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T16:16:04.166588Z","caller":"traceutil/trace.go:171","msg":"trace[621626136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27006; }","duration":"196.95346ms","start":"2026-07-08T16:16:03.969621Z","end":"2026-07-08T16:16:04.166575Z","steps":["trace[621626136] 'agreement among raft nodes before linearized reading' (duration: 196.241406ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:04.346053Z","caller":"traceutil/trace.go:171","msg":"trace[1841997233] linearizableReadLoop","detail":"{readStateIndex:27902; appliedIndex:27898; }","duration":"134.437665ms","start":"2026-07-08T16:16:04.211582Z","end":"2026-07-08T16:16:04.34602Z","steps":["trace[1841997233] 'read index received' (duration: 6.732879ms)","trace[1841997233] 'applied index is now lower than readState.Index' (duration: 127.659504ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:04.346195Z","caller":"traceutil/trace.go:171","msg":"trace[852071082] transaction","detail":"{read_only:false; response_revision:27010; number_of_response:1; }","duration":"169.939544ms","start":"2026-07-08T16:16:04.176235Z","end":"2026-07-08T16:16:04.346175Z","steps":["trace[852071082] 'process raft request' (duration: 169.654368ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:04.346194Z","caller":"traceutil/trace.go:171","msg":"trace[1338905967] transaction","detail":"{read_only:false; response_revision:27009; number_of_response:1; }","duration":"174.119754ms","start":"2026-07-08T16:16:04.172044Z","end":"2026-07-08T16:16:04.346164Z","steps":["trace[1338905967] 'process raft request' (duration: 173.775296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:04.346423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.816792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-08T16:16:04.346042Z","caller":"traceutil/trace.go:171","msg":"trace[60341539] transaction","detail":"{read_only:false; response_revision:27008; number_of_response:1; }","duration":"174.141613ms","start":"2026-07-08T16:16:04.17179Z","end":"2026-07-08T16:16:04.345932Z","steps":["trace[60341539] 'process raft request' (duration: 173.231757ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:04.346535Z","caller":"traceutil/trace.go:171","msg":"trace[817667798] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:27010; }","duration":"134.979055ms","start":"2026-07-08T16:16:04.211522Z","end":"2026-07-08T16:16:04.346501Z","steps":["trace[817667798] 'agreement among raft nodes before linearized reading' (duration: 134.71538ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:08.485227Z","caller":"traceutil/trace.go:171","msg":"trace[137381489] transaction","detail":"{read_only:false; response_revision:27041; number_of_response:1; }","duration":"109.597609ms","start":"2026-07-08T16:16:08.375598Z","end":"2026-07-08T16:16:08.485195Z","steps":["trace[137381489] 'process raft request' (duration: 109.404516ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:08.517561Z","caller":"traceutil/trace.go:171","msg":"trace[1700029956] linearizableReadLoop","detail":"{readStateIndex:27935; appliedIndex:27933; }","duration":"122.602408ms","start":"2026-07-08T16:16:08.394923Z","end":"2026-07-08T16:16:08.517525Z","steps":["trace[1700029956] 'read index received' (duration: 90.143697ms)","trace[1700029956] 'applied index is now lower than readState.Index' (duration: 32.457131ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:08.517633Z","caller":"traceutil/trace.go:171","msg":"trace[1719174810] transaction","detail":"{read_only:false; response_revision:27042; number_of_response:1; }","duration":"141.449287ms","start":"2026-07-08T16:16:08.376152Z","end":"2026-07-08T16:16:08.517601Z","steps":["trace[1719174810] 'process raft request' (duration: 140.937528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:08.517725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.752211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:16:08.517827Z","caller":"traceutil/trace.go:171","msg":"trace[1044419138] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:27042; }","duration":"122.938343ms","start":"2026-07-08T16:16:08.394873Z","end":"2026-07-08T16:16:08.517812Z","steps":["trace[1044419138] 'agreement among raft nodes before linearized reading' (duration: 122.76691ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:12.323546Z","caller":"traceutil/trace.go:171","msg":"trace[2098614703] transaction","detail":"{read_only:false; response_revision:27072; number_of_response:1; }","duration":"144.70551ms","start":"2026-07-08T16:16:12.178809Z","end":"2026-07-08T16:16:12.323515Z","steps":["trace[2098614703] 'process raft request' (duration: 144.548457ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:12.332245Z","caller":"traceutil/trace.go:171","msg":"trace[728506104] transaction","detail":"{read_only:false; response_revision:27073; number_of_response:1; }","duration":"137.816328ms","start":"2026-07-08T16:16:12.194403Z","end":"2026-07-08T16:16:12.33222Z","steps":["trace[728506104] 'process raft request' (duration: 137.461321ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:13.813578Z","caller":"traceutil/trace.go:171","msg":"trace[339573358] transaction","detail":"{read_only:false; response_revision:27083; number_of_response:1; }","duration":"241.88341ms","start":"2026-07-08T16:16:13.571666Z","end":"2026-07-08T16:16:13.81355Z","steps":["trace[339573358] 'process raft request' (duration: 241.612895ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:13.813808Z","caller":"traceutil/trace.go:171","msg":"trace[1080939367] transaction","detail":"{read_only:false; response_revision:27084; number_of_response:1; }","duration":"239.850372ms","start":"2026-07-08T16:16:13.573944Z","end":"2026-07-08T16:16:13.813794Z","steps":["trace[1080939367] 'process raft request' (duration: 239.547346ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:13.813796Z","caller":"traceutil/trace.go:171","msg":"trace[1044759768] linearizableReadLoop","detail":"{readStateIndex:27978; appliedIndex:27976; }","duration":"237.854404ms","start":"2026-07-08T16:16:13.575897Z","end":"2026-07-08T16:16:13.813751Z","steps":["trace[1044759768] 'read index received' (duration: 237.344653ms)","trace[1044759768] 'applied index is now lower than readState.Index' (duration: 506.541µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:13.81396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.034418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:16:13.814009Z","caller":"traceutil/trace.go:171","msg":"trace[1971945880] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:27084; }","duration":"238.136319ms","start":"2026-07-08T16:16:13.575859Z","end":"2026-07-08T16:16:13.813995Z","steps":["trace[1971945880] 'agreement among raft nodes before linearized reading' (duration: 238.037957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:13.814069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.969393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:16:13.814126Z","caller":"traceutil/trace.go:171","msg":"trace[1510876897] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:27084; }","duration":"160.118346ms","start":"2026-07-08T16:16:13.653993Z","end":"2026-07-08T16:16:13.814112Z","steps":["trace[1510876897] 'agreement among raft nodes before linearized reading' (duration: 159.960733ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:18.472994Z","caller":"traceutil/trace.go:171","msg":"trace[1110522684] transaction","detail":"{read_only:false; response_revision:27137; number_of_response:1; }","duration":"120.214771ms","start":"2026-07-08T16:16:18.352715Z","end":"2026-07-08T16:16:18.472929Z","steps":["trace[1110522684] 'process raft request' (duration: 119.61488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:18.691234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.957629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-07-08T16:16:18.691319Z","caller":"traceutil/trace.go:171","msg":"trace[1315020919] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:27137; }","duration":"176.076261ms","start":"2026-07-08T16:16:18.515222Z","end":"2026-07-08T16:16:18.691299Z","steps":["trace[1315020919] 'range keys from in-memory index tree' (duration: 175.794626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:18.691789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.70334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-08T16:16:18.691849Z","caller":"traceutil/trace.go:171","msg":"trace[1886468467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27137; }","duration":"144.796723ms","start":"2026-07-08T16:16:18.547022Z","end":"2026-07-08T16:16:18.691818Z","steps":["trace[1886468467] 'range keys from in-memory index tree' (duration: 144.563258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:18.692056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.185502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T16:16:18.692089Z","caller":"traceutil/trace.go:171","msg":"trace[908473255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27137; }","duration":"131.247443ms","start":"2026-07-08T16:16:18.560833Z","end":"2026-07-08T16:16:18.692081Z","steps":["trace[908473255] 'range keys from in-memory index tree' (duration: 131.10051ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:18.895572Z","caller":"traceutil/trace.go:171","msg":"trace[871955267] linearizableReadLoop","detail":"{readStateIndex:28034; appliedIndex:28032; }","duration":"196.661695ms","start":"2026-07-08T16:16:18.69888Z","end":"2026-07-08T16:16:18.895542Z","steps":["trace[871955267] 'read index received' (duration: 63.319622ms)","trace[871955267] 'applied index is now lower than readState.Index' (duration: 133.340523ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:18.895828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.923829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-07-08T16:16:18.895878Z","caller":"traceutil/trace.go:171","msg":"trace[1184577117] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27139; }","duration":"197.013481ms","start":"2026-07-08T16:16:18.698844Z","end":"2026-07-08T16:16:18.895857Z","steps":["trace[1184577117] 'agreement among raft nodes before linearized reading' (duration: 196.820788ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:18.895888Z","caller":"traceutil/trace.go:171","msg":"trace[867342196] transaction","detail":"{read_only:false; response_revision:27139; number_of_response:1; }","duration":"199.596581ms","start":"2026-07-08T16:16:18.696248Z","end":"2026-07-08T16:16:18.895844Z","steps":["trace[867342196] 'process raft request' (duration: 160.39471ms)","trace[867342196] 'compare' (duration: 38.547879ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:20.839296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.443957ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-08T16:16:22.654757Z","caller":"traceutil/trace.go:171","msg":"trace[134167923] transaction","detail":"{read_only:false; response_revision:27172; number_of_response:1; }","duration":"167.498777ms","start":"2026-07-08T16:16:22.487231Z","end":"2026-07-08T16:16:22.65473Z","steps":["trace[134167923] 'process raft request' (duration: 167.186321ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:22.74588Z","caller":"traceutil/trace.go:171","msg":"trace[617034643] transaction","detail":"{read_only:false; response_revision:27173; number_of_response:1; }","duration":"121.399215ms","start":"2026-07-08T16:16:22.624457Z","end":"2026-07-08T16:16:22.745857Z","steps":["trace[617034643] 'process raft request' (duration: 121.263992ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:38.066753Z","caller":"traceutil/trace.go:171","msg":"trace[2139515052] transaction","detail":"{read_only:false; response_revision:27292; number_of_response:1; }","duration":"166.430096ms","start":"2026-07-08T16:16:37.900288Z","end":"2026-07-08T16:16:38.066718Z","steps":["trace[2139515052] 'process raft request' (duration: 166.224192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:38.293206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.073434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:16:38.29344Z","caller":"traceutil/trace.go:171","msg":"trace[212665933] linearizableReadLoop","detail":"{readStateIndex:28192; appliedIndex:28190; }","duration":"380.24541ms","start":"2026-07-08T16:16:37.913172Z","end":"2026-07-08T16:16:38.293417Z","steps":["trace[212665933] 'read index received' (duration: 153.305705ms)","trace[212665933] 'applied index is now lower than readState.Index' (duration: 226.938174ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:38.29364Z","caller":"traceutil/trace.go:171","msg":"trace[1485078127] transaction","detail":"{read_only:false; response_revision:27293; number_of_response:1; }","duration":"391.475114ms","start":"2026-07-08T16:16:37.902149Z","end":"2026-07-08T16:16:38.293625Z","steps":["trace[1485078127] 'process raft request' (duration: 287.858111ms)","trace[1485078127] 'compare' (duration: 102.87094ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:38.293724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:37.902114Z","time spent":"391.571737ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:16:38.293806Z","caller":"traceutil/trace.go:171","msg":"trace[593778477] transaction","detail":"{read_only:false; response_revision:27294; number_of_response:1; }","duration":"300.015505ms","start":"2026-07-08T16:16:37.99373Z","end":"2026-07-08T16:16:38.293745Z","steps":["trace[593778477] 'process raft request' (duration: 299.612936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:38.293839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.633287ms","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:6"} {"level":"info","ts":"2026-07-08T16:16:38.293941Z","caller":"traceutil/trace.go:171","msg":"trace[495869779] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:27294; }","duration":"380.800121ms","start":"2026-07-08T16:16:37.913116Z","end":"2026-07-08T16:16:38.293916Z","steps":["trace[495869779] 'agreement among raft nodes before linearized reading' (duration: 380.601407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:38.293848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.484344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T16:16:38.293988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:37.9131Z","time spent":"380.880022ms","remote":"127.0.0.1:56952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-07-08T16:16:38.29402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:37.993706Z","time spent":"300.190038ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:16:38.294044Z","caller":"traceutil/trace.go:171","msg":"trace[1177842741] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:27294; }","duration":"358.760909ms","start":"2026-07-08T16:16:37.935255Z","end":"2026-07-08T16:16:38.294016Z","steps":["trace[1177842741] 'agreement among raft nodes before linearized reading' (duration: 358.498674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:38.294125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:37.935248Z","time spent":"358.84666ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-07-08T16:16:38.68781Z","caller":"traceutil/trace.go:171","msg":"trace[77421976] transaction","detail":"{read_only:false; response_revision:27295; number_of_response:1; }","duration":"128.780576ms","start":"2026-07-08T16:16:38.558993Z","end":"2026-07-08T16:16:38.687774Z","steps":["trace[77421976] 'process raft request' (duration: 128.48346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:38.977568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.697813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:16:38.977707Z","caller":"traceutil/trace.go:171","msg":"trace[2066660160] transaction","detail":"{read_only:false; response_revision:27296; number_of_response:1; }","duration":"261.944724ms","start":"2026-07-08T16:16:38.715746Z","end":"2026-07-08T16:16:38.977691Z","steps":["trace[2066660160] 'process raft request' (duration: 109.019167ms)","trace[2066660160] 'compare' (duration: 152.595901ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:39.240251Z","caller":"traceutil/trace.go:171","msg":"trace[1251429666] linearizableReadLoop","detail":"{readStateIndex:28196; appliedIndex:28195; }","duration":"246.01925ms","start":"2026-07-08T16:16:38.994203Z","end":"2026-07-08T16:16:39.240222Z","steps":["trace[1251429666] 'read index received' (duration: 234.00549ms)","trace[1251429666] 'applied index is now lower than readState.Index' (duration: 12.0126ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:39.240576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.366426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T16:16:39.240611Z","caller":"traceutil/trace.go:171","msg":"trace[1836637183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27297; }","duration":"246.429128ms","start":"2026-07-08T16:16:38.994172Z","end":"2026-07-08T16:16:39.240601Z","steps":["trace[1836637183] 'agreement among raft nodes before linearized reading' (duration: 246.285626ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:39.240529Z","caller":"traceutil/trace.go:171","msg":"trace[993842255] transaction","detail":"{read_only:false; response_revision:27297; number_of_response:1; }","duration":"257.604202ms","start":"2026-07-08T16:16:38.982865Z","end":"2026-07-08T16:16:39.240469Z","steps":["trace[993842255] 'process raft request' (duration: 245.308226ms)","trace[993842255] 'compare' (duration: 11.913848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:39.310007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.511403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:16:39.310087Z","caller":"traceutil/trace.go:171","msg":"trace[1595627117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27298; }","duration":"206.670527ms","start":"2026-07-08T16:16:39.103398Z","end":"2026-07-08T16:16:39.310069Z","steps":["trace[1595627117] 'agreement among raft nodes before linearized reading' (duration: 206.516494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:39.65906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.39319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:16:39.659208Z","caller":"traceutil/trace.go:171","msg":"trace[1657003721] transaction","detail":"{read_only:false; response_revision:27299; number_of_response:1; }","duration":"413.045468ms","start":"2026-07-08T16:16:39.246127Z","end":"2026-07-08T16:16:39.659172Z","steps":["trace[1657003721] 'process raft request' (duration: 204.432164ms)","trace[1657003721] 'compare' (duration: 208.273188ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:39.659365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:39.246096Z","time spent":"413.18553ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:16:39.659455Z","caller":"traceutil/trace.go:171","msg":"trace[2077813527] linearizableReadLoop","detail":"{readStateIndex:28198; appliedIndex:28197; }","duration":"349.531312ms","start":"2026-07-08T16:16:39.309891Z","end":"2026-07-08T16:16:39.659422Z","steps":["trace[2077813527] 'read index received' (duration: 140.658483ms)","trace[2077813527] 'applied index is now lower than readState.Index' (duration: 208.871009ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:39.659636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.132008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:16:39.659667Z","caller":"traceutil/trace.go:171","msg":"trace[205453435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27301; }","duration":"357.218789ms","start":"2026-07-08T16:16:39.30244Z","end":"2026-07-08T16:16:39.659659Z","steps":["trace[205453435] 'agreement among raft nodes before linearized reading' (duration: 357.148048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:39.659684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:39.302415Z","time spent":"357.26582ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T16:16:39.659775Z","caller":"traceutil/trace.go:171","msg":"trace[1412342082] transaction","detail":"{read_only:false; response_revision:27300; number_of_response:1; }","duration":"269.218514ms","start":"2026-07-08T16:16:39.390551Z","end":"2026-07-08T16:16:39.65977Z","steps":["trace[1412342082] 'process raft request' (duration: 268.620673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:39.659782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.04678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-08T16:16:39.659814Z","caller":"traceutil/trace.go:171","msg":"trace[1341898415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27301; }","duration":"364.129771ms","start":"2026-07-08T16:16:39.295674Z","end":"2026-07-08T16:16:39.659804Z","steps":["trace[1341898415] 'agreement among raft nodes before linearized reading' (duration: 364.003899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:39.659845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:39.295648Z","time spent":"364.188583ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T16:16:39.82705Z","caller":"traceutil/trace.go:171","msg":"trace[1820670457] transaction","detail":"{read_only:false; response_revision:27302; number_of_response:1; }","duration":"160.645586ms","start":"2026-07-08T16:16:39.666356Z","end":"2026-07-08T16:16:39.827002Z","steps":["trace[1820670457] 'process raft request' (duration: 138.586553ms)","trace[1820670457] 'compare' (duration: 21.678745ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:39.828451Z","caller":"traceutil/trace.go:171","msg":"trace[798840310] transaction","detail":"{read_only:false; response_revision:27304; number_of_response:1; }","duration":"161.127175ms","start":"2026-07-08T16:16:39.667296Z","end":"2026-07-08T16:16:39.828424Z","steps":["trace[798840310] 'process raft request' (duration: 160.497832ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:39.828585Z","caller":"traceutil/trace.go:171","msg":"trace[1527534053] transaction","detail":"{read_only:false; response_revision:27305; number_of_response:1; }","duration":"160.790408ms","start":"2026-07-08T16:16:39.667762Z","end":"2026-07-08T16:16:39.828552Z","steps":["trace[1527534053] 'process raft request' (duration: 160.078154ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:39.828698Z","caller":"traceutil/trace.go:171","msg":"trace[620663838] transaction","detail":"{read_only:false; response_revision:27303; number_of_response:1; }","duration":"161.3872ms","start":"2026-07-08T16:16:39.667294Z","end":"2026-07-08T16:16:39.828681Z","steps":["trace[620663838] 'process raft request' (duration: 160.315659ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:44.927221Z","caller":"traceutil/trace.go:171","msg":"trace[875965624] transaction","detail":"{read_only:false; response_revision:27349; number_of_response:1; }","duration":"207.36853ms","start":"2026-07-08T16:16:44.719829Z","end":"2026-07-08T16:16:44.927197Z","steps":["trace[875965624] 'process raft request' (duration: 207.221407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:45.265961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.934959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:16:45.266186Z","caller":"traceutil/trace.go:171","msg":"trace[54588224] transaction","detail":"{read_only:false; response_revision:27350; number_of_response:1; }","duration":"268.293955ms","start":"2026-07-08T16:16:44.99787Z","end":"2026-07-08T16:16:45.266164Z","steps":["trace[54588224] 'process raft request' (duration: 139.057631ms)","trace[54588224] 'compare' (duration: 128.280647ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:45.276537Z","caller":"traceutil/trace.go:171","msg":"trace[1594409288] transaction","detail":"{read_only:false; response_revision:27351; number_of_response:1; }","duration":"258.480129ms","start":"2026-07-08T16:16:45.018027Z","end":"2026-07-08T16:16:45.276507Z","steps":["trace[1594409288] 'process raft request' (duration: 258.06875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:45.344627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.579514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.223\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-08T16:16:45.344722Z","caller":"traceutil/trace.go:171","msg":"trace[2032558273] range","detail":"{range_begin:/registry/masterleases/199.19.213.223; range_end:; response_count:1; response_revision:27352; }","duration":"101.715287ms","start":"2026-07-08T16:16:45.242987Z","end":"2026-07-08T16:16:45.344702Z","steps":["trace[2032558273] 'agreement among raft nodes before linearized reading' (duration: 101.556264ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:45.503512Z","caller":"traceutil/trace.go:171","msg":"trace[180117605] linearizableReadLoop","detail":"{readStateIndex:28255; appliedIndex:28253; }","duration":"142.456337ms","start":"2026-07-08T16:16:45.361028Z","end":"2026-07-08T16:16:45.503484Z","steps":["trace[180117605] 'read index received' (duration: 51.217711ms)","trace[180117605] 'applied index is now lower than readState.Index' (duration: 91.236636ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:45.503614Z","caller":"traceutil/trace.go:171","msg":"trace[311231691] transaction","detail":"{read_only:false; response_revision:27353; number_of_response:1; }","duration":"151.306936ms","start":"2026-07-08T16:16:45.352286Z","end":"2026-07-08T16:16:45.503593Z","steps":["trace[311231691] 'process raft request' (duration: 151.020821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:45.503783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.727652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T16:16:45.503829Z","caller":"traceutil/trace.go:171","msg":"trace[341650255] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:27353; }","duration":"142.855795ms","start":"2026-07-08T16:16:45.360963Z","end":"2026-07-08T16:16:45.503818Z","steps":["trace[341650255] 'agreement among raft nodes before linearized reading' (duration: 142.62355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:45.751439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.494379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T16:16:45.751703Z","caller":"traceutil/trace.go:171","msg":"trace[1252591751] linearizableReadLoop","detail":"{readStateIndex:28256; appliedIndex:28255; }","duration":"231.360919ms","start":"2026-07-08T16:16:45.520316Z","end":"2026-07-08T16:16:45.751677Z","steps":["trace[1252591751] 'read index received' (duration: 54.538794ms)","trace[1252591751] 'applied index is now lower than readState.Index' (duration: 176.819805ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:45.751865Z","caller":"traceutil/trace.go:171","msg":"trace[1159362311] transaction","detail":"{read_only:false; response_revision:27354; number_of_response:1; }","duration":"282.891776ms","start":"2026-07-08T16:16:45.468908Z","end":"2026-07-08T16:16:45.7518Z","steps":["trace[1159362311] 'process raft request' (duration: 105.916149ms)","trace[1159362311] 'compare' (duration: 176.302315ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:45.751915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.600533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:16:45.751965Z","caller":"traceutil/trace.go:171","msg":"trace[220502464] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:27354; }","duration":"231.703395ms","start":"2026-07-08T16:16:45.520249Z","end":"2026-07-08T16:16:45.751952Z","steps":["trace[220502464] 'agreement among raft nodes before linearized reading' (duration: 231.545532ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:45.979761Z","caller":"traceutil/trace.go:171","msg":"trace[1897166012] transaction","detail":"{read_only:false; response_revision:27355; number_of_response:1; }","duration":"295.59863ms","start":"2026-07-08T16:16:45.684129Z","end":"2026-07-08T16:16:45.979727Z","steps":["trace[1897166012] 'process raft request' (duration: 295.404126ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:46.071652Z","caller":"traceutil/trace.go:171","msg":"trace[2033770550] transaction","detail":"{read_only:false; response_revision:27357; number_of_response:1; }","duration":"221.524712ms","start":"2026-07-08T16:16:45.850102Z","end":"2026-07-08T16:16:46.071626Z","steps":["trace[2033770550] 'process raft request' (duration: 221.431001ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:46.071886Z","caller":"traceutil/trace.go:171","msg":"trace[1133040899] linearizableReadLoop","detail":"{readStateIndex:28258; appliedIndex:28256; }","duration":"320.11011ms","start":"2026-07-08T16:16:45.751751Z","end":"2026-07-08T16:16:46.071861Z","steps":["trace[1133040899] 'read index received' (duration: 227.810433ms)","trace[1133040899] 'applied index is now lower than readState.Index' (duration: 92.298477ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:46.071936Z","caller":"traceutil/trace.go:171","msg":"trace[242406844] transaction","detail":"{read_only:false; response_revision:27356; number_of_response:1; }","duration":"373.299538ms","start":"2026-07-08T16:16:45.698623Z","end":"2026-07-08T16:16:46.071922Z","steps":["trace[242406844] 'process raft request' (duration: 349.651165ms)","trace[242406844] 'compare' (duration: 22.618453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:46.072282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:45.698597Z","time spent":"373.592853ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:16:46.072348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.998565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-08T16:16:46.072394Z","caller":"traceutil/trace.go:171","msg":"trace[1488420459] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:27358; }","duration":"310.096706ms","start":"2026-07-08T16:16:45.762283Z","end":"2026-07-08T16:16:46.072379Z","steps":["trace[1488420459] 'agreement among raft nodes before linearized reading' (duration: 309.920233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.072441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:45.762257Z","time spent":"310.175528ms","remote":"127.0.0.1:57550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-08T16:16:46.072552Z","caller":"traceutil/trace.go:171","msg":"trace[865495636] transaction","detail":"{read_only:false; response_revision:27358; number_of_response:1; }","duration":"221.373058ms","start":"2026-07-08T16:16:45.851165Z","end":"2026-07-08T16:16:46.072538Z","steps":["trace[865495636] 'process raft request' (duration: 220.426811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.072841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.060264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T16:16:46.072877Z","caller":"traceutil/trace.go:171","msg":"trace[330470727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27358; }","duration":"372.140425ms","start":"2026-07-08T16:16:45.700727Z","end":"2026-07-08T16:16:46.072868Z","steps":["trace[330470727] 'agreement among raft nodes before linearized reading' (duration: 372.004332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.072908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:45.700698Z","time spent":"372.203527ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T16:16:46.073085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.453356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-08T16:16:46.073103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.9753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-54tf7.18c05befeaacf020\" ","response":"range_response_count:1 size:1395"} {"level":"info","ts":"2026-07-08T16:16:46.073135Z","caller":"traceutil/trace.go:171","msg":"trace[1393866824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27358; }","duration":"214.527847ms","start":"2026-07-08T16:16:45.858591Z","end":"2026-07-08T16:16:46.073119Z","steps":["trace[1393866824] 'agreement among raft nodes before linearized reading' (duration: 214.417965ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:46.073154Z","caller":"traceutil/trace.go:171","msg":"trace[385674297] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-54tf7.18c05befeaacf020; range_end:; response_count:1; response_revision:27358; }","duration":"526.031562ms","start":"2026-07-08T16:16:45.547092Z","end":"2026-07-08T16:16:46.073124Z","steps":["trace[385674297] 'agreement among raft nodes before linearized reading' (duration: 525.93214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.073186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:45.547069Z","time spent":"526.109973ms","remote":"127.0.0.1:57468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":1418,"request content":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-54tf7.18c05befeaacf020\" "} {"level":"warn","ts":"2026-07-08T16:16:46.073336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.427185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-08T16:16:46.073375Z","caller":"traceutil/trace.go:171","msg":"trace[493805044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27358; }","duration":"216.509915ms","start":"2026-07-08T16:16:45.856855Z","end":"2026-07-08T16:16:46.073365Z","steps":["trace[493805044] 'agreement among raft nodes before linearized reading' (duration: 216.411364ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:46.292844Z","caller":"traceutil/trace.go:171","msg":"trace[609346306] linearizableReadLoop","detail":"{readStateIndex:28261; appliedIndex:28260; }","duration":"214.788063ms","start":"2026-07-08T16:16:46.078021Z","end":"2026-07-08T16:16:46.292809Z","steps":["trace[609346306] 'read index received' (duration: 214.562718ms)","trace[609346306] 'applied index is now lower than readState.Index' (duration: 223.685µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:46.292927Z","caller":"traceutil/trace.go:171","msg":"trace[1364185542] transaction","detail":"{read_only:false; response_revision:27359; number_of_response:1; }","duration":"215.619688ms","start":"2026-07-08T16:16:46.077259Z","end":"2026-07-08T16:16:46.292879Z","steps":["trace[1364185542] 'process raft request' (duration: 215.323002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.293195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.135959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-08T16:16:46.293242Z","caller":"traceutil/trace.go:171","msg":"trace[1247731819] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:27359; }","duration":"215.327393ms","start":"2026-07-08T16:16:46.077903Z","end":"2026-07-08T16:16:46.29323Z","steps":["trace[1247731819] 'agreement among raft nodes before linearized reading' (duration: 215.071918ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:46.438963Z","caller":"traceutil/trace.go:171","msg":"trace[1906683517] transaction","detail":"{read_only:false; response_revision:27360; number_of_response:1; }","duration":"359.305359ms","start":"2026-07-08T16:16:46.079621Z","end":"2026-07-08T16:16:46.438927Z","steps":["trace[1906683517] 'process raft request' (duration: 276.791009ms)","trace[1906683517] 'compare' (duration: 82.221155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:46.439148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.86913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-08T16:16:46.439218Z","caller":"traceutil/trace.go:171","msg":"trace[1387440990] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27363; }","duration":"360.994251ms","start":"2026-07-08T16:16:46.078212Z","end":"2026-07-08T16:16:46.439206Z","steps":["trace[1387440990] 'agreement among raft nodes before linearized reading' (duration: 360.815848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.439282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:46.078202Z","time spent":"361.069424ms","remote":"127.0.0.1:57572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-08T16:16:46.439417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:46.079608Z","time spent":"359.442213ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:16:46.439548Z","caller":"traceutil/trace.go:171","msg":"trace[1995189740] transaction","detail":"{read_only:false; response_revision:27363; number_of_response:1; }","duration":"343.166211ms","start":"2026-07-08T16:16:46.096369Z","end":"2026-07-08T16:16:46.439535Z","steps":["trace[1995189740] 'process raft request' (duration: 342.514488ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:46.43955Z","caller":"traceutil/trace.go:171","msg":"trace[72514398] transaction","detail":"{read_only:false; response_revision:27362; number_of_response:1; }","duration":"355.018407ms","start":"2026-07-08T16:16:46.084464Z","end":"2026-07-08T16:16:46.439482Z","steps":["trace[72514398] 'process raft request' (duration: 354.380975ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:46.439625Z","caller":"traceutil/trace.go:171","msg":"trace[1325104076] transaction","detail":"{read_only:false; response_revision:27361; number_of_response:1; }","duration":"359.893591ms","start":"2026-07-08T16:16:46.079699Z","end":"2026-07-08T16:16:46.439592Z","steps":["trace[1325104076] 'process raft request' (duration: 359.091436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.43968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:46.096358Z","time spent":"343.236472ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T16:16:46.4398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:46.079693Z","time spent":"360.019974ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:16:46.438962Z","caller":"traceutil/trace.go:171","msg":"trace[45063102] linearizableReadLoop","detail":"{readStateIndex:28265; appliedIndex:28261; }","duration":"145.998355ms","start":"2026-07-08T16:16:46.292934Z","end":"2026-07-08T16:16:46.438932Z","steps":["trace[45063102] 'read index received' (duration: 63.497576ms)","trace[45063102] 'applied index is now lower than readState.Index' (duration: 82.499419ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T16:16:46.43985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T16:16:46.084443Z","time spent":"355.227051ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T16:16:46.498307Z","caller":"traceutil/trace.go:171","msg":"trace[1162028527] transaction","detail":"{read_only:false; response_revision:27364; number_of_response:1; }","duration":"175.372537ms","start":"2026-07-08T16:16:46.322907Z","end":"2026-07-08T16:16:46.498279Z","steps":["trace[1162028527] 'process raft request' (duration: 174.928089ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:46.49852Z","caller":"traceutil/trace.go:171","msg":"trace[156333475] transaction","detail":"{read_only:false; response_revision:27365; number_of_response:1; }","duration":"171.109906ms","start":"2026-07-08T16:16:46.3274Z","end":"2026-07-08T16:16:46.49851Z","steps":["trace[156333475] 'process raft request' (duration: 170.565005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.498745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.248621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T16:16:46.498971Z","caller":"traceutil/trace.go:171","msg":"trace[1694325630] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:27365; }","duration":"146.500686ms","start":"2026-07-08T16:16:46.352459Z","end":"2026-07-08T16:16:46.498959Z","steps":["trace[1694325630] 'agreement among raft nodes before linearized reading' (duration: 146.153609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.499295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.223099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:16:46.499423Z","caller":"traceutil/trace.go:171","msg":"trace[874434446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27365; }","duration":"194.411402ms","start":"2026-07-08T16:16:46.304988Z","end":"2026-07-08T16:16:46.4994Z","steps":["trace[874434446] 'agreement among raft nodes before linearized reading' (duration: 194.147157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.499348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.975057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-pk9vk.18c05bed64ce8fba\" ","response":"range_response_count:1 size:1387"} {"level":"info","ts":"2026-07-08T16:16:46.499515Z","caller":"traceutil/trace.go:171","msg":"trace[483394509] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-pk9vk.18c05bed64ce8fba; range_end:; response_count:1; response_revision:27365; }","duration":"202.186881ms","start":"2026-07-08T16:16:46.297318Z","end":"2026-07-08T16:16:46.499505Z","steps":["trace[483394509] 'agreement among raft nodes before linearized reading' (duration: 201.927896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:46.727216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.696937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T16:16:46.727353Z","caller":"traceutil/trace.go:171","msg":"trace[499440268] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27366; }","duration":"125.915231ms","start":"2026-07-08T16:16:46.601422Z","end":"2026-07-08T16:16:46.727337Z","steps":["trace[499440268] 'range keys from in-memory index tree' (duration: 125.579435ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:47.065354Z","caller":"traceutil/trace.go:171","msg":"trace[659428281] linearizableReadLoop","detail":"{readStateIndex:28269; appliedIndex:28268; }","duration":"134.376673ms","start":"2026-07-08T16:16:46.930946Z","end":"2026-07-08T16:16:47.065323Z","steps":["trace[659428281] 'read index received' (duration: 134.172609ms)","trace[659428281] 'applied index is now lower than readState.Index' (duration: 202.444µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:16:47.065525Z","caller":"traceutil/trace.go:171","msg":"trace[1944790250] transaction","detail":"{read_only:false; response_revision:27367; number_of_response:1; }","duration":"136.956382ms","start":"2026-07-08T16:16:46.928525Z","end":"2026-07-08T16:16:47.065482Z","steps":["trace[1944790250] 'process raft request' (duration: 136.575245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:47.065664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.694109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-08T16:16:47.065705Z","caller":"traceutil/trace.go:171","msg":"trace[1768000658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27367; }","duration":"134.814452ms","start":"2026-07-08T16:16:46.930878Z","end":"2026-07-08T16:16:47.065692Z","steps":["trace[1768000658] 'agreement among raft nodes before linearized reading' (duration: 134.558007ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:16:51.652214Z","caller":"traceutil/trace.go:171","msg":"trace[1845752854] transaction","detail":"{read_only:false; response_revision:27404; number_of_response:1; }","duration":"115.965199ms","start":"2026-07-08T16:16:51.536221Z","end":"2026-07-08T16:16:51.652186Z","steps":["trace[1845752854] 'process raft request' (duration: 115.788536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T16:16:51.901074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.042616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-08T16:16:51.901192Z","caller":"traceutil/trace.go:171","msg":"trace[824410960] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27404; }","duration":"146.236979ms","start":"2026-07-08T16:16:51.75493Z","end":"2026-07-08T16:16:51.901167Z","steps":["trace[824410960] 'range keys from in-memory index tree' (duration: 145.824561ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T16:17:02.094784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24566} {"level":"info","ts":"2026-07-08T16:17:02.248188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24566,"took":"148.528113ms","hash":388292616,"current-db-size-bytes":60727296,"current-db-size":"61 MB","current-db-size-in-use-bytes":36352000,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-08T16:17:02.248253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":388292616,"revision":24566,"compact-revision":21657} {"level":"info","ts":"2026-07-08T16:19:42.670891Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"12dd93b2fab6fcb","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T16:19:42.675672Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-07-08T16:19:42.675815Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-07-08T16:22:02.102468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27491} {"level":"info","ts":"2026-07-08T16:22:02.24751Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27491,"took":"139.589753ms","hash":2064636578,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":36446208,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-08T16:22:02.247591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2064636578,"revision":27491,"compact-revision":24566} {"level":"info","ts":"2026-07-08T16:27:02.109981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30412} {"level":"info","ts":"2026-07-08T16:27:02.249811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30412,"took":"133.951055ms","hash":3642126257,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":31657984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T16:27:02.249896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3642126257,"revision":30412,"compact-revision":27491} {"level":"info","ts":"2026-07-08T16:32:02.116168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32685} {"level":"info","ts":"2026-07-08T16:32:02.264435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32685,"took":"143.721249ms","hash":280417040,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":30752768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T16:32:02.26449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":280417040,"revision":32685,"compact-revision":30412} {"level":"info","ts":"2026-07-08T16:35:14.907642Z","caller":"traceutil/trace.go:171","msg":"trace[710466953] transaction","detail":"{read_only:false; response_revision:36376; number_of_response:1; }","duration":"102.172554ms","start":"2026-07-08T16:35:14.80544Z","end":"2026-07-08T16:35:14.907612Z","steps":["trace[710466953] 'process raft request' (duration: 71.971036ms)","trace[710466953] 'compare' (duration: 30.040145ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T16:37:02.124872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34935} {"level":"info","ts":"2026-07-08T16:37:02.277727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34935,"took":"146.383809ms","hash":356984696,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":31006720,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T16:37:02.277817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":356984696,"revision":34935,"compact-revision":32685} {"level":"info","ts":"2026-07-08T16:40:22.032701Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"12dd93b2fab6fcb","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T16:40:22.038322Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-07-08T16:40:22.038469Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-07-08T16:42:02.132704Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37240} {"level":"info","ts":"2026-07-08T16:42:02.258473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37240,"took":"120.437894ms","hash":3446506291,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":30859264,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T16:42:02.258556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3446506291,"revision":37240,"compact-revision":34935}