{"level":"warn","ts":"2026-07-02T10:06:56.999822Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T10:06:56.999909Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.60:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.60:2380","--initial-cluster=instance=https://199.19.213.60:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.60:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.60:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-02T10:06:57.000757Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T10:06:57.000775Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.60:2380"]} {"level":"info","ts":"2026-07-02T10:06:57.000793Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T10:06:57.001603Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"]} {"level":"info","ts":"2026-07-02T10:06:57.001684Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.60:2380"],"listen-peer-urls":["https://199.19.213.60:2380"],"advertise-client-urls":["https://199.19.213.60:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.60:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T10:06:57.006089Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.199977ms"} {"level":"info","ts":"2026-07-02T10:06:57.014795Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3490cdb1ff3fc828","cluster-id":"872e6d60ada2e30c"} {"level":"info","ts":"2026-07-02T10:06:57.014874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T10:06:57.014897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became follower at term 0"} {"level":"info","ts":"2026-07-02T10:06:57.014911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3490cdb1ff3fc828 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T10:06:57.014919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became follower at term 1"} {"level":"info","ts":"2026-07-02T10:06:57.015037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=(3787753450993862696)"} {"level":"warn","ts":"2026-07-02T10:06:57.023276Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T10:06:57.025391Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T10:06:57.026908Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T10:06:57.030918Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3490cdb1ff3fc828","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T10:06:57.031097Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3490cdb1ff3fc828","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T10:06:57.031091Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T10:06:57.03406Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T10:06:57.034249Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T10:06:57.034773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=(3787753450993862696)"} {"level":"info","ts":"2026-07-02T10:06:57.035252Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"872e6d60ada2e30c","local-member-id":"3490cdb1ff3fc828","added-peer-id":"3490cdb1ff3fc828","added-peer-peer-urls":["https://199.19.213.60:2380"]} {"level":"info","ts":"2026-07-02T10:06:57.037283Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T10:06:57.037316Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.60:2380"} {"level":"info","ts":"2026-07-02T10:06:57.037366Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.60:2380"} {"level":"info","ts":"2026-07-02T10:06:57.037919Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3490cdb1ff3fc828","initial-advertise-peer-urls":["https://199.19.213.60:2380"],"listen-peer-urls":["https://199.19.213.60:2380"],"advertise-client-urls":["https://199.19.213.60:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T10:06:57.037968Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T10:06:57.81594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T10:06:57.81601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T10:06:57.816033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 received MsgPreVoteResp from 3490cdb1ff3fc828 at term 1"} {"level":"info","ts":"2026-07-02T10:06:57.816056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became candidate at term 2"} {"level":"info","ts":"2026-07-02T10:06:57.816072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 received MsgVoteResp from 3490cdb1ff3fc828 at term 2"} {"level":"info","ts":"2026-07-02T10:06:57.816119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became leader at term 2"} {"level":"info","ts":"2026-07-02T10:06:57.816143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3490cdb1ff3fc828 elected leader 3490cdb1ff3fc828 at term 2"} {"level":"info","ts":"2026-07-02T10:06:57.956033Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3490cdb1ff3fc828","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.60:2379]}","request-path":"/0/members/3490cdb1ff3fc828/attributes","cluster-id":"872e6d60ada2e30c","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T10:06:57.956089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T10:06:57.956186Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T10:06:57.956195Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T10:06:57.956396Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T10:06:57.95647Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T10:06:57.958496Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.60:2379"} {"level":"info","ts":"2026-07-02T10:06:57.959285Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T10:06:58.217954Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"872e6d60ada2e30c","local-member-id":"3490cdb1ff3fc828","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T10:06:58.21816Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T10:06:58.218246Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T10:06:58.218244Z","caller":"traceutil/trace.go:171","msg":"trace[493429013] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"210.890495ms","start":"2026-07-02T10:06:58.007332Z","end":"2026-07-02T10:06:58.218222Z","steps":["trace[493429013] 'read index received' (duration: 210.613797ms)","trace[493429013] 'applied index is now lower than readState.Index' (duration: 275.138µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:06:58.218699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.206413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-02T10:06:58.218686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.07803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-02T10:06:58.218722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.827321ms","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-07-02T10:06:58.218768Z","caller":"traceutil/trace.go:171","msg":"trace[1683560398] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"211.426499ms","start":"2026-07-02T10:06:58.007324Z","end":"2026-07-02T10:06:58.218751Z","steps":["trace[1683560398] 'agreement among raft nodes before linearized reading' (duration: 210.966127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:06:58.218793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.216823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T10:06:58.218795Z","caller":"traceutil/trace.go:171","msg":"trace[819146164] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"127.956445ms","start":"2026-07-02T10:06:58.090823Z","end":"2026-07-02T10:06:58.21878Z","steps":["trace[819146164] 'agreement among raft nodes before linearized reading' (duration: 127.809211ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:06:58.218811Z","caller":"traceutil/trace.go:171","msg":"trace[104037497] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"141.247125ms","start":"2026-07-02T10:06:58.077559Z","end":"2026-07-02T10:06:58.218806Z","steps":["trace[104037497] 'agreement among raft nodes before linearized reading' (duration: 141.214964ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:06:58.218753Z","caller":"traceutil/trace.go:171","msg":"trace[804443269] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"211.311456ms","start":"2026-07-02T10:06:58.007429Z","end":"2026-07-02T10:06:58.218741Z","steps":["trace[804443269] 'agreement among raft nodes before linearized reading' (duration: 210.909465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:06:58.218858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.328417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T10:06:58.218877Z","caller":"traceutil/trace.go:171","msg":"trace[685868456] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"141.377958ms","start":"2026-07-02T10:06:58.077495Z","end":"2026-07-02T10:06:58.218873Z","steps":["trace[685868456] 'agreement among raft nodes before linearized reading' (duration: 141.343727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:06:58.218949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.798585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T10:06:58.218969Z","caller":"traceutil/trace.go:171","msg":"trace[1067983340] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"152.827645ms","start":"2026-07-02T10:06:58.066137Z","end":"2026-07-02T10:06:58.218964Z","steps":["trace[1067983340] 'agreement among raft nodes before linearized reading' (duration: 152.798924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:06:58.219104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.97395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T10:06:58.21913Z","caller":"traceutil/trace.go:171","msg":"trace[1584754423] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"153.018881ms","start":"2026-07-02T10:06:58.066105Z","end":"2026-07-02T10:06:58.219123Z","steps":["trace[1584754423] 'agreement among raft nodes before linearized reading' (duration: 152.979439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:06:58.219243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.504774ms","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-07-02T10:06:58.219275Z","caller":"traceutil/trace.go:171","msg":"trace[1854250962] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"166.587496ms","start":"2026-07-02T10:06:58.05268Z","end":"2026-07-02T10:06:58.219267Z","steps":["trace[1854250962] 'agreement among raft nodes before linearized reading' (duration: 166.540805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:06:58.219457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.571344ms","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-07-02T10:06:58.219492Z","caller":"traceutil/trace.go:171","msg":"trace[1923549367] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"114.611725ms","start":"2026-07-02T10:06:58.104872Z","end":"2026-07-02T10:06:58.219484Z","steps":["trace[1923549367] 'agreement among raft nodes before linearized reading' (duration: 114.439501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:06:58.219735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.882663ms","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-07-02T10:06:58.219769Z","caller":"traceutil/trace.go:171","msg":"trace[1102685068] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"114.933475ms","start":"2026-07-02T10:06:58.104829Z","end":"2026-07-02T10:06:58.219762Z","steps":["trace[1102685068] 'agreement among raft nodes before linearized reading' (duration: 114.772081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:06:58.219874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.894531ms","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-07-02T10:06:58.219899Z","caller":"traceutil/trace.go:171","msg":"trace[2059754828] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"128.936122ms","start":"2026-07-02T10:06:58.090958Z","end":"2026-07-02T10:06:58.219894Z","steps":["trace[2059754828] 'agreement among raft nodes before linearized reading' (duration: 128.895761ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:04.280856Z","caller":"traceutil/trace.go:171","msg":"trace[20254639] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"101.035179ms","start":"2026-07-02T10:07:04.179767Z","end":"2026-07-02T10:07:04.280803Z","steps":["trace[20254639] 'process raft request' (duration: 57.896913ms)","trace[20254639] 'compare' (duration: 42.869219ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:07:04.519422Z","caller":"traceutil/trace.go:171","msg":"trace[604858837] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"138.426626ms","start":"2026-07-02T10:07:04.380931Z","end":"2026-07-02T10:07:04.519357Z","steps":["trace[604858837] 'process raft request' (duration: 138.269932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:04.51991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.519316ms","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-07-02T10:07:04.520017Z","caller":"traceutil/trace.go:171","msg":"trace[1473833139] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:319; }","duration":"126.66525ms","start":"2026-07-02T10:07:04.393335Z","end":"2026-07-02T10:07:04.52Z","steps":["trace[1473833139] 'agreement among raft nodes before linearized reading' (duration: 126.523086ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:04.519805Z","caller":"traceutil/trace.go:171","msg":"trace[780848888] linearizableReadLoop","detail":"{readStateIndex:332; appliedIndex:332; }","duration":"126.353352ms","start":"2026-07-02T10:07:04.393377Z","end":"2026-07-02T10:07:04.51973Z","steps":["trace[780848888] 'read index received' (duration: 126.346201ms)","trace[780848888] 'applied index is now lower than readState.Index' (duration: 5.681µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:07:04.95029Z","caller":"traceutil/trace.go:171","msg":"trace[1661975192] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"105.984987ms","start":"2026-07-02T10:07:04.844282Z","end":"2026-07-02T10:07:04.950267Z","steps":["trace[1661975192] 'process raft request' (duration: 105.764651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:43.236656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302459837,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T10:07:43.385518Z","caller":"traceutil/trace.go:171","msg":"trace[1547648176] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"976.104906ms","start":"2026-07-02T10:07:42.409385Z","end":"2026-07-02T10:07:43.38549Z","steps":["trace[1547648176] 'process raft request' (duration: 974.170605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:43.386248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:42.409364Z","time spent":"976.225168ms","remote":"127.0.0.1:58528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:07:43.392384Z","caller":"traceutil/trace.go:171","msg":"trace[431038712] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:602; }","duration":"656.468973ms","start":"2026-07-02T10:07:42.735891Z","end":"2026-07-02T10:07:43.39236Z","steps":["trace[431038712] 'read index received' (duration: 647.782726ms)","trace[431038712] 'applied index is now lower than readState.Index' (duration: 8.685017ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:07:43.392594Z","caller":"traceutil/trace.go:171","msg":"trace[807382730] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"938.683838ms","start":"2026-07-02T10:07:42.453894Z","end":"2026-07-02T10:07:43.392578Z","steps":["trace[807382730] 'process raft request' (duration: 938.179105ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:43.392642Z","caller":"traceutil/trace.go:171","msg":"trace[2147441630] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"936.023008ms","start":"2026-07-02T10:07:42.456598Z","end":"2026-07-02T10:07:43.392621Z","steps":["trace[2147441630] 'process raft request' (duration: 935.725241ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:43.392672Z","caller":"traceutil/trace.go:171","msg":"trace[890480060] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"970.365904ms","start":"2026-07-02T10:07:42.422293Z","end":"2026-07-02T10:07:43.392659Z","steps":["trace[890480060] 'process raft request' (duration: 969.539993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:43.392741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.099787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-02T10:07:43.392742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:42.456574Z","time spent":"936.109851ms","remote":"127.0.0.1:58528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T10:07:43.392787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:42.422276Z","time spent":"970.431787ms","remote":"127.0.0.1:58528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:07:43.392786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.871723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T10:07:43.39282Z","caller":"traceutil/trace.go:171","msg":"trace[1974831291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"173.192819ms","start":"2026-07-02T10:07:43.219593Z","end":"2026-07-02T10:07:43.392786Z","steps":["trace[1974831291] 'agreement among raft nodes before linearized reading' (duration: 173.064515ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:43.392853Z","caller":"traceutil/trace.go:171","msg":"trace[1904315726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"656.988486ms","start":"2026-07-02T10:07:42.735849Z","end":"2026-07-02T10:07:43.392837Z","steps":["trace[1904315726] 'agreement among raft nodes before linearized reading' (duration: 656.784391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:43.392884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:42.735829Z","time spent":"657.050177ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T10:07:43.393228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:42.453872Z","time spent":"939.319415ms","remote":"127.0.0.1:58528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:07:44.626139Z","caller":"traceutil/trace.go:171","msg":"trace[1649623686] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"173.139428ms","start":"2026-07-02T10:07:44.452975Z","end":"2026-07-02T10:07:44.626114Z","steps":["trace[1649623686] 'read index received' (duration: 172.633575ms)","trace[1649623686] 'applied index is now lower than readState.Index' (duration: 504.663µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:07:44.626462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.467797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-07-02T10:07:44.626539Z","caller":"traceutil/trace.go:171","msg":"trace[348267008] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:622; }","duration":"173.58774ms","start":"2026-07-02T10:07:44.452936Z","end":"2026-07-02T10:07:44.626523Z","steps":["trace[348267008] 'agreement among raft nodes before linearized reading' (duration: 173.287622ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:44.626819Z","caller":"traceutil/trace.go:171","msg":"trace[1196542054] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"180.373978ms","start":"2026-07-02T10:07:44.4458Z","end":"2026-07-02T10:07:44.626174Z","steps":["trace[1196542054] 'process raft request' (duration: 179.813534ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:46.855441Z","caller":"traceutil/trace.go:171","msg":"trace[105081278] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"266.23784ms","start":"2026-07-02T10:07:46.589185Z","end":"2026-07-02T10:07:46.855423Z","steps":["trace[105081278] 'read index received' (duration: 265.772627ms)","trace[105081278] 'applied index is now lower than readState.Index' (duration: 464.613µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:07:46.855517Z","caller":"traceutil/trace.go:171","msg":"trace[2090470892] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"404.818556ms","start":"2026-07-02T10:07:46.450678Z","end":"2026-07-02T10:07:46.855497Z","steps":["trace[2090470892] 'process raft request' (duration: 404.497006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:46.855633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.432374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:07:46.855664Z","caller":"traceutil/trace.go:171","msg":"trace[2122376556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"266.495416ms","start":"2026-07-02T10:07:46.589161Z","end":"2026-07-02T10:07:46.855657Z","steps":["trace[2122376556] 'agreement among raft nodes before linearized reading' (duration: 266.329181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:46.855734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:46.450656Z","time spent":"404.97366ms","remote":"127.0.0.1:58570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T10:07:47.447779Z","caller":"traceutil/trace.go:171","msg":"trace[84006850] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"121.275465ms","start":"2026-07-02T10:07:47.326477Z","end":"2026-07-02T10:07:47.447752Z","steps":["trace[84006850] 'process raft request' (duration: 117.163217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:50.060559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302459978,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T10:07:50.356118Z","caller":"traceutil/trace.go:171","msg":"trace[356275451] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"799.085744ms","start":"2026-07-02T10:07:49.557002Z","end":"2026-07-02T10:07:50.356088Z","steps":["trace[356275451] 'process raft request' (duration: 752.976072ms)","trace[356275451] 'compare' (duration: 45.883527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:07:50.356278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:49.55699Z","time spent":"799.206498ms","remote":"127.0.0.1:58740","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-07-02T10:07:50.358102Z","caller":"traceutil/trace.go:171","msg":"trace[171438450] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"798.380647ms","start":"2026-07-02T10:07:49.559694Z","end":"2026-07-02T10:07:50.358074Z","steps":["trace[171438450] 'read index received' (duration: 750.258341ms)","trace[171438450] 'applied index is now lower than readState.Index' (duration: 48.121126ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:07:50.358278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.818961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T10:07:50.358288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.584692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T10:07:50.358161Z","caller":"traceutil/trace.go:171","msg":"trace[2102670560] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"403.277265ms","start":"2026-07-02T10:07:49.954844Z","end":"2026-07-02T10:07:50.358121Z","steps":["trace[2102670560] 'process raft request' (duration: 402.456754ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:50.358341Z","caller":"traceutil/trace.go:171","msg":"trace[838861879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"798.671154ms","start":"2026-07-02T10:07:49.559655Z","end":"2026-07-02T10:07:50.358327Z","steps":["trace[838861879] 'agreement among raft nodes before linearized reading' (duration: 798.50261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:50.358376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:49.559648Z","time spent":"798.720765ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T10:07:50.358446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.850319ms","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-07-02T10:07:50.35845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:49.954827Z","time spent":"403.537442ms","remote":"127.0.0.1:58570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:07:50.358488Z","caller":"traceutil/trace.go:171","msg":"trace[1391809985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"257.935882ms","start":"2026-07-02T10:07:50.100537Z","end":"2026-07-02T10:07:50.358473Z","steps":["trace[1391809985] 'agreement among raft nodes before linearized reading' (duration: 257.813059ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:50.358324Z","caller":"traceutil/trace.go:171","msg":"trace[2128597749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"769.919534ms","start":"2026-07-02T10:07:49.588391Z","end":"2026-07-02T10:07:50.358311Z","steps":["trace[2128597749] 'agreement among raft nodes before linearized reading' (duration: 769.834161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:50.358669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.014392ms","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-07-02T10:07:50.358714Z","caller":"traceutil/trace.go:171","msg":"trace[1153578362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"274.084994ms","start":"2026-07-02T10:07:50.084617Z","end":"2026-07-02T10:07:50.358702Z","steps":["trace[1153578362] 'agreement among raft nodes before linearized reading' (duration: 274.004422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:50.358691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:49.588374Z","time spent":"770.245763ms","remote":"127.0.0.1:58480","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-07-02T10:07:51.232844Z","caller":"traceutil/trace.go:171","msg":"trace[1419425238] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"354.879982ms","start":"2026-07-02T10:07:50.877938Z","end":"2026-07-02T10:07:51.232818Z","steps":["trace[1419425238] 'process raft request' (duration: 354.711128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:51.232999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:50.877912Z","time spent":"355.010076ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:07:51.431576Z","caller":"traceutil/trace.go:171","msg":"trace[1190653269] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:678; }","duration":"523.204406ms","start":"2026-07-02T10:07:50.908334Z","end":"2026-07-02T10:07:51.431539Z","steps":["trace[1190653269] 'read index received' (duration: 324.378516ms)","trace[1190653269] 'applied index is now lower than readState.Index' (duration: 198.82441ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:07:51.431907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.343643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9kd7h\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-07-02T10:07:51.431935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.577805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.60\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T10:07:51.431982Z","caller":"traceutil/trace.go:171","msg":"trace[353675718] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9kd7h; range_end:; response_count:1; response_revision:657; }","duration":"404.465756ms","start":"2026-07-02T10:07:51.027496Z","end":"2026-07-02T10:07:51.431962Z","steps":["trace[353675718] 'agreement among raft nodes before linearized reading' (duration: 404.241521ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:51.432006Z","caller":"traceutil/trace.go:171","msg":"trace[918772118] range","detail":"{range_begin:/registry/masterleases/199.19.213.60; range_end:; response_count:1; response_revision:657; }","duration":"523.721609ms","start":"2026-07-02T10:07:50.908268Z","end":"2026-07-02T10:07:51.43199Z","steps":["trace[918772118] 'agreement among raft nodes before linearized reading' (duration: 523.447732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:51.432035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:51.02748Z","time spent":"404.541618ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-9kd7h\" "} {"level":"warn","ts":"2026-07-02T10:07:51.432106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:50.908222Z","time spent":"523.821022ms","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.60\" "} {"level":"info","ts":"2026-07-02T10:07:51.432391Z","caller":"traceutil/trace.go:171","msg":"trace[1280989241] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"528.804062ms","start":"2026-07-02T10:07:50.902861Z","end":"2026-07-02T10:07:51.431665Z","steps":["trace[1280989241] 'process raft request' (duration: 508.375049ms)","trace[1280989241] 'compare' (duration: 19.797217ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:07:51.432518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:50.902838Z","time spent":"529.613132ms","remote":"127.0.0.1:58570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:07:52.010227Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:07:52.510772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:07:53.011775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:07:53.512328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:07:53.58872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000172272s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-02T10:07:53.588842Z","caller":"traceutil/trace.go:171","msg":"trace[1697752590] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000338756s","start":"2026-07-02T10:07:51.588477Z","end":"2026-07-02T10:07:53.588816Z","steps":["trace[1697752590] 'agreement among raft nodes before linearized reading' (duration: 2.000209163s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:53.588938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:51.588455Z","time spent":"2.000467089s","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T10:07:54.01345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:07:54.513697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:07:54.600076Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.091545735s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T10:07:54.600508Z","caller":"traceutil/trace.go:171","msg":"trace[837906308] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"3.107581583s","start":"2026-07-02T10:07:51.492898Z","end":"2026-07-02T10:07:54.600479Z","steps":["trace[837906308] 'process raft request' (duration: 3.107370078s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.600626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:51.49288Z","time spent":"3.107684415s","remote":"127.0.0.1:58714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T10:07:54.622301Z","caller":"traceutil/trace.go:171","msg":"trace[1125875207] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:683; }","duration":"3.112914032s","start":"2026-07-02T10:07:51.509362Z","end":"2026-07-02T10:07:54.622276Z","steps":["trace[1125875207] 'read index received' (duration: 3.09099368s)","trace[1125875207] 'applied index is now lower than readState.Index' (duration: 21.919792ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:07:54.622385Z","caller":"traceutil/trace.go:171","msg":"trace[375427788] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"407.34951ms","start":"2026-07-02T10:07:54.21503Z","end":"2026-07-02T10:07:54.62238Z","steps":["trace[375427788] 'process raft request' (duration: 407.200737ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:54.622427Z","caller":"traceutil/trace.go:171","msg":"trace[886281936] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.029553539s","start":"2026-07-02T10:07:53.592846Z","end":"2026-07-02T10:07:54.622399Z","steps":["trace[886281936] 'process raft request' (duration: 1.029302473s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.622498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027492256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:07:54.622517Z","caller":"traceutil/trace.go:171","msg":"trace[409536543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"1.027540038s","start":"2026-07-02T10:07:53.594971Z","end":"2026-07-02T10:07:54.622511Z","steps":["trace[409536543] 'agreement among raft nodes before linearized reading' (duration: 1.027511787s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.622534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:53.594957Z","time spent":"1.027572988s","remote":"127.0.0.1:58480","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-07-02T10:07:54.622559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:53.592823Z","time spent":"1.029669732s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T10:07:54.622463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:54.215008Z","time spent":"407.391872ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:07:54.622714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.233797998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T10:07:54.622756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.113391624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T10:07:54.622776Z","caller":"traceutil/trace.go:171","msg":"trace[975523572] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:662; }","duration":"3.113411965s","start":"2026-07-02T10:07:51.509359Z","end":"2026-07-02T10:07:54.622771Z","steps":["trace[975523572] 'agreement among raft nodes before linearized reading' (duration: 3.113358423s)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:54.622766Z","caller":"traceutil/trace.go:171","msg":"trace[163449187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"2.23388087s","start":"2026-07-02T10:07:52.388867Z","end":"2026-07-02T10:07:54.622748Z","steps":["trace[163449187] 'agreement among raft nodes before linearized reading' (duration: 2.233766657s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.622789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:51.509316Z","time spent":"3.113470106s","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-07-02T10:07:54.622805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:52.388849Z","time spent":"2.233947042s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T10:07:54.622595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.261521972s","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-07-02T10:07:54.622857Z","caller":"traceutil/trace.go:171","msg":"trace[1695079817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"2.26183361s","start":"2026-07-02T10:07:52.361019Z","end":"2026-07-02T10:07:54.622852Z","steps":["trace[1695079817] 'agreement among raft nodes before linearized reading' (duration: 2.261426369s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.62287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:52.361Z","time spent":"2.261866281s","remote":"127.0.0.1:58740","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-07-02T10:07:54.623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.2472894s","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-07-02T10:07:54.623016Z","caller":"traceutil/trace.go:171","msg":"trace[1477063404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"2.247325792s","start":"2026-07-02T10:07:52.375685Z","end":"2026-07-02T10:07:54.623011Z","steps":["trace[1477063404] 'agreement among raft nodes before linearized reading' (duration: 2.24727157s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.623028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:52.375676Z","time spent":"2.247349142s","remote":"127.0.0.1:58740","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-07-02T10:07:54.623092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.242484995s","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-07-02T10:07:54.623151Z","caller":"traceutil/trace.go:171","msg":"trace[1960537053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"2.242572377s","start":"2026-07-02T10:07:52.380562Z","end":"2026-07-02T10:07:54.623134Z","steps":["trace[1960537053] 'agreement among raft nodes before linearized reading' (duration: 2.242410393s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.62319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:52.380551Z","time spent":"2.242631218s","remote":"127.0.0.1:58740","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-07-02T10:07:54.93348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.734245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:07:54.933712Z","caller":"traceutil/trace.go:171","msg":"trace[455264781] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:686; }","duration":"302.347691ms","start":"2026-07-02T10:07:54.631348Z","end":"2026-07-02T10:07:54.933696Z","steps":["trace[455264781] 'read index received' (duration: 77.162385ms)","trace[455264781] 'applied index is now lower than readState.Index' (duration: 225.184436ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:07:54.933809Z","caller":"traceutil/trace.go:171","msg":"trace[567401701] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"305.939225ms","start":"2026-07-02T10:07:54.627856Z","end":"2026-07-02T10:07:54.933795Z","steps":["trace[567401701] 'process raft request' (duration: 305.774511ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:54.933851Z","caller":"traceutil/trace.go:171","msg":"trace[620916722] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"305.060152ms","start":"2026-07-02T10:07:54.62877Z","end":"2026-07-02T10:07:54.93383Z","steps":["trace[620916722] 'process raft request' (duration: 304.891558ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:54.93385Z","caller":"traceutil/trace.go:171","msg":"trace[626570426] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"306.722784ms","start":"2026-07-02T10:07:54.627085Z","end":"2026-07-02T10:07:54.933808Z","steps":["trace[626570426] 'process raft request' (duration: 81.411724ms)","trace[626570426] 'compare' (duration: 224.593882ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:07:54.93389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:54.627837Z","time spent":"306.009776ms","remote":"127.0.0.1:58740","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-07-02T10:07:54.93385Z","caller":"traceutil/trace.go:171","msg":"trace[227044164] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"306.184971ms","start":"2026-07-02T10:07:54.627636Z","end":"2026-07-02T10:07:54.933821Z","steps":["trace[227044164] 'process raft request' (duration: 305.941884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.933953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.595648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T10:07:54.933968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:54.628761Z","time spent":"305.135644ms","remote":"127.0.0.1:58740","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-07-02T10:07:54.934011Z","caller":"traceutil/trace.go:171","msg":"trace[1589830102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"302.677329ms","start":"2026-07-02T10:07:54.631319Z","end":"2026-07-02T10:07:54.933996Z","steps":["trace[1589830102] 'agreement among raft nodes before linearized reading' (duration: 302.508255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.934029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:54.627067Z","time spent":"306.861668ms","remote":"127.0.0.1:58740","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-07-02T10:07:54.934047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:54.627618Z","time spent":"306.376116ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:07:54.934084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.24161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:07:54.93417Z","caller":"traceutil/trace.go:171","msg":"trace[104803046] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:666; }","duration":"299.373503ms","start":"2026-07-02T10:07:54.634778Z","end":"2026-07-02T10:07:54.934152Z","steps":["trace[104803046] 'agreement among raft nodes before linearized reading' (duration: 299.222459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:07:54.93405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:07:54.631311Z","time spent":"302.729991ms","remote":"127.0.0.1:58480","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-07-02T10:07:55.121217Z","caller":"traceutil/trace.go:171","msg":"trace[374506060] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"121.696156ms","start":"2026-07-02T10:07:54.999487Z","end":"2026-07-02T10:07:55.121183Z","steps":["trace[374506060] 'process raft request' (duration: 118.836071ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:55.121268Z","caller":"traceutil/trace.go:171","msg":"trace[157261526] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"119.416257ms","start":"2026-07-02T10:07:55.001825Z","end":"2026-07-02T10:07:55.121241Z","steps":["trace[157261526] 'process raft request' (duration: 119.285564ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:55.121443Z","caller":"traceutil/trace.go:171","msg":"trace[840755464] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"119.521199ms","start":"2026-07-02T10:07:55.001899Z","end":"2026-07-02T10:07:55.12142Z","steps":["trace[840755464] 'process raft request' (duration: 119.273072ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:55.179204Z","caller":"traceutil/trace.go:171","msg":"trace[743325213] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"135.033444ms","start":"2026-07-02T10:07:55.044148Z","end":"2026-07-02T10:07:55.179181Z","steps":["trace[743325213] 'process raft request' (duration: 134.926852ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:55.33312Z","caller":"traceutil/trace.go:171","msg":"trace[664701693] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"197.067084ms","start":"2026-07-02T10:07:55.135845Z","end":"2026-07-02T10:07:55.332912Z","steps":["trace[664701693] 'process raft request' (duration: 193.345826ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:56.040032Z","caller":"traceutil/trace.go:171","msg":"trace[373374556] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"196.923069ms","start":"2026-07-02T10:07:55.843082Z","end":"2026-07-02T10:07:56.040006Z","steps":["trace[373374556] 'process raft request' (duration: 196.789825ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:56.226792Z","caller":"traceutil/trace.go:171","msg":"trace[690872278] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"133.71111ms","start":"2026-07-02T10:07:56.093063Z","end":"2026-07-02T10:07:56.226774Z","steps":["trace[690872278] 'process raft request' (duration: 133.586937ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:07:58.579913Z","caller":"traceutil/trace.go:171","msg":"trace[2040985240] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"254.247515ms","start":"2026-07-02T10:07:58.325638Z","end":"2026-07-02T10:07:58.579886Z","steps":["trace[2040985240] 'process raft request' (duration: 253.894806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:10.321059Z","caller":"traceutil/trace.go:171","msg":"trace[2028068913] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"277.610516ms","start":"2026-07-02T10:08:10.04342Z","end":"2026-07-02T10:08:10.32103Z","steps":["trace[2028068913] 'read index received' (duration: 277.415111ms)","trace[2028068913] 'applied index is now lower than readState.Index' (duration: 194.265µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:08:10.321176Z","caller":"traceutil/trace.go:171","msg":"trace[2022409902] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"322.753583ms","start":"2026-07-02T10:08:09.998374Z","end":"2026-07-02T10:08:10.321128Z","steps":["trace[2022409902] 'process raft request' (duration: 322.491336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:10.321229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.79459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:08:10.321277Z","caller":"traceutil/trace.go:171","msg":"trace[1270668762] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:822; }","duration":"277.862652ms","start":"2026-07-02T10:08:10.043404Z","end":"2026-07-02T10:08:10.321267Z","steps":["trace[1270668762] 'agreement among raft nodes before linearized reading' (duration: 277.770149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:10.321435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:09.998355Z","time spent":"322.921118ms","remote":"127.0.0.1:58740","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-07-02T10:08:10.32146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.603763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hzx74\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-02T10:08:10.321555Z","caller":"traceutil/trace.go:171","msg":"trace[948428480] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hzx74; range_end:; response_count:1; response_revision:822; }","duration":"139.760548ms","start":"2026-07-02T10:08:10.181773Z","end":"2026-07-02T10:08:10.321534Z","steps":["trace[948428480] 'agreement among raft nodes before linearized reading' (duration: 139.47621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:10.955219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460395,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:08:11.371978Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.040906986s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T10:08:11.372363Z","caller":"traceutil/trace.go:171","msg":"trace[180318952] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:851; }","duration":"917.778942ms","start":"2026-07-02T10:08:10.454562Z","end":"2026-07-02T10:08:11.372341Z","steps":["trace[180318952] 'read index received' (duration: 917.530186ms)","trace[180318952] 'applied index is now lower than readState.Index' (duration: 247.736µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:08:11.372448Z","caller":"traceutil/trace.go:171","msg":"trace[2032127302] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"1.041540693s","start":"2026-07-02T10:08:10.330877Z","end":"2026-07-02T10:08:11.372418Z","steps":["trace[2032127302] 'process raft request' (duration: 1.041292377s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:11.372504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.929516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:08:11.372544Z","caller":"traceutil/trace.go:171","msg":"trace[539942915] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:823; }","duration":"918.030879ms","start":"2026-07-02T10:08:10.454503Z","end":"2026-07-02T10:08:11.372534Z","steps":["trace[539942915] 'agreement among raft nodes before linearized reading' (duration: 917.949877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:11.372576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:10.45448Z","time spent":"918.08458ms","remote":"127.0.0.1:58832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:08:11.372568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:10.330854Z","time spent":"1.041635275s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:08:11.372766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:08:11.372828Z","caller":"traceutil/trace.go:171","msg":"trace[200086718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"784.070282ms","start":"2026-07-02T10:08:10.588738Z","end":"2026-07-02T10:08:11.372808Z","steps":["trace[200086718] 'agreement among raft nodes before linearized reading' (duration: 783.924048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:11.372833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.463054ms","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-07-02T10:08:11.372856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:10.58866Z","time spent":"784.191136ms","remote":"127.0.0.1:58480","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-07-02T10:08:11.372871Z","caller":"traceutil/trace.go:171","msg":"trace[106703071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:823; }","duration":"335.500236ms","start":"2026-07-02T10:08:11.037354Z","end":"2026-07-02T10:08:11.372855Z","steps":["trace[106703071] 'agreement among raft nodes before linearized reading' (duration: 335.367233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:11.372904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:11.037351Z","time spent":"335.547157ms","remote":"127.0.0.1:58740","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-07-02T10:08:11.373011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.854832ms","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-07-02T10:08:11.373013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.465675ms","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-07-02T10:08:11.373039Z","caller":"traceutil/trace.go:171","msg":"trace[1184976005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:823; }","duration":"341.910334ms","start":"2026-07-02T10:08:11.03112Z","end":"2026-07-02T10:08:11.373031Z","steps":["trace[1184976005] 'agreement among raft nodes before linearized reading' (duration: 341.821101ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:11.373046Z","caller":"traceutil/trace.go:171","msg":"trace[1606967472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:823; }","duration":"335.506116ms","start":"2026-07-02T10:08:11.03753Z","end":"2026-07-02T10:08:11.373036Z","steps":["trace[1606967472] 'agreement among raft nodes before linearized reading' (duration: 335.410393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:11.373055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:11.031105Z","time spent":"341.946114ms","remote":"127.0.0.1:58740","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-07-02T10:08:11.373071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:11.037527Z","time spent":"335.538477ms","remote":"127.0.0.1:58740","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-07-02T10:08:11.373127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.186055ms","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-07-02T10:08:11.37314Z","caller":"traceutil/trace.go:171","msg":"trace[1908698936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:823; }","duration":"336.199075ms","start":"2026-07-02T10:08:11.036937Z","end":"2026-07-02T10:08:11.373136Z","steps":["trace[1908698936] 'agreement among raft nodes before linearized reading' (duration: 336.154883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:11.373154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:11.036905Z","time spent":"336.246207ms","remote":"127.0.0.1:58740","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-07-02T10:08:11.373151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.319073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.60\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T10:08:11.373225Z","caller":"traceutil/trace.go:171","msg":"trace[1697483994] range","detail":"{range_begin:/registry/masterleases/199.19.213.60; range_end:; response_count:1; response_revision:823; }","duration":"463.440096ms","start":"2026-07-02T10:08:10.909767Z","end":"2026-07-02T10:08:11.373207Z","steps":["trace[1697483994] 'agreement among raft nodes before linearized reading' (duration: 463.22318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:11.373291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:10.909748Z","time spent":"463.533848ms","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.60\" "} {"level":"info","ts":"2026-07-02T10:08:13.517756Z","caller":"traceutil/trace.go:171","msg":"trace[918372708] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"126.970084ms","start":"2026-07-02T10:08:13.390763Z","end":"2026-07-02T10:08:13.517733Z","steps":["trace[918372708] 'process raft request' (duration: 126.940833ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:13.517771Z","caller":"traceutil/trace.go:171","msg":"trace[791603187] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"127.094596ms","start":"2026-07-02T10:08:13.390651Z","end":"2026-07-02T10:08:13.517745Z","steps":["trace[791603187] 'process raft request' (duration: 126.892941ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:13.517919Z","caller":"traceutil/trace.go:171","msg":"trace[1743096800] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"127.238231ms","start":"2026-07-02T10:08:13.390672Z","end":"2026-07-02T10:08:13.51791Z","steps":["trace[1743096800] 'process raft request' (duration: 126.998654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:13.739485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.819579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:08:13.739566Z","caller":"traceutil/trace.go:171","msg":"trace[1207476690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"149.957103ms","start":"2026-07-02T10:08:13.589589Z","end":"2026-07-02T10:08:13.739546Z","steps":["trace[1207476690] 'range keys from in-memory index tree' (duration: 149.735148ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:20.406982Z","caller":"traceutil/trace.go:171","msg":"trace[827368333] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"150.820926ms","start":"2026-07-02T10:08:20.25614Z","end":"2026-07-02T10:08:20.406961Z","steps":["trace[827368333] 'process raft request' (duration: 150.56754ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:20.407478Z","caller":"traceutil/trace.go:171","msg":"trace[296756536] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"150.114617ms","start":"2026-07-02T10:08:20.257352Z","end":"2026-07-02T10:08:20.407466Z","steps":["trace[296756536] 'process raft request' (duration: 149.556463ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:20.40858Z","caller":"traceutil/trace.go:171","msg":"trace[2004160766] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"150.844217ms","start":"2026-07-02T10:08:20.257704Z","end":"2026-07-02T10:08:20.408548Z","steps":["trace[2004160766] 'process raft request' (duration: 149.477461ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:20.40947Z","caller":"traceutil/trace.go:171","msg":"trace[343842249] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"146.251237ms","start":"2026-07-02T10:08:20.26319Z","end":"2026-07-02T10:08:20.409442Z","steps":["trace[343842249] 'process raft request' (duration: 144.374098ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:21.662648Z","caller":"traceutil/trace.go:171","msg":"trace[1237653995] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"101.60816ms","start":"2026-07-02T10:08:21.561016Z","end":"2026-07-02T10:08:21.662624Z","steps":["trace[1237653995] 'read index received' (duration: 101.445845ms)","trace[1237653995] 'applied index is now lower than readState.Index' (duration: 161.305µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:08:21.662737Z","caller":"traceutil/trace.go:171","msg":"trace[89201802] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"164.753967ms","start":"2026-07-02T10:08:21.497952Z","end":"2026-07-02T10:08:21.662706Z","steps":["trace[89201802] 'process raft request' (duration: 164.52446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:21.662885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.852426ms","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-07-02T10:08:21.662925Z","caller":"traceutil/trace.go:171","msg":"trace[323679479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"101.936168ms","start":"2026-07-02T10:08:21.560975Z","end":"2026-07-02T10:08:21.662911Z","steps":["trace[323679479] 'agreement among raft nodes before linearized reading' (duration: 101.775134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:21.663006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.792444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T10:08:21.66304Z","caller":"traceutil/trace.go:171","msg":"trace[1809434759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"101.881966ms","start":"2026-07-02T10:08:21.561148Z","end":"2026-07-02T10:08:21.66303Z","steps":["trace[1809434759] 'agreement among raft nodes before linearized reading' (duration: 101.738283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:21.662988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.883467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T10:08:21.663158Z","caller":"traceutil/trace.go:171","msg":"trace[2108059452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"102.050211ms","start":"2026-07-02T10:08:21.561055Z","end":"2026-07-02T10:08:21.663106Z","steps":["trace[2108059452] 'agreement among raft nodes before linearized reading' (duration: 101.736993ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:23.788824Z","caller":"traceutil/trace.go:171","msg":"trace[59456804] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"111.142464ms","start":"2026-07-02T10:08:23.677662Z","end":"2026-07-02T10:08:23.788805Z","steps":["trace[59456804] 'read index received' (duration: 110.951839ms)","trace[59456804] 'applied index is now lower than readState.Index' (duration: 189.955µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:08:23.788917Z","caller":"traceutil/trace.go:171","msg":"trace[287616637] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"117.940321ms","start":"2026-07-02T10:08:23.670937Z","end":"2026-07-02T10:08:23.788877Z","steps":["trace[287616637] 'process raft request' (duration: 117.760076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:23.78902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.482968ms","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-07-02T10:08:23.789059Z","caller":"traceutil/trace.go:171","msg":"trace[270144181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"110.553791ms","start":"2026-07-02T10:08:23.678494Z","end":"2026-07-02T10:08:23.789048Z","steps":["trace[270144181] 'agreement among raft nodes before linearized reading' (duration: 110.423067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:23.789108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.418752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T10:08:23.789131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.190461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T10:08:23.789189Z","caller":"traceutil/trace.go:171","msg":"trace[784233261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"111.511425ms","start":"2026-07-02T10:08:23.677658Z","end":"2026-07-02T10:08:23.789169Z","steps":["trace[784233261] 'agreement among raft nodes before linearized reading' (duration: 111.236148ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:23.789204Z","caller":"traceutil/trace.go:171","msg":"trace[1745259151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"110.308984ms","start":"2026-07-02T10:08:23.678882Z","end":"2026-07-02T10:08:23.789191Z","steps":["trace[1745259151] 'agreement among raft nodes before linearized reading' (duration: 110.124459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:25.744235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.449321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:08:25.74434Z","caller":"traceutil/trace.go:171","msg":"trace[1340558301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"154.609357ms","start":"2026-07-02T10:08:25.589709Z","end":"2026-07-02T10:08:25.744319Z","steps":["trace[1340558301] 'range keys from in-memory index tree' (duration: 154.3772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:25.744333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.284667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-k5dhv\" ","response":"range_response_count:1 size:5676"} {"level":"info","ts":"2026-07-02T10:08:25.744411Z","caller":"traceutil/trace.go:171","msg":"trace[1015129301] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-k5dhv; range_end:; response_count:1; response_revision:954; }","duration":"121.405791ms","start":"2026-07-02T10:08:25.622984Z","end":"2026-07-02T10:08:25.74439Z","steps":["trace[1015129301] 'range keys from in-memory index tree' (duration: 121.113152ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:29.975532Z","caller":"traceutil/trace.go:171","msg":"trace[1640374451] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"131.075969ms","start":"2026-07-02T10:08:29.844437Z","end":"2026-07-02T10:08:29.975513Z","steps":["trace[1640374451] 'read index received' (duration: 130.791822ms)","trace[1640374451] 'applied index is now lower than readState.Index' (duration: 283.167µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:08:29.975615Z","caller":"traceutil/trace.go:171","msg":"trace[1963731053] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"135.921674ms","start":"2026-07-02T10:08:29.83966Z","end":"2026-07-02T10:08:29.975581Z","steps":["trace[1963731053] 'process raft request' (duration: 135.662087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:29.976277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.809367ms","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-07-02T10:08:29.976329Z","caller":"traceutil/trace.go:171","msg":"trace[87735051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:977; }","duration":"131.930101ms","start":"2026-07-02T10:08:29.844387Z","end":"2026-07-02T10:08:29.976317Z","steps":["trace[87735051] 'agreement among raft nodes before linearized reading' (duration: 131.232623ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:40.6756Z","caller":"traceutil/trace.go:171","msg":"trace[863941373] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"141.766525ms","start":"2026-07-02T10:08:40.533802Z","end":"2026-07-02T10:08:40.675569Z","steps":["trace[863941373] 'process raft request' (duration: 141.377354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:41.519966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.597547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.60\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T10:08:41.520056Z","caller":"traceutil/trace.go:171","msg":"trace[1556208671] range","detail":"{range_begin:/registry/masterleases/199.19.213.60; range_end:; response_count:1; response_revision:1016; }","duration":"608.740301ms","start":"2026-07-02T10:08:40.911295Z","end":"2026-07-02T10:08:41.520035Z","steps":["trace[1556208671] 'range keys from in-memory index tree' (duration: 608.328911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:41.5201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:40.911275Z","time spent":"608.815753ms","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.60\" "} {"level":"info","ts":"2026-07-02T10:08:46.950173Z","caller":"traceutil/trace.go:171","msg":"trace[590638917] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"212.566389ms","start":"2026-07-02T10:08:46.737579Z","end":"2026-07-02T10:08:46.950146Z","steps":["trace[590638917] 'read index received' (duration: 212.416595ms)","trace[590638917] 'applied index is now lower than readState.Index' (duration: 148.804µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:08:46.950223Z","caller":"traceutil/trace.go:171","msg":"trace[2145118689] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"259.393935ms","start":"2026-07-02T10:08:46.690804Z","end":"2026-07-02T10:08:46.950198Z","steps":["trace[2145118689] 'process raft request' (duration: 259.126169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:46.950345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.746944ms","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-07-02T10:08:46.950376Z","caller":"traceutil/trace.go:171","msg":"trace[139131501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1040; }","duration":"212.826966ms","start":"2026-07-02T10:08:46.73754Z","end":"2026-07-02T10:08:46.950367Z","steps":["trace[139131501] 'agreement among raft nodes before linearized reading' (duration: 212.699533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:47.702443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.202568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:08:47.702512Z","caller":"traceutil/trace.go:171","msg":"trace[777743262] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1041; }","duration":"132.341871ms","start":"2026-07-02T10:08:47.570158Z","end":"2026-07-02T10:08:47.7025Z","steps":["trace[777743262] 'count revisions from in-memory index tree' (duration: 132.125365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:48.642254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:08:49.142959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:08:49.64448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302460856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:08:50.059484Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.971049067s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T10:08:50.059824Z","caller":"traceutil/trace.go:171","msg":"trace[1198640962] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"1.971461777s","start":"2026-07-02T10:08:48.088332Z","end":"2026-07-02T10:08:50.059793Z","steps":["trace[1198640962] 'process raft request' (duration: 1.971315493s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:50.059998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:48.088316Z","time spent":"1.97157351s","remote":"127.0.0.1:58740","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-07-02T10:08:50.067554Z","caller":"traceutil/trace.go:171","msg":"trace[8486384] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"1.925567624s","start":"2026-07-02T10:08:48.141956Z","end":"2026-07-02T10:08:50.067523Z","steps":["trace[8486384] 'read index received' (duration: 1.917884436s)","trace[8486384] 'applied index is now lower than readState.Index' (duration: 7.681948ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:08:50.067719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925756239s","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-07-02T10:08:50.067761Z","caller":"traceutil/trace.go:171","msg":"trace[922230790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"1.925827731s","start":"2026-07-02T10:08:48.141923Z","end":"2026-07-02T10:08:50.067751Z","steps":["trace[922230790] 'agreement among raft nodes before linearized reading' (duration: 1.925697208s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:50.067793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:48.141907Z","time spent":"1.925878392s","remote":"127.0.0.1:58740","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-07-02T10:08:50.067798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.923767419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-02T10:08:50.067805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480489773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:08:50.067834Z","caller":"traceutil/trace.go:171","msg":"trace[1383650858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"1.923849361s","start":"2026-07-02T10:08:48.143975Z","end":"2026-07-02T10:08:50.067824Z","steps":["trace[1383650858] 'agreement among raft nodes before linearized reading' (duration: 1.923691587s)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:08:50.06787Z","caller":"traceutil/trace.go:171","msg":"trace[1581187662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"1.480599595s","start":"2026-07-02T10:08:48.587254Z","end":"2026-07-02T10:08:50.067854Z","steps":["trace[1581187662] 'agreement among raft nodes before linearized reading' (duration: 1.480441791s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:50.067874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:48.143932Z","time spent":"1.923933753s","remote":"127.0.0.1:58740","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-07-02T10:08:50.067903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:48.587235Z","time spent":"1.480662677s","remote":"127.0.0.1:58480","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-07-02T10:08:50.067982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.92386393s","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-07-02T10:08:50.068024Z","caller":"traceutil/trace.go:171","msg":"trace[2136273675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"1.923906271s","start":"2026-07-02T10:08:48.144105Z","end":"2026-07-02T10:08:50.068011Z","steps":["trace[2136273675] 'agreement among raft nodes before linearized reading' (duration: 1.923799428s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:50.068038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047919862s","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-07-02T10:08:50.068049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:48.14404Z","time spent":"1.924002675s","remote":"127.0.0.1:58740","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-07-02T10:08:50.068067Z","caller":"traceutil/trace.go:171","msg":"trace[1950571173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"1.048001975s","start":"2026-07-02T10:08:49.020057Z","end":"2026-07-02T10:08:50.068059Z","steps":["trace[1950571173] 'agreement among raft nodes before linearized reading' (duration: 1.047920233s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:50.068094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:49.020028Z","time spent":"1.048059606s","remote":"127.0.0.1:58740","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-07-02T10:08:50.067598Z","caller":"traceutil/trace.go:171","msg":"trace[1344504575] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"1.102048308s","start":"2026-07-02T10:08:48.965533Z","end":"2026-07-02T10:08:50.067582Z","steps":["trace[1344504575] 'process raft request' (duration: 1.101819692s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:08:50.068182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:08:48.96551Z","time spent":"1.102622072s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:08:56.865699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.840821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-02T10:08:56.865665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.736187ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:08:56.865777Z","caller":"traceutil/trace.go:171","msg":"trace[1280661338] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1068; }","duration":"100.86052ms","start":"2026-07-02T10:08:56.764899Z","end":"2026-07-02T10:08:56.86576Z","steps":["trace[1280661338] 'range keys from in-memory index tree' (duration: 100.718787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:12.527188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.163703587s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:09:12.527317Z","caller":"traceutil/trace.go:171","msg":"trace[2100126730] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"2.364453634s","start":"2026-07-02T10:09:10.162842Z","end":"2026-07-02T10:09:12.527296Z","steps":["trace[2100126730] 'read index received' (duration: 200.565711ms)","trace[2100126730] 'applied index is now lower than readState.Index' (duration: 2.163886543s)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:12.527623Z","caller":"traceutil/trace.go:171","msg":"trace[521284591] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"2.365410038s","start":"2026-07-02T10:09:10.162192Z","end":"2026-07-02T10:09:12.527602Z","steps":["trace[521284591] 'process raft request' (duration: 201.204277ms)","trace[521284591] 'compare' (duration: 2.163601504s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:09:12.52823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.365372897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-02T10:09:12.528221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:10.162173Z","time spent":"2.36550422s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:09:12.528305Z","caller":"traceutil/trace.go:171","msg":"trace[1659788827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"2.36548968s","start":"2026-07-02T10:09:10.162797Z","end":"2026-07-02T10:09:12.528287Z","steps":["trace[1659788827] 'agreement among raft nodes before linearized reading' (duration: 2.365272424s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:12.528353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:10.16278Z","time spent":"2.365560061s","remote":"127.0.0.1:58740","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-07-02T10:09:12.590226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000533161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-02T10:09:12.590323Z","caller":"traceutil/trace.go:171","msg":"trace[650179819] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000690844s","start":"2026-07-02T10:09:10.589611Z","end":"2026-07-02T10:09:12.590302Z","steps":["trace[650179819] 'agreement among raft nodes before linearized reading' (duration: 2.000576502s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:12.590409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:10.58959Z","time spent":"2.000800038s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/02 10:09:12 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-02T10:09:13.028134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302461027,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T10:09:13.282281Z","caller":"traceutil/trace.go:171","msg":"trace[1922318953] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"1.133156018s","start":"2026-07-02T10:09:12.149089Z","end":"2026-07-02T10:09:13.282245Z","steps":["trace[1922318953] 'process raft request' (duration: 1.082478802s)","trace[1922318953] 'compare' (duration: 50.278506ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:09:13.282443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:12.149069Z","time spent":"1.133286872s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:09:13.310199Z","caller":"traceutil/trace.go:171","msg":"trace[1163637608] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1151; }","duration":"782.818208ms","start":"2026-07-02T10:09:12.527351Z","end":"2026-07-02T10:09:13.310169Z","steps":["trace[1163637608] 'read index received' (duration: 704.079429ms)","trace[1163637608] 'applied index is now lower than readState.Index' (duration: 78.737469ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:13.310277Z","caller":"traceutil/trace.go:171","msg":"trace[1344624769] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"777.935712ms","start":"2026-07-02T10:09:12.53231Z","end":"2026-07-02T10:09:13.310246Z","steps":["trace[1344624769] 'process raft request' (duration: 777.707226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:13.31045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.145625168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-02T10:09:13.310462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:12.532289Z","time spent":"778.094517ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:09:13.310457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146207835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:13.310542Z","caller":"traceutil/trace.go:171","msg":"trace[1755167835] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1111; }","duration":"1.146334558s","start":"2026-07-02T10:09:12.164197Z","end":"2026-07-02T10:09:13.310532Z","steps":["trace[1755167835] 'agreement among raft nodes before linearized reading' (duration: 1.146168454s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:13.310595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:12.164176Z","time spent":"1.14641131s","remote":"127.0.0.1:58786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:09:13.310536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.213135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T10:09:13.310641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.537566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:13.310721Z","caller":"traceutil/trace.go:171","msg":"trace[2113689697] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1111; }","duration":"435.67955ms","start":"2026-07-02T10:09:12.875026Z","end":"2026-07-02T10:09:13.310705Z","steps":["trace[2113689697] 'agreement among raft nodes before linearized reading' (duration: 435.533916ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:13.310699Z","caller":"traceutil/trace.go:171","msg":"trace[2128234041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"720.41666ms","start":"2026-07-02T10:09:12.590263Z","end":"2026-07-02T10:09:13.31068Z","steps":["trace[2128234041] 'agreement among raft nodes before linearized reading' (duration: 720.150553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:13.3106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.145827944s","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-07-02T10:09:13.31078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:12.590249Z","time spent":"720.518392ms","remote":"127.0.0.1:58476","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-07-02T10:09:13.310786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:12.875003Z","time spent":"435.750631ms","remote":"127.0.0.1:58714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-07-02T10:09:13.310802Z","caller":"traceutil/trace.go:171","msg":"trace[662749543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"3.146027369s","start":"2026-07-02T10:09:10.164761Z","end":"2026-07-02T10:09:13.310789Z","steps":["trace[662749543] 'agreement among raft nodes before linearized reading' (duration: 3.145776232s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:13.3109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:10.16471Z","time spent":"3.146179052s","remote":"127.0.0.1:58740","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-07-02T10:09:13.310754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.234772848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:13.3105Z","caller":"traceutil/trace.go:171","msg":"trace[1069012830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"3.14570706s","start":"2026-07-02T10:09:10.164779Z","end":"2026-07-02T10:09:13.310486Z","steps":["trace[1069012830] 'agreement among raft nodes before linearized reading' (duration: 3.145528976s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:13.311005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:10.164775Z","time spent":"3.146217854s","remote":"127.0.0.1:58740","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-07-02T10:09:13.311015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.398240231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.60\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T10:09:13.311052Z","caller":"traceutil/trace.go:171","msg":"trace[1580809827] range","detail":"{range_begin:/registry/masterleases/199.19.213.60; range_end:; response_count:1; response_revision:1111; }","duration":"2.398313743s","start":"2026-07-02T10:09:10.912728Z","end":"2026-07-02T10:09:13.311042Z","steps":["trace[1580809827] 'agreement among raft nodes before linearized reading' (duration: 2.39819061s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:13.311094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:10.912708Z","time spent":"2.398378134s","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.60\" "} {"level":"info","ts":"2026-07-02T10:09:13.31111Z","caller":"traceutil/trace.go:171","msg":"trace[1904880065] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1111; }","duration":"2.235030764s","start":"2026-07-02T10:09:11.075924Z","end":"2026-07-02T10:09:13.310955Z","steps":["trace[1904880065] 'agreement among raft nodes before linearized reading' (duration: 2.234266285s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:13.311142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:11.075902Z","time spent":"2.23523012s","remote":"127.0.0.1:58686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:09:13.311555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.141504442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T10:09:13.311604Z","caller":"traceutil/trace.go:171","msg":"trace[114253150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"3.141588624s","start":"2026-07-02T10:09:10.170003Z","end":"2026-07-02T10:09:13.311592Z","steps":["trace[114253150] 'agreement among raft nodes before linearized reading' (duration: 3.140220359s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:13.312005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:10.169988Z","time spent":"3.141984265s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T10:09:14.637018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.612764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:14.637113Z","caller":"traceutil/trace.go:171","msg":"trace[659824610] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1117; }","duration":"145.774888ms","start":"2026-07-02T10:09:14.491318Z","end":"2026-07-02T10:09:14.637093Z","steps":["trace[659824610] 'count revisions from in-memory index tree' (duration: 145.436909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:14.637262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.914098ms","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-07-02T10:09:14.637346Z","caller":"traceutil/trace.go:171","msg":"trace[672917598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1117; }","duration":"104.039072ms","start":"2026-07-02T10:09:14.533289Z","end":"2026-07-02T10:09:14.637329Z","steps":["trace[672917598] 'range keys from in-memory index tree' (duration: 103.679863ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:14.91461Z","caller":"traceutil/trace.go:171","msg":"trace[1416796041] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"130.948985ms","start":"2026-07-02T10:09:14.783639Z","end":"2026-07-02T10:09:14.914588Z","steps":["trace[1416796041] 'read index received' (duration: 130.759251ms)","trace[1416796041] 'applied index is now lower than readState.Index' (duration: 188.844µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:14.914697Z","caller":"traceutil/trace.go:171","msg":"trace[137918473] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"273.009517ms","start":"2026-07-02T10:09:14.641654Z","end":"2026-07-02T10:09:14.914664Z","steps":["trace[137918473] 'process raft request' (duration: 272.799242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:14.914802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.142421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:14.914889Z","caller":"traceutil/trace.go:171","msg":"trace[1356255549] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1118; }","duration":"131.254694ms","start":"2026-07-02T10:09:14.783616Z","end":"2026-07-02T10:09:14.914871Z","steps":["trace[1356255549] 'agreement among raft nodes before linearized reading' (duration: 131.082779ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:16.640221Z","caller":"traceutil/trace.go:171","msg":"trace[1915888024] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"112.541811ms","start":"2026-07-02T10:09:16.527654Z","end":"2026-07-02T10:09:16.640196Z","steps":["trace[1915888024] 'process raft request' (duration: 112.357337ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:17.665709Z","caller":"traceutil/trace.go:171","msg":"trace[1424045581] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"366.862146ms","start":"2026-07-02T10:09:17.298824Z","end":"2026-07-02T10:09:17.665686Z","steps":["trace[1424045581] 'process raft request' (duration: 366.721932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:17.665868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:17.298799Z","time spent":"366.98996ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:09:17.665962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.489133ms","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-07-02T10:09:17.66602Z","caller":"traceutil/trace.go:171","msg":"trace[664349021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1126; }","duration":"303.611626ms","start":"2026-07-02T10:09:17.362398Z","end":"2026-07-02T10:09:17.66601Z","steps":["trace[664349021] 'agreement among raft nodes before linearized reading' (duration: 303.37093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:17.666062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:17.362374Z","time spent":"303.679078ms","remote":"127.0.0.1:58740","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":"info","ts":"2026-07-02T10:09:17.665715Z","caller":"traceutil/trace.go:171","msg":"trace[481024018] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1171; }","duration":"303.220806ms","start":"2026-07-02T10:09:17.362466Z","end":"2026-07-02T10:09:17.665687Z","steps":["trace[481024018] 'read index received' (duration: 303.210416ms)","trace[481024018] 'applied index is now lower than readState.Index' (duration: 7.88µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:09:17.666326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.070652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T10:09:17.66636Z","caller":"traceutil/trace.go:171","msg":"trace[528686118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1126; }","duration":"296.152294ms","start":"2026-07-02T10:09:17.370199Z","end":"2026-07-02T10:09:17.666351Z","steps":["trace[528686118] 'agreement among raft nodes before linearized reading' (duration: 296.027951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:17.666457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.466315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T10:09:17.666479Z","caller":"traceutil/trace.go:171","msg":"trace[2082250414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1126; }","duration":"269.528077ms","start":"2026-07-02T10:09:17.396945Z","end":"2026-07-02T10:09:17.666473Z","steps":["trace[2082250414] 'agreement among raft nodes before linearized reading' (duration: 269.468225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:17.666573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.669957ms","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-07-02T10:09:17.666594Z","caller":"traceutil/trace.go:171","msg":"trace[583024656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1126; }","duration":"270.735079ms","start":"2026-07-02T10:09:17.395853Z","end":"2026-07-02T10:09:17.666588Z","steps":["trace[583024656] 'agreement among raft nodes before linearized reading' (duration: 270.666747ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:17.910958Z","caller":"traceutil/trace.go:171","msg":"trace[1492110333] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"240.266413ms","start":"2026-07-02T10:09:17.670657Z","end":"2026-07-02T10:09:17.910924Z","steps":["trace[1492110333] 'process raft request' (duration: 191.226929ms)","trace[1492110333] 'compare' (duration: 48.843159ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:17.914715Z","caller":"traceutil/trace.go:171","msg":"trace[1456179372] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"243.843556ms","start":"2026-07-02T10:09:17.670848Z","end":"2026-07-02T10:09:17.914691Z","steps":["trace[1456179372] 'process raft request' (duration: 243.778114ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:17.914887Z","caller":"traceutil/trace.go:171","msg":"trace[9929514] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"244.167043ms","start":"2026-07-02T10:09:17.670711Z","end":"2026-07-02T10:09:17.914878Z","steps":["trace[9929514] 'process raft request' (duration: 243.796054ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:17.91498Z","caller":"traceutil/trace.go:171","msg":"trace[31766196] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"243.490137ms","start":"2026-07-02T10:09:17.671482Z","end":"2026-07-02T10:09:17.914972Z","steps":["trace[31766196] 'process raft request' (duration: 243.179759ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:28.159779Z","caller":"traceutil/trace.go:171","msg":"trace[2110667940] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"197.074635ms","start":"2026-07-02T10:09:27.962669Z","end":"2026-07-02T10:09:28.159743Z","steps":["trace[2110667940] 'process raft request' (duration: 197.017583ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:28.159817Z","caller":"traceutil/trace.go:171","msg":"trace[1907391152] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"197.9542ms","start":"2026-07-02T10:09:27.961816Z","end":"2026-07-02T10:09:28.159771Z","steps":["trace[1907391152] 'process raft request' (duration: 156.380776ms)","trace[1907391152] 'compare' (duration: 41.131001ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:28.15987Z","caller":"traceutil/trace.go:171","msg":"trace[2082919553] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"197.812866ms","start":"2026-07-02T10:09:27.962007Z","end":"2026-07-02T10:09:28.15982Z","steps":["trace[2082919553] 'process raft request' (duration: 197.629831ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:32.917833Z","caller":"traceutil/trace.go:171","msg":"trace[1527486388] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1274; }","duration":"329.336719ms","start":"2026-07-02T10:09:32.588476Z","end":"2026-07-02T10:09:32.917813Z","steps":["trace[1527486388] 'read index received' (duration: 324.045843ms)","trace[1527486388] 'applied index is now lower than readState.Index' (duration: 5.290216ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:32.917936Z","caller":"traceutil/trace.go:171","msg":"trace[1614440282] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"739.482328ms","start":"2026-07-02T10:09:32.178446Z","end":"2026-07-02T10:09:32.917929Z","steps":["trace[1614440282] 'process raft request' (duration: 739.290843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:32.918014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:32.178439Z","time spent":"739.515349ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:09:32.918034Z","caller":"traceutil/trace.go:171","msg":"trace[692921277] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"740.318713ms","start":"2026-07-02T10:09:32.177701Z","end":"2026-07-02T10:09:32.91802Z","steps":["trace[692921277] 'process raft request' (duration: 734.887733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:32.918097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:32.177693Z","time spent":"740.370274ms","remote":"127.0.0.1:58740","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-07-02T10:09:32.918193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.72236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:32.918221Z","caller":"traceutil/trace.go:171","msg":"trace[1011655521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"329.785862ms","start":"2026-07-02T10:09:32.588428Z","end":"2026-07-02T10:09:32.918214Z","steps":["trace[1011655521] 'agreement among raft nodes before linearized reading' (duration: 329.74301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:32.918244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:32.588406Z","time spent":"329.833133ms","remote":"127.0.0.1:58480","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-07-02T10:09:32.918197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.470724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:09:32.918319Z","caller":"traceutil/trace.go:171","msg":"trace[1908331933] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1227; }","duration":"144.68056ms","start":"2026-07-02T10:09:32.773625Z","end":"2026-07-02T10:09:32.918306Z","steps":["trace[1908331933] 'agreement among raft nodes before linearized reading' (duration: 144.465324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:32.918392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.930934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:32.918427Z","caller":"traceutil/trace.go:171","msg":"trace[2005430803] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1227; }","duration":"118.018815ms","start":"2026-07-02T10:09:32.800396Z","end":"2026-07-02T10:09:32.918414Z","steps":["trace[2005430803] 'agreement among raft nodes before linearized reading' (duration: 117.964884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:34.381511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302461289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:09:34.871326Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.147439533s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T10:09:34.871767Z","caller":"traceutil/trace.go:171","msg":"trace[1529040967] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"1.147937789s","start":"2026-07-02T10:09:33.723804Z","end":"2026-07-02T10:09:34.871742Z","steps":["trace[1529040967] 'process raft request' (duration: 1.147730993s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:34.871915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:33.723786Z","time spent":"1.148051391s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:09:35.99829Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.122278032s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T10:09:35.998527Z","caller":"traceutil/trace.go:171","msg":"trace[478497005] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1277; }","duration":"2.117390339s","start":"2026-07-02T10:09:33.881118Z","end":"2026-07-02T10:09:35.998509Z","steps":["trace[478497005] 'read index received' (duration: 990.429189ms)","trace[478497005] 'applied index is now lower than readState.Index' (duration: 1.12696039s)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:35.99872Z","caller":"traceutil/trace.go:171","msg":"trace[1234061495] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"1.882963845s","start":"2026-07-02T10:09:34.115749Z","end":"2026-07-02T10:09:35.998713Z","steps":["trace[1234061495] 'process raft request' (duration: 1.882658936s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.998782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:34.115727Z","time spent":"1.883006966s","remote":"127.0.0.1:58740","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-07-02T10:09:35.998888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.117771061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:35.998905Z","caller":"traceutil/trace.go:171","msg":"trace[1216431691] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1230; }","duration":"2.117817912s","start":"2026-07-02T10:09:33.881082Z","end":"2026-07-02T10:09:35.9989Z","steps":["trace[1216431691] 'agreement among raft nodes before linearized reading' (duration: 2.117788251s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.998919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:33.881064Z","time spent":"2.117851453s","remote":"127.0.0.1:38288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:09:35.999042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.970606096s","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-07-02T10:09:35.999055Z","caller":"traceutil/trace.go:171","msg":"trace[660032122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"1.970657368s","start":"2026-07-02T10:09:34.028394Z","end":"2026-07-02T10:09:35.999051Z","steps":["trace[660032122] 'agreement among raft nodes before linearized reading' (duration: 1.970599176s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.999066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:34.028375Z","time spent":"1.970688418s","remote":"127.0.0.1:58740","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-07-02T10:09:35.999213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.590226ms","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-07-02T10:09:35.999226Z","caller":"traceutil/trace.go:171","msg":"trace[332431671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1230; }","duration":"984.605227ms","start":"2026-07-02T10:09:35.014617Z","end":"2026-07-02T10:09:35.999222Z","steps":["trace[332431671] 'agreement among raft nodes before linearized reading' (duration: 984.561135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.999238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:35.014562Z","time spent":"984.673339ms","remote":"127.0.0.1:58740","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-07-02T10:09:35.999257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.073975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T10:09:35.999309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077154633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T10:09:35.999323Z","caller":"traceutil/trace.go:171","msg":"trace[1275317986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"1.077177843s","start":"2026-07-02T10:09:34.922141Z","end":"2026-07-02T10:09:35.999319Z","steps":["trace[1275317986] 'agreement among raft nodes before linearized reading' (duration: 1.077142042s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.999335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:34.922137Z","time spent":"1.077195074s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T10:09:35.999327Z","caller":"traceutil/trace.go:171","msg":"trace[1165363850] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1230; }","duration":"432.195438ms","start":"2026-07-02T10:09:35.567112Z","end":"2026-07-02T10:09:35.999308Z","steps":["trace[1165363850] 'agreement among raft nodes before linearized reading' (duration: 432.043624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.999392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:35.567094Z","time spent":"432.264811ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:09:35.999433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077644077s","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-07-02T10:09:35.999446Z","caller":"traceutil/trace.go:171","msg":"trace[1175433759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1230; }","duration":"1.077678928s","start":"2026-07-02T10:09:34.921763Z","end":"2026-07-02T10:09:35.999442Z","steps":["trace[1175433759] 'agreement among raft nodes before linearized reading' (duration: 1.077638977s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.999458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:34.921748Z","time spent":"1.077707788s","remote":"127.0.0.1:58740","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-07-02T10:09:35.999538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410228832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:35.99955Z","caller":"traceutil/trace.go:171","msg":"trace[953819561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"1.410316304s","start":"2026-07-02T10:09:34.58923Z","end":"2026-07-02T10:09:35.999546Z","steps":["trace[953819561] 'agreement among raft nodes before linearized reading' (duration: 1.410277943s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.999561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:34.58921Z","time spent":"1.410347625s","remote":"127.0.0.1:58476","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-07-02T10:09:35.999636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546735523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:35.99965Z","caller":"traceutil/trace.go:171","msg":"trace[939378468] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1230; }","duration":"1.546765274s","start":"2026-07-02T10:09:34.452879Z","end":"2026-07-02T10:09:35.999645Z","steps":["trace[939378468] 'agreement among raft nodes before linearized reading' (duration: 1.546742373s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.999663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:34.452871Z","time spent":"1.546788454s","remote":"127.0.0.1:38262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:09:35.999657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.613219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:09:35.999687Z","caller":"traceutil/trace.go:171","msg":"trace[1588024330] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1230; }","duration":"380.674441ms","start":"2026-07-02T10:09:35.619004Z","end":"2026-07-02T10:09:35.999679Z","steps":["trace[1588024330] 'agreement among raft nodes before linearized reading' (duration: 380.621989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.999713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:35.618986Z","time spent":"380.719403ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:09:35.999752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773138351s","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-07-02T10:09:35.999766Z","caller":"traceutil/trace.go:171","msg":"trace[519951137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"1.773181571s","start":"2026-07-02T10:09:34.22658Z","end":"2026-07-02T10:09:35.999762Z","steps":["trace[519951137] 'agreement among raft nodes before linearized reading' (duration: 1.77314103s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:35.999779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:34.226549Z","time spent":"1.773227793s","remote":"127.0.0.1:58740","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-07-02T10:09:54.642523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302461467,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:09:55.142663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302461467,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:09:55.259616Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.171274435s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T10:09:55.260057Z","caller":"traceutil/trace.go:171","msg":"trace[521381538] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"1.171808961s","start":"2026-07-02T10:09:54.088219Z","end":"2026-07-02T10:09:55.260028Z","steps":["trace[521381538] 'process raft request' (duration: 1.171621716s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:55.260191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:54.088199Z","time spent":"1.171918035s","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:09:55.332287Z","caller":"traceutil/trace.go:171","msg":"trace[1424564481] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"351.917174ms","start":"2026-07-02T10:09:54.980318Z","end":"2026-07-02T10:09:55.332235Z","steps":["trace[1424564481] 'process raft request' (duration: 297.43129ms)","trace[1424564481] 'compare' (duration: 54.155264ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:55.332352Z","caller":"traceutil/trace.go:171","msg":"trace[886819452] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1362; }","duration":"1.190198012s","start":"2026-07-02T10:09:54.142135Z","end":"2026-07-02T10:09:55.332333Z","steps":["trace[886819452] 'read index received' (duration: 1.117685617s)","trace[886819452] 'applied index is now lower than readState.Index' (duration: 72.511635ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:09:55.332587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190423759s","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-07-02T10:09:55.33262Z","caller":"traceutil/trace.go:171","msg":"trace[242450678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1312; }","duration":"1.190502681s","start":"2026-07-02T10:09:54.142108Z","end":"2026-07-02T10:09:55.332611Z","steps":["trace[242450678] 'agreement among raft nodes before linearized reading' (duration: 1.190369936s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:55.332642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:54.142094Z","time spent":"1.190542993s","remote":"127.0.0.1:58740","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-07-02T10:09:55.332752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.902049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:55.332815Z","caller":"traceutil/trace.go:171","msg":"trace[1089665735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"744.001242ms","start":"2026-07-02T10:09:54.588795Z","end":"2026-07-02T10:09:55.332796Z","steps":["trace[1089665735] 'agreement among raft nodes before linearized reading' (duration: 743.890879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:55.332835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.883093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T10:09:55.332851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:54.588773Z","time spent":"744.068694ms","remote":"127.0.0.1:58480","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-07-02T10:09:55.332865Z","caller":"traceutil/trace.go:171","msg":"trace[335777355] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1312; }","duration":"627.958535ms","start":"2026-07-02T10:09:54.704898Z","end":"2026-07-02T10:09:55.332856Z","steps":["trace[335777355] 'agreement among raft nodes before linearized reading' (duration: 627.880293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:55.332886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:54.704872Z","time spent":"628.008856ms","remote":"127.0.0.1:38404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:09:55.332853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127061114s","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-07-02T10:09:55.332957Z","caller":"traceutil/trace.go:171","msg":"trace[397377959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1312; }","duration":"1.127177427s","start":"2026-07-02T10:09:54.205756Z","end":"2026-07-02T10:09:55.332934Z","steps":["trace[397377959] 'agreement among raft nodes before linearized reading' (duration: 1.12691632s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:55.333006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:54.205717Z","time spent":"1.12727729s","remote":"127.0.0.1:58740","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-07-02T10:09:55.333059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127221788s","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-07-02T10:09:55.333127Z","caller":"traceutil/trace.go:171","msg":"trace[928411010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1312; }","duration":"1.127334421s","start":"2026-07-02T10:09:54.205774Z","end":"2026-07-02T10:09:55.333109Z","steps":["trace[928411010] 'agreement among raft nodes before linearized reading' (duration: 1.127103725s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:55.333162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:54.205759Z","time spent":"1.127396494s","remote":"127.0.0.1:58740","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-07-02T10:09:55.333182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124827067s","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":"warn","ts":"2026-07-02T10:09:55.333185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127413293s","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-07-02T10:09:55.333206Z","caller":"traceutil/trace.go:171","msg":"trace[984135969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1312; }","duration":"1.124862169s","start":"2026-07-02T10:09:54.208337Z","end":"2026-07-02T10:09:55.333199Z","steps":["trace[984135969] 'agreement among raft nodes before linearized reading' (duration: 1.124792967s)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:55.333217Z","caller":"traceutil/trace.go:171","msg":"trace[215736621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1312; }","duration":"1.127464195s","start":"2026-07-02T10:09:54.205744Z","end":"2026-07-02T10:09:55.333209Z","steps":["trace[215736621] 'agreement among raft nodes before linearized reading' (duration: 1.127373573s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:55.333227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:54.208331Z","time spent":"1.12489241s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T10:09:55.333245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:54.205734Z","time spent":"1.127505386s","remote":"127.0.0.1:58740","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-07-02T10:09:55.336391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:54.980288Z","time spent":"352.077119ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:09:55.567918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.249851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:09:55.568222Z","caller":"traceutil/trace.go:171","msg":"trace[2114955103] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"228.063867ms","start":"2026-07-02T10:09:55.340137Z","end":"2026-07-02T10:09:55.568201Z","steps":["trace[2114955103] 'process raft request' (duration: 227.992745ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:55.568344Z","caller":"traceutil/trace.go:171","msg":"trace[1293118411] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"229.591582ms","start":"2026-07-02T10:09:55.338731Z","end":"2026-07-02T10:09:55.568322Z","steps":["trace[1293118411] 'process raft request' (duration: 124.850516ms)","trace[1293118411] 'compare' (duration: 104.069315ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:55.568448Z","caller":"traceutil/trace.go:171","msg":"trace[111723018] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"228.343745ms","start":"2026-07-02T10:09:55.340094Z","end":"2026-07-02T10:09:55.568438Z","steps":["trace[111723018] 'process raft request' (duration: 227.957684ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:55.568654Z","caller":"traceutil/trace.go:171","msg":"trace[2114788839] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"228.436178ms","start":"2026-07-02T10:09:55.340186Z","end":"2026-07-02T10:09:55.568622Z","steps":["trace[2114788839] 'process raft request' (duration: 227.978035ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:55.568857Z","caller":"traceutil/trace.go:171","msg":"trace[2057593356] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"228.598053ms","start":"2026-07-02T10:09:55.340243Z","end":"2026-07-02T10:09:55.568841Z","steps":["trace[2057593356] 'process raft request' (duration: 228.244723ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:55.568922Z","caller":"traceutil/trace.go:171","msg":"trace[972515387] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"227.459329ms","start":"2026-07-02T10:09:55.341449Z","end":"2026-07-02T10:09:55.568908Z","steps":["trace[972515387] 'process raft request' (duration: 227.308914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:56.808109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302461482,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:09:57.561491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.453318ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T10:09:57.561842Z","caller":"traceutil/trace.go:171","msg":"trace[814154870] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1371; }","duration":"1.253871109s","start":"2026-07-02T10:09:56.307944Z","end":"2026-07-02T10:09:57.561815Z","steps":["trace[814154870] 'read index received' (duration: 682.245117ms)","trace[814154870] 'applied index is now lower than readState.Index' (duration: 571.624111ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:09:57.561891Z","caller":"traceutil/trace.go:171","msg":"trace[123668055] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"1.238910583s","start":"2026-07-02T10:09:56.322965Z","end":"2026-07-02T10:09:57.561876Z","steps":["trace[123668055] 'process raft request' (duration: 1.238602185s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:57.561977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:56.322944Z","time spent":"1.238977384s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:09:58.062026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302461486,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:09:58.589694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999861432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-02T10:09:58.589766Z","caller":"traceutil/trace.go:171","msg":"trace[21676834] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999990325s","start":"2026-07-02T10:09:56.589759Z","end":"2026-07-02T10:09:58.589749Z","steps":["trace[21676834] 'agreement among raft nodes before linearized reading' (duration: 1.999894962s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.589801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:56.58974Z","time spent":"2.000054026s","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T10:09:58.602336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.294380472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-8ntss\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-07-02T10:09:58.602402Z","caller":"traceutil/trace.go:171","msg":"trace[847086877] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-8ntss; range_end:; response_count:1; response_revision:1319; }","duration":"2.294481325s","start":"2026-07-02T10:09:56.307904Z","end":"2026-07-02T10:09:58.602386Z","steps":["trace[847086877] 'agreement among raft nodes before linearized reading' (duration: 1.254024792s)","trace[847086877] 'range keys from in-memory index tree' (duration: 1.040266567s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:09:58.602435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:56.307888Z","time spent":"2.294540486s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11080,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-8ntss\" "} {"level":"warn","ts":"2026-07-02T10:09:58.602683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.374327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:09:58.603203Z","caller":"traceutil/trace.go:171","msg":"trace[1076742585] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"1.260198753s","start":"2026-07-02T10:09:57.34298Z","end":"2026-07-02T10:09:58.603178Z","steps":["trace[1076742585] 'process raft request' (duration: 1.027262521s)","trace[1076742585] 'compare' (duration: 232.039299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:09:58.603261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.342952Z","time spent":"1.260282544s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:09:58.604958Z","caller":"traceutil/trace.go:171","msg":"trace[1915096026] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"924.329335ms","start":"2026-07-02T10:09:57.680616Z","end":"2026-07-02T10:09:58.604945Z","steps":["trace[1915096026] 'process raft request' (duration: 922.133808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.60502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.680592Z","time spent":"924.398486ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:09:58.604998Z","caller":"traceutil/trace.go:171","msg":"trace[882484326] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1373; }","duration":"1.043051059s","start":"2026-07-02T10:09:57.561902Z","end":"2026-07-02T10:09:58.604953Z","steps":["trace[882484326] 'read index received' (duration: 808.845214ms)","trace[882484326] 'applied index is now lower than readState.Index' (duration: 234.203615ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:09:58.605115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111255029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:58.605369Z","caller":"traceutil/trace.go:171","msg":"trace[459916138] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1321; }","duration":"1.111496455s","start":"2026-07-02T10:09:57.493849Z","end":"2026-07-02T10:09:58.605346Z","steps":["trace[459916138] 'agreement among raft nodes before linearized reading' (duration: 1.111234808s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.605408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03149728s","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-07-02T10:09:58.605451Z","caller":"traceutil/trace.go:171","msg":"trace[222528528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1321; }","duration":"1.031567351s","start":"2026-07-02T10:09:57.573875Z","end":"2026-07-02T10:09:58.605442Z","steps":["trace[222528528] 'agreement among raft nodes before linearized reading' (duration: 1.031469419s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.60548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.573861Z","time spent":"1.031613453s","remote":"127.0.0.1:58740","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-07-02T10:09:58.605481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.493823Z","time spent":"1.111596528s","remote":"127.0.0.1:38442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:09:58.605546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029828407s","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-07-02T10:09:58.605633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030607867s","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":"warn","ts":"2026-07-02T10:09:58.60564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.404618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:58.605659Z","caller":"traceutil/trace.go:171","msg":"trace[56956467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1321; }","duration":"1.030646879s","start":"2026-07-02T10:09:57.575005Z","end":"2026-07-02T10:09:58.605652Z","steps":["trace[56956467] 'agreement among raft nodes before linearized reading' (duration: 1.030581997s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.605686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.574999Z","time spent":"1.030682659s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T10:09:58.605684Z","caller":"traceutil/trace.go:171","msg":"trace[406177450] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1321; }","duration":"840.717596ms","start":"2026-07-02T10:09:57.764955Z","end":"2026-07-02T10:09:58.605672Z","steps":["trace[406177450] 'agreement among raft nodes before linearized reading' (duration: 840.411578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.605717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.76494Z","time spent":"840.768688ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:09:58.605765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.744052ms","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":"warn","ts":"2026-07-02T10:09:58.605799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031093739s","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-07-02T10:09:58.605805Z","caller":"traceutil/trace.go:171","msg":"trace[409517853] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1321; }","duration":"901.841844ms","start":"2026-07-02T10:09:57.703953Z","end":"2026-07-02T10:09:58.605795Z","steps":["trace[409517853] 'agreement among raft nodes before linearized reading' (duration: 901.713831ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:58.605819Z","caller":"traceutil/trace.go:171","msg":"trace[1905775478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"1.031129221s","start":"2026-07-02T10:09:57.574683Z","end":"2026-07-02T10:09:58.605813Z","steps":["trace[1905775478] 'agreement among raft nodes before linearized reading' (duration: 1.031071359s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.605836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.574676Z","time spent":"1.031156211s","remote":"127.0.0.1:58740","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-07-02T10:09:58.605213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208077717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-02T10:09:58.605877Z","caller":"traceutil/trace.go:171","msg":"trace[832406823] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1321; }","duration":"1.208766556s","start":"2026-07-02T10:09:57.397095Z","end":"2026-07-02T10:09:58.605862Z","steps":["trace[832406823] 'agreement among raft nodes before linearized reading' (duration: 1.208045368s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.605918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.703932Z","time spent":"901.900455ms","remote":"127.0.0.1:58614","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-07-02T10:09:58.605998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.948777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:58.606041Z","caller":"traceutil/trace.go:171","msg":"trace[1290179951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"692.023079ms","start":"2026-07-02T10:09:57.914011Z","end":"2026-07-02T10:09:58.606034Z","steps":["trace[1290179951] 'agreement among raft nodes before linearized reading' (duration: 691.961838ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:58.605637Z","caller":"traceutil/trace.go:171","msg":"trace[1833063710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1321; }","duration":"1.0299321s","start":"2026-07-02T10:09:57.57568Z","end":"2026-07-02T10:09:58.605613Z","steps":["trace[1833063710] 'agreement among raft nodes before linearized reading' (duration: 1.029711694s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.606115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.575675Z","time spent":"1.030432933s","remote":"127.0.0.1:58740","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-07-02T10:09:58.606082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.913993Z","time spent":"692.06642ms","remote":"127.0.0.1:58480","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-07-02T10:09:58.606197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03034879s","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-07-02T10:09:58.606233Z","caller":"traceutil/trace.go:171","msg":"trace[570026183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"1.030399442s","start":"2026-07-02T10:09:57.575823Z","end":"2026-07-02T10:09:58.606222Z","steps":["trace[570026183] 'agreement among raft nodes before linearized reading' (duration: 1.030295869s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.606243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.199976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:58.606366Z","caller":"traceutil/trace.go:171","msg":"trace[268495611] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1321; }","duration":"565.36473ms","start":"2026-07-02T10:09:58.040987Z","end":"2026-07-02T10:09:58.606351Z","steps":["trace[268495611] 'agreement among raft nodes before linearized reading' (duration: 565.194976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:58.605926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.397064Z","time spent":"1.208850297s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T10:09:58.606309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:57.575814Z","time spent":"1.030486544s","remote":"127.0.0.1:58740","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-07-02T10:09:58.653277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:58.040938Z","time spent":"612.264672ms","remote":"127.0.0.1:38388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-07-02T10:09:58.920278Z","caller":"traceutil/trace.go:171","msg":"trace[1978949945] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"270.46701ms","start":"2026-07-02T10:09:58.649777Z","end":"2026-07-02T10:09:58.920244Z","steps":["trace[1978949945] 'process raft request' (duration: 270.161692ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:58.920341Z","caller":"traceutil/trace.go:171","msg":"trace[1150772928] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"264.383383ms","start":"2026-07-02T10:09:58.655926Z","end":"2026-07-02T10:09:58.920309Z","steps":["trace[1150772928] 'process raft request' (duration: 264.091155ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:58.920356Z","caller":"traceutil/trace.go:171","msg":"trace[1022409431] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"263.88016ms","start":"2026-07-02T10:09:58.656443Z","end":"2026-07-02T10:09:58.920323Z","steps":["trace[1022409431] 'process raft request' (duration: 263.628924ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:58.920553Z","caller":"traceutil/trace.go:171","msg":"trace[940495274] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"270.816599ms","start":"2026-07-02T10:09:58.649726Z","end":"2026-07-02T10:09:58.920542Z","steps":["trace[940495274] 'process raft request' (duration: 270.145562ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:58.920577Z","caller":"traceutil/trace.go:171","msg":"trace[1629805141] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"270.815889ms","start":"2026-07-02T10:09:58.649751Z","end":"2026-07-02T10:09:58.920566Z","steps":["trace[1629805141] 'process raft request' (duration: 270.162623ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:58.920561Z","caller":"traceutil/trace.go:171","msg":"trace[952482383] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"267.781751ms","start":"2026-07-02T10:09:58.652752Z","end":"2026-07-02T10:09:58.920534Z","steps":["trace[952482383] 'process raft request' (duration: 267.216486ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:58.92068Z","caller":"traceutil/trace.go:171","msg":"trace[438895234] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"271.001144ms","start":"2026-07-02T10:09:58.649666Z","end":"2026-07-02T10:09:58.920667Z","steps":["trace[438895234] 'process raft request' (duration: 268.971492ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:59.318103Z","caller":"traceutil/trace.go:171","msg":"trace[2073037592] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"378.940249ms","start":"2026-07-02T10:09:58.939096Z","end":"2026-07-02T10:09:59.318036Z","steps":["trace[2073037592] 'process raft request' (duration: 378.689253ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:09:59.318202Z","caller":"traceutil/trace.go:171","msg":"trace[511640534] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1384; }","duration":"182.295035ms","start":"2026-07-02T10:09:59.135875Z","end":"2026-07-02T10:09:59.31817Z","steps":["trace[511640534] 'read index received' (duration: 181.822152ms)","trace[511640534] 'applied index is now lower than readState.Index' (duration: 471.162µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:09:59.318316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:58.939064Z","time spent":"379.133764ms","remote":"127.0.0.1:58634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:09:59.318333Z","caller":"traceutil/trace.go:171","msg":"trace[1381488672] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"373.9382ms","start":"2026-07-02T10:09:58.944381Z","end":"2026-07-02T10:09:59.318319Z","steps":["trace[1381488672] 'process raft request' (duration: 373.587771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:59.318475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.594002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:09:59.318547Z","caller":"traceutil/trace.go:171","msg":"trace[350497550] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1332; }","duration":"182.727836ms","start":"2026-07-02T10:09:59.135798Z","end":"2026-07-02T10:09:59.318526Z","steps":["trace[350497550] 'agreement among raft nodes before linearized reading' (duration: 182.566661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:09:59.318689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:09:58.944342Z","time spent":"374.183367ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:09:59.87379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.564867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:09:59.873889Z","caller":"traceutil/trace.go:171","msg":"trace[2080224448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"157.69853ms","start":"2026-07-02T10:09:59.716167Z","end":"2026-07-02T10:09:59.873865Z","steps":["trace[2080224448] 'range keys from in-memory index tree' (duration: 157.511415ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:10:00.715582Z","caller":"traceutil/trace.go:171","msg":"trace[213139718] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"107.538195ms","start":"2026-07-02T10:10:00.608026Z","end":"2026-07-02T10:10:00.715564Z","steps":["trace[213139718] 'process raft request' (duration: 107.399471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:07.387875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422952776302461604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T10:10:09.415617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.650619434s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:10:09.415731Z","caller":"traceutil/trace.go:171","msg":"trace[278017727] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1385; }","duration":"2.650754207s","start":"2026-07-02T10:10:06.764962Z","end":"2026-07-02T10:10:09.415716Z","steps":["trace[278017727] 'range keys from in-memory index tree' (duration: 2.650593203s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.416411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984925016s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:10:09.416478Z","caller":"traceutil/trace.go:171","msg":"trace[1125870528] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"2.663937837s","start":"2026-07-02T10:10:06.752523Z","end":"2026-07-02T10:10:09.416461Z","steps":["trace[1125870528] 'process raft request' (duration: 678.892579ms)","trace[1125870528] 'compare' (duration: 1.984589907s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:10:09.416533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:06.752496Z","time spent":"2.664011709s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:10:09.589216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000077816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-02T10:10:09.589347Z","caller":"traceutil/trace.go:171","msg":"trace[970456443] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000283722s","start":"2026-07-02T10:10:07.589047Z","end":"2026-07-02T10:10:09.589331Z","steps":["trace[970456443] 'agreement among raft nodes before linearized reading' (duration: 2.000121657s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.589447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:07.589023Z","time spent":"2.000410024s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/02 10:10:09 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-02T10:10:09.837797Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.406254928s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T10:10:09.928436Z","caller":"traceutil/trace.go:171","msg":"trace[1257296816] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"2.921217438s","start":"2026-07-02T10:10:07.007185Z","end":"2026-07-02T10:10:09.928403Z","steps":["trace[1257296816] 'process raft request' (duration: 2.830828735s)","trace[1257296816] 'compare' (duration: 90.187428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:10:09.92858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:07.007169Z","time spent":"2.921341631s","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6319,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:10:09.928595Z","caller":"traceutil/trace.go:171","msg":"trace[1851865428] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1441; }","duration":"3.041779529s","start":"2026-07-02T10:10:06.88678Z","end":"2026-07-02T10:10:09.92856Z","steps":["trace[1851865428] 'read index received' (duration: 544.740987ms)","trace[1851865428] 'applied index is now lower than readState.Index' (duration: 2.497033462s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:10:09.928901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.042107707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-02T10:10:09.928964Z","caller":"traceutil/trace.go:171","msg":"trace[1964387800] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1387; }","duration":"3.04222282s","start":"2026-07-02T10:10:06.886728Z","end":"2026-07-02T10:10:09.928951Z","steps":["trace[1964387800] 'agreement among raft nodes before linearized reading' (duration: 3.042012315s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.929003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:06.886708Z","time spent":"3.042284662s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-02T10:10:09.9292Z","caller":"traceutil/trace.go:171","msg":"trace[1858599875] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"2.146055834s","start":"2026-07-02T10:10:07.783116Z","end":"2026-07-02T10:10:09.929172Z","steps":["trace[1858599875] 'process raft request' (duration: 2.145250893s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.929386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:07.78309Z","time spent":"2.146188026s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T10:10:09.929512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.972767648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T10:10:09.929523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455291986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:10:09.929561Z","caller":"traceutil/trace.go:171","msg":"trace[943226777] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1389; }","duration":"1.455375998s","start":"2026-07-02T10:10:08.474175Z","end":"2026-07-02T10:10:09.929551Z","steps":["trace[943226777] 'agreement among raft nodes before linearized reading' (duration: 1.455274316s)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:10:09.929557Z","caller":"traceutil/trace.go:171","msg":"trace[1625878365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1389; }","duration":"2.97286062s","start":"2026-07-02T10:10:06.956686Z","end":"2026-07-02T10:10:09.929547Z","steps":["trace[1625878365] 'agreement among raft nodes before linearized reading' (duration: 2.972721437s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.929584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:08.474148Z","time spent":"1.45543079s","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:10:09.929608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.956445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:10:09.929668Z","caller":"traceutil/trace.go:171","msg":"trace[247154704] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1389; }","duration":"678.049298ms","start":"2026-07-02T10:10:09.251605Z","end":"2026-07-02T10:10:09.929654Z","steps":["trace[247154704] 'agreement among raft nodes before linearized reading' (duration: 677.946755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.929689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564482174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T10:10:09.929712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:09.251586Z","time spent":"678.1131ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-02T10:10:09.929737Z","caller":"traceutil/trace.go:171","msg":"trace[1800653335] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1389; }","duration":"1.564529276s","start":"2026-07-02T10:10:08.36519Z","end":"2026-07-02T10:10:09.92972Z","steps":["trace[1800653335] 'agreement among raft nodes before linearized reading' (duration: 1.564451244s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.929768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.905626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-07-02T10:10:09.929777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:08.365133Z","time spent":"1.564632658s","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-07-02T10:10:09.929797Z","caller":"traceutil/trace.go:171","msg":"trace[1302576208] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1389; }","duration":"753.932296ms","start":"2026-07-02T10:10:09.175857Z","end":"2026-07-02T10:10:09.929789Z","steps":["trace[1302576208] 'agreement among raft nodes before linearized reading' (duration: 753.829724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.929816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:09.175805Z","time spent":"754.007149ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-02T10:10:09.929982Z","caller":"traceutil/trace.go:171","msg":"trace[1433867193] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"1.181391708s","start":"2026-07-02T10:10:08.748571Z","end":"2026-07-02T10:10:09.929963Z","steps":["trace[1433867193] 'process raft request' (duration: 1.180552776s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.930025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.971265909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-02T10:10:09.930021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.337779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-02T10:10:09.93004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830491709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-02T10:10:09.930056Z","caller":"traceutil/trace.go:171","msg":"trace[2078490335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1389; }","duration":"2.971340351s","start":"2026-07-02T10:10:06.958707Z","end":"2026-07-02T10:10:09.930047Z","steps":["trace[2078490335] 'agreement among raft nodes before linearized reading' (duration: 2.971252109s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.930081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:06.958681Z","time spent":"2.971393782s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T10:10:09.930082Z","caller":"traceutil/trace.go:171","msg":"trace[1067880158] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1389; }","duration":"1.830572121s","start":"2026-07-02T10:10:08.099496Z","end":"2026-07-02T10:10:09.930068Z","steps":["trace[1067880158] 'agreement among raft nodes before linearized reading' (duration: 1.830456338s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.930123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:08.099478Z","time spent":"1.830635944s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-02T10:10:09.930185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.806147987s","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-07-02T10:10:09.930223Z","caller":"traceutil/trace.go:171","msg":"trace[527330292] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1389; }","duration":"2.806212059s","start":"2026-07-02T10:10:07.123999Z","end":"2026-07-02T10:10:09.930211Z","steps":["trace[527330292] 'agreement among raft nodes before linearized reading' (duration: 2.806154598s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.930253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:07.123986Z","time spent":"2.80626103s","remote":"127.0.0.1:58688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:10:09.930271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.971658558s","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-07-02T10:10:09.930304Z","caller":"traceutil/trace.go:171","msg":"trace[1155026599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1389; }","duration":"2.97173388s","start":"2026-07-02T10:10:06.958562Z","end":"2026-07-02T10:10:09.930296Z","steps":["trace[1155026599] 'agreement among raft nodes before linearized reading' (duration: 2.971646648s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.930331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:06.958542Z","time spent":"2.971782662s","remote":"127.0.0.1:58740","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-07-02T10:10:09.930134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.529058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:10:09.930405Z","caller":"traceutil/trace.go:171","msg":"trace[1483311608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1389; }","duration":"340.826045ms","start":"2026-07-02T10:10:09.589572Z","end":"2026-07-02T10:10:09.930398Z","steps":["trace[1483311608] 'agreement among raft nodes before linearized reading' (duration: 340.533417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.930431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:09.589555Z","time spent":"340.870186ms","remote":"127.0.0.1:58476","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-07-02T10:10:09.929608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:06.956668Z","time spent":"2.972923131s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T10:10:09.93077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.204372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:10:09.930782Z","caller":"traceutil/trace.go:171","msg":"trace[868871731] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1389; }","duration":"993.162461ms","start":"2026-07-02T10:10:08.937604Z","end":"2026-07-02T10:10:09.930766Z","steps":["trace[868871731] 'agreement among raft nodes before linearized reading' (duration: 992.253957ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:10:09.930814Z","caller":"traceutil/trace.go:171","msg":"trace[868406011] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1389; }","duration":"881.277413ms","start":"2026-07-02T10:10:09.049527Z","end":"2026-07-02T10:10:09.930804Z","steps":["trace[868406011] 'agreement among raft nodes before linearized reading' (duration: 881.208881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.930847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:09.049511Z","time spent":"881.326425ms","remote":"127.0.0.1:58554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:10:09.930824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:08.93758Z","time spent":"993.235102ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T10:10:09.931502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.97288211s","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-07-02T10:10:09.931506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.856995351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T10:10:09.930072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:08.74855Z","time spent":"1.181477199s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:10:09.931561Z","caller":"traceutil/trace.go:171","msg":"trace[771631285] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1389; }","duration":"2.857085753s","start":"2026-07-02T10:10:07.074463Z","end":"2026-07-02T10:10:09.931549Z","steps":["trace[771631285] 'agreement among raft nodes before linearized reading' (duration: 2.856995391s)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:10:09.931557Z","caller":"traceutil/trace.go:171","msg":"trace[1723319329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1389; }","duration":"2.972980983s","start":"2026-07-02T10:10:06.958562Z","end":"2026-07-02T10:10:09.931543Z","steps":["trace[1723319329] 'agreement among raft nodes before linearized reading' (duration: 2.971877744s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.931594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:07.074448Z","time spent":"2.857138184s","remote":"127.0.0.1:58570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-02T10:10:09.931624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:06.958542Z","time spent":"2.973069196s","remote":"127.0.0.1:58740","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-07-02T10:10:09.931727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.971725672s","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-07-02T10:10:09.931754Z","caller":"traceutil/trace.go:171","msg":"trace[779923937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1389; }","duration":"2.971959827s","start":"2026-07-02T10:10:06.959787Z","end":"2026-07-02T10:10:09.931746Z","steps":["trace[779923937] 'agreement among raft nodes before linearized reading' (duration: 2.971563477s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:09.931773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:06.959777Z","time spent":"2.971990188s","remote":"127.0.0.1:58740","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-07-02T10:10:11.702022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.779375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T10:10:11.702076Z","caller":"traceutil/trace.go:171","msg":"trace[264675984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1402; }","duration":"111.886948ms","start":"2026-07-02T10:10:11.590175Z","end":"2026-07-02T10:10:11.702062Z","steps":["trace[264675984] 'range keys from in-memory index tree' (duration: 111.674152ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:10:28.337192Z","caller":"traceutil/trace.go:171","msg":"trace[1999029253] linearizableReadLoop","detail":"{readStateIndex:1536; appliedIndex:1535; }","duration":"182.375146ms","start":"2026-07-02T10:10:28.154784Z","end":"2026-07-02T10:10:28.33716Z","steps":["trace[1999029253] 'read index received' (duration: 153.329737ms)","trace[1999029253] 'applied index is now lower than readState.Index' (duration: 29.044119ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:10:28.337267Z","caller":"traceutil/trace.go:171","msg":"trace[126292992] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"246.599394ms","start":"2026-07-02T10:10:28.090638Z","end":"2026-07-02T10:10:28.337237Z","steps":["trace[126292992] 'process raft request' (duration: 217.467493ms)","trace[126292992] 'compare' (duration: 28.793573ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:10:28.337528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.972916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T10:10:28.337696Z","caller":"traceutil/trace.go:171","msg":"trace[2048808281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1476; }","duration":"225.189751ms","start":"2026-07-02T10:10:28.112483Z","end":"2026-07-02T10:10:28.337672Z","steps":["trace[2048808281] 'agreement among raft nodes before linearized reading' (duration: 224.75542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:10:28.337919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.988206ms","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-07-02T10:10:28.338078Z","caller":"traceutil/trace.go:171","msg":"trace[1880962034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1476; }","duration":"249.097389ms","start":"2026-07-02T10:10:28.088879Z","end":"2026-07-02T10:10:28.337977Z","steps":["trace[1880962034] 'agreement among raft nodes before linearized reading' (duration: 248.400681ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:10:28.520257Z","caller":"traceutil/trace.go:171","msg":"trace[1918690252] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"360.588085ms","start":"2026-07-02T10:10:28.159634Z","end":"2026-07-02T10:10:28.520222Z","steps":["trace[1918690252] 'process raft request' (duration: 297.382935ms)","trace[1918690252] 'compare' (duration: 62.849291ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:10:28.520618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:10:28.159616Z","time spent":"360.899434ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T10:10:28.559635Z","caller":"traceutil/trace.go:171","msg":"trace[1562651768] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"217.256836ms","start":"2026-07-02T10:10:28.342354Z","end":"2026-07-02T10:10:28.55961Z","steps":["trace[1562651768] 'process raft request' (duration: 217.205705ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:10:28.559702Z","caller":"traceutil/trace.go:171","msg":"trace[999280371] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"217.567654ms","start":"2026-07-02T10:10:28.3421Z","end":"2026-07-02T10:10:28.559668Z","steps":["trace[999280371] 'process raft request' (duration: 217.336918ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:11:03.831479Z","caller":"traceutil/trace.go:171","msg":"trace[617718187] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"186.141665ms","start":"2026-07-02T10:11:03.645266Z","end":"2026-07-02T10:11:03.831408Z","steps":["trace[617718187] 'process raft request' (duration: 185.871408ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:11:04.468342Z","caller":"traceutil/trace.go:171","msg":"trace[2110883109] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"127.211645ms","start":"2026-07-02T10:11:04.341099Z","end":"2026-07-02T10:11:04.46831Z","steps":["trace[2110883109] 'process raft request' (duration: 54.099138ms)","trace[2110883109] 'compare' (duration: 72.951053ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:11:04.760255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.069911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:11:04.760372Z","caller":"traceutil/trace.go:171","msg":"trace[1190185323] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"247.918312ms","start":"2026-07-02T10:11:04.512434Z","end":"2026-07-02T10:11:04.760352Z","steps":["trace[1190185323] 'process raft request' (duration: 96.667766ms)","trace[1190185323] 'compare' (duration: 150.836875ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:11:05.271593Z","caller":"traceutil/trace.go:171","msg":"trace[726996298] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"157.506057ms","start":"2026-07-02T10:11:05.114018Z","end":"2026-07-02T10:11:05.271524Z","steps":["trace[726996298] 'process raft request' (duration: 64.503545ms)","trace[726996298] 'compare' (duration: 92.806317ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:11:06.328132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.677174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:11:06.328168Z","caller":"traceutil/trace.go:171","msg":"trace[1150175445] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1651; }","duration":"154.777287ms","start":"2026-07-02T10:11:06.173382Z","end":"2026-07-02T10:11:06.32816Z","steps":["trace[1150175445] 'agreement among raft nodes before linearized reading' (duration: 154.687444ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:11:06.327969Z","caller":"traceutil/trace.go:171","msg":"trace[747348842] linearizableReadLoop","detail":"{readStateIndex:1719; appliedIndex:1718; }","duration":"154.50389ms","start":"2026-07-02T10:11:06.173441Z","end":"2026-07-02T10:11:06.327945Z","steps":["trace[747348842] 'read index received' (duration: 139.541663ms)","trace[747348842] 'applied index is now lower than readState.Index' (duration: 14.960967ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:11:06.965064Z","caller":"traceutil/trace.go:171","msg":"trace[598805700] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"104.529308ms","start":"2026-07-02T10:11:06.86051Z","end":"2026-07-02T10:11:06.965039Z","steps":["trace[598805700] 'process raft request' (duration: 104.363754ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:11:06.965443Z","caller":"traceutil/trace.go:171","msg":"trace[455054621] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"104.852827ms","start":"2026-07-02T10:11:06.860576Z","end":"2026-07-02T10:11:06.965429Z","steps":["trace[455054621] 'process raft request' (duration: 104.421366ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:11:52.241779Z","caller":"traceutil/trace.go:171","msg":"trace[768332121] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"159.131427ms","start":"2026-07-02T10:11:52.082629Z","end":"2026-07-02T10:11:52.24176Z","steps":["trace[768332121] 'process raft request' (duration: 159.015254ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:11:52.280957Z","caller":"traceutil/trace.go:171","msg":"trace[1668113516] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"177.9315ms","start":"2026-07-02T10:11:52.102996Z","end":"2026-07-02T10:11:52.280928Z","steps":["trace[1668113516] 'process raft request' (duration: 177.676963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:11:52.993491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.241789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:11:52.993751Z","caller":"traceutil/trace.go:171","msg":"trace[1274435872] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"204.557335ms","start":"2026-07-02T10:11:52.789104Z","end":"2026-07-02T10:11:52.993661Z","steps":["trace[1274435872] 'process raft request' (duration: 47.06104ms)","trace[1274435872] 'compare' (duration: 157.058553ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:11:53.270617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.639739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T10:11:53.270714Z","caller":"traceutil/trace.go:171","msg":"trace[1922623905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1865; }","duration":"173.779475ms","start":"2026-07-02T10:11:53.096907Z","end":"2026-07-02T10:11:53.270687Z","steps":["trace[1922623905] 'range keys from in-memory index tree' (duration: 173.410823ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:12:31.706172Z","caller":"traceutil/trace.go:171","msg":"trace[1321976674] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2126; }","duration":"110.512525ms","start":"2026-07-02T10:12:31.595631Z","end":"2026-07-02T10:12:31.706144Z","steps":["trace[1321976674] 'read index received' (duration: 110.31586ms)","trace[1321976674] 'applied index is now lower than readState.Index' (duration: 195.715µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:12:31.706527Z","caller":"traceutil/trace.go:171","msg":"trace[654093573] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"112.25869ms","start":"2026-07-02T10:12:31.594258Z","end":"2026-07-02T10:12:31.706516Z","steps":["trace[654093573] 'process raft request' (duration: 111.765767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:12:31.707191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.538661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T10:12:31.707631Z","caller":"traceutil/trace.go:171","msg":"trace[1861784275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2042; }","duration":"111.994123ms","start":"2026-07-02T10:12:31.595614Z","end":"2026-07-02T10:12:31.707608Z","steps":["trace[1861784275] 'agreement among raft nodes before linearized reading' (duration: 111.127041ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:13:13.796083Z","caller":"traceutil/trace.go:171","msg":"trace[1668068053] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"124.448315ms","start":"2026-07-02T10:13:13.671606Z","end":"2026-07-02T10:13:13.796054Z","steps":["trace[1668068053] 'process raft request' (duration: 124.296651ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:13:44.511599Z","caller":"traceutil/trace.go:171","msg":"trace[443435104] transaction","detail":"{read_only:false; response_revision:2374; number_of_response:1; }","duration":"282.048798ms","start":"2026-07-02T10:13:44.229518Z","end":"2026-07-02T10:13:44.511567Z","steps":["trace[443435104] 'process raft request' (duration: 257.35713ms)","trace[443435104] 'compare' (duration: 24.563745ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:13:44.556809Z","caller":"traceutil/trace.go:171","msg":"trace[1523785464] transaction","detail":"{read_only:false; response_revision:2375; number_of_response:1; }","duration":"153.299352ms","start":"2026-07-02T10:13:44.403481Z","end":"2026-07-02T10:13:44.556781Z","steps":["trace[1523785464] 'process raft request' (duration: 153.155248ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:13:49.278033Z","caller":"traceutil/trace.go:171","msg":"trace[278391756] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"112.716952ms","start":"2026-07-02T10:13:49.165293Z","end":"2026-07-02T10:13:49.27801Z","steps":["trace[278391756] 'process raft request' (duration: 112.583029ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:14:04.887584Z","caller":"traceutil/trace.go:171","msg":"trace[317638631] linearizableReadLoop","detail":"{readStateIndex:2596; appliedIndex:2595; }","duration":"465.198211ms","start":"2026-07-02T10:14:04.422349Z","end":"2026-07-02T10:14:04.887547Z","steps":["trace[317638631] 'read index received' (duration: 465.15228ms)","trace[317638631] 'applied index is now lower than readState.Index' (duration: 45.071µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:14:04.887668Z","caller":"traceutil/trace.go:171","msg":"trace[81087266] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"497.958977ms","start":"2026-07-02T10:14:04.389689Z","end":"2026-07-02T10:14:04.887648Z","steps":["trace[81087266] 'process raft request' (duration: 497.743041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:14:04.887785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.432977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-02T10:14:04.887806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:14:04.389672Z","time spent":"498.05193ms","remote":"127.0.0.1:58740","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-07-02T10:14:04.887825Z","caller":"traceutil/trace.go:171","msg":"trace[1453580893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2492; }","duration":"465.523449ms","start":"2026-07-02T10:14:04.422292Z","end":"2026-07-02T10:14:04.887815Z","steps":["trace[1453580893] 'agreement among raft nodes before linearized reading' (duration: 465.392126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:14:04.88786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:14:04.422271Z","time spent":"465.578051ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T10:14:04.913726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.522297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T10:14:04.913816Z","caller":"traceutil/trace.go:171","msg":"trace[1509911452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2494; }","duration":"485.65084ms","start":"2026-07-02T10:14:04.428145Z","end":"2026-07-02T10:14:04.913795Z","steps":["trace[1509911452] 'agreement among raft nodes before linearized reading' (duration: 485.426745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:14:04.913867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T10:14:04.428125Z","time spent":"485.728362ms","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T10:14:04.913965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.896584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-02T10:14:04.914037Z","caller":"traceutil/trace.go:171","msg":"trace[793751669] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2494; }","duration":"256.252022ms","start":"2026-07-02T10:14:04.657768Z","end":"2026-07-02T10:14:04.91402Z","steps":["trace[793751669] 'agreement among raft nodes before linearized reading' (duration: 255.77592ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:14:15.181076Z","caller":"traceutil/trace.go:171","msg":"trace[1920262253] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"166.37187ms","start":"2026-07-02T10:14:15.014663Z","end":"2026-07-02T10:14:15.181035Z","steps":["trace[1920262253] 'process raft request' (duration: 165.98033ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:14:15.184391Z","caller":"traceutil/trace.go:171","msg":"trace[375714409] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"169.663546ms","start":"2026-07-02T10:14:15.014711Z","end":"2026-07-02T10:14:15.184374Z","steps":["trace[375714409] 'process raft request' (duration: 169.492721ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:14:15.184523Z","caller":"traceutil/trace.go:171","msg":"trace[1127194955] linearizableReadLoop","detail":"{readStateIndex:2653; appliedIndex:2650; }","duration":"152.394918ms","start":"2026-07-02T10:14:15.032094Z","end":"2026-07-02T10:14:15.184489Z","steps":["trace[1127194955] 'read index received' (duration: 148.445167ms)","trace[1127194955] 'applied index is now lower than readState.Index' (duration: 3.946221ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:14:15.184711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.596323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:14:15.184748Z","caller":"traceutil/trace.go:171","msg":"trace[1689015299] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2547; }","duration":"152.683896ms","start":"2026-07-02T10:14:15.032051Z","end":"2026-07-02T10:14:15.184735Z","steps":["trace[1689015299] 'agreement among raft nodes before linearized reading' (duration: 152.583243ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:14:15.184538Z","caller":"traceutil/trace.go:171","msg":"trace[999713922] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"169.803639ms","start":"2026-07-02T10:14:15.014712Z","end":"2026-07-02T10:14:15.184516Z","steps":["trace[999713922] 'process raft request' (duration: 169.616084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:14:15.184801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.213453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T10:14:15.184866Z","caller":"traceutil/trace.go:171","msg":"trace[1060522238] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2547; }","duration":"133.320596ms","start":"2026-07-02T10:14:15.051532Z","end":"2026-07-02T10:14:15.184852Z","steps":["trace[1060522238] 'agreement among raft nodes before linearized reading' (duration: 133.198163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:14:23.529795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.765237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:14:23.530132Z","caller":"traceutil/trace.go:171","msg":"trace[1223887213] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"214.072941ms","start":"2026-07-02T10:14:23.316034Z","end":"2026-07-02T10:14:23.530107Z","steps":["trace[1223887213] 'process raft request' (duration: 108.75123ms)","trace[1223887213] 'compare' (duration: 104.664405ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:14:27.24414Z","caller":"traceutil/trace.go:171","msg":"trace[146762138] linearizableReadLoop","detail":"{readStateIndex:2714; appliedIndex:2713; }","duration":"158.924656ms","start":"2026-07-02T10:14:27.085192Z","end":"2026-07-02T10:14:27.244117Z","steps":["trace[146762138] 'read index received' (duration: 157.047938ms)","trace[146762138] 'applied index is now lower than readState.Index' (duration: 1.876128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:14:27.24443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.198984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T10:14:27.244465Z","caller":"traceutil/trace.go:171","msg":"trace[1053678276] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"172.909978ms","start":"2026-07-02T10:14:27.071504Z","end":"2026-07-02T10:14:27.244414Z","steps":["trace[1053678276] 'process raft request' (duration: 170.841684ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:14:27.244538Z","caller":"traceutil/trace.go:171","msg":"trace[775742387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2605; }","duration":"159.357967ms","start":"2026-07-02T10:14:27.085153Z","end":"2026-07-02T10:14:27.244511Z","steps":["trace[775742387] 'agreement among raft nodes before linearized reading' (duration: 159.113161ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:14:27.584043Z","caller":"traceutil/trace.go:171","msg":"trace[1419035543] linearizableReadLoop","detail":"{readStateIndex:2717; appliedIndex:2716; }","duration":"173.976985ms","start":"2026-07-02T10:14:27.410048Z","end":"2026-07-02T10:14:27.584025Z","steps":["trace[1419035543] 'read index received' (duration: 103.655658ms)","trace[1419035543] 'applied index is now lower than readState.Index' (duration: 70.320587ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:14:27.584148Z","caller":"traceutil/trace.go:171","msg":"trace[750053172] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"196.441066ms","start":"2026-07-02T10:14:27.387677Z","end":"2026-07-02T10:14:27.584118Z","steps":["trace[750053172] 'process raft request' (duration: 126.15619ms)","trace[750053172] 'compare' (duration: 70.100211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:14:27.584257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.19028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T10:14:27.584572Z","caller":"traceutil/trace.go:171","msg":"trace[1526242587] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2608; }","duration":"174.462887ms","start":"2026-07-02T10:14:27.410001Z","end":"2026-07-02T10:14:27.584464Z","steps":["trace[1526242587] 'agreement among raft nodes before linearized reading' (duration: 174.125509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:14:27.80386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.197074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T10:14:27.80393Z","caller":"traceutil/trace.go:171","msg":"trace[1440290634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2610; }","duration":"118.326559ms","start":"2026-07-02T10:14:27.685588Z","end":"2026-07-02T10:14:27.803914Z","steps":["trace[1440290634] 'range keys from in-memory index tree' (duration: 118.02245ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:14:27.950488Z","caller":"traceutil/trace.go:171","msg":"trace[2106823019] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"143.038446ms","start":"2026-07-02T10:14:27.807427Z","end":"2026-07-02T10:14:27.950465Z","steps":["trace[2106823019] 'process raft request' (duration: 142.893253ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:15:13.611218Z","caller":"traceutil/trace.go:171","msg":"trace[1651976263] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"179.366495ms","start":"2026-07-02T10:15:13.43183Z","end":"2026-07-02T10:15:13.611197Z","steps":["trace[1651976263] 'process raft request' (duration: 179.19125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:15:13.908049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.671603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:15:13.908473Z","caller":"traceutil/trace.go:171","msg":"trace[5703405] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"292.911019ms","start":"2026-07-02T10:15:13.615488Z","end":"2026-07-02T10:15:13.908399Z","steps":["trace[5703405] 'process raft request' (duration: 124.809974ms)","trace[5703405] 'compare' (duration: 167.564721ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:15:13.908494Z","caller":"traceutil/trace.go:171","msg":"trace[1094941597] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"291.852963ms","start":"2026-07-02T10:15:13.616624Z","end":"2026-07-02T10:15:13.908477Z","steps":["trace[1094941597] 'process raft request' (duration: 291.530753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:15:14.160984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.29574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T10:15:14.161412Z","caller":"traceutil/trace.go:171","msg":"trace[1345555483] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"248.628345ms","start":"2026-07-02T10:15:13.912727Z","end":"2026-07-02T10:15:14.161355Z","steps":["trace[1345555483] 'process raft request' (duration: 132.870883ms)","trace[1345555483] 'compare' (duration: 115.045224ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:15:14.161777Z","caller":"traceutil/trace.go:171","msg":"trace[493932562] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"248.929722ms","start":"2026-07-02T10:15:13.912829Z","end":"2026-07-02T10:15:14.161758Z","steps":["trace[493932562] 'process raft request' (duration: 248.790329ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:15:14.418846Z","caller":"traceutil/trace.go:171","msg":"trace[1383027515] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"165.361353ms","start":"2026-07-02T10:15:14.253454Z","end":"2026-07-02T10:15:14.418815Z","steps":["trace[1383027515] 'process raft request' (duration: 165.216049ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:15:15.791557Z","caller":"traceutil/trace.go:171","msg":"trace[148774485] linearizableReadLoop","detail":"{readStateIndex:2940; appliedIndex:2939; }","duration":"136.119758ms","start":"2026-07-02T10:15:15.655414Z","end":"2026-07-02T10:15:15.791533Z","steps":["trace[148774485] 'read index received' (duration: 135.942463ms)","trace[148774485] 'applied index is now lower than readState.Index' (duration: 176.235µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:15:15.791607Z","caller":"traceutil/trace.go:171","msg":"trace[1864829622] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"174.410808ms","start":"2026-07-02T10:15:15.617171Z","end":"2026-07-02T10:15:15.791582Z","steps":["trace[1864829622] 'process raft request' (duration: 174.216783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:15:15.791777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.351974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-02T10:15:15.791816Z","caller":"traceutil/trace.go:171","msg":"trace[178536367] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2822; }","duration":"136.466357ms","start":"2026-07-02T10:15:15.655339Z","end":"2026-07-02T10:15:15.791806Z","steps":["trace[178536367] 'agreement among raft nodes before linearized reading' (duration: 136.324233ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:15:15.914455Z","caller":"traceutil/trace.go:171","msg":"trace[1165687322] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"117.764603ms","start":"2026-07-02T10:15:15.796669Z","end":"2026-07-02T10:15:15.914434Z","steps":["trace[1165687322] 'process raft request' (duration: 117.66856ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:15:15.914458Z","caller":"traceutil/trace.go:171","msg":"trace[176082667] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"117.775153ms","start":"2026-07-02T10:15:15.796644Z","end":"2026-07-02T10:15:15.914419Z","steps":["trace[176082667] 'process raft request' (duration: 74.377651ms)","trace[176082667] 'compare' (duration: 43.212688ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:15:16.093649Z","caller":"traceutil/trace.go:171","msg":"trace[195410519] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"174.91421ms","start":"2026-07-02T10:15:15.918714Z","end":"2026-07-02T10:15:16.093628Z","steps":["trace[195410519] 'process raft request' (duration: 172.174899ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:15:16.093794Z","caller":"traceutil/trace.go:171","msg":"trace[1597694498] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"174.593322ms","start":"2026-07-02T10:15:15.919181Z","end":"2026-07-02T10:15:16.093774Z","steps":["trace[1597694498] 'process raft request' (duration: 174.409757ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:15:16.154871Z","caller":"traceutil/trace.go:171","msg":"trace[291008914] linearizableReadLoop","detail":"{readStateIndex:2946; appliedIndex:2943; }","duration":"184.065376ms","start":"2026-07-02T10:15:15.970775Z","end":"2026-07-02T10:15:16.15484Z","steps":["trace[291008914] 'read index received' (duration: 120.104104ms)","trace[291008914] 'applied index is now lower than readState.Index' (duration: 63.958192ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:15:16.15536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.5812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-02T10:15:16.155526Z","caller":"traceutil/trace.go:171","msg":"trace[1220532035] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2827; }","duration":"184.728283ms","start":"2026-07-02T10:15:15.970709Z","end":"2026-07-02T10:15:16.155437Z","steps":["trace[1220532035] 'agreement among raft nodes before linearized reading' (duration: 184.23416ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:15:17.08725Z","caller":"traceutil/trace.go:171","msg":"trace[2046984324] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"239.080738ms","start":"2026-07-02T10:15:16.848145Z","end":"2026-07-02T10:15:17.087226Z","steps":["trace[2046984324] 'process raft request' (duration: 238.907913ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:15:18.227138Z","caller":"traceutil/trace.go:171","msg":"trace[1424601821] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"125.76782ms","start":"2026-07-02T10:15:18.10135Z","end":"2026-07-02T10:15:18.227118Z","steps":["trace[1424601821] 'process raft request' (duration: 97.488409ms)","trace[1424601821] 'compare' (duration: 28.206619ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T10:15:42.567783Z","caller":"traceutil/trace.go:171","msg":"trace[1134347398] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"105.510927ms","start":"2026-07-02T10:15:42.462246Z","end":"2026-07-02T10:15:42.567756Z","steps":["trace[1134347398] 'process raft request' (duration: 105.072556ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:16:11.020552Z","caller":"traceutil/trace.go:171","msg":"trace[1877587062] transaction","detail":"{read_only:false; response_revision:3078; number_of_response:1; }","duration":"101.103437ms","start":"2026-07-02T10:16:10.919415Z","end":"2026-07-02T10:16:11.020519Z","steps":["trace[1877587062] 'process raft request' (duration: 100.875441ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:16:48.969152Z","caller":"traceutil/trace.go:171","msg":"trace[34280246] linearizableReadLoop","detail":"{readStateIndex:3388; appliedIndex:3385; }","duration":"162.341278ms","start":"2026-07-02T10:16:48.806793Z","end":"2026-07-02T10:16:48.969135Z","steps":["trace[34280246] 'read index received' (duration: 70.300336ms)","trace[34280246] 'applied index is now lower than readState.Index' (duration: 92.039902ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T10:16:48.969413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.678052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/kubeadm-certs\" ","response":"range_response_count:1 size:11451"} {"level":"info","ts":"2026-07-02T10:16:48.969439Z","caller":"traceutil/trace.go:171","msg":"trace[450460750] range","detail":"{range_begin:/registry/secrets/kube-system/kubeadm-certs; range_end:; response_count:1; response_revision:3251; }","duration":"148.742284ms","start":"2026-07-02T10:16:48.82069Z","end":"2026-07-02T10:16:48.969432Z","steps":["trace[450460750] 'agreement among raft nodes before linearized reading' (duration: 148.671032ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:16:48.969417Z","caller":"traceutil/trace.go:171","msg":"trace[1905040962] transaction","detail":"{read_only:false; response_revision:3250; number_of_response:1; }","duration":"165.081859ms","start":"2026-07-02T10:16:48.804299Z","end":"2026-07-02T10:16:48.969381Z","steps":["trace[1905040962] 'process raft request' (duration: 164.699479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T10:16:48.969494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.684436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/csi-rbd-secret\" ","response":"range_response_count:1 size:880"} {"level":"info","ts":"2026-07-02T10:16:48.969538Z","caller":"traceutil/trace.go:171","msg":"trace[114221497] transaction","detail":"{read_only:false; response_revision:3251; number_of_response:1; }","duration":"165.12372ms","start":"2026-07-02T10:16:48.804388Z","end":"2026-07-02T10:16:48.969511Z","steps":["trace[114221497] 'process raft request' (duration: 164.7118ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:16:48.969591Z","caller":"traceutil/trace.go:171","msg":"trace[1319170967] range","detail":"{range_begin:/registry/secrets/kube-system/csi-rbd-secret; range_end:; response_count:1; response_revision:3251; }","duration":"162.82025ms","start":"2026-07-02T10:16:48.806752Z","end":"2026-07-02T10:16:48.969572Z","steps":["trace[1319170967] 'agreement among raft nodes before linearized reading' (duration: 162.585354ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T10:16:57.98433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1888} {"level":"info","ts":"2026-07-02T10:16:58.025805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1888,"took":"41.033816ms","hash":3202146982,"current-db-size-bytes":23654400,"current-db-size":"24 MB","current-db-size-in-use-bytes":6795264,"current-db-size-in-use":"6.8 MB"} {"level":"info","ts":"2026-07-02T10:16:58.026018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3202146982,"revision":1888,"compact-revision":-1}