{"level":"warn","ts":"2026-07-06T23:13:50.758331Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:13:50.758436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-06T23:13:50.758511Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:13:50.758525Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-06T23:13:50.758543Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-06T23:13:50.759168Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-06T23:13:50.759243Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-06T23:13:50.76449Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.015525ms"} {"level":"info","ts":"2026-07-06T23:13:50.775587Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-06T23:13:50.775796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T23:13:50.77584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-06T23:13:50.775863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-06T23:13:50.775877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-06T23:13:50.775942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-06T23:13:50.784521Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T23:13:50.78614Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-06T23:13:50.788381Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-06T23:13:50.792358Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-06T23:13:50.792602Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-06T23:13:50.792681Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-06T23:13:50.792902Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-06T23:13:50.792926Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-06T23:13:50.803391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-06T23:13:50.804529Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-06T23:13:50.807884Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-06T23:13:50.808359Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-06T23:13:50.808437Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T23:13:50.808757Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-06T23:13:50.80879Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-06T23:13:51.676471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-06T23:13:51.676533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-06T23:13:51.67657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-06T23:13:51.676596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-06T23:13:51.676614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-06T23:13:51.67663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-06T23:13:51.676644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-06T23:13:51.677516Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:13:51.679018Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-06T23:13:51.679022Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:13:51.679038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:13:51.679575Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T23:13:51.679627Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T23:13:51.679881Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:13:51.681108Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:13:51.681237Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:13:51.68226Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-06T23:13:51.682464Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-07-06T23:13:54.188436Z","caller":"traceutil/trace.go:171","msg":"trace[146417037] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"176.945838ms","start":"2026-07-06T23:13:54.011461Z","end":"2026-07-06T23:13:54.188407Z","steps":["trace[146417037] 'read index received' (duration: 176.681391ms)","trace[146417037] 'applied index is now lower than readState.Index' (duration: 263.477µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:13:54.18859Z","caller":"traceutil/trace.go:171","msg":"trace[168043108] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"177.952548ms","start":"2026-07-06T23:13:54.010622Z","end":"2026-07-06T23:13:54.188575Z","steps":["trace[168043108] 'process raft request' (duration: 177.59158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:13:54.188915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.396217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:13:54.188997Z","caller":"traceutil/trace.go:171","msg":"trace[1144158685] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"177.532919ms","start":"2026-07-06T23:13:54.011446Z","end":"2026-07-06T23:13:54.188979Z","steps":["trace[1144158685] 'agreement among raft nodes before linearized reading' (duration: 177.307345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:13:54.696027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221005576321244,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T23:13:54.795762Z","caller":"traceutil/trace.go:171","msg":"trace[873526845] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"600.3118ms","start":"2026-07-06T23:13:54.195424Z","end":"2026-07-06T23:13:54.795736Z","steps":["trace[873526845] 'read index received' (duration: 600.063545ms)","trace[873526845] 'applied index is now lower than readState.Index' (duration: 247.105µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:13:54.795852Z","caller":"traceutil/trace.go:171","msg":"trace[1489588631] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"601.037485ms","start":"2026-07-06T23:13:54.194786Z","end":"2026-07-06T23:13:54.795823Z","steps":["trace[1489588631] 'process raft request' (duration: 600.78446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:13:54.796058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.611156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:13:54.796125Z","caller":"traceutil/trace.go:171","msg":"trace[1590086604] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"600.776729ms","start":"2026-07-06T23:13:54.195333Z","end":"2026-07-06T23:13:54.796088Z","steps":["trace[1590086604] 'agreement among raft nodes before linearized reading' (duration: 600.562514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:13:54.796198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:13:54.195295Z","time spent":"600.894101ms","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-07-06T23:13:54.796424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:13:54.194766Z","time spent":"601.159937ms","remote":"127.0.0.1:51790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:13:55.266845Z","caller":"traceutil/trace.go:171","msg":"trace[788157507] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"465.000367ms","start":"2026-07-06T23:13:54.801817Z","end":"2026-07-06T23:13:55.266817Z","steps":["trace[788157507] 'read index received' (duration: 407.954059ms)","trace[788157507] 'applied index is now lower than readState.Index' (duration: 57.045418ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:13:55.266978Z","caller":"traceutil/trace.go:171","msg":"trace[494091564] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"466.960079ms","start":"2026-07-06T23:13:54.799923Z","end":"2026-07-06T23:13:55.266883Z","steps":["trace[494091564] 'process raft request' (duration: 409.820329ms)","trace[494091564] 'compare' (duration: 56.933795ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:13:55.267133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.674137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-06T23:13:55.267144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.287615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:13:55.267176Z","caller":"traceutil/trace.go:171","msg":"trace[1360984997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"152.762319ms","start":"2026-07-06T23:13:55.114403Z","end":"2026-07-06T23:13:55.267166Z","steps":["trace[1360984997] 'agreement among raft nodes before linearized reading' (duration: 152.603856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:13:55.267148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:13:54.799899Z","time spent":"467.174033ms","remote":"127.0.0.1:51618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:13:55.267235Z","caller":"traceutil/trace.go:171","msg":"trace[10161196] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:73; }","duration":"465.414217ms","start":"2026-07-06T23:13:54.8018Z","end":"2026-07-06T23:13:55.267214Z","steps":["trace[10161196] 'agreement among raft nodes before linearized reading' (duration: 465.199823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:13:55.267292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:13:54.801792Z","time spent":"465.490969ms","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"info","ts":"2026-07-06T23:13:55.588631Z","caller":"traceutil/trace.go:171","msg":"trace[750251710] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"273.451323ms","start":"2026-07-06T23:13:55.315157Z","end":"2026-07-06T23:13:55.588609Z","steps":["trace[750251710] 'process raft request' (duration: 264.932865ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.262907Z","caller":"traceutil/trace.go:171","msg":"trace[2121885028] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"107.260763ms","start":"2026-07-06T23:14:30.155596Z","end":"2026-07-06T23:14:30.262857Z","steps":["trace[2121885028] 'process raft request' (duration: 107.12574ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.278126Z","caller":"traceutil/trace.go:171","msg":"trace[186130359] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"117.416317ms","start":"2026-07-06T23:14:30.160691Z","end":"2026-07-06T23:14:30.278107Z","steps":["trace[186130359] 'process raft request' (duration: 116.443947ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.278478Z","caller":"traceutil/trace.go:171","msg":"trace[292161235] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"117.050028ms","start":"2026-07-06T23:14:30.160788Z","end":"2026-07-06T23:14:30.277838Z","steps":["trace[292161235] 'process raft request' (duration: 116.439045ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.279735Z","caller":"traceutil/trace.go:171","msg":"trace[173106115] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"110.848689ms","start":"2026-07-06T23:14:30.168826Z","end":"2026-07-06T23:14:30.279675Z","steps":["trace[173106115] 'process raft request' (duration: 110.035322ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.281323Z","caller":"traceutil/trace.go:171","msg":"trace[544858172] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"112.039144ms","start":"2026-07-06T23:14:30.168889Z","end":"2026-07-06T23:14:30.280928Z","steps":["trace[544858172] 'process raft request' (duration: 110.304498ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.283524Z","caller":"traceutil/trace.go:171","msg":"trace[1301553295] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"111.994082ms","start":"2026-07-06T23:14:30.170742Z","end":"2026-07-06T23:14:30.282736Z","steps":["trace[1301553295] 'process raft request' (duration: 111.015202ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.283622Z","caller":"traceutil/trace.go:171","msg":"trace[280162615] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"112.055954ms","start":"2026-07-06T23:14:30.170685Z","end":"2026-07-06T23:14:30.282741Z","steps":["trace[280162615] 'process raft request' (duration: 109.242655ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.285693Z","caller":"traceutil/trace.go:171","msg":"trace[266679830] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"111.594735ms","start":"2026-07-06T23:14:30.172384Z","end":"2026-07-06T23:14:30.283978Z","steps":["trace[266679830] 'process raft request' (duration: 111.317848ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.289688Z","caller":"traceutil/trace.go:171","msg":"trace[1002777943] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"107.370205ms","start":"2026-07-06T23:14:30.179103Z","end":"2026-07-06T23:14:30.286473Z","steps":["trace[1002777943] 'process raft request' (duration: 104.847132ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:30.289865Z","caller":"traceutil/trace.go:171","msg":"trace[1754838473] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"107.203752ms","start":"2026-07-06T23:14:30.182652Z","end":"2026-07-06T23:14:30.289856Z","steps":["trace[1754838473] 'process raft request' (duration: 106.104708ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:31.314052Z","caller":"traceutil/trace.go:171","msg":"trace[910783267] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"103.932913ms","start":"2026-07-06T23:14:31.210074Z","end":"2026-07-06T23:14:31.314007Z","steps":["trace[910783267] 'process raft request' (duration: 102.038274ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:31.443326Z","caller":"traceutil/trace.go:171","msg":"trace[730238330] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"123.983174ms","start":"2026-07-06T23:14:31.319291Z","end":"2026-07-06T23:14:31.443274Z","steps":["trace[730238330] 'read index received' (duration: 73.79717ms)","trace[730238330] 'applied index is now lower than readState.Index' (duration: 50.184804ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:14:31.443351Z","caller":"traceutil/trace.go:171","msg":"trace[240882068] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"135.934645ms","start":"2026-07-06T23:14:31.307375Z","end":"2026-07-06T23:14:31.44331Z","steps":["trace[240882068] 'process raft request' (duration: 85.871953ms)","trace[240882068] 'compare' (duration: 49.46731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:14:31.498221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.548711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:14:31.498314Z","caller":"traceutil/trace.go:171","msg":"trace[62090251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"179.039331ms","start":"2026-07-06T23:14:31.319254Z","end":"2026-07-06T23:14:31.498294Z","steps":["trace[62090251] 'agreement among raft nodes before linearized reading' (duration: 124.160078ms)","trace[62090251] 'range keys from in-memory index tree' (duration: 54.368933ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:14:36.950992Z","caller":"traceutil/trace.go:171","msg":"trace[655208918] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"127.356915ms","start":"2026-07-06T23:14:36.823602Z","end":"2026-07-06T23:14:36.950959Z","steps":["trace[655208918] 'process raft request' (duration: 126.471696ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:37.261743Z","caller":"traceutil/trace.go:171","msg":"trace[2042310273] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"249.901319ms","start":"2026-07-06T23:14:37.011806Z","end":"2026-07-06T23:14:37.261708Z","steps":["trace[2042310273] 'process raft request' (duration: 249.669355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:37.53486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.430525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-clbdd\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-06T23:14:37.53495Z","caller":"traceutil/trace.go:171","msg":"trace[387239849] range","detail":"{range_begin:/registry/pods/kube-system/cilium-clbdd; range_end:; response_count:1; response_revision:638; }","duration":"115.614948ms","start":"2026-07-06T23:14:37.419313Z","end":"2026-07-06T23:14:37.534928Z","steps":["trace[387239849] 'range keys from in-memory index tree' (duration: 115.165548ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:37.652198Z","caller":"traceutil/trace.go:171","msg":"trace[1958357382] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"101.896011ms","start":"2026-07-06T23:14:37.550284Z","end":"2026-07-06T23:14:37.65218Z","steps":["trace[1958357382] 'process raft request' (duration: 101.707107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:38.275185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221005576322447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:14:38.767232Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.111597079s","expected-duration":"1s"} {"level":"info","ts":"2026-07-06T23:14:38.767797Z","caller":"traceutil/trace.go:171","msg":"trace[344705490] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.112233512s","start":"2026-07-06T23:14:37.655514Z","end":"2026-07-06T23:14:38.767748Z","steps":["trace[344705490] 'process raft request' (duration: 1.111977737s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:38.768335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:14:37.655485Z","time spent":"1.112416166s","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:14:39.257201Z","caller":"traceutil/trace.go:171","msg":"trace[1181056155] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"1.482396628s","start":"2026-07-06T23:14:37.774759Z","end":"2026-07-06T23:14:39.257156Z","steps":["trace[1181056155] 'read index received' (duration: 992.813764ms)","trace[1181056155] 'applied index is now lower than readState.Index' (duration: 489.581664ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:14:39.257301Z","caller":"traceutil/trace.go:171","msg":"trace[1005254824] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.537869832s","start":"2026-07-06T23:14:37.719365Z","end":"2026-07-06T23:14:39.257235Z","steps":["trace[1005254824] 'process raft request' (duration: 1.537281129s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:39.25752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482707884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-06T23:14:39.257629Z","caller":"traceutil/trace.go:171","msg":"trace[293718172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.482923728s","start":"2026-07-06T23:14:37.774682Z","end":"2026-07-06T23:14:39.257606Z","steps":["trace[293718172] 'agreement among raft nodes before linearized reading' (duration: 1.482585571s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:39.25765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:14:37.719333Z","time spent":"1.538116717s","remote":"127.0.0.1:51538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:14:39.257685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:14:37.77465Z","time spent":"1.48302526s","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T23:14:39.269187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029987394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:14:39.269278Z","caller":"traceutil/trace.go:171","msg":"trace[433484239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.030137847s","start":"2026-07-06T23:14:38.239118Z","end":"2026-07-06T23:14:39.269256Z","steps":["trace[433484239] 'agreement among raft nodes before linearized reading' (duration: 1.029980874s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:39.26932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:14:38.239093Z","time spent":"1.03021905s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T23:14:39.269324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.549429ms","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-07-06T23:14:39.269392Z","caller":"traceutil/trace.go:171","msg":"trace[85063919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"586.648772ms","start":"2026-07-06T23:14:38.682714Z","end":"2026-07-06T23:14:39.269363Z","steps":["trace[85063919] 'agreement among raft nodes before linearized reading' (duration: 586.436277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:39.269424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:14:38.682694Z","time spent":"586.723304ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T23:14:39.270061Z","caller":"traceutil/trace.go:171","msg":"trace[1316100188] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"175.739851ms","start":"2026-07-06T23:14:39.094305Z","end":"2026-07-06T23:14:39.270044Z","steps":["trace[1316100188] 'process raft request' (duration: 174.638718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:39.271521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.790075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-06T23:14:39.272023Z","caller":"traceutil/trace.go:171","msg":"trace[968418905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"876.085472ms","start":"2026-07-06T23:14:38.395922Z","end":"2026-07-06T23:14:39.272007Z","steps":["trace[968418905] 'agreement among raft nodes before linearized reading' (duration: 873.350705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:39.272457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:14:38.3959Z","time spent":"876.544552ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T23:14:54.525762Z","caller":"traceutil/trace.go:171","msg":"trace[1692946819] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"101.672485ms","start":"2026-07-06T23:14:54.424063Z","end":"2026-07-06T23:14:54.525735Z","steps":["trace[1692946819] 'read index received' (duration: 101.45099ms)","trace[1692946819] 'applied index is now lower than readState.Index' (duration: 220.615µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:14:54.529672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.582722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-06T23:14:54.52981Z","caller":"traceutil/trace.go:171","msg":"trace[280823891] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"105.787176ms","start":"2026-07-06T23:14:54.424002Z","end":"2026-07-06T23:14:54.529789Z","steps":["trace[280823891] 'agreement among raft nodes before linearized reading' (duration: 102.004392ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:54.530272Z","caller":"traceutil/trace.go:171","msg":"trace[2008843188] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"128.038348ms","start":"2026-07-06T23:14:54.402212Z","end":"2026-07-06T23:14:54.530251Z","steps":["trace[2008843188] 'process raft request' (duration: 123.370162ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:54.730181Z","caller":"traceutil/trace.go:171","msg":"trace[770700694] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"195.167326ms","start":"2026-07-06T23:14:54.534985Z","end":"2026-07-06T23:14:54.730153Z","steps":["trace[770700694] 'process raft request' (duration: 194.207175ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:54.737381Z","caller":"traceutil/trace.go:171","msg":"trace[664326580] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"197.070118ms","start":"2026-07-06T23:14:54.54029Z","end":"2026-07-06T23:14:54.73736Z","steps":["trace[664326580] 'process raft request' (duration: 196.951776ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:15:08.964489Z","caller":"traceutil/trace.go:171","msg":"trace[669596737] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"157.368115ms","start":"2026-07-06T23:15:08.807081Z","end":"2026-07-06T23:15:08.964449Z","steps":["trace[669596737] 'process raft request' (duration: 157.053758ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:15:23.948784Z","caller":"traceutil/trace.go:171","msg":"trace[383868810] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"176.66643ms","start":"2026-07-06T23:15:23.772091Z","end":"2026-07-06T23:15:23.948758Z","steps":["trace[383868810] 'process raft request' (duration: 176.474546ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:15:29.407901Z","caller":"traceutil/trace.go:171","msg":"trace[550547642] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"318.688391ms","start":"2026-07-06T23:15:29.089188Z","end":"2026-07-06T23:15:29.407877Z","steps":["trace[550547642] 'read index received' (duration: 318.528408ms)","trace[550547642] 'applied index is now lower than readState.Index' (duration: 158.713µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:15:29.407999Z","caller":"traceutil/trace.go:171","msg":"trace[852862318] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"337.765009ms","start":"2026-07-06T23:15:29.070204Z","end":"2026-07-06T23:15:29.407969Z","steps":["trace[852862318] 'process raft request' (duration: 337.532724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:15:29.408152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:15:29.070193Z","time spent":"337.876843ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:15:29.40815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.939297ms","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-07-06T23:15:29.408233Z","caller":"traceutil/trace.go:171","msg":"trace[1042755022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"319.037959ms","start":"2026-07-06T23:15:29.089177Z","end":"2026-07-06T23:15:29.408215Z","steps":["trace[1042755022] 'agreement among raft nodes before linearized reading' (duration: 318.791883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:15:29.40829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:15:29.089161Z","time spent":"319.11652ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-06T23:15:59.789236Z","caller":"traceutil/trace.go:171","msg":"trace[1151005696] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"211.838018ms","start":"2026-07-06T23:15:59.57737Z","end":"2026-07-06T23:15:59.789208Z","steps":["trace[1151005696] 'process raft request' (duration: 211.616003ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:15:59.789887Z","caller":"traceutil/trace.go:171","msg":"trace[1884085573] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1164; }","duration":"177.990495ms","start":"2026-07-06T23:15:59.611862Z","end":"2026-07-06T23:15:59.789853Z","steps":["trace[1884085573] 'read index received' (duration: 177.096865ms)","trace[1884085573] 'applied index is now lower than readState.Index' (duration: 891.95µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:15:59.790069Z","caller":"traceutil/trace.go:171","msg":"trace[348138536] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"210.087988ms","start":"2026-07-06T23:15:59.579961Z","end":"2026-07-06T23:15:59.790049Z","steps":["trace[348138536] 'process raft request' (duration: 209.691969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:15:59.790315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.422534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:15:59.790358Z","caller":"traceutil/trace.go:171","msg":"trace[2079652082] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1120; }","duration":"178.524447ms","start":"2026-07-06T23:15:59.611824Z","end":"2026-07-06T23:15:59.790349Z","steps":["trace[2079652082] 'agreement among raft nodes before linearized reading' (duration: 178.415104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:04.309587Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221005576323521,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T23:16:04.411247Z","caller":"traceutil/trace.go:171","msg":"trace[73682594] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"604.639406ms","start":"2026-07-06T23:16:03.806583Z","end":"2026-07-06T23:16:04.411223Z","steps":["trace[73682594] 'process raft request' (duration: 604.504663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:04.411404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:03.806551Z","time spent":"604.7696ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:16:06.222995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351833381s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:16:06.223164Z","caller":"traceutil/trace.go:171","msg":"trace[1513270903] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1178; }","duration":"2.413967602s","start":"2026-07-06T23:16:03.80918Z","end":"2026-07-06T23:16:06.223148Z","steps":["trace[1513270903] 'read index received' (duration: 601.933276ms)","trace[1513270903] 'applied index is now lower than readState.Index' (duration: 1.812033366s)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:06.223497Z","caller":"traceutil/trace.go:171","msg":"trace[47067690] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"2.413468961s","start":"2026-07-06T23:16:03.80999Z","end":"2026-07-06T23:16:06.223459Z","steps":["trace[47067690] 'process raft request' (duration: 1.060905434s)","trace[47067690] 'compare' (duration: 1.351711758s)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:06.223518Z","caller":"traceutil/trace.go:171","msg":"trace[1198123870] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"2.104053288s","start":"2026-07-06T23:16:04.119418Z","end":"2026-07-06T23:16:06.223471Z","steps":["trace[1198123870] 'process raft request' (duration: 2.103673659s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:06.223602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.414400332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-06T23:16:06.223662Z","caller":"traceutil/trace.go:171","msg":"trace[851028807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1134; }","duration":"2.414513895s","start":"2026-07-06T23:16:03.809137Z","end":"2026-07-06T23:16:06.223651Z","steps":["trace[851028807] 'agreement among raft nodes before linearized reading' (duration: 2.41430619s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:06.223702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:03.809122Z","time spent":"2.414570906s","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T23:16:06.223723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:03.809935Z","time spent":"2.413672036s","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:16:06.223738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:04.119381Z","time spent":"2.104283003s","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:16:06.282499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045763973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T23:16:06.28252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.470791101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-06T23:16:06.282569Z","caller":"traceutil/trace.go:171","msg":"trace[381388974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1135; }","duration":"1.045879225s","start":"2026-07-06T23:16:05.236675Z","end":"2026-07-06T23:16:06.282554Z","steps":["trace[381388974] 'agreement among raft nodes before linearized reading' (duration: 1.045751852s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:06.282595Z","caller":"traceutil/trace.go:171","msg":"trace[651180354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1135; }","duration":"2.470876423s","start":"2026-07-06T23:16:03.811698Z","end":"2026-07-06T23:16:06.282575Z","steps":["trace[651180354] 'agreement among raft nodes before linearized reading' (duration: 2.470679148s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:06.282606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:05.236658Z","time spent":"1.045940477s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T23:16:06.282636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:03.811653Z","time spent":"2.470975475s","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T23:16:06.28267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.470229488s","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-07-06T23:16:06.282744Z","caller":"traceutil/trace.go:171","msg":"trace[256388742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1135; }","duration":"2.47032358s","start":"2026-07-06T23:16:03.812399Z","end":"2026-07-06T23:16:06.282722Z","steps":["trace[256388742] 'agreement among raft nodes before linearized reading' (duration: 2.470083565s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:06.282784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:03.812388Z","time spent":"2.470387952s","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T23:16:06.28294Z","caller":"traceutil/trace.go:171","msg":"trace[1840730635] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"296.273027ms","start":"2026-07-06T23:16:05.98665Z","end":"2026-07-06T23:16:06.282923Z","steps":["trace[1840730635] 'process raft request' (duration: 295.583182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:06.282952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.505198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T23:16:06.283035Z","caller":"traceutil/trace.go:171","msg":"trace[119567217] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1135; }","duration":"650.622441ms","start":"2026-07-06T23:16:05.632402Z","end":"2026-07-06T23:16:06.283025Z","steps":["trace[119567217] 'agreement among raft nodes before linearized reading' (duration: 650.456457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:06.283065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:05.632386Z","time spent":"650.671091ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-06T23:16:06.443143Z","caller":"traceutil/trace.go:171","msg":"trace[1922262669] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1183; }","duration":"140.520142ms","start":"2026-07-06T23:16:06.302598Z","end":"2026-07-06T23:16:06.443118Z","steps":["trace[1922262669] 'read index received' (duration: 128.731167ms)","trace[1922262669] 'applied index is now lower than readState.Index' (duration: 11.787755ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:06.443321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.720857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:06.443377Z","caller":"traceutil/trace.go:171","msg":"trace[62048473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"140.823778ms","start":"2026-07-06T23:16:06.302541Z","end":"2026-07-06T23:16:06.443364Z","steps":["trace[62048473] 'agreement among raft nodes before linearized reading' (duration: 140.725296ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:06.44319Z","caller":"traceutil/trace.go:171","msg":"trace[1950073756] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"156.510432ms","start":"2026-07-06T23:16:06.286647Z","end":"2026-07-06T23:16:06.443158Z","steps":["trace[1950073756] 'process raft request' (duration: 144.674286ms)","trace[1950073756] 'compare' (duration: 11.607221ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:06.443212Z","caller":"traceutil/trace.go:171","msg":"trace[843932605] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"155.945639ms","start":"2026-07-06T23:16:06.287245Z","end":"2026-07-06T23:16:06.44319Z","steps":["trace[843932605] 'process raft request' (duration: 155.807866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:06.444472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.257677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-06T23:16:06.444504Z","caller":"traceutil/trace.go:171","msg":"trace[972420110] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1138; }","duration":"120.316688ms","start":"2026-07-06T23:16:06.324179Z","end":"2026-07-06T23:16:06.444496Z","steps":["trace[972420110] 'agreement among raft nodes before linearized reading' (duration: 120.214765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:06.777045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.169571ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:06.777134Z","caller":"traceutil/trace.go:171","msg":"trace[935509896] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1140; }","duration":"195.281754ms","start":"2026-07-06T23:16:06.58184Z","end":"2026-07-06T23:16:06.777121Z","steps":["trace[935509896] 'range keys from in-memory index tree' (duration: 195.158971ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:08.530132Z","caller":"traceutil/trace.go:171","msg":"trace[843605328] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"300.477602ms","start":"2026-07-06T23:16:08.229631Z","end":"2026-07-06T23:16:08.530108Z","steps":["trace[843605328] 'process raft request' (duration: 300.261377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:08.530288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:08.229615Z","time spent":"300.589464ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:08.555748Z","caller":"traceutil/trace.go:171","msg":"trace[683962504] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1188; }","duration":"318.191379ms","start":"2026-07-06T23:16:08.237544Z","end":"2026-07-06T23:16:08.555735Z","steps":["trace[683962504] 'read index received' (duration: 292.526962ms)","trace[683962504] 'applied index is now lower than readState.Index' (duration: 25.663777ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:08.555803Z","caller":"traceutil/trace.go:171","msg":"trace[1353670839] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"322.65105ms","start":"2026-07-06T23:16:08.233133Z","end":"2026-07-06T23:16:08.555784Z","steps":["trace[1353670839] 'process raft request' (duration: 322.529238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:08.555846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.302412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:08.55589Z","caller":"traceutil/trace.go:171","msg":"trace[1891239039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"318.382863ms","start":"2026-07-06T23:16:08.237499Z","end":"2026-07-06T23:16:08.555882Z","steps":["trace[1891239039] 'agreement among raft nodes before linearized reading' (duration: 318.310792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:08.555911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:08.237487Z","time spent":"318.418195ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T23:16:08.5559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:08.233101Z","time spent":"322.744323ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:16:08.555947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.680247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T23:16:08.556017Z","caller":"traceutil/trace.go:171","msg":"trace[216233826] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1142; }","duration":"209.78475ms","start":"2026-07-06T23:16:08.346215Z","end":"2026-07-06T23:16:08.555999Z","steps":["trace[216233826] 'agreement among raft nodes before linearized reading' (duration: 209.672558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:08.555999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.070038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T23:16:08.556058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.392348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:16:08.556112Z","caller":"traceutil/trace.go:171","msg":"trace[1139027496] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1142; }","duration":"286.253551ms","start":"2026-07-06T23:16:08.269834Z","end":"2026-07-06T23:16:08.556088Z","steps":["trace[1139027496] 'agreement among raft nodes before linearized reading' (duration: 286.039336ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:08.556129Z","caller":"traceutil/trace.go:171","msg":"trace[1686350288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1142; }","duration":"100.495161ms","start":"2026-07-06T23:16:08.455617Z","end":"2026-07-06T23:16:08.556112Z","steps":["trace[1686350288] 'agreement among raft nodes before linearized reading' (duration: 100.277336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:08.556111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.55944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-06T23:16:08.556161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.014717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-06T23:16:08.556187Z","caller":"traceutil/trace.go:171","msg":"trace[1385819238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1142; }","duration":"107.672574ms","start":"2026-07-06T23:16:08.448498Z","end":"2026-07-06T23:16:08.556171Z","steps":["trace[1385819238] 'agreement among raft nodes before linearized reading' (duration: 107.446758ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:08.556217Z","caller":"traceutil/trace.go:171","msg":"trace[1719280184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1142; }","duration":"258.098688ms","start":"2026-07-06T23:16:08.298104Z","end":"2026-07-06T23:16:08.556203Z","steps":["trace[1719280184] 'agreement among raft nodes before linearized reading' (duration: 257.871122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:08.556642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.176915ms","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-07-06T23:16:08.556687Z","caller":"traceutil/trace.go:171","msg":"trace[337954295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1142; }","duration":"108.244116ms","start":"2026-07-06T23:16:08.448432Z","end":"2026-07-06T23:16:08.556676Z","steps":["trace[337954295] 'agreement among raft nodes before linearized reading' (duration: 108.107883ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:08.703438Z","caller":"traceutil/trace.go:171","msg":"trace[938347692] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"141.715689ms","start":"2026-07-06T23:16:08.561698Z","end":"2026-07-06T23:16:08.703413Z","steps":["trace[938347692] 'process raft request' (duration: 141.576056ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:08.703689Z","caller":"traceutil/trace.go:171","msg":"trace[416464906] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"141.389781ms","start":"2026-07-06T23:16:08.562282Z","end":"2026-07-06T23:16:08.703672Z","steps":["trace[416464906] 'process raft request' (duration: 141.30279ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:08.703712Z","caller":"traceutil/trace.go:171","msg":"trace[1668923253] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"141.556745ms","start":"2026-07-06T23:16:08.562142Z","end":"2026-07-06T23:16:08.703699Z","steps":["trace[1668923253] 'process raft request' (duration: 141.218688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:25.276517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221005576323779,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T23:16:25.763207Z","caller":"traceutil/trace.go:171","msg":"trace[1711957019] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"987.598224ms","start":"2026-07-06T23:16:24.775502Z","end":"2026-07-06T23:16:25.7631Z","steps":["trace[1711957019] 'process raft request' (duration: 987.020891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:25.763438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.300538ms","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-07-06T23:16:25.763534Z","caller":"traceutil/trace.go:171","msg":"trace[1031231168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1247; }","duration":"987.43535ms","start":"2026-07-06T23:16:24.776075Z","end":"2026-07-06T23:16:25.76351Z","steps":["trace[1031231168] 'agreement among raft nodes before linearized reading' (duration: 987.157444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:25.763594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:24.776069Z","time spent":"987.512312ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T23:16:25.76363Z","caller":"traceutil/trace.go:171","msg":"trace[1169049966] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"986.95745ms","start":"2026-07-06T23:16:24.776087Z","end":"2026-07-06T23:16:25.763044Z","steps":["trace[1169049966] 'read index received' (duration: 986.257574ms)","trace[1169049966] 'applied index is now lower than readState.Index' (duration: 698.186µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:25.763913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.526832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-06T23:16:25.7639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.900084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:25.763952Z","caller":"traceutil/trace.go:171","msg":"trace[1111794251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"987.640255ms","start":"2026-07-06T23:16:24.776297Z","end":"2026-07-06T23:16:25.763938Z","steps":["trace[1111794251] 'agreement among raft nodes before linearized reading' (duration: 987.459121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:25.764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:24.776276Z","time spent":"987.714986ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T23:16:25.764124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:24.77548Z","time spent":"987.84794ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:25.764031Z","caller":"traceutil/trace.go:171","msg":"trace[8587891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"526.005857ms","start":"2026-07-06T23:16:25.237974Z","end":"2026-07-06T23:16:25.76398Z","steps":["trace[8587891] 'agreement among raft nodes before linearized reading' (duration: 525.832923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:25.764275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:25.237918Z","time spent":"526.341685ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T23:16:38.826413Z","caller":"traceutil/trace.go:171","msg":"trace[1226641019] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"134.751552ms","start":"2026-07-06T23:16:38.691615Z","end":"2026-07-06T23:16:38.826367Z","steps":["trace[1226641019] 'process raft request' (duration: 134.539517ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:44.061388Z","caller":"traceutil/trace.go:171","msg":"trace[1504915641] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"123.320535ms","start":"2026-07-06T23:16:43.938034Z","end":"2026-07-06T23:16:44.061354Z","steps":["trace[1504915641] 'process raft request' (duration: 44.04031ms)","trace[1504915641] 'compare' (duration: 79.07701ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:44.061664Z","caller":"traceutil/trace.go:171","msg":"trace[1727568158] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"123.589832ms","start":"2026-07-06T23:16:43.938064Z","end":"2026-07-06T23:16:44.061653Z","steps":["trace[1727568158] 'process raft request' (duration: 123.242634ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:44.807578Z","caller":"traceutil/trace.go:171","msg":"trace[435244290] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"326.21712ms","start":"2026-07-06T23:16:44.481334Z","end":"2026-07-06T23:16:44.807551Z","steps":["trace[435244290] 'process raft request' (duration: 326.019396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:44.807734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:44.481323Z","time spent":"326.330683ms","remote":"127.0.0.1:51610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:45.061673Z","caller":"traceutil/trace.go:171","msg":"trace[1923744654] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"266.276132ms","start":"2026-07-06T23:16:44.795366Z","end":"2026-07-06T23:16:45.061642Z","steps":["trace[1923744654] 'process raft request' (duration: 218.548668ms)","trace[1923744654] 'compare' (duration: 47.459518ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:45.061752Z","caller":"traceutil/trace.go:171","msg":"trace[1694180682] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"185.603936ms","start":"2026-07-06T23:16:44.876126Z","end":"2026-07-06T23:16:45.06173Z","steps":["trace[1694180682] 'process raft request' (duration: 185.516244ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:45.061869Z","caller":"traceutil/trace.go:171","msg":"trace[705769976] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"250.359254ms","start":"2026-07-06T23:16:44.811483Z","end":"2026-07-06T23:16:45.061842Z","steps":["trace[705769976] 'read index received' (duration: 202.670441ms)","trace[705769976] 'applied index is now lower than readState.Index' (duration: 47.686013ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:45.062132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.627639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-06T23:16:45.062193Z","caller":"traceutil/trace.go:171","msg":"trace[468556578] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1329; }","duration":"250.736263ms","start":"2026-07-06T23:16:44.811443Z","end":"2026-07-06T23:16:45.062179Z","steps":["trace[468556578] 'agreement among raft nodes before linearized reading' (duration: 250.58276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:45.062219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.182601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:16:45.062259Z","caller":"traceutil/trace.go:171","msg":"trace[852911624] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1329; }","duration":"115.277684ms","start":"2026-07-06T23:16:44.946973Z","end":"2026-07-06T23:16:45.06225Z","steps":["trace[852911624] 'agreement among raft nodes before linearized reading' (duration: 115.11241ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:45.184688Z","caller":"traceutil/trace.go:171","msg":"trace[1007698920] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"118.847985ms","start":"2026-07-06T23:16:45.06582Z","end":"2026-07-06T23:16:45.184668Z","steps":["trace[1007698920] 'process raft request' (duration: 115.735555ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:45.184947Z","caller":"traceutil/trace.go:171","msg":"trace[1739177963] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"118.982498ms","start":"2026-07-06T23:16:45.065956Z","end":"2026-07-06T23:16:45.184939Z","steps":["trace[1739177963] 'process raft request' (duration: 118.62756ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:45.184952Z","caller":"traceutil/trace.go:171","msg":"trace[829354039] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"117.887963ms","start":"2026-07-06T23:16:45.067038Z","end":"2026-07-06T23:16:45.184926Z","steps":["trace[829354039] 'process raft request' (duration: 117.585016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:54.547119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.742575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:54.547295Z","caller":"traceutil/trace.go:171","msg":"trace[1546881724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1407; }","duration":"308.985771ms","start":"2026-07-06T23:16:54.238289Z","end":"2026-07-06T23:16:54.547275Z","steps":["trace[1546881724] 'range keys from in-memory index tree' (duration: 308.663983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:54.547332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:54.238265Z","time spent":"309.059012ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T23:16:54.547076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.979444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-06T23:16:54.547409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.37145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-06T23:16:54.547494Z","caller":"traceutil/trace.go:171","msg":"trace[327502554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1407; }","duration":"441.498092ms","start":"2026-07-06T23:16:54.105976Z","end":"2026-07-06T23:16:54.547474Z","steps":["trace[327502554] 'range keys from in-memory index tree' (duration: 441.071333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:54.547538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:54.105965Z","time spent":"441.564204ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T23:16:54.548172Z","caller":"traceutil/trace.go:171","msg":"trace[1138295361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1407; }","duration":"446.303141ms","start":"2026-07-06T23:16:54.101069Z","end":"2026-07-06T23:16:54.547372Z","steps":["trace[1138295361] 'range keys from in-memory index tree' (duration: 445.819759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:54.548252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:54.100997Z","time spent":"447.239591ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T23:16:55.192092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.846087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:16:55.192522Z","caller":"traceutil/trace.go:171","msg":"trace[1680458018] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"101.847214ms","start":"2026-07-06T23:16:55.090656Z","end":"2026-07-06T23:16:55.192503Z","steps":["trace[1680458018] 'process raft request' (duration: 101.774122ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:55.192512Z","caller":"traceutil/trace.go:171","msg":"trace[1912248658] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"638.652952ms","start":"2026-07-06T23:16:54.553779Z","end":"2026-07-06T23:16:55.192432Z","steps":["trace[1912248658] 'process raft request' (duration: 98.373266ms)","trace[1912248658] 'compare' (duration: 539.596671ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:55.192706Z","caller":"traceutil/trace.go:171","msg":"trace[963707897] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"238.521729ms","start":"2026-07-06T23:16:54.954143Z","end":"2026-07-06T23:16:55.192664Z","steps":["trace[963707897] 'read index received' (duration: 32.871µs)","trace[963707897] 'applied index is now lower than readState.Index' (duration: 238.484808ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:55.192793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:54.553754Z","time spent":"638.888047ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:16:55.193181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.00384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-06T23:16:55.193237Z","caller":"traceutil/trace.go:171","msg":"trace[1139241874] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1410; }","duration":"239.124893ms","start":"2026-07-06T23:16:54.954095Z","end":"2026-07-06T23:16:55.19322Z","steps":["trace[1139241874] 'agreement among raft nodes before linearized reading' (duration: 238.807046ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:17:19.179383Z","caller":"traceutil/trace.go:171","msg":"trace[487787844] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"260.695204ms","start":"2026-07-06T23:17:18.918669Z","end":"2026-07-06T23:17:19.179364Z","steps":["trace[487787844] 'process raft request' (duration: 260.417917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:19.945662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.711145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-06T23:18:19.945726Z","caller":"traceutil/trace.go:171","msg":"trace[904390717] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1796; }","duration":"636.818237ms","start":"2026-07-06T23:18:19.308895Z","end":"2026-07-06T23:18:19.945714Z","steps":["trace[904390717] 'range keys from in-memory index tree' (duration: 636.49458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:19.94575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:19.308874Z","time spent":"636.870959ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-06T23:18:19.945869Z","caller":"traceutil/trace.go:171","msg":"trace[2028410126] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"436.496006ms","start":"2026-07-06T23:18:19.509367Z","end":"2026-07-06T23:18:19.945863Z","steps":["trace[2028410126] 'process raft request' (duration: 434.692366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:19.945943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:19.509332Z","time spent":"436.555528ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:21:04.965168Z","caller":"traceutil/trace.go:171","msg":"trace[749516157] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"111.354661ms","start":"2026-07-06T23:21:04.853761Z","end":"2026-07-06T23:21:04.965115Z","steps":["trace[749516157] 'process raft request' (duration: 111.049004ms)"],"step_count":1}