{"level":"warn","ts":"2026-04-18T22:10:09.004665Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:10:09.004731Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-18T22:10:09.004782Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:10:09.004793Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-18T22:10:09.00481Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T22:10:09.005311Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-04-18T22:10:09.005374Z","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.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-18T22:10:09.008319Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.782442ms"} {"level":"info","ts":"2026-04-18T22:10:09.017275Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-04-18T22:10:09.017392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T22:10:09.017415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-04-18T22:10:09.017438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T22:10:09.01745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-04-18T22:10:09.017482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-04-18T22:10:09.02386Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T22:10:09.025653Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T22:10:09.027888Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-18T22:10:09.029844Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T22:10:09.029957Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T22:10:09.030164Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:10:09.030242Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:10:09.030252Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:10:09.033394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-04-18T22:10:09.033535Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-18T22:10:09.034981Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T22:10:09.035061Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-18T22:10:09.035079Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-18T22:10:09.035205Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T22:10:09.035253Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T22:10:09.518345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T22:10:09.518389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T22:10:09.518413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-04-18T22:10:09.518424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-04-18T22:10:09.518429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-18T22:10:09.518437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-04-18T22:10:09.518444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-18T22:10:09.596943Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:10:09.612625Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T22:10:09.612656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:10:09.612737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:10:09.613133Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T22:10:09.61323Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T22:10:09.616295Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T22:10:09.622458Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:10:09.622683Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-04-18T22:10:09.622838Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:10:09.622971Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:10:25.08617Z","caller":"traceutil/trace.go:171","msg":"trace[935152750] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"109.510426ms","start":"2026-04-18T22:10:24.976621Z","end":"2026-04-18T22:10:25.086131Z","steps":["trace[935152750] 'read index received' (duration: 109.372785ms)","trace[935152750] 'applied index is now lower than readState.Index' (duration: 136.841µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:10:25.086211Z","caller":"traceutil/trace.go:171","msg":"trace[826599775] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"158.040231ms","start":"2026-04-18T22:10:24.928151Z","end":"2026-04-18T22:10:25.086191Z","steps":["trace[826599775] 'process raft request' (duration: 157.880159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:10:25.086298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.659498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-04-18T22:10:25.086352Z","caller":"traceutil/trace.go:171","msg":"trace[466902049] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"109.751439ms","start":"2026-04-18T22:10:24.976591Z","end":"2026-04-18T22:10:25.086342Z","steps":["trace[466902049] 'agreement among raft nodes before linearized reading' (duration: 109.659768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:10:25.267042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.523161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:10:25.267247Z","caller":"traceutil/trace.go:171","msg":"trace[1543303912] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"175.60284ms","start":"2026-04-18T22:10:25.091612Z","end":"2026-04-18T22:10:25.267215Z","steps":["trace[1543303912] 'process raft request' (duration: 175.539079ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:25.267327Z","caller":"traceutil/trace.go:171","msg":"trace[1392085208] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"175.880453ms","start":"2026-04-18T22:10:25.091412Z","end":"2026-04-18T22:10:25.267293Z","steps":["trace[1392085208] 'process raft request' (duration: 19.302759ms)","trace[1392085208] 'compare' (duration: 155.336019ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:10:39.178788Z","caller":"traceutil/trace.go:171","msg":"trace[1573133034] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"180.327688ms","start":"2026-04-18T22:10:38.998422Z","end":"2026-04-18T22:10:39.17875Z","steps":["trace[1573133034] 'process raft request' (duration: 180.070896ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.19425Z","caller":"traceutil/trace.go:171","msg":"trace[21677020] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"192.413172ms","start":"2026-04-18T22:10:39.00182Z","end":"2026-04-18T22:10:39.194233Z","steps":["trace[21677020] 'process raft request' (duration: 192.22159ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.194276Z","caller":"traceutil/trace.go:171","msg":"trace[1452556757] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"183.689442ms","start":"2026-04-18T22:10:39.010558Z","end":"2026-04-18T22:10:39.194248Z","steps":["trace[1452556757] 'process raft request' (duration: 183.583971ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.194523Z","caller":"traceutil/trace.go:171","msg":"trace[33943258] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"179.832322ms","start":"2026-04-18T22:10:39.014679Z","end":"2026-04-18T22:10:39.194511Z","steps":["trace[33943258] 'process raft request' (duration: 179.511489ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.194654Z","caller":"traceutil/trace.go:171","msg":"trace[1192906864] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"179.853832ms","start":"2026-04-18T22:10:39.014748Z","end":"2026-04-18T22:10:39.194602Z","steps":["trace[1192906864] 'process raft request' (duration: 179.472028ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.194814Z","caller":"traceutil/trace.go:171","msg":"trace[488029897] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"176.306185ms","start":"2026-04-18T22:10:39.018496Z","end":"2026-04-18T22:10:39.194802Z","steps":["trace[488029897] 'process raft request' (duration: 175.978002ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.194904Z","caller":"traceutil/trace.go:171","msg":"trace[1061057598] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"176.357455ms","start":"2026-04-18T22:10:39.018539Z","end":"2026-04-18T22:10:39.194896Z","steps":["trace[1061057598] 'process raft request' (duration: 176.151833ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.195017Z","caller":"traceutil/trace.go:171","msg":"trace[10661489] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"174.69803ms","start":"2026-04-18T22:10:39.020308Z","end":"2026-04-18T22:10:39.195006Z","steps":["trace[10661489] 'process raft request' (duration: 174.437327ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.195081Z","caller":"traceutil/trace.go:171","msg":"trace[819857554] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"160.158361ms","start":"2026-04-18T22:10:39.0349Z","end":"2026-04-18T22:10:39.195058Z","steps":["trace[819857554] 'process raft request' (duration: 159.991898ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.195169Z","caller":"traceutil/trace.go:171","msg":"trace[1008477575] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"148.090696ms","start":"2026-04-18T22:10:39.047056Z","end":"2026-04-18T22:10:39.195148Z","steps":["trace[1008477575] 'process raft request' (duration: 147.968564ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:39.195388Z","caller":"traceutil/trace.go:171","msg":"trace[752413866] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"117.46797ms","start":"2026-04-18T22:10:39.077911Z","end":"2026-04-18T22:10:39.195379Z","steps":["trace[752413866] 'process raft request' (duration: 117.190707ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:10:45.678744Z","caller":"traceutil/trace.go:171","msg":"trace[775742067] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"111.475143ms","start":"2026-04-18T22:10:45.567233Z","end":"2026-04-18T22:10:45.678708Z","steps":["trace[775742067] 'process raft request' (duration: 111.249721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:10:45.971069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.511191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:10:45.971221Z","caller":"traceutil/trace.go:171","msg":"trace[1696613842] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"262.831506ms","start":"2026-04-18T22:10:45.70835Z","end":"2026-04-18T22:10:45.971181Z","steps":["trace[1696613842] 'process raft request' (duration: 94.061572ms)","trace[1696613842] 'compare' (duration: 168.319539ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:10:46.10156Z","caller":"traceutil/trace.go:171","msg":"trace[887072357] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"226.601294ms","start":"2026-04-18T22:10:45.874932Z","end":"2026-04-18T22:10:46.101533Z","steps":["trace[887072357] 'process raft request' (duration: 199.084016ms)","trace[887072357] 'compare' (duration: 27.256115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:10:46.594356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.037158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:10:46.594457Z","caller":"traceutil/trace.go:171","msg":"trace[279161785] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"416.680154ms","start":"2026-04-18T22:10:46.177762Z","end":"2026-04-18T22:10:46.594442Z","steps":["trace[279161785] 'process raft request' (duration: 293.409204ms)","trace[279161785] 'compare' (duration: 122.787745ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:10:46.594497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:10:46.177746Z","time spent":"416.734574ms","remote":"127.0.0.1:45134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:17.262672Z","caller":"traceutil/trace.go:171","msg":"trace[477208981] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"350.243668ms","start":"2026-04-18T22:12:16.91239Z","end":"2026-04-18T22:12:17.262634Z","steps":["trace[477208981] 'process raft request' (duration: 348.582462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:17.263459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:16.912357Z","time spent":"350.935283ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7741,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:12:17.269838Z","caller":"traceutil/trace.go:171","msg":"trace[577659420] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1908; }","duration":"237.173991ms","start":"2026-04-18T22:12:17.032638Z","end":"2026-04-18T22:12:17.269812Z","steps":["trace[577659420] 'read index received' (duration: 227.433804ms)","trace[577659420] 'applied index is now lower than readState.Index' (duration: 9.739217ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:17.269969Z","caller":"traceutil/trace.go:171","msg":"trace[1213493459] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"318.410622ms","start":"2026-04-18T22:12:16.951531Z","end":"2026-04-18T22:12:17.269941Z","steps":["trace[1213493459] 'process raft request' (duration: 318.125796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:17.270231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.273313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:17.270266Z","caller":"traceutil/trace.go:171","msg":"trace[1642436254] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1840; }","duration":"229.339684ms","start":"2026-04-18T22:12:17.040916Z","end":"2026-04-18T22:12:17.270256Z","steps":["trace[1642436254] 'agreement among raft nodes before linearized reading' (duration: 229.199031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:17.270239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:16.951507Z","time spent":"318.553105ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:12:17.270905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.252274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4cecface-fb38-4f1b-bb66-965dc4dbbdae\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-18T22:12:17.270981Z","caller":"traceutil/trace.go:171","msg":"trace[1544474411] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4cecface-fb38-4f1b-bb66-965dc4dbbdae; range_end:; response_count:1; response_revision:1840; }","duration":"238.365036ms","start":"2026-04-18T22:12:17.032594Z","end":"2026-04-18T22:12:17.270959Z","steps":["trace[1544474411] 'agreement among raft nodes before linearized reading' (duration: 237.399426ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:17.413442Z","caller":"traceutil/trace.go:171","msg":"trace[849199872] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"137.052425ms","start":"2026-04-18T22:12:17.276367Z","end":"2026-04-18T22:12:17.41342Z","steps":["trace[849199872] 'process raft request' (duration: 74.634923ms)","trace[849199872] 'compare' (duration: 62.32343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:17.715288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.243102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4cecface-fb38-4f1b-bb66-965dc4dbbdae\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-04-18T22:12:17.715395Z","caller":"traceutil/trace.go:171","msg":"trace[1707244076] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4cecface-fb38-4f1b-bb66-965dc4dbbdae; range_end:; response_count:1; response_revision:1841; }","duration":"170.398336ms","start":"2026-04-18T22:12:17.544973Z","end":"2026-04-18T22:12:17.715371Z","steps":["trace[1707244076] 'range keys from in-memory index tree' (duration: 170.142761ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:52.165811Z","caller":"traceutil/trace.go:171","msg":"trace[366662237] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2160; }","duration":"174.859216ms","start":"2026-04-18T22:12:51.990927Z","end":"2026-04-18T22:12:52.165786Z","steps":["trace[366662237] 'read index received' (duration: 174.646762ms)","trace[366662237] 'applied index is now lower than readState.Index' (duration: 211.374µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:52.165984Z","caller":"traceutil/trace.go:171","msg":"trace[1870300757] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"302.600692ms","start":"2026-04-18T22:12:51.863346Z","end":"2026-04-18T22:12:52.165947Z","steps":["trace[1870300757] 'process raft request' (duration: 302.230946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:52.166255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:51.863324Z","time spent":"302.848726ms","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:12:52.166422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.471423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-18T22:12:52.166492Z","caller":"traceutil/trace.go:171","msg":"trace[242208831] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2084; }","duration":"159.628066ms","start":"2026-04-18T22:12:52.006845Z","end":"2026-04-18T22:12:52.166473Z","steps":["trace[242208831] 'agreement among raft nodes before linearized reading' (duration: 159.492654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:52.166252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.297283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:12:52.166583Z","caller":"traceutil/trace.go:171","msg":"trace[1851320861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2084; }","duration":"175.67734ms","start":"2026-04-18T22:12:51.990884Z","end":"2026-04-18T22:12:52.166562Z","steps":["trace[1851320861] 'agreement among raft nodes before linearized reading' (duration: 175.12127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:52.166607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.982825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-18T22:12:52.166711Z","caller":"traceutil/trace.go:171","msg":"trace[1060885872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2084; }","duration":"123.148628ms","start":"2026-04-18T22:12:52.043539Z","end":"2026-04-18T22:12:52.166688Z","steps":["trace[1060885872] 'agreement among raft nodes before linearized reading' (duration: 122.892303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:52.166828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.31506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-18T22:12:52.166868Z","caller":"traceutil/trace.go:171","msg":"trace[1998465266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2084; }","duration":"123.393631ms","start":"2026-04-18T22:12:52.043465Z","end":"2026-04-18T22:12:52.166859Z","steps":["trace[1998465266] 'agreement among raft nodes before linearized reading' (duration: 123.29881ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:52.317915Z","caller":"traceutil/trace.go:171","msg":"trace[149037737] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"146.686786ms","start":"2026-04-18T22:12:52.171203Z","end":"2026-04-18T22:12:52.317889Z","steps":["trace[149037737] 'process raft request' (duration: 146.491453ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:52.323862Z","caller":"traceutil/trace.go:171","msg":"trace[497225729] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"152.482744ms","start":"2026-04-18T22:12:52.171353Z","end":"2026-04-18T22:12:52.323836Z","steps":["trace[497225729] 'process raft request' (duration: 152.23246ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:52.324023Z","caller":"traceutil/trace.go:171","msg":"trace[541667324] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"152.527675ms","start":"2026-04-18T22:12:52.171473Z","end":"2026-04-18T22:12:52.324001Z","steps":["trace[541667324] 'process raft request' (duration: 152.24514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:52.574782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.029333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:12:52.574867Z","caller":"traceutil/trace.go:171","msg":"trace[1591238707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2088; }","duration":"143.222377ms","start":"2026-04-18T22:12:52.431626Z","end":"2026-04-18T22:12:52.574849Z","steps":["trace[1591238707] 'range keys from in-memory index tree' (duration: 142.82847ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:56.72566Z","caller":"traceutil/trace.go:171","msg":"trace[2082647807] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"102.060513ms","start":"2026-04-18T22:13:56.623575Z","end":"2026-04-18T22:13:56.725635Z","steps":["trace[2082647807] 'process raft request' (duration: 101.702539ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:29.414628Z","caller":"traceutil/trace.go:171","msg":"trace[1606378995] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"113.873098ms","start":"2026-04-18T22:14:29.300733Z","end":"2026-04-18T22:14:29.414606Z","steps":["trace[1606378995] 'process raft request' (duration: 113.715916ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:29.487548Z","caller":"traceutil/trace.go:171","msg":"trace[1806444611] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"180.692809ms","start":"2026-04-18T22:14:29.306828Z","end":"2026-04-18T22:14:29.487521Z","steps":["trace[1806444611] 'process raft request' (duration: 169.543522ms)","trace[1806444611] 'compare' (duration: 10.54977ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:29.827191Z","caller":"traceutil/trace.go:171","msg":"trace[1660515711] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"106.940722ms","start":"2026-04-18T22:14:29.720212Z","end":"2026-04-18T22:14:29.827153Z","steps":["trace[1660515711] 'process raft request' (duration: 101.658037ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:34.990548Z","caller":"traceutil/trace.go:171","msg":"trace[2068603527] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"119.965851ms","start":"2026-04-18T22:14:34.870556Z","end":"2026-04-18T22:14:34.990522Z","steps":["trace[2068603527] 'process raft request' (duration: 119.534266ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:12.936706Z","caller":"traceutil/trace.go:171","msg":"trace[237337681] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"126.775714ms","start":"2026-04-18T22:18:12.809905Z","end":"2026-04-18T22:18:12.936681Z","steps":["trace[237337681] 'process raft request' (duration: 122.906214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.376757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.533453ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-18T22:18:18.377005Z","caller":"traceutil/trace.go:171","msg":"trace[424737051] linearizableReadLoop","detail":"{readStateIndex:4917; appliedIndex:4916; }","duration":"280.526848ms","start":"2026-04-18T22:18:18.09646Z","end":"2026-04-18T22:18:18.376986Z","steps":["trace[424737051] 'read index received' (duration: 14.702819ms)","trace[424737051] 'applied index is now lower than readState.Index' (duration: 265.823129ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:18.377657Z","caller":"traceutil/trace.go:171","msg":"trace[1485999740] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"211.534186ms","start":"2026-04-18T22:18:18.166081Z","end":"2026-04-18T22:18:18.377616Z","steps":["trace[1485999740] 'process raft request' (duration: 210.790041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.610356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.869626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-18T22:18:18.610449Z","caller":"traceutil/trace.go:171","msg":"trace[771738638] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4744; }","duration":"513.986648ms","start":"2026-04-18T22:18:18.096436Z","end":"2026-04-18T22:18:18.610422Z","steps":["trace[771738638] 'agreement among raft nodes before linearized reading' (duration: 281.390894ms)","trace[771738638] 'range keys from in-memory index tree' (duration: 232.387929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:18.610518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.096417Z","time spent":"514.090591ms","remote":"127.0.0.1:44990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1364,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-04-18T22:18:18.610562Z","caller":"traceutil/trace.go:171","msg":"trace[1574664354] linearizableReadLoop","detail":"{readStateIndex:4920; appliedIndex:4918; }","duration":"232.709536ms","start":"2026-04-18T22:18:18.377795Z","end":"2026-04-18T22:18:18.610505Z","steps":["trace[1574664354] 'read index received' (duration: 227.567282ms)","trace[1574664354] 'applied index is now lower than readState.Index' (duration: 5.141034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:18.610743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.968094ms","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":"warn","ts":"2026-04-18T22:18:18.61078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.163914ms","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-04-18T22:18:18.610819Z","caller":"traceutil/trace.go:171","msg":"trace[217173207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4746; }","duration":"371.100638ms","start":"2026-04-18T22:18:18.239701Z","end":"2026-04-18T22:18:18.610802Z","steps":["trace[217173207] 'agreement among raft nodes before linearized reading' (duration: 370.894203ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:18.610834Z","caller":"traceutil/trace.go:171","msg":"trace[496920559] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4746; }","duration":"391.249026ms","start":"2026-04-18T22:18:18.219565Z","end":"2026-04-18T22:18:18.610814Z","steps":["trace[496920559] 'agreement among raft nodes before linearized reading' (duration: 391.116312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.610869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.239673Z","time spent":"371.182359ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:18:18.610889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.219538Z","time spent":"391.338808ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-18T22:18:18.610963Z","caller":"traceutil/trace.go:171","msg":"trace[806962073] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"330.332289ms","start":"2026-04-18T22:18:18.280617Z","end":"2026-04-18T22:18:18.610949Z","steps":["trace[806962073] 'process raft request' (duration: 324.729125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.611069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.280596Z","time spent":"330.409661ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:18:18.611079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.698681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T22:18:18.611125Z","caller":"traceutil/trace.go:171","msg":"trace[1678160399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4746; }","duration":"504.776493ms","start":"2026-04-18T22:18:18.106335Z","end":"2026-04-18T22:18:18.611111Z","steps":["trace[1678160399] 'agreement among raft nodes before linearized reading' (duration: 504.65172ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:18.611137Z","caller":"traceutil/trace.go:171","msg":"trace[1393789187] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"323.288676ms","start":"2026-04-18T22:18:18.287829Z","end":"2026-04-18T22:18:18.611117Z","steps":["trace[1393789187] 'process raft request' (duration: 322.589371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.61118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.106319Z","time spent":"504.849724ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:18:18.611155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.737199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:18:18.611339Z","caller":"traceutil/trace.go:171","msg":"trace[640783248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4746; }","duration":"497.004015ms","start":"2026-04-18T22:18:18.114308Z","end":"2026-04-18T22:18:18.611312Z","steps":["trace[640783248] 'agreement among raft nodes before linearized reading' (duration: 496.391842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.611422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.11429Z","time spent":"497.119177ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:18:18.611707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.469981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:18:18.611756Z","caller":"traceutil/trace.go:171","msg":"trace[259933064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4746; }","duration":"489.549053ms","start":"2026-04-18T22:18:18.122195Z","end":"2026-04-18T22:18:18.611744Z","steps":["trace[259933064] 'agreement among raft nodes before linearized reading' (duration: 489.456011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.611253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.287799Z","time spent":"323.376998ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:18:18.61145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.449741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:18:18.611934Z","caller":"traceutil/trace.go:171","msg":"trace[735009817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4746; }","duration":"102.996542ms","start":"2026-04-18T22:18:18.508924Z","end":"2026-04-18T22:18:18.61192Z","steps":["trace[735009817] 'agreement among raft nodes before linearized reading' (duration: 102.477012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.611571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.214009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:18:18.612028Z","caller":"traceutil/trace.go:171","msg":"trace[1937221835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4746; }","duration":"437.72ms","start":"2026-04-18T22:18:18.174282Z","end":"2026-04-18T22:18:18.612001Z","steps":["trace[1937221835] 'agreement among raft nodes before linearized reading' (duration: 437.049636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.612085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.174257Z","time spent":"437.816241ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:18:18.611609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.528703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d50be7d-5d2f-44d0-bc7d-755973ebd010\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-18T22:18:18.612202Z","caller":"traceutil/trace.go:171","msg":"trace[1550398344] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d50be7d-5d2f-44d0-bc7d-755973ebd010; range_end:; response_count:1; response_revision:4746; }","duration":"103.124914ms","start":"2026-04-18T22:18:18.509056Z","end":"2026-04-18T22:18:18.612181Z","steps":["trace[1550398344] 'agreement among raft nodes before linearized reading' (duration: 102.458041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.611634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.145628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:18:18.612268Z","caller":"traceutil/trace.go:171","msg":"trace[2085630257] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4746; }","duration":"369.913443ms","start":"2026-04-18T22:18:18.242343Z","end":"2026-04-18T22:18:18.612256Z","steps":["trace[2085630257] 'agreement among raft nodes before linearized reading' (duration: 368.944734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:18.61232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.242326Z","time spent":"369.979004ms","remote":"127.0.0.1:45340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:18:18.611791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.122138Z","time spent":"489.646204ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-18T22:18:19.013946Z","caller":"traceutil/trace.go:171","msg":"trace[370169613] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"255.023268ms","start":"2026-04-18T22:18:18.758895Z","end":"2026-04-18T22:18:19.013918Z","steps":["trace[370169613] 'process raft request' (duration: 156.855955ms)","trace[370169613] 'compare' (duration: 97.959519ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:19.014245Z","caller":"traceutil/trace.go:171","msg":"trace[54427175] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"255.100642ms","start":"2026-04-18T22:18:18.759135Z","end":"2026-04-18T22:18:19.014235Z","steps":["trace[54427175] 'process raft request' (duration: 254.723143ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:19.014595Z","caller":"traceutil/trace.go:171","msg":"trace[1946401659] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"255.277803ms","start":"2026-04-18T22:18:18.759297Z","end":"2026-04-18T22:18:19.014575Z","steps":["trace[1946401659] 'process raft request' (duration: 255.10482ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:19.014595Z","caller":"traceutil/trace.go:171","msg":"trace[1410146303] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"255.411007ms","start":"2026-04-18T22:18:18.759139Z","end":"2026-04-18T22:18:19.01455Z","steps":["trace[1410146303] 'process raft request' (duration: 255.020038ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:19.071097Z","caller":"traceutil/trace.go:171","msg":"trace[370741108] linearizableReadLoop","detail":"{readStateIndex:4934; appliedIndex:4929; }","duration":"301.400078ms","start":"2026-04-18T22:18:18.769676Z","end":"2026-04-18T22:18:19.071076Z","steps":["trace[370741108] 'read index received' (duration: 146.083647ms)","trace[370741108] 'applied index is now lower than readState.Index' (duration: 155.315471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:19.071348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.655543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-18T22:18:19.071391Z","caller":"traceutil/trace.go:171","msg":"trace[964430679] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4762; }","duration":"301.728074ms","start":"2026-04-18T22:18:18.769649Z","end":"2026-04-18T22:18:19.071377Z","steps":["trace[964430679] 'agreement among raft nodes before linearized reading' (duration: 301.589091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:19.071418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.769637Z","time spent":"301.774796ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-18T22:18:19.071431Z","caller":"traceutil/trace.go:171","msg":"trace[16310813] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"250.655247ms","start":"2026-04-18T22:18:18.820737Z","end":"2026-04-18T22:18:19.071393Z","steps":["trace[16310813] 'process raft request' (duration: 250.295ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:19.07154Z","caller":"traceutil/trace.go:171","msg":"trace[806676078] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"302.941589ms","start":"2026-04-18T22:18:18.768588Z","end":"2026-04-18T22:18:19.07153Z","steps":["trace[806676078] 'process raft request' (duration: 302.259926ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:19.071438Z","caller":"traceutil/trace.go:171","msg":"trace[751452769] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"298.199754ms","start":"2026-04-18T22:18:18.773205Z","end":"2026-04-18T22:18:19.071405Z","steps":["trace[751452769] 'process raft request' (duration: 297.769105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:19.071671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:18.768578Z","time spent":"303.0025ms","remote":"127.0.0.1:46190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:19.202269Z","caller":"traceutil/trace.go:171","msg":"trace[763015990] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"120.372199ms","start":"2026-04-18T22:18:19.081871Z","end":"2026-04-18T22:18:19.202243Z","steps":["trace[763015990] 'process raft request' (duration: 112.365426ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:19.202743Z","caller":"traceutil/trace.go:171","msg":"trace[1942744471] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"120.805747ms","start":"2026-04-18T22:18:19.081925Z","end":"2026-04-18T22:18:19.202731Z","steps":["trace[1942744471] 'process raft request' (duration: 120.262806ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:19.202792Z","caller":"traceutil/trace.go:171","msg":"trace[181441282] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"121.193285ms","start":"2026-04-18T22:18:19.081576Z","end":"2026-04-18T22:18:19.20277Z","steps":["trace[181441282] 'process raft request' (duration: 120.869979ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:08.919087Z","caller":"traceutil/trace.go:171","msg":"trace[2064728496] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"110.909425ms","start":"2026-04-18T22:19:08.808158Z","end":"2026-04-18T22:19:08.919067Z","steps":["trace[2064728496] 'process raft request' (duration: 88.552116ms)","trace[2064728496] 'compare' (duration: 22.277567ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:21.147148Z","caller":"traceutil/trace.go:171","msg":"trace[1454861044] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"113.550975ms","start":"2026-04-18T22:19:21.033573Z","end":"2026-04-18T22:19:21.147124Z","steps":["trace[1454861044] 'process raft request' (duration: 113.427602ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:21.16304Z","caller":"traceutil/trace.go:171","msg":"trace[239130403] linearizableReadLoop","detail":"{readStateIndex:5545; appliedIndex:5543; }","duration":"113.211219ms","start":"2026-04-18T22:19:21.049807Z","end":"2026-04-18T22:19:21.163018Z","steps":["trace[239130403] 'read index received' (duration: 97.198553ms)","trace[239130403] 'applied index is now lower than readState.Index' (duration: 16.011606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:21.163263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.446504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T22:19:21.163318Z","caller":"traceutil/trace.go:171","msg":"trace[1325897490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5353; }","duration":"113.554875ms","start":"2026-04-18T22:19:21.04975Z","end":"2026-04-18T22:19:21.163305Z","steps":["trace[1325897490] 'agreement among raft nodes before linearized reading' (duration: 113.383343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:21.16379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.340433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:19:21.163846Z","caller":"traceutil/trace.go:171","msg":"trace[431137907] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"128.627895ms","start":"2026-04-18T22:19:21.034478Z","end":"2026-04-18T22:19:21.163106Z","steps":["trace[431137907] 'process raft request' (duration: 128.424841ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:21.163847Z","caller":"traceutil/trace.go:171","msg":"trace[2060841481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5353; }","duration":"112.431546ms","start":"2026-04-18T22:19:21.051398Z","end":"2026-04-18T22:19:21.16383Z","steps":["trace[2060841481] 'agreement among raft nodes before linearized reading' (duration: 111.741874ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:09.639883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2932} {"level":"info","ts":"2026-04-18T22:20:09.70953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2932,"took":"68.313316ms","hash":3270229075,"current-db-size-bytes":53751808,"current-db-size":"54 MB","current-db-size-in-use-bytes":29462528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-18T22:20:09.709588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3270229075,"revision":2932,"compact-revision":-1} {"level":"info","ts":"2026-04-18T22:22:07.650011Z","caller":"traceutil/trace.go:171","msg":"trace[1261804037] transaction","detail":"{read_only:false; response_revision:6505; number_of_response:1; }","duration":"110.731004ms","start":"2026-04-18T22:22:07.539262Z","end":"2026-04-18T22:22:07.649993Z","steps":["trace[1261804037] 'process raft request' (duration: 110.641242ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:25:09.645508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5710} {"level":"info","ts":"2026-04-18T22:25:09.73267Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5710,"took":"86.024761ms","hash":2927771821,"current-db-size-bytes":53751808,"current-db-size":"54 MB","current-db-size-in-use-bytes":18440192,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-18T22:25:09.732743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2927771821,"revision":5710,"compact-revision":2932}