{"level":"warn","ts":"2026-04-20T08:07:40.212006Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:07:40.212092Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.116: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.116:2380","--initial-cluster=instance=https://199.19.213.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T08:07:40.212174Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:07:40.212186Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.116:2380"]} {"level":"info","ts":"2026-04-20T08:07:40.212203Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T08:07:40.212798Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.116:2379"]} {"level":"info","ts":"2026-04-20T08:07:40.212872Z","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.116:2380"],"listen-peer-urls":["https://199.19.213.116:2380"],"advertise-client-urls":["https://199.19.213.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T08:07:40.217751Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.677095ms"} {"level":"info","ts":"2026-04-20T08:07:40.227626Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca739d563b259b56","cluster-id":"2052ec4484eed829"} {"level":"info","ts":"2026-04-20T08:07:40.227692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T08:07:40.227716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became follower at term 0"} {"level":"info","ts":"2026-04-20T08:07:40.22773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca739d563b259b56 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T08:07:40.227741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became follower at term 1"} {"level":"info","ts":"2026-04-20T08:07:40.227786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 switched to configuration voters=(14588176611668237142)"} {"level":"warn","ts":"2026-04-20T08:07:40.233627Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T08:07:40.234696Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T08:07:40.235513Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T08:07:40.238018Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca739d563b259b56","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T08:07:40.239045Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca739d563b259b56","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T08:07:40.240751Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T08:07:40.241Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T08:07:40.241613Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T08:07:40.241567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 switched to configuration voters=(14588176611668237142)"} {"level":"info","ts":"2026-04-20T08:07:40.241806Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2052ec4484eed829","local-member-id":"ca739d563b259b56","added-peer-id":"ca739d563b259b56","added-peer-peer-urls":["https://199.19.213.116:2380"]} {"level":"info","ts":"2026-04-20T08:07:40.242918Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T08:07:40.242954Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.116:2380"} {"level":"info","ts":"2026-04-20T08:07:40.242991Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.116:2380"} {"level":"info","ts":"2026-04-20T08:07:40.243508Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca739d563b259b56","initial-advertise-peer-urls":["https://199.19.213.116:2380"],"listen-peer-urls":["https://199.19.213.116:2380"],"advertise-client-urls":["https://199.19.213.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T08:07:40.243598Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T08:07:40.433335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T08:07:40.433387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T08:07:40.433426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 received MsgPreVoteResp from ca739d563b259b56 at term 1"} {"level":"info","ts":"2026-04-20T08:07:40.433441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became candidate at term 2"} {"level":"info","ts":"2026-04-20T08:07:40.433452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 received MsgVoteResp from ca739d563b259b56 at term 2"} {"level":"info","ts":"2026-04-20T08:07:40.433464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca739d563b259b56 became leader at term 2"} {"level":"info","ts":"2026-04-20T08:07:40.433477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca739d563b259b56 elected leader ca739d563b259b56 at term 2"} {"level":"info","ts":"2026-04-20T08:07:40.443192Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca739d563b259b56","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.116:2379]}","request-path":"/0/members/ca739d563b259b56/attributes","cluster-id":"2052ec4484eed829","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T08:07:40.443228Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:07:40.443274Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:07:40.443465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:07:40.444197Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2052ec4484eed829","local-member-id":"ca739d563b259b56","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:07:40.44431Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:07:40.444369Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:07:40.446446Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.116:2379"} {"level":"info","ts":"2026-04-20T08:07:40.446918Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T08:07:40.44742Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T08:07:40.44744Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T08:07:42.153232Z","caller":"traceutil/trace.go:171","msg":"trace[1123404649] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"179.975791ms","start":"2026-04-20T08:07:41.973232Z","end":"2026-04-20T08:07:42.153208Z","steps":["trace[1123404649] 'process raft request' (duration: 179.801797ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:42.234079Z","caller":"traceutil/trace.go:171","msg":"trace[1020764034] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"207.979107ms","start":"2026-04-20T08:07:42.026051Z","end":"2026-04-20T08:07:42.23403Z","steps":["trace[1020764034] 'process raft request' (duration: 207.804114ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:42.23421Z","caller":"traceutil/trace.go:171","msg":"trace[1846389872] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"207.843144ms","start":"2026-04-20T08:07:42.026334Z","end":"2026-04-20T08:07:42.234177Z","steps":["trace[1846389872] 'process raft request' (duration: 207.558327ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:42.234268Z","caller":"traceutil/trace.go:171","msg":"trace[157124750] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"208.536109ms","start":"2026-04-20T08:07:42.025698Z","end":"2026-04-20T08:07:42.234234Z","steps":["trace[157124750] 'process raft request' (duration: 208.312354ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:42.234321Z","caller":"traceutil/trace.go:171","msg":"trace[1647913591] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"207.824223ms","start":"2026-04-20T08:07:42.026471Z","end":"2026-04-20T08:07:42.234295Z","steps":["trace[1647913591] 'process raft request' (duration: 207.594198ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:42.234543Z","caller":"traceutil/trace.go:171","msg":"trace[879248336] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"259.165554ms","start":"2026-04-20T08:07:41.975317Z","end":"2026-04-20T08:07:42.234482Z","steps":["trace[879248336] 'read index received' (duration: 177.823152ms)","trace[879248336] 'applied index is now lower than readState.Index' (duration: 81.341582ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:42.234696Z","caller":"traceutil/trace.go:171","msg":"trace[260764378] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"207.554519ms","start":"2026-04-20T08:07:42.027133Z","end":"2026-04-20T08:07:42.234688Z","steps":["trace[260764378] 'process raft request' (duration: 206.997756ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:42.234621Z","caller":"traceutil/trace.go:171","msg":"trace[143372507] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"208.012858ms","start":"2026-04-20T08:07:42.026559Z","end":"2026-04-20T08:07:42.234572Z","steps":["trace[143372507] 'process raft request' (duration: 207.547387ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:42.235056Z","caller":"traceutil/trace.go:171","msg":"trace[1426148601] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"261.083447ms","start":"2026-04-20T08:07:41.973399Z","end":"2026-04-20T08:07:42.234482Z","steps":["trace[1426148601] 'process raft request' (duration: 260.237658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:42.23516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.769818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:07:42.235402Z","caller":"traceutil/trace.go:171","msg":"trace[907717000] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:9; }","duration":"260.096904ms","start":"2026-04-20T08:07:41.975288Z","end":"2026-04-20T08:07:42.235385Z","steps":["trace[907717000] 'agreement among raft nodes before linearized reading' (duration: 259.495401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:42.242258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.618868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T08:07:42.242319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.524315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:07:42.242346Z","caller":"traceutil/trace.go:171","msg":"trace[1010777413] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:10; }","duration":"265.562876ms","start":"2026-04-20T08:07:41.976772Z","end":"2026-04-20T08:07:42.242335Z","steps":["trace[1010777413] 'agreement among raft nodes before linearized reading' (duration: 265.533805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:42.2424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.900717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T08:07:42.242419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.528555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:07:42.242334Z","caller":"traceutil/trace.go:171","msg":"trace[1662499364] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"265.735741ms","start":"2026-04-20T08:07:41.976577Z","end":"2026-04-20T08:07:42.242313Z","steps":["trace[1662499364] 'agreement among raft nodes before linearized reading' (duration: 265.623229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:42.2424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.917859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.116\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:07:42.243204Z","caller":"traceutil/trace.go:171","msg":"trace[1872341353] range","detail":"{range_begin:/registry/masterleases/199.19.213.116; range_end:; response_count:0; response_revision:10; }","duration":"218.803019ms","start":"2026-04-20T08:07:42.024385Z","end":"2026-04-20T08:07:42.243188Z","steps":["trace[1872341353] 'agreement among raft nodes before linearized reading' (duration: 217.895068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:42.242281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.504105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:07:42.243808Z","caller":"traceutil/trace.go:171","msg":"trace[374744994] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:10; }","duration":"267.05298ms","start":"2026-04-20T08:07:41.976745Z","end":"2026-04-20T08:07:42.243798Z","steps":["trace[374744994] 'agreement among raft nodes before linearized reading' (duration: 265.452324ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:42.242481Z","caller":"traceutil/trace.go:171","msg":"trace[1919282659] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"210.632276ms","start":"2026-04-20T08:07:42.031828Z","end":"2026-04-20T08:07:42.242461Z","steps":["trace[1919282659] 'agreement among raft nodes before linearized reading' (duration: 210.507074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:42.24248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.406624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:07:42.245027Z","caller":"traceutil/trace.go:171","msg":"trace[991270890] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"167.978171ms","start":"2026-04-20T08:07:42.077021Z","end":"2026-04-20T08:07:42.244999Z","steps":["trace[991270890] 'agreement among raft nodes before linearized reading' (duration: 165.397983ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:42.242503Z","caller":"traceutil/trace.go:171","msg":"trace[200797057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"266.974918ms","start":"2026-04-20T08:07:41.975479Z","end":"2026-04-20T08:07:42.242454Z","steps":["trace[200797057] 'agreement among raft nodes before linearized reading' (duration: 266.875966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:42.24227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.340195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:07:42.245311Z","caller":"traceutil/trace.go:171","msg":"trace[416004012] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"219.390582ms","start":"2026-04-20T08:07:42.02589Z","end":"2026-04-20T08:07:42.24528Z","steps":["trace[416004012] 'agreement among raft nodes before linearized reading' (duration: 216.285673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:42.242539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.737591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:07:42.245432Z","caller":"traceutil/trace.go:171","msg":"trace[1559293994] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"218.651426ms","start":"2026-04-20T08:07:42.026771Z","end":"2026-04-20T08:07:42.245423Z","steps":["trace[1559293994] 'agreement among raft nodes before linearized reading' (duration: 215.743542ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.575973Z","caller":"traceutil/trace.go:171","msg":"trace[1933956252] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"105.243107ms","start":"2026-04-20T08:07:50.470697Z","end":"2026-04-20T08:07:50.57594Z","steps":["trace[1933956252] 'process raft request' (duration: 58.617503ms)","trace[1933956252] 'compare' (duration: 46.480751ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:50.576686Z","caller":"traceutil/trace.go:171","msg":"trace[965088740] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"101.122825ms","start":"2026-04-20T08:07:50.47553Z","end":"2026-04-20T08:07:50.576653Z","steps":["trace[965088740] 'process raft request' (duration: 101.057573ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.577083Z","caller":"traceutil/trace.go:171","msg":"trace[141392733] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"102.21566ms","start":"2026-04-20T08:07:50.474852Z","end":"2026-04-20T08:07:50.577068Z","steps":["trace[141392733] 'process raft request' (duration: 101.667067ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.577144Z","caller":"traceutil/trace.go:171","msg":"trace[48033612] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"104.480091ms","start":"2026-04-20T08:07:50.472622Z","end":"2026-04-20T08:07:50.577102Z","steps":["trace[48033612] 'process raft request' (duration: 103.715503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:50.792977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.330243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:07:50.793077Z","caller":"traceutil/trace.go:171","msg":"trace[1542721953] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"126.049573ms","start":"2026-04-20T08:07:50.66701Z","end":"2026-04-20T08:07:50.793059Z","steps":["trace[1542721953] 'compare' (duration: 115.2009ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.806751Z","caller":"traceutil/trace.go:171","msg":"trace[177967292] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"139.613047ms","start":"2026-04-20T08:07:50.667124Z","end":"2026-04-20T08:07:50.806737Z","steps":["trace[177967292] 'process raft request' (duration: 139.249048ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.806981Z","caller":"traceutil/trace.go:171","msg":"trace[964465443] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"138.714128ms","start":"2026-04-20T08:07:50.668257Z","end":"2026-04-20T08:07:50.806971Z","steps":["trace[964465443] 'process raft request' (duration: 138.176765ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.807201Z","caller":"traceutil/trace.go:171","msg":"trace[1989657452] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"138.36343ms","start":"2026-04-20T08:07:50.668799Z","end":"2026-04-20T08:07:50.807162Z","steps":["trace[1989657452] 'process raft request' (duration: 137.695414ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:50.968517Z","caller":"traceutil/trace.go:171","msg":"trace[352572314] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"121.287796ms","start":"2026-04-20T08:07:50.847205Z","end":"2026-04-20T08:07:50.968492Z","steps":["trace[352572314] 'process raft request' (duration: 110.327011ms)","trace[352572314] 'compare' (duration: 10.865633ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:08:13.42553Z","caller":"traceutil/trace.go:171","msg":"trace[1472903476] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"191.455569ms","start":"2026-04-20T08:08:13.233999Z","end":"2026-04-20T08:08:13.425455Z","steps":["trace[1472903476] 'process raft request' (duration: 135.318432ms)","trace[1472903476] 'compare' (duration: 55.740219ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:08:16.921758Z","caller":"traceutil/trace.go:171","msg":"trace[1204865263] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"258.292306ms","start":"2026-04-20T08:08:16.663433Z","end":"2026-04-20T08:08:16.921725Z","steps":["trace[1204865263] 'read index received' (duration: 258.078221ms)","trace[1204865263] 'applied index is now lower than readState.Index' (duration: 212.995µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:08:16.921893Z","caller":"traceutil/trace.go:171","msg":"trace[917388563] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"272.602698ms","start":"2026-04-20T08:08:16.649257Z","end":"2026-04-20T08:08:16.921859Z","steps":["trace[917388563] 'process raft request' (duration: 272.327152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:08:16.922108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.651684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xxjzh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T08:08:16.922173Z","caller":"traceutil/trace.go:171","msg":"trace[966710199] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xxjzh; range_end:; response_count:1; response_revision:577; }","duration":"258.767656ms","start":"2026-04-20T08:08:16.663389Z","end":"2026-04-20T08:08:16.922156Z","steps":["trace[966710199] 'agreement among raft nodes before linearized reading' (duration: 258.528911ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:08:17.119723Z","caller":"traceutil/trace.go:171","msg":"trace[2086307122] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"161.369844ms","start":"2026-04-20T08:08:16.958333Z","end":"2026-04-20T08:08:17.119703Z","steps":["trace[2086307122] 'read index received' (duration: 160.847672ms)","trace[2086307122] 'applied index is now lower than readState.Index' (duration: 519.262µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:08:17.119816Z","caller":"traceutil/trace.go:171","msg":"trace[352102536] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"181.955956ms","start":"2026-04-20T08:08:16.937849Z","end":"2026-04-20T08:08:17.119805Z","steps":["trace[352102536] 'process raft request' (duration: 181.386054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:08:17.119987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.640442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T08:08:17.120027Z","caller":"traceutil/trace.go:171","msg":"trace[759243112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"161.724073ms","start":"2026-04-20T08:08:16.958287Z","end":"2026-04-20T08:08:17.120011Z","steps":["trace[759243112] 'agreement among raft nodes before linearized reading' (duration: 161.595659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:08:17.120259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.787217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T08:08:17.120305Z","caller":"traceutil/trace.go:171","msg":"trace[2042588597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"125.89277ms","start":"2026-04-20T08:08:16.994401Z","end":"2026-04-20T08:08:17.120293Z","steps":["trace[2042588597] 'agreement among raft nodes before linearized reading' (duration: 125.709856ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:05.382504Z","caller":"traceutil/trace.go:171","msg":"trace[1819378109] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"173.425547ms","start":"2026-04-20T08:09:05.209052Z","end":"2026-04-20T08:09:05.382477Z","steps":["trace[1819378109] 'process raft request' (duration: 173.226222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:52.403481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.392002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:09:52.40357Z","caller":"traceutil/trace.go:171","msg":"trace[210273313] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1826; }","duration":"358.538676ms","start":"2026-04-20T08:09:52.045011Z","end":"2026-04-20T08:09:52.40355Z","steps":["trace[210273313] 'count revisions from in-memory index tree' (duration: 358.318171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:52.403621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:09:52.044993Z","time spent":"358.616467ms","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:09:52.403809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.178196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:09:52.403888Z","caller":"traceutil/trace.go:171","msg":"trace[1234900128] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1826; }","duration":"171.500603ms","start":"2026-04-20T08:09:52.232369Z","end":"2026-04-20T08:09:52.40387Z","steps":["trace[1234900128] 'count revisions from in-memory index tree' (duration: 171.080594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:52.403974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.12538ms","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-04-20T08:09:52.404016Z","caller":"traceutil/trace.go:171","msg":"trace[393735290] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1826; }","duration":"326.213222ms","start":"2026-04-20T08:09:52.077793Z","end":"2026-04-20T08:09:52.404006Z","steps":["trace[393735290] 'count revisions from in-memory index tree' (duration: 326.038408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:52.404047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:09:52.077774Z","time spent":"326.263953ms","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":9,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:09:52.404227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.50324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48eed284-f607-4e49-96e0-7b5d749b9ad8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T08:09:52.40427Z","caller":"traceutil/trace.go:171","msg":"trace[1593201797] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48eed284-f607-4e49-96e0-7b5d749b9ad8; range_end:; response_count:1; response_revision:1826; }","duration":"145.581442ms","start":"2026-04-20T08:09:52.258675Z","end":"2026-04-20T08:09:52.404257Z","steps":["trace[1593201797] 'range keys from in-memory index tree' (duration: 145.331137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:52.404227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.961812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48eed284-f607-4e49-96e0-7b5d749b9ad8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T08:09:52.404392Z","caller":"traceutil/trace.go:171","msg":"trace[1385970999] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48eed284-f607-4e49-96e0-7b5d749b9ad8; range_end:; response_count:1; response_revision:1826; }","duration":"287.128185ms","start":"2026-04-20T08:09:52.117213Z","end":"2026-04-20T08:09:52.404341Z","steps":["trace[1385970999] 'range keys from in-memory index tree' (duration: 286.777606ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:52.548918Z","caller":"traceutil/trace.go:171","msg":"trace[282376983] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"138.789641ms","start":"2026-04-20T08:09:52.410102Z","end":"2026-04-20T08:09:52.548891Z","steps":["trace[282376983] 'process raft request' (duration: 138.636748ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:52.792713Z","caller":"traceutil/trace.go:171","msg":"trace[808220574] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"225.438483ms","start":"2026-04-20T08:09:52.567241Z","end":"2026-04-20T08:09:52.79268Z","steps":["trace[808220574] 'process raft request' (duration: 225.133206ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:09:52.79269Z","caller":"traceutil/trace.go:171","msg":"trace[1469335732] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"164.59241ms","start":"2026-04-20T08:09:52.628053Z","end":"2026-04-20T08:09:52.792646Z","steps":["trace[1469335732] 'read index received' (duration: 164.22003ms)","trace[1469335732] 'applied index is now lower than readState.Index' (duration: 371.079µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:09:52.793306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.196723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T08:09:52.793424Z","caller":"traceutil/trace.go:171","msg":"trace[1807304186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1828; }","duration":"165.391097ms","start":"2026-04-20T08:09:52.628015Z","end":"2026-04-20T08:09:52.793406Z","steps":["trace[1807304186] 'agreement among raft nodes before linearized reading' (duration: 164.759073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:52.793308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.494057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T08:09:52.794231Z","caller":"traceutil/trace.go:171","msg":"trace[680227413] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1828; }","duration":"107.456218ms","start":"2026-04-20T08:09:52.686706Z","end":"2026-04-20T08:09:52.794163Z","steps":["trace[680227413] 'agreement among raft nodes before linearized reading' (duration: 106.351434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:10:27.509383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.339607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:10:27.509759Z","caller":"traceutil/trace.go:171","msg":"trace[1241463183] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"502.328568ms","start":"2026-04-20T08:10:27.007399Z","end":"2026-04-20T08:10:27.509728Z","steps":["trace[1241463183] 'process raft request' (duration: 12.419139ms)","trace[1241463183] 'compare' (duration: 489.216924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:10:27.509856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:10:27.007315Z","time spent":"502.494812ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:10:27.509945Z","caller":"traceutil/trace.go:171","msg":"trace[94245094] linearizableReadLoop","detail":"{readStateIndex:2153; appliedIndex:2152; }","duration":"479.378356ms","start":"2026-04-20T08:10:27.030511Z","end":"2026-04-20T08:10:27.509889Z","steps":["trace[94245094] 'read index received' (duration: 26.711µs)","trace[94245094] 'applied index is now lower than readState.Index' (duration: 479.348245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:10:27.510215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.090027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:10:27.510259Z","caller":"traceutil/trace.go:171","msg":"trace[184550642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2074; }","duration":"387.173989ms","start":"2026-04-20T08:10:27.123072Z","end":"2026-04-20T08:10:27.510246Z","steps":["trace[184550642] 'agreement among raft nodes before linearized reading' (duration: 387.092097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:10:27.510288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:10:27.123052Z","time spent":"387.230139ms","remote":"127.0.0.1:54310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:10:27.510308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.763564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T08:10:27.510448Z","caller":"traceutil/trace.go:171","msg":"trace[757782256] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"106.786203ms","start":"2026-04-20T08:10:27.403476Z","end":"2026-04-20T08:10:27.510262Z","steps":["trace[757782256] 'process raft request' (duration: 106.023605ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:10:27.510468Z","caller":"traceutil/trace.go:171","msg":"trace[2009706804] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2073; }","duration":"479.897907ms","start":"2026-04-20T08:10:27.030484Z","end":"2026-04-20T08:10:27.510382Z","steps":["trace[2009706804] 'agreement among raft nodes before linearized reading' (duration: 479.509618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:10:27.510552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:10:27.030473Z","time spent":"480.06111ms","remote":"127.0.0.1:49826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-20T08:10:27.736325Z","caller":"traceutil/trace.go:171","msg":"trace[1655661082] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"107.99141ms","start":"2026-04-20T08:10:27.628236Z","end":"2026-04-20T08:10:27.736227Z","steps":["trace[1655661082] 'process raft request' (duration: 107.761485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:04.16098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.745162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"warn","ts":"2026-04-20T08:12:04.161036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.975894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T08:12:04.161061Z","caller":"traceutil/trace.go:171","msg":"trace[1848022499] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2699; }","duration":"228.865213ms","start":"2026-04-20T08:12:03.932178Z","end":"2026-04-20T08:12:04.161043Z","steps":["trace[1848022499] 'range keys from in-memory index tree' (duration: 228.607849ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:04.161069Z","caller":"traceutil/trace.go:171","msg":"trace[1090868334] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2699; }","duration":"198.044575ms","start":"2026-04-20T08:12:03.963015Z","end":"2026-04-20T08:12:04.16106Z","steps":["trace[1090868334] 'range keys from in-memory index tree' (duration: 197.836412ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:04.276122Z","caller":"traceutil/trace.go:171","msg":"trace[1664220630] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"188.47917ms","start":"2026-04-20T08:12:04.087616Z","end":"2026-04-20T08:12:04.276095Z","steps":["trace[1664220630] 'process raft request' (duration: 188.392689ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:04.276139Z","caller":"traceutil/trace.go:171","msg":"trace[1959637109] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"189.20937ms","start":"2026-04-20T08:12:04.086887Z","end":"2026-04-20T08:12:04.276096Z","steps":["trace[1959637109] 'process raft request' (duration: 185.68782ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:04.276389Z","caller":"traceutil/trace.go:171","msg":"trace[1057072124] linearizableReadLoop","detail":"{readStateIndex:2806; appliedIndex:2802; }","duration":"103.189384ms","start":"2026-04-20T08:12:04.173139Z","end":"2026-04-20T08:12:04.276328Z","steps":["trace[1057072124] 'read index received' (duration: 1.726105ms)","trace[1057072124] 'applied index is now lower than readState.Index' (duration: 101.461948ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:12:04.276387Z","caller":"traceutil/trace.go:171","msg":"trace[1043080225] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"112.178621ms","start":"2026-04-20T08:12:04.164175Z","end":"2026-04-20T08:12:04.276353Z","steps":["trace[1043080225] 'process raft request' (duration: 111.880417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:04.276581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.432887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a8026946292a97\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-20T08:12:04.276612Z","caller":"traceutil/trace.go:171","msg":"trace[471252839] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a8026946292a97; range_end:; response_count:1; response_revision:2703; }","duration":"103.556008ms","start":"2026-04-20T08:12:04.173048Z","end":"2026-04-20T08:12:04.276604Z","steps":["trace[471252839] 'agreement among raft nodes before linearized reading' (duration: 103.397516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:41.455688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.005278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-20T08:15:41.45577Z","caller":"traceutil/trace.go:171","msg":"trace[1416062460] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4468; }","duration":"122.103789ms","start":"2026-04-20T08:15:41.333647Z","end":"2026-04-20T08:15:41.455751Z","steps":["trace[1416062460] 'range keys from in-memory index tree' (duration: 121.097759ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:49.036153Z","caller":"traceutil/trace.go:171","msg":"trace[1628021461] linearizableReadLoop","detail":"{readStateIndex:4773; appliedIndex:4772; }","duration":"226.501433ms","start":"2026-04-20T08:15:48.809628Z","end":"2026-04-20T08:15:49.036129Z","steps":["trace[1628021461] 'read index received' (duration: 160.644653ms)","trace[1628021461] 'applied index is now lower than readState.Index' (duration: 65.85559ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:15:49.036394Z","caller":"traceutil/trace.go:171","msg":"trace[1219752760] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"233.299549ms","start":"2026-04-20T08:15:48.803062Z","end":"2026-04-20T08:15:49.036361Z","steps":["trace[1219752760] 'process raft request' (duration: 167.199484ms)","trace[1219752760] 'compare' (duration: 65.726088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:15:49.036411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.759569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b4d22975-68e7-44c9-b426-72b31867d4fe\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-20T08:15:49.036825Z","caller":"traceutil/trace.go:171","msg":"trace[875278011] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b4d22975-68e7-44c9-b426-72b31867d4fe; range_end:; response_count:1; response_revision:4596; }","duration":"227.206327ms","start":"2026-04-20T08:15:48.809599Z","end":"2026-04-20T08:15:49.036805Z","steps":["trace[875278011] 'agreement among raft nodes before linearized reading' (duration: 226.641796ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:49.092597Z","caller":"traceutil/trace.go:171","msg":"trace[762848290] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"258.185123ms","start":"2026-04-20T08:15:48.834382Z","end":"2026-04-20T08:15:49.092567Z","steps":["trace[762848290] 'process raft request' (duration: 257.957779ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:49.128374Z","caller":"traceutil/trace.go:171","msg":"trace[1072344798] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"238.162466ms","start":"2026-04-20T08:15:48.890081Z","end":"2026-04-20T08:15:49.128243Z","steps":["trace[1072344798] 'process raft request' (duration: 238.052764ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:49.147624Z","caller":"traceutil/trace.go:171","msg":"trace[2027331042] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"129.20776ms","start":"2026-04-20T08:15:49.018384Z","end":"2026-04-20T08:15:49.147592Z","steps":["trace[2027331042] 'process raft request' (duration: 129.135959ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:49.147882Z","caller":"traceutil/trace.go:171","msg":"trace[649762235] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"130.389484ms","start":"2026-04-20T08:15:49.017473Z","end":"2026-04-20T08:15:49.147863Z","steps":["trace[649762235] 'process raft request' (duration: 121.790952ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:49.14804Z","caller":"traceutil/trace.go:171","msg":"trace[980693152] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"105.861115ms","start":"2026-04-20T08:15:49.042169Z","end":"2026-04-20T08:15:49.148031Z","steps":["trace[980693152] 'process raft request' (duration: 105.385725ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:49.148359Z","caller":"traceutil/trace.go:171","msg":"trace[1531295789] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"106.09027ms","start":"2026-04-20T08:15:49.042256Z","end":"2026-04-20T08:15:49.148346Z","steps":["trace[1531295789] 'process raft request' (duration: 105.539409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:57.612228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.047044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:15:57.612477Z","caller":"traceutil/trace.go:171","msg":"trace[1621010496] linearizableReadLoop","detail":"{readStateIndex:4946; appliedIndex:4944; }","duration":"293.750583ms","start":"2026-04-20T08:15:57.318705Z","end":"2026-04-20T08:15:57.612456Z","steps":["trace[1621010496] 'read index received' (duration: 127.379182ms)","trace[1621010496] 'applied index is now lower than readState.Index' (duration: 166.37023ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:15:57.612522Z","caller":"traceutil/trace.go:171","msg":"trace[1982502029] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"429.862949ms","start":"2026-04-20T08:15:57.182615Z","end":"2026-04-20T08:15:57.612478Z","steps":["trace[1982502029] 'process raft request' (duration: 263.458268ms)","trace[1982502029] 'compare' (duration: 165.881321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:15:57.612675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:15:57.182606Z","time spent":"429.989451ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:15:57.613391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.674531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38bd9a17-e95b-43b6-9710-ff6394eb1528\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T08:15:57.613432Z","caller":"traceutil/trace.go:171","msg":"trace[598623852] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"425.33933ms","start":"2026-04-20T08:15:57.187167Z","end":"2026-04-20T08:15:57.612507Z","steps":["trace[598623852] 'process raft request' (duration: 425.168206ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:57.613498Z","caller":"traceutil/trace.go:171","msg":"trace[1173620819] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-38bd9a17-e95b-43b6-9710-ff6394eb1528; range_end:; response_count:1; response_revision:4764; }","duration":"294.787464ms","start":"2026-04-20T08:15:57.318664Z","end":"2026-04-20T08:15:57.613451Z","steps":["trace[1173620819] 'agreement among raft nodes before linearized reading' (duration: 293.903256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:57.613519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:15:57.187154Z","time spent":"426.311639ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:15:57.613946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.997921ms","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":"info","ts":"2026-04-20T08:15:57.61405Z","caller":"traceutil/trace.go:171","msg":"trace[1864618786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4764; }","duration":"222.125414ms","start":"2026-04-20T08:15:57.391886Z","end":"2026-04-20T08:15:57.614012Z","steps":["trace[1864618786] 'agreement among raft nodes before linearized reading' (duration: 221.889359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:46.290917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049065746,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:16:46.79154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049065746,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:16:47.099119Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.337200343s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T08:16:47.099776Z","caller":"traceutil/trace.go:171","msg":"trace[317099943] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"1.337935438s","start":"2026-04-20T08:16:45.761802Z","end":"2026-04-20T08:16:47.099737Z","steps":["trace[317099943] 'process raft request' (duration: 1.337684722s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.099945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:45.761778Z","time spent":"1.338073272s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:16:47.118676Z","caller":"traceutil/trace.go:171","msg":"trace[2011129128] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5382; }","duration":"1.328234531s","start":"2026-04-20T08:16:45.790423Z","end":"2026-04-20T08:16:47.118658Z","steps":["trace[2011129128] 'read index received' (duration: 1.308925479s)","trace[2011129128] 'applied index is now lower than readState.Index' (duration: 19.308382ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:47.118756Z","caller":"traceutil/trace.go:171","msg":"trace[1848528072] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"753.339368ms","start":"2026-04-20T08:16:46.365387Z","end":"2026-04-20T08:16:47.118726Z","steps":["trace[1848528072] 'process raft request' (duration: 753.207956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.118823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328367474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:16:47.118868Z","caller":"traceutil/trace.go:171","msg":"trace[1070047611] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5192; }","duration":"1.328467166s","start":"2026-04-20T08:16:45.79039Z","end":"2026-04-20T08:16:47.118857Z","steps":["trace[1070047611] 'agreement among raft nodes before linearized reading' (duration: 1.328348184s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.118888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054561084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T08:16:47.118894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:45.790375Z","time spent":"1.328512997s","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:16:47.118883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.989995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:16:47.118921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.365367Z","time spent":"753.463051ms","remote":"127.0.0.1:54596","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-04-20T08:16:47.11895Z","caller":"traceutil/trace.go:171","msg":"trace[1535584240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5192; }","duration":"995.092357ms","start":"2026-04-20T08:16:46.12384Z","end":"2026-04-20T08:16:47.118933Z","steps":["trace[1535584240] 'agreement among raft nodes before linearized reading' (duration: 994.978425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.118966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289980665s","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":"info","ts":"2026-04-20T08:16:47.118987Z","caller":"traceutil/trace.go:171","msg":"trace[924626832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5192; }","duration":"1.290017235s","start":"2026-04-20T08:16:45.828965Z","end":"2026-04-20T08:16:47.118982Z","steps":["trace[924626832] 'agreement among raft nodes before linearized reading' (duration: 1.289955184s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.118984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.123821Z","time spent":"995.155898ms","remote":"127.0.0.1:54310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:16:47.119006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:45.828956Z","time spent":"1.290045806s","remote":"127.0.0.1:54596","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-04-20T08:16:47.118838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297527028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T08:16:47.119063Z","caller":"traceutil/trace.go:171","msg":"trace[130615384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5192; }","duration":"1.297786013s","start":"2026-04-20T08:16:45.821271Z","end":"2026-04-20T08:16:47.119057Z","steps":["trace[130615384] 'agreement among raft nodes before linearized reading' (duration: 1.297485937s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.119081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:45.821249Z","time spent":"1.297827044s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T08:16:47.119085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.807851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:16:47.119092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.84411ms","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-04-20T08:16:47.119129Z","caller":"traceutil/trace.go:171","msg":"trace[1546502790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5192; }","duration":"401.916381ms","start":"2026-04-20T08:16:46.717204Z","end":"2026-04-20T08:16:47.11912Z","steps":["trace[1546502790] 'agreement among raft nodes before linearized reading' (duration: 401.602525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.119147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.717187Z","time spent":"401.956153ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T08:16:47.119158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.196686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-04-20T08:16:47.119175Z","caller":"traceutil/trace.go:171","msg":"trace[933467714] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5192; }","duration":"106.227387ms","start":"2026-04-20T08:16:47.012943Z","end":"2026-04-20T08:16:47.119171Z","steps":["trace[933467714] 'agreement among raft nodes before linearized reading' (duration: 106.174355ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:47.118907Z","caller":"traceutil/trace.go:171","msg":"trace[610247419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5192; }","duration":"1.054607926s","start":"2026-04-20T08:16:46.064293Z","end":"2026-04-20T08:16:47.118901Z","steps":["trace[610247419] 'agreement among raft nodes before linearized reading' (duration: 1.054560705s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.119215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.064276Z","time spent":"1.054933493s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T08:16:47.119211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.052604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T08:16:47.11925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.48971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:16:47.119247Z","caller":"traceutil/trace.go:171","msg":"trace[1914220555] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:5192; }","duration":"723.153706ms","start":"2026-04-20T08:16:46.396082Z","end":"2026-04-20T08:16:47.119236Z","steps":["trace[1914220555] 'agreement among raft nodes before linearized reading' (duration: 723.093815ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:47.11927Z","caller":"traceutil/trace.go:171","msg":"trace[200142765] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5192; }","duration":"290.536381ms","start":"2026-04-20T08:16:46.828726Z","end":"2026-04-20T08:16:47.119262Z","steps":["trace[200142765] 'agreement among raft nodes before linearized reading' (duration: 290.50774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.119276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.396066Z","time spent":"723.202027ms","remote":"127.0.0.1:54474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":4,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:16:47.119299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T08:16:47.119319Z","caller":"traceutil/trace.go:171","msg":"trace[1324426774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5192; }","duration":"870.165401ms","start":"2026-04-20T08:16:46.249146Z","end":"2026-04-20T08:16:47.119311Z","steps":["trace[1324426774] 'agreement among raft nodes before linearized reading' (duration: 870.10896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.119334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.249131Z","time spent":"870.199961ms","remote":"127.0.0.1:54596","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":"info","ts":"2026-04-20T08:16:47.1191Z","caller":"traceutil/trace.go:171","msg":"trace[295624838] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5192; }","duration":"112.831592ms","start":"2026-04-20T08:16:47.006264Z","end":"2026-04-20T08:16:47.119096Z","steps":["trace[295624838] 'agreement among raft nodes before linearized reading' (duration: 112.804671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.119223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.971235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:16:47.119426Z","caller":"traceutil/trace.go:171","msg":"trace[647447320] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5192; }","duration":"113.20061ms","start":"2026-04-20T08:16:47.00622Z","end":"2026-04-20T08:16:47.119421Z","steps":["trace[647447320] 'agreement among raft nodes before linearized reading' (duration: 112.989736ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:47.119662Z","caller":"traceutil/trace.go:171","msg":"trace[1148226951] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"306.534006ms","start":"2026-04-20T08:16:46.813114Z","end":"2026-04-20T08:16:47.119648Z","steps":["trace[1148226951] 'process raft request' (duration: 305.512864ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:47.119684Z","caller":"traceutil/trace.go:171","msg":"trace[927468930] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"1.351852461s","start":"2026-04-20T08:16:45.766833Z","end":"2026-04-20T08:16:47.118685Z","steps":["trace[927468930] 'process raft request' (duration: 1.351629866s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.119745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:46.813091Z","time spent":"306.602897ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:47.119764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:45.76682Z","time spent":"1.352900982s","remote":"127.0.0.1:54596","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-04-20T08:16:47.255667Z","caller":"traceutil/trace.go:171","msg":"trace[130835705] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"131.605013ms","start":"2026-04-20T08:16:47.124036Z","end":"2026-04-20T08:16:47.255641Z","steps":["trace[130835705] 'process raft request' (duration: 87.976447ms)","trace[130835705] 'compare' (duration: 43.467602ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:47.255927Z","caller":"traceutil/trace.go:171","msg":"trace[1628102119] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"131.143004ms","start":"2026-04-20T08:16:47.124757Z","end":"2026-04-20T08:16:47.2559Z","steps":["trace[1628102119] 'process raft request' (duration: 130.845378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:47.255956Z","caller":"traceutil/trace.go:171","msg":"trace[1063339768] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"130.045142ms","start":"2026-04-20T08:16:47.125886Z","end":"2026-04-20T08:16:47.255932Z","steps":["trace[1063339768] 'process raft request' (duration: 129.911449ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:47.256014Z","caller":"traceutil/trace.go:171","msg":"trace[1534999409] linearizableReadLoop","detail":"{readStateIndex:5390; appliedIndex:5386; }","duration":"125.622231ms","start":"2026-04-20T08:16:47.130368Z","end":"2026-04-20T08:16:47.25599Z","steps":["trace[1534999409] 'read index received' (duration: 24.528707ms)","trace[1534999409] 'applied index is now lower than readState.Index' (duration: 101.092624ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:47.256122Z","caller":"traceutil/trace.go:171","msg":"trace[831707109] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"125.669392ms","start":"2026-04-20T08:16:47.130442Z","end":"2026-04-20T08:16:47.256112Z","steps":["trace[831707109] 'process raft request' (duration: 125.452848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:47.256136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.728264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:16:47.25617Z","caller":"traceutil/trace.go:171","msg":"trace[2123607846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5197; }","duration":"125.813696ms","start":"2026-04-20T08:16:47.130344Z","end":"2026-04-20T08:16:47.256158Z","steps":["trace[2123607846] 'agreement among raft nodes before linearized reading' (duration: 125.726714ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:49.301929Z","caller":"traceutil/trace.go:171","msg":"trace[631783653] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"398.381607ms","start":"2026-04-20T08:16:48.903521Z","end":"2026-04-20T08:16:49.301903Z","steps":["trace[631783653] 'process raft request' (duration: 398.185553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:49.302111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:48.903502Z","time spent":"398.51656ms","remote":"127.0.0.1:53724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:50.170921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.180291ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T08:16:50.171286Z","caller":"traceutil/trace.go:171","msg":"trace[1641776804] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"640.842346ms","start":"2026-04-20T08:16:49.530425Z","end":"2026-04-20T08:16:50.171268Z","steps":["trace[1641776804] 'process raft request' (duration: 640.803695ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:50.171323Z","caller":"traceutil/trace.go:171","msg":"trace[942068624] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"1.047061883s","start":"2026-04-20T08:16:49.12424Z","end":"2026-04-20T08:16:50.171302Z","steps":["trace[942068624] 'process raft request' (duration: 1.046901039s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.171468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.124214Z","time spent":"1.047182075s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:16:50.171471Z","caller":"traceutil/trace.go:171","msg":"trace[1921273943] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"1.066204692s","start":"2026-04-20T08:16:49.105123Z","end":"2026-04-20T08:16:50.171328Z","steps":["trace[1921273943] 'process raft request' (duration: 1.065884856s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.171581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.105096Z","time spent":"1.066423106s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:16:50.171625Z","caller":"traceutil/trace.go:171","msg":"trace[1652224484] linearizableReadLoop","detail":"{readStateIndex:5396; appliedIndex:5394; }","duration":"1.241968233s","start":"2026-04-20T08:16:48.929651Z","end":"2026-04-20T08:16:50.171619Z","steps":["trace[1652224484] 'read index received' (duration: 372.176264ms)","trace[1652224484] 'applied index is now lower than readState.Index' (duration: 869.791299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:16:50.171758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242103276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-20T08:16:50.171782Z","caller":"traceutil/trace.go:171","msg":"trace[929483574] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5205; }","duration":"1.242131046s","start":"2026-04-20T08:16:48.929645Z","end":"2026-04-20T08:16:50.171776Z","steps":["trace[929483574] 'agreement among raft nodes before linearized reading' (duration: 1.242030424s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.171801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:48.929634Z","time spent":"1.242163106s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T08:16:50.171579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.530412Z","time spent":"640.918187ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:16:50.171606Z","caller":"traceutil/trace.go:171","msg":"trace[735023619] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"1.047163495s","start":"2026-04-20T08:16:49.124436Z","end":"2026-04-20T08:16:50.1716Z","steps":["trace[735023619] 'process raft request' (duration: 1.046751136s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.171961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047061993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:16:50.17198Z","caller":"traceutil/trace.go:171","msg":"trace[2085815756] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5205; }","duration":"1.047111203s","start":"2026-04-20T08:16:49.124864Z","end":"2026-04-20T08:16:50.171975Z","steps":["trace[2085815756] 'agreement among raft nodes before linearized reading' (duration: 1.047080072s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.172003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.124852Z","time spent":"1.047146905s","remote":"127.0.0.1:54580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-20T08:16:50.172053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.124428Z","time spent":"1.047528732s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:50.172216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.430177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T08:16:50.172231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.948445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T08:16:50.172246Z","caller":"traceutil/trace.go:171","msg":"trace[172245661] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5205; }","duration":"917.500558ms","start":"2026-04-20T08:16:49.254737Z","end":"2026-04-20T08:16:50.172238Z","steps":["trace[172245661] 'agreement among raft nodes before linearized reading' (duration: 917.457747ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:50.172254Z","caller":"traceutil/trace.go:171","msg":"trace[581971509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5205; }","duration":"911.987146ms","start":"2026-04-20T08:16:49.260259Z","end":"2026-04-20T08:16:50.172247Z","steps":["trace[581971509] 'agreement among raft nodes before linearized reading' (duration: 911.885984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.172265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.254723Z","time spent":"917.536439ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":6,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:16:50.172273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.260249Z","time spent":"912.019447ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T08:16:50.172389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014163993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T08:16:50.172424Z","caller":"traceutil/trace.go:171","msg":"trace[28322746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5205; }","duration":"1.014220115s","start":"2026-04-20T08:16:49.158199Z","end":"2026-04-20T08:16:50.172419Z","steps":["trace[28322746] 'agreement among raft nodes before linearized reading' (duration: 1.014147233s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.172441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.158189Z","time spent":"1.014248395s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T08:16:50.172573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045014261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"warn","ts":"2026-04-20T08:16:50.172575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.710961ms","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-04-20T08:16:50.17262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047336827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:16:50.172628Z","caller":"traceutil/trace.go:171","msg":"trace[1068927637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5205; }","duration":"911.794453ms","start":"2026-04-20T08:16:49.26082Z","end":"2026-04-20T08:16:50.172615Z","steps":["trace[1068927637] 'agreement among raft nodes before linearized reading' (duration: 911.484747ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:50.172636Z","caller":"traceutil/trace.go:171","msg":"trace[1647768497] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5205; }","duration":"1.047395148s","start":"2026-04-20T08:16:49.125236Z","end":"2026-04-20T08:16:50.172632Z","steps":["trace[1647768497] 'agreement among raft nodes before linearized reading' (duration: 1.047372858s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.172633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.241651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T08:16:50.172655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.125215Z","time spent":"1.047436169s","remote":"127.0.0.1:54580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"info","ts":"2026-04-20T08:16:50.172661Z","caller":"traceutil/trace.go:171","msg":"trace[422923698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5205; }","duration":"912.311994ms","start":"2026-04-20T08:16:49.260343Z","end":"2026-04-20T08:16:50.172655Z","steps":["trace[422923698] 'agreement among raft nodes before linearized reading' (duration: 912.248531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.17266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.26081Z","time spent":"911.843915ms","remote":"127.0.0.1:54596","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-04-20T08:16:50.172678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.260321Z","time spent":"912.352955ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T08:16:50.172829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.579319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:16:50.172864Z","caller":"traceutil/trace.go:171","msg":"trace[1546750716] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5205; }","duration":"356.63894ms","start":"2026-04-20T08:16:49.816212Z","end":"2026-04-20T08:16:50.172851Z","steps":["trace[1546750716] 'agreement among raft nodes before linearized reading' (duration: 356.585399ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:50.172597Z","caller":"traceutil/trace.go:171","msg":"trace[706852703] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5205; }","duration":"1.045061281s","start":"2026-04-20T08:16:49.127531Z","end":"2026-04-20T08:16:50.172592Z","steps":["trace[706852703] 'agreement among raft nodes before linearized reading' (duration: 1.04499892s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.172904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.816199Z","time spent":"356.692361ms","remote":"127.0.0.1:49736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:16:50.172906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.790482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T08:16:50.172915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.12752Z","time spent":"1.045388229s","remote":"127.0.0.1:54580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6182,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"info","ts":"2026-04-20T08:16:50.172933Z","caller":"traceutil/trace.go:171","msg":"trace[883417466] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:5205; }","duration":"661.855314ms","start":"2026-04-20T08:16:49.51107Z","end":"2026-04-20T08:16:50.172925Z","steps":["trace[883417466] 'agreement among raft nodes before linearized reading' (duration: 661.772062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.172405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.625309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T08:16:50.172953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.511053Z","time spent":"661.894644ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":80,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-04-20T08:16:50.172971Z","caller":"traceutil/trace.go:171","msg":"trace[1267261703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5205; }","duration":"912.207842ms","start":"2026-04-20T08:16:49.260757Z","end":"2026-04-20T08:16:50.172965Z","steps":["trace[1267261703] 'agreement among raft nodes before linearized reading' (duration: 911.602039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.172993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:49.26075Z","time spent":"912.237232ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T08:16:50.956971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.67007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:16:50.95721Z","caller":"traceutil/trace.go:171","msg":"trace[2101494506] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"779.305195ms","start":"2026-04-20T08:16:50.177859Z","end":"2026-04-20T08:16:50.957164Z","steps":["trace[2101494506] 'process raft request' (duration: 362.358108ms)","trace[2101494506] 'compare' (duration: 416.220481ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:50.957536Z","caller":"traceutil/trace.go:171","msg":"trace[480490906] linearizableReadLoop","detail":"{readStateIndex:5405; appliedIndex:5400; }","duration":"779.328365ms","start":"2026-04-20T08:16:50.178189Z","end":"2026-04-20T08:16:50.957518Z","steps":["trace[480490906] 'read index received' (duration: 65.703426ms)","trace[480490906] 'applied index is now lower than readState.Index' (duration: 713.623949ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:50.957639Z","caller":"traceutil/trace.go:171","msg":"trace[746723404] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"779.716892ms","start":"2026-04-20T08:16:50.177911Z","end":"2026-04-20T08:16:50.957628Z","steps":["trace[746723404] 'process raft request' (duration: 779.148131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.957699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:50.177906Z","time spent":"779.757644ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:50.957922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.720022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11918"} {"level":"info","ts":"2026-04-20T08:16:50.957992Z","caller":"traceutil/trace.go:171","msg":"trace[1287634522] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5210; }","duration":"779.804494ms","start":"2026-04-20T08:16:50.178173Z","end":"2026-04-20T08:16:50.957978Z","steps":["trace[1287634522] 'agreement among raft nodes before linearized reading' (duration: 779.408396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.958071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:50.178168Z","time spent":"779.850175ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":11941,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-20T08:16:50.958071Z","caller":"traceutil/trace.go:171","msg":"trace[287449020] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"780.066479ms","start":"2026-04-20T08:16:50.177976Z","end":"2026-04-20T08:16:50.958042Z","steps":["trace[287449020] 'process raft request' (duration: 779.442267ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:50.958099Z","caller":"traceutil/trace.go:171","msg":"trace[58357162] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"780.050279ms","start":"2026-04-20T08:16:50.177944Z","end":"2026-04-20T08:16:50.957995Z","steps":["trace[58357162] 'process raft request' (duration: 779.250423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:50.958213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:50.177972Z","time spent":"780.160501ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:50.958396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:50.177846Z","time spent":"779.416607ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:50.958418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:50.17794Z","time spent":"780.392366ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:51.31163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.868851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:16:51.311713Z","caller":"traceutil/trace.go:171","msg":"trace[43391040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5210; }","duration":"187.001344ms","start":"2026-04-20T08:16:51.124695Z","end":"2026-04-20T08:16:51.311696Z","steps":["trace[43391040] 'range keys from in-memory index tree' (duration: 186.719187ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:52.677957Z","caller":"traceutil/trace.go:171","msg":"trace[846173410] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"500.317718ms","start":"2026-04-20T08:16:52.177623Z","end":"2026-04-20T08:16:52.677941Z","steps":["trace[846173410] 'process raft request' (duration: 500.211446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:52.678067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:52.177609Z","time spent":"500.392839ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:16:53.171387Z","caller":"traceutil/trace.go:171","msg":"trace[645458978] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"993.586362ms","start":"2026-04-20T08:16:52.177747Z","end":"2026-04-20T08:16:53.171333Z","steps":["trace[645458978] 'process raft request' (duration: 993.364487ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:53.171406Z","caller":"traceutil/trace.go:171","msg":"trace[1085797637] linearizableReadLoop","detail":"{readStateIndex:5407; appliedIndex:5405; }","duration":"992.713033ms","start":"2026-04-20T08:16:52.17864Z","end":"2026-04-20T08:16:53.171353Z","steps":["trace[1085797637] 'read index received' (duration: 499.280315ms)","trace[1085797637] 'applied index is now lower than readState.Index' (duration: 493.431658ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:16:53.171424Z","caller":"traceutil/trace.go:171","msg":"trace[349168872] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"992.02681ms","start":"2026-04-20T08:16:52.17937Z","end":"2026-04-20T08:16:53.171397Z","steps":["trace[349168872] 'process raft request' (duration: 991.895727ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:53.171517Z","caller":"traceutil/trace.go:171","msg":"trace[1276355557] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"991.686773ms","start":"2026-04-20T08:16:52.179819Z","end":"2026-04-20T08:16:53.171506Z","steps":["trace[1276355557] 'process raft request' (duration: 991.489989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:53.171632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:52.179806Z","time spent":"991.745374ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:53.171683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:52.179344Z","time spent":"992.137812ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:16:53.17173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.489156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T08:16:53.17178Z","caller":"traceutil/trace.go:171","msg":"trace[811816811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5214; }","duration":"924.580277ms","start":"2026-04-20T08:16:52.247187Z","end":"2026-04-20T08:16:53.171767Z","steps":["trace[811816811] 'agreement among raft nodes before linearized reading' (duration: 924.439375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:53.171807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:52.247168Z","time spent":"924.633239ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T08:16:53.171873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.483752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T08:16:53.171878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.40096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T08:16:53.171903Z","caller":"traceutil/trace.go:171","msg":"trace[1759624679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5214; }","duration":"209.528093ms","start":"2026-04-20T08:16:52.962367Z","end":"2026-04-20T08:16:53.171895Z","steps":["trace[1759624679] 'agreement among raft nodes before linearized reading' (duration: 209.412591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:53.171926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.574924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T08:16:53.171926Z","caller":"traceutil/trace.go:171","msg":"trace[384856511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5214; }","duration":"207.489191ms","start":"2026-04-20T08:16:52.964423Z","end":"2026-04-20T08:16:53.171912Z","steps":["trace[384856511] 'agreement among raft nodes before linearized reading' (duration: 207.298868ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:53.17195Z","caller":"traceutil/trace.go:171","msg":"trace[1800288603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5214; }","duration":"209.629755ms","start":"2026-04-20T08:16:52.962315Z","end":"2026-04-20T08:16:53.171944Z","steps":["trace[1800288603] 'agreement among raft nodes before linearized reading' (duration: 209.564864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:53.172002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.729697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T08:16:53.172021Z","caller":"traceutil/trace.go:171","msg":"trace[1863486354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5214; }","duration":"208.767647ms","start":"2026-04-20T08:16:52.963247Z","end":"2026-04-20T08:16:53.172015Z","steps":["trace[1863486354] 'agreement among raft nodes before linearized reading' (duration: 208.710936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:53.172074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.432077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-04-20T08:16:53.172094Z","caller":"traceutil/trace.go:171","msg":"trace[906159833] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5214; }","duration":"993.480219ms","start":"2026-04-20T08:16:52.178609Z","end":"2026-04-20T08:16:53.172089Z","steps":["trace[906159833] 'agreement among raft nodes before linearized reading' (duration: 993.409497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:16:53.172112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:52.178601Z","time spent":"993.505189ms","remote":"127.0.0.1:54580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6182,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-04-20T08:16:53.173018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:16:52.177739Z","time spent":"993.718994ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:16:53.327958Z","caller":"traceutil/trace.go:171","msg":"trace[2050532992] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"151.824039ms","start":"2026-04-20T08:16:53.176113Z","end":"2026-04-20T08:16:53.327937Z","steps":["trace[2050532992] 'process raft request' (duration: 151.600514ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:53.328284Z","caller":"traceutil/trace.go:171","msg":"trace[1859839091] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"151.331859ms","start":"2026-04-20T08:16:53.176919Z","end":"2026-04-20T08:16:53.328251Z","steps":["trace[1859839091] 'process raft request' (duration: 150.932751ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:53.328634Z","caller":"traceutil/trace.go:171","msg":"trace[338545699] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"151.526763ms","start":"2026-04-20T08:16:53.177097Z","end":"2026-04-20T08:16:53.328624Z","steps":["trace[338545699] 'process raft request' (duration: 151.171295ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:16:53.328793Z","caller":"traceutil/trace.go:171","msg":"trace[2122601117] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"150.40411ms","start":"2026-04-20T08:16:53.17819Z","end":"2026-04-20T08:16:53.328594Z","steps":["trace[2122601117] 'process raft request' (duration: 150.154645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:21.624016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049066346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:22.124873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049066346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:22.625038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049066346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:23.123291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00031918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T08:17:23.123407Z","caller":"traceutil/trace.go:171","msg":"trace[320308126] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000506994s","start":"2026-04-20T08:17:21.122881Z","end":"2026-04-20T08:17:23.123388Z","steps":["trace[320308126] 'agreement among raft nodes before linearized reading' (duration: 2.000349511s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:23.123701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.122864Z","time spent":"2.000723448s","remote":"127.0.0.1:54310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 08:17:23 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T08:17:23.125485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049066346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:23.626734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049066346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:24.127474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049066346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:24.628062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049066346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:25.123408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999811969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T08:17:25.123492Z","caller":"traceutil/trace.go:171","msg":"trace[111198627] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999949222s","start":"2026-04-20T08:17:23.123524Z","end":"2026-04-20T08:17:25.123473Z","steps":["trace[111198627] 'agreement among raft nodes before linearized reading' (duration: 1.9998456s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.123538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:23.123466Z","time spent":"2.000064304s","remote":"127.0.0.1:54322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 08:17:25 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T08:17:25.128455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049066346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:25.629394Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11193307277049066346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T08:17:25.684729Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.895767466s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T08:17:25.685585Z","caller":"traceutil/trace.go:171","msg":"trace[1846196600] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"4.896692613s","start":"2026-04-20T08:17:20.788859Z","end":"2026-04-20T08:17:25.685552Z","steps":["trace[1846196600] 'process raft request' (duration: 4.89651505s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.68582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:20.788839Z","time spent":"4.896887719s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:25.692408Z","caller":"traceutil/trace.go:171","msg":"trace[1298643761] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"4.428398023s","start":"2026-04-20T08:17:21.263984Z","end":"2026-04-20T08:17:25.692382Z","steps":["trace[1298643761] 'process raft request' (duration: 4.42827607s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.263973Z","time spent":"4.428512165s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:25.692432Z","caller":"traceutil/trace.go:171","msg":"trace[975774432] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"4.429146498s","start":"2026-04-20T08:17:21.263253Z","end":"2026-04-20T08:17:25.6924Z","steps":["trace[975774432] 'process raft request' (duration: 4.428843972s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.692663Z","caller":"traceutil/trace.go:171","msg":"trace[1119810278] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"4.425394901s","start":"2026-04-20T08:17:21.267257Z","end":"2026-04-20T08:17:25.692652Z","steps":["trace[1119810278] 'process raft request' (duration: 4.425083675s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.692794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.263229Z","time spent":"4.429533376s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:17:25.69285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.267245Z","time spent":"4.425561554s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:25.693314Z","caller":"traceutil/trace.go:171","msg":"trace[1988122780] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"530.18546ms","start":"2026-04-20T08:17:25.163116Z","end":"2026-04-20T08:17:25.693302Z","steps":["trace[1988122780] 'process raft request' (duration: 530.082308ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.69333Z","caller":"traceutil/trace.go:171","msg":"trace[2128760283] linearizableReadLoop","detail":"{readStateIndex:5641; appliedIndex:5633; }","duration":"4.570398231s","start":"2026-04-20T08:17:21.122923Z","end":"2026-04-20T08:17:25.693321Z","steps":["trace[2128760283] 'read index received' (duration: 4.562414697s)","trace[2128760283] 'applied index is now lower than readState.Index' (duration: 7.982684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:17:25.693383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:25.163097Z","time spent":"530.250661ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:17:25.693478Z","caller":"traceutil/trace.go:171","msg":"trace[1621739142] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"565.75202ms","start":"2026-04-20T08:17:25.127716Z","end":"2026-04-20T08:17:25.693468Z","steps":["trace[1621739142] 'process raft request' (duration: 564.879112ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.693516Z","caller":"traceutil/trace.go:171","msg":"trace[1675060060] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"274.785034ms","start":"2026-04-20T08:17:25.418721Z","end":"2026-04-20T08:17:25.693506Z","steps":["trace[1675060060] 'process raft request' (duration: 274.566579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.693529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:25.127694Z","time spent":"565.813041ms","remote":"127.0.0.1:54386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:17:25.693488Z","caller":"traceutil/trace.go:171","msg":"trace[872267024] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"379.902208ms","start":"2026-04-20T08:17:25.313578Z","end":"2026-04-20T08:17:25.69348Z","steps":["trace[872267024] 'process raft request' (duration: 379.659273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.693771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.295317877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T08:17:25.693771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:25.313551Z","time spent":"380.127153ms","remote":"127.0.0.1:54500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:17:25.693845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.128812357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:25.693871Z","caller":"traceutil/trace.go:171","msg":"trace[954001350] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:5440; }","duration":"2.128865798s","start":"2026-04-20T08:17:23.564997Z","end":"2026-04-20T08:17:25.693863Z","steps":["trace[954001350] 'agreement among raft nodes before linearized reading' (duration: 2.128827147s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.693903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:23.564979Z","time spent":"2.128916279s","remote":"127.0.0.1:55570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.693915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007280147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T08:17:25.693954Z","caller":"traceutil/trace.go:171","msg":"trace[1348628513] range","detail":"{range_begin:/registry/masterleases/199.19.213.116; range_end:; response_count:1; response_revision:5440; }","duration":"2.007356788s","start":"2026-04-20T08:17:23.686587Z","end":"2026-04-20T08:17:25.693944Z","steps":["trace[1348628513] 'agreement among raft nodes before linearized reading' (duration: 2.007209135s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.693811Z","caller":"traceutil/trace.go:171","msg":"trace[1487206787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5440; }","duration":"4.295397648s","start":"2026-04-20T08:17:21.398406Z","end":"2026-04-20T08:17:25.693804Z","steps":["trace[1487206787] 'agreement among raft nodes before linearized reading' (duration: 4.295288835s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.693985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:23.686569Z","time spent":"2.00740817s","remote":"127.0.0.1:54342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.116\" "} {"level":"warn","ts":"2026-04-20T08:17:25.69407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.203604051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-20T08:17:25.69411Z","caller":"traceutil/trace.go:171","msg":"trace[1595727097] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5440; }","duration":"2.203684113s","start":"2026-04-20T08:17:23.490417Z","end":"2026-04-20T08:17:25.694101Z","steps":["trace[1595727097] 'agreement among raft nodes before linearized reading' (duration: 2.203590551s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.694144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:23.490396Z","time spent":"2.203739973s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-20T08:17:25.694305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.204404924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-20T08:17:25.694346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.574609ms","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":"warn","ts":"2026-04-20T08:17:25.694312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.23338287s","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-04-20T08:17:25.694368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.233531962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T08:17:25.694383Z","caller":"traceutil/trace.go:171","msg":"trace[1847220467] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5440; }","duration":"351.65346ms","start":"2026-04-20T08:17:25.342721Z","end":"2026-04-20T08:17:25.694375Z","steps":["trace[1847220467] 'agreement among raft nodes before linearized reading' (duration: 351.597539ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.694399Z","caller":"traceutil/trace.go:171","msg":"trace[745901841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5440; }","duration":"4.233559622s","start":"2026-04-20T08:17:21.460832Z","end":"2026-04-20T08:17:25.694391Z","steps":["trace[745901841] 'agreement among raft nodes before linearized reading' (duration: 4.233499421s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.694411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:25.342703Z","time spent":"351.700382ms","remote":"127.0.0.1:54642","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-04-20T08:17:25.694435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.460793Z","time spent":"4.233632464s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T08:17:25.694477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.24258339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:17:25.694524Z","caller":"traceutil/trace.go:171","msg":"trace[874019939] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:5440; }","duration":"2.242624861s","start":"2026-04-20T08:17:23.451879Z","end":"2026-04-20T08:17:25.694504Z","steps":["trace[874019939] 'agreement among raft nodes before linearized reading' (duration: 2.242537909s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.694563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:23.451819Z","time spent":"2.242727133s","remote":"127.0.0.1:54524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":38,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.694609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.247072559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T08:17:25.694638Z","caller":"traceutil/trace.go:171","msg":"trace[463566310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5440; }","duration":"4.247118999s","start":"2026-04-20T08:17:21.447512Z","end":"2026-04-20T08:17:25.694631Z","steps":["trace[463566310] 'agreement among raft nodes before linearized reading' (duration: 4.247065708s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.69456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.739028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:17:25.694666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.447502Z","time spent":"4.24715732s","remote":"127.0.0.1:54596","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-04-20T08:17:25.694694Z","caller":"traceutil/trace.go:171","msg":"trace[1509749472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5440; }","duration":"562.899651ms","start":"2026-04-20T08:17:25.131778Z","end":"2026-04-20T08:17:25.694678Z","steps":["trace[1509749472] 'agreement among raft nodes before linearized reading' (duration: 562.742018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.69474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:25.131761Z","time spent":"562.957242ms","remote":"127.0.0.1:54310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:17:25.694065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.398392Z","time spent":"4.295617654s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T08:17:25.694806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.285247296s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:17:25.694839Z","caller":"traceutil/trace.go:171","msg":"trace[1463418039] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:5440; }","duration":"3.285305546s","start":"2026-04-20T08:17:22.409525Z","end":"2026-04-20T08:17:25.694831Z","steps":["trace[1463418039] 'agreement among raft nodes before linearized reading' (duration: 3.285256315s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.694401Z","caller":"traceutil/trace.go:171","msg":"trace[815005701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5440; }","duration":"4.233513981s","start":"2026-04-20T08:17:21.460879Z","end":"2026-04-20T08:17:25.694393Z","steps":["trace[815005701] 'agreement among raft nodes before linearized reading' (duration: 4.233368969s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.694869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.460862Z","time spent":"4.234001252s","remote":"127.0.0.1:54596","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":"warn","ts":"2026-04-20T08:17:25.694883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:22.409514Z","time spent":"3.285344027s","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":3,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.694943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025255558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-20T08:17:25.69497Z","caller":"traceutil/trace.go:171","msg":"trace[135119846] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5440; }","duration":"1.02531365s","start":"2026-04-20T08:17:24.669649Z","end":"2026-04-20T08:17:25.694963Z","steps":["trace[135119846] 'agreement among raft nodes before linearized reading' (duration: 1.025249369s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.694763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.161062511s","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":"warn","ts":"2026-04-20T08:17:25.695021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196983589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:17:25.695021Z","caller":"traceutil/trace.go:171","msg":"trace[2075614076] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5440; }","duration":"3.161351066s","start":"2026-04-20T08:17:22.53366Z","end":"2026-04-20T08:17:25.695011Z","steps":["trace[2075614076] 'agreement among raft nodes before linearized reading' (duration: 3.16104615s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.695044Z","caller":"traceutil/trace.go:171","msg":"trace[549873611] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5440; }","duration":"1.197005149s","start":"2026-04-20T08:17:24.498032Z","end":"2026-04-20T08:17:25.695037Z","steps":["trace[549873611] 'agreement among raft nodes before linearized reading' (duration: 1.196971509s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.695055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:22.533647Z","time spent":"3.161396878s","remote":"127.0.0.1:55742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":33,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.695074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:24.497969Z","time spent":"1.197097491s","remote":"127.0.0.1:49746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.695079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.442237871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8317"} {"level":"info","ts":"2026-04-20T08:17:25.695111Z","caller":"traceutil/trace.go:171","msg":"trace[468824813] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5440; }","duration":"3.442310063s","start":"2026-04-20T08:17:22.252791Z","end":"2026-04-20T08:17:25.695102Z","steps":["trace[468824813] 'agreement among raft nodes before linearized reading' (duration: 3.442217081s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.695145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:22.252768Z","time spent":"3.442370113s","remote":"127.0.0.1:54580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":8340,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-20T08:17:25.695218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210085808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:17:25.695244Z","caller":"traceutil/trace.go:171","msg":"trace[1446144042] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:5440; }","duration":"1.210155839s","start":"2026-04-20T08:17:24.485081Z","end":"2026-04-20T08:17:25.695237Z","steps":["trace[1446144042] 'agreement among raft nodes before linearized reading' (duration: 1.210094798s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.695266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:24.485059Z","time spent":"1.21019982s","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":29,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.695327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.775963449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T08:17:25.6955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690560496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:25.695511Z","caller":"traceutil/trace.go:171","msg":"trace[1100189826] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5440; }","duration":"3.776172352s","start":"2026-04-20T08:17:21.919313Z","end":"2026-04-20T08:17:25.695486Z","steps":["trace[1100189826] 'agreement among raft nodes before linearized reading' (duration: 3.775975958s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:25.695537Z","caller":"traceutil/trace.go:171","msg":"trace[1214297997] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5440; }","duration":"1.690631827s","start":"2026-04-20T08:17:24.004889Z","end":"2026-04-20T08:17:25.695521Z","steps":["trace[1214297997] 'agreement among raft nodes before linearized reading' (duration: 1.690590846s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.695567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:24.004866Z","time spent":"1.690694768s","remote":"127.0.0.1:54760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.695564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.919296Z","time spent":"3.776257395s","remote":"127.0.0.1:49790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.69575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.99761232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:17:25.69581Z","caller":"traceutil/trace.go:171","msg":"trace[1970942668] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5440; }","duration":"3.997714651s","start":"2026-04-20T08:17:21.698083Z","end":"2026-04-20T08:17:25.695798Z","steps":["trace[1970942668] 'agreement among raft nodes before linearized reading' (duration: 3.997632969s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.69585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.69806Z","time spent":"3.997781803s","remote":"127.0.0.1:55668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:17:25.694565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.238421612s","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":"info","ts":"2026-04-20T08:17:25.696019Z","caller":"traceutil/trace.go:171","msg":"trace[104364651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5440; }","duration":"4.239907792s","start":"2026-04-20T08:17:21.4561Z","end":"2026-04-20T08:17:25.696008Z","steps":["trace[104364651] 'agreement among raft nodes before linearized reading' (duration: 4.238418502s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.696058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.456082Z","time spent":"4.239965463s","remote":"127.0.0.1:54596","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":"info","ts":"2026-04-20T08:17:25.694355Z","caller":"traceutil/trace.go:171","msg":"trace[1715623756] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5440; }","duration":"4.204475126s","start":"2026-04-20T08:17:21.489864Z","end":"2026-04-20T08:17:25.694339Z","steps":["trace[1715623756] 'agreement among raft nodes before linearized reading' (duration: 4.204317773s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.697489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:21.489854Z","time spent":"4.20760273s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T08:17:25.694882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.781458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T08:17:25.695448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463346379s","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-04-20T08:17:25.69779Z","caller":"traceutil/trace.go:171","msg":"trace[496637871] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5440; }","duration":"1.46581363s","start":"2026-04-20T08:17:24.23196Z","end":"2026-04-20T08:17:25.697774Z","steps":["trace[496637871] 'agreement among raft nodes before linearized reading' (duration: 1.463334929s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.694993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:24.669633Z","time spent":"1.02535569s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-20T08:17:25.697836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:24.231937Z","time spent":"1.465884421s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":24,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-04-20T08:17:25.69774Z","caller":"traceutil/trace.go:171","msg":"trace[468047340] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5440; }","duration":"924.660146ms","start":"2026-04-20T08:17:24.773058Z","end":"2026-04-20T08:17:25.697718Z","steps":["trace[468047340] 'agreement among raft nodes before linearized reading' (duration: 921.794848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:25.697995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:17:24.773041Z","time spent":"924.942763ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-04-20T08:17:40.703699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2897} {"level":"info","ts":"2026-04-20T08:17:40.795449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2897,"took":"90.627003ms","hash":146545252,"current-db-size-bytes":53911552,"current-db-size":"54 MB","current-db-size-in-use-bytes":28811264,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T08:17:40.795536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":146545252,"revision":2897,"compact-revision":-1} {"level":"info","ts":"2026-04-20T08:22:40.708702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5537} {"level":"info","ts":"2026-04-20T08:22:40.803721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5537,"took":"93.814905ms","hash":1290582244,"current-db-size-bytes":53911552,"current-db-size":"54 MB","current-db-size-in-use-bytes":18038784,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T08:22:40.803775Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1290582244,"revision":5537,"compact-revision":2897}