{"level":"warn","ts":"2026-04-28T22:18:47.359829Z","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-28T22:18:47.359952Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-28T22:18:47.360039Z","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-28T22:18:47.360061Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-28T22:18:47.360088Z","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-28T22:18:47.361018Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-04-28T22:18:47.361123Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-28T22:18:47.95032Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"575.947836ms"} {"level":"info","ts":"2026-04-28T22:18:48.110664Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-04-28T22:18:48.110828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T22:18:48.110863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-04-28T22:18:48.110898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T22:18:48.110917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-04-28T22:18:48.110969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-04-28T22:18:48.140834Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T22:18:48.207342Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T22:18:48.268402Z","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-28T22:18:48.301137Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T22:18:48.301475Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T22:18:48.30169Z","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-28T22:18:48.30184Z","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-28T22:18:48.301884Z","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-28T22:18:48.305743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-04-28T22:18:48.305938Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-28T22:18:48.306964Z","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-28T22:18:48.307073Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-28T22:18:48.307112Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-28T22:18:48.307401Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T22:18:48.307455Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T22:18:48.611491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T22:18:48.611582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T22:18:48.611645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-04-28T22:18:48.611675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-04-28T22:18:48.611688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-28T22:18:48.61171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-04-28T22:18:48.61173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-28T22:18:48.622287Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:18:48.626323Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T22:18:48.626345Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T22:18:48.626329Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T22:18:48.626647Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T22:18:48.626705Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T22:18:48.629433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-04-28T22:18:48.630069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T22:18:48.637525Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:18:48.637704Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:18:48.637751Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-28T22:18:48.920277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.330116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:48.921005Z","caller":"traceutil/trace.go:171","msg":"trace[553133682] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"166.108863ms","start":"2026-04-28T22:18:48.754875Z","end":"2026-04-28T22:18:48.920984Z","steps":["trace[553133682] 'count revisions from in-memory index tree' (duration: 165.244926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:48.920281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.576748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:48.921119Z","caller":"traceutil/trace.go:171","msg":"trace[1129867131] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"116.500705ms","start":"2026-04-28T22:18:48.804592Z","end":"2026-04-28T22:18:48.921093Z","steps":["trace[1129867131] 'range keys from in-memory index tree' (duration: 115.446536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:48.920481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.76306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:48.921267Z","caller":"traceutil/trace.go:171","msg":"trace[911556674] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"108.563985ms","start":"2026-04-28T22:18:48.812691Z","end":"2026-04-28T22:18:48.921255Z","steps":["trace[911556674] 'range keys from in-memory index tree' (duration: 107.650989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:48.92052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.02296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:48.921387Z","caller":"traceutil/trace.go:171","msg":"trace[1650502185] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"151.879546ms","start":"2026-04-28T22:18:48.769475Z","end":"2026-04-28T22:18:48.921355Z","steps":["trace[1650502185] 'count revisions from in-memory index tree' (duration: 150.99886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:48.920547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.859291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:48.921491Z","caller":"traceutil/trace.go:171","msg":"trace[1043406617] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"108.804258ms","start":"2026-04-28T22:18:48.812661Z","end":"2026-04-28T22:18:48.921465Z","steps":["trace[1043406617] 'count revisions from in-memory index tree' (duration: 107.75878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:48.920596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.135551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:48.921585Z","caller":"traceutil/trace.go:171","msg":"trace[380095225] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"152.120348ms","start":"2026-04-28T22:18:48.769438Z","end":"2026-04-28T22:18:48.921558Z","steps":["trace[380095225] 'range keys from in-memory index tree' (duration: 151.07183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:48.92061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.063058ms","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-28T22:18:48.921693Z","caller":"traceutil/trace.go:171","msg":"trace[50854000] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"207.216747ms","start":"2026-04-28T22:18:48.714467Z","end":"2026-04-28T22:18:48.921684Z","steps":["trace[50854000] 'count revisions from in-memory index tree' (duration: 205.978608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:48.920655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.606011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:48.921787Z","caller":"traceutil/trace.go:171","msg":"trace[1434199949] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"142.781509ms","start":"2026-04-28T22:18:48.77898Z","end":"2026-04-28T22:18:48.921762Z","steps":["trace[1434199949] 'range keys from in-memory index tree' (duration: 141.575901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:48.920672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.536845ms","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":"warn","ts":"2026-04-28T22:18:48.920687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.613151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T22:18:48.920709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.589455ms","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":"warn","ts":"2026-04-28T22:18:48.920736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.622095ms","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":"warn","ts":"2026-04-28T22:18:48.920729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.064574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T22:18:48.920747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.670095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T22:18:48.920772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.473945ms","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":"warn","ts":"2026-04-28T22:18:48.920782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.077375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T22:18:48.920811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.029682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:48.92204Z","caller":"traceutil/trace.go:171","msg":"trace[1656232927] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"142.976011ms","start":"2026-04-28T22:18:48.779051Z","end":"2026-04-28T22:18:48.922027Z","steps":["trace[1656232927] 'count revisions from in-memory index tree' (duration: 141.5702ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:48.922235Z","caller":"traceutil/trace.go:171","msg":"trace[742345301] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"135.571715ms","start":"2026-04-28T22:18:48.786635Z","end":"2026-04-28T22:18:48.922207Z","steps":["trace[742345301] 'count revisions from in-memory index tree' (duration: 133.982324ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:48.922354Z","caller":"traceutil/trace.go:171","msg":"trace[1503636829] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"180.089736ms","start":"2026-04-28T22:18:48.742252Z","end":"2026-04-28T22:18:48.922341Z","steps":["trace[1503636829] 'count revisions from in-memory index tree' (duration: 178.402664ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:48.922097Z","caller":"traceutil/trace.go:171","msg":"trace[2115205681] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"194.982435ms","start":"2026-04-28T22:18:48.727107Z","end":"2026-04-28T22:18:48.922089Z","steps":["trace[2115205681] 'count revisions from in-memory index tree' (duration: 193.460635ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:48.922521Z","caller":"traceutil/trace.go:171","msg":"trace[695198228] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"167.745824ms","start":"2026-04-28T22:18:48.754759Z","end":"2026-04-28T22:18:48.922505Z","steps":["trace[695198228] 'range keys from in-memory index tree' (duration: 165.993822ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:48.922778Z","caller":"traceutil/trace.go:171","msg":"trace[506676463] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"136.088669ms","start":"2026-04-28T22:18:48.786677Z","end":"2026-04-28T22:18:48.922766Z","steps":["trace[506676463] 'range keys from in-memory index tree' (duration: 134.060485ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:48.922121Z","caller":"traceutil/trace.go:171","msg":"trace[192903371] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"180.019195ms","start":"2026-04-28T22:18:48.742096Z","end":"2026-04-28T22:18:48.922115Z","steps":["trace[192903371] 'range keys from in-memory index tree' (duration: 178.585585ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:48.922166Z","caller":"traceutil/trace.go:171","msg":"trace[1270299908] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"195.062336ms","start":"2026-04-28T22:18:48.727097Z","end":"2026-04-28T22:18:48.922159Z","steps":["trace[1270299908] 'range keys from in-memory index tree' (duration: 193.472505ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:48.92219Z","caller":"traceutil/trace.go:171","msg":"trace[705679307] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"118.115506ms","start":"2026-04-28T22:18:48.804068Z","end":"2026-04-28T22:18:48.922183Z","steps":["trace[705679307] 'count revisions from in-memory index tree' (duration: 116.527134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:50.188333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.982473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T22:18:50.188461Z","caller":"traceutil/trace.go:171","msg":"trace[2126359272] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"175.82606ms","start":"2026-04-28T22:18:50.012615Z","end":"2026-04-28T22:18:50.188441Z","steps":["trace[2126359272] 'process raft request' (duration: 41.135992ms)","trace[2126359272] 'compare' (duration: 133.819532ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:50.193949Z","caller":"traceutil/trace.go:171","msg":"trace[1513849099] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"181.280571ms","start":"2026-04-28T22:18:50.012654Z","end":"2026-04-28T22:18:50.193934Z","steps":["trace[1513849099] 'process raft request' (duration: 181.15821ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.193998Z","caller":"traceutil/trace.go:171","msg":"trace[1432548397] linearizableReadLoop","detail":"{readStateIndex:9; appliedIndex:6; }","duration":"181.226959ms","start":"2026-04-28T22:18:50.012761Z","end":"2026-04-28T22:18:50.193988Z","steps":["trace[1432548397] 'read index received' (duration: 40.98261ms)","trace[1432548397] 'applied index is now lower than readState.Index' (duration: 140.243809ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:50.194043Z","caller":"traceutil/trace.go:171","msg":"trace[1489390558] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"181.420121ms","start":"2026-04-28T22:18:50.012615Z","end":"2026-04-28T22:18:50.194035Z","steps":["trace[1489390558] 'process raft request' (duration: 181.26757ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.194143Z","caller":"traceutil/trace.go:171","msg":"trace[1444361124] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"181.121359ms","start":"2026-04-28T22:18:50.012979Z","end":"2026-04-28T22:18:50.194101Z","steps":["trace[1444361124] 'process raft request' (duration: 180.966248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:50.194287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.511981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:50.194326Z","caller":"traceutil/trace.go:171","msg":"trace[1525039339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"181.601202ms","start":"2026-04-28T22:18:50.012716Z","end":"2026-04-28T22:18:50.194317Z","steps":["trace[1525039339] 'agreement among raft nodes before linearized reading' (duration: 181.499031ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.194331Z","caller":"traceutil/trace.go:171","msg":"trace[136193076] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"181.504541ms","start":"2026-04-28T22:18:50.012804Z","end":"2026-04-28T22:18:50.194309Z","steps":["trace[136193076] 'process raft request' (duration: 181.109598ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.194176Z","caller":"traceutil/trace.go:171","msg":"trace[1738864255] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"169.254042ms","start":"2026-04-28T22:18:50.024892Z","end":"2026-04-28T22:18:50.194146Z","steps":["trace[1738864255] 'process raft request' (duration: 169.069711ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.1942Z","caller":"traceutil/trace.go:171","msg":"trace[1083191247] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"181.199959ms","start":"2026-04-28T22:18:50.012931Z","end":"2026-04-28T22:18:50.194131Z","steps":["trace[1083191247] 'process raft request' (duration: 180.997687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:50.200044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.815321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T22:18:50.200044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.207156ms","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-28T22:18:50.200088Z","caller":"traceutil/trace.go:171","msg":"trace[744881996] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"156.919432ms","start":"2026-04-28T22:18:50.043157Z","end":"2026-04-28T22:18:50.200077Z","steps":["trace[744881996] 'agreement among raft nodes before linearized reading' (duration: 156.817451ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.200095Z","caller":"traceutil/trace.go:171","msg":"trace[1000995582] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"186.259927ms","start":"2026-04-28T22:18:50.013823Z","end":"2026-04-28T22:18:50.200083Z","steps":["trace[1000995582] 'agreement among raft nodes before linearized reading' (duration: 186.185036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:50.20006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.35262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:50.200294Z","caller":"traceutil/trace.go:171","msg":"trace[209001349] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"136.647392ms","start":"2026-04-28T22:18:50.063628Z","end":"2026-04-28T22:18:50.200275Z","steps":["trace[209001349] 'agreement among raft nodes before linearized reading' (duration: 136.32415ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.322406Z","caller":"traceutil/trace.go:171","msg":"trace[1437188875] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:19; }","duration":"110.093198ms","start":"2026-04-28T22:18:50.212292Z","end":"2026-04-28T22:18:50.322385Z","steps":["trace[1437188875] 'read index received' (duration: 75.761186ms)","trace[1437188875] 'applied index is now lower than readState.Index' (duration: 34.331342ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:50.322514Z","caller":"traceutil/trace.go:171","msg":"trace[279435220] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"120.508564ms","start":"2026-04-28T22:18:50.201996Z","end":"2026-04-28T22:18:50.322504Z","steps":["trace[279435220] 'process raft request' (duration: 120.329843ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.322525Z","caller":"traceutil/trace.go:171","msg":"trace[676678364] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"120.482564ms","start":"2026-04-28T22:18:50.202027Z","end":"2026-04-28T22:18:50.32251Z","steps":["trace[676678364] 'process raft request' (duration: 120.335723ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.322558Z","caller":"traceutil/trace.go:171","msg":"trace[447389767] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"120.573115ms","start":"2026-04-28T22:18:50.201964Z","end":"2026-04-28T22:18:50.322537Z","steps":["trace[447389767] 'process raft request' (duration: 86.112262ms)","trace[447389767] 'compare' (duration: 34.14241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:50.322821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.882267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:50.322938Z","caller":"traceutil/trace.go:171","msg":"trace[1010384510] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:18; }","duration":"121.025058ms","start":"2026-04-28T22:18:50.201884Z","end":"2026-04-28T22:18:50.322909Z","steps":["trace[1010384510] 'agreement among raft nodes before linearized reading' (duration: 120.800727ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.32679Z","caller":"traceutil/trace.go:171","msg":"trace[440822395] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"110.413371ms","start":"2026-04-28T22:18:50.216359Z","end":"2026-04-28T22:18:50.326773Z","steps":["trace[440822395] 'process raft request' (duration: 110.20055ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.326881Z","caller":"traceutil/trace.go:171","msg":"trace[1672100034] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"110.410241ms","start":"2026-04-28T22:18:50.216426Z","end":"2026-04-28T22:18:50.326836Z","steps":["trace[1672100034] 'process raft request' (duration: 110.238489ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.326832Z","caller":"traceutil/trace.go:171","msg":"trace[24805243] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"110.007038ms","start":"2026-04-28T22:18:50.216795Z","end":"2026-04-28T22:18:50.326802Z","steps":["trace[24805243] 'process raft request' (duration: 109.898468ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.327004Z","caller":"traceutil/trace.go:171","msg":"trace[1512840148] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"110.632653ms","start":"2026-04-28T22:18:50.216359Z","end":"2026-04-28T22:18:50.326992Z","steps":["trace[1512840148] 'process raft request' (duration: 110.28291ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:50.327155Z","caller":"traceutil/trace.go:171","msg":"trace[1221337340] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"110.37079ms","start":"2026-04-28T22:18:50.21674Z","end":"2026-04-28T22:18:50.327111Z","steps":["trace[1221337340] 'process raft request' (duration: 109.941877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:50.911398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.42609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T22:18:50.912071Z","caller":"traceutil/trace.go:171","msg":"trace[632294285] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"458.014052ms","start":"2026-04-28T22:18:50.454028Z","end":"2026-04-28T22:18:50.912042Z","steps":["trace[632294285] 'process raft request' (duration: 139.870066ms)","trace[632294285] 'compare' (duration: 317.269218ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:50.912194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:50.453992Z","time spent":"458.148692ms","remote":"127.0.0.1:34244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":632,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T22:18:50.912385Z","caller":"traceutil/trace.go:171","msg":"trace[1589592132] linearizableReadLoop","detail":"{readStateIndex:47; appliedIndex:45; }","duration":"331.613084ms","start":"2026-04-28T22:18:50.580745Z","end":"2026-04-28T22:18:50.912358Z","steps":["trace[1589592132] 'read index received' (duration: 13.139056ms)","trace[1589592132] 'applied index is now lower than readState.Index' (duration: 318.470488ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:50.912535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.781055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:50.912598Z","caller":"traceutil/trace.go:171","msg":"trace[1440304286] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:43; }","duration":"331.893346ms","start":"2026-04-28T22:18:50.580687Z","end":"2026-04-28T22:18:50.91258Z","steps":["trace[1440304286] 'agreement among raft nodes before linearized reading' (duration: 331.771356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:50.912651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:50.580667Z","time spent":"331.972227ms","remote":"127.0.0.1:34178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-28T22:18:50.912683Z","caller":"traceutil/trace.go:171","msg":"trace[562534459] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"457.985142ms","start":"2026-04-28T22:18:50.454633Z","end":"2026-04-28T22:18:50.912618Z","steps":["trace[562534459] 'process raft request' (duration: 457.040284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:50.913748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:50.454618Z","time spent":"458.209413ms","remote":"127.0.0.1:34244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:18:51.159837Z","caller":"traceutil/trace.go:171","msg":"trace[252834497] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"243.402761ms","start":"2026-04-28T22:18:50.915978Z","end":"2026-04-28T22:18:51.159381Z","steps":["trace[252834497] 'process raft request' (duration: 209.755405ms)","trace[252834497] 'compare' (duration: 33.033422ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:51.159903Z","caller":"traceutil/trace.go:171","msg":"trace[159919008] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"241.071653ms","start":"2026-04-28T22:18:50.918789Z","end":"2026-04-28T22:18:51.15986Z","steps":["trace[159919008] 'process raft request' (duration: 240.137987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:51.16231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.208971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:51.16254Z","caller":"traceutil/trace.go:171","msg":"trace[869288150] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"175.434063ms","start":"2026-04-28T22:18:50.987063Z","end":"2026-04-28T22:18:51.162497Z","steps":["trace[869288150] 'process raft request' (duration: 173.964581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:51.162546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.430723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:51.162629Z","caller":"traceutil/trace.go:171","msg":"trace[1773599552] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:47; }","duration":"246.523733ms","start":"2026-04-28T22:18:50.916073Z","end":"2026-04-28T22:18:51.162597Z","steps":["trace[1773599552] 'agreement among raft nodes before linearized reading' (duration: 243.790623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:51.162751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.547444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:51.162341Z","caller":"traceutil/trace.go:171","msg":"trace[1193561057] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"175.146501ms","start":"2026-04-28T22:18:50.987054Z","end":"2026-04-28T22:18:51.162201Z","steps":["trace[1193561057] 'process raft request' (duration: 171.945517ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:51.162891Z","caller":"traceutil/trace.go:171","msg":"trace[544814909] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:47; }","duration":"246.781615ms","start":"2026-04-28T22:18:50.916075Z","end":"2026-04-28T22:18:51.162856Z","steps":["trace[544814909] 'agreement among raft nodes before linearized reading' (duration: 243.690543ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:51.162642Z","caller":"traceutil/trace.go:171","msg":"trace[738945919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:47; }","duration":"186.314092ms","start":"2026-04-28T22:18:50.976065Z","end":"2026-04-28T22:18:51.162379Z","steps":["trace[738945919] 'agreement among raft nodes before linearized reading' (duration: 183.526151ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:51.159057Z","caller":"traceutil/trace.go:171","msg":"trace[822033525] linearizableReadLoop","detail":"{readStateIndex:51; appliedIndex:49; }","duration":"175.536504ms","start":"2026-04-28T22:18:50.983497Z","end":"2026-04-28T22:18:51.159034Z","steps":["trace[822033525] 'read index received' (duration: 142.21883ms)","trace[822033525] 'applied index is now lower than readState.Index' (duration: 33.316534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:51.708325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.928401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-28T22:18:51.70864Z","caller":"traceutil/trace.go:171","msg":"trace[1622212881] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"197.072689ms","start":"2026-04-28T22:18:51.511527Z","end":"2026-04-28T22:18:51.708599Z","steps":["trace[1622212881] 'process raft request' (duration: 93.775695ms)","trace[1622212881] 'compare' (duration: 102.76811ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:51.708877Z","caller":"traceutil/trace.go:171","msg":"trace[1510537613] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"196.405794ms","start":"2026-04-28T22:18:51.512462Z","end":"2026-04-28T22:18:51.708868Z","steps":["trace[1510537613] 'process raft request' (duration: 196.163432ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:51.708877Z","caller":"traceutil/trace.go:171","msg":"trace[496153237] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"196.277943ms","start":"2026-04-28T22:18:51.512455Z","end":"2026-04-28T22:18:51.708733Z","steps":["trace[496153237] 'process raft request' (duration: 196.125352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:51.709037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.949472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:51.709162Z","caller":"traceutil/trace.go:171","msg":"trace[704525491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:80; }","duration":"140.158424ms","start":"2026-04-28T22:18:51.568978Z","end":"2026-04-28T22:18:51.709137Z","steps":["trace[704525491] 'agreement among raft nodes before linearized reading' (duration: 139.873342ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:51.708692Z","caller":"traceutil/trace.go:171","msg":"trace[584061434] linearizableReadLoop","detail":"{readStateIndex:85; appliedIndex:81; }","duration":"139.643289ms","start":"2026-04-28T22:18:51.56903Z","end":"2026-04-28T22:18:51.708673Z","steps":["trace[584061434] 'read index received' (duration: 36.259875ms)","trace[584061434] 'applied index is now lower than readState.Index' (duration: 103.382324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:51.709402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.329134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T22:18:51.709468Z","caller":"traceutil/trace.go:171","msg":"trace[118779243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:80; }","duration":"140.457446ms","start":"2026-04-28T22:18:51.568974Z","end":"2026-04-28T22:18:51.709431Z","steps":["trace[118779243] 'agreement among raft nodes before linearized reading' (duration: 140.327455ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:51.708721Z","caller":"traceutil/trace.go:171","msg":"trace[761508809] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"196.681615ms","start":"2026-04-28T22:18:51.511993Z","end":"2026-04-28T22:18:51.708674Z","steps":["trace[761508809] 'process raft request' (duration: 196.528254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:52.740476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.217339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:18:52.740644Z","caller":"traceutil/trace.go:171","msg":"trace[762933965] linearizableReadLoop","detail":"{readStateIndex:135; appliedIndex:133; }","duration":"102.834806ms","start":"2026-04-28T22:18:52.637795Z","end":"2026-04-28T22:18:52.74063Z","steps":["trace[762933965] 'read index received' (duration: 82.65315ms)","trace[762933965] 'applied index is now lower than readState.Index' (duration: 20.180886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:52.740713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.925147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:18:52.74073Z","caller":"traceutil/trace.go:171","msg":"trace[553883716] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:130; }","duration":"103.000048ms","start":"2026-04-28T22:18:52.637724Z","end":"2026-04-28T22:18:52.740725Z","steps":["trace[553883716] 'agreement among raft nodes before linearized reading' (duration: 102.940468ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:52.740844Z","caller":"traceutil/trace.go:171","msg":"trace[783326111] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"415.513919ms","start":"2026-04-28T22:18:52.325302Z","end":"2026-04-28T22:18:52.740816Z","steps":["trace[783326111] 'process raft request' (duration: 415.292187ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:52.740802Z","caller":"traceutil/trace.go:171","msg":"trace[63026057] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"415.75451ms","start":"2026-04-28T22:18:52.325Z","end":"2026-04-28T22:18:52.740755Z","steps":["trace[63026057] 'process raft request' (duration: 213.192589ms)","trace[63026057] 'compare' (duration: 202.067728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:52.740986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:52.325288Z","time spent":"415.629589ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:18:52.741042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:52.324985Z","time spent":"415.996692ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:18:52.999419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.403073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:18:52.999537Z","caller":"traceutil/trace.go:171","msg":"trace[386756591] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"166.204028ms","start":"2026-04-28T22:18:52.833313Z","end":"2026-04-28T22:18:52.999517Z","steps":["trace[386756591] 'process raft request' (duration: 15.636284ms)","trace[386756591] 'compare' (duration: 150.246312ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:53.008109Z","caller":"traceutil/trace.go:171","msg":"trace[1855815586] linearizableReadLoop","detail":"{readStateIndex:144; appliedIndex:142; }","duration":"174.312276ms","start":"2026-04-28T22:18:52.833768Z","end":"2026-04-28T22:18:53.008081Z","steps":["trace[1855815586] 'read index received' (duration: 15.15478ms)","trace[1855815586] 'applied index is now lower than readState.Index' (duration: 159.156576ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:53.008194Z","caller":"traceutil/trace.go:171","msg":"trace[1991242853] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"174.72727ms","start":"2026-04-28T22:18:52.833452Z","end":"2026-04-28T22:18:53.008179Z","steps":["trace[1991242853] 'process raft request' (duration: 174.531918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:53.008441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.621128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3283"} {"level":"info","ts":"2026-04-28T22:18:53.008498Z","caller":"traceutil/trace.go:171","msg":"trace[227448388] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:139; }","duration":"174.737969ms","start":"2026-04-28T22:18:52.833744Z","end":"2026-04-28T22:18:53.008482Z","steps":["trace[227448388] 'agreement among raft nodes before linearized reading' (duration: 174.459457ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:54.217308Z","caller":"traceutil/trace.go:171","msg":"trace[1417674294] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"115.465691ms","start":"2026-04-28T22:18:54.1018Z","end":"2026-04-28T22:18:54.217265Z","steps":["trace[1417674294] 'process raft request' (duration: 37.29895ms)","trace[1417674294] 'compare' (duration: 77.412556ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:54.557261Z","caller":"traceutil/trace.go:171","msg":"trace[1033270623] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"158.35283ms","start":"2026-04-28T22:18:54.398868Z","end":"2026-04-28T22:18:54.557221Z","steps":["trace[1033270623] 'process raft request' (duration: 87.832783ms)","trace[1033270623] 'compare' (duration: 70.317316ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:54.900213Z","caller":"traceutil/trace.go:171","msg":"trace[2035437377] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"117.357695ms","start":"2026-04-28T22:18:54.782811Z","end":"2026-04-28T22:18:54.900169Z","steps":["trace[2035437377] 'process raft request' (duration: 39.262163ms)","trace[2035437377] 'compare' (duration: 77.890831ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:55.101155Z","caller":"traceutil/trace.go:171","msg":"trace[1191609940] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"194.179294ms","start":"2026-04-28T22:18:54.906928Z","end":"2026-04-28T22:18:55.101107Z","steps":["trace[1191609940] 'process raft request' (duration: 112.172697ms)","trace[1191609940] 'compare' (duration: 81.732875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:55.528341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.617953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-28T22:18:55.528473Z","caller":"traceutil/trace.go:171","msg":"trace[2111524445] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:229; }","duration":"166.805785ms","start":"2026-04-28T22:18:55.361641Z","end":"2026-04-28T22:18:55.528447Z","steps":["trace[2111524445] 'range keys from in-memory index tree' (duration: 166.412172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:55.761824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.292357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:18:55.761948Z","caller":"traceutil/trace.go:171","msg":"trace[987869788] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:233; }","duration":"132.461628ms","start":"2026-04-28T22:18:55.62946Z","end":"2026-04-28T22:18:55.761921Z","steps":["trace[987869788] 'range keys from in-memory index tree' (duration: 132.196956ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.137362Z","caller":"traceutil/trace.go:171","msg":"trace[1695808328] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:244; }","duration":"136.562244ms","start":"2026-04-28T22:18:56.000774Z","end":"2026-04-28T22:18:56.137337Z","steps":["trace[1695808328] 'read index received' (duration: 133.076979ms)","trace[1695808328] 'applied index is now lower than readState.Index' (duration: 3.484065ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:56.137468Z","caller":"traceutil/trace.go:171","msg":"trace[2137304757] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"157.01883ms","start":"2026-04-28T22:18:55.980402Z","end":"2026-04-28T22:18:56.137421Z","steps":["trace[2137304757] 'process raft request' (duration: 153.562455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:56.137653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.847126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-04-28T22:18:56.137773Z","caller":"traceutil/trace.go:171","msg":"trace[925307305] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:240; }","duration":"137.055747ms","start":"2026-04-28T22:18:56.00069Z","end":"2026-04-28T22:18:56.137746Z","steps":["trace[925307305] 'agreement among raft nodes before linearized reading' (duration: 136.751305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:58.153313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.178419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:18:58.153448Z","caller":"traceutil/trace.go:171","msg":"trace[263209632] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"281.717256ms","start":"2026-04-28T22:18:57.871711Z","end":"2026-04-28T22:18:58.153428Z","steps":["trace[263209632] 'read index received' (duration: 92.366756ms)","trace[263209632] 'applied index is now lower than readState.Index' (duration: 189.34923ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:58.153521Z","caller":"traceutil/trace.go:171","msg":"trace[2040333441] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"307.823122ms","start":"2026-04-28T22:18:57.845652Z","end":"2026-04-28T22:18:58.153475Z","steps":["trace[2040333441] 'process raft request' (duration: 118.412111ms)","trace[2040333441] 'compare' (duration: 188.968347ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:58.15361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.480513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-04-28T22:18:58.153648Z","caller":"traceutil/trace.go:171","msg":"trace[496200303] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:288; }","duration":"309.554425ms","start":"2026-04-28T22:18:57.844083Z","end":"2026-04-28T22:18:58.153638Z","steps":["trace[496200303] 'agreement among raft nodes before linearized reading' (duration: 309.449874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:58.15369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:57.844073Z","time spent":"309.609245ms","remote":"127.0.0.1:34178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":689,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-28T22:18:58.153699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:57.845638Z","time spent":"307.979283ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:18:58.15371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.638258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:18:58.153843Z","caller":"traceutil/trace.go:171","msg":"trace[357646511] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:288; }","duration":"308.796319ms","start":"2026-04-28T22:18:57.845014Z","end":"2026-04-28T22:18:58.15381Z","steps":["trace[357646511] 'agreement among raft nodes before linearized reading' (duration: 308.477177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:58.153907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:57.845008Z","time spent":"308.887549ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-04-28T22:18:58.154038Z","caller":"traceutil/trace.go:171","msg":"trace[1826383716] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"278.951197ms","start":"2026-04-28T22:18:57.875041Z","end":"2026-04-28T22:18:58.153992Z","steps":["trace[1826383716] 'process raft request' (duration: 278.493524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:58.154094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.436713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:18:58.15411Z","caller":"traceutil/trace.go:171","msg":"trace[408656611] transaction","detail":"{read_only:false; number_of_response:0; response_revision:289; }","duration":"207.006676ms","start":"2026-04-28T22:18:57.947061Z","end":"2026-04-28T22:18:58.154068Z","steps":["trace[408656611] 'process raft request' (duration: 206.868645ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.154153Z","caller":"traceutil/trace.go:171","msg":"trace[1025657901] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:289; }","duration":"271.560264ms","start":"2026-04-28T22:18:57.882573Z","end":"2026-04-28T22:18:58.154133Z","steps":["trace[1025657901] 'agreement among raft nodes before linearized reading' (duration: 271.418473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:58.154181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.983085ms","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-28T22:18:58.15424Z","caller":"traceutil/trace.go:171","msg":"trace[189265230] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:289; }","duration":"214.079767ms","start":"2026-04-28T22:18:57.940145Z","end":"2026-04-28T22:18:58.154225Z","steps":["trace[189265230] 'agreement among raft nodes before linearized reading' (duration: 213.968356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:58.154265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.931875ms","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-28T22:18:58.15437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.009858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:18:58.154413Z","caller":"traceutil/trace.go:171","msg":"trace[84079543] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:289; }","duration":"189.085129ms","start":"2026-04-28T22:18:57.965313Z","end":"2026-04-28T22:18:58.154398Z","steps":["trace[84079543] 'agreement among raft nodes before linearized reading' (duration: 189.012748ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.154424Z","caller":"traceutil/trace.go:171","msg":"trace[118537939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:289; }","duration":"214.124117ms","start":"2026-04-28T22:18:57.940265Z","end":"2026-04-28T22:18:58.154389Z","steps":["trace[118537939] 'agreement among raft nodes before linearized reading' (duration: 213.774954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:58.155694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.32914ms","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-28T22:18:58.155791Z","caller":"traceutil/trace.go:171","msg":"trace[423613719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:289; }","duration":"213.487101ms","start":"2026-04-28T22:18:57.942287Z","end":"2026-04-28T22:18:58.155774Z","steps":["trace[423613719] 'agreement among raft nodes before linearized reading' (duration: 213.067558ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.287717Z","caller":"traceutil/trace.go:171","msg":"trace[763774660] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"129.461925ms","start":"2026-04-28T22:18:58.158196Z","end":"2026-04-28T22:18:58.287658Z","steps":["trace[763774660] 'process raft request' (duration: 129.266084ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.287772Z","caller":"traceutil/trace.go:171","msg":"trace[161704821] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"129.560386ms","start":"2026-04-28T22:18:58.158163Z","end":"2026-04-28T22:18:58.287723Z","steps":["trace[161704821] 'process raft request' (duration: 129.257514ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.287794Z","caller":"traceutil/trace.go:171","msg":"trace[341850255] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"129.510626ms","start":"2026-04-28T22:18:58.158253Z","end":"2026-04-28T22:18:58.287763Z","steps":["trace[341850255] 'process raft request' (duration: 129.254784ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.287865Z","caller":"traceutil/trace.go:171","msg":"trace[1761022690] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"128.62075ms","start":"2026-04-28T22:18:58.159236Z","end":"2026-04-28T22:18:58.287857Z","steps":["trace[1761022690] 'process raft request' (duration: 128.346547ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.287939Z","caller":"traceutil/trace.go:171","msg":"trace[1656365620] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"129.593517ms","start":"2026-04-28T22:18:58.158324Z","end":"2026-04-28T22:18:58.287917Z","steps":["trace[1656365620] 'process raft request' (duration: 129.223023ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.287795Z","caller":"traceutil/trace.go:171","msg":"trace[1184748690] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"127.440581ms","start":"2026-04-28T22:18:58.16033Z","end":"2026-04-28T22:18:58.28777Z","steps":["trace[1184748690] 'process raft request' (duration: 127.369521ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.288074Z","caller":"traceutil/trace.go:171","msg":"trace[1911474932] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:297; }","duration":"112.485026ms","start":"2026-04-28T22:18:58.175577Z","end":"2026-04-28T22:18:58.288062Z","steps":["trace[1911474932] 'read index received' (duration: 93.472771ms)","trace[1911474932] 'applied index is now lower than readState.Index' (duration: 19.011325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:58.288176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.63097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-04-28T22:18:58.288211Z","caller":"traceutil/trace.go:171","msg":"trace[1674515816] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:299; }","duration":"128.70445ms","start":"2026-04-28T22:18:58.159494Z","end":"2026-04-28T22:18:58.288199Z","steps":["trace[1674515816] 'agreement among raft nodes before linearized reading' (duration: 128.605919ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.28823Z","caller":"traceutil/trace.go:171","msg":"trace[1355831592] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"127.581692ms","start":"2026-04-28T22:18:58.160635Z","end":"2026-04-28T22:18:58.288217Z","steps":["trace[1355831592] 'process raft request' (duration: 127.098908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:58.288312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.257037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3807"} {"level":"info","ts":"2026-04-28T22:18:58.288364Z","caller":"traceutil/trace.go:171","msg":"trace[1136948387] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:299; }","duration":"128.334438ms","start":"2026-04-28T22:18:58.160015Z","end":"2026-04-28T22:18:58.28835Z","steps":["trace[1136948387] 'agreement among raft nodes before linearized reading' (duration: 128.204077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:58.288402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.72029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3807"} {"level":"info","ts":"2026-04-28T22:18:58.288433Z","caller":"traceutil/trace.go:171","msg":"trace[452632563] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:299; }","duration":"128.75968ms","start":"2026-04-28T22:18:58.159664Z","end":"2026-04-28T22:18:58.288424Z","steps":["trace[452632563] 'agreement among raft nodes before linearized reading' (duration: 128.67274ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.288535Z","caller":"traceutil/trace.go:171","msg":"trace[445688383] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"129.202923ms","start":"2026-04-28T22:18:58.159319Z","end":"2026-04-28T22:18:58.288521Z","steps":["trace[445688383] 'process raft request' (duration: 128.353226ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.289185Z","caller":"traceutil/trace.go:171","msg":"trace[1979007497] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"131.074497ms","start":"2026-04-28T22:18:58.158088Z","end":"2026-04-28T22:18:58.289162Z","steps":["trace[1979007497] 'process raft request' (duration: 111.026756ms)","trace[1979007497] 'compare' (duration: 18.126297ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:58.453657Z","caller":"traceutil/trace.go:171","msg":"trace[1527348302] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"144.767024ms","start":"2026-04-28T22:18:58.308851Z","end":"2026-04-28T22:18:58.453618Z","steps":["trace[1527348302] 'process raft request' (duration: 112.685476ms)","trace[1527348302] 'compare' (duration: 31.766025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:58.454093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.524808ms","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-28T22:18:58.454181Z","caller":"traceutil/trace.go:171","msg":"trace[1382011161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:305; }","duration":"104.66119ms","start":"2026-04-28T22:18:58.349499Z","end":"2026-04-28T22:18:58.45416Z","steps":["trace[1382011161] 'agreement among raft nodes before linearized reading' (duration: 104.273108ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.649431Z","caller":"traceutil/trace.go:171","msg":"trace[1800881509] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:317; }","duration":"146.704828ms","start":"2026-04-28T22:18:58.502698Z","end":"2026-04-28T22:18:58.649403Z","steps":["trace[1800881509] 'read index received' (duration: 122.353066ms)","trace[1800881509] 'applied index is now lower than readState.Index' (duration: 24.349642ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:58.649476Z","caller":"traceutil/trace.go:171","msg":"trace[192979054] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"158.717333ms","start":"2026-04-28T22:18:58.49072Z","end":"2026-04-28T22:18:58.649437Z","steps":["trace[192979054] 'process raft request' (duration: 134.455201ms)","trace[192979054] 'compare' (duration: 23.959809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:58.649781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.03743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:18:58.649833Z","caller":"traceutil/trace.go:171","msg":"trace[54350101] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:311; }","duration":"147.184851ms","start":"2026-04-28T22:18:58.502635Z","end":"2026-04-28T22:18:58.64982Z","steps":["trace[54350101] 'agreement among raft nodes before linearized reading' (duration: 146.877139ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.844642Z","caller":"traceutil/trace.go:171","msg":"trace[2023973697] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"110.745633ms","start":"2026-04-28T22:18:58.733869Z","end":"2026-04-28T22:18:58.844614Z","steps":["trace[2023973697] 'process raft request' (duration: 110.695472ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:58.844715Z","caller":"traceutil/trace.go:171","msg":"trace[756728636] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"151.033708ms","start":"2026-04-28T22:18:58.693639Z","end":"2026-04-28T22:18:58.844672Z","steps":["trace[756728636] 'process raft request' (duration: 69.16221ms)","trace[756728636] 'compare' (duration: 81.582716ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:59.072956Z","caller":"traceutil/trace.go:171","msg":"trace[2066346941] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"192.698471ms","start":"2026-04-28T22:18:58.880243Z","end":"2026-04-28T22:18:59.072942Z","steps":["trace[2066346941] 'process raft request' (duration: 117.256519ms)","trace[2066346941] 'compare' (duration: 75.311251ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:59.072978Z","caller":"traceutil/trace.go:171","msg":"trace[726912194] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"192.59793ms","start":"2026-04-28T22:18:58.880368Z","end":"2026-04-28T22:18:59.072966Z","steps":["trace[726912194] 'process raft request' (duration: 192.510899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:59.073044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.62537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3468"} {"level":"info","ts":"2026-04-28T22:18:59.072936Z","caller":"traceutil/trace.go:171","msg":"trace[331388137] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:325; }","duration":"192.509379ms","start":"2026-04-28T22:18:58.880401Z","end":"2026-04-28T22:18:59.07291Z","steps":["trace[331388137] 'read index received' (duration: 54.979379ms)","trace[331388137] 'applied index is now lower than readState.Index' (duration: 137.5288ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:59.073085Z","caller":"traceutil/trace.go:171","msg":"trace[1809046158] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:318; }","duration":"192.725781ms","start":"2026-04-28T22:18:58.880347Z","end":"2026-04-28T22:18:59.073073Z","steps":["trace[1809046158] 'agreement among raft nodes before linearized reading' (duration: 192.63986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:59.073147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.614413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:18:59.073168Z","caller":"traceutil/trace.go:171","msg":"trace[1785588404] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:318; }","duration":"191.686954ms","start":"2026-04-28T22:18:58.881475Z","end":"2026-04-28T22:18:59.073162Z","steps":["trace[1785588404] 'agreement among raft nodes before linearized reading' (duration: 191.650434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:59.073212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.188831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-28T22:18:59.073228Z","caller":"traceutil/trace.go:171","msg":"trace[1870330197] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:318; }","duration":"136.246562ms","start":"2026-04-28T22:18:58.936976Z","end":"2026-04-28T22:18:59.073223Z","steps":["trace[1870330197] 'agreement among raft nodes before linearized reading' (duration: 136.212281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:59.07328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.276472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3468"} {"level":"info","ts":"2026-04-28T22:18:59.073299Z","caller":"traceutil/trace.go:171","msg":"trace[663778321] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:318; }","duration":"136.296462ms","start":"2026-04-28T22:18:58.936997Z","end":"2026-04-28T22:18:59.073293Z","steps":["trace[663778321] 'agreement among raft nodes before linearized reading' (duration: 136.259362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:59.290249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.033979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:18:59.29031Z","caller":"traceutil/trace.go:171","msg":"trace[1936770439] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"197.038147ms","start":"2026-04-28T22:18:59.093007Z","end":"2026-04-28T22:18:59.290045Z","steps":["trace[1936770439] 'process raft request' (duration: 194.144497ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:59.290358Z","caller":"traceutil/trace.go:171","msg":"trace[1704915096] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:320; }","duration":"112.22028ms","start":"2026-04-28T22:18:59.178117Z","end":"2026-04-28T22:18:59.290337Z","steps":["trace[1704915096] 'agreement among raft nodes before linearized reading' (duration: 112.025959ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:59.290496Z","caller":"traceutil/trace.go:171","msg":"trace[1053465987] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"111.841608ms","start":"2026-04-28T22:18:59.178188Z","end":"2026-04-28T22:18:59.29003Z","steps":["trace[1053465987] 'read index received' (duration: 108.885517ms)","trace[1053465987] 'applied index is now lower than readState.Index' (duration: 2.954631ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:59.742234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.274271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:18:59.742312Z","caller":"traceutil/trace.go:171","msg":"trace[443337856] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"390.330519ms","start":"2026-04-28T22:18:59.351965Z","end":"2026-04-28T22:18:59.742296Z","steps":["trace[443337856] 'read index received' (duration: 189.859177ms)","trace[443337856] 'applied index is now lower than readState.Index' (duration: 200.470612ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:18:59.742331Z","caller":"traceutil/trace.go:171","msg":"trace[474555988] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"441.551219ms","start":"2026-04-28T22:18:59.300769Z","end":"2026-04-28T22:18:59.74232Z","steps":["trace[474555988] 'process raft request' (duration: 241.132567ms)","trace[474555988] 'compare' (duration: 199.990399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:18:59.742377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.426219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-28T22:18:59.742376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:59.300758Z","time spent":"441.59514ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:18:59.74239Z","caller":"traceutil/trace.go:171","msg":"trace[719088306] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:322; }","duration":"390.49267ms","start":"2026-04-28T22:18:59.351892Z","end":"2026-04-28T22:18:59.742385Z","steps":["trace[719088306] 'agreement among raft nodes before linearized reading' (duration: 390.46151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:18:59.742404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:59.351863Z","time spent":"390.537331ms","remote":"127.0.0.1:34006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-04-28T22:18:59.742491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.970803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:18:59.742504Z","caller":"traceutil/trace.go:171","msg":"trace[930805478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:322; }","duration":"286.035764ms","start":"2026-04-28T22:18:59.456464Z","end":"2026-04-28T22:18:59.7425Z","steps":["trace[930805478] 'agreement among raft nodes before linearized reading' (duration: 286.015324ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:00.082516Z","caller":"traceutil/trace.go:171","msg":"trace[1094052888] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:334; }","duration":"323.361274ms","start":"2026-04-28T22:18:59.759122Z","end":"2026-04-28T22:19:00.082483Z","steps":["trace[1094052888] 'read index received' (duration: 236.197763ms)","trace[1094052888] 'applied index is now lower than readState.Index' (duration: 87.162091ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:00.082522Z","caller":"traceutil/trace.go:171","msg":"trace[1477879630] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"327.642955ms","start":"2026-04-28T22:18:59.754831Z","end":"2026-04-28T22:19:00.082474Z","steps":["trace[1477879630] 'process raft request' (duration: 240.469154ms)","trace[1477879630] 'compare' (duration: 86.903529ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:00.082694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.545285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T22:19:00.082735Z","caller":"traceutil/trace.go:171","msg":"trace[1788728536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:326; }","duration":"323.628025ms","start":"2026-04-28T22:18:59.759096Z","end":"2026-04-28T22:19:00.082724Z","steps":["trace[1788728536] 'agreement among raft nodes before linearized reading' (duration: 323.505945ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:00.082706Z","caller":"traceutil/trace.go:171","msg":"trace[1467631799] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"323.780936ms","start":"2026-04-28T22:18:59.758897Z","end":"2026-04-28T22:19:00.082678Z","steps":["trace[1467631799] 'process raft request' (duration: 323.457804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.082776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:59.759087Z","time spent":"323.679946ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T22:19:00.082795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:59.754815Z","time spent":"327.833726ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:00.082852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:59.758884Z","time spent":"323.889517ms","remote":"127.0.0.1:34006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":184,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T22:19:00.082919Z","caller":"traceutil/trace.go:171","msg":"trace[2017514] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"323.165872ms","start":"2026-04-28T22:18:59.759702Z","end":"2026-04-28T22:19:00.082868Z","steps":["trace[2017514] 'process raft request' (duration: 322.714079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.083071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:18:59.759676Z","time spent":"323.330663ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T22:19:00.466101Z","caller":"traceutil/trace.go:171","msg":"trace[1222786313] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"191.930306ms","start":"2026-04-28T22:19:00.274144Z","end":"2026-04-28T22:19:00.466074Z","steps":["trace[1222786313] 'read index received' (duration: 188.811714ms)","trace[1222786313] 'applied index is now lower than readState.Index' (duration: 3.117642ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:00.466346Z","caller":"traceutil/trace.go:171","msg":"trace[1205748617] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"229.137277ms","start":"2026-04-28T22:19:00.237197Z","end":"2026-04-28T22:19:00.466334Z","steps":["trace[1205748617] 'process raft request' (duration: 225.834954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.466558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.398479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:00.466599Z","caller":"traceutil/trace.go:171","msg":"trace[779015758] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:331; }","duration":"192.496009ms","start":"2026-04-28T22:19:00.274091Z","end":"2026-04-28T22:19:00.466587Z","steps":["trace[779015758] 'agreement among raft nodes before linearized reading' (duration: 192.421369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.466752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.372746ms","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-28T22:19:00.466785Z","caller":"traceutil/trace.go:171","msg":"trace[1166125334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"173.436577ms","start":"2026-04-28T22:19:00.293341Z","end":"2026-04-28T22:19:00.466778Z","steps":["trace[1166125334] 'agreement among raft nodes before linearized reading' (duration: 173.382166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.466948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.274075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T22:19:00.466981Z","caller":"traceutil/trace.go:171","msg":"trace[1833284796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:331; }","duration":"173.323925ms","start":"2026-04-28T22:19:00.293649Z","end":"2026-04-28T22:19:00.466973Z","steps":["trace[1833284796] 'agreement among raft nodes before linearized reading' (duration: 173.272024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.93008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.617421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:19:00.9302Z","caller":"traceutil/trace.go:171","msg":"trace[1311708279] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"156.474858ms","start":"2026-04-28T22:19:00.773706Z","end":"2026-04-28T22:19:00.930181Z","steps":["trace[1311708279] 'read index received' (duration: 3.598185ms)","trace[1311708279] 'applied index is now lower than readState.Index' (duration: 152.875183ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:00.930478Z","caller":"traceutil/trace.go:171","msg":"trace[1744332780] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"171.841475ms","start":"2026-04-28T22:19:00.75862Z","end":"2026-04-28T22:19:00.930462Z","steps":["trace[1744332780] 'process raft request' (duration: 18.746421ms)","trace[1744332780] 'compare' (duration: 152.49263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:00.930724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.034392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-28T22:19:00.930964Z","caller":"traceutil/trace.go:171","msg":"trace[1988272941] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:342; }","duration":"157.135722ms","start":"2026-04-28T22:19:00.773625Z","end":"2026-04-28T22:19:00.930761Z","steps":["trace[1988272941] 'agreement among raft nodes before linearized reading' (duration: 157.045191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.931136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.440394ms","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-28T22:19:00.931174Z","caller":"traceutil/trace.go:171","msg":"trace[447368673] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:342; }","duration":"157.494925ms","start":"2026-04-28T22:19:00.77367Z","end":"2026-04-28T22:19:00.931165Z","steps":["trace[447368673] 'agreement among raft nodes before linearized reading' (duration: 157.437344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.931302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.711948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-28T22:19:00.931337Z","caller":"traceutil/trace.go:171","msg":"trace[214612610] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:342; }","duration":"143.777688ms","start":"2026-04-28T22:19:00.787551Z","end":"2026-04-28T22:19:00.931329Z","steps":["trace[214612610] 'agreement among raft nodes before linearized reading' (duration: 143.724887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.932073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.074372ms","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-28T22:19:00.93215Z","caller":"traceutil/trace.go:171","msg":"trace[1005013005] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:342; }","duration":"137.171192ms","start":"2026-04-28T22:19:00.794962Z","end":"2026-04-28T22:19:00.932134Z","steps":["trace[1005013005] 'agreement among raft nodes before linearized reading' (duration: 137.066841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.932285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.931975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-28T22:19:00.932319Z","caller":"traceutil/trace.go:171","msg":"trace[1582047601] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:342; }","duration":"139.002375ms","start":"2026-04-28T22:19:00.793308Z","end":"2026-04-28T22:19:00.93231Z","steps":["trace[1582047601] 'agreement among raft nodes before linearized reading' (duration: 138.946264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:00.932353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.048306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-28T22:19:00.932396Z","caller":"traceutil/trace.go:171","msg":"trace[534975289] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:342; }","duration":"139.119506ms","start":"2026-04-28T22:19:00.793264Z","end":"2026-04-28T22:19:00.932384Z","steps":["trace[534975289] 'agreement among raft nodes before linearized reading' (duration: 139.042995ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:01.436791Z","caller":"traceutil/trace.go:171","msg":"trace[473680391] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"130.321971ms","start":"2026-04-28T22:19:01.306427Z","end":"2026-04-28T22:19:01.436749Z","steps":["trace[473680391] 'process raft request' (duration: 66.662516ms)","trace[473680391] 'compare' (duration: 63.367822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:01.436987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.248812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-28T22:19:01.437095Z","caller":"traceutil/trace.go:171","msg":"trace[1823868505] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:357; }","duration":"103.499013ms","start":"2026-04-28T22:19:01.333572Z","end":"2026-04-28T22:19:01.437071Z","steps":["trace[1823868505] 'agreement among raft nodes before linearized reading' (duration: 103.188691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:01.659237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.459616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:19:01.659503Z","caller":"traceutil/trace.go:171","msg":"trace[85743368] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"161.055974ms","start":"2026-04-28T22:19:01.498432Z","end":"2026-04-28T22:19:01.659488Z","steps":["trace[85743368] 'process raft request' (duration: 161.020004ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:01.65955Z","caller":"traceutil/trace.go:171","msg":"trace[1572067417] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"214.212655ms","start":"2026-04-28T22:19:01.445288Z","end":"2026-04-28T22:19:01.659501Z","steps":["trace[1572067417] 'process raft request' (duration: 214.039514ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:01.659685Z","caller":"traceutil/trace.go:171","msg":"trace[1504541356] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"214.877801ms","start":"2026-04-28T22:19:01.444784Z","end":"2026-04-28T22:19:01.659662Z","steps":["trace[1504541356] 'process raft request' (duration: 98.924042ms)","trace[1504541356] 'compare' (duration: 115.339895ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:01.659792Z","caller":"traceutil/trace.go:171","msg":"trace[239379742] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:369; }","duration":"176.992816ms","start":"2026-04-28T22:19:01.482781Z","end":"2026-04-28T22:19:01.659774Z","steps":["trace[239379742] 'read index received' (duration: 12.485867ms)","trace[239379742] 'applied index is now lower than readState.Index' (duration: 164.505269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:01.659951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.161467ms","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-28T22:19:01.660002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.859805ms","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-28T22:19:01.660006Z","caller":"traceutil/trace.go:171","msg":"trace[852191097] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:362; }","duration":"177.259868ms","start":"2026-04-28T22:19:01.482731Z","end":"2026-04-28T22:19:01.659991Z","steps":["trace[852191097] 'agreement among raft nodes before linearized reading' (duration: 177.156347ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:01.660037Z","caller":"traceutil/trace.go:171","msg":"trace[1568735540] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:362; }","duration":"123.925976ms","start":"2026-04-28T22:19:01.5361Z","end":"2026-04-28T22:19:01.660026Z","steps":["trace[1568735540] 'agreement among raft nodes before linearized reading' (duration: 123.861665ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:01.660365Z","caller":"traceutil/trace.go:171","msg":"trace[1505088116] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"212.785467ms","start":"2026-04-28T22:19:01.446758Z","end":"2026-04-28T22:19:01.659544Z","steps":["trace[1505088116] 'process raft request' (duration: 212.657256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:02.040805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.417044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:19:02.041161Z","caller":"traceutil/trace.go:171","msg":"trace[1731906346] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"354.504685ms","start":"2026-04-28T22:19:01.686637Z","end":"2026-04-28T22:19:02.041142Z","steps":["trace[1731906346] 'process raft request' (duration: 354.427455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:02.041286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:01.686626Z","time spent":"354.626006ms","remote":"127.0.0.1:34294","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-28T22:19:02.041592Z","caller":"traceutil/trace.go:171","msg":"trace[761606047] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"373.497498ms","start":"2026-04-28T22:19:01.668079Z","end":"2026-04-28T22:19:02.041576Z","steps":["trace[761606047] 'process raft request' (duration: 91.229178ms)","trace[761606047] 'compare' (duration: 281.169523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:02.041701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:01.668062Z","time spent":"373.60585ms","remote":"127.0.0.1:34310","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-28T22:19:02.219938Z","caller":"traceutil/trace.go:171","msg":"trace[1811239704] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:376; }","duration":"171.891007ms","start":"2026-04-28T22:19:02.048027Z","end":"2026-04-28T22:19:02.219918Z","steps":["trace[1811239704] 'read index received' (duration: 8.095307ms)","trace[1811239704] 'applied index is now lower than readState.Index' (duration: 163.79479ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:02.220067Z","caller":"traceutil/trace.go:171","msg":"trace[2069806006] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"172.719742ms","start":"2026-04-28T22:19:02.047337Z","end":"2026-04-28T22:19:02.220057Z","steps":["trace[2069806006] 'process raft request' (duration: 172.51259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:02.220255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.207899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T22:19:02.22031Z","caller":"traceutil/trace.go:171","msg":"trace[1942235534] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:368; }","duration":"172.296169ms","start":"2026-04-28T22:19:02.048002Z","end":"2026-04-28T22:19:02.220298Z","steps":["trace[1942235534] 'agreement among raft nodes before linearized reading' (duration: 172.198059ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.220441Z","caller":"traceutil/trace.go:171","msg":"trace[1383468649] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"173.476248ms","start":"2026-04-28T22:19:02.046921Z","end":"2026-04-28T22:19:02.220397Z","steps":["trace[1383468649] 'process raft request' (duration: 145.769955ms)","trace[1383468649] 'compare' (duration: 27.002208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:02.220592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.553083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T22:19:02.220667Z","caller":"traceutil/trace.go:171","msg":"trace[2128769829] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"109.747154ms","start":"2026-04-28T22:19:02.110908Z","end":"2026-04-28T22:19:02.220655Z","steps":["trace[2128769829] 'agreement among raft nodes before linearized reading' (duration: 109.507062ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.3557Z","caller":"traceutil/trace.go:171","msg":"trace[2093912158] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"129.923565ms","start":"2026-04-28T22:19:02.225731Z","end":"2026-04-28T22:19:02.355654Z","steps":["trace[2093912158] 'process raft request' (duration: 95.070051ms)","trace[2093912158] 'compare' (duration: 34.620322ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:02.35606Z","caller":"traceutil/trace.go:171","msg":"trace[1825165847] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:379; }","duration":"129.001398ms","start":"2026-04-28T22:19:02.226967Z","end":"2026-04-28T22:19:02.355969Z","steps":["trace[1825165847] 'read index received' (duration: 5.67618ms)","trace[1825165847] 'applied index is now lower than readState.Index' (duration: 123.323828ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:02.356277Z","caller":"traceutil/trace.go:171","msg":"trace[1818925925] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"128.001061ms","start":"2026-04-28T22:19:02.22823Z","end":"2026-04-28T22:19:02.356231Z","steps":["trace[1818925925] 'process raft request' (duration: 127.465337ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.356358Z","caller":"traceutil/trace.go:171","msg":"trace[1415226165] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"130.555499ms","start":"2026-04-28T22:19:02.225763Z","end":"2026-04-28T22:19:02.356319Z","steps":["trace[1415226165] 'process raft request' (duration: 129.813834ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.356275Z","caller":"traceutil/trace.go:171","msg":"trace[1188617837] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"127.86732ms","start":"2026-04-28T22:19:02.228376Z","end":"2026-04-28T22:19:02.356243Z","steps":["trace[1188617837] 'process raft request' (duration: 127.392146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:02.356448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.440011ms","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-28T22:19:02.356597Z","caller":"traceutil/trace.go:171","msg":"trace[1404856061] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:373; }","duration":"129.638882ms","start":"2026-04-28T22:19:02.226928Z","end":"2026-04-28T22:19:02.356566Z","steps":["trace[1404856061] 'agreement among raft nodes before linearized reading' (duration: 129.30173ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.60974Z","caller":"traceutil/trace.go:171","msg":"trace[174251372] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:386; }","duration":"212.885821ms","start":"2026-04-28T22:19:02.396829Z","end":"2026-04-28T22:19:02.609715Z","steps":["trace[174251372] 'read index received' (duration: 129.985395ms)","trace[174251372] 'applied index is now lower than readState.Index' (duration: 82.899386ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:02.609784Z","caller":"traceutil/trace.go:171","msg":"trace[736519958] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"246.071852ms","start":"2026-04-28T22:19:02.363684Z","end":"2026-04-28T22:19:02.609756Z","steps":["trace[736519958] 'process raft request' (duration: 163.167936ms)","trace[736519958] 'compare' (duration: 82.585944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:02.609883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.030862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-28T22:19:02.609857Z","caller":"traceutil/trace.go:171","msg":"trace[1359072626] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"236.409015ms","start":"2026-04-28T22:19:02.373432Z","end":"2026-04-28T22:19:02.609841Z","steps":["trace[1359072626] 'process raft request' (duration: 236.241683ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.609916Z","caller":"traceutil/trace.go:171","msg":"trace[715486209] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:379; }","duration":"213.082612ms","start":"2026-04-28T22:19:02.396823Z","end":"2026-04-28T22:19:02.609906Z","steps":["trace[715486209] 'agreement among raft nodes before linearized reading' (duration: 213.004552ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.609904Z","caller":"traceutil/trace.go:171","msg":"trace[266148530] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"237.629843ms","start":"2026-04-28T22:19:02.372253Z","end":"2026-04-28T22:19:02.609883Z","steps":["trace[266148530] 'process raft request' (duration: 237.385381ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.610069Z","caller":"traceutil/trace.go:171","msg":"trace[1987744269] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"243.438373ms","start":"2026-04-28T22:19:02.366615Z","end":"2026-04-28T22:19:02.610054Z","steps":["trace[1987744269] 'process raft request' (duration: 242.97891ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.612516Z","caller":"traceutil/trace.go:171","msg":"trace[839883843] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"215.138947ms","start":"2026-04-28T22:19:02.397353Z","end":"2026-04-28T22:19:02.612492Z","steps":["trace[839883843] 'process raft request' (duration: 214.992576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:02.612544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.573887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T22:19:02.612585Z","caller":"traceutil/trace.go:171","msg":"trace[1489029416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:381; }","duration":"127.643348ms","start":"2026-04-28T22:19:02.48493Z","end":"2026-04-28T22:19:02.612573Z","steps":["trace[1489029416] 'agreement among raft nodes before linearized reading' (duration: 127.551977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:02.612755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.966005ms","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-28T22:19:02.61278Z","caller":"traceutil/trace.go:171","msg":"trace[1907013437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:381; }","duration":"110.026875ms","start":"2026-04-28T22:19:02.502747Z","end":"2026-04-28T22:19:02.612774Z","steps":["trace[1907013437] 'agreement among raft nodes before linearized reading' (duration: 109.985345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:02.612872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.656632ms","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-28T22:19:02.612894Z","caller":"traceutil/trace.go:171","msg":"trace[1048888587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:381; }","duration":"119.730473ms","start":"2026-04-28T22:19:02.493158Z","end":"2026-04-28T22:19:02.612889Z","steps":["trace[1048888587] 'agreement among raft nodes before linearized reading' (duration: 119.694932ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.612593Z","caller":"traceutil/trace.go:171","msg":"trace[1234991239] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"213.898787ms","start":"2026-04-28T22:19:02.398655Z","end":"2026-04-28T22:19:02.612554Z","steps":["trace[1234991239] 'process raft request' (duration: 213.774767ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.81407Z","caller":"traceutil/trace.go:171","msg":"trace[300719506] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"106.517332ms","start":"2026-04-28T22:19:02.707522Z","end":"2026-04-28T22:19:02.814039Z","steps":["trace[300719506] 'process raft request' (duration: 47.095438ms)","trace[300719506] 'compare' (duration: 59.284163ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:04.024705Z","caller":"traceutil/trace.go:171","msg":"trace[110517091] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"100.870576ms","start":"2026-04-28T22:19:03.923807Z","end":"2026-04-28T22:19:04.024677Z","steps":["trace[110517091] 'process raft request' (duration: 100.689148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:04.244419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.149946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:19:04.244668Z","caller":"traceutil/trace.go:171","msg":"trace[435833184] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"199.773617ms","start":"2026-04-28T22:19:04.044858Z","end":"2026-04-28T22:19:04.244631Z","steps":["trace[435833184] 'process raft request' (duration: 49.307556ms)","trace[435833184] 'compare' (duration: 149.937046ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:04.542376Z","caller":"traceutil/trace.go:171","msg":"trace[1359822942] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"294.660546ms","start":"2026-04-28T22:19:04.247688Z","end":"2026-04-28T22:19:04.542349Z","steps":["trace[1359822942] 'process raft request' (duration: 278.444164ms)","trace[1359822942] 'compare' (duration: 16.089426ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:04.56153Z","caller":"traceutil/trace.go:171","msg":"trace[1336378589] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"313.47517ms","start":"2026-04-28T22:19:04.248039Z","end":"2026-04-28T22:19:04.561514Z","steps":["trace[1336378589] 'process raft request' (duration: 313.142054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:04.561621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:04.248007Z","time spent":"313.569825ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T22:19:05.155379Z","caller":"traceutil/trace.go:171","msg":"trace[2103387991] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"134.363467ms","start":"2026-04-28T22:19:05.020989Z","end":"2026-04-28T22:19:05.155352Z","steps":["trace[2103387991] 'process raft request' (duration: 70.714972ms)","trace[2103387991] 'compare' (duration: 63.499378ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:05.844451Z","caller":"traceutil/trace.go:171","msg":"trace[157177507] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"345.643715ms","start":"2026-04-28T22:19:05.498774Z","end":"2026-04-28T22:19:05.844418Z","steps":["trace[157177507] 'process raft request' (duration: 345.457877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:05.844626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:05.498757Z","time spent":"345.772371ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:07.264301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.362172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-28T22:19:07.264384Z","caller":"traceutil/trace.go:171","msg":"trace[939290218] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:402; }","duration":"484.479327ms","start":"2026-04-28T22:19:06.77989Z","end":"2026-04-28T22:19:07.264369Z","steps":["trace[939290218] 'range keys from in-memory index tree' (duration: 484.296679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:07.264349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3337808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T22:19:07.26448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:06.779882Z","time spent":"484.586912ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-28T22:19:07.264505Z","caller":"traceutil/trace.go:171","msg":"trace[1783215804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"1.33399639s","start":"2026-04-28T22:19:05.930489Z","end":"2026-04-28T22:19:07.264486Z","steps":["trace[1783215804] 'range keys from in-memory index tree' (duration: 1.333677125s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:07.26455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265390855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-28T22:19:07.264608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.955726ms","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-28T22:19:07.264645Z","caller":"traceutil/trace.go:171","msg":"trace[2103672753] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:402; }","duration":"1.265544252s","start":"2026-04-28T22:19:05.99908Z","end":"2026-04-28T22:19:07.264624Z","steps":["trace[2103672753] 'range keys from in-memory index tree' (duration: 1.265167825s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:07.26459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.019736ms","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-28T22:19:07.264703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:05.999032Z","time spent":"1.265661097s","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-04-28T22:19:07.264565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:05.930466Z","time spent":"1.334085204s","remote":"127.0.0.1:33808","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-28T22:19:07.264729Z","caller":"traceutil/trace.go:171","msg":"trace[567579845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:402; }","duration":"506.190243ms","start":"2026-04-28T22:19:06.758518Z","end":"2026-04-28T22:19:07.264708Z","steps":["trace[567579845] 'range keys from in-memory index tree' (duration: 505.9114ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:07.2647Z","caller":"traceutil/trace.go:171","msg":"trace[1142640209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:402; }","duration":"534.102483ms","start":"2026-04-28T22:19:06.730576Z","end":"2026-04-28T22:19:07.264678Z","steps":["trace[1142640209] 'range keys from in-memory index tree' (duration: 533.836691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:07.264836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:06.758511Z","time spent":"506.311349ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T22:19:07.264922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:06.730555Z","time spent":"534.347824ms","remote":"127.0.0.1:34064","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-28T22:19:07.265119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.743801ms","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-28T22:19:07.265186Z","caller":"traceutil/trace.go:171","msg":"trace[517434925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"718.813044ms","start":"2026-04-28T22:19:06.546362Z","end":"2026-04-28T22:19:07.265175Z","steps":["trace[517434925] 'range keys from in-memory index tree' (duration: 718.658387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:07.265215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:06.546299Z","time spent":"718.910068ms","remote":"127.0.0.1:34064","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-28T22:19:07.264864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.265426ms","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-28T22:19:07.265317Z","caller":"traceutil/trace.go:171","msg":"trace[626230197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:402; }","duration":"506.830822ms","start":"2026-04-28T22:19:06.758467Z","end":"2026-04-28T22:19:07.265298Z","steps":["trace[626230197] 'range keys from in-memory index tree' (duration: 506.1187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:07.265375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:06.758446Z","time spent":"506.911796ms","remote":"127.0.0.1:34064","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-28T22:19:07.776005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495692306395788,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T22:19:07.998468Z","caller":"traceutil/trace.go:171","msg":"trace[779422859] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"727.792429ms","start":"2026-04-28T22:19:07.270628Z","end":"2026-04-28T22:19:07.99842Z","steps":["trace[779422859] 'process raft request' (duration: 646.397791ms)","trace[779422859] 'compare' (duration: 81.090995ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:07.998578Z","caller":"traceutil/trace.go:171","msg":"trace[1720934100] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"727.547958ms","start":"2026-04-28T22:19:07.271018Z","end":"2026-04-28T22:19:07.998566Z","steps":["trace[1720934100] 'process raft request' (duration: 727.345299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:07.998641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:07.271001Z","time spent":"727.604581ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:07.998643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:07.270604Z","time spent":"727.965237ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:19:07.998876Z","caller":"traceutil/trace.go:171","msg":"trace[1462595535] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"727.030474ms","start":"2026-04-28T22:19:07.271834Z","end":"2026-04-28T22:19:07.998864Z","steps":["trace[1462595535] 'process raft request' (duration: 726.976782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:07.998945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:07.271826Z","time spent":"727.076936ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:19:07.999037Z","caller":"traceutil/trace.go:171","msg":"trace[1448105268] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"727.851232ms","start":"2026-04-28T22:19:07.271173Z","end":"2026-04-28T22:19:07.999024Z","steps":["trace[1448105268] 'process raft request' (duration: 727.36039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:07.999086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:07.271164Z","time spent":"727.894284ms","remote":"127.0.0.1:34064","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-28T22:19:08.223497Z","caller":"traceutil/trace.go:171","msg":"trace[1749857802] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:415; }","duration":"947.756587ms","start":"2026-04-28T22:19:07.275718Z","end":"2026-04-28T22:19:08.223474Z","steps":["trace[1749857802] 'read index received' (duration: 641.262148ms)","trace[1749857802] 'applied index is now lower than readState.Index' (duration: 306.493359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:08.223654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.918854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:08.223629Z","caller":"traceutil/trace.go:171","msg":"trace[1641304995] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"948.394395ms","start":"2026-04-28T22:19:07.275139Z","end":"2026-04-28T22:19:08.223533Z","steps":["trace[1641304995] 'process raft request' (duration: 901.354429ms)","trace[1641304995] 'compare' (duration: 46.782065ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:08.223665Z","caller":"traceutil/trace.go:171","msg":"trace[71055830] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"947.865192ms","start":"2026-04-28T22:19:07.275759Z","end":"2026-04-28T22:19:08.223624Z","steps":["trace[71055830] 'process raft request' (duration: 947.651832ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:08.223687Z","caller":"traceutil/trace.go:171","msg":"trace[1464301945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"948.006428ms","start":"2026-04-28T22:19:07.27567Z","end":"2026-04-28T22:19:08.223677Z","steps":["trace[1464301945] 'agreement among raft nodes before linearized reading' (duration: 947.902453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:08.22388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:07.275744Z","time spent":"948.014709ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:08.223964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:07.275073Z","time spent":"948.673518ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:08.223985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:07.275655Z","time spent":"948.27981ms","remote":"127.0.0.1:33800","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-28T22:19:08.346888Z","caller":"traceutil/trace.go:171","msg":"trace[1634497961] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"110.818192ms","start":"2026-04-28T22:19:08.236026Z","end":"2026-04-28T22:19:08.346844Z","steps":["trace[1634497961] 'process raft request' (duration: 110.498477ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:08.658602Z","caller":"traceutil/trace.go:171","msg":"trace[1401018733] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"126.266344ms","start":"2026-04-28T22:19:08.532313Z","end":"2026-04-28T22:19:08.658579Z","steps":["trace[1401018733] 'process raft request' (duration: 126.210561ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:08.658698Z","caller":"traceutil/trace.go:171","msg":"trace[1345718997] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"300.911983ms","start":"2026-04-28T22:19:08.357729Z","end":"2026-04-28T22:19:08.658641Z","steps":["trace[1345718997] 'process raft request' (duration: 258.089086ms)","trace[1345718997] 'compare' (duration: 42.549634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:08.659698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:08.357701Z","time spent":"301.830834ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:19:08.982443Z","caller":"traceutil/trace.go:171","msg":"trace[1048336811] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"311.542478ms","start":"2026-04-28T22:19:08.670873Z","end":"2026-04-28T22:19:08.982416Z","steps":["trace[1048336811] 'process raft request' (duration: 304.813057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:08.982614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:08.670855Z","time spent":"311.673424ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:19:16.264338Z","caller":"traceutil/trace.go:171","msg":"trace[15071593] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"230.806801ms","start":"2026-04-28T22:19:16.033503Z","end":"2026-04-28T22:19:16.264309Z","steps":["trace[15071593] 'process raft request' (duration: 159.222966ms)","trace[15071593] 'compare' (duration: 71.241412ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:16.488114Z","caller":"traceutil/trace.go:171","msg":"trace[583707584] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"216.918255ms","start":"2026-04-28T22:19:16.271157Z","end":"2026-04-28T22:19:16.488076Z","steps":["trace[583707584] 'process raft request' (duration: 216.814071ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:16.488149Z","caller":"traceutil/trace.go:171","msg":"trace[1743587075] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"217.594843ms","start":"2026-04-28T22:19:16.270511Z","end":"2026-04-28T22:19:16.488106Z","steps":["trace[1743587075] 'process raft request' (duration: 217.28075ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:16.488205Z","caller":"traceutil/trace.go:171","msg":"trace[1500412406] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"216.464618ms","start":"2026-04-28T22:19:16.271715Z","end":"2026-04-28T22:19:16.48818Z","steps":["trace[1500412406] 'process raft request' (duration: 216.315852ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:19.170585Z","caller":"traceutil/trace.go:171","msg":"trace[2056829162] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"105.531093ms","start":"2026-04-28T22:19:19.065031Z","end":"2026-04-28T22:19:19.170562Z","steps":["trace[2056829162] 'process raft request' (duration: 105.395838ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:21.256872Z","caller":"traceutil/trace.go:171","msg":"trace[176325044] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"103.911756ms","start":"2026-04-28T22:19:21.152924Z","end":"2026-04-28T22:19:21.256835Z","steps":["trace[176325044] 'process raft request' (duration: 51.546932ms)","trace[176325044] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/deployments/kube-system/coredns; req_size:4177; } (duration: 51.99905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:22.840736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.642801ms","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-28T22:19:22.840773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.9422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T22:19:22.840822Z","caller":"traceutil/trace.go:171","msg":"trace[1597627119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"296.757085ms","start":"2026-04-28T22:19:22.544046Z","end":"2026-04-28T22:19:22.840803Z","steps":["trace[1597627119] 'range keys from in-memory index tree' (duration: 296.579588ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:22.840846Z","caller":"traceutil/trace.go:171","msg":"trace[853827133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:472; }","duration":"308.064675ms","start":"2026-04-28T22:19:22.532761Z","end":"2026-04-28T22:19:22.840826Z","steps":["trace[853827133] 'range keys from in-memory index tree' (duration: 307.862557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:22.840779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.955195ms","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-28T22:19:22.840939Z","caller":"traceutil/trace.go:171","msg":"trace[1269050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"296.153422ms","start":"2026-04-28T22:19:22.544761Z","end":"2026-04-28T22:19:22.840914Z","steps":["trace[1269050] 'range keys from in-memory index tree' (duration: 295.895012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:22.840885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:22.532744Z","time spent":"308.134908ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T22:19:24.608846Z","caller":"traceutil/trace.go:171","msg":"trace[998143284] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"252.062782ms","start":"2026-04-28T22:19:24.356759Z","end":"2026-04-28T22:19:24.608822Z","steps":["trace[998143284] 'process raft request' (duration: 251.909076ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:29.185325Z","caller":"traceutil/trace.go:171","msg":"trace[1846454088] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"180.155287ms","start":"2026-04-28T22:19:29.005141Z","end":"2026-04-28T22:19:29.185296Z","steps":["trace[1846454088] 'process raft request' (duration: 169.963068ms)","trace[1846454088] 'compare' (duration: 10.063394ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:29.262264Z","caller":"traceutil/trace.go:171","msg":"trace[2079239881] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"143.928831ms","start":"2026-04-28T22:19:29.11831Z","end":"2026-04-28T22:19:29.262239Z","steps":["trace[2079239881] 'process raft request' (duration: 143.822508ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:35.047384Z","caller":"traceutil/trace.go:171","msg":"trace[2028344553] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"433.946633ms","start":"2026-04-28T22:19:34.613417Z","end":"2026-04-28T22:19:35.047363Z","steps":["trace[2028344553] 'process raft request' (duration: 433.83731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.047572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.613383Z","time spent":"434.095708ms","remote":"127.0.0.1:33864","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-28T22:19:35.086086Z","caller":"traceutil/trace.go:171","msg":"trace[1864347866] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"468.161009ms","start":"2026-04-28T22:19:34.61789Z","end":"2026-04-28T22:19:35.086051Z","steps":["trace[1864347866] 'process raft request' (duration: 467.87068ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:35.086167Z","caller":"traceutil/trace.go:171","msg":"trace[331841151] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"461.00113ms","start":"2026-04-28T22:19:34.625153Z","end":"2026-04-28T22:19:35.086154Z","steps":["trace[331841151] 'process raft request' (duration: 460.809713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.086246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.62514Z","time spent":"461.060572ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:19:35.08626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.617861Z","time spent":"468.321875ms","remote":"127.0.0.1:33864","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-28T22:19:35.0866Z","caller":"traceutil/trace.go:171","msg":"trace[1075498157] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"455.854349ms","start":"2026-04-28T22:19:34.630693Z","end":"2026-04-28T22:19:35.086547Z","steps":["trace[1075498157] 'process raft request' (duration: 455.371523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.086812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.630673Z","time spent":"456.045494ms","remote":"127.0.0.1:33864","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-28T22:19:35.087488Z","caller":"traceutil/trace.go:171","msg":"trace[703877869] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"456.718658ms","start":"2026-04-28T22:19:34.630749Z","end":"2026-04-28T22:19:35.087468Z","steps":["trace[703877869] 'process raft request' (duration: 455.659222ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:35.087516Z","caller":"traceutil/trace.go:171","msg":"trace[1214964787] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"452.293801ms","start":"2026-04-28T22:19:34.635186Z","end":"2026-04-28T22:19:35.08748Z","steps":["trace[1214964787] 'process raft request' (duration: 452.000601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.087569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.630746Z","time spent":"456.79222ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T22:19:35.087787Z","caller":"traceutil/trace.go:171","msg":"trace[67478139] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"450.359636ms","start":"2026-04-28T22:19:34.637389Z","end":"2026-04-28T22:19:35.087749Z","steps":["trace[67478139] 'process raft request' (duration: 449.926622ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:35.087789Z","caller":"traceutil/trace.go:171","msg":"trace[1622491778] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"452.477306ms","start":"2026-04-28T22:19:34.635261Z","end":"2026-04-28T22:19:35.087739Z","steps":["trace[1622491778] 'process raft request' (duration: 452.008801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.087963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.637374Z","time spent":"450.46234ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T22:19:35.08801Z","caller":"traceutil/trace.go:171","msg":"trace[1529604475] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"436.246566ms","start":"2026-04-28T22:19:34.651745Z","end":"2026-04-28T22:19:35.087991Z","steps":["trace[1529604475] 'process raft request' (duration: 435.705448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.088046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.635255Z","time spent":"452.629471ms","remote":"127.0.0.1:33864","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-28T22:19:35.088072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.651646Z","time spent":"436.39985ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:19:35.088355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.635169Z","time spent":"452.391604ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T22:19:35.088632Z","caller":"traceutil/trace.go:171","msg":"trace[1138644522] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"428.139845ms","start":"2026-04-28T22:19:34.660468Z","end":"2026-04-28T22:19:35.088608Z","steps":["trace[1138644522] 'process raft request' (duration: 427.842585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.088733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.660445Z","time spent":"428.241828ms","remote":"127.0.0.1:33864","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-28T22:19:35.089948Z","caller":"traceutil/trace.go:171","msg":"trace[1309138804] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"404.492257ms","start":"2026-04-28T22:19:34.68544Z","end":"2026-04-28T22:19:35.089932Z","steps":["trace[1309138804] 'process raft request' (duration: 403.749232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.090015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.685412Z","time spent":"404.57429ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T22:19:35.090487Z","caller":"traceutil/trace.go:171","msg":"trace[1320497464] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:551; }","duration":"374.127705ms","start":"2026-04-28T22:19:34.716335Z","end":"2026-04-28T22:19:35.090462Z","steps":["trace[1320497464] 'read index received' (duration: 331.039564ms)","trace[1320497464] 'applied index is now lower than readState.Index' (duration: 43.086831ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:35.090594Z","caller":"traceutil/trace.go:171","msg":"trace[1955524787] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"268.94729ms","start":"2026-04-28T22:19:34.821634Z","end":"2026-04-28T22:19:35.090581Z","steps":["trace[1955524787] 'process raft request' (duration: 268.609069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.090845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.494667ms","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-28T22:19:35.090908Z","caller":"traceutil/trace.go:171","msg":"trace[1693998433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"374.605631ms","start":"2026-04-28T22:19:34.716288Z","end":"2026-04-28T22:19:35.090893Z","steps":["trace[1693998433] 'agreement among raft nodes before linearized reading' (duration: 374.404435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:35.09095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:34.716276Z","time spent":"374.666093ms","remote":"127.0.0.1:34064","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-28T22:19:37.79722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495692306396230,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T22:19:38.219759Z","caller":"traceutil/trace.go:171","msg":"trace[977488917] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"943.745565ms","start":"2026-04-28T22:19:37.275993Z","end":"2026-04-28T22:19:38.219738Z","steps":["trace[977488917] 'process raft request' (duration: 943.58752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:38.219899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:37.275972Z","time spent":"943.857018ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:19:38.659778Z","caller":"traceutil/trace.go:171","msg":"trace[1935232444] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"1.363139681s","start":"2026-04-28T22:19:37.296607Z","end":"2026-04-28T22:19:38.659747Z","steps":["trace[1935232444] 'read index received' (duration: 923.207332ms)","trace[1935232444] 'applied index is now lower than readState.Index' (duration: 439.931139ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:38.65993Z","caller":"traceutil/trace.go:171","msg":"trace[431140651] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.288895485s","start":"2026-04-28T22:19:37.37102Z","end":"2026-04-28T22:19:38.659916Z","steps":["trace[431140651] 'process raft request' (duration: 1.288456121s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:38.660094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:37.37099Z","time spent":"1.289008278s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:19:38.660297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363671368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:38.660388Z","caller":"traceutil/trace.go:171","msg":"trace[1248232321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"1.363794982s","start":"2026-04-28T22:19:37.296567Z","end":"2026-04-28T22:19:38.660362Z","steps":["trace[1248232321] 'agreement among raft nodes before linearized reading' (duration: 1.363610266s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:38.660436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090840452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T22:19:38.660442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:37.29655Z","time spent":"1.363880505s","remote":"127.0.0.1:33808","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-28T22:19:38.660491Z","caller":"traceutil/trace.go:171","msg":"trace[1298825968] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"1.090927975s","start":"2026-04-28T22:19:37.569545Z","end":"2026-04-28T22:19:38.660473Z","steps":["trace[1298825968] 'agreement among raft nodes before linearized reading' (duration: 1.090835292s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:38.660535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:37.569529Z","time spent":"1.090995957s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-04-28T22:19:38.661087Z","caller":"traceutil/trace.go:171","msg":"trace[2124177078] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"485.021789ms","start":"2026-04-28T22:19:38.176042Z","end":"2026-04-28T22:19:38.661064Z","steps":["trace[2124177078] 'process raft request' (duration: 483.609384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:38.661138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.429417ms","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-28T22:19:38.661186Z","caller":"traceutil/trace.go:171","msg":"trace[1025251010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:647; }","duration":"281.53636ms","start":"2026-04-28T22:19:38.379636Z","end":"2026-04-28T22:19:38.661172Z","steps":["trace[1025251010] 'agreement among raft nodes before linearized reading' (duration: 281.402996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:38.661233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:38.176018Z","time spent":"485.145283ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:19:38.66155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.861771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-28T22:19:38.661605Z","caller":"traceutil/trace.go:171","msg":"trace[516083797] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:647; }","duration":"291.968435ms","start":"2026-04-28T22:19:38.369623Z","end":"2026-04-28T22:19:38.661592Z","steps":["trace[516083797] 'agreement among raft nodes before linearized reading' (duration: 291.786379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:38.661705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.954709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:38.661737Z","caller":"traceutil/trace.go:171","msg":"trace[1965540375] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"692.023623ms","start":"2026-04-28T22:19:37.969706Z","end":"2026-04-28T22:19:38.661729Z","steps":["trace[1965540375] 'agreement among raft nodes before linearized reading' (duration: 691.977311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:38.661762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:37.969685Z","time spent":"692.071014ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-28T22:19:38.66188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.651065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:38.66191Z","caller":"traceutil/trace.go:171","msg":"trace[740351897] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"891.717246ms","start":"2026-04-28T22:19:37.770184Z","end":"2026-04-28T22:19:38.661901Z","steps":["trace[740351897] 'agreement among raft nodes before linearized reading' (duration: 891.678165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:38.661943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:37.770163Z","time spent":"891.774049ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-28T22:19:43.072971Z","caller":"traceutil/trace.go:171","msg":"trace[69239762] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"103.289602ms","start":"2026-04-28T22:19:42.969659Z","end":"2026-04-28T22:19:43.072949Z","steps":["trace[69239762] 'process raft request' (duration: 103.166389ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:43.283992Z","caller":"traceutil/trace.go:171","msg":"trace[671367921] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"204.624425ms","start":"2026-04-28T22:19:43.079344Z","end":"2026-04-28T22:19:43.283968Z","steps":["trace[671367921] 'process raft request' (duration: 203.44661ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:43.284171Z","caller":"traceutil/trace.go:171","msg":"trace[679136221] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"164.082297ms","start":"2026-04-28T22:19:43.120068Z","end":"2026-04-28T22:19:43.284151Z","steps":["trace[679136221] 'process raft request' (duration: 163.840279ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:43.284623Z","caller":"traceutil/trace.go:171","msg":"trace[194698402] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:719; }","duration":"123.706852ms","start":"2026-04-28T22:19:43.160908Z","end":"2026-04-28T22:19:43.284615Z","steps":["trace[194698402] 'read index received' (duration: 121.868566ms)","trace[194698402] 'applied index is now lower than readState.Index' (duration: 1.837456ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:43.284677Z","caller":"traceutil/trace.go:171","msg":"trace[1453396138] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"164.513929ms","start":"2026-04-28T22:19:43.120158Z","end":"2026-04-28T22:19:43.284672Z","steps":["trace[1453396138] 'process raft request' (duration: 163.935972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:43.284875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.773564ms","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-28T22:19:43.284934Z","caller":"traceutil/trace.go:171","msg":"trace[1638205731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:697; }","duration":"123.855936ms","start":"2026-04-28T22:19:43.161063Z","end":"2026-04-28T22:19:43.284919Z","steps":["trace[1638205731] 'agreement among raft nodes before linearized reading' (duration: 123.677571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:43.285127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.203006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T22:19:43.285145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.055351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T22:19:43.285165Z","caller":"traceutil/trace.go:171","msg":"trace[1898649514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:697; }","duration":"124.292359ms","start":"2026-04-28T22:19:43.160862Z","end":"2026-04-28T22:19:43.285154Z","steps":["trace[1898649514] 'agreement among raft nodes before linearized reading' (duration: 124.157515ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:43.285178Z","caller":"traceutil/trace.go:171","msg":"trace[755842076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:697; }","duration":"123.087832ms","start":"2026-04-28T22:19:43.16208Z","end":"2026-04-28T22:19:43.285168Z","steps":["trace[755842076] 'agreement among raft nodes before linearized reading' (duration: 122.99965ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:43.430306Z","caller":"traceutil/trace.go:171","msg":"trace[736710926] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"139.620254ms","start":"2026-04-28T22:19:43.290665Z","end":"2026-04-28T22:19:43.430285Z","steps":["trace[736710926] 'process raft request' (duration: 139.548462ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:43.430359Z","caller":"traceutil/trace.go:171","msg":"trace[1940635954] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"139.717018ms","start":"2026-04-28T22:19:43.29061Z","end":"2026-04-28T22:19:43.430327Z","steps":["trace[1940635954] 'process raft request' (duration: 135.116738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:45.544215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495692306396366,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T22:19:45.810619Z","caller":"traceutil/trace.go:171","msg":"trace[1112410113] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"767.379514ms","start":"2026-04-28T22:19:45.043214Z","end":"2026-04-28T22:19:45.810593Z","steps":["trace[1112410113] 'read index received' (duration: 767.166148ms)","trace[1112410113] 'applied index is now lower than readState.Index' (duration: 212.086µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:45.810845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.132878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:45.810902Z","caller":"traceutil/trace.go:171","msg":"trace[257528005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"512.268783ms","start":"2026-04-28T22:19:45.29862Z","end":"2026-04-28T22:19:45.810889Z","steps":["trace[257528005] 'agreement among raft nodes before linearized reading' (duration: 512.156339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:45.810953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:45.298588Z","time spent":"512.355135ms","remote":"127.0.0.1:33808","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-28T22:19:45.810943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.695184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rwl6s\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T22:19:45.811055Z","caller":"traceutil/trace.go:171","msg":"trace[391661411] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rwl6s; range_end:; response_count:1; response_revision:705; }","duration":"767.87934ms","start":"2026-04-28T22:19:45.043161Z","end":"2026-04-28T22:19:45.81104Z","steps":["trace[391661411] 'agreement among raft nodes before linearized reading' (duration: 767.583731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:45.811101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:45.043125Z","time spent":"767.965283ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rwl6s\" "} {"level":"warn","ts":"2026-04-28T22:19:45.811113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.928193ms","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-28T22:19:45.811204Z","caller":"traceutil/trace.go:171","msg":"trace[1426745301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"372.079777ms","start":"2026-04-28T22:19:45.439101Z","end":"2026-04-28T22:19:45.811181Z","steps":["trace[1426745301] 'agreement among raft nodes before linearized reading' (duration: 371.809389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:45.81125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:45.439076Z","time spent":"372.16589ms","remote":"127.0.0.1:34064","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-28T22:19:45.811321Z","caller":"traceutil/trace.go:171","msg":"trace[1552451512] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"932.534859ms","start":"2026-04-28T22:19:44.878131Z","end":"2026-04-28T22:19:45.810666Z","steps":["trace[1552451512] 'process raft request' (duration: 932.275622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:45.811349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.043685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T22:19:45.811496Z","caller":"traceutil/trace.go:171","msg":"trace[956314598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"450.252531ms","start":"2026-04-28T22:19:45.361205Z","end":"2026-04-28T22:19:45.811458Z","steps":["trace[956314598] 'agreement among raft nodes before linearized reading' (duration: 449.821168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:45.81156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:45.361186Z","time spent":"450.363844ms","remote":"127.0.0.1:34064","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-28T22:19:45.811705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:44.878097Z","time spent":"933.331514ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:45.811761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.414548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T22:19:45.811821Z","caller":"traceutil/trace.go:171","msg":"trace[2135140502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"371.518201ms","start":"2026-04-28T22:19:45.440291Z","end":"2026-04-28T22:19:45.811809Z","steps":["trace[2135140502] 'agreement among raft nodes before linearized reading' (duration: 371.360086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:45.811855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:45.440271Z","time spent":"371.577693ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T22:19:46.50273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495692306396369,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T22:19:46.79523Z","caller":"traceutil/trace.go:171","msg":"trace[481580793] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"973.505334ms","start":"2026-04-28T22:19:45.82169Z","end":"2026-04-28T22:19:46.795195Z","steps":["trace[481580793] 'process raft request' (duration: 973.279627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:46.795459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:45.82168Z","time spent":"973.644348ms","remote":"127.0.0.1:34064","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-28T22:19:46.795503Z","caller":"traceutil/trace.go:171","msg":"trace[1926334696] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"971.784592ms","start":"2026-04-28T22:19:45.823698Z","end":"2026-04-28T22:19:46.795482Z","steps":["trace[1926334696] 'process raft request' (duration: 971.429072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:46.795695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:45.823657Z","time spent":"971.914876ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:19:46.795716Z","caller":"traceutil/trace.go:171","msg":"trace[1830686253] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:731; }","duration":"793.420132ms","start":"2026-04-28T22:19:46.002262Z","end":"2026-04-28T22:19:46.795682Z","steps":["trace[1830686253] 'read index received' (duration: 792.64542ms)","trace[1830686253] 'applied index is now lower than readState.Index' (duration: 772.082µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:46.795839Z","caller":"traceutil/trace.go:171","msg":"trace[742713986] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"966.885406ms","start":"2026-04-28T22:19:45.828905Z","end":"2026-04-28T22:19:46.79579Z","steps":["trace[742713986] 'process raft request' (duration: 966.448533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:46.795915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.949329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T22:19:46.795936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.688179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T22:19:46.795979Z","caller":"traceutil/trace.go:171","msg":"trace[133565932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"499.039731ms","start":"2026-04-28T22:19:46.296921Z","end":"2026-04-28T22:19:46.79596Z","steps":["trace[133565932] 'agreement among raft nodes before linearized reading' (duration: 498.935108ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:46.79598Z","caller":"traceutil/trace.go:171","msg":"trace[1826378989] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:709; }","duration":"793.780972ms","start":"2026-04-28T22:19:46.002187Z","end":"2026-04-28T22:19:46.795968Z","steps":["trace[1826378989] 'agreement among raft nodes before linearized reading' (duration: 793.630898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:46.796009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.309702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T22:19:46.796023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:46.296908Z","time spent":"499.104274ms","remote":"127.0.0.1:33800","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-28T22:19:46.796028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:46.002163Z","time spent":"793.850574ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"info","ts":"2026-04-28T22:19:46.79605Z","caller":"traceutil/trace.go:171","msg":"trace[252078614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"528.453216ms","start":"2026-04-28T22:19:46.267586Z","end":"2026-04-28T22:19:46.79604Z","steps":["trace[252078614] 'agreement among raft nodes before linearized reading' (duration: 528.265381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:46.79607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:45.828886Z","time spent":"967.061772ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:46.796086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:46.267563Z","time spent":"528.515068ms","remote":"127.0.0.1:34064","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-28T22:19:47.33029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.3522ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-28T22:19:47.330657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:46.797849Z","time spent":"532.802019ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T22:19:47.33076Z","caller":"traceutil/trace.go:171","msg":"trace[1275187964] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"526.848394ms","start":"2026-04-28T22:19:46.803883Z","end":"2026-04-28T22:19:47.330731Z","steps":["trace[1275187964] 'process raft request' (duration: 526.602267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:47.331199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:46.80385Z","time spent":"527.248016ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:48.319075Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495692306396376,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T22:19:48.7521Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.419585671s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T22:19:48.752572Z","caller":"traceutil/trace.go:171","msg":"trace[1773342814] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"933.962628ms","start":"2026-04-28T22:19:47.818581Z","end":"2026-04-28T22:19:48.752543Z","steps":["trace[1773342814] 'read index received' (duration: 933.678449ms)","trace[1773342814] 'applied index is now lower than readState.Index' (duration: 282.979µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:48.752703Z","caller":"traceutil/trace.go:171","msg":"trace[1495920422] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"1.420316192s","start":"2026-04-28T22:19:47.332353Z","end":"2026-04-28T22:19:48.752669Z","steps":["trace[1495920422] 'process raft request' (duration: 1.419988603s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:48.752881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.273536ms","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":"warn","ts":"2026-04-28T22:19:48.752885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:47.332319Z","time spent":"1.420466897s","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:19:48.75293Z","caller":"traceutil/trace.go:171","msg":"trace[1626146704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:711; }","duration":"934.383969ms","start":"2026-04-28T22:19:47.818534Z","end":"2026-04-28T22:19:48.752918Z","steps":["trace[1626146704] 'agreement among raft nodes before linearized reading' (duration: 934.121562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:48.752975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:47.818513Z","time spent":"934.450592ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T22:19:48.752957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.865218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:48.753046Z","caller":"traceutil/trace.go:171","msg":"trace[66674107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"454.026322ms","start":"2026-04-28T22:19:48.298999Z","end":"2026-04-28T22:19:48.753025Z","steps":["trace[66674107] 'agreement among raft nodes before linearized reading' (duration: 453.815926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:48.753092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:48.298978Z","time spent":"454.106255ms","remote":"127.0.0.1:33800","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-28T22:19:48.753001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.417973ms","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-28T22:19:48.753342Z","caller":"traceutil/trace.go:171","msg":"trace[347505965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:711; }","duration":"885.856255ms","start":"2026-04-28T22:19:47.867465Z","end":"2026-04-28T22:19:48.753321Z","steps":["trace[347505965] 'agreement among raft nodes before linearized reading' (duration: 885.32267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:48.753394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:47.867435Z","time spent":"885.947868ms","remote":"127.0.0.1:34064","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-28T22:19:49.720578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.500031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:19:49.720789Z","caller":"traceutil/trace.go:171","msg":"trace[1492804607] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:737; }","duration":"961.963197ms","start":"2026-04-28T22:19:48.758808Z","end":"2026-04-28T22:19:49.720771Z","steps":["trace[1492804607] 'read index received' (duration: 66.190778ms)","trace[1492804607] 'applied index is now lower than readState.Index' (duration: 895.771189ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:49.720879Z","caller":"traceutil/trace.go:171","msg":"trace[1715821642] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"962.993537ms","start":"2026-04-28T22:19:48.757875Z","end":"2026-04-28T22:19:49.720868Z","steps":["trace[1715821642] 'process raft request' (duration: 67.088054ms)","trace[1715821642] 'compare' (duration: 895.231274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:49.720943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:48.757855Z","time spent":"963.056209ms","remote":"127.0.0.1:34064","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-28T22:19:49.721338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.498922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-28T22:19:49.721429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.190675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T22:19:49.721502Z","caller":"traceutil/trace.go:171","msg":"trace[341495466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:713; }","duration":"918.309547ms","start":"2026-04-28T22:19:48.803168Z","end":"2026-04-28T22:19:49.721477Z","steps":["trace[341495466] 'agreement among raft nodes before linearized reading' (duration: 918.114592ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:49.721503Z","caller":"traceutil/trace.go:171","msg":"trace[16749770] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:713; }","duration":"962.712029ms","start":"2026-04-28T22:19:48.75874Z","end":"2026-04-28T22:19:49.721452Z","steps":["trace[16749770] 'agreement among raft nodes before linearized reading' (duration: 962.487582ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:49.721541Z","caller":"traceutil/trace.go:171","msg":"trace[2109369005] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"963.382977ms","start":"2026-04-28T22:19:48.758137Z","end":"2026-04-28T22:19:49.72152Z","steps":["trace[2109369005] 'process raft request' (duration: 962.544344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:49.721618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:48.758717Z","time spent":"962.860103ms","remote":"127.0.0.1:34074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-28T22:19:49.721645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:48.758124Z","time spent":"963.469871ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:49.721721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.788314ms","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-28T22:19:49.721779Z","caller":"traceutil/trace.go:171","msg":"trace[1388060881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:713; }","duration":"917.833405ms","start":"2026-04-28T22:19:48.803926Z","end":"2026-04-28T22:19:49.721759Z","steps":["trace[1388060881] 'agreement among raft nodes before linearized reading' (duration: 917.733422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:49.721585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.521907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T22:19:49.721829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:48.803858Z","time spent":"917.963069ms","remote":"127.0.0.1:34064","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-28T22:19:49.721847Z","caller":"traceutil/trace.go:171","msg":"trace[292250820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:713; }","duration":"385.837197ms","start":"2026-04-28T22:19:49.335994Z","end":"2026-04-28T22:19:49.721832Z","steps":["trace[292250820] 'agreement among raft nodes before linearized reading' (duration: 385.422125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:49.721889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:49.335975Z","time spent":"385.90062ms","remote":"127.0.0.1:34064","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-28T22:19:49.721636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:48.803104Z","time spent":"918.444021ms","remote":"127.0.0.1:34064","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-28T22:19:49.960627Z","caller":"traceutil/trace.go:171","msg":"trace[1643546216] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"232.598592ms","start":"2026-04-28T22:19:49.727997Z","end":"2026-04-28T22:19:49.960596Z","steps":["trace[1643546216] 'process raft request' (duration: 232.52086ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:49.960747Z","caller":"traceutil/trace.go:171","msg":"trace[1227670436] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"218.755887ms","start":"2026-04-28T22:19:49.741978Z","end":"2026-04-28T22:19:49.960734Z","steps":["trace[1227670436] 'process raft request' (duration: 218.580852ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:49.960638Z","caller":"traceutil/trace.go:171","msg":"trace[47332934] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"233.117636ms","start":"2026-04-28T22:19:49.727491Z","end":"2026-04-28T22:19:49.960609Z","steps":["trace[47332934] 'process raft request' (duration: 187.560886ms)","trace[47332934] 'compare' (duration: 45.297173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:51.99061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495692306396395,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T22:19:52.491698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495692306396395,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T22:19:52.822171Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.486133579s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T22:19:52.859844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593477746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:52.859936Z","caller":"traceutil/trace.go:171","msg":"trace[1269990790] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:717; }","duration":"1.5936338s","start":"2026-04-28T22:19:51.266281Z","end":"2026-04-28T22:19:52.859915Z","steps":["trace[1269990790] 'count revisions from in-memory index tree' (duration: 1.593371993s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.859978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:51.266258Z","time spent":"1.593709652s","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-28T22:19:52.860025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.562246286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:52.860111Z","caller":"traceutil/trace.go:171","msg":"trace[214231318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"2.56239965s","start":"2026-04-28T22:19:50.297686Z","end":"2026-04-28T22:19:52.860086Z","steps":["trace[214231318] 'range keys from in-memory index tree' (duration: 2.562132603s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.860174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:50.297657Z","time spent":"2.562503023s","remote":"127.0.0.1:33800","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-28T22:19:52.924281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.812884ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T22:19:52.924656Z","caller":"traceutil/trace.go:171","msg":"trace[523459105] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:743; }","duration":"1.434719942s","start":"2026-04-28T22:19:51.489911Z","end":"2026-04-28T22:19:52.924631Z","steps":["trace[523459105] 'read index received' (duration: 1.33258744s)","trace[523459105] 'applied index is now lower than readState.Index' (duration: 102.130862ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:52.924734Z","caller":"traceutil/trace.go:171","msg":"trace[1630187993] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"852.578781ms","start":"2026-04-28T22:19:52.072112Z","end":"2026-04-28T22:19:52.924691Z","steps":["trace[1630187993] 'process raft request' (duration: 852.347445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.924839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434916538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T22:19:52.924881Z","caller":"traceutil/trace.go:171","msg":"trace[2080851565] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:718; }","duration":"1.435027561s","start":"2026-04-28T22:19:51.489843Z","end":"2026-04-28T22:19:52.92487Z","steps":["trace[2080851565] 'agreement among raft nodes before linearized reading' (duration: 1.434925148s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.924928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:51.489815Z","time spent":"1.435102993s","remote":"127.0.0.1:34216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-28T22:19:52.92495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:52.072089Z","time spent":"852.737316ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:52.925275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198538481s","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-28T22:19:52.925335Z","caller":"traceutil/trace.go:171","msg":"trace[1990684949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"1.198640213s","start":"2026-04-28T22:19:51.726681Z","end":"2026-04-28T22:19:52.925321Z","steps":["trace[1990684949] 'agreement among raft nodes before linearized reading' (duration: 1.198235372s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.925374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:51.726659Z","time spent":"1.198705854s","remote":"127.0.0.1:34064","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-28T22:19:52.925428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.148091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:52.925543Z","caller":"traceutil/trace.go:171","msg":"trace[363917562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"627.314405ms","start":"2026-04-28T22:19:52.29821Z","end":"2026-04-28T22:19:52.925525Z","steps":["trace[363917562] 'agreement among raft nodes before linearized reading' (duration: 627.117739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.925572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.409913ms","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-28T22:19:52.925636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163458598s","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-28T22:19:52.925682Z","caller":"traceutil/trace.go:171","msg":"trace[1299587798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"1.16354069s","start":"2026-04-28T22:19:51.762128Z","end":"2026-04-28T22:19:52.925669Z","steps":["trace[1299587798] 'agreement among raft nodes before linearized reading' (duration: 1.163353245s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.925691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198155918s","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-28T22:19:52.925692Z","caller":"traceutil/trace.go:171","msg":"trace[1313039330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"960.597048ms","start":"2026-04-28T22:19:51.965066Z","end":"2026-04-28T22:19:52.925663Z","steps":["trace[1313039330] 'agreement among raft nodes before linearized reading' (duration: 960.155156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.925726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:51.762087Z","time spent":"1.163631392s","remote":"127.0.0.1:34064","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-28T22:19:52.925735Z","caller":"traceutil/trace.go:171","msg":"trace[553614061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"1.19823791s","start":"2026-04-28T22:19:51.727486Z","end":"2026-04-28T22:19:52.925724Z","steps":["trace[553614061] 'agreement among raft nodes before linearized reading' (duration: 1.198104947s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.925751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.25911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T22:19:52.92571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:52.298169Z","time spent":"627.455239ms","remote":"127.0.0.1:33808","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-28T22:19:52.925761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:51.965045Z","time spent":"960.70493ms","remote":"127.0.0.1:34064","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-28T22:19:52.925789Z","caller":"traceutil/trace.go:171","msg":"trace[1533361169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"959.319211ms","start":"2026-04-28T22:19:51.966459Z","end":"2026-04-28T22:19:52.925778Z","steps":["trace[1533361169] 'agreement among raft nodes before linearized reading' (duration: 959.203988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:52.925823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:51.966446Z","time spent":"959.370512ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T22:19:52.925768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:51.727453Z","time spent":"1.198308922s","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T22:19:55.10688Z","caller":"traceutil/trace.go:171","msg":"trace[1400939850] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"113.288405ms","start":"2026-04-28T22:19:54.993523Z","end":"2026-04-28T22:19:55.106812Z","steps":["trace[1400939850] 'process raft request' (duration: 113.200913ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:55.106922Z","caller":"traceutil/trace.go:171","msg":"trace[858934249] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"114.57121ms","start":"2026-04-28T22:19:54.992294Z","end":"2026-04-28T22:19:55.106865Z","steps":["trace[858934249] 'process raft request' (duration: 102.211146ms)","trace[858934249] 'compare' (duration: 12.060206ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:55.107306Z","caller":"traceutil/trace.go:171","msg":"trace[546137478] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"112.797481ms","start":"2026-04-28T22:19:54.994392Z","end":"2026-04-28T22:19:55.107189Z","steps":["trace[546137478] 'process raft request' (duration: 112.3809ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:55.890677Z","caller":"traceutil/trace.go:171","msg":"trace[92992608] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"108.558135ms","start":"2026-04-28T22:19:55.782057Z","end":"2026-04-28T22:19:55.890615Z","steps":["trace[92992608] 'process raft request' (duration: 108.327269ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:20:01.149624Z","caller":"traceutil/trace.go:171","msg":"trace[288193509] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"115.457081ms","start":"2026-04-28T22:20:01.034142Z","end":"2026-04-28T22:20:01.149599Z","steps":["trace[288193509] 'process raft request' (duration: 115.278276ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:20:01.152031Z","caller":"traceutil/trace.go:171","msg":"trace[920924884] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"114.892397ms","start":"2026-04-28T22:20:01.037119Z","end":"2026-04-28T22:20:01.152012Z","steps":["trace[920924884] 'process raft request' (duration: 114.59102ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:20:01.298536Z","caller":"traceutil/trace.go:171","msg":"trace[1029886897] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"107.783565ms","start":"2026-04-28T22:20:01.190725Z","end":"2026-04-28T22:20:01.298508Z","steps":["trace[1029886897] 'process raft request' (duration: 101.939526ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:20:06.304845Z","caller":"traceutil/trace.go:171","msg":"trace[748093473] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"114.621441ms","start":"2026-04-28T22:20:06.190195Z","end":"2026-04-28T22:20:06.304816Z","steps":["trace[748093473] 'process raft request' (duration: 114.395696ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:20:06.305013Z","caller":"traceutil/trace.go:171","msg":"trace[37575434] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"113.993936ms","start":"2026-04-28T22:20:06.190992Z","end":"2026-04-28T22:20:06.304986Z","steps":["trace[37575434] 'process raft request' (duration: 113.76333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:20:10.797176Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495692306396713,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T22:20:11.152604Z","caller":"traceutil/trace.go:171","msg":"trace[774527896] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"945.697324ms","start":"2026-04-28T22:20:10.206878Z","end":"2026-04-28T22:20:11.152576Z","steps":["trace[774527896] 'process raft request' (duration: 945.51617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:20:11.152793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:20:10.206844Z","time spent":"945.842797ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:20:11.153207Z","caller":"traceutil/trace.go:171","msg":"trace[130440392] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:871; }","duration":"857.063729ms","start":"2026-04-28T22:20:10.296122Z","end":"2026-04-28T22:20:11.153185Z","steps":["trace[130440392] 'read index received' (duration: 856.24057ms)","trace[130440392] 'applied index is now lower than readState.Index' (duration: 822.059µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:20:11.153259Z","caller":"traceutil/trace.go:171","msg":"trace[1015204206] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"396.192339ms","start":"2026-04-28T22:20:10.757051Z","end":"2026-04-28T22:20:11.153243Z","steps":["trace[1015204206] 'process raft request' (duration: 395.990574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:20:11.153326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:20:10.757011Z","time spent":"396.282151ms","remote":"127.0.0.1:34064","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-28T22:20:11.153518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.373006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:20:11.153558Z","caller":"traceutil/trace.go:171","msg":"trace[813141594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"857.496839ms","start":"2026-04-28T22:20:10.296052Z","end":"2026-04-28T22:20:11.153548Z","steps":["trace[813141594] 'agreement among raft nodes before linearized reading' (duration: 857.229633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:20:11.153641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:20:10.296013Z","time spent":"857.620202ms","remote":"127.0.0.1:33800","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-28T22:20:24.865367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.518905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-7hntc\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-04-28T22:20:24.865561Z","caller":"traceutil/trace.go:171","msg":"trace[216605147] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-7hntc; range_end:; response_count:1; response_revision:997; }","duration":"208.76909ms","start":"2026-04-28T22:20:24.656772Z","end":"2026-04-28T22:20:24.865541Z","steps":["trace[216605147] 'range keys from in-memory index tree' (duration: 208.294721ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:20:44.128224Z","caller":"traceutil/trace.go:171","msg":"trace[1750645458] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"110.484021ms","start":"2026-04-28T22:20:44.017701Z","end":"2026-04-28T22:20:44.128185Z","steps":["trace[1750645458] 'process raft request' (duration: 110.270627ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:21:06.059944Z","caller":"traceutil/trace.go:171","msg":"trace[41533313] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"118.707764ms","start":"2026-04-28T22:21:05.941168Z","end":"2026-04-28T22:21:06.059876Z","steps":["trace[41533313] 'process raft request' (duration: 64.922444ms)","trace[41533313] 'compare' (duration: 53.486356ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:21:16.244208Z","caller":"traceutil/trace.go:171","msg":"trace[1999059750] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"143.191429ms","start":"2026-04-28T22:21:16.100986Z","end":"2026-04-28T22:21:16.244177Z","steps":["trace[1999059750] 'read index received' (duration: 123.234415ms)","trace[1999059750] 'applied index is now lower than readState.Index' (duration: 19.954694ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:21:16.24447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.451663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T22:21:16.244516Z","caller":"traceutil/trace.go:171","msg":"trace[702480459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1188; }","duration":"143.566245ms","start":"2026-04-28T22:21:16.100939Z","end":"2026-04-28T22:21:16.244505Z","steps":["trace[702480459] 'agreement among raft nodes before linearized reading' (duration: 143.356891ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:21:18.138078Z","caller":"traceutil/trace.go:171","msg":"trace[1601984906] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"142.422196ms","start":"2026-04-28T22:21:17.995634Z","end":"2026-04-28T22:21:18.138056Z","steps":["trace[1601984906] 'read index received' (duration: 75.372065ms)","trace[1601984906] 'applied index is now lower than readState.Index' (duration: 67.049561ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:21:18.13812Z","caller":"traceutil/trace.go:171","msg":"trace[1845657707] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"271.91706ms","start":"2026-04-28T22:21:17.866161Z","end":"2026-04-28T22:21:18.138078Z","steps":["trace[1845657707] 'process raft request' (duration: 204.817948ms)","trace[1845657707] 'compare' (duration: 66.921849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:21:18.138644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.470873ms","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-28T22:21:18.138674Z","caller":"traceutil/trace.go:171","msg":"trace[1511093965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1193; }","duration":"129.522243ms","start":"2026-04-28T22:21:18.009142Z","end":"2026-04-28T22:21:18.138665Z","steps":["trace[1511093965] 'agreement among raft nodes before linearized reading' (duration: 129.456832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:21:18.138648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.360116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:21:18.138759Z","caller":"traceutil/trace.go:171","msg":"trace[2133880203] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1193; }","duration":"136.502758ms","start":"2026-04-28T22:21:18.002234Z","end":"2026-04-28T22:21:18.138737Z","steps":["trace[2133880203] 'agreement among raft nodes before linearized reading' (duration: 136.314006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:21:18.138287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.63712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T22:21:18.138818Z","caller":"traceutil/trace.go:171","msg":"trace[297971664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"143.25798ms","start":"2026-04-28T22:21:17.995552Z","end":"2026-04-28T22:21:18.13881Z","steps":["trace[297971664] 'agreement among raft nodes before linearized reading' (duration: 142.6062ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:21:34.544904Z","caller":"traceutil/trace.go:171","msg":"trace[118396668] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"218.4541ms","start":"2026-04-28T22:21:34.326397Z","end":"2026-04-28T22:21:34.544851Z","steps":["trace[118396668] 'process raft request' (duration: 189.081986ms)","trace[118396668] 'compare' (duration: 29.047829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:21:34.54524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.79994ms","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-28T22:21:34.544638Z","caller":"traceutil/trace.go:171","msg":"trace[2141030193] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"107.196381ms","start":"2026-04-28T22:21:34.437421Z","end":"2026-04-28T22:21:34.544617Z","steps":["trace[2141030193] 'read index received' (duration: 78.098392ms)","trace[2141030193] 'applied index is now lower than readState.Index' (duration: 29.097309ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:21:34.545311Z","caller":"traceutil/trace.go:171","msg":"trace[1002973902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"107.927442ms","start":"2026-04-28T22:21:34.437367Z","end":"2026-04-28T22:21:34.545295Z","steps":["trace[1002973902] 'agreement among raft nodes before linearized reading' (duration: 107.688678ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:21:36.428631Z","caller":"traceutil/trace.go:171","msg":"trace[1894665521] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"102.204051ms","start":"2026-04-28T22:21:36.326389Z","end":"2026-04-28T22:21:36.428593Z","steps":["trace[1894665521] 'process raft request' (duration: 97.85784ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:00.844996Z","caller":"traceutil/trace.go:171","msg":"trace[1198023264] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"106.932885ms","start":"2026-04-28T22:22:00.738035Z","end":"2026-04-28T22:22:00.844968Z","steps":["trace[1198023264] 'process raft request' (duration: 106.729312ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:01.911351Z","caller":"traceutil/trace.go:171","msg":"trace[1406949946] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"274.055519ms","start":"2026-04-28T22:22:01.637267Z","end":"2026-04-28T22:22:01.911323Z","steps":["trace[1406949946] 'read index received' (duration: 186.115444ms)","trace[1406949946] 'applied index is now lower than readState.Index' (duration: 87.938215ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:22:01.911552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.960838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:22:01.911587Z","caller":"traceutil/trace.go:171","msg":"trace[602127464] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1366; }","duration":"271.061229ms","start":"2026-04-28T22:22:01.640516Z","end":"2026-04-28T22:22:01.911577Z","steps":["trace[602127464] 'agreement among raft nodes before linearized reading' (duration: 270.967998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:01.911674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.332352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:22:01.911764Z","caller":"traceutil/trace.go:171","msg":"trace[1915989489] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1366; }","duration":"274.536455ms","start":"2026-04-28T22:22:01.637207Z","end":"2026-04-28T22:22:01.911743Z","steps":["trace[1915989489] 'agreement among raft nodes before linearized reading' (duration: 274.276292ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:02.192981Z","caller":"traceutil/trace.go:171","msg":"trace[2135169873] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"103.123199ms","start":"2026-04-28T22:22:02.089833Z","end":"2026-04-28T22:22:02.192956Z","steps":["trace[2135169873] 'process raft request' (duration: 95.328568ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:02.738726Z","caller":"traceutil/trace.go:171","msg":"trace[2142748651] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"441.461566ms","start":"2026-04-28T22:22:02.297238Z","end":"2026-04-28T22:22:02.738699Z","steps":["trace[2142748651] 'read index received' (duration: 437.409214ms)","trace[2142748651] 'applied index is now lower than readState.Index' (duration: 4.051242ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:22:02.73882Z","caller":"traceutil/trace.go:171","msg":"trace[1319936272] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"469.73906ms","start":"2026-04-28T22:22:02.269049Z","end":"2026-04-28T22:22:02.738788Z","steps":["trace[1319936272] 'process raft request' (duration: 465.678738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:02.739023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:22:02.269018Z","time spent":"469.912702ms","remote":"127.0.0.1:34064","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-28T22:22:02.73913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.839181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:22:02.739201Z","caller":"traceutil/trace.go:171","msg":"trace[408902562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1369; }","duration":"441.968002ms","start":"2026-04-28T22:22:02.29722Z","end":"2026-04-28T22:22:02.739188Z","steps":["trace[408902562] 'agreement among raft nodes before linearized reading' (duration: 441.74573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:02.739271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:22:02.297159Z","time spent":"442.100514ms","remote":"127.0.0.1:33800","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-28T22:22:02.739513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.098476ms","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-28T22:22:02.739585Z","caller":"traceutil/trace.go:171","msg":"trace[1145167879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1369; }","duration":"130.225307ms","start":"2026-04-28T22:22:02.609343Z","end":"2026-04-28T22:22:02.739568Z","steps":["trace[1145167879] 'agreement among raft nodes before linearized reading' (duration: 129.934404ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:04.21313Z","caller":"traceutil/trace.go:171","msg":"trace[1216097231] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"191.219859ms","start":"2026-04-28T22:22:04.021884Z","end":"2026-04-28T22:22:04.213104Z","steps":["trace[1216097231] 'process raft request' (duration: 191.043347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:04.986924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.30681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:22:04.987023Z","caller":"traceutil/trace.go:171","msg":"trace[1999704291] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"186.982136ms","start":"2026-04-28T22:22:04.800025Z","end":"2026-04-28T22:22:04.987007Z","steps":["trace[1999704291] 'read index received' (duration: 84.424443ms)","trace[1999704291] 'applied index is now lower than readState.Index' (duration: 102.556613ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:22:04.987176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.150158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T22:22:04.987153Z","caller":"traceutil/trace.go:171","msg":"trace[580276588] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"193.311566ms","start":"2026-04-28T22:22:04.79379Z","end":"2026-04-28T22:22:04.987102Z","steps":["trace[580276588] 'process raft request' (duration: 90.741703ms)","trace[580276588] 'compare' (duration: 102.148078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:22:04.987238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.441513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:22:04.987267Z","caller":"traceutil/trace.go:171","msg":"trace[2031935803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1377; }","duration":"187.260299ms","start":"2026-04-28T22:22:04.799976Z","end":"2026-04-28T22:22:04.987236Z","steps":["trace[2031935803] 'agreement among raft nodes before linearized reading' (duration: 187.114667ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:04.987298Z","caller":"traceutil/trace.go:171","msg":"trace[1211800784] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1377; }","duration":"170.548275ms","start":"2026-04-28T22:22:04.816735Z","end":"2026-04-28T22:22:04.987284Z","steps":["trace[1211800784] 'agreement among raft nodes before linearized reading' (duration: 170.426874ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:05.209947Z","caller":"traceutil/trace.go:171","msg":"trace[222241483] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"218.027796ms","start":"2026-04-28T22:22:04.991892Z","end":"2026-04-28T22:22:05.209919Z","steps":["trace[222241483] 'process raft request' (duration: 217.977846ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:05.210156Z","caller":"traceutil/trace.go:171","msg":"trace[663544630] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"218.963648ms","start":"2026-04-28T22:22:04.991183Z","end":"2026-04-28T22:22:05.210147Z","steps":["trace[663544630] 'process raft request' (duration: 218.503022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:06.465895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.08239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:22:06.466151Z","caller":"traceutil/trace.go:171","msg":"trace[1923462257] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"289.63347ms","start":"2026-04-28T22:22:06.176481Z","end":"2026-04-28T22:22:06.466115Z","steps":["trace[1923462257] 'process raft request' (duration: 60.217946ms)","trace[1923462257] 'compare' (duration: 228.815167ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:22:06.466201Z","caller":"traceutil/trace.go:171","msg":"trace[546978703] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1438; }","duration":"135.049051ms","start":"2026-04-28T22:22:06.331128Z","end":"2026-04-28T22:22:06.466177Z","steps":["trace[546978703] 'read index received' (duration: 131.920112ms)","trace[546978703] 'applied index is now lower than readState.Index' (duration: 3.127689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:22:06.466286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.160312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:22:06.466277Z","caller":"traceutil/trace.go:171","msg":"trace[2020515828] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"247.279991ms","start":"2026-04-28T22:22:06.218958Z","end":"2026-04-28T22:22:06.466238Z","steps":["trace[2020515828] 'process raft request' (duration: 247.047748ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:06.466329Z","caller":"traceutil/trace.go:171","msg":"trace[1792034583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1383; }","duration":"135.244274ms","start":"2026-04-28T22:22:06.331075Z","end":"2026-04-28T22:22:06.46632Z","steps":["trace[1792034583] 'agreement among raft nodes before linearized reading' (duration: 135.175883ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:07.189624Z","caller":"traceutil/trace.go:171","msg":"trace[149135126] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1441; }","duration":"155.519517ms","start":"2026-04-28T22:22:07.034077Z","end":"2026-04-28T22:22:07.189596Z","steps":["trace[149135126] 'read index received' (duration: 155.304104ms)","trace[149135126] 'applied index is now lower than readState.Index' (duration: 214.493µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:22:07.189823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.733819ms","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-28T22:22:07.189855Z","caller":"traceutil/trace.go:171","msg":"trace[284755171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1385; }","duration":"155.81933ms","start":"2026-04-28T22:22:07.034026Z","end":"2026-04-28T22:22:07.189846Z","steps":["trace[284755171] 'agreement among raft nodes before linearized reading' (duration: 155.670198ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:07.189844Z","caller":"traceutil/trace.go:171","msg":"trace[326422392] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"193.485151ms","start":"2026-04-28T22:22:06.996338Z","end":"2026-04-28T22:22:07.189823Z","steps":["trace[326422392] 'process raft request' (duration: 193.125176ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:07.439506Z","caller":"traceutil/trace.go:171","msg":"trace[318259108] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"225.101892ms","start":"2026-04-28T22:22:07.214387Z","end":"2026-04-28T22:22:07.439489Z","steps":["trace[318259108] 'read index received' (duration: 224.94434ms)","trace[318259108] 'applied index is now lower than readState.Index' (duration: 157.002µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:22:07.439589Z","caller":"traceutil/trace.go:171","msg":"trace[700373854] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"244.62736ms","start":"2026-04-28T22:22:07.194943Z","end":"2026-04-28T22:22:07.439571Z","steps":["trace[700373854] 'process raft request' (duration: 244.442728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:07.439995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.595448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T22:22:07.440029Z","caller":"traceutil/trace.go:171","msg":"trace[627386754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1386; }","duration":"225.676719ms","start":"2026-04-28T22:22:07.214337Z","end":"2026-04-28T22:22:07.440014Z","steps":["trace[627386754] 'agreement among raft nodes before linearized reading' (duration: 225.231263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:07.440119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.477744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:22:07.440324Z","caller":"traceutil/trace.go:171","msg":"trace[1170103630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1386; }","duration":"143.738346ms","start":"2026-04-28T22:22:07.296565Z","end":"2026-04-28T22:22:07.440304Z","steps":["trace[1170103630] 'agreement among raft nodes before linearized reading' (duration: 143.436263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:07.440285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.533308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T22:22:07.440431Z","caller":"traceutil/trace.go:171","msg":"trace[724246398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1386; }","duration":"225.70427ms","start":"2026-04-28T22:22:07.214704Z","end":"2026-04-28T22:22:07.440408Z","steps":["trace[724246398] 'agreement among raft nodes before linearized reading' (duration: 225.241944ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:14.671056Z","caller":"traceutil/trace.go:171","msg":"trace[657065420] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"161.225145ms","start":"2026-04-28T22:22:14.509803Z","end":"2026-04-28T22:22:14.671028Z","steps":["trace[657065420] 'process raft request' (duration: 160.543947ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:15.534439Z","caller":"traceutil/trace.go:171","msg":"trace[34151288] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"307.93598ms","start":"2026-04-28T22:22:15.226477Z","end":"2026-04-28T22:22:15.534413Z","steps":["trace[34151288] 'process raft request' (duration: 307.606416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:15.534761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:22:15.226455Z","time spent":"308.056992ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:22:15.701362Z","caller":"traceutil/trace.go:171","msg":"trace[40216558] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"116.681985ms","start":"2026-04-28T22:22:15.584651Z","end":"2026-04-28T22:22:15.701333Z","steps":["trace[40216558] 'process raft request' (duration: 94.033583ms)","trace[40216558] 'compare' (duration: 22.563391ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:22:19.854105Z","caller":"traceutil/trace.go:171","msg":"trace[315325276] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"119.173312ms","start":"2026-04-28T22:22:19.734912Z","end":"2026-04-28T22:22:19.854085Z","steps":["trace[315325276] 'process raft request' (duration: 119.04345ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:31.113585Z","caller":"traceutil/trace.go:171","msg":"trace[473907462] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"128.164868ms","start":"2026-04-28T22:22:30.985395Z","end":"2026-04-28T22:22:31.11356Z","steps":["trace[473907462] 'process raft request' (duration: 128.037537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:31.981156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.106144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/kube-root-ca.crt\" ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-04-28T22:22:31.981258Z","caller":"traceutil/trace.go:171","msg":"trace[1907519962] range","detail":"{range_begin:/registry/configmaps/default/kube-root-ca.crt; range_end:; response_count:1; response_revision:1475; }","duration":"108.242005ms","start":"2026-04-28T22:22:31.872987Z","end":"2026-04-28T22:22:31.981229Z","steps":["trace[1907519962] 'range keys from in-memory index tree' (duration: 108.008922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:31.981403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.74691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" ","response":"range_response_count:1 size:1550"} {"level":"info","ts":"2026-04-28T22:22:31.981961Z","caller":"traceutil/trace.go:171","msg":"trace[1440482379] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/envoy-gateway-config; range_end:; response_count:1; response_revision:1475; }","duration":"108.340307ms","start":"2026-04-28T22:22:31.873601Z","end":"2026-04-28T22:22:31.981941Z","steps":["trace[1440482379] 'range keys from in-memory index tree' (duration: 107.579127ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:32.697755Z","caller":"traceutil/trace.go:171","msg":"trace[1258428074] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"143.602131ms","start":"2026-04-28T22:22:32.554128Z","end":"2026-04-28T22:22:32.69773Z","steps":["trace[1258428074] 'process raft request' (duration: 143.306298ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:32.8972Z","caller":"traceutil/trace.go:171","msg":"trace[1564158296] linearizableReadLoop","detail":"{readStateIndex:1541; appliedIndex:1540; }","duration":"139.907416ms","start":"2026-04-28T22:22:32.757267Z","end":"2026-04-28T22:22:32.897174Z","steps":["trace[1564158296] 'read index received' (duration: 119.706643ms)","trace[1564158296] 'applied index is now lower than readState.Index' (duration: 20.199493ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:22:32.897217Z","caller":"traceutil/trace.go:171","msg":"trace[557795867] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"139.958547ms","start":"2026-04-28T22:22:32.757225Z","end":"2026-04-28T22:22:32.897184Z","steps":["trace[557795867] 'process raft request' (duration: 119.734344ms)","trace[557795867] 'compare' (duration: 20.020151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:22:32.897424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.134609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-04-28T22:22:32.897468Z","caller":"traceutil/trace.go:171","msg":"trace[1439408873] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1478; }","duration":"140.222699ms","start":"2026-04-28T22:22:32.757231Z","end":"2026-04-28T22:22:32.897454Z","steps":["trace[1439408873] 'agreement among raft nodes before linearized reading' (duration: 140.068798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:32.897482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.354117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/envoy-gateway-system/\" range_end:\"/registry/persistentvolumeclaims/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:22:32.897671Z","caller":"traceutil/trace.go:171","msg":"trace[669374037] range","detail":"{range_begin:/registry/persistentvolumeclaims/envoy-gateway-system/; range_end:/registry/persistentvolumeclaims/envoy-gateway-system0; response_count:0; response_revision:1478; }","duration":"114.59646ms","start":"2026-04-28T22:22:32.783047Z","end":"2026-04-28T22:22:32.897644Z","steps":["trace[669374037] 'agreement among raft nodes before linearized reading' (duration: 114.325176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:42.226873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.170991ms","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-28T22:22:42.226973Z","caller":"traceutil/trace.go:171","msg":"trace[291565894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1509; }","duration":"108.371254ms","start":"2026-04-28T22:22:42.118584Z","end":"2026-04-28T22:22:42.226955Z","steps":["trace[291565894] 'range keys from in-memory index tree' (duration: 107.920409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:42.227084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.914739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:1 size:199"} {"level":"info","ts":"2026-04-28T22:22:42.22725Z","caller":"traceutil/trace.go:171","msg":"trace[1105202794] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:1; response_revision:1509; }","duration":"141.102392ms","start":"2026-04-28T22:22:42.086127Z","end":"2026-04-28T22:22:42.22723Z","steps":["trace[1105202794] 'range keys from in-memory index tree' (duration: 140.762647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:42.227678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.720411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"info","ts":"2026-04-28T22:22:42.227916Z","caller":"traceutil/trace.go:171","msg":"trace[1635947419] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:1509; }","duration":"149.714802ms","start":"2026-04-28T22:22:42.078187Z","end":"2026-04-28T22:22:42.227902Z","steps":["trace[1635947419] 'range keys from in-memory index tree' (duration: 148.61555ms)"],"step_count":1}