{"level":"warn","ts":"2026-01-28T00:50:45.512361Z","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-28T00:50:45.512634Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-28T00:50:45.512788Z","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-28T00:50:45.512849Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-01-28T00:50:45.512918Z","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-28T00:50:45.513647Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-01-28T00:50:45.514761Z","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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-28T00:50:45.524082Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.042484ms"} {"level":"info","ts":"2026-01-28T00:50:45.553291Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-01-28T00:50:45.553406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-01-28T00:50:45.553428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-01-28T00:50:45.553456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-28T00:50:45.553468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-01-28T00:50:45.5535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-01-28T00:50:45.563461Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-28T00:50:45.567859Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-28T00:50:45.570339Z","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-28T00:50:45.574171Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-28T00:50:45.575573Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-28T00:50:45.575684Z","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-28T00:50:45.575739Z","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-28T00:50:45.575749Z","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-28T00:50:45.577076Z","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-28T00:50:45.577493Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-01-28T00:50:45.577509Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-01-28T00:50:45.577671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-01-28T00:50:45.577756Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-01-28T00:50:45.578528Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-28T00:50:45.57864Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-28T00:50:46.555519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-01-28T00:50:46.555606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-01-28T00:50:46.55563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-01-28T00:50:46.555642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-01-28T00:50:46.555748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-01-28T00:50:46.555769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-01-28T00:50:46.555783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-01-28T00:50:46.558943Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-01-28T00:50:46.558968Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T00:50:46.559759Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T00:50:46.562969Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T00:50:46.566722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-28T00:50:46.573092Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-01-28T00:50:46.580332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T00:50:46.580406Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T00:50:46.580425Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T00:50:46.620912Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-28T00:50:46.62102Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-01-28T00:51:00.797548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132356842271863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T00:51:01.298466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132356842271863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T00:51:01.364358Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.39665669s","expected-duration":"1s"} {"level":"info","ts":"2026-01-28T00:51:01.3647Z","caller":"traceutil/trace.go:171","msg":"trace[1763167312] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"1.067647605s","start":"2026-01-28T00:51:00.297032Z","end":"2026-01-28T00:51:01.36468Z","steps":["trace[1763167312] 'read index received' (duration: 1.06746484s)","trace[1763167312] 'applied index is now lower than readState.Index' (duration: 181.825µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T00:51:01.364815Z","caller":"traceutil/trace.go:171","msg":"trace[1040672133] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.397366522s","start":"2026-01-28T00:50:59.967439Z","end":"2026-01-28T00:51:01.364805Z","steps":["trace[1040672133] 'process raft request' (duration: 1.397072213s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:01.365044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.842595ms","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-28T00:51:01.365113Z","caller":"traceutil/trace.go:171","msg":"trace[822058999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"903.941157ms","start":"2026-01-28T00:51:00.461155Z","end":"2026-01-28T00:51:01.365096Z","steps":["trace[822058999] 'agreement among raft nodes before linearized reading' (duration: 903.830324ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:01.365151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:00.461143Z","time spent":"903.99927ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-28T00:51:01.365328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:50:59.967381Z","time spent":"1.397460465s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T00:51:01.365362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068327287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-01-28T00:51:01.365394Z","caller":"traceutil/trace.go:171","msg":"trace[338212907] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:410; }","duration":"1.068398679s","start":"2026-01-28T00:51:00.296987Z","end":"2026-01-28T00:51:01.365385Z","steps":["trace[338212907] 'agreement among raft nodes before linearized reading' (duration: 1.068321596s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:01.36542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:00.296967Z","time spent":"1.06844685s","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4218,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-01-28T00:51:01.365565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.490713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-28T00:51:01.365595Z","caller":"traceutil/trace.go:171","msg":"trace[1777274893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"208.556335ms","start":"2026-01-28T00:51:01.157031Z","end":"2026-01-28T00:51:01.365587Z","steps":["trace[1777274893] 'agreement among raft nodes before linearized reading' (duration: 208.509683ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:01.365762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.794013ms","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-28T00:51:01.365801Z","caller":"traceutil/trace.go:171","msg":"trace[1795935357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"903.840734ms","start":"2026-01-28T00:51:00.461948Z","end":"2026-01-28T00:51:01.365789Z","steps":["trace[1795935357] 'agreement among raft nodes before linearized reading' (duration: 903.773532ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:01.365836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:00.461943Z","time spent":"903.882785ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-28T00:51:01.796561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.934741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T00:51:01.796674Z","caller":"traceutil/trace.go:171","msg":"trace[1801993116] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"423.760509ms","start":"2026-01-28T00:51:01.372894Z","end":"2026-01-28T00:51:01.796655Z","steps":["trace[1801993116] 'process raft request' (duration: 146.502608ms)","trace[1801993116] 'compare' (duration: 276.814677ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:51:01.796756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:01.372878Z","time spent":"423.851291ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:01.797369Z","caller":"traceutil/trace.go:171","msg":"trace[71214905] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"422.654664ms","start":"2026-01-28T00:51:01.374703Z","end":"2026-01-28T00:51:01.797358Z","steps":["trace[71214905] 'process raft request' (duration: 422.606852ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:01.797444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:01.374695Z","time spent":"422.716945ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4049,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:01.799612Z","caller":"traceutil/trace.go:171","msg":"trace[1452996773] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"426.145594ms","start":"2026-01-28T00:51:01.373442Z","end":"2026-01-28T00:51:01.799588Z","steps":["trace[1452996773] 'process raft request' (duration: 423.833281ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:01.799881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:01.373437Z","time spent":"426.34907ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:01.800356Z","caller":"traceutil/trace.go:171","msg":"trace[403367457] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"426.960609ms","start":"2026-01-28T00:51:01.373381Z","end":"2026-01-28T00:51:01.800342Z","steps":["trace[403367457] 'process raft request' (duration: 423.845621ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:01.800477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:01.373371Z","time spent":"427.069272ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:19.840864Z","caller":"traceutil/trace.go:171","msg":"trace[1679546971] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"383.865097ms","start":"2026-01-28T00:51:19.456984Z","end":"2026-01-28T00:51:19.840849Z","steps":["trace[1679546971] 'read index received' (duration: 383.752983ms)","trace[1679546971] 'applied index is now lower than readState.Index' (duration: 111.284µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T00:51:19.84094Z","caller":"traceutil/trace.go:171","msg":"trace[225428653] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"395.680248ms","start":"2026-01-28T00:51:19.445252Z","end":"2026-01-28T00:51:19.840933Z","steps":["trace[225428653] 'process raft request' (duration: 395.506912ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:19.841013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:19.44524Z","time spent":"395.715098ms","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T00:51:19.841141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.140145ms","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-28T00:51:19.841192Z","caller":"traceutil/trace.go:171","msg":"trace[2090575733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"384.221567ms","start":"2026-01-28T00:51:19.456959Z","end":"2026-01-28T00:51:19.84118Z","steps":["trace[2090575733] 'agreement among raft nodes before linearized reading' (duration: 384.079883ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:19.841219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:19.456948Z","time spent":"384.265679ms","remote":"127.0.0.1:48930","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":"info","ts":"2026-01-28T00:51:19.851395Z","caller":"traceutil/trace.go:171","msg":"trace[1816860124] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"254.537367ms","start":"2026-01-28T00:51:19.596848Z","end":"2026-01-28T00:51:19.851386Z","steps":["trace[1816860124] 'process raft request' (duration: 254.320391ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:51:20.354733Z","caller":"traceutil/trace.go:171","msg":"trace[4912882] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"342.916731ms","start":"2026-01-28T00:51:20.011801Z","end":"2026-01-28T00:51:20.354718Z","steps":["trace[4912882] 'read index received' (duration: 283.502067ms)","trace[4912882] 'applied index is now lower than readState.Index' (duration: 59.414054ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T00:51:20.354853Z","caller":"traceutil/trace.go:171","msg":"trace[105644612] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"357.180589ms","start":"2026-01-28T00:51:19.99766Z","end":"2026-01-28T00:51:20.354841Z","steps":["trace[105644612] 'process raft request' (duration: 297.727533ms)","trace[105644612] 'compare' (duration: 59.098455ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:51:20.354919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:19.997644Z","time spent":"357.225021ms","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-28T00:51:20.355043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.238201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T00:51:20.355065Z","caller":"traceutil/trace.go:171","msg":"trace[760566783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"343.283893ms","start":"2026-01-28T00:51:20.011777Z","end":"2026-01-28T00:51:20.355061Z","steps":["trace[760566783] 'agreement among raft nodes before linearized reading' (duration: 343.229171ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:20.355079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:20.011765Z","time spent":"343.310363ms","remote":"127.0.0.1:48710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T00:51:20.355179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.852438ms","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-01-28T00:51:20.355192Z","caller":"traceutil/trace.go:171","msg":"trace[2089111366] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:567; }","duration":"160.886879ms","start":"2026-01-28T00:51:20.194301Z","end":"2026-01-28T00:51:20.355188Z","steps":["trace[2089111366] 'agreement among raft nodes before linearized reading' (duration: 160.867068ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:20.690172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.102373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T00:51:20.690275Z","caller":"traceutil/trace.go:171","msg":"trace[194458572] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"327.372483ms","start":"2026-01-28T00:51:20.362882Z","end":"2026-01-28T00:51:20.690254Z","steps":["trace[194458572] 'process raft request' (duration: 203.112715ms)","trace[194458572] 'compare' (duration: 123.99481ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:51:20.690333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:20.3627Z","time spent":"327.606711ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:20.695157Z","caller":"traceutil/trace.go:171","msg":"trace[1250400144] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"271.830029ms","start":"2026-01-28T00:51:20.42331Z","end":"2026-01-28T00:51:20.69514Z","steps":["trace[1250400144] 'process raft request' (duration: 270.89745ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:51:20.697349Z","caller":"traceutil/trace.go:171","msg":"trace[1405557993] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"333.937819ms","start":"2026-01-28T00:51:20.363399Z","end":"2026-01-28T00:51:20.697337Z","steps":["trace[1405557993] 'process raft request' (duration: 330.44189ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:20.697408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:20.363393Z","time spent":"333.97357ms","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:20.697676Z","caller":"traceutil/trace.go:171","msg":"trace[1577471125] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"102.782205ms","start":"2026-01-28T00:51:20.594843Z","end":"2026-01-28T00:51:20.697625Z","steps":["trace[1577471125] 'read index received' (duration: 98.932325ms)","trace[1577471125] 'applied index is now lower than readState.Index' (duration: 3.84735ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:51:20.697817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.965591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-01-28T00:51:20.697849Z","caller":"traceutil/trace.go:171","msg":"trace[1389139571] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:570; }","duration":"103.025043ms","start":"2026-01-28T00:51:20.594807Z","end":"2026-01-28T00:51:20.697832Z","steps":["trace[1389139571] 'agreement among raft nodes before linearized reading' (duration: 102.900259ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:21.198197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.805493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T00:51:21.201211Z","caller":"traceutil/trace.go:171","msg":"trace[1177854418] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"375.395401ms","start":"2026-01-28T00:51:20.825804Z","end":"2026-01-28T00:51:21.201199Z","steps":["trace[1177854418] 'read index received' (duration: 163.269703ms)","trace[1177854418] 'applied index is now lower than readState.Index' (duration: 212.124487ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:51:21.201352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.538305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-01-28T00:51:21.201382Z","caller":"traceutil/trace.go:171","msg":"trace[87428750] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"375.594077ms","start":"2026-01-28T00:51:20.825777Z","end":"2026-01-28T00:51:21.201371Z","steps":["trace[87428750] 'agreement among raft nodes before linearized reading' (duration: 375.488994ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:21.201413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:20.825765Z","time spent":"375.640108ms","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-01-28T00:51:21.201677Z","caller":"traceutil/trace.go:171","msg":"trace[1748794814] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"482.823462ms","start":"2026-01-28T00:51:20.718329Z","end":"2026-01-28T00:51:21.201152Z","steps":["trace[1748794814] 'process raft request' (duration: 270.657934ms)","trace[1748794814] 'compare' (duration: 208.693239ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:51:21.20174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:20.718311Z","time spent":"483.392869ms","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:21.234059Z","caller":"traceutil/trace.go:171","msg":"trace[856698479] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"351.688877ms","start":"2026-01-28T00:51:20.882347Z","end":"2026-01-28T00:51:21.234035Z","steps":["trace[856698479] 'process raft request' (duration: 351.306545ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:21.234113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.456135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-01-28T00:51:21.234276Z","caller":"traceutil/trace.go:171","msg":"trace[725452722] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"239.6464ms","start":"2026-01-28T00:51:20.994612Z","end":"2026-01-28T00:51:21.234259Z","steps":["trace[725452722] 'agreement among raft nodes before linearized reading' (duration: 239.393762ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:21.234436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.782927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T00:51:21.234477Z","caller":"traceutil/trace.go:171","msg":"trace[1694447120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"219.844829ms","start":"2026-01-28T00:51:21.014627Z","end":"2026-01-28T00:51:21.234472Z","steps":["trace[1694447120] 'agreement among raft nodes before linearized reading' (duration: 219.785627ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:21.234219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:20.88233Z","time spent":"351.80066ms","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:21.982525Z","caller":"traceutil/trace.go:171","msg":"trace[2142545330] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"114.335908ms","start":"2026-01-28T00:51:21.868173Z","end":"2026-01-28T00:51:21.982509Z","steps":["trace[2142545330] 'process raft request' (duration: 114.133401ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:51:26.921307Z","caller":"traceutil/trace.go:171","msg":"trace[1533561130] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"204.600191ms","start":"2026-01-28T00:51:26.716687Z","end":"2026-01-28T00:51:26.921287Z","steps":["trace[1533561130] 'process raft request' (duration: 204.429676ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:51:28.230314Z","caller":"traceutil/trace.go:171","msg":"trace[675003643] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"140.664884ms","start":"2026-01-28T00:51:28.089624Z","end":"2026-01-28T00:51:28.230288Z","steps":["trace[675003643] 'process raft request' (duration: 136.637718ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:51:28.233069Z","caller":"traceutil/trace.go:171","msg":"trace[1626235296] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"142.513932ms","start":"2026-01-28T00:51:28.090529Z","end":"2026-01-28T00:51:28.233043Z","steps":["trace[1626235296] 'process raft request' (duration: 139.506038ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:51:37.748613Z","caller":"traceutil/trace.go:171","msg":"trace[1326886788] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"357.635123ms","start":"2026-01-28T00:51:37.390961Z","end":"2026-01-28T00:51:37.748596Z","steps":["trace[1326886788] 'process raft request' (duration: 357.520499ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:37.748746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:37.390946Z","time spent":"357.722706ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18231,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:42.920975Z","caller":"traceutil/trace.go:171","msg":"trace[482063520] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"109.732443ms","start":"2026-01-28T00:51:42.81122Z","end":"2026-01-28T00:51:42.920953Z","steps":["trace[482063520] 'process raft request' (duration: 109.602869ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:51:46.247152Z","caller":"traceutil/trace.go:171","msg":"trace[521862020] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"108.913778ms","start":"2026-01-28T00:51:46.138216Z","end":"2026-01-28T00:51:46.24713Z","steps":["trace[521862020] 'process raft request' (duration: 108.568777ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:48.670247Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132356842272518,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-28T00:51:48.905996Z","caller":"traceutil/trace.go:171","msg":"trace[390538178] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"736.676821ms","start":"2026-01-28T00:51:48.169303Z","end":"2026-01-28T00:51:48.90598Z","steps":["trace[390538178] 'read index received' (duration: 736.532447ms)","trace[390538178] 'applied index is now lower than readState.Index' (duration: 143.654µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T00:51:48.906068Z","caller":"traceutil/trace.go:171","msg":"trace[484904888] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"782.983286ms","start":"2026-01-28T00:51:48.123079Z","end":"2026-01-28T00:51:48.906062Z","steps":["trace[484904888] 'process raft request' (duration: 782.80338ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:48.906134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.123065Z","time spent":"783.020418ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-28T00:51:48.906328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.025922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T00:51:48.906349Z","caller":"traceutil/trace.go:171","msg":"trace[1804938935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"737.071644ms","start":"2026-01-28T00:51:48.169271Z","end":"2026-01-28T00:51:48.906342Z","steps":["trace[1804938935] 'agreement among raft nodes before linearized reading' (duration: 736.990331ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:48.906369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.169257Z","time spent":"737.106675ms","remote":"127.0.0.1:48710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T00:51:48.906501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.48986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-28T00:51:48.906518Z","caller":"traceutil/trace.go:171","msg":"trace[2064044748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"655.542511ms","start":"2026-01-28T00:51:48.25097Z","end":"2026-01-28T00:51:48.906513Z","steps":["trace[2064044748] 'agreement among raft nodes before linearized reading' (duration: 655.475249ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:48.906536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.250951Z","time spent":"655.581513ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-28T00:51:48.906706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.696286ms","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-28T00:51:48.906728Z","caller":"traceutil/trace.go:171","msg":"trace[1532881343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"592.736298ms","start":"2026-01-28T00:51:48.313985Z","end":"2026-01-28T00:51:48.906721Z","steps":["trace[1532881343] 'agreement among raft nodes before linearized reading' (duration: 592.683986ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:48.906747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.313975Z","time spent":"592.768269ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-28T00:51:48.90685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.26935ms","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-28T00:51:48.906867Z","caller":"traceutil/trace.go:171","msg":"trace[82876066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"597.307861ms","start":"2026-01-28T00:51:48.309554Z","end":"2026-01-28T00:51:48.906861Z","steps":["trace[82876066] 'agreement among raft nodes before linearized reading' (duration: 597.26133ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:48.906883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.309542Z","time spent":"597.337533ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-28T00:51:48.906984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.063384ms","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-28T00:51:48.907001Z","caller":"traceutil/trace.go:171","msg":"trace[1995595539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"618.119085ms","start":"2026-01-28T00:51:48.288877Z","end":"2026-01-28T00:51:48.906996Z","steps":["trace[1995595539] 'agreement among raft nodes before linearized reading' (duration: 618.070594ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:48.907016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.288851Z","time spent":"618.161837ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-28T00:51:49.51118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132356842272522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T00:51:49.804709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.404248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T00:51:49.80494Z","caller":"traceutil/trace.go:171","msg":"trace[198699699] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:763; }","duration":"794.054874ms","start":"2026-01-28T00:51:49.010868Z","end":"2026-01-28T00:51:49.804923Z","steps":["trace[198699699] 'read index received' (duration: 628.376747ms)","trace[198699699] 'applied index is now lower than readState.Index' (duration: 165.677207ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T00:51:49.805408Z","caller":"traceutil/trace.go:171","msg":"trace[279031626] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"894.369967ms","start":"2026-01-28T00:51:48.910915Z","end":"2026-01-28T00:51:49.805285Z","steps":["trace[279031626] 'process raft request' (duration: 728.319789ms)","trace[279031626] 'compare' (duration: 165.311565ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:51:49.805484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.910902Z","time spent":"894.542282ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:49.805638Z","caller":"traceutil/trace.go:171","msg":"trace[1184705750] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"893.765328ms","start":"2026-01-28T00:51:48.911863Z","end":"2026-01-28T00:51:49.805629Z","steps":["trace[1184705750] 'process raft request' (duration: 892.929322ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:49.805702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.911854Z","time spent":"893.81825ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:49.805869Z","caller":"traceutil/trace.go:171","msg":"trace[462196309] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"892.719915ms","start":"2026-01-28T00:51:48.91314Z","end":"2026-01-28T00:51:49.80586Z","steps":["trace[462196309] 'process raft request' (duration: 891.696773ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:49.805917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.913134Z","time spent":"892.756546ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:51:49.806029Z","caller":"traceutil/trace.go:171","msg":"trace[2090232482] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"892.852229ms","start":"2026-01-28T00:51:48.913168Z","end":"2026-01-28T00:51:49.80602Z","steps":["trace[2090232482] 'process raft request' (duration: 891.723394ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:49.806073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:48.913164Z","time spent":"892.88586ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T00:51:49.806188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.315774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T00:51:49.806211Z","caller":"traceutil/trace.go:171","msg":"trace[484205989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"795.354145ms","start":"2026-01-28T00:51:49.010851Z","end":"2026-01-28T00:51:49.806205Z","steps":["trace[484205989] 'agreement among raft nodes before linearized reading' (duration: 795.312884ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:49.806226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:49.010842Z","time spent":"795.381306ms","remote":"127.0.0.1:48718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-28T00:51:49.853546Z","caller":"traceutil/trace.go:171","msg":"trace[454039320] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"135.067325ms","start":"2026-01-28T00:51:49.718455Z","end":"2026-01-28T00:51:49.853522Z","steps":["trace[454039320] 'process raft request' (duration: 134.963782ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:49.853663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.492408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T00:51:49.853711Z","caller":"traceutil/trace.go:171","msg":"trace[1037284860] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:747; }","duration":"156.635653ms","start":"2026-01-28T00:51:49.697063Z","end":"2026-01-28T00:51:49.853699Z","steps":["trace[1037284860] 'agreement among raft nodes before linearized reading' (duration: 156.478808ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:49.85381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.499469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-28T00:51:49.853826Z","caller":"traceutil/trace.go:171","msg":"trace[2121522957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"767.551481ms","start":"2026-01-28T00:51:49.086269Z","end":"2026-01-28T00:51:49.853821Z","steps":["trace[2121522957] 'agreement among raft nodes before linearized reading' (duration: 767.486999ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:49.853842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:51:49.086257Z","time spent":"767.579782ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-28T00:51:56.015663Z","caller":"traceutil/trace.go:171","msg":"trace[1500896520] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"138.633967ms","start":"2026-01-28T00:51:55.877011Z","end":"2026-01-28T00:51:56.015645Z","steps":["trace[1500896520] 'read index received' (duration: 138.462352ms)","trace[1500896520] 'applied index is now lower than readState.Index' (duration: 170.935µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T00:51:56.015756Z","caller":"traceutil/trace.go:171","msg":"trace[79536572] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"144.421738ms","start":"2026-01-28T00:51:55.871328Z","end":"2026-01-28T00:51:56.015749Z","steps":["trace[79536572] 'process raft request' (duration: 144.177301ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:56.015955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.485542ms","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-28T00:51:56.016018Z","caller":"traceutil/trace.go:171","msg":"trace[1282001297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"117.592616ms","start":"2026-01-28T00:51:55.898412Z","end":"2026-01-28T00:51:56.016005Z","steps":["trace[1282001297] 'agreement among raft nodes before linearized reading' (duration: 117.444701ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:56.016136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.108652ms","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-28T00:51:56.01618Z","caller":"traceutil/trace.go:171","msg":"trace[1636851228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"139.146693ms","start":"2026-01-28T00:51:55.877009Z","end":"2026-01-28T00:51:56.016155Z","steps":["trace[1636851228] 'agreement among raft nodes before linearized reading' (duration: 139.080141ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:51:56.016188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.962828ms","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-28T00:51:56.016209Z","caller":"traceutil/trace.go:171","msg":"trace[307426012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"105.00562ms","start":"2026-01-28T00:51:55.911198Z","end":"2026-01-28T00:51:56.016203Z","steps":["trace[307426012] 'agreement among raft nodes before linearized reading' (duration: 104.951588ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:52:03.505692Z","caller":"traceutil/trace.go:171","msg":"trace[1232291033] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"184.160108ms","start":"2026-01-28T00:52:03.321517Z","end":"2026-01-28T00:52:03.505677Z","steps":["trace[1232291033] 'process raft request' (duration: 183.841898ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:05.515394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.326162ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-28T00:52:05.515483Z","caller":"traceutil/trace.go:171","msg":"trace[1913955747] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"177.578831ms","start":"2026-01-28T00:52:05.337888Z","end":"2026-01-28T00:52:05.515467Z","steps":["trace[1913955747] 'read index received' (duration: 29.123805ms)","trace[1913955747] 'applied index is now lower than readState.Index' (duration: 148.453556ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:52:05.515576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.674314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T00:52:05.515607Z","caller":"traceutil/trace.go:171","msg":"trace[238354841] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:795; }","duration":"177.741876ms","start":"2026-01-28T00:52:05.337853Z","end":"2026-01-28T00:52:05.515595Z","steps":["trace[238354841] 'agreement among raft nodes before linearized reading' (duration: 177.656753ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:05.515852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.771201ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T00:52:05.515914Z","caller":"traceutil/trace.go:171","msg":"trace[345002926] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:795; }","duration":"124.834733ms","start":"2026-01-28T00:52:05.391071Z","end":"2026-01-28T00:52:05.515906Z","steps":["trace[345002926] 'range keys from in-memory index tree' (duration: 124.764241ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:52:06.338938Z","caller":"traceutil/trace.go:171","msg":"trace[1241885024] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"226.88316ms","start":"2026-01-28T00:52:06.112033Z","end":"2026-01-28T00:52:06.338916Z","steps":["trace[1241885024] 'read index received' (duration: 226.625552ms)","trace[1241885024] 'applied index is now lower than readState.Index' (duration: 256.868µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T00:52:06.339307Z","caller":"traceutil/trace.go:171","msg":"trace[724968690] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"231.038821ms","start":"2026-01-28T00:52:06.10826Z","end":"2026-01-28T00:52:06.339299Z","steps":["trace[724968690] 'process raft request' (duration: 230.454442ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:06.3394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.348335ms","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-28T00:52:06.340181Z","caller":"traceutil/trace.go:171","msg":"trace[2144646733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"228.15766ms","start":"2026-01-28T00:52:06.112017Z","end":"2026-01-28T00:52:06.340175Z","steps":["trace[2144646733] 'agreement among raft nodes before linearized reading' (duration: 227.309254ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:06.340458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.733829ms","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-28T00:52:06.340477Z","caller":"traceutil/trace.go:171","msg":"trace[185557053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"193.790271ms","start":"2026-01-28T00:52:06.146682Z","end":"2026-01-28T00:52:06.340472Z","steps":["trace[185557053] 'agreement among raft nodes before linearized reading' (duration: 193.739279ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:06.340553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.244787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-28T00:52:06.340568Z","caller":"traceutil/trace.go:171","msg":"trace[733458147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:796; }","duration":"189.279099ms","start":"2026-01-28T00:52:06.151285Z","end":"2026-01-28T00:52:06.340564Z","steps":["trace[733458147] 'agreement among raft nodes before linearized reading' (duration: 189.242687ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:06.340637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.648922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-28T00:52:06.340651Z","caller":"traceutil/trace.go:171","msg":"trace[1598688212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"190.687363ms","start":"2026-01-28T00:52:06.149961Z","end":"2026-01-28T00:52:06.340648Z","steps":["trace[1598688212] 'agreement among raft nodes before linearized reading' (duration: 190.650462ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:52:06.799078Z","caller":"traceutil/trace.go:171","msg":"trace[884106589] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"453.580465ms","start":"2026-01-28T00:52:06.345469Z","end":"2026-01-28T00:52:06.79905Z","steps":["trace[884106589] 'process raft request' (duration: 453.41994ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:06.799256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:06.345456Z","time spent":"453.72705ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:52:07.916317Z","caller":"traceutil/trace.go:171","msg":"trace[1565605248] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.570665501s","start":"2026-01-28T00:52:06.345625Z","end":"2026-01-28T00:52:07.916291Z","steps":["trace[1565605248] 'process raft request' (duration: 1.569806334s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:07.918449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:06.345621Z","time spent":"1.572753798s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:52:07.916575Z","caller":"traceutil/trace.go:171","msg":"trace[240915872] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"1.570767505s","start":"2026-01-28T00:52:06.345766Z","end":"2026-01-28T00:52:07.916534Z","steps":["trace[240915872] 'process raft request' (duration: 1.570400033s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:07.919172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:06.345752Z","time spent":"1.573321425s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:52:07.919923Z","caller":"traceutil/trace.go:171","msg":"trace[321509065] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"1.572515481s","start":"2026-01-28T00:52:06.347377Z","end":"2026-01-28T00:52:07.919892Z","steps":["trace[321509065] 'process raft request' (duration: 1.569101963s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:07.920065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:06.347371Z","time spent":"1.572606363s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T00:52:08.180454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.382366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T00:52:08.180536Z","caller":"traceutil/trace.go:171","msg":"trace[664539823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"164.512411ms","start":"2026-01-28T00:52:08.016008Z","end":"2026-01-28T00:52:08.18052Z","steps":["trace[664539823] 'range keys from in-memory index tree' (duration: 164.327585ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:52:20.580125Z","caller":"traceutil/trace.go:171","msg":"trace[393137562] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"167.975189ms","start":"2026-01-28T00:52:20.412135Z","end":"2026-01-28T00:52:20.58011Z","steps":["trace[393137562] 'process raft request' (duration: 167.829114ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:52:20.84536Z","caller":"traceutil/trace.go:171","msg":"trace[1914743094] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"290.294344ms","start":"2026-01-28T00:52:20.555046Z","end":"2026-01-28T00:52:20.845341Z","steps":["trace[1914743094] 'process raft request' (duration: 268.342584ms)","trace[1914743094] 'compare' (duration: 21.873707ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T00:52:24.199869Z","caller":"traceutil/trace.go:171","msg":"trace[676879471] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"116.251463ms","start":"2026-01-28T00:52:24.083602Z","end":"2026-01-28T00:52:24.199854Z","steps":["trace[676879471] 'read index received' (duration: 116.115949ms)","trace[676879471] 'applied index is now lower than readState.Index' (duration: 134.794µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:52:24.200026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.412009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-28T00:52:24.200053Z","caller":"traceutil/trace.go:171","msg":"trace[1914251302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"116.47391ms","start":"2026-01-28T00:52:24.083572Z","end":"2026-01-28T00:52:24.200045Z","steps":["trace[1914251302] 'agreement among raft nodes before linearized reading' (duration: 116.343626ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:52:24.200124Z","caller":"traceutil/trace.go:171","msg":"trace[10432688] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"122.282303ms","start":"2026-01-28T00:52:24.077836Z","end":"2026-01-28T00:52:24.200118Z","steps":["trace[10432688] 'process raft request' (duration: 121.921222ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:32.534433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.490451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-28T00:52:32.534483Z","caller":"traceutil/trace.go:171","msg":"trace[639273325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"312.570353ms","start":"2026-01-28T00:52:32.2219Z","end":"2026-01-28T00:52:32.534471Z","steps":["trace[639273325] 'range keys from in-memory index tree' (duration: 312.378817ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:32.534508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:32.221873Z","time spent":"312.627055ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T00:52:32.534683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.745848ms","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-28T00:52:32.534707Z","caller":"traceutil/trace.go:171","msg":"trace[94001230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"214.79074ms","start":"2026-01-28T00:52:32.31991Z","end":"2026-01-28T00:52:32.534701Z","steps":["trace[94001230] 'range keys from in-memory index tree' (duration: 214.689407ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:39.035542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.435498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T00:52:39.03575Z","caller":"traceutil/trace.go:171","msg":"trace[2139894132] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"369.655807ms","start":"2026-01-28T00:52:38.666084Z","end":"2026-01-28T00:52:39.03574Z","steps":["trace[2139894132] 'process raft request' (duration: 64.960631ms)","trace[2139894132] 'compare' (duration: 304.299314ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:52:39.035785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:38.666059Z","time spent":"369.709689ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:52:39.035876Z","caller":"traceutil/trace.go:171","msg":"trace[1248062852] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"368.132279ms","start":"2026-01-28T00:52:38.667739Z","end":"2026-01-28T00:52:39.035871Z","steps":["trace[1248062852] 'process raft request' (duration: 367.862211ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:39.035899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:38.667732Z","time spent":"368.153891ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T00:52:39.58808Z","caller":"traceutil/trace.go:171","msg":"trace[913271793] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"548.813266ms","start":"2026-01-28T00:52:39.03925Z","end":"2026-01-28T00:52:39.588063Z","steps":["trace[913271793] 'process raft request' (duration: 548.705833ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:39.588187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:39.03922Z","time spent":"548.908539ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T00:52:39.941895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.208283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-28T00:52:39.941949Z","caller":"traceutil/trace.go:171","msg":"trace[772694975] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:937; }","duration":"116.308146ms","start":"2026-01-28T00:52:39.825628Z","end":"2026-01-28T00:52:39.941936Z","steps":["trace[772694975] 'range keys from in-memory index tree' (duration: 116.073409ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:52:40.191609Z","caller":"traceutil/trace.go:171","msg":"trace[1197757243] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"176.410125ms","start":"2026-01-28T00:52:40.015184Z","end":"2026-01-28T00:52:40.191594Z","steps":["trace[1197757243] 'read index received' (duration: 176.292921ms)","trace[1197757243] 'applied index is now lower than readState.Index' (duration: 116.524µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:52:40.191704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.503378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T00:52:40.191723Z","caller":"traceutil/trace.go:171","msg":"trace[1867650956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"176.56541ms","start":"2026-01-28T00:52:40.01515Z","end":"2026-01-28T00:52:40.191716Z","steps":["trace[1867650956] 'agreement among raft nodes before linearized reading' (duration: 176.513948ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:40.191935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.559552ms","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-28T00:52:40.19195Z","caller":"traceutil/trace.go:171","msg":"trace[1074039539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"101.596573ms","start":"2026-01-28T00:52:40.090349Z","end":"2026-01-28T00:52:40.191946Z","steps":["trace[1074039539] 'agreement among raft nodes before linearized reading' (duration: 101.529981ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:52.434662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.057171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T00:52:52.435Z","caller":"traceutil/trace.go:171","msg":"trace[640877804] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"196.971683ms","start":"2026-01-28T00:52:52.238005Z","end":"2026-01-28T00:52:52.434977Z","steps":["trace[640877804] 'process raft request' (duration: 32.511739ms)","trace[640877804] 'compare' (duration: 163.913276ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:52:53.112989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.697328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-28T00:52:53.113092Z","caller":"traceutil/trace.go:171","msg":"trace[1506944552] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"810.202904ms","start":"2026-01-28T00:52:52.302872Z","end":"2026-01-28T00:52:53.113074Z","steps":["trace[1506944552] 'read index received' (duration: 494.426344ms)","trace[1506944552] 'applied index is now lower than readState.Index' (duration: 315.7755ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T00:52:53.113172Z","caller":"traceutil/trace.go:171","msg":"trace[426967884] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"871.290486ms","start":"2026-01-28T00:52:52.241871Z","end":"2026-01-28T00:52:53.113162Z","steps":["trace[426967884] 'process raft request' (duration: 555.35264ms)","trace[426967884] 'compare' (duration: 315.157851ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T00:52:53.113217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:52.241859Z","time spent":"871.336128ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":896,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-28T00:52:53.113481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.864379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-01-28T00:52:53.114673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.790205ms","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-01-28T00:52:53.114935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.467899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T00:52:53.115028Z","caller":"traceutil/trace.go:171","msg":"trace[758939648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1029; }","duration":"812.117855ms","start":"2026-01-28T00:52:52.302831Z","end":"2026-01-28T00:52:53.114949Z","steps":["trace[758939648] 'agreement among raft nodes before linearized reading' (duration: 811.757063ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:53.115227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:52.302814Z","time spent":"812.396183ms","remote":"127.0.0.1:48930","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":"info","ts":"2026-01-28T00:52:53.115434Z","caller":"traceutil/trace.go:171","msg":"trace[781943907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"100.550041ms","start":"2026-01-28T00:52:53.014421Z","end":"2026-01-28T00:52:53.114971Z","steps":["trace[781943907] 'agreement among raft nodes before linearized reading' (duration: 100.363686ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T00:52:53.114312Z","caller":"traceutil/trace.go:171","msg":"trace[1563237392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1029; }","duration":"399.730907ms","start":"2026-01-28T00:52:52.71456Z","end":"2026-01-28T00:52:53.114291Z","steps":["trace[1563237392] 'agreement among raft nodes before linearized reading' (duration: 398.695684ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T00:52:53.1159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T00:52:52.714543Z","time spent":"401.340717ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T00:52:53.116492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.547093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-6fdt5\" ","response":"range_response_count:1 size:12336"} {"level":"info","ts":"2026-01-28T00:52:53.117353Z","caller":"traceutil/trace.go:171","msg":"trace[1587992893] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-6fdt5; range_end:; response_count:1; response_revision:1029; }","duration":"206.427991ms","start":"2026-01-28T00:52:52.910908Z","end":"2026-01-28T00:52:53.117336Z","steps":["trace[1587992893] 'agreement among raft nodes before linearized reading' (duration: 205.501242ms)"],"step_count":1}