{"level":"warn","ts":"2026-06-02T23:47:05.902453Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T23:47:05.902532Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-06-02T23:47:05.902587Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T23:47:05.902598Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-02T23:47:05.902613Z","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-06-02T23:47:05.90322Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-06-02T23:47:05.903317Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-06-02T23:47:05.907132Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.610423ms"} {"level":"info","ts":"2026-06-02T23:47:05.915187Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-06-02T23:47:05.915253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T23:47:05.915275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-06-02T23:47:05.915314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T23:47:05.915326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-06-02T23:47:05.915361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-06-02T23:47:05.921886Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T23:47:05.925158Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T23:47:05.926502Z","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-06-02T23:47:05.929972Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T23:47:05.930143Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T23:47:05.930425Z","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-06-02T23:47:05.930515Z","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-06-02T23:47:05.930528Z","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-06-02T23:47:05.932483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-06-02T23:47:05.932932Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-02T23:47:05.934231Z","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-06-02T23:47:05.934312Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-02T23:47:05.934346Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-02T23:47:05.934474Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T23:47:05.934518Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T23:47:06.715922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T23:47:06.715979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T23:47:06.716013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-06-02T23:47:06.716034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-06-02T23:47:06.716045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-02T23:47:06.716064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-06-02T23:47:06.716087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-02T23:47:06.718329Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T23:47:06.718548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T23:47:06.718554Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T23:47:06.718638Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T23:47:06.718703Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T23:47:06.71891Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:47:06.719527Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:47:06.719631Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:47:06.719661Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:47:06.724413Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-06-02T23:47:06.724879Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-02T23:47:08.781508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.581978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-02T23:47:08.781621Z","caller":"traceutil/trace.go:171","msg":"trace[1789604756] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:91; }","duration":"520.428367ms","start":"2026-06-02T23:47:08.261178Z","end":"2026-06-02T23:47:08.781607Z","steps":["trace[1789604756] 'read index received' (duration: 201.215033ms)","trace[1789604756] 'applied index is now lower than readState.Index' (duration: 319.212314ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:08.781766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.588338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-02T23:47:08.781799Z","caller":"traceutil/trace.go:171","msg":"trace[1218738546] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:87; }","duration":"520.655938ms","start":"2026-06-02T23:47:08.261134Z","end":"2026-06-02T23:47:08.78179Z","steps":["trace[1218738546] 'agreement among raft nodes before linearized reading' (duration: 520.561377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:08.781832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:08.261122Z","time spent":"520.703018ms","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-06-02T23:47:08.781783Z","caller":"traceutil/trace.go:171","msg":"trace[543448463] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"527.041043ms","start":"2026-06-02T23:47:08.254659Z","end":"2026-06-02T23:47:08.7817Z","steps":["trace[543448463] 'process raft request' (duration: 207.655808ms)","trace[543448463] 'compare' (duration: 318.443187ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:08.781933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.262791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-02T23:47:08.782026Z","caller":"traceutil/trace.go:171","msg":"trace[1023833125] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:87; }","duration":"515.398822ms","start":"2026-06-02T23:47:08.266606Z","end":"2026-06-02T23:47:08.782005Z","steps":["trace[1023833125] 'agreement among raft nodes before linearized reading' (duration: 515.21514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:08.782079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:08.266596Z","time spent":"515.473192ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-06-02T23:47:08.782236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:08.254643Z","time spent":"527.330426ms","remote":"127.0.0.1:35378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:47:09.09681Z","caller":"traceutil/trace.go:171","msg":"trace[1936108567] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"309.683415ms","start":"2026-06-02T23:47:08.787098Z","end":"2026-06-02T23:47:09.096782Z","steps":["trace[1936108567] 'process raft request' (duration: 267.076659ms)","trace[1936108567] 'compare' (duration: 42.476345ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:09.096987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:08.787083Z","time spent":"309.820316ms","remote":"127.0.0.1:35378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:47:09.100595Z","caller":"traceutil/trace.go:171","msg":"trace[28602114] linearizableReadLoop","detail":"{readStateIndex:94; appliedIndex:92; }","duration":"206.357501ms","start":"2026-06-02T23:47:08.89422Z","end":"2026-06-02T23:47:09.100577Z","steps":["trace[28602114] 'read index received' (duration: 160.145633ms)","trace[28602114] 'applied index is now lower than readState.Index' (duration: 46.210988ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:47:09.100679Z","caller":"traceutil/trace.go:171","msg":"trace[644707655] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"312.355048ms","start":"2026-06-02T23:47:08.788315Z","end":"2026-06-02T23:47:09.10067Z","steps":["trace[644707655] 'process raft request' (duration: 312.176597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:09.100758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:08.788303Z","time spent":"312.40135ms","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:47:09.100775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.753196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-02T23:47:09.100807Z","caller":"traceutil/trace.go:171","msg":"trace[2049781945] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:89; }","duration":"205.840956ms","start":"2026-06-02T23:47:08.894957Z","end":"2026-06-02T23:47:09.100798Z","steps":["trace[2049781945] 'agreement among raft nodes before linearized reading' (duration: 205.763886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:09.100914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.701054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-02T23:47:09.100944Z","caller":"traceutil/trace.go:171","msg":"trace[1520359579] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:89; }","duration":"206.752935ms","start":"2026-06-02T23:47:08.894178Z","end":"2026-06-02T23:47:09.100931Z","steps":["trace[1520359579] 'agreement among raft nodes before linearized reading' (duration: 206.720864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:09.101031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.041891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-02T23:47:09.101066Z","caller":"traceutil/trace.go:171","msg":"trace[1543062824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:89; }","duration":"100.121382ms","start":"2026-06-02T23:47:09.000935Z","end":"2026-06-02T23:47:09.101056Z","steps":["trace[1543062824] 'agreement among raft nodes before linearized reading' (duration: 100.064741ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.441562Z","caller":"traceutil/trace.go:171","msg":"trace[228485653] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"133.918253ms","start":"2026-06-02T23:47:43.307624Z","end":"2026-06-02T23:47:43.441542Z","steps":["trace[228485653] 'process raft request' (duration: 133.879212ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.441669Z","caller":"traceutil/trace.go:171","msg":"trace[1339498010] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"134.063779ms","start":"2026-06-02T23:47:43.307566Z","end":"2026-06-02T23:47:43.441629Z","steps":["trace[1339498010] 'process raft request' (duration: 129.692117ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.442648Z","caller":"traceutil/trace.go:171","msg":"trace[1958386011] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"127.425992ms","start":"2026-06-02T23:47:43.3152Z","end":"2026-06-02T23:47:43.442626Z","steps":["trace[1958386011] 'process raft request' (duration: 127.216384ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.442789Z","caller":"traceutil/trace.go:171","msg":"trace[1505898037] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"127.326728ms","start":"2026-06-02T23:47:43.315451Z","end":"2026-06-02T23:47:43.442778Z","steps":["trace[1505898037] 'process raft request' (duration: 127.109479ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.442941Z","caller":"traceutil/trace.go:171","msg":"trace[675747093] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"127.564638ms","start":"2026-06-02T23:47:43.315371Z","end":"2026-06-02T23:47:43.442936Z","steps":["trace[675747093] 'process raft request' (duration: 127.1319ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.442972Z","caller":"traceutil/trace.go:171","msg":"trace[518327195] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"121.177652ms","start":"2026-06-02T23:47:43.321777Z","end":"2026-06-02T23:47:43.442955Z","steps":["trace[518327195] 'process raft request' (duration: 120.925521ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.443035Z","caller":"traceutil/trace.go:171","msg":"trace[2108209895] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"117.352612ms","start":"2026-06-02T23:47:43.325679Z","end":"2026-06-02T23:47:43.443032Z","steps":["trace[2108209895] 'process raft request' (duration: 117.045989ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.443055Z","caller":"traceutil/trace.go:171","msg":"trace[1737021474] transaction","detail":"{read_only:false; number_of_response:1; response_revision:532; }","duration":"127.562909ms","start":"2026-06-02T23:47:43.315483Z","end":"2026-06-02T23:47:43.443046Z","steps":["trace[1737021474] 'process raft request' (duration: 127.138211ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.443131Z","caller":"traceutil/trace.go:171","msg":"trace[2080417773] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"126.90992ms","start":"2026-06-02T23:47:43.316213Z","end":"2026-06-02T23:47:43.443123Z","steps":["trace[2080417773] 'process raft request' (duration: 126.44315ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:43.442987Z","caller":"traceutil/trace.go:171","msg":"trace[2042584409] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"127.59803ms","start":"2026-06-02T23:47:43.315384Z","end":"2026-06-02T23:47:43.442982Z","steps":["trace[2042584409] 'process raft request' (duration: 127.143881ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:47.828958Z","caller":"traceutil/trace.go:171","msg":"trace[1669368845] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"187.593529ms","start":"2026-06-02T23:47:47.641323Z","end":"2026-06-02T23:47:47.828916Z","steps":["trace[1669368845] 'read index received' (duration: 186.791507ms)","trace[1669368845] 'applied index is now lower than readState.Index' (duration: 801.102µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:47:47.829046Z","caller":"traceutil/trace.go:171","msg":"trace[1893763293] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"203.687229ms","start":"2026-06-02T23:47:47.625328Z","end":"2026-06-02T23:47:47.829015Z","steps":["trace[1893763293] 'process raft request' (duration: 202.997241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:47.829105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.763555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:47:47.829147Z","caller":"traceutil/trace.go:171","msg":"trace[540217909] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:617; }","duration":"187.852338ms","start":"2026-06-02T23:47:47.641282Z","end":"2026-06-02T23:47:47.829134Z","steps":["trace[540217909] 'agreement among raft nodes before linearized reading' (duration: 187.778685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:47.82919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.828577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:47:47.829294Z","caller":"traceutil/trace.go:171","msg":"trace[957633718] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:617; }","duration":"187.954684ms","start":"2026-06-02T23:47:47.64132Z","end":"2026-06-02T23:47:47.829275Z","steps":["trace[957633718] 'agreement among raft nodes before linearized reading' (duration: 187.813897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:47.829692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.937872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:47:47.829734Z","caller":"traceutil/trace.go:171","msg":"trace[812711093] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:617; }","duration":"188.008855ms","start":"2026-06-02T23:47:47.641714Z","end":"2026-06-02T23:47:47.829723Z","steps":["trace[812711093] 'agreement among raft nodes before linearized reading' (duration: 187.916051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:47.829883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.299178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:47:47.82996Z","caller":"traceutil/trace.go:171","msg":"trace[989032458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"128.397862ms","start":"2026-06-02T23:47:47.701538Z","end":"2026-06-02T23:47:47.829936Z","steps":["trace[989032458] 'agreement among raft nodes before linearized reading' (duration: 128.301768ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:48.034105Z","caller":"traceutil/trace.go:171","msg":"trace[1178232782] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"147.591812ms","start":"2026-06-02T23:47:47.88649Z","end":"2026-06-02T23:47:48.034081Z","steps":["trace[1178232782] 'process raft request' (duration: 147.457587ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:49.392752Z","caller":"traceutil/trace.go:171","msg":"trace[1276299858] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"102.181197ms","start":"2026-06-02T23:47:49.290544Z","end":"2026-06-02T23:47:49.392726Z","steps":["trace[1276299858] 'process raft request' (duration: 102.01679ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:49.566535Z","caller":"traceutil/trace.go:171","msg":"trace[1355500459] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"170.025717ms","start":"2026-06-02T23:47:49.396486Z","end":"2026-06-02T23:47:49.566512Z","steps":["trace[1355500459] 'process raft request' (duration: 169.109851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:50.203353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266258124357740,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T23:47:50.223567Z","caller":"traceutil/trace.go:171","msg":"trace[747055679] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"520.373625ms","start":"2026-06-02T23:47:49.70317Z","end":"2026-06-02T23:47:50.223544Z","steps":["trace[747055679] 'read index received' (duration: 520.197099ms)","trace[747055679] 'applied index is now lower than readState.Index' (duration: 173.276µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:50.223746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.316832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:47:50.22441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.221628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-02T23:47:50.224489Z","caller":"traceutil/trace.go:171","msg":"trace[1591037730] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:633; }","duration":"521.322392ms","start":"2026-06-02T23:47:49.703137Z","end":"2026-06-02T23:47:50.224459Z","steps":["trace[1591037730] 'agreement among raft nodes before linearized reading' (duration: 520.537401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:50.224535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:49.703116Z","time spent":"521.408616ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"info","ts":"2026-06-02T23:47:50.224691Z","caller":"traceutil/trace.go:171","msg":"trace[61903488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"520.403275ms","start":"2026-06-02T23:47:49.703372Z","end":"2026-06-02T23:47:50.223775Z","steps":["trace[61903488] 'agreement among raft nodes before linearized reading' (duration: 520.314252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:50.224781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:49.703352Z","time spent":"521.399275ms","remote":"127.0.0.1:35304","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-06-02T23:47:50.223609Z","caller":"traceutil/trace.go:171","msg":"trace[1787101711] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"591.505664ms","start":"2026-06-02T23:47:49.632069Z","end":"2026-06-02T23:47:50.223574Z","steps":["trace[1787101711] 'process raft request' (duration: 591.343377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:50.225257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:49.63205Z","time spent":"593.049413ms","remote":"127.0.0.1:35896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:48:05.510572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.584358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rj6zf\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-06-02T23:48:05.510668Z","caller":"traceutil/trace.go:171","msg":"trace[1046378700] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rj6zf; range_end:; response_count:1; response_revision:796; }","duration":"140.738433ms","start":"2026-06-02T23:48:05.369908Z","end":"2026-06-02T23:48:05.510646Z","steps":["trace[1046378700] 'range keys from in-memory index tree' (duration: 140.311089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:40.966244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.868567ms","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-06-02T23:48:40.966311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.599065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:48:40.966338Z","caller":"traceutil/trace.go:171","msg":"trace[2031761417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"160.010481ms","start":"2026-06-02T23:48:40.806309Z","end":"2026-06-02T23:48:40.96632Z","steps":["trace[2031761417] 'range keys from in-memory index tree' (duration: 159.686321ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:48:40.966391Z","caller":"traceutil/trace.go:171","msg":"trace[1196759828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"263.723637ms","start":"2026-06-02T23:48:40.702642Z","end":"2026-06-02T23:48:40.966366Z","steps":["trace[1196759828] 'range keys from in-memory index tree' (duration: 263.517611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:40.966244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.314288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-02T23:48:40.966496Z","caller":"traceutil/trace.go:171","msg":"trace[1704621048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1218; }","duration":"269.607435ms","start":"2026-06-02T23:48:40.696864Z","end":"2026-06-02T23:48:40.966472Z","steps":["trace[1704621048] 'range keys from in-memory index tree' (duration: 269.128844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:40.966495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.025041ms","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-06-02T23:48:40.966548Z","caller":"traceutil/trace.go:171","msg":"trace[1706412440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"163.096522ms","start":"2026-06-02T23:48:40.803436Z","end":"2026-06-02T23:48:40.966532Z","steps":["trace[1706412440] 'range keys from in-memory index tree' (duration: 162.865477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:04.862322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.019349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:49:04.862416Z","caller":"traceutil/trace.go:171","msg":"trace[686474063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1478; }","duration":"160.161682ms","start":"2026-06-02T23:49:04.702233Z","end":"2026-06-02T23:49:04.862395Z","steps":["trace[686474063] 'range keys from in-memory index tree' (duration: 159.939938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:04.862514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.083051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:49:04.862613Z","caller":"traceutil/trace.go:171","msg":"trace[1676288468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1478; }","duration":"183.226254ms","start":"2026-06-02T23:49:04.679365Z","end":"2026-06-02T23:49:04.862591Z","steps":["trace[1676288468] 'range keys from in-memory index tree' (duration: 182.826226ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:28.926169Z","caller":"traceutil/trace.go:171","msg":"trace[433960881] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"219.513501ms","start":"2026-06-02T23:49:28.706632Z","end":"2026-06-02T23:49:28.926146Z","steps":["trace[433960881] 'read index received' (duration: 219.313538ms)","trace[433960881] 'applied index is now lower than readState.Index' (duration: 198.933µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:49:28.926291Z","caller":"traceutil/trace.go:171","msg":"trace[1108010105] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"231.541734ms","start":"2026-06-02T23:49:28.694697Z","end":"2026-06-02T23:49:28.926238Z","steps":["trace[1108010105] 'process raft request' (duration: 231.29602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:28.926412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.868002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10666"} {"level":"info","ts":"2026-06-02T23:49:28.926453Z","caller":"traceutil/trace.go:171","msg":"trace[1897381625] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1891; }","duration":"195.955513ms","start":"2026-06-02T23:49:28.730487Z","end":"2026-06-02T23:49:28.926443Z","steps":["trace[1897381625] 'agreement among raft nodes before linearized reading' (duration: 195.807371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:28.926508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.414316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-02T23:49:28.926526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.804056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-457de484-dab5-4e5a-8dc3-bed654558278\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-06-02T23:49:28.926593Z","caller":"traceutil/trace.go:171","msg":"trace[775139319] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1891; }","duration":"150.537878ms","start":"2026-06-02T23:49:28.776035Z","end":"2026-06-02T23:49:28.926573Z","steps":["trace[775139319] 'agreement among raft nodes before linearized reading' (duration: 150.327755ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:28.926615Z","caller":"traceutil/trace.go:171","msg":"trace[1581319037] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-457de484-dab5-4e5a-8dc3-bed654558278; range_end:; response_count:1; response_revision:1891; }","duration":"220.02073ms","start":"2026-06-02T23:49:28.706579Z","end":"2026-06-02T23:49:28.926599Z","steps":["trace[1581319037] 'agreement among raft nodes before linearized reading' (duration: 219.705984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:29.424954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.933998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:49:29.425306Z","caller":"traceutil/trace.go:171","msg":"trace[796523464] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"273.421874ms","start":"2026-06-02T23:49:29.151866Z","end":"2026-06-02T23:49:29.425288Z","steps":["trace[796523464] 'process raft request' (duration: 273.359533ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:29.425482Z","caller":"traceutil/trace.go:171","msg":"trace[65262568] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"393.723652ms","start":"2026-06-02T23:49:29.031723Z","end":"2026-06-02T23:49:29.425446Z","steps":["trace[65262568] 'process raft request' (duration: 393.385436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:29.426191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.760794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-457de484-dab5-4e5a-8dc3-bed654558278\" ","response":"range_response_count:1 size:3625"} {"level":"warn","ts":"2026-06-02T23:49:29.426205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:29.031718Z","time spent":"394.326541ms","remote":"127.0.0.1:35614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:49:29.426262Z","caller":"traceutil/trace.go:171","msg":"trace[1452045663] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-457de484-dab5-4e5a-8dc3-bed654558278; range_end:; response_count:1; response_revision:1899; }","duration":"395.884097ms","start":"2026-06-02T23:49:29.030361Z","end":"2026-06-02T23:49:29.426245Z","steps":["trace[1452045663] 'agreement among raft nodes before linearized reading' (duration: 395.675903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:29.426317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:29.030334Z","time spent":"395.966448ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-457de484-dab5-4e5a-8dc3-bed654558278\" "} {"level":"info","ts":"2026-06-02T23:49:29.425558Z","caller":"traceutil/trace.go:171","msg":"trace[9656496] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"394.439903ms","start":"2026-06-02T23:49:29.031105Z","end":"2026-06-02T23:49:29.425544Z","steps":["trace[9656496] 'process raft request' (duration: 393.936305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:29.426485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:29.031097Z","time spent":"395.330898ms","remote":"127.0.0.1:35474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:49:29.425573Z","caller":"traceutil/trace.go:171","msg":"trace[369322620] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"392.948857ms","start":"2026-06-02T23:49:29.032587Z","end":"2026-06-02T23:49:29.425536Z","steps":["trace[369322620] 'process raft request' (duration: 392.595621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:29.42681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:29.032582Z","time spent":"394.117737ms","remote":"127.0.0.1:35474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:49:29.425649Z","caller":"traceutil/trace.go:171","msg":"trace[1240036092] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"393.68193ms","start":"2026-06-02T23:49:29.031951Z","end":"2026-06-02T23:49:29.425633Z","steps":["trace[1240036092] 'process raft request' (duration: 393.198202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:29.427506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:29.031947Z","time spent":"395.501121ms","remote":"127.0.0.1:35614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:49:29.425696Z","caller":"traceutil/trace.go:171","msg":"trace[693443579] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"398.194686ms","start":"2026-06-02T23:49:29.027491Z","end":"2026-06-02T23:49:29.425686Z","steps":["trace[693443579] 'process raft request' (duration: 97.440754ms)","trace[693443579] 'compare' (duration: 299.559122ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:49:29.427636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:29.027483Z","time spent":"400.114268ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:49:29.428809Z","caller":"traceutil/trace.go:171","msg":"trace[238655669] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"395.109154ms","start":"2026-06-02T23:49:29.030404Z","end":"2026-06-02T23:49:29.425513Z","steps":["trace[238655669] 'read index received' (duration: 3.564519ms)","trace[238655669] 'applied index is now lower than readState.Index' (duration: 391.541335ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:49:29.429213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.11729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:49:29.429595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.493802ms","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-06-02T23:49:29.429646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.743665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-02T23:49:29.429651Z","caller":"traceutil/trace.go:171","msg":"trace[469897359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1899; }","duration":"138.584033ms","start":"2026-06-02T23:49:29.291054Z","end":"2026-06-02T23:49:29.429638Z","steps":["trace[469897359] 'agreement among raft nodes before linearized reading' (duration: 138.42239ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:29.429826Z","caller":"traceutil/trace.go:171","msg":"trace[1605465173] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1899; }","duration":"223.198951ms","start":"2026-06-02T23:49:29.206054Z","end":"2026-06-02T23:49:29.429253Z","steps":["trace[1605465173] 'agreement among raft nodes before linearized reading' (duration: 223.12177ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:29.430042Z","caller":"traceutil/trace.go:171","msg":"trace[684069157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1899; }","duration":"141.860339ms","start":"2026-06-02T23:49:29.287851Z","end":"2026-06-02T23:49:29.429712Z","steps":["trace[684069157] 'agreement among raft nodes before linearized reading' (duration: 141.648274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:29.430247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.690502ms","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-06-02T23:49:29.430293Z","caller":"traceutil/trace.go:171","msg":"trace[220825820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1899; }","duration":"136.759613ms","start":"2026-06-02T23:49:29.293522Z","end":"2026-06-02T23:49:29.430281Z","steps":["trace[220825820] 'agreement among raft nodes before linearized reading' (duration: 136.55026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:36.160804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.476806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:49:36.1609Z","caller":"traceutil/trace.go:171","msg":"trace[1781870787] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"111.933649ms","start":"2026-06-02T23:49:36.048953Z","end":"2026-06-02T23:49:36.160887Z","steps":["trace[1781870787] 'compare' (duration: 102.377524ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:40.138947Z","caller":"traceutil/trace.go:171","msg":"trace[1954224598] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2070; }","duration":"141.3582ms","start":"2026-06-02T23:49:39.997565Z","end":"2026-06-02T23:49:40.138923Z","steps":["trace[1954224598] 'read index received' (duration: 141.153487ms)","trace[1954224598] 'applied index is now lower than readState.Index' (duration: 203.623µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:49:40.139128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.548453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-02T23:49:40.139165Z","caller":"traceutil/trace.go:171","msg":"trace[1438913994] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1994; }","duration":"141.620763ms","start":"2026-06-02T23:49:39.997532Z","end":"2026-06-02T23:49:40.139153Z","steps":["trace[1438913994] 'agreement among raft nodes before linearized reading' (duration: 141.482281ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:40.139234Z","caller":"traceutil/trace.go:171","msg":"trace[1226449235] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"147.72062ms","start":"2026-06-02T23:49:39.991485Z","end":"2026-06-02T23:49:40.139206Z","steps":["trace[1226449235] 'process raft request' (duration: 147.284384ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:40.533963Z","caller":"traceutil/trace.go:171","msg":"trace[1525894840] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"138.057117ms","start":"2026-06-02T23:49:40.395877Z","end":"2026-06-02T23:49:40.533934Z","steps":["trace[1525894840] 'process raft request' (duration: 137.822623ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:57.617384Z","caller":"traceutil/trace.go:171","msg":"trace[627725250] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"105.993388ms","start":"2026-06-02T23:49:57.511358Z","end":"2026-06-02T23:49:57.617351Z","steps":["trace[627725250] 'process raft request' (duration: 105.674544ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:57.896056Z","caller":"traceutil/trace.go:171","msg":"trace[1334294522] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"273.243067ms","start":"2026-06-02T23:49:57.622787Z","end":"2026-06-02T23:49:57.89603Z","steps":["trace[1334294522] 'process raft request' (duration: 265.121338ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:57.896621Z","caller":"traceutil/trace.go:171","msg":"trace[466735725] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"273.658973ms","start":"2026-06-02T23:49:57.622861Z","end":"2026-06-02T23:49:57.89652Z","steps":["trace[466735725] 'process raft request' (duration: 273.121306ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:57.897153Z","caller":"traceutil/trace.go:171","msg":"trace[266143354] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"273.695384ms","start":"2026-06-02T23:49:57.623442Z","end":"2026-06-02T23:49:57.897137Z","steps":["trace[266143354] 'process raft request' (duration: 272.75096ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:57.897122Z","caller":"traceutil/trace.go:171","msg":"trace[809483561] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2186; }","duration":"270.906504ms","start":"2026-06-02T23:49:57.626191Z","end":"2026-06-02T23:49:57.897097Z","steps":["trace[809483561] 'read index received' (duration: 35.922078ms)","trace[809483561] 'applied index is now lower than readState.Index' (duration: 234.983246ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:49:57.897258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.022375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:49:57.897313Z","caller":"traceutil/trace.go:171","msg":"trace[1682301375] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2110; }","duration":"271.140246ms","start":"2026-06-02T23:49:57.626163Z","end":"2026-06-02T23:49:57.897303Z","steps":["trace[1682301375] 'agreement among raft nodes before linearized reading' (duration: 271.021855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:57.897415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.928964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T23:49:57.897443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.772588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:49:57.897459Z","caller":"traceutil/trace.go:171","msg":"trace[791451664] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2110; }","duration":"205.015845ms","start":"2026-06-02T23:49:57.692431Z","end":"2026-06-02T23:49:57.897447Z","steps":["trace[791451664] 'agreement among raft nodes before linearized reading' (duration: 204.914553ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:57.897476Z","caller":"traceutil/trace.go:171","msg":"trace[1417888565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2110; }","duration":"195.85304ms","start":"2026-06-02T23:49:57.701613Z","end":"2026-06-02T23:49:57.897466Z","steps":["trace[1417888565] 'agreement among raft nodes before linearized reading' (duration: 195.786059ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:59.895187Z","caller":"traceutil/trace.go:171","msg":"trace[1188952537] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"179.661011ms","start":"2026-06-02T23:49:59.715501Z","end":"2026-06-02T23:49:59.895162Z","steps":["trace[1188952537] 'process raft request' (duration: 178.669415ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:59.895312Z","caller":"traceutil/trace.go:171","msg":"trace[1763733439] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"150.591366ms","start":"2026-06-02T23:49:59.744699Z","end":"2026-06-02T23:49:59.895291Z","steps":["trace[1763733439] 'process raft request' (duration: 150.299162ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:18.11284Z","caller":"traceutil/trace.go:171","msg":"trace[931205213] transaction","detail":"{read_only:false; response_revision:2639; number_of_response:1; }","duration":"144.388566ms","start":"2026-06-02T23:51:17.96843Z","end":"2026-06-02T23:51:18.112818Z","steps":["trace[931205213] 'process raft request' (duration: 144.234664ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:50.925825Z","caller":"traceutil/trace.go:171","msg":"trace[30030057] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"133.52205ms","start":"2026-06-02T23:51:50.792266Z","end":"2026-06-02T23:51:50.925788Z","steps":["trace[30030057] 'process raft request' (duration: 133.346306ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:56.968629Z","caller":"traceutil/trace.go:171","msg":"trace[364373015] linearizableReadLoop","detail":"{readStateIndex:2960; appliedIndex:2959; }","duration":"284.734665ms","start":"2026-06-02T23:51:56.683875Z","end":"2026-06-02T23:51:56.968609Z","steps":["trace[364373015] 'read index received' (duration: 284.711264ms)","trace[364373015] 'applied index is now lower than readState.Index' (duration: 22.501µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:51:56.968734Z","caller":"traceutil/trace.go:171","msg":"trace[678280233] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"306.64593ms","start":"2026-06-02T23:51:56.662076Z","end":"2026-06-02T23:51:56.968722Z","steps":["trace[678280233] 'process raft request' (duration: 306.420432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:56.968827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:56.66206Z","time spent":"306.697852ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:51:56.968905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.060055ms","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-06-02T23:51:56.968986Z","caller":"traceutil/trace.go:171","msg":"trace[1933779018] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2853; }","duration":"285.173969ms","start":"2026-06-02T23:51:56.683799Z","end":"2026-06-02T23:51:56.968973Z","steps":["trace[1933779018] 'agreement among raft nodes before linearized reading' (duration: 285.031644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:56.970231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.537607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T23:51:56.970278Z","caller":"traceutil/trace.go:171","msg":"trace[2075060251] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2854; }","duration":"245.604428ms","start":"2026-06-02T23:51:56.724662Z","end":"2026-06-02T23:51:56.970266Z","steps":["trace[2075060251] 'agreement among raft nodes before linearized reading' (duration: 245.543016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:56.97052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.581844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:51:56.970636Z","caller":"traceutil/trace.go:171","msg":"trace[836927282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2854; }","duration":"264.493904ms","start":"2026-06-02T23:51:56.706114Z","end":"2026-06-02T23:51:56.970608Z","steps":["trace[836927282] 'agreement among raft nodes before linearized reading' (duration: 263.570393ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:58.754781Z","caller":"traceutil/trace.go:171","msg":"trace[1716879516] transaction","detail":"{read_only:false; response_revision:3176; number_of_response:1; }","duration":"294.539429ms","start":"2026-06-02T23:52:58.460199Z","end":"2026-06-02T23:52:58.754738Z","steps":["trace[1716879516] 'process raft request' (duration: 294.316433ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:59.194254Z","caller":"traceutil/trace.go:171","msg":"trace[1731406426] linearizableReadLoop","detail":"{readStateIndex:3297; appliedIndex:3296; }","duration":"428.789613ms","start":"2026-06-02T23:52:58.765433Z","end":"2026-06-02T23:52:59.194222Z","steps":["trace[1731406426] 'read index received' (duration: 428.586808ms)","trace[1731406426] 'applied index is now lower than readState.Index' (duration: 201.315µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:52:59.194311Z","caller":"traceutil/trace.go:171","msg":"trace[1190349872] transaction","detail":"{read_only:false; response_revision:3177; number_of_response:1; }","duration":"432.507918ms","start":"2026-06-02T23:52:58.761776Z","end":"2026-06-02T23:52:59.194284Z","steps":["trace[1190349872] 'process raft request' (duration: 432.238951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.194441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.761758Z","time spent":"432.606681ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:52:59.194767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.312946ms","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-06-02T23:52:59.194836Z","caller":"traceutil/trace.go:171","msg":"trace[434827444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3177; }","duration":"429.415518ms","start":"2026-06-02T23:52:58.765404Z","end":"2026-06-02T23:52:59.194819Z","steps":["trace[434827444] 'agreement among raft nodes before linearized reading' (duration: 428.959307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.194884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.765393Z","time spent":"429.47761ms","remote":"127.0.0.1:35598","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":"warn","ts":"2026-06-02T23:52:59.195555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.377053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T23:52:59.19662Z","caller":"traceutil/trace.go:171","msg":"trace[1820201863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3178; }","duration":"422.461051ms","start":"2026-06-02T23:52:58.774132Z","end":"2026-06-02T23:52:59.196593Z","steps":["trace[1820201863] 'agreement among raft nodes before linearized reading' (duration: 421.251699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.197354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.77412Z","time spent":"423.20776ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T23:52:59.195761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.253861ms","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-06-02T23:52:59.197563Z","caller":"traceutil/trace.go:171","msg":"trace[252462871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3178; }","duration":"423.137868ms","start":"2026-06-02T23:52:58.774407Z","end":"2026-06-02T23:52:59.197544Z","steps":["trace[252462871] 'agreement among raft nodes before linearized reading' (duration: 421.161228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.197722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.774393Z","time spent":"423.289242ms","remote":"127.0.0.1:35598","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":"info","ts":"2026-06-02T23:52:59.932714Z","caller":"traceutil/trace.go:171","msg":"trace[1485466789] transaction","detail":"{read_only:false; response_revision:3183; number_of_response:1; }","duration":"171.991211ms","start":"2026-06-02T23:52:59.760697Z","end":"2026-06-02T23:52:59.932688Z","steps":["trace[1485466789] 'process raft request' (duration: 171.755565ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:12.313614Z","caller":"traceutil/trace.go:171","msg":"trace[641270772] linearizableReadLoop","detail":"{readStateIndex:3371; appliedIndex:3370; }","duration":"168.912556ms","start":"2026-06-02T23:53:12.144683Z","end":"2026-06-02T23:53:12.313595Z","steps":["trace[641270772] 'read index received' (duration: 168.777363ms)","trace[641270772] 'applied index is now lower than readState.Index' (duration: 134.323µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:12.313646Z","caller":"traceutil/trace.go:171","msg":"trace[983842528] transaction","detail":"{read_only:false; response_revision:3248; number_of_response:1; }","duration":"233.942487ms","start":"2026-06-02T23:53:12.07968Z","end":"2026-06-02T23:53:12.313623Z","steps":["trace[983842528] 'process raft request' (duration: 233.776483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:12.31376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.054719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:53:12.313805Z","caller":"traceutil/trace.go:171","msg":"trace[1498774542] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:3248; }","duration":"169.148322ms","start":"2026-06-02T23:53:12.144644Z","end":"2026-06-02T23:53:12.313792Z","steps":["trace[1498774542] 'agreement among raft nodes before linearized reading' (duration: 169.0657ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:24.661279Z","caller":"traceutil/trace.go:171","msg":"trace[1077053684] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4586; }","duration":"248.451222ms","start":"2026-06-02T23:55:24.412808Z","end":"2026-06-02T23:55:24.661259Z","steps":["trace[1077053684] 'process raft request' (duration: 248.3035ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:24.663706Z","caller":"traceutil/trace.go:171","msg":"trace[1476281140] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"208.175132ms","start":"2026-06-02T23:55:24.455507Z","end":"2026-06-02T23:55:24.663682Z","steps":["trace[1476281140] 'process raft request' (duration: 208.04907ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:33.792923Z","caller":"traceutil/trace.go:171","msg":"trace[570856398] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"108.368143ms","start":"2026-06-02T23:55:33.684526Z","end":"2026-06-02T23:55:33.792894Z","steps":["trace[570856398] 'process raft request' (duration: 106.216936ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:34.006383Z","caller":"traceutil/trace.go:171","msg":"trace[473356465] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"203.129542ms","start":"2026-06-02T23:55:33.803233Z","end":"2026-06-02T23:55:34.006363Z","steps":["trace[473356465] 'process raft request' (duration: 203.01393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:34.006487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.214675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:34.006517Z","caller":"traceutil/trace.go:171","msg":"trace[1024339076] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4724; }","duration":"151.286387ms","start":"2026-06-02T23:55:33.855224Z","end":"2026-06-02T23:55:34.006511Z","steps":["trace[1024339076] 'agreement among raft nodes before linearized reading' (duration: 151.211425ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:34.006389Z","caller":"traceutil/trace.go:171","msg":"trace[1713363259] linearizableReadLoop","detail":"{readStateIndex:4907; appliedIndex:4906; }","duration":"151.090073ms","start":"2026-06-02T23:55:33.855259Z","end":"2026-06-02T23:55:34.006349Z","steps":["trace[1713363259] 'read index received' (duration: 150.976211ms)","trace[1713363259] 'applied index is now lower than readState.Index' (duration: 112.982µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:34.021247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.021026ms","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-06-02T23:55:34.0213Z","caller":"traceutil/trace.go:171","msg":"trace[158052546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4725; }","duration":"101.135119ms","start":"2026-06-02T23:55:33.920153Z","end":"2026-06-02T23:55:34.021288Z","steps":["trace[158052546] 'agreement among raft nodes before linearized reading' (duration: 100.950095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:34.021334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.016407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T23:55:34.021402Z","caller":"traceutil/trace.go:171","msg":"trace[2114505558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4725; }","duration":"101.145558ms","start":"2026-06-02T23:55:33.920239Z","end":"2026-06-02T23:55:34.021385Z","steps":["trace[2114505558] 'agreement among raft nodes before linearized reading' (duration: 100.855533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:34.021499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.06099ms","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-06-02T23:55:34.02152Z","caller":"traceutil/trace.go:171","msg":"trace[1770897035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4725; }","duration":"107.100121ms","start":"2026-06-02T23:55:33.914414Z","end":"2026-06-02T23:55:34.021514Z","steps":["trace[1770897035] 'agreement among raft nodes before linearized reading' (duration: 107.044569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:34.021597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.998997ms","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-06-02T23:55:34.021613Z","caller":"traceutil/trace.go:171","msg":"trace[1399867554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4725; }","duration":"108.052717ms","start":"2026-06-02T23:55:33.913556Z","end":"2026-06-02T23:55:34.021608Z","steps":["trace[1399867554] 'agreement among raft nodes before linearized reading' (duration: 108.007696ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:34.721921Z","caller":"traceutil/trace.go:171","msg":"trace[1325637882] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"218.094192ms","start":"2026-06-02T23:55:34.503799Z","end":"2026-06-02T23:55:34.721893Z","steps":["trace[1325637882] 'process raft request' (duration: 217.793656ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:34.740432Z","caller":"traceutil/trace.go:171","msg":"trace[1901707651] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"236.484746ms","start":"2026-06-02T23:55:34.503907Z","end":"2026-06-02T23:55:34.740392Z","steps":["trace[1901707651] 'process raft request' (duration: 236.17378ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:34.740839Z","caller":"traceutil/trace.go:171","msg":"trace[1784503511] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"236.75752ms","start":"2026-06-02T23:55:34.504059Z","end":"2026-06-02T23:55:34.740817Z","steps":["trace[1784503511] 'process raft request' (duration: 236.129639ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:34.741347Z","caller":"traceutil/trace.go:171","msg":"trace[1650612160] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"236.227041ms","start":"2026-06-02T23:55:34.505084Z","end":"2026-06-02T23:55:34.741311Z","steps":["trace[1650612160] 'process raft request' (duration: 235.384397ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:34.742574Z","caller":"traceutil/trace.go:171","msg":"trace[1835820141] linearizableReadLoop","detail":"{readStateIndex:4944; appliedIndex:4941; }","duration":"237.91309ms","start":"2026-06-02T23:55:34.504635Z","end":"2026-06-02T23:55:34.742548Z","steps":["trace[1835820141] 'read index received' (duration: 216.982433ms)","trace[1835820141] 'applied index is now lower than readState.Index' (duration: 20.929587ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:34.742792Z","caller":"traceutil/trace.go:171","msg":"trace[1081253384] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"232.991126ms","start":"2026-06-02T23:55:34.509781Z","end":"2026-06-02T23:55:34.742772Z","steps":["trace[1081253384] 'process raft request' (duration: 232.571079ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:34.743002Z","caller":"traceutil/trace.go:171","msg":"trace[1030184001] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"237.137638ms","start":"2026-06-02T23:55:34.505854Z","end":"2026-06-02T23:55:34.742991Z","steps":["trace[1030184001] 'process raft request' (duration: 235.399928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:34.743022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.366848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:34.743064Z","caller":"traceutil/trace.go:171","msg":"trace[165162143] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:4765; }","duration":"238.442369ms","start":"2026-06-02T23:55:34.504607Z","end":"2026-06-02T23:55:34.74305Z","steps":["trace[165162143] 'agreement among raft nodes before linearized reading' (duration: 238.368968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:34.743296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.313931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0c81bba3-a033-4ff0-8897-0e6128844ca0\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-06-02T23:55:34.743326Z","caller":"traceutil/trace.go:171","msg":"trace[8108780] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0c81bba3-a033-4ff0-8897-0e6128844ca0; range_end:; response_count:1; response_revision:4765; }","duration":"237.351951ms","start":"2026-06-02T23:55:34.505965Z","end":"2026-06-02T23:55:34.743317Z","steps":["trace[8108780] 'agreement among raft nodes before linearized reading' (duration: 237.237289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:34.743704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.533233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-06-02T23:55:34.743736Z","caller":"traceutil/trace.go:171","msg":"trace[1639993517] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4765; }","duration":"237.571343ms","start":"2026-06-02T23:55:34.506155Z","end":"2026-06-02T23:55:34.743726Z","steps":["trace[1639993517] 'agreement among raft nodes before linearized reading' (duration: 237.485932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:34.744211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.989601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-06-02T23:55:34.744343Z","caller":"traceutil/trace.go:171","msg":"trace[1513693766] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4765; }","duration":"238.180626ms","start":"2026-06-02T23:55:34.506137Z","end":"2026-06-02T23:55:34.744318Z","steps":["trace[1513693766] 'agreement among raft nodes before linearized reading' (duration: 237.792579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:46.83531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.385348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:46.835397Z","caller":"traceutil/trace.go:171","msg":"trace[949090092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4977; }","duration":"132.51934ms","start":"2026-06-02T23:55:46.702859Z","end":"2026-06-02T23:55:46.835379Z","steps":["trace[949090092] 'range keys from in-memory index tree' (duration: 132.258496ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:23.132646Z","caller":"traceutil/trace.go:171","msg":"trace[1461444462] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"134.827619ms","start":"2026-06-02T23:56:22.99779Z","end":"2026-06-02T23:56:23.132618Z","steps":["trace[1461444462] 'process raft request' (duration: 134.299761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:57:00.97143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.62089ms","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-06-02T23:57:00.971505Z","caller":"traceutil/trace.go:171","msg":"trace[674453858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5562; }","duration":"460.711092ms","start":"2026-06-02T23:57:00.510779Z","end":"2026-06-02T23:57:00.97149Z","steps":["trace[674453858] 'range keys from in-memory index tree' (duration: 460.451808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:57:00.971543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:57:00.510757Z","time spent":"460.777143ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T23:57:00.971964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.002296ms","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-06-02T23:57:00.972038Z","caller":"traceutil/trace.go:171","msg":"trace[1363407514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5562; }","duration":"461.069127ms","start":"2026-06-02T23:57:00.510935Z","end":"2026-06-02T23:57:00.972004Z","steps":["trace[1363407514] 'range keys from in-memory index tree' (duration: 460.822023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:57:00.972068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:57:00.51093Z","time spent":"461.131118ms","remote":"127.0.0.1:35598","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-06-02T23:57:00.972537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.364381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T23:57:00.972674Z","caller":"traceutil/trace.go:171","msg":"trace[1688225658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5562; }","duration":"461.537945ms","start":"2026-06-02T23:57:00.511105Z","end":"2026-06-02T23:57:00.972643Z","steps":["trace[1688225658] 'range keys from in-memory index tree' (duration: 461.115888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:57:00.972765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:57:00.5111Z","time spent":"461.653997ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T23:57:06.742851Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2905} {"level":"info","ts":"2026-06-02T23:57:06.861397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2905,"took":"117.134056ms","hash":190793880,"current-db-size-bytes":52158464,"current-db-size":"52 MB","current-db-size-in-use-bytes":29343744,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-02T23:57:06.861475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":190793880,"revision":2905,"compact-revision":-1} {"level":"warn","ts":"2026-06-02T23:59:50.164806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.189694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:59:50.164879Z","caller":"traceutil/trace.go:171","msg":"trace[1614292738] transaction","detail":"{read_only:false; response_revision:6736; number_of_response:1; }","duration":"152.356713ms","start":"2026-06-02T23:59:50.01251Z","end":"2026-06-02T23:59:50.164867Z","steps":["trace[1614292738] 'process raft request' (duration: 29.041237ms)","trace[1614292738] 'compare' (duration: 123.026732ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:02:06.580685Z","caller":"traceutil/trace.go:171","msg":"trace[1529572946] linearizableReadLoop","detail":"{readStateIndex:7758; appliedIndex:7757; }","duration":"145.794936ms","start":"2026-06-03T00:02:06.434866Z","end":"2026-06-03T00:02:06.580661Z","steps":["trace[1529572946] 'read index received' (duration: 145.638741ms)","trace[1529572946] 'applied index is now lower than readState.Index' (duration: 155.255µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:02:06.580739Z","caller":"traceutil/trace.go:171","msg":"trace[1205569794] transaction","detail":"{read_only:false; response_revision:7480; number_of_response:1; }","duration":"155.852244ms","start":"2026-06-03T00:02:06.424854Z","end":"2026-06-03T00:02:06.580706Z","steps":["trace[1205569794] 'process raft request' (duration: 155.663968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:06.580859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.980491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:830"} {"level":"info","ts":"2026-06-03T00:02:06.580918Z","caller":"traceutil/trace.go:171","msg":"trace[1105723992] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:7480; }","duration":"146.081104ms","start":"2026-06-03T00:02:06.434826Z","end":"2026-06-03T00:02:06.580907Z","steps":["trace[1105723992] 'agreement among raft nodes before linearized reading' (duration: 145.973221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:06.581038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.788035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-06-03T00:02:06.581107Z","caller":"traceutil/trace.go:171","msg":"trace[1386842550] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:7480; }","duration":"116.880498ms","start":"2026-06-03T00:02:06.464209Z","end":"2026-06-03T00:02:06.581089Z","steps":["trace[1386842550] 'agreement among raft nodes before linearized reading' (duration: 116.766165ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:02:06.922198Z","caller":"traceutil/trace.go:171","msg":"trace[1795934783] linearizableReadLoop","detail":"{readStateIndex:7759; appliedIndex:7758; }","duration":"313.398978ms","start":"2026-06-03T00:02:06.60878Z","end":"2026-06-03T00:02:06.922179Z","steps":["trace[1795934783] 'read index received' (duration: 313.368017ms)","trace[1795934783] 'applied index is now lower than readState.Index' (duration: 29.981µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:02:06.922305Z","caller":"traceutil/trace.go:171","msg":"trace[1193414135] transaction","detail":"{read_only:false; response_revision:7481; number_of_response:1; }","duration":"313.869352ms","start":"2026-06-03T00:02:06.608397Z","end":"2026-06-03T00:02:06.922266Z","steps":["trace[1193414135] 'process raft request' (duration: 313.658726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:06.922547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T00:02:06.608381Z","time spent":"314.041967ms","remote":"127.0.0.1:35598","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-06-03T00:02:06.922367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.576983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-03T00:02:06.92275Z","caller":"traceutil/trace.go:171","msg":"trace[1467866979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7481; }","duration":"313.874903ms","start":"2026-06-03T00:02:06.60877Z","end":"2026-06-03T00:02:06.922644Z","steps":["trace[1467866979] 'agreement among raft nodes before linearized reading' (duration: 313.504062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:06.923054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T00:02:06.608704Z","time spent":"314.182021ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-03T00:02:06.940716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.272158ms","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-06-03T00:02:06.940756Z","caller":"traceutil/trace.go:171","msg":"trace[1676728875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7483; }","duration":"330.34069ms","start":"2026-06-03T00:02:06.610406Z","end":"2026-06-03T00:02:06.940747Z","steps":["trace[1676728875] 'agreement among raft nodes before linearized reading' (duration: 330.187136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:06.940729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.070071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:700"} {"level":"warn","ts":"2026-06-03T00:02:06.940783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T00:02:06.610394Z","time spent":"330.382271ms","remote":"127.0.0.1:35598","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":"info","ts":"2026-06-03T00:02:06.940782Z","caller":"traceutil/trace.go:171","msg":"trace[735528738] transaction","detail":"{read_only:false; response_revision:7483; number_of_response:1; }","duration":"196.883792ms","start":"2026-06-03T00:02:06.743869Z","end":"2026-06-03T00:02:06.940753Z","steps":["trace[735528738] 'process raft request' (duration: 196.682486ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:02:06.940809Z","caller":"traceutil/trace.go:171","msg":"trace[1063332049] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:7483; }","duration":"274.181983ms","start":"2026-06-03T00:02:06.666605Z","end":"2026-06-03T00:02:06.940787Z","steps":["trace[1063332049] 'agreement among raft nodes before linearized reading' (duration: 274.019049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:06.940787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.329444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-03T00:02:06.940894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.833816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T00:02:06.94087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.259635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-06-03T00:02:06.940936Z","caller":"traceutil/trace.go:171","msg":"trace[596114005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7483; }","duration":"238.881508ms","start":"2026-06-03T00:02:06.702042Z","end":"2026-06-03T00:02:06.940924Z","steps":["trace[596114005] 'agreement among raft nodes before linearized reading' (duration: 238.833107ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:02:06.940854Z","caller":"traceutil/trace.go:171","msg":"trace[1225639435] transaction","detail":"{read_only:false; response_revision:7482; number_of_response:1; }","duration":"198.320393ms","start":"2026-06-03T00:02:06.742525Z","end":"2026-06-03T00:02:06.940846Z","steps":["trace[1225639435] 'process raft request' (duration: 197.920302ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:02:06.940961Z","caller":"traceutil/trace.go:171","msg":"trace[311159472] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:7483; }","duration":"277.39841ms","start":"2026-06-03T00:02:06.66354Z","end":"2026-06-03T00:02:06.940938Z","steps":["trace[311159472] 'agreement among raft nodes before linearized reading' (duration: 277.203843ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:02:06.940917Z","caller":"traceutil/trace.go:171","msg":"trace[2511683] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:7483; }","duration":"289.4941ms","start":"2026-06-03T00:02:06.651404Z","end":"2026-06-03T00:02:06.940898Z","steps":["trace[2511683] 'agreement among raft nodes before linearized reading' (duration: 289.276052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:06.941473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.331101ms","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-06-03T00:02:06.941521Z","caller":"traceutil/trace.go:171","msg":"trace[1303004581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7483; }","duration":"327.409534ms","start":"2026-06-03T00:02:06.6141Z","end":"2026-06-03T00:02:06.94151Z","steps":["trace[1303004581] 'agreement among raft nodes before linearized reading' (duration: 327.262589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:06.941554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T00:02:06.614094Z","time spent":"327.452585ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T00:02:07.23169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.858558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T00:02:07.232132Z","caller":"traceutil/trace.go:171","msg":"trace[955211592] transaction","detail":"{read_only:false; response_revision:7484; number_of_response:1; }","duration":"304.978606ms","start":"2026-06-03T00:02:06.927118Z","end":"2026-06-03T00:02:07.232096Z","steps":["trace[955211592] 'process raft request' (duration: 104.635503ms)","trace[955211592] 'compare' (duration: 199.723744ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T00:02:07.23229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T00:02:06.927101Z","time spent":"305.099119ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T00:02:07.234397Z","caller":"traceutil/trace.go:171","msg":"trace[1160087331] linearizableReadLoop","detail":"{readStateIndex:7763; appliedIndex:7761; }","duration":"291.150716ms","start":"2026-06-03T00:02:06.943228Z","end":"2026-06-03T00:02:07.234378Z","steps":["trace[1160087331] 'read index received' (duration: 88.519986ms)","trace[1160087331] 'applied index is now lower than readState.Index' (duration: 202.62964ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:02:07.234468Z","caller":"traceutil/trace.go:171","msg":"trace[1064886619] transaction","detail":"{read_only:false; response_revision:7485; number_of_response:1; }","duration":"288.834717ms","start":"2026-06-03T00:02:06.945599Z","end":"2026-06-03T00:02:07.234433Z","steps":["trace[1064886619] 'process raft request' (duration: 288.658232ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:02:07.234501Z","caller":"traceutil/trace.go:171","msg":"trace[320676915] transaction","detail":"{read_only:false; response_revision:7486; number_of_response:1; }","duration":"288.60383ms","start":"2026-06-03T00:02:06.945878Z","end":"2026-06-03T00:02:07.234482Z","steps":["trace[320676915] 'process raft request' (duration: 288.458706ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:02:07.23446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5597} {"level":"info","ts":"2026-06-03T00:02:07.234559Z","caller":"traceutil/trace.go:171","msg":"trace[1304893315] compact","detail":"{revision:5597; response_revision:7486; }","duration":"292.436154ms","start":"2026-06-03T00:02:06.942103Z","end":"2026-06-03T00:02:07.234539Z","steps":["trace[1304893315] 'process raft request' (duration: 289.93434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:07.234698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.448125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T00:02:07.234784Z","caller":"traceutil/trace.go:171","msg":"trace[959442953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7486; }","duration":"291.577669ms","start":"2026-06-03T00:02:06.943188Z","end":"2026-06-03T00:02:07.234766Z","steps":["trace[959442953] 'agreement among raft nodes before linearized reading' (duration: 291.379873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:07.234927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.903152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"warn","ts":"2026-06-03T00:02:07.235014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.712131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-06-03T00:02:07.235061Z","caller":"traceutil/trace.go:171","msg":"trace[2088030209] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:7486; }","duration":"210.796984ms","start":"2026-06-03T00:02:07.024251Z","end":"2026-06-03T00:02:07.235048Z","steps":["trace[2088030209] 'agreement among raft nodes before linearized reading' (duration: 210.413432ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:02:07.235067Z","caller":"traceutil/trace.go:171","msg":"trace[950569686] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:7486; }","duration":"218.081408ms","start":"2026-06-03T00:02:07.016919Z","end":"2026-06-03T00:02:07.235Z","steps":["trace[950569686] 'agreement among raft nodes before linearized reading' (duration: 217.726437ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:02:07.313299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5597,"took":"76.423203ms","hash":3416260672,"current-db-size-bytes":52158464,"current-db-size":"52 MB","current-db-size-in-use-bytes":18575360,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-03T00:02:07.313359Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3416260672,"revision":5597,"compact-revision":2905} {"level":"warn","ts":"2026-06-03T00:02:37.292234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.648922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-prometheus-tls\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-06-03T00:02:37.292358Z","caller":"traceutil/trace.go:171","msg":"trace[1976841200] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-prometheus-tls; range_end:; response_count:1; response_revision:7635; }","duration":"141.816477ms","start":"2026-06-03T00:02:37.150513Z","end":"2026-06-03T00:02:37.29233Z","steps":["trace[1976841200] 'range keys from in-memory index tree' (duration: 141.443817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:02:37.292426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.801787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:17456"} {"level":"info","ts":"2026-06-03T00:02:37.292543Z","caller":"traceutil/trace.go:171","msg":"trace[1586428996] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:7635; }","duration":"125.943201ms","start":"2026-06-03T00:02:37.166574Z","end":"2026-06-03T00:02:37.292518Z","steps":["trace[1586428996] 'range keys from in-memory index tree' (duration: 125.615782ms)"],"step_count":1}