{"level":"warn","ts":"2026-01-31T16:12:01.862107Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T16:12:01.862195Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218: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.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218: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-01-31T16:12:01.862254Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T16:12:01.862263Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-01-31T16:12:01.862283Z","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-01-31T16:12:01.862955Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-01-31T16:12:01.865226Z","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.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218: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-01-31T16:12:01.869487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.041429ms"} {"level":"info","ts":"2026-01-31T16:12:01.881884Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-01-31T16:12:01.882027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T16:12:01.8821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-01-31T16:12:01.884274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T16:12:01.884293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-01-31T16:12:01.88434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-01-31T16:12:01.892395Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T16:12:01.901672Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T16:12:01.904634Z","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-01-31T16:12:01.907714Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T16:12:01.910322Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T16:12:01.9116Z","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-01-31T16:12:01.911812Z","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-01-31T16:12:01.911875Z","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-01-31T16:12:01.912498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-01-31T16:12:01.912872Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-01-31T16:12:01.916651Z","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-01-31T16:12:01.916845Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T16:12:01.916882Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T16:12:01.916992Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-01-31T16:12:01.917017Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-01-31T16:12:02.484743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T16:12:02.4848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T16:12:02.48482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-01-31T16:12:02.484832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-01-31T16:12:02.484841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-01-31T16:12:02.484853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-01-31T16:12:02.48486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-01-31T16:12:02.491121Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T16:12:02.491389Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T16:12:02.492649Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T16:12:02.492918Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T16:12:02.494342Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T16:12:02.49436Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T16:12:02.494474Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-31T16:12:02.49583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-01-31T16:12:02.497501Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T16:12:02.497556Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T16:12:02.49757Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T16:12:28.33629Z","caller":"traceutil/trace.go:171","msg":"trace[1444158607] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"562.769915ms","start":"2026-01-31T16:12:27.773497Z","end":"2026-01-31T16:12:28.336267Z","steps":["trace[1444158607] 'process raft request' (duration: 562.652542ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:28.33761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:12:27.773469Z","time spent":"563.447082ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T16:12:28.379258Z","caller":"traceutil/trace.go:171","msg":"trace[1199333998] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"306.061353ms","start":"2026-01-31T16:12:28.072783Z","end":"2026-01-31T16:12:28.378845Z","steps":["trace[1199333998] 'read index received' (duration: 265.085093ms)","trace[1199333998] 'applied index is now lower than readState.Index' (duration: 40.97541ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:12:28.379206Z","caller":"traceutil/trace.go:171","msg":"trace[1397372695] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"372.793393ms","start":"2026-01-31T16:12:28.006395Z","end":"2026-01-31T16:12:28.379188Z","steps":["trace[1397372695] 'process raft request' (duration: 372.325541ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:28.380385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:12:28.00638Z","time spent":"373.949653ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T16:12:28.380495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.678244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:12:28.380737Z","caller":"traceutil/trace.go:171","msg":"trace[639887472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"307.969092ms","start":"2026-01-31T16:12:28.072744Z","end":"2026-01-31T16:12:28.380713Z","steps":["trace[639887472] 'agreement among raft nodes before linearized reading' (duration: 307.129931ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:28.380816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:12:28.072732Z","time spent":"308.073804ms","remote":"127.0.0.1:41786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T16:12:28.380237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.454455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-01-31T16:12:28.381128Z","caller":"traceutil/trace.go:171","msg":"trace[1227372821] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:470; }","duration":"259.40609ms","start":"2026-01-31T16:12:28.121708Z","end":"2026-01-31T16:12:28.381114Z","steps":["trace[1227372821] 'agreement among raft nodes before linearized reading' (duration: 258.444466ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:28.779542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.32568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-01-31T16:12:28.779611Z","caller":"traceutil/trace.go:171","msg":"trace[513065437] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:470; }","duration":"250.468624ms","start":"2026-01-31T16:12:28.529126Z","end":"2026-01-31T16:12:28.779594Z","steps":["trace[513065437] 'range keys from in-memory index tree' (duration: 250.234268ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:28.779885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.316668ms","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-01-31T16:12:28.779917Z","caller":"traceutil/trace.go:171","msg":"trace[1426818194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"209.37468ms","start":"2026-01-31T16:12:28.570533Z","end":"2026-01-31T16:12:28.779908Z","steps":["trace[1426818194] 'range keys from in-memory index tree' (duration: 209.244076ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:28.780113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.38362ms","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-01-31T16:12:28.780141Z","caller":"traceutil/trace.go:171","msg":"trace[1209041031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:470; }","duration":"205.433121ms","start":"2026-01-31T16:12:28.574699Z","end":"2026-01-31T16:12:28.780132Z","steps":["trace[1209041031] 'range keys from in-memory index tree' (duration: 205.332519ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:28.780318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.152054ms","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-01-31T16:12:28.780343Z","caller":"traceutil/trace.go:171","msg":"trace[1897602578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"203.203865ms","start":"2026-01-31T16:12:28.577132Z","end":"2026-01-31T16:12:28.780336Z","steps":["trace[1897602578] 'range keys from in-memory index tree' (duration: 203.070302ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:12:31.711744Z","caller":"traceutil/trace.go:171","msg":"trace[1770424160] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"131.619942ms","start":"2026-01-31T16:12:31.580107Z","end":"2026-01-31T16:12:31.711727Z","steps":["trace[1770424160] 'process raft request' (duration: 131.491459ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:12:38.567026Z","caller":"traceutil/trace.go:171","msg":"trace[1240529550] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"193.952972ms","start":"2026-01-31T16:12:38.373051Z","end":"2026-01-31T16:12:38.567004Z","steps":["trace[1240529550] 'process raft request' (duration: 193.749627ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:12:38.887567Z","caller":"traceutil/trace.go:171","msg":"trace[1180560201] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"132.912676ms","start":"2026-01-31T16:12:38.754595Z","end":"2026-01-31T16:12:38.887508Z","steps":["trace[1180560201] 'read index received' (duration: 132.587237ms)","trace[1180560201] 'applied index is now lower than readState.Index' (duration: 324.759µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:12:38.887657Z","caller":"traceutil/trace.go:171","msg":"trace[1147394066] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"466.837201ms","start":"2026-01-31T16:12:38.420814Z","end":"2026-01-31T16:12:38.887651Z","steps":["trace[1147394066] 'process raft request' (duration: 466.406999ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:38.887731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:12:38.420798Z","time spent":"466.876451ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T16:12:38.887984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.380768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m26nh\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-31T16:12:38.888051Z","caller":"traceutil/trace.go:171","msg":"trace[1916774422] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m26nh; range_end:; response_count:1; response_revision:531; }","duration":"133.47657ms","start":"2026-01-31T16:12:38.754563Z","end":"2026-01-31T16:12:38.888039Z","steps":["trace[1916774422] 'agreement among raft nodes before linearized reading' (duration: 133.352877ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:12:41.126772Z","caller":"traceutil/trace.go:171","msg":"trace[1684718544] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"196.253439ms","start":"2026-01-31T16:12:40.930502Z","end":"2026-01-31T16:12:41.126756Z","steps":["trace[1684718544] 'process raft request' (duration: 196.158717ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:12:41.126959Z","caller":"traceutil/trace.go:171","msg":"trace[1361206486] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:568; }","duration":"191.443048ms","start":"2026-01-31T16:12:40.935488Z","end":"2026-01-31T16:12:41.126931Z","steps":["trace[1361206486] 'read index received' (duration: 191.324045ms)","trace[1361206486] 'applied index is now lower than readState.Index' (duration: 117.553µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:12:41.12715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.654183ms","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-01-31T16:12:41.127184Z","caller":"traceutil/trace.go:171","msg":"trace[1835001708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"191.708264ms","start":"2026-01-31T16:12:40.935466Z","end":"2026-01-31T16:12:41.127174Z","steps":["trace[1835001708] 'agreement among raft nodes before linearized reading' (duration: 191.568541ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:41.198846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.922654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:12:41.198999Z","caller":"traceutil/trace.go:171","msg":"trace[589154320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"125.195291ms","start":"2026-01-31T16:12:41.07379Z","end":"2026-01-31T16:12:41.198985Z","steps":["trace[589154320] 'agreement among raft nodes before linearized reading' (duration: 124.909504ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:41.200014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.352433ms","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-01-31T16:12:41.200077Z","caller":"traceutil/trace.go:171","msg":"trace[1207029982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"200.469936ms","start":"2026-01-31T16:12:40.9996Z","end":"2026-01-31T16:12:41.20007Z","steps":["trace[1207029982] 'agreement among raft nodes before linearized reading' (duration: 199.114732ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:41.200276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.774224ms","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-01-31T16:12:41.200755Z","caller":"traceutil/trace.go:171","msg":"trace[1472343438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"204.883126ms","start":"2026-01-31T16:12:40.995452Z","end":"2026-01-31T16:12:41.200335Z","steps":["trace[1472343438] 'agreement among raft nodes before linearized reading' (duration: 203.268226ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:12:58.780525Z","caller":"traceutil/trace.go:171","msg":"trace[1677305341] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"137.572413ms","start":"2026-01-31T16:12:58.642933Z","end":"2026-01-31T16:12:58.780506Z","steps":["trace[1677305341] 'process raft request' (duration: 137.47609ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:12:59.685769Z","caller":"traceutil/trace.go:171","msg":"trace[1530777500] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"357.395826ms","start":"2026-01-31T16:12:59.328355Z","end":"2026-01-31T16:12:59.685751Z","steps":["trace[1530777500] 'read index received' (duration: 357.273663ms)","trace[1530777500] 'applied index is now lower than readState.Index' (duration: 121.413µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:12:59.685858Z","caller":"traceutil/trace.go:171","msg":"trace[1815144124] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"578.934841ms","start":"2026-01-31T16:12:59.106917Z","end":"2026-01-31T16:12:59.685852Z","steps":["trace[1815144124] 'process raft request' (duration: 578.724706ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:59.68595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:12:59.106901Z","time spent":"578.975723ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T16:12:59.68607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.311103ms","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-01-31T16:12:59.686126Z","caller":"traceutil/trace.go:171","msg":"trace[1337216356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"355.399716ms","start":"2026-01-31T16:12:59.330712Z","end":"2026-01-31T16:12:59.686112Z","steps":["trace[1337216356] 'agreement among raft nodes before linearized reading' (duration: 355.260282ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:59.686154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:12:59.330697Z","time spent":"355.450837ms","remote":"127.0.0.1:42036","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-01-31T16:12:59.686272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.582257ms","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-01-31T16:12:59.686297Z","caller":"traceutil/trace.go:171","msg":"trace[1890480379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:717; }","duration":"335.607407ms","start":"2026-01-31T16:12:59.350683Z","end":"2026-01-31T16:12:59.68629Z","steps":["trace[1890480379] 'agreement among raft nodes before linearized reading' (duration: 335.521975ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:59.686306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.745381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-01-31T16:12:59.686317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:12:59.350663Z","time spent":"335.648988ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-31T16:12:59.686328Z","caller":"traceutil/trace.go:171","msg":"trace[1432830227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"341.835524ms","start":"2026-01-31T16:12:59.344485Z","end":"2026-01-31T16:12:59.686321Z","steps":["trace[1432830227] 'agreement among raft nodes before linearized reading' (duration: 341.782723ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:59.686347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:12:59.344474Z","time spent":"341.869624ms","remote":"127.0.0.1:42036","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-01-31T16:12:59.686425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.074702ms","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-01-31T16:12:59.68644Z","caller":"traceutil/trace.go:171","msg":"trace[655266148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:717; }","duration":"358.112464ms","start":"2026-01-31T16:12:59.328323Z","end":"2026-01-31T16:12:59.686435Z","steps":["trace[655266148] 'agreement among raft nodes before linearized reading' (duration: 358.069823ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:12:59.686455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:12:59.328309Z","time spent":"358.143424ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-31T16:12:59.793536Z","caller":"traceutil/trace.go:171","msg":"trace[881556075] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"102.038018ms","start":"2026-01-31T16:12:59.691477Z","end":"2026-01-31T16:12:59.793515Z","steps":["trace[881556075] 'process raft request' (duration: 84.875936ms)","trace[881556075] 'compare' (duration: 16.641369ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:12:59.794009Z","caller":"traceutil/trace.go:171","msg":"trace[1580143305] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"101.401913ms","start":"2026-01-31T16:12:59.692595Z","end":"2026-01-31T16:12:59.793997Z","steps":["trace[1580143305] 'process raft request' (duration: 100.467889ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:16.311588Z","caller":"traceutil/trace.go:171","msg":"trace[752155527] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"101.999788ms","start":"2026-01-31T16:13:16.209565Z","end":"2026-01-31T16:13:16.311565Z","steps":["trace[752155527] 'process raft request' (duration: 101.845475ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:19.210983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.368208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:13:19.211336Z","caller":"traceutil/trace.go:171","msg":"trace[62514933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"137.917641ms","start":"2026-01-31T16:13:19.073397Z","end":"2026-01-31T16:13:19.211315Z","steps":["trace[62514933] 'range keys from in-memory index tree' (duration: 137.312256ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:20.082226Z","caller":"traceutil/trace.go:171","msg":"trace[335711330] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"209.303679ms","start":"2026-01-31T16:13:19.872903Z","end":"2026-01-31T16:13:20.082207Z","steps":["trace[335711330] 'read index received' (duration: 157.608267ms)","trace[335711330] 'applied index is now lower than readState.Index' (duration: 51.690582ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:13:20.082357Z","caller":"traceutil/trace.go:171","msg":"trace[1640673985] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"215.478913ms","start":"2026-01-31T16:13:19.866839Z","end":"2026-01-31T16:13:20.082318Z","steps":["trace[1640673985] 'process raft request' (duration: 163.68991ms)","trace[1640673985] 'compare' (duration: 51.474356ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T16:13:20.08274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.830411ms","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-01-31T16:13:20.082769Z","caller":"traceutil/trace.go:171","msg":"trace[872292399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"209.917194ms","start":"2026-01-31T16:13:19.872845Z","end":"2026-01-31T16:13:20.082762Z","steps":["trace[872292399] 'agreement among raft nodes before linearized reading' (duration: 209.797441ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:20.083083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.306358ms","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-01-31T16:13:20.08311Z","caller":"traceutil/trace.go:171","msg":"trace[83314327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"209.3739ms","start":"2026-01-31T16:13:19.87373Z","end":"2026-01-31T16:13:20.083104Z","steps":["trace[83314327] 'agreement among raft nodes before linearized reading' (duration: 209.303118ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:20.21312Z","caller":"traceutil/trace.go:171","msg":"trace[1120211194] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"125.439696ms","start":"2026-01-31T16:13:20.087661Z","end":"2026-01-31T16:13:20.213101Z","steps":["trace[1120211194] 'process raft request' (duration: 124.324619ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:20.226573Z","caller":"traceutil/trace.go:171","msg":"trace[2024710139] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"118.847811ms","start":"2026-01-31T16:13:20.107711Z","end":"2026-01-31T16:13:20.226559Z","steps":["trace[2024710139] 'process raft request' (duration: 118.813731ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:20.226876Z","caller":"traceutil/trace.go:171","msg":"trace[96332472] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"137.251384ms","start":"2026-01-31T16:13:20.089617Z","end":"2026-01-31T16:13:20.226868Z","steps":["trace[96332472] 'process raft request' (duration: 136.844244ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:32.051675Z","caller":"traceutil/trace.go:171","msg":"trace[1256727420] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"140.888317ms","start":"2026-01-31T16:13:31.910766Z","end":"2026-01-31T16:13:32.051654Z","steps":["trace[1256727420] 'process raft request' (duration: 140.716583ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:32.498846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.599955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-31T16:13:32.498901Z","caller":"traceutil/trace.go:171","msg":"trace[392971114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:868; }","duration":"221.679507ms","start":"2026-01-31T16:13:32.277211Z","end":"2026-01-31T16:13:32.49889Z","steps":["trace[392971114] 'range keys from in-memory index tree' (duration: 221.502553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:32.499082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.373836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:13:32.499094Z","caller":"traceutil/trace.go:171","msg":"trace[199919542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:868; }","duration":"425.406997ms","start":"2026-01-31T16:13:32.073683Z","end":"2026-01-31T16:13:32.49909Z","steps":["trace[199919542] 'range keys from in-memory index tree' (duration: 425.287194ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:32.499106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:32.073673Z","time spent":"425.429898ms","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T16:13:32.499274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.171511ms","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-01-31T16:13:32.49929Z","caller":"traceutil/trace.go:171","msg":"trace[1030818617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:868; }","duration":"343.208941ms","start":"2026-01-31T16:13:32.156074Z","end":"2026-01-31T16:13:32.499283Z","steps":["trace[1030818617] 'range keys from in-memory index tree' (duration: 343.065328ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:32.499301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:13:32.156061Z","time spent":"343.237702ms","remote":"127.0.0.1:42036","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-01-31T16:13:32.499411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.460785ms","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-01-31T16:13:32.499423Z","caller":"traceutil/trace.go:171","msg":"trace[1905161148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"231.499586ms","start":"2026-01-31T16:13:32.26792Z","end":"2026-01-31T16:13:32.499419Z","steps":["trace[1905161148] 'range keys from in-memory index tree' (duration: 231.398563ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:41.139257Z","caller":"traceutil/trace.go:171","msg":"trace[1773089931] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"157.385139ms","start":"2026-01-31T16:13:40.981856Z","end":"2026-01-31T16:13:41.139242Z","steps":["trace[1773089931] 'process raft request' (duration: 157.300607ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:46.780898Z","caller":"traceutil/trace.go:171","msg":"trace[1006837712] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"211.449537ms","start":"2026-01-31T16:13:46.569431Z","end":"2026-01-31T16:13:46.780881Z","steps":["trace[1006837712] 'read index received' (duration: 211.310794ms)","trace[1006837712] 'applied index is now lower than readState.Index' (duration: 137.883µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:13:46.780974Z","caller":"traceutil/trace.go:171","msg":"trace[1740394530] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"216.318797ms","start":"2026-01-31T16:13:46.564649Z","end":"2026-01-31T16:13:46.780968Z","steps":["trace[1740394530] 'process raft request' (duration: 216.129783ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:46.781155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.708673ms","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-01-31T16:13:46.781176Z","caller":"traceutil/trace.go:171","msg":"trace[1335831989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"211.768325ms","start":"2026-01-31T16:13:46.569402Z","end":"2026-01-31T16:13:46.78117Z","steps":["trace[1335831989] 'agreement among raft nodes before linearized reading' (duration: 211.667103ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:46.781401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.380851ms","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-01-31T16:13:46.781492Z","caller":"traceutil/trace.go:171","msg":"trace[2064599956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"194.504174ms","start":"2026-01-31T16:13:46.58697Z","end":"2026-01-31T16:13:46.781474Z","steps":["trace[2064599956] 'agreement among raft nodes before linearized reading' (duration: 194.30516ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:13:53.468472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.535322ms","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-01-31T16:13:53.468521Z","caller":"traceutil/trace.go:171","msg":"trace[69287514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:947; }","duration":"257.621623ms","start":"2026-01-31T16:13:53.210888Z","end":"2026-01-31T16:13:53.468509Z","steps":["trace[69287514] 'range keys from in-memory index tree' (duration: 257.418198ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T16:13:53.651999Z","caller":"traceutil/trace.go:171","msg":"trace[105304265] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"177.200922ms","start":"2026-01-31T16:13:53.474782Z","end":"2026-01-31T16:13:53.651983Z","steps":["trace[105304265] 'process raft request' (duration: 177.089869ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:03.475695Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599836068373674,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T16:14:04.00762Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.078896718s","expected-duration":"1s"} {"level":"info","ts":"2026-01-31T16:14:04.010811Z","caller":"traceutil/trace.go:171","msg":"trace[2131131648] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.082164137s","start":"2026-01-31T16:14:02.928623Z","end":"2026-01-31T16:14:04.010787Z","steps":["trace[2131131648] 'process raft request' (duration: 1.081976863s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:04.010946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:02.928607Z","time spent":"1.082269101s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T16:14:04.016096Z","caller":"traceutil/trace.go:171","msg":"trace[1910888131] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"1.040994213s","start":"2026-01-31T16:14:02.975088Z","end":"2026-01-31T16:14:04.016082Z","steps":["trace[1910888131] 'read index received' (duration: 1.036246927s)","trace[1910888131] 'applied index is now lower than readState.Index' (duration: 4.746876ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T16:14:04.016298Z","caller":"traceutil/trace.go:171","msg":"trace[713334285] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.085806256s","start":"2026-01-31T16:14:02.930483Z","end":"2026-01-31T16:14:04.016289Z","steps":["trace[713334285] 'process raft request' (duration: 1.085528309s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:04.016396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:02.930471Z","time spent":"1.085846158s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T16:14:04.016618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041518506s","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-01-31T16:14:04.016645Z","caller":"traceutil/trace.go:171","msg":"trace[189388021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"1.041574987s","start":"2026-01-31T16:14:02.97506Z","end":"2026-01-31T16:14:04.016635Z","steps":["trace[189388021] 'agreement among raft nodes before linearized reading' (duration: 1.041447864s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:04.016672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:02.97505Z","time spent":"1.041612528s","remote":"127.0.0.1:42036","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-01-31T16:14:04.016747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.343478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T16:14:04.016863Z","caller":"traceutil/trace.go:171","msg":"trace[1795611035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"942.486681ms","start":"2026-01-31T16:14:03.074367Z","end":"2026-01-31T16:14:04.016854Z","steps":["trace[1795611035] 'agreement among raft nodes before linearized reading' (duration: 942.361938ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:04.016881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:03.074351Z","time spent":"942.525302ms","remote":"127.0.0.1:41786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T16:14:04.016983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.717835ms","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-01-31T16:14:04.017019Z","caller":"traceutil/trace.go:171","msg":"trace[78832672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"334.790877ms","start":"2026-01-31T16:14:03.682222Z","end":"2026-01-31T16:14:04.017013Z","steps":["trace[78832672] 'agreement among raft nodes before linearized reading' (duration: 334.714645ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T16:14:04.017037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T16:14:03.682204Z","time spent":"334.828158ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}