{"level":"warn","ts":"2026-05-08T18:16:56.385366Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T18:16:56.385468Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.228: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.228:2380","--initial-cluster=instance=https://199.19.213.228:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.228:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.228: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-05-08T18:16:56.385605Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T18:16:56.385624Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-05-08T18:16:56.385666Z","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-05-08T18:16:56.38646Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"]} {"level":"info","ts":"2026-05-08T18:16:56.386604Z","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.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.228: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-05-08T18:16:56.3914Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.307972ms"} {"level":"info","ts":"2026-05-08T18:16:56.397399Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db79120667ce701f","cluster-id":"793c581eb68f3646"} {"level":"info","ts":"2026-05-08T18:16:56.397456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T18:16:56.397474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 0"} {"level":"info","ts":"2026-05-08T18:16:56.397487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db79120667ce701f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T18:16:56.397494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 1"} {"level":"info","ts":"2026-05-08T18:16:56.397526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"warn","ts":"2026-05-08T18:16:56.405902Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T18:16:56.409315Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T18:16:56.410991Z","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-05-08T18:16:56.412882Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db79120667ce701f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T18:16:56.412978Z","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-05-08T18:16:56.413049Z","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-05-08T18:16:56.413059Z","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-05-08T18:16:56.41332Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db79120667ce701f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T18:16:56.41898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"info","ts":"2026-05-08T18:16:56.419288Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","added-peer-id":"db79120667ce701f","added-peer-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-05-08T18:16:56.419748Z","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-05-08T18:16:56.41983Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-05-08T18:16:56.419854Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-05-08T18:16:56.419972Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db79120667ce701f","initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T18:16:56.420014Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T18:16:57.398327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T18:16:57.398394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T18:16:57.398446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgPreVoteResp from db79120667ce701f at term 1"} {"level":"info","ts":"2026-05-08T18:16:57.398468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became candidate at term 2"} {"level":"info","ts":"2026-05-08T18:16:57.398482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgVoteResp from db79120667ce701f at term 2"} {"level":"info","ts":"2026-05-08T18:16:57.398508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became leader at term 2"} {"level":"info","ts":"2026-05-08T18:16:57.39853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db79120667ce701f elected leader db79120667ce701f at term 2"} {"level":"info","ts":"2026-05-08T18:16:57.515522Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db79120667ce701f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.228:2379]}","request-path":"/0/members/db79120667ce701f/attributes","cluster-id":"793c581eb68f3646","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T18:16:57.515544Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:16:57.515575Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:16:57.51584Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T18:16:57.515893Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T18:16:57.516267Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:16:57.518538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.228:2379"} {"level":"info","ts":"2026-05-08T18:16:57.519173Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T18:16:57.733416Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:16:57.73355Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:16:57.733595Z","caller":"traceutil/trace.go:171","msg":"trace[512420206] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"171.864039ms","start":"2026-05-08T18:16:57.561715Z","end":"2026-05-08T18:16:57.733579Z","steps":["trace[512420206] 'read index received' (duration: 171.626425ms)","trace[512420206] 'applied index is now lower than readState.Index' (duration: 236.464µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:16:57.733644Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-08T18:16:57.733748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.914419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-08T18:16:57.733745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.012731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T18:16:57.733799Z","caller":"traceutil/trace.go:171","msg":"trace[959087839] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"171.98621ms","start":"2026-05-08T18:16:57.561805Z","end":"2026-05-08T18:16:57.733791Z","steps":["trace[959087839] 'agreement among raft nodes before linearized reading' (duration: 171.852178ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:16:57.733807Z","caller":"traceutil/trace.go:171","msg":"trace[632805637] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"172.088673ms","start":"2026-05-08T18:16:57.56171Z","end":"2026-05-08T18:16:57.733798Z","steps":["trace[632805637] 'agreement among raft nodes before linearized reading' (duration: 171.92684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:57.734127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.915991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T18:16:57.73416Z","caller":"traceutil/trace.go:171","msg":"trace[842724118] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"100.968992ms","start":"2026-05-08T18:16:57.633184Z","end":"2026-05-08T18:16:57.734153Z","steps":["trace[842724118] 'agreement among raft nodes before linearized reading' (duration: 100.921541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:57.734266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.512262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T18:16:57.734297Z","caller":"traceutil/trace.go:171","msg":"trace[142332881] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"112.580103ms","start":"2026-05-08T18:16:57.621711Z","end":"2026-05-08T18:16:57.734291Z","steps":["trace[142332881] 'agreement among raft nodes before linearized reading' (duration: 112.514322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:57.734438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.261087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T18:16:57.734538Z","caller":"traceutil/trace.go:171","msg":"trace[1146911593] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"101.369068ms","start":"2026-05-08T18:16:57.633159Z","end":"2026-05-08T18:16:57.734528Z","steps":["trace[1146911593] 'agreement among raft nodes before linearized reading' (duration: 101.259726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:57.734715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.925809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T18:16:57.734742Z","caller":"traceutil/trace.go:171","msg":"trace[1269599612] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"112.9704ms","start":"2026-05-08T18:16:57.621766Z","end":"2026-05-08T18:16:57.734736Z","steps":["trace[1269599612] 'agreement among raft nodes before linearized reading' (duration: 112.930389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:57.734277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.3998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T18:16:57.73486Z","caller":"traceutil/trace.go:171","msg":"trace[1186928328] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"130.00839ms","start":"2026-05-08T18:16:57.604845Z","end":"2026-05-08T18:16:57.734853Z","steps":["trace[1186928328] 'agreement among raft nodes before linearized reading' (duration: 129.41507ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:01.011969Z","caller":"traceutil/trace.go:171","msg":"trace[1635631207] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"106.461675ms","start":"2026-05-08T18:17:00.905483Z","end":"2026-05-08T18:17:01.011944Z","steps":["trace[1635631207] 'process raft request' (duration: 104.732869ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:14.744769Z","caller":"traceutil/trace.go:171","msg":"trace[673366354] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"138.70254ms","start":"2026-05-08T18:17:14.606033Z","end":"2026-05-08T18:17:14.744736Z","steps":["trace[673366354] 'process raft request' (duration: 138.501507ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:15.101094Z","caller":"traceutil/trace.go:171","msg":"trace[1054144898] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"193.038027ms","start":"2026-05-08T18:17:14.908029Z","end":"2026-05-08T18:17:15.101067Z","steps":["trace[1054144898] 'process raft request' (duration: 192.932326ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:15.101344Z","caller":"traceutil/trace.go:171","msg":"trace[2090997670] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"194.945262ms","start":"2026-05-08T18:17:14.906342Z","end":"2026-05-08T18:17:15.101288Z","steps":["trace[2090997670] 'process raft request' (duration: 146.139226ms)","trace[2090997670] 'compare' (duration: 48.31741ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:17:27.967822Z","caller":"traceutil/trace.go:171","msg":"trace[365316972] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"124.022295ms","start":"2026-05-08T18:17:27.843774Z","end":"2026-05-08T18:17:27.967797Z","steps":["trace[365316972] 'process raft request' (duration: 123.885633ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:27.977604Z","caller":"traceutil/trace.go:171","msg":"trace[1360404054] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"131.075683ms","start":"2026-05-08T18:17:27.846512Z","end":"2026-05-08T18:17:27.977588Z","steps":["trace[1360404054] 'process raft request' (duration: 130.912741ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:27.977693Z","caller":"traceutil/trace.go:171","msg":"trace[1958266325] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"130.439715ms","start":"2026-05-08T18:17:27.847229Z","end":"2026-05-08T18:17:27.977669Z","steps":["trace[1958266325] 'process raft request' (duration: 130.286833ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:27.977772Z","caller":"traceutil/trace.go:171","msg":"trace[1846206961] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"122.612948ms","start":"2026-05-08T18:17:27.855115Z","end":"2026-05-08T18:17:27.977728Z","steps":["trace[1846206961] 'process raft request' (duration: 122.437607ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:27.97784Z","caller":"traceutil/trace.go:171","msg":"trace[1924885189] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"122.520168ms","start":"2026-05-08T18:17:27.855309Z","end":"2026-05-08T18:17:27.977829Z","steps":["trace[1924885189] 'process raft request' (duration: 122.359496ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:27.97788Z","caller":"traceutil/trace.go:171","msg":"trace[576185593] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"118.033879ms","start":"2026-05-08T18:17:27.859819Z","end":"2026-05-08T18:17:27.977853Z","steps":["trace[576185593] 'process raft request' (duration: 117.884377ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:27.977918Z","caller":"traceutil/trace.go:171","msg":"trace[317069847] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"122.74229ms","start":"2026-05-08T18:17:27.855167Z","end":"2026-05-08T18:17:27.97791Z","steps":["trace[317069847] 'process raft request' (duration: 122.417077ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:27.977994Z","caller":"traceutil/trace.go:171","msg":"trace[1740725620] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"104.828053ms","start":"2026-05-08T18:17:27.873159Z","end":"2026-05-08T18:17:27.977987Z","steps":["trace[1740725620] 'process raft request' (duration: 104.616651ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:27.978077Z","caller":"traceutil/trace.go:171","msg":"trace[203163048] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"117.955078ms","start":"2026-05-08T18:17:27.859942Z","end":"2026-05-08T18:17:27.977897Z","steps":["trace[203163048] 'process raft request' (duration: 117.793197ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:32.015926Z","caller":"traceutil/trace.go:171","msg":"trace[1645479921] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"168.796198ms","start":"2026-05-08T18:17:31.847099Z","end":"2026-05-08T18:17:32.015895Z","steps":["trace[1645479921] 'process raft request' (duration: 166.233451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:34.19737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.272496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:17:34.197559Z","caller":"traceutil/trace.go:171","msg":"trace[1000600757] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"109.072347ms","start":"2026-05-08T18:17:34.088467Z","end":"2026-05-08T18:17:34.197539Z","steps":["trace[1000600757] 'compare' (duration: 101.149704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:35.816742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079350017188647432,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:17:35.861788Z","caller":"traceutil/trace.go:171","msg":"trace[526129994] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"577.847047ms","start":"2026-05-08T18:17:35.283913Z","end":"2026-05-08T18:17:35.86176Z","steps":["trace[526129994] 'process raft request' (duration: 577.594595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:35.862016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:17:35.283894Z","time spent":"578.04149ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:17:35.999475Z","caller":"traceutil/trace.go:171","msg":"trace[1050608478] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"682.788112ms","start":"2026-05-08T18:17:35.316656Z","end":"2026-05-08T18:17:35.999444Z","steps":["trace[1050608478] 'read index received' (duration: 544.985231ms)","trace[1050608478] 'applied index is now lower than readState.Index' (duration: 137.801271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:17:35.999621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.949834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:17:35.999702Z","caller":"traceutil/trace.go:171","msg":"trace[1723377028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"683.173895ms","start":"2026-05-08T18:17:35.316503Z","end":"2026-05-08T18:17:35.999676Z","steps":["trace[1723377028] 'agreement among raft nodes before linearized reading' (duration: 683.041994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:35.999751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:17:35.316478Z","time spent":"683.265917ms","remote":"127.0.0.1:55174","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-05-08T18:17:35.999786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.312903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T18:17:35.999855Z","caller":"traceutil/trace.go:171","msg":"trace[614043989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"624.552915ms","start":"2026-05-08T18:17:35.375285Z","end":"2026-05-08T18:17:35.999838Z","steps":["trace[614043989] 'agreement among raft nodes before linearized reading' (duration: 624.319343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:35.999903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:17:35.375264Z","time spent":"624.630467ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:17:36.694768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.714829ms","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-05-08T18:17:36.69484Z","caller":"traceutil/trace.go:171","msg":"trace[625350295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"201.83352ms","start":"2026-05-08T18:17:36.492995Z","end":"2026-05-08T18:17:36.694828Z","steps":["trace[625350295] 'range keys from in-memory index tree' (duration: 201.577258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:36.695225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.447582ms","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-05-08T18:17:36.695352Z","caller":"traceutil/trace.go:171","msg":"trace[271597591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"493.945516ms","start":"2026-05-08T18:17:36.201381Z","end":"2026-05-08T18:17:36.695327Z","steps":["trace[271597591] 'range keys from in-memory index tree' (duration: 493.26296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:36.695407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:17:36.201359Z","time spent":"494.034428ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T18:17:38.748142Z","caller":"traceutil/trace.go:171","msg":"trace[1133051106] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"117.278062ms","start":"2026-05-08T18:17:38.630839Z","end":"2026-05-08T18:17:38.748117Z","steps":["trace[1133051106] 'process raft request' (duration: 116.965099ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:18:19.23418Z","caller":"traceutil/trace.go:171","msg":"trace[381480821] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"175.347965ms","start":"2026-05-08T18:18:19.058807Z","end":"2026-05-08T18:18:19.234155Z","steps":["trace[381480821] 'process raft request' (duration: 175.169989ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:18:19.235943Z","caller":"traceutil/trace.go:171","msg":"trace[2141256079] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"175.593013ms","start":"2026-05-08T18:18:19.060315Z","end":"2026-05-08T18:18:19.235908Z","steps":["trace[2141256079] 'process raft request' (duration: 175.415467ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:06.094775Z","caller":"traceutil/trace.go:171","msg":"trace[974960534] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"382.153791ms","start":"2026-05-08T18:19:05.712596Z","end":"2026-05-08T18:19:06.09475Z","steps":["trace[974960534] 'read index received' (duration: 325.535605ms)","trace[974960534] 'applied index is now lower than readState.Index' (duration: 56.616686ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:06.094985Z","caller":"traceutil/trace.go:171","msg":"trace[1949023210] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"303.811462ms","start":"2026-05-08T18:19:05.791088Z","end":"2026-05-08T18:19:06.094899Z","steps":["trace[1949023210] 'process raft request' (duration: 303.511726ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:06.095119Z","caller":"traceutil/trace.go:171","msg":"trace[1782437606] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"116.016434ms","start":"2026-05-08T18:19:05.978999Z","end":"2026-05-08T18:19:06.095015Z","steps":["trace[1782437606] 'process raft request' (duration: 115.729748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:06.095183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.481247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1cdb30af-3d72-4f17-9c4f-d97b33e5dbc7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-08T18:19:06.095608Z","caller":"traceutil/trace.go:171","msg":"trace[127752400] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1cdb30af-3d72-4f17-9c4f-d97b33e5dbc7; range_end:; response_count:1; response_revision:1862; }","duration":"383.004558ms","start":"2026-05-08T18:19:05.712555Z","end":"2026-05-08T18:19:06.095559Z","steps":["trace[127752400] 'agreement among raft nodes before linearized reading' (duration: 382.355784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:06.095639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:05.791044Z","time spent":"304.116008ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:19:06.095671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:05.712536Z","time spent":"383.119591ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1cdb30af-3d72-4f17-9c4f-d97b33e5dbc7\" "} {"level":"info","ts":"2026-05-08T18:19:06.216792Z","caller":"traceutil/trace.go:171","msg":"trace[2099404854] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"113.815836ms","start":"2026-05-08T18:19:06.102938Z","end":"2026-05-08T18:19:06.216754Z","steps":["trace[2099404854] 'process raft request' (duration: 113.477588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:06.703827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.912007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:19:06.703923Z","caller":"traceutil/trace.go:171","msg":"trace[1616605173] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1863; }","duration":"364.05843ms","start":"2026-05-08T18:19:06.339845Z","end":"2026-05-08T18:19:06.703904Z","steps":["trace[1616605173] 'count revisions from in-memory index tree' (duration: 363.832706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:06.703971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:06.339824Z","time spent":"364.135692ms","remote":"127.0.0.1:55282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":20,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-08T18:19:06.704124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.272753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:19:06.704189Z","caller":"traceutil/trace.go:171","msg":"trace[243550033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1863; }","duration":"387.401955ms","start":"2026-05-08T18:19:06.316771Z","end":"2026-05-08T18:19:06.704173Z","steps":["trace[243550033] 'range keys from in-memory index tree' (duration: 387.18007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:06.704222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:06.316749Z","time spent":"387.463866ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:19:06.710031Z","caller":"traceutil/trace.go:171","msg":"trace[1546797071] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"202.269825ms","start":"2026-05-08T18:19:06.507741Z","end":"2026-05-08T18:19:06.710011Z","steps":["trace[1546797071] 'read index received' (duration: 202.067201ms)","trace[1546797071] 'applied index is now lower than readState.Index' (duration: 201.894µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:06.710099Z","caller":"traceutil/trace.go:171","msg":"trace[1338124618] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"226.174409ms","start":"2026-05-08T18:19:06.483899Z","end":"2026-05-08T18:19:06.710074Z","steps":["trace[1338124618] 'process raft request' (duration: 225.980155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:06.710192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.212572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:19:06.710236Z","caller":"traceutil/trace.go:171","msg":"trace[109565814] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1864; }","duration":"138.277994ms","start":"2026-05-08T18:19:06.571948Z","end":"2026-05-08T18:19:06.710226Z","steps":["trace[109565814] 'agreement among raft nodes before linearized reading' (duration: 138.192742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:06.710279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.5371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T18:19:06.710336Z","caller":"traceutil/trace.go:171","msg":"trace[840076185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1864; }","duration":"202.627042ms","start":"2026-05-08T18:19:06.507693Z","end":"2026-05-08T18:19:06.71032Z","steps":["trace[840076185] 'agreement among raft nodes before linearized reading' (duration: 202.444449ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:18.403601Z","caller":"traceutil/trace.go:171","msg":"trace[1320494896] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"267.693534ms","start":"2026-05-08T18:19:18.135882Z","end":"2026-05-08T18:19:18.403575Z","steps":["trace[1320494896] 'process raft request' (duration: 267.531331ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:18.410881Z","caller":"traceutil/trace.go:171","msg":"trace[1174567013] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2034; }","duration":"103.532934ms","start":"2026-05-08T18:19:18.307328Z","end":"2026-05-08T18:19:18.410861Z","steps":["trace[1174567013] 'read index received' (duration: 96.191352ms)","trace[1174567013] 'applied index is now lower than readState.Index' (duration: 7.340712ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:18.410929Z","caller":"traceutil/trace.go:171","msg":"trace[587918648] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"274.969296ms","start":"2026-05-08T18:19:18.135932Z","end":"2026-05-08T18:19:18.410901Z","steps":["trace[587918648] 'process raft request' (duration: 274.780261ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:18.411008Z","caller":"traceutil/trace.go:171","msg":"trace[560079001] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"272.332954ms","start":"2026-05-08T18:19:18.138645Z","end":"2026-05-08T18:19:18.410978Z","steps":["trace[560079001] 'process raft request' (duration: 272.17615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:18.411114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.767479ms","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-05-08T18:19:18.411155Z","caller":"traceutil/trace.go:171","msg":"trace[1928551603] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1965; }","duration":"103.866591ms","start":"2026-05-08T18:19:18.307274Z","end":"2026-05-08T18:19:18.41114Z","steps":["trace[1928551603] 'agreement among raft nodes before linearized reading' (duration: 103.719948ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:45.055975Z","caller":"traceutil/trace.go:171","msg":"trace[1215026479] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"170.928137ms","start":"2026-05-08T18:19:44.885025Z","end":"2026-05-08T18:19:45.055953Z","steps":["trace[1215026479] 'read index received' (duration: 170.710094ms)","trace[1215026479] 'applied index is now lower than readState.Index' (duration: 217.223µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:19:45.056431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.382574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T18:19:45.056564Z","caller":"traceutil/trace.go:171","msg":"trace[407323002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2123; }","duration":"171.561058ms","start":"2026-05-08T18:19:44.88499Z","end":"2026-05-08T18:19:45.056551Z","steps":["trace[407323002] 'agreement among raft nodes before linearized reading' (duration: 171.267713ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:45.057126Z","caller":"traceutil/trace.go:171","msg":"trace[2081559582] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"178.782077ms","start":"2026-05-08T18:19:44.878329Z","end":"2026-05-08T18:19:45.057112Z","steps":["trace[2081559582] 'process raft request' (duration: 177.465906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:45.902931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.578298ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-08T18:19:45.903276Z","caller":"traceutil/trace.go:171","msg":"trace[751106012] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2204; }","duration":"243.713992ms","start":"2026-05-08T18:19:45.659459Z","end":"2026-05-08T18:19:45.903173Z","steps":["trace[751106012] 'read index received' (duration: 71.951µs)","trace[751106012] 'applied index is now lower than readState.Index' (duration: 243.640351ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:19:45.903707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.006298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:19:45.903847Z","caller":"traceutil/trace.go:171","msg":"trace[1328532560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2127; }","duration":"244.1473ms","start":"2026-05-08T18:19:45.659654Z","end":"2026-05-08T18:19:45.903801Z","steps":["trace[1328532560] 'agreement among raft nodes before linearized reading' (duration: 243.883786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:45.903884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.1841ms","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-05-08T18:19:45.904037Z","caller":"traceutil/trace.go:171","msg":"trace[1780753342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2127; }","duration":"233.470036ms","start":"2026-05-08T18:19:45.670538Z","end":"2026-05-08T18:19:45.904008Z","steps":["trace[1780753342] 'agreement among raft nodes before linearized reading' (duration: 233.045039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:45.904136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.672729ms","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-05-08T18:19:45.904204Z","caller":"traceutil/trace.go:171","msg":"trace[1069096262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2127; }","duration":"244.77627ms","start":"2026-05-08T18:19:45.659411Z","end":"2026-05-08T18:19:45.904187Z","steps":["trace[1069096262] 'agreement among raft nodes before linearized reading' (duration: 244.061049ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:46.081432Z","caller":"traceutil/trace.go:171","msg":"trace[1257548815] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"171.703064ms","start":"2026-05-08T18:19:45.909695Z","end":"2026-05-08T18:19:46.081398Z","steps":["trace[1257548815] 'process raft request' (duration: 171.424389ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:46.081641Z","caller":"traceutil/trace.go:171","msg":"trace[1013472106] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"171.673413ms","start":"2026-05-08T18:19:45.909893Z","end":"2026-05-08T18:19:46.081566Z","steps":["trace[1013472106] 'process raft request' (duration: 171.44154ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:46.674536Z","caller":"traceutil/trace.go:171","msg":"trace[617806125] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"181.141459ms","start":"2026-05-08T18:19:46.493369Z","end":"2026-05-08T18:19:46.674511Z","steps":["trace[617806125] 'process raft request' (duration: 181.000807ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:46.861276Z","caller":"traceutil/trace.go:171","msg":"trace[1363687750] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2208; }","duration":"252.950173ms","start":"2026-05-08T18:19:46.608302Z","end":"2026-05-08T18:19:46.861252Z","steps":["trace[1363687750] 'read index received' (duration: 66.023549ms)","trace[1363687750] 'applied index is now lower than readState.Index' (duration: 186.925614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:19:46.861496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.174206ms","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-05-08T18:19:46.861544Z","caller":"traceutil/trace.go:171","msg":"trace[2114175325] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2133; }","duration":"253.275019ms","start":"2026-05-08T18:19:46.608252Z","end":"2026-05-08T18:19:46.861527Z","steps":["trace[2114175325] 'agreement among raft nodes before linearized reading' (duration: 253.093945ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:46.861469Z","caller":"traceutil/trace.go:171","msg":"trace[2103737139] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"366.615988ms","start":"2026-05-08T18:19:46.49469Z","end":"2026-05-08T18:19:46.861306Z","steps":["trace[2103737139] 'process raft request' (duration: 345.675007ms)","trace[2103737139] 'compare' (duration: 20.500604ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:46.861372Z","caller":"traceutil/trace.go:171","msg":"trace[560633896] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"366.387495ms","start":"2026-05-08T18:19:46.494935Z","end":"2026-05-08T18:19:46.861323Z","steps":["trace[560633896] 'process raft request' (duration: 366.217952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:46.862084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:46.494666Z","time spent":"367.305811ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:19:46.862442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:46.494902Z","time spent":"367.24267ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:19:47.218478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.273339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:19:47.21857Z","caller":"traceutil/trace.go:171","msg":"trace[881029843] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2133; }","duration":"167.444272ms","start":"2026-05-08T18:19:47.051106Z","end":"2026-05-08T18:19:47.218551Z","steps":["trace[881029843] 'count revisions from in-memory index tree' (duration: 167.176057ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:47.219482Z","caller":"traceutil/trace.go:171","msg":"trace[1342897274] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2211; }","duration":"145.324363ms","start":"2026-05-08T18:19:47.074129Z","end":"2026-05-08T18:19:47.219454Z","steps":["trace[1342897274] 'read index received' (duration: 145.074179ms)","trace[1342897274] 'applied index is now lower than readState.Index' (duration: 248.994µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:47.219527Z","caller":"traceutil/trace.go:171","msg":"trace[984393063] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"157.167434ms","start":"2026-05-08T18:19:47.06234Z","end":"2026-05-08T18:19:47.219507Z","steps":["trace[984393063] 'process raft request' (duration: 156.929951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:47.219744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.599207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T18:19:47.219784Z","caller":"traceutil/trace.go:171","msg":"trace[297304319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2134; }","duration":"145.702439ms","start":"2026-05-08T18:19:47.074072Z","end":"2026-05-08T18:19:47.219774Z","steps":["trace[297304319] 'agreement among raft nodes before linearized reading' (duration: 145.529456ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:35.507341Z","caller":"traceutil/trace.go:171","msg":"trace[1054751581] linearizableReadLoop","detail":"{readStateIndex:2590; appliedIndex:2589; }","duration":"131.461051ms","start":"2026-05-08T18:20:35.375858Z","end":"2026-05-08T18:20:35.507319Z","steps":["trace[1054751581] 'read index received' (duration: 131.33862ms)","trace[1054751581] 'applied index is now lower than readState.Index' (duration: 121.311µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:20:35.507473Z","caller":"traceutil/trace.go:171","msg":"trace[221844047] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"137.195628ms","start":"2026-05-08T18:20:35.370263Z","end":"2026-05-08T18:20:35.507458Z","steps":["trace[221844047] 'process raft request' (duration: 136.933145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:20:35.507776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.899217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-08T18:20:35.507819Z","caller":"traceutil/trace.go:171","msg":"trace[1425581670] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2501; }","duration":"131.974289ms","start":"2026-05-08T18:20:35.375833Z","end":"2026-05-08T18:20:35.507807Z","steps":["trace[1425581670] 'agreement among raft nodes before linearized reading' (duration: 131.830477ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:21:08.587469Z","caller":"traceutil/trace.go:171","msg":"trace[109144398] linearizableReadLoop","detail":"{readStateIndex:2780; appliedIndex:2779; }","duration":"121.421843ms","start":"2026-05-08T18:21:08.466025Z","end":"2026-05-08T18:21:08.587447Z","steps":["trace[109144398] 'read index received' (duration: 121.235981ms)","trace[109144398] 'applied index is now lower than readState.Index' (duration: 185.052µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:21:08.587737Z","caller":"traceutil/trace.go:171","msg":"trace[482515088] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"185.408285ms","start":"2026-05-08T18:21:08.402316Z","end":"2026-05-08T18:21:08.587725Z","steps":["trace[482515088] 'process raft request' (duration: 184.9948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:21:08.58807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.036501ms","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-05-08T18:21:08.588178Z","caller":"traceutil/trace.go:171","msg":"trace[2123745107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2683; }","duration":"122.150193ms","start":"2026-05-08T18:21:08.465967Z","end":"2026-05-08T18:21:08.588117Z","steps":["trace[2123745107] 'agreement among raft nodes before linearized reading' (duration: 121.96616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:21:08.589041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.870514ms","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-05-08T18:21:08.589132Z","caller":"traceutil/trace.go:171","msg":"trace[1762419578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2683; }","duration":"119.984456ms","start":"2026-05-08T18:21:08.469128Z","end":"2026-05-08T18:21:08.589113Z","steps":["trace[1762419578] 'agreement among raft nodes before linearized reading' (duration: 119.704322ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:21:08.704996Z","caller":"traceutil/trace.go:171","msg":"trace[1663977749] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"111.929615ms","start":"2026-05-08T18:21:08.593037Z","end":"2026-05-08T18:21:08.704966Z","steps":["trace[1663977749] 'process raft request' (duration: 111.820004ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:21:08.706579Z","caller":"traceutil/trace.go:171","msg":"trace[1662353986] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"113.286353ms","start":"2026-05-08T18:21:08.593282Z","end":"2026-05-08T18:21:08.706568Z","steps":["trace[1662353986] 'process raft request' (duration: 113.09016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:34.582429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.010289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:34.582713Z","caller":"traceutil/trace.go:171","msg":"trace[159730612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3135; }","duration":"101.345421ms","start":"2026-05-08T18:22:34.48125Z","end":"2026-05-08T18:22:34.582595Z","steps":["trace[159730612] 'range keys from in-memory index tree' (duration: 100.848864ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:23:33.545039Z","caller":"traceutil/trace.go:171","msg":"trace[2100804701] transaction","detail":"{read_only:false; response_revision:3534; number_of_response:1; }","duration":"105.965539ms","start":"2026-05-08T18:23:33.439053Z","end":"2026-05-08T18:23:33.545019Z","steps":["trace[2100804701] 'process raft request' (duration: 105.833725ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:04.335107Z","caller":"traceutil/trace.go:171","msg":"trace[2086479764] transaction","detail":"{read_only:false; response_revision:3734; number_of_response:1; }","duration":"267.492533ms","start":"2026-05-08T18:24:04.067594Z","end":"2026-05-08T18:24:04.335087Z","steps":["trace[2086479764] 'process raft request' (duration: 267.369439ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:04.337273Z","caller":"traceutil/trace.go:171","msg":"trace[1110019904] transaction","detail":"{read_only:false; response_revision:3735; number_of_response:1; }","duration":"183.552868ms","start":"2026-05-08T18:24:04.153695Z","end":"2026-05-08T18:24:04.337248Z","steps":["trace[1110019904] 'process raft request' (duration: 183.391114ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:04.534578Z","caller":"traceutil/trace.go:171","msg":"trace[519141542] transaction","detail":"{read_only:false; response_revision:3736; number_of_response:1; }","duration":"151.706568ms","start":"2026-05-08T18:24:04.382839Z","end":"2026-05-08T18:24:04.534546Z","steps":["trace[519141542] 'process raft request' (duration: 93.170154ms)","trace[519141542] 'compare' (duration: 58.286047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:24:05.122337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.824078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:24:05.122475Z","caller":"traceutil/trace.go:171","msg":"trace[1668078764] linearizableReadLoop","detail":"{readStateIndex:3879; appliedIndex:3878; }","duration":"153.054701ms","start":"2026-05-08T18:24:04.969398Z","end":"2026-05-08T18:24:05.122453Z","steps":["trace[1668078764] 'read index received' (duration: 22.028838ms)","trace[1668078764] 'applied index is now lower than readState.Index' (duration: 131.023703ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:24:05.122674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.250696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:24:05.122802Z","caller":"traceutil/trace.go:171","msg":"trace[551771576] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3739; }","duration":"153.405539ms","start":"2026-05-08T18:24:04.969374Z","end":"2026-05-08T18:24:05.12278Z","steps":["trace[551771576] 'agreement among raft nodes before linearized reading' (duration: 153.227655ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:05.122657Z","caller":"traceutil/trace.go:171","msg":"trace[524780251] transaction","detail":"{read_only:false; response_revision:3739; number_of_response:1; }","duration":"153.608654ms","start":"2026-05-08T18:24:04.96902Z","end":"2026-05-08T18:24:05.122629Z","steps":["trace[524780251] 'process raft request' (duration: 22.369806ms)","trace[524780251] 'compare' (duration: 130.315535ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:05.128266Z","caller":"traceutil/trace.go:171","msg":"trace[1300801882] transaction","detail":"{read_only:false; response_revision:3740; number_of_response:1; }","duration":"117.647311ms","start":"2026-05-08T18:24:05.010595Z","end":"2026-05-08T18:24:05.128242Z","steps":["trace[1300801882] 'process raft request' (duration: 117.395715ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:43.559016Z","caller":"traceutil/trace.go:171","msg":"trace[1589687826] transaction","detail":"{read_only:false; response_revision:4006; number_of_response:1; }","duration":"146.146279ms","start":"2026-05-08T18:24:43.412844Z","end":"2026-05-08T18:24:43.558991Z","steps":["trace[1589687826] 'process raft request' (duration: 93.539813ms)","trace[1589687826] 'compare' (duration: 52.498643ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:48.832478Z","caller":"traceutil/trace.go:171","msg":"trace[1671294672] transaction","detail":"{read_only:false; response_revision:4335; number_of_response:1; }","duration":"124.013155ms","start":"2026-05-08T18:24:48.708441Z","end":"2026-05-08T18:24:48.832454Z","steps":["trace[1671294672] 'process raft request' (duration: 123.865652ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:48.853695Z","caller":"traceutil/trace.go:171","msg":"trace[242643003] transaction","detail":"{read_only:false; response_revision:4336; number_of_response:1; }","duration":"133.986451ms","start":"2026-05-08T18:24:48.719683Z","end":"2026-05-08T18:24:48.85367Z","steps":["trace[242643003] 'process raft request' (duration: 133.511891ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:50.888608Z","caller":"traceutil/trace.go:171","msg":"trace[1927992684] transaction","detail":"{read_only:false; response_revision:4380; number_of_response:1; }","duration":"121.149398ms","start":"2026-05-08T18:24:50.767421Z","end":"2026-05-08T18:24:50.888571Z","steps":["trace[1927992684] 'process raft request' (duration: 77.669312ms)","trace[1927992684] 'compare' (duration: 42.908683ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:53.299101Z","caller":"traceutil/trace.go:171","msg":"trace[279060404] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"107.880732ms","start":"2026-05-08T18:24:53.191207Z","end":"2026-05-08T18:24:53.299088Z","steps":["trace[279060404] 'process raft request' (duration: 107.80442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:54.04201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.333592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T18:24:54.042102Z","caller":"traceutil/trace.go:171","msg":"trace[1886573184] linearizableReadLoop","detail":"{readStateIndex:4649; appliedIndex:4648; }","duration":"491.97024ms","start":"2026-05-08T18:24:53.550118Z","end":"2026-05-08T18:24:54.042088Z","steps":["trace[1886573184] 'read index received' (duration: 70.24506ms)","trace[1886573184] 'applied index is now lower than readState.Index' (duration: 421.72393ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:54.042247Z","caller":"traceutil/trace.go:171","msg":"trace[656427373] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4481; }","duration":"495.206589ms","start":"2026-05-08T18:24:53.54702Z","end":"2026-05-08T18:24:54.042227Z","steps":["trace[656427373] 'process raft request' (duration: 73.451948ms)","trace[656427373] 'compare' (duration: 421.22882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:24:54.042398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:53.547004Z","time spent":"495.29845ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-05-08T18:24:54.042551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.407329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-05-08T18:24:54.042648Z","caller":"traceutil/trace.go:171","msg":"trace[1977887817] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4481; }","duration":"492.529042ms","start":"2026-05-08T18:24:53.5501Z","end":"2026-05-08T18:24:54.042629Z","steps":["trace[1977887817] 'agreement among raft nodes before linearized reading' (duration: 492.247365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:54.042692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:53.550091Z","time spent":"492.589983ms","remote":"127.0.0.1:55282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1365,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-05-08T18:24:54.075093Z","caller":"traceutil/trace.go:171","msg":"trace[1208296055] transaction","detail":"{read_only:false; response_revision:4482; number_of_response:1; }","duration":"432.270683ms","start":"2026-05-08T18:24:53.642793Z","end":"2026-05-08T18:24:54.075064Z","steps":["trace[1208296055] 'process raft request' (duration: 431.63036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:54.075441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:53.642762Z","time spent":"432.58909ms","remote":"127.0.0.1:55282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":452258,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:24:54.084136Z","caller":"traceutil/trace.go:171","msg":"trace[2112177494] transaction","detail":"{read_only:false; response_revision:4483; number_of_response:1; }","duration":"122.669533ms","start":"2026-05-08T18:24:53.96145Z","end":"2026-05-08T18:24:54.08412Z","steps":["trace[2112177494] 'process raft request' (duration: 122.503499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:54.084567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.559106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-314885b3-8996-4198-b92f-862ddf28ac6e\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-05-08T18:24:54.084589Z","caller":"traceutil/trace.go:171","msg":"trace[452415588] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-314885b3-8996-4198-b92f-862ddf28ac6e; range_end:; response_count:1; response_revision:4483; }","duration":"296.653618ms","start":"2026-05-08T18:24:53.787927Z","end":"2026-05-08T18:24:54.084581Z","steps":["trace[452415588] 'agreement among raft nodes before linearized reading' (duration: 296.586936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:54.08471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.214236ms","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-05-08T18:24:54.084724Z","caller":"traceutil/trace.go:171","msg":"trace[1162089145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4483; }","duration":"464.276197ms","start":"2026-05-08T18:24:53.620443Z","end":"2026-05-08T18:24:54.084719Z","steps":["trace[1162089145] 'agreement among raft nodes before linearized reading' (duration: 464.220216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:54.084739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:53.620426Z","time spent":"464.309328ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T18:24:54.273844Z","caller":"traceutil/trace.go:171","msg":"trace[1517302520] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"184.55079ms","start":"2026-05-08T18:24:54.089269Z","end":"2026-05-08T18:24:54.27382Z","steps":["trace[1517302520] 'process raft request' (duration: 96.946355ms)","trace[1517302520] 'compare' (duration: 87.412491ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:54.274042Z","caller":"traceutil/trace.go:171","msg":"trace[1122888479] linearizableReadLoop","detail":"{readStateIndex:4653; appliedIndex:4651; }","duration":"178.4993ms","start":"2026-05-08T18:24:54.095535Z","end":"2026-05-08T18:24:54.274034Z","steps":["trace[1122888479] 'read index received' (duration: 90.750482ms)","trace[1122888479] 'applied index is now lower than readState.Index' (duration: 87.748238ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:54.27416Z","caller":"traceutil/trace.go:171","msg":"trace[393907530] transaction","detail":"{read_only:false; response_revision:4485; number_of_response:1; }","duration":"179.501422ms","start":"2026-05-08T18:24:54.094652Z","end":"2026-05-08T18:24:54.274153Z","steps":["trace[393907530] 'process raft request' (duration: 179.123314ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:54.274331Z","caller":"traceutil/trace.go:171","msg":"trace[885056229] transaction","detail":"{read_only:false; response_revision:4486; number_of_response:1; }","duration":"178.693235ms","start":"2026-05-08T18:24:54.095597Z","end":"2026-05-08T18:24:54.27429Z","steps":["trace[885056229] 'process raft request' (duration: 178.404669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:54.274387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.703315ms","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-05-08T18:24:54.274372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.810927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-05-08T18:24:54.274416Z","caller":"traceutil/trace.go:171","msg":"trace[2010140488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4486; }","duration":"178.778916ms","start":"2026-05-08T18:24:54.09563Z","end":"2026-05-08T18:24:54.274409Z","steps":["trace[2010140488] 'agreement among raft nodes before linearized reading' (duration: 178.652013ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:54.274442Z","caller":"traceutil/trace.go:171","msg":"trace[1188032187] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4486; }","duration":"178.904168ms","start":"2026-05-08T18:24:54.09552Z","end":"2026-05-08T18:24:54.274424Z","steps":["trace[1188032187] 'agreement among raft nodes before linearized reading' (duration: 178.716834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:54.274499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.842344ms","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-05-08T18:24:54.274533Z","caller":"traceutil/trace.go:171","msg":"trace[2091216792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4486; }","duration":"129.923025ms","start":"2026-05-08T18:24:54.1446Z","end":"2026-05-08T18:24:54.274523Z","steps":["trace[2091216792] 'agreement among raft nodes before linearized reading' (duration: 129.825493ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:56.611286Z","caller":"traceutil/trace.go:171","msg":"trace[1069123285] linearizableReadLoop","detail":"{readStateIndex:4679; appliedIndex:4678; }","duration":"253.434347ms","start":"2026-05-08T18:24:56.357825Z","end":"2026-05-08T18:24:56.611259Z","steps":["trace[1069123285] 'read index received' (duration: 185.268604ms)","trace[1069123285] 'applied index is now lower than readState.Index' (duration: 68.164823ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:56.611382Z","caller":"traceutil/trace.go:171","msg":"trace[823174391] transaction","detail":"{read_only:false; response_revision:4510; number_of_response:1; }","duration":"259.27386ms","start":"2026-05-08T18:24:56.352098Z","end":"2026-05-08T18:24:56.611372Z","steps":["trace[823174391] 'process raft request' (duration: 191.106887ms)","trace[823174391] 'compare' (duration: 67.885077ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:24:56.611475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.161452ms","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-05-08T18:24:56.611502Z","caller":"traceutil/trace.go:171","msg":"trace[1314397852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4510; }","duration":"253.241064ms","start":"2026-05-08T18:24:56.358254Z","end":"2026-05-08T18:24:56.611495Z","steps":["trace[1314397852] 'agreement among raft nodes before linearized reading' (duration: 253.133301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:56.611619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.790385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:24:56.611641Z","caller":"traceutil/trace.go:171","msg":"trace[1252049573] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4510; }","duration":"253.841096ms","start":"2026-05-08T18:24:56.357794Z","end":"2026-05-08T18:24:56.611635Z","steps":["trace[1252049573] 'agreement among raft nodes before linearized reading' (duration: 253.774934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:01.097737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.707793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:25:01.09783Z","caller":"traceutil/trace.go:171","msg":"trace[1648281125] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"274.166509ms","start":"2026-05-08T18:25:00.823652Z","end":"2026-05-08T18:25:01.097819Z","steps":["trace[1648281125] 'process raft request' (duration: 70.323173ms)","trace[1648281125] 'compare' (duration: 203.54069ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:01.127152Z","caller":"traceutil/trace.go:171","msg":"trace[1958472197] linearizableReadLoop","detail":"{readStateIndex:4742; appliedIndex:4741; }","duration":"183.15091ms","start":"2026-05-08T18:25:00.943934Z","end":"2026-05-08T18:25:01.127085Z","steps":["trace[1958472197] 'read index received' (duration: 182.711751ms)","trace[1958472197] 'applied index is now lower than readState.Index' (duration: 437.909µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:01.127604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.652401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:25:01.127658Z","caller":"traceutil/trace.go:171","msg":"trace[1308818969] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:4570; }","duration":"183.758934ms","start":"2026-05-08T18:25:00.943886Z","end":"2026-05-08T18:25:01.127645Z","steps":["trace[1308818969] 'agreement among raft nodes before linearized reading' (duration: 183.612061ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:01.127434Z","caller":"traceutil/trace.go:171","msg":"trace[1457065737] transaction","detail":"{read_only:false; response_revision:4570; number_of_response:1; }","duration":"303.017382ms","start":"2026-05-08T18:25:00.824183Z","end":"2026-05-08T18:25:01.1272Z","steps":["trace[1457065737] 'process raft request' (duration: 302.566882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:01.127982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:00.824149Z","time spent":"303.656566ms","remote":"127.0.0.1:55428","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-05-08T18:25:01.143803Z","caller":"traceutil/trace.go:171","msg":"trace[635316408] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"179.115235ms","start":"2026-05-08T18:25:00.964667Z","end":"2026-05-08T18:25:01.143782Z","steps":["trace[635316408] 'process raft request' (duration: 179.013463ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:03.741096Z","caller":"traceutil/trace.go:171","msg":"trace[1982786402] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"202.98216ms","start":"2026-05-08T18:25:03.538092Z","end":"2026-05-08T18:25:03.741074Z","steps":["trace[1982786402] 'process raft request' (duration: 149.543928ms)","trace[1982786402] 'compare' (duration: 53.249718ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:03.744567Z","caller":"traceutil/trace.go:171","msg":"trace[1342458897] linearizableReadLoop","detail":"{readStateIndex:4825; appliedIndex:4823; }","duration":"204.754146ms","start":"2026-05-08T18:25:03.539797Z","end":"2026-05-08T18:25:03.744551Z","steps":["trace[1342458897] 'read index received' (duration: 147.850173ms)","trace[1342458897] 'applied index is now lower than readState.Index' (duration: 56.903123ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:03.744604Z","caller":"traceutil/trace.go:171","msg":"trace[2018005973] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"205.140394ms","start":"2026-05-08T18:25:03.539446Z","end":"2026-05-08T18:25:03.744586Z","steps":["trace[2018005973] 'process raft request' (duration: 205.009421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:03.744779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.96283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-05-08T18:25:03.74482Z","caller":"traceutil/trace.go:171","msg":"trace[841194096] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4651; }","duration":"205.034482ms","start":"2026-05-08T18:25:03.539774Z","end":"2026-05-08T18:25:03.744809Z","steps":["trace[841194096] 'agreement among raft nodes before linearized reading' (duration: 204.865138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:03.744806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.505391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9318"} {"level":"warn","ts":"2026-05-08T18:25:03.744883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.048433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3296"} {"level":"info","ts":"2026-05-08T18:25:03.744893Z","caller":"traceutil/trace.go:171","msg":"trace[801015650] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4651; }","duration":"203.639994ms","start":"2026-05-08T18:25:03.541242Z","end":"2026-05-08T18:25:03.744882Z","steps":["trace[801015650] 'agreement among raft nodes before linearized reading' (duration: 203.43063ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:03.744923Z","caller":"traceutil/trace.go:171","msg":"trace[367592807] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4651; }","duration":"192.088134ms","start":"2026-05-08T18:25:03.55282Z","end":"2026-05-08T18:25:03.744908Z","steps":["trace[367592807] 'agreement among raft nodes before linearized reading' (duration: 192.023382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:03.74511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.123876ms","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-05-08T18:25:03.745134Z","caller":"traceutil/trace.go:171","msg":"trace[1674096789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4651; }","duration":"194.160266ms","start":"2026-05-08T18:25:03.550968Z","end":"2026-05-08T18:25:03.745128Z","steps":["trace[1674096789] 'agreement among raft nodes before linearized reading' (duration: 194.098915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:03.745161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.805363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7582"} {"level":"info","ts":"2026-05-08T18:25:03.745207Z","caller":"traceutil/trace.go:171","msg":"trace[1116015712] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4651; }","duration":"197.877653ms","start":"2026-05-08T18:25:03.547318Z","end":"2026-05-08T18:25:03.745195Z","steps":["trace[1116015712] 'agreement among raft nodes before linearized reading' (duration: 197.767061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:04.24441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.442895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:25:04.244511Z","caller":"traceutil/trace.go:171","msg":"trace[2100734960] linearizableReadLoop","detail":"{readStateIndex:4837; appliedIndex:4836; }","duration":"221.078504ms","start":"2026-05-08T18:25:04.023413Z","end":"2026-05-08T18:25:04.244491Z","steps":["trace[2100734960] 'read index received' (duration: 116.418544ms)","trace[2100734960] 'applied index is now lower than readState.Index' (duration: 104.65881ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:04.244708Z","caller":"traceutil/trace.go:171","msg":"trace[29268664] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"222.415951ms","start":"2026-05-08T18:25:04.022262Z","end":"2026-05-08T18:25:04.244678Z","steps":["trace[29268664] 'process raft request' (duration: 117.637109ms)","trace[29268664] 'compare' (duration: 104.311623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:04.244786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.93098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-08T18:25:04.244818Z","caller":"traceutil/trace.go:171","msg":"trace[523229554] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4663; }","duration":"220.001041ms","start":"2026-05-08T18:25:04.024806Z","end":"2026-05-08T18:25:04.244807Z","steps":["trace[523229554] 'agreement among raft nodes before linearized reading' (duration: 219.834027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:04.245008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.574456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"warn","ts":"2026-05-08T18:25:04.244996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.559114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-05-08T18:25:04.245055Z","caller":"traceutil/trace.go:171","msg":"trace[647761542] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4663; }","duration":"169.647458ms","start":"2026-05-08T18:25:04.075397Z","end":"2026-05-08T18:25:04.245044Z","steps":["trace[647761542] 'agreement among raft nodes before linearized reading' (duration: 169.529475ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:04.245092Z","caller":"traceutil/trace.go:171","msg":"trace[1504657736] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4663; }","duration":"221.668136ms","start":"2026-05-08T18:25:04.023402Z","end":"2026-05-08T18:25:04.24507Z","steps":["trace[1504657736] 'agreement among raft nodes before linearized reading' (duration: 221.427631ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:04.947124Z","caller":"traceutil/trace.go:171","msg":"trace[1003976063] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4849; }","duration":"126.945181ms","start":"2026-05-08T18:25:04.820162Z","end":"2026-05-08T18:25:04.947107Z","steps":["trace[1003976063] 'read index received' (duration: 52.396215ms)","trace[1003976063] 'applied index is now lower than readState.Index' (duration: 74.548266ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:04.947192Z","caller":"traceutil/trace.go:171","msg":"trace[1067750433] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"173.460917ms","start":"2026-05-08T18:25:04.77368Z","end":"2026-05-08T18:25:04.947141Z","steps":["trace[1067750433] 'process raft request' (duration: 98.937881ms)","trace[1067750433] 'compare' (duration: 74.413514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:04.947256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.091845ms","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-05-08T18:25:04.947282Z","caller":"traceutil/trace.go:171","msg":"trace[1602230195] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4676; }","duration":"127.162056ms","start":"2026-05-08T18:25:04.820111Z","end":"2026-05-08T18:25:04.947273Z","steps":["trace[1602230195] 'agreement among raft nodes before linearized reading' (duration: 127.083984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:05.740486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.026645ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-08T18:25:07.012735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.557601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:25:07.012815Z","caller":"traceutil/trace.go:171","msg":"trace[1671766414] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4688; }","duration":"494.722454ms","start":"2026-05-08T18:25:06.518074Z","end":"2026-05-08T18:25:07.012796Z","steps":["trace[1671766414] 'count revisions from in-memory index tree' (duration: 494.461778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:07.012878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:06.518055Z","time spent":"494.793455ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-08T18:25:07.013037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.768907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18adaa5390182fae\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-05-08T18:25:07.013151Z","caller":"traceutil/trace.go:171","msg":"trace[1574866983] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18adaa5390182fae; range_end:; response_count:1; response_revision:4688; }","duration":"160.94647ms","start":"2026-05-08T18:25:06.852187Z","end":"2026-05-08T18:25:07.013133Z","steps":["trace[1574866983] 'range keys from in-memory index tree' (duration: 160.597272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:07.01327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.216035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-08T18:25:07.013358Z","caller":"traceutil/trace.go:171","msg":"trace[1958380708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4688; }","duration":"352.367658ms","start":"2026-05-08T18:25:06.660971Z","end":"2026-05-08T18:25:07.013338Z","steps":["trace[1958380708] 'range keys from in-memory index tree' (duration: 351.96172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:07.01343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:06.660958Z","time spent":"352.441019ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T18:25:07.013657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.081953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:25:07.013712Z","caller":"traceutil/trace.go:171","msg":"trace[1521511623] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:4688; }","duration":"162.177435ms","start":"2026-05-08T18:25:06.851522Z","end":"2026-05-08T18:25:07.013699Z","steps":["trace[1521511623] 'count revisions from in-memory index tree' (duration: 162.011942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:07.013916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.636406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-08T18:25:07.014056Z","caller":"traceutil/trace.go:171","msg":"trace[1338238806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4688; }","duration":"357.785279ms","start":"2026-05-08T18:25:06.656215Z","end":"2026-05-08T18:25:07.014Z","steps":["trace[1338238806] 'range keys from in-memory index tree' (duration: 357.424242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:07.014158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:06.656188Z","time spent":"357.913623ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T18:25:10.660157Z","caller":"traceutil/trace.go:171","msg":"trace[608723932] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"128.093105ms","start":"2026-05-08T18:25:10.532041Z","end":"2026-05-08T18:25:10.660134Z","steps":["trace[608723932] 'process raft request' (duration: 116.706303ms)","trace[608723932] 'compare' (duration: 11.27572ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:10.708052Z","caller":"traceutil/trace.go:171","msg":"trace[492793603] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"127.883022ms","start":"2026-05-08T18:25:10.580135Z","end":"2026-05-08T18:25:10.708018Z","steps":["trace[492793603] 'process raft request' (duration: 127.759619ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:10.708611Z","caller":"traceutil/trace.go:171","msg":"trace[1595729857] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"131.184799ms","start":"2026-05-08T18:25:10.57738Z","end":"2026-05-08T18:25:10.708565Z","steps":["trace[1595729857] 'process raft request' (duration: 130.393102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:11.219624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.047191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:25:11.219878Z","caller":"traceutil/trace.go:171","msg":"trace[1352905215] linearizableReadLoop","detail":"{readStateIndex:4922; appliedIndex:4920; }","duration":"240.544331ms","start":"2026-05-08T18:25:10.979316Z","end":"2026-05-08T18:25:11.21986Z","steps":["trace[1352905215] 'read index received' (duration: 51.251µs)","trace[1352905215] 'applied index is now lower than readState.Index' (duration: 240.4919ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:11.220079Z","caller":"traceutil/trace.go:171","msg":"trace[554037370] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"504.334998ms","start":"2026-05-08T18:25:10.715729Z","end":"2026-05-08T18:25:11.220064Z","steps":["trace[554037370] 'process raft request' (duration: 503.994151ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:11.219847Z","caller":"traceutil/trace.go:171","msg":"trace[438306023] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"505.45837ms","start":"2026-05-08T18:25:10.714352Z","end":"2026-05-08T18:25:11.219811Z","steps":["trace[438306023] 'process raft request' (duration: 97.090872ms)","trace[438306023] 'compare' (duration: 407.413579ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:11.220152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:10.715716Z","time spent":"504.399299ms","remote":"127.0.0.1:55336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":991,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:25:11.22037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:10.714337Z","time spent":"505.872609ms","remote":"127.0.0.1:55434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1407,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:25:11.220416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.096311ms","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-05-08T18:25:11.220582Z","caller":"traceutil/trace.go:171","msg":"trace[576312396] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4745; }","duration":"241.288167ms","start":"2026-05-08T18:25:10.979266Z","end":"2026-05-08T18:25:11.220555Z","steps":["trace[576312396] 'agreement among raft nodes before linearized reading' (duration: 240.99553ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:11.289922Z","caller":"traceutil/trace.go:171","msg":"trace[523709107] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"264.13027ms","start":"2026-05-08T18:25:11.025768Z","end":"2026-05-08T18:25:11.289899Z","steps":["trace[523709107] 'process raft request' (duration: 263.963407ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:11.396751Z","caller":"traceutil/trace.go:171","msg":"trace[39814551] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"337.899752ms","start":"2026-05-08T18:25:11.058821Z","end":"2026-05-08T18:25:11.396721Z","steps":["trace[39814551] 'process raft request' (duration: 271.864878ms)","trace[39814551] 'compare' (duration: 65.782829ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:11.396929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:11.05881Z","time spent":"338.047525ms","remote":"127.0.0.1:55346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10548,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:25:11.39706Z","caller":"traceutil/trace.go:171","msg":"trace[2048383820] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"241.420589ms","start":"2026-05-08T18:25:11.155632Z","end":"2026-05-08T18:25:11.397052Z","steps":["trace[2048383820] 'process raft request' (duration: 241.01956ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:11.397168Z","caller":"traceutil/trace.go:171","msg":"trace[252511308] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"239.05267ms","start":"2026-05-08T18:25:11.158103Z","end":"2026-05-08T18:25:11.397155Z","steps":["trace[252511308] 'process raft request' (duration: 238.671141ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:11.39728Z","caller":"traceutil/trace.go:171","msg":"trace[531277431] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"171.712228ms","start":"2026-05-08T18:25:11.225503Z","end":"2026-05-08T18:25:11.397215Z","steps":["trace[531277431] 'process raft request' (duration: 171.314859ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:11.397221Z","caller":"traceutil/trace.go:171","msg":"trace[1491987854] linearizableReadLoop","detail":"{readStateIndex:4926; appliedIndex:4922; }","duration":"177.280512ms","start":"2026-05-08T18:25:11.219916Z","end":"2026-05-08T18:25:11.397196Z","steps":["trace[1491987854] 'read index received' (duration: 69.780531ms)","trace[1491987854] 'applied index is now lower than readState.Index' (duration: 107.498501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:11.397639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.718459ms","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-05-08T18:25:11.39769Z","caller":"traceutil/trace.go:171","msg":"trace[588398339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4750; }","duration":"364.79038ms","start":"2026-05-08T18:25:11.032887Z","end":"2026-05-08T18:25:11.397677Z","steps":["trace[588398339] 'agreement among raft nodes before linearized reading' (duration: 364.609627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:11.397829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:11.032855Z","time spent":"364.954954ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T18:25:11.397773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.895923ms","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-05-08T18:25:11.399985Z","caller":"traceutil/trace.go:171","msg":"trace[160735287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4750; }","duration":"368.17278ms","start":"2026-05-08T18:25:11.031792Z","end":"2026-05-08T18:25:11.399965Z","steps":["trace[160735287] 'agreement among raft nodes before linearized reading' (duration: 365.774931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:11.400034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:11.031771Z","time spent":"368.249961ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T18:25:11.585139Z","caller":"traceutil/trace.go:171","msg":"trace[917228771] linearizableReadLoop","detail":"{readStateIndex:4930; appliedIndex:4929; }","duration":"135.9871ms","start":"2026-05-08T18:25:11.449123Z","end":"2026-05-08T18:25:11.58511Z","steps":["trace[917228771] 'read index received' (duration: 134.195843ms)","trace[917228771] 'applied index is now lower than readState.Index' (duration: 1.789357ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:11.585183Z","caller":"traceutil/trace.go:171","msg":"trace[134882341] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"137.938501ms","start":"2026-05-08T18:25:11.447211Z","end":"2026-05-08T18:25:11.585149Z","steps":["trace[134882341] 'process raft request' (duration: 136.091503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:11.585434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.282076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-08T18:25:11.585484Z","caller":"traceutil/trace.go:171","msg":"trace[413316019] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4753; }","duration":"136.365968ms","start":"2026-05-08T18:25:11.449102Z","end":"2026-05-08T18:25:11.585468Z","steps":["trace[413316019] 'agreement among raft nodes before linearized reading' (duration: 136.121953ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:11.586907Z","caller":"traceutil/trace.go:171","msg":"trace[1455843106] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"131.525309ms","start":"2026-05-08T18:25:11.455357Z","end":"2026-05-08T18:25:11.586882Z","steps":["trace[1455843106] 'process raft request' (duration: 131.401306ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:12.817798Z","caller":"traceutil/trace.go:171","msg":"trace[569133163] linearizableReadLoop","detail":"{readStateIndex:4943; appliedIndex:4942; }","duration":"312.291786ms","start":"2026-05-08T18:25:12.505484Z","end":"2026-05-08T18:25:12.817776Z","steps":["trace[569133163] 'read index received' (duration: 311.148342ms)","trace[569133163] 'applied index is now lower than readState.Index' (duration: 1.142394ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:12.817918Z","caller":"traceutil/trace.go:171","msg":"trace[526237855] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"335.045518ms","start":"2026-05-08T18:25:12.482787Z","end":"2026-05-08T18:25:12.817833Z","steps":["trace[526237855] 'process raft request' (duration: 333.864414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:12.818268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:12.482764Z","time spent":"335.293463ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:25:12.81843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.922278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-08T18:25:12.818505Z","caller":"traceutil/trace.go:171","msg":"trace[1049376004] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4767; }","duration":"313.048301ms","start":"2026-05-08T18:25:12.505437Z","end":"2026-05-08T18:25:12.818485Z","steps":["trace[1049376004] 'agreement among raft nodes before linearized reading' (duration: 312.427609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:12.818549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:12.505415Z","time spent":"313.123442ms","remote":"127.0.0.1:55332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-05-08T18:25:18.183604Z","caller":"traceutil/trace.go:171","msg":"trace[1389034472] linearizableReadLoop","detail":"{readStateIndex:4980; appliedIndex:4979; }","duration":"173.703008ms","start":"2026-05-08T18:25:18.009883Z","end":"2026-05-08T18:25:18.183586Z","steps":["trace[1389034472] 'read index received' (duration: 173.574816ms)","trace[1389034472] 'applied index is now lower than readState.Index' (duration: 127.362µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:18.183721Z","caller":"traceutil/trace.go:171","msg":"trace[473553386] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"242.950248ms","start":"2026-05-08T18:25:17.940744Z","end":"2026-05-08T18:25:18.183695Z","steps":["trace[473553386] 'process raft request' (duration: 242.707213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:18.18376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.853981ms","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-05-08T18:25:18.183788Z","caller":"traceutil/trace.go:171","msg":"trace[626053658] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:4802; }","duration":"173.911252ms","start":"2026-05-08T18:25:18.009867Z","end":"2026-05-08T18:25:18.183778Z","steps":["trace[626053658] 'agreement among raft nodes before linearized reading' (duration: 173.7964ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:18.569162Z","caller":"traceutil/trace.go:171","msg":"trace[1887599567] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"378.665012ms","start":"2026-05-08T18:25:18.190472Z","end":"2026-05-08T18:25:18.569137Z","steps":["trace[1887599567] 'process raft request' (duration: 357.334635ms)","trace[1887599567] 'compare' (duration: 20.868318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:18.569319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:18.190449Z","time spent":"378.770405ms","remote":"127.0.0.1:55434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:25:18.640167Z","caller":"traceutil/trace.go:171","msg":"trace[145283504] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"449.600543ms","start":"2026-05-08T18:25:18.190529Z","end":"2026-05-08T18:25:18.64013Z","steps":["trace[145283504] 'process raft request' (duration: 449.291327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:18.640362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:18.190497Z","time spent":"449.768876ms","remote":"127.0.0.1:55336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":991,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:25:18.640686Z","caller":"traceutil/trace.go:171","msg":"trace[1553857151] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"445.129174ms","start":"2026-05-08T18:25:18.195526Z","end":"2026-05-08T18:25:18.640656Z","steps":["trace[1553857151] 'process raft request' (duration: 444.90359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:18.640914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:18.195435Z","time spent":"445.395919ms","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:25:18.640921Z","caller":"traceutil/trace.go:171","msg":"trace[1217768651] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"216.71125ms","start":"2026-05-08T18:25:18.424176Z","end":"2026-05-08T18:25:18.640888Z","steps":["trace[1217768651] 'process raft request' (duration: 216.362763ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:18.641259Z","caller":"traceutil/trace.go:171","msg":"trace[880344451] linearizableReadLoop","detail":"{readStateIndex:4983; appliedIndex:4980; }","duration":"378.607022ms","start":"2026-05-08T18:25:18.262637Z","end":"2026-05-08T18:25:18.641244Z","steps":["trace[880344451] 'read index received' (duration: 285.12872ms)","trace[880344451] 'applied index is now lower than readState.Index' (duration: 93.476992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:18.64142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.786496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:25:18.641494Z","caller":"traceutil/trace.go:171","msg":"trace[1410454507] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4806; }","duration":"378.914358ms","start":"2026-05-08T18:25:18.262568Z","end":"2026-05-08T18:25:18.641482Z","steps":["trace[1410454507] 'agreement among raft nodes before linearized reading' (duration: 378.808276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:18.641542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.352477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-08T18:25:18.641622Z","caller":"traceutil/trace.go:171","msg":"trace[1581517103] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4806; }","duration":"362.469179ms","start":"2026-05-08T18:25:18.27912Z","end":"2026-05-08T18:25:18.64159Z","steps":["trace[1581517103] 'agreement among raft nodes before linearized reading' (duration: 362.283465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:18.641655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.238509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-08T18:25:18.64169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:18.279095Z","time spent":"362.585841ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-08T18:25:18.641707Z","caller":"traceutil/trace.go:171","msg":"trace[2047563391] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:4806; }","duration":"170.337422ms","start":"2026-05-08T18:25:18.471355Z","end":"2026-05-08T18:25:18.641693Z","steps":["trace[2047563391] 'agreement among raft nodes before linearized reading' (duration: 170.25056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:18.641944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.462004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-05-08T18:25:18.642034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.031717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-08T18:25:18.642072Z","caller":"traceutil/trace.go:171","msg":"trace[31135690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4806; }","duration":"250.101679ms","start":"2026-05-08T18:25:18.39196Z","end":"2026-05-08T18:25:18.642062Z","steps":["trace[31135690] 'agreement among raft nodes before linearized reading' (duration: 250.021657ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:18.642037Z","caller":"traceutil/trace.go:171","msg":"trace[632396718] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4806; }","duration":"146.586796ms","start":"2026-05-08T18:25:18.495437Z","end":"2026-05-08T18:25:18.642024Z","steps":["trace[632396718] 'agreement among raft nodes before linearized reading' (duration: 146.375892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:18.641963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.464505ms","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-05-08T18:25:18.642294Z","caller":"traceutil/trace.go:171","msg":"trace[1330904326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4806; }","duration":"200.797782ms","start":"2026-05-08T18:25:18.441461Z","end":"2026-05-08T18:25:18.642259Z","steps":["trace[1330904326] 'agreement among raft nodes before linearized reading' (duration: 200.428414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:18.641565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:18.262543Z","time spent":"379.009871ms","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-05-08T18:25:19.540735Z","caller":"traceutil/trace.go:171","msg":"trace[868201204] linearizableReadLoop","detail":"{readStateIndex:4990; appliedIndex:4989; }","duration":"483.245154ms","start":"2026-05-08T18:25:19.057441Z","end":"2026-05-08T18:25:19.540686Z","steps":["trace[868201204] 'read index received' (duration: 460.688322ms)","trace[868201204] 'applied index is now lower than readState.Index' (duration: 22.555862ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:19.541041Z","caller":"traceutil/trace.go:171","msg":"trace[1646366170] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"758.511654ms","start":"2026-05-08T18:25:18.782515Z","end":"2026-05-08T18:25:19.541027Z","steps":["trace[1646366170] 'process raft request' (duration: 735.602325ms)","trace[1646366170] 'compare' (duration: 22.425038ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:19.54119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:18.782434Z","time spent":"758.638967ms","remote":"127.0.0.1:55268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:25:19.541423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.968549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:25:19.541457Z","caller":"traceutil/trace.go:171","msg":"trace[1339018035] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4812; }","duration":"484.0592ms","start":"2026-05-08T18:25:19.057388Z","end":"2026-05-08T18:25:19.541447Z","steps":["trace[1339018035] 'agreement among raft nodes before linearized reading' (duration: 483.984938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:19.541501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:19.057366Z","time spent":"484.109361ms","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-05-08T18:25:19.545622Z","caller":"traceutil/trace.go:171","msg":"trace[1318057647] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"221.039205ms","start":"2026-05-08T18:25:19.324523Z","end":"2026-05-08T18:25:19.545563Z","steps":["trace[1318057647] 'process raft request' (duration: 220.7509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:19.546218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.152297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:25:19.54637Z","caller":"traceutil/trace.go:171","msg":"trace[1562670050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4815; }","duration":"229.439553ms","start":"2026-05-08T18:25:19.316911Z","end":"2026-05-08T18:25:19.546351Z","steps":["trace[1562670050] 'agreement among raft nodes before linearized reading' (duration: 228.82078ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:20.684538Z","caller":"traceutil/trace.go:171","msg":"trace[797243736] linearizableReadLoop","detail":"{readStateIndex:5006; appliedIndex:5005; }","duration":"174.792514ms","start":"2026-05-08T18:25:20.509708Z","end":"2026-05-08T18:25:20.6845Z","steps":["trace[797243736] 'read index received' (duration: 148.683474ms)","trace[797243736] 'applied index is now lower than readState.Index' (duration: 26.10414ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:20.684575Z","caller":"traceutil/trace.go:171","msg":"trace[1653440533] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"172.928466ms","start":"2026-05-08T18:25:20.511625Z","end":"2026-05-08T18:25:20.684553Z","steps":["trace[1653440533] 'process raft request' (duration: 172.805274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:20.684949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.218512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10685"} {"level":"warn","ts":"2026-05-08T18:25:20.684952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.669754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-08T18:25:20.685008Z","caller":"traceutil/trace.go:171","msg":"trace[2086592823] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4829; }","duration":"175.314325ms","start":"2026-05-08T18:25:20.509675Z","end":"2026-05-08T18:25:20.684989Z","steps":["trace[2086592823] 'agreement among raft nodes before linearized reading' (duration: 175.09072ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:20.685018Z","caller":"traceutil/trace.go:171","msg":"trace[1495472817] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4829; }","duration":"117.765257ms","start":"2026-05-08T18:25:20.567234Z","end":"2026-05-08T18:25:20.684999Z","steps":["trace[1495472817] 'agreement among raft nodes before linearized reading' (duration: 117.512652ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:20.684481Z","caller":"traceutil/trace.go:171","msg":"trace[1196653680] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"192.993196ms","start":"2026-05-08T18:25:20.491453Z","end":"2026-05-08T18:25:20.684446Z","steps":["trace[1196653680] 'process raft request' (duration: 166.926677ms)","trace[1196653680] 'compare' (duration: 25.925006ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:35.892053Z","caller":"traceutil/trace.go:171","msg":"trace[1908557856] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"132.031123ms","start":"2026-05-08T18:25:35.759981Z","end":"2026-05-08T18:25:35.892012Z","steps":["trace[1908557856] 'process raft request' (duration: 54.867777ms)","trace[1908557856] 'compare' (duration: 76.935432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:25:55.425086Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079350017188657639,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:25:55.579495Z","caller":"traceutil/trace.go:171","msg":"trace[1622919407] linearizableReadLoop","detail":"{readStateIndex:5284; appliedIndex:5283; }","duration":"655.018871ms","start":"2026-05-08T18:25:54.924455Z","end":"2026-05-08T18:25:55.579473Z","steps":["trace[1622919407] 'read index received' (duration: 654.93272ms)","trace[1622919407] 'applied index is now lower than readState.Index' (duration: 85.041µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:55.579627Z","caller":"traceutil/trace.go:171","msg":"trace[1472562372] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"718.028658ms","start":"2026-05-08T18:25:54.861568Z","end":"2026-05-08T18:25:55.579597Z","steps":["trace[1472562372] 'process raft request' (duration: 717.783194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:55.579913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.43942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-08T18:25:55.580023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:54.861549Z","time spent":"718.368884ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:25:55.579999Z","caller":"traceutil/trace.go:171","msg":"trace[313361917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5097; }","duration":"655.573961ms","start":"2026-05-08T18:25:54.924404Z","end":"2026-05-08T18:25:55.579977Z","steps":["trace[313361917] 'agreement among raft nodes before linearized reading' (duration: 655.245215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:55.580307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:25:54.924383Z","time spent":"655.898189ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T18:25:55.580903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.615082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:25:55.581027Z","caller":"traceutil/trace.go:171","msg":"trace[1300786918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5097; }","duration":"264.773315ms","start":"2026-05-08T18:25:55.316232Z","end":"2026-05-08T18:25:55.581006Z","steps":["trace[1300786918] 'agreement among raft nodes before linearized reading' (duration: 264.621552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:04.651953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.963974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:26:04.652063Z","caller":"traceutil/trace.go:171","msg":"trace[579421304] linearizableReadLoop","detail":"{readStateIndex:5432; appliedIndex:5431; }","duration":"435.867352ms","start":"2026-05-08T18:26:04.216177Z","end":"2026-05-08T18:26:04.652044Z","steps":["trace[579421304] 'read index received' (duration: 251.707485ms)","trace[579421304] 'applied index is now lower than readState.Index' (duration: 184.158737ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:26:04.652116Z","caller":"traceutil/trace.go:171","msg":"trace[52468667] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"448.597833ms","start":"2026-05-08T18:26:04.203486Z","end":"2026-05-08T18:26:04.652084Z","steps":["trace[52468667] 'process raft request' (duration: 264.436986ms)","trace[52468667] 'compare' (duration: 183.847372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:26:04.652263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:26:04.203472Z","time spent":"448.717785ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:26:04.652301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.280672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:26:04.652346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.932901ms","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":"info","ts":"2026-05-08T18:26:04.652387Z","caller":"traceutil/trace.go:171","msg":"trace[1908821038] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5241; }","duration":"407.391634ms","start":"2026-05-08T18:26:04.244974Z","end":"2026-05-08T18:26:04.652366Z","steps":["trace[1908821038] 'agreement among raft nodes before linearized reading' (duration: 407.228891ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:26:04.652397Z","caller":"traceutil/trace.go:171","msg":"trace[1535589409] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5241; }","duration":"174.026342ms","start":"2026-05-08T18:26:04.478355Z","end":"2026-05-08T18:26:04.652382Z","steps":["trace[1535589409] 'agreement among raft nodes before linearized reading' (duration: 173.922941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:04.652443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:26:04.244935Z","time spent":"407.497985ms","remote":"127.0.0.1:39020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-05-08T18:26:04.652687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.368743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:26:04.65274Z","caller":"traceutil/trace.go:171","msg":"trace[1581503101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5241; }","duration":"336.451125ms","start":"2026-05-08T18:26:04.316279Z","end":"2026-05-08T18:26:04.65273Z","steps":["trace[1581503101] 'agreement among raft nodes before linearized reading' (duration: 336.374073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:04.652763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:26:04.316262Z","time spent":"336.495375ms","remote":"127.0.0.1:55174","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-05-08T18:26:04.65293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.754848ms","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-05-08T18:26:04.653003Z","caller":"traceutil/trace.go:171","msg":"trace[816115449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5241; }","duration":"436.86801ms","start":"2026-05-08T18:26:04.216118Z","end":"2026-05-08T18:26:04.652986Z","steps":["trace[816115449] 'agreement among raft nodes before linearized reading' (duration: 436.010135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:04.653054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:26:04.216075Z","time spent":"436.964321ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T18:26:11.966412Z","caller":"traceutil/trace.go:171","msg":"trace[1824027073] linearizableReadLoop","detail":"{readStateIndex:5474; appliedIndex:5473; }","duration":"193.95795ms","start":"2026-05-08T18:26:11.772427Z","end":"2026-05-08T18:26:11.966385Z","steps":["trace[1824027073] 'read index received' (duration: 193.912949ms)","trace[1824027073] 'applied index is now lower than readState.Index' (duration: 44.231µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:26:11.966542Z","caller":"traceutil/trace.go:171","msg":"trace[1764538817] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"195.834564ms","start":"2026-05-08T18:26:11.770636Z","end":"2026-05-08T18:26:11.96647Z","steps":["trace[1764538817] 'process raft request' (duration: 195.591189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:11.966773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.329327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-08T18:26:11.966821Z","caller":"traceutil/trace.go:171","msg":"trace[1283272549] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5281; }","duration":"194.420148ms","start":"2026-05-08T18:26:11.772388Z","end":"2026-05-08T18:26:11.966809Z","steps":["trace[1283272549] 'agreement among raft nodes before linearized reading' (duration: 194.179163ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:26:12.120946Z","caller":"traceutil/trace.go:171","msg":"trace[468474329] linearizableReadLoop","detail":"{readStateIndex:5475; appliedIndex:5474; }","duration":"154.423438ms","start":"2026-05-08T18:26:11.966496Z","end":"2026-05-08T18:26:12.120919Z","steps":["trace[468474329] 'read index received' (duration: 85.009164ms)","trace[468474329] 'applied index is now lower than readState.Index' (duration: 69.413394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:26:12.121103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.037861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-08T18:26:12.121225Z","caller":"traceutil/trace.go:171","msg":"trace[1710979420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5282; }","duration":"300.183094ms","start":"2026-05-08T18:26:11.821023Z","end":"2026-05-08T18:26:12.121206Z","steps":["trace[1710979420] 'agreement among raft nodes before linearized reading' (duration: 299.92854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.121249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.878666ms","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-05-08T18:26:12.121281Z","caller":"traceutil/trace.go:171","msg":"trace[907949675] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5282; }","duration":"310.943857ms","start":"2026-05-08T18:26:11.810328Z","end":"2026-05-08T18:26:12.121272Z","steps":["trace[907949675] 'agreement among raft nodes before linearized reading' (duration: 310.868736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.121296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:26:11.821011Z","time spent":"300.255305ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T18:26:12.121309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:26:11.810307Z","time spent":"310.997138ms","remote":"127.0.0.1:55406","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-05-08T18:26:12.121483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.141896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:26:12.121515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.679996ms","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-05-08T18:26:12.121638Z","caller":"traceutil/trace.go:171","msg":"trace[1259852497] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5282; }","duration":"313.832069ms","start":"2026-05-08T18:26:11.807793Z","end":"2026-05-08T18:26:12.121625Z","steps":["trace[1259852497] 'agreement among raft nodes before linearized reading' (duration: 313.392881ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:26:12.121856Z","caller":"traceutil/trace.go:171","msg":"trace[662454735] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5282; }","duration":"313.295179ms","start":"2026-05-08T18:26:11.808262Z","end":"2026-05-08T18:26:12.121558Z","steps":["trace[662454735] 'agreement among raft nodes before linearized reading' (duration: 312.915743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.121973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:26:11.807778Z","time spent":"314.103693ms","remote":"127.0.0.1:55406","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-05-08T18:26:12.121169Z","caller":"traceutil/trace.go:171","msg":"trace[1469690268] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"243.532427ms","start":"2026-05-08T18:26:11.87762Z","end":"2026-05-08T18:26:12.121152Z","steps":["trace[1469690268] 'process raft request' (duration: 173.816228ms)","trace[1469690268] 'compare' (duration: 69.346033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:26:12.122242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:26:11.808244Z","time spent":"313.979272ms","remote":"127.0.0.1:55406","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":"info","ts":"2026-05-08T18:26:12.205554Z","caller":"traceutil/trace.go:171","msg":"trace[326850456] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"214.610615ms","start":"2026-05-08T18:26:11.990914Z","end":"2026-05-08T18:26:12.205524Z","steps":["trace[326850456] 'process raft request' (duration: 214.267299ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:26:12.205605Z","caller":"traceutil/trace.go:171","msg":"trace[179098576] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"208.608847ms","start":"2026-05-08T18:26:11.996963Z","end":"2026-05-08T18:26:12.205572Z","steps":["trace[179098576] 'process raft request' (duration: 208.444935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.206071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.69546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:26:12.206145Z","caller":"traceutil/trace.go:171","msg":"trace[489007299] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5284; }","duration":"217.857263ms","start":"2026-05-08T18:26:11.988271Z","end":"2026-05-08T18:26:12.206128Z","steps":["trace[489007299] 'agreement among raft nodes before linearized reading' (duration: 217.496607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.206339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.849776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-08T18:26:12.206788Z","caller":"traceutil/trace.go:171","msg":"trace[1301071164] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5284; }","duration":"217.388365ms","start":"2026-05-08T18:26:11.989379Z","end":"2026-05-08T18:26:12.206767Z","steps":["trace[1301071164] 'agreement among raft nodes before linearized reading' (duration: 216.349166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.428841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.980271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:26:12.428941Z","caller":"traceutil/trace.go:171","msg":"trace[1708792596] linearizableReadLoop","detail":"{readStateIndex:5478; appliedIndex:5477; }","duration":"223.417783ms","start":"2026-05-08T18:26:12.205507Z","end":"2026-05-08T18:26:12.428925Z","steps":["trace[1708792596] 'read index received' (duration: 17.177788ms)","trace[1708792596] 'applied index is now lower than readState.Index' (duration: 206.238545ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:26:12.429032Z","caller":"traceutil/trace.go:171","msg":"trace[1773466988] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"303.011198ms","start":"2026-05-08T18:26:12.125989Z","end":"2026-05-08T18:26:12.429Z","steps":["trace[1773466988] 'process raft request' (duration: 96.776153ms)","trace[1773466988] 'compare' (duration: 205.850049ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:26:12.429113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.725295ms","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-05-08T18:26:12.429144Z","caller":"traceutil/trace.go:171","msg":"trace[719333778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5285; }","duration":"258.796186ms","start":"2026-05-08T18:26:12.170337Z","end":"2026-05-08T18:26:12.429134Z","steps":["trace[719333778] 'agreement among raft nodes before linearized reading' (duration: 258.672823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.42918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:26:12.12597Z","time spent":"303.13194ms","remote":"127.0.0.1:55428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:26:12.429361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.052759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"warn","ts":"2026-05-08T18:26:12.429379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.746708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:26:12.429397Z","caller":"traceutil/trace.go:171","msg":"trace[154353227] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5285; }","duration":"217.117779ms","start":"2026-05-08T18:26:12.212271Z","end":"2026-05-08T18:26:12.429389Z","steps":["trace[154353227] 'agreement among raft nodes before linearized reading' (duration: 217.019037ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:26:12.429422Z","caller":"traceutil/trace.go:171","msg":"trace[769443838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5285; }","duration":"113.786469ms","start":"2026-05-08T18:26:12.315622Z","end":"2026-05-08T18:26:12.429408Z","steps":["trace[769443838] 'agreement among raft nodes before linearized reading' (duration: 113.704407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.429693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.925797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:26:12.429736Z","caller":"traceutil/trace.go:171","msg":"trace[450656288] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5285; }","duration":"135.016809ms","start":"2026-05-08T18:26:12.294707Z","end":"2026-05-08T18:26:12.429724Z","steps":["trace[450656288] 'agreement among raft nodes before linearized reading' (duration: 134.919197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.429997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.210103ms","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-05-08T18:26:12.430112Z","caller":"traceutil/trace.go:171","msg":"trace[1087057169] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5285; }","duration":"158.377417ms","start":"2026-05-08T18:26:12.271717Z","end":"2026-05-08T18:26:12.430094Z","steps":["trace[1087057169] 'agreement among raft nodes before linearized reading' (duration: 158.210604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:26:12.430006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.63823ms","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-05-08T18:26:12.431239Z","caller":"traceutil/trace.go:171","msg":"trace[112763551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5285; }","duration":"202.929315ms","start":"2026-05-08T18:26:12.227294Z","end":"2026-05-08T18:26:12.430223Z","steps":["trace[112763551] 'agreement among raft nodes before linearized reading' (duration: 202.443846ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:26:12.574718Z","caller":"traceutil/trace.go:171","msg":"trace[975391203] linearizableReadLoop","detail":"{readStateIndex:5480; appliedIndex:5478; }","duration":"137.28094ms","start":"2026-05-08T18:26:12.437417Z","end":"2026-05-08T18:26:12.574698Z","steps":["trace[975391203] 'read index received' (duration: 51.736588ms)","trace[975391203] 'applied index is now lower than readState.Index' (duration: 85.543292ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:26:12.574859Z","caller":"traceutil/trace.go:171","msg":"trace[882360874] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"139.047022ms","start":"2026-05-08T18:26:12.435739Z","end":"2026-05-08T18:26:12.574786Z","steps":["trace[882360874] 'process raft request' (duration: 104.721827ms)","trace[882360874] 'compare' (duration: 34.157812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:26:12.575033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.585106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-05-08T18:26:12.575117Z","caller":"traceutil/trace.go:171","msg":"trace[72893865] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5287; }","duration":"137.720037ms","start":"2026-05-08T18:26:12.437379Z","end":"2026-05-08T18:26:12.575099Z","steps":["trace[72893865] 'agreement among raft nodes before linearized reading' (duration: 137.462623ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:26:12.721558Z","caller":"traceutil/trace.go:171","msg":"trace[1350552895] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"116.473717ms","start":"2026-05-08T18:26:12.605048Z","end":"2026-05-08T18:26:12.721521Z","steps":["trace[1350552895] 'process raft request' (duration: 82.291334ms)","trace[1350552895] 'compare' (duration: 33.947988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:26:13.037664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.392438ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:26:13.037808Z","caller":"traceutil/trace.go:171","msg":"trace[325165032] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5289; }","duration":"100.552391ms","start":"2026-05-08T18:26:12.937234Z","end":"2026-05-08T18:26:13.037786Z","steps":["trace[325165032] 'range keys from in-memory index tree' (duration: 100.379237ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:26:57.557839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2942} {"level":"info","ts":"2026-05-08T18:26:57.862102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2942,"took":"303.233511ms","hash":4103517133,"current-db-size-bytes":53927936,"current-db-size":"54 MB","current-db-size-in-use-bytes":29237248,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-08T18:26:57.862199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4103517133,"revision":2942,"compact-revision":-1} {"level":"info","ts":"2026-05-08T18:27:00.929589Z","caller":"traceutil/trace.go:171","msg":"trace[1781763970] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"129.181983ms","start":"2026-05-08T18:27:00.800382Z","end":"2026-05-08T18:27:00.929564Z","steps":["trace[1781763970] 'process raft request' (duration: 108.160683ms)","trace[1781763970] 'compare' (duration: 20.578642ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:27:01.447755Z","caller":"traceutil/trace.go:171","msg":"trace[1379540304] linearizableReadLoop","detail":"{readStateIndex:5874; appliedIndex:5873; }","duration":"130.210016ms","start":"2026-05-08T18:27:01.317525Z","end":"2026-05-08T18:27:01.447735Z","steps":["trace[1379540304] 'read index received' (duration: 130.058724ms)","trace[1379540304] 'applied index is now lower than readState.Index' (duration: 150.652µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:27:01.447865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.322608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:27:01.447889Z","caller":"traceutil/trace.go:171","msg":"trace[282223453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5669; }","duration":"130.397479ms","start":"2026-05-08T18:27:01.317483Z","end":"2026-05-08T18:27:01.44788Z","steps":["trace[282223453] 'agreement among raft nodes before linearized reading' (duration: 130.323848ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:01.447875Z","caller":"traceutil/trace.go:171","msg":"trace[1374864181] transaction","detail":"{read_only:false; response_revision:5669; number_of_response:1; }","duration":"140.531438ms","start":"2026-05-08T18:27:01.307307Z","end":"2026-05-08T18:27:01.447838Z","steps":["trace[1374864181] 'process raft request' (duration: 140.314224ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:06.601548Z","caller":"traceutil/trace.go:171","msg":"trace[220705417] transaction","detail":"{read_only:false; response_revision:5697; number_of_response:1; }","duration":"133.008789ms","start":"2026-05-08T18:27:06.468511Z","end":"2026-05-08T18:27:06.60152Z","steps":["trace[220705417] 'process raft request' (duration: 132.810776ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:06.601512Z","caller":"traceutil/trace.go:171","msg":"trace[943945384] transaction","detail":"{read_only:false; response_revision:5696; number_of_response:1; }","duration":"132.980558ms","start":"2026-05-08T18:27:06.46849Z","end":"2026-05-08T18:27:06.601471Z","steps":["trace[943945384] 'process raft request' (duration: 45.99674ms)","trace[943945384] 'compare' (duration: 86.602552ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:27:37.245647Z","caller":"traceutil/trace.go:171","msg":"trace[643924290] transaction","detail":"{read_only:false; response_revision:5851; number_of_response:1; }","duration":"104.335856ms","start":"2026-05-08T18:27:37.141275Z","end":"2026-05-08T18:27:37.245611Z","steps":["trace[643924290] 'process raft request' (duration: 104.025061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:27:45.398587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.834739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-08T18:27:45.398717Z","caller":"traceutil/trace.go:171","msg":"trace[1266513320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5891; }","duration":"108.008551ms","start":"2026-05-08T18:27:45.290685Z","end":"2026-05-08T18:27:45.398693Z","steps":["trace[1266513320] 'range keys from in-memory index tree' (duration: 107.666406ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:45.511721Z","caller":"traceutil/trace.go:171","msg":"trace[1980524880] transaction","detail":"{read_only:false; response_revision:5894; number_of_response:1; }","duration":"107.180579ms","start":"2026-05-08T18:27:45.404505Z","end":"2026-05-08T18:27:45.511685Z","steps":["trace[1980524880] 'process raft request' (duration: 106.928205ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:45.512232Z","caller":"traceutil/trace.go:171","msg":"trace[387995519] transaction","detail":"{read_only:false; response_revision:5893; number_of_response:1; }","duration":"108.588921ms","start":"2026-05-08T18:27:45.4036Z","end":"2026-05-08T18:27:45.512189Z","steps":["trace[387995519] 'process raft request' (duration: 95.228939ms)","trace[387995519] 'compare' (duration: 12.493408ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:29:25.297052Z","caller":"traceutil/trace.go:171","msg":"trace[271739457] transaction","detail":"{read_only:false; response_revision:6655; number_of_response:1; }","duration":"187.612286ms","start":"2026-05-08T18:29:25.109381Z","end":"2026-05-08T18:29:25.296993Z","steps":["trace[271739457] 'process raft request' (duration: 145.27032ms)","trace[271739457] 'compare' (duration: 42.126193ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:29:25.297082Z","caller":"traceutil/trace.go:171","msg":"trace[1842844143] transaction","detail":"{read_only:false; response_revision:6656; number_of_response:1; }","duration":"138.250605ms","start":"2026-05-08T18:29:25.158801Z","end":"2026-05-08T18:29:25.297051Z","steps":["trace[1842844143] 'process raft request' (duration: 138.103853ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:29:31.732609Z","caller":"traceutil/trace.go:171","msg":"trace[374893428] linearizableReadLoop","detail":"{readStateIndex:6932; appliedIndex:6931; }","duration":"100.149697ms","start":"2026-05-08T18:29:31.632435Z","end":"2026-05-08T18:29:31.732584Z","steps":["trace[374893428] 'read index received' (duration: 99.877123ms)","trace[374893428] 'applied index is now lower than readState.Index' (duration: 271.454µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:29:31.733008Z","caller":"traceutil/trace.go:171","msg":"trace[1326219285] transaction","detail":"{read_only:false; response_revision:6688; number_of_response:1; }","duration":"145.240834ms","start":"2026-05-08T18:29:31.587754Z","end":"2026-05-08T18:29:31.732995Z","steps":["trace[1326219285] 'process raft request' (duration: 144.647034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:29:31.733242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.797827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-08T18:29:31.733282Z","caller":"traceutil/trace.go:171","msg":"trace[479780540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6688; }","duration":"100.884829ms","start":"2026-05-08T18:29:31.632387Z","end":"2026-05-08T18:29:31.733272Z","steps":["trace[479780540] 'agreement among raft nodes before linearized reading' (duration: 100.796087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:29:31.827695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.180508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:29:31.827773Z","caller":"traceutil/trace.go:171","msg":"trace[1779918975] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:6688; }","duration":"123.277759ms","start":"2026-05-08T18:29:31.704478Z","end":"2026-05-08T18:29:31.827756Z","steps":["trace[1779918975] 'agreement among raft nodes before linearized reading' (duration: 30.010993ms)","trace[1779918975] 'count revisions from in-memory index tree' (duration: 93.152365ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:29:35.20194Z","caller":"traceutil/trace.go:171","msg":"trace[1259857249] transaction","detail":"{read_only:false; response_revision:6704; number_of_response:1; }","duration":"119.016424ms","start":"2026-05-08T18:29:35.082898Z","end":"2026-05-08T18:29:35.201915Z","steps":["trace[1259857249] 'process raft request' (duration: 118.77063ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:47.93987Z","caller":"traceutil/trace.go:171","msg":"trace[89573868] linearizableReadLoop","detail":"{readStateIndex:7670; appliedIndex:7669; }","duration":"131.57853ms","start":"2026-05-08T18:31:47.808266Z","end":"2026-05-08T18:31:47.939845Z","steps":["trace[89573868] 'read index received' (duration: 131.524189ms)","trace[89573868] 'applied index is now lower than readState.Index' (duration: 53.111µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:31:47.939976Z","caller":"traceutil/trace.go:171","msg":"trace[1043542564] transaction","detail":"{read_only:false; response_revision:7399; number_of_response:1; }","duration":"147.34607ms","start":"2026-05-08T18:31:47.792598Z","end":"2026-05-08T18:31:47.939944Z","steps":["trace[1043542564] 'process raft request' (duration: 147.107053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:31:47.940291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.005162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-05-08T18:31:47.940398Z","caller":"traceutil/trace.go:171","msg":"trace[33527898] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap; range_end:; response_count:1; response_revision:7399; }","duration":"132.144445ms","start":"2026-05-08T18:31:47.808233Z","end":"2026-05-08T18:31:47.940377Z","steps":["trace[33527898] 'agreement among raft nodes before linearized reading' (duration: 131.755645ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:47.943564Z","caller":"traceutil/trace.go:171","msg":"trace[787263450] transaction","detail":"{read_only:false; response_revision:7400; number_of_response:1; }","duration":"118.086742ms","start":"2026-05-08T18:31:47.825456Z","end":"2026-05-08T18:31:47.943542Z","steps":["trace[787263450] 'process raft request' (duration: 117.65743ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:57.563279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5627} {"level":"info","ts":"2026-05-08T18:31:57.651069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5627,"took":"85.746236ms","hash":1822229106,"current-db-size-bytes":53927936,"current-db-size":"54 MB","current-db-size-in-use-bytes":18173952,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-08T18:31:57.651119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1822229106,"revision":5627,"compact-revision":2942} {"level":"info","ts":"2026-05-08T18:32:02.176458Z","caller":"traceutil/trace.go:171","msg":"trace[1916462306] transaction","detail":"{read_only:false; response_revision:7473; number_of_response:1; }","duration":"181.928537ms","start":"2026-05-08T18:32:01.994508Z","end":"2026-05-08T18:32:02.176436Z","steps":["trace[1916462306] 'process raft request' (duration: 180.949342ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:32:02.212678Z","caller":"traceutil/trace.go:171","msg":"trace[1134022443] linearizableReadLoop","detail":"{readStateIndex:7750; appliedIndex:7747; }","duration":"210.56102ms","start":"2026-05-08T18:32:02.002094Z","end":"2026-05-08T18:32:02.212655Z","steps":["trace[1134022443] 'read index received' (duration: 173.396754ms)","trace[1134022443] 'applied index is now lower than readState.Index' (duration: 37.163526ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:32:02.212809Z","caller":"traceutil/trace.go:171","msg":"trace[660570141] transaction","detail":"{read_only:false; response_revision:7475; number_of_response:1; }","duration":"217.961433ms","start":"2026-05-08T18:32:01.994837Z","end":"2026-05-08T18:32:02.212798Z","steps":["trace[660570141] 'process raft request' (duration: 217.786648ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:32:02.212837Z","caller":"traceutil/trace.go:171","msg":"trace[1194715314] transaction","detail":"{read_only:false; response_revision:7474; number_of_response:1; }","duration":"217.942553ms","start":"2026-05-08T18:32:01.994852Z","end":"2026-05-08T18:32:02.212794Z","steps":["trace[1194715314] 'process raft request' (duration: 217.633225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:32:02.212978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.966604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-node-lease/\" range_end:\"/registry/jobs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:32:02.213047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.288388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" ","response":"range_response_count:1 size:1550"} {"level":"info","ts":"2026-05-08T18:32:02.213057Z","caller":"traceutil/trace.go:171","msg":"trace[1701600504] range","detail":"{range_begin:/registry/jobs/kube-node-lease/; range_end:/registry/jobs/kube-node-lease0; response_count:0; response_revision:7475; }","duration":"153.031915ms","start":"2026-05-08T18:32:02.059983Z","end":"2026-05-08T18:32:02.213015Z","steps":["trace[1701600504] 'agreement among raft nodes before linearized reading' (duration: 152.933803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:32:02.213048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.652298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/kube-root-ca.crt\" ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-05-08T18:32:02.213092Z","caller":"traceutil/trace.go:171","msg":"trace[389391914] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/envoy-gateway-config; range_end:; response_count:1; response_revision:7475; }","duration":"149.36831ms","start":"2026-05-08T18:32:02.063712Z","end":"2026-05-08T18:32:02.21308Z","steps":["trace[389391914] 'agreement among raft nodes before linearized reading' (duration: 149.213346ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:32:02.213123Z","caller":"traceutil/trace.go:171","msg":"trace[1930077591] range","detail":"{range_begin:/registry/configmaps/default/kube-root-ca.crt; range_end:; response_count:1; response_revision:7475; }","duration":"206.75306ms","start":"2026-05-08T18:32:02.006351Z","end":"2026-05-08T18:32:02.213104Z","steps":["trace[1930077591] 'agreement among raft nodes before linearized reading' (duration: 206.545725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:32:02.213218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.106814ms","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-05-08T18:32:02.213285Z","caller":"traceutil/trace.go:171","msg":"trace[26495801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7475; }","duration":"211.216937ms","start":"2026-05-08T18:32:02.002047Z","end":"2026-05-08T18:32:02.213264Z","steps":["trace[26495801] 'agreement among raft nodes before linearized reading' (duration: 210.95446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:32:02.213352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.21289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/\" range_end:\"/registry/serviceaccounts/ingress-nginx0\" limit:500 ","response":"range_response_count:3 size:2230"} {"level":"info","ts":"2026-05-08T18:32:02.213461Z","caller":"traceutil/trace.go:171","msg":"trace[653022652] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/; range_end:/registry/serviceaccounts/ingress-nginx0; response_count:3; response_revision:7475; }","duration":"195.378155ms","start":"2026-05-08T18:32:02.01806Z","end":"2026-05-08T18:32:02.213438Z","steps":["trace[653022652] 'agreement among raft nodes before linearized reading' (duration: 194.999535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:32:05.222792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.568892ms","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-05-08T18:32:05.222878Z","caller":"traceutil/trace.go:171","msg":"trace[1768041904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7489; }","duration":"136.701905ms","start":"2026-05-08T18:32:05.086157Z","end":"2026-05-08T18:32:05.222859Z","steps":["trace[1768041904] 'range keys from in-memory index tree' (duration: 136.394797ms)"],"step_count":1}