{"level":"warn","ts":"2026-02-06T18:46:54.614528Z","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-06T18:46:54.614663Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186: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.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186: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-06T18:46:54.614755Z","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-06T18:46:54.614812Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-02-06T18:46:54.614846Z","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-06T18:46:54.61764Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-02-06T18:46:54.617732Z","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.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186: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-06T18:46:54.623448Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.402224ms"} {"level":"info","ts":"2026-02-06T18:46:54.646201Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-02-06T18:46:54.646279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T18:46:54.646322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-02-06T18:46:54.646352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T18:46:54.646375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-02-06T18:46:54.646429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-02-06T18:46:54.654882Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T18:46:54.659248Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T18:46:54.664553Z","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-06T18:46:54.670425Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T18:46:54.673976Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T18:46:54.674532Z","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-06T18:46:54.676235Z","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-06T18:46:54.676266Z","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-06T18:46:54.695522Z","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-06T18:46:54.695727Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T18:46:54.696275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-02-06T18:46:54.69685Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-02-06T18:46:54.698393Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-02-06T18:46:54.697286Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T18:46:54.698525Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-02-06T18:46:54.946769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T18:46:54.946859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T18:46:54.946909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-02-06T18:46:54.946986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-02-06T18:46:54.947066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-02-06T18:46:54.947123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-02-06T18:46:54.947177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-02-06T18:46:54.953191Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T18:46:54.955558Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T18:46:54.955639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T18:46:54.956407Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T18:46:54.957427Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T18:46:54.957518Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T18:46:54.957588Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T18:46:54.960019Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"info","ts":"2026-02-06T18:46:54.960651Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T18:46:54.960697Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T18:46:54.971018Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T18:46:56.802735Z","caller":"traceutil/trace.go:171","msg":"trace[726053057] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"123.439107ms","start":"2026-02-06T18:46:56.679268Z","end":"2026-02-06T18:46:56.802707Z","steps":["trace[726053057] 'process raft request' (duration: 90.458969ms)","trace[726053057] 'compare' (duration: 32.520577ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:46:56.803767Z","caller":"traceutil/trace.go:171","msg":"trace[1412006770] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"116.240058ms","start":"2026-02-06T18:46:56.68751Z","end":"2026-02-06T18:46:56.80375Z","steps":["trace[1412006770] 'process raft request' (duration: 115.098039ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:56.80282Z","caller":"traceutil/trace.go:171","msg":"trace[953115646] linearizableReadLoop","detail":"{readStateIndex:20; appliedIndex:17; }","duration":"115.541771ms","start":"2026-02-06T18:46:56.687256Z","end":"2026-02-06T18:46:56.802798Z","steps":["trace[953115646] 'read index received' (duration: 6.86651ms)","trace[953115646] 'applied index is now lower than readState.Index' (duration: 108.674411ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:46:56.80381Z","caller":"traceutil/trace.go:171","msg":"trace[756895853] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"124.415332ms","start":"2026-02-06T18:46:56.679386Z","end":"2026-02-06T18:46:56.803801Z","steps":["trace[756895853] 'process raft request' (duration: 123.081038ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:56.803838Z","caller":"traceutil/trace.go:171","msg":"trace[1099029223] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"116.682849ms","start":"2026-02-06T18:46:56.687148Z","end":"2026-02-06T18:46:56.803831Z","steps":["trace[1099029223] 'process raft request' (duration: 115.356646ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:56.803865Z","caller":"traceutil/trace.go:171","msg":"trace[1106640153] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"116.556317ms","start":"2026-02-06T18:46:56.687302Z","end":"2026-02-06T18:46:56.803858Z","steps":["trace[1106640153] 'process raft request' (duration: 115.253814ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:56.8044Z","caller":"traceutil/trace.go:171","msg":"trace[1548894704] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"116.861544ms","start":"2026-02-06T18:46:56.687524Z","end":"2026-02-06T18:46:56.804386Z","steps":["trace[1548894704] 'process raft request' (duration: 115.163791ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:56.804468Z","caller":"traceutil/trace.go:171","msg":"trace[1860975040] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"116.867854ms","start":"2026-02-06T18:46:56.687593Z","end":"2026-02-06T18:46:56.804461Z","steps":["trace[1860975040] 'process raft request' (duration: 115.12814ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:56.804507Z","caller":"traceutil/trace.go:171","msg":"trace[577150267] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"115.407848ms","start":"2026-02-06T18:46:56.689093Z","end":"2026-02-06T18:46:56.804501Z","steps":["trace[577150267] 'process raft request' (duration: 113.674814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:46:56.804684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.396437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-06T18:46:56.808377Z","caller":"traceutil/trace.go:171","msg":"trace[1861772230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22; }","duration":"121.068827ms","start":"2026-02-06T18:46:56.687237Z","end":"2026-02-06T18:46:56.808306Z","steps":["trace[1861772230] 'agreement among raft nodes before linearized reading' (duration: 117.385027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:46:56.808492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.176505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T18:46:56.808757Z","caller":"traceutil/trace.go:171","msg":"trace[801877231] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:25; }","duration":"121.17998ms","start":"2026-02-06T18:46:56.687344Z","end":"2026-02-06T18:46:56.808524Z","steps":["trace[801877231] 'agreement among raft nodes before linearized reading' (duration: 119.882558ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:56.809368Z","caller":"traceutil/trace.go:171","msg":"trace[596844979] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"111.907521ms","start":"2026-02-06T18:46:56.697448Z","end":"2026-02-06T18:46:56.809355Z","steps":["trace[596844979] 'process raft request' (duration: 109.631064ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:56.809694Z","caller":"traceutil/trace.go:171","msg":"trace[438596388] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"111.649765ms","start":"2026-02-06T18:46:56.69803Z","end":"2026-02-06T18:46:56.80968Z","steps":["trace[438596388] 'process raft request' (duration: 109.118152ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:56.809951Z","caller":"traceutil/trace.go:171","msg":"trace[1188599617] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"107.337428ms","start":"2026-02-06T18:46:56.702203Z","end":"2026-02-06T18:46:56.80954Z","steps":["trace[1188599617] 'process raft request' (duration: 104.98914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:46:56.810399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.957684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T18:46:56.810501Z","caller":"traceutil/trace.go:171","msg":"trace[1679674863] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:25; }","duration":"123.074307ms","start":"2026-02-06T18:46:56.687408Z","end":"2026-02-06T18:46:56.810482Z","steps":["trace[1679674863] 'agreement among raft nodes before linearized reading' (duration: 122.957535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:46:56.810857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.391169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T18:46:56.810963Z","caller":"traceutil/trace.go:171","msg":"trace[657639418] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:0; response_revision:25; }","duration":"109.562903ms","start":"2026-02-06T18:46:56.701386Z","end":"2026-02-06T18:46:56.810949Z","steps":["trace[657639418] 'agreement among raft nodes before linearized reading' (duration: 109.395259ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:46:58.075104Z","caller":"traceutil/trace.go:171","msg":"trace[1478865418] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"110.380403ms","start":"2026-02-06T18:46:57.964591Z","end":"2026-02-06T18:46:58.074971Z","steps":["trace[1478865418] 'process raft request' (duration: 41.927518ms)","trace[1478865418] 'compare' (duration: 68.286631ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:47:21.437797Z","caller":"traceutil/trace.go:171","msg":"trace[1848953826] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"203.147357ms","start":"2026-02-06T18:47:21.234625Z","end":"2026-02-06T18:47:21.437773Z","steps":["trace[1848953826] 'process raft request' (duration: 202.724317ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:47:29.13805Z","caller":"traceutil/trace.go:171","msg":"trace[1719975998] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"164.992387ms","start":"2026-02-06T18:47:28.97304Z","end":"2026-02-06T18:47:29.138032Z","steps":["trace[1719975998] 'process raft request' (duration: 164.640999ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:47:31.322943Z","caller":"traceutil/trace.go:171","msg":"trace[1612015045] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"152.43871ms","start":"2026-02-06T18:47:31.170473Z","end":"2026-02-06T18:47:31.322912Z","steps":["trace[1612015045] 'process raft request' (duration: 152.217255ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:47:31.328981Z","caller":"traceutil/trace.go:171","msg":"trace[798594262] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"155.515095ms","start":"2026-02-06T18:47:31.173449Z","end":"2026-02-06T18:47:31.328964Z","steps":["trace[798594262] 'process raft request' (duration: 155.431393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:32.212716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.2959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T18:47:32.212843Z","caller":"traceutil/trace.go:171","msg":"trace[412665726] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"114.013531ms","start":"2026-02-06T18:47:32.098814Z","end":"2026-02-06T18:47:32.212827Z","steps":["trace[412665726] 'read index received' (duration: 41.201µs)","trace[412665726] 'applied index is now lower than readState.Index' (duration: 113.97127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:47:32.212909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.102634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:47:32.212925Z","caller":"traceutil/trace.go:171","msg":"trace[22117646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"114.158845ms","start":"2026-02-06T18:47:32.09876Z","end":"2026-02-06T18:47:32.212919Z","steps":["trace[22117646] 'agreement among raft nodes before linearized reading' (duration: 114.109434ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:47:32.213007Z","caller":"traceutil/trace.go:171","msg":"trace[1538848861] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"715.199004ms","start":"2026-02-06T18:47:31.497771Z","end":"2026-02-06T18:47:32.21297Z","steps":["trace[1538848861] 'process raft request' (duration: 122.424448ms)","trace[1538848861] 'compare' (duration: 591.040004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:47:32.213354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:31.497747Z","time spent":"715.515492ms","remote":"127.0.0.1:39022","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-06T18:47:37.166741Z","caller":"traceutil/trace.go:171","msg":"trace[911569398] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"855.380395ms","start":"2026-02-06T18:47:36.311336Z","end":"2026-02-06T18:47:37.166716Z","steps":["trace[911569398] 'process raft request' (duration: 855.218872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:37.167464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:36.311317Z","time spent":"855.768024ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:47:51.393788Z","caller":"traceutil/trace.go:171","msg":"trace[844718830] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"370.095371ms","start":"2026-02-06T18:47:51.023674Z","end":"2026-02-06T18:47:51.393769Z","steps":["trace[844718830] 'process raft request' (duration: 369.558068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:51.393888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:51.023662Z","time spent":"370.169934ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:47:51.666123Z","caller":"traceutil/trace.go:171","msg":"trace[86941783] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"110.808185ms","start":"2026-02-06T18:47:51.555279Z","end":"2026-02-06T18:47:51.666087Z","steps":["trace[86941783] 'process raft request' (duration: 110.662551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:54.211264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110809727s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T18:47:54.211418Z","caller":"traceutil/trace.go:171","msg":"trace[831371107] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"1.111175946s","start":"2026-02-06T18:47:53.100221Z","end":"2026-02-06T18:47:54.211397Z","steps":["trace[831371107] 'read index received' (duration: 48.431µs)","trace[831371107] 'applied index is now lower than readState.Index' (duration: 1.111126235s)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:47:54.211702Z","caller":"traceutil/trace.go:171","msg":"trace[1015603218] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.443903986s","start":"2026-02-06T18:47:52.767784Z","end":"2026-02-06T18:47:54.211688Z","steps":["trace[1015603218] 'process raft request' (duration: 332.547076ms)","trace[1015603218] 'compare' (duration: 1.110648363s)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:47:54.21177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:52.767764Z","time spent":"1.443971248s","remote":"127.0.0.1:38940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:47:54.211984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111759641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:47:54.212015Z","caller":"traceutil/trace.go:171","msg":"trace[650482982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"1.111818443s","start":"2026-02-06T18:47:53.100185Z","end":"2026-02-06T18:47:54.212003Z","steps":["trace[650482982] 'agreement among raft nodes before linearized reading' (duration: 1.111765172s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:54.212044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:53.100163Z","time spent":"1.111875914s","remote":"127.0.0.1:38734","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-06T18:47:54.230257Z","caller":"traceutil/trace.go:171","msg":"trace[1258340127] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"782.347342ms","start":"2026-02-06T18:47:53.447895Z","end":"2026-02-06T18:47:54.230242Z","steps":["trace[1258340127] 'process raft request' (duration: 782.26126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:54.230338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:53.447875Z","time spent":"782.421714ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:47:54.23065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.770769ms","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-06T18:47:54.23067Z","caller":"traceutil/trace.go:171","msg":"trace[1126553613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"461.827111ms","start":"2026-02-06T18:47:53.768837Z","end":"2026-02-06T18:47:54.230664Z","steps":["trace[1126553613] 'agreement among raft nodes before linearized reading' (duration: 461.743379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:54.230684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:53.76882Z","time spent":"461.860881ms","remote":"127.0.0.1:39022","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-06T18:47:54.230778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.296968ms","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-06T18:47:54.230793Z","caller":"traceutil/trace.go:171","msg":"trace[1945177791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"554.310289ms","start":"2026-02-06T18:47:53.676477Z","end":"2026-02-06T18:47:54.230787Z","steps":["trace[1945177791] 'agreement among raft nodes before linearized reading' (duration: 554.268528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:54.231047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:53.676418Z","time spent":"554.620726ms","remote":"127.0.0.1:39022","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-06T18:47:54.23116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.044546ms","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-06T18:47:54.231175Z","caller":"traceutil/trace.go:171","msg":"trace[843579317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"605.090037ms","start":"2026-02-06T18:47:53.626079Z","end":"2026-02-06T18:47:54.231169Z","steps":["trace[843579317] 'agreement among raft nodes before linearized reading' (duration: 605.037046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:54.231191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:53.626061Z","time spent":"605.126597ms","remote":"127.0.0.1:39022","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-06T18:47:54.231274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.628169ms","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-06T18:47:54.231287Z","caller":"traceutil/trace.go:171","msg":"trace[330620744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"807.66254ms","start":"2026-02-06T18:47:53.42362Z","end":"2026-02-06T18:47:54.231282Z","steps":["trace[330620744] 'agreement among raft nodes before linearized reading' (duration: 807.623859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:54.2313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:53.423601Z","time spent":"807.695641ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T18:47:54.231397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.987503ms","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-06T18:47:54.231412Z","caller":"traceutil/trace.go:171","msg":"trace[317759984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"832.024635ms","start":"2026-02-06T18:47:53.399382Z","end":"2026-02-06T18:47:54.231407Z","steps":["trace[317759984] 'agreement among raft nodes before linearized reading' (duration: 831.962063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:54.231425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:53.399337Z","time spent":"832.084086ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T18:47:54.730892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360944467873348553,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T18:47:55.039961Z","caller":"traceutil/trace.go:171","msg":"trace[68336942] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"817.566792ms","start":"2026-02-06T18:47:54.222374Z","end":"2026-02-06T18:47:55.03994Z","steps":["trace[68336942] 'process raft request' (duration: 817.445019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.040093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.222358Z","time spent":"817.665564ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:47:55.333688Z","caller":"traceutil/trace.go:171","msg":"trace[131939785] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"1.094237844s","start":"2026-02-06T18:47:54.239419Z","end":"2026-02-06T18:47:55.333657Z","steps":["trace[131939785] 'process raft request' (duration: 1.093916777s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.333858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.239407Z","time spent":"1.094373977s","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1453,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:47:55.334068Z","caller":"traceutil/trace.go:171","msg":"trace[2008462117] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"1.093190438s","start":"2026-02-06T18:47:54.240854Z","end":"2026-02-06T18:47:55.334044Z","steps":["trace[2008462117] 'process raft request' (duration: 1.092733977s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.334206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.240832Z","time spent":"1.093297111s","remote":"127.0.0.1:38922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":901,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:47:55.334317Z","caller":"traceutil/trace.go:171","msg":"trace[57104039] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"1.090676016s","start":"2026-02-06T18:47:54.243634Z","end":"2026-02-06T18:47:55.33431Z","steps":["trace[57104039] 'process raft request' (duration: 1.090563303s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.334356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.243627Z","time spent":"1.090707178s","remote":"127.0.0.1:39022","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-06T18:47:55.334441Z","caller":"traceutil/trace.go:171","msg":"trace[1626997149] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"1.092629824s","start":"2026-02-06T18:47:54.241807Z","end":"2026-02-06T18:47:55.334437Z","steps":["trace[1626997149] 'process raft request' (duration: 1.09204248s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.334467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.241796Z","time spent":"1.092656096s","remote":"127.0.0.1:39022","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-06T18:47:55.334523Z","caller":"traceutil/trace.go:171","msg":"trace[1543524575] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"1.089749974s","start":"2026-02-06T18:47:54.244765Z","end":"2026-02-06T18:47:55.334515Z","steps":["trace[1543524575] 'process raft request' (duration: 1.089526909s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.334575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.244759Z","time spent":"1.089790985s","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:47:55.334776Z","caller":"traceutil/trace.go:171","msg":"trace[1215047481] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"1.087479269s","start":"2026-02-06T18:47:54.247273Z","end":"2026-02-06T18:47:55.334752Z","steps":["trace[1215047481] 'process raft request' (duration: 1.087208682s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.334809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.247265Z","time spent":"1.08752658s","remote":"127.0.0.1:39022","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-06T18:47:55.334827Z","caller":"traceutil/trace.go:171","msg":"trace[575350101] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:718; }","duration":"1.104254548s","start":"2026-02-06T18:47:54.230564Z","end":"2026-02-06T18:47:55.334819Z","steps":["trace[575350101] 'read index received' (duration: 810.575221ms)","trace[575350101] 'applied index is now lower than readState.Index' (duration: 293.678417ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:47:55.334894Z","caller":"traceutil/trace.go:171","msg":"trace[61264327] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"1.086846994s","start":"2026-02-06T18:47:54.248042Z","end":"2026-02-06T18:47:55.334889Z","steps":["trace[61264327] 'process raft request' (duration: 1.08668518s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.334921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.247945Z","time spent":"1.086962657s","remote":"127.0.0.1:39022","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-06T18:47:55.334929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119594842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:47:55.334973Z","caller":"traceutil/trace.go:171","msg":"trace[1511135189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"1.119669444s","start":"2026-02-06T18:47:54.215295Z","end":"2026-02-06T18:47:55.334964Z","steps":["trace[1511135189] 'agreement among raft nodes before linearized reading' (duration: 1.119612373s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.334995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.215279Z","time spent":"1.119711885s","remote":"127.0.0.1:38742","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-06T18:47:55.767644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.072398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T18:47:55.767724Z","caller":"traceutil/trace.go:171","msg":"trace[1826437188] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"432.863614ms","start":"2026-02-06T18:47:55.334848Z","end":"2026-02-06T18:47:55.767712Z","steps":["trace[1826437188] 'read index received' (duration: 174.680793ms)","trace[1826437188] 'applied index is now lower than readState.Index' (duration: 258.181311ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:47:55.767869Z","caller":"traceutil/trace.go:171","msg":"trace[1427386768] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"721.055008ms","start":"2026-02-06T18:47:55.046807Z","end":"2026-02-06T18:47:55.767862Z","steps":["trace[1427386768] 'process raft request' (duration: 462.705773ms)","trace[1427386768] 'compare' (duration: 257.777171ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:47:55.768112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528756668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-tzrld\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-02-06T18:47:55.768132Z","caller":"traceutil/trace.go:171","msg":"trace[1905203670] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-tzrld; range_end:; response_count:1; response_revision:703; }","duration":"1.528811339s","start":"2026-02-06T18:47:54.239312Z","end":"2026-02-06T18:47:55.768124Z","steps":["trace[1905203670] 'agreement among raft nodes before linearized reading' (duration: 1.528716787s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.768147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:54.239294Z","time spent":"1.52884949s","remote":"127.0.0.1:38940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5048,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-tzrld\" "} {"level":"warn","ts":"2026-02-06T18:47:55.768529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:55.046789Z","time spent":"721.097198ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:47:55.849386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.232849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:47:55.849462Z","caller":"traceutil/trace.go:171","msg":"trace[1857902948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"505.353493ms","start":"2026-02-06T18:47:55.344092Z","end":"2026-02-06T18:47:55.849446Z","steps":["trace[1857902948] 'agreement among raft nodes before linearized reading' (duration: 505.2164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:47:55.849496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:47:55.344078Z","time spent":"505.410904ms","remote":"127.0.0.1:38734","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-06T18:48:01.501704Z","caller":"traceutil/trace.go:171","msg":"trace[1240927736] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"106.753826ms","start":"2026-02-06T18:48:01.394929Z","end":"2026-02-06T18:48:01.501683Z","steps":["trace[1240927736] 'process raft request' (duration: 56.990451ms)","trace[1240927736] 'compare' (duration: 49.294703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:48:01.501885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.829468ms","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-06T18:48:01.501906Z","caller":"traceutil/trace.go:171","msg":"trace[2030916505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"106.90544ms","start":"2026-02-06T18:48:01.394991Z","end":"2026-02-06T18:48:01.501897Z","steps":["trace[2030916505] 'agreement among raft nodes before linearized reading' (duration: 106.815678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:02.807094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.170317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:48:02.807174Z","caller":"traceutil/trace.go:171","msg":"trace[500969053] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:737; }","duration":"725.327531ms","start":"2026-02-06T18:48:02.081827Z","end":"2026-02-06T18:48:02.807155Z","steps":["trace[500969053] 'count revisions from in-memory index tree' (duration: 725.077415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:02.807209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:02.081807Z","time spent":"725.393253ms","remote":"127.0.0.1:39076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-06T18:48:20.019064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360944467873348790,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T18:48:20.340153Z","caller":"traceutil/trace.go:171","msg":"trace[1707874249] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"821.689784ms","start":"2026-02-06T18:48:19.518441Z","end":"2026-02-06T18:48:20.340131Z","steps":["trace[1707874249] 'read index received' (duration: 821.452829ms)","trace[1707874249] 'applied index is now lower than readState.Index' (duration: 236.305µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:48:20.340385Z","caller":"traceutil/trace.go:171","msg":"trace[338534741] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"825.207959ms","start":"2026-02-06T18:48:19.51517Z","end":"2026-02-06T18:48:20.340378Z","steps":["trace[338534741] 'process raft request' (duration: 824.788939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:20.340473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:19.515158Z","time spent":"825.24638ms","remote":"127.0.0.1:39022","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-06T18:48:20.340653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.211378ms","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-06T18:48:20.340676Z","caller":"traceutil/trace.go:171","msg":"trace[68121875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"822.277879ms","start":"2026-02-06T18:48:19.518391Z","end":"2026-02-06T18:48:20.340669Z","steps":["trace[68121875] 'agreement among raft nodes before linearized reading' (duration: 822.189807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:20.340692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:19.51837Z","time spent":"822.31805ms","remote":"127.0.0.1:39022","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-06T18:48:20.340781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.2101ms","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-06T18:48:20.340794Z","caller":"traceutil/trace.go:171","msg":"trace[1665802885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"745.257051ms","start":"2026-02-06T18:48:19.595533Z","end":"2026-02-06T18:48:20.34079Z","steps":["trace[1665802885] 'agreement among raft nodes before linearized reading' (duration: 745.22013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:20.340812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:19.595519Z","time spent":"745.283221ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T18:48:20.340917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.165056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T18:48:20.340929Z","caller":"traceutil/trace.go:171","msg":"trace[1491423727] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:789; }","duration":"204.199497ms","start":"2026-02-06T18:48:20.136725Z","end":"2026-02-06T18:48:20.340925Z","steps":["trace[1491423727] 'agreement among raft nodes before linearized reading' (duration: 204.159386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:20.34101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.532813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:48:20.341027Z","caller":"traceutil/trace.go:171","msg":"trace[1746460549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"240.587805ms","start":"2026-02-06T18:48:20.100435Z","end":"2026-02-06T18:48:20.341023Z","steps":["trace[1746460549] 'agreement among raft nodes before linearized reading' (duration: 240.562214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:20.341107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.88895ms","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-06T18:48:20.341118Z","caller":"traceutil/trace.go:171","msg":"trace[1906172258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"622.936571ms","start":"2026-02-06T18:48:19.718178Z","end":"2026-02-06T18:48:20.341115Z","steps":["trace[1906172258] 'agreement among raft nodes before linearized reading' (duration: 622.90254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:20.341129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:19.718161Z","time spent":"622.965301ms","remote":"127.0.0.1:39022","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-06T18:48:20.341195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.708198ms","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-06T18:48:20.341208Z","caller":"traceutil/trace.go:171","msg":"trace[1138400098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"744.731869ms","start":"2026-02-06T18:48:19.596472Z","end":"2026-02-06T18:48:20.341204Z","steps":["trace[1138400098] 'agreement among raft nodes before linearized reading' (duration: 744.697938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:20.341219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:19.596468Z","time spent":"744.748439ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T18:48:20.829717Z","caller":"traceutil/trace.go:171","msg":"trace[703958013] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"481.811114ms","start":"2026-02-06T18:48:20.347884Z","end":"2026-02-06T18:48:20.829696Z","steps":["trace[703958013] 'process raft request' (duration: 481.784264ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:20.829896Z","caller":"traceutil/trace.go:171","msg":"trace[1610221858] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"484.643073ms","start":"2026-02-06T18:48:20.345191Z","end":"2026-02-06T18:48:20.829834Z","steps":["trace[1610221858] 'process raft request' (duration: 484.340225ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:20.829931Z","caller":"traceutil/trace.go:171","msg":"trace[435516157] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"484.661723ms","start":"2026-02-06T18:48:20.345263Z","end":"2026-02-06T18:48:20.829925Z","steps":["trace[435516157] 'process raft request' (duration: 484.357546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:20.829951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:20.34518Z","time spent":"484.744825ms","remote":"127.0.0.1:39022","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-06T18:48:20.829965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:20.345258Z","time spent":"484.688674ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:48:20.83004Z","caller":"traceutil/trace.go:171","msg":"trace[516499968] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"482.752117ms","start":"2026-02-06T18:48:20.347283Z","end":"2026-02-06T18:48:20.830035Z","steps":["trace[516499968] 'process raft request' (duration: 482.363978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:20.830067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:20.347277Z","time spent":"482.775768ms","remote":"127.0.0.1:39022","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-06T18:48:20.830103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:20.342865Z","time spent":"487.236855ms","remote":"127.0.0.1:38784","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-06T18:48:20.829896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:20.347878Z","time spent":"481.911447ms","remote":"127.0.0.1:39022","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-06T18:48:24.793831Z","caller":"traceutil/trace.go:171","msg":"trace[1777590021] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"359.042272ms","start":"2026-02-06T18:48:24.434747Z","end":"2026-02-06T18:48:24.793789Z","steps":["trace[1777590021] 'process raft request' (duration: 358.809717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:24.79401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:24.434708Z","time spent":"359.208256ms","remote":"127.0.0.1:39022","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-06T18:48:25.583023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.191707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T18:48:25.585012Z","caller":"traceutil/trace.go:171","msg":"trace[1575081846] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"727.077341ms","start":"2026-02-06T18:48:24.857889Z","end":"2026-02-06T18:48:25.584966Z","steps":["trace[1575081846] 'process raft request' (duration: 331.833155ms)","trace[1575081846] 'compare' (duration: 392.942831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:48:25.585243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:24.857867Z","time spent":"727.310868ms","remote":"127.0.0.1:39022","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-06T18:48:26.102943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.580591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T18:48:26.103293Z","caller":"traceutil/trace.go:171","msg":"trace[2048559119] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:830; }","duration":"1.001970795s","start":"2026-02-06T18:48:25.1013Z","end":"2026-02-06T18:48:26.103271Z","steps":["trace[2048559119] 'read index received' (duration: 88.428623ms)","trace[2048559119] 'applied index is now lower than readState.Index' (duration: 913.540802ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:48:26.103703Z","caller":"traceutil/trace.go:171","msg":"trace[530325158] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.244377613s","start":"2026-02-06T18:48:24.859308Z","end":"2026-02-06T18:48:26.103685Z","steps":["trace[530325158] 'process raft request' (duration: 1.141948682s)","trace[530325158] 'compare' (duration: 101.17276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:48:26.10379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:24.8593Z","time spent":"1.244449976s","remote":"127.0.0.1:39022","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-06T18:48:26.104008Z","caller":"traceutil/trace.go:171","msg":"trace[2022446787] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"1.24464671s","start":"2026-02-06T18:48:24.85935Z","end":"2026-02-06T18:48:26.103997Z","steps":["trace[2022446787] 'process raft request' (duration: 1.243716876s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:26.104067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:24.859346Z","time spent":"1.244693271s","remote":"127.0.0.1:39022","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-06T18:48:26.104203Z","caller":"traceutil/trace.go:171","msg":"trace[805484353] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.244053835s","start":"2026-02-06T18:48:24.860136Z","end":"2026-02-06T18:48:26.10419Z","steps":["trace[805484353] 'process raft request' (duration: 1.24304505s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:26.104265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:24.860128Z","time spent":"1.244104316s","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:48:26.104396Z","caller":"traceutil/trace.go:171","msg":"trace[1331804262] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.091759621s","start":"2026-02-06T18:48:25.012625Z","end":"2026-02-06T18:48:26.104385Z","steps":["trace[1331804262] 'process raft request' (duration: 1.090598972s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:26.104451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:25.012592Z","time spent":"1.091833533s","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:48:26.104679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003374219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:48:26.104722Z","caller":"traceutil/trace.go:171","msg":"trace[19888444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"1.003466731s","start":"2026-02-06T18:48:25.101243Z","end":"2026-02-06T18:48:26.10471Z","steps":["trace[19888444] 'agreement among raft nodes before linearized reading' (duration: 1.003314837s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:26.104779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:25.101218Z","time spent":"1.003554723s","remote":"127.0.0.1:38734","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-06T18:48:26.727459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.168699ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-06T18:48:26.727576Z","caller":"traceutil/trace.go:171","msg":"trace[1824759060] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"624.218509ms","start":"2026-02-06T18:48:26.103335Z","end":"2026-02-06T18:48:26.727554Z","steps":["trace[1824759060] 'read index received' (duration: 188.996649ms)","trace[1824759060] 'applied index is now lower than readState.Index' (duration: 435.22003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:48:26.727901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.997208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-06T18:48:26.727957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.58811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:48:26.727984Z","caller":"traceutil/trace.go:171","msg":"trace[2048265609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"620.167953ms","start":"2026-02-06T18:48:26.107793Z","end":"2026-02-06T18:48:26.727961Z","steps":["trace[2048265609] 'agreement among raft nodes before linearized reading' (duration: 620.034449ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:26.728002Z","caller":"traceutil/trace.go:171","msg":"trace[2045539599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"341.658934ms","start":"2026-02-06T18:48:26.386327Z","end":"2026-02-06T18:48:26.727986Z","steps":["trace[2045539599] 'agreement among raft nodes before linearized reading' (duration: 341.570521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:26.728027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:26.107771Z","time spent":"620.247764ms","remote":"127.0.0.1:38742","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-06T18:48:26.728043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:26.386316Z","time spent":"341.718685ms","remote":"127.0.0.1:38734","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-06T18:48:26.728256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313303906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-06T18:48:26.728271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.321821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:48:26.728285Z","caller":"traceutil/trace.go:171","msg":"trace[276626205] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:805; }","duration":"1.313335567s","start":"2026-02-06T18:48:25.41494Z","end":"2026-02-06T18:48:26.728276Z","steps":["trace[276626205] 'agreement among raft nodes before linearized reading' (duration: 1.313286815s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:26.728321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:25.414866Z","time spent":"1.313447469s","remote":"127.0.0.1:39070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-06T18:48:26.728326Z","caller":"traceutil/trace.go:171","msg":"trace[953812320] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:805; }","duration":"352.412033ms","start":"2026-02-06T18:48:26.375883Z","end":"2026-02-06T18:48:26.728295Z","steps":["trace[953812320] 'agreement among raft nodes before linearized reading' (duration: 352.349651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:26.728356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:26.375864Z","time spent":"352.484435ms","remote":"127.0.0.1:38912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-06T18:48:38.540759Z","caller":"traceutil/trace.go:171","msg":"trace[2130154482] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"350.467226ms","start":"2026-02-06T18:48:38.190269Z","end":"2026-02-06T18:48:38.540737Z","steps":["trace[2130154482] 'process raft request' (duration: 350.323082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:38.540935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:38.190259Z","time spent":"350.581098ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:48:38.54969Z","caller":"traceutil/trace.go:171","msg":"trace[1897412365] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"358.827207ms","start":"2026-02-06T18:48:38.190845Z","end":"2026-02-06T18:48:38.549672Z","steps":["trace[1897412365] 'process raft request' (duration: 358.550891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:38.549796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:38.190819Z","time spent":"358.91701ms","remote":"127.0.0.1:39022","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-06T18:48:38.549999Z","caller":"traceutil/trace.go:171","msg":"trace[1933017003] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"358.10489ms","start":"2026-02-06T18:48:38.191887Z","end":"2026-02-06T18:48:38.549992Z","steps":["trace[1933017003] 'process raft request' (duration: 357.599807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:38.550048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:38.19188Z","time spent":"358.149701ms","remote":"127.0.0.1:39022","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-06T18:48:39.913674Z","caller":"traceutil/trace.go:171","msg":"trace[2121051524] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"183.768813ms","start":"2026-02-06T18:48:39.729844Z","end":"2026-02-06T18:48:39.913613Z","steps":["trace[2121051524] 'process raft request' (duration: 183.607549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:40.285439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.114484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:48:40.285522Z","caller":"traceutil/trace.go:171","msg":"trace[304624896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"182.249667ms","start":"2026-02-06T18:48:40.103252Z","end":"2026-02-06T18:48:40.285502Z","steps":["trace[304624896] 'range keys from in-memory index tree' (duration: 182.042821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:40.286156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.602755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T18:48:40.286176Z","caller":"traceutil/trace.go:171","msg":"trace[1556268441] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:893; }","duration":"148.658626ms","start":"2026-02-06T18:48:40.13751Z","end":"2026-02-06T18:48:40.286168Z","steps":["trace[1556268441] 'range keys from in-memory index tree' (duration: 148.039682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:40.919121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.221687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T18:48:40.919224Z","caller":"traceutil/trace.go:171","msg":"trace[100206815] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"371.275068ms","start":"2026-02-06T18:48:40.547936Z","end":"2026-02-06T18:48:40.919211Z","steps":["trace[100206815] 'read index received' (duration: 136.981145ms)","trace[100206815] 'applied index is now lower than readState.Index' (duration: 234.292843ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:48:40.919291Z","caller":"traceutil/trace.go:171","msg":"trace[218541197] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"411.811217ms","start":"2026-02-06T18:48:40.507468Z","end":"2026-02-06T18:48:40.919279Z","steps":["trace[218541197] 'process raft request' (duration: 177.515104ms)","trace[218541197] 'compare' (duration: 233.027333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:48:40.91983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:40.507443Z","time spent":"411.868968ms","remote":"127.0.0.1:38784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:48:40.920275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.334254ms","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-06T18:48:40.920302Z","caller":"traceutil/trace.go:171","msg":"trace[1869599957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"372.398716ms","start":"2026-02-06T18:48:40.547896Z","end":"2026-02-06T18:48:40.920295Z","steps":["trace[1869599957] 'agreement among raft nodes before linearized reading' (duration: 372.17234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:40.920324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:40.547877Z","time spent":"372.442437ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T18:48:40.920631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.504284ms","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-06T18:48:40.920665Z","caller":"traceutil/trace.go:171","msg":"trace[1944339016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"366.547294ms","start":"2026-02-06T18:48:40.554112Z","end":"2026-02-06T18:48:40.920659Z","steps":["trace[1944339016] 'agreement among raft nodes before linearized reading' (duration: 366.472633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:40.920687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:40.55411Z","time spent":"366.572775ms","remote":"127.0.0.1:39022","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-06T18:48:40.920916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.810331ms","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-06T18:48:40.920935Z","caller":"traceutil/trace.go:171","msg":"trace[1358303993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"366.847622ms","start":"2026-02-06T18:48:40.554082Z","end":"2026-02-06T18:48:40.92093Z","steps":["trace[1358303993] 'agreement among raft nodes before linearized reading' (duration: 366.472813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:40.920968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:40.554072Z","time spent":"366.877252ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T18:48:41.060288Z","caller":"traceutil/trace.go:171","msg":"trace[2016088841] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:934; }","duration":"100.360682ms","start":"2026-02-06T18:48:40.959861Z","end":"2026-02-06T18:48:41.060222Z","steps":["trace[2016088841] 'read index received' (duration: 100.345071ms)","trace[2016088841] 'applied index is now lower than readState.Index' (duration: 12.741µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:48:41.060563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.668969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T18:48:41.06059Z","caller":"traceutil/trace.go:171","msg":"trace[1672548949] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:898; }","duration":"100.74845ms","start":"2026-02-06T18:48:40.959835Z","end":"2026-02-06T18:48:41.060584Z","steps":["trace[1672548949] 'agreement among raft nodes before linearized reading' (duration: 100.526335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:45.857612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.123516ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-06T18:48:46.398978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.779627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:48:46.399037Z","caller":"traceutil/trace.go:171","msg":"trace[404110107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"293.88888ms","start":"2026-02-06T18:48:46.105134Z","end":"2026-02-06T18:48:46.399022Z","steps":["trace[404110107] 'range keys from in-memory index tree' (duration: 293.664505ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:46.658307Z","caller":"traceutil/trace.go:171","msg":"trace[1943406632] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"121.43526ms","start":"2026-02-06T18:48:46.53685Z","end":"2026-02-06T18:48:46.658286Z","steps":["trace[1943406632] 'process raft request' (duration: 121.286346ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:47.327834Z","caller":"traceutil/trace.go:171","msg":"trace[2032628083] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"211.729829ms","start":"2026-02-06T18:48:47.116077Z","end":"2026-02-06T18:48:47.327807Z","steps":["trace[2032628083] 'read index received' (duration: 211.535415ms)","trace[2032628083] 'applied index is now lower than readState.Index' (duration: 192.974µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:48:47.327982Z","caller":"traceutil/trace.go:171","msg":"trace[1542025421] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"248.917506ms","start":"2026-02-06T18:48:47.079054Z","end":"2026-02-06T18:48:47.327971Z","steps":["trace[1542025421] 'process raft request' (duration: 248.580228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:47.328237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.972302ms","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-06T18:48:47.328313Z","caller":"traceutil/trace.go:171","msg":"trace[1412039178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"204.076824ms","start":"2026-02-06T18:48:47.124216Z","end":"2026-02-06T18:48:47.328293Z","steps":["trace[1412039178] 'agreement among raft nodes before linearized reading' (duration: 203.874349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:47.328535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.504989ms","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-06T18:48:47.328566Z","caller":"traceutil/trace.go:171","msg":"trace[1202466447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"203.54928ms","start":"2026-02-06T18:48:47.125008Z","end":"2026-02-06T18:48:47.328557Z","steps":["trace[1202466447] 'agreement among raft nodes before linearized reading' (duration: 203.468618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:47.328682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.583929ms","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-06T18:48:47.328724Z","caller":"traceutil/trace.go:171","msg":"trace[441345873] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"212.680532ms","start":"2026-02-06T18:48:47.116032Z","end":"2026-02-06T18:48:47.328712Z","steps":["trace[441345873] 'agreement among raft nodes before linearized reading' (duration: 212.519688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:47.748678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.978368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T18:48:47.748833Z","caller":"traceutil/trace.go:171","msg":"trace[90575998] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"410.103096ms","start":"2026-02-06T18:48:47.338709Z","end":"2026-02-06T18:48:47.748812Z","steps":["trace[90575998] 'process raft request' (duration: 102.928844ms)","trace[90575998] 'compare' (duration: 295.209523ms)","trace[90575998] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:473; } (duration: 11.681043ms)"],"step_count":3} {"level":"warn","ts":"2026-02-06T18:48:47.748936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:47.338681Z","time spent":"410.226198ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:48:47.87467Z","caller":"traceutil/trace.go:171","msg":"trace[268691539] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"535.844368ms","start":"2026-02-06T18:48:47.338807Z","end":"2026-02-06T18:48:47.874652Z","steps":["trace[268691539] 'process raft request' (duration: 535.817737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:47.874793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:47.338804Z","time spent":"535.925121ms","remote":"127.0.0.1:39022","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-06T18:48:47.875021Z","caller":"traceutil/trace.go:171","msg":"trace[1414035508] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"536.258789ms","start":"2026-02-06T18:48:47.338756Z","end":"2026-02-06T18:48:47.875015Z","steps":["trace[1414035508] 'process raft request' (duration: 535.766207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:47.875048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:47.338733Z","time spent":"536.300039ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:48:48.340705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.463734ms","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-06T18:48:48.340765Z","caller":"traceutil/trace.go:171","msg":"trace[352891904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:914; }","duration":"306.531366ms","start":"2026-02-06T18:48:48.03422Z","end":"2026-02-06T18:48:48.340751Z","steps":["trace[352891904] 'range keys from in-memory index tree' (duration: 306.335091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:48.340798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:48.034201Z","time spent":"306.590827ms","remote":"127.0.0.1:39022","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-06T18:48:48.340944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.533125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:48:48.340962Z","caller":"traceutil/trace.go:171","msg":"trace[390817958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"238.582636ms","start":"2026-02-06T18:48:48.102375Z","end":"2026-02-06T18:48:48.340958Z","steps":["trace[390817958] 'range keys from in-memory index tree' (duration: 238.478063ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:50.640068Z","caller":"traceutil/trace.go:171","msg":"trace[1020790914] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"249.786347ms","start":"2026-02-06T18:48:50.390263Z","end":"2026-02-06T18:48:50.64005Z","steps":["trace[1020790914] 'read index received' (duration: 218.905102ms)","trace[1020790914] 'applied index is now lower than readState.Index' (duration: 30.880555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:48:50.640258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.978641ms","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-06T18:48:50.640279Z","caller":"traceutil/trace.go:171","msg":"trace[118536246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"250.040813ms","start":"2026-02-06T18:48:50.390231Z","end":"2026-02-06T18:48:50.640272Z","steps":["trace[118536246] 'agreement among raft nodes before linearized reading' (duration: 249.887729ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:50.640571Z","caller":"traceutil/trace.go:171","msg":"trace[2119179212] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"280.103708ms","start":"2026-02-06T18:48:50.360458Z","end":"2026-02-06T18:48:50.640562Z","steps":["trace[2119179212] 'process raft request' (duration: 248.843053ms)","trace[2119179212] 'compare' (duration: 30.2376ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:48:52.075133Z","caller":"traceutil/trace.go:171","msg":"trace[1706555574] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"186.94001ms","start":"2026-02-06T18:48:51.888167Z","end":"2026-02-06T18:48:52.075107Z","steps":["trace[1706555574] 'read index received' (duration: 186.772106ms)","trace[1706555574] 'applied index is now lower than readState.Index' (duration: 167.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:48:52.075427Z","caller":"traceutil/trace.go:171","msg":"trace[1558310259] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"276.406969ms","start":"2026-02-06T18:48:51.799011Z","end":"2026-02-06T18:48:52.075418Z","steps":["trace[1558310259] 'process raft request' (duration: 275.984519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:52.076141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.841641ms","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-06T18:48:52.07625Z","caller":"traceutil/trace.go:171","msg":"trace[1074555828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"187.993045ms","start":"2026-02-06T18:48:51.888218Z","end":"2026-02-06T18:48:52.076211Z","steps":["trace[1074555828] 'agreement among raft nodes before linearized reading' (duration: 187.260227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:52.077152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.978068ms","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-06T18:48:52.077197Z","caller":"traceutil/trace.go:171","msg":"trace[1843824480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"189.04143ms","start":"2026-02-06T18:48:51.888135Z","end":"2026-02-06T18:48:52.077177Z","steps":["trace[1843824480] 'agreement among raft nodes before linearized reading' (duration: 187.502363ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:52.491952Z","caller":"traceutil/trace.go:171","msg":"trace[501440555] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"407.503012ms","start":"2026-02-06T18:48:52.08443Z","end":"2026-02-06T18:48:52.491933Z","steps":["trace[501440555] 'process raft request' (duration: 406.831876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:52.49211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:52.084409Z","time spent":"407.621144ms","remote":"127.0.0.1:39022","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-06T18:48:52.492782Z","caller":"traceutil/trace.go:171","msg":"trace[1204093716] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:962; }","duration":"294.925596ms","start":"2026-02-06T18:48:52.197839Z","end":"2026-02-06T18:48:52.492765Z","steps":["trace[1204093716] 'read index received' (duration: 293.0021ms)","trace[1204093716] 'applied index is now lower than readState.Index' (duration: 1.922696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:48:52.492893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.031329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:48:52.492912Z","caller":"traceutil/trace.go:171","msg":"trace[1158869178] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:926; }","duration":"295.0719ms","start":"2026-02-06T18:48:52.197834Z","end":"2026-02-06T18:48:52.492906Z","steps":["trace[1158869178] 'agreement among raft nodes before linearized reading' (duration: 294.996778ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:52.493074Z","caller":"traceutil/trace.go:171","msg":"trace[1517276381] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"393.222709ms","start":"2026-02-06T18:48:52.099845Z","end":"2026-02-06T18:48:52.493068Z","steps":["trace[1517276381] 'process raft request' (duration: 392.815619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:52.493132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:52.099826Z","time spent":"393.261849ms","remote":"127.0.0.1:39022","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-06T18:48:56.697554Z","caller":"traceutil/trace.go:171","msg":"trace[55647502] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"151.634069ms","start":"2026-02-06T18:48:56.545903Z","end":"2026-02-06T18:48:56.697537Z","steps":["trace[55647502] 'read index received' (duration: 151.461434ms)","trace[55647502] 'applied index is now lower than readState.Index' (duration: 172.065µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:48:56.697704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.790512ms","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-06T18:48:56.697724Z","caller":"traceutil/trace.go:171","msg":"trace[402062480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"151.843844ms","start":"2026-02-06T18:48:56.545873Z","end":"2026-02-06T18:48:56.697717Z","steps":["trace[402062480] 'agreement among raft nodes before linearized reading' (duration: 151.728721ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:56.6979Z","caller":"traceutil/trace.go:171","msg":"trace[1504951064] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"180.629329ms","start":"2026-02-06T18:48:56.517255Z","end":"2026-02-06T18:48:56.697885Z","steps":["trace[1504951064] 'process raft request' (duration: 180.181108ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:56.928189Z","caller":"traceutil/trace.go:171","msg":"trace[1408726088] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"194.179194ms","start":"2026-02-06T18:48:56.733989Z","end":"2026-02-06T18:48:56.928168Z","steps":["trace[1408726088] 'read index received' (duration: 192.035212ms)","trace[1408726088] 'applied index is now lower than readState.Index' (duration: 2.143172ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:48:56.928287Z","caller":"traceutil/trace.go:171","msg":"trace[2116633826] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"225.131291ms","start":"2026-02-06T18:48:56.703146Z","end":"2026-02-06T18:48:56.928278Z","steps":["trace[2116633826] 'process raft request' (duration: 222.863316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:56.92847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.462821ms","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-06T18:48:56.928531Z","caller":"traceutil/trace.go:171","msg":"trace[2112289498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:936; }","duration":"194.557314ms","start":"2026-02-06T18:48:56.733959Z","end":"2026-02-06T18:48:56.928517Z","steps":["trace[2112289498] 'agreement among raft nodes before linearized reading' (duration: 194.40125ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:56.929447Z","caller":"traceutil/trace.go:171","msg":"trace[667260800] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"175.723699ms","start":"2026-02-06T18:48:56.753712Z","end":"2026-02-06T18:48:56.929436Z","steps":["trace[667260800] 'process raft request' (duration: 175.459652ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:59.073956Z","caller":"traceutil/trace.go:171","msg":"trace[1645609635] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"360.943758ms","start":"2026-02-06T18:48:58.712988Z","end":"2026-02-06T18:48:59.073931Z","steps":["trace[1645609635] 'process raft request' (duration: 360.780434ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:48:59.07592Z","caller":"traceutil/trace.go:171","msg":"trace[125412343] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"141.774031ms","start":"2026-02-06T18:48:58.934128Z","end":"2026-02-06T18:48:59.075902Z","steps":["trace[125412343] 'read index received' (duration: 139.962277ms)","trace[125412343] 'applied index is now lower than readState.Index' (duration: 1.811014ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:48:59.076131Z","caller":"traceutil/trace.go:171","msg":"trace[1944121993] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"234.957459ms","start":"2026-02-06T18:48:58.841163Z","end":"2026-02-06T18:48:59.076121Z","steps":["trace[1944121993] 'process raft request' (duration: 234.663682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:59.076429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.293223ms","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-06T18:48:59.076475Z","caller":"traceutil/trace.go:171","msg":"trace[14142519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"142.377925ms","start":"2026-02-06T18:48:58.934086Z","end":"2026-02-06T18:48:59.076464Z","steps":["trace[14142519] 'agreement among raft nodes before linearized reading' (duration: 142.216231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:59.076682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.389811ms","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-06T18:48:59.076717Z","caller":"traceutil/trace.go:171","msg":"trace[549904146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:942; }","duration":"136.455502ms","start":"2026-02-06T18:48:58.940253Z","end":"2026-02-06T18:48:59.076708Z","steps":["trace[549904146] 'agreement among raft nodes before linearized reading' (duration: 136.37109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:48:59.074576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:48:58.712976Z","time spent":"361.510172ms","remote":"127.0.0.1:39022","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-06T18:49:01.226564Z","caller":"traceutil/trace.go:171","msg":"trace[1791135442] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"135.513189ms","start":"2026-02-06T18:49:01.091027Z","end":"2026-02-06T18:49:01.22654Z","steps":["trace[1791135442] 'process raft request' (duration: 135.378406ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:49:01.226953Z","caller":"traceutil/trace.go:171","msg":"trace[1072603378] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:996; }","duration":"133.235385ms","start":"2026-02-06T18:49:01.09371Z","end":"2026-02-06T18:49:01.226946Z","steps":["trace[1072603378] 'read index received' (duration: 133.232014ms)","trace[1072603378] 'applied index is now lower than readState.Index' (duration: 2.901µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:49:01.227068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.338717ms","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-06T18:49:01.227088Z","caller":"traceutil/trace.go:171","msg":"trace[1707415316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"133.373188ms","start":"2026-02-06T18:49:01.093709Z","end":"2026-02-06T18:49:01.227082Z","steps":["trace[1707415316] 'agreement among raft nodes before linearized reading' (duration: 133.262945ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:49:01.349058Z","caller":"traceutil/trace.go:171","msg":"trace[1290684469] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"231.832893ms","start":"2026-02-06T18:49:01.117176Z","end":"2026-02-06T18:49:01.349009Z","steps":["trace[1290684469] 'process raft request' (duration: 207.712991ms)","trace[1290684469] 'compare' (duration: 23.60547ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:49:01.397416Z","caller":"traceutil/trace.go:171","msg":"trace[404924995] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"167.295765ms","start":"2026-02-06T18:49:01.230103Z","end":"2026-02-06T18:49:01.397399Z","steps":["trace[404924995] 'process raft request' (duration: 164.413436ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:49:02.243833Z","caller":"traceutil/trace.go:171","msg":"trace[2001621640] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"128.847607ms","start":"2026-02-06T18:49:02.114953Z","end":"2026-02-06T18:49:02.243801Z","steps":["trace[2001621640] 'process raft request' (duration: 128.664623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:05.222883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.220109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:49:05.22296Z","caller":"traceutil/trace.go:171","msg":"trace[880103501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"122.337432ms","start":"2026-02-06T18:49:05.100606Z","end":"2026-02-06T18:49:05.222944Z","steps":["trace[880103501] 'range keys from in-memory index tree' (duration: 122.152448ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:49:18.457475Z","caller":"traceutil/trace.go:171","msg":"trace[770412831] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"141.422166ms","start":"2026-02-06T18:49:18.316019Z","end":"2026-02-06T18:49:18.457441Z","steps":["trace[770412831] 'process raft request' (duration: 141.222832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:19.16048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.162843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-06T18:49:19.160538Z","caller":"traceutil/trace.go:171","msg":"trace[1995155116] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1063; }","duration":"353.32418ms","start":"2026-02-06T18:49:18.807198Z","end":"2026-02-06T18:49:19.160522Z","steps":["trace[1995155116] 'range keys from in-memory index tree' (duration: 352.03761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:19.160575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:49:18.807132Z","time spent":"353.431473ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-06T18:49:19.160609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.439898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-06T18:49:19.160661Z","caller":"traceutil/trace.go:171","msg":"trace[1606854340] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1063; }","duration":"591.663484ms","start":"2026-02-06T18:49:18.568982Z","end":"2026-02-06T18:49:19.160645Z","steps":["trace[1606854340] 'range keys from in-memory index tree' (duration: 591.296535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:19.160689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:49:18.56896Z","time spent":"591.722755ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-06T18:49:19.91148Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360944467873349422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T18:49:20.181498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.016403204s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T18:49:20.217023Z","caller":"traceutil/trace.go:171","msg":"trace[735424717] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"1.051985721s","start":"2026-02-06T18:49:19.165019Z","end":"2026-02-06T18:49:20.217005Z","steps":["trace[735424717] 'process raft request' (duration: 1.051880338s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:20.217175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:49:19.165005Z","time spent":"1.052108773s","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:49:20.220164Z","caller":"traceutil/trace.go:171","msg":"trace[847768851] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1107; }","duration":"809.511479ms","start":"2026-02-06T18:49:19.410637Z","end":"2026-02-06T18:49:20.220149Z","steps":["trace[847768851] 'read index received' (duration: 806.898378ms)","trace[847768851] 'applied index is now lower than readState.Index' (duration: 2.612481ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:49:20.220412Z","caller":"traceutil/trace.go:171","msg":"trace[1227392465] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"1.055359201s","start":"2026-02-06T18:49:19.165041Z","end":"2026-02-06T18:49:20.2204Z","steps":["trace[1227392465] 'process raft request' (duration: 1.055002572s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:20.220491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:49:19.165025Z","time spent":"1.055418402s","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:49:20.220616Z","caller":"traceutil/trace.go:171","msg":"trace[1623773742] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"810.623886ms","start":"2026-02-06T18:49:19.409984Z","end":"2026-02-06T18:49:20.220608Z","steps":["trace[1623773742] 'process raft request' (duration: 810.129213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:20.220681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:49:19.409961Z","time spent":"810.669518ms","remote":"127.0.0.1:39022","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-06T18:49:20.220835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.195226ms","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-06T18:49:20.220858Z","caller":"traceutil/trace.go:171","msg":"trace[1658370313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"810.243428ms","start":"2026-02-06T18:49:19.410609Z","end":"2026-02-06T18:49:20.220852Z","steps":["trace[1658370313] 'agreement among raft nodes before linearized reading' (duration: 810.148255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:20.220882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:49:19.410599Z","time spent":"810.276388ms","remote":"127.0.0.1:39022","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-06T18:49:20.221164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.102942ms","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-06T18:49:20.221215Z","caller":"traceutil/trace.go:171","msg":"trace[1713758190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"749.184524ms","start":"2026-02-06T18:49:19.472024Z","end":"2026-02-06T18:49:20.221209Z","steps":["trace[1713758190] 'agreement among raft nodes before linearized reading' (duration: 748.939088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:20.221238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:49:19.472011Z","time spent":"749.222405ms","remote":"127.0.0.1:39022","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-06T18:49:20.221752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.326666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:49:20.22178Z","caller":"traceutil/trace.go:171","msg":"trace[125845420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"123.354146ms","start":"2026-02-06T18:49:20.098418Z","end":"2026-02-06T18:49:20.221772Z","steps":["trace[125845420] 'agreement among raft nodes before linearized reading' (duration: 123.314415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:20.221944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.872208ms","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-06T18:49:20.221967Z","caller":"traceutil/trace.go:171","msg":"trace[1738486991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"173.92004ms","start":"2026-02-06T18:49:20.048042Z","end":"2026-02-06T18:49:20.221962Z","steps":["trace[1738486991] 'agreement among raft nodes before linearized reading' (duration: 173.816847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:20.222076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.634204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-06T18:49:20.222096Z","caller":"traceutil/trace.go:171","msg":"trace[190241888] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1066; }","duration":"268.681616ms","start":"2026-02-06T18:49:19.953409Z","end":"2026-02-06T18:49:20.222091Z","steps":["trace[190241888] 'agreement among raft nodes before linearized reading' (duration: 268.633775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:20.222228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.96618ms","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-06T18:49:20.222248Z","caller":"traceutil/trace.go:171","msg":"trace[21778430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1066; }","duration":"730.98733ms","start":"2026-02-06T18:49:19.491256Z","end":"2026-02-06T18:49:20.222243Z","steps":["trace[21778430] 'agreement among raft nodes before linearized reading' (duration: 730.933209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:49:20.222268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:49:19.491223Z","time spent":"731.041572ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T18:52:20.947858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.070963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T18:52:20.94795Z","caller":"traceutil/trace.go:171","msg":"trace[1009050638] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1829; }","duration":"116.210936ms","start":"2026-02-06T18:52:20.831722Z","end":"2026-02-06T18:52:20.947933Z","steps":["trace[1009050638] 'count revisions from in-memory index tree' (duration: 115.9237ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:52:21.212634Z","caller":"traceutil/trace.go:171","msg":"trace[622720878] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"120.920028ms","start":"2026-02-06T18:52:21.091698Z","end":"2026-02-06T18:52:21.212618Z","steps":["trace[622720878] 'read index received' (duration: 120.794465ms)","trace[622720878] 'applied index is now lower than readState.Index' (duration: 125.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:52:21.212842Z","caller":"traceutil/trace.go:171","msg":"trace[1527315982] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"293.319509ms","start":"2026-02-06T18:52:20.919492Z","end":"2026-02-06T18:52:21.212812Z","steps":["trace[1527315982] 'process raft request' (duration: 293.047002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:52:21.213012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.300497ms","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-06T18:52:21.213029Z","caller":"traceutil/trace.go:171","msg":"trace[1012088796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1830; }","duration":"121.364849ms","start":"2026-02-06T18:52:21.091658Z","end":"2026-02-06T18:52:21.213023Z","steps":["trace[1012088796] 'agreement among raft nodes before linearized reading' (duration: 121.282817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:52:21.21312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.570884ms","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-06T18:52:21.213132Z","caller":"traceutil/trace.go:171","msg":"trace[640330150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1830; }","duration":"113.597384ms","start":"2026-02-06T18:52:21.099531Z","end":"2026-02-06T18:52:21.213128Z","steps":["trace[640330150] 'agreement among raft nodes before linearized reading' (duration: 113.557763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:52:30.581901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:52:30.152984Z","time spent":"428.911433ms","remote":"127.0.0.1:38784","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-06T18:52:31.060428Z","caller":"traceutil/trace.go:171","msg":"trace[2071359611] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"258.821697ms","start":"2026-02-06T18:52:30.801569Z","end":"2026-02-06T18:52:31.060391Z","steps":["trace[2071359611] 'process raft request' (duration: 210.606904ms)","trace[2071359611] 'compare' (duration: 48.08149ms)"],"step_count":2}