{"level":"warn","ts":"2026-02-02T01:22:28.272714Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T01:22:28.272814Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-02T01:22:28.273299Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T01:22:28.273352Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-02T01:22:28.273387Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-02T01:22:28.27414Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-02T01:22:28.274248Z","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.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-02T01:22:28.281629Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.150172ms"} {"level":"info","ts":"2026-02-02T01:22:28.316545Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-02T01:22:28.316624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T01:22:28.3167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-02T01:22:28.316719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T01:22:28.316727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-02T01:22:28.316811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-02T01:22:28.328026Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T01:22:28.332743Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T01:22:28.336924Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-02T01:22:28.34021Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T01:22:28.340692Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T01:22:28.340879Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-02T01:22:28.340905Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-02T01:22:28.340914Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-02T01:22:28.341454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-02T01:22:28.341568Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-02T01:22:28.356466Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-02T01:22:28.357055Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-02T01:22:28.357069Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-02T01:22:28.357808Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T01:22:28.357831Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T01:22:28.918272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T01:22:28.918341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T01:22:28.918362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-02T01:22:28.918374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-02T01:22:28.91838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-02T01:22:28.918388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-02T01:22:28.918395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-02T01:22:28.92644Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T01:22:28.926587Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T01:22:28.926923Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T01:22:28.92735Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T01:22:28.931572Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T01:22:28.931589Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T01:22:28.933706Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-02-02T01:22:28.934021Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T01:22:28.934071Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T01:22:28.934083Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T01:22:28.939395Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T01:22:46.758324Z","caller":"traceutil/trace.go:171","msg":"trace[789954884] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"201.573936ms","start":"2026-02-02T01:22:46.556726Z","end":"2026-02-02T01:22:46.7583Z","steps":["trace[789954884] 'process raft request' (duration: 201.091557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:22:52.478613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:22:52.045139Z","time spent":"433.470639ms","remote":"127.0.0.1:59636","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-02T01:22:59.174833Z","caller":"traceutil/trace.go:171","msg":"trace[261369616] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"218.496129ms","start":"2026-02-02T01:22:58.956322Z","end":"2026-02-02T01:22:59.174818Z","steps":["trace[261369616] 'process raft request' (duration: 218.394077ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:22:59.176376Z","caller":"traceutil/trace.go:171","msg":"trace[330693435] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"117.362659ms","start":"2026-02-02T01:22:59.059005Z","end":"2026-02-02T01:22:59.176367Z","steps":["trace[330693435] 'read index received' (duration: 116.122884ms)","trace[330693435] 'applied index is now lower than readState.Index' (duration: 1.239365ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:22:59.176486Z","caller":"traceutil/trace.go:171","msg":"trace[777320432] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"220.027669ms","start":"2026-02-02T01:22:58.956452Z","end":"2026-02-02T01:22:59.17648Z","steps":["trace[777320432] 'process raft request' (duration: 219.879226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:22:59.176589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.574863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4061"} {"level":"info","ts":"2026-02-02T01:22:59.176618Z","caller":"traceutil/trace.go:171","msg":"trace[1062345641] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:517; }","duration":"117.639315ms","start":"2026-02-02T01:22:59.058972Z","end":"2026-02-02T01:22:59.176612Z","steps":["trace[1062345641] 'agreement among raft nodes before linearized reading' (duration: 117.581374ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:23:01.369828Z","caller":"traceutil/trace.go:171","msg":"trace[237479308] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"184.556962ms","start":"2026-02-02T01:23:01.185254Z","end":"2026-02-02T01:23:01.369811Z","steps":["trace[237479308] 'read index received' (duration: 184.4436ms)","trace[237479308] 'applied index is now lower than readState.Index' (duration: 112.852µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:23:01.369903Z","caller":"traceutil/trace.go:171","msg":"trace[616902722] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"190.245893ms","start":"2026-02-02T01:23:01.179651Z","end":"2026-02-02T01:23:01.369897Z","steps":["trace[616902722] 'process raft request' (duration: 190.07847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:01.370075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.092917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"warn","ts":"2026-02-02T01:23:01.370143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.880618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T01:23:01.37015Z","caller":"traceutil/trace.go:171","msg":"trace[1371130032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"171.184868ms","start":"2026-02-02T01:23:01.198951Z","end":"2026-02-02T01:23:01.370135Z","steps":["trace[1371130032] 'agreement among raft nodes before linearized reading' (duration: 171.030465ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:23:01.370169Z","caller":"traceutil/trace.go:171","msg":"trace[868611749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"184.913678ms","start":"2026-02-02T01:23:01.185245Z","end":"2026-02-02T01:23:01.370159Z","steps":["trace[868611749] 'agreement among raft nodes before linearized reading' (duration: 184.829857ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:23:04.689644Z","caller":"traceutil/trace.go:171","msg":"trace[1096487939] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"211.355389ms","start":"2026-02-02T01:23:04.478271Z","end":"2026-02-02T01:23:04.689626Z","steps":["trace[1096487939] 'process raft request' (duration: 211.272498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:06.020882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.334207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g97sx\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-02T01:23:06.02096Z","caller":"traceutil/trace.go:171","msg":"trace[1274817169] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g97sx; range_end:; response_count:1; response_revision:537; }","duration":"183.47409ms","start":"2026-02-02T01:23:05.837467Z","end":"2026-02-02T01:23:06.020941Z","steps":["trace[1274817169] 'range keys from in-memory index tree' (duration: 183.170204ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:23:06.143691Z","caller":"traceutil/trace.go:171","msg":"trace[704282094] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"106.591017ms","start":"2026-02-02T01:23:06.037073Z","end":"2026-02-02T01:23:06.143664Z","steps":["trace[704282094] 'process raft request' (duration: 106.427964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:08.313173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.841506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T01:23:08.313259Z","caller":"traceutil/trace.go:171","msg":"trace[1566744716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"157.961118ms","start":"2026-02-02T01:23:08.155283Z","end":"2026-02-02T01:23:08.313244Z","steps":["trace[1566744716] 'range keys from in-memory index tree' (duration: 157.710443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:26.256957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100751929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T01:23:26.257016Z","caller":"traceutil/trace.go:171","msg":"trace[522135579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"1.100889433s","start":"2026-02-02T01:23:25.156113Z","end":"2026-02-02T01:23:26.257002Z","steps":["trace[522135579] 'range keys from in-memory index tree' (duration: 1.100659618s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:26.257038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:23:25.156098Z","time spent":"1.100935023s","remote":"127.0.0.1:59584","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-02-02T01:23:26.257871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.242159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T01:23:26.257978Z","caller":"traceutil/trace.go:171","msg":"trace[1299599684] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"781.861334ms","start":"2026-02-02T01:23:25.476102Z","end":"2026-02-02T01:23:26.257963Z","steps":["trace[1299599684] 'read index received' (duration: 139.965883ms)","trace[1299599684] 'applied index is now lower than readState.Index' (duration: 641.894391ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:23:26.258194Z","caller":"traceutil/trace.go:171","msg":"trace[354732870] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"1.018769687s","start":"2026-02-02T01:23:25.23941Z","end":"2026-02-02T01:23:26.25818Z","steps":["trace[354732870] 'process raft request' (duration: 376.714573ms)","trace[354732870] 'compare' (duration: 641.121695ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:23:26.258253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:23:25.239393Z","time spent":"1.018827848s","remote":"127.0.0.1:59872","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-02-02T01:23:26.258455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.353505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-02T01:23:26.258478Z","caller":"traceutil/trace.go:171","msg":"trace[2063430614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"782.393415ms","start":"2026-02-02T01:23:25.476077Z","end":"2026-02-02T01:23:26.25847Z","steps":["trace[2063430614] 'agreement among raft nodes before linearized reading' (duration: 782.329874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:26.258491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:23:25.476064Z","time spent":"782.424515ms","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T01:23:26.258553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.153527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-02T01:23:26.258564Z","caller":"traceutil/trace.go:171","msg":"trace[1938771580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"731.178728ms","start":"2026-02-02T01:23:25.527381Z","end":"2026-02-02T01:23:26.25856Z","steps":["trace[1938771580] 'agreement among raft nodes before linearized reading' (duration: 731.146107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:26.258592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:23:25.527372Z","time spent":"731.216618ms","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T01:23:26.258644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.960112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T01:23:26.258655Z","caller":"traceutil/trace.go:171","msg":"trace[1880654232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"733.002233ms","start":"2026-02-02T01:23:25.52565Z","end":"2026-02-02T01:23:26.258652Z","steps":["trace[1880654232] 'agreement among raft nodes before linearized reading' (duration: 732.970922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:26.258666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:23:25.525634Z","time spent":"733.029603ms","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-02T01:23:26.558223Z","caller":"traceutil/trace.go:171","msg":"trace[247353769] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:746; }","duration":"269.740287ms","start":"2026-02-02T01:23:26.288464Z","end":"2026-02-02T01:23:26.558204Z","steps":["trace[247353769] 'read index received' (duration: 17.555125ms)","trace[247353769] 'applied index is now lower than readState.Index' (duration: 252.184402ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:23:26.558309Z","caller":"traceutil/trace.go:171","msg":"trace[1608406493] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"269.917501ms","start":"2026-02-02T01:23:26.288385Z","end":"2026-02-02T01:23:26.558302Z","steps":["trace[1608406493] 'process raft request' (duration: 269.788558ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:23:26.558299Z","caller":"traceutil/trace.go:171","msg":"trace[1707561421] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"280.418837ms","start":"2026-02-02T01:23:26.277858Z","end":"2026-02-02T01:23:26.558277Z","steps":["trace[1707561421] 'process raft request' (duration: 280.205323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:26.558546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.067483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T01:23:26.558568Z","caller":"traceutil/trace.go:171","msg":"trace[762638549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"270.111064ms","start":"2026-02-02T01:23:26.288448Z","end":"2026-02-02T01:23:26.558559Z","steps":["trace[762638549] 'agreement among raft nodes before linearized reading' (duration: 270.065094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:26.558716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.00123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T01:23:26.558729Z","caller":"traceutil/trace.go:171","msg":"trace[131293191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"121.042672ms","start":"2026-02-02T01:23:26.437682Z","end":"2026-02-02T01:23:26.558724Z","steps":["trace[131293191] 'agreement among raft nodes before linearized reading' (duration: 120.97952ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:23:42.742899Z","caller":"traceutil/trace.go:171","msg":"trace[644940838] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"101.618974ms","start":"2026-02-02T01:23:42.641262Z","end":"2026-02-02T01:23:42.742881Z","steps":["trace[644940838] 'process raft request' (duration: 101.237675ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:23:44.600497Z","caller":"traceutil/trace.go:171","msg":"trace[1809567421] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"232.535371ms","start":"2026-02-02T01:23:44.367939Z","end":"2026-02-02T01:23:44.600475Z","steps":["trace[1809567421] 'process raft request' (duration: 232.405528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:44.983335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.93945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T01:23:44.983416Z","caller":"traceutil/trace.go:171","msg":"trace[2095218623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"204.032882ms","start":"2026-02-02T01:23:44.779361Z","end":"2026-02-02T01:23:44.983394Z","steps":["trace[2095218623] 'range keys from in-memory index tree' (duration: 203.791677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:44.983511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.122684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T01:23:44.983586Z","caller":"traceutil/trace.go:171","msg":"trace[141987482] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"204.224177ms","start":"2026-02-02T01:23:44.779342Z","end":"2026-02-02T01:23:44.983566Z","steps":["trace[141987482] 'range keys from in-memory index tree' (duration: 203.959501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:23:44.983787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.70884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-02T01:23:44.98382Z","caller":"traceutil/trace.go:171","msg":"trace[2000980199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:782; }","duration":"237.768792ms","start":"2026-02-02T01:23:44.746041Z","end":"2026-02-02T01:23:44.98381Z","steps":["trace[2000980199] 'range keys from in-memory index tree' (duration: 237.588438ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:23:48.759814Z","caller":"traceutil/trace.go:171","msg":"trace[453716129] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"141.957432ms","start":"2026-02-02T01:23:48.617818Z","end":"2026-02-02T01:23:48.759776Z","steps":["trace[453716129] 'process raft request' (duration: 141.683986ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:01.043824Z","caller":"traceutil/trace.go:171","msg":"trace[1839658855] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"232.816926ms","start":"2026-02-02T01:24:00.810983Z","end":"2026-02-02T01:24:01.0438Z","steps":["trace[1839658855] 'process raft request' (duration: 232.563481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:01.600348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674470722691955,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T01:24:01.648433Z","caller":"traceutil/trace.go:171","msg":"trace[888812815] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"548.685511ms","start":"2026-02-02T01:24:01.099732Z","end":"2026-02-02T01:24:01.648418Z","steps":["trace[888812815] 'read index received' (duration: 548.544418ms)","trace[888812815] 'applied index is now lower than readState.Index' (duration: 140.613µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:24:01.648596Z","caller":"traceutil/trace.go:171","msg":"trace[1982676991] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"561.414962ms","start":"2026-02-02T01:24:01.087174Z","end":"2026-02-02T01:24:01.648589Z","steps":["trace[1982676991] 'process raft request' (duration: 561.153716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:01.64866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:01.087165Z","time spent":"561.449382ms","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":488,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T01:24:01.648792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.057999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-02T01:24:01.648847Z","caller":"traceutil/trace.go:171","msg":"trace[1516934776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"549.124191ms","start":"2026-02-02T01:24:01.099717Z","end":"2026-02-02T01:24:01.648841Z","steps":["trace[1516934776] 'agreement among raft nodes before linearized reading' (duration: 549.026639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:01.648868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:01.09971Z","time spent":"549.155252ms","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T01:24:01.648979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.497325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-02T01:24:01.648999Z","caller":"traceutil/trace.go:171","msg":"trace[1193124545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"530.528195ms","start":"2026-02-02T01:24:01.118466Z","end":"2026-02-02T01:24:01.648995Z","steps":["trace[1193124545] 'agreement among raft nodes before linearized reading' (duration: 530.479543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:01.649012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:01.11846Z","time spent":"530.549056ms","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T01:24:01.649095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.213308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-02T01:24:01.64911Z","caller":"traceutil/trace.go:171","msg":"trace[1816817663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:878; }","duration":"344.25149ms","start":"2026-02-02T01:24:01.304855Z","end":"2026-02-02T01:24:01.649107Z","steps":["trace[1816817663] 'agreement among raft nodes before linearized reading' (duration: 344.214389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:01.649122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:01.304841Z","time spent":"344.27819ms","remote":"127.0.0.1:59872","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-02-02T01:24:01.861893Z","caller":"traceutil/trace.go:171","msg":"trace[949140741] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"208.193734ms","start":"2026-02-02T01:24:01.653688Z","end":"2026-02-02T01:24:01.861882Z","steps":["trace[949140741] 'process raft request' (duration: 208.170002ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:01.862018Z","caller":"traceutil/trace.go:171","msg":"trace[722342908] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"209.882469ms","start":"2026-02-02T01:24:01.65211Z","end":"2026-02-02T01:24:01.861993Z","steps":["trace[722342908] 'process raft request' (duration: 142.381341ms)","trace[722342908] 'compare' (duration: 67.313713ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:24:10.271161Z","caller":"traceutil/trace.go:171","msg":"trace[187275408] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"351.567694ms","start":"2026-02-02T01:24:09.919571Z","end":"2026-02-02T01:24:10.271138Z","steps":["trace[187275408] 'read index received' (duration: 351.331959ms)","trace[187275408] 'applied index is now lower than readState.Index' (duration: 234.845µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:24:10.271394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.800269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T01:24:10.271426Z","caller":"traceutil/trace.go:171","msg":"trace[1191261352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"351.87515ms","start":"2026-02-02T01:24:09.919542Z","end":"2026-02-02T01:24:10.271417Z","steps":["trace[1191261352] 'agreement among raft nodes before linearized reading' (duration: 351.680236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:10.271462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:09.919527Z","time spent":"351.927162ms","remote":"127.0.0.1:59872","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-02-02T01:24:10.271524Z","caller":"traceutil/trace.go:171","msg":"trace[732931881] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"382.300788ms","start":"2026-02-02T01:24:09.889201Z","end":"2026-02-02T01:24:10.271502Z","steps":["trace[732931881] 'process raft request' (duration: 381.772017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:10.271612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:09.889175Z","time spent":"382.3915ms","remote":"127.0.0.1:59872","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-02-02T01:24:12.363102Z","caller":"traceutil/trace.go:171","msg":"trace[1866955147] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"209.284455ms","start":"2026-02-02T01:24:12.153801Z","end":"2026-02-02T01:24:12.363086Z","steps":["trace[1866955147] 'read index received' (duration: 205.911934ms)","trace[1866955147] 'applied index is now lower than readState.Index' (duration: 3.371821ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:24:12.363283Z","caller":"traceutil/trace.go:171","msg":"trace[797953666] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"230.820793ms","start":"2026-02-02T01:24:12.132456Z","end":"2026-02-02T01:24:12.363277Z","steps":["trace[797953666] 'process raft request' (duration: 227.304809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:12.363406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.595052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T01:24:12.363428Z","caller":"traceutil/trace.go:171","msg":"trace[178054938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"209.644052ms","start":"2026-02-02T01:24:12.153778Z","end":"2026-02-02T01:24:12.363422Z","steps":["trace[178054938] 'agreement among raft nodes before linearized reading' (duration: 209.600841ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:13.939992Z","caller":"traceutil/trace.go:171","msg":"trace[783488612] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"107.477228ms","start":"2026-02-02T01:24:13.832498Z","end":"2026-02-02T01:24:13.939976Z","steps":["trace[783488612] 'process raft request' (duration: 72.078964ms)","trace[783488612] 'compare' (duration: 35.177779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:24:14.402922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.316797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T01:24:14.402986Z","caller":"traceutil/trace.go:171","msg":"trace[1286956627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"249.40978ms","start":"2026-02-02T01:24:14.15356Z","end":"2026-02-02T01:24:14.40297Z","steps":["trace[1286956627] 'range keys from in-memory index tree' (duration: 249.270046ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:15.528232Z","caller":"traceutil/trace.go:171","msg":"trace[1473497336] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"348.9837ms","start":"2026-02-02T01:24:15.179234Z","end":"2026-02-02T01:24:15.528218Z","steps":["trace[1473497336] 'read index received' (duration: 348.870167ms)","trace[1473497336] 'applied index is now lower than readState.Index' (duration: 113.033µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:24:15.528347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.104473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T01:24:15.528363Z","caller":"traceutil/trace.go:171","msg":"trace[727736771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"349.156074ms","start":"2026-02-02T01:24:15.179201Z","end":"2026-02-02T01:24:15.528357Z","steps":["trace[727736771] 'agreement among raft nodes before linearized reading' (duration: 349.080262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:15.528379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:15.179196Z","time spent":"349.179174ms","remote":"127.0.0.1:59572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T01:24:15.528522Z","caller":"traceutil/trace.go:171","msg":"trace[1122305810] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"371.136701ms","start":"2026-02-02T01:24:15.15738Z","end":"2026-02-02T01:24:15.528517Z","steps":["trace[1122305810] 'process raft request' (duration: 370.756293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:15.528562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:15.157372Z","time spent":"371.161911ms","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T01:24:17.706636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.565967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T01:24:17.706835Z","caller":"traceutil/trace.go:171","msg":"trace[1412864692] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"233.41071ms","start":"2026-02-02T01:24:17.473412Z","end":"2026-02-02T01:24:17.706822Z","steps":["trace[1412864692] 'process raft request' (duration: 42.604848ms)","trace[1412864692] 'compare' (duration: 190.493085ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:24:17.77364Z","caller":"traceutil/trace.go:171","msg":"trace[1150288965] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:961; }","duration":"280.184985ms","start":"2026-02-02T01:24:17.493444Z","end":"2026-02-02T01:24:17.773629Z","steps":["trace[1150288965] 'read index received' (duration: 22.578212ms)","trace[1150288965] 'applied index is now lower than readState.Index' (duration: 257.606013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:24:17.774228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.770848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T01:24:17.774254Z","caller":"traceutil/trace.go:171","msg":"trace[1067299773] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:930; }","duration":"280.825499ms","start":"2026-02-02T01:24:17.493422Z","end":"2026-02-02T01:24:17.774248Z","steps":["trace[1067299773] 'agreement among raft nodes before linearized reading' (duration: 280.771548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:17.77455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.477542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T01:24:17.77457Z","caller":"traceutil/trace.go:171","msg":"trace[1214503976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"242.515853ms","start":"2026-02-02T01:24:17.53205Z","end":"2026-02-02T01:24:17.774565Z","steps":["trace[1214503976] 'agreement among raft nodes before linearized reading' (duration: 242.434101ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:17.968792Z","caller":"traceutil/trace.go:171","msg":"trace[991179240] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"160.984527ms","start":"2026-02-02T01:24:17.807788Z","end":"2026-02-02T01:24:17.968772Z","steps":["trace[991179240] 'read index received' (duration: 160.812393ms)","trace[991179240] 'applied index is now lower than readState.Index' (duration: 171.194µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:24:17.96906Z","caller":"traceutil/trace.go:171","msg":"trace[115281278] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"180.90394ms","start":"2026-02-02T01:24:17.788145Z","end":"2026-02-02T01:24:17.969049Z","steps":["trace[115281278] 'process raft request' (duration: 180.507772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:17.969296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.492077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-02T01:24:17.969338Z","caller":"traceutil/trace.go:171","msg":"trace[191315005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"161.567478ms","start":"2026-02-02T01:24:17.807761Z","end":"2026-02-02T01:24:17.969328Z","steps":["trace[191315005] 'agreement among raft nodes before linearized reading' (duration: 161.448346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:18.347786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.131541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T01:24:18.347877Z","caller":"traceutil/trace.go:171","msg":"trace[78344439] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"186.801796ms","start":"2026-02-02T01:24:18.161059Z","end":"2026-02-02T01:24:18.347861Z","steps":["trace[78344439] 'read index received' (duration: 18.43µs)","trace[78344439] 'applied index is now lower than readState.Index' (duration: 186.781866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:24:18.347935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.873218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T01:24:18.347908Z","caller":"traceutil/trace.go:171","msg":"trace[450513242] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"371.61618ms","start":"2026-02-02T01:24:17.976266Z","end":"2026-02-02T01:24:18.347883Z","steps":["trace[450513242] 'process raft request' (duration: 131.296665ms)","trace[450513242] 'compare' (duration: 239.831025ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:24:18.347961Z","caller":"traceutil/trace.go:171","msg":"trace[1071515478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"186.918589ms","start":"2026-02-02T01:24:18.161034Z","end":"2026-02-02T01:24:18.347952Z","steps":["trace[1071515478] 'agreement among raft nodes before linearized reading' (duration: 186.870237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:18.348033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:17.97626Z","time spent":"371.707573ms","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T01:24:18.675233Z","caller":"traceutil/trace.go:171","msg":"trace[268246757] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"254.520688ms","start":"2026-02-02T01:24:18.420691Z","end":"2026-02-02T01:24:18.675212Z","steps":["trace[268246757] 'read index received' (duration: 236.035975ms)","trace[268246757] 'applied index is now lower than readState.Index' (duration: 18.483793ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:24:18.675473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.762723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T01:24:18.675509Z","caller":"traceutil/trace.go:171","msg":"trace[1906782677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"254.819614ms","start":"2026-02-02T01:24:18.42068Z","end":"2026-02-02T01:24:18.6755Z","steps":["trace[1906782677] 'agreement among raft nodes before linearized reading' (duration: 254.6275ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:18.675732Z","caller":"traceutil/trace.go:171","msg":"trace[766368926] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"255.682294ms","start":"2026-02-02T01:24:18.420038Z","end":"2026-02-02T01:24:18.67572Z","steps":["trace[766368926] 'process raft request' (duration: 236.73183ms)","trace[766368926] 'compare' (duration: 18.023163ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:24:24.220437Z","caller":"traceutil/trace.go:171","msg":"trace[1008901023] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"226.165534ms","start":"2026-02-02T01:24:23.994252Z","end":"2026-02-02T01:24:24.220418Z","steps":["trace[1008901023] 'process raft request' (duration: 226.051282ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:24.220777Z","caller":"traceutil/trace.go:171","msg":"trace[1165736141] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:994; }","duration":"196.229628ms","start":"2026-02-02T01:24:24.024539Z","end":"2026-02-02T01:24:24.220769Z","steps":["trace[1165736141] 'read index received' (duration: 196.226898ms)","trace[1165736141] 'applied index is now lower than readState.Index' (duration: 2.23µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:24:24.220887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.32985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-02-02T01:24:24.220903Z","caller":"traceutil/trace.go:171","msg":"trace[860209301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"196.38429ms","start":"2026-02-02T01:24:24.024513Z","end":"2026-02-02T01:24:24.220898Z","steps":["trace[860209301] 'agreement among raft nodes before linearized reading' (duration: 196.292318ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:24.388612Z","caller":"traceutil/trace.go:171","msg":"trace[1031323816] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"164.564083ms","start":"2026-02-02T01:24:24.224019Z","end":"2026-02-02T01:24:24.388583Z","steps":["trace[1031323816] 'process raft request' (duration: 136.844173ms)","trace[1031323816] 'compare' (duration: 27.487095ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:24:24.567201Z","caller":"traceutil/trace.go:171","msg":"trace[350114612] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"174.21327ms","start":"2026-02-02T01:24:24.392966Z","end":"2026-02-02T01:24:24.567179Z","steps":["trace[350114612] 'process raft request' (duration: 168.091099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:25.04578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.25723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-02T01:24:25.045826Z","caller":"traceutil/trace.go:171","msg":"trace[1539204526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"338.331031ms","start":"2026-02-02T01:24:24.707485Z","end":"2026-02-02T01:24:25.045816Z","steps":["trace[1539204526] 'range keys from in-memory index tree' (duration: 338.157018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:25.045847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:24.707466Z","time spent":"338.376503ms","remote":"127.0.0.1:59872","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":"warn","ts":"2026-02-02T01:24:25.045961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.417821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T01:24:25.045974Z","caller":"traceutil/trace.go:171","msg":"trace[1309942525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"328.452152ms","start":"2026-02-02T01:24:24.717517Z","end":"2026-02-02T01:24:25.045969Z","steps":["trace[1309942525] 'range keys from in-memory index tree' (duration: 328.36455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:25.045986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:24.717509Z","time spent":"328.474093ms","remote":"127.0.0.1:59872","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-02-02T01:24:25.217164Z","caller":"traceutil/trace.go:171","msg":"trace[415797333] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"166.594968ms","start":"2026-02-02T01:24:25.050544Z","end":"2026-02-02T01:24:25.217139Z","steps":["trace[415797333] 'process raft request' (duration: 166.511346ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:25.218078Z","caller":"traceutil/trace.go:171","msg":"trace[1999739715] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"165.631086ms","start":"2026-02-02T01:24:25.052437Z","end":"2026-02-02T01:24:25.218068Z","steps":["trace[1999739715] 'process raft request' (duration: 165.438572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:32.761515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.017386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-02T01:24:32.761605Z","caller":"traceutil/trace.go:171","msg":"trace[928005654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"346.113738ms","start":"2026-02-02T01:24:32.415466Z","end":"2026-02-02T01:24:32.76158Z","steps":["trace[928005654] 'range keys from in-memory index tree' (duration: 345.840123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:24:32.761646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:24:32.415302Z","time spent":"346.336323ms","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T01:24:32.763695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.139853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T01:24:32.76375Z","caller":"traceutil/trace.go:171","msg":"trace[1467984987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1004; }","duration":"148.226784ms","start":"2026-02-02T01:24:32.61551Z","end":"2026-02-02T01:24:32.763737Z","steps":["trace[1467984987] 'range keys from in-memory index tree' (duration: 145.875416ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:32.967721Z","caller":"traceutil/trace.go:171","msg":"trace[1073192256] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"199.66967ms","start":"2026-02-02T01:24:32.76802Z","end":"2026-02-02T01:24:32.967689Z","steps":["trace[1073192256] 'process raft request' (duration: 199.503437ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:32.992568Z","caller":"traceutil/trace.go:171","msg":"trace[430182493] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"223.250553ms","start":"2026-02-02T01:24:32.7692Z","end":"2026-02-02T01:24:32.99245Z","steps":["trace[430182493] 'process raft request' (duration: 222.735042ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T01:24:32.993196Z","caller":"traceutil/trace.go:171","msg":"trace[507227119] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"141.88314ms","start":"2026-02-02T01:24:32.851298Z","end":"2026-02-02T01:24:32.993181Z","steps":["trace[507227119] 'process raft request' (duration: 140.720856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:27:33.943728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.019086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T01:27:33.94402Z","caller":"traceutil/trace.go:171","msg":"trace[2015407240] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"169.6474ms","start":"2026-02-02T01:27:33.774352Z","end":"2026-02-02T01:27:33.943999Z","steps":["trace[2015407240] 'process raft request' (duration: 42.304105ms)","trace[2015407240] 'compare' (duration: 125.899804ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T01:27:37.250629Z","caller":"traceutil/trace.go:171","msg":"trace[1190964052] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"309.964779ms","start":"2026-02-02T01:27:36.940644Z","end":"2026-02-02T01:27:37.250608Z","steps":["trace[1190964052] 'process raft request' (duration: 309.846816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T01:27:37.250826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:27:36.940634Z","time spent":"310.127373ms","remote":"127.0.0.1:59784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T01:27:37.254344Z","caller":"traceutil/trace.go:171","msg":"trace[1489303977] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1878; }","duration":"312.112227ms","start":"2026-02-02T01:27:36.942216Z","end":"2026-02-02T01:27:37.254329Z","steps":["trace[1489303977] 'read index received' (duration: 312.104687ms)","trace[1489303977] 'applied index is now lower than readState.Index' (duration: 6.26µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:27:37.341335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.015398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-02T01:27:37.341548Z","caller":"traceutil/trace.go:171","msg":"trace[1201217597] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1805; }","duration":"399.370735ms","start":"2026-02-02T01:27:36.942164Z","end":"2026-02-02T01:27:37.341535Z","steps":["trace[1201217597] 'agreement among raft nodes before linearized reading' (duration: 312.341092ms)","trace[1201217597] 'range keys from in-memory index tree' (duration: 86.654985ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:27:37.341622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T01:27:36.942142Z","time spent":"399.445697ms","remote":"127.0.0.1:59754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1266,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"info","ts":"2026-02-02T01:27:37.342227Z","caller":"traceutil/trace.go:171","msg":"trace[1386871479] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"298.191772ms","start":"2026-02-02T01:27:37.044024Z","end":"2026-02-02T01:27:37.342216Z","steps":["trace[1386871479] 'process raft request' (duration: 242.926693ms)","trace[1386871479] 'compare' (duration: 54.183576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T01:27:37.3462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.219679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-02T01:27:37.346501Z","caller":"traceutil/trace.go:171","msg":"trace[608284926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1806; }","duration":"168.562377ms","start":"2026-02-02T01:27:37.177931Z","end":"2026-02-02T01:27:37.346493Z","steps":["trace[608284926] 'agreement among raft nodes before linearized reading' (duration: 168.21949ms)"],"step_count":1}