{"level":"warn","ts":"2026-02-08T22:09:53.891775Z","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-08T22:09:53.891871Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-08T22:09:53.891951Z","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-08T22:09:53.891961Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-02-08T22:09:53.891985Z","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-08T22:09:53.899134Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-02-08T22:09:53.906291Z","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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-08T22:09:53.92103Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.831401ms"} {"level":"info","ts":"2026-02-08T22:09:53.931331Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-02-08T22:09:53.931373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T22:09:53.931442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-02-08T22:09:53.931526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T22:09:53.931538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-02-08T22:09:53.9316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-02-08T22:09:53.940156Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T22:09:53.962074Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T22:09:53.964514Z","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-08T22:09:53.969573Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T22:09:53.977139Z","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-08T22:09:53.977323Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T22:09:53.977347Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T22:09:53.977488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T22:09:53.977687Z","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-08T22:09:53.977716Z","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-08T22:09:53.977722Z","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-08T22:09:53.977917Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-02-08T22:09:53.977929Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-02-08T22:09:53.980652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-02-08T22:09:53.980774Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-02-08T22:09:54.132698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T22:09:54.132735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T22:09:54.132755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-02-08T22:09:54.132765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-02-08T22:09:54.132853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-02-08T22:09:54.132892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-02-08T22:09:54.132904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-02-08T22:09:54.135969Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:54.138154Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T22:09:54.138295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:09:54.13856Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:09:54.138712Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T22:09:54.138725Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T22:09:54.139114Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:54.139205Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:54.139224Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:54.141478Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T22:09:54.142488Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-02-08T22:10:06.849472Z","caller":"traceutil/trace.go:171","msg":"trace[1981543933] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"157.92621ms","start":"2026-02-08T22:10:06.691523Z","end":"2026-02-08T22:10:06.849449Z","steps":["trace[1981543933] 'process raft request' (duration: 157.801409ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:07.750247Z","caller":"traceutil/trace.go:171","msg":"trace[190187099] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"183.976036ms","start":"2026-02-08T22:10:07.566249Z","end":"2026-02-08T22:10:07.750225Z","steps":["trace[190187099] 'process raft request' (duration: 182.494336ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:10.349917Z","caller":"traceutil/trace.go:171","msg":"trace[781130543] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"110.617274ms","start":"2026-02-08T22:10:10.239286Z","end":"2026-02-08T22:10:10.349903Z","steps":["trace[781130543] 'process raft request' (duration: 87.759706ms)","trace[781130543] 'compare' (duration: 22.754217ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:10.350119Z","caller":"traceutil/trace.go:171","msg":"trace[762228707] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"110.710404ms","start":"2026-02-08T22:10:10.239403Z","end":"2026-02-08T22:10:10.350114Z","steps":["trace[762228707] 'process raft request' (duration: 110.471613ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:10.350311Z","caller":"traceutil/trace.go:171","msg":"trace[1671134178] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"109.672747ms","start":"2026-02-08T22:10:10.240617Z","end":"2026-02-08T22:10:10.35029Z","steps":["trace[1671134178] 'process raft request' (duration: 109.395725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:18.206466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:17.717935Z","time spent":"488.527977ms","remote":"127.0.0.1:37554","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T22:10:18.206478Z","caller":"traceutil/trace.go:171","msg":"trace[1222813171] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"202.255572ms","start":"2026-02-08T22:10:18.0042Z","end":"2026-02-08T22:10:18.206455Z","steps":["trace[1222813171] 'read index received' (duration: 202.068761ms)","trace[1222813171] 'applied index is now lower than readState.Index' (duration: 185.711µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:18.20661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.392553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T22:10:18.206641Z","caller":"traceutil/trace.go:171","msg":"trace[2082408353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"202.485003ms","start":"2026-02-08T22:10:18.004145Z","end":"2026-02-08T22:10:18.20663Z","steps":["trace[2082408353] 'agreement among raft nodes before linearized reading' (duration: 202.400042ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:18.790628Z","caller":"traceutil/trace.go:171","msg":"trace[2047663261] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:479; }","duration":"373.268077ms","start":"2026-02-08T22:10:18.417343Z","end":"2026-02-08T22:10:18.790611Z","steps":["trace[2047663261] 'read index received' (duration: 365.219584ms)","trace[2047663261] 'applied index is now lower than readState.Index' (duration: 8.047793ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:18.790708Z","caller":"traceutil/trace.go:171","msg":"trace[443430837] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"568.584644ms","start":"2026-02-08T22:10:18.222116Z","end":"2026-02-08T22:10:18.790701Z","steps":["trace[443430837] 'process raft request' (duration: 568.458363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:18.791048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:18.222099Z","time spent":"568.627694ms","remote":"127.0.0.1:37780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:18.791233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.891201ms","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-08T22:10:18.79126Z","caller":"traceutil/trace.go:171","msg":"trace[1263809132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"373.951501ms","start":"2026-02-08T22:10:18.417301Z","end":"2026-02-08T22:10:18.791253Z","steps":["trace[1263809132] 'agreement among raft nodes before linearized reading' (duration: 373.902281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:18.791279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:18.417288Z","time spent":"373.987492ms","remote":"127.0.0.1:37780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:10:18.79137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.659388ms","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-08T22:10:18.791392Z","caller":"traceutil/trace.go:171","msg":"trace[1345314905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"186.713209ms","start":"2026-02-08T22:10:18.604674Z","end":"2026-02-08T22:10:18.791387Z","steps":["trace[1345314905] 'agreement among raft nodes before linearized reading' (duration: 186.677189ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:18.792083Z","caller":"traceutil/trace.go:171","msg":"trace[1347149873] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"584.794131ms","start":"2026-02-08T22:10:18.207092Z","end":"2026-02-08T22:10:18.791886Z","steps":["trace[1347149873] 'process raft request' (duration: 575.460409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:18.794598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:18.207082Z","time spent":"585.227943ms","remote":"127.0.0.1:37554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:10:22.97199Z","caller":"traceutil/trace.go:171","msg":"trace[736132826] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"155.490244ms","start":"2026-02-08T22:10:22.816477Z","end":"2026-02-08T22:10:22.971967Z","steps":["trace[736132826] 'read index received' (duration: 155.302302ms)","trace[736132826] 'applied index is now lower than readState.Index' (duration: 187.122µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:22.97229Z","caller":"traceutil/trace.go:171","msg":"trace[901757328] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"169.442384ms","start":"2026-02-08T22:10:22.802834Z","end":"2026-02-08T22:10:22.972276Z","steps":["trace[901757328] 'process raft request' (duration: 169.019211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:22.972489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.992627ms","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-08T22:10:22.972522Z","caller":"traceutil/trace.go:171","msg":"trace[1846093044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"156.049017ms","start":"2026-02-08T22:10:22.816463Z","end":"2026-02-08T22:10:22.972512Z","steps":["trace[1846093044] 'agreement among raft nodes before linearized reading' (duration: 155.976877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:22.972655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.507987ms","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-08T22:10:22.972696Z","caller":"traceutil/trace.go:171","msg":"trace[1198727410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"154.558537ms","start":"2026-02-08T22:10:22.818129Z","end":"2026-02-08T22:10:22.972688Z","steps":["trace[1198727410] 'agreement among raft nodes before linearized reading' (duration: 154.497857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:22.973339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.805817ms","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-08T22:10:22.973384Z","caller":"traceutil/trace.go:171","msg":"trace[646289537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"146.862248ms","start":"2026-02-08T22:10:22.82651Z","end":"2026-02-08T22:10:22.973372Z","steps":["trace[646289537] 'agreement among raft nodes before linearized reading' (duration: 146.781287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:29.767459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.939334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v22qg\" ","response":"range_response_count:1 size:16656"} {"level":"warn","ts":"2026-02-08T22:10:29.767483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.613591ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T22:10:29.767542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.76918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:29.767557Z","caller":"traceutil/trace.go:171","msg":"trace[500527318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"261.80963ms","start":"2026-02-08T22:10:29.505743Z","end":"2026-02-08T22:10:29.767553Z","steps":["trace[500527318] 'range keys from in-memory index tree' (duration: 261.719179ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:29.767556Z","caller":"traceutil/trace.go:171","msg":"trace[1661607173] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:556; }","duration":"418.707422ms","start":"2026-02-08T22:10:29.348833Z","end":"2026-02-08T22:10:29.76754Z","steps":["trace[1661607173] 'range keys from in-memory index tree' (duration: 418.592941ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:29.767519Z","caller":"traceutil/trace.go:171","msg":"trace[1182797896] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v22qg; range_end:; response_count:1; response_revision:556; }","duration":"170.041015ms","start":"2026-02-08T22:10:29.597463Z","end":"2026-02-08T22:10:29.767504Z","steps":["trace[1182797896] 'range keys from in-memory index tree' (duration: 169.768432ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:30.026951Z","caller":"traceutil/trace.go:171","msg":"trace[302884048] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"250.256333ms","start":"2026-02-08T22:10:29.776675Z","end":"2026-02-08T22:10:30.026932Z","steps":["trace[302884048] 'process raft request' (duration: 250.150428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:31.511803Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388242222616485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:10:32.012788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388242222616485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:10:32.233054Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.645714752s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-08T22:10:32.328254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:31.573057Z","time spent":"755.192557ms","remote":"127.0.0.1:58848","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T22:10:32.328836Z","caller":"traceutil/trace.go:171","msg":"trace[194649380] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"1.318139085s","start":"2026-02-08T22:10:31.010679Z","end":"2026-02-08T22:10:32.328818Z","steps":["trace[194649380] 'read index received' (duration: 1.22247602s)","trace[194649380] 'applied index is now lower than readState.Index' (duration: 95.662375ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:32.330175Z","caller":"traceutil/trace.go:171","msg":"trace[44014696] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.742934106s","start":"2026-02-08T22:10:30.587228Z","end":"2026-02-08T22:10:32.330162Z","steps":["trace[44014696] 'process raft request' (duration: 1.645978364s)","trace[44014696] 'compare' (duration: 95.530499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:32.330296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:30.587205Z","time spent":"1.74301966s","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4345,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:32.330456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319777888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T22:10:32.330479Z","caller":"traceutil/trace.go:171","msg":"trace[1911003915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"1.31982641s","start":"2026-02-08T22:10:31.010645Z","end":"2026-02-08T22:10:32.330472Z","steps":["trace[1911003915] 'agreement among raft nodes before linearized reading' (duration: 1.319724305s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:32.330502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:31.01063Z","time spent":"1.319866221s","remote":"127.0.0.1:37780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T22:10:32.33133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246187647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-08T22:10:32.331408Z","caller":"traceutil/trace.go:171","msg":"trace[356055957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"1.246275751s","start":"2026-02-08T22:10:31.085119Z","end":"2026-02-08T22:10:32.331395Z","steps":["trace[356055957] 'agreement among raft nodes before linearized reading' (duration: 1.246079652s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:32.331451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:31.085072Z","time spent":"1.246372835s","remote":"127.0.0.1:37780","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-02-08T22:10:32.332793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.183493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:32.33284Z","caller":"traceutil/trace.go:171","msg":"trace[1540047788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"827.251256ms","start":"2026-02-08T22:10:31.50558Z","end":"2026-02-08T22:10:32.332831Z","steps":["trace[1540047788] 'agreement among raft nodes before linearized reading' (duration: 827.192694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:32.33287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:31.505567Z","time spent":"827.298319ms","remote":"127.0.0.1:37532","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-08T22:10:32.33304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220725658s","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-08T22:10:32.333108Z","caller":"traceutil/trace.go:171","msg":"trace[1987844357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"1.220818972s","start":"2026-02-08T22:10:31.112283Z","end":"2026-02-08T22:10:32.333102Z","steps":["trace[1987844357] 'agreement among raft nodes before linearized reading' (duration: 1.220710027s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:32.333161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:31.112266Z","time spent":"1.220889585s","remote":"127.0.0.1:37780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T22:10:32.333253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236038493s","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-08T22:10:32.333285Z","caller":"traceutil/trace.go:171","msg":"trace[584997171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"1.236106835s","start":"2026-02-08T22:10:31.097173Z","end":"2026-02-08T22:10:32.333279Z","steps":["trace[584997171] 'agreement among raft nodes before linearized reading' (duration: 1.236030862s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:32.333313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:31.097151Z","time spent":"1.236155987s","remote":"127.0.0.1:37780","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":"info","ts":"2026-02-08T22:10:37.027669Z","caller":"traceutil/trace.go:171","msg":"trace[1153011615] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"158.775038ms","start":"2026-02-08T22:10:36.868877Z","end":"2026-02-08T22:10:37.027652Z","steps":["trace[1153011615] 'process raft request' (duration: 156.440891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:37.029582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.541668ms","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-02-08T22:10:37.029636Z","caller":"traceutil/trace.go:171","msg":"trace[1519841594] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:633; }","duration":"148.633241ms","start":"2026-02-08T22:10:36.880992Z","end":"2026-02-08T22:10:37.029625Z","steps":["trace[1519841594] 'agreement among raft nodes before linearized reading' (duration: 148.464164ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:37.030106Z","caller":"traceutil/trace.go:171","msg":"trace[714236370] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"145.909448ms","start":"2026-02-08T22:10:36.881021Z","end":"2026-02-08T22:10:37.02693Z","steps":["trace[714236370] 'read index received' (duration: 144.328452ms)","trace[714236370] 'applied index is now lower than readState.Index' (duration: 1.580326ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:12:00.083603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.175138ms","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-02-08T22:12:00.08379Z","caller":"traceutil/trace.go:171","msg":"trace[676426322] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:997; }","duration":"735.373601ms","start":"2026-02-08T22:11:59.348396Z","end":"2026-02-08T22:12:00.08377Z","steps":["trace[676426322] 'range keys from in-memory index tree' (duration: 735.157867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:12:00.08416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.082128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:12:00.084331Z","caller":"traceutil/trace.go:171","msg":"trace[215244552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"576.373883ms","start":"2026-02-08T22:11:59.507905Z","end":"2026-02-08T22:12:00.084279Z","steps":["trace[215244552] 'range keys from in-memory index tree' (duration: 576.003285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:12:00.08438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:11:59.507892Z","time spent":"576.475794ms","remote":"127.0.0.1:37532","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-08T22:12:01.318447Z","caller":"traceutil/trace.go:171","msg":"trace[2014992677] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"482.572401ms","start":"2026-02-08T22:12:00.835848Z","end":"2026-02-08T22:12:01.31842Z","steps":["trace[2014992677] 'process raft request' (duration: 482.323297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:12:01.318646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:12:00.835832Z","time spent":"482.731794ms","remote":"127.0.0.1:37780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:12:01.365399Z","caller":"traceutil/trace.go:171","msg":"trace[478057487] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"481.18906ms","start":"2026-02-08T22:12:00.884195Z","end":"2026-02-08T22:12:01.365384Z","steps":["trace[478057487] 'read index received' (duration: 434.85947ms)","trace[478057487] 'applied index is now lower than readState.Index' (duration: 46.32894ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:12:01.365717Z","caller":"traceutil/trace.go:171","msg":"trace[527074719] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"527.161588ms","start":"2026-02-08T22:12:00.838531Z","end":"2026-02-08T22:12:01.365693Z","steps":["trace[527074719] 'process raft request' (duration: 526.780271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:12:01.367676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.71378ms","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-08T22:12:01.369492Z","caller":"traceutil/trace.go:171","msg":"trace[1347556054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"410.536353ms","start":"2026-02-08T22:12:00.958943Z","end":"2026-02-08T22:12:01.369479Z","steps":["trace[1347556054] 'agreement among raft nodes before linearized reading' (duration: 408.617218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:12:01.369637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:12:00.958926Z","time spent":"410.684686ms","remote":"127.0.0.1:37780","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-08T22:12:01.367889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.956977ms","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-08T22:12:01.370085Z","caller":"traceutil/trace.go:171","msg":"trace[1563412310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"160.188647ms","start":"2026-02-08T22:12:01.209887Z","end":"2026-02-08T22:12:01.370076Z","steps":["trace[1563412310] 'agreement among raft nodes before linearized reading' (duration: 157.953136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:12:01.36807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.872508ms","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-08T22:12:01.370462Z","caller":"traceutil/trace.go:171","msg":"trace[1805747007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"486.287832ms","start":"2026-02-08T22:12:00.884164Z","end":"2026-02-08T22:12:01.370452Z","steps":["trace[1805747007] 'agreement among raft nodes before linearized reading' (duration: 483.857898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:12:01.370524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:12:00.884151Z","time spent":"486.362883ms","remote":"127.0.0.1:37780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:12:01.372849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:12:00.838522Z","time spent":"529.630854ms","remote":"127.0.0.1:37780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}