{"level":"warn","ts":"2026-09-16T15:55:06.480101Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-16T15:55:06.480482Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.19: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://162.253.55.19:2380","--initial-cluster=instance=https://162.253.55.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.19: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-09-16T15:55:06.480763Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-16T15:55:06.48078Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-09-16T15:55:06.480804Z","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-09-16T15:55:06.481602Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"]} {"level":"info","ts":"2026-09-16T15:55:06.481692Z","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://162.253.55.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.19: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-09-16T15:55:06.486953Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.650381ms"} {"level":"info","ts":"2026-09-16T15:55:06.49591Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ded76e421897a43d","cluster-id":"8853d1107d87769b"} {"level":"info","ts":"2026-09-16T15:55:06.496181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=()"} {"level":"info","ts":"2026-09-16T15:55:06.496219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 0"} {"level":"info","ts":"2026-09-16T15:55:06.496233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ded76e421897a43d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-16T15:55:06.496252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 1"} {"level":"info","ts":"2026-09-16T15:55:06.496369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"warn","ts":"2026-09-16T15:55:06.507301Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-16T15:55:06.509464Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-16T15:55:06.510936Z","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-09-16T15:55:06.513434Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ded76e421897a43d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-16T15:55:06.513577Z","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-09-16T15:55:06.513678Z","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-09-16T15:55:06.513691Z","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-09-16T15:55:06.513719Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ded76e421897a43d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-16T15:55:06.51505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"info","ts":"2026-09-16T15:55:06.515224Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","added-peer-id":"ded76e421897a43d","added-peer-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-09-16T15:55:06.520318Z","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-09-16T15:55:06.520397Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-09-16T15:55:06.520442Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-09-16T15:55:06.520671Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ded76e421897a43d","initial-advertise-peer-urls":["https://162.253.55.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-16T15:55:06.520696Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-16T15:55:06.796883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d is starting a new election at term 1"} {"level":"info","ts":"2026-09-16T15:55:06.796921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-16T15:55:06.796935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgPreVoteResp from ded76e421897a43d at term 1"} {"level":"info","ts":"2026-09-16T15:55:06.796958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became candidate at term 2"} {"level":"info","ts":"2026-09-16T15:55:06.796963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgVoteResp from ded76e421897a43d at term 2"} {"level":"info","ts":"2026-09-16T15:55:06.796971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became leader at term 2"} {"level":"info","ts":"2026-09-16T15:55:06.796979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ded76e421897a43d elected leader ded76e421897a43d at term 2"} {"level":"info","ts":"2026-09-16T15:55:06.797645Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T15:55:06.799199Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T15:55:06.799242Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T15:55:06.79928Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T15:55:06.7993Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ded76e421897a43d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.19:2379]}","request-path":"/0/members/ded76e421897a43d/attributes","cluster-id":"8853d1107d87769b","publish-timeout":"7s"} {"level":"info","ts":"2026-09-16T15:55:06.79982Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T15:55:06.80034Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T15:55:06.801932Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-16T15:55:06.801962Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-16T15:55:06.806185Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-16T15:55:06.809032Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.19:2379"} {"level":"info","ts":"2026-09-16T15:55:16.5868Z","caller":"traceutil/trace.go:171","msg":"trace[1640537758] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"111.625508ms","start":"2026-09-16T15:55:16.475138Z","end":"2026-09-16T15:55:16.586763Z","steps":["trace[1640537758] 'process raft request' (duration: 61.100687ms)","trace[1640537758] 'compare' (duration: 50.158327ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:55:16.788802Z","caller":"traceutil/trace.go:171","msg":"trace[202920585] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"108.028004ms","start":"2026-09-16T15:55:16.680745Z","end":"2026-09-16T15:55:16.788773Z","steps":["trace[202920585] 'process raft request' (duration: 30.71504ms)","trace[202920585] 'compare' (duration: 77.108536ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:55:16.788857Z","caller":"traceutil/trace.go:171","msg":"trace[1625591860] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"102.186356ms","start":"2026-09-16T15:55:16.68666Z","end":"2026-09-16T15:55:16.788846Z","steps":["trace[1625591860] 'process raft request' (duration: 102.075642ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:55:16.789173Z","caller":"traceutil/trace.go:171","msg":"trace[525799930] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"106.880541ms","start":"2026-09-16T15:55:16.682263Z","end":"2026-09-16T15:55:16.789143Z","steps":["trace[525799930] 'process raft request' (duration: 106.442565ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:55:19.534734Z","caller":"traceutil/trace.go:171","msg":"trace[523506382] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"275.580733ms","start":"2026-09-16T15:55:19.259114Z","end":"2026-09-16T15:55:19.534695Z","steps":["trace[523506382] 'process raft request' (duration: 222.898548ms)","trace[523506382] 'compare' (duration: 52.52207ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:55:19.867239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.128408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T15:55:19.867545Z","caller":"traceutil/trace.go:171","msg":"trace[1014278799] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"303.897759ms","start":"2026-09-16T15:55:19.5636Z","end":"2026-09-16T15:55:19.867498Z","steps":["trace[1014278799] 'process raft request' (duration: 99.849497ms)","trace[1014278799] 'compare' (duration: 202.976303ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:55:19.867739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:55:19.563575Z","time spent":"304.017972ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T15:55:20.10039Z","caller":"traceutil/trace.go:171","msg":"trace[1157083206] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"171.197775ms","start":"2026-09-16T15:55:19.929163Z","end":"2026-09-16T15:55:20.100361Z","steps":["trace[1157083206] 'process raft request' (duration: 166.553244ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:55:52.514436Z","caller":"traceutil/trace.go:171","msg":"trace[2044959383] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"114.637786ms","start":"2026-09-16T15:55:52.399772Z","end":"2026-09-16T15:55:52.51441Z","steps":["trace[2044959383] 'process raft request' (duration: 114.398519ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:55:52.515791Z","caller":"traceutil/trace.go:171","msg":"trace[1385228804] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:663; }","duration":"109.372125ms","start":"2026-09-16T15:55:52.406364Z","end":"2026-09-16T15:55:52.515736Z","steps":["trace[1385228804] 'read index received' (duration: 109.353325ms)","trace[1385228804] 'applied index is now lower than readState.Index' (duration: 15.4µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:55:52.516111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.721857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-09-16T15:55:52.516137Z","caller":"traceutil/trace.go:171","msg":"trace[1322414707] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:635; }","duration":"109.79956ms","start":"2026-09-16T15:55:52.406328Z","end":"2026-09-16T15:55:52.516128Z","steps":["trace[1322414707] 'agreement among raft nodes before linearized reading' (duration: 109.546852ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:55:52.799185Z","caller":"traceutil/trace.go:171","msg":"trace[1672077949] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"123.248797ms","start":"2026-09-16T15:55:52.675903Z","end":"2026-09-16T15:55:52.799152Z","steps":["trace[1672077949] 'read index received' (duration: 119.411302ms)","trace[1672077949] 'applied index is now lower than readState.Index' (duration: 3.835665ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:55:52.799257Z","caller":"traceutil/trace.go:171","msg":"trace[1838471467] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"131.00588ms","start":"2026-09-16T15:55:52.668218Z","end":"2026-09-16T15:55:52.799224Z","steps":["trace[1838471467] 'process raft request' (duration: 127.073142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:55:52.799457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.525326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-09-16T15:55:52.799519Z","caller":"traceutil/trace.go:171","msg":"trace[161262297] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:645; }","duration":"123.62607ms","start":"2026-09-16T15:55:52.675875Z","end":"2026-09-16T15:55:52.799501Z","steps":["trace[161262297] 'agreement among raft nodes before linearized reading' (duration: 123.401192ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:56:00.254786Z","caller":"traceutil/trace.go:171","msg":"trace[694458611] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"377.277625ms","start":"2026-09-16T15:55:59.877481Z","end":"2026-09-16T15:56:00.254758Z","steps":["trace[694458611] 'process raft request' (duration: 377.130371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:56:00.254951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:55:59.877464Z","time spent":"377.41882ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T15:56:00.26469Z","caller":"traceutil/trace.go:171","msg":"trace[420061758] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:738; }","duration":"382.496762ms","start":"2026-09-16T15:55:59.882167Z","end":"2026-09-16T15:56:00.264664Z","steps":["trace[420061758] 'read index received' (duration: 372.538095ms)","trace[420061758] 'applied index is now lower than readState.Index' (duration: 9.955027ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:56:00.264718Z","caller":"traceutil/trace.go:171","msg":"trace[962059434] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"382.73681ms","start":"2026-09-16T15:55:59.881952Z","end":"2026-09-16T15:56:00.264689Z","steps":["trace[962059434] 'process raft request' (duration: 382.574635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:56:00.264872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:55:59.881933Z","time spent":"382.841713ms","remote":"127.0.0.1:37454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T15:56:00.264917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.735849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:56:00.26496Z","caller":"traceutil/trace.go:171","msg":"trace[1989471079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"382.810342ms","start":"2026-09-16T15:55:59.882136Z","end":"2026-09-16T15:56:00.264947Z","steps":["trace[1989471079] 'agreement among raft nodes before linearized reading' (duration: 382.656227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:56:00.264987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.057051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-16T15:56:00.264994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:55:59.882126Z","time spent":"382.859763ms","remote":"127.0.0.1:37430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-16T15:56:00.265032Z","caller":"traceutil/trace.go:171","msg":"trace[1819991905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:710; }","duration":"151.142783ms","start":"2026-09-16T15:56:00.113876Z","end":"2026-09-16T15:56:00.265019Z","steps":["trace[1819991905] 'agreement among raft nodes before linearized reading' (duration: 150.972468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:56:17.780647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.380241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-16T15:56:17.780775Z","caller":"traceutil/trace.go:171","msg":"trace[1855904557] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:826; }","duration":"301.576797ms","start":"2026-09-16T15:56:17.479175Z","end":"2026-09-16T15:56:17.780751Z","steps":["trace[1855904557] 'count revisions from in-memory index tree' (duration: 301.277888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:56:17.780835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:56:17.479144Z","time spent":"301.67243ms","remote":"127.0.0.1:37850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-09-16T15:56:54.301348Z","caller":"traceutil/trace.go:171","msg":"trace[1048893861] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"235.9967ms","start":"2026-09-16T15:56:54.065324Z","end":"2026-09-16T15:56:54.30132Z","steps":["trace[1048893861] 'read index received' (duration: 235.790494ms)","trace[1048893861] 'applied index is now lower than readState.Index' (duration: 204.366µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:56:54.30149Z","caller":"traceutil/trace.go:171","msg":"trace[1279833321] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"249.343532ms","start":"2026-09-16T15:56:54.052102Z","end":"2026-09-16T15:56:54.301446Z","steps":["trace[1279833321] 'process raft request' (duration: 249.079395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:56:54.30163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.298449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-16T15:56:54.301674Z","caller":"traceutil/trace.go:171","msg":"trace[387050894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"236.397531ms","start":"2026-09-16T15:56:54.065266Z","end":"2026-09-16T15:56:54.301663Z","steps":["trace[387050894] 'agreement among raft nodes before linearized reading' (duration: 236.186505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:56:54.302278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.058345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-16T15:56:54.302331Z","caller":"traceutil/trace.go:171","msg":"trace[1172263860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1254; }","duration":"130.151417ms","start":"2026-09-16T15:56:54.172165Z","end":"2026-09-16T15:56:54.302317Z","steps":["trace[1172263860] 'agreement among raft nodes before linearized reading' (duration: 129.334504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:57:44.583864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.698592ms","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-09-16T15:57:44.584001Z","caller":"traceutil/trace.go:171","msg":"trace[1320878592] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1932; }","duration":"478.888646ms","start":"2026-09-16T15:57:44.105086Z","end":"2026-09-16T15:57:44.583974Z","steps":["trace[1320878592] 'range keys from in-memory index tree' (duration: 478.551418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:57:44.584047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:57:44.105066Z","time spent":"478.968159ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-16T15:57:44.607051Z","caller":"traceutil/trace.go:171","msg":"trace[438009473] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"321.324268ms","start":"2026-09-16T15:57:44.285701Z","end":"2026-09-16T15:57:44.607025Z","steps":["trace[438009473] 'process raft request' (duration: 321.155843ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:44.607116Z","caller":"traceutil/trace.go:171","msg":"trace[1885731703] linearizableReadLoop","detail":"{readStateIndex:2012; appliedIndex:2012; }","duration":"253.883204ms","start":"2026-09-16T15:57:44.353206Z","end":"2026-09-16T15:57:44.607089Z","steps":["trace[1885731703] 'read index received' (duration: 253.873594ms)","trace[1885731703] 'applied index is now lower than readState.Index' (duration: 7.51µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:57:44.607453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:57:44.285671Z","time spent":"321.519383ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T15:57:44.607504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.259144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-616a5ad6-924c-4f96-ab23-67a0086beefe\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-16T15:57:44.607623Z","caller":"traceutil/trace.go:171","msg":"trace[1339998590] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-616a5ad6-924c-4f96-ab23-67a0086beefe; range_end:; response_count:1; response_revision:1933; }","duration":"254.434629ms","start":"2026-09-16T15:57:44.353158Z","end":"2026-09-16T15:57:44.607592Z","steps":["trace[1339998590] 'agreement among raft nodes before linearized reading' (duration: 254.066589ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:44.757951Z","caller":"traceutil/trace.go:171","msg":"trace[550361981] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2012; }","duration":"150.539097ms","start":"2026-09-16T15:57:44.607392Z","end":"2026-09-16T15:57:44.757931Z","steps":["trace[550361981] 'read index received' (duration: 142.043064ms)","trace[550361981] 'applied index is now lower than readState.Index' (duration: 8.494963ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:57:44.758059Z","caller":"traceutil/trace.go:171","msg":"trace[469609742] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"181.873003ms","start":"2026-09-16T15:57:44.576151Z","end":"2026-09-16T15:57:44.758024Z","steps":["trace[469609742] 'process raft request' (duration: 181.733128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:57:44.758165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.722701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-16T15:57:44.758174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.970038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-616a5ad6-924c-4f96-ab23-67a0086beefe\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-16T15:57:44.758195Z","caller":"traceutil/trace.go:171","msg":"trace[253861148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1935; }","duration":"184.838854ms","start":"2026-09-16T15:57:44.573347Z","end":"2026-09-16T15:57:44.758186Z","steps":["trace[253861148] 'agreement among raft nodes before linearized reading' (duration: 184.67677ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:44.758204Z","caller":"traceutil/trace.go:171","msg":"trace[447063699] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-616a5ad6-924c-4f96-ab23-67a0086beefe; range_end:; response_count:1; response_revision:1935; }","duration":"332.03972ms","start":"2026-09-16T15:57:44.426155Z","end":"2026-09-16T15:57:44.758194Z","steps":["trace[447063699] 'agreement among raft nodes before linearized reading' (duration: 331.849835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:57:44.758228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:57:44.426123Z","time spent":"332.098852ms","remote":"127.0.0.1:37592","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-616a5ad6-924c-4f96-ab23-67a0086beefe\" "} {"level":"warn","ts":"2026-09-16T15:57:44.758337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.305696ms","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-09-16T15:57:44.758358Z","caller":"traceutil/trace.go:171","msg":"trace[1315337033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1935; }","duration":"178.395888ms","start":"2026-09-16T15:57:44.579956Z","end":"2026-09-16T15:57:44.758352Z","steps":["trace[1315337033] 'agreement among raft nodes before linearized reading' (duration: 178.341367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:57:44.758463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.477778ms","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-09-16T15:57:44.758577Z","caller":"traceutil/trace.go:171","msg":"trace[943010106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1935; }","duration":"198.636092ms","start":"2026-09-16T15:57:44.559906Z","end":"2026-09-16T15:57:44.758542Z","steps":["trace[943010106] 'agreement among raft nodes before linearized reading' (duration: 198.396146ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:44.759037Z","caller":"traceutil/trace.go:171","msg":"trace[1188883053] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"252.999559ms","start":"2026-09-16T15:57:44.506009Z","end":"2026-09-16T15:57:44.759009Z","steps":["trace[1188883053] 'process raft request' (duration: 243.413816ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:45.034932Z","caller":"traceutil/trace.go:171","msg":"trace[1043356080] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"419.427579ms","start":"2026-09-16T15:57:44.615479Z","end":"2026-09-16T15:57:45.034906Z","steps":["trace[1043356080] 'process raft request' (duration: 379.488327ms)","trace[1043356080] 'compare' (duration: 39.773418ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:57:45.035086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:57:44.615453Z","time spent":"419.559912ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T15:57:45.035331Z","caller":"traceutil/trace.go:171","msg":"trace[2016237439] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"270.713322ms","start":"2026-09-16T15:57:44.764579Z","end":"2026-09-16T15:57:45.035292Z","steps":["trace[2016237439] 'process raft request' (duration: 270.29459ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:45.035695Z","caller":"traceutil/trace.go:171","msg":"trace[1317341336] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"270.63836ms","start":"2026-09-16T15:57:44.765024Z","end":"2026-09-16T15:57:45.035663Z","steps":["trace[1317341336] 'process raft request' (duration: 270.165586ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:45.035967Z","caller":"traceutil/trace.go:171","msg":"trace[205539878] linearizableReadLoop","detail":"{readStateIndex:2018; appliedIndex:2014; }","duration":"252.01942ms","start":"2026-09-16T15:57:44.783934Z","end":"2026-09-16T15:57:45.035953Z","steps":["trace[205539878] 'read index received' (duration: 210.958518ms)","trace[205539878] 'applied index is now lower than readState.Index' (duration: 41.059952ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:57:45.036081Z","caller":"traceutil/trace.go:171","msg":"trace[1653749751] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"271.004439ms","start":"2026-09-16T15:57:44.765064Z","end":"2026-09-16T15:57:45.036069Z","steps":["trace[1653749751] 'process raft request' (duration: 270.493216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:57:45.036253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.973351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:57:45.036294Z","caller":"traceutil/trace.go:171","msg":"trace[1206382203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1939; }","duration":"165.015532ms","start":"2026-09-16T15:57:44.871267Z","end":"2026-09-16T15:57:45.036282Z","steps":["trace[1206382203] 'agreement among raft nodes before linearized reading' (duration: 164.95286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:57:45.036287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.280255ms","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-09-16T15:57:45.036345Z","caller":"traceutil/trace.go:171","msg":"trace[549523984] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1939; }","duration":"242.392778ms","start":"2026-09-16T15:57:44.793931Z","end":"2026-09-16T15:57:45.036324Z","steps":["trace[549523984] 'agreement among raft nodes before linearized reading' (duration: 242.156631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:57:45.036966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.995698ms","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-09-16T15:57:45.037207Z","caller":"traceutil/trace.go:171","msg":"trace[1996115992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1939; }","duration":"253.335537ms","start":"2026-09-16T15:57:44.783851Z","end":"2026-09-16T15:57:45.037187Z","steps":["trace[1996115992] 'agreement among raft nodes before linearized reading' (duration: 252.240246ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:52.767289Z","caller":"traceutil/trace.go:171","msg":"trace[1091184958] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2095; }","duration":"128.9735ms","start":"2026-09-16T15:57:52.638297Z","end":"2026-09-16T15:57:52.76727Z","steps":["trace[1091184958] 'read index received' (duration: 128.766274ms)","trace[1091184958] 'applied index is now lower than readState.Index' (duration: 206.386µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:57:52.767478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.167216ms","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-09-16T15:57:52.767509Z","caller":"traceutil/trace.go:171","msg":"trace[1182839305] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2015; }","duration":"129.258498ms","start":"2026-09-16T15:57:52.638238Z","end":"2026-09-16T15:57:52.767497Z","steps":["trace[1182839305] 'agreement among raft nodes before linearized reading' (duration: 129.110724ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:52.767683Z","caller":"traceutil/trace.go:171","msg":"trace[1300489399] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"130.4393ms","start":"2026-09-16T15:57:52.637236Z","end":"2026-09-16T15:57:52.767675Z","steps":["trace[1300489399] 'process raft request' (duration: 129.892854ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:58:15.133899Z","caller":"traceutil/trace.go:171","msg":"trace[950707912] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2244; }","duration":"219.838919ms","start":"2026-09-16T15:58:14.914025Z","end":"2026-09-16T15:58:15.133864Z","steps":["trace[950707912] 'read index received' (duration: 219.646094ms)","trace[950707912] 'applied index is now lower than readState.Index' (duration: 191.195µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:58:15.134144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.090266ms","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-09-16T15:58:15.134215Z","caller":"traceutil/trace.go:171","msg":"trace[206063387] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2159; }","duration":"220.216259ms","start":"2026-09-16T15:58:14.913984Z","end":"2026-09-16T15:58:15.1342Z","steps":["trace[206063387] 'agreement among raft nodes before linearized reading' (duration: 220.009764ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:58:15.134343Z","caller":"traceutil/trace.go:171","msg":"trace[1072109526] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"254.185546ms","start":"2026-09-16T15:58:14.88012Z","end":"2026-09-16T15:58:15.134306Z","steps":["trace[1072109526] 'process raft request' (duration: 253.560309ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:58:15.204551Z","caller":"traceutil/trace.go:171","msg":"trace[1138751576] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"224.969507ms","start":"2026-09-16T15:58:14.979555Z","end":"2026-09-16T15:58:15.204525Z","steps":["trace[1138751576] 'process raft request' (duration: 224.811643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:58:15.377724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.426038ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-16T15:58:15.377902Z","caller":"traceutil/trace.go:171","msg":"trace[122946105] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2246; }","duration":"121.478552ms","start":"2026-09-16T15:58:15.256405Z","end":"2026-09-16T15:58:15.377884Z","steps":["trace[122946105] 'read index received' (duration: 45.701µs)","trace[122946105] 'applied index is now lower than readState.Index' (duration: 121.431111ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:58:15.378249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.82625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-16T15:58:15.378316Z","caller":"traceutil/trace.go:171","msg":"trace[1662968257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2160; }","duration":"121.952203ms","start":"2026-09-16T15:58:15.256346Z","end":"2026-09-16T15:58:15.378298Z","steps":["trace[1662968257] 'agreement among raft nodes before linearized reading' (duration: 121.655566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:58:15.378496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.544576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-16T15:58:15.378582Z","caller":"traceutil/trace.go:171","msg":"trace[890776939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2160; }","duration":"113.65676ms","start":"2026-09-16T15:58:15.264907Z","end":"2026-09-16T15:58:15.378564Z","steps":["trace[890776939] 'agreement among raft nodes before linearized reading' (duration: 113.454345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:58:15.378954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.206785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-16T15:58:15.379017Z","caller":"traceutil/trace.go:171","msg":"trace[1806068436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2160; }","duration":"114.292287ms","start":"2026-09-16T15:58:15.264704Z","end":"2026-09-16T15:58:15.378996Z","steps":["trace[1806068436] 'agreement among raft nodes before linearized reading' (duration: 113.675811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:58:15.379328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.662771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-16T15:58:15.379365Z","caller":"traceutil/trace.go:171","msg":"trace[1195330683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2160; }","duration":"116.702682ms","start":"2026-09-16T15:58:15.262652Z","end":"2026-09-16T15:58:15.379355Z","steps":["trace[1195330683] 'agreement among raft nodes before linearized reading' (duration: 115.420658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:58:15.707027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.888451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:58:15.707106Z","caller":"traceutil/trace.go:171","msg":"trace[1870937850] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2164; }","duration":"184.027024ms","start":"2026-09-16T15:58:15.523061Z","end":"2026-09-16T15:58:15.707088Z","steps":["trace[1870937850] 'count revisions from in-memory index tree' (duration: 183.762967ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:58:15.722308Z","caller":"traceutil/trace.go:171","msg":"trace[2109873327] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"111.851451ms","start":"2026-09-16T15:58:15.61043Z","end":"2026-09-16T15:58:15.722281Z","steps":["trace[2109873327] 'process raft request' (duration: 111.654196ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:58:15.727398Z","caller":"traceutil/trace.go:171","msg":"trace[1375350225] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"108.377708ms","start":"2026-09-16T15:58:15.619003Z","end":"2026-09-16T15:58:15.727381Z","steps":["trace[1375350225] 'process raft request' (duration: 108.289455ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:30.048178Z","caller":"traceutil/trace.go:171","msg":"trace[1473197642] linearizableReadLoop","detail":"{readStateIndex:2764; appliedIndex:2763; }","duration":"251.965533ms","start":"2026-09-16T15:59:29.796189Z","end":"2026-09-16T15:59:30.048155Z","steps":["trace[1473197642] 'read index received' (duration: 251.894931ms)","trace[1473197642] 'applied index is now lower than readState.Index' (duration: 69.732µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:59:30.048276Z","caller":"traceutil/trace.go:171","msg":"trace[1016723808] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"269.503435ms","start":"2026-09-16T15:59:29.778749Z","end":"2026-09-16T15:59:30.048252Z","steps":["trace[1016723808] 'process raft request' (duration: 269.266148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:59:30.048503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.294924ms","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-09-16T15:59:30.048562Z","caller":"traceutil/trace.go:171","msg":"trace[1874240021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2661; }","duration":"252.408437ms","start":"2026-09-16T15:59:29.796136Z","end":"2026-09-16T15:59:30.048545Z","steps":["trace[1874240021] 'agreement among raft nodes before linearized reading' (duration: 252.214681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:59:30.098583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.941924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-16T15:59:30.098611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.112436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.19\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-16T15:59:30.098611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.44848ms","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-09-16T15:59:30.098653Z","caller":"traceutil/trace.go:171","msg":"trace[604876405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2663; }","duration":"228.056757ms","start":"2026-09-16T15:59:29.870581Z","end":"2026-09-16T15:59:30.098638Z","steps":["trace[604876405] 'agreement among raft nodes before linearized reading' (duration: 227.745488ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:30.098673Z","caller":"traceutil/trace.go:171","msg":"trace[243962728] range","detail":"{range_begin:/registry/masterleases/162.253.55.19; range_end:; response_count:1; response_revision:2663; }","duration":"282.218669ms","start":"2026-09-16T15:59:29.816436Z","end":"2026-09-16T15:59:30.098655Z","steps":["trace[243962728] 'agreement among raft nodes before linearized reading' (duration: 281.90044ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:30.098677Z","caller":"traceutil/trace.go:171","msg":"trace[2092613361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2663; }","duration":"294.531222ms","start":"2026-09-16T15:59:29.804132Z","end":"2026-09-16T15:59:30.098663Z","steps":["trace[2092613361] 'agreement among raft nodes before linearized reading' (duration: 294.223374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:59:30.098822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.793333ms","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-09-16T15:59:30.098967Z","caller":"traceutil/trace.go:171","msg":"trace[22756093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2663; }","duration":"300.012161ms","start":"2026-09-16T15:59:29.798921Z","end":"2026-09-16T15:59:30.098933Z","steps":["trace[22756093] 'agreement among raft nodes before linearized reading' (duration: 299.391483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:59:30.099033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:59:29.798898Z","time spent":"300.122413ms","remote":"127.0.0.1:37694","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":"info","ts":"2026-09-16T16:00:05.667512Z","caller":"traceutil/trace.go:171","msg":"trace[411853624] linearizableReadLoop","detail":"{readStateIndex:2968; appliedIndex:2967; }","duration":"291.523763ms","start":"2026-09-16T16:00:05.375966Z","end":"2026-09-16T16:00:05.66749Z","steps":["trace[411853624] 'read index received' (duration: 291.356819ms)","trace[411853624] 'applied index is now lower than readState.Index' (duration: 166.144µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:00:05.667591Z","caller":"traceutil/trace.go:171","msg":"trace[1886722285] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"361.294327ms","start":"2026-09-16T16:00:05.306271Z","end":"2026-09-16T16:00:05.667566Z","steps":["trace[1886722285] 'process raft request' (duration: 361.050711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:00:05.667727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.74061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:00:05.667812Z","caller":"traceutil/trace.go:171","msg":"trace[569005750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2857; }","duration":"291.866134ms","start":"2026-09-16T16:00:05.375935Z","end":"2026-09-16T16:00:05.667802Z","steps":["trace[569005750] 'agreement among raft nodes before linearized reading' (duration: 291.671578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:00:05.667939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:00:05.306244Z","time spent":"361.475443ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:00:05.769351Z","caller":"traceutil/trace.go:171","msg":"trace[707638530] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"231.446593ms","start":"2026-09-16T16:00:05.537871Z","end":"2026-09-16T16:00:05.769317Z","steps":["trace[707638530] 'process raft request' (duration: 172.860954ms)","trace[707638530] 'compare' (duration: 58.299102ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:03:01.923817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.230651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-09-16T16:03:01.923915Z","caller":"traceutil/trace.go:171","msg":"trace[1038165106] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:3869; }","duration":"129.375895ms","start":"2026-09-16T16:03:01.794518Z","end":"2026-09-16T16:03:01.923894Z","steps":["trace[1038165106] 'range keys from in-memory index tree' (duration: 129.15023ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:04:39.093855Z","caller":"traceutil/trace.go:171","msg":"trace[855789749] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"219.907935ms","start":"2026-09-16T16:04:38.873915Z","end":"2026-09-16T16:04:39.093823Z","steps":["trace[855789749] 'process raft request' (duration: 188.592688ms)","trace[855789749] 'compare' (duration: 31.177563ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:04:39.122907Z","caller":"traceutil/trace.go:171","msg":"trace[1992243389] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"173.843024ms","start":"2026-09-16T16:04:38.949038Z","end":"2026-09-16T16:04:39.122881Z","steps":["trace[1992243389] 'process raft request' (duration: 173.717651ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:04:39.342258Z","caller":"traceutil/trace.go:171","msg":"trace[1113279400] linearizableReadLoop","detail":"{readStateIndex:5174; appliedIndex:5173; }","duration":"143.488693ms","start":"2026-09-16T16:04:39.198748Z","end":"2026-09-16T16:04:39.342237Z","steps":["trace[1113279400] 'read index received' (duration: 143.248097ms)","trace[1113279400] 'applied index is now lower than readState.Index' (duration: 239.836µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:04:39.342453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.696899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-16T16:04:39.342499Z","caller":"traceutil/trace.go:171","msg":"trace[1002827708] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4979; }","duration":"143.795182ms","start":"2026-09-16T16:04:39.19869Z","end":"2026-09-16T16:04:39.342485Z","steps":["trace[1002827708] 'agreement among raft nodes before linearized reading' (duration: 143.630388ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:04:39.342634Z","caller":"traceutil/trace.go:171","msg":"trace[26050924] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"164.922765ms","start":"2026-09-16T16:04:39.177687Z","end":"2026-09-16T16:04:39.34261Z","steps":["trace[26050924] 'process raft request' (duration: 164.423062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:40.129425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.192034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:04:40.129551Z","caller":"traceutil/trace.go:171","msg":"trace[1994129025] linearizableReadLoop","detail":"{readStateIndex:5185; appliedIndex:5184; }","duration":"256.747059ms","start":"2026-09-16T16:04:39.872783Z","end":"2026-09-16T16:04:40.12953Z","steps":["trace[1994129025] 'read index received' (duration: 140.36495ms)","trace[1994129025] 'applied index is now lower than readState.Index' (duration: 116.380549ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:04:40.129657Z","caller":"traceutil/trace.go:171","msg":"trace[1121828478] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"264.214529ms","start":"2026-09-16T16:04:39.865414Z","end":"2026-09-16T16:04:40.129628Z","steps":["trace[1121828478] 'process raft request' (duration: 147.726507ms)","trace[1121828478] 'compare' (duration: 115.977379ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:04:40.12974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.955464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:04:40.129796Z","caller":"traceutil/trace.go:171","msg":"trace[180168291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4989; }","duration":"257.055607ms","start":"2026-09-16T16:04:39.872728Z","end":"2026-09-16T16:04:40.129784Z","steps":["trace[180168291] 'agreement among raft nodes before linearized reading' (duration: 256.953594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:40.130007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.065294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-16T16:04:40.130055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.183121ms","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-09-16T16:04:40.130091Z","caller":"traceutil/trace.go:171","msg":"trace[153502012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4989; }","duration":"126.249613ms","start":"2026-09-16T16:04:40.003832Z","end":"2026-09-16T16:04:40.130082Z","steps":["trace[153502012] 'agreement among raft nodes before linearized reading' (duration: 126.11317ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:04:40.130136Z","caller":"traceutil/trace.go:171","msg":"trace[1765396677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4989; }","duration":"164.206136ms","start":"2026-09-16T16:04:39.965874Z","end":"2026-09-16T16:04:40.130081Z","steps":["trace[1765396677] 'agreement among raft nodes before linearized reading' (duration: 163.851826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:40.130239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.134829ms","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-09-16T16:04:40.130267Z","caller":"traceutil/trace.go:171","msg":"trace[1070900553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4989; }","duration":"123.194131ms","start":"2026-09-16T16:04:40.007066Z","end":"2026-09-16T16:04:40.13026Z","steps":["trace[1070900553] 'agreement among raft nodes before linearized reading' (duration: 123.123029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:40.130369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.727336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-16T16:04:40.130408Z","caller":"traceutil/trace.go:171","msg":"trace[635258016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4989; }","duration":"135.772006ms","start":"2026-09-16T16:04:39.994615Z","end":"2026-09-16T16:04:40.130387Z","steps":["trace[635258016] 'agreement among raft nodes before linearized reading' (duration: 135.707805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:40.130401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.704842ms","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-09-16T16:04:40.13044Z","caller":"traceutil/trace.go:171","msg":"trace[1996641272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4989; }","duration":"127.767433ms","start":"2026-09-16T16:04:40.002662Z","end":"2026-09-16T16:04:40.130429Z","steps":["trace[1996641272] 'agreement among raft nodes before linearized reading' (duration: 127.62844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:45.968252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.092379ms","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-09-16T16:04:45.968373Z","caller":"traceutil/trace.go:171","msg":"trace[600200936] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5087; }","duration":"104.239003ms","start":"2026-09-16T16:04:45.864117Z","end":"2026-09-16T16:04:45.968356Z","steps":["trace[600200936] 'range keys from in-memory index tree' (duration: 104.072919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:45.968955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.653017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:04:45.969185Z","caller":"traceutil/trace.go:171","msg":"trace[776101887] linearizableReadLoop","detail":"{readStateIndex:5287; appliedIndex:5285; }","duration":"370.963217ms","start":"2026-09-16T16:04:45.598205Z","end":"2026-09-16T16:04:45.969168Z","steps":["trace[776101887] 'read index received' (duration: 27.491µs)","trace[776101887] 'applied index is now lower than readState.Index' (duration: 370.934886ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:04:45.96927Z","caller":"traceutil/trace.go:171","msg":"trace[491948840] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"455.665329ms","start":"2026-09-16T16:04:45.513594Z","end":"2026-09-16T16:04:45.969259Z","steps":["trace[491948840] 'process raft request' (duration: 47.584771ms)","trace[491948840] 'compare' (duration: 407.448712ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:04:45.969328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:04:45.513556Z","time spent":"455.738731ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T16:04:45.969532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.297905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86d1893e-ce2b-411d-bb45-1d0b72030568\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-09-16T16:04:45.969635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.822127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18d5d8b1acac7f6a\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-09-16T16:04:45.96966Z","caller":"traceutil/trace.go:171","msg":"trace[722512770] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-86d1893e-ce2b-411d-bb45-1d0b72030568; range_end:; response_count:1; response_revision:5089; }","duration":"371.44139ms","start":"2026-09-16T16:04:45.598198Z","end":"2026-09-16T16:04:45.96964Z","steps":["trace[722512770] 'agreement among raft nodes before linearized reading' (duration: 371.188612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:45.96972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:04:45.598142Z","time spent":"371.563433ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86d1893e-ce2b-411d-bb45-1d0b72030568\" "} {"level":"info","ts":"2026-09-16T16:04:45.96972Z","caller":"traceutil/trace.go:171","msg":"trace[236087442] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"404.256606ms","start":"2026-09-16T16:04:45.565445Z","end":"2026-09-16T16:04:45.969701Z","steps":["trace[236087442] 'process raft request' (duration: 403.66181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:45.969813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:04:45.565417Z","time spent":"404.351649ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:04:45.969677Z","caller":"traceutil/trace.go:171","msg":"trace[2057805040] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18d5d8b1acac7f6a; range_end:; response_count:1; response_revision:5089; }","duration":"138.863198ms","start":"2026-09-16T16:04:45.830802Z","end":"2026-09-16T16:04:45.969665Z","steps":["trace[2057805040] 'agreement among raft nodes before linearized reading' (duration: 138.713345ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:04:46.468792Z","caller":"traceutil/trace.go:171","msg":"trace[561560941] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"284.662223ms","start":"2026-09-16T16:04:46.184107Z","end":"2026-09-16T16:04:46.468769Z","steps":["trace[561560941] 'process raft request' (duration: 284.602231ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:04:46.469001Z","caller":"traceutil/trace.go:171","msg":"trace[1121119110] linearizableReadLoop","detail":"{readStateIndex:5288; appliedIndex:5287; }","duration":"314.057688ms","start":"2026-09-16T16:04:46.154921Z","end":"2026-09-16T16:04:46.468979Z","steps":["trace[1121119110] 'read index received' (duration: 262.383147ms)","trace[1121119110] 'applied index is now lower than readState.Index' (duration: 51.672561ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:04:46.46901Z","caller":"traceutil/trace.go:171","msg":"trace[2090329485] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"495.779521ms","start":"2026-09-16T16:04:45.973195Z","end":"2026-09-16T16:04:46.468975Z","steps":["trace[2090329485] 'process raft request' (duration: 444.142531ms)","trace[2090329485] 'compare' (duration: 51.107395ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:04:46.469135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.206951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:04:46.469183Z","caller":"traceutil/trace.go:171","msg":"trace[1585703730] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5091; }","duration":"314.307014ms","start":"2026-09-16T16:04:46.154861Z","end":"2026-09-16T16:04:46.469168Z","steps":["trace[1585703730] 'agreement among raft nodes before linearized reading' (duration: 314.224922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:46.469219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:04:46.154837Z","time spent":"314.370676ms","remote":"127.0.0.1:37488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-09-16T16:04:46.46921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:04:45.973177Z","time spent":"495.968276ms","remote":"127.0.0.1:37482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T16:04:46.469316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.29014ms","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-09-16T16:04:46.469368Z","caller":"traceutil/trace.go:171","msg":"trace[1002898627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5091; }","duration":"239.378212ms","start":"2026-09-16T16:04:46.229978Z","end":"2026-09-16T16:04:46.469357Z","steps":["trace[1002898627] 'agreement among raft nodes before linearized reading' (duration: 239.218588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:46.469382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.597464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-16T16:04:46.469531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.139726ms","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-09-16T16:04:46.469542Z","caller":"traceutil/trace.go:171","msg":"trace[954949182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5091; }","duration":"298.673576ms","start":"2026-09-16T16:04:46.170733Z","end":"2026-09-16T16:04:46.469407Z","steps":["trace[954949182] 'agreement among raft nodes before linearized reading' (duration: 298.466691ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:04:46.469688Z","caller":"traceutil/trace.go:171","msg":"trace[608410870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5091; }","duration":"242.29871ms","start":"2026-09-16T16:04:46.227262Z","end":"2026-09-16T16:04:46.469561Z","steps":["trace[608410870] 'agreement among raft nodes before linearized reading' (duration: 242.193267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:46.469848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.067848ms","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-09-16T16:04:46.469934Z","caller":"traceutil/trace.go:171","msg":"trace[1385939611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5091; }","duration":"265.186213ms","start":"2026-09-16T16:04:46.204733Z","end":"2026-09-16T16:04:46.46992Z","steps":["trace[1385939611] 'agreement among raft nodes before linearized reading' (duration: 265.004267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:46.469733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.91254ms","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-09-16T16:04:46.471782Z","caller":"traceutil/trace.go:171","msg":"trace[740558242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5091; }","duration":"243.966595ms","start":"2026-09-16T16:04:46.227778Z","end":"2026-09-16T16:04:46.471744Z","steps":["trace[740558242] 'agreement among raft nodes before linearized reading' (duration: 241.88964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:04:47.014765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.398357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:04:47.014877Z","caller":"traceutil/trace.go:171","msg":"trace[238972383] linearizableReadLoop","detail":"{readStateIndex:5300; appliedIndex:5299; }","duration":"145.883506ms","start":"2026-09-16T16:04:46.868973Z","end":"2026-09-16T16:04:47.014856Z","steps":["trace[238972383] 'read index received' (duration: 52.231µs)","trace[238972383] 'applied index is now lower than readState.Index' (duration: 145.829135ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:04:47.015076Z","caller":"traceutil/trace.go:171","msg":"trace[1244269981] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"177.210202ms","start":"2026-09-16T16:04:46.837835Z","end":"2026-09-16T16:04:47.015045Z","steps":["trace[1244269981] 'process raft request' (duration: 29.424095ms)","trace[1244269981] 'compare' (duration: 147.224592ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:04:47.015154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.117181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:04:47.015319Z","caller":"traceutil/trace.go:171","msg":"trace[1938238386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5102; }","duration":"146.335639ms","start":"2026-09-16T16:04:46.868914Z","end":"2026-09-16T16:04:47.01525Z","steps":["trace[1938238386] 'agreement among raft nodes before linearized reading' (duration: 146.105272ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:04:47.319201Z","caller":"traceutil/trace.go:171","msg":"trace[1695171482] linearizableReadLoop","detail":"{readStateIndex:5301; appliedIndex:5300; }","duration":"123.051795ms","start":"2026-09-16T16:04:47.196107Z","end":"2026-09-16T16:04:47.319159Z","steps":["trace[1695171482] 'read index received' (duration: 77.304154ms)","trace[1695171482] 'applied index is now lower than readState.Index' (duration: 45.746711ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:04:47.319234Z","caller":"traceutil/trace.go:171","msg":"trace[1165465334] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"158.904733ms","start":"2026-09-16T16:04:47.160288Z","end":"2026-09-16T16:04:47.319193Z","steps":["trace[1165465334] 'process raft request' (duration: 113.171772ms)","trace[1165465334] 'compare' (duration: 45.595508ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:04:47.319535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.401456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6pxx8.18d5d8b0b1c28ab7\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-09-16T16:04:47.319616Z","caller":"traceutil/trace.go:171","msg":"trace[1502703004] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6pxx8.18d5d8b0b1c28ab7; range_end:; response_count:1; response_revision:5103; }","duration":"123.55243ms","start":"2026-09-16T16:04:47.19605Z","end":"2026-09-16T16:04:47.319602Z","steps":["trace[1502703004] 'agreement among raft nodes before linearized reading' (duration: 123.21778ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:04:47.52799Z","caller":"traceutil/trace.go:171","msg":"trace[152964909] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"120.353004ms","start":"2026-09-16T16:04:47.407616Z","end":"2026-09-16T16:04:47.527969Z","steps":["trace[152964909] 'process raft request' (duration: 120.224941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:02.816222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.898777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17232"} {"level":"info","ts":"2026-09-16T16:05:02.816323Z","caller":"traceutil/trace.go:171","msg":"trace[1909513230] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5295; }","duration":"226.037731ms","start":"2026-09-16T16:05:02.590261Z","end":"2026-09-16T16:05:02.816299Z","steps":["trace[1909513230] 'range keys from in-memory index tree' (duration: 225.814265ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:02.816711Z","caller":"traceutil/trace.go:171","msg":"trace[1713751742] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"225.291461ms","start":"2026-09-16T16:05:02.591401Z","end":"2026-09-16T16:05:02.816692Z","steps":["trace[1713751742] 'process raft request' (duration: 225.090956ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:03.308248Z","caller":"traceutil/trace.go:171","msg":"trace[1105796438] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"716.229742ms","start":"2026-09-16T16:05:02.592003Z","end":"2026-09-16T16:05:03.308233Z","steps":["trace[1105796438] 'process raft request' (duration: 716.106527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:03.30837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.591988Z","time spent":"716.316534ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T16:05:03.308514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.40263ms","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-09-16T16:05:03.308573Z","caller":"traceutil/trace.go:171","msg":"trace[1010369839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5299; }","duration":"706.494451ms","start":"2026-09-16T16:05:02.602069Z","end":"2026-09-16T16:05:03.308563Z","steps":["trace[1010369839] 'agreement among raft nodes before linearized reading' (duration: 706.351587ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:03.308651Z","caller":"traceutil/trace.go:171","msg":"trace[386819965] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"717.003321ms","start":"2026-09-16T16:05:02.591613Z","end":"2026-09-16T16:05:03.308617Z","steps":["trace[386819965] 'process raft request' (duration: 699.178126ms)","trace[386819965] 'compare' (duration: 16.968693ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:05:03.308734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.602054Z","time spent":"706.645956ms","remote":"127.0.0.1:37694","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-09-16T16:05:03.308801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.591564Z","time spent":"717.155176ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:05:03.308233Z","caller":"traceutil/trace.go:171","msg":"trace[171180180] linearizableReadLoop","detail":"{readStateIndex:5503; appliedIndex:5499; }","duration":"706.097231ms","start":"2026-09-16T16:05:02.602118Z","end":"2026-09-16T16:05:03.308215Z","steps":["trace[171180180] 'read index received' (duration: 214.491703ms)","trace[171180180] 'applied index is now lower than readState.Index' (duration: 491.604758ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:03.308924Z","caller":"traceutil/trace.go:171","msg":"trace[1275895091] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"716.555059ms","start":"2026-09-16T16:05:02.592359Z","end":"2026-09-16T16:05:03.308914Z","steps":["trace[1275895091] 'process raft request' (duration: 715.81705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:03.308995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.592335Z","time spent":"716.630401ms","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:05:03.320156Z","caller":"traceutil/trace.go:171","msg":"trace[1272633419] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"485.540136ms","start":"2026-09-16T16:05:02.834595Z","end":"2026-09-16T16:05:03.320136Z","steps":["trace[1272633419] 'process raft request' (duration: 485.344421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:03.320281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.834554Z","time spent":"485.655399ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T16:05:03.320715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.961066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:05:03.320693Z","caller":"traceutil/trace.go:171","msg":"trace[447594951] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"245.048639ms","start":"2026-09-16T16:05:03.075617Z","end":"2026-09-16T16:05:03.320666Z","steps":["trace[447594951] 'process raft request' (duration: 244.462273ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:03.320746Z","caller":"traceutil/trace.go:171","msg":"trace[1485395010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5301; }","duration":"450.029948ms","start":"2026-09-16T16:05:02.870708Z","end":"2026-09-16T16:05:03.320738Z","steps":["trace[1485395010] 'agreement among raft nodes before linearized reading' (duration: 449.981487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:03.320765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.87069Z","time spent":"450.07176ms","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-16T16:05:03.320827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.360912ms","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-09-16T16:05:03.320974Z","caller":"traceutil/trace.go:171","msg":"trace[1474060868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5301; }","duration":"696.526896ms","start":"2026-09-16T16:05:02.624399Z","end":"2026-09-16T16:05:03.320926Z","steps":["trace[1474060868] 'agreement among raft nodes before linearized reading' (duration: 696.233348ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:03.32104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.600381ms","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-09-16T16:05:03.321067Z","caller":"traceutil/trace.go:171","msg":"trace[415422559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5301; }","duration":"701.639501ms","start":"2026-09-16T16:05:02.619419Z","end":"2026-09-16T16:05:03.321059Z","steps":["trace[415422559] 'agreement among raft nodes before linearized reading' (duration: 701.58833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:03.321083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.619411Z","time spent":"701.668052ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-16T16:05:03.321096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.624376Z","time spent":"696.647258ms","remote":"127.0.0.1:37694","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-09-16T16:05:03.321168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.269859ms","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-09-16T16:05:03.321191Z","caller":"traceutil/trace.go:171","msg":"trace[1971202050] 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:5301; }","duration":"702.357451ms","start":"2026-09-16T16:05:02.618825Z","end":"2026-09-16T16:05:03.321182Z","steps":["trace[1971202050] 'agreement among raft nodes before linearized reading' (duration: 702.30419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:03.32122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.618812Z","time spent":"702.389352ms","remote":"127.0.0.1:37572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-09-16T16:05:03.320996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.915129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-16T16:05:03.321301Z","caller":"traceutil/trace.go:171","msg":"trace[2086367373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5301; }","duration":"702.221718ms","start":"2026-09-16T16:05:02.619044Z","end":"2026-09-16T16:05:03.321266Z","steps":["trace[2086367373] 'agreement among raft nodes before linearized reading' (duration: 701.885619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:03.321371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.619037Z","time spent":"702.29532ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-16T16:05:03.321496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.375879ms","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-09-16T16:05:03.321526Z","caller":"traceutil/trace.go:171","msg":"trace[981119958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5301; }","duration":"703.443041ms","start":"2026-09-16T16:05:02.618076Z","end":"2026-09-16T16:05:03.32152Z","steps":["trace[981119958] 'agreement among raft nodes before linearized reading' (duration: 702.519526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:03.321548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:02.618055Z","time spent":"703.484561ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-16T16:05:06.844175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2867} {"level":"info","ts":"2026-09-16T16:05:06.944145Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2867,"took":"98.733955ms","hash":3320888235,"current-db-size-bytes":52985856,"current-db-size":"53 MB","current-db-size-in-use-bytes":27766784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-16T16:05:06.944196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3320888235,"revision":2867,"compact-revision":-1} {"level":"info","ts":"2026-09-16T16:05:26.374784Z","caller":"traceutil/trace.go:171","msg":"trace[1569939297] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"122.31597ms","start":"2026-09-16T16:05:26.252426Z","end":"2026-09-16T16:05:26.374742Z","steps":["trace[1569939297] 'process raft request' (duration: 107.962077ms)","trace[1569939297] 'compare' (duration: 14.033525ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:26.374864Z","caller":"traceutil/trace.go:171","msg":"trace[1846884778] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"114.759549ms","start":"2026-09-16T16:05:26.260096Z","end":"2026-09-16T16:05:26.374856Z","steps":["trace[1846884778] 'process raft request' (duration: 114.599915ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:28.488868Z","caller":"traceutil/trace.go:171","msg":"trace[964185111] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"109.019494ms","start":"2026-09-16T16:05:28.379829Z","end":"2026-09-16T16:05:28.488848Z","steps":["trace[964185111] 'process raft request' (duration: 108.906262ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:28.503Z","caller":"traceutil/trace.go:171","msg":"trace[242942213] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"123.1014ms","start":"2026-09-16T16:05:28.379874Z","end":"2026-09-16T16:05:28.502975Z","steps":["trace[242942213] 'process raft request' (duration: 122.975747ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:29.964907Z","caller":"traceutil/trace.go:171","msg":"trace[353389276] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"503.168292ms","start":"2026-09-16T16:05:29.461715Z","end":"2026-09-16T16:05:29.964884Z","steps":["trace[353389276] 'process raft request' (duration: 502.934986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:29.965048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:29.461692Z","time spent":"503.284284ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:05:29.980956Z","caller":"traceutil/trace.go:171","msg":"trace[1712506437] linearizableReadLoop","detail":"{readStateIndex:5674; appliedIndex:5673; }","duration":"325.429524ms","start":"2026-09-16T16:05:29.65549Z","end":"2026-09-16T16:05:29.98092Z","steps":["trace[1712506437] 'read index received' (duration: 309.766206ms)","trace[1712506437] 'applied index is now lower than readState.Index' (duration: 15.661778ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:29.981056Z","caller":"traceutil/trace.go:171","msg":"trace[214427015] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"412.859366ms","start":"2026-09-16T16:05:29.568157Z","end":"2026-09-16T16:05:29.981016Z","steps":["trace[214427015] 'process raft request' (duration: 412.519396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:29.981279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:29.568115Z","time spent":"413.013829ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T16:05:29.981518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.006109ms","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-09-16T16:05:29.981849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.992843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-16T16:05:29.981885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.421793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.19\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-16T16:05:29.981898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.005545ms","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-09-16T16:05:29.981947Z","caller":"traceutil/trace.go:171","msg":"trace[1291053180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5464; }","duration":"229.056126ms","start":"2026-09-16T16:05:29.752879Z","end":"2026-09-16T16:05:29.981935Z","steps":["trace[1291053180] 'agreement among raft nodes before linearized reading' (duration: 228.916851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:29.982004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.444335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/keystone-openid-metadata\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-16T16:05:29.981964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.775542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:05:29.981949Z","caller":"traceutil/trace.go:171","msg":"trace[1266791553] range","detail":"{range_begin:/registry/masterleases/162.253.55.19; range_end:; response_count:1; response_revision:5464; }","duration":"146.512345ms","start":"2026-09-16T16:05:29.835418Z","end":"2026-09-16T16:05:29.98193Z","steps":["trace[1266791553] 'agreement among raft nodes before linearized reading' (duration: 146.221126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:29.981813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.502377ms","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-09-16T16:05:29.982168Z","caller":"traceutil/trace.go:171","msg":"trace[1394741265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5464; }","duration":"208.891548ms","start":"2026-09-16T16:05:29.773255Z","end":"2026-09-16T16:05:29.982147Z","steps":["trace[1394741265] 'agreement among raft nodes before linearized reading' (duration: 208.310633ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:29.981912Z","caller":"traceutil/trace.go:171","msg":"trace[1832867734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5464; }","duration":"208.070546ms","start":"2026-09-16T16:05:29.773824Z","end":"2026-09-16T16:05:29.981895Z","steps":["trace[1832867734] 'agreement among raft nodes before linearized reading' (duration: 207.819459ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:29.981857Z","caller":"traceutil/trace.go:171","msg":"trace[163125193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5464; }","duration":"326.372968ms","start":"2026-09-16T16:05:29.655424Z","end":"2026-09-16T16:05:29.981797Z","steps":["trace[163125193] 'agreement among raft nodes before linearized reading' (duration: 325.724452ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:29.982088Z","caller":"traceutil/trace.go:171","msg":"trace[90411319] range","detail":"{range_begin:/registry/configmaps/openstack/keystone-openid-metadata; range_end:; response_count:0; response_revision:5464; }","duration":"233.149544ms","start":"2026-09-16T16:05:29.748917Z","end":"2026-09-16T16:05:29.982066Z","steps":["trace[90411319] 'agreement among raft nodes before linearized reading' (duration: 232.409844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:29.981576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.382298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-16T16:05:29.982333Z","caller":"traceutil/trace.go:171","msg":"trace[400825663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5464; }","duration":"111.43837ms","start":"2026-09-16T16:05:29.870739Z","end":"2026-09-16T16:05:29.982177Z","steps":["trace[400825663] 'agreement among raft nodes before linearized reading' (duration: 110.752242ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:29.982702Z","caller":"traceutil/trace.go:171","msg":"trace[1138309308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5464; }","duration":"178.558969ms","start":"2026-09-16T16:05:29.804129Z","end":"2026-09-16T16:05:29.982688Z","steps":["trace[1138309308] 'agreement among raft nodes before linearized reading' (duration: 177.295776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:29.982475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:29.655394Z","time spent":"327.059807ms","remote":"127.0.0.1:37694","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":"info","ts":"2026-09-16T16:05:30.104389Z","caller":"traceutil/trace.go:171","msg":"trace[1201360856] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"117.101692ms","start":"2026-09-16T16:05:29.987253Z","end":"2026-09-16T16:05:30.104355Z","steps":["trace[1201360856] 'process raft request' (duration: 74.097376ms)","trace[1201360856] 'compare' (duration: 42.740958ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:30.104421Z","caller":"traceutil/trace.go:171","msg":"trace[1488935444] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"116.939577ms","start":"2026-09-16T16:05:29.987464Z","end":"2026-09-16T16:05:30.104403Z","steps":["trace[1488935444] 'process raft request' (duration: 116.837854ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:30.104567Z","caller":"traceutil/trace.go:171","msg":"trace[1259748924] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"115.159919ms","start":"2026-09-16T16:05:29.989397Z","end":"2026-09-16T16:05:30.104557Z","steps":["trace[1259748924] 'process raft request' (duration: 114.970214ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:30.104699Z","caller":"traceutil/trace.go:171","msg":"trace[257434760] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"114.681426ms","start":"2026-09-16T16:05:29.990006Z","end":"2026-09-16T16:05:30.104687Z","steps":["trace[257434760] 'process raft request' (duration: 114.538332ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:30.104835Z","caller":"traceutil/trace.go:171","msg":"trace[207321005] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"114.916503ms","start":"2026-09-16T16:05:29.989909Z","end":"2026-09-16T16:05:30.104826Z","steps":["trace[207321005] 'process raft request' (duration: 114.554423ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:30.104682Z","caller":"traceutil/trace.go:171","msg":"trace[1635723604] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"114.288895ms","start":"2026-09-16T16:05:29.990369Z","end":"2026-09-16T16:05:30.104658Z","steps":["trace[1635723604] 'process raft request' (duration: 114.212923ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:35.963755Z","caller":"traceutil/trace.go:171","msg":"trace[445915120] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"105.536682ms","start":"2026-09-16T16:05:35.858194Z","end":"2026-09-16T16:05:35.963731Z","steps":["trace[445915120] 'process raft request' (duration: 105.258735ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.161659Z","caller":"traceutil/trace.go:171","msg":"trace[1550900601] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"112.459497ms","start":"2026-09-16T16:05:36.049177Z","end":"2026-09-16T16:05:36.161636Z","steps":["trace[1550900601] 'process raft request' (duration: 112.140418ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.505437Z","caller":"traceutil/trace.go:171","msg":"trace[370976732] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"204.858228ms","start":"2026-09-16T16:05:36.300548Z","end":"2026-09-16T16:05:36.505406Z","steps":["trace[370976732] 'process raft request' (duration: 157.808375ms)","trace[370976732] 'compare' (duration: 46.699054ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:36.505772Z","caller":"traceutil/trace.go:171","msg":"trace[1965062955] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"202.770413ms","start":"2026-09-16T16:05:36.302974Z","end":"2026-09-16T16:05:36.505744Z","steps":["trace[1965062955] 'process raft request' (duration: 202.536408ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.505861Z","caller":"traceutil/trace.go:171","msg":"trace[1109513608] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"205.179097ms","start":"2026-09-16T16:05:36.300669Z","end":"2026-09-16T16:05:36.505848Z","steps":["trace[1109513608] 'process raft request' (duration: 204.519049ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.505916Z","caller":"traceutil/trace.go:171","msg":"trace[860959869] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"202.599538ms","start":"2026-09-16T16:05:36.303302Z","end":"2026-09-16T16:05:36.505901Z","steps":["trace[860959869] 'process raft request' (duration: 202.363041ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.505925Z","caller":"traceutil/trace.go:171","msg":"trace[266827937] linearizableReadLoop","detail":"{readStateIndex:5720; appliedIndex:5716; }","duration":"203.205846ms","start":"2026-09-16T16:05:36.302698Z","end":"2026-09-16T16:05:36.505904Z","steps":["trace[266827937] 'read index received' (duration: 36.291528ms)","trace[266827937] 'applied index is now lower than readState.Index' (duration: 166.913628ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:36.506069Z","caller":"traceutil/trace.go:171","msg":"trace[1495097067] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"202.948919ms","start":"2026-09-16T16:05:36.303111Z","end":"2026-09-16T16:05:36.50606Z","steps":["trace[1495097067] 'process raft request' (duration: 202.432334ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.506092Z","caller":"traceutil/trace.go:171","msg":"trace[401856763] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"202.821634ms","start":"2026-09-16T16:05:36.303259Z","end":"2026-09-16T16:05:36.50608Z","steps":["trace[401856763] 'process raft request' (duration: 202.331891ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.506105Z","caller":"traceutil/trace.go:171","msg":"trace[1594920893] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"202.743162ms","start":"2026-09-16T16:05:36.303352Z","end":"2026-09-16T16:05:36.506095Z","steps":["trace[1594920893] 'process raft request' (duration: 202.331221ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.506169Z","caller":"traceutil/trace.go:171","msg":"trace[1627674814] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"202.870365ms","start":"2026-09-16T16:05:36.303291Z","end":"2026-09-16T16:05:36.506162Z","steps":["trace[1627674814] 'process raft request' (duration: 202.350411ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.5062Z","caller":"traceutil/trace.go:171","msg":"trace[1312974149] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"203.058331ms","start":"2026-09-16T16:05:36.303113Z","end":"2026-09-16T16:05:36.506171Z","steps":["trace[1312974149] 'process raft request' (duration: 202.452045ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.505919Z","caller":"traceutil/trace.go:171","msg":"trace[173513056] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"203.058012ms","start":"2026-09-16T16:05:36.302848Z","end":"2026-09-16T16:05:36.505906Z","steps":["trace[173513056] 'process raft request' (duration: 202.622249ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.505785Z","caller":"traceutil/trace.go:171","msg":"trace[33336776] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"205.064205ms","start":"2026-09-16T16:05:36.300696Z","end":"2026-09-16T16:05:36.50576Z","steps":["trace[33336776] 'process raft request' (duration: 204.710055ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.506309Z","caller":"traceutil/trace.go:171","msg":"trace[284685330] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"202.936017ms","start":"2026-09-16T16:05:36.303338Z","end":"2026-09-16T16:05:36.506274Z","steps":["trace[284685330] 'process raft request' (duration: 202.363421ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.506393Z","caller":"traceutil/trace.go:171","msg":"trace[321487572] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"202.947098ms","start":"2026-09-16T16:05:36.303435Z","end":"2026-09-16T16:05:36.506382Z","steps":["trace[321487572] 'process raft request' (duration: 202.413954ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.506572Z","caller":"traceutil/trace.go:171","msg":"trace[1175890833] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"203.170653ms","start":"2026-09-16T16:05:36.303382Z","end":"2026-09-16T16:05:36.506553Z","steps":["trace[1175890833] 'process raft request' (duration: 202.421944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:36.506705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.981945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-16T16:05:36.506738Z","caller":"traceutil/trace.go:171","msg":"trace[2141419430] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5520; }","duration":"204.065968ms","start":"2026-09-16T16:05:36.302664Z","end":"2026-09-16T16:05:36.50673Z","steps":["trace[2141419430] 'agreement among raft nodes before linearized reading' (duration: 203.875212ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.506777Z","caller":"traceutil/trace.go:171","msg":"trace[1556604281] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"203.412311ms","start":"2026-09-16T16:05:36.303353Z","end":"2026-09-16T16:05:36.506766Z","steps":["trace[1556604281] 'process raft request' (duration: 202.367622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:36.506801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.768773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:62000"} {"level":"warn","ts":"2026-09-16T16:05:36.506851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.719992ms","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-09-16T16:05:36.506866Z","caller":"traceutil/trace.go:171","msg":"trace[596036955] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5520; }","duration":"199.876176ms","start":"2026-09-16T16:05:36.306974Z","end":"2026-09-16T16:05:36.50685Z","steps":["trace[596036955] 'agreement among raft nodes before linearized reading' (duration: 199.576647ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.506889Z","caller":"traceutil/trace.go:171","msg":"trace[882031045] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5520; }","duration":"181.767183ms","start":"2026-09-16T16:05:36.325112Z","end":"2026-09-16T16:05:36.506879Z","steps":["trace[882031045] 'agreement among raft nodes before linearized reading' (duration: 181.64143ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.506952Z","caller":"traceutil/trace.go:171","msg":"trace[218186512] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"203.543174ms","start":"2026-09-16T16:05:36.3034Z","end":"2026-09-16T16:05:36.506943Z","steps":["trace[218186512] 'process raft request' (duration: 202.430095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:36.72415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.352803ms","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-09-16T16:05:36.724209Z","caller":"traceutil/trace.go:171","msg":"trace[1187285589] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5531; }","duration":"115.445266ms","start":"2026-09-16T16:05:36.608747Z","end":"2026-09-16T16:05:36.724193Z","steps":["trace[1187285589] 'agreement among raft nodes before linearized reading' (duration: 115.293532ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.84325Z","caller":"traceutil/trace.go:171","msg":"trace[1109707723] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"129.783889ms","start":"2026-09-16T16:05:36.713439Z","end":"2026-09-16T16:05:36.843223Z","steps":["trace[1109707723] 'process raft request' (duration: 126.681806ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.843338Z","caller":"traceutil/trace.go:171","msg":"trace[1502731581] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"129.660805ms","start":"2026-09-16T16:05:36.713657Z","end":"2026-09-16T16:05:36.843318Z","steps":["trace[1502731581] 'process raft request' (duration: 129.568053ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.843357Z","caller":"traceutil/trace.go:171","msg":"trace[1787539363] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"129.705736ms","start":"2026-09-16T16:05:36.713639Z","end":"2026-09-16T16:05:36.843345Z","steps":["trace[1787539363] 'process raft request' (duration: 129.502361ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.843347Z","caller":"traceutil/trace.go:171","msg":"trace[970179885] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"129.671675ms","start":"2026-09-16T16:05:36.713643Z","end":"2026-09-16T16:05:36.843315Z","steps":["trace[970179885] 'process raft request' (duration: 129.526852ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.843523Z","caller":"traceutil/trace.go:171","msg":"trace[525966847] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"129.83765ms","start":"2026-09-16T16:05:36.713675Z","end":"2026-09-16T16:05:36.843513Z","steps":["trace[525966847] 'process raft request' (duration: 129.772789ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.843511Z","caller":"traceutil/trace.go:171","msg":"trace[841755443] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"129.800399ms","start":"2026-09-16T16:05:36.713694Z","end":"2026-09-16T16:05:36.843495Z","steps":["trace[841755443] 'process raft request' (duration: 129.559593ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.84359Z","caller":"traceutil/trace.go:171","msg":"trace[1747560983] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"129.821429ms","start":"2026-09-16T16:05:36.713681Z","end":"2026-09-16T16:05:36.843503Z","steps":["trace[1747560983] 'process raft request' (duration: 129.598253ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.843709Z","caller":"traceutil/trace.go:171","msg":"trace[341336368] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"129.941113ms","start":"2026-09-16T16:05:36.71375Z","end":"2026-09-16T16:05:36.843691Z","steps":["trace[341336368] 'process raft request' (duration: 129.780639ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.843746Z","caller":"traceutil/trace.go:171","msg":"trace[961034155] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"129.966763ms","start":"2026-09-16T16:05:36.71374Z","end":"2026-09-16T16:05:36.843707Z","steps":["trace[961034155] 'process raft request' (duration: 129.764158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:36.844015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.218064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-16T16:05:36.844174Z","caller":"traceutil/trace.go:171","msg":"trace[1484838415] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5543; }","duration":"132.424499ms","start":"2026-09-16T16:05:36.711729Z","end":"2026-09-16T16:05:36.844154Z","steps":["trace[1484838415] 'agreement among raft nodes before linearized reading' (duration: 132.07708ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.844543Z","caller":"traceutil/trace.go:171","msg":"trace[1698784077] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"130.764524ms","start":"2026-09-16T16:05:36.713759Z","end":"2026-09-16T16:05:36.844523Z","steps":["trace[1698784077] 'process raft request' (duration: 129.87151ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.843569Z","caller":"traceutil/trace.go:171","msg":"trace[1961690356] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"129.856471ms","start":"2026-09-16T16:05:36.713703Z","end":"2026-09-16T16:05:36.84356Z","steps":["trace[1961690356] 'process raft request' (duration: 129.774499ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.844694Z","caller":"traceutil/trace.go:171","msg":"trace[1542273903] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"130.908929ms","start":"2026-09-16T16:05:36.713776Z","end":"2026-09-16T16:05:36.844685Z","steps":["trace[1542273903] 'process raft request' (duration: 129.904672ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:36.84378Z","caller":"traceutil/trace.go:171","msg":"trace[2031068893] linearizableReadLoop","detail":"{readStateIndex:5756; appliedIndex:5744; }","duration":"119.816114ms","start":"2026-09-16T16:05:36.723951Z","end":"2026-09-16T16:05:36.843767Z","steps":["trace[2031068893] 'read index received' (duration: 116.115735ms)","trace[2031068893] 'applied index is now lower than readState.Index' (duration: 3.699049ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:05:36.84492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.862054ms","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-09-16T16:05:36.844979Z","caller":"traceutil/trace.go:171","msg":"trace[2117192996] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5543; }","duration":"116.942185ms","start":"2026-09-16T16:05:36.72802Z","end":"2026-09-16T16:05:36.844962Z","steps":["trace[2117192996] 'agreement among raft nodes before linearized reading' (duration: 116.797302ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.043472Z","caller":"traceutil/trace.go:171","msg":"trace[1548415294] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"100.632971ms","start":"2026-09-16T16:05:36.94282Z","end":"2026-09-16T16:05:37.043452Z","steps":["trace[1548415294] 'process raft request' (duration: 100.447286ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.043718Z","caller":"traceutil/trace.go:171","msg":"trace[1124839272] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"100.325574ms","start":"2026-09-16T16:05:36.943369Z","end":"2026-09-16T16:05:37.043694Z","steps":["trace[1124839272] 'process raft request' (duration: 100.004015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:37.194378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.383544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-09-16T16:05:37.194441Z","caller":"traceutil/trace.go:171","msg":"trace[1443310315] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5566; }","duration":"124.494517ms","start":"2026-09-16T16:05:37.069932Z","end":"2026-09-16T16:05:37.194426Z","steps":["trace[1443310315] 'agreement among raft nodes before linearized reading' (duration: 124.327263ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.346195Z","caller":"traceutil/trace.go:171","msg":"trace[1945277606] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"100.888988ms","start":"2026-09-16T16:05:37.245286Z","end":"2026-09-16T16:05:37.346175Z","steps":["trace[1945277606] 'process raft request' (duration: 100.727474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:37.346378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.361883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/keystone-api\" ","response":"range_response_count:1 size:11114"} {"level":"info","ts":"2026-09-16T16:05:37.346498Z","caller":"traceutil/trace.go:171","msg":"trace[350057354] range","detail":"{range_begin:/registry/deployments/openstack/keystone-api; range_end:; response_count:1; response_revision:5568; }","duration":"142.486797ms","start":"2026-09-16T16:05:37.203972Z","end":"2026-09-16T16:05:37.346459Z","steps":["trace[350057354] 'agreement among raft nodes before linearized reading' (duration: 142.21163ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.403325Z","caller":"traceutil/trace.go:171","msg":"trace[1786003116] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"107.221047ms","start":"2026-09-16T16:05:37.296082Z","end":"2026-09-16T16:05:37.403303Z","steps":["trace[1786003116] 'process raft request' (duration: 107.003941ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.403434Z","caller":"traceutil/trace.go:171","msg":"trace[1621989816] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"106.56601ms","start":"2026-09-16T16:05:37.296849Z","end":"2026-09-16T16:05:37.403415Z","steps":["trace[1621989816] 'process raft request' (duration: 106.425586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:37.403522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.85194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-16T16:05:37.403589Z","caller":"traceutil/trace.go:171","msg":"trace[1141190768] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5572; }","duration":"105.948203ms","start":"2026-09-16T16:05:37.297624Z","end":"2026-09-16T16:05:37.403572Z","steps":["trace[1141190768] 'agreement among raft nodes before linearized reading' (duration: 105.735967ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.403745Z","caller":"traceutil/trace.go:171","msg":"trace[442122062] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"107.500585ms","start":"2026-09-16T16:05:37.29623Z","end":"2026-09-16T16:05:37.403731Z","steps":["trace[442122062] 'process raft request' (duration: 106.977972ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.403553Z","caller":"traceutil/trace.go:171","msg":"trace[1743053810] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"104.808062ms","start":"2026-09-16T16:05:37.29871Z","end":"2026-09-16T16:05:37.403518Z","steps":["trace[1743053810] 'process raft request' (duration: 104.604867ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.600329Z","caller":"traceutil/trace.go:171","msg":"trace[1306753608] linearizableReadLoop","detail":"{readStateIndex:5791; appliedIndex:5789; }","duration":"125.523825ms","start":"2026-09-16T16:05:37.474783Z","end":"2026-09-16T16:05:37.600307Z","steps":["trace[1306753608] 'read index received' (duration: 37.058528ms)","trace[1306753608] 'applied index is now lower than readState.Index' (duration: 88.464027ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:37.600434Z","caller":"traceutil/trace.go:171","msg":"trace[1965338167] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"177.436818ms","start":"2026-09-16T16:05:37.422985Z","end":"2026-09-16T16:05:37.600422Z","steps":["trace[1965338167] 'process raft request' (duration: 88.847247ms)","trace[1965338167] 'compare' (duration: 88.290363ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:37.600835Z","caller":"traceutil/trace.go:171","msg":"trace[970294604] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"166.510696ms","start":"2026-09-16T16:05:37.434303Z","end":"2026-09-16T16:05:37.600814Z","steps":["trace[970294604] 'process raft request' (duration: 165.951692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:37.60089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.988823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/keystone-api\" ","response":"range_response_count:1 size:11412"} {"level":"info","ts":"2026-09-16T16:05:37.60094Z","caller":"traceutil/trace.go:171","msg":"trace[1088511075] range","detail":"{range_begin:/registry/deployments/openstack/keystone-api; range_end:; response_count:1; response_revision:5578; }","duration":"178.082715ms","start":"2026-09-16T16:05:37.422847Z","end":"2026-09-16T16:05:37.60093Z","steps":["trace[1088511075] 'agreement among raft nodes before linearized reading' (duration: 177.93299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:37.600943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.347917ms","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-09-16T16:05:37.601031Z","caller":"traceutil/trace.go:171","msg":"trace[1732639403] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5578; }","duration":"156.47743ms","start":"2026-09-16T16:05:37.444527Z","end":"2026-09-16T16:05:37.601004Z","steps":["trace[1732639403] 'agreement among raft nodes before linearized reading' (duration: 156.236583ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.61895Z","caller":"traceutil/trace.go:171","msg":"trace[890719246] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"139.011065ms","start":"2026-09-16T16:05:37.479885Z","end":"2026-09-16T16:05:37.618896Z","steps":["trace[890719246] 'process raft request' (duration: 138.84135ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.619127Z","caller":"traceutil/trace.go:171","msg":"trace[724704491] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"137.725509ms","start":"2026-09-16T16:05:37.481382Z","end":"2026-09-16T16:05:37.619108Z","steps":["trace[724704491] 'process raft request' (duration: 137.552775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:37.619184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.006734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:05:37.61926Z","caller":"traceutil/trace.go:171","msg":"trace[1677989090] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5581; }","duration":"139.126227ms","start":"2026-09-16T16:05:37.480113Z","end":"2026-09-16T16:05:37.619239Z","steps":["trace[1677989090] 'agreement among raft nodes before linearized reading' (duration: 138.972543ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:37.619002Z","caller":"traceutil/trace.go:171","msg":"trace[1898166272] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"138.303415ms","start":"2026-09-16T16:05:37.480676Z","end":"2026-09-16T16:05:37.618979Z","steps":["trace[1898166272] 'process raft request' (duration: 138.183942ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:38.464312Z","caller":"traceutil/trace.go:171","msg":"trace[316278641] transaction","detail":"{read_only:false; response_revision:5587; number_of_response:1; }","duration":"322.476412ms","start":"2026-09-16T16:05:38.141812Z","end":"2026-09-16T16:05:38.464288Z","steps":["trace[316278641] 'process raft request' (duration: 322.261197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:38.464472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:38.141788Z","time spent":"322.601816ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:05:38.505989Z","caller":"traceutil/trace.go:171","msg":"trace[1855511295] linearizableReadLoop","detail":"{readStateIndex:5801; appliedIndex:5799; }","duration":"222.866579ms","start":"2026-09-16T16:05:38.2831Z","end":"2026-09-16T16:05:38.505967Z","steps":["trace[1855511295] 'read index received' (duration: 180.968662ms)","trace[1855511295] 'applied index is now lower than readState.Index' (duration: 41.897157ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:05:38.506119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.991392ms","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-09-16T16:05:38.506085Z","caller":"traceutil/trace.go:171","msg":"trace[26077481] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"339.161976ms","start":"2026-09-16T16:05:38.166865Z","end":"2026-09-16T16:05:38.506027Z","steps":["trace[26077481] 'process raft request' (duration: 338.982522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:38.506249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.909162ms","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-09-16T16:05:38.506264Z","caller":"traceutil/trace.go:171","msg":"trace[1253973327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5588; }","duration":"206.947593ms","start":"2026-09-16T16:05:38.299313Z","end":"2026-09-16T16:05:38.506261Z","steps":["trace[1253973327] 'agreement among raft nodes before linearized reading' (duration: 206.843031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:38.506271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:05:38.166836Z","time spent":"339.332561ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:05:38.506141Z","caller":"traceutil/trace.go:171","msg":"trace[1315919260] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5588; }","duration":"223.074994ms","start":"2026-09-16T16:05:38.283059Z","end":"2026-09-16T16:05:38.506134Z","steps":["trace[1315919260] 'agreement among raft nodes before linearized reading' (duration: 223.007652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:38.628774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.689ms","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-09-16T16:05:38.628855Z","caller":"traceutil/trace.go:171","msg":"trace[1564556186] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5591; }","duration":"116.801183ms","start":"2026-09-16T16:05:38.512036Z","end":"2026-09-16T16:05:38.628837Z","steps":["trace[1564556186] 'agreement among raft nodes before linearized reading' (duration: 116.549075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:38.628943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.749414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-16T16:05:38.628967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.069264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-16T16:05:38.628998Z","caller":"traceutil/trace.go:171","msg":"trace[503490673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5591; }","duration":"115.824526ms","start":"2026-09-16T16:05:38.513157Z","end":"2026-09-16T16:05:38.628982Z","steps":["trace[503490673] 'agreement among raft nodes before linearized reading' (duration: 115.650951ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:38.629028Z","caller":"traceutil/trace.go:171","msg":"trace[19302903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5591; }","duration":"116.157315ms","start":"2026-09-16T16:05:38.512855Z","end":"2026-09-16T16:05:38.629012Z","steps":["trace[19302903] 'agreement among raft nodes before linearized reading' (duration: 115.984861ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:38.782099Z","caller":"traceutil/trace.go:171","msg":"trace[251084440] transaction","detail":"{read_only:false; response_revision:5595; number_of_response:1; }","duration":"148.424525ms","start":"2026-09-16T16:05:38.633657Z","end":"2026-09-16T16:05:38.782081Z","steps":["trace[251084440] 'process raft request' (duration: 147.86561ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:38.782102Z","caller":"traceutil/trace.go:171","msg":"trace[498354987] transaction","detail":"{read_only:false; response_revision:5596; number_of_response:1; }","duration":"148.368203ms","start":"2026-09-16T16:05:38.633723Z","end":"2026-09-16T16:05:38.782091Z","steps":["trace[498354987] 'process raft request' (duration: 147.827198ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:38.782266Z","caller":"traceutil/trace.go:171","msg":"trace[548824916] transaction","detail":"{read_only:false; response_revision:5594; number_of_response:1; }","duration":"148.598109ms","start":"2026-09-16T16:05:38.633656Z","end":"2026-09-16T16:05:38.782255Z","steps":["trace[548824916] 'process raft request' (duration: 121.356534ms)","trace[548824916] 'compare' (duration: 26.399853ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:40.052936Z","caller":"traceutil/trace.go:171","msg":"trace[2141436057] linearizableReadLoop","detail":"{readStateIndex:5818; appliedIndex:5817; }","duration":"217.337191ms","start":"2026-09-16T16:05:39.835562Z","end":"2026-09-16T16:05:40.0529Z","steps":["trace[2141436057] 'read index received' (duration: 216.988152ms)","trace[2141436057] 'applied index is now lower than readState.Index' (duration: 347.799µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:40.052995Z","caller":"traceutil/trace.go:171","msg":"trace[2038846877] transaction","detail":"{read_only:false; response_revision:5605; number_of_response:1; }","duration":"292.586895ms","start":"2026-09-16T16:05:39.760373Z","end":"2026-09-16T16:05:40.052959Z","steps":["trace[2038846877] 'process raft request' (duration: 292.286708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:40.053228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.40554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-16T16:05:40.053292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.66404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-16T16:05:40.053336Z","caller":"traceutil/trace.go:171","msg":"trace[1263058618] range","detail":"{range_begin:/registry/masterleases/162.253.55.19; range_end:; response_count:1; response_revision:5605; }","duration":"217.803493ms","start":"2026-09-16T16:05:39.835521Z","end":"2026-09-16T16:05:40.053325Z","steps":["trace[1263058618] 'agreement among raft nodes before linearized reading' (duration: 217.548126ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.053328Z","caller":"traceutil/trace.go:171","msg":"trace[1780431555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5605; }","duration":"182.548533ms","start":"2026-09-16T16:05:39.870767Z","end":"2026-09-16T16:05:40.053315Z","steps":["trace[1780431555] 'agreement among raft nodes before linearized reading' (duration: 182.40381ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.194424Z","caller":"traceutil/trace.go:171","msg":"trace[1130920439] linearizableReadLoop","detail":"{readStateIndex:5819; appliedIndex:5818; }","duration":"139.444595ms","start":"2026-09-16T16:05:40.054924Z","end":"2026-09-16T16:05:40.194368Z","steps":["trace[1130920439] 'read index received' (duration: 46.694235ms)","trace[1130920439] 'applied index is now lower than readState.Index' (duration: 92.74661ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:05:40.19463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.659202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:05:40.194688Z","caller":"traceutil/trace.go:171","msg":"trace[655006033] transaction","detail":"{read_only:false; response_revision:5607; number_of_response:1; }","duration":"133.583769ms","start":"2026-09-16T16:05:40.061078Z","end":"2026-09-16T16:05:40.194662Z","steps":["trace[655006033] 'process raft request' (duration: 133.299532ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.194724Z","caller":"traceutil/trace.go:171","msg":"trace[994822268] transaction","detail":"{read_only:false; response_revision:5606; number_of_response:1; }","duration":"135.441978ms","start":"2026-09-16T16:05:40.059225Z","end":"2026-09-16T16:05:40.194667Z","steps":["trace[994822268] 'process raft request' (duration: 135.034197ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.194688Z","caller":"traceutil/trace.go:171","msg":"trace[843050529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5607; }","duration":"139.777884ms","start":"2026-09-16T16:05:40.054885Z","end":"2026-09-16T16:05:40.194663Z","steps":["trace[843050529] 'agreement among raft nodes before linearized reading' (duration: 139.655031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:40.226731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.239339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-6sh7w\" ","response":"range_response_count:1 size:7552"} {"level":"info","ts":"2026-09-16T16:05:40.22683Z","caller":"traceutil/trace.go:171","msg":"trace[371257076] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-6sh7w; range_end:; response_count:1; response_revision:5608; }","duration":"169.438944ms","start":"2026-09-16T16:05:40.057368Z","end":"2026-09-16T16:05:40.226807Z","steps":["trace[371257076] 'agreement among raft nodes before linearized reading' (duration: 168.990182ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.347549Z","caller":"traceutil/trace.go:171","msg":"trace[1154481040] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"108.058849ms","start":"2026-09-16T16:05:40.239465Z","end":"2026-09-16T16:05:40.347524Z","steps":["trace[1154481040] 'process raft request' (duration: 76.532829ms)","trace[1154481040] 'compare' (duration: 31.291733ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:40.901622Z","caller":"traceutil/trace.go:171","msg":"trace[766640175] linearizableReadLoop","detail":"{readStateIndex:5832; appliedIndex:5829; }","duration":"112.795185ms","start":"2026-09-16T16:05:40.788781Z","end":"2026-09-16T16:05:40.901576Z","steps":["trace[766640175] 'read index received' (duration: 16.465709ms)","trace[766640175] 'applied index is now lower than readState.Index' (duration: 96.328226ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:40.901686Z","caller":"traceutil/trace.go:171","msg":"trace[1452410535] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"113.037081ms","start":"2026-09-16T16:05:40.788627Z","end":"2026-09-16T16:05:40.901664Z","steps":["trace[1452410535] 'process raft request' (duration: 112.789125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:40.901885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.088083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-16T16:05:40.901912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.23476ms","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-09-16T16:05:40.901937Z","caller":"traceutil/trace.go:171","msg":"trace[120648206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5618; }","duration":"113.192336ms","start":"2026-09-16T16:05:40.78873Z","end":"2026-09-16T16:05:40.901923Z","steps":["trace[120648206] 'agreement among raft nodes before linearized reading' (duration: 113.006661ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.901967Z","caller":"traceutil/trace.go:171","msg":"trace[594766421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5618; }","duration":"112.325213ms","start":"2026-09-16T16:05:40.789625Z","end":"2026-09-16T16:05:40.90195Z","steps":["trace[594766421] 'agreement among raft nodes before linearized reading' (duration: 112.177539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:40.902063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.382045ms","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-09-16T16:05:40.902096Z","caller":"traceutil/trace.go:171","msg":"trace[1122695569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5618; }","duration":"112.439666ms","start":"2026-09-16T16:05:40.789646Z","end":"2026-09-16T16:05:40.902086Z","steps":["trace[1122695569] 'agreement among raft nodes before linearized reading' (duration: 112.342253ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.901513Z","caller":"traceutil/trace.go:171","msg":"trace[495376374] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"132.141532ms","start":"2026-09-16T16:05:40.769342Z","end":"2026-09-16T16:05:40.901483Z","steps":["trace[495376374] 'process raft request' (duration: 97.717324ms)","trace[495376374] 'compare' (duration: 34.207332ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:54.092936Z","caller":"traceutil/trace.go:171","msg":"trace[2147161085] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"122.144964ms","start":"2026-09-16T16:05:53.970737Z","end":"2026-09-16T16:05:54.092882Z","steps":["trace[2147161085] 'process raft request' (duration: 121.851825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:54.458642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.285987ms","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-09-16T16:05:54.458721Z","caller":"traceutil/trace.go:171","msg":"trace[273920014] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:5690; }","duration":"205.438481ms","start":"2026-09-16T16:05:54.253262Z","end":"2026-09-16T16:05:54.458701Z","steps":["trace[273920014] 'count revisions from in-memory index tree' (duration: 205.190944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:06:05.446059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.497085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:06:05.44641Z","caller":"traceutil/trace.go:171","msg":"trace[1753554996] transaction","detail":"{read_only:false; response_revision:5774; number_of_response:1; }","duration":"231.021911ms","start":"2026-09-16T16:06:05.215369Z","end":"2026-09-16T16:06:05.446391Z","steps":["trace[1753554996] 'process raft request' (duration: 65.127774ms)","trace[1753554996] 'compare' (duration: 165.178817ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:06:05.608406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.251549ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-16T16:06:05.608504Z","caller":"traceutil/trace.go:171","msg":"trace[1469855400] linearizableReadLoop","detail":"{readStateIndex:5994; appliedIndex:5992; }","duration":"170.702403ms","start":"2026-09-16T16:06:05.437784Z","end":"2026-09-16T16:06:05.608487Z","steps":["trace[1469855400] 'read index received' (duration: 47.161µs)","trace[1469855400] 'applied index is now lower than readState.Index' (duration: 170.653922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:06:05.608648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.749866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-16T16:06:05.608648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.852829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:06:05.6087Z","caller":"traceutil/trace.go:171","msg":"trace[244568835] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5774; }","duration":"170.830888ms","start":"2026-09-16T16:06:05.437854Z","end":"2026-09-16T16:06:05.608685Z","steps":["trace[244568835] 'agreement among raft nodes before linearized reading' (duration: 170.728985ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:06:05.608704Z","caller":"traceutil/trace.go:171","msg":"trace[464909584] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5774; }","duration":"170.979672ms","start":"2026-09-16T16:06:05.437711Z","end":"2026-09-16T16:06:05.608691Z","steps":["trace[464909584] 'agreement among raft nodes before linearized reading' (duration: 170.863998ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:06:06.801977Z","caller":"traceutil/trace.go:171","msg":"trace[2079791297] transaction","detail":"{read_only:false; response_revision:5775; number_of_response:1; }","duration":"159.331272ms","start":"2026-09-16T16:06:06.642625Z","end":"2026-09-16T16:06:06.801956Z","steps":["trace[2079791297] 'process raft request' (duration: 159.212759ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:06:06.865298Z","caller":"traceutil/trace.go:171","msg":"trace[365064266] transaction","detail":"{read_only:false; response_revision:5776; number_of_response:1; }","duration":"116.395529ms","start":"2026-09-16T16:06:06.748884Z","end":"2026-09-16T16:06:06.865279Z","steps":["trace[365064266] 'process raft request' (duration: 116.185203ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:06:09.089432Z","caller":"traceutil/trace.go:171","msg":"trace[534645636] linearizableReadLoop","detail":"{readStateIndex:6012; appliedIndex:6010; }","duration":"111.835197ms","start":"2026-09-16T16:06:08.977567Z","end":"2026-09-16T16:06:09.089402Z","steps":["trace[534645636] 'read index received' (duration: 3.38272ms)","trace[534645636] 'applied index is now lower than readState.Index' (duration: 108.451537ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:06:09.089478Z","caller":"traceutil/trace.go:171","msg":"trace[1073413229] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"132.545658ms","start":"2026-09-16T16:06:08.956914Z","end":"2026-09-16T16:06:09.089459Z","steps":["trace[1073413229] 'process raft request' (duration: 87.90569ms)","trace[1073413229] 'compare' (duration: 44.31443ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:06:09.089779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.192716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-16T16:06:09.089859Z","caller":"traceutil/trace.go:171","msg":"trace[401146765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5792; }","duration":"112.292409ms","start":"2026-09-16T16:06:08.977531Z","end":"2026-09-16T16:06:09.089823Z","steps":["trace[401146765] 'agreement among raft nodes before linearized reading' (duration: 111.9621ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:06:09.281881Z","caller":"traceutil/trace.go:171","msg":"trace[1945118851] transaction","detail":"{read_only:false; response_revision:5795; number_of_response:1; }","duration":"141.98516ms","start":"2026-09-16T16:06:09.13987Z","end":"2026-09-16T16:06:09.281856Z","steps":["trace[1945118851] 'process raft request' (duration: 66.178562ms)","trace[1945118851] 'compare' (duration: 75.652794ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:06:09.608284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.740525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:06:09.608411Z","caller":"traceutil/trace.go:171","msg":"trace[918866433] linearizableReadLoop","detail":"{readStateIndex:6017; appliedIndex:6016; }","duration":"212.163237ms","start":"2026-09-16T16:06:09.396225Z","end":"2026-09-16T16:06:09.608389Z","steps":["trace[918866433] 'read index received' (duration: 26.137736ms)","trace[918866433] 'applied index is now lower than readState.Index' (duration: 186.023681ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:06:09.608464Z","caller":"traceutil/trace.go:171","msg":"trace[1371185121] transaction","detail":"{read_only:false; response_revision:5797; number_of_response:1; }","duration":"322.534047ms","start":"2026-09-16T16:06:09.285907Z","end":"2026-09-16T16:06:09.608441Z","steps":["trace[1371185121] 'process raft request' (duration: 136.524575ms)","trace[1371185121] 'compare' (duration: 185.620741ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:06:09.608564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.333671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-16T16:06:09.608569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:06:09.2859Z","time spent":"322.614649ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:06:09.608642Z","caller":"traceutil/trace.go:171","msg":"trace[516058633] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5797; }","duration":"212.423065ms","start":"2026-09-16T16:06:09.396179Z","end":"2026-09-16T16:06:09.608602Z","steps":["trace[516058633] 'agreement among raft nodes before linearized reading' (duration: 212.330063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:06:09.608772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.282751ms","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-09-16T16:06:09.60884Z","caller":"traceutil/trace.go:171","msg":"trace[1081866079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5797; }","duration":"141.395944ms","start":"2026-09-16T16:06:09.467428Z","end":"2026-09-16T16:06:09.608824Z","steps":["trace[1081866079] 'agreement among raft nodes before linearized reading' (duration: 141.142397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:06:29.592083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.656009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:06:29.592508Z","caller":"traceutil/trace.go:171","msg":"trace[1011207098] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5955; }","duration":"189.138262ms","start":"2026-09-16T16:06:29.403348Z","end":"2026-09-16T16:06:29.592486Z","steps":["trace[1011207098] 'count revisions from in-memory index tree' (duration: 188.550247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:06:29.592357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.655583ms","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-09-16T16:06:29.592898Z","caller":"traceutil/trace.go:171","msg":"trace[768543149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5955; }","duration":"114.229ms","start":"2026-09-16T16:06:29.478656Z","end":"2026-09-16T16:06:29.592885Z","steps":["trace[768543149] 'range keys from in-memory index tree' (duration: 113.49357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:06:29.828576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.825113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8314"} {"level":"info","ts":"2026-09-16T16:06:29.828704Z","caller":"traceutil/trace.go:171","msg":"trace[503855528] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5956; }","duration":"133.000078ms","start":"2026-09-16T16:06:29.69568Z","end":"2026-09-16T16:06:29.82868Z","steps":["trace[503855528] 'range keys from in-memory index tree' (duration: 132.519926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:08:14.28434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.933777ms","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-09-16T16:08:14.284448Z","caller":"traceutil/trace.go:171","msg":"trace[1103711839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6572; }","duration":"155.073491ms","start":"2026-09-16T16:08:14.129339Z","end":"2026-09-16T16:08:14.284412Z","steps":["trace[1103711839] 'range keys from in-memory index tree' (duration: 154.775844ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:08:14.417023Z","caller":"traceutil/trace.go:171","msg":"trace[129241537] transaction","detail":"{read_only:false; response_revision:6573; number_of_response:1; }","duration":"127.98707ms","start":"2026-09-16T16:08:14.289012Z","end":"2026-09-16T16:08:14.416999Z","steps":["trace[129241537] 'process raft request' (duration: 127.60971ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:08:16.628395Z","caller":"traceutil/trace.go:171","msg":"trace[1038098936] linearizableReadLoop","detail":"{readStateIndex:6832; appliedIndex:6831; }","duration":"154.869278ms","start":"2026-09-16T16:08:16.473503Z","end":"2026-09-16T16:08:16.628372Z","steps":["trace[1038098936] 'read index received' (duration: 154.666763ms)","trace[1038098936] 'applied index is now lower than readState.Index' (duration: 201.625µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:08:16.628489Z","caller":"traceutil/trace.go:171","msg":"trace[735187109] transaction","detail":"{read_only:false; response_revision:6584; number_of_response:1; }","duration":"203.04868ms","start":"2026-09-16T16:08:16.425409Z","end":"2026-09-16T16:08:16.628458Z","steps":["trace[735187109] 'process raft request' (duration: 202.844225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:08:16.628591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.061463ms","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-09-16T16:08:16.628676Z","caller":"traceutil/trace.go:171","msg":"trace[2112647647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6584; }","duration":"155.218416ms","start":"2026-09-16T16:08:16.473444Z","end":"2026-09-16T16:08:16.628663Z","steps":["trace[2112647647] 'agreement among raft nodes before linearized reading' (duration: 155.019221ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:08:36.020683Z","caller":"traceutil/trace.go:171","msg":"trace[1883956724] transaction","detail":"{read_only:false; response_revision:6786; number_of_response:1; }","duration":"146.466699ms","start":"2026-09-16T16:08:35.874151Z","end":"2026-09-16T16:08:36.020618Z","steps":["trace[1883956724] 'process raft request' (duration: 76.506532ms)","trace[1883956724] 'compare' (duration: 69.763081ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:08:36.020923Z","caller":"traceutil/trace.go:171","msg":"trace[952580506] transaction","detail":"{read_only:false; response_revision:6787; number_of_response:1; }","duration":"132.187219ms","start":"2026-09-16T16:08:35.888709Z","end":"2026-09-16T16:08:36.020896Z","steps":["trace[952580506] 'process raft request' (duration: 131.858081ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:08:53.032541Z","caller":"traceutil/trace.go:171","msg":"trace[744920422] transaction","detail":"{read_only:false; response_revision:6929; number_of_response:1; }","duration":"109.233157ms","start":"2026-09-16T16:08:52.923285Z","end":"2026-09-16T16:08:53.032518Z","steps":["trace[744920422] 'process raft request' (duration: 108.950399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:08:53.411043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.402355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:08:53.411322Z","caller":"traceutil/trace.go:171","msg":"trace[161247242] transaction","detail":"{read_only:false; response_revision:6930; number_of_response:1; }","duration":"374.087005ms","start":"2026-09-16T16:08:53.037193Z","end":"2026-09-16T16:08:53.41128Z","steps":["trace[161247242] 'process raft request' (duration: 55.29122ms)","trace[161247242] 'compare' (duration: 318.17889ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:08:53.411468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:08:53.037177Z","time spent":"374.233019ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T16:08:53.411586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.019055ms","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-09-16T16:08:53.411699Z","caller":"traceutil/trace.go:171","msg":"trace[678504644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6931; }","duration":"363.161768ms","start":"2026-09-16T16:08:53.048521Z","end":"2026-09-16T16:08:53.411683Z","steps":["trace[678504644] 'agreement among raft nodes before linearized reading' (duration: 362.869611ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:08:53.411358Z","caller":"traceutil/trace.go:171","msg":"trace[1657491524] linearizableReadLoop","detail":"{readStateIndex:7190; appliedIndex:7188; }","duration":"362.773828ms","start":"2026-09-16T16:08:53.048562Z","end":"2026-09-16T16:08:53.411336Z","steps":["trace[1657491524] 'read index received' (duration: 43.918442ms)","trace[1657491524] 'applied index is now lower than readState.Index' (duration: 318.853626ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:08:53.411808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:08:53.048507Z","time spent":"363.23476ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-16T16:08:53.411992Z","caller":"traceutil/trace.go:171","msg":"trace[990553787] transaction","detail":"{read_only:false; response_revision:6931; number_of_response:1; }","duration":"374.470744ms","start":"2026-09-16T16:08:53.0375Z","end":"2026-09-16T16:08:53.411971Z","steps":["trace[990553787] 'process raft request' (duration: 373.717025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:08:53.412086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:08:53.037472Z","time spent":"374.565177ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T16:08:53.412118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.10612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-09-16T16:08:53.412177Z","caller":"traceutil/trace.go:171","msg":"trace[49493831] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6931; }","duration":"174.195142ms","start":"2026-09-16T16:08:53.237965Z","end":"2026-09-16T16:08:53.41216Z","steps":["trace[49493831] 'agreement among raft nodes before linearized reading' (duration: 174.042498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:08:58.674492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.023344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:08:58.674769Z","caller":"traceutil/trace.go:171","msg":"trace[398210411] transaction","detail":"{read_only:false; response_revision:6963; number_of_response:1; }","duration":"505.078023ms","start":"2026-09-16T16:08:58.169652Z","end":"2026-09-16T16:08:58.67473Z","steps":["trace[398210411] 'process raft request' (duration: 135.72084ms)","trace[398210411] 'compare' (duration: 367.967378ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:08:58.674962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:08:58.169637Z","time spent":"505.218237ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:08:58.675022Z","caller":"traceutil/trace.go:171","msg":"trace[1580823435] linearizableReadLoop","detail":"{readStateIndex:7223; appliedIndex:7221; }","duration":"477.593237ms","start":"2026-09-16T16:08:58.197398Z","end":"2026-09-16T16:08:58.674991Z","steps":["trace[1580823435] 'read index received' (duration: 28.506423ms)","trace[1580823435] 'applied index is now lower than readState.Index' (duration: 449.084724ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:08:58.675203Z","caller":"traceutil/trace.go:171","msg":"trace[1474110953] transaction","detail":"{read_only:false; response_revision:6964; number_of_response:1; }","duration":"461.424767ms","start":"2026-09-16T16:08:58.213764Z","end":"2026-09-16T16:08:58.675189Z","steps":["trace[1474110953] 'process raft request' (duration: 460.836632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:08:58.675255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.843443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-16T16:08:58.67527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:08:58.21371Z","time spent":"461.52694ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:08:58.675305Z","caller":"traceutil/trace.go:171","msg":"trace[1613810683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6964; }","duration":"477.923095ms","start":"2026-09-16T16:08:58.197365Z","end":"2026-09-16T16:08:58.675288Z","steps":["trace[1613810683] 'agreement among raft nodes before linearized reading' (duration: 477.70612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:08:58.675356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:08:58.19735Z","time spent":"477.995247ms","remote":"127.0.0.1:37694","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":"warn","ts":"2026-09-16T16:08:58.89677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.140169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:08:58.896956Z","caller":"traceutil/trace.go:171","msg":"trace[1235587047] transaction","detail":"{read_only:false; response_revision:6965; number_of_response:1; }","duration":"212.215109ms","start":"2026-09-16T16:08:58.68471Z","end":"2026-09-16T16:08:58.896925Z","steps":["trace[1235587047] 'process raft request' (duration: 111.742482ms)","trace[1235587047] 'compare' (duration: 99.761ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:08:59.717229Z","caller":"traceutil/trace.go:171","msg":"trace[478686232] transaction","detail":"{read_only:false; response_revision:6969; number_of_response:1; }","duration":"152.293211ms","start":"2026-09-16T16:08:59.564896Z","end":"2026-09-16T16:08:59.717189Z","steps":["trace[478686232] 'process raft request' (duration: 101.205936ms)","trace[478686232] 'compare' (duration: 50.373837ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:09:15.115502Z","caller":"traceutil/trace.go:171","msg":"trace[2041805375] transaction","detail":"{read_only:false; response_revision:7098; number_of_response:1; }","duration":"132.647112ms","start":"2026-09-16T16:09:14.982828Z","end":"2026-09-16T16:09:15.115475Z","steps":["trace[2041805375] 'process raft request' (duration: 132.330853ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:09:15.728577Z","caller":"traceutil/trace.go:171","msg":"trace[124801190] transaction","detail":"{read_only:false; response_revision:7101; number_of_response:1; }","duration":"138.539381ms","start":"2026-09-16T16:09:15.590016Z","end":"2026-09-16T16:09:15.728555Z","steps":["trace[124801190] 'process raft request' (duration: 87.989606ms)","trace[124801190] 'compare' (duration: 50.467933ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:09:24.041779Z","caller":"traceutil/trace.go:171","msg":"trace[2014797755] linearizableReadLoop","detail":"{readStateIndex:7411; appliedIndex:7410; }","duration":"210.410505ms","start":"2026-09-16T16:09:23.831349Z","end":"2026-09-16T16:09:24.04176Z","steps":["trace[2014797755] 'read index received' (duration: 210.266681ms)","trace[2014797755] 'applied index is now lower than readState.Index' (duration: 142.954µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:09:24.041998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.52168ms","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-09-16T16:09:24.04203Z","caller":"traceutil/trace.go:171","msg":"trace[110868848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7143; }","duration":"150.596992ms","start":"2026-09-16T16:09:23.891425Z","end":"2026-09-16T16:09:24.042022Z","steps":["trace[110868848] 'agreement among raft nodes before linearized reading' (duration: 150.456789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:24.042085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.495443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107255"} {"level":"info","ts":"2026-09-16T16:09:24.042129Z","caller":"traceutil/trace.go:171","msg":"trace[1388449600] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:7143; }","duration":"103.643847ms","start":"2026-09-16T16:09:23.938472Z","end":"2026-09-16T16:09:24.042116Z","steps":["trace[1388449600] 'agreement among raft nodes before linearized reading' (duration: 103.443902ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:09:24.041857Z","caller":"traceutil/trace.go:171","msg":"trace[274511607] transaction","detail":"{read_only:false; response_revision:7143; number_of_response:1; }","duration":"234.994619ms","start":"2026-09-16T16:09:23.806832Z","end":"2026-09-16T16:09:24.041826Z","steps":["trace[274511607] 'process raft request' (duration: 234.780334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:24.046041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.957458ms","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-09-16T16:09:24.046138Z","caller":"traceutil/trace.go:171","msg":"trace[1627666493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7143; }","duration":"214.807366ms","start":"2026-09-16T16:09:23.83131Z","end":"2026-09-16T16:09:24.046118Z","steps":["trace[1627666493] 'agreement among raft nodes before linearized reading' (duration: 210.584129ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:09:24.183145Z","caller":"traceutil/trace.go:171","msg":"trace[1807480650] transaction","detail":"{read_only:false; response_revision:7144; number_of_response:1; }","duration":"134.961523ms","start":"2026-09-16T16:09:24.048162Z","end":"2026-09-16T16:09:24.183124Z","steps":["trace[1807480650] 'process raft request' (duration: 43.108246ms)","trace[1807480650] 'compare' (duration: 91.708944ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:09:24.183337Z","caller":"traceutil/trace.go:171","msg":"trace[791440298] transaction","detail":"{read_only:false; response_revision:7145; number_of_response:1; }","duration":"125.842971ms","start":"2026-09-16T16:09:24.057459Z","end":"2026-09-16T16:09:24.183302Z","steps":["trace[791440298] 'process raft request' (duration: 125.633686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:24.568996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.711698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:09:24.569369Z","caller":"traceutil/trace.go:171","msg":"trace[1504795774] transaction","detail":"{read_only:false; response_revision:7146; number_of_response:1; }","duration":"345.100032ms","start":"2026-09-16T16:09:24.224249Z","end":"2026-09-16T16:09:24.56935Z","steps":["trace[1504795774] 'process raft request' (duration: 174.955273ms)","trace[1504795774] 'compare' (duration: 169.486982ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:09:24.569474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:09:24.224225Z","time spent":"345.187704ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:09:24.569471Z","caller":"traceutil/trace.go:171","msg":"trace[487424975] transaction","detail":"{read_only:false; response_revision:7147; number_of_response:1; }","duration":"188.582638ms","start":"2026-09-16T16:09:24.380871Z","end":"2026-09-16T16:09:24.569453Z","steps":["trace[487424975] 'process raft request' (duration: 188.23663ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:26.016661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.545909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:09:26.016752Z","caller":"traceutil/trace.go:171","msg":"trace[1022940989] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:7154; }","duration":"234.679633ms","start":"2026-09-16T16:09:25.782051Z","end":"2026-09-16T16:09:26.016731Z","steps":["trace[1022940989] 'count revisions from in-memory index tree' (duration: 234.444107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:26.016713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.549162ms","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-09-16T16:09:26.017211Z","caller":"traceutil/trace.go:171","msg":"trace[86043526] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7154; }","duration":"153.064156ms","start":"2026-09-16T16:09:25.864133Z","end":"2026-09-16T16:09:26.017197Z","steps":["trace[86043526] 'range keys from in-memory index tree' (duration: 152.534962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:26.016912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.333816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:1 size:5746"} {"level":"info","ts":"2026-09-16T16:09:26.017329Z","caller":"traceutil/trace.go:171","msg":"trace[142800855] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:1; response_revision:7154; }","duration":"128.770247ms","start":"2026-09-16T16:09:25.88853Z","end":"2026-09-16T16:09:26.017301Z","steps":["trace[142800855] 'range keys from in-memory index tree' (duration: 128.165322ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:09:26.304128Z","caller":"traceutil/trace.go:171","msg":"trace[778333068] transaction","detail":"{read_only:false; response_revision:7156; number_of_response:1; }","duration":"114.792402ms","start":"2026-09-16T16:09:26.18932Z","end":"2026-09-16T16:09:26.304112Z","steps":["trace[778333068] 'process raft request' (duration: 114.691709ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:09:26.321969Z","caller":"traceutil/trace.go:171","msg":"trace[824785974] linearizableReadLoop","detail":"{readStateIndex:7426; appliedIndex:7425; }","duration":"127.310331ms","start":"2026-09-16T16:09:26.194635Z","end":"2026-09-16T16:09:26.321946Z","steps":["trace[824785974] 'read index received' (duration: 109.635591ms)","trace[824785974] 'applied index is now lower than readState.Index' (duration: 17.67346ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:09:26.322126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.461964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/policies.rabbitmq.com\" ","response":"range_response_count:1 size:6229"} {"level":"info","ts":"2026-09-16T16:09:26.322151Z","caller":"traceutil/trace.go:171","msg":"trace[388243956] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/policies.rabbitmq.com; range_end:; response_count:1; response_revision:7157; }","duration":"127.528385ms","start":"2026-09-16T16:09:26.194614Z","end":"2026-09-16T16:09:26.322142Z","steps":["trace[388243956] 'agreement among raft nodes before linearized reading' (duration: 127.414923ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:09:26.322041Z","caller":"traceutil/trace.go:171","msg":"trace[845036609] transaction","detail":"{read_only:false; response_revision:7157; number_of_response:1; }","duration":"131.014165ms","start":"2026-09-16T16:09:26.191014Z","end":"2026-09-16T16:09:26.322028Z","steps":["trace[845036609] 'process raft request' (duration: 130.561564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:26.322373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.39838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-16T16:09:26.32239Z","caller":"traceutil/trace.go:171","msg":"trace[1302147196] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:7157; }","duration":"121.41924ms","start":"2026-09-16T16:09:26.200966Z","end":"2026-09-16T16:09:26.322385Z","steps":["trace[1302147196] 'agreement among raft nodes before linearized reading' (duration: 121.302697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:26.549964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.031225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/schemareplications.rabbitmq.com\" ","response":"range_response_count:1 size:7109"} {"level":"info","ts":"2026-09-16T16:09:26.550057Z","caller":"traceutil/trace.go:171","msg":"trace[1417723253] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/schemareplications.rabbitmq.com; range_end:; response_count:1; response_revision:7157; }","duration":"184.158767ms","start":"2026-09-16T16:09:26.365878Z","end":"2026-09-16T16:09:26.550037Z","steps":["trace[1417723253] 'range keys from in-memory index tree' (duration: 183.877891ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:09:26.563271Z","caller":"traceutil/trace.go:171","msg":"trace[1799265418] transaction","detail":"{read_only:false; response_revision:7158; number_of_response:1; }","duration":"120.840146ms","start":"2026-09-16T16:09:26.44241Z","end":"2026-09-16T16:09:26.56325Z","steps":["trace[1799265418] 'process raft request' (duration: 120.736624ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:09:26.874467Z","caller":"traceutil/trace.go:171","msg":"trace[2126613823] transaction","detail":"{read_only:false; response_revision:7159; number_of_response:1; }","duration":"298.99285ms","start":"2026-09-16T16:09:26.57545Z","end":"2026-09-16T16:09:26.874443Z","steps":["trace[2126613823] 'process raft request' (duration: 241.348182ms)","trace[2126613823] 'compare' (duration: 57.572506ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:09:26.941399Z","caller":"traceutil/trace.go:171","msg":"trace[2061403814] linearizableReadLoop","detail":"{readStateIndex:7429; appliedIndex:7427; }","duration":"354.578826ms","start":"2026-09-16T16:09:26.586797Z","end":"2026-09-16T16:09:26.941376Z","steps":["trace[2061403814] 'read index received' (duration: 230.008895ms)","trace[2061403814] 'applied index is now lower than readState.Index' (duration: 124.568721ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:09:26.941519Z","caller":"traceutil/trace.go:171","msg":"trace[1147635190] transaction","detail":"{read_only:false; response_revision:7160; number_of_response:1; }","duration":"363.127743ms","start":"2026-09-16T16:09:26.578376Z","end":"2026-09-16T16:09:26.941504Z","steps":["trace[1147635190] 'process raft request' (duration: 362.861646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:26.941677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:09:26.578351Z","time spent":"363.204495ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T16:09:26.941941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.131579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/shovels.rabbitmq.com\" ","response":"range_response_count:1 size:8339"} {"level":"info","ts":"2026-09-16T16:09:26.941994Z","caller":"traceutil/trace.go:171","msg":"trace[816480310] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/shovels.rabbitmq.com; range_end:; response_count:1; response_revision:7160; }","duration":"355.226912ms","start":"2026-09-16T16:09:26.586752Z","end":"2026-09-16T16:09:26.941979Z","steps":["trace[816480310] 'agreement among raft nodes before linearized reading' (duration: 355.053688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:26.942039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:09:26.586741Z","time spent":"355.286983ms","remote":"127.0.0.1:37458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":8363,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/shovels.rabbitmq.com\" "} {"level":"info","ts":"2026-09-16T16:09:27.299358Z","caller":"traceutil/trace.go:171","msg":"trace[1775752709] transaction","detail":"{read_only:false; response_revision:7161; number_of_response:1; }","duration":"367.637909ms","start":"2026-09-16T16:09:26.931704Z","end":"2026-09-16T16:09:27.299342Z","steps":["trace[1775752709] 'process raft request' (duration: 367.545047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:27.299473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:09:26.93168Z","time spent":"367.737331ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:09:27.300071Z","caller":"traceutil/trace.go:171","msg":"trace[1456965479] linearizableReadLoop","detail":"{readStateIndex:7430; appliedIndex:7430; }","duration":"358.579438ms","start":"2026-09-16T16:09:26.94147Z","end":"2026-09-16T16:09:27.30005Z","steps":["trace[1456965479] 'read index received' (duration: 358.574188ms)","trace[1456965479] 'applied index is now lower than readState.Index' (duration: 3.73µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:09:27.300275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.000942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:09:27.30038Z","caller":"traceutil/trace.go:171","msg":"trace[553333887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7161; }","duration":"431.058494ms","start":"2026-09-16T16:09:26.86924Z","end":"2026-09-16T16:09:27.300299Z","steps":["trace[553333887] 'agreement among raft nodes before linearized reading' (duration: 430.8953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:27.300404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:09:26.869227Z","time spent":"431.170666ms","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-16T16:09:27.343408Z","caller":"traceutil/trace.go:171","msg":"trace[702813337] transaction","detail":"{read_only:false; response_revision:7162; number_of_response:1; }","duration":"375.874599ms","start":"2026-09-16T16:09:26.967514Z","end":"2026-09-16T16:09:27.343389Z","steps":["trace[702813337] 'process raft request' (duration: 375.50071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:27.343723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:09:26.967495Z","time spent":"375.96488ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T16:09:27.34399Z","caller":"traceutil/trace.go:171","msg":"trace[810862442] transaction","detail":"{read_only:false; response_revision:7163; number_of_response:1; }","duration":"253.056842ms","start":"2026-09-16T16:09:27.090924Z","end":"2026-09-16T16:09:27.343981Z","steps":["trace[810862442] 'process raft request' (duration: 252.225661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:27.34429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.365102ms","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-09-16T16:09:27.344325Z","caller":"traceutil/trace.go:171","msg":"trace[315184001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7163; }","duration":"138.443224ms","start":"2026-09-16T16:09:27.205874Z","end":"2026-09-16T16:09:27.344317Z","steps":["trace[315184001] 'agreement among raft nodes before linearized reading' (duration: 138.331742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:27.344704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.658562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/superstreams.rabbitmq.com\" ","response":"range_response_count:1 size:6038"} {"level":"info","ts":"2026-09-16T16:09:27.344735Z","caller":"traceutil/trace.go:171","msg":"trace[1500956694] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/superstreams.rabbitmq.com; range_end:; response_count:1; response_revision:7163; }","duration":"395.721084ms","start":"2026-09-16T16:09:26.949006Z","end":"2026-09-16T16:09:27.344727Z","steps":["trace[1500956694] 'agreement among raft nodes before linearized reading' (duration: 395.644252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:09:27.344821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:09:26.948965Z","time spent":"395.784056ms","remote":"127.0.0.1:37458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":6062,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/superstreams.rabbitmq.com\" "} {"level":"info","ts":"2026-09-16T16:10:06.857536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5324} {"level":"info","ts":"2026-09-16T16:10:07.057523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5324,"took":"198.731715ms","hash":1443818432,"current-db-size-bytes":52985856,"current-db-size":"53 MB","current-db-size-in-use-bytes":19013632,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-16T16:10:07.057611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1443818432,"revision":5324,"compact-revision":2867} {"level":"warn","ts":"2026-09-16T16:11:11.197648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.055751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-16T16:11:11.197755Z","caller":"traceutil/trace.go:171","msg":"trace[1369777154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7708; }","duration":"111.206715ms","start":"2026-09-16T16:11:11.086521Z","end":"2026-09-16T16:11:11.197728Z","steps":["trace[1369777154] 'range keys from in-memory index tree' (duration: 110.861077ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:11:42.633952Z","caller":"traceutil/trace.go:171","msg":"trace[1207886955] transaction","detail":"{read_only:false; response_revision:7869; number_of_response:1; }","duration":"124.075254ms","start":"2026-09-16T16:11:42.509855Z","end":"2026-09-16T16:11:42.63393Z","steps":["trace[1207886955] 'process raft request' (duration: 115.980866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:11:42.634065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.570614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:11:42.633948Z","caller":"traceutil/trace.go:171","msg":"trace[232008587] linearizableReadLoop","detail":"{readStateIndex:8166; appliedIndex:8165; }","duration":"101.436829ms","start":"2026-09-16T16:11:42.53248Z","end":"2026-09-16T16:11:42.633917Z","steps":["trace[232008587] 'read index received' (duration: 93.380962ms)","trace[232008587] 'applied index is now lower than readState.Index' (duration: 8.054417ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:11:42.63412Z","caller":"traceutil/trace.go:171","msg":"trace[763161643] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:7869; }","duration":"101.692527ms","start":"2026-09-16T16:11:42.532408Z","end":"2026-09-16T16:11:42.634101Z","steps":["trace[763161643] 'agreement among raft nodes before linearized reading' (duration: 101.589314ms)"],"step_count":1}