{"level":"warn","ts":"2026-03-18T16:40:43.133397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T16:40:43.133512Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.135: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.135:2380","--initial-cluster=instance=https://199.19.213.135:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.135:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.135: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-03-18T16:40:43.133594Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T16:40:43.133612Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.135:2380"]} {"level":"info","ts":"2026-03-18T16:40:43.133639Z","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-03-18T16:40:43.134403Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.135:2379"]} {"level":"info","ts":"2026-03-18T16:40:43.134488Z","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.135:2380"],"listen-peer-urls":["https://199.19.213.135:2380"],"advertise-client-urls":["https://199.19.213.135:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.135:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.135: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-03-18T16:40:43.138271Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.520738ms"} {"level":"info","ts":"2026-03-18T16:40:43.148777Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c1c5c7ba53ae44cf","cluster-id":"77fbd80d02a39aa"} {"level":"info","ts":"2026-03-18T16:40:43.149148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T16:40:43.149196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf became follower at term 0"} {"level":"info","ts":"2026-03-18T16:40:43.149213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c1c5c7ba53ae44cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T16:40:43.149222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf became follower at term 1"} {"level":"info","ts":"2026-03-18T16:40:43.149289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf switched to configuration voters=(13962785822813865167)"} {"level":"warn","ts":"2026-03-18T16:40:43.156354Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T16:40:43.158378Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T16:40:43.15954Z","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-03-18T16:40:43.162886Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c1c5c7ba53ae44cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T16:40:43.172215Z","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-03-18T16:40:43.172345Z","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-03-18T16:40:43.172363Z","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-03-18T16:40:43.172801Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c1c5c7ba53ae44cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T16:40:43.173583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf switched to configuration voters=(13962785822813865167)"} {"level":"info","ts":"2026-03-18T16:40:43.173747Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77fbd80d02a39aa","local-member-id":"c1c5c7ba53ae44cf","added-peer-id":"c1c5c7ba53ae44cf","added-peer-peer-urls":["https://199.19.213.135:2380"]} {"level":"info","ts":"2026-03-18T16:40:43.175275Z","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-03-18T16:40:43.175403Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.135:2380"} {"level":"info","ts":"2026-03-18T16:40:43.175445Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.135:2380"} {"level":"info","ts":"2026-03-18T16:40:43.175723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c1c5c7ba53ae44cf","initial-advertise-peer-urls":["https://199.19.213.135:2380"],"listen-peer-urls":["https://199.19.213.135:2380"],"advertise-client-urls":["https://199.19.213.135:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.135:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T16:40:43.175743Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T16:40:43.950137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T16:40:43.950274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T16:40:43.950391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf received MsgPreVoteResp from c1c5c7ba53ae44cf at term 1"} {"level":"info","ts":"2026-03-18T16:40:43.950433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf became candidate at term 2"} {"level":"info","ts":"2026-03-18T16:40:43.950457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf received MsgVoteResp from c1c5c7ba53ae44cf at term 2"} {"level":"info","ts":"2026-03-18T16:40:43.950481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1c5c7ba53ae44cf became leader at term 2"} {"level":"info","ts":"2026-03-18T16:40:43.950506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c1c5c7ba53ae44cf elected leader c1c5c7ba53ae44cf at term 2"} {"level":"info","ts":"2026-03-18T16:40:43.951762Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T16:40:43.952555Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c1c5c7ba53ae44cf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.135:2379]}","request-path":"/0/members/c1c5c7ba53ae44cf/attributes","cluster-id":"77fbd80d02a39aa","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T16:40:43.952727Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T16:40:43.952805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T16:40:43.953305Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77fbd80d02a39aa","local-member-id":"c1c5c7ba53ae44cf","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T16:40:43.953442Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T16:40:43.953487Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T16:40:43.955845Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T16:40:43.956858Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.135:2379"} {"level":"info","ts":"2026-03-18T16:40:43.961867Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T16:40:43.963087Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T16:40:54.491175Z","caller":"traceutil/trace.go:171","msg":"trace[1542867788] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"197.913919ms","start":"2026-03-18T16:40:54.293237Z","end":"2026-03-18T16:40:54.491151Z","steps":["trace[1542867788] 'read index received' (duration: 197.756548ms)","trace[1542867788] 'applied index is now lower than readState.Index' (duration: 155.341µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:40:54.491249Z","caller":"traceutil/trace.go:171","msg":"trace[370781423] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"238.123758ms","start":"2026-03-18T16:40:54.253077Z","end":"2026-03-18T16:40:54.4912Z","steps":["trace[370781423] 'process raft request' (duration: 237.932726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:40:54.49139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.143691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-18T16:40:54.491506Z","caller":"traceutil/trace.go:171","msg":"trace[1656519971] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:353; }","duration":"198.274682ms","start":"2026-03-18T16:40:54.293216Z","end":"2026-03-18T16:40:54.491491Z","steps":["trace[1656519971] 'agreement among raft nodes before linearized reading' (duration: 198.104681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:40:54.491615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.807927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-18T16:40:54.491662Z","caller":"traceutil/trace.go:171","msg":"trace[908770729] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:353; }","duration":"148.917088ms","start":"2026-03-18T16:40:54.342732Z","end":"2026-03-18T16:40:54.491649Z","steps":["trace[908770729] 'agreement among raft nodes before linearized reading' (duration: 148.790917ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:40:59.487817Z","caller":"traceutil/trace.go:171","msg":"trace[1820624709] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"113.896257ms","start":"2026-03-18T16:40:59.373885Z","end":"2026-03-18T16:40:59.487782Z","steps":["trace[1820624709] 'process raft request' (duration: 77.526835ms)","trace[1820624709] 'compare' (duration: 36.080969ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:41:00.226322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4958354345904541838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T16:41:00.727064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4958354345904541838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T16:41:00.753071Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.234361751s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T16:41:00.753605Z","caller":"traceutil/trace.go:171","msg":"trace[420646593] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.234977577s","start":"2026-03-18T16:40:59.518586Z","end":"2026-03-18T16:41:00.753563Z","steps":["trace[420646593] 'process raft request' (duration: 1.234768345s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:00.754583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:40:59.518565Z","time spent":"1.235114938s","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:41:01.432856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.394159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T16:41:01.433038Z","caller":"traceutil/trace.go:171","msg":"trace[1308580489] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"1.91430734s","start":"2026-03-18T16:40:59.5187Z","end":"2026-03-18T16:41:01.433007Z","steps":["trace[1308580489] 'process raft request' (duration: 1.686813198s)","trace[1308580489] 'compare' (duration: 225.494199ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:41:01.433111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:40:59.518683Z","time spent":"1.914399531s","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:41:01.43297Z","caller":"traceutil/trace.go:171","msg":"trace[1549835630] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"1.707372495s","start":"2026-03-18T16:40:59.725582Z","end":"2026-03-18T16:41:01.432954Z","steps":["trace[1549835630] 'read index received' (duration: 1.027698638s)","trace[1549835630] 'applied index is now lower than readState.Index' (duration: 679.672717ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:41:01.436621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373313588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-18T16:41:01.436676Z","caller":"traceutil/trace.go:171","msg":"trace[314058369] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:412; }","duration":"1.376324283s","start":"2026-03-18T16:41:00.060339Z","end":"2026-03-18T16:41:01.436663Z","steps":["trace[314058369] 'agreement among raft nodes before linearized reading' (duration: 1.373338009s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:01.436692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.208306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-18T16:41:01.436711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:00.060307Z","time spent":"1.376396953s","remote":"127.0.0.1:50604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-18T16:41:01.436731Z","caller":"traceutil/trace.go:171","msg":"trace[928139933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"117.291867ms","start":"2026-03-18T16:41:01.319427Z","end":"2026-03-18T16:41:01.436719Z","steps":["trace[928139933] 'agreement among raft nodes before linearized reading' (duration: 117.234716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:01.436637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707592097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-18T16:41:01.436855Z","caller":"traceutil/trace.go:171","msg":"trace[2103652728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"1.711332259s","start":"2026-03-18T16:40:59.725509Z","end":"2026-03-18T16:41:01.436841Z","steps":["trace[2103652728] 'agreement among raft nodes before linearized reading' (duration: 1.707592017s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:01.43695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:40:59.725437Z","time spent":"1.711499691s","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T16:41:01.436932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180362236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:41:01.437027Z","caller":"traceutil/trace.go:171","msg":"trace[162067553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"1.180507387s","start":"2026-03-18T16:41:00.256491Z","end":"2026-03-18T16:41:01.436998Z","steps":["trace[162067553] 'agreement among raft nodes before linearized reading' (duration: 1.180351235s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:01.437062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:00.256433Z","time spent":"1.180621778s","remote":"127.0.0.1:50402","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-03-18T16:41:01.828281Z","caller":"traceutil/trace.go:171","msg":"trace[589814549] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"380.862911ms","start":"2026-03-18T16:41:01.447389Z","end":"2026-03-18T16:41:01.828252Z","steps":["trace[589814549] 'read index received' (duration: 378.547795ms)","trace[589814549] 'applied index is now lower than readState.Index' (duration: 2.314026ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:41:01.828445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.019983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:41:01.828483Z","caller":"traceutil/trace.go:171","msg":"trace[1355596931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"381.125674ms","start":"2026-03-18T16:41:01.447345Z","end":"2026-03-18T16:41:01.828471Z","steps":["trace[1355596931] 'agreement among raft nodes before linearized reading' (duration: 381.029713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:01.828534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:01.447329Z","time spent":"381.195114ms","remote":"127.0.0.1:50402","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-03-18T16:41:01.82875Z","caller":"traceutil/trace.go:171","msg":"trace[347999077] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"387.534597ms","start":"2026-03-18T16:41:01.441165Z","end":"2026-03-18T16:41:01.8287Z","steps":["trace[347999077] 'process raft request' (duration: 384.768746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:01.829121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:01.441148Z","time spent":"387.746819ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:41:01.829977Z","caller":"traceutil/trace.go:171","msg":"trace[1534514315] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"376.333489ms","start":"2026-03-18T16:41:01.453619Z","end":"2026-03-18T16:41:01.829953Z","steps":["trace[1534514315] 'process raft request' (duration: 376.269088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:01.830322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:01.453607Z","time spent":"376.441421ms","remote":"127.0.0.1:50604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:41:01.830306Z","caller":"traceutil/trace.go:171","msg":"trace[1064404085] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"382.096596ms","start":"2026-03-18T16:41:01.447987Z","end":"2026-03-18T16:41:01.830083Z","steps":["trace[1064404085] 'process raft request' (duration: 381.739151ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:01.830581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:01.447973Z","time spent":"382.553651ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:41:09.170557Z","caller":"traceutil/trace.go:171","msg":"trace[1223825148] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"369.881747ms","start":"2026-03-18T16:41:08.800647Z","end":"2026-03-18T16:41:09.170529Z","steps":["trace[1223825148] 'process raft request' (duration: 369.742555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:09.170711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:08.800616Z","time spent":"370.018629ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:41:17.701997Z","caller":"traceutil/trace.go:171","msg":"trace[1783135596] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"178.618864ms","start":"2026-03-18T16:41:17.523351Z","end":"2026-03-18T16:41:17.701969Z","steps":["trace[1783135596] 'process raft request' (duration: 178.442771ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:41:18.156697Z","caller":"traceutil/trace.go:171","msg":"trace[455247476] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"188.565581ms","start":"2026-03-18T16:41:17.968104Z","end":"2026-03-18T16:41:18.15667Z","steps":["trace[455247476] 'read index received' (duration: 188.406738ms)","trace[455247476] 'applied index is now lower than readState.Index' (duration: 157.443µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:41:18.156877Z","caller":"traceutil/trace.go:171","msg":"trace[1380533136] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"366.450093ms","start":"2026-03-18T16:41:17.790286Z","end":"2026-03-18T16:41:18.156736Z","steps":["trace[1380533136] 'process raft request' (duration: 366.235679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:18.156909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.763314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T16:41:18.157069Z","caller":"traceutil/trace.go:171","msg":"trace[1838626643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"188.993688ms","start":"2026-03-18T16:41:17.968045Z","end":"2026-03-18T16:41:18.157039Z","steps":["trace[1838626643] 'agreement among raft nodes before linearized reading' (duration: 188.720263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:18.156922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.64847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-18T16:41:18.157237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:17.790254Z","time spent":"366.830959ms","remote":"127.0.0.1:50604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:41:18.157319Z","caller":"traceutil/trace.go:171","msg":"trace[1087037047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"182.095077ms","start":"2026-03-18T16:41:17.975189Z","end":"2026-03-18T16:41:18.157284Z","steps":["trace[1087037047] 'agreement among raft nodes before linearized reading' (duration: 181.644889ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:41:19.560102Z","caller":"traceutil/trace.go:171","msg":"trace[1858707490] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"307.97112ms","start":"2026-03-18T16:41:19.25207Z","end":"2026-03-18T16:41:19.560041Z","steps":["trace[1858707490] 'process raft request' (duration: 307.793027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:19.560324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:19.252043Z","time spent":"308.144033ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:41:24.775725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.559569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T16:41:24.775837Z","caller":"traceutil/trace.go:171","msg":"trace[1272225951] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"590.173219ms","start":"2026-03-18T16:41:24.185646Z","end":"2026-03-18T16:41:24.775819Z","steps":["trace[1272225951] 'read index received' (duration: 243.474728ms)","trace[1272225951] 'applied index is now lower than readState.Index' (duration: 346.697381ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:41:24.775934Z","caller":"traceutil/trace.go:171","msg":"trace[1781930938] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"609.674946ms","start":"2026-03-18T16:41:24.166224Z","end":"2026-03-18T16:41:24.775899Z","steps":["trace[1781930938] 'process raft request' (duration: 262.846612ms)","trace[1781930938] 'compare' (duration: 346.432746ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T16:41:24.776121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:24.166205Z","time spent":"609.810539ms","remote":"127.0.0.1:50608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:41:24.776577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.935063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-18T16:41:24.776551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.583834ms","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-03-18T16:41:24.776609Z","caller":"traceutil/trace.go:171","msg":"trace[276844149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"557.009225ms","start":"2026-03-18T16:41:24.21959Z","end":"2026-03-18T16:41:24.776599Z","steps":["trace[276844149] 'agreement among raft nodes before linearized reading' (duration: 556.871412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:24.776634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.990847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-03-18T16:41:24.776641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:24.219579Z","time spent":"557.054915ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T16:41:24.776651Z","caller":"traceutil/trace.go:171","msg":"trace[536242742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"563.793549ms","start":"2026-03-18T16:41:24.212832Z","end":"2026-03-18T16:41:24.776625Z","steps":["trace[536242742] 'agreement among raft nodes before linearized reading' (duration: 563.350701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:24.776731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:24.212789Z","time spent":"563.930282ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-18T16:41:24.776667Z","caller":"traceutil/trace.go:171","msg":"trace[1007152369] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:555; }","duration":"591.023638ms","start":"2026-03-18T16:41:24.185632Z","end":"2026-03-18T16:41:24.776656Z","steps":["trace[1007152369] 'agreement among raft nodes before linearized reading' (duration: 590.277912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:24.776856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:24.185566Z","time spent":"591.279992ms","remote":"127.0.0.1:50546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-18T16:41:25.87427Z","caller":"traceutil/trace.go:171","msg":"trace[1250082913] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"138.662726ms","start":"2026-03-18T16:41:25.735581Z","end":"2026-03-18T16:41:25.874243Z","steps":["trace[1250082913] 'read index received' (duration: 138.38546ms)","trace[1250082913] 'applied index is now lower than readState.Index' (duration: 273.746µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:41:25.874424Z","caller":"traceutil/trace.go:171","msg":"trace[1814046098] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"276.758841ms","start":"2026-03-18T16:41:25.597616Z","end":"2026-03-18T16:41:25.874375Z","steps":["trace[1814046098] 'process raft request' (duration: 276.423103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:25.8745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.885961ms","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-03-18T16:41:25.874554Z","caller":"traceutil/trace.go:171","msg":"trace[717405127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"139.004243ms","start":"2026-03-18T16:41:25.735533Z","end":"2026-03-18T16:41:25.874537Z","steps":["trace[717405127] 'agreement among raft nodes before linearized reading' (duration: 138.813619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:27.287723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4958354345904542136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T16:41:27.788495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4958354345904542136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T16:41:27.96122Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.215333697s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T16:41:27.961748Z","caller":"traceutil/trace.go:171","msg":"trace[1090709504] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"1.174699579s","start":"2026-03-18T16:41:26.787022Z","end":"2026-03-18T16:41:27.961722Z","steps":["trace[1090709504] 'read index received' (duration: 1.174390143s)","trace[1090709504] 'applied index is now lower than readState.Index' (duration: 308.066µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:41:27.961907Z","caller":"traceutil/trace.go:171","msg":"trace[874342175] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.216123633s","start":"2026-03-18T16:41:26.745738Z","end":"2026-03-18T16:41:27.961862Z","steps":["trace[874342175] 'process raft request' (duration: 1.215784936s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:27.962065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.140746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:41:27.962105Z","caller":"traceutil/trace.go:171","msg":"trace[357163009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"710.243668ms","start":"2026-03-18T16:41:27.251847Z","end":"2026-03-18T16:41:27.962091Z","steps":["trace[357163009] 'agreement among raft nodes before linearized reading' (duration: 710.169526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:27.962111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:26.745711Z","time spent":"1.216261056s","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:41:27.962132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:27.251825Z","time spent":"710.300899ms","remote":"127.0.0.1:50402","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-03-18T16:41:27.962225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.310002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.135\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T16:41:27.962316Z","caller":"traceutil/trace.go:171","msg":"trace[2108685995] range","detail":"{range_begin:/registry/masterleases/199.19.213.135; range_end:; response_count:1; response_revision:560; }","duration":"948.473275ms","start":"2026-03-18T16:41:27.013816Z","end":"2026-03-18T16:41:27.962289Z","steps":["trace[2108685995] 'agreement among raft nodes before linearized reading' (duration: 948.26466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:27.96236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:27.013789Z","time spent":"948.560346ms","remote":"127.0.0.1:50460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.135\" "} {"level":"warn","ts":"2026-03-18T16:41:27.962404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174954315s","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-03-18T16:41:27.962425Z","caller":"traceutil/trace.go:171","msg":"trace[84566104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"1.175459865s","start":"2026-03-18T16:41:26.786959Z","end":"2026-03-18T16:41:27.962419Z","steps":["trace[84566104] 'agreement among raft nodes before linearized reading' (duration: 1.174878273s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:27.962441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:26.786916Z","time spent":"1.175520266s","remote":"127.0.0.1:50682","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-03-18T16:41:27.962655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174610177s","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-03-18T16:41:27.962719Z","caller":"traceutil/trace.go:171","msg":"trace[1111201941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"1.174682958s","start":"2026-03-18T16:41:26.788017Z","end":"2026-03-18T16:41:27.9627Z","steps":["trace[1111201941] 'agreement among raft nodes before linearized reading' (duration: 1.1743036s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:27.962752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:26.788012Z","time spent":"1.1747329s","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T16:41:28.623925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:27.963715Z","time spent":"660.202979ms","remote":"127.0.0.1:50460","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-18T16:41:28.624386Z","caller":"traceutil/trace.go:171","msg":"trace[1208204469] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"656.986132ms","start":"2026-03-18T16:41:27.967368Z","end":"2026-03-18T16:41:28.624354Z","steps":["trace[1208204469] 'process raft request' (duration: 656.312699ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:41:28.624433Z","caller":"traceutil/trace.go:171","msg":"trace[1122506126] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"657.043514ms","start":"2026-03-18T16:41:27.967375Z","end":"2026-03-18T16:41:28.624419Z","steps":["trace[1122506126] 'process raft request' (duration: 656.433491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:28.624536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:27.967366Z","time spent":"657.098875ms","remote":"127.0.0.1:50682","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-03-18T16:41:28.624536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:27.96735Z","time spent":"657.104035ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:41:28.624635Z","caller":"traceutil/trace.go:171","msg":"trace[575110462] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"657.178677ms","start":"2026-03-18T16:41:27.967439Z","end":"2026-03-18T16:41:28.624617Z","steps":["trace[575110462] 'process raft request' (duration: 656.86353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:28.624719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:27.967417Z","time spent":"657.260218ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:41:28.624871Z","caller":"traceutil/trace.go:171","msg":"trace[142027570] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"657.271858ms","start":"2026-03-18T16:41:27.967549Z","end":"2026-03-18T16:41:28.624821Z","steps":["trace[142027570] 'process raft request' (duration: 656.822999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:41:28.624942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:41:27.967537Z","time spent":"657.371271ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:41:30.700106Z","caller":"traceutil/trace.go:171","msg":"trace[1386194714] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"196.251765ms","start":"2026-03-18T16:41:30.503822Z","end":"2026-03-18T16:41:30.700074Z","steps":["trace[1386194714] 'process raft request' (duration: 196.079911ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:41:43.423338Z","caller":"traceutil/trace.go:171","msg":"trace[1879406372] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"115.118731ms","start":"2026-03-18T16:41:43.30819Z","end":"2026-03-18T16:41:43.423309Z","steps":["trace[1879406372] 'process raft request' (duration: 70.122177ms)","trace[1879406372] 'compare' (duration: 44.854491ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:42:23.300176Z","caller":"traceutil/trace.go:171","msg":"trace[1534041467] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"129.679196ms","start":"2026-03-18T16:42:23.170454Z","end":"2026-03-18T16:42:23.300133Z","steps":["trace[1534041467] 'process raft request' (duration: 129.286504ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:42:23.447831Z","caller":"traceutil/trace.go:171","msg":"trace[2023033121] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"141.6168ms","start":"2026-03-18T16:42:23.306178Z","end":"2026-03-18T16:42:23.447794Z","steps":["trace[2023033121] 'process raft request' (duration: 80.353466ms)","trace[2023033121] 'compare' (duration: 61.108639ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T16:42:23.462922Z","caller":"traceutil/trace.go:171","msg":"trace[1939080757] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"156.500978ms","start":"2026-03-18T16:42:23.306393Z","end":"2026-03-18T16:42:23.462894Z","steps":["trace[1939080757] 'process raft request' (duration: 156.416426ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:42:23.463161Z","caller":"traceutil/trace.go:171","msg":"trace[1292561830] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"156.859899ms","start":"2026-03-18T16:42:23.306292Z","end":"2026-03-18T16:42:23.463152Z","steps":["trace[1292561830] 'process raft request' (duration: 156.329643ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:42:55.680037Z","caller":"traceutil/trace.go:171","msg":"trace[238293338] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"198.276378ms","start":"2026-03-18T16:42:55.481722Z","end":"2026-03-18T16:42:55.679998Z","steps":["trace[238293338] 'process raft request' (duration: 198.141583ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:42:55.985204Z","caller":"traceutil/trace.go:171","msg":"trace[1913756386] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"300.902439ms","start":"2026-03-18T16:42:55.68428Z","end":"2026-03-18T16:42:55.985182Z","steps":["trace[1913756386] 'process raft request' (duration: 300.723453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:42:55.985353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:42:55.684253Z","time spent":"301.025043ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T16:42:55.987734Z","caller":"traceutil/trace.go:171","msg":"trace[741675940] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"303.404116ms","start":"2026-03-18T16:42:55.684305Z","end":"2026-03-18T16:42:55.987709Z","steps":["trace[741675940] 'process raft request' (duration: 303.239361ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T16:42:55.987882Z","caller":"traceutil/trace.go:171","msg":"trace[674578832] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"303.406346ms","start":"2026-03-18T16:42:55.68445Z","end":"2026-03-18T16:42:55.987856Z","steps":["trace[674578832] 'process raft request' (duration: 303.22167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:42:55.987969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:42:55.684288Z","time spent":"303.602512ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:42:55.988028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:42:55.684441Z","time spent":"303.51216ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T16:43:04.725974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.129129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T16:43:04.726058Z","caller":"traceutil/trace.go:171","msg":"trace[73096374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"477.233232ms","start":"2026-03-18T16:43:04.248806Z","end":"2026-03-18T16:43:04.72604Z","steps":["trace[73096374] 'range keys from in-memory index tree' (duration: 477.037705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:43:04.726052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.968252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-18T16:43:04.726099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:43:04.248744Z","time spent":"477.346616ms","remote":"127.0.0.1:50402","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-03-18T16:43:04.726128Z","caller":"traceutil/trace.go:171","msg":"trace[1628296351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"687.103916ms","start":"2026-03-18T16:43:04.039004Z","end":"2026-03-18T16:43:04.726108Z","steps":["trace[1628296351] 'range keys from in-memory index tree' (duration: 686.763324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:43:04.726167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:43:04.038978Z","time spent":"687.180438ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T16:43:04.726135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.587928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-18T16:43:04.726259Z","caller":"traceutil/trace.go:171","msg":"trace[657633512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"686.788065ms","start":"2026-03-18T16:43:04.03944Z","end":"2026-03-18T16:43:04.726228Z","steps":["trace[657633512] 'range keys from in-memory index tree' (duration: 686.330979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:43:04.725974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.104191ms","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-03-18T16:43:04.726356Z","caller":"traceutil/trace.go:171","msg":"trace[846116547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"696.586368ms","start":"2026-03-18T16:43:04.029758Z","end":"2026-03-18T16:43:04.726345Z","steps":["trace[846116547] 'range keys from in-memory index tree' (duration: 695.841022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T16:43:04.726367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:43:04.039411Z","time spent":"686.94226ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T16:43:04.726387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T16:43:04.029731Z","time spent":"696.64678ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}