{"level":"warn","ts":"2026-04-03T05:04:58.557924Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T05:04:58.558039Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-03T05:04:58.558103Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T05:04:58.558119Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-03T05:04:58.558145Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-03T05:04:58.558671Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-04-03T05:04:58.558742Z","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://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-03T05:04:58.562573Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.618857ms"} {"level":"info","ts":"2026-04-03T05:04:58.57102Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-04-03T05:04:58.571106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T05:04:58.571131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-04-03T05:04:58.571152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T05:04:58.571173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-04-03T05:04:58.571219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-04-03T05:04:58.577955Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T05:04:58.581461Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T05:04:58.582957Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-03T05:04:58.586146Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T05:04:58.586356Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-03T05:04:58.586531Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-03T05:04:58.586548Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-03T05:04:58.586422Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T05:04:58.587558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-04-03T05:04:58.587742Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-03T05:04:58.589626Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-03T05:04:58.589771Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-03T05:04:58.589805Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-03T05:04:58.589878Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T05:04:58.58991Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T05:04:58.672165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T05:04:58.672208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T05:04:58.672248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-04-03T05:04:58.672269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-04-03T05:04:58.672287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-03T05:04:58.672304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-04-03T05:04:58.672319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-03T05:04:58.673299Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T05:04:58.673301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T05:04:58.673328Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:04:58.673373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T05:04:58.673704Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T05:04:58.67374Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T05:04:58.67484Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:04:58.675008Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:04:58.675053Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:04:58.676739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-03T05:04:58.676927Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-04-03T05:05:28.69292Z","caller":"traceutil/trace.go:171","msg":"trace[1009255809] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"219.561596ms","start":"2026-04-03T05:05:28.473328Z","end":"2026-04-03T05:05:28.69289Z","steps":["trace[1009255809] 'process raft request' (duration: 219.410403ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.704372Z","caller":"traceutil/trace.go:171","msg":"trace[1090871260] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"227.216171ms","start":"2026-04-03T05:05:28.477136Z","end":"2026-04-03T05:05:28.704352Z","steps":["trace[1090871260] 'process raft request' (duration: 226.928005ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.704632Z","caller":"traceutil/trace.go:171","msg":"trace[813726214] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"226.916015ms","start":"2026-04-03T05:05:28.477685Z","end":"2026-04-03T05:05:28.704601Z","steps":["trace[813726214] 'process raft request' (duration: 226.604698ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.70471Z","caller":"traceutil/trace.go:171","msg":"trace[1854323651] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"218.856961ms","start":"2026-04-03T05:05:28.485793Z","end":"2026-04-03T05:05:28.70465Z","steps":["trace[1854323651] 'process raft request' (duration: 218.638636ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.704789Z","caller":"traceutil/trace.go:171","msg":"trace[419133687] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"208.675482ms","start":"2026-04-03T05:05:28.496101Z","end":"2026-04-03T05:05:28.704777Z","steps":["trace[419133687] 'process raft request' (duration: 208.429276ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.704972Z","caller":"traceutil/trace.go:171","msg":"trace[1631560994] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"218.948113ms","start":"2026-04-03T05:05:28.486013Z","end":"2026-04-03T05:05:28.704961Z","steps":["trace[1631560994] 'process raft request' (duration: 218.465572ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.704755Z","caller":"traceutil/trace.go:171","msg":"trace[649575999] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"219.335621ms","start":"2026-04-03T05:05:28.485359Z","end":"2026-04-03T05:05:28.704695Z","steps":["trace[649575999] 'process raft request' (duration: 219.025754ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.705207Z","caller":"traceutil/trace.go:171","msg":"trace[1252507016] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"181.940827ms","start":"2026-04-03T05:05:28.52323Z","end":"2026-04-03T05:05:28.705171Z","steps":["trace[1252507016] 'process raft request' (duration: 181.416306ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.705224Z","caller":"traceutil/trace.go:171","msg":"trace[1057932242] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"165.947992ms","start":"2026-04-03T05:05:28.539252Z","end":"2026-04-03T05:05:28.7052Z","steps":["trace[1057932242] 'process raft request' (duration: 165.484202ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.704539Z","caller":"traceutil/trace.go:171","msg":"trace[479025138] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"219.990765ms","start":"2026-04-03T05:05:28.484529Z","end":"2026-04-03T05:05:28.70452Z","steps":["trace[479025138] 'process raft request' (duration: 219.814211ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:28.70528Z","caller":"traceutil/trace.go:171","msg":"trace[784618272] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"197.121983ms","start":"2026-04-03T05:05:28.508151Z","end":"2026-04-03T05:05:28.705273Z","steps":["trace[784618272] 'process raft request' (duration: 196.432239ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:34.854109Z","caller":"traceutil/trace.go:171","msg":"trace[1115652922] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"133.711467ms","start":"2026-04-03T05:05:34.720363Z","end":"2026-04-03T05:05:34.854074Z","steps":["trace[1115652922] 'read index received' (duration: 41.741988ms)","trace[1115652922] 'applied index is now lower than readState.Index' (duration: 91.967789ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:05:34.854298Z","caller":"traceutil/trace.go:171","msg":"trace[1871999314] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"192.318139ms","start":"2026-04-03T05:05:34.661965Z","end":"2026-04-03T05:05:34.854283Z","steps":["trace[1871999314] 'process raft request' (duration: 192.037803ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:34.854418Z","caller":"traceutil/trace.go:171","msg":"trace[1027830151] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"303.178316ms","start":"2026-04-03T05:05:34.551205Z","end":"2026-04-03T05:05:34.854384Z","steps":["trace[1027830151] 'process raft request' (duration: 210.90338ms)","trace[1027830151] 'compare' (duration: 91.663673ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:05:34.854467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.413289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T05:05:34.8546Z","caller":"traceutil/trace.go:171","msg":"trace[57087674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"118.588932ms","start":"2026-04-03T05:05:34.735992Z","end":"2026-04-03T05:05:34.854581Z","steps":["trace[57087674] 'agreement among raft nodes before linearized reading' (duration: 118.366087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:34.854675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.167497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:05:34.854731Z","caller":"traceutil/trace.go:171","msg":"trace[14456267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"134.431873ms","start":"2026-04-03T05:05:34.720288Z","end":"2026-04-03T05:05:34.854719Z","steps":["trace[14456267] 'agreement among raft nodes before linearized reading' (duration: 134.193617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:34.854831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.282622ms","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-04-03T05:05:34.855002Z","caller":"traceutil/trace.go:171","msg":"trace[312456545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"109.472546ms","start":"2026-04-03T05:05:34.745473Z","end":"2026-04-03T05:05:34.854945Z","steps":["trace[312456545] 'agreement among raft nodes before linearized reading' (duration: 109.007045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:34.855012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:05:34.551179Z","time spent":"303.34015ms","remote":"127.0.0.1:52454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:05:35.043726Z","caller":"traceutil/trace.go:171","msg":"trace[1257679231] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:638; }","duration":"177.288077ms","start":"2026-04-03T05:05:34.866411Z","end":"2026-04-03T05:05:35.043699Z","steps":["trace[1257679231] 'read index received' (duration: 61.700369ms)","trace[1257679231] 'applied index is now lower than readState.Index' (duration: 115.586478ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:05:35.043823Z","caller":"traceutil/trace.go:171","msg":"trace[1726859002] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"184.015902ms","start":"2026-04-03T05:05:34.859737Z","end":"2026-04-03T05:05:35.043753Z","steps":["trace[1726859002] 'process raft request' (duration: 183.757046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:35.043993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.566602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:05:35.044045Z","caller":"traceutil/trace.go:171","msg":"trace[758541727] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:620; }","duration":"177.682135ms","start":"2026-04-03T05:05:34.866348Z","end":"2026-04-03T05:05:35.044031Z","steps":["trace[758541727] 'agreement among raft nodes before linearized reading' (duration: 177.568402ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:35.043893Z","caller":"traceutil/trace.go:171","msg":"trace[529774311] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"183.797877ms","start":"2026-04-03T05:05:34.860065Z","end":"2026-04-03T05:05:35.043863Z","steps":["trace[529774311] 'process raft request' (duration: 183.583703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:35.044281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.856426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T05:05:35.044318Z","caller":"traceutil/trace.go:171","msg":"trace[292704237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"162.939068ms","start":"2026-04-03T05:05:34.881371Z","end":"2026-04-03T05:05:35.04431Z","steps":["trace[292704237] 'agreement among raft nodes before linearized reading' (duration: 162.875096ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:35.245485Z","caller":"traceutil/trace.go:171","msg":"trace[162402966] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"194.984597ms","start":"2026-04-03T05:05:35.050469Z","end":"2026-04-03T05:05:35.245454Z","steps":["trace[162402966] 'process raft request' (duration: 155.241172ms)","trace[162402966] 'compare' (duration: 39.627333ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:05:35.273641Z","caller":"traceutil/trace.go:171","msg":"trace[300174161] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"213.158399ms","start":"2026-04-03T05:05:35.060458Z","end":"2026-04-03T05:05:35.273617Z","steps":["trace[300174161] 'process raft request' (duration: 213.038916ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:36.144252Z","caller":"traceutil/trace.go:171","msg":"trace[1526481400] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"424.350845ms","start":"2026-04-03T05:05:35.719857Z","end":"2026-04-03T05:05:36.144208Z","steps":["trace[1526481400] 'read index received' (duration: 423.19182ms)","trace[1526481400] 'applied index is now lower than readState.Index' (duration: 1.157815ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:05:36.144325Z","caller":"traceutil/trace.go:171","msg":"trace[1554599187] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"700.147702ms","start":"2026-04-03T05:05:35.444134Z","end":"2026-04-03T05:05:36.144282Z","steps":["trace[1554599187] 'process raft request' (duration: 699.235733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:36.144482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.57829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:05:36.144579Z","caller":"traceutil/trace.go:171","msg":"trace[581034903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"424.744024ms","start":"2026-04-03T05:05:35.719811Z","end":"2026-04-03T05:05:36.144555Z","steps":["trace[581034903] 'agreement among raft nodes before linearized reading' (duration: 424.55396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:36.144644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:05:35.719793Z","time spent":"424.837615ms","remote":"127.0.0.1:51986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T05:05:36.144939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:05:35.444076Z","time spent":"700.468079ms","remote":"127.0.0.1:52050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:05:37.327499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.258968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:05:37.327572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.085264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:05:37.327619Z","caller":"traceutil/trace.go:171","msg":"trace[1996421678] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:636; }","duration":"107.164616ms","start":"2026-04-03T05:05:37.220432Z","end":"2026-04-03T05:05:37.327596Z","steps":["trace[1996421678] 'range keys from in-memory index tree' (duration: 107.038483ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:37.327574Z","caller":"traceutil/trace.go:171","msg":"trace[969337353] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:636; }","duration":"107.38008ms","start":"2026-04-03T05:05:37.220175Z","end":"2026-04-03T05:05:37.327555Z","steps":["trace[969337353] 'count revisions from in-memory index tree' (duration: 107.197537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:37.327812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.017933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:05:37.32781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.068932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:05:37.327888Z","caller":"traceutil/trace.go:171","msg":"trace[1019671095] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:636; }","duration":"157.199766ms","start":"2026-04-03T05:05:37.170666Z","end":"2026-04-03T05:05:37.327866Z","steps":["trace[1019671095] 'range keys from in-memory index tree' (duration: 156.992571ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:37.327906Z","caller":"traceutil/trace.go:171","msg":"trace[773486245] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:636; }","duration":"158.060994ms","start":"2026-04-03T05:05:37.169767Z","end":"2026-04-03T05:05:37.327828Z","steps":["trace[773486245] 'count revisions from in-memory index tree' (duration: 157.918351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:37.327775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.612755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:05:37.328034Z","caller":"traceutil/trace.go:171","msg":"trace[654729252] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:636; }","duration":"107.879081ms","start":"2026-04-03T05:05:37.220131Z","end":"2026-04-03T05:05:37.32801Z","steps":["trace[654729252] 'range keys from in-memory index tree' (duration: 107.519143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:37.328064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.234988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:05:37.328138Z","caller":"traceutil/trace.go:171","msg":"trace[1464813086] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:636; }","duration":"158.358451ms","start":"2026-04-03T05:05:37.169759Z","end":"2026-04-03T05:05:37.328117Z","steps":["trace[1464813086] 'range keys from in-memory index tree' (duration: 158.133976ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:52.596317Z","caller":"traceutil/trace.go:171","msg":"trace[126073562] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"510.85615ms","start":"2026-04-03T05:05:52.085436Z","end":"2026-04-03T05:05:52.596293Z","steps":["trace[126073562] 'process raft request' (duration: 510.581945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:52.596495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.937056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-03T05:05:52.596536Z","caller":"traceutil/trace.go:171","msg":"trace[576603425] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:786; }","duration":"372.049309ms","start":"2026-04-03T05:05:52.224475Z","end":"2026-04-03T05:05:52.596524Z","steps":["trace[576603425] 'agreement among raft nodes before linearized reading' (duration: 371.893126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:52.596535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:05:52.085403Z","time spent":"511.014224ms","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:05:52.59657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:05:52.224442Z","time spent":"372.12112ms","remote":"127.0.0.1:52000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"info","ts":"2026-04-03T05:05:52.596266Z","caller":"traceutil/trace.go:171","msg":"trace[1223956498] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"371.673511ms","start":"2026-04-03T05:05:52.224561Z","end":"2026-04-03T05:05:52.596234Z","steps":["trace[1223956498] 'read index received' (duration: 371.467797ms)","trace[1223956498] 'applied index is now lower than readState.Index' (duration: 204.064µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:06:19.927053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.714144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:06:19.927094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.594957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-03T05:06:19.927144Z","caller":"traceutil/trace.go:171","msg":"trace[1404597304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"206.844147ms","start":"2026-04-03T05:06:19.720282Z","end":"2026-04-03T05:06:19.927126Z","steps":["trace[1404597304] 'range keys from in-memory index tree' (duration: 206.615922ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:06:19.927185Z","caller":"traceutil/trace.go:171","msg":"trace[381165422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1174; }","duration":"133.73117ms","start":"2026-04-03T05:06:19.793432Z","end":"2026-04-03T05:06:19.927163Z","steps":["trace[381165422] 'range keys from in-memory index tree' (duration: 133.428303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:06:19.927243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.183804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T05:06:19.927337Z","caller":"traceutil/trace.go:171","msg":"trace[643063210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1174; }","duration":"383.311706ms","start":"2026-04-03T05:06:19.543998Z","end":"2026-04-03T05:06:19.92731Z","steps":["trace[643063210] 'range keys from in-memory index tree' (duration: 382.99787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:06:19.927383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:06:19.543982Z","time spent":"383.392769ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T05:06:39.588749Z","caller":"traceutil/trace.go:171","msg":"trace[14474884] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1473; }","duration":"175.824345ms","start":"2026-04-03T05:06:39.412889Z","end":"2026-04-03T05:06:39.588713Z","steps":["trace[14474884] 'read index received' (duration: 175.620219ms)","trace[14474884] 'applied index is now lower than readState.Index' (duration: 202.556µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:06:39.588877Z","caller":"traceutil/trace.go:171","msg":"trace[2046630411] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"194.371432ms","start":"2026-04-03T05:06:39.394474Z","end":"2026-04-03T05:06:39.588845Z","steps":["trace[2046630411] 'process raft request' (duration: 194.002203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:06:39.589097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.183122ms","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-04-03T05:06:39.589157Z","caller":"traceutil/trace.go:171","msg":"trace[1664606721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1434; }","duration":"176.306775ms","start":"2026-04-03T05:06:39.412835Z","end":"2026-04-03T05:06:39.589141Z","steps":["trace[1664606721] 'agreement among raft nodes before linearized reading' (duration: 176.06791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:06:39.58932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.698788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T05:06:39.589471Z","caller":"traceutil/trace.go:171","msg":"trace[1237857811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1434; }","duration":"148.879421ms","start":"2026-04-03T05:06:39.440561Z","end":"2026-04-03T05:06:39.58944Z","steps":["trace[1237857811] 'agreement among raft nodes before linearized reading' (duration: 148.456292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:04.114131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.132012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:07:04.114251Z","caller":"traceutil/trace.go:171","msg":"trace[374768099] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1895; }","duration":"420.842299ms","start":"2026-04-03T05:07:03.693392Z","end":"2026-04-03T05:07:04.114234Z","steps":["trace[374768099] 'read index received' (duration: 26.177905ms)","trace[374768099] 'applied index is now lower than readState.Index' (duration: 394.662974ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:07:04.11439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.993472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:07:04.11432Z","caller":"traceutil/trace.go:171","msg":"trace[84938830] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"439.184902ms","start":"2026-04-03T05:07:03.675098Z","end":"2026-04-03T05:07:04.114283Z","steps":["trace[84938830] 'process raft request' (duration: 44.555229ms)","trace[84938830] 'compare' (duration: 394.03064ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:04.114428Z","caller":"traceutil/trace.go:171","msg":"trace[1211046496] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1829; }","duration":"421.073094ms","start":"2026-04-03T05:07:03.693346Z","end":"2026-04-03T05:07:04.114419Z","steps":["trace[1211046496] 'agreement among raft nodes before linearized reading' (duration: 420.976832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:04.114466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:03.693326Z","time spent":"421.130806ms","remote":"127.0.0.1:52048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:07:04.114648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.702622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-03T05:07:04.114699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:03.675087Z","time spent":"439.368876ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:07:04.114734Z","caller":"traceutil/trace.go:171","msg":"trace[64389720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1829; }","duration":"403.820226ms","start":"2026-04-03T05:07:03.710894Z","end":"2026-04-03T05:07:04.114714Z","steps":["trace[64389720] 'agreement among raft nodes before linearized reading' (duration: 403.57332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:04.114784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:03.710882Z","time spent":"403.893818ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T05:07:04.114932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.415859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:07:04.115029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.218442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T05:07:04.115155Z","caller":"traceutil/trace.go:171","msg":"trace[667089733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1829; }","duration":"403.380235ms","start":"2026-04-03T05:07:03.711744Z","end":"2026-04-03T05:07:04.115124Z","steps":["trace[667089733] 'agreement among raft nodes before linearized reading' (duration: 403.12161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:04.115208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:03.711723Z","time spent":"403.476048ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-03T05:07:04.114996Z","caller":"traceutil/trace.go:171","msg":"trace[606155687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1829; }","duration":"394.50668ms","start":"2026-04-03T05:07:03.72047Z","end":"2026-04-03T05:07:04.114977Z","steps":["trace[606155687] 'agreement among raft nodes before linearized reading' (duration: 394.397478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:04.115752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:03.720456Z","time spent":"395.266437ms","remote":"127.0.0.1:51986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:07:04.299773Z","caller":"traceutil/trace.go:171","msg":"trace[2020900320] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"180.641835ms","start":"2026-04-03T05:07:04.119102Z","end":"2026-04-03T05:07:04.299744Z","steps":["trace[2020900320] 'process raft request' (duration: 180.44898ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:04.315872Z","caller":"traceutil/trace.go:171","msg":"trace[918719692] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"196.405241ms","start":"2026-04-03T05:07:04.119444Z","end":"2026-04-03T05:07:04.315849Z","steps":["trace[918719692] 'process raft request' (duration: 196.225007ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:04.316237Z","caller":"traceutil/trace.go:171","msg":"trace[1599462852] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1896; }","duration":"136.270977ms","start":"2026-04-03T05:07:04.179957Z","end":"2026-04-03T05:07:04.316228Z","steps":["trace[1599462852] 'read index received' (duration: 119.645602ms)","trace[1599462852] 'applied index is now lower than readState.Index' (duration: 16.624565ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:04.31629Z","caller":"traceutil/trace.go:171","msg":"trace[1042798890] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"196.79154ms","start":"2026-04-03T05:07:04.119467Z","end":"2026-04-03T05:07:04.316258Z","steps":["trace[1042798890] 'process raft request' (duration: 196.33447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:04.316465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.480163ms","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-04-03T05:07:04.316502Z","caller":"traceutil/trace.go:171","msg":"trace[2073968042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1832; }","duration":"136.609536ms","start":"2026-04-03T05:07:04.179885Z","end":"2026-04-03T05:07:04.316494Z","steps":["trace[2073968042] 'agreement among raft nodes before linearized reading' (duration: 136.404331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:04.316496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.168452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-03T05:07:04.316564Z","caller":"traceutil/trace.go:171","msg":"trace[594075917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1832; }","duration":"134.258444ms","start":"2026-04-03T05:07:04.182289Z","end":"2026-04-03T05:07:04.316548Z","steps":["trace[594075917] 'agreement among raft nodes before linearized reading' (duration: 134.080599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:04.520357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.18247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:07:04.520647Z","caller":"traceutil/trace.go:171","msg":"trace[1068347316] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"198.630439ms","start":"2026-04-03T05:07:04.321987Z","end":"2026-04-03T05:07:04.520618Z","steps":["trace[1068347316] 'process raft request' (duration: 64.9944ms)","trace[1068347316] 'compare' (duration: 132.590857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:07:04.916072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.839507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:07:04.916156Z","caller":"traceutil/trace.go:171","msg":"trace[1137693515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1835; }","duration":"195.00063ms","start":"2026-04-03T05:07:04.721136Z","end":"2026-04-03T05:07:04.916136Z","steps":["trace[1137693515] 'range keys from in-memory index tree' (duration: 194.632913ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:18.691984Z","caller":"traceutil/trace.go:171","msg":"trace[12501744] linearizableReadLoop","detail":"{readStateIndex:2025; appliedIndex:2024; }","duration":"247.463854ms","start":"2026-04-03T05:07:18.444494Z","end":"2026-04-03T05:07:18.691958Z","steps":["trace[12501744] 'read index received' (duration: 244.891457ms)","trace[12501744] 'applied index is now lower than readState.Index' (duration: 2.570977ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:18.69207Z","caller":"traceutil/trace.go:171","msg":"trace[476340708] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"267.610898ms","start":"2026-04-03T05:07:18.424428Z","end":"2026-04-03T05:07:18.692039Z","steps":["trace[476340708] 'process raft request' (duration: 264.955749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:18.69226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.74016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-5448d56d95-9rl5h\" ","response":"range_response_count:1 size:5817"} {"level":"info","ts":"2026-04-03T05:07:18.692327Z","caller":"traceutil/trace.go:171","msg":"trace[80454931] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-5448d56d95-9rl5h; range_end:; response_count:1; response_revision:1953; }","duration":"247.839482ms","start":"2026-04-03T05:07:18.444455Z","end":"2026-04-03T05:07:18.692294Z","steps":["trace[80454931] 'agreement among raft nodes before linearized reading' (duration: 247.644268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:18.692374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.340903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T05:07:18.692434Z","caller":"traceutil/trace.go:171","msg":"trace[490358623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1953; }","duration":"113.445555ms","start":"2026-04-03T05:07:18.578975Z","end":"2026-04-03T05:07:18.69242Z","steps":["trace[490358623] 'agreement among raft nodes before linearized reading' (duration: 113.285312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:50.071142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.272213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-03T05:07:50.071233Z","caller":"traceutil/trace.go:171","msg":"trace[680373545] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2136; }","duration":"138.404408ms","start":"2026-04-03T05:07:49.932809Z","end":"2026-04-03T05:07:50.071214Z","steps":["trace[680373545] 'range keys from in-memory index tree' (duration: 138.13378ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:50.595949Z","caller":"traceutil/trace.go:171","msg":"trace[1257346079] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"316.121732ms","start":"2026-04-03T05:07:50.279803Z","end":"2026-04-03T05:07:50.595925Z","steps":["trace[1257346079] 'process raft request' (duration: 315.925417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:50.597791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:07:50.279779Z","time spent":"317.93238ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:07:50.812299Z","caller":"traceutil/trace.go:171","msg":"trace[2145863558] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"211.319556ms","start":"2026-04-03T05:07:50.600943Z","end":"2026-04-03T05:07:50.812263Z","steps":["trace[2145863558] 'process raft request' (duration: 114.723683ms)","trace[2145863558] 'compare' (duration: 96.360148ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:07:50.812394Z","caller":"traceutil/trace.go:171","msg":"trace[2037119303] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"211.150043ms","start":"2026-04-03T05:07:50.601208Z","end":"2026-04-03T05:07:50.812358Z","steps":["trace[2037119303] 'process raft request' (duration: 210.959729ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:50.812397Z","caller":"traceutil/trace.go:171","msg":"trace[882673144] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"211.059141ms","start":"2026-04-03T05:07:50.601298Z","end":"2026-04-03T05:07:50.812358Z","steps":["trace[882673144] 'process raft request' (duration: 210.935788ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:07:55.893368Z","caller":"traceutil/trace.go:171","msg":"trace[1550369755] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"118.747933ms","start":"2026-04-03T05:07:55.774594Z","end":"2026-04-03T05:07:55.893342Z","steps":["trace[1550369755] 'process raft request' (duration: 118.60388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:07:55.913028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.474054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:07:55.913103Z","caller":"traceutil/trace.go:171","msg":"trace[402243051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2167; }","duration":"112.577376ms","start":"2026-04-03T05:07:55.800508Z","end":"2026-04-03T05:07:55.913085Z","steps":["trace[402243051] 'agreement among raft nodes before linearized reading' (duration: 112.431792ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:08:44.038636Z","caller":"traceutil/trace.go:171","msg":"trace[912816149] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"140.83329ms","start":"2026-04-03T05:08:43.897776Z","end":"2026-04-03T05:08:44.038609Z","steps":["trace[912816149] 'process raft request' (duration: 140.680447ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:11:43.367146Z","caller":"traceutil/trace.go:171","msg":"trace[638296745] transaction","detail":"{read_only:false; response_revision:3774; number_of_response:1; }","duration":"158.018741ms","start":"2026-04-03T05:11:43.209069Z","end":"2026-04-03T05:11:43.367087Z","steps":["trace[638296745] 'process raft request' (duration: 89.228658ms)","trace[638296745] 'compare' (duration: 68.578769ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:13:18.531033Z","caller":"traceutil/trace.go:171","msg":"trace[1467674414] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"104.654991ms","start":"2026-04-03T05:13:18.426327Z","end":"2026-04-03T05:13:18.530982Z","steps":["trace[1467674414] 'process raft request' (duration: 104.357115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:18.76947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.46622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:13:18.769706Z","caller":"traceutil/trace.go:171","msg":"trace[249232676] linearizableReadLoop","detail":"{readStateIndex:5286; appliedIndex:5283; }","duration":"208.350842ms","start":"2026-04-03T05:13:18.561338Z","end":"2026-04-03T05:13:18.769689Z","steps":["trace[249232676] 'read index received' (duration: 58.512394ms)","trace[249232676] 'applied index is now lower than readState.Index' (duration: 149.837378ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:13:18.769745Z","caller":"traceutil/trace.go:171","msg":"trace[810749175] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"231.345405ms","start":"2026-04-03T05:13:18.538365Z","end":"2026-04-03T05:13:18.769711Z","steps":["trace[810749175] 'process raft request' (duration: 81.530007ms)","trace[810749175] 'compare' (duration: 149.347087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:13:18.769891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.563877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T05:13:18.769914Z","caller":"traceutil/trace.go:171","msg":"trace[2016873638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5096; }","duration":"208.627568ms","start":"2026-04-03T05:13:18.56128Z","end":"2026-04-03T05:13:18.769908Z","steps":["trace[2016873638] 'agreement among raft nodes before linearized reading' (duration: 208.525306ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:13:18.769792Z","caller":"traceutil/trace.go:171","msg":"trace[1768619715] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"230.528686ms","start":"2026-04-03T05:13:18.539254Z","end":"2026-04-03T05:13:18.769782Z","steps":["trace[1768619715] 'process raft request' (duration: 230.391613ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:13:18.76975Z","caller":"traceutil/trace.go:171","msg":"trace[1469335621] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"231.341515ms","start":"2026-04-03T05:13:18.538367Z","end":"2026-04-03T05:13:18.769708Z","steps":["trace[1469335621] 'process raft request' (duration: 231.209992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:18.770157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.504861ms","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:5"} {"level":"info","ts":"2026-04-03T05:13:18.770181Z","caller":"traceutil/trace.go:171","msg":"trace[476736312] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5096; }","duration":"127.575823ms","start":"2026-04-03T05:13:18.642599Z","end":"2026-04-03T05:13:18.770175Z","steps":["trace[476736312] 'agreement among raft nodes before linearized reading' (duration: 127.530852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:18.770331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.899765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-03T05:13:18.770352Z","caller":"traceutil/trace.go:171","msg":"trace[1725112053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5096; }","duration":"116.948077ms","start":"2026-04-03T05:13:18.653397Z","end":"2026-04-03T05:13:18.770346Z","steps":["trace[1725112053] 'agreement among raft nodes before linearized reading' (duration: 116.888235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:18.770347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.749407ms","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-04-03T05:13:18.77041Z","caller":"traceutil/trace.go:171","msg":"trace[241040110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5096; }","duration":"114.8572ms","start":"2026-04-03T05:13:18.655538Z","end":"2026-04-03T05:13:18.770396Z","steps":["trace[241040110] 'agreement among raft nodes before linearized reading' (duration: 114.641015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:18.770441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.599019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-03T05:13:18.770487Z","caller":"traceutil/trace.go:171","msg":"trace[2029767072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5096; }","duration":"125.731122ms","start":"2026-04-03T05:13:18.644744Z","end":"2026-04-03T05:13:18.770476Z","steps":["trace[2029767072] 'agreement among raft nodes before linearized reading' (duration: 125.548507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:18.770577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.552227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-03T05:13:18.770608Z","caller":"traceutil/trace.go:171","msg":"trace[852683418] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5096; }","duration":"120.609037ms","start":"2026-04-03T05:13:18.64999Z","end":"2026-04-03T05:13:18.770599Z","steps":["trace[852683418] 'agreement among raft nodes before linearized reading' (duration: 120.513815ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:13:30.753614Z","caller":"traceutil/trace.go:171","msg":"trace[355263943] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"211.693236ms","start":"2026-04-03T05:13:30.541898Z","end":"2026-04-03T05:13:30.753591Z","steps":["trace[355263943] 'process raft request' (duration: 211.513782ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:13:30.816827Z","caller":"traceutil/trace.go:171","msg":"trace[1473108258] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"262.182683ms","start":"2026-04-03T05:13:30.554605Z","end":"2026-04-03T05:13:30.816788Z","steps":["trace[1473108258] 'process raft request' (duration: 261.929558ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:13:43.939832Z","caller":"traceutil/trace.go:171","msg":"trace[1355471124] linearizableReadLoop","detail":"{readStateIndex:5695; appliedIndex:5694; }","duration":"261.826814ms","start":"2026-04-03T05:13:43.677975Z","end":"2026-04-03T05:13:43.939801Z","steps":["trace[1355471124] 'read index received' (duration: 261.530128ms)","trace[1355471124] 'applied index is now lower than readState.Index' (duration: 295.746µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:13:43.940201Z","caller":"traceutil/trace.go:171","msg":"trace[2019724273] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"283.65057ms","start":"2026-04-03T05:13:43.656526Z","end":"2026-04-03T05:13:43.940177Z","steps":["trace[2019724273] 'process raft request' (duration: 283.070428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:43.940685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.689884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"info","ts":"2026-04-03T05:13:43.940751Z","caller":"traceutil/trace.go:171","msg":"trace[1581245665] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5496; }","duration":"262.827406ms","start":"2026-04-03T05:13:43.677904Z","end":"2026-04-03T05:13:43.940732Z","steps":["trace[1581245665] 'agreement among raft nodes before linearized reading' (duration: 262.594441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:44.285168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.874969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:13:44.285316Z","caller":"traceutil/trace.go:171","msg":"trace[1930782388] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"557.951524ms","start":"2026-04-03T05:13:43.727328Z","end":"2026-04-03T05:13:44.285279Z","steps":["trace[1930782388] 'process raft request' (duration: 403.83103ms)","trace[1930782388] 'compare' (duration: 153.676243ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:13:44.285447Z","caller":"traceutil/trace.go:171","msg":"trace[1112328752] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"194.404422ms","start":"2026-04-03T05:13:44.091024Z","end":"2026-04-03T05:13:44.285429Z","steps":["trace[1112328752] 'process raft request' (duration: 194.360661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:44.285472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:13:43.727301Z","time spent":"558.079806ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:13:44.285578Z","caller":"traceutil/trace.go:171","msg":"trace[787266128] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"333.424249ms","start":"2026-04-03T05:13:43.952113Z","end":"2026-04-03T05:13:44.285537Z","steps":["trace[787266128] 'process raft request' (duration: 333.193194ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:13:44.285613Z","caller":"traceutil/trace.go:171","msg":"trace[1908738335] linearizableReadLoop","detail":"{readStateIndex:5696; appliedIndex:5695; }","duration":"345.637242ms","start":"2026-04-03T05:13:43.939921Z","end":"2026-04-03T05:13:44.285559Z","steps":["trace[1908738335] 'read index received' (duration: 191.254742ms)","trace[1908738335] 'applied index is now lower than readState.Index' (duration: 154.37807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:13:44.285696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.141123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:13:44.28574Z","caller":"traceutil/trace.go:171","msg":"trace[155818511] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5499; }","duration":"495.225575ms","start":"2026-04-03T05:13:43.790504Z","end":"2026-04-03T05:13:44.285729Z","steps":["trace[155818511] 'agreement among raft nodes before linearized reading' (duration: 495.148774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:44.285741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:13:43.952093Z","time spent":"333.560173ms","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:13:44.285782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:13:43.790488Z","time spent":"495.279366ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:13:44.285987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.109646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-03T05:13:44.286066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.266573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-03T05:13:44.28614Z","caller":"traceutil/trace.go:171","msg":"trace[1174464580] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5499; }","duration":"586.366465ms","start":"2026-04-03T05:13:43.699753Z","end":"2026-04-03T05:13:44.286119Z","steps":["trace[1174464580] 'agreement among raft nodes before linearized reading' (duration: 586.041188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:44.286205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:13:43.699687Z","time spent":"586.501939ms","remote":"127.0.0.1:52098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-03T05:13:44.286497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.626829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-28fwd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-03T05:13:44.286641Z","caller":"traceutil/trace.go:171","msg":"trace[315576150] range","detail":"{range_begin:/registry/pods/openstack/keepalived-28fwd; range_end:; response_count:1; response_revision:5499; }","duration":"183.354485ms","start":"2026-04-03T05:13:44.103243Z","end":"2026-04-03T05:13:44.286598Z","steps":["trace[315576150] 'agreement among raft nodes before linearized reading' (duration: 182.593008ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:13:44.28679Z","caller":"traceutil/trace.go:171","msg":"trace[176023916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5499; }","duration":"606.923604ms","start":"2026-04-03T05:13:43.679848Z","end":"2026-04-03T05:13:44.286017Z","steps":["trace[176023916] 'agreement among raft nodes before linearized reading' (duration: 606.056725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:13:44.286863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:13:43.679838Z","time spent":"607.008516ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T05:14:59.268599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3033} {"level":"info","ts":"2026-04-03T05:14:59.348955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3033,"took":"78.743926ms","hash":4173144931,"current-db-size-bytes":56987648,"current-db-size":"57 MB","current-db-size-in-use-bytes":31780864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-03T05:14:59.349024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4173144931,"revision":3033,"compact-revision":-1} {"level":"info","ts":"2026-04-03T05:19:34.991865Z","caller":"traceutil/trace.go:171","msg":"trace[447998996] linearizableReadLoop","detail":"{readStateIndex:8461; appliedIndex:8460; }","duration":"108.591593ms","start":"2026-04-03T05:19:34.883238Z","end":"2026-04-03T05:19:34.991829Z","steps":["trace[447998996] 'read index received' (duration: 11.471876ms)","trace[447998996] 'applied index is now lower than readState.Index' (duration: 97.118277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:19:34.992142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.891821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-03T05:19:34.992189Z","caller":"traceutil/trace.go:171","msg":"trace[1134641720] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8178; }","duration":"108.990943ms","start":"2026-04-03T05:19:34.883185Z","end":"2026-04-03T05:19:34.992176Z","steps":["trace[1134641720] 'agreement among raft nodes before linearized reading' (duration: 108.83813ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:34.991956Z","caller":"traceutil/trace.go:171","msg":"trace[342376120] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"110.848835ms","start":"2026-04-03T05:19:34.881078Z","end":"2026-04-03T05:19:34.991927Z","steps":["trace[342376120] 'process raft request' (duration: 29.741494ms)","trace[342376120] 'compare' (duration: 80.875615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:19:34.992289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.190905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T05:19:34.992419Z","caller":"traceutil/trace.go:171","msg":"trace[595201539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8178; }","duration":"108.323608ms","start":"2026-04-03T05:19:34.884078Z","end":"2026-04-03T05:19:34.992401Z","steps":["trace[595201539] 'agreement among raft nodes before linearized reading' (duration: 108.036032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:36.528071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675801244375010,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T05:19:36.595526Z","caller":"traceutil/trace.go:171","msg":"trace[1610566825] linearizableReadLoop","detail":"{readStateIndex:8464; appliedIndex:8463; }","duration":"568.211432ms","start":"2026-04-03T05:19:36.027284Z","end":"2026-04-03T05:19:36.595496Z","steps":["trace[1610566825] 'read index received' (duration: 568.089739ms)","trace[1610566825] 'applied index is now lower than readState.Index' (duration: 120.553µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:19:36.595664Z","caller":"traceutil/trace.go:171","msg":"trace[1945768699] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"780.608303ms","start":"2026-04-03T05:19:35.81502Z","end":"2026-04-03T05:19:36.595628Z","steps":["trace[1945768699] 'process raft request' (duration: 780.298056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:36.595713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.406977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:36.595868Z","caller":"traceutil/trace.go:171","msg":"trace[2018749377] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8181; }","duration":"568.635442ms","start":"2026-04-03T05:19:36.027218Z","end":"2026-04-03T05:19:36.595854Z","steps":["trace[2018749377] 'agreement among raft nodes before linearized reading' (duration: 568.421387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:36.595896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:35.814965Z","time spent":"780.802368ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:19:36.595925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:36.027195Z","time spent":"568.712253ms","remote":"127.0.0.1:53718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:19:36.612771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.409011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-28fwd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-03T05:19:36.612832Z","caller":"traceutil/trace.go:171","msg":"trace[1466867456] range","detail":"{range_begin:/registry/pods/openstack/keepalived-28fwd; range_end:; response_count:1; response_revision:8183; }","duration":"173.526323ms","start":"2026-04-03T05:19:36.439291Z","end":"2026-04-03T05:19:36.612817Z","steps":["trace[1466867456] 'agreement among raft nodes before linearized reading' (duration: 173.315039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:36.612808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.759418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T05:19:36.612976Z","caller":"traceutil/trace.go:171","msg":"trace[973216531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8183; }","duration":"436.957822ms","start":"2026-04-03T05:19:36.175942Z","end":"2026-04-03T05:19:36.6129Z","steps":["trace[973216531] 'agreement among raft nodes before linearized reading' (duration: 436.616385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:36.613078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:36.175915Z","time spent":"437.148927ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T05:19:38.22436Z","caller":"traceutil/trace.go:171","msg":"trace[60004532] linearizableReadLoop","detail":"{readStateIndex:8474; appliedIndex:8473; }","duration":"521.543511ms","start":"2026-04-03T05:19:37.702778Z","end":"2026-04-03T05:19:38.224322Z","steps":["trace[60004532] 'read index received' (duration: 458.424552ms)","trace[60004532] 'applied index is now lower than readState.Index' (duration: 63.117109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:19:38.22455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.758596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:19:38.224632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.697986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:38.224745Z","caller":"traceutil/trace.go:171","msg":"trace[2029054862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8190; }","duration":"504.865049ms","start":"2026-04-03T05:19:37.719852Z","end":"2026-04-03T05:19:38.224717Z","steps":["trace[2029054862] 'agreement among raft nodes before linearized reading' (duration: 504.660214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:38.224809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:37.719837Z","time spent":"504.962081ms","remote":"127.0.0.1:51988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:19:38.224712Z","caller":"traceutil/trace.go:171","msg":"trace[1307234557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8190; }","duration":"521.95725ms","start":"2026-04-03T05:19:37.702735Z","end":"2026-04-03T05:19:38.224692Z","steps":["trace[1307234557] 'agreement among raft nodes before linearized reading' (duration: 521.733445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:38.22496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:37.70269Z","time spent":"522.255287ms","remote":"127.0.0.1:51986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T05:19:39.361058Z","caller":"traceutil/trace.go:171","msg":"trace[2140388895] linearizableReadLoop","detail":"{readStateIndex:8482; appliedIndex:8481; }","duration":"398.117405ms","start":"2026-04-03T05:19:38.962895Z","end":"2026-04-03T05:19:39.361013Z","steps":["trace[2140388895] 'read index received' (duration: 397.758457ms)","trace[2140388895] 'applied index is now lower than readState.Index' (duration: 357.048µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:19:39.361131Z","caller":"traceutil/trace.go:171","msg":"trace[1955630798] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"440.947961ms","start":"2026-04-03T05:19:38.92014Z","end":"2026-04-03T05:19:39.361088Z","steps":["trace[1955630798] 'process raft request' (duration: 440.636894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.361346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:38.920115Z","time spent":"441.120135ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:19:39.361425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.432253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-03T05:19:39.361588Z","caller":"traceutil/trace.go:171","msg":"trace[1177442884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8197; }","duration":"398.720918ms","start":"2026-04-03T05:19:38.962848Z","end":"2026-04-03T05:19:39.361568Z","steps":["trace[1177442884] 'agreement among raft nodes before linearized reading' (duration: 398.3404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.361645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:38.96283Z","time spent":"398.803302ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T05:19:39.362109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.456749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.362192Z","caller":"traceutil/trace.go:171","msg":"trace[1098552442] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8197; }","duration":"176.563381ms","start":"2026-04-03T05:19:39.185606Z","end":"2026-04-03T05:19:39.362169Z","steps":["trace[1098552442] 'agreement among raft nodes before linearized reading' (duration: 176.397617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.362205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.269248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:19:39.362208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.502043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-03T05:19:39.362502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.059185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.362529Z","caller":"traceutil/trace.go:171","msg":"trace[595446799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8197; }","duration":"337.888402ms","start":"2026-04-03T05:19:39.02461Z","end":"2026-04-03T05:19:39.362498Z","steps":["trace[595446799] 'agreement among raft nodes before linearized reading' (duration: 337.35166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.362591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:39.024584Z","time spent":"337.997645ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:19:39.362621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.237851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:19:39.362643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.586728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.362666Z","caller":"traceutil/trace.go:171","msg":"trace[1479546612] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8197; }","duration":"132.291872ms","start":"2026-04-03T05:19:39.230357Z","end":"2026-04-03T05:19:39.362649Z","steps":["trace[1479546612] 'agreement among raft nodes before linearized reading' (duration: 132.232211ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:39.362676Z","caller":"traceutil/trace.go:171","msg":"trace[164219674] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8197; }","duration":"235.642769ms","start":"2026-04-03T05:19:39.127021Z","end":"2026-04-03T05:19:39.362664Z","steps":["trace[164219674] 'agreement among raft nodes before linearized reading' (duration: 235.596128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.362863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.926058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.362913Z","caller":"traceutil/trace.go:171","msg":"trace[1387278643] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8197; }","duration":"132.986439ms","start":"2026-04-03T05:19:39.229913Z","end":"2026-04-03T05:19:39.362899Z","steps":["trace[1387278643] 'agreement among raft nodes before linearized reading' (duration: 132.919237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.362999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.325386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-03T05:19:39.362537Z","caller":"traceutil/trace.go:171","msg":"trace[917410576] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8197; }","duration":"217.141066ms","start":"2026-04-03T05:19:39.145385Z","end":"2026-04-03T05:19:39.362526Z","steps":["trace[917410576] 'agreement among raft nodes before linearized reading' (duration: 216.898011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.36311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.212844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.363157Z","caller":"traceutil/trace.go:171","msg":"trace[528444119] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8197; }","duration":"133.278155ms","start":"2026-04-03T05:19:39.229864Z","end":"2026-04-03T05:19:39.363143Z","steps":["trace[528444119] 'agreement among raft nodes before linearized reading' (duration: 133.216244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.362124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.339322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:19:39.362863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.118709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.363311Z","caller":"traceutil/trace.go:171","msg":"trace[91855172] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8197; }","duration":"236.58734ms","start":"2026-04-03T05:19:39.126701Z","end":"2026-04-03T05:19:39.363288Z","steps":["trace[91855172] 'agreement among raft nodes before linearized reading' (duration: 235.328432ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:39.363357Z","caller":"traceutil/trace.go:171","msg":"trace[1166257865] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8197; }","duration":"236.682012ms","start":"2026-04-03T05:19:39.126664Z","end":"2026-04-03T05:19:39.363346Z","steps":["trace[1166257865] 'agreement among raft nodes before linearized reading' (duration: 236.159041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.363288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.026093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:19:39.362918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.986209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.363468Z","caller":"traceutil/trace.go:171","msg":"trace[300445787] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:8197; }","duration":"297.589322ms","start":"2026-04-03T05:19:39.06587Z","end":"2026-04-03T05:19:39.363459Z","steps":["trace[300445787] 'agreement among raft nodes before linearized reading' (duration: 297.015739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.363556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.535523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.363603Z","caller":"traceutil/trace.go:171","msg":"trace[753312287] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8197; }","duration":"156.611496ms","start":"2026-04-03T05:19:39.206981Z","end":"2026-04-03T05:19:39.363593Z","steps":["trace[753312287] 'agreement among raft nodes before linearized reading' (duration: 156.525654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.36383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.847743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:19:39.363855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.183327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:19:39.363867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.029588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-03T05:19:39.363955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.301757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.36399Z","caller":"traceutil/trace.go:171","msg":"trace[443135718] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8197; }","duration":"177.357818ms","start":"2026-04-03T05:19:39.186623Z","end":"2026-04-03T05:19:39.363981Z","steps":["trace[443135718] 'agreement among raft nodes before linearized reading' (duration: 177.314117ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:39.363982Z","caller":"traceutil/trace.go:171","msg":"trace[272865468] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8197; }","duration":"318.184051ms","start":"2026-04-03T05:19:39.045771Z","end":"2026-04-03T05:19:39.363955Z","steps":["trace[272865468] 'agreement among raft nodes before linearized reading' (duration: 317.71014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.362594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.219999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:19:39.364026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.34478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:19:39.364036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:39.04576Z","time spent":"318.264892ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-03T05:19:39.364065Z","caller":"traceutil/trace.go:171","msg":"trace[583125168] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8197; }","duration":"178.406021ms","start":"2026-04-03T05:19:39.185649Z","end":"2026-04-03T05:19:39.364055Z","steps":["trace[583125168] 'agreement among raft nodes before linearized reading' (duration: 178.34303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.363915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.994916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.362257Z","caller":"traceutil/trace.go:171","msg":"trace[1963943305] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8197; }","duration":"216.36354ms","start":"2026-04-03T05:19:39.145875Z","end":"2026-04-03T05:19:39.362239Z","steps":["trace[1963943305] 'agreement among raft nodes before linearized reading' (duration: 216.290688ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:39.364158Z","caller":"traceutil/trace.go:171","msg":"trace[571330751] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8197; }","duration":"158.233201ms","start":"2026-04-03T05:19:39.205899Z","end":"2026-04-03T05:19:39.364132Z","steps":["trace[571330751] 'agreement among raft nodes before linearized reading' (duration: 157.990245ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:39.364061Z","caller":"traceutil/trace.go:171","msg":"trace[1705345658] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8197; }","duration":"218.727142ms","start":"2026-04-03T05:19:39.145319Z","end":"2026-04-03T05:19:39.364047Z","steps":["trace[1705345658] 'agreement among raft nodes before linearized reading' (duration: 217.245439ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:39.363039Z","caller":"traceutil/trace.go:171","msg":"trace[1022381969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8197; }","duration":"301.382287ms","start":"2026-04-03T05:19:39.061646Z","end":"2026-04-03T05:19:39.363028Z","steps":["trace[1022381969] 'agreement among raft nodes before linearized reading' (duration: 301.290605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.364315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.172388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:19:39.364356Z","caller":"traceutil/trace.go:171","msg":"trace[183379601] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8197; }","duration":"110.221559ms","start":"2026-04-03T05:19:39.254119Z","end":"2026-04-03T05:19:39.364341Z","steps":["trace[183379601] 'agreement among raft nodes before linearized reading' (duration: 110.157888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:19:39.364375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:19:39.061636Z","time spent":"302.673886ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T05:19:39.364298Z","caller":"traceutil/trace.go:171","msg":"trace[888304441] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8197; }","duration":"109.249198ms","start":"2026-04-03T05:19:39.254643Z","end":"2026-04-03T05:19:39.363892Z","steps":["trace[888304441] 'agreement among raft nodes before linearized reading' (duration: 108.946622ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:39.363866Z","caller":"traceutil/trace.go:171","msg":"trace[594530476] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8197; }","duration":"157.886324ms","start":"2026-04-03T05:19:39.205971Z","end":"2026-04-03T05:19:39.363857Z","steps":["trace[594530476] 'agreement among raft nodes before linearized reading' (duration: 157.826522ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:39.36342Z","caller":"traceutil/trace.go:171","msg":"trace[1412037554] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8197; }","duration":"109.394001ms","start":"2026-04-03T05:19:39.254005Z","end":"2026-04-03T05:19:39.363399Z","steps":["trace[1412037554] 'agreement among raft nodes before linearized reading' (duration: 107.97173ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:19:59.276819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6142} {"level":"info","ts":"2026-04-03T05:19:59.385118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6142,"took":"106.144049ms","hash":3250743788,"current-db-size-bytes":56987648,"current-db-size":"57 MB","current-db-size-in-use-bytes":22818816,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-03T05:19:59.385199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3250743788,"revision":6142,"compact-revision":3033} {"level":"warn","ts":"2026-04-03T05:20:03.778012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.325221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/csiaddonsnodes.csiaddons.openshift.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:20:03.778129Z","caller":"traceutil/trace.go:171","msg":"trace[496137791] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/csiaddonsnodes.csiaddons.openshift.io; range_end:; response_count:0; response_revision:8381; }","duration":"157.479405ms","start":"2026-04-03T05:20:03.620623Z","end":"2026-04-03T05:20:03.778102Z","steps":["trace[496137791] 'range keys from in-memory index tree' (duration: 157.201369ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:03.778959Z","caller":"traceutil/trace.go:171","msg":"trace[841245709] transaction","detail":"{read_only:false; response_revision:8382; number_of_response:1; }","duration":"157.748822ms","start":"2026-04-03T05:20:03.621192Z","end":"2026-04-03T05:20:03.778941Z","steps":["trace[841245709] 'process raft request' (duration: 156.141825ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:03.781696Z","caller":"traceutil/trace.go:171","msg":"trace[8026892] transaction","detail":"{read_only:false; response_revision:8384; number_of_response:1; }","duration":"160.211636ms","start":"2026-04-03T05:20:03.621464Z","end":"2026-04-03T05:20:03.781676Z","steps":["trace[8026892] 'process raft request' (duration: 160.154355ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:03.782104Z","caller":"traceutil/trace.go:171","msg":"trace[324942743] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"160.723957ms","start":"2026-04-03T05:20:03.621361Z","end":"2026-04-03T05:20:03.782085Z","steps":["trace[324942743] 'process raft request' (duration: 160.149775ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:04.966861Z","caller":"traceutil/trace.go:171","msg":"trace[2115818088] transaction","detail":"{read_only:false; response_revision:8390; number_of_response:1; }","duration":"108.340268ms","start":"2026-04-03T05:20:04.858479Z","end":"2026-04-03T05:20:04.966819Z","steps":["trace[2115818088] 'process raft request' (duration: 108.096613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:06.466208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.60886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:20:06.466302Z","caller":"traceutil/trace.go:171","msg":"trace[2066313228] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8395; }","duration":"455.717582ms","start":"2026-04-03T05:20:06.010561Z","end":"2026-04-03T05:20:06.466279Z","steps":["trace[2066313228] 'count revisions from in-memory index tree' (duration: 455.509847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:06.46635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:06.010539Z","time spent":"455.800453ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-03T05:20:07.003921Z","caller":"traceutil/trace.go:171","msg":"trace[2008947983] transaction","detail":"{read_only:false; response_revision:8396; number_of_response:1; }","duration":"270.694542ms","start":"2026-04-03T05:20:06.733196Z","end":"2026-04-03T05:20:07.00389Z","steps":["trace[2008947983] 'process raft request' (duration: 270.374755ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:07.014316Z","caller":"traceutil/trace.go:171","msg":"trace[634884844] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"278.541407ms","start":"2026-04-03T05:20:06.735741Z","end":"2026-04-03T05:20:07.014283Z","steps":["trace[634884844] 'process raft request' (duration: 278.287232ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:07.014552Z","caller":"traceutil/trace.go:171","msg":"trace[783220314] transaction","detail":"{read_only:false; response_revision:8398; number_of_response:1; }","duration":"278.431915ms","start":"2026-04-03T05:20:06.736083Z","end":"2026-04-03T05:20:07.014515Z","steps":["trace[783220314] 'process raft request' (duration: 278.144049ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:07.015153Z","caller":"traceutil/trace.go:171","msg":"trace[1829939165] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"105.192628ms","start":"2026-04-03T05:20:06.909941Z","end":"2026-04-03T05:20:07.015134Z","steps":["trace[1829939165] 'process raft request' (duration: 104.994473ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:09.237626Z","caller":"traceutil/trace.go:171","msg":"trace[811383281] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"228.433188ms","start":"2026-04-03T05:20:09.009167Z","end":"2026-04-03T05:20:09.237601Z","steps":["trace[811383281] 'process raft request' (duration: 228.262044ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:09.375069Z","caller":"traceutil/trace.go:171","msg":"trace[125028894] linearizableReadLoop","detail":"{readStateIndex:8703; appliedIndex:8700; }","duration":"347.971766ms","start":"2026-04-03T05:20:09.027065Z","end":"2026-04-03T05:20:09.375037Z","steps":["trace[125028894] 'read index received' (duration: 210.470897ms)","trace[125028894] 'applied index is now lower than readState.Index' (duration: 137.499169ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:20:09.375109Z","caller":"traceutil/trace.go:171","msg":"trace[1648943721] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"354.354189ms","start":"2026-04-03T05:20:09.020706Z","end":"2026-04-03T05:20:09.37506Z","steps":["trace[1648943721] 'process raft request' (duration: 354.240336ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:09.37524Z","caller":"traceutil/trace.go:171","msg":"trace[1658000198] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"189.427177ms","start":"2026-04-03T05:20:09.185802Z","end":"2026-04-03T05:20:09.375229Z","steps":["trace[1658000198] 'process raft request' (duration: 189.183682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:09.375318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:09.020681Z","time spent":"354.524522ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:20:09.375514Z","caller":"traceutil/trace.go:171","msg":"trace[583026278] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"356.764432ms","start":"2026-04-03T05:20:09.018721Z","end":"2026-04-03T05:20:09.375485Z","steps":["trace[583026278] 'process raft request' (duration: 356.047876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:09.375542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.472647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-03T05:20:09.375532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.407386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-28fwd\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-03T05:20:09.375569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:09.018702Z","time spent":"356.836615ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:20:09.37558Z","caller":"traceutil/trace.go:171","msg":"trace[568329024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8410; }","duration":"348.53724ms","start":"2026-04-03T05:20:09.027033Z","end":"2026-04-03T05:20:09.37557Z","steps":["trace[568329024] 'agreement among raft nodes before linearized reading' (duration: 348.370745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:09.375625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:09.027024Z","time spent":"348.595991ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T05:20:09.37564Z","caller":"traceutil/trace.go:171","msg":"trace[824328895] range","detail":"{range_begin:/registry/pods/openstack/keepalived-28fwd; range_end:; response_count:1; response_revision:8410; }","duration":"344.54414ms","start":"2026-04-03T05:20:09.031051Z","end":"2026-04-03T05:20:09.375595Z","steps":["trace[824328895] 'agreement among raft nodes before linearized reading' (duration: 344.287874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:09.375668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.560087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-04-03T05:20:09.375693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:09.031031Z","time spent":"344.648742ms","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-28fwd\" "} {"level":"info","ts":"2026-04-03T05:20:09.375742Z","caller":"traceutil/trace.go:171","msg":"trace[1994116124] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8410; }","duration":"213.68171ms","start":"2026-04-03T05:20:09.162041Z","end":"2026-04-03T05:20:09.375722Z","steps":["trace[1994116124] 'agreement among raft nodes before linearized reading' (duration: 213.422504ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:19.782072Z","caller":"traceutil/trace.go:171","msg":"trace[1574305460] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"549.174647ms","start":"2026-04-03T05:20:19.232826Z","end":"2026-04-03T05:20:19.782Z","steps":["trace[1574305460] 'process raft request' (duration: 548.86614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:19.78231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.23281Z","time spent":"549.369622ms","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4634,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:20:19.997957Z","caller":"traceutil/trace.go:171","msg":"trace[1119635539] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8504; }","duration":"763.89159ms","start":"2026-04-03T05:20:19.234046Z","end":"2026-04-03T05:20:19.997938Z","steps":["trace[1119635539] 'process raft request' (duration: 763.44799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:19.99816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.234028Z","time spent":"764.000212ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-04-03T05:20:19.99856Z","caller":"traceutil/trace.go:171","msg":"trace[1011296286] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"728.540151ms","start":"2026-04-03T05:20:19.270008Z","end":"2026-04-03T05:20:19.998548Z","steps":["trace[1011296286] 'process raft request' (duration: 727.656231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:19.998637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.269981Z","time spent":"728.616472ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:20:19.998927Z","caller":"traceutil/trace.go:171","msg":"trace[114019519] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"248.310113ms","start":"2026-04-03T05:20:19.750603Z","end":"2026-04-03T05:20:19.998913Z","steps":["trace[114019519] 'process raft request' (duration: 248.19315ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:19.998939Z","caller":"traceutil/trace.go:171","msg":"trace[870180952] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"589.223282ms","start":"2026-04-03T05:20:19.409539Z","end":"2026-04-03T05:20:19.998763Z","steps":["trace[870180952] 'process raft request' (duration: 588.860354ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:19.999009Z","caller":"traceutil/trace.go:171","msg":"trace[276179666] transaction","detail":"{read_only:false; response_revision:8507; number_of_response:1; }","duration":"583.990714ms","start":"2026-04-03T05:20:19.414992Z","end":"2026-04-03T05:20:19.998983Z","steps":["trace[276179666] 'process raft request' (duration: 583.494064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:19.999176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.414968Z","time spent":"584.106828ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:20:19.999237Z","caller":"traceutil/trace.go:171","msg":"trace[1494984264] linearizableReadLoop","detail":"{readStateIndex:8806; appliedIndex:8801; }","duration":"569.772748ms","start":"2026-04-03T05:20:19.429097Z","end":"2026-04-03T05:20:19.99887Z","steps":["trace[1494984264] 'read index received' (duration: 352.730703ms)","trace[1494984264] 'applied index is now lower than readState.Index' (duration: 217.040935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:20:19.999209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.409511Z","time spent":"589.502288ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:20:19.99932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.204077ms","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-04-03T05:20:19.999395Z","caller":"traceutil/trace.go:171","msg":"trace[79707022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8508; }","duration":"570.314449ms","start":"2026-04-03T05:20:19.429062Z","end":"2026-04-03T05:20:19.999377Z","steps":["trace[79707022] 'agreement among raft nodes before linearized reading' (duration: 570.101554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:19.999437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.429054Z","time spent":"570.375191ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T05:20:19.999448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.59887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-d8lzb\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-04-03T05:20:19.999497Z","caller":"traceutil/trace.go:171","msg":"trace[1931844871] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-d8lzb; range_end:; response_count:1; response_revision:8508; }","duration":"210.689213ms","start":"2026-04-03T05:20:19.788796Z","end":"2026-04-03T05:20:19.999486Z","steps":["trace[1931844871] 'agreement among raft nodes before linearized reading' (duration: 210.551929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:19.999683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.686396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-04-03T05:20:19.999707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.457113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-03T05:20:19.999755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.367046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T05:20:19.999784Z","caller":"traceutil/trace.go:171","msg":"trace[1391737356] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8508; }","duration":"358.85285ms","start":"2026-04-03T05:20:19.640908Z","end":"2026-04-03T05:20:19.999761Z","steps":["trace[1391737356] 'agreement among raft nodes before linearized reading' (duration: 358.483761ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:19.999793Z","caller":"traceutil/trace.go:171","msg":"trace[341156544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8508; }","duration":"111.433848ms","start":"2026-04-03T05:20:19.888347Z","end":"2026-04-03T05:20:19.999781Z","steps":["trace[341156544] 'agreement among raft nodes before linearized reading' (duration: 111.339956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:19.999836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.640882Z","time spent":"358.945951ms","remote":"127.0.0.1:52136","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":"info","ts":"2026-04-03T05:20:19.999839Z","caller":"traceutil/trace.go:171","msg":"trace[950212287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8508; }","duration":"402.585886ms","start":"2026-04-03T05:20:19.597188Z","end":"2026-04-03T05:20:19.999774Z","steps":["trace[950212287] 'agreement among raft nodes before linearized reading' (duration: 402.196137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:19.999885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.443918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-03T05:20:19.999854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.424127ms","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-04-03T05:20:19.99994Z","caller":"traceutil/trace.go:171","msg":"trace[1605746708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8508; }","duration":"111.53505ms","start":"2026-04-03T05:20:19.888394Z","end":"2026-04-03T05:20:19.999929Z","steps":["trace[1605746708] 'agreement among raft nodes before linearized reading' (duration: 111.180012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:19.99998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.597166Z","time spent":"402.760219ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-03T05:20:19.99999Z","caller":"traceutil/trace.go:171","msg":"trace[665794271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8508; }","duration":"305.610311ms","start":"2026-04-03T05:20:19.694358Z","end":"2026-04-03T05:20:19.999968Z","steps":["trace[665794271] 'agreement among raft nodes before linearized reading' (duration: 305.292594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:20.000105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.694336Z","time spent":"305.729893ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:20:20.000955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.414916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-28fwd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-03T05:20:20.001007Z","caller":"traceutil/trace.go:171","msg":"trace[595816058] range","detail":"{range_begin:/registry/pods/openstack/keepalived-28fwd; range_end:; response_count:1; response_revision:8508; }","duration":"555.481658ms","start":"2026-04-03T05:20:19.445513Z","end":"2026-04-03T05:20:20.000994Z","steps":["trace[595816058] 'agreement among raft nodes before linearized reading' (duration: 553.796661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:20.001038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:19.445502Z","time spent":"555.529348ms","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-28fwd\" "} {"level":"warn","ts":"2026-04-03T05:20:21.417959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675801244376045,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T05:20:21.758073Z","caller":"traceutil/trace.go:171","msg":"trace[1557781957] linearizableReadLoop","detail":"{readStateIndex:8818; appliedIndex:8817; }","duration":"840.900919ms","start":"2026-04-03T05:20:20.917147Z","end":"2026-04-03T05:20:21.758048Z","steps":["trace[1557781957] 'read index received' (duration: 840.724256ms)","trace[1557781957] 'applied index is now lower than readState.Index' (duration: 175.633µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:20:21.7581Z","caller":"traceutil/trace.go:171","msg":"trace[1014255355] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"899.998688ms","start":"2026-04-03T05:20:20.858073Z","end":"2026-04-03T05:20:21.758072Z","steps":["trace[1014255355] 'process raft request' (duration: 899.810564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:21.758239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:20.858056Z","time spent":"900.10727ms","remote":"127.0.0.1:54450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:20:21.758243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.083143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:20:21.758278Z","caller":"traceutil/trace.go:171","msg":"trace[309193937] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:8519; }","duration":"841.158765ms","start":"2026-04-03T05:20:20.91711Z","end":"2026-04-03T05:20:21.758269Z","steps":["trace[309193937] 'agreement among raft nodes before linearized reading' (duration: 841.079274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:21.758347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:20.917095Z","time spent":"841.242237ms","remote":"127.0.0.1:52260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:20:21.75836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.265269ms","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:5"} {"level":"info","ts":"2026-04-03T05:20:21.758449Z","caller":"traceutil/trace.go:171","msg":"trace[1343550103] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:8519; }","duration":"765.393403ms","start":"2026-04-03T05:20:20.993035Z","end":"2026-04-03T05:20:21.758429Z","steps":["trace[1343550103] 'agreement among raft nodes before linearized reading' (duration: 765.197759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:21.758504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:20.993022Z","time spent":"765.471554ms","remote":"127.0.0.1:53110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-03T05:20:22.245712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.756118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:20:22.245776Z","caller":"traceutil/trace.go:171","msg":"trace[325160796] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8519; }","duration":"324.880411ms","start":"2026-04-03T05:20:21.92088Z","end":"2026-04-03T05:20:22.245761Z","steps":["trace[325160796] 'count revisions from in-memory index tree' (duration: 324.651476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:22.245834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:20:21.920858Z","time spent":"324.967653ms","remote":"127.0.0.1:52334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"info","ts":"2026-04-03T05:20:22.246081Z","caller":"traceutil/trace.go:171","msg":"trace[855320881] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"240.496828ms","start":"2026-04-03T05:20:22.005557Z","end":"2026-04-03T05:20:22.246054Z","steps":["trace[855320881] 'process raft request' (duration: 217.209139ms)","trace[855320881] 'compare' (duration: 23.038024ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:20:22.291095Z","caller":"traceutil/trace.go:171","msg":"trace[1721744997] linearizableReadLoop","detail":"{readStateIndex:8821; appliedIndex:8818; }","duration":"275.648612ms","start":"2026-04-03T05:20:22.015429Z","end":"2026-04-03T05:20:22.291078Z","steps":["trace[1721744997] 'read index received' (duration: 207.358858ms)","trace[1721744997] 'applied index is now lower than readState.Index' (duration: 68.288394ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:20:22.291207Z","caller":"traceutil/trace.go:171","msg":"trace[694522703] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"247.086825ms","start":"2026-04-03T05:20:22.044066Z","end":"2026-04-03T05:20:22.291153Z","steps":["trace[694522703] 'process raft request' (duration: 246.968502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:22.291436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.803535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-03T05:20:22.291455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.00341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T05:20:22.291491Z","caller":"traceutil/trace.go:171","msg":"trace[1244553013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8523; }","duration":"219.936348ms","start":"2026-04-03T05:20:22.071539Z","end":"2026-04-03T05:20:22.291475Z","steps":["trace[1244553013] 'agreement among raft nodes before linearized reading' (duration: 219.684012ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:22.291518Z","caller":"traceutil/trace.go:171","msg":"trace[1169217781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8523; }","duration":"276.111772ms","start":"2026-04-03T05:20:22.015391Z","end":"2026-04-03T05:20:22.291503Z","steps":["trace[1169217781] 'agreement among raft nodes before linearized reading' (duration: 275.825615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:22.291659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.017405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-28fwd\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-03T05:20:22.291744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.709463ms","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-04-03T05:20:22.291798Z","caller":"traceutil/trace.go:171","msg":"trace[526979205] range","detail":"{range_begin:/registry/pods/openstack/keepalived-28fwd; range_end:; response_count:1; response_revision:8523; }","duration":"213.237699ms","start":"2026-04-03T05:20:22.078529Z","end":"2026-04-03T05:20:22.291767Z","steps":["trace[526979205] 'agreement among raft nodes before linearized reading' (duration: 212.742448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:22.291681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.818759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:20:22.291811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.138702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-03T05:20:22.291831Z","caller":"traceutil/trace.go:171","msg":"trace[1500282226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8523; }","duration":"219.839626ms","start":"2026-04-03T05:20:22.071973Z","end":"2026-04-03T05:20:22.291813Z","steps":["trace[1500282226] 'agreement among raft nodes before linearized reading' (duration: 219.500359ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:22.291864Z","caller":"traceutil/trace.go:171","msg":"trace[1725578032] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8523; }","duration":"161.042644ms","start":"2026-04-03T05:20:22.130809Z","end":"2026-04-03T05:20:22.291852Z","steps":["trace[1725578032] 'agreement among raft nodes before linearized reading' (duration: 160.700417ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:22.291887Z","caller":"traceutil/trace.go:171","msg":"trace[259174397] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8523; }","duration":"220.274705ms","start":"2026-04-03T05:20:22.071588Z","end":"2026-04-03T05:20:22.291863Z","steps":["trace[259174397] 'agreement among raft nodes before linearized reading' (duration: 219.973719ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:22.291794Z","caller":"traceutil/trace.go:171","msg":"trace[331773756] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"282.463605ms","start":"2026-04-03T05:20:22.009281Z","end":"2026-04-03T05:20:22.291745Z","steps":["trace[331773756] 'process raft request' (duration: 281.706128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:22.291881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.497777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-03T05:20:22.292065Z","caller":"traceutil/trace.go:171","msg":"trace[660046005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8523; }","duration":"218.823124ms","start":"2026-04-03T05:20:22.073217Z","end":"2026-04-03T05:20:22.29204Z","steps":["trace[660046005] 'agreement among raft nodes before linearized reading' (duration: 218.244952ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:22.291743Z","caller":"traceutil/trace.go:171","msg":"trace[1102855103] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"283.480847ms","start":"2026-04-03T05:20:22.008239Z","end":"2026-04-03T05:20:22.29172Z","steps":["trace[1102855103] 'process raft request' (duration: 282.617748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:20:22.292285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.899085ms","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-04-03T05:20:22.292551Z","caller":"traceutil/trace.go:171","msg":"trace[1996958009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8523; }","duration":"219.120421ms","start":"2026-04-03T05:20:22.073345Z","end":"2026-04-03T05:20:22.292465Z","steps":["trace[1996958009] 'agreement among raft nodes before linearized reading' (duration: 218.738892ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:20:24.236716Z","caller":"traceutil/trace.go:171","msg":"trace[1845578343] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"179.20283ms","start":"2026-04-03T05:20:24.057484Z","end":"2026-04-03T05:20:24.236687Z","steps":["trace[1845578343] 'process raft request' (duration: 146.221994ms)","trace[1845578343] 'compare' (duration: 32.848703ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:21:14.535865Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002496.wal"} {"level":"info","ts":"2026-04-03T05:21:34.941517Z","caller":"traceutil/trace.go:171","msg":"trace[183994448] transaction","detail":"{read_only:false; response_revision:9182; number_of_response:1; }","duration":"349.817419ms","start":"2026-04-03T05:21:34.591649Z","end":"2026-04-03T05:21:34.941467Z","steps":["trace[183994448] 'process raft request' (duration: 330.389395ms)","trace[183994448] 'compare' (duration: 18.969564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:21:34.941756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:21:34.591604Z","time spent":"350.027484ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:21:34.958665Z","caller":"traceutil/trace.go:171","msg":"trace[1872945401] linearizableReadLoop","detail":"{readStateIndex:9503; appliedIndex:9501; }","duration":"166.664821ms","start":"2026-04-03T05:21:34.791985Z","end":"2026-04-03T05:21:34.95865Z","steps":["trace[1872945401] 'read index received' (duration: 130.146076ms)","trace[1872945401] 'applied index is now lower than readState.Index' (duration: 36.517445ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:21:34.958753Z","caller":"traceutil/trace.go:171","msg":"trace[247857834] transaction","detail":"{read_only:false; response_revision:9183; number_of_response:1; }","duration":"363.721319ms","start":"2026-04-03T05:21:34.595022Z","end":"2026-04-03T05:21:34.958743Z","steps":["trace[247857834] 'process raft request' (duration: 363.527495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:21:34.958826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:21:34.595006Z","time spent":"363.771221ms","remote":"127.0.0.1:52216","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-04-03T05:21:34.959016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.208433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-03T05:21:34.959119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.417107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-03T05:21:34.9591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.091181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-03T05:21:34.959156Z","caller":"traceutil/trace.go:171","msg":"trace[1922796541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9183; }","duration":"137.448128ms","start":"2026-04-03T05:21:34.821695Z","end":"2026-04-03T05:21:34.959143Z","steps":["trace[1922796541] 'agreement among raft nodes before linearized reading' (duration: 137.375967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:21:34.959153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.278472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T05:21:34.959194Z","caller":"traceutil/trace.go:171","msg":"trace[2018284986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9183; }","duration":"149.339233ms","start":"2026-04-03T05:21:34.809844Z","end":"2026-04-03T05:21:34.959184Z","steps":["trace[2018284986] 'agreement among raft nodes before linearized reading' (duration: 149.229291ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:21:34.959195Z","caller":"traceutil/trace.go:171","msg":"trace[343956761] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9183; }","duration":"167.216954ms","start":"2026-04-03T05:21:34.791953Z","end":"2026-04-03T05:21:34.95917Z","steps":["trace[343956761] 'agreement among raft nodes before linearized reading' (duration: 167.021789ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:21:34.95925Z","caller":"traceutil/trace.go:171","msg":"trace[1583828748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9183; }","duration":"133.332365ms","start":"2026-04-03T05:21:34.825777Z","end":"2026-04-03T05:21:34.95911Z","steps":["trace[1583828748] 'agreement among raft nodes before linearized reading' (duration: 133.132901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:21:34.959051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.54152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-03T05:21:34.959144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.611185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-28fwd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-03T05:21:34.959353Z","caller":"traceutil/trace.go:171","msg":"trace[107218700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9183; }","duration":"137.857127ms","start":"2026-04-03T05:21:34.821475Z","end":"2026-04-03T05:21:34.959333Z","steps":["trace[107218700] 'agreement among raft nodes before linearized reading' (duration: 137.491289ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:21:34.959383Z","caller":"traceutil/trace.go:171","msg":"trace[2074706918] range","detail":"{range_begin:/registry/pods/openstack/keepalived-28fwd; range_end:; response_count:1; response_revision:9183; }","duration":"125.88795ms","start":"2026-04-03T05:21:34.833481Z","end":"2026-04-03T05:21:34.959369Z","steps":["trace[2074706918] 'agreement among raft nodes before linearized reading' (duration: 125.512142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:21:34.959624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.964058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-03T05:21:34.959679Z","caller":"traceutil/trace.go:171","msg":"trace[369916856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9183; }","duration":"159.079931ms","start":"2026-04-03T05:21:34.800586Z","end":"2026-04-03T05:21:34.959666Z","steps":["trace[369916856] 'agreement among raft nodes before linearized reading' (duration: 158.294633ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:21:35.400392Z","caller":"traceutil/trace.go:171","msg":"trace[2062612389] transaction","detail":"{read_only:false; response_revision:9184; number_of_response:1; }","duration":"437.17377ms","start":"2026-04-03T05:21:34.963192Z","end":"2026-04-03T05:21:35.400366Z","steps":["trace[2062612389] 'process raft request' (duration: 392.578484ms)","trace[2062612389] 'compare' (duration: 44.458963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:21:35.400553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:21:34.96317Z","time spent":"437.292943ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:21:35.491262Z","caller":"traceutil/trace.go:171","msg":"trace[334564497] transaction","detail":"{read_only:false; response_revision:9186; number_of_response:1; }","duration":"527.564468ms","start":"2026-04-03T05:21:34.963667Z","end":"2026-04-03T05:21:35.491231Z","steps":["trace[334564497] 'process raft request' (duration: 527.477846ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:21:35.491271Z","caller":"traceutil/trace.go:171","msg":"trace[162525429] transaction","detail":"{read_only:false; response_revision:9185; number_of_response:1; }","duration":"527.991867ms","start":"2026-04-03T05:21:34.96325Z","end":"2026-04-03T05:21:35.491242Z","steps":["trace[162525429] 'process raft request' (duration: 527.757822ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:21:35.491513Z","caller":"traceutil/trace.go:171","msg":"trace[1104578288] transaction","detail":"{read_only:false; response_revision:9188; number_of_response:1; }","duration":"527.476786ms","start":"2026-04-03T05:21:34.964026Z","end":"2026-04-03T05:21:35.491503Z","steps":["trace[1104578288] 'process raft request' (duration: 527.357823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:21:35.491437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:21:34.963648Z","time spent":"527.70486ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:21:35.49158Z","caller":"traceutil/trace.go:171","msg":"trace[903871149] transaction","detail":"{read_only:false; response_revision:9187; number_of_response:1; }","duration":"527.67676ms","start":"2026-04-03T05:21:34.963874Z","end":"2026-04-03T05:21:35.491551Z","steps":["trace[903871149] 'process raft request' (duration: 527.311462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:21:35.491647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:21:34.964019Z","time spent":"527.539247ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:21:35.491588Z","caller":"traceutil/trace.go:171","msg":"trace[761025149] linearizableReadLoop","detail":"{readStateIndex:9506; appliedIndex:9503; }","duration":"527.68063ms","start":"2026-04-03T05:21:34.963879Z","end":"2026-04-03T05:21:35.49156Z","steps":["trace[761025149] 'read index received' (duration: 391.907609ms)","trace[761025149] 'applied index is now lower than readState.Index' (duration: 135.771861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:21:35.491695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:21:34.963231Z","time spent":"528.298333ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:21:35.491773Z","caller":"traceutil/trace.go:171","msg":"trace[386749041] transaction","detail":"{read_only:false; response_revision:9189; number_of_response:1; }","duration":"526.660127ms","start":"2026-04-03T05:21:34.965101Z","end":"2026-04-03T05:21:35.491761Z","steps":["trace[386749041] 'process raft request' (duration: 526.361081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:21:35.49177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:21:34.963859Z","time spent":"527.824093ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:21:35.491839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:21:34.965094Z","time spent":"526.710868ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:21:35.492266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.360825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:29 size:261411"} {"level":"info","ts":"2026-04-03T05:21:35.49234Z","caller":"traceutil/trace.go:171","msg":"trace[813733162] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:29; response_revision:9189; }","duration":"528.462257ms","start":"2026-04-03T05:21:34.963862Z","end":"2026-04-03T05:21:35.492324Z","steps":["trace[813733162] 'agreement among raft nodes before linearized reading' (duration: 527.840603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:21:35.492397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:21:34.963853Z","time spent":"528.528439ms","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":29,"response size":261434,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-03T05:22:19.742315Z","caller":"traceutil/trace.go:171","msg":"trace[1269180344] transaction","detail":"{read_only:false; response_revision:9577; number_of_response:1; }","duration":"111.98964ms","start":"2026-04-03T05:22:19.630304Z","end":"2026-04-03T05:22:19.742293Z","steps":["trace[1269180344] 'process raft request' (duration: 111.660983ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:22:19.911273Z","caller":"traceutil/trace.go:171","msg":"trace[1436211431] transaction","detail":"{read_only:false; response_revision:9578; number_of_response:1; }","duration":"164.108044ms","start":"2026-04-03T05:22:19.747131Z","end":"2026-04-03T05:22:19.911239Z","steps":["trace[1436211431] 'process raft request' (duration: 140.969397ms)","trace[1436211431] 'compare' (duration: 22.697596ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:22:19.911285Z","caller":"traceutil/trace.go:171","msg":"trace[1321409186] linearizableReadLoop","detail":"{readStateIndex:9908; appliedIndex:9907; }","duration":"161.026686ms","start":"2026-04-03T05:22:19.750231Z","end":"2026-04-03T05:22:19.911258Z","steps":["trace[1321409186] 'read index received' (duration: 138.029971ms)","trace[1321409186] 'applied index is now lower than readState.Index' (duration: 22.995315ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:22:19.911792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.068126ms","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":"warn","ts":"2026-04-03T05:22:19.911867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.607648ms","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-04-03T05:22:19.911898Z","caller":"traceutil/trace.go:171","msg":"trace[1767973551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9579; }","duration":"161.204079ms","start":"2026-04-03T05:22:19.750671Z","end":"2026-04-03T05:22:19.911875Z","steps":["trace[1767973551] 'agreement among raft nodes before linearized reading' (duration: 160.832731ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:22:19.911915Z","caller":"traceutil/trace.go:171","msg":"trace[1146924064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9579; }","duration":"161.68589ms","start":"2026-04-03T05:22:19.750216Z","end":"2026-04-03T05:22:19.911902Z","steps":["trace[1146924064] 'agreement among raft nodes before linearized reading' (duration: 161.203759ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:22:19.912021Z","caller":"traceutil/trace.go:171","msg":"trace[1993749996] transaction","detail":"{read_only:false; response_revision:9579; number_of_response:1; }","duration":"159.90283ms","start":"2026-04-03T05:22:19.75196Z","end":"2026-04-03T05:22:19.911862Z","steps":["trace[1993749996] 'process raft request' (duration: 159.247245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:22:19.912077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.636437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-28fwd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-03T05:22:19.91213Z","caller":"traceutil/trace.go:171","msg":"trace[494204106] range","detail":"{range_begin:/registry/pods/openstack/keepalived-28fwd; range_end:; response_count:1; response_revision:9579; }","duration":"113.702108ms","start":"2026-04-03T05:22:19.798415Z","end":"2026-04-03T05:22:19.912117Z","steps":["trace[494204106] 'agreement among raft nodes before linearized reading' (duration: 113.565305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:22:19.912241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.015552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-03T05:22:19.912289Z","caller":"traceutil/trace.go:171","msg":"trace[1947204511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9579; }","duration":"160.063273ms","start":"2026-04-03T05:22:19.752216Z","end":"2026-04-03T05:22:19.912279Z","steps":["trace[1947204511] 'agreement among raft nodes before linearized reading' (duration: 159.953631ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:22:27.968635Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"791a7e25b533cd26","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-03T05:22:27.975389Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-03T05:22:27.975509Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-03T05:24:59.284471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8311} {"level":"info","ts":"2026-04-03T05:24:59.368299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8311,"took":"80.604299ms","hash":81968580,"current-db-size-bytes":56987648,"current-db-size":"57 MB","current-db-size-in-use-bytes":25018368,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-03T05:24:59.368401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":81968580,"revision":8311,"compact-revision":6142} {"level":"info","ts":"2026-04-03T05:29:59.291398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10734} {"level":"info","ts":"2026-04-03T05:29:59.377645Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10734,"took":"83.414549ms","hash":3188694288,"current-db-size-bytes":56987648,"current-db-size":"57 MB","current-db-size-in-use-bytes":20299776,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-03T05:29:59.377714Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3188694288,"revision":10734,"compact-revision":8311}