{"level":"warn","ts":"2026-02-01T18:49:22.327732Z","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-01T18:49:22.328011Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-01T18:49:22.328129Z","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-01T18:49:22.328186Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-02-01T18:49:22.328253Z","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-01T18:49:22.32884Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-02-01T18:49:22.328958Z","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://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-01T18:49:22.338516Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.30511ms"} {"level":"info","ts":"2026-02-01T18:49:22.350745Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-02-01T18:49:22.350912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:49:22.350976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-02-01T18:49:22.351029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:49:22.351081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-02-01T18:49:22.351145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-02-01T18:49:22.363576Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:49:22.372031Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:49:22.385144Z","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-01T18:49:22.395173Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:49:22.397678Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:49:22.397857Z","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-01T18:49:22.39791Z","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-01T18:49:22.397923Z","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-01T18:49:22.400183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-02-01T18:49:22.424072Z","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-01T18:49:22.424471Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-02-01T18:49:22.424475Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:49:22.424629Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-02-01T18:49:22.424653Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-02-01T18:49:22.42469Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:49:22.554616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:49:22.554757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:49:22.554823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-02-01T18:49:22.554874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:49:22.554913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-02-01T18:49:22.554973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-02-01T18:49:22.555008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-02-01T18:49:22.557641Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:49:22.557682Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:49:22.55807Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:49:22.558194Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:49:22.558843Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:49:22.558858Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:49:22.562744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:49:22.564066Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-02-01T18:49:22.564662Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:49:22.564791Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:49:22.564872Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:49:32.764486Z","caller":"traceutil/trace.go:171","msg":"trace[1135516915] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"151.847604ms","start":"2026-02-01T18:49:32.612621Z","end":"2026-02-01T18:49:32.764469Z","steps":["trace[1135516915] 'read index received' (duration: 150.595043ms)","trace[1135516915] 'applied index is now lower than readState.Index' (duration: 1.251551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:49:32.764936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.259387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-01T18:49:32.765032Z","caller":"traceutil/trace.go:171","msg":"trace[1101798388] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:341; }","duration":"152.436699ms","start":"2026-02-01T18:49:32.612576Z","end":"2026-02-01T18:49:32.765013Z","steps":["trace[1101798388] 'agreement among raft nodes before linearized reading' (duration: 152.191517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:32.765211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.623006ms","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-01T18:49:32.765482Z","caller":"traceutil/trace.go:171","msg":"trace[857179662] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"200.097305ms","start":"2026-02-01T18:49:32.565361Z","end":"2026-02-01T18:49:32.765458Z","steps":["trace[857179662] 'process raft request' (duration: 197.849046ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:32.765668Z","caller":"traceutil/trace.go:171","msg":"trace[1066442470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:341; }","duration":"126.18634ms","start":"2026-02-01T18:49:32.639322Z","end":"2026-02-01T18:49:32.765508Z","steps":["trace[1066442470] 'agreement among raft nodes before linearized reading' (duration: 125.621256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:32.766384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.202291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-01T18:49:32.766472Z","caller":"traceutil/trace.go:171","msg":"trace[778181514] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:341; }","duration":"103.325601ms","start":"2026-02-01T18:49:32.663135Z","end":"2026-02-01T18:49:32.76646Z","steps":["trace[778181514] 'agreement among raft nodes before linearized reading' (duration: 103.21919ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:38.49722Z","caller":"traceutil/trace.go:171","msg":"trace[522472386] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"407.54956ms","start":"2026-02-01T18:49:38.089648Z","end":"2026-02-01T18:49:38.497197Z","steps":["trace[522472386] 'read index received' (duration: 407.376128ms)","trace[522472386] 'applied index is now lower than readState.Index' (duration: 172.462µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:49:38.498037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.108166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-01T18:49:38.498079Z","caller":"traceutil/trace.go:171","msg":"trace[201178988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"293.192377ms","start":"2026-02-01T18:49:38.204875Z","end":"2026-02-01T18:49:38.498068Z","steps":["trace[201178988] 'agreement among raft nodes before linearized reading' (duration: 293.108097ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:38.498187Z","caller":"traceutil/trace.go:171","msg":"trace[1416382969] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"458.077506ms","start":"2026-02-01T18:49:38.040101Z","end":"2026-02-01T18:49:38.498178Z","steps":["trace[1416382969] 'process raft request' (duration: 456.976467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:38.498668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:38.040086Z","time spent":"458.120386ms","remote":"127.0.0.1:59200","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-01T18:49:38.498771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.120563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:49:38.498796Z","caller":"traceutil/trace.go:171","msg":"trace[1792455007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"409.165323ms","start":"2026-02-01T18:49:38.089621Z","end":"2026-02-01T18:49:38.498786Z","steps":["trace[1792455007] 'agreement among raft nodes before linearized reading' (duration: 409.128133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:38.498821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:38.089608Z","time spent":"409.207593ms","remote":"127.0.0.1:58942","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-01T18:49:39.075336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694503161475726,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:49:40.33994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202744119s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:49:40.340031Z","caller":"traceutil/trace.go:171","msg":"trace[558308772] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.831755046s","start":"2026-02-01T18:49:38.508258Z","end":"2026-02-01T18:49:40.340013Z","steps":["trace[558308772] 'process raft request' (duration: 628.761065ms)","trace[558308772] 'compare' (duration: 1.20157212s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:49:40.340087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:38.508245Z","time spent":"1.831816596s","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:49:40.445266Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.308272084s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:49:40.50074Z","caller":"traceutil/trace.go:171","msg":"trace[1997914743] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:426; }","duration":"1.925603837s","start":"2026-02-01T18:49:38.575116Z","end":"2026-02-01T18:49:40.50072Z","steps":["trace[1997914743] 'read index received' (duration: 561.911334ms)","trace[1997914743] 'applied index is now lower than readState.Index' (duration: 1.363691913s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:49:40.500846Z","caller":"traceutil/trace.go:171","msg":"trace[1262889888] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.989901387s","start":"2026-02-01T18:49:38.510937Z","end":"2026-02-01T18:49:40.500838Z","steps":["trace[1262889888] 'process raft request' (duration: 1.934442238s)","trace[1262889888] 'compare' (duration: 55.236917ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:49:40.500925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:38.510928Z","time spent":"1.989935837s","remote":"127.0.0.1:59122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:49:40.50115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690396717s","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-01T18:49:40.501216Z","caller":"traceutil/trace.go:171","msg":"trace[983734470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.690500208s","start":"2026-02-01T18:49:38.810699Z","end":"2026-02-01T18:49:40.501199Z","steps":["trace[983734470] 'agreement among raft nodes before linearized reading' (duration: 1.690390897s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:40.501256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:38.810682Z","time spent":"1.690566979s","remote":"127.0.0.1:59200","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-01T18:49:40.501401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364542089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:49:40.501428Z","caller":"traceutil/trace.go:171","msg":"trace[832396832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"1.36460363s","start":"2026-02-01T18:49:39.136817Z","end":"2026-02-01T18:49:40.501421Z","steps":["trace[832396832] 'agreement among raft nodes before linearized reading' (duration: 1.364559889s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:40.50144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427184753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-01T18:49:40.501471Z","caller":"traceutil/trace.go:171","msg":"trace[446866403] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:411; }","duration":"1.427238253s","start":"2026-02-01T18:49:39.074224Z","end":"2026-02-01T18:49:40.501462Z","steps":["trace[446866403] 'agreement among raft nodes before linearized reading' (duration: 1.427182083s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:40.501481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.926363183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T18:49:40.501501Z","caller":"traceutil/trace.go:171","msg":"trace[1170076979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"1.926407193s","start":"2026-02-01T18:49:38.575089Z","end":"2026-02-01T18:49:40.501496Z","steps":["trace[1170076979] 'agreement among raft nodes before linearized reading' (duration: 1.926371343s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:40.501501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:39.074212Z","time spent":"1.427283763s","remote":"127.0.0.1:59374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4056,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-01T18:49:40.501514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:38.575074Z","time spent":"1.926437063s","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:49:40.501621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206923441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:49:40.501636Z","caller":"traceutil/trace.go:171","msg":"trace[300490772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"1.206965471s","start":"2026-02-01T18:49:39.294666Z","end":"2026-02-01T18:49:40.501631Z","steps":["trace[300490772] 'agreement among raft nodes before linearized reading' (duration: 1.206937331s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:40.50165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:39.294651Z","time spent":"1.206994981s","remote":"127.0.0.1:58946","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-01T18:49:40.501694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690029103s","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-01T18:49:40.50174Z","caller":"traceutil/trace.go:171","msg":"trace[1894124405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.690149134s","start":"2026-02-01T18:49:38.811582Z","end":"2026-02-01T18:49:40.501731Z","steps":["trace[1894124405] 'agreement among raft nodes before linearized reading' (duration: 1.690027193s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:40.501765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:38.811573Z","time spent":"1.690186414s","remote":"127.0.0.1:59200","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-01T18:49:40.501446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:39.136798Z","time spent":"1.364643801s","remote":"127.0.0.1:58942","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-01T18:49:40.728688Z","caller":"traceutil/trace.go:171","msg":"trace[1232198871] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"207.192319ms","start":"2026-02-01T18:49:40.521467Z","end":"2026-02-01T18:49:40.728659Z","steps":["trace[1232198871] 'process raft request' (duration: 206.824427ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:40.737009Z","caller":"traceutil/trace.go:171","msg":"trace[262062187] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:428; }","duration":"212.712864ms","start":"2026-02-01T18:49:40.524278Z","end":"2026-02-01T18:49:40.736991Z","steps":["trace[262062187] 'read index received' (duration: 203.969883ms)","trace[262062187] 'applied index is now lower than readState.Index' (duration: 8.742351ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:49:40.737245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.817925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:49:40.737584Z","caller":"traceutil/trace.go:171","msg":"trace[835537103] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"214.511339ms","start":"2026-02-01T18:49:40.523062Z","end":"2026-02-01T18:49:40.737574Z","steps":["trace[835537103] 'process raft request' (duration: 213.758653ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:40.738455Z","caller":"traceutil/trace.go:171","msg":"trace[1922908154] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"206.16273ms","start":"2026-02-01T18:49:40.532284Z","end":"2026-02-01T18:49:40.738447Z","steps":["trace[1922908154] 'process raft request' (duration: 204.661039ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:40.740051Z","caller":"traceutil/trace.go:171","msg":"trace[2026730083] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"210.97402ms","start":"2026-02-01T18:49:40.529067Z","end":"2026-02-01T18:49:40.740041Z","steps":["trace[2026730083] 'process raft request' (duration: 207.847254ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:40.741133Z","caller":"traceutil/trace.go:171","msg":"trace[999091819] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"208.810662ms","start":"2026-02-01T18:49:40.532312Z","end":"2026-02-01T18:49:40.741122Z","steps":["trace[999091819] 'process raft request' (duration: 204.655799ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:40.737278Z","caller":"traceutil/trace.go:171","msg":"trace[1799030521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"213.015246ms","start":"2026-02-01T18:49:40.524254Z","end":"2026-02-01T18:49:40.737269Z","steps":["trace[1799030521] 'agreement among raft nodes before linearized reading' (duration: 212.805815ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:40.905276Z","caller":"traceutil/trace.go:171","msg":"trace[525709018] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"114.009755ms","start":"2026-02-01T18:49:40.791248Z","end":"2026-02-01T18:49:40.905258Z","steps":["trace[525709018] 'process raft request' (duration: 113.726123ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:40.905708Z","caller":"traceutil/trace.go:171","msg":"trace[1325337772] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"114.72159ms","start":"2026-02-01T18:49:40.790976Z","end":"2026-02-01T18:49:40.905698Z","steps":["trace[1325337772] 'process raft request' (duration: 69.646455ms)","trace[1325337772] 'compare' (duration: 44.245209ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:49:40.90678Z","caller":"traceutil/trace.go:171","msg":"trace[1403751719] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"115.467537ms","start":"2026-02-01T18:49:40.791303Z","end":"2026-02-01T18:49:40.906771Z","steps":["trace[1403751719] 'process raft request' (duration: 113.707022ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:40.90792Z","caller":"traceutil/trace.go:171","msg":"trace[676003997] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"116.491884ms","start":"2026-02-01T18:49:40.791416Z","end":"2026-02-01T18:49:40.907908Z","steps":["trace[676003997] 'process raft request' (duration: 113.629001ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:41.11139Z","caller":"traceutil/trace.go:171","msg":"trace[1640745222] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"138.190945ms","start":"2026-02-01T18:49:40.97318Z","end":"2026-02-01T18:49:41.111371Z","steps":["trace[1640745222] 'process raft request' (duration: 99.1078ms)","trace[1640745222] 'compare' (duration: 38.484539ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:49:41.112057Z","caller":"traceutil/trace.go:171","msg":"trace[175351886] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"135.383391ms","start":"2026-02-01T18:49:40.97666Z","end":"2026-02-01T18:49:41.112043Z","steps":["trace[175351886] 'read index received' (duration: 17.963985ms)","trace[175351886] 'applied index is now lower than readState.Index' (duration: 117.418606ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:49:41.112629Z","caller":"traceutil/trace.go:171","msg":"trace[576146141] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"135.865624ms","start":"2026-02-01T18:49:40.976752Z","end":"2026-02-01T18:49:41.112617Z","steps":["trace[576146141] 'process raft request' (duration: 134.570444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:41.114595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.896511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-gbtlb\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-02-01T18:49:41.11464Z","caller":"traceutil/trace.go:171","msg":"trace[314693596] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-gbtlb; range_end:; response_count:1; response_revision:427; }","duration":"137.954912ms","start":"2026-02-01T18:49:40.976674Z","end":"2026-02-01T18:49:41.114628Z","steps":["trace[314693596] 'agreement among raft nodes before linearized reading' (duration: 137.851841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:41.115574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.844228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-01T18:49:41.11577Z","caller":"traceutil/trace.go:171","msg":"trace[301292876] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:427; }","duration":"138.960599ms","start":"2026-02-01T18:49:40.976643Z","end":"2026-02-01T18:49:41.115604Z","steps":["trace[301292876] 'agreement among raft nodes before linearized reading' (duration: 138.828048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:41.116271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.014041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-02-01T18:49:41.116677Z","caller":"traceutil/trace.go:171","msg":"trace[1884671345] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:427; }","duration":"117.436765ms","start":"2026-02-01T18:49:40.999227Z","end":"2026-02-01T18:49:41.116664Z","steps":["trace[1884671345] 'agreement among raft nodes before linearized reading' (duration: 116.958601ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:41.488825Z","caller":"traceutil/trace.go:171","msg":"trace[769607187] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"137.530815ms","start":"2026-02-01T18:49:41.350513Z","end":"2026-02-01T18:49:41.488044Z","steps":["trace[769607187] 'process raft request' (duration: 115.348207ms)","trace[769607187] 'compare' (duration: 22.065247ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:49:52.944923Z","caller":"traceutil/trace.go:171","msg":"trace[169641954] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"128.763247ms","start":"2026-02-01T18:49:52.816142Z","end":"2026-02-01T18:49:52.944905Z","steps":["trace[169641954] 'process raft request' (duration: 128.411934ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:53.732994Z","caller":"traceutil/trace.go:171","msg":"trace[1018909287] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"284.669792ms","start":"2026-02-01T18:49:53.448306Z","end":"2026-02-01T18:49:53.732976Z","steps":["trace[1018909287] 'process raft request' (duration: 284.576712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:00.795237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694503161475999,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:50:00.840843Z","caller":"traceutil/trace.go:171","msg":"trace[384619131] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"546.570652ms","start":"2026-02-01T18:50:00.294252Z","end":"2026-02-01T18:50:00.840823Z","steps":["trace[384619131] 'read index received' (duration: 546.388551ms)","trace[384619131] 'applied index is now lower than readState.Index' (duration: 181.241µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:50:00.841147Z","caller":"traceutil/trace.go:171","msg":"trace[1620543183] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"835.993619ms","start":"2026-02-01T18:50:00.005143Z","end":"2026-02-01T18:50:00.841136Z","steps":["trace[1620543183] 'process raft request' (duration: 835.555285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:00.841231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:00.005124Z","time spent":"836.047519ms","remote":"127.0.0.1:59122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:50:00.841438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.178427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:00.84148Z","caller":"traceutil/trace.go:171","msg":"trace[1101884661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"547.244428ms","start":"2026-02-01T18:50:00.294224Z","end":"2026-02-01T18:50:00.841468Z","steps":["trace[1101884661] 'agreement among raft nodes before linearized reading' (duration: 547.160727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:00.841508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:00.29421Z","time spent":"547.290888ms","remote":"127.0.0.1:58942","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-01T18:50:00.841622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.924702ms","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-01T18:50:00.841653Z","caller":"traceutil/trace.go:171","msg":"trace[1789749753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"378.996193ms","start":"2026-02-01T18:50:00.462647Z","end":"2026-02-01T18:50:00.841643Z","steps":["trace[1789749753] 'agreement among raft nodes before linearized reading' (duration: 378.902772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:00.84168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:00.462628Z","time spent":"379.044333ms","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T18:50:01.01945Z","caller":"traceutil/trace.go:171","msg":"trace[19986153] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"165.296014ms","start":"2026-02-01T18:50:00.854134Z","end":"2026-02-01T18:50:01.01943Z","steps":["trace[19986153] 'process raft request' (duration: 98.443709ms)","trace[19986153] 'compare' (duration: 66.442322ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:50:01.019751Z","caller":"traceutil/trace.go:171","msg":"trace[214892543] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"165.468825ms","start":"2026-02-01T18:50:00.854271Z","end":"2026-02-01T18:50:01.01974Z","steps":["trace[214892543] 'read index received' (duration: 98.313658ms)","trace[214892543] 'applied index is now lower than readState.Index' (duration: 67.152627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:01.855895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.781355ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-01T18:50:01.855968Z","caller":"traceutil/trace.go:171","msg":"trace[655520162] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"836.18232ms","start":"2026-02-01T18:50:01.019772Z","end":"2026-02-01T18:50:01.855955Z","steps":["trace[655520162] 'read index received' (duration: 41.67µs)","trace[655520162] 'applied index is now lower than readState.Index' (duration: 836.13955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:01.856089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.163052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T18:50:01.856106Z","caller":"traceutil/trace.go:171","msg":"trace[2068810403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"887.221852ms","start":"2026-02-01T18:50:00.968878Z","end":"2026-02-01T18:50:01.8561Z","steps":["trace[2068810403] 'agreement among raft nodes before linearized reading' (duration: 887.109191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:01.85613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:00.968865Z","time spent":"887.259582ms","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:50:01.856348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002075108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:01.856369Z","caller":"traceutil/trace.go:171","msg":"trace[1722835811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"1.002111058s","start":"2026-02-01T18:50:00.85425Z","end":"2026-02-01T18:50:01.856361Z","steps":["trace[1722835811] 'agreement among raft nodes before linearized reading' (duration: 165.553025ms)","trace[1722835811] 'range keys from in-memory index tree' (duration: 836.528773ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:01.856386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:00.854242Z","time spent":"1.002140098s","remote":"127.0.0.1:58946","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-01T18:50:01.856886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.465111ms","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-01T18:50:01.856906Z","caller":"traceutil/trace.go:171","msg":"trace[929316451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"853.510892ms","start":"2026-02-01T18:50:01.003389Z","end":"2026-02-01T18:50:01.8569Z","steps":["trace[929316451] 'agreement among raft nodes before linearized reading' (duration: 853.452461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:01.856924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:01.003375Z","time spent":"853.544122ms","remote":"127.0.0.1:59200","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-01T18:50:01.857031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.694489ms","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-01T18:50:01.857048Z","caller":"traceutil/trace.go:171","msg":"trace[444519423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"875.73752ms","start":"2026-02-01T18:50:00.981305Z","end":"2026-02-01T18:50:01.857043Z","steps":["trace[444519423] 'agreement among raft nodes before linearized reading' (duration: 875.689759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:01.857072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:00.981291Z","time spent":"875.77657ms","remote":"127.0.0.1:59200","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-01T18:50:02.370121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694503161476008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:50:02.870314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694503161476008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:50:03.371191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694503161476008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:50:03.869865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000452666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-01T18:50:03.869967Z","caller":"traceutil/trace.go:171","msg":"trace[198170939] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000585347s","start":"2026-02-01T18:50:01.86936Z","end":"2026-02-01T18:50:03.869946Z","steps":["trace[198170939] 'agreement among raft nodes before linearized reading' (duration: 2.000447936s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:03.870019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:01.869345Z","time spent":"2.000662467s","remote":"127.0.0.1:58946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:50:03.871544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694503161476008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:50:04.004698Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.135960115s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:50:04.00505Z","caller":"traceutil/trace.go:171","msg":"trace[1163833766] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"2.136345977s","start":"2026-02-01T18:50:01.868683Z","end":"2026-02-01T18:50:04.005029Z","steps":["trace[1163833766] 'process raft request' (duration: 2.136216066s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:04.005151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:01.868676Z","time spent":"2.136411397s","remote":"127.0.0.1:59200","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-01T18:50:04.064986Z","caller":"traceutil/trace.go:171","msg":"trace[1795927817] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:575; }","duration":"2.19560702s","start":"2026-02-01T18:50:01.869362Z","end":"2026-02-01T18:50:04.064969Z","steps":["trace[1795927817] 'read index received' (duration: 2.136194615s)","trace[1795927817] 'applied index is now lower than readState.Index' (duration: 59.411655ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:50:04.065218Z","caller":"traceutil/trace.go:171","msg":"trace[1643670989] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"2.196238754s","start":"2026-02-01T18:50:01.868971Z","end":"2026-02-01T18:50:04.06521Z","steps":["trace[1643670989] 'process raft request' (duration: 2.195859771s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:04.065286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:01.868967Z","time spent":"2.196270274s","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:50:04.06543Z","caller":"traceutil/trace.go:171","msg":"trace[686208486] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"2.196392925s","start":"2026-02-01T18:50:01.868999Z","end":"2026-02-01T18:50:04.065391Z","steps":["trace[686208486] 'process raft request' (duration: 2.195917781s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:04.065463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:01.868995Z","time spent":"2.196449606s","remote":"127.0.0.1:59200","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-01T18:50:04.06554Z","caller":"traceutil/trace.go:171","msg":"trace[911553154] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"189.685396ms","start":"2026-02-01T18:50:03.875849Z","end":"2026-02-01T18:50:04.065534Z","steps":["trace[911553154] 'process raft request' (duration: 189.089491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:04.065706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042345534s","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-01T18:50:04.065728Z","caller":"traceutil/trace.go:171","msg":"trace[1481183446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"1.042399765s","start":"2026-02-01T18:50:03.023322Z","end":"2026-02-01T18:50:04.065721Z","steps":["trace[1481183446] 'agreement among raft nodes before linearized reading' (duration: 1.042312575s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:04.065746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:03.023304Z","time spent":"1.042437626s","remote":"127.0.0.1:59200","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-01T18:50:04.065902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.471301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:04.065922Z","caller":"traceutil/trace.go:171","msg":"trace[2133549384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"187.509371ms","start":"2026-02-01T18:50:03.878407Z","end":"2026-02-01T18:50:04.065916Z","steps":["trace[2133549384] 'agreement among raft nodes before linearized reading' (duration: 187.476741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:05.491216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.2827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:05.491266Z","caller":"traceutil/trace.go:171","msg":"trace[567419086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"200.365691ms","start":"2026-02-01T18:50:05.290888Z","end":"2026-02-01T18:50:05.491254Z","steps":["trace[567419086] 'range keys from in-memory index tree' (duration: 200.233559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:06.417407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.182109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:50:06.417501Z","caller":"traceutil/trace.go:171","msg":"trace[1455984111] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"213.797147ms","start":"2026-02-01T18:50:06.203686Z","end":"2026-02-01T18:50:06.417483Z","steps":["trace[1455984111] 'process raft request' (duration: 31.480217ms)","trace[1455984111] 'compare' (duration: 182.042288ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:50:06.568345Z","caller":"traceutil/trace.go:171","msg":"trace[260231577] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"128.974231ms","start":"2026-02-01T18:50:06.439347Z","end":"2026-02-01T18:50:06.568321Z","steps":["trace[260231577] 'process raft request' (duration: 124.486559ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:06.572394Z","caller":"traceutil/trace.go:171","msg":"trace[1803419685] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"128.253376ms","start":"2026-02-01T18:50:06.44413Z","end":"2026-02-01T18:50:06.572384Z","steps":["trace[1803419685] 'process raft request' (duration: 128.235476ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:06.572741Z","caller":"traceutil/trace.go:171","msg":"trace[715369447] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"129.888727ms","start":"2026-02-01T18:50:06.442837Z","end":"2026-02-01T18:50:06.572726Z","steps":["trace[715369447] 'process raft request' (duration: 129.509814ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:06.572986Z","caller":"traceutil/trace.go:171","msg":"trace[1757031073] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"131.620049ms","start":"2026-02-01T18:50:06.441357Z","end":"2026-02-01T18:50:06.572977Z","steps":["trace[1757031073] 'process raft request' (duration: 130.951124ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:09.51429Z","caller":"traceutil/trace.go:171","msg":"trace[657087934] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"262.819175ms","start":"2026-02-01T18:50:09.251452Z","end":"2026-02-01T18:50:09.514271Z","steps":["trace[657087934] 'process raft request' (duration: 258.529865ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:24.408388Z","caller":"traceutil/trace.go:171","msg":"trace[1428353097] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"220.237828ms","start":"2026-02-01T18:50:24.188128Z","end":"2026-02-01T18:50:24.408366Z","steps":["trace[1428353097] 'process raft request' (duration: 220.114564ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:24.408934Z","caller":"traceutil/trace.go:171","msg":"trace[135543105] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:789; }","duration":"138.168791ms","start":"2026-02-01T18:50:24.270751Z","end":"2026-02-01T18:50:24.40892Z","steps":["trace[135543105] 'read index received' (duration: 137.426671ms)","trace[135543105] 'applied index is now lower than readState.Index' (duration: 740.82µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:24.409138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.364546ms","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-01T18:50:24.40917Z","caller":"traceutil/trace.go:171","msg":"trace[1454385791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"138.448089ms","start":"2026-02-01T18:50:24.270712Z","end":"2026-02-01T18:50:24.40916Z","steps":["trace[1454385791] 'agreement among raft nodes before linearized reading' (duration: 138.314805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:24.409318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.116653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:24.409344Z","caller":"traceutil/trace.go:171","msg":"trace[890852466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"116.168824ms","start":"2026-02-01T18:50:24.293167Z","end":"2026-02-01T18:50:24.409336Z","steps":["trace[890852466] 'agreement among raft nodes before linearized reading' (duration: 116.131253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:25.032234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.643229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:50:25.032356Z","caller":"traceutil/trace.go:171","msg":"trace[342668277] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:789; }","duration":"562.836252ms","start":"2026-02-01T18:50:24.469505Z","end":"2026-02-01T18:50:25.032341Z","steps":["trace[342668277] 'read index received' (duration: 113.03952ms)","trace[342668277] 'applied index is now lower than readState.Index' (duration: 449.795792ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:50:25.032425Z","caller":"traceutil/trace.go:171","msg":"trace[1508820391] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"617.725231ms","start":"2026-02-01T18:50:24.414688Z","end":"2026-02-01T18:50:25.032414Z","steps":["trace[1508820391] 'process raft request' (duration: 167.847336ms)","trace[1508820391] 'compare' (duration: 449.560427ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:25.032481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:24.414672Z","time spent":"617.786243ms","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:50:25.0326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.091518ms","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-01T18:50:25.032668Z","caller":"traceutil/trace.go:171","msg":"trace[428756323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"563.201211ms","start":"2026-02-01T18:50:24.469453Z","end":"2026-02-01T18:50:25.032654Z","steps":["trace[428756323] 'agreement among raft nodes before linearized reading' (duration: 562.999056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:25.032686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.491837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-01T18:50:25.032697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:24.469434Z","time spent":"563.256403ms","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T18:50:25.032711Z","caller":"traceutil/trace.go:171","msg":"trace[571229725] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"276.559989ms","start":"2026-02-01T18:50:24.756144Z","end":"2026-02-01T18:50:25.032704Z","steps":["trace[571229725] 'agreement among raft nodes before linearized reading' (duration: 276.470346ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:25.03281Z","caller":"traceutil/trace.go:171","msg":"trace[110916505] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"615.870111ms","start":"2026-02-01T18:50:24.416918Z","end":"2026-02-01T18:50:25.032788Z","steps":["trace[110916505] 'process raft request' (duration: 615.384948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:25.032904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:24.416909Z","time spent":"615.919323ms","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:50:25.167816Z","caller":"traceutil/trace.go:171","msg":"trace[645430824] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"127.591566ms","start":"2026-02-01T18:50:25.040201Z","end":"2026-02-01T18:50:25.167792Z","steps":["trace[645430824] 'process raft request' (duration: 116.101338ms)","trace[645430824] 'compare' (duration: 11.421197ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:50:27.178887Z","caller":"traceutil/trace.go:171","msg":"trace[725103568] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"136.14055ms","start":"2026-02-01T18:50:27.042733Z","end":"2026-02-01T18:50:27.178874Z","steps":["trace[725103568] 'read index received' (duration: 136.045098ms)","trace[725103568] 'applied index is now lower than readState.Index' (duration: 93.402µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:50:27.179028Z","caller":"traceutil/trace.go:171","msg":"trace[1101909610] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"137.866586ms","start":"2026-02-01T18:50:27.041156Z","end":"2026-02-01T18:50:27.179022Z","steps":["trace[1101909610] 'process raft request' (duration: 137.62911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:27.179162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.418157ms","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-01T18:50:27.179179Z","caller":"traceutil/trace.go:171","msg":"trace[1100775618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:774; }","duration":"136.454118ms","start":"2026-02-01T18:50:27.042718Z","end":"2026-02-01T18:50:27.179173Z","steps":["trace[1100775618] 'agreement among raft nodes before linearized reading' (duration: 136.390776ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:51:31.993146Z","caller":"traceutil/trace.go:171","msg":"trace[1324884349] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"310.425125ms","start":"2026-02-01T18:51:31.682714Z","end":"2026-02-01T18:51:31.993139Z","steps":["trace[1324884349] 'process raft request' (duration: 310.1098ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:51:31.992931Z","caller":"traceutil/trace.go:171","msg":"trace[220331022] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"251.113326ms","start":"2026-02-01T18:51:31.741802Z","end":"2026-02-01T18:51:31.992916Z","steps":["trace[220331022] 'read index received' (duration: 250.958234ms)","trace[220331022] 'applied index is now lower than readState.Index' (duration: 154.532µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:51:31.993329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:51:31.682698Z","time spent":"310.583767ms","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:51:31.993373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.570242ms","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-01T18:51:31.993787Z","caller":"traceutil/trace.go:171","msg":"trace[439169791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"252.020649ms","start":"2026-02-01T18:51:31.741734Z","end":"2026-02-01T18:51:31.993754Z","steps":["trace[439169791] 'agreement among raft nodes before linearized reading' (duration: 251.492701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:51:31.993412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.955978ms","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-01T18:51:31.993935Z","caller":"traceutil/trace.go:171","msg":"trace[2060804253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"250.481666ms","start":"2026-02-01T18:51:31.743432Z","end":"2026-02-01T18:51:31.993914Z","steps":["trace[2060804253] 'agreement among raft nodes before linearized reading' (duration: 249.954408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:51:31.993469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.510386ms","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-01T18:51:31.993985Z","caller":"traceutil/trace.go:171","msg":"trace[1312069732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"218.054464ms","start":"2026-02-01T18:51:31.775927Z","end":"2026-02-01T18:51:31.993982Z","steps":["trace[1312069732] 'agreement among raft nodes before linearized reading' (duration: 217.514296ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:51:32.14077Z","caller":"traceutil/trace.go:171","msg":"trace[714749794] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"139.934307ms","start":"2026-02-01T18:51:32.000819Z","end":"2026-02-01T18:51:32.140754Z","steps":["trace[714749794] 'process raft request' (duration: 139.897646ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:51:32.14089Z","caller":"traceutil/trace.go:171","msg":"trace[1188031613] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"140.227862ms","start":"2026-02-01T18:51:32.000636Z","end":"2026-02-01T18:51:32.140864Z","steps":["trace[1188031613] 'process raft request' (duration: 83.836933ms)","trace[1188031613] 'compare' (duration: 56.163585ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:51:33.21093Z","caller":"traceutil/trace.go:171","msg":"trace[1329569035] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"336.905152ms","start":"2026-02-01T18:51:32.874008Z","end":"2026-02-01T18:51:33.210914Z","steps":["trace[1329569035] 'process raft request' (duration: 336.805981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:51:33.21103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:51:32.873984Z","time spent":"336.995914ms","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}