{"level":"warn","ts":"2026-08-15T02:38:31.55941Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:38:31.559507Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.130:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.130:2380","--initial-cluster=instance=https://199.19.213.130:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.130:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.130: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-08-15T02:38:31.559581Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:38:31.559598Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-08-15T02:38:31.559619Z","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-08-15T02:38:31.56045Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"]} {"level":"info","ts":"2026-08-15T02:38:31.560556Z","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.19.213.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.130: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-08-15T02:38:31.568721Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.593156ms"} {"level":"info","ts":"2026-08-15T02:38:31.579879Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a727d7e7ce5fe36a","cluster-id":"db8a5e7d54164af4"} {"level":"info","ts":"2026-08-15T02:38:31.580119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=()"} {"level":"info","ts":"2026-08-15T02:38:31.580159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 0"} {"level":"info","ts":"2026-08-15T02:38:31.580188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a727d7e7ce5fe36a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-15T02:38:31.580212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 1"} {"level":"info","ts":"2026-08-15T02:38:31.580267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"warn","ts":"2026-08-15T02:38:31.595525Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-15T02:38:31.597159Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-15T02:38:31.598636Z","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-08-15T02:38:31.603052Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a727d7e7ce5fe36a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-15T02:38:31.603254Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a727d7e7ce5fe36a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-15T02:38:31.603431Z","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-08-15T02:38:31.607308Z","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-08-15T02:38:31.607329Z","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-08-15T02:38:31.611761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"info","ts":"2026-08-15T02:38:31.611951Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","added-peer-id":"a727d7e7ce5fe36a","added-peer-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-08-15T02:38:31.615994Z","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-08-15T02:38:31.61614Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-08-15T02:38:31.616181Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-08-15T02:38:31.616915Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a727d7e7ce5fe36a","initial-advertise-peer-urls":["https://199.19.213.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-15T02:38:31.616952Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-15T02:38:32.181203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a is starting a new election at term 1"} {"level":"info","ts":"2026-08-15T02:38:32.181249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became pre-candidate at term 1"} {"level":"info","ts":"2026-08-15T02:38:32.181275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgPreVoteResp from a727d7e7ce5fe36a at term 1"} {"level":"info","ts":"2026-08-15T02:38:32.181288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became candidate at term 2"} {"level":"info","ts":"2026-08-15T02:38:32.181296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgVoteResp from a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-08-15T02:38:32.18131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became leader at term 2"} {"level":"info","ts":"2026-08-15T02:38:32.181321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a727d7e7ce5fe36a elected leader a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-08-15T02:38:32.182369Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a727d7e7ce5fe36a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.130:2379]}","request-path":"/0/members/a727d7e7ce5fe36a/attributes","cluster-id":"db8a5e7d54164af4","publish-timeout":"7s"} {"level":"info","ts":"2026-08-15T02:38:32.182413Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:38:32.182783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:38:32.184715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-15T02:38:32.184992Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:38:32.185652Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.130:2379"} {"level":"info","ts":"2026-08-15T02:38:32.186212Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:38:32.186314Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:38:32.186344Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:38:32.18646Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-15T02:38:32.186488Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-08-15T02:38:35.030894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111225050,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-15T02:38:35.390957Z","caller":"traceutil/trace.go:171","msg":"trace[1536822280] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"860.144482ms","start":"2026-08-15T02:38:34.530784Z","end":"2026-08-15T02:38:35.390929Z","steps":["trace[1536822280] 'read index received' (duration: 859.908177ms)","trace[1536822280] 'applied index is now lower than readState.Index' (duration: 235.385µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:38:35.391042Z","caller":"traceutil/trace.go:171","msg":"trace[282868114] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"861.037294ms","start":"2026-08-15T02:38:34.529977Z","end":"2026-08-15T02:38:35.391014Z","steps":["trace[282868114] 'process raft request' (duration: 860.764818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:38:35.391379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.836301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-15T02:38:35.391492Z","caller":"traceutil/trace.go:171","msg":"trace[213377190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"748.007336ms","start":"2026-08-15T02:38:34.643463Z","end":"2026-08-15T02:38:35.391471Z","steps":["trace[213377190] 'agreement among raft nodes before linearized reading' (duration: 747.755769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:38:35.391503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.673176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-15T02:38:35.391678Z","caller":"traceutil/trace.go:171","msg":"trace[1761376649] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:74; }","duration":"860.928412ms","start":"2026-08-15T02:38:34.530732Z","end":"2026-08-15T02:38:35.39166Z","steps":["trace[1761376649] 'agreement among raft nodes before linearized reading' (duration: 860.43248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:38:35.391534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:38:34.64345Z","time spent":"748.075477ms","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:38:35.391831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:38:34.530709Z","time spent":"861.083526ms","remote":"127.0.0.1:51340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-08-15T02:38:35.391993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:38:34.529948Z","time spent":"861.214649ms","remote":"127.0.0.1:51356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:38:36.111564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.268767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-15T02:38:36.11161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.281402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-15T02:38:36.111659Z","caller":"traceutil/trace.go:171","msg":"trace[1370091489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:75; }","duration":"440.38886ms","start":"2026-08-15T02:38:35.67125Z","end":"2026-08-15T02:38:36.111639Z","steps":["trace[1370091489] 'range keys from in-memory index tree' (duration: 440.125662ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:38:36.111687Z","caller":"traceutil/trace.go:171","msg":"trace[1395418310] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:75; }","duration":"690.407615ms","start":"2026-08-15T02:38:35.42126Z","end":"2026-08-15T02:38:36.111667Z","steps":["trace[1395418310] 'range keys from in-memory index tree' (duration: 690.21417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:38:36.11171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:38:35.671174Z","time spent":"440.526894ms","remote":"127.0.0.1:51248","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-08-15T02:38:36.111731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:38:35.421246Z","time spent":"690.477926ms","remote":"127.0.0.1:51326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-08-15T02:38:36.111927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.629392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-15T02:38:36.11212Z","caller":"traceutil/trace.go:171","msg":"trace[160742764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"467.214246ms","start":"2026-08-15T02:38:35.644886Z","end":"2026-08-15T02:38:36.1121Z","steps":["trace[160742764] 'range keys from in-memory index tree' (duration: 466.441348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:38:36.112287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:38:35.644841Z","time spent":"467.426132ms","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:38:36.382803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.353047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-15T02:38:36.383014Z","caller":"traceutil/trace.go:171","msg":"trace[81715343] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"265.975666ms","start":"2026-08-15T02:38:36.117021Z","end":"2026-08-15T02:38:36.382996Z","steps":["trace[81715343] 'process raft request' (duration: 265.894324ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:38:36.383044Z","caller":"traceutil/trace.go:171","msg":"trace[394435504] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"269.535334ms","start":"2026-08-15T02:38:36.11345Z","end":"2026-08-15T02:38:36.382985Z","steps":["trace[394435504] 'process raft request' (duration: 157.797947ms)","trace[394435504] 'compare' (duration: 111.166682ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:38:36.383282Z","caller":"traceutil/trace.go:171","msg":"trace[166334055] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"268.761015ms","start":"2026-08-15T02:38:36.114511Z","end":"2026-08-15T02:38:36.383272Z","steps":["trace[166334055] 'read index received' (duration: 156.749111ms)","trace[166334055] 'applied index is now lower than readState.Index' (duration: 112.010784ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:38:36.383407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.889068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-15T02:38:36.383433Z","caller":"traceutil/trace.go:171","msg":"trace[1005769458] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:77; }","duration":"268.953079ms","start":"2026-08-15T02:38:36.114471Z","end":"2026-08-15T02:38:36.383424Z","steps":["trace[1005769458] 'agreement among raft nodes before linearized reading' (duration: 268.838787ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:38:53.466908Z","caller":"traceutil/trace.go:171","msg":"trace[1369254042] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"135.67136ms","start":"2026-08-15T02:38:53.331204Z","end":"2026-08-15T02:38:53.466875Z","steps":["trace[1369254042] 'process raft request' (duration: 135.494306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.366534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111226186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:39:41.546069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:41.12813Z","time spent":"417.930891ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-15T02:39:41.547645Z","caller":"traceutil/trace.go:171","msg":"trace[2099115209] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"791.672621ms","start":"2026-08-15T02:39:40.755949Z","end":"2026-08-15T02:39:41.547622Z","steps":["trace[2099115209] 'process raft request' (duration: 791.557598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.547819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.755923Z","time spent":"791.833085ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:39:41.557571Z","caller":"traceutil/trace.go:171","msg":"trace[830905114] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"793.904497ms","start":"2026-08-15T02:39:40.763632Z","end":"2026-08-15T02:39:41.557536Z","steps":["trace[830905114] 'process raft request' (duration: 793.545889ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:41.557591Z","caller":"traceutil/trace.go:171","msg":"trace[820000206] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"784.27108ms","start":"2026-08-15T02:39:40.773272Z","end":"2026-08-15T02:39:41.557543Z","steps":["trace[820000206] 'process raft request' (duration: 784.137916ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:41.557721Z","caller":"traceutil/trace.go:171","msg":"trace[1231788534] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"794.151473ms","start":"2026-08-15T02:39:40.763544Z","end":"2026-08-15T02:39:41.557696Z","steps":["trace[1231788534] 'process raft request' (duration: 793.525478ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:41.557837Z","caller":"traceutil/trace.go:171","msg":"trace[460953539] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"780.18612ms","start":"2026-08-15T02:39:40.777643Z","end":"2026-08-15T02:39:41.557829Z","steps":["trace[460953539] 'process raft request' (duration: 779.911583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.557887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.763524Z","time spent":"794.296916ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:39:41.557874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.777639Z","time spent":"780.22016ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:39:41.557963Z","caller":"traceutil/trace.go:171","msg":"trace[1451143718] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"783.489951ms","start":"2026-08-15T02:39:40.774464Z","end":"2026-08-15T02:39:41.557954Z","steps":["trace[1451143718] 'process raft request' (duration: 782.987178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.557744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.763627Z","time spent":"794.00328ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:39:41.557837Z","caller":"traceutil/trace.go:171","msg":"trace[1118386245] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"780.20689ms","start":"2026-08-15T02:39:40.777593Z","end":"2026-08-15T02:39:41.5578Z","steps":["trace[1118386245] 'process raft request' (duration: 779.920373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.55812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.777587Z","time spent":"780.514627ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:39:41.558128Z","caller":"traceutil/trace.go:171","msg":"trace[713055151] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:622; }","duration":"691.992127ms","start":"2026-08-15T02:39:40.86611Z","end":"2026-08-15T02:39:41.558102Z","steps":["trace[713055151] 'read index received' (duration: 682.185635ms)","trace[713055151] 'applied index is now lower than readState.Index' (duration: 9.804862ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:39:41.55822Z","caller":"traceutil/trace.go:171","msg":"trace[102250186] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"733.238953ms","start":"2026-08-15T02:39:40.824976Z","end":"2026-08-15T02:39:41.558215Z","steps":["trace[102250186] 'process raft request' (duration: 733.030408ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:41.558187Z","caller":"traceutil/trace.go:171","msg":"trace[2084222447] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"771.122895ms","start":"2026-08-15T02:39:40.787054Z","end":"2026-08-15T02:39:41.558177Z","steps":["trace[2084222447] 'process raft request' (duration: 770.565601ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:41.558229Z","caller":"traceutil/trace.go:171","msg":"trace[2024234758] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"771.284019ms","start":"2026-08-15T02:39:40.786938Z","end":"2026-08-15T02:39:41.558222Z","steps":["trace[2024234758] 'process raft request' (duration: 770.647204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.558105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.774456Z","time spent":"783.557602ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:39:41.558266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.787035Z","time spent":"771.211228ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:39:41.558199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.079359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:39:41.558333Z","caller":"traceutil/trace.go:171","msg":"trace[41565652] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:606; }","duration":"692.260734ms","start":"2026-08-15T02:39:40.866065Z","end":"2026-08-15T02:39:41.558326Z","steps":["trace[41565652] 'agreement among raft nodes before linearized reading' (duration: 692.079499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.558357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.866046Z","time spent":"692.303895ms","remote":"127.0.0.1:51378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:39:41.558264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.824958Z","time spent":"733.283435ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:39:41.558263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.786918Z","time spent":"771.330431ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:39:41.557783Z","caller":"traceutil/trace.go:171","msg":"trace[1492577026] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"783.233375ms","start":"2026-08-15T02:39:40.774521Z","end":"2026-08-15T02:39:41.557754Z","steps":["trace[1492577026] 'process raft request' (duration: 782.961998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.558677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.774517Z","time spent":"784.116906ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:39:41.558814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.773252Z","time spent":"784.399002ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:39:41.558283Z","caller":"traceutil/trace.go:171","msg":"trace[1789829395] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"618.114949ms","start":"2026-08-15T02:39:40.940163Z","end":"2026-08-15T02:39:41.558278Z","steps":["trace[1789829395] 'process raft request' (duration: 617.889044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.559212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.940139Z","time spent":"619.052362ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:39:41.563427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.747162ms","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-08-15T02:39:41.563466Z","caller":"traceutil/trace.go:171","msg":"trace[761647142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"572.824194ms","start":"2026-08-15T02:39:40.990633Z","end":"2026-08-15T02:39:41.563457Z","steps":["trace[761647142] 'agreement among raft nodes before linearized reading' (duration: 572.718381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.563493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:40.990619Z","time spent":"572.868595ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-15T02:39:41.563732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.912627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:39:41.563765Z","caller":"traceutil/trace.go:171","msg":"trace[690092088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"291.981819ms","start":"2026-08-15T02:39:41.271774Z","end":"2026-08-15T02:39:41.563755Z","steps":["trace[690092088] 'agreement among raft nodes before linearized reading' (duration: 291.936798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.563872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.924541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:39:41.563897Z","caller":"traceutil/trace.go:171","msg":"trace[1125672828] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:608; }","duration":"508.998162ms","start":"2026-08-15T02:39:41.054892Z","end":"2026-08-15T02:39:41.56389Z","steps":["trace[1125672828] 'agreement among raft nodes before linearized reading' (duration: 508.960322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:41.563919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:41.05487Z","time spent":"509.041363ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:39:44.769604Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111226348,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:39:45.223066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.800816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:39:45.223232Z","caller":"traceutil/trace.go:171","msg":"trace[658812835] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"1.023995651s","start":"2026-08-15T02:39:44.199212Z","end":"2026-08-15T02:39:45.223208Z","steps":["trace[658812835] 'process raft request' (duration: 750.974559ms)","trace[658812835] 'compare' (duration: 272.53095ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:39:45.223306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:44.199182Z","time spent":"1.024092543s","remote":"127.0.0.1:51058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:39:45.42916Z","caller":"traceutil/trace.go:171","msg":"trace[703255255] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"1.228813144s","start":"2026-08-15T02:39:44.200317Z","end":"2026-08-15T02:39:45.429131Z","steps":["trace[703255255] 'process raft request' (duration: 1.228531298s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:45.429351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:44.200297Z","time spent":"1.228969819s","remote":"127.0.0.1:51166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:39:45.42996Z","caller":"traceutil/trace.go:171","msg":"trace[268657344] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:708; }","duration":"1.161121267s","start":"2026-08-15T02:39:44.268819Z","end":"2026-08-15T02:39:45.42994Z","steps":["trace[268657344] 'read index received' (duration: 681.519249ms)","trace[268657344] 'applied index is now lower than readState.Index' (duration: 479.601128ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:39:45.430001Z","caller":"traceutil/trace.go:171","msg":"trace[1335787730] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"820.527732ms","start":"2026-08-15T02:39:44.609439Z","end":"2026-08-15T02:39:45.429966Z","steps":["trace[1335787730] 'process raft request' (duration: 820.0473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:45.430502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16166633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:39:45.430591Z","caller":"traceutil/trace.go:171","msg":"trace[1617638565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"1.161756083s","start":"2026-08-15T02:39:44.26878Z","end":"2026-08-15T02:39:45.430536Z","steps":["trace[1617638565] 'agreement among raft nodes before linearized reading' (duration: 1.161270751s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:45.430622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:44.268764Z","time spent":"1.161851846s","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:39:45.430073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:44.609406Z","time spent":"820.633375ms","remote":"127.0.0.1:51056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:39:45.430978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.754343ms","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-08-15T02:39:45.43101Z","caller":"traceutil/trace.go:171","msg":"trace[1480997211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"726.836115ms","start":"2026-08-15T02:39:44.704162Z","end":"2026-08-15T02:39:45.430998Z","steps":["trace[1480997211] 'agreement among raft nodes before linearized reading' (duration: 726.669111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:45.431045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:44.704146Z","time spent":"726.891176ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-15T02:39:45.431449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.581345ms","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":"info","ts":"2026-08-15T02:39:45.43149Z","caller":"traceutil/trace.go:171","msg":"trace[1525471880] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:680; }","duration":"156.635837ms","start":"2026-08-15T02:39:45.274844Z","end":"2026-08-15T02:39:45.43148Z","steps":["trace[1525471880] 'agreement among raft nodes before linearized reading' (duration: 156.556665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:45.431608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.574212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:39:45.431957Z","caller":"traceutil/trace.go:171","msg":"trace[1831626879] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:680; }","duration":"299.002132ms","start":"2026-08-15T02:39:45.132916Z","end":"2026-08-15T02:39:45.431918Z","steps":["trace[1831626879] 'agreement among raft nodes before linearized reading' (duration: 298.559201ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:46.957878Z","caller":"traceutil/trace.go:171","msg":"trace[106849370] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"104.754289ms","start":"2026-08-15T02:39:46.853102Z","end":"2026-08-15T02:39:46.957856Z","steps":["trace[106849370] 'process raft request' (duration: 103.328134ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:47.752942Z","caller":"traceutil/trace.go:171","msg":"trace[901374685] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"303.492682ms","start":"2026-08-15T02:39:47.449425Z","end":"2026-08-15T02:39:47.752917Z","steps":["trace[901374685] 'read index received' (duration: 302.685953ms)","trace[901374685] 'applied index is now lower than readState.Index' (duration: 805.769µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:39:47.753125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.683866ms","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-08-15T02:39:47.753169Z","caller":"traceutil/trace.go:171","msg":"trace[1100273589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"303.791629ms","start":"2026-08-15T02:39:47.449366Z","end":"2026-08-15T02:39:47.753157Z","steps":["trace[1100273589] 'agreement among raft nodes before linearized reading' (duration: 303.693826ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:47.753022Z","caller":"traceutil/trace.go:171","msg":"trace[445461161] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"325.263218ms","start":"2026-08-15T02:39:47.427733Z","end":"2026-08-15T02:39:47.752996Z","steps":["trace[445461161] 'process raft request' (duration: 324.615812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:47.753184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.832688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-15T02:39:47.753215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:47.449343Z","time spent":"303.863641ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-15T02:39:47.753268Z","caller":"traceutil/trace.go:171","msg":"trace[1518801130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"138.971351ms","start":"2026-08-15T02:39:47.614274Z","end":"2026-08-15T02:39:47.753246Z","steps":["trace[1518801130] 'agreement among raft nodes before linearized reading' (duration: 138.809657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:47.754612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:47.427694Z","time spent":"325.521574ms","remote":"127.0.0.1:51056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:39:49.387457Z","caller":"traceutil/trace.go:171","msg":"trace[2141058896] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"116.091769ms","start":"2026-08-15T02:39:49.271345Z","end":"2026-08-15T02:39:49.387437Z","steps":["trace[2141058896] 'read index received' (duration: 115.538005ms)","trace[2141058896] 'applied index is now lower than readState.Index' (duration: 552.853µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:39:49.387657Z","caller":"traceutil/trace.go:171","msg":"trace[1075471199] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"149.8412ms","start":"2026-08-15T02:39:49.237695Z","end":"2026-08-15T02:39:49.387536Z","steps":["trace[1075471199] 'process raft request' (duration: 149.350788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:49.387663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.295854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:39:49.387844Z","caller":"traceutil/trace.go:171","msg":"trace[1007616963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"116.54974ms","start":"2026-08-15T02:39:49.27128Z","end":"2026-08-15T02:39:49.38783Z","steps":["trace[1007616963] 'agreement among raft nodes before linearized reading' (duration: 116.304614ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:49.718705Z","caller":"traceutil/trace.go:171","msg":"trace[329056641] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"117.846322ms","start":"2026-08-15T02:39:49.600835Z","end":"2026-08-15T02:39:49.718681Z","steps":["trace[329056641] 'process raft request' (duration: 117.676108ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:49.718759Z","caller":"traceutil/trace.go:171","msg":"trace[733550379] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"115.301199ms","start":"2026-08-15T02:39:49.603437Z","end":"2026-08-15T02:39:49.718738Z","steps":["trace[733550379] 'process raft request' (duration: 115.251938ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:49.719107Z","caller":"traceutil/trace.go:171","msg":"trace[1548116878] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"118.099777ms","start":"2026-08-15T02:39:49.600941Z","end":"2026-08-15T02:39:49.719041Z","steps":["trace[1548116878] 'process raft request' (duration: 117.687477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:50.309355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.799239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:39:50.309473Z","caller":"traceutil/trace.go:171","msg":"trace[1032275953] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"467.522201ms","start":"2026-08-15T02:39:49.841934Z","end":"2026-08-15T02:39:50.309457Z","steps":["trace[1032275953] 'read index received' (duration: 228.511367ms)","trace[1032275953] 'applied index is now lower than readState.Index' (duration: 239.009754ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:39:50.309546Z","caller":"traceutil/trace.go:171","msg":"trace[967237598] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"533.558467ms","start":"2026-08-15T02:39:49.775975Z","end":"2026-08-15T02:39:50.309533Z","steps":["trace[967237598] 'process raft request' (duration: 294.493781ms)","trace[967237598] 'compare' (duration: 238.608185ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:39:50.30961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:49.775953Z","time spent":"533.626409ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:39:50.309833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.88483ms","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-08-15T02:39:50.309975Z","caller":"traceutil/trace.go:171","msg":"trace[570528197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"468.058274ms","start":"2026-08-15T02:39:49.841855Z","end":"2026-08-15T02:39:50.309913Z","steps":["trace[570528197] 'agreement among raft nodes before linearized reading' (duration: 467.747787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:50.310096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:49.841834Z","time spent":"468.249568ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-15T02:39:50.340968Z","caller":"traceutil/trace.go:171","msg":"trace[914968043] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"302.230411ms","start":"2026-08-15T02:39:50.038713Z","end":"2026-08-15T02:39:50.340944Z","steps":["trace[914968043] 'process raft request' (duration: 302.125928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:50.341209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.53367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-15T02:39:50.34129Z","caller":"traceutil/trace.go:171","msg":"trace[808567819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:715; }","duration":"220.621022ms","start":"2026-08-15T02:39:50.120649Z","end":"2026-08-15T02:39:50.34127Z","steps":["trace[808567819] 'agreement among raft nodes before linearized reading' (duration: 220.383576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:50.341258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.376871ms","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-08-15T02:39:50.341353Z","caller":"traceutil/trace.go:171","msg":"trace[367674840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:715; }","duration":"468.501905ms","start":"2026-08-15T02:39:49.872833Z","end":"2026-08-15T02:39:50.341335Z","steps":["trace[367674840] 'agreement among raft nodes before linearized reading' (duration: 468.182247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:50.341372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:50.03869Z","time spent":"302.394335ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:39:50.34145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:49.872817Z","time spent":"468.592766ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-15T02:39:54.56491Z","caller":"traceutil/trace.go:171","msg":"trace[545254397] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"411.85505ms","start":"2026-08-15T02:39:54.153038Z","end":"2026-08-15T02:39:54.564893Z","steps":["trace[545254397] 'process raft request' (duration: 411.774758ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:54.564917Z","caller":"traceutil/trace.go:171","msg":"trace[1209803935] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:777; }","duration":"293.869636ms","start":"2026-08-15T02:39:54.271019Z","end":"2026-08-15T02:39:54.564889Z","steps":["trace[1209803935] 'read index received' (duration: 293.859875ms)","trace[1209803935] 'applied index is now lower than readState.Index' (duration: 7.971µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:39:54.565035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:54.153022Z","time spent":"411.956543ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:39:54.565139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.087471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:39:54.565221Z","caller":"traceutil/trace.go:171","msg":"trace[664872397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"294.221804ms","start":"2026-08-15T02:39:54.270977Z","end":"2026-08-15T02:39:54.565199Z","steps":["trace[664872397] 'agreement among raft nodes before linearized reading' (duration: 293.971228ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:54.572118Z","caller":"traceutil/trace.go:171","msg":"trace[835642685] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"219.281319ms","start":"2026-08-15T02:39:54.352821Z","end":"2026-08-15T02:39:54.572103Z","steps":["trace[835642685] 'process raft request' (duration: 219.081444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:54.572139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.521949ms","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-08-15T02:39:54.572176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.288314ms","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-08-15T02:39:54.572193Z","caller":"traceutil/trace.go:171","msg":"trace[659732601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"250.60867ms","start":"2026-08-15T02:39:54.321569Z","end":"2026-08-15T02:39:54.572178Z","steps":["trace[659732601] 'agreement among raft nodes before linearized reading' (duration: 250.446865ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:54.572212Z","caller":"traceutil/trace.go:171","msg":"trace[1010838231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"196.362135ms","start":"2026-08-15T02:39:54.375839Z","end":"2026-08-15T02:39:54.572201Z","steps":["trace[1010838231] 'agreement among raft nodes before linearized reading' (duration: 196.19965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:54.57223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.288612ms","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-08-15T02:39:54.572307Z","caller":"traceutil/trace.go:171","msg":"trace[1139241039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"198.402525ms","start":"2026-08-15T02:39:54.373882Z","end":"2026-08-15T02:39:54.572284Z","steps":["trace[1139241039] 'agreement among raft nodes before linearized reading' (duration: 198.181809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:54.572329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.408385ms","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-08-15T02:39:54.572353Z","caller":"traceutil/trace.go:171","msg":"trace[1318934682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"213.446945ms","start":"2026-08-15T02:39:54.358898Z","end":"2026-08-15T02:39:54.572345Z","steps":["trace[1318934682] 'agreement among raft nodes before linearized reading' (duration: 213.375174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:07.650618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111226748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:40:08.15077Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111226748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:40:08.466911Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.342019791s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:40:08.46757Z","caller":"traceutil/trace.go:171","msg":"trace[367435342] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"1.317000495s","start":"2026-08-15T02:40:07.150506Z","end":"2026-08-15T02:40:08.467507Z","steps":["trace[367435342] 'read index received' (duration: 1.316616856s)","trace[367435342] 'applied index is now lower than readState.Index' (duration: 382.619µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:40:08.467849Z","caller":"traceutil/trace.go:171","msg":"trace[776200605] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"1.343003486s","start":"2026-08-15T02:40:07.124806Z","end":"2026-08-15T02:40:08.467809Z","steps":["trace[776200605] 'process raft request' (duration: 1.342387731s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:08.467886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195890563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:40:08.467999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.125135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-15T02:40:08.468131Z","caller":"traceutil/trace.go:171","msg":"trace[74897475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:875; }","duration":"457.34462ms","start":"2026-08-15T02:40:08.010767Z","end":"2026-08-15T02:40:08.468111Z","steps":["trace[74897475] 'agreement among raft nodes before linearized reading' (duration: 457.090134ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:08.468002Z","caller":"traceutil/trace.go:171","msg":"trace[1147956243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"1.196060348s","start":"2026-08-15T02:40:07.27192Z","end":"2026-08-15T02:40:08.46798Z","steps":["trace[1147956243] 'agreement among raft nodes before linearized reading' (duration: 1.195874403s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:08.468243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:08.010742Z","time spent":"457.483704ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-15T02:40:08.468046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.020598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-15T02:40:08.468343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:07.2719Z","time spent":"1.196405086s","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-15T02:40:08.468417Z","caller":"traceutil/trace.go:171","msg":"trace[1327028311] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:875; }","duration":"599.456939ms","start":"2026-08-15T02:40:07.868946Z","end":"2026-08-15T02:40:08.468403Z","steps":["trace[1327028311] 'agreement among raft nodes before linearized reading' (duration: 598.959487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:08.468466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:07.868916Z","time spent":"599.534551ms","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":93,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:40:08.468557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31801596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.130\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-15T02:40:08.468729Z","caller":"traceutil/trace.go:171","msg":"trace[1118004758] range","detail":"{range_begin:/registry/masterleases/199.19.213.130; range_end:; response_count:1; response_revision:875; }","duration":"1.318205955s","start":"2026-08-15T02:40:07.150474Z","end":"2026-08-15T02:40:08.46868Z","steps":["trace[1118004758] 'agreement among raft nodes before linearized reading' (duration: 1.317217651s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:08.469062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:07.150461Z","time spent":"1.318585554s","remote":"127.0.0.1:51008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.130\" "} {"level":"warn","ts":"2026-08-15T02:40:08.468195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:07.124793Z","time spent":"1.34317501s","remote":"127.0.0.1:51152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:40:12.794816Z","caller":"traceutil/trace.go:171","msg":"trace[1834250012] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"165.287239ms","start":"2026-08-15T02:40:12.629505Z","end":"2026-08-15T02:40:12.794792Z","steps":["trace[1834250012] 'process raft request' (duration: 164.160452ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:12.933888Z","caller":"traceutil/trace.go:171","msg":"trace[1825999955] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"133.648881ms","start":"2026-08-15T02:40:12.800218Z","end":"2026-08-15T02:40:12.933867Z","steps":["trace[1825999955] 'process raft request' (duration: 133.390204ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:12.933916Z","caller":"traceutil/trace.go:171","msg":"trace[995068966] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"134.410569ms","start":"2026-08-15T02:40:12.799483Z","end":"2026-08-15T02:40:12.933894Z","steps":["trace[995068966] 'process raft request' (duration: 125.463479ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:12.934232Z","caller":"traceutil/trace.go:171","msg":"trace[575318727] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"133.926667ms","start":"2026-08-15T02:40:12.800297Z","end":"2026-08-15T02:40:12.934224Z","steps":["trace[575318727] 'process raft request' (duration: 133.363564ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:12.934243Z","caller":"traceutil/trace.go:171","msg":"trace[1724228676] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"132.631565ms","start":"2026-08-15T02:40:12.8016Z","end":"2026-08-15T02:40:12.934231Z","steps":["trace[1724228676] 'process raft request' (duration: 132.120503ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:12.934434Z","caller":"traceutil/trace.go:171","msg":"trace[1072120034] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"133.2694ms","start":"2026-08-15T02:40:12.801157Z","end":"2026-08-15T02:40:12.934426Z","steps":["trace[1072120034] 'process raft request' (duration: 132.529652ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:12.934509Z","caller":"traceutil/trace.go:171","msg":"trace[2082286010] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"132.142003ms","start":"2026-08-15T02:40:12.802357Z","end":"2026-08-15T02:40:12.934499Z","steps":["trace[2082286010] 'process raft request' (duration: 131.809335ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:13.087955Z","caller":"traceutil/trace.go:171","msg":"trace[1906110997] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"114.053319ms","start":"2026-08-15T02:40:12.973878Z","end":"2026-08-15T02:40:13.087931Z","steps":["trace[1906110997] 'process raft request' (duration: 113.985127ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:13.088033Z","caller":"traceutil/trace.go:171","msg":"trace[1078387860] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"114.176241ms","start":"2026-08-15T02:40:12.973822Z","end":"2026-08-15T02:40:13.087998Z","steps":["trace[1078387860] 'process raft request' (duration: 70.853505ms)","trace[1078387860] 'compare' (duration: 43.031269ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:40:13.235329Z","caller":"traceutil/trace.go:171","msg":"trace[167200581] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"140.51436ms","start":"2026-08-15T02:40:13.094778Z","end":"2026-08-15T02:40:13.235293Z","steps":["trace[167200581] 'process raft request' (duration: 139.418073ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:13.235462Z","caller":"traceutil/trace.go:171","msg":"trace[296179] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:956; }","duration":"139.324441ms","start":"2026-08-15T02:40:13.096103Z","end":"2026-08-15T02:40:13.235427Z","steps":["trace[296179] 'read index received' (duration: 43.110921ms)","trace[296179] 'applied index is now lower than readState.Index' (duration: 96.211029ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:40:13.235728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.600757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-08-15T02:40:13.235715Z","caller":"traceutil/trace.go:171","msg":"trace[869967821] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"140.88374ms","start":"2026-08-15T02:40:13.094796Z","end":"2026-08-15T02:40:13.23568Z","steps":["trace[869967821] 'process raft request' (duration: 140.418558ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:13.235779Z","caller":"traceutil/trace.go:171","msg":"trace[991404183] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:920; }","duration":"139.71595ms","start":"2026-08-15T02:40:13.096051Z","end":"2026-08-15T02:40:13.235767Z","steps":["trace[991404183] 'agreement among raft nodes before linearized reading' (duration: 139.479814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:18.750145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.701454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:40:18.750262Z","caller":"traceutil/trace.go:171","msg":"trace[308127435] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:998; }","duration":"112.866489ms","start":"2026-08-15T02:40:18.637371Z","end":"2026-08-15T02:40:18.750238Z","steps":["trace[308127435] 'count revisions from in-memory index tree' (duration: 112.603082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:24.769745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111227044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:40:25.270286Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111227044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:40:25.676681Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.127913601s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:40:25.67727Z","caller":"traceutil/trace.go:171","msg":"trace[872962586] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"2.12867278s","start":"2026-08-15T02:40:23.548573Z","end":"2026-08-15T02:40:25.677246Z","steps":["trace[872962586] 'process raft request' (duration: 2.128408313s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:25.677426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:23.548547Z","time spent":"2.128795332s","remote":"127.0.0.1:51152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:40:25.762756Z","caller":"traceutil/trace.go:171","msg":"trace[481534910] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1056; }","duration":"1.493088171s","start":"2026-08-15T02:40:24.269651Z","end":"2026-08-15T02:40:25.762739Z","steps":["trace[481534910] 'read index received' (duration: 1.407316789s)","trace[481534910] 'applied index is now lower than readState.Index' (duration: 85.770542ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:40:25.762818Z","caller":"traceutil/trace.go:171","msg":"trace[1420538872] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.498965166s","start":"2026-08-15T02:40:24.263816Z","end":"2026-08-15T02:40:25.762781Z","steps":["trace[1420538872] 'process raft request' (duration: 1.498818051s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:25.762904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493228804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:40:25.762952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:24.263795Z","time spent":"1.499080299s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:40:25.763007Z","caller":"traceutil/trace.go:171","msg":"trace[800114606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"1.493364068s","start":"2026-08-15T02:40:24.269614Z","end":"2026-08-15T02:40:25.762978Z","steps":["trace[800114606] 'agreement among raft nodes before linearized reading' (duration: 1.493196224s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:25.76306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:24.269597Z","time spent":"1.49345403s","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:40:25.769803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.280837ms","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-08-15T02:40:25.769842Z","caller":"traceutil/trace.go:171","msg":"trace[1287792756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"764.351619ms","start":"2026-08-15T02:40:25.00548Z","end":"2026-08-15T02:40:25.769832Z","steps":["trace[1287792756] 'agreement among raft nodes before linearized reading' (duration: 764.212896ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:25.769865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:25.005462Z","time spent":"764.39812ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-15T02:40:25.769858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.703724ms","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-08-15T02:40:25.769919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153199543s","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-08-15T02:40:25.769935Z","caller":"traceutil/trace.go:171","msg":"trace[1559520407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"763.840087ms","start":"2026-08-15T02:40:25.006074Z","end":"2026-08-15T02:40:25.769914Z","steps":["trace[1559520407] 'agreement among raft nodes before linearized reading' (duration: 763.613152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:25.769952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.358382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-15T02:40:25.769982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:25.006054Z","time spent":"763.918069ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-15T02:40:25.770014Z","caller":"traceutil/trace.go:171","msg":"trace[1504410272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"758.443403ms","start":"2026-08-15T02:40:25.011556Z","end":"2026-08-15T02:40:25.769999Z","steps":["trace[1504410272] 'agreement among raft nodes before linearized reading' (duration: 758.2911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:25.770042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:25.011546Z","time spent":"758.491215ms","remote":"127.0.0.1:51248","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-08-15T02:40:25.770053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.706257ms","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-08-15T02:40:25.770087Z","caller":"traceutil/trace.go:171","msg":"trace[1325530526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1021; }","duration":"766.738028ms","start":"2026-08-15T02:40:25.003339Z","end":"2026-08-15T02:40:25.770077Z","steps":["trace[1325530526] 'agreement among raft nodes before linearized reading' (duration: 766.656796ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:25.769972Z","caller":"traceutil/trace.go:171","msg":"trace[1465531954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.153280075s","start":"2026-08-15T02:40:24.616678Z","end":"2026-08-15T02:40:25.769958Z","steps":["trace[1465531954] 'agreement among raft nodes before linearized reading' (duration: 1.153151432s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:25.770119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:24.616661Z","time spent":"1.153452249s","remote":"127.0.0.1:51248","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-08-15T02:40:25.770115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:25.003239Z","time spent":"766.868991ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-15T02:40:27.321934Z","caller":"traceutil/trace.go:171","msg":"trace[85561677] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"168.680692ms","start":"2026-08-15T02:40:27.153238Z","end":"2026-08-15T02:40:27.321918Z","steps":["trace[85561677] 'read index received' (duration: 161.894396ms)","trace[85561677] 'applied index is now lower than readState.Index' (duration: 6.785526ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:40:27.322018Z","caller":"traceutil/trace.go:171","msg":"trace[770572015] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"444.607337ms","start":"2026-08-15T02:40:26.877404Z","end":"2026-08-15T02:40:27.322011Z","steps":["trace[770572015] 'process raft request' (duration: 437.772139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:27.322091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:26.877378Z","time spent":"444.656418ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:40:27.322404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.154715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.130\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-15T02:40:27.322466Z","caller":"traceutil/trace.go:171","msg":"trace[536302391] range","detail":"{range_begin:/registry/masterleases/199.19.213.130; range_end:; response_count:1; response_revision:1028; }","duration":"169.260757ms","start":"2026-08-15T02:40:27.153189Z","end":"2026-08-15T02:40:27.32245Z","steps":["trace[536302391] 'agreement among raft nodes before linearized reading' (duration: 169.103043ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:50.168654Z","caller":"traceutil/trace.go:171","msg":"trace[329636760] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"134.716776ms","start":"2026-08-15T02:40:50.033903Z","end":"2026-08-15T02:40:50.16862Z","steps":["trace[329636760] 'process raft request' (duration: 119.721067ms)","trace[329636760] 'compare' (duration: 14.752163ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:40:50.168674Z","caller":"traceutil/trace.go:171","msg":"trace[938646087] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"134.143943ms","start":"2026-08-15T02:40:50.034502Z","end":"2026-08-15T02:40:50.168646Z","steps":["trace[938646087] 'process raft request' (duration: 134.096762ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:50.168691Z","caller":"traceutil/trace.go:171","msg":"trace[1165944899] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"134.691206ms","start":"2026-08-15T02:40:50.033962Z","end":"2026-08-15T02:40:50.168653Z","steps":["trace[1165944899] 'process raft request' (duration: 134.588584ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:56.177905Z","caller":"traceutil/trace.go:171","msg":"trace[1554974702] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"128.697968ms","start":"2026-08-15T02:40:56.049186Z","end":"2026-08-15T02:40:56.177884Z","steps":["trace[1554974702] 'read index received' (duration: 128.475493ms)","trace[1554974702] 'applied index is now lower than readState.Index' (duration: 221.685µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:40:56.178016Z","caller":"traceutil/trace.go:171","msg":"trace[1877845535] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"169.653327ms","start":"2026-08-15T02:40:56.008328Z","end":"2026-08-15T02:40:56.177981Z","steps":["trace[1877845535] 'process raft request' (duration: 169.420811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:56.178101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.900763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-15T02:40:56.178139Z","caller":"traceutil/trace.go:171","msg":"trace[871078493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1130; }","duration":"128.978535ms","start":"2026-08-15T02:40:56.049146Z","end":"2026-08-15T02:40:56.178125Z","steps":["trace[871078493] 'agreement among raft nodes before linearized reading' (duration: 128.853932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:56.687723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111227316,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:40:57.188807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111227316,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:40:57.298243Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.116058442s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:40:57.309618Z","caller":"traceutil/trace.go:171","msg":"trace[1109157385] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"1.127487891s","start":"2026-08-15T02:40:56.18211Z","end":"2026-08-15T02:40:57.309598Z","steps":["trace[1109157385] 'process raft request' (duration: 1.116393s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:57.309743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:56.182093Z","time spent":"1.127580073s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:40:57.324588Z","caller":"traceutil/trace.go:171","msg":"trace[1941483533] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1178; }","duration":"1.136884879s","start":"2026-08-15T02:40:56.187677Z","end":"2026-08-15T02:40:57.324562Z","steps":["trace[1941483533] 'read index received' (duration: 1.110774573s)","trace[1941483533] 'applied index is now lower than readState.Index' (duration: 26.109206ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:40:57.324617Z","caller":"traceutil/trace.go:171","msg":"trace[230553596] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"1.142199469s","start":"2026-08-15T02:40:56.182389Z","end":"2026-08-15T02:40:57.324589Z","steps":["trace[230553596] 'process raft request' (duration: 1.142034235s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:57.324819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:56.182376Z","time spent":"1.142301362s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:40:57.32488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098925563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-15T02:40:57.324902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137194338s","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-08-15T02:40:57.324987Z","caller":"traceutil/trace.go:171","msg":"trace[1553400992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1132; }","duration":"1.13730961s","start":"2026-08-15T02:40:56.187658Z","end":"2026-08-15T02:40:57.324968Z","steps":["trace[1553400992] 'agreement among raft nodes before linearized reading' (duration: 1.137029403s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:57.324945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.257511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:40:57.32503Z","caller":"traceutil/trace.go:171","msg":"trace[1449938176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1132; }","duration":"1.099037227s","start":"2026-08-15T02:40:56.225876Z","end":"2026-08-15T02:40:57.324913Z","steps":["trace[1449938176] 'agreement among raft nodes before linearized reading' (duration: 1.098822552s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:57.325038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:56.187632Z","time spent":"1.137396733s","remote":"127.0.0.1:51248","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-08-15T02:40:57.32508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:56.225853Z","time spent":"1.099214921s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-15T02:40:57.32509Z","caller":"traceutil/trace.go:171","msg":"trace[825677873] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1132; }","duration":"366.475636ms","start":"2026-08-15T02:40:56.958564Z","end":"2026-08-15T02:40:57.325039Z","steps":["trace[825677873] 'agreement among raft nodes before linearized reading' (duration: 366.182589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:57.325171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:56.958542Z","time spent":"366.589628ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:40:57.325171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09919196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-15T02:40:57.325175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054136841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:40:57.325087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.507393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.130\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-15T02:40:57.325415Z","caller":"traceutil/trace.go:171","msg":"trace[1601085590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1132; }","duration":"1.054383917s","start":"2026-08-15T02:40:56.270927Z","end":"2026-08-15T02:40:57.325311Z","steps":["trace[1601085590] 'agreement among raft nodes before linearized reading' (duration: 1.053891566s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:57.325356Z","caller":"traceutil/trace.go:171","msg":"trace[64901326] range","detail":"{range_begin:/registry/masterleases/199.19.213.130; range_end:; response_count:1; response_revision:1132; }","duration":"169.82051ms","start":"2026-08-15T02:40:57.155522Z","end":"2026-08-15T02:40:57.325343Z","steps":["trace[64901326] 'agreement among raft nodes before linearized reading' (duration: 169.326218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:57.325521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:56.27091Z","time spent":"1.054598743s","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-15T02:40:57.325228Z","caller":"traceutil/trace.go:171","msg":"trace[970207660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1132; }","duration":"1.099294533s","start":"2026-08-15T02:40:56.225918Z","end":"2026-08-15T02:40:57.325213Z","steps":["trace[970207660] 'agreement among raft nodes before linearized reading' (duration: 1.099069558s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:57.32575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:40:56.225894Z","time spent":"1.099840956s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-15T02:40:57.475402Z","caller":"traceutil/trace.go:171","msg":"trace[1256074778] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"145.648112ms","start":"2026-08-15T02:40:57.329727Z","end":"2026-08-15T02:40:57.475375Z","steps":["trace[1256074778] 'process raft request' (duration: 96.171835ms)","trace[1256074778] 'compare' (duration: 49.288572ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:40:57.475666Z","caller":"traceutil/trace.go:171","msg":"trace[984679395] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"144.998165ms","start":"2026-08-15T02:40:57.330654Z","end":"2026-08-15T02:40:57.475653Z","steps":["trace[984679395] 'process raft request' (duration: 144.680598ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:57.475882Z","caller":"traceutil/trace.go:171","msg":"trace[1713845078] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"139.795389ms","start":"2026-08-15T02:40:57.336077Z","end":"2026-08-15T02:40:57.475872Z","steps":["trace[1713845078] 'process raft request' (duration: 139.734927ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:57.476126Z","caller":"traceutil/trace.go:171","msg":"trace[1251350989] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1180; }","duration":"141.348526ms","start":"2026-08-15T02:40:57.334761Z","end":"2026-08-15T02:40:57.476109Z","steps":["trace[1251350989] 'read index received' (duration: 676.277µs)","trace[1251350989] 'applied index is now lower than readState.Index' (duration: 140.670269ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:40:57.476237Z","caller":"traceutil/trace.go:171","msg":"trace[317749488] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"145.469077ms","start":"2026-08-15T02:40:57.330758Z","end":"2026-08-15T02:40:57.476227Z","steps":["trace[317749488] 'process raft request' (duration: 144.859043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:57.476416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.633353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:40:57.476466Z","caller":"traceutil/trace.go:171","msg":"trace[1551817739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1136; }","duration":"141.719935ms","start":"2026-08-15T02:40:57.334734Z","end":"2026-08-15T02:40:57.476454Z","steps":["trace[1551817739] 'agreement among raft nodes before linearized reading' (duration: 141.627272ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:40:58.455286Z","caller":"traceutil/trace.go:171","msg":"trace[358323967] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"270.879374ms","start":"2026-08-15T02:40:58.184375Z","end":"2026-08-15T02:40:58.455254Z","steps":["trace[358323967] 'process raft request' (duration: 270.607918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:40:58.98713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.689892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:40:58.987671Z","caller":"traceutil/trace.go:171","msg":"trace[1137869152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"187.305727ms","start":"2026-08-15T02:40:58.800345Z","end":"2026-08-15T02:40:58.987651Z","steps":["trace[1137869152] 'range keys from in-memory index tree' (duration: 186.56452ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:00.683041Z","caller":"traceutil/trace.go:171","msg":"trace[1332694210] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"221.939211ms","start":"2026-08-15T02:41:00.460782Z","end":"2026-08-15T02:41:00.682721Z","steps":["trace[1332694210] 'process raft request' (duration: 221.808598ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:01.657764Z","caller":"traceutil/trace.go:171","msg":"trace[1044628784] linearizableReadLoop","detail":"{readStateIndex:1196; appliedIndex:1194; }","duration":"145.868926ms","start":"2026-08-15T02:41:01.511873Z","end":"2026-08-15T02:41:01.657742Z","steps":["trace[1044628784] 'read index received' (duration: 94.193836ms)","trace[1044628784] 'applied index is now lower than readState.Index' (duration: 51.67335ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:01.658054Z","caller":"traceutil/trace.go:171","msg":"trace[1231394544] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"155.626215ms","start":"2026-08-15T02:41:01.502413Z","end":"2026-08-15T02:41:01.65804Z","steps":["trace[1231394544] 'process raft request' (duration: 103.35515ms)","trace[1231394544] 'compare' (duration: 51.793313ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:01.658133Z","caller":"traceutil/trace.go:171","msg":"trace[1835963782] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"155.384048ms","start":"2026-08-15T02:41:01.50268Z","end":"2026-08-15T02:41:01.658064Z","steps":["trace[1835963782] 'process raft request' (duration: 154.986449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:01.658217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.799445ms","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-08-15T02:41:01.658272Z","caller":"traceutil/trace.go:171","msg":"trace[363953186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1147; }","duration":"145.875287ms","start":"2026-08-15T02:41:01.512384Z","end":"2026-08-15T02:41:01.658259Z","steps":["trace[363953186] 'agreement among raft nodes before linearized reading' (duration: 145.651561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:01.658541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.781331ms","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-08-15T02:41:01.659477Z","caller":"traceutil/trace.go:171","msg":"trace[1308979433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1147; }","duration":"145.791655ms","start":"2026-08-15T02:41:01.513666Z","end":"2026-08-15T02:41:01.659457Z","steps":["trace[1308979433] 'agreement among raft nodes before linearized reading' (duration: 144.429372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:01.659879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.992198ms","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-08-15T02:41:01.659936Z","caller":"traceutil/trace.go:171","msg":"trace[117964438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1147; }","duration":"148.084151ms","start":"2026-08-15T02:41:01.511839Z","end":"2026-08-15T02:41:01.659924Z","steps":["trace[117964438] 'agreement among raft nodes before linearized reading' (duration: 146.120363ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:04.840209Z","caller":"traceutil/trace.go:171","msg":"trace[1656932487] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"143.794546ms","start":"2026-08-15T02:41:04.696349Z","end":"2026-08-15T02:41:04.840144Z","steps":["trace[1656932487] 'process raft request' (duration: 143.620622ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:09.044347Z","caller":"traceutil/trace.go:171","msg":"trace[913698830] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"180.907391ms","start":"2026-08-15T02:41:08.863419Z","end":"2026-08-15T02:41:09.044326Z","steps":["trace[913698830] 'process raft request' (duration: 180.767288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:12.967226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.002788ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-08-15T02:41:13.438039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.029157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:41:13.438127Z","caller":"traceutil/trace.go:171","msg":"trace[1229488709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1184; }","duration":"166.178151ms","start":"2026-08-15T02:41:13.271929Z","end":"2026-08-15T02:41:13.438108Z","steps":["trace[1229488709] 'range keys from in-memory index tree' (duration: 165.919275ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:13.86478Z","caller":"traceutil/trace.go:171","msg":"trace[861358073] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"101.076794ms","start":"2026-08-15T02:41:13.763676Z","end":"2026-08-15T02:41:13.864753Z","steps":["trace[861358073] 'process raft request' (duration: 100.579462ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:14.029814Z","caller":"traceutil/trace.go:171","msg":"trace[139711585] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"160.085243ms","start":"2026-08-15T02:41:13.869701Z","end":"2026-08-15T02:41:14.029787Z","steps":["trace[139711585] 'process raft request' (duration: 159.306714ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:14.072263Z","caller":"traceutil/trace.go:171","msg":"trace[1505646058] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"202.410545ms","start":"2026-08-15T02:41:13.869829Z","end":"2026-08-15T02:41:14.07224Z","steps":["trace[1505646058] 'process raft request' (duration: 202.315903ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:14.072682Z","caller":"traceutil/trace.go:171","msg":"trace[1347061372] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"202.296483ms","start":"2026-08-15T02:41:13.870371Z","end":"2026-08-15T02:41:14.072667Z","steps":["trace[1347061372] 'process raft request' (duration: 201.824161ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:14.072736Z","caller":"traceutil/trace.go:171","msg":"trace[1923141368] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"202.944399ms","start":"2026-08-15T02:41:13.869773Z","end":"2026-08-15T02:41:14.072717Z","steps":["trace[1923141368] 'process raft request' (duration: 202.273081ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:18.956672Z","caller":"traceutil/trace.go:171","msg":"trace[1652737282] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"162.208485ms","start":"2026-08-15T02:41:18.794435Z","end":"2026-08-15T02:41:18.956644Z","steps":["trace[1652737282] 'process raft request' (duration: 124.494346ms)","trace[1652737282] 'compare' (duration: 37.584726ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:19.077159Z","caller":"traceutil/trace.go:171","msg":"trace[2117884898] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"114.726548ms","start":"2026-08-15T02:41:18.962408Z","end":"2026-08-15T02:41:19.077134Z","steps":["trace[2117884898] 'process raft request' (duration: 104.701173ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:19.445633Z","caller":"traceutil/trace.go:171","msg":"trace[2046642073] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"174.179037ms","start":"2026-08-15T02:41:19.271434Z","end":"2026-08-15T02:41:19.445613Z","steps":["trace[2046642073] 'read index received' (duration: 124.641319ms)","trace[2046642073] 'applied index is now lower than readState.Index' (duration: 49.537108ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:19.445728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.28397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:41:19.445751Z","caller":"traceutil/trace.go:171","msg":"trace[1998328861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"174.346142ms","start":"2026-08-15T02:41:19.271399Z","end":"2026-08-15T02:41:19.445745Z","steps":["trace[1998328861] 'agreement among raft nodes before linearized reading' (duration: 174.2792ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:19.445746Z","caller":"traceutil/trace.go:171","msg":"trace[1972411469] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"246.378937ms","start":"2026-08-15T02:41:19.199314Z","end":"2026-08-15T02:41:19.445693Z","steps":["trace[1972411469] 'process raft request' (duration: 196.769748ms)","trace[1972411469] 'compare' (duration: 49.340182ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:19.649349Z","caller":"traceutil/trace.go:171","msg":"trace[1780069035] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"201.839801ms","start":"2026-08-15T02:41:19.447478Z","end":"2026-08-15T02:41:19.649318Z","steps":["trace[1780069035] 'read index received' (duration: 109.341836ms)","trace[1780069035] 'applied index is now lower than readState.Index' (duration: 92.496095ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:19.649397Z","caller":"traceutil/trace.go:171","msg":"trace[961205876] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"201.923443ms","start":"2026-08-15T02:41:19.447439Z","end":"2026-08-15T02:41:19.649362Z","steps":["trace[961205876] 'process raft request' (duration: 109.428078ms)","trace[961205876] 'compare' (duration: 92.27527ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:19.649627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.128278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:79968"} {"level":"info","ts":"2026-08-15T02:41:19.649684Z","caller":"traceutil/trace.go:171","msg":"trace[1866551191] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1220; }","duration":"202.237451ms","start":"2026-08-15T02:41:19.447434Z","end":"2026-08-15T02:41:19.649671Z","steps":["trace[1866551191] 'agreement among raft nodes before linearized reading' (duration: 202.016706ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:19.829299Z","caller":"traceutil/trace.go:171","msg":"trace[1951670308] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"176.695609ms","start":"2026-08-15T02:41:19.652578Z","end":"2026-08-15T02:41:19.829273Z","steps":["trace[1951670308] 'process raft request' (duration: 176.471842ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:19.876977Z","caller":"traceutil/trace.go:171","msg":"trace[391409171] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"223.053109ms","start":"2026-08-15T02:41:19.65389Z","end":"2026-08-15T02:41:19.876943Z","steps":["trace[391409171] 'process raft request' (duration: 222.70644ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:19.877047Z","caller":"traceutil/trace.go:171","msg":"trace[248274434] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"222.869055ms","start":"2026-08-15T02:41:19.654132Z","end":"2026-08-15T02:41:19.877001Z","steps":["trace[248274434] 'process raft request' (duration: 222.600647ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:20.695055Z","caller":"traceutil/trace.go:171","msg":"trace[1667951789] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"129.705873ms","start":"2026-08-15T02:41:20.565327Z","end":"2026-08-15T02:41:20.695033Z","steps":["trace[1667951789] 'process raft request' (duration: 128.263577ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:20.695797Z","caller":"traceutil/trace.go:171","msg":"trace[1872158422] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"128.395131ms","start":"2026-08-15T02:41:20.567378Z","end":"2026-08-15T02:41:20.695773Z","steps":["trace[1872158422] 'process raft request' (duration: 128.281798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:22.49057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.820849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:41:22.490638Z","caller":"traceutil/trace.go:171","msg":"trace[1960365114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"219.924403ms","start":"2026-08-15T02:41:22.270698Z","end":"2026-08-15T02:41:22.490622Z","steps":["trace[1960365114] 'range keys from in-memory index tree' (duration: 219.772478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:24.598564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111227632,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:41:25.099722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111227632,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:41:25.16213Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.12749982s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:41:25.162355Z","caller":"traceutil/trace.go:171","msg":"trace[951172583] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"1.127849638s","start":"2026-08-15T02:41:24.034493Z","end":"2026-08-15T02:41:25.162343Z","steps":["trace[951172583] 'process raft request' (duration: 1.127762046s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:25.162381Z","caller":"traceutil/trace.go:171","msg":"trace[1245791807] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1325; }","duration":"1.063967772s","start":"2026-08-15T02:41:24.098388Z","end":"2026-08-15T02:41:25.162356Z","steps":["trace[1245791807] 'read index received' (duration: 1.063957801s)","trace[1245791807] 'applied index is now lower than readState.Index' (duration: 7.83µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:25.162442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:24.034472Z","time spent":"1.12792508s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:41:25.162614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064214758s","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-08-15T02:41:25.162693Z","caller":"traceutil/trace.go:171","msg":"trace[357321017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1269; }","duration":"1.064342391s","start":"2026-08-15T02:41:24.098326Z","end":"2026-08-15T02:41:25.162668Z","steps":["trace[357321017] 'agreement among raft nodes before linearized reading' (duration: 1.064112315s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:25.162731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:24.098305Z","time spent":"1.064418942s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-15T02:41:25.407681Z","caller":"traceutil/trace.go:171","msg":"trace[1323560286] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"241.726034ms","start":"2026-08-15T02:41:25.165938Z","end":"2026-08-15T02:41:25.407664Z","steps":["trace[1323560286] 'process raft request' (duration: 241.693633ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:25.407752Z","caller":"traceutil/trace.go:171","msg":"trace[1849765567] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"260.991923ms","start":"2026-08-15T02:41:25.146709Z","end":"2026-08-15T02:41:25.4077Z","steps":["trace[1849765567] 'process raft request' (duration: 225.075447ms)","trace[1849765567] 'compare' (duration: 35.732972ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:25.407768Z","caller":"traceutil/trace.go:171","msg":"trace[1006389706] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"245.306382ms","start":"2026-08-15T02:41:25.162432Z","end":"2026-08-15T02:41:25.407739Z","steps":["trace[1006389706] 'read index received' (duration: 209.363965ms)","trace[1006389706] 'applied index is now lower than readState.Index' (duration: 35.936087ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:25.407949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293666662s","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-08-15T02:41:25.407962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135960438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:41:25.407965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280149813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-15T02:41:25.408037Z","caller":"traceutil/trace.go:171","msg":"trace[1768290490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"1.136108141s","start":"2026-08-15T02:41:24.271908Z","end":"2026-08-15T02:41:25.408016Z","steps":["trace[1768290490] 'agreement among raft nodes before linearized reading' (duration: 1.135959948s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:25.408101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:24.271882Z","time spent":"1.136204443s","remote":"127.0.0.1:50990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-15T02:41:25.408044Z","caller":"traceutil/trace.go:171","msg":"trace[1150944245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"1.280257175s","start":"2026-08-15T02:41:24.127771Z","end":"2026-08-15T02:41:25.408028Z","steps":["trace[1150944245] 'agreement among raft nodes before linearized reading' (duration: 1.280006159s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:25.408122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281514605s","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-08-15T02:41:25.408204Z","caller":"traceutil/trace.go:171","msg":"trace[754745765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"1.281639809s","start":"2026-08-15T02:41:24.126542Z","end":"2026-08-15T02:41:25.408182Z","steps":["trace[754745765] 'agreement among raft nodes before linearized reading' (duration: 1.281355943s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:25.408259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:24.12653Z","time spent":"1.281720461s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-15T02:41:25.408204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:24.127757Z","time spent":"1.280417849s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-15T02:41:25.407992Z","caller":"traceutil/trace.go:171","msg":"trace[215018979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"1.293749155s","start":"2026-08-15T02:41:24.11423Z","end":"2026-08-15T02:41:25.40798Z","steps":["trace[215018979] 'agreement among raft nodes before linearized reading' (duration: 1.293606321s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:25.40945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:24.114211Z","time spent":"1.295198589s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-15T02:41:32.265123Z","caller":"traceutil/trace.go:171","msg":"trace[157205188] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"131.184368ms","start":"2026-08-15T02:41:32.133915Z","end":"2026-08-15T02:41:32.265099Z","steps":["trace[157205188] 'process raft request' (duration: 131.038345ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:35.636299Z","caller":"traceutil/trace.go:171","msg":"trace[1663828965] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"111.068079ms","start":"2026-08-15T02:41:35.525208Z","end":"2026-08-15T02:41:35.636276Z","steps":["trace[1663828965] 'read index received' (duration: 82.222986ms)","trace[1663828965] 'applied index is now lower than readState.Index' (duration: 28.843813ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:35.636484Z","caller":"traceutil/trace.go:171","msg":"trace[531439404] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"120.249241ms","start":"2026-08-15T02:41:35.516218Z","end":"2026-08-15T02:41:35.636468Z","steps":["trace[531439404] 'process raft request' (duration: 91.278775ms)","trace[531439404] 'compare' (duration: 28.658809ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:35.63658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.354316ms","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-08-15T02:41:35.636629Z","caller":"traceutil/trace.go:171","msg":"trace[129087565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"111.473118ms","start":"2026-08-15T02:41:35.525143Z","end":"2026-08-15T02:41:35.636616Z","steps":["trace[129087565] 'agreement among raft nodes before linearized reading' (duration: 111.287334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:35.636643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.735476ms","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-08-15T02:41:35.636678Z","caller":"traceutil/trace.go:171","msg":"trace[920819737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"100.814778ms","start":"2026-08-15T02:41:35.535853Z","end":"2026-08-15T02:41:35.636668Z","steps":["trace[920819737] 'agreement among raft nodes before linearized reading' (duration: 100.675635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:35.636689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.282088ms","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-08-15T02:41:35.63676Z","caller":"traceutil/trace.go:171","msg":"trace[263761905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"103.407421ms","start":"2026-08-15T02:41:35.533335Z","end":"2026-08-15T02:41:35.636742Z","steps":["trace[263761905] 'agreement among raft nodes before linearized reading' (duration: 103.184966ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:37.33916Z","caller":"traceutil/trace.go:171","msg":"trace[1134264759] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"123.45269ms","start":"2026-08-15T02:41:37.215677Z","end":"2026-08-15T02:41:37.33913Z","steps":["trace[1134264759] 'process raft request' (duration: 61.303165ms)","trace[1134264759] 'compare' (duration: 61.897399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:37.887773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.016921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:41:37.887835Z","caller":"traceutil/trace.go:171","msg":"trace[1189647153] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1309; }","duration":"397.090542ms","start":"2026-08-15T02:41:37.490728Z","end":"2026-08-15T02:41:37.887818Z","steps":["trace[1189647153] 'count revisions from in-memory index tree' (duration: 396.948568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:37.887859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:37.490674Z","time spent":"397.178994ms","remote":"127.0.0.1:51340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-08-15T02:41:37.887886Z","caller":"traceutil/trace.go:171","msg":"trace[1781840713] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"244.032749ms","start":"2026-08-15T02:41:37.643829Z","end":"2026-08-15T02:41:37.887862Z","steps":["trace[1781840713] 'read index received' (duration: 232.082838ms)","trace[1781840713] 'applied index is now lower than readState.Index' (duration: 11.948601ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:37.887929Z","caller":"traceutil/trace.go:171","msg":"trace[485248713] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"370.430081ms","start":"2026-08-15T02:41:37.517468Z","end":"2026-08-15T02:41:37.887898Z","steps":["trace[485248713] 'process raft request' (duration: 358.427589ms)","trace[485248713] 'compare' (duration: 11.835518ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:37.888032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.080211ms","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":"info","ts":"2026-08-15T02:41:37.888075Z","caller":"traceutil/trace.go:171","msg":"trace[120635199] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1310; }","duration":"107.132622ms","start":"2026-08-15T02:41:37.780932Z","end":"2026-08-15T02:41:37.888065Z","steps":["trace[120635199] 'agreement among raft nodes before linearized reading' (duration: 107.058391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:37.888068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:37.517446Z","time spent":"370.544674ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:41:37.888099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.861822ms","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":"warn","ts":"2026-08-15T02:41:37.888122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.815716ms","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-08-15T02:41:37.888127Z","caller":"traceutil/trace.go:171","msg":"trace[1070757049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"242.957475ms","start":"2026-08-15T02:41:37.645162Z","end":"2026-08-15T02:41:37.88812Z","steps":["trace[1070757049] 'agreement among raft nodes before linearized reading' (duration: 242.825131ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:37.88814Z","caller":"traceutil/trace.go:171","msg":"trace[1643847623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"186.832376ms","start":"2026-08-15T02:41:37.701302Z","end":"2026-08-15T02:41:37.888134Z","steps":["trace[1643847623] 'agreement among raft nodes before linearized reading' (duration: 186.735064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:37.888203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.343188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-15T02:41:37.888274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.301898ms","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-08-15T02:41:37.888312Z","caller":"traceutil/trace.go:171","msg":"trace[81099932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"242.337579ms","start":"2026-08-15T02:41:37.645964Z","end":"2026-08-15T02:41:37.888302Z","steps":["trace[81099932] 'agreement among raft nodes before linearized reading' (duration: 242.215476ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:37.888325Z","caller":"traceutil/trace.go:171","msg":"trace[638510158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"244.507491ms","start":"2026-08-15T02:41:37.643788Z","end":"2026-08-15T02:41:37.888295Z","steps":["trace[638510158] 'agreement among raft nodes before linearized reading' (duration: 244.170153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:38.772992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086130111227753,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:41:39.08541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.806341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:41:39.085481Z","caller":"traceutil/trace.go:171","msg":"trace[218358825] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"1.192912505s","start":"2026-08-15T02:41:37.892556Z","end":"2026-08-15T02:41:39.085468Z","steps":["trace[218358825] 'process raft request' (duration: 207.989631ms)","trace[218358825] 'compare' (duration: 984.547755ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:39.08552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:37.892531Z","time spent":"1.192970997s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:41:39.199794Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.099235701s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:41:39.223895Z","caller":"traceutil/trace.go:171","msg":"trace[369812287] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"1.33048662s","start":"2026-08-15T02:41:37.893395Z","end":"2026-08-15T02:41:39.223882Z","steps":["trace[369812287] 'process raft request' (duration: 1.306586057s)","trace[369812287] 'compare' (duration: 23.649047ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:39.223939Z","caller":"traceutil/trace.go:171","msg":"trace[620786132] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.328972624s","start":"2026-08-15T02:41:37.894956Z","end":"2026-08-15T02:41:39.223928Z","steps":["trace[620786132] 'process raft request' (duration: 1.32880979s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:39.223985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:37.893375Z","time spent":"1.330560702s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:41:39.224047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:37.894948Z","time spent":"1.329027175s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:41:39.223892Z","caller":"traceutil/trace.go:171","msg":"trace[1697568171] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.328737388s","start":"2026-08-15T02:41:37.895132Z","end":"2026-08-15T02:41:39.22387Z","steps":["trace[1697568171] 'process raft request' (duration: 1.328700397s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:39.22421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:37.895112Z","time spent":"1.329079556s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:41:39.241326Z","caller":"traceutil/trace.go:171","msg":"trace[83805996] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1370; }","duration":"969.306033ms","start":"2026-08-15T02:41:38.272006Z","end":"2026-08-15T02:41:39.241312Z","steps":["trace[83805996] 'read index received' (duration: 927.983085ms)","trace[83805996] 'applied index is now lower than readState.Index' (duration: 41.322348ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:39.241389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.381906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:41:39.241403Z","caller":"traceutil/trace.go:171","msg":"trace[1211604282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"969.445677ms","start":"2026-08-15T02:41:38.271953Z","end":"2026-08-15T02:41:39.241399Z","steps":["trace[1211604282] 'agreement among raft nodes before linearized reading' (duration: 969.408576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:39.241419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:38.271933Z","time spent":"969.481717ms","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-15T02:41:39.241524Z","caller":"traceutil/trace.go:171","msg":"trace[1469821815] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"713.577109ms","start":"2026-08-15T02:41:38.527941Z","end":"2026-08-15T02:41:39.241518Z","steps":["trace[1469821815] 'process raft request' (duration: 713.301872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:39.241571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:38.527899Z","time spent":"713.642301ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:41:44.625413Z","caller":"traceutil/trace.go:171","msg":"trace[1127233606] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"354.412882ms","start":"2026-08-15T02:41:44.270978Z","end":"2026-08-15T02:41:44.625391Z","steps":["trace[1127233606] 'read index received' (duration: 354.239108ms)","trace[1127233606] 'applied index is now lower than readState.Index' (duration: 172.954µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:44.625503Z","caller":"traceutil/trace.go:171","msg":"trace[1058282356] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"663.966569ms","start":"2026-08-15T02:41:43.961526Z","end":"2026-08-15T02:41:44.625492Z","steps":["trace[1058282356] 'process raft request' (duration: 663.732384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:44.625551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.553365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:41:44.625588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:43.961503Z","time spent":"664.02714ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:41:44.625589Z","caller":"traceutil/trace.go:171","msg":"trace[1882767306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"354.664117ms","start":"2026-08-15T02:41:44.270915Z","end":"2026-08-15T02:41:44.625579Z","steps":["trace[1882767306] 'agreement among raft nodes before linearized reading' (duration: 354.565335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:44.625618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:44.270898Z","time spent":"354.713579ms","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:41:45.574101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.004518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:41:45.574565Z","caller":"traceutil/trace.go:171","msg":"trace[100005583] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"269.423869ms","start":"2026-08-15T02:41:45.305099Z","end":"2026-08-15T02:41:45.574523Z","steps":["trace[100005583] 'process raft request' (duration: 150.928939ms)","trace[100005583] 'compare' (duration: 117.869954ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:45.574649Z","caller":"traceutil/trace.go:171","msg":"trace[1650721625] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1412; }","duration":"158.843293ms","start":"2026-08-15T02:41:45.415775Z","end":"2026-08-15T02:41:45.574618Z","steps":["trace[1650721625] 'read index received' (duration: 40.163409ms)","trace[1650721625] 'applied index is now lower than readState.Index' (duration: 118.678804ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:45.574675Z","caller":"traceutil/trace.go:171","msg":"trace[1897104452] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"269.333477ms","start":"2026-08-15T02:41:45.305325Z","end":"2026-08-15T02:41:45.574658Z","steps":["trace[1897104452] 'process raft request' (duration: 269.099102ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:45.574769Z","caller":"traceutil/trace.go:171","msg":"trace[519194206] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"255.300345ms","start":"2026-08-15T02:41:45.31946Z","end":"2026-08-15T02:41:45.57476Z","steps":["trace[519194206] 'process raft request' (duration: 255.10203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:45.574978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.181081ms","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-08-15T02:41:45.575053Z","caller":"traceutil/trace.go:171","msg":"trace[1446277788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1355; }","duration":"159.319494ms","start":"2026-08-15T02:41:45.415716Z","end":"2026-08-15T02:41:45.575035Z","steps":["trace[1446277788] 'agreement among raft nodes before linearized reading' (duration: 159.12092ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:47.172196Z","caller":"traceutil/trace.go:171","msg":"trace[1860416128] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"442.066898ms","start":"2026-08-15T02:41:46.730102Z","end":"2026-08-15T02:41:47.172169Z","steps":["trace[1860416128] 'read index received' (duration: 441.868764ms)","trace[1860416128] 'applied index is now lower than readState.Index' (duration: 197.274µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:47.172359Z","caller":"traceutil/trace.go:171","msg":"trace[1055191710] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"541.034132ms","start":"2026-08-15T02:41:46.631314Z","end":"2026-08-15T02:41:47.172348Z","steps":["trace[1055191710] 'process raft request' (duration: 540.730384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.17247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:46.631291Z","time spent":"541.103573ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:41:47.17266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.546811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:41:47.172729Z","caller":"traceutil/trace.go:171","msg":"trace[1462266757] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1357; }","duration":"442.668973ms","start":"2026-08-15T02:41:46.730045Z","end":"2026-08-15T02:41:47.172714Z","steps":["trace[1462266757] 'agreement among raft nodes before linearized reading' (duration: 442.570471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.172755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:46.73002Z","time spent":"442.728414ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:41:47.543927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.029578ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-15T02:41:47.544011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:47.174007Z","time spent":"370.000632ms","remote":"127.0.0.1:51008","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-15T02:41:47.544672Z","caller":"traceutil/trace.go:171","msg":"trace[1447005232] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"368.452444ms","start":"2026-08-15T02:41:47.176203Z","end":"2026-08-15T02:41:47.544655Z","steps":["trace[1447005232] 'process raft request' (duration: 368.349282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.544787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:47.176186Z","time spent":"368.538716ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:41:47.545201Z","caller":"traceutil/trace.go:171","msg":"trace[1784460847] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1419; }","duration":"277.114546ms","start":"2026-08-15T02:41:47.268074Z","end":"2026-08-15T02:41:47.545189Z","steps":["trace[1784460847] 'read index received' (duration: 277.110226ms)","trace[1784460847] 'applied index is now lower than readState.Index' (duration: 3.5µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:47.545306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.22305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:41:47.545331Z","caller":"traceutil/trace.go:171","msg":"trace[423308615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"277.27428ms","start":"2026-08-15T02:41:47.268048Z","end":"2026-08-15T02:41:47.545323Z","steps":["trace[423308615] 'agreement among raft nodes before linearized reading' (duration: 277.224919ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:47.563482Z","caller":"traceutil/trace.go:171","msg":"trace[887652711] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"253.88704ms","start":"2026-08-15T02:41:47.309573Z","end":"2026-08-15T02:41:47.56346Z","steps":["trace[887652711] 'process raft request' (duration: 253.791878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.563582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.529324ms","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-08-15T02:41:47.563627Z","caller":"traceutil/trace.go:171","msg":"trace[522753577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1359; }","duration":"217.604056ms","start":"2026-08-15T02:41:47.346012Z","end":"2026-08-15T02:41:47.563616Z","steps":["trace[522753577] 'agreement among raft nodes before linearized reading' (duration: 217.455472ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:47.875958Z","caller":"traceutil/trace.go:171","msg":"trace[770391194] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"253.309965ms","start":"2026-08-15T02:41:47.622581Z","end":"2026-08-15T02:41:47.875891Z","steps":["trace[770391194] 'read index received' (duration: 253.159862ms)","trace[770391194] 'applied index is now lower than readState.Index' (duration: 148.833µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:47.876449Z","caller":"traceutil/trace.go:171","msg":"trace[537742394] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"308.452651ms","start":"2026-08-15T02:41:47.56797Z","end":"2026-08-15T02:41:47.876423Z","steps":["trace[537742394] 'process raft request' (duration: 307.758014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.876585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:47.567952Z","time spent":"308.559713ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:41:47.876794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.141486ms","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-08-15T02:41:47.876822Z","caller":"traceutil/trace.go:171","msg":"trace[1216178082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"272.202938ms","start":"2026-08-15T02:41:47.604612Z","end":"2026-08-15T02:41:47.876815Z","steps":["trace[1216178082] 'agreement among raft nodes before linearized reading' (duration: 272.078265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.876915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.015257ms","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-08-15T02:41:47.876971Z","caller":"traceutil/trace.go:171","msg":"trace[1694276300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"298.124639ms","start":"2026-08-15T02:41:47.578833Z","end":"2026-08-15T02:41:47.876957Z","steps":["trace[1694276300] 'agreement among raft nodes before linearized reading' (duration: 297.927334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.876991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.132375ms","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-08-15T02:41:47.877068Z","caller":"traceutil/trace.go:171","msg":"trace[676317765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"297.264838ms","start":"2026-08-15T02:41:47.579784Z","end":"2026-08-15T02:41:47.877049Z","steps":["trace[676317765] 'agreement among raft nodes before linearized reading' (duration: 297.009672ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:47.985323Z","caller":"traceutil/trace.go:171","msg":"trace[1767953336] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"153.880992ms","start":"2026-08-15T02:41:47.83141Z","end":"2026-08-15T02:41:47.985291Z","steps":["trace[1767953336] 'process raft request' (duration: 62.30564ms)","trace[1767953336] 'compare' (duration: 91.185912ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:47.985499Z","caller":"traceutil/trace.go:171","msg":"trace[709817508] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"104.081388ms","start":"2026-08-15T02:41:47.881386Z","end":"2026-08-15T02:41:47.985467Z","steps":["trace[709817508] 'process raft request' (duration: 104.021106ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:47.985712Z","caller":"traceutil/trace.go:171","msg":"trace[1065220755] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"109.647743ms","start":"2026-08-15T02:41:47.87605Z","end":"2026-08-15T02:41:47.985698Z","steps":["trace[1065220755] 'read index received' (duration: 17.5913ms)","trace[1065220755] 'applied index is now lower than readState.Index' (duration: 92.055413ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:47.985743Z","caller":"traceutil/trace.go:171","msg":"trace[2056903385] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"104.659411ms","start":"2026-08-15T02:41:47.881059Z","end":"2026-08-15T02:41:47.985718Z","steps":["trace[2056903385] 'process raft request' (duration: 104.164979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.985846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.113889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:41:47.985881Z","caller":"traceutil/trace.go:171","msg":"trace[889894256] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1365; }","duration":"353.186402ms","start":"2026-08-15T02:41:47.632683Z","end":"2026-08-15T02:41:47.98587Z","steps":["trace[889894256] 'agreement among raft nodes before linearized reading' (duration: 353.100089ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:47.985839Z","caller":"traceutil/trace.go:171","msg":"trace[50524260] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"104.645951ms","start":"2026-08-15T02:41:47.88115Z","end":"2026-08-15T02:41:47.985796Z","steps":["trace[50524260] 'process raft request' (duration: 104.20142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.986228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.271098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-08-15T02:41:47.986127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:47.63267Z","time spent":"353.444878ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-15T02:41:47.986297Z","caller":"traceutil/trace.go:171","msg":"trace[261981464] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1365; }","duration":"361.388971ms","start":"2026-08-15T02:41:47.62489Z","end":"2026-08-15T02:41:47.986279Z","steps":["trace[261981464] 'agreement among raft nodes before linearized reading' (duration: 361.270149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.986474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:47.624869Z","time spent":"361.541065ms","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-08-15T02:41:49.29241Z","caller":"traceutil/trace.go:171","msg":"trace[914388399] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"113.314153ms","start":"2026-08-15T02:41:49.179071Z","end":"2026-08-15T02:41:49.292385Z","steps":["trace[914388399] 'process raft request' (duration: 112.681498ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:49.827201Z","caller":"traceutil/trace.go:171","msg":"trace[299144995] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"250.843787ms","start":"2026-08-15T02:41:49.576333Z","end":"2026-08-15T02:41:49.827176Z","steps":["trace[299144995] 'process raft request' (duration: 250.650593ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:49.944357Z","caller":"traceutil/trace.go:171","msg":"trace[358736363] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1431; }","duration":"216.994691ms","start":"2026-08-15T02:41:49.727337Z","end":"2026-08-15T02:41:49.944332Z","steps":["trace[358736363] 'read index received' (duration: 99.679951ms)","trace[358736363] 'applied index is now lower than readState.Index' (duration: 117.3139ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:49.944525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.165346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:41:49.944565Z","caller":"traceutil/trace.go:171","msg":"trace[1477944840] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1372; }","duration":"217.268868ms","start":"2026-08-15T02:41:49.727286Z","end":"2026-08-15T02:41:49.944555Z","steps":["trace[1477944840] 'agreement among raft nodes before linearized reading' (duration: 217.174366ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:49.944802Z","caller":"traceutil/trace.go:171","msg":"trace[262633780] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"321.060148ms","start":"2026-08-15T02:41:49.623409Z","end":"2026-08-15T02:41:49.944469Z","steps":["trace[262633780] 'process raft request' (duration: 320.759431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:49.945034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:49.623384Z","time spent":"321.53174ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:41:51.283372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1659601s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:41:51.283547Z","caller":"traceutil/trace.go:171","msg":"trace[2103903572] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"1.272002074s","start":"2026-08-15T02:41:50.011531Z","end":"2026-08-15T02:41:51.283533Z","steps":["trace[2103903572] 'process raft request' (duration: 1.271934123s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:51.283594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:50.011496Z","time spent":"1.272077206s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:41:51.28375Z","caller":"traceutil/trace.go:171","msg":"trace[2108147591] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1434; }","duration":"1.013685136s","start":"2026-08-15T02:41:50.270056Z","end":"2026-08-15T02:41:51.283742Z","steps":["trace[2108147591] 'read index received' (duration: 349.761148ms)","trace[2108147591] 'applied index is now lower than readState.Index' (duration: 663.920338ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:51.283778Z","caller":"traceutil/trace.go:171","msg":"trace[1444978268] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"1.272326472s","start":"2026-08-15T02:41:50.011404Z","end":"2026-08-15T02:41:51.28373Z","steps":["trace[1444978268] 'process raft request' (duration: 105.940693ms)","trace[1444978268] 'compare' (duration: 1.165654801s)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:51.28382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013765128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:41:51.28384Z","caller":"traceutil/trace.go:171","msg":"trace[1404639160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"1.013816639s","start":"2026-08-15T02:41:50.270018Z","end":"2026-08-15T02:41:51.283834Z","steps":["trace[1404639160] 'agreement among raft nodes before linearized reading' (duration: 1.013753987s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:51.283858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:50.270002Z","time spent":"1.01385276s","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:41:51.283921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:50.011381Z","time spent":"1.272460476s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:41:51.283995Z","caller":"traceutil/trace.go:171","msg":"trace[109999276] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"1.271518912s","start":"2026-08-15T02:41:50.012468Z","end":"2026-08-15T02:41:51.283987Z","steps":["trace[109999276] 'process raft request' (duration: 1.271037411s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:51.284053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:50.012457Z","time spent":"1.271561504s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:41:51.575898Z","caller":"traceutil/trace.go:171","msg":"trace[1627412973] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"277.407843ms","start":"2026-08-15T02:41:51.298461Z","end":"2026-08-15T02:41:51.575869Z","steps":["trace[1627412973] 'process raft request' (duration: 276.972393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:54.479499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.438467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:41:54.479608Z","caller":"traceutil/trace.go:171","msg":"trace[1836635525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1394; }","duration":"207.592052ms","start":"2026-08-15T02:41:54.27199Z","end":"2026-08-15T02:41:54.479582Z","steps":["trace[1836635525] 'range keys from in-memory index tree' (duration: 207.328325ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:55.924437Z","caller":"traceutil/trace.go:171","msg":"trace[1032208303] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"612.847892ms","start":"2026-08-15T02:41:55.311561Z","end":"2026-08-15T02:41:55.924409Z","steps":["trace[1032208303] 'process raft request' (duration: 543.468601ms)","trace[1032208303] 'compare' (duration: 69.019372ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:55.924542Z","caller":"traceutil/trace.go:171","msg":"trace[515213685] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"335.320715ms","start":"2026-08-15T02:41:55.589201Z","end":"2026-08-15T02:41:55.924522Z","steps":["trace[515213685] 'process raft request' (duration: 335.062789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:55.924577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:55.311533Z","time spent":"612.970625ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:41:55.924664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:41:55.589176Z","time spent":"335.426028ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:41:56.291786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.955121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-15T02:41:56.291886Z","caller":"traceutil/trace.go:171","msg":"trace[1796246369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1406; }","duration":"224.091654ms","start":"2026-08-15T02:41:56.067772Z","end":"2026-08-15T02:41:56.291863Z","steps":["trace[1796246369] 'range keys from in-memory index tree' (duration: 223.795867ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:57.781008Z","caller":"traceutil/trace.go:171","msg":"trace[776314951] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"132.759977ms","start":"2026-08-15T02:41:57.648219Z","end":"2026-08-15T02:41:57.780979Z","steps":["trace[776314951] 'process raft request' (duration: 132.435339ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:00.085419Z","caller":"traceutil/trace.go:171","msg":"trace[1157926618] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"117.69815ms","start":"2026-08-15T02:41:59.967699Z","end":"2026-08-15T02:42:00.085397Z","steps":["trace[1157926618] 'process raft request' (duration: 34.926861ms)","trace[1157926618] 'compare' (duration: 82.611625ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:03.023683Z","caller":"traceutil/trace.go:171","msg":"trace[521053607] linearizableReadLoop","detail":"{readStateIndex:1518; appliedIndex:1517; }","duration":"131.403353ms","start":"2026-08-15T02:42:02.892258Z","end":"2026-08-15T02:42:03.023661Z","steps":["trace[521053607] 'read index received' (duration: 131.178568ms)","trace[521053607] 'applied index is now lower than readState.Index' (duration: 223.965µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:03.023878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.607029ms","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-08-15T02:42:03.023917Z","caller":"traceutil/trace.go:171","msg":"trace[1446669103] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1454; }","duration":"131.697561ms","start":"2026-08-15T02:42:02.892209Z","end":"2026-08-15T02:42:03.023907Z","steps":["trace[1446669103] 'agreement among raft nodes before linearized reading' (duration: 131.539727ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:03.024226Z","caller":"traceutil/trace.go:171","msg":"trace[173732883] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"145.782675ms","start":"2026-08-15T02:42:02.87843Z","end":"2026-08-15T02:42:03.024212Z","steps":["trace[173732883] 'process raft request' (duration: 145.091167ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:07.510514Z","caller":"traceutil/trace.go:171","msg":"trace[2025240561] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"105.257136ms","start":"2026-08-15T02:42:07.405229Z","end":"2026-08-15T02:42:07.510486Z","steps":["trace[2025240561] 'process raft request' (duration: 47.287352ms)","trace[2025240561] 'compare' (duration: 57.850371ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:26.27474Z","caller":"traceutil/trace.go:171","msg":"trace[1406893877] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"106.337823ms","start":"2026-08-15T02:42:26.168364Z","end":"2026-08-15T02:42:26.274702Z","steps":["trace[1406893877] 'process raft request' (duration: 58.008205ms)","trace[1406893877] 'compare' (duration: 48.135204ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:33.032177Z","caller":"traceutil/trace.go:171","msg":"trace[1076322056] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"148.603165ms","start":"2026-08-15T02:42:32.883554Z","end":"2026-08-15T02:42:33.032157Z","steps":["trace[1076322056] 'process raft request' (duration: 148.46277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:54.879902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.459782ms","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-08-15T02:42:54.880011Z","caller":"traceutil/trace.go:171","msg":"trace[1934002851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1692; }","duration":"192.623487ms","start":"2026-08-15T02:42:54.687363Z","end":"2026-08-15T02:42:54.879986Z","steps":["trace[1934002851] 'range keys from in-memory index tree' (duration: 192.173595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:54.880441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.643976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-08-15T02:42:54.880627Z","caller":"traceutil/trace.go:171","msg":"trace[1211714711] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1692; }","duration":"115.25859ms","start":"2026-08-15T02:42:54.765333Z","end":"2026-08-15T02:42:54.880592Z","steps":["trace[1211714711] 'range keys from in-memory index tree' (duration: 114.293686ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:55.017391Z","caller":"traceutil/trace.go:171","msg":"trace[1863624575] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"131.717241ms","start":"2026-08-15T02:42:54.88563Z","end":"2026-08-15T02:42:55.017347Z","steps":["trace[1863624575] 'process raft request' (duration: 129.017845ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:38.092299Z","caller":"traceutil/trace.go:171","msg":"trace[2024388044] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"183.208123ms","start":"2026-08-15T02:44:37.909065Z","end":"2026-08-15T02:44:38.092273Z","steps":["trace[2024388044] 'process raft request' (duration: 183.074059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:44.382404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.835541ms","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-08-15T02:44:44.382486Z","caller":"traceutil/trace.go:171","msg":"trace[844576472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2218; }","duration":"145.962874ms","start":"2026-08-15T02:44:44.236505Z","end":"2026-08-15T02:44:44.382468Z","steps":["trace[844576472] 'range keys from in-memory index tree' (duration: 145.660157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:44.382504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.547098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:44.38259Z","caller":"traceutil/trace.go:171","msg":"trace[1088843995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2218; }","duration":"110.672492ms","start":"2026-08-15T02:44:44.271896Z","end":"2026-08-15T02:44:44.382568Z","steps":["trace[1088843995] 'range keys from in-memory index tree' (duration: 110.469697ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:44.4979Z","caller":"traceutil/trace.go:171","msg":"trace[134102675] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"105.258379ms","start":"2026-08-15T02:44:44.39262Z","end":"2026-08-15T02:44:44.497878Z","steps":["trace[134102675] 'process raft request' (duration: 105.144256ms)"],"step_count":1}