{"level":"warn","ts":"2026-05-05T22:15:41.855716Z","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-05T22:15:41.855821Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-05T22:15:41.855929Z","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-05T22:15:41.855956Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-05T22:15:41.855994Z","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-05T22:15:41.856885Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-05-05T22:15:41.856982Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-05T22:15:41.861017Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.563078ms"} {"level":"info","ts":"2026-05-05T22:15:41.871314Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-05-05T22:15:41.871372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T22:15:41.871392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-05-05T22:15:41.87141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T22:15:41.871482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-05-05T22:15:41.871619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-05-05T22:15:41.877574Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T22:15:41.879118Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T22:15:41.880281Z","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-05T22:15:41.882909Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T22:15:41.883559Z","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-05T22:15:41.883626Z","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-05T22:15:41.883636Z","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-05T22:15:41.883643Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T22:15:41.885681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-05-05T22:15:41.885896Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-05T22:15:41.889374Z","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-05T22:15:41.889613Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T22:15:41.889656Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T22:15:41.88979Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-05T22:15:41.889812Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-05T22:15:42.671911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T22:15:42.672031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T22:15:42.672101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-05-05T22:15:42.672131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-05-05T22:15:42.672145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-05T22:15:42.672165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-05-05T22:15:42.672188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-05T22:15:42.673509Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T22:15:42.673785Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:15:42.673829Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:15:42.673968Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:42.674055Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T22:15:42.674157Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T22:15:42.674783Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:42.674871Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:42.674894Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:42.677353Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T22:15:42.691834Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"warn","ts":"2026-05-05T22:15:42.985429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.422053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.985515Z","caller":"traceutil/trace.go:171","msg":"trace[580869409] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"202.550486ms","start":"2026-05-05T22:15:42.782948Z","end":"2026-05-05T22:15:42.985499Z","steps":["trace[580869409] 'count revisions from in-memory index tree' (duration: 202.361661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.985697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.702827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.98573Z","caller":"traceutil/trace.go:171","msg":"trace[948286263] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"187.731257ms","start":"2026-05-05T22:15:42.797985Z","end":"2026-05-05T22:15:42.985717Z","steps":["trace[948286263] 'count revisions from in-memory index tree' (duration: 187.656866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.985926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.767559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.985952Z","caller":"traceutil/trace.go:171","msg":"trace[141959665] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"187.82102ms","start":"2026-05-05T22:15:42.798124Z","end":"2026-05-05T22:15:42.985946Z","steps":["trace[141959665] 'range keys from in-memory index tree' (duration: 187.688987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.986035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.630306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.986056Z","caller":"traceutil/trace.go:171","msg":"trace[506110408] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"178.678658ms","start":"2026-05-05T22:15:42.807372Z","end":"2026-05-05T22:15:42.986051Z","steps":["trace[506110408] 'count revisions from in-memory index tree' (duration: 178.567225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.986154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.656107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.986177Z","caller":"traceutil/trace.go:171","msg":"trace[1296772875] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"178.697428ms","start":"2026-05-05T22:15:42.807474Z","end":"2026-05-05T22:15:42.986171Z","steps":["trace[1296772875] 'range keys from in-memory index tree' (duration: 178.620916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.986253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.746976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.986278Z","caller":"traceutil/trace.go:171","msg":"trace[1973440578] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"167.783238ms","start":"2026-05-05T22:15:42.818489Z","end":"2026-05-05T22:15:42.986272Z","steps":["trace[1973440578] 'count revisions from in-memory index tree' (duration: 167.713715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.987104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.674471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.987177Z","caller":"traceutil/trace.go:171","msg":"trace[1719248368] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"156.749633ms","start":"2026-05-05T22:15:42.830408Z","end":"2026-05-05T22:15:42.987158Z","steps":["trace[1719248368] 'range keys from in-memory index tree' (duration: 156.604769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.987399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.952088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.98745Z","caller":"traceutil/trace.go:171","msg":"trace[113911557] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"157.023841ms","start":"2026-05-05T22:15:42.830411Z","end":"2026-05-05T22:15:42.987435Z","steps":["trace[113911557] 'count revisions from in-memory index tree' (duration: 156.875096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.987617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.971307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.987662Z","caller":"traceutil/trace.go:171","msg":"trace[1059066616] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"141.04692ms","start":"2026-05-05T22:15:42.846602Z","end":"2026-05-05T22:15:42.987649Z","steps":["trace[1059066616] 'range keys from in-memory index tree' (duration: 140.894545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.987778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.902476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.98782Z","caller":"traceutil/trace.go:171","msg":"trace[982969856] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"140.957037ms","start":"2026-05-05T22:15:42.846852Z","end":"2026-05-05T22:15:42.987809Z","steps":["trace[982969856] 'count revisions from in-memory index tree' (duration: 140.871455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.987893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.717197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T22:15:42.986384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.811648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.987964Z","caller":"traceutil/trace.go:171","msg":"trace[1895251130] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"169.421032ms","start":"2026-05-05T22:15:42.818534Z","end":"2026-05-05T22:15:42.987955Z","steps":["trace[1895251130] 'range keys from in-memory index tree' (duration: 167.751597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.98797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.890092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.98801Z","caller":"traceutil/trace.go:171","msg":"trace[190262235] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"125.930153ms","start":"2026-05-05T22:15:42.86207Z","end":"2026-05-05T22:15:42.988Z","steps":["trace[190262235] 'range keys from in-memory index tree' (duration: 125.742648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.988216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.137566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.988267Z","caller":"traceutil/trace.go:171","msg":"trace[211801659] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"115.226857ms","start":"2026-05-05T22:15:42.873028Z","end":"2026-05-05T22:15:42.988255Z","steps":["trace[211801659] 'range keys from in-memory index tree' (duration: 115.071893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.988471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.326361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.988519Z","caller":"traceutil/trace.go:171","msg":"trace[2040578117] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"115.418233ms","start":"2026-05-05T22:15:42.873089Z","end":"2026-05-05T22:15:42.988507Z","steps":["trace[2040578117] 'count revisions from in-memory index tree' (duration: 115.259189ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:42.987933Z","caller":"traceutil/trace.go:171","msg":"trace[960729441] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"125.777739ms","start":"2026-05-05T22:15:42.862144Z","end":"2026-05-05T22:15:42.987922Z","steps":["trace[960729441] 'count revisions from in-memory index tree' (duration: 125.686056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.988701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.588266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.988739Z","caller":"traceutil/trace.go:171","msg":"trace[1081203100] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"103.650308ms","start":"2026-05-05T22:15:42.885077Z","end":"2026-05-05T22:15:42.988727Z","steps":["trace[1081203100] 'count revisions from in-memory index tree' (duration: 103.523345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.988942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.668809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:42.988996Z","caller":"traceutil/trace.go:171","msg":"trace[1914779742] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"103.759221ms","start":"2026-05-05T22:15:42.885224Z","end":"2026-05-05T22:15:42.988983Z","steps":["trace[1914779742] 'range keys from in-memory index tree' (duration: 103.548136ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:17.117248Z","caller":"traceutil/trace.go:171","msg":"trace[896070918] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"111.664564ms","start":"2026-05-05T22:16:17.00556Z","end":"2026-05-05T22:16:17.117225Z","steps":["trace[896070918] 'process raft request' (duration: 111.53532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:17.629754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.723652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:16:17.630084Z","caller":"traceutil/trace.go:171","msg":"trace[1235880333] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"413.448267ms","start":"2026-05-05T22:16:17.21662Z","end":"2026-05-05T22:16:17.630068Z","steps":["trace[1235880333] 'read index received' (duration: 212.018115ms)","trace[1235880333] 'applied index is now lower than readState.Index' (duration: 201.429322ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:17.630081Z","caller":"traceutil/trace.go:171","msg":"trace[441892313] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"453.710992ms","start":"2026-05-05T22:16:17.176323Z","end":"2026-05-05T22:16:17.630034Z","steps":["trace[441892313] 'process raft request' (duration: 252.301781ms)","trace[441892313] 'compare' (duration: 200.599289ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:16:17.630248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.617752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jtbfw\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-05T22:16:17.630203Z","caller":"traceutil/trace.go:171","msg":"trace[468903858] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"453.435205ms","start":"2026-05-05T22:16:17.17669Z","end":"2026-05-05T22:16:17.630125Z","steps":["trace[468903858] 'process raft request' (duration: 453.290891ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:17.630275Z","caller":"traceutil/trace.go:171","msg":"trace[2138874987] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"452.356775ms","start":"2026-05-05T22:16:17.177866Z","end":"2026-05-05T22:16:17.630222Z","steps":["trace[2138874987] 'process raft request' (duration: 452.16465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:17.630278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:17.176295Z","time spent":"453.888997ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:16:17.630313Z","caller":"traceutil/trace.go:171","msg":"trace[1303031241] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jtbfw; range_end:; response_count:1; response_revision:584; }","duration":"413.728105ms","start":"2026-05-05T22:16:17.216565Z","end":"2026-05-05T22:16:17.630293Z","steps":["trace[1303031241] 'agreement among raft nodes before linearized reading' (duration: 413.581411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:17.630427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:17.216551Z","time spent":"413.863109ms","remote":"127.0.0.1:42846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-jtbfw\" "} {"level":"info","ts":"2026-05-05T22:16:17.630114Z","caller":"traceutil/trace.go:171","msg":"trace[845972021] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"453.401734ms","start":"2026-05-05T22:16:17.176678Z","end":"2026-05-05T22:16:17.63008Z","steps":["trace[845972021] 'process raft request' (duration: 453.255119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:17.63062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:17.176673Z","time spent":"453.658631ms","remote":"127.0.0.1:42846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:17.63069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:17.176649Z","time spent":"453.948829ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:16:17.630815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:17.17785Z","time spent":"452.512819ms","remote":"127.0.0.1:42896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:30.768551Z","caller":"traceutil/trace.go:171","msg":"trace[1874566297] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"170.129179ms","start":"2026-05-05T22:17:30.598385Z","end":"2026-05-05T22:17:30.768514Z","steps":["trace[1874566297] 'process raft request' (duration: 169.846882ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:30.768935Z","caller":"traceutil/trace.go:171","msg":"trace[977874851] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"169.884842ms","start":"2026-05-05T22:17:30.598708Z","end":"2026-05-05T22:17:30.768593Z","steps":["trace[977874851] 'process raft request' (duration: 169.649776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:31.223969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.524161ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T22:17:31.224054Z","caller":"traceutil/trace.go:171","msg":"trace[599513605] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"145.978972ms","start":"2026-05-05T22:17:31.07806Z","end":"2026-05-05T22:17:31.224039Z","steps":["trace[599513605] 'read index received' (duration: 11.324898ms)","trace[599513605] 'applied index is now lower than readState.Index' (duration: 134.652794ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:31.224153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.091745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:31.224182Z","caller":"traceutil/trace.go:171","msg":"trace[860974173] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1034; }","duration":"146.125546ms","start":"2026-05-05T22:17:31.078045Z","end":"2026-05-05T22:17:31.224171Z","steps":["trace[860974173] 'agreement among raft nodes before linearized reading' (duration: 146.069754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:31.22427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.741236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T22:17:31.224324Z","caller":"traceutil/trace.go:171","msg":"trace[1246779018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1034; }","duration":"109.842639ms","start":"2026-05-05T22:17:31.114468Z","end":"2026-05-05T22:17:31.224311Z","steps":["trace[1246779018] 'agreement among raft nodes before linearized reading' (duration: 109.679454ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:03.105504Z","caller":"traceutil/trace.go:171","msg":"trace[806531214] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"109.168071ms","start":"2026-05-05T22:18:02.996309Z","end":"2026-05-05T22:18:03.105477Z","steps":["trace[806531214] 'read index received' (duration: 108.915124ms)","trace[806531214] 'applied index is now lower than readState.Index' (duration: 251.987µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:03.105584Z","caller":"traceutil/trace.go:171","msg":"trace[806019800] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"159.446009ms","start":"2026-05-05T22:18:02.946106Z","end":"2026-05-05T22:18:03.105552Z","steps":["trace[806019800] 'process raft request' (duration: 159.210783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:03.105688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.372886ms","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-05T22:18:03.105723Z","caller":"traceutil/trace.go:171","msg":"trace[1818391633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"109.447539ms","start":"2026-05-05T22:18:02.996266Z","end":"2026-05-05T22:18:03.105714Z","steps":["trace[1818391633] 'agreement among raft nodes before linearized reading' (duration: 109.282894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:03.105728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.396587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T22:18:03.10589Z","caller":"traceutil/trace.go:171","msg":"trace[1044966539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"109.608413ms","start":"2026-05-05T22:18:02.996268Z","end":"2026-05-05T22:18:03.105877Z","steps":["trace[1044966539] 'agreement among raft nodes before linearized reading' (duration: 109.337506ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:03.274725Z","caller":"traceutil/trace.go:171","msg":"trace[1631327046] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"164.334241ms","start":"2026-05-05T22:18:03.110355Z","end":"2026-05-05T22:18:03.274689Z","steps":["trace[1631327046] 'process raft request' (duration: 124.018074ms)","trace[1631327046] 'compare' (duration: 39.973348ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:03.292574Z","caller":"traceutil/trace.go:171","msg":"trace[699553701] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"181.797946ms","start":"2026-05-05T22:18:03.110754Z","end":"2026-05-05T22:18:03.292552Z","steps":["trace[699553701] 'process raft request' (duration: 181.668293ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:21:58.668962Z","caller":"traceutil/trace.go:171","msg":"trace[1419938001] linearizableReadLoop","detail":"{readStateIndex:2456; appliedIndex:2455; }","duration":"266.360503ms","start":"2026-05-05T22:21:58.402583Z","end":"2026-05-05T22:21:58.668944Z","steps":["trace[1419938001] 'read index received' (duration: 266.224349ms)","trace[1419938001] 'applied index is now lower than readState.Index' (duration: 135.234µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:21:58.668994Z","caller":"traceutil/trace.go:171","msg":"trace[1530724198] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"376.27887ms","start":"2026-05-05T22:21:58.292682Z","end":"2026-05-05T22:21:58.668961Z","steps":["trace[1530724198] 'process raft request' (duration: 376.142687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:21:58.669184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:21:58.292663Z","time spent":"376.423604ms","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:21:58.669199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.061857ms","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-05T22:21:58.669241Z","caller":"traceutil/trace.go:171","msg":"trace[825354205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2361; }","duration":"230.142619ms","start":"2026-05-05T22:21:58.439085Z","end":"2026-05-05T22:21:58.669228Z","steps":["trace[825354205] 'agreement among raft nodes before linearized reading' (duration: 229.979704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:21:58.669365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.706422ms","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-05T22:21:58.669385Z","caller":"traceutil/trace.go:171","msg":"trace[564476091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2361; }","duration":"209.767534ms","start":"2026-05-05T22:21:58.45961Z","end":"2026-05-05T22:21:58.669378Z","steps":["trace[564476091] 'agreement among raft nodes before linearized reading' (duration: 209.678781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:21:58.669477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.899357ms","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-05T22:21:58.669499Z","caller":"traceutil/trace.go:171","msg":"trace[984505654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2361; }","duration":"266.957099ms","start":"2026-05-05T22:21:58.402535Z","end":"2026-05-05T22:21:58.669492Z","steps":["trace[984505654] 'agreement among raft nodes before linearized reading' (duration: 266.896717ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:00.733965Z","caller":"traceutil/trace.go:171","msg":"trace[340840396] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"406.039357ms","start":"2026-05-05T22:22:00.3279Z","end":"2026-05-05T22:22:00.733939Z","steps":["trace[340840396] 'process raft request' (duration: 405.832191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:00.734085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:00.327887Z","time spent":"406.13673ms","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:22:02.959912Z","caller":"traceutil/trace.go:171","msg":"trace[768074763] transaction","detail":"{read_only:false; response_revision:2380; number_of_response:1; }","duration":"211.549165ms","start":"2026-05-05T22:22:02.748335Z","end":"2026-05-05T22:22:02.959884Z","steps":["trace[768074763] 'process raft request' (duration: 211.388011ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:02.960526Z","caller":"traceutil/trace.go:171","msg":"trace[372412652] transaction","detail":"{read_only:false; response_revision:2381; number_of_response:1; }","duration":"210.288659ms","start":"2026-05-05T22:22:02.750213Z","end":"2026-05-05T22:22:02.960502Z","steps":["trace[372412652] 'process raft request' (duration: 210.100624ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:02.960772Z","caller":"traceutil/trace.go:171","msg":"trace[1862751784] transaction","detail":"{read_only:false; response_revision:2382; number_of_response:1; }","duration":"209.975651ms","start":"2026-05-05T22:22:02.750778Z","end":"2026-05-05T22:22:02.960754Z","steps":["trace[1862751784] 'process raft request' (duration: 209.672313ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:02.96109Z","caller":"traceutil/trace.go:171","msg":"trace[1885030158] transaction","detail":"{read_only:false; response_revision:2384; number_of_response:1; }","duration":"209.108657ms","start":"2026-05-05T22:22:02.751965Z","end":"2026-05-05T22:22:02.961074Z","steps":["trace[1885030158] 'process raft request' (duration: 209.047586ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:02.961274Z","caller":"traceutil/trace.go:171","msg":"trace[1489354541] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"209.500308ms","start":"2026-05-05T22:22:02.751628Z","end":"2026-05-05T22:22:02.961128Z","steps":["trace[1489354541] 'process raft request' (duration: 209.061476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:04.08819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278620198291701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:22:04.588408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278620198291701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:22:05.089024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278620198291701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:22:05.590166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278620198291701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:22:05.894582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.307322983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-05T22:22:05.894672Z","caller":"traceutil/trace.go:171","msg":"trace[184934178] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.307461868s","start":"2026-05-05T22:22:03.587193Z","end":"2026-05-05T22:22:05.894655Z","steps":["trace[184934178] 'agreement among raft nodes before linearized reading' (duration: 2.307340394s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.894701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:03.587183Z","time spent":"2.307512019s","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:22:05.904735Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.329537509s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T22:22:05.905197Z","caller":"traceutil/trace.go:171","msg":"trace[1281202328] linearizableReadLoop","detail":"{readStateIndex:2481; appliedIndex:2480; }","duration":"2.317954858s","start":"2026-05-05T22:22:03.587217Z","end":"2026-05-05T22:22:05.905172Z","steps":["trace[1281202328] 'read index received' (duration: 2.317922827s)","trace[1281202328] 'applied index is now lower than readState.Index' (duration: 30.851µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:05.905277Z","caller":"traceutil/trace.go:171","msg":"trace[1750543654] transaction","detail":"{read_only:false; response_revision:2385; number_of_response:1; }","duration":"2.330158816s","start":"2026-05-05T22:22:03.575091Z","end":"2026-05-05T22:22:05.90525Z","steps":["trace[1750543654] 'process raft request' (duration: 2.329906939s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.905939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:03.575073Z","time spent":"2.330795055s","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:22:05.905997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.738443ms","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-05T22:22:05.906027Z","caller":"traceutil/trace.go:171","msg":"trace[473079901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2386; }","duration":"939.778744ms","start":"2026-05-05T22:22:04.96624Z","end":"2026-05-05T22:22:05.906018Z","steps":["trace[473079901] 'agreement among raft nodes before linearized reading' (duration: 939.691051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.906057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.966228Z","time spent":"939.823955ms","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T22:22:05.906063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78485274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-tmqwb\" ","response":"range_response_count:1 size:13723"} {"level":"info","ts":"2026-05-05T22:22:05.906089Z","caller":"traceutil/trace.go:171","msg":"trace[582600720] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-tmqwb; range_end:; response_count:1; response_revision:2386; }","duration":"1.784875381s","start":"2026-05-05T22:22:04.121205Z","end":"2026-05-05T22:22:05.906081Z","steps":["trace[582600720] 'agreement among raft nodes before linearized reading' (duration: 1.784811209s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.906109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.12116Z","time spent":"1.784944303s","remote":"127.0.0.1:42846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":13747,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-tmqwb\" "} {"level":"warn","ts":"2026-05-05T22:22:05.905997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.318642447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-05T22:22:05.906174Z","caller":"traceutil/trace.go:171","msg":"trace[112640132] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2386; }","duration":"2.318823422s","start":"2026-05-05T22:22:03.587342Z","end":"2026-05-05T22:22:05.906166Z","steps":["trace[112640132] 'agreement among raft nodes before linearized reading' (duration: 2.318535694s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.906188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.123825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-05-05T22:22:05.9062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:03.587297Z","time spent":"2.318895125s","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-05T22:22:05.906253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.838290901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-05T22:22:05.906256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.02737ms","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-05T22:22:05.906274Z","caller":"traceutil/trace.go:171","msg":"trace[280782897] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2386; }","duration":"1.838337803s","start":"2026-05-05T22:22:04.067928Z","end":"2026-05-05T22:22:05.906265Z","steps":["trace[280782897] 'agreement among raft nodes before linearized reading' (duration: 1.838282391s)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:05.906221Z","caller":"traceutil/trace.go:171","msg":"trace[457378724] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2386; }","duration":"166.185727ms","start":"2026-05-05T22:22:05.740021Z","end":"2026-05-05T22:22:05.906207Z","steps":["trace[457378724] 'agreement among raft nodes before linearized reading' (duration: 166.115695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.906299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.067912Z","time spent":"1.838382354s","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T22:22:05.906362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.318305668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:05.90638Z","caller":"traceutil/trace.go:171","msg":"trace[1091870974] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2386; }","duration":"2.318332569s","start":"2026-05-05T22:22:03.58804Z","end":"2026-05-05T22:22:05.906373Z","steps":["trace[1091870974] 'agreement among raft nodes before linearized reading' (duration: 2.318301118s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.906354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.155844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-05T22:22:05.906401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:03.588035Z","time spent":"2.318359839s","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:22:05.906181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.45942ms","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-05T22:22:05.906415Z","caller":"traceutil/trace.go:171","msg":"trace[469705134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2386; }","duration":"940.241417ms","start":"2026-05-05T22:22:04.966164Z","end":"2026-05-05T22:22:05.906406Z","steps":["trace[469705134] 'agreement among raft nodes before linearized reading' (duration: 940.130413ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:05.906281Z","caller":"traceutil/trace.go:171","msg":"trace[1840732828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2386; }","duration":"940.057521ms","start":"2026-05-05T22:22:04.966215Z","end":"2026-05-05T22:22:05.906273Z","steps":["trace[1840732828] 'agreement among raft nodes before linearized reading' (duration: 939.990049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.906832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.966212Z","time spent":"940.610446ms","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T22:22:05.906441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.966136Z","time spent":"940.298558ms","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T22:22:05.906433Z","caller":"traceutil/trace.go:171","msg":"trace[981906286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2386; }","duration":"941.744268ms","start":"2026-05-05T22:22:04.964682Z","end":"2026-05-05T22:22:05.906426Z","steps":["trace[981906286] 'agreement among raft nodes before linearized reading' (duration: 941.273475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.910562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.964646Z","time spent":"945.858492ms","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:22:07.345925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.345699426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-tmqwb\" ","response":"range_response_count:1 size:13723"} {"level":"warn","ts":"2026-05-05T22:22:07.345975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.938672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-05T22:22:07.345993Z","caller":"traceutil/trace.go:171","msg":"trace[831697011] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-tmqwb; range_end:; response_count:1; response_revision:2396; }","duration":"1.345801969s","start":"2026-05-05T22:22:06.000171Z","end":"2026-05-05T22:22:07.345973Z","steps":["trace[831697011] 'range keys from in-memory index tree' (duration: 1.345508061s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.345999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.16099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-05T22:22:07.346032Z","caller":"traceutil/trace.go:171","msg":"trace[1374325035] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2396; }","duration":"771.033615ms","start":"2026-05-05T22:22:06.574984Z","end":"2026-05-05T22:22:07.346018Z","steps":["trace[1374325035] 'range keys from in-memory index tree' (duration: 770.816939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.346033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.000147Z","time spent":"1.345879271s","remote":"127.0.0.1:42846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":13747,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-tmqwb\" "} {"level":"warn","ts":"2026-05-05T22:22:07.346059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.574968Z","time spent":"771.085656ms","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-05T22:22:07.34606Z","caller":"traceutil/trace.go:171","msg":"trace[729496468] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2396; }","duration":"766.222492ms","start":"2026-05-05T22:22:06.57982Z","end":"2026-05-05T22:22:07.346042Z","steps":["trace[729496468] 'range keys from in-memory index tree' (duration: 766.050637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.346107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.579795Z","time spent":"766.299084ms","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T22:22:07.346205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.908849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:07.346247Z","caller":"traceutil/trace.go:171","msg":"trace[75321893] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2396; }","duration":"455.985861ms","start":"2026-05-05T22:22:06.89025Z","end":"2026-05-05T22:22:07.346236Z","steps":["trace[75321893] 'count revisions from in-memory index tree' (duration: 455.795156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.346283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.890234Z","time spent":"456.040302ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:22:07.346323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.356422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:07.346379Z","caller":"traceutil/trace.go:171","msg":"trace[1210181925] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2396; }","duration":"179.455615ms","start":"2026-05-05T22:22:07.166907Z","end":"2026-05-05T22:22:07.346363Z","steps":["trace[1210181925] 'count revisions from in-memory index tree' (duration: 179.28089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.346438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.478678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:22:07.346458Z","caller":"traceutil/trace.go:171","msg":"trace[1878414477] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2396; }","duration":"310.542849ms","start":"2026-05-05T22:22:07.035909Z","end":"2026-05-05T22:22:07.346452Z","steps":["trace[1878414477] 'count revisions from in-memory index tree' (duration: 310.365854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.346469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.972604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T22:22:07.346477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:07.035888Z","time spent":"310.583791ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:22:07.346497Z","caller":"traceutil/trace.go:171","msg":"trace[1644167114] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2396; }","duration":"415.030305ms","start":"2026-05-05T22:22:06.931459Z","end":"2026-05-05T22:22:07.346489Z","steps":["trace[1644167114] 'count revisions from in-memory index tree' (duration: 414.925603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.34652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.931445Z","time spent":"415.068647ms","remote":"127.0.0.1:42826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:22:07.946494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.539501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-tmqwb\" ","response":"range_response_count:1 size:13723"} {"level":"info","ts":"2026-05-05T22:22:07.946568Z","caller":"traceutil/trace.go:171","msg":"trace[399258516] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-tmqwb; range_end:; response_count:1; response_revision:2398; }","duration":"445.650234ms","start":"2026-05-05T22:22:07.5009Z","end":"2026-05-05T22:22:07.94655Z","steps":["trace[399258516] 'range keys from in-memory index tree' (duration: 445.323775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.946679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:07.500884Z","time spent":"445.785687ms","remote":"127.0.0.1:42846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":13747,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-tmqwb\" "}