{"level":"warn","ts":"2026-04-26T10:04:59.841891Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-26T10:04:59.84204Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-04-26T10:04:59.842373Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-26T10:04:59.842394Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-26T10:04:59.842427Z","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-04-26T10:04:59.84322Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-04-26T10:04:59.843475Z","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.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-04-26T10:04:59.849225Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.016231ms"} {"level":"info","ts":"2026-04-26T10:04:59.859835Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-04-26T10:04:59.860085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-04-26T10:04:59.860127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-04-26T10:04:59.860137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-26T10:04:59.86061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-04-26T10:04:59.860663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-04-26T10:04:59.871459Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-26T10:04:59.873086Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-26T10:04:59.874571Z","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-04-26T10:04:59.878055Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-26T10:04:59.878301Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-26T10:04:59.878359Z","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-04-26T10:04:59.879926Z","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-04-26T10:04:59.879962Z","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-04-26T10:04:59.880974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-04-26T10:04:59.882775Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-26T10:04:59.883771Z","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-04-26T10:04:59.883939Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-26T10:04:59.883985Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-26T10:04:59.885546Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-26T10:04:59.885591Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-26T10:05:00.861863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-04-26T10:05:00.861974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-26T10:05:00.862027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-04-26T10:05:00.862069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-04-26T10:05:00.862093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-26T10:05:00.862123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-04-26T10:05:00.862155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-26T10:05:00.886429Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-04-26T10:05:00.886475Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-26T10:05:00.886686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-26T10:05:00.886896Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T10:05:00.88693Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-26T10:05:00.8873Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-26T10:05:00.88815Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-26T10:05:00.8914Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-04-26T10:05:00.89513Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T10:05:00.895321Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T10:05:00.895444Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-26T10:05:01.110076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.52056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:01.110271Z","caller":"traceutil/trace.go:171","msg":"trace[979176361] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"107.758472ms","start":"2026-04-26T10:05:01.002495Z","end":"2026-04-26T10:05:01.110254Z","steps":["trace[979176361] 'count revisions from in-memory index tree' (duration: 107.45679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:01.110475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.274156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:01.110517Z","caller":"traceutil/trace.go:171","msg":"trace[1914630991] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"121.329606ms","start":"2026-04-26T10:05:00.98918Z","end":"2026-04-26T10:05:01.110509Z","steps":["trace[1914630991] 'range keys from in-memory index tree' (duration: 121.241265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:01.110609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.693589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:01.11063Z","caller":"traceutil/trace.go:171","msg":"trace[1976682270] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"133.745459ms","start":"2026-04-26T10:05:00.976879Z","end":"2026-04-26T10:05:01.110625Z","steps":["trace[1976682270] 'count revisions from in-memory index tree' (duration: 133.608098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:01.111183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.889651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:01.111361Z","caller":"traceutil/trace.go:171","msg":"trace[826201535] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"122.099082ms","start":"2026-04-26T10:05:00.989249Z","end":"2026-04-26T10:05:01.111348Z","steps":["trace[826201535] 'count revisions from in-memory index tree' (duration: 121.839391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:01.111268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.978242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:01.111599Z","caller":"traceutil/trace.go:171","msg":"trace[317896640] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"109.268675ms","start":"2026-04-26T10:05:01.002256Z","end":"2026-04-26T10:05:01.111524Z","steps":["trace[317896640] 'range keys from in-memory index tree' (duration: 108.882521ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.320577Z","caller":"traceutil/trace.go:171","msg":"trace[2060512657] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"114.635039ms","start":"2026-04-26T10:05:02.205919Z","end":"2026-04-26T10:05:02.320554Z","steps":["trace[2060512657] 'process raft request' (duration: 114.526199ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.320668Z","caller":"traceutil/trace.go:171","msg":"trace[308330070] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"114.75072ms","start":"2026-04-26T10:05:02.205903Z","end":"2026-04-26T10:05:02.320654Z","steps":["trace[308330070] 'process raft request' (duration: 114.502628ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.320588Z","caller":"traceutil/trace.go:171","msg":"trace[882719313] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"114.562269ms","start":"2026-04-26T10:05:02.205897Z","end":"2026-04-26T10:05:02.320459Z","steps":["trace[882719313] 'process raft request' (duration: 114.456838ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.320568Z","caller":"traceutil/trace.go:171","msg":"trace[987294294] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:6; }","duration":"113.855612ms","start":"2026-04-26T10:05:02.206691Z","end":"2026-04-26T10:05:02.320547Z","steps":["trace[987294294] 'read index received' (duration: 39.573974ms)","trace[987294294] 'applied index is now lower than readState.Index' (duration: 74.280688ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:02.320814Z","caller":"traceutil/trace.go:171","msg":"trace[836758303] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"114.83267ms","start":"2026-04-26T10:05:02.205973Z","end":"2026-04-26T10:05:02.320805Z","steps":["trace[836758303] 'process raft request' (duration: 114.536278ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.3209Z","caller":"traceutil/trace.go:171","msg":"trace[520274481] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"114.73052ms","start":"2026-04-26T10:05:02.205928Z","end":"2026-04-26T10:05:02.320658Z","steps":["trace[520274481] 'process raft request' (duration: 114.549099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:02.321645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.898791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:02.321731Z","caller":"traceutil/trace.go:171","msg":"trace[1748465749] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"115.033812ms","start":"2026-04-26T10:05:02.206654Z","end":"2026-04-26T10:05:02.321688Z","steps":["trace[1748465749] 'agreement among raft nodes before linearized reading' (duration: 113.941793ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.550644Z","caller":"traceutil/trace.go:171","msg":"trace[1972283946] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"251.385479ms","start":"2026-04-26T10:05:02.299201Z","end":"2026-04-26T10:05:02.550586Z","steps":["trace[1972283946] 'process raft request' (duration: 175.434776ms)","trace[1972283946] 'compare' (duration: 75.69967ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:02.550764Z","caller":"traceutil/trace.go:171","msg":"trace[776632294] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"226.382084ms","start":"2026-04-26T10:05:02.324365Z","end":"2026-04-26T10:05:02.550747Z","steps":["trace[776632294] 'process raft request' (duration: 226.194633ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.550821Z","caller":"traceutil/trace.go:171","msg":"trace[1378218627] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"226.601376ms","start":"2026-04-26T10:05:02.32421Z","end":"2026-04-26T10:05:02.550811Z","steps":["trace[1378218627] 'process raft request' (duration: 226.303963ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.550825Z","caller":"traceutil/trace.go:171","msg":"trace[2020002486] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"225.570047ms","start":"2026-04-26T10:05:02.325226Z","end":"2026-04-26T10:05:02.550796Z","steps":["trace[2020002486] 'process raft request' (duration: 225.534797ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.550952Z","caller":"traceutil/trace.go:171","msg":"trace[1447056616] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:12; }","duration":"230.183526ms","start":"2026-04-26T10:05:02.320758Z","end":"2026-04-26T10:05:02.550941Z","steps":["trace[1447056616] 'read index received' (duration: 153.955581ms)","trace[1447056616] 'applied index is now lower than readState.Index' (duration: 76.226965ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:02.551163Z","caller":"traceutil/trace.go:171","msg":"trace[565943270] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"226.701577ms","start":"2026-04-26T10:05:02.324448Z","end":"2026-04-26T10:05:02.55115Z","steps":["trace[565943270] 'process raft request' (duration: 226.165463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:02.551225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.396806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-26T10:05:02.551248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.962781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:02.551267Z","caller":"traceutil/trace.go:171","msg":"trace[838419681] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"226.755927ms","start":"2026-04-26T10:05:02.324503Z","end":"2026-04-26T10:05:02.551259Z","steps":["trace[838419681] 'process raft request' (duration: 226.132172ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.551287Z","caller":"traceutil/trace.go:171","msg":"trace[1578519896] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:16; }","duration":"147.498728ms","start":"2026-04-26T10:05:02.403775Z","end":"2026-04-26T10:05:02.551273Z","steps":["trace[1578519896] 'agreement among raft nodes before linearized reading' (duration: 147.349427ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.551329Z","caller":"traceutil/trace.go:171","msg":"trace[1202493802] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:16; }","duration":"292.071303ms","start":"2026-04-26T10:05:02.259212Z","end":"2026-04-26T10:05:02.551283Z","steps":["trace[1202493802] 'agreement among raft nodes before linearized reading' (duration: 291.868852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:02.551476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.079884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-26T10:05:02.551518Z","caller":"traceutil/trace.go:171","msg":"trace[1137625414] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:16; }","duration":"296.149925ms","start":"2026-04-26T10:05:02.255354Z","end":"2026-04-26T10:05:02.551504Z","steps":["trace[1137625414] 'agreement among raft nodes before linearized reading' (duration: 296.091614ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.551545Z","caller":"traceutil/trace.go:171","msg":"trace[1810003724] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"226.956919ms","start":"2026-04-26T10:05:02.324563Z","end":"2026-04-26T10:05:02.55152Z","steps":["trace[1810003724] 'process raft request' (duration: 226.108142ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.551811Z","caller":"traceutil/trace.go:171","msg":"trace[114035097] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"227.213521ms","start":"2026-04-26T10:05:02.324576Z","end":"2026-04-26T10:05:02.551789Z","steps":["trace[114035097] 'process raft request' (duration: 226.159753ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.764941Z","caller":"traceutil/trace.go:171","msg":"trace[854564392] linearizableReadLoop","detail":"{readStateIndex:29; appliedIndex:27; }","duration":"138.384913ms","start":"2026-04-26T10:05:02.626531Z","end":"2026-04-26T10:05:02.764916Z","steps":["trace[854564392] 'read index received' (duration: 45.122749ms)","trace[854564392] 'applied index is now lower than readState.Index' (duration: 93.261444ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:02.765081Z","caller":"traceutil/trace.go:171","msg":"trace[996559125] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"168.008086ms","start":"2026-04-26T10:05:02.59706Z","end":"2026-04-26T10:05:02.765068Z","steps":["trace[996559125] 'process raft request' (duration: 74.4559ms)","trace[996559125] 'compare' (duration: 93.248804ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:02.765064Z","caller":"traceutil/trace.go:171","msg":"trace[1746951354] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"159.410476ms","start":"2026-04-26T10:05:02.605602Z","end":"2026-04-26T10:05:02.765012Z","steps":["trace[1746951354] 'process raft request' (duration: 159.272245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:02.765117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.362331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:02.765164Z","caller":"traceutil/trace.go:171","msg":"trace[1640616860] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:25; }","duration":"206.467051ms","start":"2026-04-26T10:05:02.558683Z","end":"2026-04-26T10:05:02.76515Z","steps":["trace[1640616860] 'agreement among raft nodes before linearized reading' (duration: 206.33392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:02.765354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.245873ms","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-04-26T10:05:02.765482Z","caller":"traceutil/trace.go:171","msg":"trace[1777669785] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:25; }","duration":"204.423834ms","start":"2026-04-26T10:05:02.561005Z","end":"2026-04-26T10:05:02.765429Z","steps":["trace[1777669785] 'agreement among raft nodes before linearized reading' (duration: 204.180102ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.772316Z","caller":"traceutil/trace.go:171","msg":"trace[1765492898] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"141.090066ms","start":"2026-04-26T10:05:02.631218Z","end":"2026-04-26T10:05:02.772308Z","steps":["trace[1765492898] 'process raft request' (duration: 141.054135ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.772304Z","caller":"traceutil/trace.go:171","msg":"trace[844293431] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"142.221315ms","start":"2026-04-26T10:05:02.630061Z","end":"2026-04-26T10:05:02.772282Z","steps":["trace[844293431] 'process raft request' (duration: 142.115025ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.772353Z","caller":"traceutil/trace.go:171","msg":"trace[2075392402] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"141.782951ms","start":"2026-04-26T10:05:02.630558Z","end":"2026-04-26T10:05:02.772341Z","steps":["trace[2075392402] 'process raft request' (duration: 141.68849ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.772477Z","caller":"traceutil/trace.go:171","msg":"trace[1758242753] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"142.090263ms","start":"2026-04-26T10:05:02.63038Z","end":"2026-04-26T10:05:02.77247Z","steps":["trace[1758242753] 'process raft request' (duration: 141.827911ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:02.772526Z","caller":"traceutil/trace.go:171","msg":"trace[404883718] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"142.018382ms","start":"2026-04-26T10:05:02.6305Z","end":"2026-04-26T10:05:02.772519Z","steps":["trace[404883718] 'process raft request' (duration: 141.72737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:03.031889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.081335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-26T10:05:03.032062Z","caller":"traceutil/trace.go:171","msg":"trace[796313356] linearizableReadLoop","detail":"{readStateIndex:41; appliedIndex:40; }","duration":"182.670913ms","start":"2026-04-26T10:05:02.849376Z","end":"2026-04-26T10:05:03.032047Z","steps":["trace[796313356] 'read index received' (duration: 74.874353ms)","trace[796313356] 'applied index is now lower than readState.Index' (duration: 107.7951ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:03.032079Z","caller":"traceutil/trace.go:171","msg":"trace[107301427] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"233.335688ms","start":"2026-04-26T10:05:02.798677Z","end":"2026-04-26T10:05:03.032012Z","steps":["trace[107301427] 'process raft request' (duration: 125.637539ms)","trace[107301427] 'compare' (duration: 106.928044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:03.032272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.877176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:03.032332Z","caller":"traceutil/trace.go:171","msg":"trace[765081506] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:38; }","duration":"182.987556ms","start":"2026-04-26T10:05:02.849333Z","end":"2026-04-26T10:05:03.03232Z","steps":["trace[765081506] 'agreement among raft nodes before linearized reading' (duration: 182.843095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:03.032329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.89341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T10:05:03.032415Z","caller":"traceutil/trace.go:171","msg":"trace[111412443] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:38; }","duration":"142.023872ms","start":"2026-04-26T10:05:02.89037Z","end":"2026-04-26T10:05:03.032394Z","steps":["trace[111412443] 'agreement among raft nodes before linearized reading' (duration: 141.872451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:06.879149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.766725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-26T10:05:06.879277Z","caller":"traceutil/trace.go:171","msg":"trace[115493261] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:268; }","duration":"118.938786ms","start":"2026-04-26T10:05:06.760316Z","end":"2026-04-26T10:05:06.879255Z","steps":["trace[115493261] 'range keys from in-memory index tree' (duration: 118.600754ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:06.9575Z","caller":"traceutil/trace.go:171","msg":"trace[2105094296] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"119.849343ms","start":"2026-04-26T10:05:06.837622Z","end":"2026-04-26T10:05:06.957472Z","steps":["trace[2105094296] 'process raft request' (duration: 119.744753ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:06.99502Z","caller":"traceutil/trace.go:171","msg":"trace[492466271] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"155.054901ms","start":"2026-04-26T10:05:06.839938Z","end":"2026-04-26T10:05:06.994993Z","steps":["trace[492466271] 'process raft request' (duration: 154.721108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:07.284914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.659299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T10:05:07.285035Z","caller":"traceutil/trace.go:171","msg":"trace[235201146] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"241.855627ms","start":"2026-04-26T10:05:07.043161Z","end":"2026-04-26T10:05:07.285016Z","steps":["trace[235201146] 'read index received' (duration: 133.925055ms)","trace[235201146] 'applied index is now lower than readState.Index' (duration: 107.929452ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:07.285126Z","caller":"traceutil/trace.go:171","msg":"trace[1927967411] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"280.416996ms","start":"2026-04-26T10:05:07.004665Z","end":"2026-04-26T10:05:07.285082Z","steps":["trace[1927967411] 'process raft request' (duration: 172.377984ms)","trace[1927967411] 'compare' (duration: 107.477828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:07.285187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.021058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-26T10:05:07.285266Z","caller":"traceutil/trace.go:171","msg":"trace[1278236013] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:271; }","duration":"242.144918ms","start":"2026-04-26T10:05:07.043109Z","end":"2026-04-26T10:05:07.285254Z","steps":["trace[1278236013] 'agreement among raft nodes before linearized reading' (duration: 242.013457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:07.285316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.778935ms","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-04-26T10:05:07.285402Z","caller":"traceutil/trace.go:171","msg":"trace[600731799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:271; }","duration":"182.919885ms","start":"2026-04-26T10:05:07.102461Z","end":"2026-04-26T10:05:07.285381Z","steps":["trace[600731799] 'agreement among raft nodes before linearized reading' (duration: 182.733204ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:07.477411Z","caller":"traceutil/trace.go:171","msg":"trace[917685850] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"126.127634ms","start":"2026-04-26T10:05:07.351256Z","end":"2026-04-26T10:05:07.477384Z","steps":["trace[917685850] 'process raft request' (duration: 49.707398ms)","trace[917685850] 'compare' (duration: 76.162284ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:07.477531Z","caller":"traceutil/trace.go:171","msg":"trace[474935821] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"125.499868ms","start":"2026-04-26T10:05:07.351961Z","end":"2026-04-26T10:05:07.477461Z","steps":["trace[474935821] 'process raft request' (duration: 125.309637ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:07.739535Z","caller":"traceutil/trace.go:171","msg":"trace[1002339782] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"246.472683ms","start":"2026-04-26T10:05:07.493023Z","end":"2026-04-26T10:05:07.739495Z","steps":["trace[1002339782] 'process raft request' (duration: 190.028012ms)","trace[1002339782] 'compare' (duration: 56.169698ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:07.739764Z","caller":"traceutil/trace.go:171","msg":"trace[169403057] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"177.272523ms","start":"2026-04-26T10:05:07.562464Z","end":"2026-04-26T10:05:07.739736Z","steps":["trace[169403057] 'process raft request' (duration: 176.899361ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:07.886311Z","caller":"traceutil/trace.go:171","msg":"trace[2116907151] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"134.7253ms","start":"2026-04-26T10:05:07.751561Z","end":"2026-04-26T10:05:07.886286Z","steps":["trace[2116907151] 'process raft request' (duration: 126.85201ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:08.113613Z","caller":"traceutil/trace.go:171","msg":"trace[1559012182] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"103.48667ms","start":"2026-04-26T10:05:08.010098Z","end":"2026-04-26T10:05:08.113585Z","steps":["trace[1559012182] 'process raft request' (duration: 82.958471ms)","trace[1559012182] 'compare' (duration: 20.159626ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:10.560341Z","caller":"traceutil/trace.go:171","msg":"trace[453034234] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"249.678216ms","start":"2026-04-26T10:05:10.310636Z","end":"2026-04-26T10:05:10.560314Z","steps":["trace[453034234] 'read index received' (duration: 249.502194ms)","trace[453034234] 'applied index is now lower than readState.Index' (duration: 175.142µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:10.560408Z","caller":"traceutil/trace.go:171","msg":"trace[199057904] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"290.957828ms","start":"2026-04-26T10:05:10.269395Z","end":"2026-04-26T10:05:10.560353Z","steps":["trace[199057904] 'process raft request' (duration: 290.795747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:10.560555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.901567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-26T10:05:10.560567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.788448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-26T10:05:10.560599Z","caller":"traceutil/trace.go:171","msg":"trace[1347895340] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:303; }","duration":"250.000709ms","start":"2026-04-26T10:05:10.310586Z","end":"2026-04-26T10:05:10.560586Z","steps":["trace[1347895340] 'agreement among raft nodes before linearized reading' (duration: 249.871277ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:10.560647Z","caller":"traceutil/trace.go:171","msg":"trace[2086836019] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:303; }","duration":"198.945682ms","start":"2026-04-26T10:05:10.361679Z","end":"2026-04-26T10:05:10.560625Z","steps":["trace[2086836019] 'agreement among raft nodes before linearized reading' (duration: 198.80647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:10.560761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.881471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-26T10:05:10.560787Z","caller":"traceutil/trace.go:171","msg":"trace[1791994080] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:303; }","duration":"148.907551ms","start":"2026-04-26T10:05:10.411871Z","end":"2026-04-26T10:05:10.560779Z","steps":["trace[1791994080] 'agreement among raft nodes before linearized reading' (duration: 148.863361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:11.11036Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121321348716519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T10:05:11.533007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.011266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T10:05:11.533115Z","caller":"traceutil/trace.go:171","msg":"trace[1221799403] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"968.414768ms","start":"2026-04-26T10:05:10.564681Z","end":"2026-04-26T10:05:11.533096Z","steps":["trace[1221799403] 'process raft request' (duration: 590.236512ms)","trace[1221799403] 'compare' (duration: 377.566102ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:11.533193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.564659Z","time spent":"968.505899ms","remote":"127.0.0.1:47882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T10:05:12.632279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.356482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T10:05:12.633392Z","caller":"traceutil/trace.go:171","msg":"trace[424956720] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"2.064778641s","start":"2026-04-26T10:05:10.568575Z","end":"2026-04-26T10:05:12.633353Z","steps":["trace[424956720] 'process raft request' (duration: 1.17726355s)","trace[424956720] 'compare' (duration: 886.193501ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:12.63348Z","caller":"traceutil/trace.go:171","msg":"trace[60697885] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"2.063241889s","start":"2026-04-26T10:05:10.570186Z","end":"2026-04-26T10:05:12.633428Z","steps":["trace[60697885] 'process raft request' (duration: 2.063027088s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:12.633513Z","caller":"traceutil/trace.go:171","msg":"trace[248959462] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"2.060244854s","start":"2026-04-26T10:05:10.573244Z","end":"2026-04-26T10:05:12.633488Z","steps":["trace[248959462] 'process raft request' (duration: 2.060119224s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:12.633527Z","caller":"traceutil/trace.go:171","msg":"trace[1471448875] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"2.060284546s","start":"2026-04-26T10:05:10.573217Z","end":"2026-04-26T10:05:12.633502Z","steps":["trace[1471448875] 'process raft request' (duration: 2.060095364s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:12.633621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.573227Z","time spent":"2.060337446s","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:12.633628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.570166Z","time spent":"2.06338128s","remote":"127.0.0.1:47954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:12.633643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.5732Z","time spent":"2.060377476s","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:12.63381Z","caller":"traceutil/trace.go:171","msg":"trace[727124099] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"2.060289566s","start":"2026-04-26T10:05:10.573504Z","end":"2026-04-26T10:05:12.633793Z","steps":["trace[727124099] 'process raft request' (duration: 2.059929893s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:12.633869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.573493Z","time spent":"2.060343356s","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:12.633968Z","caller":"traceutil/trace.go:171","msg":"trace[1718305913] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"1.096447553s","start":"2026-04-26T10:05:11.537508Z","end":"2026-04-26T10:05:12.633956Z","steps":["trace[1718305913] 'process raft request' (duration: 1.096355422s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:12.633974Z","caller":"traceutil/trace.go:171","msg":"trace[990964124] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"2.060400896s","start":"2026-04-26T10:05:10.573561Z","end":"2026-04-26T10:05:12.633962Z","steps":["trace[990964124] 'process raft request' (duration: 2.060122944s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:12.634032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.573549Z","time spent":"2.060455256s","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:12.634036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:11.537474Z","time spent":"1.096527943s","remote":"127.0.0.1:47882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:12.634003Z","caller":"traceutil/trace.go:171","msg":"trace[478654489] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"2.060282795s","start":"2026-04-26T10:05:10.573684Z","end":"2026-04-26T10:05:12.633966Z","steps":["trace[478654489] 'process raft request' (duration: 2.060104433s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:12.634141Z","caller":"traceutil/trace.go:171","msg":"trace[1095782594] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:314; }","duration":"2.024189731s","start":"2026-04-26T10:05:10.609914Z","end":"2026-04-26T10:05:12.634104Z","steps":["trace[1095782594] 'read index received' (duration: 545.029428ms)","trace[1095782594] 'applied index is now lower than readState.Index' (duration: 1.479157763s)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:12.634273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.573672Z","time spent":"2.060423576s","remote":"127.0.0.1:47882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:12.634329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.024414383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-26T10:05:12.63435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973327396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-26T10:05:12.634385Z","caller":"traceutil/trace.go:171","msg":"trace[2041362730] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:312; }","duration":"2.024523424s","start":"2026-04-26T10:05:10.609844Z","end":"2026-04-26T10:05:12.634368Z","steps":["trace[2041362730] 'agreement among raft nodes before linearized reading' (duration: 2.024426994s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:12.634392Z","caller":"traceutil/trace.go:171","msg":"trace[779816229] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:312; }","duration":"1.973405976s","start":"2026-04-26T10:05:10.660972Z","end":"2026-04-26T10:05:12.634378Z","steps":["trace[779816229] 'agreement among raft nodes before linearized reading' (duration: 1.973331426s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:12.634415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315582362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-26T10:05:12.634419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.609825Z","time spent":"2.024586315s","remote":"127.0.0.1:47980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-26T10:05:12.634433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.660954Z","time spent":"1.973468357s","remote":"127.0.0.1:47980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-04-26T10:05:12.634466Z","caller":"traceutil/trace.go:171","msg":"trace[1684556675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:312; }","duration":"1.315709693s","start":"2026-04-26T10:05:11.318739Z","end":"2026-04-26T10:05:12.634449Z","steps":["trace[1684556675] 'agreement among raft nodes before linearized reading' (duration: 1.315578522s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:12.634509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:11.318684Z","time spent":"1.315816634s","remote":"127.0.0.1:48040","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-04-26T10:05:12.634566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.893164277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-26T10:05:12.634596Z","caller":"traceutil/trace.go:171","msg":"trace[2147124294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:312; }","duration":"1.893213867s","start":"2026-04-26T10:05:10.741371Z","end":"2026-04-26T10:05:12.634585Z","steps":["trace[2147124294] 'agreement among raft nodes before linearized reading' (duration: 1.893151446s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:12.634627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.741358Z","time spent":"1.893262627s","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T10:05:12.634777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.786338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-26T10:05:12.634811Z","caller":"traceutil/trace.go:171","msg":"trace[1722253121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:312; }","duration":"480.872938ms","start":"2026-04-26T10:05:12.153928Z","end":"2026-04-26T10:05:12.634801Z","steps":["trace[1722253121] 'agreement among raft nodes before linearized reading' (duration: 480.807808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:12.634839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.153895Z","time spent":"480.93776ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-26T10:05:12.634832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046814129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-26T10:05:12.634866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.924565426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-26T10:05:12.634916Z","caller":"traceutil/trace.go:171","msg":"trace[44271478] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:312; }","duration":"1.924690957s","start":"2026-04-26T10:05:10.710212Z","end":"2026-04-26T10:05:12.634903Z","steps":["trace[44271478] 'agreement among raft nodes before linearized reading' (duration: 1.924585486s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:12.634909Z","caller":"traceutil/trace.go:171","msg":"trace[2128132210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:312; }","duration":"1.04692399s","start":"2026-04-26T10:05:11.58797Z","end":"2026-04-26T10:05:12.634894Z","steps":["trace[2128132210] 'agreement among raft nodes before linearized reading' (duration: 1.046775259s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:12.634949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.710165Z","time spent":"1.924777147s","remote":"127.0.0.1:47980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-04-26T10:05:12.634984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:11.587955Z","time spent":"1.04701782s","remote":"127.0.0.1:48040","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-04-26T10:05:12.633599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:10.568559Z","time spent":"2.064920441s","remote":"127.0.0.1:47856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T10:05:13.478074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.577163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T10:05:13.478365Z","caller":"traceutil/trace.go:171","msg":"trace[1819101439] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"837.615594ms","start":"2026-04-26T10:05:12.640718Z","end":"2026-04-26T10:05:13.478333Z","steps":["trace[1819101439] 'process raft request' (duration: 837.485543ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:13.478385Z","caller":"traceutil/trace.go:171","msg":"trace[147678140] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"837.868936ms","start":"2026-04-26T10:05:12.640459Z","end":"2026-04-26T10:05:13.478328Z","steps":["trace[147678140] 'process raft request' (duration: 593.93407ms)","trace[147678140] 'compare' (duration: 243.412672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:13.478499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.640704Z","time spent":"837.738335ms","remote":"127.0.0.1:47882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:13.478653Z","caller":"traceutil/trace.go:171","msg":"trace[460181235] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"835.74999ms","start":"2026-04-26T10:05:12.642891Z","end":"2026-04-26T10:05:13.478641Z","steps":["trace[460181235] 'process raft request' (duration: 835.492777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:13.478679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.640415Z","time spent":"838.114298ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:13.478746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.642884Z","time spent":"835.79211ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:13.478791Z","caller":"traceutil/trace.go:171","msg":"trace[712028991] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"836.039421ms","start":"2026-04-26T10:05:12.642727Z","end":"2026-04-26T10:05:13.478766Z","steps":["trace[712028991] 'process raft request' (duration: 835.539627ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:13.478813Z","caller":"traceutil/trace.go:171","msg":"trace[1306441616] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"835.466048ms","start":"2026-04-26T10:05:12.643322Z","end":"2026-04-26T10:05:13.478788Z","steps":["trace[1306441616] 'process raft request' (duration: 835.155396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:13.478902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.643313Z","time spent":"835.549308ms","remote":"127.0.0.1:48040","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-04-26T10:05:13.478909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.642687Z","time spent":"836.164033ms","remote":"127.0.0.1:47948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:13.478877Z","caller":"traceutil/trace.go:171","msg":"trace[1617568768] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"835.991221ms","start":"2026-04-26T10:05:12.642847Z","end":"2026-04-26T10:05:13.478838Z","steps":["trace[1617568768] 'process raft request' (duration: 835.481617ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:13.479049Z","caller":"traceutil/trace.go:171","msg":"trace[882146955] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"836.079232ms","start":"2026-04-26T10:05:12.64296Z","end":"2026-04-26T10:05:13.479039Z","steps":["trace[882146955] 'process raft request' (duration: 835.483428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:13.479086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.642833Z","time spent":"836.149283ms","remote":"127.0.0.1:48040","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-04-26T10:05:13.479097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.642953Z","time spent":"836.117502ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:13.479117Z","caller":"traceutil/trace.go:171","msg":"trace[1701909475] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"834.550601ms","start":"2026-04-26T10:05:12.644539Z","end":"2026-04-26T10:05:13.479089Z","steps":["trace[1701909475] 'process raft request' (duration: 833.969737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:13.479251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.644532Z","time spent":"834.652841ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:13.479308Z","caller":"traceutil/trace.go:171","msg":"trace[488159899] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"834.44404ms","start":"2026-04-26T10:05:12.644848Z","end":"2026-04-26T10:05:13.479292Z","steps":["trace[488159899] 'process raft request' (duration: 834.041857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:13.479386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.644835Z","time spent":"834.50798ms","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:13.479402Z","caller":"traceutil/trace.go:171","msg":"trace[1626017812] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"834.253639ms","start":"2026-04-26T10:05:12.645137Z","end":"2026-04-26T10:05:13.479391Z","steps":["trace[1626017812] 'process raft request' (duration: 834.039637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:13.479456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.645118Z","time spent":"834.313719ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:13.479633Z","caller":"traceutil/trace.go:171","msg":"trace[50598298] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:323; }","duration":"512.662238ms","start":"2026-04-26T10:05:12.966957Z","end":"2026-04-26T10:05:13.47962Z","steps":["trace[50598298] 'read index received' (duration: 267.462083ms)","trace[50598298] 'applied index is now lower than readState.Index' (duration: 245.199345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:13.479721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.767699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-26T10:05:13.479755Z","caller":"traceutil/trace.go:171","msg":"trace[1886211997] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:324; }","duration":"512.851459ms","start":"2026-04-26T10:05:12.966891Z","end":"2026-04-26T10:05:13.479742Z","steps":["trace[1886211997] 'agreement among raft nodes before linearized reading' (duration: 512.783759ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:13.479741Z","caller":"traceutil/trace.go:171","msg":"trace[1340603263] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"627.195373ms","start":"2026-04-26T10:05:12.852535Z","end":"2026-04-26T10:05:13.479731Z","steps":["trace[1340603263] 'process raft request' (duration: 626.984711ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:13.479776Z","caller":"traceutil/trace.go:171","msg":"trace[811935758] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"833.772354ms","start":"2026-04-26T10:05:12.645987Z","end":"2026-04-26T10:05:13.479759Z","steps":["trace[811935758] 'process raft request' (duration: 833.345801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:13.479791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.966866Z","time spent":"512.918699ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-26T10:05:13.479833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.852511Z","time spent":"627.287784ms","remote":"127.0.0.1:48040","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-04-26T10:05:13.479855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:12.645979Z","time spent":"833.832755ms","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:14.318991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.931874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T10:05:14.319269Z","caller":"traceutil/trace.go:171","msg":"trace[1844763953] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"831.546337ms","start":"2026-04-26T10:05:13.487696Z","end":"2026-04-26T10:05:14.319242Z","steps":["trace[1844763953] 'process raft request' (duration: 243.265011ms)","trace[1844763953] 'compare' (duration: 587.730993ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:14.319315Z","caller":"traceutil/trace.go:171","msg":"trace[123882038] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:336; }","duration":"760.517871ms","start":"2026-04-26T10:05:13.558776Z","end":"2026-04-26T10:05:14.319294Z","steps":["trace[123882038] 'read index received' (duration: 172.102814ms)","trace[123882038] 'applied index is now lower than readState.Index' (duration: 588.413337ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:14.319369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:13.487676Z","time spent":"831.650758ms","remote":"127.0.0.1:48212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:14.31944Z","caller":"traceutil/trace.go:171","msg":"trace[2132296706] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"828.233243ms","start":"2026-04-26T10:05:13.491192Z","end":"2026-04-26T10:05:14.319425Z","steps":["trace[2132296706] 'process raft request' (duration: 828.018321ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:14.319441Z","caller":"traceutil/trace.go:171","msg":"trace[1078784569] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"831.541737ms","start":"2026-04-26T10:05:13.487862Z","end":"2026-04-26T10:05:14.319404Z","steps":["trace[1078784569] 'process raft request' (duration: 831.239925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.319537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:13.487838Z","time spent":"831.660438ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T10:05:14.319598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.268971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-26T10:05:14.31963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.859964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-26T10:05:14.319535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:13.491182Z","time spent":"828.304723ms","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:14.319687Z","caller":"traceutil/trace.go:171","msg":"trace[252371711] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:329; }","duration":"509.434301ms","start":"2026-04-26T10:05:13.810234Z","end":"2026-04-26T10:05:14.319669Z","steps":["trace[252371711] 'agreement among raft nodes before linearized reading' (duration: 509.24733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.319734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:13.810201Z","time spent":"509.525834ms","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"info","ts":"2026-04-26T10:05:14.319685Z","caller":"traceutil/trace.go:171","msg":"trace[680127522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:329; }","duration":"760.950404ms","start":"2026-04-26T10:05:13.558724Z","end":"2026-04-26T10:05:14.319675Z","steps":["trace[680127522] 'agreement among raft nodes before linearized reading' (duration: 760.860514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.319872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:13.558655Z","time spent":"761.195165ms","remote":"127.0.0.1:47980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-26T10:05:14.320137Z","caller":"traceutil/trace.go:171","msg":"trace[2050191269] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"830.54651ms","start":"2026-04-26T10:05:13.489563Z","end":"2026-04-26T10:05:14.320109Z","steps":["trace[2050191269] 'process raft request' (duration: 829.591933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.320253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:13.48955Z","time spent":"830.636522ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:14.716177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.986997ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-26T10:05:14.716292Z","caller":"traceutil/trace.go:171","msg":"trace[661455296] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"391.843708ms","start":"2026-04-26T10:05:14.324429Z","end":"2026-04-26T10:05:14.716272Z","steps":["trace[661455296] 'read index received' (duration: 258.787531ms)","trace[661455296] 'applied index is now lower than readState.Index' (duration: 133.054447ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:14.716324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:14.320686Z","time spent":"395.629836ms","remote":"127.0.0.1:47814","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-26T10:05:14.716409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.969808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-26T10:05:14.716437Z","caller":"traceutil/trace.go:171","msg":"trace[452437737] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:329; }","duration":"392.03419ms","start":"2026-04-26T10:05:14.324394Z","end":"2026-04-26T10:05:14.716428Z","steps":["trace[452437737] 'agreement among raft nodes before linearized reading' (duration: 391.954009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.716481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:14.32438Z","time spent":"392.09273ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-26T10:05:14.860584Z","caller":"traceutil/trace.go:171","msg":"trace[519529235] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"535.374132ms","start":"2026-04-26T10:05:14.325178Z","end":"2026-04-26T10:05:14.860552Z","steps":["trace[519529235] 'process raft request' (duration: 535.088579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.860787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:14.325161Z","time spent":"535.521504ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:14.861042Z","caller":"traceutil/trace.go:171","msg":"trace[372579971] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"535.19641ms","start":"2026-04-26T10:05:14.325824Z","end":"2026-04-26T10:05:14.861021Z","steps":["trace[372579971] 'process raft request' (duration: 534.634377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.861257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:14.325815Z","time spent":"535.286751ms","remote":"127.0.0.1:48232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2824,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:14.861428Z","caller":"traceutil/trace.go:171","msg":"trace[263170766] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:341; }","duration":"145.088515ms","start":"2026-04-26T10:05:14.716319Z","end":"2026-04-26T10:05:14.861407Z","steps":["trace[263170766] 'read index received' (duration: 144.017878ms)","trace[263170766] 'applied index is now lower than readState.Index' (duration: 1.069157ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:14.86151Z","caller":"traceutil/trace.go:171","msg":"trace[928034450] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"535.600694ms","start":"2026-04-26T10:05:14.325893Z","end":"2026-04-26T10:05:14.861493Z","steps":["trace[928034450] 'process raft request' (duration: 534.921789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.861581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.452134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-26T10:05:14.861641Z","caller":"traceutil/trace.go:171","msg":"trace[1195459114] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:333; }","duration":"534.534205ms","start":"2026-04-26T10:05:14.327091Z","end":"2026-04-26T10:05:14.861625Z","steps":["trace[1195459114] 'agreement among raft nodes before linearized reading' (duration: 534.398304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.861696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:14.327078Z","time spent":"534.606176ms","remote":"127.0.0.1:48212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3729,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-26T10:05:14.861669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:14.325879Z","time spent":"535.667944ms","remote":"127.0.0.1:47856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:14.86293Z","caller":"traceutil/trace.go:171","msg":"trace[356652515] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"534.182553ms","start":"2026-04-26T10:05:14.327601Z","end":"2026-04-26T10:05:14.861784Z","steps":["trace[356652515] 'process raft request' (duration: 533.727069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:14.863201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:14.327591Z","time spent":"535.411502ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:14.921578Z","caller":"traceutil/trace.go:171","msg":"trace[26513717] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"203.890907ms","start":"2026-04-26T10:05:14.717664Z","end":"2026-04-26T10:05:14.921555Z","steps":["trace[26513717] 'process raft request' (duration: 203.689035ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:14.921566Z","caller":"traceutil/trace.go:171","msg":"trace[1666104289] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"195.817589ms","start":"2026-04-26T10:05:14.725725Z","end":"2026-04-26T10:05:14.921543Z","steps":["trace[1666104289] 'process raft request' (duration: 195.764748ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:15.122467Z","caller":"traceutil/trace.go:171","msg":"trace[2060195456] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"256.210435ms","start":"2026-04-26T10:05:14.86623Z","end":"2026-04-26T10:05:15.122441Z","steps":["trace[2060195456] 'process raft request' (duration: 246.392624ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:15.122541Z","caller":"traceutil/trace.go:171","msg":"trace[1390557596] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"254.571943ms","start":"2026-04-26T10:05:14.867934Z","end":"2026-04-26T10:05:15.122506Z","steps":["trace[1390557596] 'process raft request' (duration: 254.447353ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:15.122769Z","caller":"traceutil/trace.go:171","msg":"trace[1892125050] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"253.294464ms","start":"2026-04-26T10:05:14.869435Z","end":"2026-04-26T10:05:15.12273Z","steps":["trace[1892125050] 'process raft request' (duration: 252.995862ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:15.123128Z","caller":"traceutil/trace.go:171","msg":"trace[1361049149] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"252.111114ms","start":"2026-04-26T10:05:14.871001Z","end":"2026-04-26T10:05:15.123112Z","steps":["trace[1361049149] 'process raft request' (duration: 251.48747ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:15.123559Z","caller":"traceutil/trace.go:171","msg":"trace[1198879432] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"245.150335ms","start":"2026-04-26T10:05:14.878391Z","end":"2026-04-26T10:05:15.123542Z","steps":["trace[1198879432] 'process raft request' (duration: 244.653201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:15.123955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.523946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2839"} {"level":"info","ts":"2026-04-26T10:05:15.124074Z","caller":"traceutil/trace.go:171","msg":"trace[963279782] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:340; }","duration":"253.706497ms","start":"2026-04-26T10:05:14.870344Z","end":"2026-04-26T10:05:15.12405Z","steps":["trace[963279782] 'agreement among raft nodes before linearized reading' (duration: 253.427595ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:15.123156Z","caller":"traceutil/trace.go:171","msg":"trace[1146802829] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:345; }","duration":"252.742939ms","start":"2026-04-26T10:05:14.870378Z","end":"2026-04-26T10:05:15.123121Z","steps":["trace[1146802829] 'read index received' (duration: 51.013395ms)","trace[1146802829] 'applied index is now lower than readState.Index' (duration: 201.727994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:15.124402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.706027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-26T10:05:15.124456Z","caller":"traceutil/trace.go:171","msg":"trace[1838139748] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:340; }","duration":"200.832808ms","start":"2026-04-26T10:05:14.923607Z","end":"2026-04-26T10:05:15.12444Z","steps":["trace[1838139748] 'agreement among raft nodes before linearized reading' (duration: 200.690947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:15.12456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.312439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-5f8kv\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-26T10:05:15.124608Z","caller":"traceutil/trace.go:171","msg":"trace[1480993652] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-5f8kv; range_end:; response_count:1; response_revision:340; }","duration":"198.383879ms","start":"2026-04-26T10:05:14.926212Z","end":"2026-04-26T10:05:15.124595Z","steps":["trace[1480993652] 'agreement among raft nodes before linearized reading' (duration: 198.297189ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:15.353981Z","caller":"traceutil/trace.go:171","msg":"trace[1946161908] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"181.459543ms","start":"2026-04-26T10:05:15.172486Z","end":"2026-04-26T10:05:15.353945Z","steps":["trace[1946161908] 'process raft request' (duration: 141.760034ms)","trace[1946161908] 'compare' (duration: 39.545598ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:15.365614Z","caller":"traceutil/trace.go:171","msg":"trace[534483477] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"191.929779ms","start":"2026-04-26T10:05:15.17367Z","end":"2026-04-26T10:05:15.3656Z","steps":["trace[534483477] 'process raft request' (duration: 191.772847ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:15.3659Z","caller":"traceutil/trace.go:171","msg":"trace[2131347604] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"190.495728ms","start":"2026-04-26T10:05:15.175347Z","end":"2026-04-26T10:05:15.365843Z","steps":["trace[2131347604] 'process raft request' (duration: 190.190625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:16.000209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.405634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"warn","ts":"2026-04-26T10:05:16.000252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.852568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-26T10:05:16.000244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.246651ms","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-04-26T10:05:16.00033Z","caller":"traceutil/trace.go:171","msg":"trace[911047611] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:349; }","duration":"468.578065ms","start":"2026-04-26T10:05:15.531721Z","end":"2026-04-26T10:05:16.000299Z","steps":["trace[911047611] 'range keys from in-memory index tree' (duration: 468.220893ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:16.000366Z","caller":"traceutil/trace.go:171","msg":"trace[716738819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:349; }","duration":"512.997739ms","start":"2026-04-26T10:05:15.487336Z","end":"2026-04-26T10:05:16.000333Z","steps":["trace[716738819] 'range keys from in-memory index tree' (duration: 512.722257ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:16.000378Z","caller":"traceutil/trace.go:171","msg":"trace[1167197628] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:349; }","duration":"513.443482ms","start":"2026-04-26T10:05:15.486905Z","end":"2026-04-26T10:05:16.000349Z","steps":["trace[1167197628] 'range keys from in-memory index tree' (duration: 513.088719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:16.000397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:15.531633Z","time spent":"468.751726ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-04-26T10:05:16.000426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:15.487326Z","time spent":"513.088539ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-26T10:05:16.00044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:15.486858Z","time spent":"513.564163ms","remote":"127.0.0.1:48040","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-04-26T10:05:16.000504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.907588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-26T10:05:16.000509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.869077ms","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-04-26T10:05:16.000598Z","caller":"traceutil/trace.go:171","msg":"trace[1208999758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:349; }","duration":"513.15125ms","start":"2026-04-26T10:05:15.487421Z","end":"2026-04-26T10:05:16.000572Z","steps":["trace[1208999758] 'range keys from in-memory index tree' (duration: 512.583666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:16.000649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:15.487377Z","time spent":"513.262221ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-26T10:05:16.000665Z","caller":"traceutil/trace.go:171","msg":"trace[1428876648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:349; }","duration":"513.12665ms","start":"2026-04-26T10:05:15.487501Z","end":"2026-04-26T10:05:16.000628Z","steps":["trace[1428876648] 'range keys from in-memory index tree' (duration: 512.584956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:16.000755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:15.487459Z","time spent":"513.277021ms","remote":"127.0.0.1:48040","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":"info","ts":"2026-04-26T10:05:16.120971Z","caller":"traceutil/trace.go:171","msg":"trace[1002815432] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"110.409299ms","start":"2026-04-26T10:05:16.010524Z","end":"2026-04-26T10:05:16.120933Z","steps":["trace[1002815432] 'process raft request' (duration: 110.168837ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:16.173336Z","caller":"traceutil/trace.go:171","msg":"trace[1721488209] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"162.148303ms","start":"2026-04-26T10:05:16.011156Z","end":"2026-04-26T10:05:16.173304Z","steps":["trace[1721488209] 'process raft request' (duration: 162.071333ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:16.173346Z","caller":"traceutil/trace.go:171","msg":"trace[1918760283] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"162.396464ms","start":"2026-04-26T10:05:16.010917Z","end":"2026-04-26T10:05:16.173313Z","steps":["trace[1918760283] 'process raft request' (duration: 162.152013ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:16.173655Z","caller":"traceutil/trace.go:171","msg":"trace[356577290] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"162.281794ms","start":"2026-04-26T10:05:16.011362Z","end":"2026-04-26T10:05:16.173644Z","steps":["trace[356577290] 'process raft request' (duration: 161.902791ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:16.174096Z","caller":"traceutil/trace.go:171","msg":"trace[98550195] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"160.853194ms","start":"2026-04-26T10:05:16.013216Z","end":"2026-04-26T10:05:16.174069Z","steps":["trace[98550195] 'process raft request' (duration: 160.33723ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:16.1741Z","caller":"traceutil/trace.go:171","msg":"trace[809430416] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"157.265207ms","start":"2026-04-26T10:05:16.016809Z","end":"2026-04-26T10:05:16.174074Z","steps":["trace[809430416] 'process raft request' (duration: 157.089076ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:16.361335Z","caller":"traceutil/trace.go:171","msg":"trace[1775894875] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"162.034402ms","start":"2026-04-26T10:05:16.199243Z","end":"2026-04-26T10:05:16.361277Z","steps":["trace[1775894875] 'process raft request' (duration: 72.748436ms)","trace[1775894875] 'compare' (duration: 89.084564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:16.741311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.154283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-04-26T10:05:16.741409Z","caller":"traceutil/trace.go:171","msg":"trace[998497159] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:358; }","duration":"180.315505ms","start":"2026-04-26T10:05:16.561071Z","end":"2026-04-26T10:05:16.741387Z","steps":["trace[998497159] 'range keys from in-memory index tree' (duration: 180.014663ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:18.341823Z","caller":"traceutil/trace.go:171","msg":"trace[1027841104] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"142.549747ms","start":"2026-04-26T10:05:18.199242Z","end":"2026-04-26T10:05:18.341792Z","steps":["trace[1027841104] 'process raft request' (duration: 142.494016ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:18.341853Z","caller":"traceutil/trace.go:171","msg":"trace[1092346019] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"142.602237ms","start":"2026-04-26T10:05:18.199219Z","end":"2026-04-26T10:05:18.341821Z","steps":["trace[1092346019] 'process raft request' (duration: 76.217264ms)","trace[1092346019] 'compare' (duration: 66.141791ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:22.480563Z","caller":"traceutil/trace.go:171","msg":"trace[2090463862] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"110.630357ms","start":"2026-04-26T10:05:22.36991Z","end":"2026-04-26T10:05:22.48054Z","steps":["trace[2090463862] 'read index received' (duration: 106.961751ms)","trace[2090463862] 'applied index is now lower than readState.Index' (duration: 3.667636ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:22.480607Z","caller":"traceutil/trace.go:171","msg":"trace[494030245] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"114.243621ms","start":"2026-04-26T10:05:22.366331Z","end":"2026-04-26T10:05:22.480574Z","steps":["trace[494030245] 'process raft request' (duration: 110.587826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:22.480723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.786098ms","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-04-26T10:05:22.480769Z","caller":"traceutil/trace.go:171","msg":"trace[2114279645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"110.861638ms","start":"2026-04-26T10:05:22.369897Z","end":"2026-04-26T10:05:22.480759Z","steps":["trace[2114279645] 'agreement among raft nodes before linearized reading' (duration: 110.734458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:24.310858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121321348716642,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-26T10:05:24.346259Z","caller":"traceutil/trace.go:171","msg":"trace[1252668845] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"535.830059ms","start":"2026-04-26T10:05:23.810399Z","end":"2026-04-26T10:05:24.346229Z","steps":["trace[1252668845] 'read index received' (duration: 535.640897ms)","trace[1252668845] 'applied index is now lower than readState.Index' (duration: 183.392µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:24.346334Z","caller":"traceutil/trace.go:171","msg":"trace[1122843773] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"537.998354ms","start":"2026-04-26T10:05:23.808289Z","end":"2026-04-26T10:05:24.346287Z","steps":["trace[1122843773] 'process raft request' (duration: 537.705291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:24.346592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:23.808257Z","time spent":"538.185045ms","remote":"127.0.0.1:48040","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-04-26T10:05:24.347359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.07848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-26T10:05:24.347412Z","caller":"traceutil/trace.go:171","msg":"trace[1451596446] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:378; }","duration":"537.046726ms","start":"2026-04-26T10:05:23.810352Z","end":"2026-04-26T10:05:24.347399Z","steps":["trace[1451596446] 'agreement among raft nodes before linearized reading' (duration: 536.06715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:24.34746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:23.810342Z","time spent":"537.107606ms","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"info","ts":"2026-04-26T10:05:26.365118Z","caller":"traceutil/trace.go:171","msg":"trace[607198796] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"115.942365ms","start":"2026-04-26T10:05:26.249137Z","end":"2026-04-26T10:05:26.36508Z","steps":["trace[607198796] 'process raft request' (duration: 97.119757ms)","trace[607198796] 'compare' (duration: 18.248963ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:36.820243Z","caller":"traceutil/trace.go:171","msg":"trace[196700399] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"277.323923ms","start":"2026-04-26T10:05:36.542896Z","end":"2026-04-26T10:05:36.82022Z","steps":["trace[196700399] 'process raft request' (duration: 277.206612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:37.398819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.469589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T10:05:37.399295Z","caller":"traceutil/trace.go:171","msg":"trace[2074844309] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:455; }","duration":"727.777985ms","start":"2026-04-26T10:05:36.671456Z","end":"2026-04-26T10:05:37.399234Z","steps":["trace[2074844309] 'read index received' (duration: 148.755786ms)","trace[2074844309] 'applied index is now lower than readState.Index' (duration: 578.991509ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:37.399513Z","caller":"traceutil/trace.go:171","msg":"trace[383619495] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"853.880097ms","start":"2026-04-26T10:05:36.545623Z","end":"2026-04-26T10:05:37.399503Z","steps":["trace[383619495] 'process raft request' (duration: 662.595852ms)","trace[383619495] 'compare' (duration: 190.021907ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:37.399778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:36.545606Z","time spent":"853.926837ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:37.400139Z","caller":"traceutil/trace.go:171","msg":"trace[58311881] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"791.604026ms","start":"2026-04-26T10:05:36.608502Z","end":"2026-04-26T10:05:37.400106Z","steps":["trace[58311881] 'process raft request' (duration: 790.60937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:37.40041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:36.60848Z","time spent":"791.850438ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:37.402261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.762765ms","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-04-26T10:05:37.402384Z","caller":"traceutil/trace.go:171","msg":"trace[207645302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:439; }","duration":"730.929316ms","start":"2026-04-26T10:05:36.671409Z","end":"2026-04-26T10:05:37.402338Z","steps":["trace[207645302] 'agreement among raft nodes before linearized reading' (duration: 728.155748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:37.402493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:36.671393Z","time spent":"731.040687ms","remote":"127.0.0.1:48040","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-04-26T10:05:37.402725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.458358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-26T10:05:37.403511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.323389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T10:05:37.403574Z","caller":"traceutil/trace.go:171","msg":"trace[592362240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:439; }","duration":"700.42833ms","start":"2026-04-26T10:05:36.703127Z","end":"2026-04-26T10:05:37.403556Z","steps":["trace[592362240] 'agreement among raft nodes before linearized reading' (duration: 696.539416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:37.403604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:36.703107Z","time spent":"700.491312ms","remote":"127.0.0.1:60898","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-04-26T10:05:37.403768Z","caller":"traceutil/trace.go:171","msg":"trace[544983389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:439; }","duration":"699.601456ms","start":"2026-04-26T10:05:36.704137Z","end":"2026-04-26T10:05:37.403738Z","steps":["trace[544983389] 'agreement among raft nodes before linearized reading' (duration: 696.821478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:37.40396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:36.704116Z","time spent":"699.721346ms","remote":"127.0.0.1:48040","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-04-26T10:05:40.819681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121321348716853,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T10:05:41.295033Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.046412875s","expected-duration":"1s"} {"level":"info","ts":"2026-04-26T10:05:41.295639Z","caller":"traceutil/trace.go:171","msg":"trace[1046935411] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.047097819s","start":"2026-04-26T10:05:40.248502Z","end":"2026-04-26T10:05:41.2956Z","steps":["trace[1046935411] 'process raft request' (duration: 1.046813537s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.295868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.248479Z","time spent":"1.04728028s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:41.308238Z","caller":"traceutil/trace.go:171","msg":"trace[532285485] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.056125215s","start":"2026-04-26T10:05:40.252083Z","end":"2026-04-26T10:05:41.308208Z","steps":["trace[532285485] 'process raft request' (duration: 1.055917384s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:41.308362Z","caller":"traceutil/trace.go:171","msg":"trace[1049607872] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.054525975s","start":"2026-04-26T10:05:40.253821Z","end":"2026-04-26T10:05:41.308347Z","steps":["trace[1049607872] 'process raft request' (duration: 1.054271563s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:41.308768Z","caller":"traceutil/trace.go:171","msg":"trace[1271614145] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.040710449s","start":"2026-04-26T10:05:40.268035Z","end":"2026-04-26T10:05:41.308746Z","steps":["trace[1271614145] 'process raft request' (duration: 1.040542278s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.308841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.268027Z","time spent":"1.040777919s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T10:05:41.308796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.253803Z","time spent":"1.054966018s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T10:05:41.308376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.252066Z","time spent":"1.056237016s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:41.309066Z","caller":"traceutil/trace.go:171","msg":"trace[318173270] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.036072909s","start":"2026-04-26T10:05:40.272953Z","end":"2026-04-26T10:05:41.309026Z","steps":["trace[318173270] 'process raft request' (duration: 1.035674136s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:41.308417Z","caller":"traceutil/trace.go:171","msg":"trace[1597401177] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.05227169s","start":"2026-04-26T10:05:40.256137Z","end":"2026-04-26T10:05:41.308409Z","steps":["trace[1597401177] 'process raft request' (duration: 1.052075379s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.309169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.256116Z","time spent":"1.053030335s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:41.308616Z","caller":"traceutil/trace.go:171","msg":"trace[1953537668] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.051528556s","start":"2026-04-26T10:05:40.257076Z","end":"2026-04-26T10:05:41.308605Z","steps":["trace[1953537668] 'process raft request' (duration: 1.051177883s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.309228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.272943Z","time spent":"1.036211259s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:41.309249Z","caller":"traceutil/trace.go:171","msg":"trace[828108141] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.033314462s","start":"2026-04-26T10:05:40.275891Z","end":"2026-04-26T10:05:41.309206Z","steps":["trace[828108141] 'process raft request' (duration: 1.032848839s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:41.309288Z","caller":"traceutil/trace.go:171","msg":"trace[2104497053] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.015464998s","start":"2026-04-26T10:05:40.293814Z","end":"2026-04-26T10:05:41.309279Z","steps":["trace[2104497053] 'process raft request' (duration: 1.014997985s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.309319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.293794Z","time spent":"1.015511448s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T10:05:41.309248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.25706Z","time spent":"1.05217119s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:41.309391Z","caller":"traceutil/trace.go:171","msg":"trace[1290169479] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"892.335591ms","start":"2026-04-26T10:05:40.417045Z","end":"2026-04-26T10:05:41.309381Z","steps":["trace[1290169479] 'process raft request' (duration: 892.058529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.309401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.275863Z","time spent":"1.033455752s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:41.308689Z","caller":"traceutil/trace.go:171","msg":"trace[817991520] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.041368383s","start":"2026-04-26T10:05:40.267316Z","end":"2026-04-26T10:05:41.308684Z","steps":["trace[817991520] 'process raft request' (duration: 1.041187952s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.309422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.417014Z","time spent":"892.393391ms","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:41.308653Z","caller":"traceutil/trace.go:171","msg":"trace[1896133246] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.043245435s","start":"2026-04-26T10:05:40.265401Z","end":"2026-04-26T10:05:41.308647Z","steps":["trace[1896133246] 'process raft request' (duration: 1.042903912s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.309563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.265391Z","time spent":"1.044123229s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T10:05:41.309466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.267309Z","time spent":"1.042133747s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T10:05:41.30971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.730861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T10:05:41.309952Z","caller":"traceutil/trace.go:171","msg":"trace[1363374190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"991.002315ms","start":"2026-04-26T10:05:40.318923Z","end":"2026-04-26T10:05:41.309926Z","steps":["trace[1363374190] 'agreement among raft nodes before linearized reading' (duration: 990.712522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.310004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.318909Z","time spent":"991.078345ms","remote":"127.0.0.1:60898","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-04-26T10:05:41.309434Z","caller":"traceutil/trace.go:171","msg":"trace[1214895089] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:498; }","duration":"990.316199ms","start":"2026-04-26T10:05:40.318959Z","end":"2026-04-26T10:05:41.309275Z","steps":["trace[1214895089] 'read index received' (duration: 976.412302ms)","trace[1214895089] 'applied index is now lower than readState.Index' (duration: 13.902587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:41.317555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.742866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T10:05:41.317614Z","caller":"traceutil/trace.go:171","msg":"trace[1347489041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"614.853388ms","start":"2026-04-26T10:05:40.702746Z","end":"2026-04-26T10:05:41.317599Z","steps":["trace[1347489041] 'agreement among raft nodes before linearized reading' (duration: 614.648977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.317645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.702695Z","time spent":"614.943928ms","remote":"127.0.0.1:60912","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-04-26T10:05:41.317979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.51688ms","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-04-26T10:05:41.318009Z","caller":"traceutil/trace.go:171","msg":"trace[2086681708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"485.584331ms","start":"2026-04-26T10:05:40.832415Z","end":"2026-04-26T10:05:41.318Z","steps":["trace[2086681708] 'agreement among raft nodes before linearized reading' (duration: 485.46143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:41.318035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:40.832395Z","time spent":"485.633871ms","remote":"127.0.0.1:48040","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-04-26T10:05:42.110926Z","caller":"traceutil/trace.go:171","msg":"trace[1346371963] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"475.604351ms","start":"2026-04-26T10:05:41.635291Z","end":"2026-04-26T10:05:42.110895Z","steps":["trace[1346371963] 'process raft request' (duration: 468.545907ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:42.110978Z","caller":"traceutil/trace.go:171","msg":"trace[294038942] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:557; }","duration":"408.119477ms","start":"2026-04-26T10:05:41.702837Z","end":"2026-04-26T10:05:42.110957Z","steps":["trace[294038942] 'read index received' (duration: 401.012722ms)","trace[294038942] 'applied index is now lower than readState.Index' (duration: 7.106335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:42.111073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.227237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T10:05:42.111093Z","caller":"traceutil/trace.go:171","msg":"trace[1608889735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"408.291617ms","start":"2026-04-26T10:05:41.702795Z","end":"2026-04-26T10:05:42.111087Z","steps":["trace[1608889735] 'agreement among raft nodes before linearized reading' (duration: 408.229147ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:42.111071Z","caller":"traceutil/trace.go:171","msg":"trace[880375312] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"467.301369ms","start":"2026-04-26T10:05:41.643737Z","end":"2026-04-26T10:05:42.111038Z","steps":["trace[880375312] 'process raft request' (duration: 466.830966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:42.111117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:41.702782Z","time spent":"408.328938ms","remote":"127.0.0.1:60898","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-04-26T10:05:42.11111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:41.635268Z","time spent":"475.737572ms","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:42.11126Z","caller":"traceutil/trace.go:171","msg":"trace[1399601171] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"435.631219ms","start":"2026-04-26T10:05:41.675621Z","end":"2026-04-26T10:05:42.111252Z","steps":["trace[1399601171] 'process raft request' (duration: 435.268757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:42.111263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:41.643718Z","time spent":"467.45013ms","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:42.111312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:41.675602Z","time spent":"435.67451ms","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T10:05:47.595914Z","caller":"traceutil/trace.go:171","msg":"trace[903427603] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"123.450358ms","start":"2026-04-26T10:05:47.47243Z","end":"2026-04-26T10:05:47.59588Z","steps":["trace[903427603] 'read index received' (duration: 26.593331ms)","trace[903427603] 'applied index is now lower than readState.Index' (duration: 96.855597ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:47.596102Z","caller":"traceutil/trace.go:171","msg":"trace[1456122379] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"208.460894ms","start":"2026-04-26T10:05:47.387513Z","end":"2026-04-26T10:05:47.595974Z","steps":["trace[1456122379] 'process raft request' (duration: 111.96951ms)","trace[1456122379] 'compare' (duration: 95.848301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:47.596765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.857762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-26T10:05:47.596893Z","caller":"traceutil/trace.go:171","msg":"trace[270411699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"124.491517ms","start":"2026-04-26T10:05:47.472371Z","end":"2026-04-26T10:05:47.596862Z","steps":["trace[270411699] 'agreement among raft nodes before linearized reading' (duration: 123.666366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:48.143581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.596848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T10:05:48.143888Z","caller":"traceutil/trace.go:171","msg":"trace[807355359] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"500.742357ms","start":"2026-04-26T10:05:47.643107Z","end":"2026-04-26T10:05:48.14385Z","steps":["trace[807355359] 'process raft request' (duration: 335.742855ms)","trace[807355359] 'compare' (duration: 164.283556ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:05:48.143856Z","caller":"traceutil/trace.go:171","msg":"trace[2016867042] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"444.899307ms","start":"2026-04-26T10:05:47.698938Z","end":"2026-04-26T10:05:48.143837Z","steps":["trace[2016867042] 'read index received' (duration: 279.906155ms)","trace[2016867042] 'applied index is now lower than readState.Index' (duration: 164.992022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:48.144052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:47.643082Z","time spent":"500.885363ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:05:48.144242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.002626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2v755\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-26T10:05:48.144272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.327474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T10:05:48.144305Z","caller":"traceutil/trace.go:171","msg":"trace[267324994] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2v755; range_end:; response_count:1; response_revision:592; }","duration":"204.09927ms","start":"2026-04-26T10:05:47.940192Z","end":"2026-04-26T10:05:48.144292Z","steps":["trace[267324994] 'agreement among raft nodes before linearized reading' (duration: 203.922253ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:05:48.144338Z","caller":"traceutil/trace.go:171","msg":"trace[817268458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"445.388395ms","start":"2026-04-26T10:05:47.698915Z","end":"2026-04-26T10:05:48.144303Z","steps":["trace[817268458] 'agreement among raft nodes before linearized reading' (duration: 445.031712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:48.144375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:47.698906Z","time spent":"445.460588ms","remote":"127.0.0.1:60912","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-04-26T10:05:48.144671Z","caller":"traceutil/trace.go:171","msg":"trace[1146230453] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"475.456096ms","start":"2026-04-26T10:05:47.6692Z","end":"2026-04-26T10:05:48.144656Z","steps":["trace[1146230453] 'process raft request' (duration: 474.550592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:48.144756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:47.669179Z","time spent":"475.525729ms","remote":"127.0.0.1:48272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T10:05:49.20322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121321348717045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T10:05:49.703719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121321348717045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T10:05:50.031587Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.484476626s","expected-duration":"1s"} {"level":"info","ts":"2026-04-26T10:05:50.032613Z","caller":"traceutil/trace.go:171","msg":"trace[1229634851] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.486940245s","start":"2026-04-26T10:05:48.545644Z","end":"2026-04-26T10:05:50.032584Z","steps":["trace[1229634851] 'process raft request' (duration: 1.486365755s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:50.032768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:48.545616Z","time spent":"1.48706743s","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T10:05:50.075551Z","caller":"traceutil/trace.go:171","msg":"trace[1136699992] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"1.372778066s","start":"2026-04-26T10:05:48.702746Z","end":"2026-04-26T10:05:50.075524Z","steps":["trace[1136699992] 'read index received' (duration: 1.329019353s)","trace[1136699992] 'applied index is now lower than readState.Index' (duration: 43.757323ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T10:05:50.075709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372943492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T10:05:50.07574Z","caller":"traceutil/trace.go:171","msg":"trace[491796094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"1.373056456s","start":"2026-04-26T10:05:48.702674Z","end":"2026-04-26T10:05:50.075731Z","steps":["trace[491796094] 'agreement among raft nodes before linearized reading' (duration: 1.372974253s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:50.075779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:48.702655Z","time spent":"1.373116528s","remote":"127.0.0.1:60898","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-04-26T10:05:50.075795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.266502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-26T10:05:50.075818Z","caller":"traceutil/trace.go:171","msg":"trace[539257615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"379.325004ms","start":"2026-04-26T10:05:49.696487Z","end":"2026-04-26T10:05:50.075812Z","steps":["trace[539257615] 'agreement among raft nodes before linearized reading' (duration: 379.226531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:50.075834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:49.696473Z","time spent":"379.358056ms","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T10:05:50.075831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.526402ms","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-04-26T10:05:50.075892Z","caller":"traceutil/trace.go:171","msg":"trace[1968232166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"646.629686ms","start":"2026-04-26T10:05:49.429243Z","end":"2026-04-26T10:05:50.075872Z","steps":["trace[1968232166] 'agreement among raft nodes before linearized reading' (duration: 646.44074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:50.075931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:49.429223Z","time spent":"646.700849ms","remote":"127.0.0.1:48040","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-04-26T10:05:50.07597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.415968ms","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-04-26T10:05:50.075986Z","caller":"traceutil/trace.go:171","msg":"trace[2097286148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"379.460099ms","start":"2026-04-26T10:05:49.696521Z","end":"2026-04-26T10:05:50.075982Z","steps":["trace[2097286148] 'agreement among raft nodes before linearized reading' (duration: 379.417718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:50.075998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:49.696509Z","time spent":"379.48589ms","remote":"127.0.0.1:48040","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-04-26T10:05:50.076077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.214779ms","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-04-26T10:05:50.07609Z","caller":"traceutil/trace.go:171","msg":"trace[1380207338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"448.266292ms","start":"2026-04-26T10:05:49.627819Z","end":"2026-04-26T10:05:50.076086Z","steps":["trace[1380207338] 'agreement among raft nodes before linearized reading' (duration: 448.21728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:05:50.076102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:05:49.627801Z","time spent":"448.298092ms","remote":"127.0.0.1:48040","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-04-26T10:06:06.662372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121321348717372,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T10:06:07.163298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121321348717372,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T10:06:07.641209Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.592907692s","expected-duration":"1s"} {"level":"info","ts":"2026-04-26T10:06:07.641639Z","caller":"traceutil/trace.go:171","msg":"trace[1080447088] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"1.479511736s","start":"2026-04-26T10:06:06.1621Z","end":"2026-04-26T10:06:07.641612Z","steps":["trace[1080447088] 'read index received' (duration: 1.479376262s)","trace[1080447088] 'applied index is now lower than readState.Index' (duration: 134.134µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:06:07.641784Z","caller":"traceutil/trace.go:171","msg":"trace[247507196] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"1.593585772s","start":"2026-04-26T10:06:06.048186Z","end":"2026-04-26T10:06:07.641772Z","steps":["trace[247507196] 'process raft request' (duration: 1.593287243s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:06:07.641929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:06:06.048161Z","time spent":"1.593676375s","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:06:07.642039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479918328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-26T10:06:07.642168Z","caller":"traceutil/trace.go:171","msg":"trace[1593706922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"1.480095343s","start":"2026-04-26T10:06:06.162054Z","end":"2026-04-26T10:06:07.642149Z","steps":["trace[1593706922] 'agreement among raft nodes before linearized reading' (duration: 1.479830435s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:06:07.642217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:06:06.162034Z","time spent":"1.480171674s","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-26T10:06:07.655232Z","caller":"traceutil/trace.go:171","msg":"trace[371305340] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"362.732936ms","start":"2026-04-26T10:06:07.292484Z","end":"2026-04-26T10:06:07.655217Z","steps":["trace[371305340] 'process raft request' (duration: 362.603952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:06:07.655427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:06:07.292458Z","time spent":"362.811198ms","remote":"127.0.0.1:47954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5051,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T10:06:07.655478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4871633s","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-04-26T10:06:07.655534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487117199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-26T10:06:07.655518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486357287s","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-04-26T10:06:07.655608Z","caller":"traceutil/trace.go:171","msg":"trace[905752200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.487269954s","start":"2026-04-26T10:06:06.168323Z","end":"2026-04-26T10:06:07.655593Z","steps":["trace[905752200] 'agreement among raft nodes before linearized reading' (duration: 1.487044627s)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:06:07.65561Z","caller":"traceutil/trace.go:171","msg":"trace[486865028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"1.487353705s","start":"2026-04-26T10:06:06.168227Z","end":"2026-04-26T10:06:07.65558Z","steps":["trace[486865028] 'agreement among raft nodes before linearized reading' (duration: 1.486997115s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:06:07.655644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:06:06.168289Z","time spent":"1.487349056s","remote":"127.0.0.1:48040","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":"info","ts":"2026-04-26T10:06:07.655654Z","caller":"traceutil/trace.go:171","msg":"trace[402364748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.486525092s","start":"2026-04-26T10:06:06.169103Z","end":"2026-04-26T10:06:07.655628Z","steps":["trace[402364748] 'agreement among raft nodes before linearized reading' (duration: 1.486143341s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:06:07.655662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:06:06.168206Z","time spent":"1.487445118s","remote":"127.0.0.1:48040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T10:06:07.655718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T10:06:06.169073Z","time spent":"1.486635235s","remote":"127.0.0.1:48040","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-04-26T10:07:18.565734Z","caller":"traceutil/trace.go:171","msg":"trace[852659209] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"136.858198ms","start":"2026-04-26T10:07:18.428847Z","end":"2026-04-26T10:07:18.565705Z","steps":["trace[852659209] 'process raft request' (duration: 136.683686ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:07:42.536146Z","caller":"traceutil/trace.go:171","msg":"trace[1259602433] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"109.678798ms","start":"2026-04-26T10:07:42.426447Z","end":"2026-04-26T10:07:42.536126Z","steps":["trace[1259602433] 'process raft request' (duration: 66.263945ms)","trace[1259602433] 'compare' (duration: 43.323772ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:07:46.547453Z","caller":"traceutil/trace.go:171","msg":"trace[115603455] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"139.315683ms","start":"2026-04-26T10:07:46.408108Z","end":"2026-04-26T10:07:46.547423Z","steps":["trace[115603455] 'process raft request' (duration: 134.758922ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:07:46.593894Z","caller":"traceutil/trace.go:171","msg":"trace[1672517225] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1284; }","duration":"175.832031ms","start":"2026-04-26T10:07:46.418041Z","end":"2026-04-26T10:07:46.593873Z","steps":["trace[1672517225] 'read index received' (duration: 124.816729ms)","trace[1672517225] 'applied index is now lower than readState.Index' (duration: 51.014212ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T10:07:46.594002Z","caller":"traceutil/trace.go:171","msg":"trace[469144300] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"183.96959ms","start":"2026-04-26T10:07:46.410009Z","end":"2026-04-26T10:07:46.593978Z","steps":["trace[469144300] 'process raft request' (duration: 183.681227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:07:46.594094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.036663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-26T10:07:46.594131Z","caller":"traceutil/trace.go:171","msg":"trace[1404138867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"176.118216ms","start":"2026-04-26T10:07:46.418Z","end":"2026-04-26T10:07:46.594119Z","steps":["trace[1404138867] 'agreement among raft nodes before linearized reading' (duration: 175.993893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T10:07:46.594217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.563921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-26T10:07:46.594282Z","caller":"traceutil/trace.go:171","msg":"trace[878377251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"152.693663ms","start":"2026-04-26T10:07:46.441572Z","end":"2026-04-26T10:07:46.594265Z","steps":["trace[878377251] 'agreement among raft nodes before linearized reading' (duration: 152.468019ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:07:58.850313Z","caller":"traceutil/trace.go:171","msg":"trace[1731058575] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"103.805091ms","start":"2026-04-26T10:07:58.746484Z","end":"2026-04-26T10:07:58.850289Z","steps":["trace[1731058575] 'process raft request' (duration: 103.674589ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:08:29.051686Z","caller":"traceutil/trace.go:171","msg":"trace[101848523] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"117.908329ms","start":"2026-04-26T10:08:28.933758Z","end":"2026-04-26T10:08:29.051666Z","steps":["trace[101848523] 'process raft request' (duration: 117.769058ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:08:29.100269Z","caller":"traceutil/trace.go:171","msg":"trace[865867926] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"162.985695ms","start":"2026-04-26T10:08:28.937253Z","end":"2026-04-26T10:08:29.100239Z","steps":["trace[865867926] 'process raft request' (duration: 162.782983ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:08:29.100292Z","caller":"traceutil/trace.go:171","msg":"trace[91539856] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"130.998664ms","start":"2026-04-26T10:08:28.969264Z","end":"2026-04-26T10:08:29.100263Z","steps":["trace[91539856] 'process raft request' (duration: 130.895183ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T10:08:29.242359Z","caller":"traceutil/trace.go:171","msg":"trace[1141681020] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"137.958252ms","start":"2026-04-26T10:08:29.104373Z","end":"2026-04-26T10:08:29.242331Z","steps":["trace[1141681020] 'process raft request' (duration: 42.502449ms)","trace[1141681020] 'compare' (duration: 95.313402ms)"],"step_count":2}