{"level":"warn","ts":"2026-05-05T16:51:02.34681Z","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-05T16:51:02.346889Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.243:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.243:2380","--initial-cluster=instance=https://199.19.213.243:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.243:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.243: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-05T16:51:02.346952Z","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-05T16:51:02.346965Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-05-05T16:51:02.346987Z","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-05T16:51:02.347653Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"]} {"level":"info","ts":"2026-05-05T16:51:02.347882Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.243: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-05T16:51:02.351292Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.899935ms"} {"level":"info","ts":"2026-05-05T16:51:02.357455Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"864e14457b828f9c","cluster-id":"6c0ae60a355554b9"} {"level":"info","ts":"2026-05-05T16:51:02.357512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T16:51:02.35753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 0"} {"level":"info","ts":"2026-05-05T16:51:02.357539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 864e14457b828f9c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T16:51:02.357546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 1"} {"level":"info","ts":"2026-05-05T16:51:02.357576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"warn","ts":"2026-05-05T16:51:02.364182Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T16:51:02.365139Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T16:51:02.366095Z","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-05T16:51:02.367906Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"864e14457b828f9c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T16:51:02.368102Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"864e14457b828f9c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T16:51:02.368203Z","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-05T16:51:02.368239Z","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-05T16:51:02.368252Z","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-05T16:51:02.369244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"info","ts":"2026-05-05T16:51:02.369495Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","added-peer-id":"864e14457b828f9c","added-peer-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-05-05T16:51:02.373115Z","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-05T16:51:02.373326Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-05-05T16:51:02.373378Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-05-05T16:51:02.373416Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"864e14457b828f9c","initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T16:51:02.37347Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T16:51:02.65787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T16:51:02.657971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T16:51:02.658021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgPreVoteResp from 864e14457b828f9c at term 1"} {"level":"info","ts":"2026-05-05T16:51:02.658044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became candidate at term 2"} {"level":"info","ts":"2026-05-05T16:51:02.658064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgVoteResp from 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-05-05T16:51:02.658082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became leader at term 2"} {"level":"info","ts":"2026-05-05T16:51:02.658098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 864e14457b828f9c elected leader 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-05-05T16:51:02.661452Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"864e14457b828f9c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.243:2379]}","request-path":"/0/members/864e14457b828f9c/attributes","cluster-id":"6c0ae60a355554b9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T16:51:02.661788Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:51:02.661937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:51:02.662196Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T16:51:02.662231Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T16:51:02.661848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:51:02.662784Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:51:02.662911Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:51:02.66295Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:51:02.665408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T16:51:02.669538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.243:2379"} {"level":"info","ts":"2026-05-05T16:51:30.968964Z","caller":"traceutil/trace.go:171","msg":"trace[1878293508] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"289.757405ms","start":"2026-05-05T16:51:30.679154Z","end":"2026-05-05T16:51:30.968912Z","steps":["trace[1878293508] 'process raft request' (duration: 286.174888ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.969061Z","caller":"traceutil/trace.go:171","msg":"trace[2012154647] transaction","detail":"{read_only:false; number_of_response:0; response_revision:518; }","duration":"289.852042ms","start":"2026-05-05T16:51:30.679188Z","end":"2026-05-05T16:51:30.96904Z","steps":["trace[2012154647] 'process raft request' (duration: 289.652576ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.969138Z","caller":"traceutil/trace.go:171","msg":"trace[393207061] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"289.841883ms","start":"2026-05-05T16:51:30.679253Z","end":"2026-05-05T16:51:30.969095Z","steps":["trace[393207061] 'process raft request' (duration: 289.69563ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.969164Z","caller":"traceutil/trace.go:171","msg":"trace[1768834519] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"289.916119ms","start":"2026-05-05T16:51:30.67921Z","end":"2026-05-05T16:51:30.969126Z","steps":["trace[1768834519] 'process raft request' (duration: 289.663028ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.969207Z","caller":"traceutil/trace.go:171","msg":"trace[1526809376] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"287.210631ms","start":"2026-05-05T16:51:30.681968Z","end":"2026-05-05T16:51:30.969179Z","steps":["trace[1526809376] 'process raft request' (duration: 287.011434ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.96941Z","caller":"traceutil/trace.go:171","msg":"trace[2055220174] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"287.387175ms","start":"2026-05-05T16:51:30.682006Z","end":"2026-05-05T16:51:30.969393Z","steps":["trace[2055220174] 'process raft request' (duration: 287.106903ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.971424Z","caller":"traceutil/trace.go:171","msg":"trace[1925671549] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"284.901545ms","start":"2026-05-05T16:51:30.686481Z","end":"2026-05-05T16:51:30.971382Z","steps":["trace[1925671549] 'process raft request' (duration: 282.840721ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.972634Z","caller":"traceutil/trace.go:171","msg":"trace[1292360527] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"274.173906ms","start":"2026-05-05T16:51:30.698419Z","end":"2026-05-05T16:51:30.972593Z","steps":["trace[1292360527] 'process raft request' (duration: 271.118071ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.972898Z","caller":"traceutil/trace.go:171","msg":"trace[134605555] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"275.22461ms","start":"2026-05-05T16:51:30.697644Z","end":"2026-05-05T16:51:30.972868Z","steps":["trace[134605555] 'process raft request' (duration: 271.816767ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.976206Z","caller":"traceutil/trace.go:171","msg":"trace[24300869] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"270.825798ms","start":"2026-05-05T16:51:30.704557Z","end":"2026-05-05T16:51:30.975383Z","steps":["trace[24300869] 'process raft request' (duration: 267.3611ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:30.977522Z","caller":"traceutil/trace.go:171","msg":"trace[105880140] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:535; }","duration":"295.29411ms","start":"2026-05-05T16:51:30.682177Z","end":"2026-05-05T16:51:30.977471Z","steps":["trace[105880140] 'read index received' (duration: 58.722739ms)","trace[105880140] 'applied index is now lower than readState.Index' (duration: 236.570351ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:51:30.977621Z","caller":"traceutil/trace.go:171","msg":"trace[1419710237] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"271.765183ms","start":"2026-05-05T16:51:30.705848Z","end":"2026-05-05T16:51:30.977613Z","steps":["trace[1419710237] 'process raft request' (duration: 270.624091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:51:30.97778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.581533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:51:30.977815Z","caller":"traceutil/trace.go:171","msg":"trace[603328541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"295.649528ms","start":"2026-05-05T16:51:30.682158Z","end":"2026-05-05T16:51:30.977808Z","steps":["trace[603328541] 'agreement among raft nodes before linearized reading' (duration: 295.570432ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:31.005713Z","caller":"traceutil/trace.go:171","msg":"trace[36393236] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"210.798409ms","start":"2026-05-05T16:51:30.794889Z","end":"2026-05-05T16:51:31.005688Z","steps":["trace[36393236] 'process raft request' (duration: 210.359554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:31.005751Z","caller":"traceutil/trace.go:171","msg":"trace[641053125] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"185.378771ms","start":"2026-05-05T16:51:30.820341Z","end":"2026-05-05T16:51:31.00572Z","steps":["trace[641053125] 'process raft request' (duration: 185.044044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:51:31.117022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.496989ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T16:51:31.117318Z","caller":"traceutil/trace.go:171","msg":"trace[473983602] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:547; }","duration":"139.742887ms","start":"2026-05-05T16:51:30.977562Z","end":"2026-05-05T16:51:31.117305Z","steps":["trace[473983602] 'read index received' (duration: 27.574725ms)","trace[473983602] 'applied index is now lower than readState.Index' (duration: 112.167462ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:51:31.117376Z","caller":"traceutil/trace.go:171","msg":"trace[1062089123] transaction","detail":"{read_only:false; number_of_response:0; response_revision:529; }","duration":"140.841306ms","start":"2026-05-05T16:51:30.976529Z","end":"2026-05-05T16:51:31.11737Z","steps":["trace[1062089123] 'process raft request' (duration: 140.563234ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:31.117543Z","caller":"traceutil/trace.go:171","msg":"trace[910078765] transaction","detail":"{read_only:false; number_of_response:0; response_revision:529; }","duration":"140.579756ms","start":"2026-05-05T16:51:30.976959Z","end":"2026-05-05T16:51:31.117539Z","steps":["trace[910078765] 'process raft request' (duration: 140.207206ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:31.117604Z","caller":"traceutil/trace.go:171","msg":"trace[1995988323] transaction","detail":"{read_only:false; number_of_response:0; response_revision:529; }","duration":"140.010089ms","start":"2026-05-05T16:51:30.97759Z","end":"2026-05-05T16:51:31.1176Z","steps":["trace[1995988323] 'process raft request' (duration: 139.589155ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:31.117657Z","caller":"traceutil/trace.go:171","msg":"trace[1970610118] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"134.89827ms","start":"2026-05-05T16:51:30.982754Z","end":"2026-05-05T16:51:31.117653Z","steps":["trace[1970610118] 'process raft request' (duration: 134.438363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:31.117792Z","caller":"traceutil/trace.go:171","msg":"trace[1891246806] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"129.408269ms","start":"2026-05-05T16:51:30.98835Z","end":"2026-05-05T16:51:31.117758Z","steps":["trace[1891246806] 'process raft request' (duration: 128.92342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:51:31.118061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.463415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-05-05T16:51:31.118113Z","caller":"traceutil/trace.go:171","msg":"trace[607896967] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:531; }","duration":"143.584786ms","start":"2026-05-05T16:51:30.974514Z","end":"2026-05-05T16:51:31.118099Z","steps":["trace[607896967] 'agreement among raft nodes before linearized reading' (duration: 143.426493ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:31.118977Z","caller":"traceutil/trace.go:171","msg":"trace[603798846] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"106.833214ms","start":"2026-05-05T16:51:31.012117Z","end":"2026-05-05T16:51:31.11895Z","steps":["trace[603798846] 'process raft request' (duration: 106.603686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:51:31.123264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.806232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:51:31.123361Z","caller":"traceutil/trace.go:171","msg":"trace[1935657809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"141.89568ms","start":"2026-05-05T16:51:30.981417Z","end":"2026-05-05T16:51:31.123313Z","steps":["trace[1935657809] 'agreement among raft nodes before linearized reading' (duration: 141.782621ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:31.492696Z","caller":"traceutil/trace.go:171","msg":"trace[80304032] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"101.80657ms","start":"2026-05-05T16:51:31.390857Z","end":"2026-05-05T16:51:31.492664Z","steps":["trace[80304032] 'process raft request' (duration: 91.024977ms)","trace[80304032] 'compare' (duration: 10.113311ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:51:37.453103Z","caller":"traceutil/trace.go:171","msg":"trace[2040001322] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"661.811616ms","start":"2026-05-05T16:51:36.791262Z","end":"2026-05-05T16:51:37.453074Z","steps":["trace[2040001322] 'process raft request' (duration: 659.542822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:51:37.455683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:51:36.79124Z","time spent":"662.867508ms","remote":"127.0.0.1:53822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:51:38.311078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.188358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:51:38.311267Z","caller":"traceutil/trace.go:171","msg":"trace[33157223] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"707.754787ms","start":"2026-05-05T16:51:37.603486Z","end":"2026-05-05T16:51:38.31124Z","steps":["trace[33157223] 'process raft request' (duration: 444.323804ms)","trace[33157223] 'compare' (duration: 263.023037ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:51:38.311718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:51:37.603467Z","time spent":"708.179975ms","remote":"127.0.0.1:53998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:51:38.311318Z","caller":"traceutil/trace.go:171","msg":"trace[1248384241] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"283.683721ms","start":"2026-05-05T16:51:38.027616Z","end":"2026-05-05T16:51:38.311299Z","steps":["trace[1248384241] 'process raft request' (duration: 283.638078ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:38.31132Z","caller":"traceutil/trace.go:171","msg":"trace[101436687] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"701.878798ms","start":"2026-05-05T16:51:37.609398Z","end":"2026-05-05T16:51:38.311276Z","steps":["trace[101436687] 'read index received' (duration: 438.425023ms)","trace[101436687] 'applied index is now lower than readState.Index' (duration: 263.450915ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:51:38.311381Z","caller":"traceutil/trace.go:171","msg":"trace[989885986] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"699.877761ms","start":"2026-05-05T16:51:37.611464Z","end":"2026-05-05T16:51:38.311342Z","steps":["trace[989885986] 'process raft request' (duration: 699.726531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:51:38.312158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:51:37.611449Z","time spent":"700.671785ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:51:38.31218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.137329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:51:38.312225Z","caller":"traceutil/trace.go:171","msg":"trace[1725412801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"632.222644ms","start":"2026-05-05T16:51:37.679989Z","end":"2026-05-05T16:51:38.312212Z","steps":["trace[1725412801] 'agreement among raft nodes before linearized reading' (duration: 632.154929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:51:38.31144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.022567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:51:38.312355Z","caller":"traceutil/trace.go:171","msg":"trace[1636838468] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:615; }","duration":"702.975621ms","start":"2026-05-05T16:51:37.609363Z","end":"2026-05-05T16:51:38.312339Z","steps":["trace[1636838468] 'agreement among raft nodes before linearized reading' (duration: 702.010386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:51:38.3124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:51:37.609351Z","time spent":"703.036735ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-05T16:51:38.312268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:51:37.679961Z","time spent":"632.299159ms","remote":"127.0.0.1:53758","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-05T16:51:38.312545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.203598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T16:51:38.312656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.932617ms","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-05T16:51:38.312806Z","caller":"traceutil/trace.go:171","msg":"trace[1635581679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"504.083957ms","start":"2026-05-05T16:51:37.808712Z","end":"2026-05-05T16:51:38.312796Z","steps":["trace[1635581679] 'agreement among raft nodes before linearized reading' (duration: 503.613386ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:38.312721Z","caller":"traceutil/trace.go:171","msg":"trace[1601187904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"493.523428ms","start":"2026-05-05T16:51:37.819179Z","end":"2026-05-05T16:51:38.312703Z","steps":["trace[1601187904] 'agreement among raft nodes before linearized reading' (duration: 493.050697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:51:38.312859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:51:37.819169Z","time spent":"493.676679ms","remote":"127.0.0.1:53998","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-05T16:51:38.312888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:51:37.808654Z","time spent":"504.188634ms","remote":"127.0.0.1:53998","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-05T16:53:05.288716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.214041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e989f0ce-bace-47c0-895d-bea3abd3d7c6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T16:53:05.288841Z","caller":"traceutil/trace.go:171","msg":"trace[632913634] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e989f0ce-bace-47c0-895d-bea3abd3d7c6; range_end:; response_count:1; response_revision:1831; }","duration":"305.398254ms","start":"2026-05-05T16:53:04.983419Z","end":"2026-05-05T16:53:05.288817Z","steps":["trace[632913634] 'range keys from in-memory index tree' (duration: 305.011847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:53:05.288885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:53:04.983397Z","time spent":"305.478027ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e989f0ce-bace-47c0-895d-bea3abd3d7c6\" "} {"level":"warn","ts":"2026-05-05T16:53:05.28893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.211717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:53:05.289232Z","caller":"traceutil/trace.go:171","msg":"trace[1961033962] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"285.795824ms","start":"2026-05-05T16:53:05.003393Z","end":"2026-05-05T16:53:05.289189Z","steps":["trace[1961033962] 'process raft request' (duration: 97.087955ms)","trace[1961033962] 'compare' (duration: 188.093155ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:18.082236Z","caller":"traceutil/trace.go:171","msg":"trace[1561516176] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"123.2694ms","start":"2026-05-05T16:53:17.95891Z","end":"2026-05-05T16:53:18.082179Z","steps":["trace[1561516176] 'process raft request' (duration: 123.063025ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:38.357679Z","caller":"traceutil/trace.go:171","msg":"trace[2107519209] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"118.65578ms","start":"2026-05-05T16:53:38.238973Z","end":"2026-05-05T16:53:38.357629Z","steps":["trace[2107519209] 'process raft request' (duration: 102.538239ms)","trace[2107519209] 'compare' (duration: 15.871216ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:39.174805Z","caller":"traceutil/trace.go:171","msg":"trace[2054564603] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"179.617132ms","start":"2026-05-05T16:53:38.995172Z","end":"2026-05-05T16:53:39.17479Z","steps":["trace[2054564603] 'process raft request' (duration: 179.262305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:53:39.17506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.58788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T16:53:39.174707Z","caller":"traceutil/trace.go:171","msg":"trace[626614179] linearizableReadLoop","detail":"{readStateIndex:2149; appliedIndex:2148; }","duration":"176.202672ms","start":"2026-05-05T16:53:38.998439Z","end":"2026-05-05T16:53:39.174642Z","steps":["trace[626614179] 'read index received' (duration: 175.936117ms)","trace[626614179] 'applied index is now lower than readState.Index' (duration: 265.335µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:39.175204Z","caller":"traceutil/trace.go:171","msg":"trace[1320991657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2072; }","duration":"176.768494ms","start":"2026-05-05T16:53:38.998408Z","end":"2026-05-05T16:53:39.175176Z","steps":["trace[1320991657] 'agreement among raft nodes before linearized reading' (duration: 176.449457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:53:39.191973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.591157ms","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-05T16:53:39.192057Z","caller":"traceutil/trace.go:171","msg":"trace[2041950429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2073; }","duration":"192.693289ms","start":"2026-05-05T16:53:38.999343Z","end":"2026-05-05T16:53:39.192037Z","steps":["trace[2041950429] 'agreement among raft nodes before linearized reading' (duration: 192.438944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:53:39.19214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.945632ms","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-05T16:53:39.192223Z","caller":"traceutil/trace.go:171","msg":"trace[593601418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2073; }","duration":"143.091816ms","start":"2026-05-05T16:53:39.049107Z","end":"2026-05-05T16:53:39.192199Z","steps":["trace[593601418] 'agreement among raft nodes before linearized reading' (duration: 142.799249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:53:39.392394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.23117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:53:39.392541Z","caller":"traceutil/trace.go:171","msg":"trace[329087289] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"196.035857ms","start":"2026-05-05T16:53:39.196486Z","end":"2026-05-05T16:53:39.392522Z","steps":["trace[329087289] 'process raft request' (duration: 24.590693ms)","trace[329087289] 'compare' (duration: 170.557296ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:39.393115Z","caller":"traceutil/trace.go:171","msg":"trace[2020203851] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"196.036337ms","start":"2026-05-05T16:53:39.197058Z","end":"2026-05-05T16:53:39.393095Z","steps":["trace[2020203851] 'process raft request' (duration: 195.433344ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:39.393122Z","caller":"traceutil/trace.go:171","msg":"trace[397256918] linearizableReadLoop","detail":"{readStateIndex:2153; appliedIndex:2150; }","duration":"192.227688ms","start":"2026-05-05T16:53:39.200845Z","end":"2026-05-05T16:53:39.393072Z","steps":["trace[397256918] 'read index received' (duration: 1.809016ms)","trace[397256918] 'applied index is now lower than readState.Index' (duration: 190.416592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:53:39.393242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.371761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:53:39.39334Z","caller":"traceutil/trace.go:171","msg":"trace[1194966175] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2076; }","duration":"192.501924ms","start":"2026-05-05T16:53:39.200813Z","end":"2026-05-05T16:53:39.393315Z","steps":["trace[1194966175] 'agreement among raft nodes before linearized reading' (duration: 192.356091ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:44.784008Z","caller":"traceutil/trace.go:171","msg":"trace[1847868164] linearizableReadLoop","detail":"{readStateIndex:2624; appliedIndex:2623; }","duration":"103.540135ms","start":"2026-05-05T16:54:44.680449Z","end":"2026-05-05T16:54:44.783989Z","steps":["trace[1847868164] 'read index received' (duration: 103.454423ms)","trace[1847868164] 'applied index is now lower than readState.Index' (duration: 84.812µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:54:44.784215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.744709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:44.784294Z","caller":"traceutil/trace.go:171","msg":"trace[339889279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2532; }","duration":"103.855682ms","start":"2026-05-05T16:54:44.680421Z","end":"2026-05-05T16:54:44.784276Z","steps":["trace[339889279] 'agreement among raft nodes before linearized reading' (duration: 103.721129ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:44.784604Z","caller":"traceutil/trace.go:171","msg":"trace[1754913377] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"184.874897ms","start":"2026-05-05T16:54:44.599712Z","end":"2026-05-05T16:54:44.784587Z","steps":["trace[1754913377] 'process raft request' (duration: 184.114512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:16.899604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.19499ms","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-05T16:55:16.899735Z","caller":"traceutil/trace.go:171","msg":"trace[1109740026] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2710; }","duration":"102.370713ms","start":"2026-05-05T16:55:16.797347Z","end":"2026-05-05T16:55:16.899718Z","steps":["trace[1109740026] 'range keys from in-memory index tree' (duration: 102.045537ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:46.67791Z","caller":"traceutil/trace.go:171","msg":"trace[787647554] linearizableReadLoop","detail":"{readStateIndex:4556; appliedIndex:4555; }","duration":"109.818449ms","start":"2026-05-05T16:58:46.568076Z","end":"2026-05-05T16:58:46.677894Z","steps":["trace[787647554] 'read index received' (duration: 109.746569ms)","trace[787647554] 'applied index is now lower than readState.Index' (duration: 70.85µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:46.677942Z","caller":"traceutil/trace.go:171","msg":"trace[928744817] transaction","detail":"{read_only:false; response_revision:4390; number_of_response:1; }","duration":"115.196808ms","start":"2026-05-05T16:58:46.562707Z","end":"2026-05-05T16:58:46.677904Z","steps":["trace[928744817] 'process raft request' (duration: 115.092528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:46.678813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.010038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:4340"} {"level":"info","ts":"2026-05-05T16:58:46.678869Z","caller":"traceutil/trace.go:171","msg":"trace[1448303679] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4390; }","duration":"110.820677ms","start":"2026-05-05T16:58:46.568035Z","end":"2026-05-05T16:58:46.678856Z","steps":["trace[1448303679] 'agreement among raft nodes before linearized reading' (duration: 109.959769ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:57.979708Z","caller":"traceutil/trace.go:171","msg":"trace[1146226868] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"281.72142ms","start":"2026-05-05T16:58:57.697964Z","end":"2026-05-05T16:58:57.979686Z","steps":["trace[1146226868] 'process raft request' (duration: 227.728394ms)","trace[1146226868] 'compare' (duration: 53.532446ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:57.979701Z","caller":"traceutil/trace.go:171","msg":"trace[81298816] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"281.53418ms","start":"2026-05-05T16:58:57.698139Z","end":"2026-05-05T16:58:57.979673Z","steps":["trace[81298816] 'process raft request' (duration: 281.43968ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.02447Z","caller":"traceutil/trace.go:171","msg":"trace[733208147] linearizableReadLoop","detail":"{readStateIndex:4855; appliedIndex:4852; }","duration":"317.751724ms","start":"2026-05-05T16:58:57.706699Z","end":"2026-05-05T16:58:58.02445Z","steps":["trace[733208147] 'read index received' (duration: 219.006096ms)","trace[733208147] 'applied index is now lower than readState.Index' (duration: 98.744778ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:58.024573Z","caller":"traceutil/trace.go:171","msg":"trace[1286938657] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"322.371823ms","start":"2026-05-05T16:58:57.70218Z","end":"2026-05-05T16:58:58.024552Z","steps":["trace[1286938657] 'process raft request' (duration: 322.154153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.024641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.921924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-05T16:58:58.024678Z","caller":"traceutil/trace.go:171","msg":"trace[898733984] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4685; }","duration":"317.969484ms","start":"2026-05-05T16:58:57.706695Z","end":"2026-05-05T16:58:58.024665Z","steps":["trace[898733984] 'agreement among raft nodes before linearized reading' (duration: 317.848154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.024707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:57.706686Z","time spent":"318.012764ms","remote":"127.0.0.1:53892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-05T16:58:58.02473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:57.702171Z","time spent":"322.473533ms","remote":"127.0.0.1:54002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1868,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:58.035652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.318732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:58:58.035677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.617016ms","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-05T16:58:58.035719Z","caller":"traceutil/trace.go:171","msg":"trace[85577288] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4686; }","duration":"251.420872ms","start":"2026-05-05T16:58:57.784285Z","end":"2026-05-05T16:58:58.035706Z","steps":["trace[85577288] 'agreement among raft nodes before linearized reading' (duration: 251.318163ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.035722Z","caller":"traceutil/trace.go:171","msg":"trace[434519444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4686; }","duration":"274.700606ms","start":"2026-05-05T16:58:57.761011Z","end":"2026-05-05T16:58:58.035712Z","steps":["trace[434519444] 'agreement among raft nodes before linearized reading' (duration: 274.554627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.035953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.348347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-05T16:58:58.036001Z","caller":"traceutil/trace.go:171","msg":"trace[580446815] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4686; }","duration":"309.420107ms","start":"2026-05-05T16:58:57.726564Z","end":"2026-05-05T16:58:58.035984Z","steps":["trace[580446815] 'agreement among raft nodes before linearized reading' (duration: 309.298847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.036034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:57.726549Z","time spent":"309.474647ms","remote":"127.0.0.1:53822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1844,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-05T16:58:58.036331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.643976ms","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-05T16:58:58.036357Z","caller":"traceutil/trace.go:171","msg":"trace[1500705150] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4686; }","duration":"154.708976ms","start":"2026-05-05T16:58:57.881641Z","end":"2026-05-05T16:58:58.03635Z","steps":["trace[1500705150] 'agreement among raft nodes before linearized reading' (duration: 153.939757ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.348266Z","caller":"traceutil/trace.go:171","msg":"trace[1697493973] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"171.92734ms","start":"2026-05-05T16:58:58.176308Z","end":"2026-05-05T16:58:58.348236Z","steps":["trace[1697493973] 'process raft request' (duration: 119.769176ms)","trace[1697493973] 'compare' (duration: 51.902775ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:59:00.903634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.638811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T16:59:00.903641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.708261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T16:59:00.903713Z","caller":"traceutil/trace.go:171","msg":"trace[312923954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4716; }","duration":"151.767801ms","start":"2026-05-05T16:59:00.751927Z","end":"2026-05-05T16:59:00.903695Z","steps":["trace[312923954] 'range keys from in-memory index tree' (duration: 151.361721ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:00.903734Z","caller":"traceutil/trace.go:171","msg":"trace[1266996486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4716; }","duration":"148.853061ms","start":"2026-05-05T16:59:00.754862Z","end":"2026-05-05T16:59:00.903715Z","steps":["trace[1266996486] 'range keys from in-memory index tree' (duration: 148.505121ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:02.859105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2951} {"level":"info","ts":"2026-05-05T17:01:02.939293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2951,"took":"78.934564ms","hash":2436059873,"current-db-size-bytes":53579776,"current-db-size":"54 MB","current-db-size-in-use-bytes":29556736,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T17:01:02.939367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2436059873,"revision":2951,"compact-revision":-1} {"level":"info","ts":"2026-05-05T17:06:02.86395Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5701} {"level":"info","ts":"2026-05-05T17:06:02.938265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5701,"took":"72.820394ms","hash":2947351701,"current-db-size-bytes":53579776,"current-db-size":"54 MB","current-db-size-in-use-bytes":18305024,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T17:06:02.938324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2947351701,"revision":5701,"compact-revision":2951}