{"level":"warn","ts":"2026-02-05T00:40:17.086656Z","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-05T00:40:17.086722Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-05T00:40:17.086781Z","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-05T00:40:17.086793Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-05T00:40:17.086812Z","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-05T00:40:17.087596Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-05T00:40:17.087664Z","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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-05T00:40:17.095321Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.403069ms"} {"level":"info","ts":"2026-02-05T00:40:17.115797Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-05T00:40:17.11585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T00:40:17.11588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-05T00:40:17.115887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T00:40:17.115898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-05T00:40:17.115925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-05T00:40:17.126212Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T00:40:17.138076Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T00:40:17.144902Z","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-05T00:40:17.152698Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T00:40:17.154071Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T00:40:17.154408Z","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-05T00:40:17.158762Z","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-05T00:40:17.158783Z","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-05T00:40:17.161593Z","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-05T00:40:17.161804Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T00:40:17.162121Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T00:40:17.16232Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-05T00:40:17.162335Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-05T00:40:17.1662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-05T00:40:17.166313Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-05T00:40:18.116146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T00:40:18.116198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T00:40:18.116226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-05T00:40:18.116291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-05T00:40:18.116304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-05T00:40:18.116312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-05T00:40:18.116349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-05T00:40:18.1182Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T00:40:18.118685Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:40:18.118804Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T00:40:18.119088Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T00:40:18.120131Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:40:18.120219Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:40:18.120239Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:40:18.124922Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-05T00:40:18.131956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T00:40:18.174374Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T00:40:18.174392Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-05T00:40:21.366706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.577042ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-05T00:40:21.366864Z","caller":"traceutil/trace.go:171","msg":"trace[2010617803] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"110.374077ms","start":"2026-02-05T00:40:21.256478Z","end":"2026-02-05T00:40:21.366852Z","steps":["trace[2010617803] 'process raft request' (duration: 110.304797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:22.78722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.743628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:40:22.788108Z","caller":"traceutil/trace.go:171","msg":"trace[1073898973] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"1.416793456s","start":"2026-02-05T00:40:21.371304Z","end":"2026-02-05T00:40:22.788098Z","steps":["trace[1073898973] 'process raft request' (duration: 418.637697ms)","trace[1073898973] 'compare' (duration: 996.583157ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:40:22.788266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:21.371286Z","time spent":"1.416937747s","remote":"127.0.0.1:45900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T00:40:22.788065Z","caller":"traceutil/trace.go:171","msg":"trace[153845961] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:246; }","duration":"1.392091898s","start":"2026-02-05T00:40:21.395958Z","end":"2026-02-05T00:40:22.78805Z","steps":["trace[153845961] 'read index received' (duration: 393.923359ms)","trace[153845961] 'applied index is now lower than readState.Index' (duration: 998.167479ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:40:22.789929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393969822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:40:22.790085Z","caller":"traceutil/trace.go:171","msg":"trace[1673254285] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:0; response_revision:241; }","duration":"1.394144633s","start":"2026-02-05T00:40:21.395909Z","end":"2026-02-05T00:40:22.790054Z","steps":["trace[1673254285] 'agreement among raft nodes before linearized reading' (duration: 1.39373528s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:22.790222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:21.395868Z","time spent":"1.394323024s","remote":"127.0.0.1:45748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":29,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-05T00:40:23.071366Z","caller":"traceutil/trace.go:171","msg":"trace[1726878407] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:247; }","duration":"281.555112ms","start":"2026-02-05T00:40:22.789788Z","end":"2026-02-05T00:40:23.071343Z","steps":["trace[1726878407] 'read index received' (duration: 278.895332ms)","trace[1726878407] 'applied index is now lower than readState.Index' (duration: 2.65884ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:40:23.071605Z","caller":"traceutil/trace.go:171","msg":"trace[1195840381] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"758.634738ms","start":"2026-02-05T00:40:22.312954Z","end":"2026-02-05T00:40:23.071588Z","steps":["trace[1195840381] 'process raft request' (duration: 755.718956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:23.071706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:22.31293Z","time spent":"758.711158ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T00:40:23.071971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380016032s","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-05T00:40:23.072107Z","caller":"traceutil/trace.go:171","msg":"trace[1930231437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:242; }","duration":"1.380187273s","start":"2026-02-05T00:40:21.691905Z","end":"2026-02-05T00:40:23.072092Z","steps":["trace[1930231437] 'agreement among raft nodes before linearized reading' (duration: 1.379693039s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:23.072236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:21.691902Z","time spent":"1.380321854s","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T00:40:23.073526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.817063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T00:40:23.073556Z","caller":"traceutil/trace.go:171","msg":"trace[1383160227] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:243; }","duration":"256.869853ms","start":"2026-02-05T00:40:22.816679Z","end":"2026-02-05T00:40:23.073549Z","steps":["trace[1383160227] 'agreement among raft nodes before linearized reading' (duration: 256.808653ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:40:23.073886Z","caller":"traceutil/trace.go:171","msg":"trace[2123144713] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"254.745167ms","start":"2026-02-05T00:40:22.819133Z","end":"2026-02-05T00:40:23.073878Z","steps":["trace[2123144713] 'process raft request' (duration: 254.315094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:23.072776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38106682s","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-05T00:40:23.0743Z","caller":"traceutil/trace.go:171","msg":"trace[1260979675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:242; }","duration":"1.382620751s","start":"2026-02-05T00:40:21.69167Z","end":"2026-02-05T00:40:23.074291Z","steps":["trace[1260979675] 'agreement among raft nodes before linearized reading' (duration: 1.381063379s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:23.074444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:21.691656Z","time spent":"1.382778332s","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T00:40:48.231557Z","caller":"traceutil/trace.go:171","msg":"trace[1351203536] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"333.632386ms","start":"2026-02-05T00:40:47.897907Z","end":"2026-02-05T00:40:48.23154Z","steps":["trace[1351203536] 'read index received' (duration: 333.498755ms)","trace[1351203536] 'applied index is now lower than readState.Index' (duration: 132.941µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:40:48.231678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.758807ms","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-05T00:40:48.231701Z","caller":"traceutil/trace.go:171","msg":"trace[1373059937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"333.835368ms","start":"2026-02-05T00:40:47.897858Z","end":"2026-02-05T00:40:48.231693Z","steps":["trace[1373059937] 'agreement among raft nodes before linearized reading' (duration: 333.754307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:48.231725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:47.897834Z","time spent":"333.885508ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T00:40:48.231974Z","caller":"traceutil/trace.go:171","msg":"trace[44739971] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"623.072562ms","start":"2026-02-05T00:40:47.608895Z","end":"2026-02-05T00:40:48.231967Z","steps":["trace[44739971] 'process raft request' (duration: 622.527908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:48.232157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:47.608879Z","time spent":"623.112992ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:40:48.609058Z","caller":"traceutil/trace.go:171","msg":"trace[1170377000] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"205.087276ms","start":"2026-02-05T00:40:48.403944Z","end":"2026-02-05T00:40:48.609032Z","steps":["trace[1170377000] 'process raft request' (duration: 198.193373ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:40:48.609854Z","caller":"traceutil/trace.go:171","msg":"trace[328887313] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"203.736397ms","start":"2026-02-05T00:40:48.406083Z","end":"2026-02-05T00:40:48.609819Z","steps":["trace[328887313] 'process raft request' (duration: 203.529636ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:40:52.826936Z","caller":"traceutil/trace.go:171","msg":"trace[1844502545] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"181.044603ms","start":"2026-02-05T00:40:52.64587Z","end":"2026-02-05T00:40:52.826915Z","steps":["trace[1844502545] 'process raft request' (duration: 180.913383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:53.786216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528961516396692,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T00:40:53.986477Z","caller":"traceutil/trace.go:171","msg":"trace[998646214] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"700.300303ms","start":"2026-02-05T00:40:53.286153Z","end":"2026-02-05T00:40:53.986453Z","steps":["trace[998646214] 'read index received' (duration: 700.068051ms)","trace[998646214] 'applied index is now lower than readState.Index' (duration: 231.162µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:40:53.987235Z","caller":"traceutil/trace.go:171","msg":"trace[1663107421] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"734.967605ms","start":"2026-02-05T00:40:53.252253Z","end":"2026-02-05T00:40:53.987221Z","steps":["trace[1663107421] 'process raft request' (duration: 734.03374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:53.987555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:53.252232Z","time spent":"735.249207ms","remote":"127.0.0.1:45632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T00:40:53.98811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.942433ms","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-05T00:40:53.988407Z","caller":"traceutil/trace.go:171","msg":"trace[1482064996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"702.390736ms","start":"2026-02-05T00:40:53.285995Z","end":"2026-02-05T00:40:53.988386Z","steps":["trace[1482064996] 'agreement among raft nodes before linearized reading' (duration: 701.957003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:53.98868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:53.285963Z","time spent":"702.699447ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T00:40:53.98892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.568532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:40:53.988963Z","caller":"traceutil/trace.go:171","msg":"trace[1945175798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"605.635413ms","start":"2026-02-05T00:40:53.383312Z","end":"2026-02-05T00:40:53.988947Z","steps":["trace[1945175798] 'agreement among raft nodes before linearized reading' (duration: 605.579613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:53.988989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:53.383294Z","time spent":"605.689853ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T00:40:53.988467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.426709ms","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-05T00:40:53.989316Z","caller":"traceutil/trace.go:171","msg":"trace[959214368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"692.316324ms","start":"2026-02-05T00:40:53.29699Z","end":"2026-02-05T00:40:53.989306Z","steps":["trace[959214368] 'agreement among raft nodes before linearized reading' (duration: 691.356798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:53.989354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:53.29698Z","time spent":"692.366855ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T00:40:53.988832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.643883ms","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-05T00:40:53.989455Z","caller":"traceutil/trace.go:171","msg":"trace[1243742512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:556; }","duration":"270.286657ms","start":"2026-02-05T00:40:53.719161Z","end":"2026-02-05T00:40:53.989448Z","steps":["trace[1243742512] 'agreement among raft nodes before linearized reading' (duration: 269.649493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:53.988856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.382528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:40:53.989582Z","caller":"traceutil/trace.go:171","msg":"trace[2107888599] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"346.137312ms","start":"2026-02-05T00:40:53.643434Z","end":"2026-02-05T00:40:53.989571Z","steps":["trace[2107888599] 'agreement among raft nodes before linearized reading' (duration: 345.408098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:53.989612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:53.643416Z","time spent":"346.186662ms","remote":"127.0.0.1:45632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T00:40:53.98888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.911057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:40:53.989735Z","caller":"traceutil/trace.go:171","msg":"trace[1538201079] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"546.785753ms","start":"2026-02-05T00:40:53.442941Z","end":"2026-02-05T00:40:53.989726Z","steps":["trace[1538201079] 'agreement among raft nodes before linearized reading' (duration: 545.925548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:53.989757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:40:53.442927Z","time spent":"546.823803ms","remote":"127.0.0.1:45632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T00:40:53.988795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.023821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-05T00:40:53.993503Z","caller":"traceutil/trace.go:171","msg":"trace[570058352] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:556; }","duration":"146.747ms","start":"2026-02-05T00:40:53.846723Z","end":"2026-02-05T00:40:53.99347Z","steps":["trace[570058352] 'agreement among raft nodes before linearized reading' (duration: 142.043861ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:40:58.580521Z","caller":"traceutil/trace.go:171","msg":"trace[1808702363] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"195.065083ms","start":"2026-02-05T00:40:58.385431Z","end":"2026-02-05T00:40:58.580496Z","steps":["trace[1808702363] 'process raft request' (duration: 194.924438ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:40:58.590909Z","caller":"traceutil/trace.go:171","msg":"trace[137351859] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"205.188486ms","start":"2026-02-05T00:40:58.385692Z","end":"2026-02-05T00:40:58.590881Z","steps":["trace[137351859] 'read index received' (duration: 195.730968ms)","trace[137351859] 'applied index is now lower than readState.Index' (duration: 9.456738ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:40:58.592701Z","caller":"traceutil/trace.go:171","msg":"trace[685880344] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"207.080075ms","start":"2026-02-05T00:40:58.385588Z","end":"2026-02-05T00:40:58.592668Z","steps":["trace[685880344] 'process raft request' (duration: 205.211056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:58.592854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.129717ms","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-05T00:40:58.592924Z","caller":"traceutil/trace.go:171","msg":"trace[316475840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"207.227301ms","start":"2026-02-05T00:40:58.385687Z","end":"2026-02-05T00:40:58.592914Z","steps":["trace[316475840] 'agreement among raft nodes before linearized reading' (duration: 206.627809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:40:58.593253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.349505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:40:58.593325Z","caller":"traceutil/trace.go:171","msg":"trace[54467261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"207.433269ms","start":"2026-02-05T00:40:58.385886Z","end":"2026-02-05T00:40:58.593319Z","steps":["trace[54467261] 'agreement among raft nodes before linearized reading' (duration: 207.350546ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:41:01.680296Z","caller":"traceutil/trace.go:171","msg":"trace[1681150647] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"297.800088ms","start":"2026-02-05T00:41:01.382478Z","end":"2026-02-05T00:41:01.680278Z","steps":["trace[1681150647] 'read index received' (duration: 297.670493ms)","trace[1681150647] 'applied index is now lower than readState.Index' (duration: 128.605µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:41:01.68039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.900172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:41:01.680411Z","caller":"traceutil/trace.go:171","msg":"trace[1092255991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"297.971124ms","start":"2026-02-05T00:41:01.382433Z","end":"2026-02-05T00:41:01.680404Z","steps":["trace[1092255991] 'agreement among raft nodes before linearized reading' (duration: 297.911612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:01.680588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:41:01.224363Z","time spent":"456.221861ms","remote":"127.0.0.1:45604","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T00:41:02.018457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.556975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dx76m\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-05T00:41:02.018533Z","caller":"traceutil/trace.go:171","msg":"trace[1031892320] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dx76m; range_end:; response_count:1; response_revision:614; }","duration":"206.676559ms","start":"2026-02-05T00:41:01.811838Z","end":"2026-02-05T00:41:02.018515Z","steps":["trace[1031892320] 'range keys from in-memory index tree' (duration: 206.467062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:02.018617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.418015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-05T00:41:02.018674Z","caller":"traceutil/trace.go:171","msg":"trace[1564850948] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:614; }","duration":"309.498347ms","start":"2026-02-05T00:41:01.709161Z","end":"2026-02-05T00:41:02.018659Z","steps":["trace[1564850948] 'range keys from in-memory index tree' (duration: 309.308641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:02.018704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:41:01.709154Z","time spent":"309.542629ms","remote":"127.0.0.1:45846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-05T00:41:02.737114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.804663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-05T00:41:02.73718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.766216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T00:41:02.737213Z","caller":"traceutil/trace.go:171","msg":"trace[123344936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"139.913267ms","start":"2026-02-05T00:41:02.597256Z","end":"2026-02-05T00:41:02.73717Z","steps":["trace[123344936] 'range keys from in-memory index tree' (duration: 139.597056ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:41:02.737244Z","caller":"traceutil/trace.go:171","msg":"trace[1283423903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"134.85231ms","start":"2026-02-05T00:41:02.602379Z","end":"2026-02-05T00:41:02.737232Z","steps":["trace[1283423903] 'range keys from in-memory index tree' (duration: 134.679644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:02.7374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.091033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-05T00:41:02.737398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.541988ms","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-05T00:41:02.737414Z","caller":"traceutil/trace.go:171","msg":"trace[669394672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"124.117563ms","start":"2026-02-05T00:41:02.613292Z","end":"2026-02-05T00:41:02.73741Z","steps":["trace[669394672] 'range keys from in-memory index tree' (duration: 124.041091ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:41:02.737428Z","caller":"traceutil/trace.go:171","msg":"trace[666666419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:615; }","duration":"481.59792ms","start":"2026-02-05T00:41:02.255822Z","end":"2026-02-05T00:41:02.73742Z","steps":["trace[666666419] 'range keys from in-memory index tree' (duration: 481.451455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:02.737458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:41:02.255791Z","time spent":"481.657662ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T00:41:02.737574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.275452ms","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-05T00:41:02.737589Z","caller":"traceutil/trace.go:171","msg":"trace[728047781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"373.327063ms","start":"2026-02-05T00:41:02.364257Z","end":"2026-02-05T00:41:02.737584Z","steps":["trace[728047781] 'range keys from in-memory index tree' (duration: 373.156657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:02.737606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:41:02.364236Z","time spent":"373.365435ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T00:41:02.960523Z","caller":"traceutil/trace.go:171","msg":"trace[1521769047] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"217.503348ms","start":"2026-02-05T00:41:02.743001Z","end":"2026-02-05T00:41:02.960504Z","steps":["trace[1521769047] 'process raft request' (duration: 217.389264ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:41:03.109808Z","caller":"traceutil/trace.go:171","msg":"trace[1575708211] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"366.543033ms","start":"2026-02-05T00:41:02.743239Z","end":"2026-02-05T00:41:03.109782Z","steps":["trace[1575708211] 'process raft request' (duration: 318.84717ms)","trace[1575708211] 'compare' (duration: 47.101792ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:41:03.109953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:41:02.743232Z","time spent":"366.645866ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:41:03.109979Z","caller":"traceutil/trace.go:171","msg":"trace[1207960766] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"365.515786ms","start":"2026-02-05T00:41:02.74445Z","end":"2026-02-05T00:41:03.109965Z","steps":["trace[1207960766] 'process raft request' (duration: 365.283288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:03.110073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:41:02.744443Z","time spent":"365.573958ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:41:03.110196Z","caller":"traceutil/trace.go:171","msg":"trace[1536288032] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"365.707393ms","start":"2026-02-05T00:41:02.744483Z","end":"2026-02-05T00:41:03.11019Z","steps":["trace[1536288032] 'process raft request' (duration: 365.424723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:03.110219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:41:02.744479Z","time spent":"365.727013ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:41:03.110313Z","caller":"traceutil/trace.go:171","msg":"trace[597244559] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"363.804936ms","start":"2026-02-05T00:41:02.74647Z","end":"2026-02-05T00:41:03.110275Z","steps":["trace[597244559] 'process raft request' (duration: 363.701392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:03.110338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:41:02.746463Z","time spent":"363.861089ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:41:05.151166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770511429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:41:05.15121Z","caller":"traceutil/trace.go:171","msg":"trace[1046812106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"1.770584262s","start":"2026-02-05T00:41:03.380616Z","end":"2026-02-05T00:41:05.1512Z","steps":["trace[1046812106] 'range keys from in-memory index tree' (duration: 1.770449677s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:41:05.151227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:41:03.3806Z","time spent":"1.770622313s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T00:41:05.151791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.21142ms","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-05T00:41:05.151809Z","caller":"traceutil/trace.go:171","msg":"trace[245510748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"188.22842ms","start":"2026-02-05T00:41:04.963575Z","end":"2026-02-05T00:41:05.151804Z","steps":["trace[245510748] 'range keys from in-memory index tree' (duration: 188.127727ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:41:05.333755Z","caller":"traceutil/trace.go:171","msg":"trace[347160395] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"174.289541ms","start":"2026-02-05T00:41:05.15943Z","end":"2026-02-05T00:41:05.333719Z","steps":["trace[347160395] 'process raft request' (duration: 174.114095ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:41:05.333993Z","caller":"traceutil/trace.go:171","msg":"trace[166617111] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"171.708555ms","start":"2026-02-05T00:41:05.162261Z","end":"2026-02-05T00:41:05.33397Z","steps":["trace[166617111] 'process raft request' (duration: 171.400454ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:41:05.334131Z","caller":"traceutil/trace.go:171","msg":"trace[1058799440] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:643; }","duration":"168.669681ms","start":"2026-02-05T00:41:05.165452Z","end":"2026-02-05T00:41:05.334122Z","steps":["trace[1058799440] 'read index received' (duration: 69.929967ms)","trace[1058799440] 'applied index is now lower than readState.Index' (duration: 98.738914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:41:05.33421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.745564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:41:05.336291Z","caller":"traceutil/trace.go:171","msg":"trace[539579764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"170.857425ms","start":"2026-02-05T00:41:05.165425Z","end":"2026-02-05T00:41:05.336282Z","steps":["trace[539579764] 'agreement among raft nodes before linearized reading' (duration: 168.729203ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:41:05.334312Z","caller":"traceutil/trace.go:171","msg":"trace[1165031200] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"168.174635ms","start":"2026-02-05T00:41:05.166127Z","end":"2026-02-05T00:41:05.334301Z","steps":["trace[1165031200] 'process raft request' (duration: 167.74861ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:41:05.334379Z","caller":"traceutil/trace.go:171","msg":"trace[1852757341] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"174.968734ms","start":"2026-02-05T00:41:05.159402Z","end":"2026-02-05T00:41:05.334371Z","steps":["trace[1852757341] 'process raft request' (duration: 173.091531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:42:22.091169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.641989ms","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-05T00:42:22.092119Z","caller":"traceutil/trace.go:171","msg":"trace[1786998221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"135.640375ms","start":"2026-02-05T00:42:21.956455Z","end":"2026-02-05T00:42:22.092095Z","steps":["trace[1786998221] 'range keys from in-memory index tree' (duration: 134.418915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:42:22.091891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.842168ms","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-05T00:42:22.092735Z","caller":"traceutil/trace.go:171","msg":"trace[159680138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"122.709513ms","start":"2026-02-05T00:42:21.97001Z","end":"2026-02-05T00:42:22.09272Z","steps":["trace[159680138] 'range keys from in-memory index tree' (duration: 121.606895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:42:22.092065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.7045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a692b76-7d4d-47a1-b584-6688e24de1ae\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-05T00:42:22.093377Z","caller":"traceutil/trace.go:171","msg":"trace[1605400917] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a692b76-7d4d-47a1-b584-6688e24de1ae; range_end:; response_count:1; response_revision:953; }","duration":"121.99413ms","start":"2026-02-05T00:42:21.971309Z","end":"2026-02-05T00:42:22.093304Z","steps":["trace[1605400917] 'range keys from in-memory index tree' (duration: 120.527297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:42:22.094707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.952682ms","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-05T00:42:22.094878Z","caller":"traceutil/trace.go:171","msg":"trace[1521140417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"152.234407ms","start":"2026-02-05T00:42:21.942627Z","end":"2026-02-05T00:42:22.094861Z","steps":["trace[1521140417] 'range keys from in-memory index tree' (duration: 151.7767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:42:28.543541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.128871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:42:28.543603Z","caller":"traceutil/trace.go:171","msg":"trace[1874906016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"160.227973ms","start":"2026-02-05T00:42:28.383358Z","end":"2026-02-05T00:42:28.543585Z","steps":["trace[1874906016] 'range keys from in-memory index tree' (duration: 160.05655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:42:36.202592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.922197ms","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-05T00:42:36.20265Z","caller":"traceutil/trace.go:171","msg":"trace[375407433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1014; }","duration":"307.015679ms","start":"2026-02-05T00:42:35.89562Z","end":"2026-02-05T00:42:36.202635Z","steps":["trace[375407433] 'range keys from in-memory index tree' (duration: 306.598883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:42:36.202718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:42:35.895599Z","time spent":"307.11203ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T00:42:37.150813Z","caller":"traceutil/trace.go:171","msg":"trace[1175885164] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"904.699691ms","start":"2026-02-05T00:42:36.246093Z","end":"2026-02-05T00:42:37.150792Z","steps":["trace[1175885164] 'process raft request' (duration: 849.093942ms)","trace[1175885164] 'compare' (duration: 54.543573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:42:37.150944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:42:36.246074Z","time spent":"904.794862ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:42:37.151019Z","caller":"traceutil/trace.go:171","msg":"trace[356237623] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1061; }","duration":"900.714062ms","start":"2026-02-05T00:42:36.250281Z","end":"2026-02-05T00:42:37.150995Z","steps":["trace[356237623] 'read index received' (duration: 20.483633ms)","trace[356237623] 'applied index is now lower than readState.Index' (duration: 880.228589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:42:37.163372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.072984ms","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-05T00:42:37.163415Z","caller":"traceutil/trace.go:171","msg":"trace[1957124134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"913.152254ms","start":"2026-02-05T00:42:36.250251Z","end":"2026-02-05T00:42:37.163404Z","steps":["trace[1957124134] 'agreement among raft nodes before linearized reading' (duration: 900.841343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:42:37.163438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:42:36.250245Z","time spent":"913.187625ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}