{"level":"warn","ts":"2026-07-02T05:12:32.051343Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:12:32.051444Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.220: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://199.19.213.220:2380","--initial-cluster=instance=https://199.19.213.220:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.220:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.220: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-07-02T05:12:32.051527Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:12:32.051537Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.220:2380"]} {"level":"info","ts":"2026-07-02T05:12:32.051558Z","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-07-02T05:12:32.052179Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.220:2379"]} {"level":"info","ts":"2026-07-02T05:12:32.052255Z","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://199.19.213.220:2380"],"listen-peer-urls":["https://199.19.213.220:2380"],"advertise-client-urls":["https://199.19.213.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.220: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-07-02T05:12:32.055965Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.481713ms"} {"level":"info","ts":"2026-07-02T05:12:32.065256Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab3c4fae41c4ba3","cluster-id":"574139c3944fcfd2"} {"level":"info","ts":"2026-07-02T05:12:32.065397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:12:32.065438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became follower at term 0"} {"level":"info","ts":"2026-07-02T05:12:32.065453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab3c4fae41c4ba3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:12:32.065493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became follower at term 1"} {"level":"info","ts":"2026-07-02T05:12:32.065535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 switched to configuration voters=(771176543058414499)"} {"level":"warn","ts":"2026-07-02T05:12:32.072325Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:12:32.073753Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:12:32.074946Z","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-07-02T05:12:32.07749Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab3c4fae41c4ba3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:12:32.077784Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab3c4fae41c4ba3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:12:32.077994Z","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-07-02T05:12:32.07803Z","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-07-02T05:12:32.078043Z","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-07-02T05:12:32.078805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 switched to configuration voters=(771176543058414499)"} {"level":"info","ts":"2026-07-02T05:12:32.08073Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"574139c3944fcfd2","local-member-id":"ab3c4fae41c4ba3","added-peer-id":"ab3c4fae41c4ba3","added-peer-peer-urls":["https://199.19.213.220:2380"]} {"level":"info","ts":"2026-07-02T05:12:32.083912Z","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-07-02T05:12:32.084103Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.220:2380"} {"level":"info","ts":"2026-07-02T05:12:32.08421Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.220:2380"} {"level":"info","ts":"2026-07-02T05:12:32.084456Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab3c4fae41c4ba3","initial-advertise-peer-urls":["https://199.19.213.220:2380"],"listen-peer-urls":["https://199.19.213.220:2380"],"advertise-client-urls":["https://199.19.213.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:12:32.084558Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:12:32.76628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:12:32.766341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:12:32.766391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 received MsgPreVoteResp from ab3c4fae41c4ba3 at term 1"} {"level":"info","ts":"2026-07-02T05:12:32.766421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:12:32.766439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 received MsgVoteResp from ab3c4fae41c4ba3 at term 2"} {"level":"info","ts":"2026-07-02T05:12:32.766461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became leader at term 2"} {"level":"info","ts":"2026-07-02T05:12:32.766481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab3c4fae41c4ba3 elected leader ab3c4fae41c4ba3 at term 2"} {"level":"info","ts":"2026-07-02T05:12:32.767975Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab3c4fae41c4ba3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.220:2379]}","request-path":"/0/members/ab3c4fae41c4ba3/attributes","cluster-id":"574139c3944fcfd2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:12:32.768046Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:12:32.768066Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:12:32.768411Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:12:32.768414Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:12:32.768494Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:12:32.769752Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"574139c3944fcfd2","local-member-id":"ab3c4fae41c4ba3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:12:32.769835Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:12:32.769857Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:12:32.772409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.220:2379"} {"level":"info","ts":"2026-07-02T05:12:32.772594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:12:37.691475Z","caller":"traceutil/trace.go:171","msg":"trace[1018358840] transaction","detail":"{read_only:false; number_of_response:0; response_revision:255; }","duration":"100.913825ms","start":"2026-07-02T05:12:37.590531Z","end":"2026-07-02T05:12:37.691445Z","steps":["trace[1018358840] 'process raft request' (duration: 100.69836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:38.111934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.820062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:12:38.112177Z","caller":"traceutil/trace.go:171","msg":"trace[438987159] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"411.809391ms","start":"2026-07-02T05:12:37.700349Z","end":"2026-07-02T05:12:38.112158Z","steps":["trace[438987159] 'process raft request' (duration: 411.75338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:38.112242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:37.700328Z","time spent":"411.888003ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:12:38.112328Z","caller":"traceutil/trace.go:171","msg":"trace[455810214] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:268; }","duration":"419.088606ms","start":"2026-07-02T05:12:37.69318Z","end":"2026-07-02T05:12:38.112269Z","steps":["trace[455810214] 'read index received' (duration: 160.882594ms)","trace[455810214] 'applied index is now lower than readState.Index' (duration: 258.201911ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:38.11242Z","caller":"traceutil/trace.go:171","msg":"trace[222544551] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"444.634916ms","start":"2026-07-02T05:12:37.667711Z","end":"2026-07-02T05:12:38.112346Z","steps":["trace[222544551] 'process raft request' (duration: 186.356043ms)","trace[222544551] 'compare' (duration: 256.639338ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:38.112593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:37.667694Z","time spent":"444.81872ms","remote":"127.0.0.1:60410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:12:38.112653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.938157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-07-02T05:12:38.112667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.450153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-07-02T05:12:38.112691Z","caller":"traceutil/trace.go:171","msg":"trace[406226340] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"415.016419ms","start":"2026-07-02T05:12:37.697664Z","end":"2026-07-02T05:12:38.11268Z","steps":["trace[406226340] 'agreement among raft nodes before linearized reading' (duration: 414.915447ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:38.11271Z","caller":"traceutil/trace.go:171","msg":"trace[1648099535] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:261; }","duration":"419.608496ms","start":"2026-07-02T05:12:37.693088Z","end":"2026-07-02T05:12:38.112696Z","steps":["trace[1648099535] 'agreement among raft nodes before linearized reading' (duration: 419.390812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:38.112728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:37.697654Z","time spent":"415.06847ms","remote":"127.0.0.1:60444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-02T05:12:38.112753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:37.693039Z","time spent":"419.704188ms","remote":"127.0.0.1:60532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-02T05:12:38.112836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.023815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-07-02T05:12:38.112925Z","caller":"traceutil/trace.go:171","msg":"trace[856061158] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:261; }","duration":"419.137867ms","start":"2026-07-02T05:12:37.693762Z","end":"2026-07-02T05:12:38.1129Z","steps":["trace[856061158] 'agreement among raft nodes before linearized reading' (duration: 418.83278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:38.112963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:37.693756Z","time spent":"419.199908ms","remote":"127.0.0.1:60542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4002,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-07-02T05:12:38.113174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.458653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-07-02T05:12:38.113204Z","caller":"traceutil/trace.go:171","msg":"trace[26912446] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:261; }","duration":"419.508225ms","start":"2026-07-02T05:12:37.693687Z","end":"2026-07-02T05:12:38.113195Z","steps":["trace[26912446] 'agreement among raft nodes before linearized reading' (duration: 418.860941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:38.113228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:37.693674Z","time spent":"419.547695ms","remote":"127.0.0.1:60542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3488,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-07-02T05:12:38.255315Z","caller":"traceutil/trace.go:171","msg":"trace[2108036205] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"135.944816ms","start":"2026-07-02T05:12:38.119347Z","end":"2026-07-02T05:12:38.255292Z","steps":["trace[2108036205] 'process raft request' (duration: 135.841524ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:38.255561Z","caller":"traceutil/trace.go:171","msg":"trace[623996083] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:270; }","duration":"100.755751ms","start":"2026-07-02T05:12:38.154587Z","end":"2026-07-02T05:12:38.255343Z","steps":["trace[623996083] 'read index received' (duration: 93.305273ms)","trace[623996083] 'applied index is now lower than readState.Index' (duration: 7.449848ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:38.255706Z","caller":"traceutil/trace.go:171","msg":"trace[190309061] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"134.122917ms","start":"2026-07-02T05:12:38.121547Z","end":"2026-07-02T05:12:38.25567Z","steps":["trace[190309061] 'process raft request' (duration: 133.697078ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:38.255768Z","caller":"traceutil/trace.go:171","msg":"trace[62650843] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"127.906546ms","start":"2026-07-02T05:12:38.12785Z","end":"2026-07-02T05:12:38.255756Z","steps":["trace[62650843] 'process raft request' (duration: 127.438766ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:38.255819Z","caller":"traceutil/trace.go:171","msg":"trace[98281979] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"137.08336ms","start":"2026-07-02T05:12:38.118725Z","end":"2026-07-02T05:12:38.255808Z","steps":["trace[98281979] 'process raft request' (duration: 129.212103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:38.255888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.266602ms","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-07-02T05:12:38.256756Z","caller":"traceutil/trace.go:171","msg":"trace[969400126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:265; }","duration":"102.213421ms","start":"2026-07-02T05:12:38.154531Z","end":"2026-07-02T05:12:38.256745Z","steps":["trace[969400126] 'agreement among raft nodes before linearized reading' (duration: 101.236321ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:09.651637Z","caller":"traceutil/trace.go:171","msg":"trace[404136602] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"103.001958ms","start":"2026-07-02T05:13:09.548606Z","end":"2026-07-02T05:13:09.651607Z","steps":["trace[404136602] 'process raft request' (duration: 102.637881ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:09.651723Z","caller":"traceutil/trace.go:171","msg":"trace[1900528264] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"103.125861ms","start":"2026-07-02T05:13:09.548562Z","end":"2026-07-02T05:13:09.651688Z","steps":["trace[1900528264] 'process raft request' (duration: 102.547489ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:15.663421Z","caller":"traceutil/trace.go:171","msg":"trace[1191224142] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"267.142533ms","start":"2026-07-02T05:13:15.396248Z","end":"2026-07-02T05:13:15.663391Z","steps":["trace[1191224142] 'process raft request' (duration: 266.930138ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:15.839547Z","caller":"traceutil/trace.go:171","msg":"trace[424591756] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"222.565689ms","start":"2026-07-02T05:13:15.61694Z","end":"2026-07-02T05:13:15.839505Z","steps":["trace[424591756] 'read index received' (duration: 46.276269ms)","trace[424591756] 'applied index is now lower than readState.Index' (duration: 176.28769ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:15.839725Z","caller":"traceutil/trace.go:171","msg":"trace[517971851] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"346.720786ms","start":"2026-07-02T05:13:15.492991Z","end":"2026-07-02T05:13:15.839712Z","steps":["trace[517971851] 'process raft request' (duration: 340.819031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:15.840107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:15.492954Z","time spent":"346.813287ms","remote":"127.0.0.1:60432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:15.840459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.148051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4s9c6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-02T05:13:15.840595Z","caller":"traceutil/trace.go:171","msg":"trace[423495405] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4s9c6; range_end:; response_count:1; response_revision:585; }","duration":"223.682893ms","start":"2026-07-02T05:13:15.616886Z","end":"2026-07-02T05:13:15.840569Z","steps":["trace[423495405] 'agreement among raft nodes before linearized reading' (duration: 223.048849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:14:06.1943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.683773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:14:06.194452Z","caller":"traceutil/trace.go:171","msg":"trace[1918688596] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"110.490484ms","start":"2026-07-02T05:14:06.083949Z","end":"2026-07-02T05:14:06.194439Z","steps":["trace[1918688596] 'process raft request' (duration: 110.443653ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:06.19452Z","caller":"traceutil/trace.go:171","msg":"trace[1213992511] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"280.436575ms","start":"2026-07-02T05:14:05.914048Z","end":"2026-07-02T05:14:06.194485Z","steps":["trace[1213992511] 'process raft request' (duration: 125.518297ms)","trace[1213992511] 'compare' (duration: 154.555481ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:14:27.557484Z","caller":"traceutil/trace.go:171","msg":"trace[406562593] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"107.067085ms","start":"2026-07-02T05:14:27.450392Z","end":"2026-07-02T05:14:27.557459Z","steps":["trace[406562593] 'process raft request' (duration: 106.926602ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:28.956298Z","caller":"traceutil/trace.go:171","msg":"trace[1924347292] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"127.698762ms","start":"2026-07-02T05:14:28.828574Z","end":"2026-07-02T05:14:28.956273Z","steps":["trace[1924347292] 'read index received' (duration: 127.57807ms)","trace[1924347292] 'applied index is now lower than readState.Index' (duration: 119.542µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:14:28.956327Z","caller":"traceutil/trace.go:171","msg":"trace[1541052557] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"137.34533ms","start":"2026-07-02T05:14:28.818955Z","end":"2026-07-02T05:14:28.9563Z","steps":["trace[1541052557] 'process raft request' (duration: 137.150606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:14:28.956598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.002959ms","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-07-02T05:14:28.956652Z","caller":"traceutil/trace.go:171","msg":"trace[212388832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1045; }","duration":"128.10647ms","start":"2026-07-02T05:14:28.828531Z","end":"2026-07-02T05:14:28.956637Z","steps":["trace[212388832] 'agreement among raft nodes before linearized reading' (duration: 127.880645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:14:28.956911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.714665ms","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-07-02T05:14:28.957161Z","caller":"traceutil/trace.go:171","msg":"trace[1851755050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1045; }","duration":"124.005022ms","start":"2026-07-02T05:14:28.83313Z","end":"2026-07-02T05:14:28.957135Z","steps":["trace[1851755050] 'agreement among raft nodes before linearized reading' (duration: 123.332447ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:47.831446Z","caller":"traceutil/trace.go:171","msg":"trace[14451540] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"178.03933ms","start":"2026-07-02T05:14:47.653393Z","end":"2026-07-02T05:14:47.831432Z","steps":["trace[14451540] 'process raft request' (duration: 177.742854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:15:02.134724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.660489ms","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-07-02T05:15:02.134782Z","caller":"traceutil/trace.go:171","msg":"trace[764529824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1226; }","duration":"243.771832ms","start":"2026-07-02T05:15:01.890998Z","end":"2026-07-02T05:15:02.134769Z","steps":["trace[764529824] 'range keys from in-memory index tree' (duration: 243.496757ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:15:02.888888Z","caller":"traceutil/trace.go:171","msg":"trace[1528525270] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"263.16001ms","start":"2026-07-02T05:15:02.625675Z","end":"2026-07-02T05:15:02.888835Z","steps":["trace[1528525270] 'process raft request' (duration: 228.893629ms)","trace[1528525270] 'compare' (duration: 34.027586ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:15:15.557791Z","caller":"traceutil/trace.go:171","msg":"trace[311955284] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"215.977951ms","start":"2026-07-02T05:15:15.34178Z","end":"2026-07-02T05:15:15.557758Z","steps":["trace[311955284] 'process raft request' (duration: 215.784857ms)"],"step_count":1}