{"level":"warn","ts":"2026-05-10T03:57:47.094736Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T03:57:47.094824Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-10T03:57:47.094891Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T03:57:47.094903Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-05-10T03:57:47.09492Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-10T03:57:47.095521Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-05-10T03:57:47.095589Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-10T03:57:47.099331Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.543115ms"} {"level":"info","ts":"2026-05-10T03:57:47.104475Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-05-10T03:57:47.104532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-05-10T03:57:47.104551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-05-10T03:57:47.104559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-10T03:57:47.104569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-05-10T03:57:47.104599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-05-10T03:57:47.112932Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-10T03:57:47.113967Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-10T03:57:47.114871Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-10T03:57:47.116985Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-10T03:57:47.119219Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T03:57:47.119267Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T03:57:47.119283Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T03:57:47.119774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-05-10T03:57:47.119777Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-10T03:57:47.120698Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-05-10T03:57:47.122114Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-10T03:57:47.12225Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-05-10T03:57:47.122301Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-10T03:57:47.122305Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-05-10T03:57:47.122328Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-10T03:57:47.205534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-05-10T03:57:47.205586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-10T03:57:47.205728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-05-10T03:57:47.205762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-05-10T03:57:47.205844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-05-10T03:57:47.20593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-05-10T03:57:47.205955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-05-10T03:57:47.206939Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T03:57:47.207785Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T03:57:47.207799Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-05-10T03:57:47.208034Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T03:57:47.208302Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T03:57:47.208451Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T03:57:47.2085Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T03:57:47.210656Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-10T03:57:47.210695Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-10T03:57:47.211855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-05-10T03:57:47.212152Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-10T03:57:47.863966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.894373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T03:57:47.86426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.997141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.864335Z","caller":"traceutil/trace.go:171","msg":"trace[1591801681] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"241.061892ms","start":"2026-05-10T03:57:47.623256Z","end":"2026-05-10T03:57:47.864318Z","steps":["trace[1591801681] 'count revisions from in-memory index tree' (duration: 240.970601ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:57:47.86444Z","caller":"traceutil/trace.go:171","msg":"trace[613254517] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"336.272767ms","start":"2026-05-10T03:57:47.52801Z","end":"2026-05-10T03:57:47.864283Z","steps":["trace[613254517] 'range keys from in-memory index tree' (duration: 335.845232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.86457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.528002Z","time spent":"336.51843ms","remote":"127.0.0.1:44818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.86474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.391937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.864779Z","caller":"traceutil/trace.go:171","msg":"trace[2064694233] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"230.445548ms","start":"2026-05-10T03:57:47.634324Z","end":"2026-05-10T03:57:47.86477Z","steps":["trace[2064694233] 'range keys from in-memory index tree' (duration: 230.343636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.864924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.592449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.864957Z","caller":"traceutil/trace.go:171","msg":"trace[391680555] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"230.6459ms","start":"2026-05-10T03:57:47.634304Z","end":"2026-05-10T03:57:47.86495Z","steps":["trace[391680555] 'count revisions from in-memory index tree' (duration: 230.518608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.865114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.402401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.865151Z","caller":"traceutil/trace.go:171","msg":"trace[1896877135] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"175.450181ms","start":"2026-05-10T03:57:47.689693Z","end":"2026-05-10T03:57:47.865143Z","steps":["trace[1896877135] 'count revisions from in-memory index tree' (duration: 175.374571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.865326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.675895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.865368Z","caller":"traceutil/trace.go:171","msg":"trace[1772577107] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"175.729505ms","start":"2026-05-10T03:57:47.689625Z","end":"2026-05-10T03:57:47.865355Z","steps":["trace[1772577107] 'range keys from in-memory index tree' (duration: 175.572012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.864884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.51371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T03:57:47.86552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.156813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.865557Z","caller":"traceutil/trace.go:171","msg":"trace[1227486175] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"166.235764ms","start":"2026-05-10T03:57:47.699313Z","end":"2026-05-10T03:57:47.865548Z","steps":["trace[1227486175] 'range keys from in-memory index tree' (duration: 166.075902ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:57:47.865575Z","caller":"traceutil/trace.go:171","msg":"trace[305996168] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"397.133347ms","start":"2026-05-10T03:57:47.46833Z","end":"2026-05-10T03:57:47.865464Z","steps":["trace[305996168] 'count revisions from in-memory index tree' (duration: 396.46876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.865699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.362486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.865736Z","caller":"traceutil/trace.go:171","msg":"trace[1331546288] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"166.424447ms","start":"2026-05-10T03:57:47.699304Z","end":"2026-05-10T03:57:47.865728Z","steps":["trace[1331546288] 'count revisions from in-memory index tree' (duration: 166.281485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.865828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.17645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.865858Z","caller":"traceutil/trace.go:171","msg":"trace[1381720022] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"157.21421ms","start":"2026-05-10T03:57:47.708636Z","end":"2026-05-10T03:57:47.865851Z","steps":["trace[1381720022] 'range keys from in-memory index tree' (duration: 157.15392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.865626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.46832Z","time spent":"397.293589ms","remote":"127.0.0.1:44784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.865991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.386523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.866024Z","caller":"traceutil/trace.go:171","msg":"trace[155207467] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"157.433013ms","start":"2026-05-10T03:57:47.708584Z","end":"2026-05-10T03:57:47.866017Z","steps":["trace[155207467] 'count revisions from in-memory index tree' (duration: 157.302272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.092183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T03:57:47.86614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.582173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.866171Z","caller":"traceutil/trace.go:171","msg":"trace[504585230] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"145.642234ms","start":"2026-05-10T03:57:47.720521Z","end":"2026-05-10T03:57:47.866163Z","steps":["trace[504585230] 'range keys from in-memory index tree' (duration: 145.538433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.418397Z","time spent":"447.85951ms","remote":"127.0.0.1:44720","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-10T03:57:47.866271Z","caller":"traceutil/trace.go:171","msg":"trace[231321648] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"373.261416ms","start":"2026-05-10T03:57:47.492887Z","end":"2026-05-10T03:57:47.866149Z","steps":["trace[231321648] 'count revisions from in-memory index tree' (duration: 372.105981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.444121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.866389Z","caller":"traceutil/trace.go:171","msg":"trace[1674338896] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"145.496602ms","start":"2026-05-10T03:57:47.720882Z","end":"2026-05-10T03:57:47.866379Z","steps":["trace[1674338896] 'count revisions from in-memory index tree' (duration: 145.41121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.492882Z","time spent":"373.57454ms","remote":"127.0.0.1:44786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.86654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.23135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.866589Z","caller":"traceutil/trace.go:171","msg":"trace[756609080] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"134.31624ms","start":"2026-05-10T03:57:47.732258Z","end":"2026-05-10T03:57:47.866575Z","steps":["trace[756609080] 'range keys from in-memory index tree' (duration: 134.180159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.903614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.866854Z","caller":"traceutil/trace.go:171","msg":"trace[248893558] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"374.020085ms","start":"2026-05-10T03:57:47.492824Z","end":"2026-05-10T03:57:47.866844Z","steps":["trace[248893558] 'range keys from in-memory index tree' (duration: 373.793123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.371063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.866971Z","caller":"traceutil/trace.go:171","msg":"trace[1302189403] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"443.424243ms","start":"2026-05-10T03:57:47.423539Z","end":"2026-05-10T03:57:47.866963Z","steps":["trace[1302189403] 'count revisions from in-memory index tree' (duration: 443.253452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.423523Z","time spent":"443.463955ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.867142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.407673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.867195Z","caller":"traceutil/trace.go:171","msg":"trace[1585098589] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"420.521864ms","start":"2026-05-10T03:57:47.446666Z","end":"2026-05-10T03:57:47.867188Z","steps":["trace[1585098589] 'count revisions from in-memory index tree' (duration: 420.329641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.86723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.446652Z","time spent":"420.568565ms","remote":"127.0.0.1:44770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":27,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.867418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.570151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.867469Z","caller":"traceutil/trace.go:171","msg":"trace[1545221521] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"411.636082ms","start":"2026-05-10T03:57:47.455822Z","end":"2026-05-10T03:57:47.867458Z","steps":["trace[1545221521] 'range keys from in-memory index tree' (duration: 411.52111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.867491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.455812Z","time spent":"411.671932ms","remote":"127.0.0.1:44774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.867653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.680992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.867726Z","caller":"traceutil/trace.go:171","msg":"trace[1452952360] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"411.781663ms","start":"2026-05-10T03:57:47.455938Z","end":"2026-05-10T03:57:47.867719Z","steps":["trace[1452952360] 'count revisions from in-memory index tree' (duration: 411.631202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.841456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.867862Z","caller":"traceutil/trace.go:171","msg":"trace[1592257280] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"112.87631ms","start":"2026-05-10T03:57:47.754973Z","end":"2026-05-10T03:57:47.867849Z","steps":["trace[1592257280] 'range keys from in-memory index tree' (duration: 111.774065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.867964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.68461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.868036Z","caller":"traceutil/trace.go:171","msg":"trace[1113831075] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"399.72854ms","start":"2026-05-10T03:57:47.468257Z","end":"2026-05-10T03:57:47.867985Z","steps":["trace[1113831075] 'range keys from in-memory index tree' (duration: 399.579788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.868063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.468247Z","time spent":"399.810211ms","remote":"127.0.0.1:44784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.868262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.299637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.868349Z","caller":"traceutil/trace.go:171","msg":"trace[728166493] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"362.389688ms","start":"2026-05-10T03:57:47.505952Z","end":"2026-05-10T03:57:47.868341Z","steps":["trace[728166493] 'count revisions from in-memory index tree' (duration: 362.235295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.868929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.505933Z","time spent":"362.983435ms","remote":"127.0.0.1:44788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.869141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.067567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.869221Z","caller":"traceutil/trace.go:171","msg":"trace[1100022872] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"363.162558ms","start":"2026-05-10T03:57:47.506049Z","end":"2026-05-10T03:57:47.869212Z","steps":["trace[1100022872] 'range keys from in-memory index tree' (duration: 363.020436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.869295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.506041Z","time spent":"363.243799ms","remote":"127.0.0.1:44788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.869464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.315841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.869558Z","caller":"traceutil/trace.go:171","msg":"trace[84127047] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"352.441352ms","start":"2026-05-10T03:57:47.517102Z","end":"2026-05-10T03:57:47.869543Z","steps":["trace[84127047] 'range keys from in-memory index tree' (duration: 352.181189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.869662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.51708Z","time spent":"352.571653ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.869819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.635114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.869898Z","caller":"traceutil/trace.go:171","msg":"trace[1293186163] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"352.743666ms","start":"2026-05-10T03:57:47.517143Z","end":"2026-05-10T03:57:47.869887Z","steps":["trace[1293186163] 'count revisions from in-memory index tree' (duration: 352.520723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.869987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.517125Z","time spent":"352.852227ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.864071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.179629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.870384Z","caller":"traceutil/trace.go:171","msg":"trace[586420489] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"283.503179ms","start":"2026-05-10T03:57:47.586847Z","end":"2026-05-10T03:57:47.87035Z","steps":["trace[586420489] 'count revisions from in-memory index tree' (duration: 277.073628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.492814Z","time spent":"374.126906ms","remote":"127.0.0.1:44786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.863991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.521703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T03:57:47.864102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.181569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.870774Z","caller":"traceutil/trace.go:171","msg":"trace[1842614911] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"295.367489ms","start":"2026-05-10T03:57:47.575397Z","end":"2026-05-10T03:57:47.870764Z","steps":["trace[1842614911] 'count revisions from in-memory index tree' (duration: 288.388101ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:57:47.870858Z","caller":"traceutil/trace.go:171","msg":"trace[813387033] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"283.911555ms","start":"2026-05-10T03:57:47.586877Z","end":"2026-05-10T03:57:47.870788Z","steps":["trace[813387033] 'range keys from in-memory index tree' (duration: 277.078618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.864153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.916894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.871145Z","caller":"traceutil/trace.go:171","msg":"trace[439338038] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"271.912453ms","start":"2026-05-10T03:57:47.599217Z","end":"2026-05-10T03:57:47.87113Z","steps":["trace[439338038] 'count revisions from in-memory index tree' (duration: 264.851374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.864183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.160985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.871461Z","caller":"traceutil/trace.go:171","msg":"trace[891477287] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"260.447767ms","start":"2026-05-10T03:57:47.610999Z","end":"2026-05-10T03:57:47.871447Z","steps":["trace[891477287] 'range keys from in-memory index tree' (duration: 253.107674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.864209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.000423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.871709Z","caller":"traceutil/trace.go:171","msg":"trace[1107172419] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"260.496528ms","start":"2026-05-10T03:57:47.611202Z","end":"2026-05-10T03:57:47.871698Z","steps":["trace[1107172419] 'count revisions from in-memory index tree' (duration: 252.967932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.864233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.099693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.871848Z","caller":"traceutil/trace.go:171","msg":"trace[507930289] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"248.713289ms","start":"2026-05-10T03:57:47.623127Z","end":"2026-05-10T03:57:47.87184Z","steps":["trace[507930289] 'range keys from in-memory index tree' (duration: 241.070692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.344454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.872019Z","caller":"traceutil/trace.go:171","msg":"trace[1850678286] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"129.59884ms","start":"2026-05-10T03:57:47.742412Z","end":"2026-05-10T03:57:47.872011Z","steps":["trace[1850678286] 'range keys from in-memory index tree' (duration: 124.292983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.259923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.872206Z","caller":"traceutil/trace.go:171","msg":"trace[1024467699] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"129.682861ms","start":"2026-05-10T03:57:47.742516Z","end":"2026-05-10T03:57:47.872199Z","steps":["trace[1024467699] 'count revisions from in-memory index tree' (duration: 121.118103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.13034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.872384Z","caller":"traceutil/trace.go:171","msg":"trace[1165820751] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"448.768252ms","start":"2026-05-10T03:57:47.423597Z","end":"2026-05-10T03:57:47.872365Z","steps":["trace[1165820751] 'range keys from in-memory index tree' (duration: 443.1051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.872448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.423594Z","time spent":"448.817942ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":121,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.866889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.745515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.872606Z","caller":"traceutil/trace.go:171","msg":"trace[320539632] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"117.487558ms","start":"2026-05-10T03:57:47.75511Z","end":"2026-05-10T03:57:47.872598Z","steps":["trace[320539632] 'count revisions from in-memory index tree' (duration: 111.659254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.866756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.391962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.873014Z","caller":"traceutil/trace.go:171","msg":"trace[609974777] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"140.687541ms","start":"2026-05-10T03:57:47.732312Z","end":"2026-05-10T03:57:47.872999Z","steps":["trace[609974777] 'count revisions from in-memory index tree' (duration: 134.307511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.873287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.649053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T03:57:47.86789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.455927Z","time spent":"411.815824ms","remote":"127.0.0.1:44774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-05-10T03:57:47.873317Z","caller":"traceutil/trace.go:171","msg":"trace[1315593591] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"344.697804ms","start":"2026-05-10T03:57:47.528614Z","end":"2026-05-10T03:57:47.873312Z","steps":["trace[1315593591] 'count revisions from in-memory index tree' (duration: 344.568612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.873342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.528606Z","time spent":"344.728724ms","remote":"127.0.0.1:44818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.873442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.225109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-10T03:57:47.86413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.989985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.874611Z","caller":"traceutil/trace.go:171","msg":"trace[553244028] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"275.491298ms","start":"2026-05-10T03:57:47.599109Z","end":"2026-05-10T03:57:47.874601Z","steps":["trace[553244028] 'range keys from in-memory index tree' (duration: 264.925404ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:57:47.873473Z","caller":"traceutil/trace.go:171","msg":"trace[1648397185] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"333.27045ms","start":"2026-05-10T03:57:47.540195Z","end":"2026-05-10T03:57:47.873465Z","steps":["trace[1648397185] 'range keys from in-memory index tree' (duration: 333.169508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.87477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.540186Z","time spent":"334.572256ms","remote":"127.0.0.1:44834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.874905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.446325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.87493Z","caller":"traceutil/trace.go:171","msg":"trace[520247679] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"334.522895ms","start":"2026-05-10T03:57:47.540401Z","end":"2026-05-10T03:57:47.874924Z","steps":["trace[520247679] 'count revisions from in-memory index tree' (duration: 334.345433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.874954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.54037Z","time spent":"334.578606ms","remote":"127.0.0.1:44834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.875052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.384701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.875076Z","caller":"traceutil/trace.go:171","msg":"trace[1974492732] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"322.408182ms","start":"2026-05-10T03:57:47.552661Z","end":"2026-05-10T03:57:47.875069Z","steps":["trace[1974492732] 'range keys from in-memory index tree' (duration: 322.31887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.875095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.552645Z","time spent":"322.444282ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.875255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.388411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.875285Z","caller":"traceutil/trace.go:171","msg":"trace[948363330] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"322.459862ms","start":"2026-05-10T03:57:47.552818Z","end":"2026-05-10T03:57:47.875278Z","steps":["trace[948363330] 'count revisions from in-memory index tree' (duration: 322.332241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.875317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.552807Z","time spent":"322.492903ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.875457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.239578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.875499Z","caller":"traceutil/trace.go:171","msg":"trace[299935391] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"310.299058ms","start":"2026-05-10T03:57:47.565187Z","end":"2026-05-10T03:57:47.875486Z","steps":["trace[299935391] 'range keys from in-memory index tree' (duration: 310.187617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.875522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.56518Z","time spent":"310.336068ms","remote":"127.0.0.1:44854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 "} {"level":"warn","ts":"2026-05-10T03:57:47.875709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.015265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.875741Z","caller":"traceutil/trace.go:171","msg":"trace[715850831] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"310.058935ms","start":"2026-05-10T03:57:47.565674Z","end":"2026-05-10T03:57:47.875733Z","steps":["trace[715850831] 'count revisions from in-memory index tree' (duration: 309.905384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.875772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.565635Z","time spent":"310.123705ms","remote":"127.0.0.1:44854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-10T03:57:47.875941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.537435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-10T03:57:47.875971Z","caller":"traceutil/trace.go:171","msg":"trace[113138824] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"300.581985ms","start":"2026-05-10T03:57:47.575383Z","end":"2026-05-10T03:57:47.875965Z","steps":["trace[113138824] 'range keys from in-memory index tree' (duration: 300.429444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:57:47.875996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:57:47.575376Z","time spent":"300.613225ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":27,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 "} {"level":"info","ts":"2026-05-10T03:58:16.692418Z","caller":"traceutil/trace.go:171","msg":"trace[1798539511] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"202.100082ms","start":"2026-05-10T03:58:16.490296Z","end":"2026-05-10T03:58:16.692396Z","steps":["trace[1798539511] 'process raft request' (duration: 150.389532ms)","trace[1798539511] 'compare' (duration: 51.594469ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T03:58:16.69321Z","caller":"traceutil/trace.go:171","msg":"trace[53426579] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"199.648297ms","start":"2026-05-10T03:58:16.493547Z","end":"2026-05-10T03:58:16.693195Z","steps":["trace[53426579] 'process raft request' (duration: 199.524376ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:58:16.693333Z","caller":"traceutil/trace.go:171","msg":"trace[1495517086] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"166.928021ms","start":"2026-05-10T03:58:16.52637Z","end":"2026-05-10T03:58:16.693298Z","steps":["trace[1495517086] 'process raft request' (duration: 166.78424ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:58:16.694222Z","caller":"traceutil/trace.go:171","msg":"trace[459659498] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"115.620116ms","start":"2026-05-10T03:58:16.578582Z","end":"2026-05-10T03:58:16.694202Z","steps":["trace[459659498] 'process raft request' (duration: 115.128141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:58:17.154991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.376093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T03:58:17.155075Z","caller":"traceutil/trace.go:171","msg":"trace[216641876] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"446.414651ms","start":"2026-05-10T03:58:16.708648Z","end":"2026-05-10T03:58:17.155062Z","steps":["trace[216641876] 'process raft request' (duration: 295.595283ms)","trace[216641876] 'compare' (duration: 150.296303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T03:58:17.155115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:58:16.708632Z","time spent":"446.464231ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T03:58:17.158651Z","caller":"traceutil/trace.go:171","msg":"trace[1976877955] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:627; }","duration":"235.741727ms","start":"2026-05-10T03:58:16.922891Z","end":"2026-05-10T03:58:17.158633Z","steps":["trace[1976877955] 'read index received' (duration: 81.405116ms)","trace[1976877955] 'applied index is now lower than readState.Index' (duration: 154.336041ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T03:58:17.158721Z","caller":"traceutil/trace.go:171","msg":"trace[307006066] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"429.70852ms","start":"2026-05-10T03:58:16.729007Z","end":"2026-05-10T03:58:17.158716Z","steps":["trace[307006066] 'process raft request' (duration: 429.365836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:58:17.158817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:58:16.72899Z","time spent":"429.74655ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T03:58:17.15901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.182122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T03:58:17.159033Z","caller":"traceutil/trace.go:171","msg":"trace[1637114780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"146.240893ms","start":"2026-05-10T03:58:17.012786Z","end":"2026-05-10T03:58:17.159027Z","steps":["trace[1637114780] 'agreement among raft nodes before linearized reading' (duration: 146.175092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:58:17.15907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.161451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T03:58:17.159138Z","caller":"traceutil/trace.go:171","msg":"trace[953026270] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:611; }","duration":"236.271802ms","start":"2026-05-10T03:58:16.92285Z","end":"2026-05-10T03:58:17.159121Z","steps":["trace[953026270] 'agreement among raft nodes before linearized reading' (duration: 236.10642ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:58:17.159255Z","caller":"traceutil/trace.go:171","msg":"trace[161353102] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"328.390992ms","start":"2026-05-10T03:58:16.830856Z","end":"2026-05-10T03:58:17.159247Z","steps":["trace[161353102] 'process raft request' (duration: 327.707255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:58:17.159314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:58:16.830822Z","time spent":"328.453912ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T03:58:17.159487Z","caller":"traceutil/trace.go:171","msg":"trace[1597014356] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"398.435997ms","start":"2026-05-10T03:58:16.760571Z","end":"2026-05-10T03:58:17.159007Z","steps":["trace[1597014356] 'process raft request' (duration: 397.891972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:58:17.159851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:58:16.760554Z","time spent":"399.122285ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T03:58:21.744823Z","caller":"traceutil/trace.go:171","msg":"trace[1422179272] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"118.850633ms","start":"2026-05-10T03:58:21.625621Z","end":"2026-05-10T03:58:21.744472Z","steps":["trace[1422179272] 'process raft request' (duration: 118.60523ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:58:23.236742Z","caller":"traceutil/trace.go:171","msg":"trace[1178465443] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"230.963788ms","start":"2026-05-10T03:58:23.005741Z","end":"2026-05-10T03:58:23.236705Z","steps":["trace[1178465443] 'process raft request' (duration: 230.817697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:58:23.556049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.668271ms","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-05-10T03:58:23.556128Z","caller":"traceutil/trace.go:171","msg":"trace[735909494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"250.799402ms","start":"2026-05-10T03:58:23.30531Z","end":"2026-05-10T03:58:23.556109Z","steps":["trace[735909494] 'range keys from in-memory index tree' (duration: 250.526579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:58:24.197468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.319995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-10T03:58:24.197578Z","caller":"traceutil/trace.go:171","msg":"trace[1426891196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"214.472647ms","start":"2026-05-10T03:58:23.983087Z","end":"2026-05-10T03:58:24.19756Z","steps":["trace[1426891196] 'range keys from in-memory index tree' (duration: 214.146604ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:58:52.376958Z","caller":"traceutil/trace.go:171","msg":"trace[489951329] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"172.034405ms","start":"2026-05-10T03:58:52.204906Z","end":"2026-05-10T03:58:52.37694Z","steps":["trace[489951329] 'process raft request' (duration: 171.671271ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:59:23.404039Z","caller":"traceutil/trace.go:171","msg":"trace[926841543] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"116.651494ms","start":"2026-05-10T03:59:23.287354Z","end":"2026-05-10T03:59:23.404006Z","steps":["trace[926841543] 'process raft request' (duration: 116.52013ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:59:23.4097Z","caller":"traceutil/trace.go:171","msg":"trace[1977503963] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"114.669325ms","start":"2026-05-10T03:59:23.295006Z","end":"2026-05-10T03:59:23.409676Z","steps":["trace[1977503963] 'process raft request' (duration: 114.560982ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T03:59:53.333643Z","caller":"traceutil/trace.go:171","msg":"trace[755747601] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"338.713013ms","start":"2026-05-10T03:59:52.994906Z","end":"2026-05-10T03:59:53.333619Z","steps":["trace[755747601] 'read index received' (duration: 338.445407ms)","trace[755747601] 'applied index is now lower than readState.Index' (duration: 266.856µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T03:59:53.333826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.895348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T03:59:53.333894Z","caller":"traceutil/trace.go:171","msg":"trace[1917029721] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1846; }","duration":"339.00364ms","start":"2026-05-10T03:59:52.994877Z","end":"2026-05-10T03:59:53.33388Z","steps":["trace[1917029721] 'agreement among raft nodes before linearized reading' (duration: 338.840197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:59:53.333934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:59:52.99486Z","time spent":"339.064771ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-10T03:59:53.33417Z","caller":"traceutil/trace.go:171","msg":"trace[1615500750] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"359.777712ms","start":"2026-05-10T03:59:52.974356Z","end":"2026-05-10T03:59:53.334134Z","steps":["trace[1615500750] 'process raft request' (duration: 359.068656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:59:53.33435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T03:59:52.974332Z","time spent":"359.931175ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T03:59:53.572515Z","caller":"traceutil/trace.go:171","msg":"trace[1670783362] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"117.225727ms","start":"2026-05-10T03:59:53.455264Z","end":"2026-05-10T03:59:53.57249Z","steps":["trace[1670783362] 'process raft request' (duration: 117.061303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T03:59:53.839761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.095949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T03:59:53.839848Z","caller":"traceutil/trace.go:171","msg":"trace[899794771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1848; }","duration":"129.289834ms","start":"2026-05-10T03:59:53.710538Z","end":"2026-05-10T03:59:53.839827Z","steps":["trace[899794771] 'range keys from in-memory index tree' (duration: 128.826483ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:00:33.971203Z","caller":"traceutil/trace.go:171","msg":"trace[386223806] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"106.89033ms","start":"2026-05-10T04:00:33.864284Z","end":"2026-05-10T04:00:33.971174Z","steps":["trace[386223806] 'read index received' (duration: 106.676596ms)","trace[386223806] 'applied index is now lower than readState.Index' (duration: 212.454µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:00:33.971284Z","caller":"traceutil/trace.go:171","msg":"trace[1025067196] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"256.294683ms","start":"2026-05-10T04:00:33.71496Z","end":"2026-05-10T04:00:33.971255Z","steps":["trace[1025067196] 'process raft request' (duration: 256.075059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:00:33.97147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.151234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:00:33.971532Z","caller":"traceutil/trace.go:171","msg":"trace[1866734958] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2125; }","duration":"107.285427ms","start":"2026-05-10T04:00:33.864237Z","end":"2026-05-10T04:00:33.971522Z","steps":["trace[1866734958] 'agreement among raft nodes before linearized reading' (duration: 107.043923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:35.416121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.277318ms","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-05-10T04:01:35.416221Z","caller":"traceutil/trace.go:171","msg":"trace[1776826440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2559; }","duration":"177.39724ms","start":"2026-05-10T04:01:35.238806Z","end":"2026-05-10T04:01:35.416203Z","steps":["trace[1776826440] 'range keys from in-memory index tree' (duration: 177.144506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:01:35.416117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.704189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:01:35.416371Z","caller":"traceutil/trace.go:171","msg":"trace[2071201282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2559; }","duration":"202.008373ms","start":"2026-05-10T04:01:35.214346Z","end":"2026-05-10T04:01:35.416354Z","steps":["trace[2071201282] 'count revisions from in-memory index tree' (duration: 201.642928ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:02:08.687629Z","caller":"traceutil/trace.go:171","msg":"trace[984997697] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"180.454385ms","start":"2026-05-10T04:02:08.50714Z","end":"2026-05-10T04:02:08.687594Z","steps":["trace[984997697] 'process raft request' (duration: 180.223542ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:02:08.692393Z","caller":"traceutil/trace.go:171","msg":"trace[806454583] linearizableReadLoop","detail":"{readStateIndex:2843; appliedIndex:2841; }","duration":"160.454245ms","start":"2026-05-10T04:02:08.531914Z","end":"2026-05-10T04:02:08.692369Z","steps":["trace[806454583] 'read index received' (duration: 155.386937ms)","trace[806454583] 'applied index is now lower than readState.Index' (duration: 5.066088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:02:08.692574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.648558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:02:08.692613Z","caller":"traceutil/trace.go:171","msg":"trace[2047866249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2742; }","duration":"160.741999ms","start":"2026-05-10T04:02:08.531857Z","end":"2026-05-10T04:02:08.692599Z","steps":["trace[2047866249] 'agreement among raft nodes before linearized reading' (duration: 160.634288ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:02:08.692976Z","caller":"traceutil/trace.go:171","msg":"trace[1444166571] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"185.005827ms","start":"2026-05-10T04:02:08.507488Z","end":"2026-05-10T04:02:08.692494Z","steps":["trace[1444166571] 'process raft request' (duration: 184.747483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:09.015716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.827219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:02:09.015866Z","caller":"traceutil/trace.go:171","msg":"trace[196834893] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"318.67911ms","start":"2026-05-10T04:02:08.697164Z","end":"2026-05-10T04:02:09.015843Z","steps":["trace[196834893] 'process raft request' (duration: 140.599598ms)","trace[196834893] 'compare' (duration: 176.931407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:02:09.015939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:08.697143Z","time spent":"318.767761ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:02:09.193129Z","caller":"traceutil/trace.go:171","msg":"trace[1554172240] linearizableReadLoop","detail":"{readStateIndex:2846; appliedIndex:2845; }","duration":"280.522051ms","start":"2026-05-10T04:02:08.912582Z","end":"2026-05-10T04:02:09.193104Z","steps":["trace[1554172240] 'read index received' (duration: 226.355202ms)","trace[1554172240] 'applied index is now lower than readState.Index' (duration: 54.165539ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:02:09.193244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.646213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:02:09.193265Z","caller":"traceutil/trace.go:171","msg":"trace[1377068135] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2745; }","duration":"280.747884ms","start":"2026-05-10T04:02:08.912511Z","end":"2026-05-10T04:02:09.193259Z","steps":["trace[1377068135] 'agreement among raft nodes before linearized reading' (duration: 280.677264ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:02:09.193401Z","caller":"traceutil/trace.go:171","msg":"trace[254211846] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"298.967631ms","start":"2026-05-10T04:02:08.894386Z","end":"2026-05-10T04:02:09.193353Z","steps":["trace[254211846] 'process raft request' (duration: 244.510377ms)","trace[254211846] 'compare' (duration: 53.962757ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:16.15796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513383024261924,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:03:16.626298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304026211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:03:16.626385Z","caller":"traceutil/trace.go:171","msg":"trace[1400661539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3089; }","duration":"1.304170117s","start":"2026-05-10T04:03:15.322196Z","end":"2026-05-10T04:03:16.626367Z","steps":["trace[1400661539] 'range keys from in-memory index tree' (duration: 1.303827042s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.626425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.322171Z","time spent":"1.30424501s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T04:03:16.626454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621607499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-10T04:03:16.62654Z","caller":"traceutil/trace.go:171","msg":"trace[2128527300] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3089; }","duration":"1.621729685s","start":"2026-05-10T04:03:15.004789Z","end":"2026-05-10T04:03:16.626519Z","steps":["trace[2128527300] 'range keys from in-memory index tree' (duration: 1.621448403s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.626603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.004771Z","time spent":"1.621822398s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-10T04:03:16.626737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516726766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T04:03:16.626847Z","caller":"traceutil/trace.go:171","msg":"trace[590424436] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3089; }","duration":"1.516852781s","start":"2026-05-10T04:03:15.109969Z","end":"2026-05-10T04:03:16.626822Z","steps":["trace[590424436] 'count revisions from in-memory index tree' (duration: 1.51659506s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.62691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.109959Z","time spent":"1.516934234s","remote":"127.0.0.1:60770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:03:16.626933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249204307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-10T04:03:16.626966Z","caller":"traceutil/trace.go:171","msg":"trace[1105069117] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3089; }","duration":"1.249262659s","start":"2026-05-10T04:03:15.377693Z","end":"2026-05-10T04:03:16.626956Z","steps":["trace[1105069117] 'range keys from in-memory index tree' (duration: 1.249098313s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.626986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607744966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T04:03:16.627112Z","caller":"traceutil/trace.go:171","msg":"trace[146975137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3089; }","duration":"1.607813489s","start":"2026-05-10T04:03:15.019208Z","end":"2026-05-10T04:03:16.627022Z","steps":["trace[146975137] 'range keys from in-memory index tree' (duration: 1.607065409s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.627156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.019199Z","time spent":"1.607947324s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T04:03:16.627001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.377679Z","time spent":"1.249315582s","remote":"127.0.0.1:44900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-10T04:03:16.627654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.605421032s","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-05-10T04:03:16.627711Z","caller":"traceutil/trace.go:171","msg":"trace[1675514931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3089; }","duration":"1.605474654s","start":"2026-05-10T04:03:15.02222Z","end":"2026-05-10T04:03:16.627695Z","steps":["trace[1675514931] 'range keys from in-memory index tree' (duration: 1.603910881s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.62773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.732191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-10T04:03:16.627749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.022189Z","time spent":"1.605553357s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-10T04:03:16.627997Z","caller":"traceutil/trace.go:171","msg":"trace[1582135400] transaction","detail":"{read_only:false; response_revision:3090; number_of_response:1; }","duration":"1.168324067s","start":"2026-05-10T04:03:15.459656Z","end":"2026-05-10T04:03:16.62798Z","steps":["trace[1582135400] 'process raft request' (duration: 939.278973ms)","trace[1582135400] 'compare' (duration: 227.919068ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:16.628128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.459632Z","time spent":"1.16841027s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:03:16.722381Z","caller":"traceutil/trace.go:171","msg":"trace[1003611277] linearizableReadLoop","detail":"{readStateIndex:3207; appliedIndex:3204; }","duration":"1.065423186s","start":"2026-05-10T04:03:15.656925Z","end":"2026-05-10T04:03:16.722348Z","steps":["trace[1003611277] 'read index received' (duration: 742.016304ms)","trace[1003611277] 'applied index is now lower than readState.Index' (duration: 323.404692ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:16.722457Z","caller":"traceutil/trace.go:171","msg":"trace[2024809858] transaction","detail":"{read_only:false; response_revision:3092; number_of_response:1; }","duration":"181.445003ms","start":"2026-05-10T04:03:16.540993Z","end":"2026-05-10T04:03:16.722438Z","steps":["trace[2024809858] 'process raft request' (duration: 180.900451ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:16.722544Z","caller":"traceutil/trace.go:171","msg":"trace[1221973817] transaction","detail":"{read_only:false; response_revision:3091; number_of_response:1; }","duration":"623.100897ms","start":"2026-05-10T04:03:16.099427Z","end":"2026-05-10T04:03:16.722528Z","steps":["trace[1221973817] 'process raft request' (duration: 622.225642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.722657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.090905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-10T04:03:16.72267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:16.099405Z","time spent":"623.175661ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:03:16.722702Z","caller":"traceutil/trace.go:171","msg":"trace[807907984] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3096; }","duration":"906.180169ms","start":"2026-05-10T04:03:15.816512Z","end":"2026-05-10T04:03:16.722692Z","steps":["trace[807907984] 'agreement among raft nodes before linearized reading' (duration: 906.063484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.722735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.816493Z","time spent":"906.235462ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-10T04:03:16.722813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.754359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:16.722846Z","caller":"traceutil/trace.go:171","msg":"trace[1022538989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3096; }","duration":"190.78258ms","start":"2026-05-10T04:03:16.532053Z","end":"2026-05-10T04:03:16.722835Z","steps":["trace[1022538989] 'agreement among raft nodes before linearized reading' (duration: 190.741418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.72303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.079414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-10T04:03:16.723066Z","caller":"traceutil/trace.go:171","msg":"trace[846134497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3096; }","duration":"779.140317ms","start":"2026-05-10T04:03:15.943916Z","end":"2026-05-10T04:03:16.723056Z","steps":["trace[846134497] 'agreement among raft nodes before linearized reading' (duration: 779.056553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.723093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.943905Z","time spent":"779.181259ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T04:03:16.723104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066181786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-10T04:03:16.723147Z","caller":"traceutil/trace.go:171","msg":"trace[2024733912] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3096; }","duration":"1.06626384s","start":"2026-05-10T04:03:15.656868Z","end":"2026-05-10T04:03:16.723132Z","steps":["trace[2024733912] 'agreement among raft nodes before linearized reading' (duration: 1.066147615s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.723223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.656846Z","time spent":"1.066368494s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-10T04:03:16.723259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.602611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:16.723397Z","caller":"traceutil/trace.go:171","msg":"trace[1886337002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3096; }","duration":"805.702594ms","start":"2026-05-10T04:03:15.917618Z","end":"2026-05-10T04:03:16.72332Z","steps":["trace[1886337002] 'agreement among raft nodes before linearized reading' (duration: 805.462085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:16.723441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:15.917556Z","time spent":"805.875761ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T04:03:16.955272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.828085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-05-10T04:03:16.955345Z","caller":"traceutil/trace.go:171","msg":"trace[655540919] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3096; }","duration":"228.934469ms","start":"2026-05-10T04:03:16.726395Z","end":"2026-05-10T04:03:16.955329Z","steps":["trace[655540919] 'range keys from in-memory index tree' (duration: 228.69335ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:16.955484Z","caller":"traceutil/trace.go:171","msg":"trace[1372702543] transaction","detail":"{read_only:false; response_revision:3097; number_of_response:1; }","duration":"227.233571ms","start":"2026-05-10T04:03:16.728238Z","end":"2026-05-10T04:03:16.955472Z","steps":["trace[1372702543] 'process raft request' (duration: 145.842506ms)","trace[1372702543] 'compare' (duration: 80.937517ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:16.955573Z","caller":"traceutil/trace.go:171","msg":"trace[447813575] transaction","detail":"{read_only:false; response_revision:3099; number_of_response:1; }","duration":"226.377547ms","start":"2026-05-10T04:03:16.729187Z","end":"2026-05-10T04:03:16.955565Z","steps":["trace[447813575] 'process raft request' (duration: 226.138207ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:16.955624Z","caller":"traceutil/trace.go:171","msg":"trace[334683534] transaction","detail":"{read_only:false; response_revision:3098; number_of_response:1; }","duration":"226.765322ms","start":"2026-05-10T04:03:16.72882Z","end":"2026-05-10T04:03:16.955586Z","steps":["trace[334683534] 'process raft request' (duration: 226.421819ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:18.78031Z","caller":"traceutil/trace.go:171","msg":"trace[1435203385] transaction","detail":"{read_only:false; response_revision:3103; number_of_response:1; }","duration":"107.515678ms","start":"2026-05-10T04:03:18.672752Z","end":"2026-05-10T04:03:18.780268Z","steps":["trace[1435203385] 'process raft request' (duration: 107.349491ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:18.863685Z","caller":"traceutil/trace.go:171","msg":"trace[113486844] transaction","detail":"{read_only:false; response_revision:3106; number_of_response:1; }","duration":"111.694716ms","start":"2026-05-10T04:03:18.751965Z","end":"2026-05-10T04:03:18.86366Z","steps":["trace[113486844] 'process raft request' (duration: 111.653714ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:18.863984Z","caller":"traceutil/trace.go:171","msg":"trace[1020707447] transaction","detail":"{read_only:false; response_revision:3105; number_of_response:1; }","duration":"133.316709ms","start":"2026-05-10T04:03:18.730631Z","end":"2026-05-10T04:03:18.863947Z","steps":["trace[1020707447] 'process raft request' (duration: 132.949325ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:18.864025Z","caller":"traceutil/trace.go:171","msg":"trace[293080804] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"134.82539ms","start":"2026-05-10T04:03:18.729167Z","end":"2026-05-10T04:03:18.863993Z","steps":["trace[293080804] 'process raft request' (duration: 134.32161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:18.864243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.008657ms","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-05-10T04:03:18.864377Z","caller":"traceutil/trace.go:171","msg":"trace[340247291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3106; }","duration":"136.174704ms","start":"2026-05-10T04:03:18.728183Z","end":"2026-05-10T04:03:18.864358Z","steps":["trace[340247291] 'agreement among raft nodes before linearized reading' (duration: 135.845631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:18.864292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.81856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T04:03:18.864489Z","caller":"traceutil/trace.go:171","msg":"trace[1545681703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3106; }","duration":"136.068129ms","start":"2026-05-10T04:03:18.728408Z","end":"2026-05-10T04:03:18.864476Z","steps":["trace[1545681703] 'agreement among raft nodes before linearized reading' (duration: 135.747347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:19.102227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.783148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:03:19.102295Z","caller":"traceutil/trace.go:171","msg":"trace[769756156] transaction","detail":"{read_only:false; response_revision:3107; number_of_response:1; }","duration":"318.526546ms","start":"2026-05-10T04:03:18.783756Z","end":"2026-05-10T04:03:19.102283Z","steps":["trace[769756156] 'process raft request' (duration: 104.624783ms)","trace[769756156] 'compare' (duration: 213.516768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:19.102328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:18.783743Z","time spent":"318.571027ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:03:19.140142Z","caller":"traceutil/trace.go:171","msg":"trace[21953153] transaction","detail":"{read_only:false; response_revision:3108; number_of_response:1; }","duration":"271.264929ms","start":"2026-05-10T04:03:18.868864Z","end":"2026-05-10T04:03:19.140129Z","steps":["trace[21953153] 'process raft request' (duration: 271.069341ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:19.140121Z","caller":"traceutil/trace.go:171","msg":"trace[118608580] linearizableReadLoop","detail":"{readStateIndex:3225; appliedIndex:3223; }","duration":"180.867685ms","start":"2026-05-10T04:03:18.959232Z","end":"2026-05-10T04:03:19.1401Z","steps":["trace[118608580] 'read index received' (duration: 180.642096ms)","trace[118608580] 'applied index is now lower than readState.Index' (duration: 224.819µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:19.140247Z","caller":"traceutil/trace.go:171","msg":"trace[2010613333] transaction","detail":"{read_only:false; response_revision:3109; number_of_response:1; }","duration":"271.02563ms","start":"2026-05-10T04:03:18.869205Z","end":"2026-05-10T04:03:19.140231Z","steps":["trace[2010613333] 'process raft request' (duration: 270.858424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:19.140297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.052423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-10T04:03:19.140332Z","caller":"traceutil/trace.go:171","msg":"trace[94009002] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3109; }","duration":"181.138396ms","start":"2026-05-10T04:03:18.959186Z","end":"2026-05-10T04:03:19.140325Z","steps":["trace[94009002] 'agreement among raft nodes before linearized reading' (duration: 180.97793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:19.140439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.769911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-10T04:03:19.140446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.849146ms","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-05-10T04:03:19.140463Z","caller":"traceutil/trace.go:171","msg":"trace[1222737249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3109; }","duration":"180.814453ms","start":"2026-05-10T04:03:18.959643Z","end":"2026-05-10T04:03:19.140457Z","steps":["trace[1222737249] 'agreement among raft nodes before linearized reading' (duration: 180.706709ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:19.140467Z","caller":"traceutil/trace.go:171","msg":"trace[148021681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3109; }","duration":"153.891137ms","start":"2026-05-10T04:03:18.98657Z","end":"2026-05-10T04:03:19.140461Z","steps":["trace[148021681] 'agreement among raft nodes before linearized reading' (duration: 153.823445ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:19.266285Z","caller":"traceutil/trace.go:171","msg":"trace[330675662] transaction","detail":"{read_only:false; response_revision:3111; number_of_response:1; }","duration":"122.864278ms","start":"2026-05-10T04:03:19.143392Z","end":"2026-05-10T04:03:19.266256Z","steps":["trace[330675662] 'process raft request' (duration: 82.608117ms)","trace[330675662] 'compare' (duration: 39.96721ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:19.266407Z","caller":"traceutil/trace.go:171","msg":"trace[830450645] transaction","detail":"{read_only:false; response_revision:3112; number_of_response:1; }","duration":"122.796786ms","start":"2026-05-10T04:03:19.143594Z","end":"2026-05-10T04:03:19.266391Z","steps":["trace[830450645] 'process raft request' (duration: 122.501014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:19.776102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.233038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:19.776188Z","caller":"traceutil/trace.go:171","msg":"trace[1903487982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3112; }","duration":"243.381084ms","start":"2026-05-10T04:03:19.532789Z","end":"2026-05-10T04:03:19.77617Z","steps":["trace[1903487982] 'range keys from in-memory index tree' (duration: 243.126574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:19.776189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.59319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-10T04:03:19.776285Z","caller":"traceutil/trace.go:171","msg":"trace[2093273379] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3112; }","duration":"399.716515ms","start":"2026-05-10T04:03:19.376545Z","end":"2026-05-10T04:03:19.776262Z","steps":["trace[2093273379] 'range keys from in-memory index tree' (duration: 399.420843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:19.776351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:19.376538Z","time spent":"399.800218ms","remote":"127.0.0.1:44900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-10T04:03:20.516558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.078102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:03:20.516906Z","caller":"traceutil/trace.go:171","msg":"trace[1639636981] transaction","detail":"{read_only:false; response_revision:3113; number_of_response:1; }","duration":"182.31589ms","start":"2026-05-10T04:03:20.334466Z","end":"2026-05-10T04:03:20.516782Z","steps":["trace[1639636981] 'process raft request' (duration: 52.892495ms)","trace[1639636981] 'compare' (duration: 128.915985ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:20.786172Z","caller":"traceutil/trace.go:171","msg":"trace[1351208659] transaction","detail":"{read_only:false; response_revision:3114; number_of_response:1; }","duration":"112.214214ms","start":"2026-05-10T04:03:20.673934Z","end":"2026-05-10T04:03:20.786148Z","steps":["trace[1351208659] 'process raft request' (duration: 112.063518ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:21.129645Z","caller":"traceutil/trace.go:171","msg":"trace[1622448538] transaction","detail":"{read_only:false; response_revision:3115; number_of_response:1; }","duration":"259.789245ms","start":"2026-05-10T04:03:20.869822Z","end":"2026-05-10T04:03:21.129611Z","steps":["trace[1622448538] 'process raft request' (duration: 243.973242ms)","trace[1622448538] 'compare' (duration: 15.648357ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:21.147503Z","caller":"traceutil/trace.go:171","msg":"trace[147707454] transaction","detail":"{read_only:false; response_revision:3116; number_of_response:1; }","duration":"277.14043ms","start":"2026-05-10T04:03:20.870335Z","end":"2026-05-10T04:03:21.147476Z","steps":["trace[147707454] 'process raft request' (duration: 276.859458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:21.404242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.597601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:03:21.404425Z","caller":"traceutil/trace.go:171","msg":"trace[1605979173] linearizableReadLoop","detail":"{readStateIndex:3236; appliedIndex:3233; }","duration":"241.043081ms","start":"2026-05-10T04:03:21.163365Z","end":"2026-05-10T04:03:21.404408Z","steps":["trace[1605979173] 'read index received' (duration: 44.66087ms)","trace[1605979173] 'applied index is now lower than readState.Index' (duration: 196.381361ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:21.404521Z","caller":"traceutil/trace.go:171","msg":"trace[152387288] transaction","detail":"{read_only:false; response_revision:3119; number_of_response:1; }","duration":"251.213573ms","start":"2026-05-10T04:03:21.153282Z","end":"2026-05-10T04:03:21.404495Z","steps":["trace[152387288] 'process raft request' (duration: 251.075247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:21.404608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.856266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-10T04:03:21.404652Z","caller":"traceutil/trace.go:171","msg":"trace[591706528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3119; }","duration":"134.93972ms","start":"2026-05-10T04:03:21.269702Z","end":"2026-05-10T04:03:21.404641Z","steps":["trace[591706528] 'agreement among raft nodes before linearized reading' (duration: 134.811035ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:21.404663Z","caller":"traceutil/trace.go:171","msg":"trace[1267705343] transaction","detail":"{read_only:false; response_revision:3118; number_of_response:1; }","duration":"251.870648ms","start":"2026-05-10T04:03:21.15276Z","end":"2026-05-10T04:03:21.40463Z","steps":["trace[1267705343] 'process raft request' (duration: 126.795818ms)","trace[1267705343] 'compare' (duration: 124.486837ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:21.404764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.578235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-10T04:03:21.404804Z","caller":"traceutil/trace.go:171","msg":"trace[721822646] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3119; }","duration":"134.614377ms","start":"2026-05-10T04:03:21.270179Z","end":"2026-05-10T04:03:21.404793Z","steps":["trace[721822646] 'agreement among raft nodes before linearized reading' (duration: 134.532413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:21.404802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.420767ms","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-05-10T04:03:21.404936Z","caller":"traceutil/trace.go:171","msg":"trace[641837412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3119; }","duration":"241.587623ms","start":"2026-05-10T04:03:21.163336Z","end":"2026-05-10T04:03:21.404924Z","steps":["trace[641837412] 'agreement among raft nodes before linearized reading' (duration: 241.155206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:21.749396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.951181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:03:21.749675Z","caller":"traceutil/trace.go:171","msg":"trace[1149116408] linearizableReadLoop","detail":"{readStateIndex:3239; appliedIndex:3236; }","duration":"339.352136ms","start":"2026-05-10T04:03:21.410301Z","end":"2026-05-10T04:03:21.749654Z","steps":["trace[1149116408] 'read index received' (duration: 105.000139ms)","trace[1149116408] 'applied index is now lower than readState.Index' (duration: 234.350577ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:21.749785Z","caller":"traceutil/trace.go:171","msg":"trace[2108670487] transaction","detail":"{read_only:false; response_revision:3122; number_of_response:1; }","duration":"339.989701ms","start":"2026-05-10T04:03:21.409785Z","end":"2026-05-10T04:03:21.749775Z","steps":["trace[2108670487] 'process raft request' (duration: 339.820834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:21.749859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:21.409771Z","time spent":"340.050094ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:03:21.749953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.718641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:21.750038Z","caller":"traceutil/trace.go:171","msg":"trace[2088189719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3122; }","duration":"218.838435ms","start":"2026-05-10T04:03:21.531157Z","end":"2026-05-10T04:03:21.749996Z","steps":["trace[2088189719] 'agreement among raft nodes before linearized reading' (duration: 218.661498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:21.750231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.928799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-05-10T04:03:21.750337Z","caller":"traceutil/trace.go:171","msg":"trace[735121051] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3122; }","duration":"340.083945ms","start":"2026-05-10T04:03:21.410239Z","end":"2026-05-10T04:03:21.750323Z","steps":["trace[735121051] 'agreement among raft nodes before linearized reading' (duration: 339.573415ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:21.750368Z","caller":"traceutil/trace.go:171","msg":"trace[58391121] transaction","detail":"{read_only:false; response_revision:3120; number_of_response:1; }","duration":"342.044922ms","start":"2026-05-10T04:03:21.408308Z","end":"2026-05-10T04:03:21.750352Z","steps":["trace[58391121] 'process raft request' (duration: 107.035879ms)","trace[58391121] 'compare' (duration: 233.810056ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:21.750449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:21.40829Z","time spent":"342.120565ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:03:21.750374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:21.410216Z","time spent":"340.148138ms","remote":"127.0.0.1:44900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1340,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-05-10T04:03:21.750768Z","caller":"traceutil/trace.go:171","msg":"trace[227687049] transaction","detail":"{read_only:false; response_revision:3121; number_of_response:1; }","duration":"341.71712ms","start":"2026-05-10T04:03:21.408993Z","end":"2026-05-10T04:03:21.75071Z","steps":["trace[227687049] 'process raft request' (duration: 340.552154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:21.751015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:21.408977Z","time spent":"341.946658ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:03:23.379243Z","caller":"traceutil/trace.go:171","msg":"trace[436483378] linearizableReadLoop","detail":"{readStateIndex:3242; appliedIndex:3241; }","duration":"167.783403ms","start":"2026-05-10T04:03:23.211414Z","end":"2026-05-10T04:03:23.379197Z","steps":["trace[436483378] 'read index received' (duration: 140.655683ms)","trace[436483378] 'applied index is now lower than readState.Index' (duration: 27.12619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:23.379603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.175867ms","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-05-10T04:03:23.379629Z","caller":"traceutil/trace.go:171","msg":"trace[429834341] transaction","detail":"{read_only:false; response_revision:3125; number_of_response:1; }","duration":"225.974884ms","start":"2026-05-10T04:03:23.153629Z","end":"2026-05-10T04:03:23.379604Z","steps":["trace[429834341] 'process raft request' (duration: 198.521482ms)","trace[429834341] 'compare' (duration: 26.724984ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:23.379671Z","caller":"traceutil/trace.go:171","msg":"trace[77587561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3125; }","duration":"168.305042ms","start":"2026-05-10T04:03:23.211352Z","end":"2026-05-10T04:03:23.379657Z","steps":["trace[77587561] 'agreement among raft nodes before linearized reading' (duration: 168.100855ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:23.555158Z","caller":"traceutil/trace.go:171","msg":"trace[115432672] linearizableReadLoop","detail":"{readStateIndex:3245; appliedIndex:3243; }","duration":"133.731393ms","start":"2026-05-10T04:03:23.421397Z","end":"2026-05-10T04:03:23.555129Z","steps":["trace[115432672] 'read index received' (duration: 5.386391ms)","trace[115432672] 'applied index is now lower than readState.Index' (duration: 128.343902ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:23.55518Z","caller":"traceutil/trace.go:171","msg":"trace[1994948593] transaction","detail":"{read_only:false; response_revision:3128; number_of_response:1; }","duration":"136.038453ms","start":"2026-05-10T04:03:23.419112Z","end":"2026-05-10T04:03:23.555151Z","steps":["trace[1994948593] 'process raft request' (duration: 109.941924ms)","trace[1994948593] 'compare' (duration: 25.928152ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:23.555379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.962181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-05-10T04:03:23.555443Z","caller":"traceutil/trace.go:171","msg":"trace[1306736888] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3128; }","duration":"134.069856ms","start":"2026-05-10T04:03:23.421361Z","end":"2026-05-10T04:03:23.555431Z","steps":["trace[1306736888] 'agreement among raft nodes before linearized reading' (duration: 133.872668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:23.848635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.527021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:23.848715Z","caller":"traceutil/trace.go:171","msg":"trace[1824725156] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:3128; }","duration":"179.657496ms","start":"2026-05-10T04:03:23.669039Z","end":"2026-05-10T04:03:23.848696Z","steps":["trace[1824725156] 'count revisions from in-memory index tree' (duration: 179.452108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:23.848695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.22305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ae186136cdff0c\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-10T04:03:23.849478Z","caller":"traceutil/trace.go:171","msg":"trace[1136636705] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ae186136cdff0c; range_end:; response_count:1; response_revision:3128; }","duration":"179.032522ms","start":"2026-05-10T04:03:23.670421Z","end":"2026-05-10T04:03:23.849453Z","steps":["trace[1136636705] 'range keys from in-memory index tree' (duration: 178.050973ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:24.142941Z","caller":"traceutil/trace.go:171","msg":"trace[1067644877] transaction","detail":"{read_only:false; response_revision:3130; number_of_response:1; }","duration":"290.052242ms","start":"2026-05-10T04:03:23.85285Z","end":"2026-05-10T04:03:24.142902Z","steps":["trace[1067644877] 'process raft request' (duration: 232.715723ms)","trace[1067644877] 'compare' (duration: 57.058818ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:24.143025Z","caller":"traceutil/trace.go:171","msg":"trace[603141291] linearizableReadLoop","detail":"{readStateIndex:3249; appliedIndex:3245; }","duration":"283.33398ms","start":"2026-05-10T04:03:23.859664Z","end":"2026-05-10T04:03:24.142998Z","steps":["trace[603141291] 'read index received' (duration: 20.968219ms)","trace[603141291] 'applied index is now lower than readState.Index' (duration: 262.364391ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:24.143204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.506096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:24.143268Z","caller":"traceutil/trace.go:171","msg":"trace[1617660302] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:3132; }","duration":"283.624482ms","start":"2026-05-10T04:03:23.859628Z","end":"2026-05-10T04:03:24.143253Z","steps":["trace[1617660302] 'agreement among raft nodes before linearized reading' (duration: 283.469176ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:24.143388Z","caller":"traceutil/trace.go:171","msg":"trace[1950774130] transaction","detail":"{read_only:false; response_revision:3132; number_of_response:1; }","duration":"289.313074ms","start":"2026-05-10T04:03:23.854064Z","end":"2026-05-10T04:03:24.143377Z","steps":["trace[1950774130] 'process raft request' (duration: 288.872807ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:24.143243Z","caller":"traceutil/trace.go:171","msg":"trace[1553057032] transaction","detail":"{read_only:false; response_revision:3131; number_of_response:1; }","duration":"290.230489ms","start":"2026-05-10T04:03:23.852949Z","end":"2026-05-10T04:03:24.143179Z","steps":["trace[1553057032] 'process raft request' (duration: 289.876985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:51.880623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513383024262455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:03:52.381127Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513383024262455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:03:52.881436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513383024262455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:03:53.382542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513383024262455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:03:53.531955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000484873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-10T04:03:53.532041Z","caller":"traceutil/trace.go:171","msg":"trace[1939847286] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000622277s","start":"2026-05-10T04:03:51.531399Z","end":"2026-05-10T04:03:53.532021Z","steps":["trace[1939847286] 'agreement among raft nodes before linearized reading' (duration: 2.000506423s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:53.532115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:51.531354Z","time spent":"2.000750952s","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T04:03:53.882919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513383024262455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:03:54.383078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513383024262455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:03:54.492319Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.541942002s","expected-duration":"1s"} {"level":"info","ts":"2026-05-10T04:03:54.492687Z","caller":"traceutil/trace.go:171","msg":"trace[202714820] transaction","detail":"{read_only:false; response_revision:3268; number_of_response:1; }","duration":"3.542433699s","start":"2026-05-10T04:03:50.950227Z","end":"2026-05-10T04:03:54.492661Z","steps":["trace[202714820] 'process raft request' (duration: 3.542296355s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.492826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:50.950192Z","time spent":"3.542549714s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:03:54.8941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.058536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:03:54.894304Z","caller":"traceutil/trace.go:171","msg":"trace[1676675092] linearizableReadLoop","detail":"{readStateIndex:3393; appliedIndex:3390; }","duration":"3.514841395s","start":"2026-05-10T04:03:51.379446Z","end":"2026-05-10T04:03:54.894288Z","steps":["trace[1676675092] 'read index received' (duration: 3.113170267s)","trace[1676675092] 'applied index is now lower than readState.Index' (duration: 401.670218ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:54.894403Z","caller":"traceutil/trace.go:171","msg":"trace[1201615278] transaction","detail":"{read_only:false; response_revision:3269; number_of_response:1; }","duration":"3.649659735s","start":"2026-05-10T04:03:51.244732Z","end":"2026-05-10T04:03:54.894392Z","steps":["trace[1201615278] 'process raft request' (duration: 3.505195005s)","trace[1201615278] 'compare' (duration: 143.786107ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:54.894467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:51.244686Z","time spent":"3.649749059s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:03:54.894763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.341015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T04:03:54.894804Z","caller":"traceutil/trace.go:171","msg":"trace[1428125917] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3270; }","duration":"292.438139ms","start":"2026-05-10T04:03:54.602355Z","end":"2026-05-10T04:03:54.894793Z","steps":["trace[1428125917] 'agreement among raft nodes before linearized reading' (duration: 292.368216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.894789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.363371512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-10T04:03:54.894809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427751998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:54.894884Z","caller":"traceutil/trace.go:171","msg":"trace[1004702231] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:3270; }","duration":"1.427887363s","start":"2026-05-10T04:03:53.466978Z","end":"2026-05-10T04:03:54.894865Z","steps":["trace[1004702231] 'agreement among raft nodes before linearized reading' (duration: 1.427754759s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.894941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:53.466953Z","time spent":"1.427976976s","remote":"127.0.0.1:45004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-05-10T04:03:54.894946Z","caller":"traceutil/trace.go:171","msg":"trace[738476449] transaction","detail":"{read_only:false; response_revision:3270; number_of_response:1; }","duration":"3.382759732s","start":"2026-05-10T04:03:51.512174Z","end":"2026-05-10T04:03:54.894934Z","steps":["trace[738476449] 'process raft request' (duration: 3.382025406s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.894901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59533289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:03:54.89495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.515492058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-10T04:03:54.895004Z","caller":"traceutil/trace.go:171","msg":"trace[947876585] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3270; }","duration":"1.595484794s","start":"2026-05-10T04:03:53.299498Z","end":"2026-05-10T04:03:54.894983Z","steps":["trace[947876585] 'agreement among raft nodes before linearized reading' (duration: 1.59535478s)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:54.895046Z","caller":"traceutil/trace.go:171","msg":"trace[1343639700] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3270; }","duration":"3.515634333s","start":"2026-05-10T04:03:51.379387Z","end":"2026-05-10T04:03:54.895022Z","steps":["trace[1343639700] 'agreement among raft nodes before linearized reading' (duration: 3.515360233s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.895069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:53.299473Z","time spent":"1.595585838s","remote":"127.0.0.1:49910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:03:54.895046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:51.512142Z","time spent":"3.382841134s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:03:54.895123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:51.379373Z","time spent":"3.515736327s","remote":"127.0.0.1:44900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-10T04:03:54.895167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.362174156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:54.895208Z","caller":"traceutil/trace.go:171","msg":"trace[2062166195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3270; }","duration":"1.3622685s","start":"2026-05-10T04:03:53.53293Z","end":"2026-05-10T04:03:54.895198Z","steps":["trace[2062166195] 'agreement among raft nodes before linearized reading' (duration: 1.362211448s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.895242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:53.532897Z","time spent":"1.362338952s","remote":"127.0.0.1:44684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T04:03:54.895254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.861146897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:54.8953Z","caller":"traceutil/trace.go:171","msg":"trace[484580712] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:3270; }","duration":"2.861260401s","start":"2026-05-10T04:03:52.034028Z","end":"2026-05-10T04:03:54.895289Z","steps":["trace[484580712] 'agreement among raft nodes before linearized reading' (duration: 2.861190228s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.895336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:52.033988Z","time spent":"2.861340103s","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-10T04:03:54.894875Z","caller":"traceutil/trace.go:171","msg":"trace[650483176] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:3270; }","duration":"2.363521018s","start":"2026-05-10T04:03:52.531333Z","end":"2026-05-10T04:03:54.894854Z","steps":["trace[650483176] 'agreement among raft nodes before linearized reading' (duration: 2.363369253s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.89542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:52.531309Z","time spent":"2.364090607s","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":6,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:03:54.8955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.887289335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-10T04:03:54.895493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.178504047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T04:03:54.895545Z","caller":"traceutil/trace.go:171","msg":"trace[1220741861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3270; }","duration":"3.17858871s","start":"2026-05-10T04:03:51.716949Z","end":"2026-05-10T04:03:54.895537Z","steps":["trace[1220741861] 'agreement among raft nodes before linearized reading' (duration: 3.178441885s)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:54.895546Z","caller":"traceutil/trace.go:171","msg":"trace[65534627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3270; }","duration":"2.887390838s","start":"2026-05-10T04:03:52.008142Z","end":"2026-05-10T04:03:54.895533Z","steps":["trace[65534627] 'agreement among raft nodes before linearized reading' (duration: 2.887254743s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.895584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:52.008108Z","time spent":"2.88746905s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T04:03:54.895574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:51.716934Z","time spent":"3.178634822s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T04:03:54.895637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.604862275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-10T04:03:54.895673Z","caller":"traceutil/trace.go:171","msg":"trace[1087844263] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3270; }","duration":"2.604937287s","start":"2026-05-10T04:03:52.290727Z","end":"2026-05-10T04:03:54.895665Z","steps":["trace[1087844263] 'agreement among raft nodes before linearized reading' (duration: 2.604843694s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.895551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.547317509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-10T04:03:54.895693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.796411794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:54.895737Z","caller":"traceutil/trace.go:171","msg":"trace[947291041] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3270; }","duration":"2.547560428s","start":"2026-05-10T04:03:52.348166Z","end":"2026-05-10T04:03:54.895726Z","steps":["trace[947291041] 'agreement among raft nodes before linearized reading' (duration: 2.547291968s)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:54.895754Z","caller":"traceutil/trace.go:171","msg":"trace[1126295949] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:3270; }","duration":"2.796522417s","start":"2026-05-10T04:03:52.099223Z","end":"2026-05-10T04:03:54.895746Z","steps":["trace[1126295949] 'agreement among raft nodes before linearized reading' (duration: 2.796446465s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.895779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:52.099199Z","time spent":"2.796573349s","remote":"127.0.0.1:49872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:03:54.89578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:52.348131Z","time spent":"2.54763877s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-10T04:03:54.895911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.315324036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-10T04:03:54.89594Z","caller":"traceutil/trace.go:171","msg":"trace[884838201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3270; }","duration":"3.315388429s","start":"2026-05-10T04:03:51.580544Z","end":"2026-05-10T04:03:54.895932Z","steps":["trace[884838201] 'agreement among raft nodes before linearized reading' (duration: 3.315306406s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.895706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:52.290707Z","time spent":"2.60499338s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T04:03:54.895967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:51.580525Z","time spent":"3.31543653s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T04:03:54.902508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.329516032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:03:54.902608Z","caller":"traceutil/trace.go:171","msg":"trace[457807824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3270; }","duration":"3.329687908s","start":"2026-05-10T04:03:51.572902Z","end":"2026-05-10T04:03:54.90259Z","steps":["trace[457807824] 'agreement among raft nodes before linearized reading' (duration: 3.321751198s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:54.903342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:51.572879Z","time spent":"3.330438394s","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-10T04:03:55.079696Z","caller":"traceutil/trace.go:171","msg":"trace[615619336] transaction","detail":"{read_only:false; response_revision:3272; number_of_response:1; }","duration":"177.08096ms","start":"2026-05-10T04:03:54.902596Z","end":"2026-05-10T04:03:55.079677Z","steps":["trace[615619336] 'process raft request' (duration: 176.889214ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:55.079999Z","caller":"traceutil/trace.go:171","msg":"trace[42441458] transaction","detail":"{read_only:false; response_revision:3273; number_of_response:1; }","duration":"175.823657ms","start":"2026-05-10T04:03:54.904141Z","end":"2026-05-10T04:03:55.079965Z","steps":["trace[42441458] 'process raft request' (duration: 175.493356ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:55.080498Z","caller":"traceutil/trace.go:171","msg":"trace[323781269] transaction","detail":"{read_only:false; response_revision:3274; number_of_response:1; }","duration":"175.770415ms","start":"2026-05-10T04:03:54.904708Z","end":"2026-05-10T04:03:55.080478Z","steps":["trace[323781269] 'process raft request' (duration: 175.161925ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:55.080854Z","caller":"traceutil/trace.go:171","msg":"trace[2093177348] transaction","detail":"{read_only:false; response_revision:3276; number_of_response:1; }","duration":"175.503956ms","start":"2026-05-10T04:03:54.905336Z","end":"2026-05-10T04:03:55.08084Z","steps":["trace[2093177348] 'process raft request' (duration: 175.108812ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:55.081023Z","caller":"traceutil/trace.go:171","msg":"trace[1300233249] transaction","detail":"{read_only:false; response_revision:3275; number_of_response:1; }","duration":"176.073595ms","start":"2026-05-10T04:03:54.904939Z","end":"2026-05-10T04:03:55.081012Z","steps":["trace[1300233249] 'process raft request' (duration: 175.420723ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:55.080862Z","caller":"traceutil/trace.go:171","msg":"trace[2017117861] transaction","detail":"{read_only:false; response_revision:3277; number_of_response:1; }","duration":"174.601595ms","start":"2026-05-10T04:03:54.906246Z","end":"2026-05-10T04:03:55.080848Z","steps":["trace[2017117861] 'process raft request' (duration: 174.230242ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:55.080552Z","caller":"traceutil/trace.go:171","msg":"trace[178059059] linearizableReadLoop","detail":"{readStateIndex:3396; appliedIndex:3393; }","duration":"175.945881ms","start":"2026-05-10T04:03:54.904583Z","end":"2026-05-10T04:03:55.080529Z","steps":["trace[178059059] 'read index received' (duration: 22.393132ms)","trace[178059059] 'applied index is now lower than readState.Index' (duration: 153.551859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:55.081302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.698556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-05-10T04:03:55.081744Z","caller":"traceutil/trace.go:171","msg":"trace[894102426] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3277; }","duration":"177.171713ms","start":"2026-05-10T04:03:54.904555Z","end":"2026-05-10T04:03:55.081727Z","steps":["trace[894102426] 'agreement among raft nodes before linearized reading' (duration: 176.594103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:55.082097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.084376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:55.082148Z","caller":"traceutil/trace.go:171","msg":"trace[204585914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3277; }","duration":"176.153867ms","start":"2026-05-10T04:03:54.905982Z","end":"2026-05-10T04:03:55.082136Z","steps":["trace[204585914] 'agreement among raft nodes before linearized reading' (duration: 176.055914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:55.082409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.833134ms","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-05-10T04:03:55.082444Z","caller":"traceutil/trace.go:171","msg":"trace[1182708223] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3277; }","duration":"140.901117ms","start":"2026-05-10T04:03:54.941535Z","end":"2026-05-10T04:03:55.082436Z","steps":["trace[1182708223] 'agreement among raft nodes before linearized reading' (duration: 140.768732ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:59.077898Z","caller":"traceutil/trace.go:171","msg":"trace[2141544643] linearizableReadLoop","detail":"{readStateIndex:3415; appliedIndex:3414; }","duration":"125.714111ms","start":"2026-05-10T04:03:58.952156Z","end":"2026-05-10T04:03:59.07787Z","steps":["trace[2141544643] 'read index received' (duration: 125.561016ms)","trace[2141544643] 'applied index is now lower than readState.Index' (duration: 152.005µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:59.077978Z","caller":"traceutil/trace.go:171","msg":"trace[610660888] transaction","detail":"{read_only:false; response_revision:3291; number_of_response:1; }","duration":"148.239895ms","start":"2026-05-10T04:03:58.929695Z","end":"2026-05-10T04:03:59.077935Z","steps":["trace[610660888] 'process raft request' (duration: 148.008967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:59.078107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.931978ms","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-05-10T04:03:59.078151Z","caller":"traceutil/trace.go:171","msg":"trace[739881008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3291; }","duration":"126.044442ms","start":"2026-05-10T04:03:58.952094Z","end":"2026-05-10T04:03:59.078139Z","steps":["trace[739881008] 'agreement among raft nodes before linearized reading' (duration: 125.881786ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:59.243044Z","caller":"traceutil/trace.go:171","msg":"trace[1483542784] transaction","detail":"{read_only:false; response_revision:3292; number_of_response:1; }","duration":"159.384566ms","start":"2026-05-10T04:03:59.083621Z","end":"2026-05-10T04:03:59.243006Z","steps":["trace[1483542784] 'process raft request' (duration: 114.378075ms)","trace[1483542784] 'compare' (duration: 44.809575ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:59.243049Z","caller":"traceutil/trace.go:171","msg":"trace[889471633] linearizableReadLoop","detail":"{readStateIndex:3416; appliedIndex:3415; }","duration":"140.775336ms","start":"2026-05-10T04:03:59.102244Z","end":"2026-05-10T04:03:59.243019Z","steps":["trace[889471633] 'read index received' (duration: 95.864938ms)","trace[889471633] 'applied index is now lower than readState.Index' (duration: 44.909118ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:59.243455Z","caller":"traceutil/trace.go:171","msg":"trace[354200582] transaction","detail":"{read_only:false; response_revision:3293; number_of_response:1; }","duration":"137.705463ms","start":"2026-05-10T04:03:59.105686Z","end":"2026-05-10T04:03:59.243392Z","steps":["trace[354200582] 'process raft request' (duration: 137.285089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:59.243537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.274233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T04:03:59.243584Z","caller":"traceutil/trace.go:171","msg":"trace[595493144] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:3293; }","duration":"141.401026ms","start":"2026-05-10T04:03:59.102171Z","end":"2026-05-10T04:03:59.243572Z","steps":["trace[595493144] 'agreement among raft nodes before linearized reading' (duration: 140.932211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:59.243956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.083473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-10T04:03:59.244425Z","caller":"traceutil/trace.go:171","msg":"trace[470428205] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3293; }","duration":"125.568099ms","start":"2026-05-10T04:03:59.118838Z","end":"2026-05-10T04:03:59.244406Z","steps":["trace[470428205] 'agreement among raft nodes before linearized reading' (duration: 124.99401ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:12.795089Z","caller":"traceutil/trace.go:171","msg":"trace[1346194181] transaction","detail":"{read_only:false; response_revision:3450; number_of_response:1; }","duration":"117.511951ms","start":"2026-05-10T04:04:12.677549Z","end":"2026-05-10T04:04:12.795061Z","steps":["trace[1346194181] 'process raft request' (duration: 117.381727ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:09.990714Z","caller":"traceutil/trace.go:171","msg":"trace[2057923673] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4920; }","duration":"129.513994ms","start":"2026-05-10T04:06:09.861178Z","end":"2026-05-10T04:06:09.990692Z","steps":["trace[2057923673] 'read index received' (duration: 129.33892ms)","trace[2057923673] 'applied index is now lower than readState.Index' (duration: 174.134µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:09.990769Z","caller":"traceutil/trace.go:171","msg":"trace[1882619577] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"131.396496ms","start":"2026-05-10T04:06:09.859346Z","end":"2026-05-10T04:06:09.990742Z","steps":["trace[1882619577] 'process raft request' (duration: 131.217262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:09.990964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.771781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:06:09.991026Z","caller":"traceutil/trace.go:171","msg":"trace[311738905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4743; }","duration":"129.869832ms","start":"2026-05-10T04:06:09.861141Z","end":"2026-05-10T04:06:09.991011Z","steps":["trace[311738905] 'agreement among raft nodes before linearized reading' (duration: 129.660968ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:10.364112Z","caller":"traceutil/trace.go:171","msg":"trace[168774316] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"103.674776ms","start":"2026-05-10T04:06:10.260414Z","end":"2026-05-10T04:06:10.364089Z","steps":["trace[168774316] 'process raft request' (duration: 103.548563ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:10.424789Z","caller":"traceutil/trace.go:171","msg":"trace[864218953] linearizableReadLoop","detail":"{readStateIndex:4928; appliedIndex:4926; }","duration":"128.970306ms","start":"2026-05-10T04:06:10.2958Z","end":"2026-05-10T04:06:10.424771Z","steps":["trace[864218953] 'read index received' (duration: 68.169809ms)","trace[864218953] 'applied index is now lower than readState.Index' (duration: 60.799597ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:10.424893Z","caller":"traceutil/trace.go:171","msg":"trace[1841521808] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"162.693483ms","start":"2026-05-10T04:06:10.262187Z","end":"2026-05-10T04:06:10.424881Z","steps":["trace[1841521808] 'process raft request' (duration: 156.335312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:10.425464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.638281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-10T04:06:10.425561Z","caller":"traceutil/trace.go:171","msg":"trace[1575159274] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:4750; }","duration":"129.777854ms","start":"2026-05-10T04:06:10.295763Z","end":"2026-05-10T04:06:10.425541Z","steps":["trace[1575159274] 'agreement among raft nodes before linearized reading' (duration: 129.286183ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:21.751133Z","caller":"traceutil/trace.go:171","msg":"trace[428518807] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"144.527456ms","start":"2026-05-10T04:06:21.606582Z","end":"2026-05-10T04:06:21.75111Z","steps":["trace[428518807] 'process raft request' (duration: 144.409523ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:22.255342Z","caller":"traceutil/trace.go:171","msg":"trace[1214612798] linearizableReadLoop","detail":"{readStateIndex:5092; appliedIndex:5091; }","duration":"157.283495ms","start":"2026-05-10T04:06:22.098036Z","end":"2026-05-10T04:06:22.255319Z","steps":["trace[1214612798] 'read index received' (duration: 157.197863ms)","trace[1214612798] 'applied index is now lower than readState.Index' (duration: 84.772µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:06:22.255586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.696452ms","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-05-10T04:06:22.255643Z","caller":"traceutil/trace.go:171","msg":"trace[591578718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4907; }","duration":"137.775014ms","start":"2026-05-10T04:06:22.117856Z","end":"2026-05-10T04:06:22.255631Z","steps":["trace[591578718] 'agreement among raft nodes before linearized reading' (duration: 137.60781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:22.255663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.942911ms","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-05-10T04:06:22.255753Z","caller":"traceutil/trace.go:171","msg":"trace[138657693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4907; }","duration":"146.057283ms","start":"2026-05-10T04:06:22.109677Z","end":"2026-05-10T04:06:22.255734Z","steps":["trace[138657693] 'agreement among raft nodes before linearized reading' (duration: 145.806267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:22.255755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.708914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-10T04:06:22.255802Z","caller":"traceutil/trace.go:171","msg":"trace[396219525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4907; }","duration":"157.788576ms","start":"2026-05-10T04:06:22.098002Z","end":"2026-05-10T04:06:22.255791Z","steps":["trace[396219525] 'agreement among raft nodes before linearized reading' (duration: 157.443269ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:22.255917Z","caller":"traceutil/trace.go:171","msg":"trace[1161285281] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"236.28078ms","start":"2026-05-10T04:06:22.019152Z","end":"2026-05-10T04:06:22.255432Z","steps":["trace[1161285281] 'process raft request' (duration: 236.028145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:22.256011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.665215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-10T04:06:22.256259Z","caller":"traceutil/trace.go:171","msg":"trace[303769825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4907; }","duration":"145.91595ms","start":"2026-05-10T04:06:22.110333Z","end":"2026-05-10T04:06:22.256249Z","steps":["trace[303769825] 'agreement among raft nodes before linearized reading' (duration: 145.371298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:22.256065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.322026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T04:06:22.25642Z","caller":"traceutil/trace.go:171","msg":"trace[1419150169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4907; }","duration":"138.667893ms","start":"2026-05-10T04:06:22.117726Z","end":"2026-05-10T04:06:22.256394Z","steps":["trace[1419150169] 'agreement among raft nodes before linearized reading' (duration: 138.029459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:22.310203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.928179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-10T04:06:22.3103Z","caller":"traceutil/trace.go:171","msg":"trace[1473704394] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4907; }","duration":"196.067092ms","start":"2026-05-10T04:06:22.114211Z","end":"2026-05-10T04:06:22.310279Z","steps":["trace[1473704394] 'agreement among raft nodes before linearized reading' (duration: 141.505294ms)","trace[1473704394] 'range keys from in-memory index tree' (duration: 53.487824ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:22.537361Z","caller":"traceutil/trace.go:171","msg":"trace[1393603082] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"128.508334ms","start":"2026-05-10T04:06:22.408828Z","end":"2026-05-10T04:06:22.537336Z","steps":["trace[1393603082] 'process raft request' (duration: 93.478748ms)","trace[1393603082] 'compare' (duration: 34.824112ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:22.550669Z","caller":"traceutil/trace.go:171","msg":"trace[1256047347] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"135.794841ms","start":"2026-05-10T04:06:22.414851Z","end":"2026-05-10T04:06:22.550646Z","steps":["trace[1256047347] 'process raft request' (duration: 135.638808ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:22.550861Z","caller":"traceutil/trace.go:171","msg":"trace[1515935804] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"134.922193ms","start":"2026-05-10T04:06:22.415924Z","end":"2026-05-10T04:06:22.550846Z","steps":["trace[1515935804] 'process raft request' (duration: 134.661257ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:22.550968Z","caller":"traceutil/trace.go:171","msg":"trace[1651769016] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"134.954323ms","start":"2026-05-10T04:06:22.415981Z","end":"2026-05-10T04:06:22.550935Z","steps":["trace[1651769016] 'process raft request' (duration: 134.806599ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:22.551072Z","caller":"traceutil/trace.go:171","msg":"trace[1034702444] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5099; }","duration":"106.641452ms","start":"2026-05-10T04:06:22.444417Z","end":"2026-05-10T04:06:22.551059Z","steps":["trace[1034702444] 'read index received' (duration: 58.035903ms)","trace[1034702444] 'applied index is now lower than readState.Index' (duration: 48.604879ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:22.551104Z","caller":"traceutil/trace.go:171","msg":"trace[528215145] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"134.412581ms","start":"2026-05-10T04:06:22.416671Z","end":"2026-05-10T04:06:22.551084Z","steps":["trace[528215145] 'process raft request' (duration: 134.206796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:22.551218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.794755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-10T04:06:22.551428Z","caller":"traceutil/trace.go:171","msg":"trace[775185613] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4919; }","duration":"107.042931ms","start":"2026-05-10T04:06:22.444376Z","end":"2026-05-10T04:06:22.551418Z","steps":["trace[775185613] 'agreement among raft nodes before linearized reading' (duration: 106.713044ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:07:47.403086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2946} {"level":"info","ts":"2026-05-10T04:07:47.493428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2946,"took":"88.883642ms","hash":603422789,"current-db-size-bytes":53530624,"current-db-size":"54 MB","current-db-size-in-use-bytes":29364224,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-10T04:07:47.49354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":603422789,"revision":2946,"compact-revision":-1} {"level":"info","ts":"2026-05-10T04:08:34.089204Z","caller":"traceutil/trace.go:171","msg":"trace[686612797] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"238.662008ms","start":"2026-05-10T04:08:33.850512Z","end":"2026-05-10T04:08:34.089174Z","steps":["trace[686612797] 'process raft request' (duration: 238.508715ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:08:34.089235Z","caller":"traceutil/trace.go:171","msg":"trace[1119108015] linearizableReadLoop","detail":"{readStateIndex:6090; appliedIndex:6090; }","duration":"130.594281ms","start":"2026-05-10T04:08:33.958616Z","end":"2026-05-10T04:08:34.08921Z","steps":["trace[1119108015] 'read index received' (duration: 130.58103ms)","trace[1119108015] 'applied index is now lower than readState.Index' (duration: 11.111µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:08:34.089653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.937297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-10T04:08:34.089843Z","caller":"traceutil/trace.go:171","msg":"trace[59306555] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5876; }","duration":"131.256453ms","start":"2026-05-10T04:08:33.95857Z","end":"2026-05-10T04:08:34.089827Z","steps":["trace[59306555] 'agreement among raft nodes before linearized reading' (duration: 130.714903ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:08:34.090455Z","caller":"traceutil/trace.go:171","msg":"trace[691770124] transaction","detail":"{read_only:false; response_revision:5877; number_of_response:1; }","duration":"116.07361ms","start":"2026-05-10T04:08:33.974348Z","end":"2026-05-10T04:08:34.090422Z","steps":["trace[691770124] 'process raft request' (duration: 115.893717ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:08:34.530186Z","caller":"traceutil/trace.go:171","msg":"trace[579045268] transaction","detail":"{read_only:false; response_revision:5878; number_of_response:1; }","duration":"342.229392ms","start":"2026-05-10T04:08:34.187929Z","end":"2026-05-10T04:08:34.530158Z","steps":["trace[579045268] 'process raft request' (duration: 336.187413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:08:34.53031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:08:34.187895Z","time spent":"342.347703ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:08:34.531388Z","caller":"traceutil/trace.go:171","msg":"trace[190692207] transaction","detail":"{read_only:false; response_revision:5879; number_of_response:1; }","duration":"250.989418ms","start":"2026-05-10T04:08:34.280351Z","end":"2026-05-10T04:08:34.53134Z","steps":["trace[190692207] 'process raft request' (duration: 250.785744ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:12:47.409387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5642} {"level":"info","ts":"2026-05-10T04:12:47.485922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5642,"took":"74.528834ms","hash":1500007731,"current-db-size-bytes":53530624,"current-db-size":"54 MB","current-db-size-in-use-bytes":18247680,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-10T04:12:47.485995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1500007731,"revision":5642,"compact-revision":2946}