{"level":"warn","ts":"2026-01-27T19:42:00.835425Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-27T19:42:00.855793Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.204.45.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-01-27T19:42:00.859582Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-27T19:42:00.859691Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-01-27T19:42:00.85979Z","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-01-27T19:42:00.862223Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-01-27T19:42:00.862487Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-01-27T19:42:01.141127Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"201.820699ms"} {"level":"info","ts":"2026-01-27T19:42:01.158589Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-01-27T19:42:01.158695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-01-27T19:42:01.158725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-01-27T19:42:01.158742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-27T19:42:01.158751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-01-27T19:42:01.158797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-01-27T19:42:01.198823Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-27T19:42:01.222281Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-27T19:42:01.225643Z","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-01-27T19:42:01.229876Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-27T19:42:01.230709Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-27T19:42:01.230838Z","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-01-27T19:42:01.23087Z","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-01-27T19:42:01.230878Z","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-01-27T19:42:01.231085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-01-27T19:42:01.231247Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-01-27T19:42:01.259624Z","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-01-27T19:42:01.260023Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-01-27T19:42:01.26016Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-01-27T19:42:01.260403Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-27T19:42:01.261076Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-27T19:42:01.559672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-01-27T19:42:01.559783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-01-27T19:42:01.559834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-01-27T19:42:01.55989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-01-27T19:42:01.559912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-01-27T19:42:01.559961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-01-27T19:42:01.559998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-01-27T19:42:01.562026Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-01-27T19:42:01.562106Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-27T19:42:01.563726Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:42:01.564156Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-27T19:42:01.570708Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-27T19:42:01.57073Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-27T19:42:01.57441Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-01-27T19:42:01.576114Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:42:01.576189Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:42:01.576212Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T19:42:01.580676Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-27T19:42:03.718172Z","caller":"traceutil/trace.go:171","msg":"trace[562064336] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"102.178573ms","start":"2026-01-27T19:42:03.615973Z","end":"2026-01-27T19:42:03.718152Z","steps":["trace[562064336] 'process raft request' (duration: 102.151513ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:03.718684Z","caller":"traceutil/trace.go:171","msg":"trace[1533759457] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"103.765615ms","start":"2026-01-27T19:42:03.614905Z","end":"2026-01-27T19:42:03.718671Z","steps":["trace[1533759457] 'process raft request' (duration: 103.018835ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:03.719037Z","caller":"traceutil/trace.go:171","msg":"trace[694484753] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"104.022151ms","start":"2026-01-27T19:42:03.615003Z","end":"2026-01-27T19:42:03.719025Z","steps":["trace[694484753] 'process raft request' (duration: 103.003674ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:03.719343Z","caller":"traceutil/trace.go:171","msg":"trace[930573969] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"104.293328ms","start":"2026-01-27T19:42:03.615036Z","end":"2026-01-27T19:42:03.719329Z","steps":["trace[930573969] 'process raft request' (duration: 103.005684ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:03.719577Z","caller":"traceutil/trace.go:171","msg":"trace[147309373] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"104.487674ms","start":"2026-01-27T19:42:03.615065Z","end":"2026-01-27T19:42:03.719553Z","steps":["trace[147309373] 'process raft request' (duration: 103.003585ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:03.721871Z","caller":"traceutil/trace.go:171","msg":"trace[1417487147] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"105.931351ms","start":"2026-01-27T19:42:03.615926Z","end":"2026-01-27T19:42:03.721857Z","steps":["trace[1417487147] 'process raft request' (duration: 102.166253ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:03.733583Z","caller":"traceutil/trace.go:171","msg":"trace[1970299419] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:19; }","duration":"110.633113ms","start":"2026-01-27T19:42:03.62294Z","end":"2026-01-27T19:42:03.733573Z","steps":["trace[1970299419] 'read index received' (duration: 110.629943ms)","trace[1970299419] 'applied index is now lower than readState.Index' (duration: 2.73µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:03.781665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.667965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-4cc7k\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-01-27T19:42:03.781823Z","caller":"traceutil/trace.go:171","msg":"trace[2132526563] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-4cc7k; range_end:; response_count:1; response_revision:16; }","duration":"158.802209ms","start":"2026-01-27T19:42:03.62292Z","end":"2026-01-27T19:42:03.781722Z","steps":["trace[2132526563] 'agreement among raft nodes before linearized reading' (duration: 110.810378ms)","trace[2132526563] 'range keys from in-memory index tree' (duration: 47.829876ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:03.783768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.734789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-27T19:42:03.783796Z","caller":"traceutil/trace.go:171","msg":"trace[1270480219] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:23; }","duration":"160.772179ms","start":"2026-01-27T19:42:03.623015Z","end":"2026-01-27T19:42:03.783787Z","steps":["trace[1270480219] 'agreement among raft nodes before linearized reading' (duration: 160.680027ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:03.7845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.849105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-01-27T19:42:03.784564Z","caller":"traceutil/trace.go:171","msg":"trace[506229937] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:23; }","duration":"106.933597ms","start":"2026-01-27T19:42:03.677625Z","end":"2026-01-27T19:42:03.784559Z","steps":["trace[506229937] 'agreement among raft nodes before linearized reading' (duration: 106.852814ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:03.785002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.295264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-27T19:42:03.785022Z","caller":"traceutil/trace.go:171","msg":"trace[858398560] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:23; }","duration":"110.344326ms","start":"2026-01-27T19:42:03.674673Z","end":"2026-01-27T19:42:03.785017Z","steps":["trace[858398560] 'agreement among raft nodes before linearized reading' (duration: 110.310545ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:03.785203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.955956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-27T19:42:03.785223Z","caller":"traceutil/trace.go:171","msg":"trace[1844195037] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:0; response_revision:23; }","duration":"119.997537ms","start":"2026-01-27T19:42:03.665221Z","end":"2026-01-27T19:42:03.785218Z","steps":["trace[1844195037] 'agreement among raft nodes before linearized reading' (duration: 119.964716ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:05.436355Z","caller":"traceutil/trace.go:171","msg":"trace[1709430512] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"106.730094ms","start":"2026-01-27T19:42:05.329609Z","end":"2026-01-27T19:42:05.436339Z","steps":["trace[1709430512] 'process raft request' (duration: 34.498419ms)","trace[1709430512] 'compare' (duration: 72.162373ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:42:05.647331Z","caller":"traceutil/trace.go:171","msg":"trace[2125750478] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"110.785824ms","start":"2026-01-27T19:42:05.53652Z","end":"2026-01-27T19:42:05.647306Z","steps":["trace[2125750478] 'process raft request' (duration: 78.215247ms)","trace[2125750478] 'compare' (duration: 32.473024ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:06.055899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.023047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:42:06.056321Z","caller":"traceutil/trace.go:171","msg":"trace[1762336243] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"176.124016ms","start":"2026-01-27T19:42:05.880159Z","end":"2026-01-27T19:42:06.056283Z","steps":["trace[1762336243] 'process raft request' (duration: 176.028804ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:06.058152Z","caller":"traceutil/trace.go:171","msg":"trace[683465708] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"178.166092ms","start":"2026-01-27T19:42:05.879961Z","end":"2026-01-27T19:42:06.058127Z","steps":["trace[683465708] 'process raft request' (duration: 22.320301ms)","trace[683465708] 'compare' (duration: 147.882153ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:06.501662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.566168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:42:06.502182Z","caller":"traceutil/trace.go:171","msg":"trace[527900747] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"355.185987ms","start":"2026-01-27T19:42:06.146975Z","end":"2026-01-27T19:42:06.502161Z","steps":["trace[527900747] 'process raft request' (duration: 355.059843ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:06.502373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:06.146965Z","time spent":"355.348941ms","remote":"127.0.0.1:52674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-27T19:42:06.502544Z","caller":"traceutil/trace.go:171","msg":"trace[627050215] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"356.008678ms","start":"2026-01-27T19:42:06.146514Z","end":"2026-01-27T19:42:06.502522Z","steps":["trace[627050215] 'compare' (duration: 347.444475ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:06.502656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:06.146503Z","time spent":"356.06365ms","remote":"127.0.0.1:47748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-27T19:42:06.793311Z","caller":"traceutil/trace.go:171","msg":"trace[444098304] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"285.332335ms","start":"2026-01-27T19:42:06.507948Z","end":"2026-01-27T19:42:06.79328Z","steps":["trace[444098304] 'process raft request' (duration: 226.315961ms)","trace[444098304] 'compare' (duration: 58.84594ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:42:06.793693Z","caller":"traceutil/trace.go:171","msg":"trace[2001308393] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"283.901376ms","start":"2026-01-27T19:42:06.509783Z","end":"2026-01-27T19:42:06.793684Z","steps":["trace[2001308393] 'process raft request' (duration: 283.475275ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:13.034656Z","caller":"traceutil/trace.go:171","msg":"trace[453710973] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"106.042629ms","start":"2026-01-27T19:42:12.928582Z","end":"2026-01-27T19:42:13.034625Z","steps":["trace[453710973] 'process raft request' (duration: 50.609567ms)","trace[453710973] 'compare' (duration: 55.331779ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:27.307741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.846658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-01-27T19:42:27.307801Z","caller":"traceutil/trace.go:171","msg":"trace[1197324441] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:464; }","duration":"200.944102ms","start":"2026-01-27T19:42:27.106844Z","end":"2026-01-27T19:42:27.307788Z","steps":["trace[1197324441] 'range keys from in-memory index tree' (duration: 200.754085ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:27.796685Z","caller":"traceutil/trace.go:171","msg":"trace[1445460676] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"327.641753ms","start":"2026-01-27T19:42:27.469021Z","end":"2026-01-27T19:42:27.796663Z","steps":["trace[1445460676] 'read index received' (duration: 327.502238ms)","trace[1445460676] 'applied index is now lower than readState.Index' (duration: 138.335µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:27.796813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.769027ms","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-01-27T19:42:27.796843Z","caller":"traceutil/trace.go:171","msg":"trace[2003502559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"327.84381ms","start":"2026-01-27T19:42:27.468991Z","end":"2026-01-27T19:42:27.796835Z","steps":["trace[2003502559] 'agreement among raft nodes before linearized reading' (duration: 327.755367ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:27.796876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:27.468968Z","time spent":"327.900001ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-27T19:42:27.797084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:27.312145Z","time spent":"484.937722ms","remote":"127.0.0.1:52626","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-27T19:42:27.904173Z","caller":"traceutil/trace.go:171","msg":"trace[857005270] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"104.236128ms","start":"2026-01-27T19:42:27.79991Z","end":"2026-01-27T19:42:27.904146Z","steps":["trace[857005270] 'process raft request' (duration: 100.332941ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:27.904235Z","caller":"traceutil/trace.go:171","msg":"trace[146421759] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"104.081342ms","start":"2026-01-27T19:42:27.800138Z","end":"2026-01-27T19:42:27.90422Z","steps":["trace[146421759] 'process raft request' (duration: 103.826693ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:28.77181Z","caller":"traceutil/trace.go:171","msg":"trace[1211738699] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"154.810349ms","start":"2026-01-27T19:42:28.61686Z","end":"2026-01-27T19:42:28.771671Z","steps":["trace[1211738699] 'process raft request' (duration: 154.704145ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:32.535556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.380528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:42:32.535655Z","caller":"traceutil/trace.go:171","msg":"trace[1887324473] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"258.05986ms","start":"2026-01-27T19:42:32.277577Z","end":"2026-01-27T19:42:32.535637Z","steps":["trace[1887324473] 'process raft request' (duration: 17.484985ms)","trace[1887324473] 'compare' (duration: 240.271334ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:33.006407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.961034ms","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-01-27T19:42:33.006531Z","caller":"traceutil/trace.go:171","msg":"trace[328232729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"188.12121ms","start":"2026-01-27T19:42:32.818389Z","end":"2026-01-27T19:42:33.006511Z","steps":["trace[328232729] 'range keys from in-memory index tree' (duration: 187.83517ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:33.00673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.316921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:33.00676Z","caller":"traceutil/trace.go:171","msg":"trace[472816128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"121.375863ms","start":"2026-01-27T19:42:32.885373Z","end":"2026-01-27T19:42:33.006748Z","steps":["trace[472816128] 'range keys from in-memory index tree' (duration: 121.239408ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:33.193348Z","caller":"traceutil/trace.go:171","msg":"trace[2065129107] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"172.304322ms","start":"2026-01-27T19:42:33.021023Z","end":"2026-01-27T19:42:33.193327Z","steps":["trace[2065129107] 'process raft request' (duration: 172.187497ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:33.638291Z","caller":"traceutil/trace.go:171","msg":"trace[1737415953] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"114.38446ms","start":"2026-01-27T19:42:33.523887Z","end":"2026-01-27T19:42:33.638272Z","steps":["trace[1737415953] 'process raft request' (duration: 114.266775ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:34.512913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:42:35.013899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:42:35.138668Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.140308015s","expected-duration":"1s"} {"level":"info","ts":"2026-01-27T19:42:35.140526Z","caller":"traceutil/trace.go:171","msg":"trace[479651420] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.142186625s","start":"2026-01-27T19:42:33.998278Z","end":"2026-01-27T19:42:35.140465Z","steps":["trace[479651420] 'process raft request' (duration: 1.140729771s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:35.140962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:33.998262Z","time spent":"1.142425333s","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:42:35.150028Z","caller":"traceutil/trace.go:171","msg":"trace[68373081] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:526; }","duration":"1.137958392s","start":"2026-01-27T19:42:34.012047Z","end":"2026-01-27T19:42:35.150005Z","steps":["trace[68373081] 'read index received' (duration: 1.127036776s)","trace[68373081] 'applied index is now lower than readState.Index' (duration: 10.920476ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:42:35.15035Z","caller":"traceutil/trace.go:171","msg":"trace[947242836] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"702.194082ms","start":"2026-01-27T19:42:34.448138Z","end":"2026-01-27T19:42:35.150332Z","steps":["trace[947242836] 'process raft request' (duration: 701.662063ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:35.150638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:34.448116Z","time spent":"702.416231ms","remote":"127.0.0.1:47670","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-01-27T19:42:35.151053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.854698ms","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-01-27T19:42:35.151162Z","caller":"traceutil/trace.go:171","msg":"trace[954095957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"611.958792ms","start":"2026-01-27T19:42:34.539162Z","end":"2026-01-27T19:42:35.151121Z","steps":["trace[954095957] 'agreement among raft nodes before linearized reading' (duration: 611.787055ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:35.151252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:34.539148Z","time spent":"612.091467ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:42:35.151665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.8408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:35.151776Z","caller":"traceutil/trace.go:171","msg":"trace[1094226304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"265.988696ms","start":"2026-01-27T19:42:34.885777Z","end":"2026-01-27T19:42:35.151765Z","steps":["trace[1094226304] 'agreement among raft nodes before linearized reading' (duration: 265.866311ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:35.151891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139818681s","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-01-27T19:42:35.151944Z","caller":"traceutil/trace.go:171","msg":"trace[1388438346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"1.139911435s","start":"2026-01-27T19:42:34.012023Z","end":"2026-01-27T19:42:35.151935Z","steps":["trace[1388438346] 'agreement among raft nodes before linearized reading' (duration: 1.138362987s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:35.152028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:34.012013Z","time spent":"1.140006638s","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:42:37.20439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.685434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-27T19:42:37.204477Z","caller":"traceutil/trace.go:171","msg":"trace[1578604926] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"513.594329ms","start":"2026-01-27T19:42:36.690866Z","end":"2026-01-27T19:42:37.20446Z","steps":["trace[1578604926] 'process raft request' (duration: 140.78179ms)","trace[1578604926] 'compare' (duration: 372.59363ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:37.204526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:36.690848Z","time spent":"513.658901ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-27T19:42:37.402483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539688,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:42:37.90355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539688,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:42:37.988623Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156858454s","expected-duration":"1s"} {"level":"info","ts":"2026-01-27T19:42:37.996089Z","caller":"traceutil/trace.go:171","msg":"trace[1493442241] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:533; }","duration":"1.10676428s","start":"2026-01-27T19:42:36.88931Z","end":"2026-01-27T19:42:37.996074Z","steps":["trace[1493442241] 'read index received' (duration: 1.099427514s)","trace[1493442241] 'applied index is now lower than readState.Index' (duration: 7.336186ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:42:37.996116Z","caller":"traceutil/trace.go:171","msg":"trace[490315475] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.304987341s","start":"2026-01-27T19:42:36.691109Z","end":"2026-01-27T19:42:37.996097Z","steps":["trace[490315475] 'process raft request' (duration: 1.297666775s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:37.996199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:36.691103Z","time spent":"1.305053503s","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12737,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-27T19:42:37.996211Z","caller":"traceutil/trace.go:171","msg":"trace[162305092] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.304583936s","start":"2026-01-27T19:42:36.69162Z","end":"2026-01-27T19:42:37.996204Z","steps":["trace[162305092] 'process raft request' (duration: 1.30441847s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:38.000361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.846616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-01-27T19:42:38.000464Z","caller":"traceutil/trace.go:171","msg":"trace[220508414] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:522; }","duration":"892.984901ms","start":"2026-01-27T19:42:37.107467Z","end":"2026-01-27T19:42:38.000452Z","steps":["trace[220508414] 'agreement among raft nodes before linearized reading' (duration: 889.007691ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:38.000535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:37.107451Z","time spent":"893.076035ms","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-01-27T19:42:37.996275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:36.691612Z","time spent":"1.304619357s","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1745,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-27T19:42:38.00265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113338729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:38.002839Z","caller":"traceutil/trace.go:171","msg":"trace[2093134112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"1.113524896s","start":"2026-01-27T19:42:36.889302Z","end":"2026-01-27T19:42:38.002827Z","steps":["trace[2093134112] 'agreement among raft nodes before linearized reading' (duration: 1.113324798s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:38.002904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:36.889246Z","time spent":"1.113648071s","remote":"127.0.0.1:52606","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-01-27T19:42:38.00307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.141283ms","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-01-27T19:42:38.005047Z","caller":"traceutil/trace.go:171","msg":"trace[1903493497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"832.125168ms","start":"2026-01-27T19:42:37.172904Z","end":"2026-01-27T19:42:38.005029Z","steps":["trace[1903493497] 'agreement among raft nodes before linearized reading' (duration: 830.139813ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:38.00509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:37.172895Z","time spent":"832.18274ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:42:38.003672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.105499ms","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-01-27T19:42:38.00521Z","caller":"traceutil/trace.go:171","msg":"trace[990474495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"832.68871ms","start":"2026-01-27T19:42:37.172512Z","end":"2026-01-27T19:42:38.005201Z","steps":["trace[990474495] 'agreement among raft nodes before linearized reading' (duration: 830.904212ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:38.005236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:37.172493Z","time spent":"832.736431ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:42:38.004409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.121326ms","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-01-27T19:42:38.005412Z","caller":"traceutil/trace.go:171","msg":"trace[338326265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"834.157065ms","start":"2026-01-27T19:42:37.171247Z","end":"2026-01-27T19:42:38.005404Z","steps":["trace[338326265] 'agreement among raft nodes before linearized reading' (duration: 833.142626ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:38.005441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:37.171228Z","time spent":"834.207257ms","remote":"127.0.0.1:47670","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-01-27T19:42:38.004443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.730469ms","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-01-27T19:42:38.005784Z","caller":"traceutil/trace.go:171","msg":"trace[380914553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"861.093811ms","start":"2026-01-27T19:42:37.144682Z","end":"2026-01-27T19:42:38.005776Z","steps":["trace[380914553] 'agreement among raft nodes before linearized reading' (duration: 859.74449ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:38.00582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:37.144662Z","time spent":"861.146132ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-27T19:42:44.605335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539770,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-27T19:42:44.66501Z","caller":"traceutil/trace.go:171","msg":"trace[240777762] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"560.071292ms","start":"2026-01-27T19:42:44.104923Z","end":"2026-01-27T19:42:44.664994Z","steps":["trace[240777762] 'process raft request' (duration: 559.960448ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:44.665128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:44.104916Z","time spent":"560.147786ms","remote":"127.0.0.1:47670","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-01-27T19:42:44.906889Z","caller":"traceutil/trace.go:171","msg":"trace[1750753680] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"801.744787ms","start":"2026-01-27T19:42:44.105128Z","end":"2026-01-27T19:42:44.906872Z","steps":["trace[1750753680] 'read index received' (duration: 560.26914ms)","trace[1750753680] 'applied index is now lower than readState.Index' (duration: 241.473487ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:44.907106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.951695ms","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-01-27T19:42:44.907176Z","caller":"traceutil/trace.go:171","msg":"trace[409773212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"802.038649ms","start":"2026-01-27T19:42:44.105119Z","end":"2026-01-27T19:42:44.907158Z","steps":["trace[409773212] 'agreement among raft nodes before linearized reading' (duration: 801.872893ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:44.907218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:44.105115Z","time spent":"802.093671ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-27T19:42:44.907324Z","caller":"traceutil/trace.go:171","msg":"trace[445991021] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"799.314072ms","start":"2026-01-27T19:42:44.108001Z","end":"2026-01-27T19:42:44.907315Z","steps":["trace[445991021] 'process raft request' (duration: 748.043301ms)","trace[445991021] 'compare' (duration: 50.659626ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:44.907403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:44.107995Z","time spent":"799.364563ms","remote":"127.0.0.1:47670","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-01-27T19:42:45.066993Z","caller":"traceutil/trace.go:171","msg":"trace[1986930558] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"145.884844ms","start":"2026-01-27T19:42:44.921091Z","end":"2026-01-27T19:42:45.066976Z","steps":["trace[1986930558] 'process raft request' (duration: 137.165598ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:45.081282Z","caller":"traceutil/trace.go:171","msg":"trace[1524955947] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"158.14025ms","start":"2026-01-27T19:42:44.923127Z","end":"2026-01-27T19:42:45.081267Z","steps":["trace[1524955947] 'process raft request' (duration: 158.081508ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:46.38484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539773,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:42:46.401293Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.047627768s","expected-duration":"1s"} {"level":"info","ts":"2026-01-27T19:42:46.501176Z","caller":"traceutil/trace.go:171","msg":"trace[1687648950] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"617.107134ms","start":"2026-01-27T19:42:45.884044Z","end":"2026-01-27T19:42:46.501151Z","steps":["trace[1687648950] 'read index received' (duration: 517.342787ms)","trace[1687648950] 'applied index is now lower than readState.Index' (duration: 99.763587ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:42:46.501251Z","caller":"traceutil/trace.go:171","msg":"trace[1283045162] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.147650495s","start":"2026-01-27T19:42:45.353593Z","end":"2026-01-27T19:42:46.501243Z","steps":["trace[1283045162] 'process raft request' (duration: 1.047843896s)","trace[1283045162] 'compare' (duration: 99.645343ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:46.501312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:45.353577Z","time spent":"1.147687357s","remote":"127.0.0.1:47670","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-01-27T19:42:46.501443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.550406ms","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-01-27T19:42:46.5015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.460099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:46.501522Z","caller":"traceutil/trace.go:171","msg":"trace[1728658257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"617.508321ms","start":"2026-01-27T19:42:45.884007Z","end":"2026-01-27T19:42:46.501515Z","steps":["trace[1728658257] 'agreement among raft nodes before linearized reading' (duration: 617.47427ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:46.501527Z","caller":"traceutil/trace.go:171","msg":"trace[1407047426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"411.663631ms","start":"2026-01-27T19:42:46.089847Z","end":"2026-01-27T19:42:46.501511Z","steps":["trace[1407047426] 'agreement among raft nodes before linearized reading' (duration: 411.462073ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:46.501535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:45.883989Z","time spent":"617.543082ms","remote":"127.0.0.1:52606","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-01-27T19:42:46.501566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:46.089833Z","time spent":"411.723403ms","remote":"127.0.0.1:47670","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-01-27T19:42:47.386598Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:42:47.887571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:42:48.388243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:42:48.886942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000395463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-27T19:42:48.887043Z","caller":"traceutil/trace.go:171","msg":"trace[1903942656] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000542619s","start":"2026-01-27T19:42:46.886476Z","end":"2026-01-27T19:42:48.887019Z","steps":["trace[1903942656] 'agreement among raft nodes before linearized reading' (duration: 2.000410814s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:48.887123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:46.886463Z","time spent":"2.000644464s","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T19:42:48.889142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263467441539781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T19:42:49.036785Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.364122397s","expected-duration":"1s"} {"level":"info","ts":"2026-01-27T19:42:49.037153Z","caller":"traceutil/trace.go:171","msg":"trace[1080451981] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"2.150631346s","start":"2026-01-27T19:42:46.8865Z","end":"2026-01-27T19:42:49.037132Z","steps":["trace[1080451981] 'read index received' (duration: 2.150412078s)","trace[1080451981] 'applied index is now lower than readState.Index' (duration: 218.258µs)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:42:49.037405Z","caller":"traceutil/trace.go:171","msg":"trace[261902324] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"2.364791864s","start":"2026-01-27T19:42:46.672601Z","end":"2026-01-27T19:42:49.037393Z","steps":["trace[261902324] 'process raft request' (duration: 2.364363607s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:49.037499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:46.672591Z","time spent":"2.364842647s","remote":"127.0.0.1:47670","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-01-27T19:42:49.037694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.113503807s","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-01-27T19:42:49.03773Z","caller":"traceutil/trace.go:171","msg":"trace[376516239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"2.113561169s","start":"2026-01-27T19:42:46.924157Z","end":"2026-01-27T19:42:49.037718Z","steps":["trace[376516239] 'agreement among raft nodes before linearized reading' (duration: 2.113503137s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:49.037759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:46.924141Z","time spent":"2.113610891s","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:42:49.03792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.932861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:49.037948Z","caller":"traceutil/trace.go:171","msg":"trace[1368340114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"149.959532ms","start":"2026-01-27T19:42:48.88798Z","end":"2026-01-27T19:42:49.037939Z","steps":["trace[1368340114] 'agreement among raft nodes before linearized reading' (duration: 149.91863ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:49.03807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.915073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:49.038098Z","caller":"traceutil/trace.go:171","msg":"trace[968980873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"257.965456ms","start":"2026-01-27T19:42:48.780122Z","end":"2026-01-27T19:42:49.038088Z","steps":["trace[968980873] 'agreement among raft nodes before linearized reading' (duration: 257.929755ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:49.038283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.295252ms","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-01-27T19:42:49.038309Z","caller":"traceutil/trace.go:171","msg":"trace[228894575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"493.359974ms","start":"2026-01-27T19:42:48.544941Z","end":"2026-01-27T19:42:49.038301Z","steps":["trace[228894575] 'agreement among raft nodes before linearized reading' (duration: 493.26613ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:49.038338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:48.544921Z","time spent":"493.408426ms","remote":"127.0.0.1:47670","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-01-27T19:42:49.039637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.930643224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-01-27T19:42:49.039748Z","caller":"traceutil/trace.go:171","msg":"trace[1753641286] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:565; }","duration":"1.9307866s","start":"2026-01-27T19:42:47.108949Z","end":"2026-01-27T19:42:49.039735Z","steps":["trace[1753641286] 'agreement among raft nodes before linearized reading' (duration: 1.930587222s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:49.039823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:47.108935Z","time spent":"1.930876163s","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-01-27T19:42:49.040122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.955157937s","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-01-27T19:42:49.04021Z","caller":"traceutil/trace.go:171","msg":"trace[729737596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"1.955264051s","start":"2026-01-27T19:42:47.084934Z","end":"2026-01-27T19:42:49.040198Z","steps":["trace[729737596] 'agreement among raft nodes before linearized reading' (duration: 1.955061072s)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:49.040278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:47.084924Z","time spent":"1.955343704s","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-27T19:42:49.204207Z","caller":"traceutil/trace.go:171","msg":"trace[1960173048] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"156.407929ms","start":"2026-01-27T19:42:49.047781Z","end":"2026-01-27T19:42:49.204189Z","steps":["trace[1960173048] 'process raft request' (duration: 61.314495ms)","trace[1960173048] 'compare' (duration: 94.679657ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:42:49.204539Z","caller":"traceutil/trace.go:171","msg":"trace[2020937153] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"151.464478ms","start":"2026-01-27T19:42:49.053067Z","end":"2026-01-27T19:42:49.204532Z","steps":["trace[2020937153] 'process raft request' (duration: 151.442738ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:49.204766Z","caller":"traceutil/trace.go:171","msg":"trace[1476330228] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"155.69634ms","start":"2026-01-27T19:42:49.049053Z","end":"2026-01-27T19:42:49.204749Z","steps":["trace[1476330228] 'process raft request' (duration: 155.371667ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:49.204902Z","caller":"traceutil/trace.go:171","msg":"trace[853698693] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"151.896206ms","start":"2026-01-27T19:42:49.052998Z","end":"2026-01-27T19:42:49.204894Z","steps":["trace[853698693] 'process raft request' (duration: 151.49121ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:50.005172Z","caller":"traceutil/trace.go:171","msg":"trace[1412644400] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"119.17217ms","start":"2026-01-27T19:42:49.885983Z","end":"2026-01-27T19:42:50.005155Z","steps":["trace[1412644400] 'read index received' (duration: 30.701674ms)","trace[1412644400] 'applied index is now lower than readState.Index' (duration: 88.469816ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:42:50.005296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.295845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:50.005314Z","caller":"traceutil/trace.go:171","msg":"trace[1134818679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"119.350537ms","start":"2026-01-27T19:42:49.885957Z","end":"2026-01-27T19:42:50.005307Z","steps":["trace[1134818679] 'agreement among raft nodes before linearized reading' (duration: 119.269664ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:50.005454Z","caller":"traceutil/trace.go:171","msg":"trace[621577252] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"127.957426ms","start":"2026-01-27T19:42:49.877491Z","end":"2026-01-27T19:42:50.005448Z","steps":["trace[621577252] 'process raft request' (duration: 127.540509ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:42:51.5829Z","caller":"traceutil/trace.go:171","msg":"trace[725654521] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"417.457383ms","start":"2026-01-27T19:42:51.165427Z","end":"2026-01-27T19:42:51.582884Z","steps":["trace[725654521] 'read index received' (duration: 417.400171ms)","trace[725654521] 'applied index is now lower than readState.Index' (duration: 56.472µs)"],"step_count":2} {"level":"info","ts":"2026-01-27T19:42:51.582972Z","caller":"traceutil/trace.go:171","msg":"trace[1928510576] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"420.330901ms","start":"2026-01-27T19:42:51.162636Z","end":"2026-01-27T19:42:51.582967Z","steps":["trace[1928510576] 'process raft request' (duration: 420.123152ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:51.583026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:51.162617Z","time spent":"420.368812ms","remote":"127.0.0.1:52632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-27T19:42:51.583164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.717974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:51.583233Z","caller":"traceutil/trace.go:171","msg":"trace[352515535] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:587; }","duration":"417.808818ms","start":"2026-01-27T19:42:51.165408Z","end":"2026-01-27T19:42:51.583217Z","steps":["trace[352515535] 'agreement among raft nodes before linearized reading' (duration: 417.638321ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:51.58326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:51.165388Z","time spent":"417.86594ms","remote":"127.0.0.1:52632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-01-27T19:42:51.588817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.179636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:51.588857Z","caller":"traceutil/trace.go:171","msg":"trace[1934014183] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:590; }","duration":"422.228318ms","start":"2026-01-27T19:42:51.166617Z","end":"2026-01-27T19:42:51.588846Z","steps":["trace[1934014183] 'agreement among raft nodes before linearized reading' (duration: 422.149905ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:51.58888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:51.166588Z","time spent":"422.287751ms","remote":"127.0.0.1:52632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-01-27T19:42:51.589044Z","caller":"traceutil/trace.go:171","msg":"trace[1750030805] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"410.823971ms","start":"2026-01-27T19:42:51.178215Z","end":"2026-01-27T19:42:51.589039Z","steps":["trace[1750030805] 'process raft request' (duration: 410.428045ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:51.589087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:51.178204Z","time spent":"410.852473ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-27T19:42:51.589173Z","caller":"traceutil/trace.go:171","msg":"trace[405746189] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"249.501166ms","start":"2026-01-27T19:42:51.339666Z","end":"2026-01-27T19:42:51.589167Z","steps":["trace[405746189] 'process raft request' (duration: 249.046088ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:51.589303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.135839ms","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-01-27T19:42:51.589316Z","caller":"traceutil/trace.go:171","msg":"trace[1114726340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"373.17918ms","start":"2026-01-27T19:42:51.216133Z","end":"2026-01-27T19:42:51.589312Z","steps":["trace[1114726340] 'agreement among raft nodes before linearized reading' (duration: 373.122598ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:51.589327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:51.216122Z","time spent":"373.202551ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-27T19:42:51.589397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.550808ms","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-01-27T19:42:51.589411Z","caller":"traceutil/trace.go:171","msg":"trace[82402279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"376.585249ms","start":"2026-01-27T19:42:51.212822Z","end":"2026-01-27T19:42:51.589407Z","steps":["trace[82402279] 'agreement among raft nodes before linearized reading' (duration: 376.547308ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:51.589423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:51.212808Z","time spent":"376.610851ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-27T19:42:51.589489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.924715ms","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-01-27T19:42:51.589501Z","caller":"traceutil/trace.go:171","msg":"trace[1345036176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"378.972407ms","start":"2026-01-27T19:42:51.210524Z","end":"2026-01-27T19:42:51.589497Z","steps":["trace[1345036176] 'agreement among raft nodes before linearized reading' (duration: 378.938906ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:51.589511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:51.210504Z","time spent":"379.004468ms","remote":"127.0.0.1:47670","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-01-27T19:42:51.589561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.054271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:42:51.589573Z","caller":"traceutil/trace.go:171","msg":"trace[1270426618] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:590; }","duration":"422.077222ms","start":"2026-01-27T19:42:51.167492Z","end":"2026-01-27T19:42:51.589569Z","steps":["trace[1270426618] 'agreement among raft nodes before linearized reading' (duration: 422.058671ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T19:42:51.589586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T19:42:51.167488Z","time spent":"422.093593ms","remote":"127.0.0.1:52632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-01-27T19:42:54.319163Z","caller":"traceutil/trace.go:171","msg":"trace[1783052195] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"155.92351ms","start":"2026-01-27T19:42:54.163221Z","end":"2026-01-27T19:42:54.319144Z","steps":["trace[1783052195] 'process raft request' (duration: 155.754373ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:44:07.637885Z","caller":"traceutil/trace.go:171","msg":"trace[612472767] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"101.731112ms","start":"2026-01-27T19:44:07.536134Z","end":"2026-01-27T19:44:07.637865Z","steps":["trace[612472767] 'process raft request' (duration: 101.336932ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:44:15.94561Z","caller":"traceutil/trace.go:171","msg":"trace[358191527] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"230.003371ms","start":"2026-01-27T19:44:15.71558Z","end":"2026-01-27T19:44:15.945583Z","steps":["trace[358191527] 'read index received' (duration: 229.720787ms)","trace[358191527] 'applied index is now lower than readState.Index' (duration: 281.304µs)"],"step_count":2} {"level":"warn","ts":"2026-01-27T19:44:15.946045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.441614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T19:44:15.946094Z","caller":"traceutil/trace.go:171","msg":"trace[1108296824] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1000; }","duration":"230.551879ms","start":"2026-01-27T19:44:15.71553Z","end":"2026-01-27T19:44:15.946082Z","steps":["trace[1108296824] 'agreement among raft nodes before linearized reading' (duration: 230.160389ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T19:44:15.946337Z","caller":"traceutil/trace.go:171","msg":"trace[884404640] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"275.180368ms","start":"2026-01-27T19:44:15.671038Z","end":"2026-01-27T19:44:15.946218Z","steps":["trace[884404640] 'process raft request' (duration: 274.390058ms)"],"step_count":1}