{"level":"warn","ts":"2026-02-05T17:37:30.406959Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:37:30.407222Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-02-05T17:37:30.407387Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:37:30.40744Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-05T17:37:30.407483Z","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-02-05T17:37:30.409243Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-02-05T17:37:30.410059Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-02-05T17:37:30.416091Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.702389ms"} {"level":"info","ts":"2026-02-05T17:37:30.437131Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-05T17:37:30.437229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T17:37:30.437254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-05T17:37:30.437297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T17:37:30.437309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-05T17:37:30.437343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-05T17:37:30.452041Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T17:37:30.464656Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T17:37:30.480034Z","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-02-05T17:37:30.483047Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T17:37:30.493823Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T17:37:30.495972Z","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-02-05T17:37:30.49603Z","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-02-05T17:37:30.496039Z","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-02-05T17:37:30.49713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-05T17:37:30.508409Z","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-02-05T17:37:30.508566Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-05T17:37:30.508789Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-05T17:37:30.508803Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-05T17:37:30.509011Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T17:37:30.509905Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T17:37:30.738576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T17:37:30.73886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T17:37:30.739033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-05T17:37:30.73915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-05T17:37:30.739373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-05T17:37:30.740339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-05T17:37:30.740392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-05T17:37:30.742858Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:37:30.744318Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T17:37:30.744342Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:37:30.744719Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:37:30.746736Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-05T17:37:30.74705Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:37:30.74713Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:37:30.747177Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:37:30.747476Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T17:37:30.747491Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T17:37:30.763481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T17:37:32.909821Z","caller":"traceutil/trace.go:171","msg":"trace[2135299795] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"154.60513ms","start":"2026-02-05T17:37:32.755184Z","end":"2026-02-05T17:37:32.90979Z","steps":["trace[2135299795] 'process raft request' (duration: 109.454506ms)","trace[2135299795] 'compare' (duration: 44.983433ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:32.911086Z","caller":"traceutil/trace.go:171","msg":"trace[652603230] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"154.972733ms","start":"2026-02-05T17:37:32.756087Z","end":"2026-02-05T17:37:32.91106Z","steps":["trace[652603230] 'process raft request' (duration: 154.178957ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:32.912621Z","caller":"traceutil/trace.go:171","msg":"trace[418368451] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"104.775045ms","start":"2026-02-05T17:37:32.807826Z","end":"2026-02-05T17:37:32.912601Z","steps":["trace[418368451] 'process raft request' (duration: 102.6593ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:32.914113Z","caller":"traceutil/trace.go:171","msg":"trace[446707065] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:12; }","duration":"102.060037ms","start":"2026-02-05T17:37:32.812039Z","end":"2026-02-05T17:37:32.9141Z","steps":["trace[446707065] 'read index received' (duration: 52.615614ms)","trace[446707065] 'applied index is now lower than readState.Index' (duration: 49.442343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:32.914287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.977436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:37:32.91433Z","caller":"traceutil/trace.go:171","msg":"trace[1500158212] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:12; }","duration":"108.085958ms","start":"2026-02-05T17:37:32.806235Z","end":"2026-02-05T17:37:32.914321Z","steps":["trace[1500158212] 'agreement among raft nodes before linearized reading' (duration: 107.965177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:32.914483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.006395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:37:32.914529Z","caller":"traceutil/trace.go:171","msg":"trace[1979822106] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"127.090415ms","start":"2026-02-05T17:37:32.78743Z","end":"2026-02-05T17:37:32.914521Z","steps":["trace[1979822106] 'agreement among raft nodes before linearized reading' (duration: 127.033485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:32.914657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.903297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:37:32.914748Z","caller":"traceutil/trace.go:171","msg":"trace[932678030] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"128.963767ms","start":"2026-02-05T17:37:32.785717Z","end":"2026-02-05T17:37:32.914681Z","steps":["trace[932678030] 'agreement among raft nodes before linearized reading' (duration: 128.918887ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:32.921106Z","caller":"traceutil/trace.go:171","msg":"trace[368595951] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"101.699143ms","start":"2026-02-05T17:37:32.819318Z","end":"2026-02-05T17:37:32.921018Z","steps":["trace[368595951] 'process raft request' (duration: 98.734334ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:32.921255Z","caller":"traceutil/trace.go:171","msg":"trace[492196362] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"101.933595ms","start":"2026-02-05T17:37:32.819284Z","end":"2026-02-05T17:37:32.921218Z","steps":["trace[492196362] 'process raft request' (duration: 98.755473ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:32.921712Z","caller":"traceutil/trace.go:171","msg":"trace[2045491934] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"102.091236ms","start":"2026-02-05T17:37:32.819371Z","end":"2026-02-05T17:37:32.921463Z","steps":["trace[2045491934] 'process raft request' (duration: 101.314811ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:32.922055Z","caller":"traceutil/trace.go:171","msg":"trace[224191353] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"102.478808ms","start":"2026-02-05T17:37:32.819474Z","end":"2026-02-05T17:37:32.921953Z","steps":["trace[224191353] 'process raft request' (duration: 101.23511ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:32.922309Z","caller":"traceutil/trace.go:171","msg":"trace[1647445990] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"103.123064ms","start":"2026-02-05T17:37:32.819168Z","end":"2026-02-05T17:37:32.922291Z","steps":["trace[1647445990] 'process raft request' (duration: 98.839374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:32.935652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.555114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-05T17:37:32.935703Z","caller":"traceutil/trace.go:171","msg":"trace[1268771771] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"110.626684ms","start":"2026-02-05T17:37:32.825063Z","end":"2026-02-05T17:37:32.935689Z","steps":["trace[1268771771] 'agreement among raft nodes before linearized reading' (duration: 110.544533ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.088902Z","caller":"traceutil/trace.go:171","msg":"trace[447907060] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"153.272579ms","start":"2026-02-05T17:37:32.935591Z","end":"2026-02-05T17:37:33.088863Z","steps":["trace[447907060] 'process raft request' (duration: 153.097388ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.088994Z","caller":"traceutil/trace.go:171","msg":"trace[2138835001] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"108.130976ms","start":"2026-02-05T17:37:32.980812Z","end":"2026-02-05T17:37:33.088943Z","steps":["trace[2138835001] 'process raft request' (duration: 108.101175ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.089212Z","caller":"traceutil/trace.go:171","msg":"trace[2064586967] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"153.953613ms","start":"2026-02-05T17:37:32.935244Z","end":"2026-02-05T17:37:33.089198Z","steps":["trace[2064586967] 'process raft request' (duration: 98.28809ms)","trace[2064586967] 'compare' (duration: 54.897308ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:33.089399Z","caller":"traceutil/trace.go:171","msg":"trace[473699994] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"153.844252ms","start":"2026-02-05T17:37:32.935545Z","end":"2026-02-05T17:37:33.089389Z","steps":["trace[473699994] 'process raft request' (duration: 153.097108ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.092986Z","caller":"traceutil/trace.go:171","msg":"trace[769062450] linearizableReadLoop","detail":"{readStateIndex:30; appliedIndex:24; }","duration":"148.952389ms","start":"2026-02-05T17:37:32.944017Z","end":"2026-02-05T17:37:33.092969Z","steps":["trace[769062450] 'read index received' (duration: 89.553361ms)","trace[769062450] 'applied index is now lower than readState.Index' (duration: 59.396948ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:33.093236Z","caller":"traceutil/trace.go:171","msg":"trace[54996851] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"151.374237ms","start":"2026-02-05T17:37:32.941845Z","end":"2026-02-05T17:37:33.093219Z","steps":["trace[54996851] 'process raft request' (duration: 146.927806ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.093438Z","caller":"traceutil/trace.go:171","msg":"trace[175085060] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"151.559327ms","start":"2026-02-05T17:37:32.94187Z","end":"2026-02-05T17:37:33.093429Z","steps":["trace[175085060] 'process raft request' (duration: 146.956006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:33.093674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.772308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:37:33.093772Z","caller":"traceutil/trace.go:171","msg":"trace[434856971] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:28; }","duration":"151.886759ms","start":"2026-02-05T17:37:32.941872Z","end":"2026-02-05T17:37:33.093759Z","steps":["trace[434856971] 'agreement among raft nodes before linearized reading' (duration: 151.717988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:33.093975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.725591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-05T17:37:33.094079Z","caller":"traceutil/trace.go:171","msg":"trace[1276822940] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:28; }","duration":"156.838342ms","start":"2026-02-05T17:37:32.937232Z","end":"2026-02-05T17:37:33.09407Z","steps":["trace[1276822940] 'agreement among raft nodes before linearized reading' (duration: 156.706461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:33.094251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.013695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:37:33.094336Z","caller":"traceutil/trace.go:171","msg":"trace[483138217] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:28; }","duration":"123.128916ms","start":"2026-02-05T17:37:32.971199Z","end":"2026-02-05T17:37:33.094328Z","steps":["trace[483138217] 'agreement among raft nodes before linearized reading' (duration: 123.033795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:33.094552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.796303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:37:33.094691Z","caller":"traceutil/trace.go:171","msg":"trace[1011121320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:28; }","duration":"119.941714ms","start":"2026-02-05T17:37:32.974742Z","end":"2026-02-05T17:37:33.094683Z","steps":["trace[1011121320] 'agreement among raft nodes before linearized reading' (duration: 119.793193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:33.094867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.327327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:37:33.094964Z","caller":"traceutil/trace.go:171","msg":"trace[501913995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:28; }","duration":"120.457628ms","start":"2026-02-05T17:37:32.974495Z","end":"2026-02-05T17:37:33.094952Z","steps":["trace[501913995] 'agreement among raft nodes before linearized reading' (duration: 120.351327ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.095494Z","caller":"traceutil/trace.go:171","msg":"trace[1221475190] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"148.343415ms","start":"2026-02-05T17:37:32.947142Z","end":"2026-02-05T17:37:33.095485Z","steps":["trace[1221475190] 'process raft request' (duration: 141.713661ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.089132Z","caller":"traceutil/trace.go:171","msg":"trace[2007677767] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"151.851199ms","start":"2026-02-05T17:37:32.937271Z","end":"2026-02-05T17:37:33.089122Z","steps":["trace[2007677767] 'process raft request' (duration: 151.474476ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.294151Z","caller":"traceutil/trace.go:171","msg":"trace[248124116] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"152.593661ms","start":"2026-02-05T17:37:33.141541Z","end":"2026-02-05T17:37:33.294134Z","steps":["trace[248124116] 'process raft request' (duration: 152.549771ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.294447Z","caller":"traceutil/trace.go:171","msg":"trace[26151376] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"188.680403ms","start":"2026-02-05T17:37:33.105758Z","end":"2026-02-05T17:37:33.294438Z","steps":["trace[26151376] 'process raft request' (duration: 188.23557ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.294746Z","caller":"traceutil/trace.go:171","msg":"trace[582676844] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"192.73244ms","start":"2026-02-05T17:37:33.101993Z","end":"2026-02-05T17:37:33.294726Z","steps":["trace[582676844] 'process raft request' (duration: 107.170558ms)","trace[582676844] 'compare' (duration: 84.572566ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:33.294815Z","caller":"traceutil/trace.go:171","msg":"trace[1877162277] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"189.247067ms","start":"2026-02-05T17:37:33.105562Z","end":"2026-02-05T17:37:33.294809Z","steps":["trace[1877162277] 'process raft request' (duration: 188.326541ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.294831Z","caller":"traceutil/trace.go:171","msg":"trace[1369574514] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"189.190827ms","start":"2026-02-05T17:37:33.105636Z","end":"2026-02-05T17:37:33.294827Z","steps":["trace[1369574514] 'process raft request' (duration: 188.27941ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.294846Z","caller":"traceutil/trace.go:171","msg":"trace[457575135] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"189.174277ms","start":"2026-02-05T17:37:33.105667Z","end":"2026-02-05T17:37:33.294841Z","steps":["trace[457575135] 'process raft request' (duration: 188.26456ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.294861Z","caller":"traceutil/trace.go:171","msg":"trace[940700527] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"189.129247ms","start":"2026-02-05T17:37:33.105728Z","end":"2026-02-05T17:37:33.294857Z","steps":["trace[940700527] 'process raft request' (duration: 188.24939ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.294958Z","caller":"traceutil/trace.go:171","msg":"trace[225589272] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"185.666993ms","start":"2026-02-05T17:37:33.109285Z","end":"2026-02-05T17:37:33.294952Z","steps":["trace[225589272] 'process raft request' (duration: 184.784587ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.295015Z","caller":"traceutil/trace.go:171","msg":"trace[930859581] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"188.770845ms","start":"2026-02-05T17:37:33.106235Z","end":"2026-02-05T17:37:33.295006Z","steps":["trace[930859581] 'process raft request' (duration: 187.773628ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.295073Z","caller":"traceutil/trace.go:171","msg":"trace[2088964176] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"186.72575ms","start":"2026-02-05T17:37:33.108343Z","end":"2026-02-05T17:37:33.295068Z","steps":["trace[2088964176] 'process raft request' (duration: 185.707603ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:33.295144Z","caller":"traceutil/trace.go:171","msg":"trace[1102081011] linearizableReadLoop","detail":"{readStateIndex:42; appliedIndex:33; }","duration":"163.712747ms","start":"2026-02-05T17:37:33.131425Z","end":"2026-02-05T17:37:33.295137Z","steps":["trace[1102081011] 'read index received' (duration: 8.467986ms)","trace[1102081011] 'applied index is now lower than readState.Index' (duration: 155.243981ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:33.295202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.765217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:37:33.296624Z","caller":"traceutil/trace.go:171","msg":"trace[1508285617] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:39; }","duration":"165.206076ms","start":"2026-02-05T17:37:33.131394Z","end":"2026-02-05T17:37:33.2966Z","steps":["trace[1508285617] 'agreement among raft nodes before linearized reading' (duration: 163.761657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:33.296719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.193303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:37:33.296741Z","caller":"traceutil/trace.go:171","msg":"trace[1022072021] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:39; }","duration":"142.237963ms","start":"2026-02-05T17:37:33.154497Z","end":"2026-02-05T17:37:33.296735Z","steps":["trace[1022072021] 'agreement among raft nodes before linearized reading' (duration: 142.183392ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:35.74481Z","caller":"traceutil/trace.go:171","msg":"trace[1383757993] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"140.485093ms","start":"2026-02-05T17:37:35.604306Z","end":"2026-02-05T17:37:35.744791Z","steps":["trace[1383757993] 'process raft request' (duration: 67.523278ms)","trace[1383757993] 'compare' (duration: 72.868364ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:41.294208Z","caller":"traceutil/trace.go:171","msg":"trace[1940764717] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"112.149789ms","start":"2026-02-05T17:37:41.182042Z","end":"2026-02-05T17:37:41.294191Z","steps":["trace[1940764717] 'process raft request' (duration: 60.471351ms)","trace[1940764717] 'compare' (duration: 50.731271ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:41.294326Z","caller":"traceutil/trace.go:171","msg":"trace[1396543372] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:370; }","duration":"108.282194ms","start":"2026-02-05T17:37:41.186036Z","end":"2026-02-05T17:37:41.294318Z","steps":["trace[1396543372] 'read index received' (duration: 30.078064ms)","trace[1396543372] 'applied index is now lower than readState.Index' (duration: 78.20332ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:41.294534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.481162ms","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-02-05T17:37:41.295603Z","caller":"traceutil/trace.go:171","msg":"trace[587757589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:365; }","duration":"109.569147ms","start":"2026-02-05T17:37:41.18602Z","end":"2026-02-05T17:37:41.295589Z","steps":["trace[587757589] 'agreement among raft nodes before linearized reading' (duration: 108.455941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:51.498475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.916806ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-05T17:37:51.498552Z","caller":"traceutil/trace.go:171","msg":"trace[1154948412] linearizableReadLoop","detail":"{readStateIndex:467; appliedIndex:466; }","duration":"130.647151ms","start":"2026-02-05T17:37:51.367893Z","end":"2026-02-05T17:37:51.49854Z","steps":["trace[1154948412] 'read index received' (duration: 33.751µs)","trace[1154948412] 'applied index is now lower than readState.Index' (duration: 130.61238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:51.498652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.767764ms","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-02-05T17:37:51.498668Z","caller":"traceutil/trace.go:171","msg":"trace[72139039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:456; }","duration":"126.793114ms","start":"2026-02-05T17:37:51.371869Z","end":"2026-02-05T17:37:51.498663Z","steps":["trace[72139039] 'agreement among raft nodes before linearized reading' (duration: 126.737662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:51.498689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.784626ms","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-02-05T17:37:51.498761Z","caller":"traceutil/trace.go:171","msg":"trace[1635933767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:456; }","duration":"130.884199ms","start":"2026-02-05T17:37:51.367857Z","end":"2026-02-05T17:37:51.498741Z","steps":["trace[1635933767] 'agreement among raft nodes before linearized reading' (duration: 130.723513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:04.106215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088255681481050,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:38:04.342612Z","caller":"traceutil/trace.go:171","msg":"trace[629907019] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"737.642651ms","start":"2026-02-05T17:38:03.604955Z","end":"2026-02-05T17:38:04.342598Z","steps":["trace[629907019] 'process raft request' (duration: 737.546398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:04.342987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:03.604943Z","time spent":"737.714903ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:04.343266Z","caller":"traceutil/trace.go:171","msg":"trace[857523317] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:534; }","duration":"737.237578ms","start":"2026-02-05T17:38:03.606021Z","end":"2026-02-05T17:38:04.343259Z","steps":["trace[857523317] 'read index received' (duration: 737.234678ms)","trace[857523317] 'applied index is now lower than readState.Index' (duration: 2.4µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:04.343316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.291279ms","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-02-05T17:38:04.344939Z","caller":"traceutil/trace.go:171","msg":"trace[1570692242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"738.928109ms","start":"2026-02-05T17:38:03.605996Z","end":"2026-02-05T17:38:04.344924Z","steps":["trace[1570692242] 'agreement among raft nodes before linearized reading' (duration: 737.29613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:04.344983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:03.605985Z","time spent":"738.982931ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:38:04.372877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.373813ms","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-02-05T17:38:04.372935Z","caller":"traceutil/trace.go:171","msg":"trace[513488033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"764.453895ms","start":"2026-02-05T17:38:03.608467Z","end":"2026-02-05T17:38:04.372921Z","steps":["trace[513488033] 'agreement among raft nodes before linearized reading' (duration: 764.367493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:04.372969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:03.608457Z","time spent":"764.504737ms","remote":"127.0.0.1:54354","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-02-05T17:38:04.373091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.863598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:04.373117Z","caller":"traceutil/trace.go:171","msg":"trace[2081638084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"376.919069ms","start":"2026-02-05T17:38:03.99619Z","end":"2026-02-05T17:38:04.373109Z","steps":["trace[2081638084] 'agreement among raft nodes before linearized reading' (duration: 376.880188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:04.374336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.338812ms","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-02-05T17:38:04.374386Z","caller":"traceutil/trace.go:171","msg":"trace[487316179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"694.427865ms","start":"2026-02-05T17:38:03.679947Z","end":"2026-02-05T17:38:04.374375Z","steps":["trace[487316179] 'agreement among raft nodes before linearized reading' (duration: 694.349232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:04.375394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:03.679935Z","time spent":"695.444065ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:04.37314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:03.996177Z","time spent":"376.958091ms","remote":"127.0.0.1:54098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:38:06.031063Z","caller":"traceutil/trace.go:171","msg":"trace[2113255743] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"244.755937ms","start":"2026-02-05T17:38:05.786281Z","end":"2026-02-05T17:38:06.031037Z","steps":["trace[2113255743] 'process raft request' (duration: 229.674223ms)","trace[2113255743] 'compare' (duration: 14.969911ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:06.100682Z","caller":"traceutil/trace.go:171","msg":"trace[2035840143] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:541; }","duration":"106.184783ms","start":"2026-02-05T17:38:05.994483Z","end":"2026-02-05T17:38:06.100668Z","steps":["trace[2035840143] 'read index received' (duration: 21.479061ms)","trace[2035840143] 'applied index is now lower than readState.Index' (duration: 84.703682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:06.100758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.261716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:06.100777Z","caller":"traceutil/trace.go:171","msg":"trace[256793539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"106.314158ms","start":"2026-02-05T17:38:05.994457Z","end":"2026-02-05T17:38:06.100771Z","steps":["trace[256793539] 'agreement among raft nodes before linearized reading' (duration: 106.259646ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:06.100743Z","caller":"traceutil/trace.go:171","msg":"trace[1280041067] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"314.18531ms","start":"2026-02-05T17:38:05.786494Z","end":"2026-02-05T17:38:06.100679Z","steps":["trace[1280041067] 'process raft request' (duration: 314.083877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:06.100875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:05.786488Z","time spent":"314.324814ms","remote":"127.0.0.1:54288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:38:06.100949Z","caller":"traceutil/trace.go:171","msg":"trace[302789199] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"303.274648ms","start":"2026-02-05T17:38:05.797669Z","end":"2026-02-05T17:38:06.100943Z","steps":["trace[302789199] 'process raft request' (duration: 302.978679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:06.10099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:05.797646Z","time spent":"303.316529ms","remote":"127.0.0.1:54200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12737,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:38:06.232708Z","caller":"traceutil/trace.go:171","msg":"trace[1592026430] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"102.993355ms","start":"2026-02-05T17:38:06.129697Z","end":"2026-02-05T17:38:06.23269Z","steps":["trace[1592026430] 'process raft request' (duration: 102.894422ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:06.420575Z","caller":"traceutil/trace.go:171","msg":"trace[1490893326] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"113.224552ms","start":"2026-02-05T17:38:06.307333Z","end":"2026-02-05T17:38:06.420558Z","steps":["trace[1490893326] 'process raft request' (duration: 84.9456ms)","trace[1490893326] 'compare' (duration: 28.080736ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:06.43861Z","caller":"traceutil/trace.go:171","msg":"trace[770258750] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"130.223975ms","start":"2026-02-05T17:38:06.308375Z","end":"2026-02-05T17:38:06.438599Z","steps":["trace[770258750] 'process raft request' (duration: 129.872695ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:06.615656Z","caller":"traceutil/trace.go:171","msg":"trace[1045562856] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:552; }","duration":"177.304163ms","start":"2026-02-05T17:38:06.43833Z","end":"2026-02-05T17:38:06.615634Z","steps":["trace[1045562856] 'read index received' (duration: 547.466µs)","trace[1045562856] 'applied index is now lower than readState.Index' (duration: 176.755847ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:06.615958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.846931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:06.616068Z","caller":"traceutil/trace.go:171","msg":"trace[1293906029] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:540; }","duration":"190.980676ms","start":"2026-02-05T17:38:06.42506Z","end":"2026-02-05T17:38:06.616041Z","steps":["trace[1293906029] 'agreement among raft nodes before linearized reading' (duration: 190.816201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:06.616075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.644121ms","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-02-05T17:38:06.616112Z","caller":"traceutil/trace.go:171","msg":"trace[871710412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"229.700363ms","start":"2026-02-05T17:38:06.386401Z","end":"2026-02-05T17:38:06.616101Z","steps":["trace[871710412] 'agreement among raft nodes before linearized reading' (duration: 229.532668ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:06.616287Z","caller":"traceutil/trace.go:171","msg":"trace[162359803] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"191.11334ms","start":"2026-02-05T17:38:06.425159Z","end":"2026-02-05T17:38:06.616272Z","steps":["trace[162359803] 'process raft request' (duration: 159.995342ms)","trace[162359803] 'compare' (duration: 30.344004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:06.616316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.857389ms","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-02-05T17:38:06.616343Z","caller":"traceutil/trace.go:171","msg":"trace[55449788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"231.892459ms","start":"2026-02-05T17:38:06.384442Z","end":"2026-02-05T17:38:06.616335Z","steps":["trace[55449788] 'agreement among raft nodes before linearized reading' (duration: 231.818097ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:06.61643Z","caller":"traceutil/trace.go:171","msg":"trace[449542774] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"191.13253ms","start":"2026-02-05T17:38:06.425289Z","end":"2026-02-05T17:38:06.616421Z","steps":["trace[449542774] 'process raft request' (duration: 190.300585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:06.616503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.9033ms","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-02-05T17:38:06.616535Z","caller":"traceutil/trace.go:171","msg":"trace[461378157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"239.956441ms","start":"2026-02-05T17:38:06.37657Z","end":"2026-02-05T17:38:06.616527Z","steps":["trace[461378157] 'agreement among raft nodes before linearized reading' (duration: 239.866378ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:06.644047Z","caller":"traceutil/trace.go:171","msg":"trace[1831776323] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"203.021328ms","start":"2026-02-05T17:38:06.441015Z","end":"2026-02-05T17:38:06.644036Z","steps":["trace[1831776323] 'process raft request' (duration: 202.926756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:06.644358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.140107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-05T17:38:06.644379Z","caller":"traceutil/trace.go:171","msg":"trace[1951514777] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:541; }","duration":"160.165547ms","start":"2026-02-05T17:38:06.484205Z","end":"2026-02-05T17:38:06.644371Z","steps":["trace[1951514777] 'agreement among raft nodes before linearized reading' (duration: 160.099335ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:08.848995Z","caller":"traceutil/trace.go:171","msg":"trace[657484236] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"187.08242ms","start":"2026-02-05T17:38:08.661898Z","end":"2026-02-05T17:38:08.848981Z","steps":["trace[657484236] 'read index received' (duration: 186.980657ms)","trace[657484236] 'applied index is now lower than readState.Index' (duration: 101.333µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:08.849062Z","caller":"traceutil/trace.go:171","msg":"trace[362326883] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"198.24916ms","start":"2026-02-05T17:38:08.650807Z","end":"2026-02-05T17:38:08.849057Z","steps":["trace[362326883] 'process raft request' (duration: 198.072565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:08.849187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.278506ms","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-02-05T17:38:08.849206Z","caller":"traceutil/trace.go:171","msg":"trace[1937291404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"187.335008ms","start":"2026-02-05T17:38:08.661862Z","end":"2026-02-05T17:38:08.849197Z","steps":["trace[1937291404] 'agreement among raft nodes before linearized reading' (duration: 187.262436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:08.849267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.286586ms","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-02-05T17:38:08.849278Z","caller":"traceutil/trace.go:171","msg":"trace[277686381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"187.304386ms","start":"2026-02-05T17:38:08.66197Z","end":"2026-02-05T17:38:08.849274Z","steps":["trace[277686381] 'agreement among raft nodes before linearized reading' (duration: 187.270335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:08.849407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.337632ms","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-02-05T17:38:08.84945Z","caller":"traceutil/trace.go:171","msg":"trace[1474702655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"178.398813ms","start":"2026-02-05T17:38:08.671038Z","end":"2026-02-05T17:38:08.849437Z","steps":["trace[1474702655] 'agreement among raft nodes before linearized reading' (duration: 178.279339ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:09.215696Z","caller":"traceutil/trace.go:171","msg":"trace[1230633152] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:568; }","duration":"219.920702ms","start":"2026-02-05T17:38:08.995756Z","end":"2026-02-05T17:38:09.215676Z","steps":["trace[1230633152] 'read index received' (duration: 216.724209ms)","trace[1230633152] 'applied index is now lower than readState.Index' (duration: 3.195953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:09.215778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.014417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:09.215769Z","caller":"traceutil/trace.go:171","msg":"trace[33089487] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"361.270144ms","start":"2026-02-05T17:38:08.854482Z","end":"2026-02-05T17:38:09.215752Z","steps":["trace[33089487] 'process raft request' (duration: 358.040379ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:09.215802Z","caller":"traceutil/trace.go:171","msg":"trace[1921799667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"220.077228ms","start":"2026-02-05T17:38:08.995717Z","end":"2026-02-05T17:38:09.215794Z","steps":["trace[1921799667] 'agreement among raft nodes before linearized reading' (duration: 220.026876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:09.215865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:08.854466Z","time spent":"361.348465ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:09.215946Z","caller":"traceutil/trace.go:171","msg":"trace[1290125352] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"358.77122ms","start":"2026-02-05T17:38:08.857166Z","end":"2026-02-05T17:38:09.215938Z","steps":["trace[1290125352] 'process raft request' (duration: 358.399869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:09.216048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:08.857158Z","time spent":"358.809321ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:09.21611Z","caller":"traceutil/trace.go:171","msg":"trace[458659272] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"357.201143ms","start":"2026-02-05T17:38:08.858901Z","end":"2026-02-05T17:38:09.216102Z","steps":["trace[458659272] 'process raft request' (duration: 356.74798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:09.216164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:08.858891Z","time spent":"357.250854ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:14.294622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067136478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T17:38:14.294679Z","caller":"traceutil/trace.go:171","msg":"trace[2081600034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"1.067222381s","start":"2026-02-05T17:38:13.227442Z","end":"2026-02-05T17:38:14.294665Z","steps":["trace[2081600034] 'range keys from in-memory index tree' (duration: 1.067048736s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:14.294706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:13.227429Z","time spent":"1.067270622s","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:38:14.294833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058923049s","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-02-05T17:38:14.294852Z","caller":"traceutil/trace.go:171","msg":"trace[235714967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"1.058955619s","start":"2026-02-05T17:38:13.235891Z","end":"2026-02-05T17:38:14.294846Z","steps":["trace[235714967] 'range keys from in-memory index tree' (duration: 1.058894347s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:14.294868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:13.235888Z","time spent":"1.05897543s","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:14.294962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059099013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:38:14.29498Z","caller":"traceutil/trace.go:171","msg":"trace[1427705705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"1.059117414s","start":"2026-02-05T17:38:13.235858Z","end":"2026-02-05T17:38:14.294975Z","steps":["trace[1427705705] 'range keys from in-memory index tree' (duration: 1.059014001s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:14.294995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:13.235846Z","time spent":"1.059145764s","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:14.295251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.291096ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:14.295298Z","caller":"traceutil/trace.go:171","msg":"trace[1438028285] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:565; }","duration":"770.338917ms","start":"2026-02-05T17:38:13.524951Z","end":"2026-02-05T17:38:14.29529Z","steps":["trace[1438028285] 'range keys from in-memory index tree' (duration: 770.284596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:14.295472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.123363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qvlx8\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-05T17:38:14.295492Z","caller":"traceutil/trace.go:171","msg":"trace[1726155712] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qvlx8; range_end:; response_count:1; response_revision:565; }","duration":"754.166545ms","start":"2026-02-05T17:38:13.54132Z","end":"2026-02-05T17:38:14.295486Z","steps":["trace[1726155712] 'range keys from in-memory index tree' (duration: 754.035131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:14.295509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:13.541306Z","time spent":"754.197485ms","remote":"127.0.0.1:54274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-qvlx8\" "} {"level":"warn","ts":"2026-02-05T17:38:14.295612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.710417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:14.295631Z","caller":"traceutil/trace.go:171","msg":"trace[93013433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"300.729307ms","start":"2026-02-05T17:38:13.994896Z","end":"2026-02-05T17:38:14.295625Z","steps":["trace[93013433] 'range keys from in-memory index tree' (duration: 300.667296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:14.295645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:13.994859Z","time spent":"300.782439ms","remote":"127.0.0.1:54098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:38:14.922343Z","caller":"traceutil/trace.go:171","msg":"trace[1403885621] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"620.403384ms","start":"2026-02-05T17:38:14.301923Z","end":"2026-02-05T17:38:14.922326Z","steps":["trace[1403885621] 'process raft request' (duration: 620.325132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:14.922439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:14.301909Z","time spent":"620.479876ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:15.051349Z","caller":"traceutil/trace.go:171","msg":"trace[810780166] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:581; }","duration":"174.446941ms","start":"2026-02-05T17:38:14.876888Z","end":"2026-02-05T17:38:15.051335Z","steps":["trace[810780166] 'read index received' (duration: 45.804299ms)","trace[810780166] 'applied index is now lower than readState.Index' (duration: 128.642102ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:15.05149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.586565ms","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-02-05T17:38:15.051511Z","caller":"traceutil/trace.go:171","msg":"trace[1134734020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"174.640667ms","start":"2026-02-05T17:38:14.876865Z","end":"2026-02-05T17:38:15.051506Z","steps":["trace[1134734020] 'agreement among raft nodes before linearized reading' (duration: 174.531134ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:15.05174Z","caller":"traceutil/trace.go:171","msg":"trace[1874910794] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"738.211586ms","start":"2026-02-05T17:38:14.313522Z","end":"2026-02-05T17:38:15.051733Z","steps":["trace[1874910794] 'process raft request' (duration: 713.694914ms)","trace[1874910794] 'compare' (duration: 23.972667ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:15.051794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:14.313491Z","time spent":"738.266137ms","remote":"127.0.0.1:54274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:15.051902Z","caller":"traceutil/trace.go:171","msg":"trace[1988118436] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"736.638062ms","start":"2026-02-05T17:38:14.315258Z","end":"2026-02-05T17:38:15.051896Z","steps":["trace[1988118436] 'process raft request' (duration: 736.009235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:15.051929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:14.315248Z","time spent":"736.668074ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:15.051984Z","caller":"traceutil/trace.go:171","msg":"trace[158798200] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"735.637214ms","start":"2026-02-05T17:38:14.316343Z","end":"2026-02-05T17:38:15.05198Z","steps":["trace[158798200] 'process raft request' (duration: 734.953816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:15.052025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:14.316336Z","time spent":"735.676065ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:15.052123Z","caller":"traceutil/trace.go:171","msg":"trace[713812690] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"452.95835ms","start":"2026-02-05T17:38:14.59916Z","end":"2026-02-05T17:38:15.052119Z","steps":["trace[713812690] 'process raft request' (duration: 452.153008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:15.052148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:14.599138Z","time spent":"452.995091ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:17.648938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:15.512236Z","time spent":"2.136696261s","remote":"127.0.0.1:40232","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T17:38:17.649229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.390613767s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:38:17.649305Z","caller":"traceutil/trace.go:171","msg":"trace[1896010874] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"2.422872842s","start":"2026-02-05T17:38:15.226417Z","end":"2026-02-05T17:38:17.64929Z","steps":["trace[1896010874] 'read index received' (duration: 32.09459ms)","trace[1896010874] 'applied index is now lower than readState.Index' (duration: 2.390777202s)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:17.649477Z","caller":"traceutil/trace.go:171","msg":"trace[926915329] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"2.588689241s","start":"2026-02-05T17:38:15.060775Z","end":"2026-02-05T17:38:17.649465Z","steps":["trace[926915329] 'process raft request' (duration: 197.774025ms)","trace[926915329] 'compare' (duration: 2.390330279s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:17.649531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:15.060754Z","time spent":"2.588747973s","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:17.649811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.423395056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T17:38:17.649841Z","caller":"traceutil/trace.go:171","msg":"trace[1927143986] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:571; }","duration":"2.423455477s","start":"2026-02-05T17:38:15.226374Z","end":"2026-02-05T17:38:17.649829Z","steps":["trace[1927143986] 'agreement among raft nodes before linearized reading' (duration: 2.423355865s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:17.649868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:15.226362Z","time spent":"2.423500248s","remote":"127.0.0.1:54128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-02-05T17:38:17.649996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657231698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:17.650021Z","caller":"traceutil/trace.go:171","msg":"trace[1919376099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"1.657276499s","start":"2026-02-05T17:38:15.992737Z","end":"2026-02-05T17:38:17.650013Z","steps":["trace[1919376099] 'agreement among raft nodes before linearized reading' (duration: 1.657241008s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:17.650049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:15.992724Z","time spent":"1.65732003s","remote":"127.0.0.1:54098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:38:17.650258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.293544ms","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-02-05T17:38:17.650429Z","caller":"traceutil/trace.go:171","msg":"trace[395732320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"585.484538ms","start":"2026-02-05T17:38:17.064937Z","end":"2026-02-05T17:38:17.650422Z","steps":["trace[395732320] 'agreement among raft nodes before linearized reading' (duration: 585.266082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:17.650453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:17.064927Z","time spent":"585.52076ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:17.650596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.869927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:38:17.65062Z","caller":"traceutil/trace.go:171","msg":"trace[1088009015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"586.926888ms","start":"2026-02-05T17:38:17.063686Z","end":"2026-02-05T17:38:17.650613Z","steps":["trace[1088009015] 'agreement among raft nodes before linearized reading' (duration: 586.856146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:17.650643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:17.063667Z","time spent":"586.971159ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:17.650752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.052788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T17:38:17.650775Z","caller":"traceutil/trace.go:171","msg":"trace[606580751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"723.110599ms","start":"2026-02-05T17:38:16.927658Z","end":"2026-02-05T17:38:17.650769Z","steps":["trace[606580751] 'agreement among raft nodes before linearized reading' (duration: 723.072518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:17.650816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:16.927639Z","time spent":"723.171241ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:38:18.834448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.884792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:38:18.834533Z","caller":"traceutil/trace.go:171","msg":"trace[1306182995] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"180.873724ms","start":"2026-02-05T17:38:18.653633Z","end":"2026-02-05T17:38:18.834507Z","steps":["trace[1306182995] 'process raft request' (duration: 13.876029ms)","trace[1306182995] 'compare' (duration: 166.79376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:20.306202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.92209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:20.306258Z","caller":"traceutil/trace.go:171","msg":"trace[1799737483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"309.018623ms","start":"2026-02-05T17:38:19.997222Z","end":"2026-02-05T17:38:20.30624Z","steps":["trace[1799737483] 'range keys from in-memory index tree' (duration: 308.851108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:20.306372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:19.997198Z","time spent":"309.166986ms","remote":"127.0.0.1:54098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:38:22.968226Z","caller":"traceutil/trace.go:171","msg":"trace[354487753] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"171.919517ms","start":"2026-02-05T17:38:22.796288Z","end":"2026-02-05T17:38:22.968208Z","steps":["trace[354487753] 'process raft request' (duration: 171.748943ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:29.078841Z","caller":"traceutil/trace.go:171","msg":"trace[1404665593] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"143.014525ms","start":"2026-02-05T17:38:28.935813Z","end":"2026-02-05T17:38:29.078827Z","steps":["trace[1404665593] 'process raft request' (duration: 142.641966ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:38.010476Z","caller":"traceutil/trace.go:171","msg":"trace[910134314] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"180.236275ms","start":"2026-02-05T17:38:37.830215Z","end":"2026-02-05T17:38:38.010452Z","steps":["trace[910134314] 'read index received' (duration: 179.9808ms)","trace[910134314] 'applied index is now lower than readState.Index' (duration: 254.545µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:38.010735Z","caller":"traceutil/trace.go:171","msg":"trace[2132459266] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"215.413316ms","start":"2026-02-05T17:38:37.79531Z","end":"2026-02-05T17:38:38.010723Z","steps":["trace[2132459266] 'process raft request' (duration: 214.951706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:38.010971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.740086ms","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-02-05T17:38:38.011006Z","caller":"traceutil/trace.go:171","msg":"trace[678113630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"180.817898ms","start":"2026-02-05T17:38:37.830176Z","end":"2026-02-05T17:38:38.010994Z","steps":["trace[678113630] 'agreement among raft nodes before linearized reading' (duration: 180.703345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:38.011206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.103638ms","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-02-05T17:38:38.011234Z","caller":"traceutil/trace.go:171","msg":"trace[1692780112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"156.132089ms","start":"2026-02-05T17:38:37.855094Z","end":"2026-02-05T17:38:38.011226Z","steps":["trace[1692780112] 'agreement among raft nodes before linearized reading' (duration: 156.053907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:38.011983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.091192ms","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-02-05T17:38:38.012053Z","caller":"traceutil/trace.go:171","msg":"trace[1996664917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"149.188654ms","start":"2026-02-05T17:38:37.86285Z","end":"2026-02-05T17:38:38.012039Z","steps":["trace[1996664917] 'agreement among raft nodes before linearized reading' (duration: 149.04506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:04.52903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.58502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:04.529131Z","caller":"traceutil/trace.go:171","msg":"trace[77506471] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"264.501906ms","start":"2026-02-05T17:39:04.264609Z","end":"2026-02-05T17:39:04.52911Z","steps":["trace[77506471] 'process raft request' (duration: 75.742863ms)","trace[77506471] 'compare' (duration: 188.497408ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:04.595352Z","caller":"traceutil/trace.go:171","msg":"trace[1775562918] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"329.208813ms","start":"2026-02-05T17:39:04.266124Z","end":"2026-02-05T17:39:04.595333Z","steps":["trace[1775562918] 'process raft request' (duration: 329.184393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:04.595464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:04.266117Z","time spent":"329.290295ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:04.595617Z","caller":"traceutil/trace.go:171","msg":"trace[1762562553] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"330.764081ms","start":"2026-02-05T17:39:04.264802Z","end":"2026-02-05T17:39:04.595566Z","steps":["trace[1762562553] 'process raft request' (duration: 330.419794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:04.595834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:04.264797Z","time spent":"330.915513ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:05.347923Z","caller":"traceutil/trace.go:171","msg":"trace[1883511027] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"110.171274ms","start":"2026-02-05T17:39:05.237733Z","end":"2026-02-05T17:39:05.347905Z","steps":["trace[1883511027] 'process raft request' (duration: 93.136382ms)","trace[1883511027] 'compare' (duration: 16.953341ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:06.844141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.928859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:06.844249Z","caller":"traceutil/trace.go:171","msg":"trace[1397056091] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"244.61703ms","start":"2026-02-05T17:39:06.59962Z","end":"2026-02-05T17:39:06.844237Z","steps":["trace[1397056091] 'read index received' (duration: 143.502647ms)","trace[1397056091] 'applied index is now lower than readState.Index' (duration: 101.113592ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:06.844308Z","caller":"traceutil/trace.go:171","msg":"trace[1924765111] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"306.921637ms","start":"2026-02-05T17:39:06.53738Z","end":"2026-02-05T17:39:06.844301Z","steps":["trace[1924765111] 'process raft request' (duration: 205.759593ms)","trace[1924765111] 'compare' (duration: 100.817667ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:06.844343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:06.537364Z","time spent":"306.961167ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:06.844419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.014038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:39:06.844462Z","caller":"traceutil/trace.go:171","msg":"trace[835139902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:863; }","duration":"244.07492ms","start":"2026-02-05T17:39:06.600376Z","end":"2026-02-05T17:39:06.844451Z","steps":["trace[835139902] 'agreement among raft nodes before linearized reading' (duration: 243.952788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:06.844558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.955206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T17:39:06.844569Z","caller":"traceutil/trace.go:171","msg":"trace[439590802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:863; }","duration":"244.990026ms","start":"2026-02-05T17:39:06.599575Z","end":"2026-02-05T17:39:06.844565Z","steps":["trace[439590802] 'agreement among raft nodes before linearized reading' (duration: 244.952085ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:09.260527Z","caller":"traceutil/trace.go:171","msg":"trace[1836829665] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"400.761799ms","start":"2026-02-05T17:39:08.859743Z","end":"2026-02-05T17:39:09.260505Z","steps":["trace[1836829665] 'read index received' (duration: 400.648967ms)","trace[1836829665] 'applied index is now lower than readState.Index' (duration: 111.682µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:09.261262Z","caller":"traceutil/trace.go:171","msg":"trace[393824117] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"408.447472ms","start":"2026-02-05T17:39:08.852796Z","end":"2026-02-05T17:39:09.261243Z","steps":["trace[393824117] 'process raft request' (duration: 407.552297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:09.261547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.302961ms","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-02-05T17:39:09.26162Z","caller":"traceutil/trace.go:171","msg":"trace[1589577391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:868; }","duration":"324.412653ms","start":"2026-02-05T17:39:08.937189Z","end":"2026-02-05T17:39:09.261602Z","steps":["trace[1589577391] 'agreement among raft nodes before linearized reading' (duration: 324.192889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:09.261697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:08.937171Z","time spent":"324.515855ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:39:09.26202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.249155ms","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-02-05T17:39:09.262056Z","caller":"traceutil/trace.go:171","msg":"trace[1162382395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:868; }","duration":"402.325977ms","start":"2026-02-05T17:39:08.859721Z","end":"2026-02-05T17:39:09.262047Z","steps":["trace[1162382395] 'agreement among raft nodes before linearized reading' (duration: 402.208785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:09.262084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:08.859712Z","time spent":"402.365578ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:39:09.262427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.810601ms","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-02-05T17:39:09.262466Z","caller":"traceutil/trace.go:171","msg":"trace[982385959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"248.874842ms","start":"2026-02-05T17:39:09.013582Z","end":"2026-02-05T17:39:09.262457Z","steps":["trace[982385959] 'agreement among raft nodes before linearized reading' (duration: 248.790271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:09.263071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.867261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:09.263109Z","caller":"traceutil/trace.go:171","msg":"trace[397929452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:868; }","duration":"267.930502ms","start":"2026-02-05T17:39:08.995169Z","end":"2026-02-05T17:39:09.2631Z","steps":["trace[397929452] 'agreement among raft nodes before linearized reading' (duration: 267.843291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:09.264087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:08.852783Z","time spent":"408.762127ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:09.41014Z","caller":"traceutil/trace.go:171","msg":"trace[1455993287] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"138.095321ms","start":"2026-02-05T17:39:09.272021Z","end":"2026-02-05T17:39:09.410116Z","steps":["trace[1455993287] 'process raft request' (duration: 137.972779ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:09.41019Z","caller":"traceutil/trace.go:171","msg":"trace[1175599529] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:897; }","duration":"134.205125ms","start":"2026-02-05T17:39:09.275959Z","end":"2026-02-05T17:39:09.410164Z","steps":["trace[1175599529] 'read index received' (duration: 134.197685ms)","trace[1175599529] 'applied index is now lower than readState.Index' (duration: 6.11µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:09.410774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.791355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-02-05T17:39:09.41093Z","caller":"traceutil/trace.go:171","msg":"trace[1649320762] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:869; }","duration":"134.980198ms","start":"2026-02-05T17:39:09.275936Z","end":"2026-02-05T17:39:09.410916Z","steps":["trace[1649320762] 'agreement among raft nodes before linearized reading' (duration: 134.672663ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:09.435641Z","caller":"traceutil/trace.go:171","msg":"trace[316396981] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"159.534952ms","start":"2026-02-05T17:39:09.276096Z","end":"2026-02-05T17:39:09.435631Z","steps":["trace[316396981] 'process raft request' (duration: 159.287048ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:09.436459Z","caller":"traceutil/trace.go:171","msg":"trace[1332600504] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"159.340508ms","start":"2026-02-05T17:39:09.277109Z","end":"2026-02-05T17:39:09.436449Z","steps":["trace[1332600504] 'process raft request' (duration: 159.286267ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:09.436584Z","caller":"traceutil/trace.go:171","msg":"trace[243639273] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"160.432497ms","start":"2026-02-05T17:39:09.276145Z","end":"2026-02-05T17:39:09.436577Z","steps":["trace[243639273] 'process raft request' (duration: 159.332638ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:13.752748Z","caller":"traceutil/trace.go:171","msg":"trace[1447697342] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"179.827649ms","start":"2026-02-05T17:39:13.5729Z","end":"2026-02-05T17:39:13.752728Z","steps":["trace[1447697342] 'process raft request' (duration: 179.689527ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:13.764202Z","caller":"traceutil/trace.go:171","msg":"trace[658993620] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"188.592775ms","start":"2026-02-05T17:39:13.575598Z","end":"2026-02-05T17:39:13.764191Z","steps":["trace[658993620] 'process raft request' (duration: 188.536604ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:15.954042Z","caller":"traceutil/trace.go:171","msg":"trace[1058711291] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"187.000427ms","start":"2026-02-05T17:39:15.767028Z","end":"2026-02-05T17:39:15.954028Z","steps":["trace[1058711291] 'read index received' (duration: 186.894395ms)","trace[1058711291] 'applied index is now lower than readState.Index' (duration: 105.542µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:15.954664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.622388ms","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-02-05T17:39:15.954683Z","caller":"traceutil/trace.go:171","msg":"trace[959134308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"187.668569ms","start":"2026-02-05T17:39:15.767009Z","end":"2026-02-05T17:39:15.954678Z","steps":["trace[959134308] 'agreement among raft nodes before linearized reading' (duration: 187.071408ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:15.954819Z","caller":"traceutil/trace.go:171","msg":"trace[1498387713] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"196.516775ms","start":"2026-02-05T17:39:15.758295Z","end":"2026-02-05T17:39:15.954812Z","steps":["trace[1498387713] 'process raft request' (duration: 195.65941ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:18.27124Z","caller":"traceutil/trace.go:171","msg":"trace[1067559042] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"303.934301ms","start":"2026-02-05T17:39:17.967285Z","end":"2026-02-05T17:39:18.271219Z","steps":["trace[1067559042] 'read index received' (duration: 303.780989ms)","trace[1067559042] 'applied index is now lower than readState.Index' (duration: 152.452µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:18.271445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.141744ms","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-02-05T17:39:18.271483Z","caller":"traceutil/trace.go:171","msg":"trace[786713707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:893; }","duration":"304.207985ms","start":"2026-02-05T17:39:17.967266Z","end":"2026-02-05T17:39:18.271474Z","steps":["trace[786713707] 'agreement among raft nodes before linearized reading' (duration: 304.043043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:18.271523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:17.967258Z","time spent":"304.256316ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T17:39:18.271795Z","caller":"traceutil/trace.go:171","msg":"trace[1576796022] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"310.811733ms","start":"2026-02-05T17:39:17.960972Z","end":"2026-02-05T17:39:18.271783Z","steps":["trace[1576796022] 'process raft request' (duration: 310.148333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:18.271877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:17.960959Z","time spent":"310.868914ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:18.522479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.985506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:18.52256Z","caller":"traceutil/trace.go:171","msg":"trace[1653383086] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"246.2484ms","start":"2026-02-05T17:39:18.2763Z","end":"2026-02-05T17:39:18.522548Z","steps":["trace[1653383086] 'process raft request' (duration: 88.144873ms)","trace[1653383086] 'compare' (duration: 157.917784ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:19.550322Z","caller":"traceutil/trace.go:171","msg":"trace[412319549] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"174.395225ms","start":"2026-02-05T17:39:19.375908Z","end":"2026-02-05T17:39:19.550303Z","steps":["trace[412319549] 'process raft request' (duration: 173.941518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:19.824562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.638085ms","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-02-05T17:39:19.824648Z","caller":"traceutil/trace.go:171","msg":"trace[1782097394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"123.796318ms","start":"2026-02-05T17:39:19.70083Z","end":"2026-02-05T17:39:19.824626Z","steps":["trace[1782097394] 'range keys from in-memory index tree' (duration: 123.475182ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:19.995219Z","caller":"traceutil/trace.go:171","msg":"trace[519878103] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"231.402655ms","start":"2026-02-05T17:39:19.763799Z","end":"2026-02-05T17:39:19.995201Z","steps":["trace[519878103] 'process raft request' (duration: 231.183262ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:20.201658Z","caller":"traceutil/trace.go:171","msg":"trace[705230123] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"219.397102ms","start":"2026-02-05T17:39:19.982238Z","end":"2026-02-05T17:39:20.201635Z","steps":["trace[705230123] 'read index received' (duration: 13.330706ms)","trace[705230123] 'applied index is now lower than readState.Index' (duration: 206.065526ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:20.201947Z","caller":"traceutil/trace.go:171","msg":"trace[1086051353] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"372.957589ms","start":"2026-02-05T17:39:19.828983Z","end":"2026-02-05T17:39:20.201941Z","steps":["trace[1086051353] 'process raft request' (duration: 281.821087ms)","trace[1086051353] 'compare' (duration: 90.737875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:20.202025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:19.828968Z","time spent":"373.00079ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:20.202133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.89387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:39:20.202155Z","caller":"traceutil/trace.go:171","msg":"trace[1529300208] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:898; }","duration":"219.94318ms","start":"2026-02-05T17:39:19.982207Z","end":"2026-02-05T17:39:20.20215Z","steps":["trace[1529300208] 'agreement among raft nodes before linearized reading' (duration: 219.906889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:20.202408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.170524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329940"} {"level":"info","ts":"2026-02-05T17:39:20.202431Z","caller":"traceutil/trace.go:171","msg":"trace[1672031506] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:898; }","duration":"179.220364ms","start":"2026-02-05T17:39:20.023206Z","end":"2026-02-05T17:39:20.202426Z","steps":["trace[1672031506] 'agreement among raft nodes before linearized reading' (duration: 179.015081ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:20.643344Z","caller":"traceutil/trace.go:171","msg":"trace[1249805618] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"288.644129ms","start":"2026-02-05T17:39:20.354683Z","end":"2026-02-05T17:39:20.643327Z","steps":["trace[1249805618] 'read index received' (duration: 249.41777ms)","trace[1249805618] 'applied index is now lower than readState.Index' (duration: 39.225739ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:20.644096Z","caller":"traceutil/trace.go:171","msg":"trace[1988513122] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"365.263899ms","start":"2026-02-05T17:39:20.278815Z","end":"2026-02-05T17:39:20.644078Z","steps":["trace[1988513122] 'process raft request' (duration: 325.519111ms)","trace[1988513122] 'compare' (duration: 38.934164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:20.644179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:20.278802Z","time spent":"365.31974ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:20.644327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.643046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:20.644356Z","caller":"traceutil/trace.go:171","msg":"trace[1962789715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"289.689546ms","start":"2026-02-05T17:39:20.354652Z","end":"2026-02-05T17:39:20.644341Z","steps":["trace[1962789715] 'agreement among raft nodes before linearized reading' (duration: 289.654106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:20.644917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.876816ms","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-02-05T17:39:20.644945Z","caller":"traceutil/trace.go:171","msg":"trace[672955856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"118.897807ms","start":"2026-02-05T17:39:20.526033Z","end":"2026-02-05T17:39:20.644931Z","steps":["trace[672955856] 'agreement among raft nodes before linearized reading' (duration: 118.739374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:20.806622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.918023ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-05T17:39:20.806715Z","caller":"traceutil/trace.go:171","msg":"trace[2007267363] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"145.077308ms","start":"2026-02-05T17:39:20.66162Z","end":"2026-02-05T17:39:20.806697Z","steps":["trace[2007267363] 'read index received' (duration: 20.347346ms)","trace[2007267363] 'applied index is now lower than readState.Index' (duration: 124.728372ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:20.806864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.23483ms","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-02-05T17:39:20.806894Z","caller":"traceutil/trace.go:171","msg":"trace[1403536299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"145.28433ms","start":"2026-02-05T17:39:20.661598Z","end":"2026-02-05T17:39:20.806882Z","steps":["trace[1403536299] 'agreement among raft nodes before linearized reading' (duration: 145.145908ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:22.321594Z","caller":"traceutil/trace.go:171","msg":"trace[504595285] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"111.18059ms","start":"2026-02-05T17:39:22.210397Z","end":"2026-02-05T17:39:22.321578Z","steps":["trace[504595285] 'process raft request' (duration: 111.093479ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:22.79759Z","caller":"traceutil/trace.go:171","msg":"trace[2013711007] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"106.536228ms","start":"2026-02-05T17:39:22.691034Z","end":"2026-02-05T17:39:22.79757Z","steps":["trace[2013711007] 'process raft request' (duration: 77.245544ms)","trace[2013711007] 'compare' (duration: 29.195252ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:26.966339Z","caller":"traceutil/trace.go:171","msg":"trace[1756046625] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"144.068968ms","start":"2026-02-05T17:39:26.822254Z","end":"2026-02-05T17:39:26.966323Z","steps":["trace[1756046625] 'read index received' (duration: 134.985387ms)","trace[1756046625] 'applied index is now lower than readState.Index' (duration: 9.082861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:26.966478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.20975ms","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-02-05T17:39:26.966496Z","caller":"traceutil/trace.go:171","msg":"trace[1686775018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"144.271032ms","start":"2026-02-05T17:39:26.822219Z","end":"2026-02-05T17:39:26.96649Z","steps":["trace[1686775018] 'agreement among raft nodes before linearized reading' (duration: 144.16581ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:26.966711Z","caller":"traceutil/trace.go:171","msg":"trace[623894779] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"237.133077ms","start":"2026-02-05T17:39:26.729572Z","end":"2026-02-05T17:39:26.966705Z","steps":["trace[623894779] 'process raft request' (duration: 227.711251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:29.899778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.04852ms","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-02-05T17:39:29.899856Z","caller":"traceutil/trace.go:171","msg":"trace[1449999426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"219.176851ms","start":"2026-02-05T17:39:29.680661Z","end":"2026-02-05T17:39:29.899838Z","steps":["trace[1449999426] 'range keys from in-memory index tree' (duration: 218.869575ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:30.26265Z","caller":"traceutil/trace.go:171","msg":"trace[228599991] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"127.17032ms","start":"2026-02-05T17:39:30.135459Z","end":"2026-02-05T17:39:30.26263Z","steps":["trace[228599991] 'read index received' (duration: 127.023318ms)","trace[228599991] 'applied index is now lower than readState.Index' (duration: 145.962µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:30.26275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.273962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:30.26278Z","caller":"traceutil/trace.go:171","msg":"trace[1317572112] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:927; }","duration":"127.315543ms","start":"2026-02-05T17:39:30.135455Z","end":"2026-02-05T17:39:30.262771Z","steps":["trace[1317572112] 'agreement among raft nodes before linearized reading' (duration: 127.256502ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:30.262939Z","caller":"traceutil/trace.go:171","msg":"trace[2067954140] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"133.807801ms","start":"2026-02-05T17:39:30.129105Z","end":"2026-02-05T17:39:30.262913Z","steps":["trace[2067954140] 'process raft request' (duration: 133.390165ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:30.50032Z","caller":"traceutil/trace.go:171","msg":"trace[109354373] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"231.66619ms","start":"2026-02-05T17:39:30.268636Z","end":"2026-02-05T17:39:30.500302Z","steps":["trace[109354373] 'process raft request' (duration: 194.058712ms)","trace[109354373] 'compare' (duration: 37.246723ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:30.776267Z","caller":"traceutil/trace.go:171","msg":"trace[808250881] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"134.531412ms","start":"2026-02-05T17:39:30.641709Z","end":"2026-02-05T17:39:30.776241Z","steps":["trace[808250881] 'process raft request' (duration: 124.932357ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:30.776928Z","caller":"traceutil/trace.go:171","msg":"trace[1389478522] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"131.664499ms","start":"2026-02-05T17:39:30.645251Z","end":"2026-02-05T17:39:30.776916Z","steps":["trace[1389478522] 'read index received' (duration: 121.399144ms)","trace[1389478522] 'applied index is now lower than readState.Index' (duration: 10.264015ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:30.919409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.222804ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-05T17:39:30.920094Z","caller":"traceutil/trace.go:171","msg":"trace[126755807] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"116.714524ms","start":"2026-02-05T17:39:30.803353Z","end":"2026-02-05T17:39:30.920068Z","steps":["trace[126755807] 'read index received' (duration: 37.581µs)","trace[126755807] 'applied index is now lower than readState.Index' (duration: 116.673983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:30.920525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.11348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-05T17:39:30.920762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.443531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4912"} {"level":"info","ts":"2026-02-05T17:39:30.920905Z","caller":"traceutil/trace.go:171","msg":"trace[253028762] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:932; }","duration":"275.642664ms","start":"2026-02-05T17:39:30.645232Z","end":"2026-02-05T17:39:30.920874Z","steps":["trace[253028762] 'agreement among raft nodes before linearized reading' (duration: 131.955433ms)","trace[253028762] 'range keys from in-memory index tree' (duration: 143.288805ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:30.920588Z","caller":"traceutil/trace.go:171","msg":"trace[7884956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:932; }","duration":"117.253191ms","start":"2026-02-05T17:39:30.803317Z","end":"2026-02-05T17:39:30.920571Z","steps":["trace[7884956] 'agreement among raft nodes before linearized reading' (duration: 116.834955ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:31.159813Z","caller":"traceutil/trace.go:171","msg":"trace[1763532215] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"174.241529ms","start":"2026-02-05T17:39:30.985549Z","end":"2026-02-05T17:39:31.15979Z","steps":["trace[1763532215] 'process raft request' (duration: 150.915999ms)","trace[1763532215] 'compare' (duration: 22.792732ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:32.294124Z","caller":"traceutil/trace.go:171","msg":"trace[1991060976] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"224.128538ms","start":"2026-02-05T17:39:32.069971Z","end":"2026-02-05T17:39:32.294099Z","steps":["trace[1991060976] 'process raft request' (duration: 223.842624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:32.489893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.471554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:32.490015Z","caller":"traceutil/trace.go:171","msg":"trace[1755495761] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"184.420235ms","start":"2026-02-05T17:39:32.305566Z","end":"2026-02-05T17:39:32.489986Z","steps":["trace[1755495761] 'process raft request' (duration: 54.739048ms)","trace[1755495761] 'compare' (duration: 129.296271ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:32.763497Z","caller":"traceutil/trace.go:171","msg":"trace[881551351] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"172.601119ms","start":"2026-02-05T17:39:32.590866Z","end":"2026-02-05T17:39:32.763467Z","steps":["trace[881551351] 'process raft request' (duration: 86.685945ms)","trace[881551351] 'compare' (duration: 85.806572ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:33.331192Z","caller":"traceutil/trace.go:171","msg":"trace[377554594] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"130.053773ms","start":"2026-02-05T17:39:33.201115Z","end":"2026-02-05T17:39:33.331169Z","steps":["trace[377554594] 'read index received' (duration: 129.921941ms)","trace[377554594] 'applied index is now lower than readState.Index' (duration: 131.102µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:33.3313Z","caller":"traceutil/trace.go:171","msg":"trace[481209975] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"263.533476ms","start":"2026-02-05T17:39:33.067756Z","end":"2026-02-05T17:39:33.33129Z","steps":["trace[481209975] 'process raft request' (duration: 263.304942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:33.33159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.461349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:39:33.331618Z","caller":"traceutil/trace.go:171","msg":"trace[1331015481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"130.527739ms","start":"2026-02-05T17:39:33.201083Z","end":"2026-02-05T17:39:33.33161Z","steps":["trace[1331015481] 'agreement among raft nodes before linearized reading' (duration: 130.455028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:33.588341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.832112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:33.58842Z","caller":"traceutil/trace.go:171","msg":"trace[1835882664] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"231.688302ms","start":"2026-02-05T17:39:33.356718Z","end":"2026-02-05T17:39:33.588406Z","steps":["trace[1835882664] 'read index received' (duration: 59.691007ms)","trace[1835882664] 'applied index is now lower than readState.Index' (duration: 171.996245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:33.588553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.840264ms","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-02-05T17:39:33.588579Z","caller":"traceutil/trace.go:171","msg":"trace[1313967882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"231.894514ms","start":"2026-02-05T17:39:33.356675Z","end":"2026-02-05T17:39:33.588569Z","steps":["trace[1313967882] 'agreement among raft nodes before linearized reading' (duration: 231.771282ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:33.588703Z","caller":"traceutil/trace.go:171","msg":"trace[228459307] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"252.98228ms","start":"2026-02-05T17:39:33.335711Z","end":"2026-02-05T17:39:33.588693Z","steps":["trace[228459307] 'process raft request' (duration: 80.72425ms)","trace[228459307] 'compare' (duration: 171.755651ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:33.793776Z","caller":"traceutil/trace.go:171","msg":"trace[2043440947] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"196.121544ms","start":"2026-02-05T17:39:33.597633Z","end":"2026-02-05T17:39:33.793755Z","steps":["trace[2043440947] 'process raft request' (duration: 111.68295ms)","trace[2043440947] 'compare' (duration: 84.351283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:35.8224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.391996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:35.822474Z","caller":"traceutil/trace.go:171","msg":"trace[1644943447] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"151.901992ms","start":"2026-02-05T17:39:35.670559Z","end":"2026-02-05T17:39:35.822461Z","steps":["trace[1644943447] 'process raft request' (duration: 36.393745ms)","trace[1644943447] 'compare' (duration: 115.268723ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:36.951487Z","caller":"traceutil/trace.go:171","msg":"trace[537893685] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"114.888ms","start":"2026-02-05T17:39:36.83643Z","end":"2026-02-05T17:39:36.951318Z","steps":["trace[537893685] 'process raft request' (duration: 114.763948ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:37.870555Z","caller":"traceutil/trace.go:171","msg":"trace[164076228] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"194.432986ms","start":"2026-02-05T17:39:37.676101Z","end":"2026-02-05T17:39:37.870534Z","steps":["trace[164076228] 'process raft request' (duration: 194.125602ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:38.560318Z","caller":"traceutil/trace.go:171","msg":"trace[1812859779] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"121.868132ms","start":"2026-02-05T17:39:38.438421Z","end":"2026-02-05T17:39:38.560289Z","steps":["trace[1812859779] 'process raft request' (duration: 104.281698ms)","trace[1812859779] 'compare' (duration: 17.460363ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:39.123064Z","caller":"traceutil/trace.go:171","msg":"trace[1178285237] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"139.117943ms","start":"2026-02-05T17:39:38.983932Z","end":"2026-02-05T17:39:39.12305Z","steps":["trace[1178285237] 'process raft request' (duration: 117.114427ms)","trace[1178285237] 'compare' (duration: 21.945206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:39.923215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.676359ms","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-02-05T17:39:39.923285Z","caller":"traceutil/trace.go:171","msg":"trace[1169430791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"515.874582ms","start":"2026-02-05T17:39:39.407393Z","end":"2026-02-05T17:39:39.923268Z","steps":["trace[1169430791] 'range keys from in-memory index tree' (duration: 515.456926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:39.923322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:39.407375Z","time spent":"515.937682ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:39:39.923551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.583058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-155ce174-acfd-4ad1-9cb4-f058807baeae\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-05T17:39:39.92358Z","caller":"traceutil/trace.go:171","msg":"trace[1295152711] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-155ce174-acfd-4ad1-9cb4-f058807baeae; range_end:; response_count:1; response_revision:963; }","duration":"481.67269ms","start":"2026-02-05T17:39:39.441898Z","end":"2026-02-05T17:39:39.923571Z","steps":["trace[1295152711] 'range keys from in-memory index tree' (duration: 481.453327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:39.923606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:39.441886Z","time spent":"481.714ms","remote":"127.0.0.1:54274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3557,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-155ce174-acfd-4ad1-9cb4-f058807baeae\" "} {"level":"info","ts":"2026-02-05T17:39:40.085443Z","caller":"traceutil/trace.go:171","msg":"trace[951763742] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"153.854578ms","start":"2026-02-05T17:39:39.931574Z","end":"2026-02-05T17:39:40.085428Z","steps":["trace[951763742] 'process raft request' (duration: 153.772897ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:40.270424Z","caller":"traceutil/trace.go:171","msg":"trace[470651642] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"338.45654ms","start":"2026-02-05T17:39:39.931946Z","end":"2026-02-05T17:39:40.270403Z","steps":["trace[470651642] 'process raft request' (duration: 338.233167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:40.272685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:39.931941Z","time spent":"340.653101ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:40.270594Z","caller":"traceutil/trace.go:171","msg":"trace[1633530520] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:998; }","duration":"273.927662ms","start":"2026-02-05T17:39:39.996643Z","end":"2026-02-05T17:39:40.27057Z","steps":["trace[1633530520] 'read index received' (duration: 88.66513ms)","trace[1633530520] 'applied index is now lower than readState.Index' (duration: 185.261462ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:40.270693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.033953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:40.271031Z","caller":"traceutil/trace.go:171","msg":"trace[1117934143] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"336.442601ms","start":"2026-02-05T17:39:39.934455Z","end":"2026-02-05T17:39:40.270897Z","steps":["trace[1117934143] 'process raft request' (duration: 335.902454ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:40.271133Z","caller":"traceutil/trace.go:171","msg":"trace[1979045374] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"337.028909ms","start":"2026-02-05T17:39:39.934094Z","end":"2026-02-05T17:39:40.271123Z","steps":["trace[1979045374] 'process raft request' (duration: 336.200668ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:40.275653Z","caller":"traceutil/trace.go:171","msg":"trace[397337957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"279.042924ms","start":"2026-02-05T17:39:39.996602Z","end":"2026-02-05T17:39:40.275645Z","steps":["trace[397337957] 'agreement among raft nodes before linearized reading' (duration: 274.035142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:40.27572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:39.934198Z","time spent":"341.481253ms","remote":"127.0.0.1:54274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3621,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:40.276165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:39.934088Z","time spent":"341.682616ms","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:41.28517Z","caller":"traceutil/trace.go:171","msg":"trace[2003344891] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"154.754604ms","start":"2026-02-05T17:39:41.130397Z","end":"2026-02-05T17:39:41.285151Z","steps":["trace[2003344891] 'process raft request' (duration: 154.49367ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:42.479943Z","caller":"traceutil/trace.go:171","msg":"trace[761387679] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"134.613798ms","start":"2026-02-05T17:39:42.345307Z","end":"2026-02-05T17:39:42.479921Z","steps":["trace[761387679] 'read index received' (duration: 29.859874ms)","trace[761387679] 'applied index is now lower than readState.Index' (duration: 104.752854ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:42.480446Z","caller":"traceutil/trace.go:171","msg":"trace[34977229] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"145.062246ms","start":"2026-02-05T17:39:42.335371Z","end":"2026-02-05T17:39:42.480434Z","steps":["trace[34977229] 'process raft request' (duration: 95.583594ms)","trace[34977229] 'compare' (duration: 48.885213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:42.48076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.43759ms","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-02-05T17:39:42.480854Z","caller":"traceutil/trace.go:171","msg":"trace[1252165504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"135.554771ms","start":"2026-02-05T17:39:42.345289Z","end":"2026-02-05T17:39:42.480843Z","steps":["trace[1252165504] 'agreement among raft nodes before linearized reading' (duration: 135.395009ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:42.61001Z","caller":"traceutil/trace.go:171","msg":"trace[2039512577] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"125.245405ms","start":"2026-02-05T17:39:42.484741Z","end":"2026-02-05T17:39:42.609987Z","steps":["trace[2039512577] 'process raft request' (duration: 97.923458ms)","trace[2039512577] 'compare' (duration: 27.205685ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:43.177666Z","caller":"traceutil/trace.go:171","msg":"trace[352591716] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"131.600145ms","start":"2026-02-05T17:39:43.046038Z","end":"2026-02-05T17:39:43.177638Z","steps":["trace[352591716] 'process raft request' (duration: 56.927203ms)","trace[352591716] 'compare' (duration: 74.55102ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:43.407844Z","caller":"traceutil/trace.go:171","msg":"trace[1714491233] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"118.711034ms","start":"2026-02-05T17:39:43.289114Z","end":"2026-02-05T17:39:43.407825Z","steps":["trace[1714491233] 'read index received' (duration: 78.633969ms)","trace[1714491233] 'applied index is now lower than readState.Index' (duration: 40.076245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:43.409664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.535289ms","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-02-05T17:39:43.409776Z","caller":"traceutil/trace.go:171","msg":"trace[1463164290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:984; }","duration":"120.681211ms","start":"2026-02-05T17:39:43.289084Z","end":"2026-02-05T17:39:43.409766Z","steps":["trace[1463164290] 'agreement among raft nodes before linearized reading' (duration: 120.474588ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:43.410108Z","caller":"traceutil/trace.go:171","msg":"trace[1916229582] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"126.805747ms","start":"2026-02-05T17:39:43.283279Z","end":"2026-02-05T17:39:43.410085Z","steps":["trace[1916229582] 'process raft request' (duration: 84.525442ms)","trace[1916229582] 'compare' (duration: 39.762551ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:43.751341Z","caller":"traceutil/trace.go:171","msg":"trace[1919153514] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"172.731064ms","start":"2026-02-05T17:39:43.578586Z","end":"2026-02-05T17:39:43.751317Z","steps":["trace[1919153514] 'process raft request' (duration: 172.591532ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:43.928292Z","caller":"traceutil/trace.go:171","msg":"trace[1863763887] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"168.454804ms","start":"2026-02-05T17:39:43.759817Z","end":"2026-02-05T17:39:43.928271Z","steps":["trace[1863763887] 'read index received' (duration: 164.044042ms)","trace[1863763887] 'applied index is now lower than readState.Index' (duration: 4.409862ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:43.928389Z","caller":"traceutil/trace.go:171","msg":"trace[2120189874] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"168.706498ms","start":"2026-02-05T17:39:43.759673Z","end":"2026-02-05T17:39:43.92838Z","steps":["trace[2120189874] 'process raft request' (duration: 164.141874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:43.928783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.942472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-02-05T17:39:43.928817Z","caller":"traceutil/trace.go:171","msg":"trace[919423177] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:988; }","duration":"169.056014ms","start":"2026-02-05T17:39:43.759749Z","end":"2026-02-05T17:39:43.928805Z","steps":["trace[919423177] 'agreement among raft nodes before linearized reading' (duration: 168.883721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:43.929031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.748235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-05T17:39:43.929057Z","caller":"traceutil/trace.go:171","msg":"trace[1112686069] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:988; }","duration":"167.795826ms","start":"2026-02-05T17:39:43.761254Z","end":"2026-02-05T17:39:43.929049Z","steps":["trace[1112686069] 'agreement among raft nodes before linearized reading' (duration: 167.716495ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:44.863583Z","caller":"traceutil/trace.go:171","msg":"trace[1653512882] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"131.729678ms","start":"2026-02-05T17:39:44.73183Z","end":"2026-02-05T17:39:44.86356Z","steps":["trace[1653512882] 'process raft request' (duration: 44.1947ms)","trace[1653512882] 'compare' (duration: 87.439116ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:47.348928Z","caller":"traceutil/trace.go:171","msg":"trace[1881797702] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"102.163353ms","start":"2026-02-05T17:39:47.24674Z","end":"2026-02-05T17:39:47.348903Z","steps":["trace[1881797702] 'process raft request' (duration: 102.01271ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:49.795686Z","caller":"traceutil/trace.go:171","msg":"trace[32824618] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"134.392091ms","start":"2026-02-05T17:39:49.66127Z","end":"2026-02-05T17:39:49.795662Z","steps":["trace[32824618] 'process raft request' (duration: 134.263239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:50.859471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.142118ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-05T17:39:51.154782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.378712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:51.155129Z","caller":"traceutil/trace.go:171","msg":"trace[1060671365] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"255.25684ms","start":"2026-02-05T17:39:50.89981Z","end":"2026-02-05T17:39:51.155067Z","steps":["trace[1060671365] 'process raft request' (duration: 112.511803ms)","trace[1060671365] 'compare' (duration: 142.094088ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:51.931582Z","caller":"traceutil/trace.go:171","msg":"trace[966780143] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"125.008866ms","start":"2026-02-05T17:39:51.806549Z","end":"2026-02-05T17:39:51.931558Z","steps":["trace[966780143] 'process raft request' (duration: 124.856254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:52.159932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.376361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:52.160055Z","caller":"traceutil/trace.go:171","msg":"trace[1282616389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"164.520474ms","start":"2026-02-05T17:39:51.99552Z","end":"2026-02-05T17:39:52.16004Z","steps":["trace[1282616389] 'range keys from in-memory index tree' (duration: 164.312451ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:54.051099Z","caller":"traceutil/trace.go:171","msg":"trace[1559624597] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"110.895279ms","start":"2026-02-05T17:39:53.94018Z","end":"2026-02-05T17:39:54.051076Z","steps":["trace[1559624597] 'process raft request' (duration: 110.725467ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:54.738121Z","caller":"traceutil/trace.go:171","msg":"trace[130230288] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"272.443787ms","start":"2026-02-05T17:39:54.465654Z","end":"2026-02-05T17:39:54.738098Z","steps":["trace[130230288] 'process raft request' (duration: 272.058472ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:54.849088Z","caller":"traceutil/trace.go:171","msg":"trace[1777734666] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"103.405354ms","start":"2026-02-05T17:39:54.74566Z","end":"2026-02-05T17:39:54.849065Z","steps":["trace[1777734666] 'read index received' (duration: 50.854531ms)","trace[1777734666] 'applied index is now lower than readState.Index' (duration: 52.549883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:54.849457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.770709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:39:54.849506Z","caller":"traceutil/trace.go:171","msg":"trace[1194992608] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1022; }","duration":"103.864161ms","start":"2026-02-05T17:39:54.745631Z","end":"2026-02-05T17:39:54.849495Z","steps":["trace[1194992608] 'agreement among raft nodes before linearized reading' (duration: 103.523867ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:54.850028Z","caller":"traceutil/trace.go:171","msg":"trace[84848528] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"107.052073ms","start":"2026-02-05T17:39:54.742959Z","end":"2026-02-05T17:39:54.850011Z","steps":["trace[84848528] 'process raft request' (duration: 53.615807ms)","trace[84848528] 'compare' (duration: 52.19682ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:55.040562Z","caller":"traceutil/trace.go:171","msg":"trace[1706660055] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"115.94991ms","start":"2026-02-05T17:39:54.924553Z","end":"2026-02-05T17:39:55.040502Z","steps":["trace[1706660055] 'process raft request' (duration: 84.206557ms)","trace[1706660055] 'compare' (duration: 31.397658ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:57.687901Z","caller":"traceutil/trace.go:171","msg":"trace[1322642766] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"145.011707ms","start":"2026-02-05T17:39:57.542854Z","end":"2026-02-05T17:39:57.687866Z","steps":["trace[1322642766] 'process raft request' (duration: 58.923749ms)","trace[1322642766] 'compare' (duration: 85.967137ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:58.193878Z","caller":"traceutil/trace.go:171","msg":"trace[1375647495] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"107.627937ms","start":"2026-02-05T17:39:58.086231Z","end":"2026-02-05T17:39:58.193859Z","steps":["trace[1375647495] 'process raft request' (duration: 107.535406ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:59.112663Z","caller":"traceutil/trace.go:171","msg":"trace[1296855653] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"230.600789ms","start":"2026-02-05T17:39:58.882039Z","end":"2026-02-05T17:39:59.11264Z","steps":["trace[1296855653] 'read index received' (duration: 230.335455ms)","trace[1296855653] 'applied index is now lower than readState.Index' (duration: 264.404µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:59.112972Z","caller":"traceutil/trace.go:171","msg":"trace[2027410683] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"273.843188ms","start":"2026-02-05T17:39:58.839117Z","end":"2026-02-05T17:39:59.11296Z","steps":["trace[2027410683] 'process raft request' (duration: 273.323941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:59.113284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.177066ms","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-02-05T17:39:59.113326Z","caller":"traceutil/trace.go:171","msg":"trace[1647117534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"231.302817ms","start":"2026-02-05T17:39:58.882013Z","end":"2026-02-05T17:39:59.113316Z","steps":["trace[1647117534] 'agreement among raft nodes before linearized reading' (duration: 231.120495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:59.113629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.421899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:59.113705Z","caller":"traceutil/trace.go:171","msg":"trace[167576881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"117.51927ms","start":"2026-02-05T17:39:58.996177Z","end":"2026-02-05T17:39:59.113696Z","steps":["trace[167576881] 'agreement among raft nodes before linearized reading' (duration: 117.416329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:59.383724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.126903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:59.383825Z","caller":"traceutil/trace.go:171","msg":"trace[247232990] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"243.495471ms","start":"2026-02-05T17:39:59.140313Z","end":"2026-02-05T17:39:59.383809Z","steps":["trace[247232990] 'read index received' (duration: 24.055925ms)","trace[247232990] 'applied index is now lower than readState.Index' (duration: 219.438216ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:59.384043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.735515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:39:59.38409Z","caller":"traceutil/trace.go:171","msg":"trace[921041699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"243.840356ms","start":"2026-02-05T17:39:59.140236Z","end":"2026-02-05T17:39:59.384076Z","steps":["trace[921041699] 'agreement among raft nodes before linearized reading' (duration: 243.622093ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:59.386031Z","caller":"traceutil/trace.go:171","msg":"trace[722904171] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"261.994995ms","start":"2026-02-05T17:39:59.122333Z","end":"2026-02-05T17:39:59.384328Z","steps":["trace[722904171] 'process raft request' (duration: 42.110542ms)","trace[722904171] 'compare' (duration: 218.99357ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:59.631419Z","caller":"traceutil/trace.go:171","msg":"trace[1391422279] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"182.282149ms","start":"2026-02-05T17:39:59.449055Z","end":"2026-02-05T17:39:59.631338Z","steps":["trace[1391422279] 'process raft request' (duration: 137.512302ms)","trace[1391422279] 'compare' (duration: 44.615175ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:00.724557Z","caller":"traceutil/trace.go:171","msg":"trace[1637978050] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"160.050939ms","start":"2026-02-05T17:40:00.564484Z","end":"2026-02-05T17:40:00.724535Z","steps":["trace[1637978050] 'process raft request' (duration: 159.926637ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:01.419744Z","caller":"traceutil/trace.go:171","msg":"trace[353266848] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"206.329132ms","start":"2026-02-05T17:40:01.213391Z","end":"2026-02-05T17:40:01.41972Z","steps":["trace[353266848] 'process raft request' (duration: 146.186451ms)","trace[353266848] 'compare' (duration: 60.001659ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:01.596975Z","caller":"traceutil/trace.go:171","msg":"trace[1700681563] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"173.854759ms","start":"2026-02-05T17:40:01.423092Z","end":"2026-02-05T17:40:01.596947Z","steps":["trace[1700681563] 'process raft request' (duration: 171.380207ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:01.597382Z","caller":"traceutil/trace.go:171","msg":"trace[371602549] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"137.512238ms","start":"2026-02-05T17:40:01.459552Z","end":"2026-02-05T17:40:01.597065Z","steps":["trace[371602549] 'process raft request' (duration: 137.324835ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:04.411867Z","caller":"traceutil/trace.go:171","msg":"trace[465713876] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"129.486063ms","start":"2026-02-05T17:40:04.28236Z","end":"2026-02-05T17:40:04.411846Z","steps":["trace[465713876] 'process raft request' (duration: 129.372481ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:07.683175Z","caller":"traceutil/trace.go:171","msg":"trace[833887926] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"207.284132ms","start":"2026-02-05T17:40:07.47587Z","end":"2026-02-05T17:40:07.683154Z","steps":["trace[833887926] 'process raft request' (duration: 207.008878ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:17.862046Z","caller":"traceutil/trace.go:171","msg":"trace[822740704] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"109.3734ms","start":"2026-02-05T17:40:17.752642Z","end":"2026-02-05T17:40:17.862016Z","steps":["trace[822740704] 'process raft request' (duration: 84.402275ms)","trace[822740704] 'compare' (duration: 24.770723ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:19.933637Z","caller":"traceutil/trace.go:171","msg":"trace[125557444] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"104.255619ms","start":"2026-02-05T17:40:19.82936Z","end":"2026-02-05T17:40:19.933616Z","steps":["trace[125557444] 'read index received' (duration: 32.627208ms)","trace[125557444] 'applied index is now lower than readState.Index' (duration: 71.627401ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:19.933904Z","caller":"traceutil/trace.go:171","msg":"trace[1906526703] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"149.738972ms","start":"2026-02-05T17:40:19.784151Z","end":"2026-02-05T17:40:19.93389Z","steps":["trace[1906526703] 'process raft request' (duration: 77.851778ms)","trace[1906526703] 'compare' (duration: 71.52173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:19.934163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.788606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:843"} {"level":"info","ts":"2026-02-05T17:40:19.935419Z","caller":"traceutil/trace.go:171","msg":"trace[1870713911] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:1102; }","duration":"106.068371ms","start":"2026-02-05T17:40:19.829334Z","end":"2026-02-05T17:40:19.935403Z","steps":["trace[1870713911] 'agreement among raft nodes before linearized reading' (duration: 104.795586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:20.185642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.038725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:830"} {"level":"info","ts":"2026-02-05T17:40:20.185713Z","caller":"traceutil/trace.go:171","msg":"trace[671425608] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:1103; }","duration":"110.137975ms","start":"2026-02-05T17:40:20.075559Z","end":"2026-02-05T17:40:20.185697Z","steps":["trace[671425608] 'range keys from in-memory index tree' (duration: 109.985714ms)"],"step_count":1}