{"level":"warn","ts":"2026-10-14T02:21:23.515307Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T02:21:23.515438Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.114: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.114:2380","--initial-cluster=instance=https://162.253.55.114:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.114:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.114: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-10-14T02:21:23.515633Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T02:21:23.515663Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.114:2380"]} {"level":"info","ts":"2026-10-14T02:21:23.515705Z","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-10-14T02:21:23.517325Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.114:2379"]} {"level":"info","ts":"2026-10-14T02:21:23.517551Z","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":8,"max-cpu-available":8,"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.114:2380"],"listen-peer-urls":["https://162.253.55.114:2380"],"advertise-client-urls":["https://162.253.55.114:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.114:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.114: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-10-14T02:21:23.520942Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.895158ms"} {"level":"info","ts":"2026-10-14T02:21:23.528459Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3bc1eef27555bbee","cluster-id":"8fe2b95d877f0557"} {"level":"info","ts":"2026-10-14T02:21:23.528571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T02:21:23.52861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee became follower at term 0"} {"level":"info","ts":"2026-10-14T02:21:23.528646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3bc1eef27555bbee [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T02:21:23.528678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee became follower at term 1"} {"level":"info","ts":"2026-10-14T02:21:23.528746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee switched to configuration voters=(4305985443860954094)"} {"level":"warn","ts":"2026-10-14T02:21:23.535987Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T02:21:23.538765Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T02:21:23.541117Z","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-10-14T02:21:23.545367Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3bc1eef27555bbee","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T02:21:23.545844Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3bc1eef27555bbee","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T02:21:23.545926Z","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-10-14T02:21:23.54599Z","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-10-14T02:21:23.546001Z","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-10-14T02:21:23.548798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee switched to configuration voters=(4305985443860954094)"} {"level":"info","ts":"2026-10-14T02:21:23.549923Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8fe2b95d877f0557","local-member-id":"3bc1eef27555bbee","added-peer-id":"3bc1eef27555bbee","added-peer-peer-urls":["https://162.253.55.114:2380"]} {"level":"info","ts":"2026-10-14T02:21:23.551113Z","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-10-14T02:21:23.551275Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3bc1eef27555bbee","initial-advertise-peer-urls":["https://162.253.55.114:2380"],"listen-peer-urls":["https://162.253.55.114:2380"],"advertise-client-urls":["https://162.253.55.114:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.114:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T02:21:23.551302Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T02:21:23.551659Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.114:2380"} {"level":"info","ts":"2026-10-14T02:21:23.551696Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.114:2380"} {"level":"info","ts":"2026-10-14T02:21:24.029526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T02:21:24.029594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T02:21:24.029648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee received MsgPreVoteResp from 3bc1eef27555bbee at term 1"} {"level":"info","ts":"2026-10-14T02:21:24.029667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee became candidate at term 2"} {"level":"info","ts":"2026-10-14T02:21:24.029679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee received MsgVoteResp from 3bc1eef27555bbee at term 2"} {"level":"info","ts":"2026-10-14T02:21:24.029694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3bc1eef27555bbee became leader at term 2"} {"level":"info","ts":"2026-10-14T02:21:24.029707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3bc1eef27555bbee elected leader 3bc1eef27555bbee at term 2"} {"level":"info","ts":"2026-10-14T02:21:24.040613Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:21:24.043163Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3bc1eef27555bbee","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.114:2379]}","request-path":"/0/members/3bc1eef27555bbee/attributes","cluster-id":"8fe2b95d877f0557","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T02:21:24.043165Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T02:21:24.043252Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T02:21:24.043471Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T02:21:24.043656Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T02:21:24.044127Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8fe2b95d877f0557","local-member-id":"3bc1eef27555bbee","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:21:24.044287Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:21:24.044345Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:21:24.044993Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.114:2379"} {"level":"info","ts":"2026-10-14T02:21:24.047588Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-14T02:21:33.200591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.858074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:21:33.200747Z","caller":"traceutil/trace.go:171","msg":"trace[1544006886] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"124.335999ms","start":"2026-10-14T02:21:33.07639Z","end":"2026-10-14T02:21:33.200726Z","steps":["trace[1544006886] 'read index received' (duration: 6.688587ms)","trace[1544006886] 'applied index is now lower than readState.Index' (duration: 117.645942ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:21:33.200837Z","caller":"traceutil/trace.go:171","msg":"trace[1057690129] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"165.627135ms","start":"2026-10-14T02:21:33.035168Z","end":"2026-10-14T02:21:33.200795Z","steps":["trace[1057690129] 'process raft request' (duration: 47.945452ms)","trace[1057690129] 'compare' (duration: 116.69016ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:21:33.200977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.045319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-10-14T02:21:33.201026Z","caller":"traceutil/trace.go:171","msg":"trace[19841260] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:329; }","duration":"123.09882ms","start":"2026-10-14T02:21:33.077911Z","end":"2026-10-14T02:21:33.20101Z","steps":["trace[19841260] 'agreement among raft nodes before linearized reading' (duration: 122.955487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:21:33.201033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.600815ms","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-10-14T02:21:33.201168Z","caller":"traceutil/trace.go:171","msg":"trace[1830353316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:329; }","duration":"124.787889ms","start":"2026-10-14T02:21:33.076353Z","end":"2026-10-14T02:21:33.201141Z","steps":["trace[1830353316] 'agreement among raft nodes before linearized reading' (duration: 124.467211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:21:39.862202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.520312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:21:39.862329Z","caller":"traceutil/trace.go:171","msg":"trace[745695072] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"524.695615ms","start":"2026-10-14T02:21:39.337602Z","end":"2026-10-14T02:21:39.862298Z","steps":["trace[745695072] 'process raft request' (duration: 105.693642ms)","trace[745695072] 'compare' (duration: 417.944158ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:21:39.862406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:21:39.337573Z","time spent":"524.797798ms","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T02:21:40.547355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.417779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:21:40.547459Z","caller":"traceutil/trace.go:171","msg":"trace[953348923] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"579.960828ms","start":"2026-10-14T02:21:39.967481Z","end":"2026-10-14T02:21:40.547442Z","steps":["trace[953348923] 'read index received' (duration: 145.633307ms)","trace[953348923] 'applied index is now lower than readState.Index' (duration: 434.326301ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:21:40.547543Z","caller":"traceutil/trace.go:171","msg":"trace[1227835264] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"647.426107ms","start":"2026-10-14T02:21:39.900105Z","end":"2026-10-14T02:21:40.547531Z","steps":["trace[1227835264] 'process raft request' (duration: 213.055865ms)","trace[1227835264] 'compare' (duration: 433.308747ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:21:40.547602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:21:39.900089Z","time spent":"647.482858ms","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T02:21:40.547618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.769603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:21:40.547654Z","caller":"traceutil/trace.go:171","msg":"trace[578966596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"396.840384ms","start":"2026-10-14T02:21:40.1508Z","end":"2026-10-14T02:21:40.54764Z","steps":["trace[578966596] 'agreement among raft nodes before linearized reading' (duration: 396.777443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:21:40.547638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.136342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-14T02:21:40.547684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:21:40.150781Z","time spent":"396.897735ms","remote":"127.0.0.1:52650","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-10-14T02:21:40.547696Z","caller":"traceutil/trace.go:171","msg":"trace[270594731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"580.233824ms","start":"2026-10-14T02:21:39.967447Z","end":"2026-10-14T02:21:40.547681Z","steps":["trace[270594731] 'agreement among raft nodes before linearized reading' (duration: 580.112512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:21:40.547682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.131072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-10-14T02:21:40.547798Z","caller":"traceutil/trace.go:171","msg":"trace[903832406] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:371; }","duration":"403.288865ms","start":"2026-10-14T02:21:40.144484Z","end":"2026-10-14T02:21:40.547773Z","steps":["trace[903832406] 'agreement among raft nodes before linearized reading' (duration: 403.07887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:21:40.547731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:21:39.967428Z","time spent":"580.297866ms","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T02:21:40.547865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:21:40.144469Z","time spent":"403.371207ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-10-14T02:21:40.681102Z","caller":"traceutil/trace.go:171","msg":"trace[638392653] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"126.862888ms","start":"2026-10-14T02:21:40.554209Z","end":"2026-10-14T02:21:40.681072Z","steps":["trace[638392653] 'process raft request' (duration: 122.954857ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:22:14.977679Z","caller":"traceutil/trace.go:171","msg":"trace[41314119] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"102.40741ms","start":"2026-10-14T02:22:14.875249Z","end":"2026-10-14T02:22:14.977656Z","steps":["trace[41314119] 'process raft request' (duration: 61.033053ms)","trace[41314119] 'compare' (duration: 41.190523ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:22:14.99086Z","caller":"traceutil/trace.go:171","msg":"trace[2041615398] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"115.345676ms","start":"2026-10-14T02:22:14.8755Z","end":"2026-10-14T02:22:14.990846Z","steps":["trace[2041615398] 'process raft request' (duration: 114.991037ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:22:14.990992Z","caller":"traceutil/trace.go:171","msg":"trace[455484290] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"112.028027ms","start":"2026-10-14T02:22:14.878944Z","end":"2026-10-14T02:22:14.990972Z","steps":["trace[455484290] 'process raft request' (duration: 111.723099ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:22:19.761901Z","caller":"traceutil/trace.go:171","msg":"trace[354558433] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"270.144493ms","start":"2026-10-14T02:22:19.491726Z","end":"2026-10-14T02:22:19.76187Z","steps":["trace[354558433] 'read index received' (duration: 268.885083ms)","trace[354558433] 'applied index is now lower than readState.Index' (duration: 1.25808ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:22:19.761961Z","caller":"traceutil/trace.go:171","msg":"trace[1651807978] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"296.322861ms","start":"2026-10-14T02:22:19.4656Z","end":"2026-10-14T02:22:19.761922Z","steps":["trace[1651807978] 'process raft request' (duration: 295.312737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:22:19.76214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.373338ms","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-10-14T02:22:19.762201Z","caller":"traceutil/trace.go:171","msg":"trace[356437415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"270.47442ms","start":"2026-10-14T02:22:19.49171Z","end":"2026-10-14T02:22:19.762185Z","steps":["trace[356437415] 'agreement among raft nodes before linearized reading' (duration: 270.324687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:22:21.041344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.52684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:22:21.041449Z","caller":"traceutil/trace.go:171","msg":"trace[687860650] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"228.488349ms","start":"2026-10-14T02:22:20.812945Z","end":"2026-10-14T02:22:21.041433Z","steps":["trace[687860650] 'read index received' (duration: 7.58155ms)","trace[687860650] 'applied index is now lower than readState.Index' (duration: 220.905659ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:22:21.041737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.792896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T02:22:21.041773Z","caller":"traceutil/trace.go:171","msg":"trace[390665781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"228.878018ms","start":"2026-10-14T02:22:20.812886Z","end":"2026-10-14T02:22:21.041764Z","steps":["trace[390665781] 'agreement among raft nodes before linearized reading' (duration: 228.637872ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:22:21.042037Z","caller":"traceutil/trace.go:171","msg":"trace[392727508] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"257.861992ms","start":"2026-10-14T02:22:20.784126Z","end":"2026-10-14T02:22:21.041988Z","steps":["trace[392727508] 'process raft request' (duration: 36.608725ms)","trace[392727508] 'compare' (duration: 220.146961ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:22:21.421383Z","caller":"traceutil/trace.go:171","msg":"trace[28373519] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"371.823275ms","start":"2026-10-14T02:22:21.049547Z","end":"2026-10-14T02:22:21.42137Z","steps":["trace[28373519] 'read index received' (duration: 354.476735ms)","trace[28373519] 'applied index is now lower than readState.Index' (duration: 17.34523ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:22:21.421594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.02882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rfkk4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T02:22:21.42171Z","caller":"traceutil/trace.go:171","msg":"trace[529696311] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rfkk4; range_end:; response_count:1; response_revision:622; }","duration":"372.186164ms","start":"2026-10-14T02:22:21.049512Z","end":"2026-10-14T02:22:21.421698Z","steps":["trace[529696311] 'agreement among raft nodes before linearized reading' (duration: 371.962989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:22:21.421745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:22:21.049501Z","time spent":"372.232445ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rfkk4\" "} {"level":"info","ts":"2026-10-14T02:22:21.421385Z","caller":"traceutil/trace.go:171","msg":"trace[1502439711] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"372.174514ms","start":"2026-10-14T02:22:21.049184Z","end":"2026-10-14T02:22:21.421359Z","steps":["trace[1502439711] 'process raft request' (duration: 354.709111ms)","trace[1502439711] 'compare' (duration: 17.255748ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:22:21.422141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.182911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:22:21.422232Z","caller":"traceutil/trace.go:171","msg":"trace[92993420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"272.293853ms","start":"2026-10-14T02:22:21.149922Z","end":"2026-10-14T02:22:21.422216Z","steps":["trace[92993420] 'agreement among raft nodes before linearized reading' (duration: 272.182401ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:22:21.422362Z","caller":"traceutil/trace.go:171","msg":"trace[1036218357] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"308.263063ms","start":"2026-10-14T02:22:21.113062Z","end":"2026-10-14T02:22:21.421325Z","steps":["trace[1036218357] 'process raft request' (duration: 308.216942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:22:21.422469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:22:21.113041Z","time spent":"309.37238ms","remote":"127.0.0.1:53000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T02:22:21.422442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:22:21.049162Z","time spent":"372.831209ms","remote":"127.0.0.1:52912","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-10-14T02:23:18.051682Z","caller":"traceutil/trace.go:171","msg":"trace[319567523] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"292.730869ms","start":"2026-10-14T02:23:17.758937Z","end":"2026-10-14T02:23:18.051668Z","steps":["trace[319567523] 'process raft request' (duration: 292.535474ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:18.051918Z","caller":"traceutil/trace.go:171","msg":"trace[1595310975] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"208.960225ms","start":"2026-10-14T02:23:17.842676Z","end":"2026-10-14T02:23:18.051636Z","steps":["trace[1595310975] 'read index received' (duration: 208.917224ms)","trace[1595310975] 'applied index is now lower than readState.Index' (duration: 42.481µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:23:18.051877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.16663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:23:18.051993Z","caller":"traceutil/trace.go:171","msg":"trace[1759579920] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1078; }","duration":"209.328643ms","start":"2026-10-14T02:23:17.842643Z","end":"2026-10-14T02:23:18.051971Z","steps":["trace[1759579920] 'agreement among raft nodes before linearized reading' (duration: 209.139988ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:39.466524Z","caller":"traceutil/trace.go:171","msg":"trace[2001915305] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"208.678147ms","start":"2026-10-14T02:23:39.257813Z","end":"2026-10-14T02:23:39.466491Z","steps":["trace[2001915305] 'process raft request' (duration: 208.463852ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:39.582005Z","caller":"traceutil/trace.go:171","msg":"trace[1767720402] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1232; }","duration":"299.568339ms","start":"2026-10-14T02:23:39.28241Z","end":"2026-10-14T02:23:39.581978Z","steps":["trace[1767720402] 'read index received' (duration: 183.990385ms)","trace[1767720402] 'applied index is now lower than readState.Index' (duration: 115.576814ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:23:39.582131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.693322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:23:39.582187Z","caller":"traceutil/trace.go:171","msg":"trace[687390254] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1184; }","duration":"299.771044ms","start":"2026-10-14T02:23:39.282402Z","end":"2026-10-14T02:23:39.582173Z","steps":["trace[687390254] 'agreement among raft nodes before linearized reading' (duration: 299.671812ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:39.582264Z","caller":"traceutil/trace.go:171","msg":"trace[840784455] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"324.277412ms","start":"2026-10-14T02:23:39.257947Z","end":"2026-10-14T02:23:39.582224Z","steps":["trace[840784455] 'process raft request' (duration: 302.463028ms)","trace[840784455] 'compare' (duration: 21.436155ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:23:39.58246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:23:39.257911Z","time spent":"324.455647ms","remote":"127.0.0.1:52912","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-10-14T02:23:40.146693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.170166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:23:40.146759Z","caller":"traceutil/trace.go:171","msg":"trace[2068919147] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1186; }","duration":"166.296759ms","start":"2026-10-14T02:23:39.980448Z","end":"2026-10-14T02:23:40.146744Z","steps":["trace[2068919147] 'range keys from in-memory index tree' (duration: 166.066684ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:40.316068Z","caller":"traceutil/trace.go:171","msg":"trace[289489621] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"164.850105ms","start":"2026-10-14T02:23:40.151191Z","end":"2026-10-14T02:23:40.316041Z","steps":["trace[289489621] 'read index received' (duration: 164.793444ms)","trace[289489621] 'applied index is now lower than readState.Index' (duration: 55.421µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:23:40.31611Z","caller":"traceutil/trace.go:171","msg":"trace[967566574] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"165.50201ms","start":"2026-10-14T02:23:40.150587Z","end":"2026-10-14T02:23:40.316089Z","steps":["trace[967566574] 'process raft request' (duration: 165.322286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:23:40.316315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.097081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:23:40.316405Z","caller":"traceutil/trace.go:171","msg":"trace[32272755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1187; }","duration":"165.230224ms","start":"2026-10-14T02:23:40.151155Z","end":"2026-10-14T02:23:40.316385Z","steps":["trace[32272755] 'agreement among raft nodes before linearized reading' (duration: 165.000789ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:40.458116Z","caller":"traceutil/trace.go:171","msg":"trace[1594772223] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1237; }","duration":"141.970717ms","start":"2026-10-14T02:23:40.316124Z","end":"2026-10-14T02:23:40.458095Z","steps":["trace[1594772223] 'read index received' (duration: 141.800393ms)","trace[1594772223] 'applied index is now lower than readState.Index' (duration: 169.524µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:23:40.458214Z","caller":"traceutil/trace.go:171","msg":"trace[592053881] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"295.331921ms","start":"2026-10-14T02:23:40.162871Z","end":"2026-10-14T02:23:40.458203Z","steps":["trace[592053881] 'process raft request' (duration: 294.987023ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:40.458746Z","caller":"traceutil/trace.go:171","msg":"trace[1649546208] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"235.372458ms","start":"2026-10-14T02:23:40.223361Z","end":"2026-10-14T02:23:40.458733Z","steps":["trace[1649546208] 'process raft request' (duration: 234.676031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:23:40.459021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.099642ms","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-10-14T02:23:40.459068Z","caller":"traceutil/trace.go:171","msg":"trace[1451089770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"210.183104ms","start":"2026-10-14T02:23:40.248872Z","end":"2026-10-14T02:23:40.459055Z","steps":["trace[1451089770] 'agreement among raft nodes before linearized reading' (duration: 210.00664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:23:40.459258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.983238ms","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-10-14T02:23:40.459293Z","caller":"traceutil/trace.go:171","msg":"trace[308477204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"229.045159ms","start":"2026-10-14T02:23:40.230239Z","end":"2026-10-14T02:23:40.459284Z","steps":["trace[308477204] 'agreement among raft nodes before linearized reading' (duration: 228.956516ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:40.462647Z","caller":"traceutil/trace.go:171","msg":"trace[1953755935] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"139.451327ms","start":"2026-10-14T02:23:40.323173Z","end":"2026-10-14T02:23:40.462625Z","steps":["trace[1953755935] 'process raft request' (duration: 139.218282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:23:40.462744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.019634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-10-14T02:23:40.462783Z","caller":"traceutil/trace.go:171","msg":"trace[895897408] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1190; }","duration":"141.077446ms","start":"2026-10-14T02:23:40.321693Z","end":"2026-10-14T02:23:40.462771Z","steps":["trace[895897408] 'agreement among raft nodes before linearized reading' (duration: 140.961823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:23:40.463248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.071519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:23:40.464881Z","caller":"traceutil/trace.go:171","msg":"trace[1877506532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1190; }","duration":"145.856688ms","start":"2026-10-14T02:23:40.317441Z","end":"2026-10-14T02:23:40.463298Z","steps":["trace[1877506532] 'agreement among raft nodes before linearized reading' (duration: 145.040229ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:49.799549Z","caller":"traceutil/trace.go:171","msg":"trace[1810687589] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"181.803264ms","start":"2026-10-14T02:23:49.617726Z","end":"2026-10-14T02:23:49.799529Z","steps":["trace[1810687589] 'read index received' (duration: 181.699762ms)","trace[1810687589] 'applied index is now lower than readState.Index' (duration: 102.562µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:23:49.799646Z","caller":"traceutil/trace.go:171","msg":"trace[1947639024] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"281.017184ms","start":"2026-10-14T02:23:49.518601Z","end":"2026-10-14T02:23:49.799618Z","steps":["trace[1947639024] 'process raft request' (duration: 280.806549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:23:49.799698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.958029ms","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-10-14T02:23:49.799747Z","caller":"traceutil/trace.go:171","msg":"trace[1196294932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"182.05237ms","start":"2026-10-14T02:23:49.617684Z","end":"2026-10-14T02:23:49.799736Z","steps":["trace[1196294932] 'agreement among raft nodes before linearized reading' (duration: 181.912657ms)"],"step_count":1}