* Re: [PATCH] perf vendor events power10: Adds 24x7 nest metric events for power10 platform
From: kajoljain @ 2021-06-28 6:28 UTC (permalink / raw)
To: Paul A. Clarke
Cc: ravi.bangoria, atrajeev, rnsastry, linuxppc-dev, linux-kernel,
acme, linux-perf-users, maddy, jolsa
In-Reply-To: <20210625132151.GC142768@li-24c3614c-2adc-11b2-a85c-85f334518bdb.ibm.com>
On 6/25/21 6:51 PM, Paul A. Clarke wrote:
> On Fri, Jun 25, 2021 at 05:29:48PM +0530, Kajol Jain wrote:
>> Patch adds 24x7 nest metric events for POWER10.
>>
>> Signed-off-by: Kajol Jain <kjain@linux.ibm.com>
>> ---
>> .../arch/powerpc/power10/nest_metrics.json | 491 ++++++++++++++++++
>> 1 file changed, 491 insertions(+)
>> create mode 100644 tools/perf/pmu-events/arch/powerpc/power10/nest_metrics.json
>>
>> diff --git a/tools/perf/pmu-events/arch/powerpc/power10/nest_metrics.json b/tools/perf/pmu-events/arch/powerpc/power10/nest_metrics.json
>> new file mode 100644
>> index 000000000000..b79046cd8b09
>> --- /dev/null
>> +++ b/tools/perf/pmu-events/arch/powerpc/power10/nest_metrics.json
>> @@ -0,0 +1,491 @@
>> +[
>> + {
>> + "MetricName": "VEC_GROUP_PUMP_RETRY_RATIO_P01",
>> + "BriefDescription": "VEC_GROUP_PUMP_RETRY_RATIO_P01",
>
> Is it possible to get better descriptions than just a restatement of the
> name, or no description at all?
>
> This comment obviously applies to almost all of the metrics herein.
Hi Paul,
Thanks for reviewing the patch. Sure I will remove description part for now.
>
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_VG_PUMP01\\,chip\\=?@ / hv_24x7@PM_PB_VG_PUMP01\\,chip\\=?@) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "VEC_GROUP_PUMP_RETRY_RATIO_P23",
>> + "BriefDescription": "VEC_GROUP_PUMP_RETRY_RATIO_P23",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_VG_PUMP23\\,chip\\=?@ / hv_24x7@PM_PB_VG_PUMP23\\,chip\\=?@) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "LOCAL_NODE_PUMP_RETRY_RATIO_P01",
>> + "BriefDescription": "LOCAL_NODE_PUMP_RETRY_RATIO_P01",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_LNS_PUMP01\\,chip\\=?@ / hv_24x7@PM_PB_LNS_PUMP01\\,chip\\=?@) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "LOCAL_NODE_PUMP_RETRY_RATIO_P23",
>> + "BriefDescription": "LOCAL_NODE_PUMP_RETRY_RATIO_P23",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_LNS_PUMP23\\,chip\\=?@ / hv_24x7@PM_PB_LNS_PUMP23\\,chip\\=?@) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "GROUP_PUMP_RETRY_RATIO_P01",
>> + "BriefDescription": "GROUP_PUMP_RETRY_RATIO_P01",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_GROUP_PUMP01\\,chip\\=?@ / hv_24x7@PM_PB_GROUP_PUMP01\\,chip\\=?@) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "GROUP_PUMP_RETRY_RATIO_P23",
>> + "BriefDescription": "GROUP_PUMP_RETRY_RATIO_P23",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_GROUP_PUMP23\\,chip\\=?@ / hv_24x7@PM_PB_GROUP_PUMP23\\,chip\\=?@) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_GROUP_PUMPS_P01",
>> + "BriefDescription": "TOTAL_GROUP_PUMPS_P01(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_GROUP_PUMP01\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_GROUP_PUMPS_P23",
>> + "BriefDescription": "TOTAL_GROUP_PUMPS_P23(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_GROUP_PUMP23\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_GROUP_PUMPS_RETRIES_P01",
>> + "BriefDescription": "TOTAL_GROUP_PUMPS_RETRIES_P01(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_GROUP_PUMP01\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_GROUP_PUMPS_RETRIES_P23",
>> + "BriefDescription": "TOTAL_GROUP_PUMPS_RETRIES_P23(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_GROUP_PUMP23\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "REMOTE_NODE_PUMPS_RETRIES_RATIO_P01",
>> + "BriefDescription": "REMOTE_NODE_PUMPS_RETRIES_RATIO_P01",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_RNS_PUMP01\\,chip\\=?@ / hv_24x7@PM_PB_RNS_PUMP01\\,chip\\=?@) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "REMOTE_NODE_PUMPS_RETRIES_RATIO_P23",
>> + "BriefDescription": "REMOTE_NODE_PUMPS_RETRIES_RATIO_P23",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_RNS_PUMP23\\,chip\\=?@ / hv_24x7@PM_PB_RNS_PUMP23\\,chip\\=?@) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_VECTOR_GROUP_PUMPS_P01",
>> + "BriefDescription": "TOTAL_VECTOR_GROUP_PUMPS_P01(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_VG_PUMP01\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_VECTOR_GROUP_PUMPS_P23",
>> + "BriefDescription": "TOTAL_VECTOR_GROUP_PUMPS_P23(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_VG_PUMP23\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_LOCAL_NODE_PUMPS_P01",
>> + "BriefDescription": "TOTAL_LOCAL_NODE_PUMPS_P01(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_LNS_PUMP01\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_LOCAL_NODE_PUMPS_P23",
>> + "BriefDescription": "TOTAL_LOCAL_NODE_PUMPS_P23(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_LNS_PUMP23\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_VECTOR_GROUP_PUMPS_RETRIES_P01",
>> + "BriefDescription": "TOTAL_VECTOR_GROUP_PUMPS_RETRIES_P01(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_VG_PUMP01\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_VECTOR_GROUP_PUMPS_RETRIES_P23",
>> + "BriefDescription": "TOTAL_VECTOR_GROUP_PUMPS_RETRIES_P23(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_VG_PUMP23\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_LOCAL_NODE_PUMPS_RETRIES_P01",
>> + "BriefDescription": "TOTAL_LOCAL_NODE_PUMPS_RETRIES_P01(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_LNS_PUMP01\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_LOCAL_NODE_PUMPS_RETRIES_P23",
>> + "BriefDescription": "TOTAL_LOCAL_NODE_PUMPS_RETRIES_P23(PER-CYC)",
>> + "MetricExpr": "(hv_24x7@PM_PB_RTY_LNS_PUMP23\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_REMOTE_NODE_PUMPS_P01",
>> + "BriefDescription": "TOTAL_REMOTE_NODE_PUMPS_P01",
>> + "MetricExpr": "(hv_24x7@PM_PB_RNS_PUMP01\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_REMOTE_NODE_PUMPS_P23",
>> + "BriefDescription": "TOTAL_REMOTE_NODE_PUMPS_P23",
>> + "MetricExpr": "(hv_24x7@PM_PB_RNS_PUMP23\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_NEAR_NODE_PUMPS_P01",
>> + "BriefDescription": "TOTAL_NEAR_NODE_PUMPS_P01",
>> + "MetricExpr": "(hv_24x7@PM_PB_NNS_PUMP01\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_NEAR_NODE_PUMPS_P23",
>> + "BriefDescription": "TOTAL_NEAR_NODE_PUMPS_P23",
>> + "MetricExpr": "(hv_24x7@PM_PB_NNS_PUMP23\\,chip\\=?@ / hv_24x7@PM_PAU_CYC\\,chip\\=?@)",
>> + "ScaleUnit": "4",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_INT_PB_BW",
>> + "BriefDescription": "TOTAL_INT_PB_BW",
>> + "MetricExpr": "(hv_24x7@PM_PB_INT_DATA_XFER\\,chip\\=?@)",
>> + "ScaleUnit": "2.09MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK0_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "XLINK0_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK0_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_XLINK0_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_XLINK0_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK0_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK1_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "XLINK1_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK1_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_XLINK1_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_XLINK1_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK1_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK2_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "XLINK2_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK2_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_XLINK2_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_XLINK2_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK2_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK3_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "XLINK3_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK3_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_XLINK3_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_XLINK3_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK3_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK4_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "XLINK4_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK4_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_XLINK4_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_XLINK4_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK4_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK5_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "XLINK5_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK5_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_XLINK5_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_XLINK5_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK5_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK6_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "XLINK6_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK6_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_XLINK6_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_XLINK6_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK6_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK7_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "XLINK7_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK7_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_XLINK7_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_XLINK7_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK7_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK0_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "XLINK0_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK0_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_XLINK0_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_XLINK0_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK0_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK1_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "XLINK1_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK1_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_XLINK1_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_XLINK1_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK1_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK2_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "XLINK2_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK2_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_XLINK2_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_XLINK2_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK2_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK3_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "XLINK3_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK3_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_XLINK3_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_XLINK3_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK3_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK4_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "XLINK4_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK4_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_XLINK4_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_XLINK4_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK4_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK5_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "XLINK5_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK5_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_XLINK5_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_XLINK5_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK5_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK6_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "XLINK6_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK6_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_XLINK6_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_XLINK6_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK6_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "XLINK7_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "XLINK7_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_XLINK7_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_XLINK7_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_XLINK7_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_XLINK7_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK0_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "ALINK0_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK0_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_ALINK0_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_ALINK0_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK0_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK1_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "ALINK1_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK1_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_ALINK1_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_ALINK1_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK1_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK2_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "ALINK2_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK2_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_ALINK2_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_ALINK2_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK2_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK3_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "ALINK3_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK3_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_ALINK3_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_ALINK3_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK3_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK4_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "ALINK4_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK4_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_ALINK4_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_ALINK4_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK4_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK5_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "ALINK5_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK5_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_ALINK5_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_ALINK5_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK5_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK6_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "ALINK6_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK6_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_ALINK6_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_ALINK6_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK6_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK7_OUT_TOTAL_UTILIZATION",
>> + "BriefDescription": "ALINK7_OUT_TOTAL_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK7_OUT_ODD_TOTAL_UTIL\\,chip\\=?@ + hv_24x7@PM_ALINK7_OUT_EVEN_TOTAL_UTIL\\,chip\\=?@) / (hv_24x7@PM_ALINK7_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK7_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK0_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "ALINK0_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK0_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_ALINK0_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_ALINK0_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK0_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK1_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "ALINK1_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK1_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_ALINK1_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_ALINK1_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK1_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK2_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "ALINK2_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK2_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_ALINK2_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_ALINK2_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK2_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK3_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "ALINK3_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK3_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_ALINK3_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_ALINK3_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK3_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK4_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "ALINK4_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK4_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_ALINK4_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_ALINK4_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK4_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK5_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "ALINK5_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK5_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_ALINK5_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_ALINK5_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK5_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK6_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "ALINK6_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK6_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_ALINK6_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_ALINK6_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK6_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "ALINK7_OUT_DATA_UTILIZATION",
>> + "BriefDescription": "ALINK7_OUT_DATA_UTILIZATION",
>> + "MetricExpr": "((hv_24x7@PM_ALINK7_OUT_ODD_DATA\\,chip\\=?@ + hv_24x7@PM_ALINK7_OUT_EVEN_DATA\\,chip\\=?@) / (hv_24x7@PM_ALINK7_OUT_ODD_AVLBL_CYCLES\\,chip\\=?@ + hv_24x7@PM_ALINK7_OUT_EVEN_AVLBL_CYCLES\\,chip\\=?@)) * 100",
>> + "ScaleUnit": "1.063%",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_DATA_BANDWIDTH_TRANSFERRED_OVER_PB_PCI1",
>> + "BriefDescription": "TOTAL_DATA_BANDWIDTH_TRANSFERRED_OVER_PB_PCI1",
>> + "MetricExpr": "(hv_24x7@PM_PCI1_32B_INOUT\\,chip\\=?@)",
>> + "ScaleUnit": "3.28e-2MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_DATA_BANDWIDTH_TRANSFERRED_OVER_PB_PCI0",
>> + "BriefDescription": "TOTAL_DATA_BANDWIDTH_TRANSFERRED_OVER_PB_PCI0",
>> + "MetricExpr": "(hv_24x7@PM_PCI0_32B_INOUT\\,chip\\=?@)",
>> + "ScaleUnit": "3.28e-2MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_MCS_READ_BW_MC0_CHAN01",
>> + "BriefDescription": "TOTAL_MCS_READ_BW_MC0_CHAN01",
>> + "MetricExpr": "(hv_24x7@PM_MCS_128B_RD_DATA_BLOCKS_MC0_CHAN01\\,chip\\=?@)",
>> + "ScaleUnit": "5.24e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_MCS_READ_BW_MC1_CHAN01",
>> + "BriefDescription": "TOTAL_MCS_READ_BW_MC1_CHAN01",
>> + "MetricExpr": "(hv_24x7@PM_MCS_128B_RD_DATA_BLOCKS_MC1_CHAN01\\,chip\\=?@)",
>> + "ScaleUnit": "5.24e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_MCS_READ_BW_MC2_CHAN01",
>> + "BriefDescription": "TOTAL_MCS_READ_BW_MC2_CHAN01",
>> + "MetricExpr": "(hv_24x7@PM_MCS_128B_RD_DATA_BLOCKS_MC2_CHAN01\\,chip\\=?@)",
>> + "ScaleUnit": "5.24e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_MCS_READ_BW_MC3_CHAN01",
>> + "BriefDescription": "TOTAL_MCS_READ_BW_MC3_CHAN01",
>> + "MetricExpr": "(hv_24x7@PM_MCS_128B_RD_DATA_BLOCKS_MC3_CHAN01\\,chip\\=?@)",
>> + "ScaleUnit": "5.24e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_MCS_WRITE_BW_MC0_CHAN01",
>> + "BriefDescription": "TOTAL_MCS_WRITE_BW_MC0_CHAN01",
>> + "MetricExpr": "(hv_24x7@PM_MCS_64B_WR_DATA_BLOCKS_MC0_CHAN01\\,chip\\=?@)",
>> + "ScaleUnit": "2.6e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_MCS_WRITE_BW_MC1_CHAN01",
>> + "BriefDescription": "TOTAL_MCS_WRITE_BW_MC1_CHAN01",
>> + "MetricExpr": "(hv_24x7@PM_MCS_64B_WR_DATA_BLOCKS_MC1_CHAN01\\,chip\\=?@)",
>> + "ScaleUnit": "2.6e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_MCS_WRITE_BW_MC2_CHAN01",
>> + "BriefDescription": "TOTAL_MCS_WRITE_BW_MC2_CHAN01",
>> + "MetricExpr": "(hv_24x7@PM_MCS_64B_WR_DATA_BLOCKS_MC2_CHAN01\\,chip\\=?@)",
>> + "ScaleUnit": "2.6e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricName": "TOTAL_MCS_WRITE_BW_MC3_CHAN01",
>> + "BriefDescription": "TOTAL_MCS_WRITE_BW_MC3_CHAN01",
>> + "MetricExpr": "(hv_24x7@PM_MCS_64B_WR_DATA_BLOCKS_MC3_CHAN01\\,chip\\=?@)",
>> + "ScaleUnit": "2.6e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricExpr": "(hv_24x7@PM_MCS_128B_RD_DATA_BLOCKS_MC0_CHAN01\\,chip\\=?@ + hv_24x7@PM_MCS_128B_RD_DATA_BLOCKS_MC1_CHAN01\\,chip\\=?@ + hv_24x7@PM_MCS_128B_RD_DATA_BLOCKS_MC2_CHAN01\\,chip\\=?@ + hv_24x7@PM_MCS_128B_RD_DATA_BLOCKS_MC3_CHAN01\\,chip\\=?@)",
>> + "MetricName": "Memory_RD_BW_Chip",
>
> The pattern up until this point was "MetricName", then "BriefDescription",
> then "MetricExpr". I think it would be helpful to continue that here,
> and for the next two as well. That should include _having_ a description,
> obviously. :-)
>
Ok I will update it.
Thanks,
Kajol Jain
>> + "MetricGroup": "Memory_BW",
>> + "ScaleUnit": "5.24e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricExpr": "(hv_24x7@PM_MCS_64B_WR_DATA_BLOCKS_MC0_CHAN01\\,chip\\=?@ + hv_24x7@PM_MCS_64B_WR_DATA_BLOCKS_MC1_CHAN01\\,chip\\=?@ + hv_24x7@PM_MCS_64B_WR_DATA_BLOCKS_MC2_CHAN01\\,chip\\=?@ + hv_24x7@PM_MCS_64B_WR_DATA_BLOCKS_MC3_CHAN01\\,chip\\=?@ )",
>> + "MetricName": "Memory_WR_BW_Chip",
>> + "MetricGroup": "Memory_BW",
>> + "ScaleUnit": "2.6e-1MB",
>> + "AggregationMode": "PerChip"
>> + },
>> + {
>> + "MetricExpr": "(hv_24x7@PM_PAU_CYC\\,chip\\=?@ )",
>> + "MetricName": "PowerBUS_Frequency",
>> + "ScaleUnit": "2.56e-7GHz",
>> + "AggregationMode": "PerChip"
>> + }
>> +]
>> --
>
> PC
>
^ permalink raw reply
* [PATCH] perf script python: Fix buffer size to report iregs in perf script
From: Kajol Jain @ 2021-06-28 6:23 UTC (permalink / raw)
To: acme
Cc: ravi.bangoria, atrajeev, rnsastry, kjain, linuxppc-dev,
linux-kernel, linux-perf-users, maddy, pc, jolsa
Commit 48a1f565261d ("perf script python: Add more PMU fields
to event handler dict") added functionality to report fields like
weight, iregs, uregs etc via perf report.
That commit predefined buffer size to 512 bytes to print those fields.
But incase of powerpc, since we added extended regs support
in commits:
Commit 068aeea3773a ("perf powerpc: Support exposing Performance Monitor
Counter SPRs as part of extended regs")
Commit d735599a069f ("powerpc/perf: Add extended regs support for
power10 platform")
Now iregs can carry more bytes of data and this predefined buffer size
can result to data loss in perf script output.
Patch resolve this issue by making buffer size dynamic based on number
of registers needed to print. It also changed return type for function
"regs_map" from int to void, as the return value is not being used by
the caller function "set_regs_in_dict".
Fixes: 068aeea3773a ("perf powerpc: Support exposing Performance Monitor
Counter SPRs as part of extended regs")
Signed-off-by: Kajol Jain <kjain@linux.ibm.com>
---
.../util/scripting-engines/trace-event-python.c | 17 ++++++++++++-----
1 file changed, 12 insertions(+), 5 deletions(-)
diff --git a/tools/perf/util/scripting-engines/trace-event-python.c b/tools/perf/util/scripting-engines/trace-event-python.c
index 4e4aa4c97ac5..c8c9706b4643 100644
--- a/tools/perf/util/scripting-engines/trace-event-python.c
+++ b/tools/perf/util/scripting-engines/trace-event-python.c
@@ -687,7 +687,7 @@ static void set_sample_datasrc_in_dict(PyObject *dict,
_PyUnicode_FromString(decode));
}
-static int regs_map(struct regs_dump *regs, uint64_t mask, char *bf, int size)
+static void regs_map(struct regs_dump *regs, uint64_t mask, char *bf, int size)
{
unsigned int i = 0, r;
int printed = 0;
@@ -695,7 +695,7 @@ static int regs_map(struct regs_dump *regs, uint64_t mask, char *bf, int size)
bf[0] = 0;
if (!regs || !regs->regs)
- return 0;
+ return;
for_each_set_bit(r, (unsigned long *) &mask, sizeof(mask) * 8) {
u64 val = regs->regs[i++];
@@ -704,8 +704,6 @@ static int regs_map(struct regs_dump *regs, uint64_t mask, char *bf, int size)
"%5s:0x%" PRIx64 " ",
perf_reg_name(r), val);
}
-
- return printed;
}
static void set_regs_in_dict(PyObject *dict,
@@ -713,7 +711,16 @@ static void set_regs_in_dict(PyObject *dict,
struct evsel *evsel)
{
struct perf_event_attr *attr = &evsel->core.attr;
- char bf[512];
+
+ /*
+ * Here value 28 is a constant size which can be used to print
+ * one register value and its corresponds to:
+ * 16 chars is to specify 64 bit register in hexadecimal.
+ * 2 chars is for appending "0x" to the hexadecimal value and
+ * 10 chars is for register name.
+ */
+ int size = __sw_hweight64(attr->sample_regs_intr) * 28;
+ char bf[size];
regs_map(&sample->intr_regs, attr->sample_regs_intr, bf, sizeof(bf));
--
2.31.1
^ permalink raw reply related
* Re: [PATCH v3] mm: pagewalk: Fix walk for hugepage tables
From: Christophe Leroy @ 2021-06-28 6:19 UTC (permalink / raw)
To: Aneesh Kumar K.V, Steven Price, akpm, linux-mm
Cc: linux-arch, linux-kernel, Oliver O'Halloran, Paul Mackerras,
linuxppc-dev, dja
In-Reply-To: <87bl7qle4o.fsf@linux.ibm.com>
Le 28/06/2021 à 08:03, Aneesh Kumar K.V a écrit :
> Christophe Leroy <christophe.leroy@csgroup.eu> writes:
>
>> Pagewalk ignores hugepd entries and walk down the tables
>> as if it was traditionnal entries, leading to crazy result.
>
> But we do handle hugetlb separately
>
> if (vma && is_vm_hugetlb_page(vma)) {
> if (ops->hugetlb_entry)
> err = walk_hugetlb_range(start, end, walk);
> } else
> err = walk_pgd_range(start, end, walk);
>
> Are we using hugepd format for non hugetlb entries?
Yes, on the 8xx we use hugepd for 8M pages for linear mapping and for kasan shadow mapping (See
commit bb5f33c06940 ("Merge "Use hugepages to map kernel mem on 8xx" into next")
And I'm working on implementing huge VMAP with 8M pages, that will also make use of hugepd.
>
>>
>> Add walk_hugepd_range() and use it to walk hugepage tables.
>>
>> Signed-off-by: Christophe Leroy <christophe.leroy@csgroup.eu>
>> Reviewed-by: Steven Price <steven.price@arm.com>
>> ---
>> v3:
>> - Rebased on next-20210624 (no change since v2)
>> - Added Steven's Reviewed-by
>> - Sent as standalone for merge via mm
>>
>> v2:
>> - Add a guard for NULL ops->pte_entry
>> - Take mm->page_table_lock when walking hugepage table, as suggested by follow_huge_pd()
>> ---
>> mm/pagewalk.c | 58 ++++++++++++++++++++++++++++++++++++++++++++++-----
>> 1 file changed, 53 insertions(+), 5 deletions(-)
>>
>> diff --git a/mm/pagewalk.c b/mm/pagewalk.c
>> index e81640d9f177..9b3db11a4d1d 100644
>> --- a/mm/pagewalk.c
>> +++ b/mm/pagewalk.c
>> @@ -58,6 +58,45 @@ static int walk_pte_range(pmd_t *pmd, unsigned long addr, unsigned long end,
>> return err;
>> }
>>
>> +#ifdef CONFIG_ARCH_HAS_HUGEPD
>> +static int walk_hugepd_range(hugepd_t *phpd, unsigned long addr,
>> + unsigned long end, struct mm_walk *walk, int pdshift)
>> +{
>> + int err = 0;
>> + const struct mm_walk_ops *ops = walk->ops;
>> + int shift = hugepd_shift(*phpd);
>> + int page_size = 1 << shift;
>> +
>> + if (!ops->pte_entry)
>> + return 0;
>> +
>> + if (addr & (page_size - 1))
>> + return 0;
>> +
>> + for (;;) {
>> + pte_t *pte;
>> +
>> + spin_lock(&walk->mm->page_table_lock);
>> + pte = hugepte_offset(*phpd, addr, pdshift);
>> + err = ops->pte_entry(pte, addr, addr + page_size, walk);
>> + spin_unlock(&walk->mm->page_table_lock);
>> +
>> + if (err)
>> + break;
>> + if (addr >= end - page_size)
>> + break;
>> + addr += page_size;
>> + }
>> + return err;
>> +}
>> +#else
>> +static int walk_hugepd_range(hugepd_t *phpd, unsigned long addr,
>> + unsigned long end, struct mm_walk *walk, int pdshift)
>> +{
>> + return 0;
>> +}
>> +#endif
>> +
>> static int walk_pmd_range(pud_t *pud, unsigned long addr, unsigned long end,
>> struct mm_walk *walk)
>> {
>> @@ -108,7 +147,10 @@ static int walk_pmd_range(pud_t *pud, unsigned long addr, unsigned long end,
>> goto again;
>> }
>>
>> - err = walk_pte_range(pmd, addr, next, walk);
>> + if (is_hugepd(__hugepd(pmd_val(*pmd))))
>> + err = walk_hugepd_range((hugepd_t *)pmd, addr, next, walk, PMD_SHIFT);
>> + else
>> + err = walk_pte_range(pmd, addr, next, walk);
>> if (err)
>> break;
>> } while (pmd++, addr = next, addr != end);
>> @@ -157,7 +199,10 @@ static int walk_pud_range(p4d_t *p4d, unsigned long addr, unsigned long end,
>> if (pud_none(*pud))
>> goto again;
>>
>> - err = walk_pmd_range(pud, addr, next, walk);
>> + if (is_hugepd(__hugepd(pud_val(*pud))))
>> + err = walk_hugepd_range((hugepd_t *)pud, addr, next, walk, PUD_SHIFT);
>> + else
>> + err = walk_pmd_range(pud, addr, next, walk);
>> if (err)
>> break;
>> } while (pud++, addr = next, addr != end);
>> @@ -189,7 +234,9 @@ static int walk_p4d_range(pgd_t *pgd, unsigned long addr, unsigned long end,
>> if (err)
>> break;
>> }
>> - if (ops->pud_entry || ops->pmd_entry || ops->pte_entry)
>> + if (is_hugepd(__hugepd(p4d_val(*p4d))))
>> + err = walk_hugepd_range((hugepd_t *)p4d, addr, next, walk, P4D_SHIFT);
>> + else if (ops->pud_entry || ops->pmd_entry || ops->pte_entry)
>> err = walk_pud_range(p4d, addr, next, walk);
>> if (err)
>> break;
>> @@ -224,8 +271,9 @@ static int walk_pgd_range(unsigned long addr, unsigned long end,
>> if (err)
>> break;
>> }
>> - if (ops->p4d_entry || ops->pud_entry || ops->pmd_entry ||
>> - ops->pte_entry)
>> + if (is_hugepd(__hugepd(pgd_val(*pgd))))
>> + err = walk_hugepd_range((hugepd_t *)pgd, addr, next, walk, PGDIR_SHIFT);
>> + else if (ops->p4d_entry || ops->pud_entry || ops->pmd_entry || ops->pte_entry)
>> err = walk_p4d_range(pgd, addr, next, walk);
>> if (err)
>> break;
>> --
>> 2.25.0
^ permalink raw reply
* Re: [PATCH v3] mm: pagewalk: Fix walk for hugepage tables
From: Aneesh Kumar K.V @ 2021-06-28 6:03 UTC (permalink / raw)
To: Christophe Leroy, Steven Price, akpm, linux-mm
Cc: linux-arch, linux-kernel, Oliver O'Halloran, Paul Mackerras,
linuxppc-dev, dja
In-Reply-To: <38d04410700c8d02f28ba37e020b62c55d6f3d2c.1624597695.git.christophe.leroy@csgroup.eu>
Christophe Leroy <christophe.leroy@csgroup.eu> writes:
> Pagewalk ignores hugepd entries and walk down the tables
> as if it was traditionnal entries, leading to crazy result.
But we do handle hugetlb separately
if (vma && is_vm_hugetlb_page(vma)) {
if (ops->hugetlb_entry)
err = walk_hugetlb_range(start, end, walk);
} else
err = walk_pgd_range(start, end, walk);
Are we using hugepd format for non hugetlb entries?
>
> Add walk_hugepd_range() and use it to walk hugepage tables.
>
> Signed-off-by: Christophe Leroy <christophe.leroy@csgroup.eu>
> Reviewed-by: Steven Price <steven.price@arm.com>
> ---
> v3:
> - Rebased on next-20210624 (no change since v2)
> - Added Steven's Reviewed-by
> - Sent as standalone for merge via mm
>
> v2:
> - Add a guard for NULL ops->pte_entry
> - Take mm->page_table_lock when walking hugepage table, as suggested by follow_huge_pd()
> ---
> mm/pagewalk.c | 58 ++++++++++++++++++++++++++++++++++++++++++++++-----
> 1 file changed, 53 insertions(+), 5 deletions(-)
>
> diff --git a/mm/pagewalk.c b/mm/pagewalk.c
> index e81640d9f177..9b3db11a4d1d 100644
> --- a/mm/pagewalk.c
> +++ b/mm/pagewalk.c
> @@ -58,6 +58,45 @@ static int walk_pte_range(pmd_t *pmd, unsigned long addr, unsigned long end,
> return err;
> }
>
> +#ifdef CONFIG_ARCH_HAS_HUGEPD
> +static int walk_hugepd_range(hugepd_t *phpd, unsigned long addr,
> + unsigned long end, struct mm_walk *walk, int pdshift)
> +{
> + int err = 0;
> + const struct mm_walk_ops *ops = walk->ops;
> + int shift = hugepd_shift(*phpd);
> + int page_size = 1 << shift;
> +
> + if (!ops->pte_entry)
> + return 0;
> +
> + if (addr & (page_size - 1))
> + return 0;
> +
> + for (;;) {
> + pte_t *pte;
> +
> + spin_lock(&walk->mm->page_table_lock);
> + pte = hugepte_offset(*phpd, addr, pdshift);
> + err = ops->pte_entry(pte, addr, addr + page_size, walk);
> + spin_unlock(&walk->mm->page_table_lock);
> +
> + if (err)
> + break;
> + if (addr >= end - page_size)
> + break;
> + addr += page_size;
> + }
> + return err;
> +}
> +#else
> +static int walk_hugepd_range(hugepd_t *phpd, unsigned long addr,
> + unsigned long end, struct mm_walk *walk, int pdshift)
> +{
> + return 0;
> +}
> +#endif
> +
> static int walk_pmd_range(pud_t *pud, unsigned long addr, unsigned long end,
> struct mm_walk *walk)
> {
> @@ -108,7 +147,10 @@ static int walk_pmd_range(pud_t *pud, unsigned long addr, unsigned long end,
> goto again;
> }
>
> - err = walk_pte_range(pmd, addr, next, walk);
> + if (is_hugepd(__hugepd(pmd_val(*pmd))))
> + err = walk_hugepd_range((hugepd_t *)pmd, addr, next, walk, PMD_SHIFT);
> + else
> + err = walk_pte_range(pmd, addr, next, walk);
> if (err)
> break;
> } while (pmd++, addr = next, addr != end);
> @@ -157,7 +199,10 @@ static int walk_pud_range(p4d_t *p4d, unsigned long addr, unsigned long end,
> if (pud_none(*pud))
> goto again;
>
> - err = walk_pmd_range(pud, addr, next, walk);
> + if (is_hugepd(__hugepd(pud_val(*pud))))
> + err = walk_hugepd_range((hugepd_t *)pud, addr, next, walk, PUD_SHIFT);
> + else
> + err = walk_pmd_range(pud, addr, next, walk);
> if (err)
> break;
> } while (pud++, addr = next, addr != end);
> @@ -189,7 +234,9 @@ static int walk_p4d_range(pgd_t *pgd, unsigned long addr, unsigned long end,
> if (err)
> break;
> }
> - if (ops->pud_entry || ops->pmd_entry || ops->pte_entry)
> + if (is_hugepd(__hugepd(p4d_val(*p4d))))
> + err = walk_hugepd_range((hugepd_t *)p4d, addr, next, walk, P4D_SHIFT);
> + else if (ops->pud_entry || ops->pmd_entry || ops->pte_entry)
> err = walk_pud_range(p4d, addr, next, walk);
> if (err)
> break;
> @@ -224,8 +271,9 @@ static int walk_pgd_range(unsigned long addr, unsigned long end,
> if (err)
> break;
> }
> - if (ops->p4d_entry || ops->pud_entry || ops->pmd_entry ||
> - ops->pte_entry)
> + if (is_hugepd(__hugepd(pgd_val(*pgd))))
> + err = walk_hugepd_range((hugepd_t *)pgd, addr, next, walk, PGDIR_SHIFT);
> + else if (ops->p4d_entry || ops->pud_entry || ops->pmd_entry || ops->pte_entry)
> err = walk_p4d_range(pgd, addr, next, walk);
> if (err)
> break;
> --
> 2.25.0
^ permalink raw reply
* Re: [PATCH v3] mm: pagewalk: Fix walk for hugepage tables
From: Christophe Leroy @ 2021-06-28 5:56 UTC (permalink / raw)
To: Andrew Morton
Cc: linux-arch, linux-kernel, Steven Price, linux-mm, Paul Mackerras,
Oliver O'Halloran, linuxppc-dev, dja
In-Reply-To: <20210627181226.983d899cc30c02420e1a6af5@linux-foundation.org>
Le 28/06/2021 à 03:12, Andrew Morton a écrit :
> On Fri, 25 Jun 2021 05:10:12 +0000 (UTC) Christophe Leroy <christophe.leroy@csgroup.eu> wrote:
>
>> Pagewalk ignores hugepd entries and walk down the tables
>> as if it was traditionnal entries, leading to crazy result.
>>
>> Add walk_hugepd_range() and use it to walk hugepage tables.
>
> More details, please? I assume "crazy result" is userspace visible?
> For how long has this bug existed? Is a -stable backport needed? Has
> a Fixes: commit been identified? etcetera!
>
I discovered the problem while porting powerpc to generic page table dump.
The generic page table dump uses walk_page_range_novma() .
Yes, "crazy result" is that when dumping /sys/kernel/debug/kernel_page_tables, you get random
entries because at the time being the pagewalk code sees huge page directories as standard page tables.
The bug has always existed as far as I can see, but as no other architectures than powerpc use huge
page directories, it only pops up now when powerpc is trying to use that generic page walking code.
So I don't think it is worth a backport to -stable, and about a Fixes: tag I don't know.
IIUC, hugepd was introduced for the first time in mm by commit cbd34da7dc9a ("mm: move the powerpc
hugepd code to mm/gup.c")
Before that, hugepd was internal to powerpc.
I guess you are asking about Fixes: tag and backporting because of the patch subject.
Should I reword the page subject to something like "mm: enable the generic page walk code to walk
huge page directories" ?
^ permalink raw reply
* Re: [powerpc][next-20210625] Kernel warning(arch/powerpc/kernel/interrupt.c:518) during boot
From: Nicholas Piggin @ 2021-06-28 3:52 UTC (permalink / raw)
To: Sachin Sant; +Cc: linux-next, linuxppc-dev
In-Reply-To: <0C513B10-78DE-48F3-AD50-3A89225E20AF@linux.vnet.ibm.com>
Excerpts from Sachin Sant's message of June 27, 2021 9:23 pm:
>
>> On 27-Jun-2021, at 3:36 PM, Nicholas Piggin <npiggin@gmail.com> wrote:
>>>
>>> So there's definitely IRQMASK=0 and no MSR[EE]=0 in this frame, which is
>>> what the warning was.
>>>
>>> I'd say either something hasn't set PACA_IRQ_HARD_DIS properly, so EE
>>> doesn't get enabled when irqs are restored, or maybe the change to
>>> arch_local_irq_restore(). Less likely that the stack got messed up.
>>>
>>> Can you try run with CONFIG_PPC_IRQ_SOFT_MASK_DEBUG=y ?
>>
>> Nevermind, I think I've found the problem. Some code runs in the
>> implicit soft-mask region without expecting to be masked. Working
>> on a fix…
>
> :-) . I was able to recreate this after few attempts. It seem the warning isn’t
> always triggered during boot. I had to run a kernel compile operation after
> boot to trigger this warning again.
>
> In case its helpful here is the additional trace with PPC_IRQ_SOFT_MASK_DEBUG.
Thanks. I ended up being able to reproduce as well, quite frequently
with some extra debug checks that specifically catch more cases.
I've got a few patches under test right now, very stable so far. I'll
post them out if they survive a nother hour or two stress testing.
The problem is some code (e.g., ret_from_fork) now gets implicitly
soft-masked where that was not expecting to be. A masked interrupt might
hit, and then when it moves out of the implicit soft-mask region it
does not re-enable interrupts. Some types of pending interrupts will
clear MSR[EE], and that ends up causing this bug on the next interrupt
that happens.
Not a wonderful escape :\ thanks for finding it. The fixes aren't too
bad, fortunately.
Thanks,
Nick
>
> [ 92.106731] ------------[ cut here ]------------
> [ 92.106738] WARNING: CPU: 45 PID: 12757 at arch/powerpc/kernel/irq.c:255 arch_local_irq_restore+0x1d0/0x200
> [ 92.106753] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts vmx_crypto uio_pdrv_genirq uio sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
> [ 92.106828] CPU: 45 PID: 12757 Comm: sh Kdump: loaded Tainted: G W 5.13.0-rc7-next-20210625 #1
> [ 92.106841] NIP: c0000000000164d0 LR: c000000000cedaa8 CTR: 0000000000000000
> [ 92.106849] REGS: c00000008dfeb7e0 TRAP: 0700 Tainted: G W (5.13.0-rc7-next-20210625)
> [ 92.106859] MSR: 8000000002823033 <SF,VEC,VSX,FP,ME,IR,DR,RI,LE> CR: 28004222 XER: 00000000
> [ 92.106892] CFAR: c00000000001632c IRQMASK: 0
> GPR00: c000000000ceda98 c00000008dfeba80 c000000002921e00 0000000000000000
> GPR04: 0000000000000000 0000000000000000 0000000000000000 00000000000000ff
> GPR08: 0000000000000001 0000000000000000 0000000000000001 0000000000000017
> GPR12: 0000000024004822 c000000007fb9200 000000012efd81d4 000000012ee50000
> GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40
> GPR20: 00003fffa93f8000 0000000000000000 00003fffa93f9300 000000012efb1988
> GPR24: 000000012ee7fe7c 000000012efccba0 000000012ee50000 c00000008d5d7600
> GPR28: c0000000314c0bc0 c000000040d9f100 c0000008beb5861c 4b72201a3063fe13
> [ 92.107024] NIP [c0000000000164d0] arch_local_irq_restore+0x1d0/0x200
> [ 92.107035] LR [c000000000cedaa8] _raw_spin_unlock_irqrestore+0x88/0xb0
> [ 92.107047] Call Trace:
> [ 92.107052] [c00000008dfeba80] [c00000008dfebb50] 0xc00000008dfebb50 (unreliable)
> [ 92.107065] [c00000008dfebab0] [238c5bf052df0858] 0x238c5bf052df0858
> [ 92.107076] [c00000008dfebae0] [c0000000008178e8] get_random_u64+0x88/0x100
> [ 92.107090] [c00000008dfebb20] [c000000000020134] arch_randomize_brk+0xb4/0xd8
> [ 92.107105] [c00000008dfebb50] [c0000000005430b0] load_elf_binary+0xe70/0x1220
> [ 92.107119] [c00000008dfebc40] [c00000000047ded0] bprm_execve+0x410/0x800
> [ 92.107132] [c00000008dfebd10] [c00000000047e8ec] do_execveat_common.isra.44+0x21c/0x240
> [ 92.107145] [c00000008dfebd80] [c00000000047e964] sys_execve+0x54/0x70
> [ 92.107157] [c00000008dfebdb0] [c000000000032334] system_call_exception+0x164/0x2e0
> [ 92.107169] [c00000008dfebe10] [c00000000000c464] system_call_common+0xf4/0x258
> [ 92.107185] --- interrupt: c00 at 0x3fff9bb6b8a8
> [ 92.107193] NIP: 00003fff9bb6b8a8 LR: 00003fff9bb6c240 CTR: 0000000000000000
> [ 92.107202] REGS: c00000008dfebe80 TRAP: 0c00 Tainted: G W (5.13.0-rc7-next-20210625)
> [ 92.107213] MSR: 800000000000f033 <SF,EE,PR,FP,ME,IR,DR,RI,LE> CR: 28004224 XER: 00000000
> [ 92.107243] IRQMASK: 0
> GPR00: 000000000000000b 00003fffc36a1440 00003fff9bc87300 00000100268a67d0
> GPR04: 0000010026887e50 0000010026882c50 fefefefefefefeff 7f7f7f7f7f7f7f7f
> GPR08: 00000100268a67d0 0000000000000000 0000000000000000 0000000000000000
> GPR12: 0000000000000000 00003fff9bce3780 0000000114200db4 0000000000000000
> GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40
> GPR20: 00000001141dd820 0000000000000000 00000001141dd740 0000000114204358
> GPR24: 0000000114203948 0000010026876454 0000000000000001 0000010026882c50
> GPR28: 0000010026887e50 0000010026882c50 00000100268a67d0 00003fffc36a1440
> [ 92.107369] NIP [00003fff9bb6b8a8] 0x3fff9bb6b8a8
> [ 92.107378] LR [00003fff9bb6c240] 0x3fff9bb6c240
> [ 92.107386] --- interrupt: c00
> [ 92.107393] Instruction dump:
> [ 92.107400] 7d2000a6 71298000 40820048 39200000 992d0152 39400000 992d0153 614a8002
> [ 92.107427] 7d410164 4bfffe6c 60000000 60000000 <0fe00000> 4bfffe5c 60000000 60000000
> [ 92.107451] ---[ end trace 5f1d49fb99f3613d ]—
>
> Complete dmesg log attached.
>
> Thanks
> -Sachin
>
>
^ permalink raw reply
* Re: [PATCH v3] mm: pagewalk: Fix walk for hugepage tables
From: Andrew Morton @ 2021-06-28 1:12 UTC (permalink / raw)
To: Christophe Leroy
Cc: linux-arch, linux-kernel, Steven Price, linux-mm, Paul Mackerras,
Oliver O'Halloran, linuxppc-dev, dja
In-Reply-To: <38d04410700c8d02f28ba37e020b62c55d6f3d2c.1624597695.git.christophe.leroy@csgroup.eu>
On Fri, 25 Jun 2021 05:10:12 +0000 (UTC) Christophe Leroy <christophe.leroy@csgroup.eu> wrote:
> Pagewalk ignores hugepd entries and walk down the tables
> as if it was traditionnal entries, leading to crazy result.
>
> Add walk_hugepd_range() and use it to walk hugepage tables.
More details, please? I assume "crazy result" is userspace visible?
For how long has this bug existed? Is a -stable backport needed? Has
a Fixes: commit been identified? etcetera!
^ permalink raw reply
* Re: [powerpc][next-20210625] WARN block/mq-deadline-main.c:743 during boot
From: Bart Van Assche @ 2021-06-27 21:12 UTC (permalink / raw)
To: Sachin Sant, linux-block; +Cc: axboe, linux-next, linuxppc-dev
In-Reply-To: <74F24228-8BC7-49FA-BD43-A9FB90269E76@linux.vnet.ibm.com>
On 6/27/21 6:30 AM, Sachin Sant wrote:
> While booting 5.13.0-rc7-next-20210625 on POWER9 LPAR following warning
> is seen [ ... ]
Please help with testing of the patch that is available at
https://lore.kernel.org/linux-block/20210627211112.12720-1-bvanassche@acm.org/T/#u
Thanks,
Bart.
^ permalink raw reply
* [Bug 195755] rcu_sched detected stalls on CPUs/tasks: (detected by 0, t=6302 jiffies, g=11405, c=11404, q=1880), ppc64, G5
From: bugzilla-daemon @ 2021-06-27 17:45 UTC (permalink / raw)
To: linuxppc-dev
In-Reply-To: <bug-195755-206035@https.bugzilla.kernel.org/>
https://bugzilla.kernel.org/show_bug.cgi?id=195755
rudi@heitbaum.com changed:
What |Removed |Added
----------------------------------------------------------------------------
CC| |rudi@heitbaum.com
--- Comment #31 from rudi@heitbaum.com ---
Same error on RK3399pro (Radxa N10) with kernel 5.13.0-rc7
[13081.586168] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13081.586749] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=2081
[13081.587613] (detected by 1, t=6302 jiffies, g=1226929, q=7859)
[13081.588159] Task dump for CPU 5:
[13081.588462] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13081.589388] Call trace:
[13081.589619] __switch_to+0xdc/0x130
[13081.589967] 0xffff800016db3bc8
[13144.605054] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13144.605636] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=8283
[13144.606501] (detected by 1, t=25207 jiffies, g=1226929, q=8960)
[13144.607055] Task dump for CPU 5:
[13144.607358] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13144.608283] Call trace:
[13144.608513] __switch_to+0xdc/0x130
[13144.608862] 0xffff800016db3bc8
[13207.623887] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13207.624453] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=14520
[13207.625307] (detected by 1, t=44112 jiffies, g=1226929, q=9270)
[13207.625851] Task dump for CPU 5:
[13207.626146] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13207.627052] Call trace:
[13207.627278] __switch_to+0xdc/0x130
[13207.627614] 0xffff800016db3bc8
[13270.642861] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13270.643441] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=20751
[13270.644314] (detected by 1, t=63017 jiffies, g=1226929, q=9388)
[13270.644867] Task dump for CPU 5:
[13270.645170] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13270.646095] Call trace:
[13270.646326] __switch_to+0xdc/0x130
[13270.646673] 0xffff800016db3bc8
[13333.661672] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13333.662238] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=26980
[13333.663092] (detected by 1, t=81922 jiffies, g=1226929, q=9585)
[13333.663636] Task dump for CPU 5:
[13333.663931] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13333.664838] Call trace:
[13333.665063] __switch_to+0xdc/0x130
[13333.665399] 0xffff800016db3bc8
[13396.680495] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13396.681062] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=33213
[13396.681917] (detected by 1, t=100827 jiffies, g=1226929, q=9817)
[13396.682469] Task dump for CPU 5:
[13396.682765] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13396.683671] Call trace:
[13396.683896] __switch_to+0xdc/0x130
[13396.684233] 0xffff800016db3bc8
[13459.699554] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13459.700120] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=39406
[13459.700974] (detected by 1, t=119732 jiffies, g=1226929, q=10436)
[13459.701533] Task dump for CPU 5:
[13459.701829] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13459.702735] Call trace:
[13459.702961] __switch_to+0xdc/0x130
[13459.703297] 0xffff800016db3bc8
[13522.718478] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13522.719049] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=45667
[13522.719902] (detected by 1, t=138637 jiffies, g=1226929, q=10742)
[13522.720460] Task dump for CPU 5:
[13522.720755] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13522.721662] Call trace:
[13522.721888] __switch_to+0xdc/0x130
[13522.722224] 0xffff800016db3bc8
[13585.737386] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13585.737953] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=51904
[13585.738807] (detected by 1, t=157542 jiffies, g=1226929, q=10947)
[13585.739365] Task dump for CPU 5:
[13585.739661] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13585.740567] Call trace:
[13585.740793] __switch_to+0xdc/0x130
[13585.741129] 0xffff800016db3bc8
[13648.756295] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13648.756862] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=58090
[13648.757715] (detected by 1, t=176447 jiffies, g=1226929, q=12205)
[13648.758274] Task dump for CPU 5:
[13648.758570] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13648.759476] Call trace:
[13648.759701] __switch_to+0xdc/0x130
[13648.760036] 0xffff800016db3bc8
[13711.775146] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13711.775715] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=64362
[13711.776569] (detected by 1, t=195352 jiffies, g=1226929, q=12462)
[13711.777127] Task dump for CPU 5:
[13711.777423] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13711.778329] Call trace:
[13711.778555] __switch_to+0xdc/0x130
[13711.778892] 0xffff800016db3bc8
[13774.793985] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13774.794553] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=70639
[13774.795408] (detected by 1, t=214257 jiffies, g=1226929, q=12569)
[13774.795967] Task dump for CPU 5:
[13774.796262] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13774.797168] Call trace:
[13774.797394] __switch_to+0xdc/0x130
[13774.797730] 0xffff800016db3bc8
[13837.813008] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13837.813575] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=76909
[13837.814429] (detected by 1, t=233162 jiffies, g=1226929, q=12746)
[13837.814986] Task dump for CPU 5:
[13837.815281] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13837.816188] Call trace:
[13837.816414] __switch_to+0xdc/0x130
[13837.816750] 0xffff800016db3bc8
[13900.831934] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13900.832514] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=83140
[13900.833387] (detected by 1, t=252067 jiffies, g=1226929, q=12831)
[13900.833957] Task dump for CPU 5:
[13900.834260] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13900.835184] Call trace:
[13900.835416] __switch_to+0xdc/0x130
[13900.835763] 0xffff800016db3bc8
[13963.850798] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[13963.851365] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=89428
[13963.852219] (detected by 1, t=270972 jiffies, g=1226929, q=12997)
[13963.852777] Task dump for CPU 5:
[13963.853072] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[13963.853978] Call trace:
[13963.854204] __switch_to+0xdc/0x130
[13963.854539] 0xffff800016db3bc8
[14026.869692] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14026.870256] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=95711
[14026.871109] (detected by 1, t=289877 jiffies, g=1226929, q=13189)
[14026.871669] Task dump for CPU 5:
[14026.871964] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14026.872870] Call trace:
[14026.873096] __switch_to+0xdc/0x130
[14026.873432] 0xffff800016db3bc8
[14089.888567] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14089.889132] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=101977
[14089.889994] (detected by 1, t=308782 jiffies, g=1226929, q=13301)
[14089.890552] Task dump for CPU 5:
[14089.890847] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14089.891753] Call trace:
[14089.891977] __switch_to+0xdc/0x130
[14089.892312] 0xffff800016db3bc8
[14152.907476] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14152.908040] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=108248
[14152.908901] (detected by 1, t=327687 jiffies, g=1226929, q=13583)
[14152.909459] Task dump for CPU 5:
[14152.909755] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14152.910661] Call trace:
[14152.910885] __switch_to+0xdc/0x130
[14152.911221] 0xffff800016db3bc8
[14215.926397] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14215.926980] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=114520
[14215.927859] (detected by 1, t=346592 jiffies, g=1226929, q=14824)
[14215.928430] Task dump for CPU 5:
[14215.928732] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14215.929657] Call trace:
[14215.929888] __switch_to+0xdc/0x130
[14215.930235] 0xffff800016db3bc8
[14278.945254] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14278.945819] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=120755
[14278.946681] (detected by 1, t=365497 jiffies, g=1226929, q=14953)
[14278.947240] Task dump for CPU 5:
[14278.947534] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14278.948441] Call trace:
[14278.948665] __switch_to+0xdc/0x130
[14278.949001] 0xffff800016db3bc8
[14341.964082] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14341.964650] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=127008
[14341.965512] (detected by 1, t=384402 jiffies, g=1226929, q=15207)
[14341.966069] Task dump for CPU 5:
[14341.966365] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14341.967271] Call trace:
[14341.967497] __switch_to+0xdc/0x130
[14341.967832] 0xffff800016db3bc8
[14404.982988] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14404.983555] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=133228
[14404.984415] (detected by 1, t=403307 jiffies, g=1226929, q=15393)
[14404.984974] Task dump for CPU 5:
[14404.985270] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14404.986176] Call trace:
[14404.986402] __switch_to+0xdc/0x130
[14404.986738] 0xffff800016db3bc8
[14468.001845] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14468.002412] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=139490
[14468.003274] (detected by 1, t=422212 jiffies, g=1226929, q=15648)
[14468.003833] Task dump for CPU 5:
[14468.004128] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14468.005035] Call trace:
[14468.005260] __switch_to+0xdc/0x130
[14468.005594] 0xffff800016db3bc8
[14531.020589] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14531.021155] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=145729
[14531.022018] (detected by 1, t=441117 jiffies, g=1226929, q=15794)
[14531.022577] Task dump for CPU 5:
[14531.022873] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14531.023779] Call trace:
[14531.024005] __switch_to+0xdc/0x130
[14531.024341] 0xffff800016db3bc8
[14594.039456] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14594.040025] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=151980
[14594.040886] (detected by 1, t=460022 jiffies, g=1226929, q=15878)
[14594.041446] Task dump for CPU 5:
[14594.041741] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14594.042647] Call trace:
[14594.042872] __switch_to+0xdc/0x130
[14594.043207] 0xffff800016db3bc8
[14657.058389] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14657.058959] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=158250
[14657.059822] (detected by 1, t=478927 jiffies, g=1226929, q=16066)
[14657.060381] Task dump for CPU 5:
[14657.060676] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14657.061583] Call trace:
[14657.061807] __switch_to+0xdc/0x130
[14657.062141] 0xffff800016db3bc8
[14720.077324] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14720.077890] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=164517
[14720.078752] (detected by 1, t=497832 jiffies, g=1226929, q=16282)
[14720.079312] Task dump for CPU 5:
[14720.079607] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14720.080513] Call trace:
[14720.080739] __switch_to+0xdc/0x130
[14720.081075] 0xffff800016db3bc8
[14783.096138] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14783.096707] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=170813
[14783.097569] (detected by 1, t=516737 jiffies, g=1226929, q=16500)
[14783.098129] Task dump for CPU 5:
[14783.098425] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14783.099331] Call trace:
[14783.099557] __switch_to+0xdc/0x130
[14783.099892] 0xffff800016db3bc8
[14846.115081] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14846.115662] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=177083
[14846.116542] (detected by 1, t=535642 jiffies, g=1226929, q=17790)
[14846.117111] Task dump for CPU 5:
[14846.117414] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14846.118339] Call trace:
[14846.118572] __switch_to+0xdc/0x130
[14846.118919] 0xffff800016db3bc8
[14909.133891] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14909.134461] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=183326
[14909.135323] (detected by 1, t=554547 jiffies, g=1226929, q=17940)
[14909.135882] Task dump for CPU 5:
[14909.136177] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14909.137084] Call trace:
[14909.137309] __switch_to+0xdc/0x130
[14909.137644] 0xffff800016db3bc8
[14972.152771] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[14972.153341] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=189592
[14972.154203] (detected by 1, t=573452 jiffies, g=1226929, q=18093)
[14972.154763] Task dump for CPU 5:
[14972.155058] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[14972.155964] Call trace:
[14972.156190] __switch_to+0xdc/0x130
[14972.156525] 0xffff800016db3bc8
[15035.171590] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15035.172159] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=195867
[15035.173022] (detected by 1, t=592357 jiffies, g=1226929, q=18270)
[15035.173583] Task dump for CPU 5:
[15035.173878] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15035.174785] Call trace:
[15035.175012] __switch_to+0xdc/0x130
[15035.175347] 0xffff800016db3bc8
[15098.190534] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15098.191104] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=202159
[15098.191966] (detected by 1, t=611262 jiffies, g=1226929, q=18399)
[15098.192524] Task dump for CPU 5:
[15098.192819] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15098.193726] Call trace:
[15098.193952] __switch_to+0xdc/0x130
[15098.194288] 0xffff800016db3bc8
[15161.209414] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15161.209983] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=208429
[15161.210846] (detected by 1, t=630167 jiffies, g=1226929, q=18573)
[15161.211404] Task dump for CPU 5:
[15161.211699] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15161.212606] Call trace:
[15161.212832] __switch_to+0xdc/0x130
[15161.213168] 0xffff800016db3bc8
[15224.228338] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15224.228905] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=214694
[15224.229767] (detected by 1, t=649072 jiffies, g=1226929, q=18750)
[15224.230326] Task dump for CPU 5:
[15224.230622] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15224.231527] Call trace:
[15224.231753] __switch_to+0xdc/0x130
[15224.232088] 0xffff800016db3bc8
[15287.247240] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15287.247805] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=220945
[15287.248668] (detected by 1, t=667977 jiffies, g=1226929, q=18876)
[15287.249226] Task dump for CPU 5:
[15287.249521] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15287.250427] Call trace:
[15287.250652] __switch_to+0xdc/0x130
[15287.250987] 0xffff800016db3bc8
[15350.266060] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15350.266628] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=227219
[15350.267491] (detected by 1, t=686882 jiffies, g=1226929, q=19238)
[15350.268050] Task dump for CPU 5:
[15350.268345] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15350.269252] Call trace:
[15350.269477] __switch_to+0xdc/0x130
[15350.269812] 0xffff800016db3bc8
[15413.284937] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15413.285504] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=233484
[15413.286368] (detected by 1, t=705787 jiffies, g=1226929, q=20770)
[15413.286926] Task dump for CPU 5:
[15413.287221] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15413.288128] Call trace:
[15413.288353] __switch_to+0xdc/0x130
[15413.288689] 0xffff800016db3bc8
[15476.304113] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15476.304693] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=239734
[15476.305571] (detected by 1, t=724692 jiffies, g=1226929, q=21025)
[15476.306141] Task dump for CPU 5:
[15476.306444] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15476.307370] Call trace:
[15476.307601] __switch_to+0xdc/0x130
[15476.307949] 0xffff800016db3bc8
[15539.322826] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15539.323390] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=245985
[15539.324252] (detected by 1, t=743597 jiffies, g=1226929, q=21217)
[15539.324812] Task dump for CPU 5:
[15539.325108] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15539.326014] Call trace:
[15539.326240] __switch_to+0xdc/0x130
[15539.326578] 0xffff800016db3bc8
[15602.341877] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15602.342442] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=252236
[15602.343304] (detected by 1, t=762502 jiffies, g=1226929, q=21374)
[15602.343864] Task dump for CPU 5:
[15602.344159] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15602.345065] Call trace:
[15602.345290] __switch_to+0xdc/0x130
[15602.345625] 0xffff800016db3bc8
[15665.360673] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15665.361243] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=258527
[15665.362106] (detected by 3, t=781407 jiffies, g=1226929, q=21533)
[15665.362666] Task dump for CPU 5:
[15665.362961] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15665.363867] Call trace:
[15665.364093] __switch_to+0xdc/0x130
[15665.364430] 0xffff800016db3bc8
[15728.379714] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15728.380280] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=264799
[15728.381141] (detected by 1, t=800312 jiffies, g=1226929, q=21623)
[15728.381699] Task dump for CPU 5:
[15728.381995] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15728.382901] Call trace:
[15728.383127] __switch_to+0xdc/0x130
[15728.383462] 0xffff800016db3bc8
[15791.398498] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15791.399064] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=271031
[15791.399927] (detected by 1, t=819217 jiffies, g=1226929, q=21803)
[15791.400486] Task dump for CPU 5:
[15791.400781] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15791.401687] Call trace:
[15791.401911] __switch_to+0xdc/0x130
[15791.402247] 0xffff800016db3bc8
[15854.417401] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15854.417968] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=277248
[15854.418832] (detected by 2, t=838122 jiffies, g=1226929, q=21993)
[15854.419391] Task dump for CPU 5:
[15854.419686] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15854.420593] Call trace:
[15854.420817] __switch_to+0xdc/0x130
[15854.421153] 0xffff800016db3bc8
[15917.436445] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15917.437012] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=283509
[15917.437874] (detected by 1, t=857027 jiffies, g=1226929, q=22214)
[15917.438434] Task dump for CPU 5:
[15917.438729] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15917.439634] Call trace:
[15917.439859] __switch_to+0xdc/0x130
[15917.440195] 0xffff800016db3bc8
[15980.455269] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[15980.455839] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=289795
[15980.456702] (detected by 1, t=875932 jiffies, g=1226929, q=22336)
[15980.457260] Task dump for CPU 5:
[15980.457555] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[15980.458462] Call trace:
[15980.458688] __switch_to+0xdc/0x130
[15980.459024] 0xffff800016db3bc8
[16043.474210] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16043.474775] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=296074
[16043.475637] (detected by 1, t=894837 jiffies, g=1226929, q=23604)
[16043.476197] Task dump for CPU 5:
[16043.476492] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16043.477398] Call trace:
[16043.477623] __switch_to+0xdc/0x130
[16043.477959] 0xffff800016db3bc8
[16106.493004] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16106.493574] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=302355
[16106.494439] (detected by 1, t=913742 jiffies, g=1226929, q=23693)
[16106.494998] Task dump for CPU 5:
[16106.495293] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16106.496200] Call trace:
[16106.496426] __switch_to+0xdc/0x130
[16106.496763] 0xffff800016db3bc8
[16169.511966] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16169.512534] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=308617
[16169.513398] (detected by 1, t=932647 jiffies, g=1226929, q=23881)
[16169.513958] Task dump for CPU 5:
[16169.514253] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16169.515160] Call trace:
[16169.515386] __switch_to+0xdc/0x130
[16169.515720] 0xffff800016db3bc8
[16232.530953] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16232.531530] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=314840
[16232.532412] (detected by 1, t=951552 jiffies, g=1226929, q=24168)
[16232.532981] Task dump for CPU 5:
[16232.533284] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16232.534208] Call trace:
[16232.534439] __switch_to+0xdc/0x130
[16232.534786] 0xffff800016db3bc8
[16295.549694] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16295.550260] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=321100
[16295.551122] (detected by 1, t=970457 jiffies, g=1226929, q=24344)
[16295.551682] Task dump for CPU 5:
[16295.551977] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16295.552883] Call trace:
[16295.553109] __switch_to+0xdc/0x130
[16295.553444] 0xffff800016db3bc8
[16358.568692] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16358.569257] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=327311
[16358.570119] (detected by 1, t=989362 jiffies, g=1226929, q=24453)
[16358.570677] Task dump for CPU 5:
[16358.570973] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16358.571878] Call trace:
[16358.572103] __switch_to+0xdc/0x130
[16358.572438] 0xffff800016db3bc8
[16421.587636] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16421.588203] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=333593
[16421.589064] (detected by 1, t=1008267 jiffies, g=1226929, q=24583)
[16421.589631] Task dump for CPU 5:
[16421.589926] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16421.590832] Call trace:
[16421.591058] __switch_to+0xdc/0x130
[16421.591393] 0xffff800016db3bc8
[16484.606656] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16484.607238] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=339823
[16484.608120] (detected by 1, t=1027172 jiffies, g=1226929, q=24800)
[16484.608697] Task dump for CPU 5:
[16484.609001] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16484.609926] Call trace:
[16484.610156] __switch_to+0xdc/0x130
[16484.610503] 0xffff800016db3bc8
[16547.625618] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16547.626182] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=346004
[16547.627043] (detected by 1, t=1046077 jiffies, g=1226929, q=24992)
[16547.627609] Task dump for CPU 5:
[16547.627905] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16547.628811] Call trace:
[16547.629035] __switch_to+0xdc/0x130
[16547.629371] 0xffff800016db3bc8
[16610.644564] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16610.645128] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=352273
[16610.645990] (detected by 1, t=1064982 jiffies, g=1226929, q=26024)
[16610.646556] Task dump for CPU 5:
[16610.646851] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16610.647758] Call trace:
[16610.647982] __switch_to+0xdc/0x130
[16610.648318] 0xffff800016db3bc8
[16673.663533] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16673.664098] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=358540
[16673.664960] (detected by 1, t=1083887 jiffies, g=1226929, q=27452)
[16673.665526] Task dump for CPU 5:
[16673.665822] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842539 flags:0x00000002[16673.666728] Call trace:
[16673.666954] __switch_to+0xdc/0x130
[16673.667289] 0xffff800016db3bc8
[16736.682410] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[16736.682978] rcu: 5-...0: (1 GPs behind) idle=aaa/1/0x4000000000000000
softirq=981287/981290 fqs=364780
[16736.683840] (detected by 1, t=1102792 jiffies, g=1226929, q=28629)
[16736.684407] Task dump for CPU 5:
[16736.684702] task:pkgjson state:R running task stack: 0
pid:845641 ppid:842408 flags:0x00000003[16736.685608] Call trace:
[16736.685834] __switch_to+0xdc/0x130
[16736.686169] 0xffff800016db3bc8
--
You may reply to this email to add a comment.
You are receiving this mail because:
You are watching the assignee of the bug.
^ permalink raw reply
* [PATCH] powerpc: Remove in_kernel_text()
From: Christophe Leroy @ 2021-06-27 17:09 UTC (permalink / raw)
To: Benjamin Herrenschmidt, Paul Mackerras, Michael Ellerman
Cc: linuxppc-dev, linux-kernel
Last user of in_kernel_text() stopped using in with
commit 549e8152de80 ("powerpc: Make the 64-bit kernel as a
position-independent executable").
Generic function is_kernel_text() does the same.
So remote it.
Signed-off-by: Christophe Leroy <christophe.leroy@csgroup.eu>
---
arch/powerpc/include/asm/sections.h | 8 --------
1 file changed, 8 deletions(-)
diff --git a/arch/powerpc/include/asm/sections.h b/arch/powerpc/include/asm/sections.h
index 324d7b298ec3..6e4af4492a14 100644
--- a/arch/powerpc/include/asm/sections.h
+++ b/arch/powerpc/include/asm/sections.h
@@ -38,14 +38,6 @@ extern char start_virt_trampolines[];
extern char end_virt_trampolines[];
#endif
-static inline int in_kernel_text(unsigned long addr)
-{
- if (addr >= (unsigned long)_stext && addr < (unsigned long)__init_end)
- return 1;
-
- return 0;
-}
-
static inline unsigned long kernel_toc_addr(void)
{
/* Defined by the linker, see vmlinux.lds.S */
--
2.25.0
^ permalink raw reply related
* [powerpc][next-20210625] WARN block/mq-deadline-main.c:743 during boot
From: Sachin Sant @ 2021-06-27 13:30 UTC (permalink / raw)
To: linux-block; +Cc: axboe, linux-next, linuxppc-dev, bvanassche
[-- Attachment #1: Type: text/plain, Size: 3254 bytes --]
While booting 5.13.0-rc7-next-20210625 on POWER9 LPAR following warning
is seen:
[ 3.111826] device-mapper: multipath service-time: version 0.3.0 loaded
[ 3.485799] ------------[ cut here ]------------
[ 3.485809] WARNING: CPU: 9 PID: 225 at block/mq-deadline-main.c:743 dd_insert_requests+0x460/0x4a0
[ 3.485819] Modules linked in: dm_service_time sd_mod t10_pi sg ibmvfc scsi_transport_fc ibmveth dm_multipath dm_mirror dm_region_hash dm_log dm_mod fuse
[ 3.485836] CPU: 9 PID: 225 Comm: kworker/9:1H Not tainted 5.13.0-rc7-next-20210625 #3
[ 3.485842] Workqueue: kblockd blk_mq_requeue_work
[ 3.485848] NIP: c0000000006a5c20 LR: c0000000006a5958 CTR: c0000000006a57c0
[ 3.485852] REGS: c000000009d2f800 TRAP: 0700 Not tainted (5.13.0-rc7-next-20210625)
[ 3.485857] MSR: 800000000282b033 <SF,VEC,VSX,EE,FP,ME,IR,DR,RI,LE> CR: 24004828 XER: 00000001
[ 3.485871] CFAR: c0000000006a5964 IRQMASK: 0
[ 3.485871] GPR00: c0000000006a5920 c000000009d2faa0 c000000001adb700 0000000000000000
[ 3.485871] GPR04: c000000028750048 c000000028750048 0000000000000000 c000000028750048
[ 3.485871] GPR08: 0000000000000020 c000000009530400 0000000000000024 0000000000000007
[ 3.485871] GPR12: 0000000000002000 c00000001ec53280 c00000000018bb48 c000000009530400
[ 3.485871] GPR16: 0000000000000000 c000000028750000 0000000c00002204 c00000000d77512c
[ 3.485871] GPR20: c00000000d775000 000000000004001a c000000000d96928 c00000002ea4e400
[ 3.485871] GPR24: c00000002e190000 0000000000000003 c000000009d2fb98 c000000009d2fac8
[ 3.485871] GPR28: c00000000d775000 0000000000000001 0000000000080700 c000000028750048
[ 3.485925] NIP [c0000000006a5c20] dd_insert_requests+0x460/0x4a0
[ 3.485930] LR [c0000000006a5958] dd_insert_requests+0x198/0x4a0
[ 3.485935] Call Trace:
[ 3.485937] [c000000009d2faa0] [c00000000001e528] __switch_to+0x2c8/0x480 (unreliable)
[ 3.485945] [c000000009d2fb70] [c00000000068550c] blk_mq_sched_insert_request+0xfc/0x210
[ 3.485951] [c000000009d2fbf0] [c00000000067d314] blk_mq_requeue_work+0x174/0x250
[ 3.485958] [c000000009d2fc60] [c00000000017f634] process_one_work+0x264/0x540
[ 3.485964] [c000000009d2fd00] [c00000000017f998] worker_thread+0x88/0x630
[ 3.485970] [c000000009d2fda0] [c00000000018bcd4] kthread+0x194/0x1a0
[ 3.485976] [c000000009d2fe10] [c00000000000c750] ret_from_kernel_thread+0x5c/0x6c
[ 3.485983] Instruction dump:
[ 3.485986] 7ea94838 2f890000 409eff64 7e248b78 7f03c378 4bfbd0dd 60000000 e9380000
[ 3.485996] 2fa90000 409eff48 fa380000 4bffff40 <0fe00000> 4bfffd44 60000000 60000000
[ 3.486005] ---[ end trace 42319989f7cb8ff4 ]---
[ OK ] Found device /dev/disk/by-uuid/186c6f84-531c-4570-89cc-6490f20090e9.
Last known tested & working build was 5.13.0-rc6-next-20210618.
This problem seems to be introduced with
commit 08a9ad8bf60738
block/mq-deadline: Add cgroup support
Reverting this patch allows the kernel to boot without the mentioned warning.
Have attached the boot log. IOSCHED_DEADLINE is set to yes.
CONFIG_MQ_IOSCHED_DEADLINE=y
CONFIG_MQ_IOSCHED_KYBER=y
# CONFIG_IOSCHED_BFQ is not set
# end of IO Schedulers
Thanks
-Sachin
[-- Attachment #2: next-boot.txt --]
[-- Type: text/plain, Size: 26332 bytes --]
[ 0.000000] crashkernel: memory value expected
[ 0.000000] hash-mmu: Page sizes from device-tree:
[ 0.000000] hash-mmu: base_shift=12: shift=12, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=0
[ 0.000000] hash-mmu: base_shift=12: shift=16, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=7
[ 0.000000] hash-mmu: base_shift=12: shift=24, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=56
[ 0.000000] hash-mmu: base_shift=16: shift=16, sllp=0x0110, avpnm=0x00000000, tlbiel=1, penc=1
[ 0.000000] hash-mmu: base_shift=16: shift=24, sllp=0x0110, avpnm=0x00000000, tlbiel=1, penc=8
[ 0.000000] hash-mmu: base_shift=24: shift=24, sllp=0x0100, avpnm=0x00000001, tlbiel=0, penc=0
[ 0.000000] hash-mmu: base_shift=34: shift=34, sllp=0x0120, avpnm=0x000007ff, tlbiel=0, penc=3
[ 0.000000] Enabling pkeys with max key count 31
[ 0.000000] Activating Kernel Userspace Execution Prevention
[ 0.000000] Activating Kernel Userspace Access Prevention
[ 0.000000] Page orders: linear mapping = 24, virtual = 16, io = 16, vmemmap = 24
[ 0.000000] Using 1TB segments
[ 0.000000] hash-mmu: Initializing hash mmu with SLB
[ 0.000000] Linux version 5.13.0-rc7-next-20210625 (root@ltcfleet2-lp13.aus.stglabs.ibm.com) (gcc (GCC) 8.4.1 20200928 (Red Hat 8.4.1-1), GNU ld version 2.30-93.el8) #1 SMP Sun Jun 27 06:16:48 EDT 2021
[ 0.000000] Found initrd at 0xc00000000c700000:0xc000000010d05c11
[ 0.000000] Using pSeries machine description
[ 0.000000] printk: bootconsole [udbg0] enabled
[ 0.000000] Partition configured for 16 cpus.
[ 0.000000] CPU maps initialized for 8 threads per core
[ 0.000000] (thread shift is 3)
[ 0.000000] Allocated 4608 bytes for 16 pacas
[ 0.000000] -----------------------------------------------------
[ 0.000000] phys_mem_size = 0xa00000000
[ 0.000000] dcache_bsize = 0x80
[ 0.000000] icache_bsize = 0x80
[ 0.000000] cpu_features = 0x0001c07b8f5f9187
[ 0.000000] possible = 0x000ffbfbcf5fb187
[ 0.000000] always = 0x0000000380008181
[ 0.000000] cpu_user_features = 0xdc0065c2 0xeff00000
[ 0.000000] mmu_features = 0x7c006e01
[ 0.000000] firmware_features = 0x0000009fc45bfc57
[ 0.000000] vmalloc start = 0xc008000000000000
[ 0.000000] IO start = 0xc00a000000000000
[ 0.000000] vmemmap start = 0xc00c000000000000
[ 0.000000] hash-mmu: ppc64_pft_size = 0x1d
[ 0.000000] hash-mmu: htab_hash_mask = 0x3fffff
[ 0.000000] -----------------------------------------------------
[ 0.000000] numa: NODE_DATA [mem 0x9fff5ef80-0x9fff63fff]
[ 0.000000] rfi-flush: fallback displacement flush available
[ 0.000000] rfi-flush: mttrig type flush available
[ 0.000000] rfi-flush: patched 14 locations (mttrig type flush)
[ 0.000000] count-cache-flush: software flush enabled.
[ 0.000000] link-stack-flush: software flush enabled.
[ 0.000000] entry-flush: patched 61 locations (mttrig type flush)
[ 0.000000] uaccess-flush: patched 1 locations (mttrig type flush)
[ 0.000000] stf-barrier: eieio barrier available
[ 0.000000] stf-barrier: patched 61 entry locations (eieio barrier)
[ 0.000000] stf-barrier: patched 14 exit locations (eieio barrier)
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:0 psize:0 block size:8
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:0 psize:2 block size:8
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:0 psize:10 block size:8
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:2 psize:2 block size:8
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:2 psize:10 block size:8
[ 0.000000] PPC64 nvram contains 15360 bytes
[ 0.000000] barrier-nospec: using ORI speculation barrier
[ 0.000000] barrier-nospec: patched 390 locations
[ 0.000000] Top of RAM: 0xa00000000, Total RAM: 0xa00000000
[ 0.000000] Memory hole size: 0MB
[ 0.000000] Zone ranges:
[ 0.000000] Normal [mem 0x0000000000000000-0x00000009ffffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 7: [mem 0x0000000000000000-0x00000009ffffffff]
[ 0.000000] Initmem setup node 7 [mem 0x0000000000000000-0x00000009ffffffff]
[ 0.000000] percpu: Embedded 10 pages/cpu s605976 r0 d49384 u1048576
[ 0.000000] pcpu-alloc: s605976 r0 d49384 u1048576 alloc=1*1048576
[ 0.000000] pcpu-alloc: [0] 00 [0] 01 [0] 02 [0] 03 [0] 04 [0] 05 [0] 06 [0] 07
[ 0.000000] pcpu-alloc: [0] 08 [0] 09 [0] 10 [0] 11 [0] 12 [0] 13 [0] 14 [0] 15
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 654720
[ 0.000000] Policy zone: Normal
[ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-5.13.0-rc7-next-20210625 root=UUID=186c6f84-531c-4570-89cc-6490f20090e9 ro crashkernel=auto biosdevname=0
[ 0.000000] Unknown command line parameters: BOOT_IMAGE=/boot/vmlinuz-5.13.0-rc7-next-20210625 crashkernel=auto biosdevname=0
[ 0.000000] Dentry cache hash table entries: 8388608 (order: 10, 67108864 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 4194304 (order: 9, 33554432 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] Memory: 41585600K/41943040K available (13376K kernel code, 5440K rwdata, 4096K rodata, 4992K init, 2929K bss, 357440K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=16, Nodes=8
[ 0.000000] ftrace: allocating 33185 entries in 13 pages
[ 0.000000] ftrace: allocated 13 pages with 3 groups
[ 0.000000] trace event string verifier disabled
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=2048 to nr_cpu_ids=16.
[ 0.000000] Rude variant of Tasks RCU enabled.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=16
[ 0.000000] NR_IRQS: 512, nr_irqs: 512, preallocated irqs: 16
[ 0.000000] pic: no ISA interrupt controller
[ 0.000000] rcu: Offload RCU callbacks from CPUs: (none).
[ 0.000000] random: get_random_u64 called from start_kernel+0x9b0/0xc10 with crng_init=0
[ 0.000000] time_init: decrementer frequency = 512.000000 MHz
[ 0.000000] time_init: processor frequency = 3150.000000 MHz
[ 0.000001] time_init: 56 bit decrementer (max: 7fffffffffffff)
[ 0.000024] clocksource: timebase: mask: 0xffffffffffffffff max_cycles: 0x761537d007, max_idle_ns: 440795202126 ns
[ 0.000064] clocksource: timebase mult[1f40000] shift[24] registered
[ 0.000090] clockevent: decrementer mult[83126f] shift[24] cpu[0]
[ 0.000118] Console: colour dummy device 80x25
[ 0.000137] printk: console [hvc0] enabled
[ 0.000155] printk: bootconsole [udbg0] disabled
[ 0.000189] pid_max: default: 32768 minimum: 301
[ 0.000213] LSM: Security Framework initializing
[ 0.000222] Yama: becoming mindful.
[ 0.000228] SELinux: Initializing.
[ 0.000308] Mount-cache hash table entries: 131072 (order: 4, 1048576 bytes, linear)
[ 0.000365] Mountpoint-cache hash table entries: 131072 (order: 4, 1048576 bytes, linear)
[ 0.000751] POWER9 performance monitor hardware support registered
[ 0.000772] rcu: Hierarchical SRCU implementation.
[ 0.001358] smp: Bringing up secondary CPUs ...
[ 0.005415] smp: Brought up 1 node, 16 CPUs
[ 0.005423] numa: Node 7 CPUs: 0-15
[ 0.005428] Big cores detected but using small core scheduling
[ 0.005867] devtmpfs: initialized
[ 0.031559] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.031568] futex hash table entries: 4096 (order: 3, 524288 bytes, linear)
[ 0.031756] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 0.031848] audit: initializing netlink subsys (disabled)
[ 0.031905] audit: type=2000 audit(1624791942.030:1): state=initialized audit_enabled=0 res=1
[ 0.031956] thermal_sys: Registered thermal governor 'fair_share'
[ 0.031958] thermal_sys: Registered thermal governor 'step_wise'
[ 0.032011] cpuidle: using governor menu
[ 0.032079] RTAS daemon started
[ 0.032184] pstore: Registered nvram as persistent store backend
[ 0.032761] EEH: pSeries platform initialized
[ 0.039808] PCI: Probing PCI hardware
[ 0.039812] EEH: No capable adapters found: recovery disabled.
[ 0.039815] PCI: Probing PCI hardware done
[ 0.039916] pseries-rng: Registering arch random hook.
[ 0.041026] Kprobes globally optimized
[ 0.041194] HugeTLB registered 16.0 MiB page size, pre-allocated 0 pages
[ 0.041199] HugeTLB registered 16.0 GiB page size, pre-allocated 0 pages
[ 0.072269] wait_for_initramfs() called before rootfs_initcalls
[ 0.075230] iommu: Default domain type: Translated
[ 0.075279] vgaarb: loaded
[ 0.075346] SCSI subsystem initialized
[ 0.075376] usbcore: registered new interface driver usbfs
[ 0.075386] usbcore: registered new interface driver hub
[ 0.075396] usbcore: registered new device driver usb
[ 0.075460] EDAC MC: Ver: 3.0.0
[ 0.075653] NetLabel: Initializing
[ 0.075656] NetLabel: domain hash size = 128
[ 0.075659] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 0.075673] NetLabel: unlabeled traffic allowed by default
[ 0.075788] clocksource: Switched to clocksource timebase
[ 0.085323] VFS: Disk quotas dquot_6.6.0
[ 0.085343] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes)
[ 0.086435] random: fast init done
[ 0.087364] NET: Registered PF_INET protocol family
[ 0.087487] IP idents hash table entries: 262144 (order: 5, 2097152 bytes, linear)
[ 0.089972] tcp_listen_portaddr_hash hash table entries: 32768 (order: 3, 524288 bytes, linear)
[ 0.090031] TCP established hash table entries: 524288 (order: 6, 4194304 bytes, linear)
[ 0.090872] TCP bind hash table entries: 65536 (order: 4, 1048576 bytes, linear)
[ 0.090960] TCP: Hash tables configured (established 524288 bind 65536)
[ 0.090991] UDP hash table entries: 32768 (order: 4, 1048576 bytes, linear)
[ 0.091101] UDP-Lite hash table entries: 32768 (order: 4, 1048576 bytes, linear)
[ 0.091241] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 0.091248] PCI: CLS 0 bytes, default 128
[ 0.091287] Trying to unpack rootfs image as initramfs...
[ 0.091950] IOMMU table initialized, virtual merging enabled
[ 0.111223] vas: API is supported only with radix page tables
[ 0.112396] hv-24x7: read 1530 catalog entries, created 509 event attrs (0 failures), 275 descs
[ 0.114351] Initialise system trusted keyrings
[ 0.114401] workingset: timestamp_bits=38 max_order=20 bucket_order=0
[ 0.115661] zbud: loaded
[ 0.134910] NET: Registered PF_ALG protocol family
[ 0.134916] Key type asymmetric registered
[ 0.134920] Asymmetric key parser 'x509' registered
[ 0.134927] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[ 0.134971] io scheduler mq-deadline registered
[ 0.134976] io scheduler kyber registered
[ 0.135283] atomic64_test: passed
[ 0.135323] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[ 0.135632] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.135882] Non-volatile memory driver v1.3
[ 0.135910] Linux agpgart interface v0.103
[ 0.136136] tpm_ibmvtpm 30000003: CRQ initialization completed
[ 1.085686] Freeing initrd memory: 71680K
[ 1.206495] rdac: device handler registered
[ 1.206534] hp_sw: device handler registered
[ 1.206537] emc: device handler registered
[ 1.206572] alua: device handler registered
[ 1.206668] libphy: Fixed MDIO Bus: probed
[ 1.206710] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.206722] ehci-pci: EHCI PCI platform driver
[ 1.206731] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 1.206740] ohci-pci: OHCI PCI platform driver
[ 1.206747] uhci_hcd: USB Universal Host Controller Interface driver
[ 1.206782] usbcore: registered new interface driver usbserial_generic
[ 1.206789] usbserial: USB Serial support registered for generic
[ 1.206845] mousedev: PS/2 mouse device common for all mice
[ 1.206945] rtc-generic rtc-generic: registered as rtc0
[ 1.206998] rtc-generic rtc-generic: setting system clock to 2021-06-27T11:05:43 UTC (1624791943)
[ 1.207079] xcede: xcede_record_size = 10
[ 1.207082] xcede: Record 0 : hint = 1, latency = 0x400 tb ticks, Wake-on-irq = 1
[ 1.207086] xcede: Record 1 : hint = 2, latency = 0x3e8000 tb ticks, Wake-on-irq = 0
[ 1.207090] cpuidle: Skipping the 2 Extended CEDE idle states
[ 1.207093] cpuidle: Fixed up CEDE exit latency to 1 us
[ 1.207260] pseries_idle_driver registered
[ 1.207280] nx_compress_pseries ibm,compression-v1: nx842_OF_upd: max_sync_size new:65536 old:0
[ 1.207285] nx_compress_pseries ibm,compression-v1: nx842_OF_upd: max_sync_sg new:510 old:0
[ 1.207290] nx_compress_pseries ibm,compression-v1: nx842_OF_upd: max_sg_len new:4080 old:0
[ 1.207328] alg: No test for 842 (842-nx)
[ 1.207352] NX-GZIP is not supported. Returned=-524
[ 1.207371] hid: raw HID events driver (C) Jiri Kosina
[ 1.207463] usbcore: registered new interface driver usbhid
[ 1.207466] usbhid: USB HID core driver
[ 1.207479] drop_monitor: Initializing network drop monitor service
[ 1.207534] Initializing XFRM netlink socket
[ 1.207610] NET: Registered PF_INET6 protocol family
[ 1.207799] Segment Routing with IPv6
[ 1.207815] NET: Registered PF_PACKET protocol family
[ 1.207820] mpls_gso: MPLS GSO support
[ 1.207890] Running MSI bitmap self-tests ...
[ 1.208529] registered taskstats version 1
[ 1.208541] Loading compiled-in X.509 certificates
[ 1.210917] alg: No test for pkcs1pad(rsa,sha1) (pkcs1pad(rsa-generic,sha1))
[ 1.211522] Loaded X.509 cert 'Build time autogenerated kernel key: 0f27eafbb5f2c161086ea66b5cf058fe022f9e54'
[ 1.211574] zswap: loaded using pool lzo/zbud
[ 1.211672] pstore: Using crash dump compression: deflate
[ 1.212994] Freeing unused kernel image (initmem) memory: 4992K
[ 1.212999] Kernel memory protection not selected by kernel config.
[ 1.213005] Run /init as init process
[ 1.213008] with arguments:
[ 1.213009] /init
[ 1.213011] with environment:
[ 1.213011] HOME=/
[ 1.213012] TERM=linux
[ 1.213013] BOOT_IMAGE=/boot/vmlinuz-5.13.0-rc7-next-20210625
[ 1.213015] crashkernel=auto
[ 1.213016] biosdevname=0
[ 1.220578] systemd[1]: systemd 239 (239-45.el8) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy)
[ 1.220694] systemd[1]: Detected architecture ppc64-le.
[ 1.220700] systemd[1]: Running in initial RAM disk.
[ 1.276398] systemd[1]: Set hostname to <ltcfleet2-lp13.aus.stglabs.ibm.com>.
[ 1.324932] random: systemd: uninitialized urandom read (16 bytes read)
[ 1.325004] systemd[1]: Listening on udev Kernel Socket.
[ 1.325194] random: systemd: uninitialized urandom read (16 bytes read)
[ 1.325205] systemd[1]: Reached target Timers.
[ 1.325308] random: systemd: uninitialized urandom read (16 bytes read)
[ 1.325409] systemd[1]: Listening on Journal Socket.
[ 1.326607] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[ 1.327362] systemd[1]: Starting Setup Virtual Console...
[ 1.328109] systemd[1]: Started Hardware RNG Entropy Gatherer Daemon.
[ 1.439732] fuse: init (API version 7.34)
[ 1.541578] device-mapper: uevent: version 1.0.3
[ 1.541657] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[ 1.925623] synth uevent: /devices/vio: failed to send uevent
[ 1.925650] vio vio: uevent: failed to send synthetic uevent
[ 1.925823] synth uevent: /devices/vio/4000: failed to send uevent
[ 1.925831] vio 4000: uevent: failed to send synthetic uevent
[ 1.925850] synth uevent: /devices/vio/4001: failed to send uevent
[ 1.925856] vio 4001: uevent: failed to send synthetic uevent
[ 1.925881] synth uevent: /devices/vio/4002: failed to send uevent
[ 1.925888] vio 4002: uevent: failed to send synthetic uevent
[ 1.925915] synth uevent: /devices/vio/4004: failed to send uevent
[ 1.925923] vio 4004: uevent: failed to send synthetic uevent
[ 2.084494] ibmveth: IBM Power Virtual Ethernet Driver 1.06
[ 2.086264] ibmveth 30000002 env2: renamed from eth0
[ 2.099854] ibmvfc: IBM Virtual Fibre Channel Driver version: 1.0.11 (April 12, 2013)
[ 2.100403] scsi host0: IBM POWER Virtual FC Adapter
[ 2.101472] ibmvfc 300004bd: Partner initialization complete
[ 2.101943] scsi host1: IBM POWER Virtual FC Adapter
[ 2.103112] ibmvfc 300008a5: Partner initialization complete
[ 2.110937] ibmvfc 300004bd: Host partition: ltcfleet2-vios1, device: vfchost7 U78D5.ND1.CSS130E-P1-C2-C1-T1 U9080.M9S.130A098-V100-C1213 max sectors 2048
[ 2.113277] ibmvfc 300008a5: Host partition: ltcfleet2-vios2, device: vfchost10 U78D5.ND2.CSS140C-P1-C2-C1-T1 U9080.M9S.130A098-V200-C2213 max sectors 2048
[ 2.191711] scsi 0:0:0:0: Direct-Access IBM 2145 0000 PQ: 0 ANSI: 6
[ 2.192475] scsi 0:0:1:0: Direct-Access IBM 2145 0000 PQ: 0 ANSI: 6
[ 2.206687] scsi 1:0:0:0: Direct-Access IBM 2145 0000 PQ: 0 ANSI: 6
[ 2.207550] scsi 1:0:1:0: Direct-Access IBM 2145 0000 PQ: 0 ANSI: 6
[ 2.215833] scsi 0:0:0:0: alua: supports implicit TPGS
[ 2.215841] scsi 0:0:0:0: alua: device naa.60050768028383d7f00000000000001f port group 0 rel port 80
[ 2.215948] scsi 0:0:1:0: alua: supports implicit TPGS
[ 2.215954] scsi 0:0:1:0: alua: device naa.60050768028383d7f00000000000001f port group 1 rel port 880
[ 2.216058] scsi 1:0:0:0: alua: supports implicit TPGS
[ 2.216063] scsi 1:0:0:0: alua: device naa.60050768028383d7f00000000000001f port group 0 rel port 80
[ 2.216158] scsi 1:0:1:0: alua: supports implicit TPGS
[ 2.216163] scsi 1:0:1:0: alua: device naa.60050768028383d7f00000000000001f port group 1 rel port 880
[ 2.233836] scsi 0:0:0:0: Attached scsi generic sg0 type 0
[ 2.233908] scsi 0:0:1:0: Attached scsi generic sg1 type 0
[ 2.233971] scsi 1:0:0:0: Attached scsi generic sg2 type 0
[ 2.234032] scsi 1:0:1:0: Attached scsi generic sg3 type 0
[ 2.236764] scsi 0:0:1:0: Power-on or device reset occurred
[ 2.236837] scsi 0:0:0:0: Power-on or device reset occurred
[ 2.248623] sd 1:0:0:0: Power-on or device reset occurred
[ 2.248649] sd 1:0:1:0: Power-on or device reset occurred
[ 2.275942] sd 0:0:1:0: alua: transition timeout set to 60 seconds
[ 2.275952] sd 0:0:1:0: alua: port group 01 state N non-preferred supports tolusna
[ 2.275966] sd 0:0:0:0: alua: transition timeout set to 60 seconds
[ 2.275972] sd 0:0:0:0: alua: port group 00 state A non-preferred supports tolusna
[ 2.276425] sd 0:0:0:0: [sda] 209715200 512-byte logical blocks: (107 GB/100 GiB)
[ 2.276426] sd 0:0:1:0: [sdb] 209715200 512-byte logical blocks: (107 GB/100 GiB)
[ 2.276530] sd 0:0:0:0: [sda] Write Protect is off
[ 2.276531] sd 0:0:1:0: [sdb] Write Protect is off
[ 2.276535] sd 0:0:0:0: [sda] Mode Sense: 97 00 10 08
[ 2.276538] sd 0:0:1:0: [sdb] Mode Sense: 97 00 10 08
[ 2.276693] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA
[ 2.276711] sd 0:0:1:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA
[ 2.296361] sd 1:0:1:0: [sdd] 209715200 512-byte logical blocks: (107 GB/100 GiB)
[ 2.296362] sd 1:0:0:0: [sdc] 209715200 512-byte logical blocks: (107 GB/100 GiB)
[ 2.296461] sd 1:0:0:0: [sdc] Write Protect is off
[ 2.296463] sd 1:0:1:0: [sdd] Write Protect is off
[ 2.296468] sd 1:0:0:0: [sdc] Mode Sense: 97 00 10 08
[ 2.296471] sd 1:0:1:0: [sdd] Mode Sense: 97 00 10 08
[ 2.296671] sd 1:0:1:0: [sdd] Write cache: disabled, read cache: enabled, supports DPO and FUA
[ 2.296671] sd 1:0:0:0: [sdc] Write cache: disabled, read cache: enabled, supports DPO and FUA
[ 2.358146] sda: sda1 sda2 sda3
[ 2.359725] sd 0:0:0:0: [sda] Attached SCSI disk
[ 2.402821] sdb: sdb1 sdb2 sdb3
[ 2.404314] sd 0:0:1:0: [sdb] Attached SCSI disk
[ 2.408138] sdc: sdc1 sdc2 sdc3
[ 2.408323] sdd: sdd1 sdd2 sdd3
[ 2.410172] sd 1:0:0:0: [sdc] Attached SCSI disk
[ 2.410333] sd 1:0:1:0: [sdd] Attached SCSI disk
[ 2.787982] device-mapper: multipath service-time: version 0.3.0 loaded
[ 3.086066] ------------[ cut here ]------------
[ 3.086075] WARNING: CPU: 15 PID: 226 at block/mq-deadline-main.c:743 dd_insert_requests+0x460/0x4a0
[ 3.086085] Modules linked in: dm_service_time sd_mod t10_pi sg ibmvfc scsi_transport_fc ibmveth dm_multipath dm_mirror dm_region_hash dm_log dm_mod fuse
[ 3.086102] CPU: 15 PID: 226 Comm: kworker/15:1H Not tainted 5.13.0-rc7-next-20210625 #1
[ 3.086109] Workqueue: kblockd blk_mq_requeue_work
[ 3.086115] NIP: c0000000006a4b40 LR: c0000000006a4878 CTR: c0000000006a46e0
[ 3.086119] REGS: c00000002824f800 TRAP: 0700 Not tainted (5.13.0-rc7-next-20210625)
[ 3.086124] MSR: 800000000282b033 <SF,VEC,VSX,EE,FP,ME,IR,DR,RI,LE> CR: 48004828 XER: 00000001
[ 3.086138] CFAR: c0000000006a4884 IRQMASK: 0
GPR00: c0000000006a4840 c00000002824faa0 c000000001adb800 0000000000000000
GPR04: c00000000b5d0048 c00000000b5d0048 0000000000000000 c00000000b5d0048
GPR08: 0000000000000020 c000000009530400 0000000000000009 0000000000000007
GPR12: 0000000000002000 c00000001ec3a280 c00000000018bd38 c000000009530400
GPR16: 0000000000000000 c00000000b5d0000 0000000c00002204 c00000000baeb32c
GPR20: c00000000baeb200 000000000004001a c000000000d96928 c00000002d1a7400
GPR24: c000000029ca0000 0000000000000003 c00000002824fb98 c00000002824fac8
GPR28: c00000000baeb200 0000000000000001 0000000000080700 c00000000b5d0048
[ 3.086192] NIP [c0000000006a4b40] dd_insert_requests+0x460/0x4a0
[ 3.086198] LR [c0000000006a4878] dd_insert_requests+0x198/0x4a0
[ 3.086203] Call Trace:
[ 3.086205] [c00000002824faa0] [c00000000001e608] __switch_to+0x2c8/0x480 (unreliable)
[ 3.086212] [c00000002824fb70] [c0000000006846cc] blk_mq_sched_insert_request+0xfc/0x210
[ 3.086219] [c00000002824fbf0] [c00000000067c4d4] blk_mq_requeue_work+0x174/0x250
[ 3.086225] [c00000002824fc60] [c00000000017f814] process_one_work+0x264/0x540
[ 3.086231] [c00000002824fd00] [c00000000017fb78] worker_thread+0x88/0x630
[ 3.086237] [c00000002824fda0] [c00000000018bec4] kthread+0x194/0x1a0
[ 3.086242] [c00000002824fe10] [c00000000000c750] ret_from_kernel_thread+0x5c/0x6c
[ 3.086249] Instruction dump:
[ 3.086252] 7ea94838 2f890000 409eff64 7e248b78 7f03c378 4bfbd35d 60000000 e9380000
[ 3.086261] 2fa90000 409eff48 fa380000 4bffff40 <0fe00000> 4bfffd44 60000000 60000000
[ 3.086271] ---[ end trace 146cb6ccf9618f87 ]---
[ 3.503164] EXT4-fs (dm-2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[ 4.299787] random: crng init done
[ 4.299797] random: 7 urandom warning(s) missed due to ratelimiting
[ 4.360924] printk: systemd: 13 output lines suppressed due to ratelimiting
[ 5.114418] SELinux: Runtime disable is deprecated, use selinux=0 on the kernel cmdline.
[ 5.114432] SELinux: Disabled at runtime.
[ 5.295807] audit: type=1404 audit(1624791947.580:2): enforcing=0 old_enforcing=0 auid=4294967295 ses=4294967295 enabled=0 old-enabled=1 lsm=selinux res=1
[ 5.628450] systemd[1]: systemd 239 (239-45.el8) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy)
[ 5.628558] systemd[1]: Detected architecture ppc64-le.
[ 5.640381] systemd[1]: Set hostname to <ltcfleet2-lp13.aus.stglabs.ibm.com>.
[ 7.560448] systemd[1]: systemd-journald.service: Succeeded.
[ 7.561093] systemd[1]: initrd-switch-root.service: Succeeded.
[ 7.561371] systemd[1]: Stopped Switch Root.
[ 7.561877] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart.
[ 7.561933] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
[ 7.561990] systemd[1]: Stopped Journal Service.
[ 7.562726] systemd[1]: Starting Journal Service...
[ 7.645456] EXT4-fs (dm-2): re-mounted. Opts: (null). Quota mode: none.
[ 7.682451] Adding 10485696k swap on /dev/mapper/mpatha3. Priority:-2 extents:1 across:10485696k FS
[ 8.118997] synth uevent: /devices/vio: failed to send uevent
[ 8.119008] vio vio: uevent: failed to send synthetic uevent
[ 8.119675] synth uevent: /devices/vio/4000: failed to send uevent
[ 8.119679] vio 4000: uevent: failed to send synthetic uevent
[ 8.119692] synth uevent: /devices/vio/4001: failed to send uevent
[ 8.119696] vio 4001: uevent: failed to send synthetic uevent
[ 8.119708] synth uevent: /devices/vio/4002: failed to send uevent
[ 8.119712] vio 4002: uevent: failed to send synthetic uevent
[ 8.119724] synth uevent: /devices/vio/4004: failed to send uevent
[ 8.119728] vio 4004: uevent: failed to send synthetic uevent
[ 9.414602] pseries_rng: Registering IBM pSeries RNG driver
[ 10.145804] ibmveth 30000002 net0: renamed from env2
[ 11.327861] RPC: Registered named UNIX socket transport module.
[ 11.327873] RPC: Registered udp transport module.
[ 11.327876] RPC: Registered tcp transport module.
[ 11.327879] RPC: Registered tcp NFSv4.1 backchannel transport module.
^ permalink raw reply
* Re: [powerpc][next-20210625] Kernel warning(arch/powerpc/kernel/interrupt.c:518) during boot
From: Sachin Sant @ 2021-06-27 11:23 UTC (permalink / raw)
To: Nicholas Piggin; +Cc: linux-next, linuxppc-dev
In-Reply-To: <1624788248.0kxmv878xd.astroid@bobo.none>
[-- Attachment #1: Type: text/plain, Size: 5132 bytes --]
> On 27-Jun-2021, at 3:36 PM, Nicholas Piggin <npiggin@gmail.com> wrote:
>>
>> So there's definitely IRQMASK=0 and no MSR[EE]=0 in this frame, which is
>> what the warning was.
>>
>> I'd say either something hasn't set PACA_IRQ_HARD_DIS properly, so EE
>> doesn't get enabled when irqs are restored, or maybe the change to
>> arch_local_irq_restore(). Less likely that the stack got messed up.
>>
>> Can you try run with CONFIG_PPC_IRQ_SOFT_MASK_DEBUG=y ?
>
> Nevermind, I think I've found the problem. Some code runs in the
> implicit soft-mask region without expecting to be masked. Working
> on a fix…
:-) . I was able to recreate this after few attempts. It seem the warning isn’t
always triggered during boot. I had to run a kernel compile operation after
boot to trigger this warning again.
In case its helpful here is the additional trace with PPC_IRQ_SOFT_MASK_DEBUG.
[ 92.106731] ------------[ cut here ]------------
[ 92.106738] WARNING: CPU: 45 PID: 12757 at arch/powerpc/kernel/irq.c:255 arch_local_irq_restore+0x1d0/0x200
[ 92.106753] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts vmx_crypto uio_pdrv_genirq uio sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
[ 92.106828] CPU: 45 PID: 12757 Comm: sh Kdump: loaded Tainted: G W 5.13.0-rc7-next-20210625 #1
[ 92.106841] NIP: c0000000000164d0 LR: c000000000cedaa8 CTR: 0000000000000000
[ 92.106849] REGS: c00000008dfeb7e0 TRAP: 0700 Tainted: G W (5.13.0-rc7-next-20210625)
[ 92.106859] MSR: 8000000002823033 <SF,VEC,VSX,FP,ME,IR,DR,RI,LE> CR: 28004222 XER: 00000000
[ 92.106892] CFAR: c00000000001632c IRQMASK: 0
GPR00: c000000000ceda98 c00000008dfeba80 c000000002921e00 0000000000000000
GPR04: 0000000000000000 0000000000000000 0000000000000000 00000000000000ff
GPR08: 0000000000000001 0000000000000000 0000000000000001 0000000000000017
GPR12: 0000000024004822 c000000007fb9200 000000012efd81d4 000000012ee50000
GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40
GPR20: 00003fffa93f8000 0000000000000000 00003fffa93f9300 000000012efb1988
GPR24: 000000012ee7fe7c 000000012efccba0 000000012ee50000 c00000008d5d7600
GPR28: c0000000314c0bc0 c000000040d9f100 c0000008beb5861c 4b72201a3063fe13
[ 92.107024] NIP [c0000000000164d0] arch_local_irq_restore+0x1d0/0x200
[ 92.107035] LR [c000000000cedaa8] _raw_spin_unlock_irqrestore+0x88/0xb0
[ 92.107047] Call Trace:
[ 92.107052] [c00000008dfeba80] [c00000008dfebb50] 0xc00000008dfebb50 (unreliable)
[ 92.107065] [c00000008dfebab0] [238c5bf052df0858] 0x238c5bf052df0858
[ 92.107076] [c00000008dfebae0] [c0000000008178e8] get_random_u64+0x88/0x100
[ 92.107090] [c00000008dfebb20] [c000000000020134] arch_randomize_brk+0xb4/0xd8
[ 92.107105] [c00000008dfebb50] [c0000000005430b0] load_elf_binary+0xe70/0x1220
[ 92.107119] [c00000008dfebc40] [c00000000047ded0] bprm_execve+0x410/0x800
[ 92.107132] [c00000008dfebd10] [c00000000047e8ec] do_execveat_common.isra.44+0x21c/0x240
[ 92.107145] [c00000008dfebd80] [c00000000047e964] sys_execve+0x54/0x70
[ 92.107157] [c00000008dfebdb0] [c000000000032334] system_call_exception+0x164/0x2e0
[ 92.107169] [c00000008dfebe10] [c00000000000c464] system_call_common+0xf4/0x258
[ 92.107185] --- interrupt: c00 at 0x3fff9bb6b8a8
[ 92.107193] NIP: 00003fff9bb6b8a8 LR: 00003fff9bb6c240 CTR: 0000000000000000
[ 92.107202] REGS: c00000008dfebe80 TRAP: 0c00 Tainted: G W (5.13.0-rc7-next-20210625)
[ 92.107213] MSR: 800000000000f033 <SF,EE,PR,FP,ME,IR,DR,RI,LE> CR: 28004224 XER: 00000000
[ 92.107243] IRQMASK: 0
GPR00: 000000000000000b 00003fffc36a1440 00003fff9bc87300 00000100268a67d0
GPR04: 0000010026887e50 0000010026882c50 fefefefefefefeff 7f7f7f7f7f7f7f7f
GPR08: 00000100268a67d0 0000000000000000 0000000000000000 0000000000000000
GPR12: 0000000000000000 00003fff9bce3780 0000000114200db4 0000000000000000
GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40
GPR20: 00000001141dd820 0000000000000000 00000001141dd740 0000000114204358
GPR24: 0000000114203948 0000010026876454 0000000000000001 0000010026882c50
GPR28: 0000010026887e50 0000010026882c50 00000100268a67d0 00003fffc36a1440
[ 92.107369] NIP [00003fff9bb6b8a8] 0x3fff9bb6b8a8
[ 92.107378] LR [00003fff9bb6c240] 0x3fff9bb6c240
[ 92.107386] --- interrupt: c00
[ 92.107393] Instruction dump:
[ 92.107400] 7d2000a6 71298000 40820048 39200000 992d0152 39400000 992d0153 614a8002
[ 92.107427] 7d410164 4bfffe6c 60000000 60000000 <0fe00000> 4bfffe5c 60000000 60000000
[ 92.107451] ---[ end trace 5f1d49fb99f3613d ]—
Complete dmesg log attached.
Thanks
-Sachin
[-- Attachment #2: next-20210625.txt --]
[-- Type: text/plain, Size: 35026 bytes --]
[ 0.000000] Reserving 512MB of memory at 128MB for crashkernel (System RAM: 35840MB)
[ 0.000000] hash-mmu: Page sizes from device-tree:
[ 0.000000] hash-mmu: base_shift=12: shift=12, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=0
[ 0.000000] hash-mmu: base_shift=12: shift=16, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=7
[ 0.000000] hash-mmu: base_shift=12: shift=24, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=56
[ 0.000000] hash-mmu: base_shift=16: shift=16, sllp=0x0110, avpnm=0x00000000, tlbiel=1, penc=1
[ 0.000000] hash-mmu: base_shift=16: shift=24, sllp=0x0110, avpnm=0x00000000, tlbiel=1, penc=8
[ 0.000000] hash-mmu: base_shift=24: shift=24, sllp=0x0100, avpnm=0x00000001, tlbiel=0, penc=0
[ 0.000000] hash-mmu: base_shift=34: shift=34, sllp=0x0120, avpnm=0x000007ff, tlbiel=0, penc=3
[ 0.000000] Enabling pkeys with max key count 8
[ 0.000000] Activating Kernel Userspace Execution Prevention
[ 0.000000] Activating Kernel Userspace Access Prevention
[ 0.000000] Page orders: linear mapping = 24, virtual = 12, io = 12, vmemmap = 24
[ 0.000000] Using 1TB segments
[ 0.000000] hash-mmu: Initializing hash mmu with SLB
[ 0.000000] Linux version 5.13.0-rc7-next-20210625 (root@ltc-zzci-2.aus.stglabs.ibm.com) (gcc (GCC) 8.4.1 20200928 (Red Hat 8.4.1-1), GNU ld version 2.30-93.el8) #1 SMP Sun Jun 27 05:45:43 CDT 2021
[ 0.000000] Found initrd at 0xc00000000d400000:0xc000000010d118ce
[ 0.000000] Using pSeries machine description
[ 0.000000] printk: bootconsole [udbg0] enabled
[ 0.000000] Partition configured for 96 cpus.
[ 0.000000] CPU maps initialized for 8 threads per core
[ 0.000000] (thread shift is 3)
[ 0.000000] Allocated 3328 bytes for 96 pacas
[ 0.000000] -----------------------------------------------------
[ 0.000000] phys_mem_size = 0x8c0000000
[ 0.000000] dcache_bsize = 0x80
[ 0.000000] icache_bsize = 0x80
[ 0.000000] cpu_features = 0x0001c07b8f5f9187
[ 0.000000] possible = 0x000ffbfbcf5fb187
[ 0.000000] always = 0x0000000380008181
[ 0.000000] cpu_user_features = 0xdc0065c2 0xeff00000
[ 0.000000] mmu_features = 0x7c006e01
[ 0.000000] firmware_features = 0x0000009fc45bfc57
[ 0.000000] vmalloc start = 0xc0003d0000000000
[ 0.000000] IO start = 0xc0003e0000000000
[ 0.000000] vmemmap start = 0xc0003f0000000000
[ 0.000000] hash-mmu: ppc64_pft_size = 0x1c
[ 0.000000] hash-mmu: htab_hash_mask = 0x1fffff
[ 0.000000] -----------------------------------------------------
[ 0.000000] numa: NODE_DATA [mem 0x8bfcd6c80-0x8bfcdbfff]
[ 0.000000] rfi-flush: fallback displacement flush available
[ 0.000000] rfi-flush: mttrig type flush available
[ 0.000000] rfi-flush: patched 14 locations (mttrig type flush)
[ 0.000000] count-cache-flush: flush disabled.
[ 0.000000] link-stack-flush: software flush enabled.
[ 0.000000] entry-flush: patched 61 locations (mttrig type flush)
[ 0.000000] uaccess-flush: patched 1 locations (mttrig type flush)
[ 0.000000] stf-barrier: eieio barrier available
[ 0.000000] stf-barrier: patched 61 entry locations (eieio barrier)
[ 0.000000] stf-barrier: patched 14 exit locations (eieio barrier)
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:0 psize:0 block size:8
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:0 psize:2 block size:8
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:0 psize:10 block size:8
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:2 psize:2 block size:8
[ 0.000000] lpar: H_BLOCK_REMOVE supports base psize:2 psize:10 block size:8
[ 0.000000] PPC64 nvram contains 15360 bytes
[ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
[ 0.000000] barrier-nospec: using ORI speculation barrier
[ 0.000000] barrier-nospec: patched 389 locations
[ 0.000000] Top of RAM: 0x8c0000000, Total RAM: 0x8c0000000
[ 0.000000] Memory hole size: 0MB
[ 0.000000] Zone ranges:
[ 0.000000] Normal [mem 0x0000000000000000-0x00000008bfffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000000000-0x00000008bfffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000008bfffffff]
[ 0.000000] percpu: Embedded 37 pages/cpu s114456 r0 d37096 u262144
[ 0.000000] pcpu-alloc: s114456 r0 d37096 u262144 alloc=1*1048576
[ 0.000000] pcpu-alloc: [0] 00 01 02 03 [0] 04 05 06 07
[ 0.000000] pcpu-alloc: [0] 08 09 10 11 [0] 12 13 14 15
[ 0.000000] pcpu-alloc: [0] 16 17 18 19 [0] 20 21 22 23
[ 0.000000] pcpu-alloc: [0] 24 25 26 27 [0] 28 29 30 31
[ 0.000000] pcpu-alloc: [0] 32 33 34 35 [0] 36 37 38 39
[ 0.000000] pcpu-alloc: [0] 40 41 42 43 [0] 44 45 46 47
[ 0.000000] pcpu-alloc: [0] 48 49 50 51 [0] 52 53 54 55
[ 0.000000] pcpu-alloc: [0] 56 57 58 59 [0] 60 61 62 63
[ 0.000000] pcpu-alloc: [0] 64 65 66 67 [0] 68 69 70 71
[ 0.000000] pcpu-alloc: [0] 72 73 74 75 [0] 76 77 78 79
[ 0.000000] pcpu-alloc: [0] 80 81 82 83 [0] 84 85 86 87
[ 0.000000] pcpu-alloc: [0] 88 89 90 91 [0] 92 93 94 95
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 9031680
[ 0.000000] Policy zone: Normal
[ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-5.13.0-rc7-next-20210625 root=UUID=681ebf25-b7c8-49b9-b247-35a96bc8183f ro crashkernel=384M-2G:64M,2G-:512M biosdevname=0
[ 0.000000] Unknown command line parameters: BOOT_IMAGE=/boot/vmlinuz-5.13.0-rc7-next-20210625 crashkernel=384M-2G:64M,2G-:512M biosdevname=0
[ 0.000000] Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] Memory: 35357448K/36700160K available (13252K kernel code, 5244K rwdata, 4064K rodata, 4384K init, 2550K bss, 1342712K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=96, Nodes=2
[ 0.000000] ftrace: allocating 33103 entries in 195 pages
[ 0.000000] ftrace: allocated 194 pages with 3 groups
[ 0.000000] trace event string verifier disabled
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=2048 to nr_cpu_ids=96.
[ 0.000000] Rude variant of Tasks RCU enabled.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=96
[ 0.000000] NR_IRQS: 512, nr_irqs: 512, preallocated irqs: 16
[ 0.000000] pic: no ISA interrupt controller
[ 0.000000] rcu: Offload RCU callbacks from CPUs: (none).
[ 0.000000] random: get_random_u64 called from start_kernel+0x9b0/0xc10 with crng_init=0
[ 0.000000] time_init: decrementer frequency = 512.000000 MHz
[ 0.000000] time_init: processor frequency = 2900.000000 MHz
[ 0.000002] time_init: 56 bit decrementer (max: 7fffffffffffff)
[ 0.000041] clocksource: timebase: mask: 0xffffffffffffffff max_cycles: 0x761537d007, max_idle_ns: 440795202126 ns
[ 0.000108] clocksource: timebase mult[1f40000] shift[24] registered
[ 0.000152] clockevent: decrementer mult[83126f] shift[24] cpu[0]
[ 0.000242] Console: colour dummy device 80x25
[ 0.000274] printk: console [hvc0] enabled
[ 0.000304] printk: bootconsole [udbg0] disabled
[ 0.000371] pid_max: default: 98304 minimum: 768
[ 0.000434] LSM: Security Framework initializing
[ 0.000454] Yama: becoming mindful.
[ 0.000467] SELinux: Initializing.
[ 0.000602] Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.000678] Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.001696] POWER9 performance monitor hardware support registered
[ 0.001737] rcu: Hierarchical SRCU implementation.
[ 0.003750] smp: Bringing up secondary CPUs ...
[ 0.028999] smp: Brought up 1 node, 64 CPUs
[ 0.029008] numa: Node 0 CPUs: 0-63
[ 0.029014] Big cores detected but using small core scheduling
[ 0.030954] devtmpfs: initialized
[ 0.035365] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.035382] futex hash table entries: 32768 (order: 10, 4194304 bytes, linear)
[ 0.035882] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 0.035994] audit: initializing netlink subsys (disabled)
[ 0.036095] audit: type=2000 audit(1624792326.030:1): state=initialized audit_enabled=0 res=1
[ 0.036166] thermal_sys: Registered thermal governor 'fair_share'
[ 0.036167] thermal_sys: Registered thermal governor 'step_wise'
[ 0.036339] cpuidle: using governor menu
[ 0.036381] RTAS daemon started
[ 0.036607] pstore: Registered nvram as persistent store backend
[ 0.037251] EEH: pSeries platform initialized
[ 0.043241] PCI: Probing PCI hardware
[ 0.043248] EEH: No capable adapters found: recovery disabled.
[ 0.043252] PCI: Probing PCI hardware done
[ 0.043348] pseries-rng: Registering arch random hook.
[ 0.044804] Kprobes globally optimized
[ 0.045174] HugeTLB registered 16.0 MiB page size, pre-allocated 0 pages
[ 0.045181] HugeTLB registered 16.0 GiB page size, pre-allocated 0 pages
[ 0.085299] wait_for_initramfs() called before rootfs_initcalls
[ 0.089670] iommu: Default domain type: Translated
[ 0.089719] vgaarb: loaded
[ 0.089801] SCSI subsystem initialized
[ 0.089837] usbcore: registered new interface driver usbfs
[ 0.089848] usbcore: registered new interface driver hub
[ 0.089879] usbcore: registered new device driver usb
[ 0.090040] EDAC MC: Ver: 3.0.0
[ 0.090338] NetLabel: Initializing
[ 0.090342] NetLabel: domain hash size = 128
[ 0.090345] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 0.090359] NetLabel: unlabeled traffic allowed by default
[ 0.091140] clocksource: Switched to clocksource timebase
[ 0.104582] VFS: Disk quotas dquot_6.6.0
[ 0.104616] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.107184] NET: Registered PF_INET protocol family
[ 0.107337] IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[ 0.110050] tcp_listen_portaddr_hash hash table entries: 32768 (order: 7, 524288 bytes, linear)
[ 0.110216] TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[ 0.110952] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[ 0.111044] TCP: Hash tables configured (established 524288 bind 65536)
[ 0.111154] UDP hash table entries: 32768 (order: 8, 1048576 bytes, linear)
[ 0.111252] UDP-Lite hash table entries: 32768 (order: 8, 1048576 bytes, linear)
[ 0.111477] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 0.111486] PCI: CLS 0 bytes, default 128
[ 0.111552] Trying to unpack rootfs image as initramfs...
[ 0.112413] IOMMU table initialized, virtual merging enabled
[ 0.128511] hv-24x7: read 1530 catalog entries, created 509 event attrs (0 failures), 275 descs
[ 0.136073] Initialise system trusted keyrings
[ 0.136166] workingset: timestamp_bits=38 max_order=24 bucket_order=0
[ 0.137613] zbud: loaded
[ 0.158833] NET: Registered PF_ALG protocol family
[ 0.158839] Key type asymmetric registered
[ 0.158842] Asymmetric key parser 'x509' registered
[ 0.158851] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[ 0.158952] io scheduler mq-deadline registered
[ 0.158959] io scheduler kyber registered
[ 0.161625] atomic64_test: passed
[ 0.161667] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[ 0.162265] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.162635] Non-volatile memory driver v1.3
[ 0.162694] Linux agpgart interface v0.103
[ 0.162865] tpm_ibmvtpm 30000003: CRQ initialization completed
[ 1.192320] rdac: device handler registered
[ 1.192440] hp_sw: device handler registered
[ 1.192444] emc: device handler registered
[ 1.192594] alua: device handler registered
[ 1.192733] libphy: Fixed MDIO Bus: probed
[ 1.192787] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.192803] ehci-pci: EHCI PCI platform driver
[ 1.192816] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 1.192828] ohci-pci: OHCI PCI platform driver
[ 1.192839] uhci_hcd: USB Universal Host Controller Interface driver
[ 1.192884] usbcore: registered new interface driver usbserial_generic
[ 1.192893] usbserial: USB Serial support registered for generic
[ 1.192971] mousedev: PS/2 mouse device common for all mice
[ 1.193091] rtc-generic rtc-generic: registered as rtc0
[ 1.193232] rtc-generic rtc-generic: setting system clock to 2021-06-27T11:12:08 UTC (1624792328)
[ 1.194808] pseries_idle_driver registered
[ 1.194820] hid: raw HID events driver (C) Jiri Kosina
[ 1.194961] usbcore: registered new interface driver usbhid
[ 1.194966] usbhid: USB HID core driver
[ 1.195009] drop_monitor: Initializing network drop monitor service
[ 1.195083] Initializing XFRM netlink socket
[ 1.195207] NET: Registered PF_INET6 protocol family
[ 1.195827] Segment Routing with IPv6
[ 1.195848] NET: Registered PF_PACKET protocol family
[ 1.195854] mpls_gso: MPLS GSO support
[ 1.195889] Running MSI bitmap self-tests ...
[ 1.198345] registered taskstats version 1
[ 1.198365] Loading compiled-in X.509 certificates
[ 1.204435] alg: No test for pkcs1pad(rsa,sha1) (pkcs1pad(rsa-generic,sha1))
[ 1.205115] Loaded X.509 cert 'Build time autogenerated kernel key: 94d0d11f98fad0e99a9eff7ee03c5e8d9a4666eb'
[ 1.205577] zswap: loaded using pool lzo/zbud
[ 1.205760] pstore: Using crash dump compression: deflate
[ 1.211882] Freeing unused kernel image (initmem) memory: 4384K
[ 1.311428] Run /init as init process
[ 1.311439] with arguments:
[ 1.311443] /init
[ 1.311446] with environment:
[ 1.311448] HOME=/
[ 1.311451] TERM=linux
[ 1.311454] BOOT_IMAGE=/boot/vmlinuz-5.13.0-rc7-next-20210625
[ 1.311458] crashkernel=384M-2G:64M,2G-:512M
[ 1.311460] biosdevname=0
[ 1.324807] systemd[1]: systemd 239 (239-45.el8) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy)
[ 1.325133] systemd[1]: Detected architecture ppc64-le.
[ 1.325141] systemd[1]: Running in initial RAM disk.
[ 1.401821] systemd[1]: Set hostname to <ltc-zzci-2.aus.stglabs.ibm.com>.
[ 1.502967] random: systemd: uninitialized urandom read (16 bytes read)
[ 1.503095] systemd[1]: Listening on Journal Socket (/dev/log).
[ 1.503295] random: systemd: uninitialized urandom read (16 bytes read)
[ 1.503307] systemd[1]: Reached target Swap.
[ 1.503428] random: systemd: uninitialized urandom read (16 bytes read)
[ 1.503440] systemd[1]: Reached target Local File Systems.
[ 1.503656] systemd[1]: Listening on Journal Socket.
[ 1.505343] systemd[1]: Starting Journal Service...
[ 1.506418] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[ 1.579212] fuse: init (API version 7.34)
[ 1.934212] synth uevent: /devices/vio: failed to send uevent
[ 1.934222] vio vio: uevent: failed to send synthetic uevent
[ 1.934308] synth uevent: /devices/vio/4000: failed to send uevent
[ 1.934312] vio 4000: uevent: failed to send synthetic uevent
[ 1.934328] synth uevent: /devices/vio/4001: failed to send uevent
[ 1.934332] vio 4001: uevent: failed to send synthetic uevent
[ 1.934347] synth uevent: /devices/vio/4002: failed to send uevent
[ 1.934351] vio 4002: uevent: failed to send synthetic uevent
[ 1.934366] synth uevent: /devices/vio/4004: failed to send uevent
[ 1.934370] vio 4004: uevent: failed to send synthetic uevent
[ 1.948417] random: fast init done
[ 2.010019] ibmveth: IBM Power Virtual Ethernet Driver 1.06
[ 2.015699] ibmveth 30000002 env2: renamed from eth0
[ 2.015929] ibmvscsi 30000066: SRP_VERSION: 16.a
[ 2.016030] ibmvscsi 30000066: Maximum ID: 64 Maximum LUN: 32 Maximum Channel: 3
[ 2.016037] scsi host0: IBM POWER Virtual SCSI Adapter 1.5.9
[ 2.016220] ibmvscsi 30000066: partner initialization complete
[ 2.016256] ibmvscsi 30000066: host srp version: 16.a, host partition ltc-zzci-vios1 (100), OS 3, max io 1048576
[ 2.016302] ibmvscsi 30000066: Client reserve enabled
[ 2.016311] ibmvscsi 30000066: sent SRP login
[ 2.016337] ibmvscsi 30000066: SRP_LOGIN succeeded
[ 2.042065] scsi 0:0:1:0: Direct-Access AIX VDASD 0001 PQ: 0 ANSI: 3
[ 2.123138] scsi 0:0:1:0: Attached scsi generic sg0 type 0
[ 2.140916] sd 0:0:1:0: [sda] 585105408 512-byte logical blocks: (300 GB/279 GiB)
[ 2.140958] sd 0:0:1:0: [sda] Write Protect is off
[ 2.140965] sd 0:0:1:0: [sda] Mode Sense: 17 00 00 08
[ 2.140998] sd 0:0:1:0: [sda] Cache data unavailable
[ 2.141005] sd 0:0:1:0: [sda] Assuming drive cache: write through
[ 2.251694] sda: sda1 sda2 sda3 sda4 < sda5 >
[ 2.254194] sd 0:0:1:0: [sda] Attached SCSI disk
[ 4.185107] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[ 5.049039] printk: systemd: 15 output lines suppressed due to ratelimiting
[ 5.499756] SELinux: Runtime disable is deprecated, use selinux=0 on the kernel cmdline.
[ 5.499767] SELinux: Disabled at runtime.
[ 5.671176] audit: type=1404 audit(1624792332.970:2): enforcing=0 old_enforcing=0 auid=4294967295 ses=4294967295 enabled=0 old-enabled=1 lsm=selinux res=1
[ 5.821638] systemd[1]: systemd 239 (239-45.el8) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy)
[ 5.821929] systemd[1]: Detected architecture ppc64-le.
[ 5.831224] systemd[1]: Set hostname to <ltc-zzci-2.aus.stglabs.ibm.com>.
[ 5.885571] random: crng init done
[ 5.885579] random: 7 urandom warning(s) missed due to ratelimiting
[ 6.980906] systemd[1]: systemd-journald.service: Succeeded.
[ 6.987018] systemd[1]: initrd-switch-root.service: Succeeded.
[ 6.987363] systemd[1]: Stopped Switch Root.
[ 6.988010] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart.
[ 6.988108] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
[ 6.988207] systemd[1]: Stopped Journal Service.
[ 6.989344] systemd[1]: Starting Journal Service...
[ 7.148524] EXT4-fs (sda2): re-mounted. Opts: (null). Quota mode: none.
[ 7.196100] Unable to find swap-space signature
[ 7.310654] synth uevent: /devices/vio: failed to send uevent
[ 7.310666] vio vio: uevent: failed to send synthetic uevent
[ 7.310933] synth uevent: /devices/vio/4000: failed to send uevent
[ 7.310937] vio 4000: uevent: failed to send synthetic uevent
[ 7.310953] synth uevent: /devices/vio/4001: failed to send uevent
[ 7.310957] vio 4001: uevent: failed to send synthetic uevent
[ 7.310971] synth uevent: /devices/vio/4002: failed to send uevent
[ 7.310975] vio 4002: uevent: failed to send synthetic uevent
[ 7.310989] synth uevent: /devices/vio/4004: failed to send uevent
[ 7.310993] vio 4004: uevent: failed to send synthetic uevent
[ 8.010469] pseries_rng: Registering IBM pSeries RNG driver
[ 8.399599] ibmveth 30000002 net0: renamed from env2
[ 8.781995] EXT4-fs (sda3): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[ 9.351118] ------------[ cut here ]------------
[ 9.351132] cfs_rq->avg.load_avg || cfs_rq->avg.util_avg || cfs_rq->avg.runnable_avg
[ 9.351138] WARNING: CPU: 48 PID: 0 at kernel/sched/fair.c:3308 update_blocked_averages+0x7c8/0x800
[ 9.351151] Modules linked in: pseries_rng xts vmx_crypto uio_pdrv_genirq uio sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
[ 9.351178] CPU: 48 PID: 0 Comm: swapper/48 Not tainted 5.13.0-rc7-next-20210625 #1
[ 9.351185] NIP: c0000000001b0a28 LR: c0000000001b0a24 CTR: c000000000721f00
[ 9.351190] REGS: c0000000285b74b0 TRAP: 0700 Not tainted (5.13.0-rc7-next-20210625)
[ 9.351195] MSR: 8000000000029033 <SF,EE,ME,IR,DR,RI,LE> CR: 48000224 XER: 00000005
[ 9.351207] CFAR: c000000000145700 IRQMASK: 1
GPR00: c0000000001b0a24 c0000000285b7750 c000000002921e00 0000000000000048
GPR04: 00000000ffff7fff c0000000285b7410 0000000000000027 c0000008bec07e18
GPR08: 0000000000000023 0000000000000001 0000000000000027 c0000000027ddd18
GPR12: 0000000000000000 c000000007fb6200 c0000008bec1a580 000000000000b971
GPR16: 000000022d5eb98e 00000000000001f7 c00000003ccd0c00 0000000000000000
GPR20: 0000000000000000 c000000002944fe0 0000000000000000 00000000000003cc
GPR24: 0000000000000000 c0000008bec1af90 0000000000000001 c0000008bec1a600
GPR28: 00000000000001f7 c00000003ccd0dc0 c00000003ccd0d00 0000000000000000
[ 9.351266] NIP [c0000000001b0a28] update_blocked_averages+0x7c8/0x800
[ 9.351272] LR [c0000000001b0a24] update_blocked_averages+0x7c4/0x800
[ 9.351277] Call Trace:
[ 9.351279] [c0000000285b7750] [c0000000001b0a24] update_blocked_averages+0x7c4/0x800 (unreliable)
[ 9.351287] [c0000000285b7870] [c0000000001b6aa0] run_rebalance_domains+0xa0/0xd0
[ 9.351294] [c0000000285b78a0] [c000000000cee0cc] __do_softirq+0x15c/0x3d4
[ 9.351301] [c0000000285b7990] [c000000000150f34] irq_exit+0x1d4/0x1e0
[ 9.351308] [c0000000285b79c0] [c000000000029da0] timer_interrupt+0x170/0x360
[ 9.351315] [c0000000285b7a20] [c00000000000998c] decrementer_common_virt+0x1ac/0x1b0
[ 9.351322] --- interrupt: 900 at plpar_hcall_norets_notrace+0x18/0x2c
[ 9.351329] NIP: c0000000000ef808 LR: c000000000a050a8 CTR: 0000000000000000
[ 9.351333] REGS: c0000000285b7a90 TRAP: 0900 Not tainted (5.13.0-rc7-next-20210625)
[ 9.351338] MSR: 800000000280b033 <SF,VEC,VSX,EE,FP,ME,IR,DR,RI,LE> CR: 22000284 XER: 20040000
[ 9.351352] CFAR: 0000000000000c00 IRQMASK: 0
GPR00: 0000000000000000 c0000000285b7d30 c000000002921e00 0000000000000000
GPR04: 0000000000000010 000000002c000040 0000000000000002 0000000000000040
GPR08: 0000000000000000 0000000000000240 0000000000000024 0000000000000000
GPR12: 00000000000c7523 c000000007fb6200 0000000000000000 000000001ef29700
GPR16: 0000000000000000 0000000000000000 0000000000000000 0000000000000000
GPR20: 0000000000000000 0000000000000000 0000000000000000 c000000002865e88
GPR24: 0000000000000001 000000022d5bd4e0 0000000000000000 0000000000000001
GPR28: c0000008bec18fc8 0000000000000001 c000000002110998 c0000000021109a0
[ 9.351412] NIP [c0000000000ef808] plpar_hcall_norets_notrace+0x18/0x2c
[ 9.351417] LR [c000000000a050a8] check_and_cede_processor+0x48/0x60
[ 9.351424] --- interrupt: 900
[ 9.351426] [c0000000285b7d30] [c0000000285b7db0] 0xc0000000285b7db0 (unreliable)
[ 9.351433] [c0000000285b7d90] [c000000000a05368] shared_cede_loop+0x78/0x170
[ 9.351440] [c0000000285b7dd0] [c000000000a01c34] cpuidle_enter_state+0x2e4/0x4e0
[ 9.351446] [c0000000285b7e30] [c000000000a01ed0] cpuidle_enter+0x50/0x70
[ 9.351452] [c0000000285b7e70] [c0000000001a318c] call_cpuidle+0x4c/0x80
[ 9.351460] [c0000000285b7e90] [c0000000001a3810] do_idle+0x380/0x3e0
[ 9.351465] [c0000000285b7f10] [c0000000001a3aec] cpu_startup_entry+0x3c/0x50
[ 9.351471] [c0000000285b7f40] [c0000000000654d0] start_secondary+0x280/0x2a0
[ 9.351479] [c0000000285b7f90] [c00000000000d054] start_secondary_prolog+0x10/0x14
[ 9.351485] Instruction dump:
[ 9.351488] 38635a78 9be9fda5 4bf94c99 60000000 0fe00000 4bfff910 e9210070 e8610088
[ 9.351499] 39400001 99490009 4bf94c79 60000000 <0fe00000> e95201ba 2faa0000 4bfffbd0
[ 9.351509] ---[ end trace 5f1d49fb99f3613b ]---
[ 9.427038] RPC: Registered named UNIX socket transport module.
[ 9.427049] RPC: Registered udp transport module.
[ 9.427052] RPC: Registered tcp transport module.
[ 9.427056] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 18.411772] sysrq: Changing Loglevel
[ 18.411785] sysrq: Loglevel set to 9
[ 19.952501] device-mapper: uevent: version 1.0.3
[ 19.952630] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[ 92.105621] ------------[ cut here ]------------
[ 92.105650] WARNING: CPU: 45 PID: 12757 at arch/powerpc/kernel/interrupt.c:518 interrupt_exit_kernel_prepare+0x280/0x2a0
[ 92.105673] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts vmx_crypto uio_pdrv_genirq uio sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
[ 92.105774] CPU: 45 PID: 12757 Comm: sh Kdump: loaded Tainted: G W 5.13.0-rc7-next-20210625 #1
[ 92.105789] NIP: c000000000032a00 LR: c00000000000c958 CTR: 000000000049f42c
[ 92.105800] REGS: c00000008dfeb460 TRAP: 0700 Tainted: G W (5.13.0-rc7-next-20210625)
[ 92.105812] MSR: 8000000000021033 <SF,ME,IR,DR,RI,LE> CR: 28088288 XER: 00000000
[ 92.105846] CFAR: c000000000032814 IRQMASK: 1
GPR00: c00000000000c958 c00000008dfeb700 c000000002921e00 c00000008dfeb7c0
GPR04: 8000000002803033 00000000000064cf 00000000000000d0 ffffffffffffffbf
GPR08: 0000000000000040 0000000000000000 0000000000000003 000000000000000f
GPR12: 0000000000008000 c000000007fb9200 000000012efd81d4 000000012ee50000
GPR16: 0000000000000007 0000400000000008 0000400000000008 c00000002c464948
GPR20: fcffffffffffffff 000000000001ffff 5455555555555555 00003fffffffffff
GPR24: 00003ffff69ff790 0000000000000000 00003ffff69ff400 0000000000000220
GPR28: 00003ffff69ff3f8 fcffffffffffffff 0000000000000000 c00000008dfeb7c0
[ 92.106004] NIP [c000000000032a00] interrupt_exit_kernel_prepare+0x280/0x2a0
[ 92.106017] LR [c00000000000c958] interrupt_return_srr_user_restart+0x2c/0x110
[ 92.106030] Call Trace:
[ 92.106036] [c00000008dfeb700] [00003ffff69ff400] 0x3ffff69ff400 (unreliable)
[ 92.106049] [c00000008dfeb750] [c00000000000c958] interrupt_return_srr_user_restart+0x2c/0x110
[ 92.106065] --- interrupt: 300 at strnlen_user+0xe0/0x240
[ 92.106076] NIP: c0000000006ff820 LR: c000000000541fc4 CTR: 0000000000003fc3
[ 92.106085] REGS: c00000008dfeb7c0 TRAP: 0300 Tainted: G W (5.13.0-rc7-next-20210625)
[ 92.106095] MSR: 8000000002803033 <SF,VEC,VSX,FP,ME,IR,DR,RI,LE> CR: 44088222 XER: 00000000
[ 92.106127] CFAR: c0000000006ff844 DAR: 00003ffff6a00000 DSISR: 40000000 IRQMASK: 0
GPR00: c000000000541fc4 c00000008dfeba60 c000000002921e00 0000000000000000
GPR04: 0000000000020000 0000000000020008 00000000000001e8 00003ffff69ffe10
GPR08: 00000000000001f0 0000000000000000 0000000000000000 0000000000000000
GPR12: 0000000000000006 c000000007fb9200 000000012efd81d4 000000012ee50000
GPR16: 0000000000000007 0000400000000008 0000400000000008 c00000002c464948
GPR20: fcffffffffffffff 000000000001ffff 5455555555555555 00003fffffffffff
GPR24: 00003ffff69ff790 0000000000000000 00003ffff69ff400 0000000000000220
GPR28: 00003ffff69ff3f8 00003ffff69ffe16 00003ffff69ff400 c00000002c464800
[ 92.106256] NIP [c0000000006ff820] strnlen_user+0xe0/0x240
[ 92.106267] LR [c000000000541fc4] create_elf_tables.isra.22+0xa04/0xc80
[ 92.106279] --- interrupt: 300
[ 92.106285] [c00000008dfeba60] [c000000000ce91b8] down_read_killable+0x28/0x130 (unreliable)
[ 92.106302] [c00000008dfeba90] [c000000000541fc4] create_elf_tables.isra.22+0xa04/0xc80
[ 92.106315] [c00000008dfebb50] [c000000000543050] load_elf_binary+0xe10/0x1220
[ 92.106329] [c00000008dfebc40] [c00000000047ded0] bprm_execve+0x410/0x800
[ 92.106343] [c00000008dfebd10] [c00000000047e8ec] do_execveat_common.isra.44+0x21c/0x240
[ 92.106356] [c00000008dfebd80] [c00000000047e964] sys_execve+0x54/0x70
[ 92.106368] [c00000008dfebdb0] [c000000000032334] system_call_exception+0x164/0x2e0
[ 92.106382] [c00000008dfebe10] [c00000000000c464] system_call_common+0xf4/0x258
[ 92.106396] --- interrupt: c00 at 0x3fff9bb6b8a8
[ 92.106405] NIP: 00003fff9bb6b8a8 LR: 00003fff9bb6c240 CTR: 0000000000000000
[ 92.106414] REGS: c00000008dfebe80 TRAP: 0c00 Tainted: G W (5.13.0-rc7-next-20210625)
[ 92.106424] MSR: 800000000000f033 <SF,EE,PR,FP,ME,IR,DR,RI,LE> CR: 28004224 XER: 00000000
[ 92.106454] IRQMASK: 0
GPR00: 000000000000000b 00003fffc36a1440 00003fff9bc87300 00000100268a67d0
GPR04: 0000010026887e50 0000010026882c50 fefefefefefefeff 7f7f7f7f7f7f7f7f
GPR08: 00000100268a67d0 0000000000000000 0000000000000000 0000000000000000
GPR12: 0000000000000000 00003fff9bce3780 0000000114200db4 0000000000000000
GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40
GPR20: 00000001141dd820 0000000000000000 00000001141dd740 0000000114204358
GPR24: 0000000114203948 0000010026876454 0000000000000001 0000010026882c50
GPR28: 0000010026887e50 0000010026882c50 00000100268a67d0 00003fffc36a1440
[ 92.106579] NIP [00003fff9bb6b8a8] 0x3fff9bb6b8a8
[ 92.106588] LR [00003fff9bb6c240] 0x3fff9bb6c240
[ 92.106596] --- interrupt: c00
[ 92.106602] Instruction dump:
[ 92.106611] 71290001 892d0153 61290001 992d0153 4082000c 392d0138 7c20492a 4bfe362d
[ 92.106631] 60000000 4bfffe34 60000000 60000000 <0fe00000> 4bfffe14 60000000 60000000
[ 92.106654] ---[ end trace 5f1d49fb99f3613c ]---
[ 92.106731] ------------[ cut here ]------------
[ 92.106738] WARNING: CPU: 45 PID: 12757 at arch/powerpc/kernel/irq.c:255 arch_local_irq_restore+0x1d0/0x200
[ 92.106753] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts vmx_crypto uio_pdrv_genirq uio sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
[ 92.106828] CPU: 45 PID: 12757 Comm: sh Kdump: loaded Tainted: G W 5.13.0-rc7-next-20210625 #1
[ 92.106841] NIP: c0000000000164d0 LR: c000000000cedaa8 CTR: 0000000000000000
[ 92.106849] REGS: c00000008dfeb7e0 TRAP: 0700 Tainted: G W (5.13.0-rc7-next-20210625)
[ 92.106859] MSR: 8000000002823033 <SF,VEC,VSX,FP,ME,IR,DR,RI,LE> CR: 28004222 XER: 00000000
[ 92.106892] CFAR: c00000000001632c IRQMASK: 0
GPR00: c000000000ceda98 c00000008dfeba80 c000000002921e00 0000000000000000
GPR04: 0000000000000000 0000000000000000 0000000000000000 00000000000000ff
GPR08: 0000000000000001 0000000000000000 0000000000000001 0000000000000017
GPR12: 0000000024004822 c000000007fb9200 000000012efd81d4 000000012ee50000
GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40
GPR20: 00003fffa93f8000 0000000000000000 00003fffa93f9300 000000012efb1988
GPR24: 000000012ee7fe7c 000000012efccba0 000000012ee50000 c00000008d5d7600
GPR28: c0000000314c0bc0 c000000040d9f100 c0000008beb5861c 4b72201a3063fe13
[ 92.107024] NIP [c0000000000164d0] arch_local_irq_restore+0x1d0/0x200
[ 92.107035] LR [c000000000cedaa8] _raw_spin_unlock_irqrestore+0x88/0xb0
[ 92.107047] Call Trace:
[ 92.107052] [c00000008dfeba80] [c00000008dfebb50] 0xc00000008dfebb50 (unreliable)
[ 92.107065] [c00000008dfebab0] [238c5bf052df0858] 0x238c5bf052df0858
[ 92.107076] [c00000008dfebae0] [c0000000008178e8] get_random_u64+0x88/0x100
[ 92.107090] [c00000008dfebb20] [c000000000020134] arch_randomize_brk+0xb4/0xd8
[ 92.107105] [c00000008dfebb50] [c0000000005430b0] load_elf_binary+0xe70/0x1220
[ 92.107119] [c00000008dfebc40] [c00000000047ded0] bprm_execve+0x410/0x800
[ 92.107132] [c00000008dfebd10] [c00000000047e8ec] do_execveat_common.isra.44+0x21c/0x240
[ 92.107145] [c00000008dfebd80] [c00000000047e964] sys_execve+0x54/0x70
[ 92.107157] [c00000008dfebdb0] [c000000000032334] system_call_exception+0x164/0x2e0
[ 92.107169] [c00000008dfebe10] [c00000000000c464] system_call_common+0xf4/0x258
[ 92.107185] --- interrupt: c00 at 0x3fff9bb6b8a8
[ 92.107193] NIP: 00003fff9bb6b8a8 LR: 00003fff9bb6c240 CTR: 0000000000000000
[ 92.107202] REGS: c00000008dfebe80 TRAP: 0c00 Tainted: G W (5.13.0-rc7-next-20210625)
[ 92.107213] MSR: 800000000000f033 <SF,EE,PR,FP,ME,IR,DR,RI,LE> CR: 28004224 XER: 00000000
[ 92.107243] IRQMASK: 0
GPR00: 000000000000000b 00003fffc36a1440 00003fff9bc87300 00000100268a67d0
GPR04: 0000010026887e50 0000010026882c50 fefefefefefefeff 7f7f7f7f7f7f7f7f
GPR08: 00000100268a67d0 0000000000000000 0000000000000000 0000000000000000
GPR12: 0000000000000000 00003fff9bce3780 0000000114200db4 0000000000000000
GPR16: 0000000000000001 00000100268a0e00 000001002687ec10 0000000114200c40
GPR20: 00000001141dd820 0000000000000000 00000001141dd740 0000000114204358
GPR24: 0000000114203948 0000010026876454 0000000000000001 0000010026882c50
GPR28: 0000010026887e50 0000010026882c50 00000100268a67d0 00003fffc36a1440
[ 92.107369] NIP [00003fff9bb6b8a8] 0x3fff9bb6b8a8
[ 92.107378] LR [00003fff9bb6c240] 0x3fff9bb6c240
[ 92.107386] --- interrupt: c00
[ 92.107393] Instruction dump:
[ 92.107400] 7d2000a6 71298000 40820048 39200000 992d0152 39400000 992d0153 614a8002
[ 92.107427] 7d410164 4bfffe6c 60000000 60000000 <0fe00000> 4bfffe5c 60000000 60000000
[ 92.107451] ---[ end trace 5f1d49fb99f3613d ]---
^ permalink raw reply
* Re: [powerpc][next-20210625] Kernel warning(arch/powerpc/kernel/interrupt.c:518) during boot
From: Nicholas Piggin @ 2021-06-27 10:06 UTC (permalink / raw)
To: linuxppc-dev, Sachin Sant; +Cc: linux-next
In-Reply-To: <1624733491.pxug6c02ws.astroid@bobo.none>
Excerpts from Nicholas Piggin's message of June 27, 2021 4:57 am:
> Excerpts from Sachin Sant's message of June 26, 2021 11:52 pm:
>> Following kernel warning is seen while booting 5.13.0-rc7-next-20210625
>> on POWER9 LPAR.
>>
>> [ 40.573592] ------------[ cut here ]------------
>> [ 40.573604] WARNING: CPU: 6 PID: 4743 at arch/powerpc/kernel/interrupt.c:518 interrupt_exit_kernel_prepare+0x280/0x2a0
>> [ 40.573614] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts uio_pdrv_genirq uio vmx_crypto sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
>> [ 40.573649] CPU: 6 PID: 4743 Comm: dracut-install Not tainted 5.13.0-rc7-next-20210625 #1
>> [ 40.573655] NIP: c000000000032990 LR: c00000000000c958 CTR: 000000000048dd1c
>> [ 40.573660] REGS: c0000000414db640 TRAP: 0700 Not tainted (5.13.0-rc7-next-20210625)
>> [ 40.573664] MSR: 8000000000021033 <SF,ME,IR,DR,RI,LE> CR: 28044288 XER: 00000000
>> [ 40.573674] CFAR: c0000000000327a4 IRQMASK: 1
>> GPR00: c00000000000c958 c0000000414db8e0 c0000000029bbd00 c0000000414db9a0
>> GPR04: 8000000000001033 0000000000000093 0000000000000048 ffffffffffffffbf
>> GPR08: 0000000000000008 0000000000000000 0000000000000003 0000000000000010
>> GPR12: 0000000000004000 c000000005587a00 0000000101dc15a8 0000000101dc1590
>> GPR16: 0000000101dc05a8 00007fffc7abe353 00007fffb7926740 0000000000000000
>> GPR20: 00007fffc7ab7ae0 fffffffffffff000 0000000000000006 c000000043cbbc00
>> GPR24: 0000000000000000 000001003da495d0 0000000000000000 0000000000000000
>> GPR28: 0000000000000000 fcffffffffffffff 0000000000000000 c0000000414db9a0
>> [ 40.573725] NIP [c000000000032990] interrupt_exit_kernel_prepare+0x280/0x2a0
>> [ 40.573730] LR [c00000000000c958] interrupt_return_srr_user_restart+0x34/0x118
>
> BTW this isn't a restart but a kernel exit. I'll have to update labels
> to make this clear.
>
>> [ 40.573736] Call Trace:
>> [ 40.573738] [c0000000414db8e0] [c000000043cbbc00] 0xc000000043cbbc00 (unreliable)
>> [ 40.573744] [c0000000414db930] [c00000000000c958] interrupt_return_srr_user_restart+0x34/0x118
>> [ 40.573751] --- interrupt: 300 at strnlen_user+0x74/0x240
>> [ 40.573756] NIP: c00000000070ccf4 LR: c00000000048a460 CTR: 000000000003fffe
>> [ 40.573760] REGS: c0000000414db9a0 TRAP: 0300 Not tainted (5.13.0-rc7-next-20210625)
>> [ 40.573764] MSR: 8000000000001033 <SF,ME,IR,DR,RI,LE> CR: 48044228 XER: 20040000
>> [ 40.573774] CFAR: c00000000048a45c DAR: 000001003da495d0 DSISR: 40000000 IRQMASK: 0
>> GPR00: c00000000048a44c c0000000414dbc40 c0000000029bbd00 0000000000000000
>> GPR04: 0000000000200000 0000000000000030 c000000043cbbc00 000001003da495d0
>> GPR08: a8aaaaaaaaaaaaaa bcffffffffffffff 000001003da495d0 0000000000000000
>> GPR12: 0000000000004000 c000000005587a00 0000000101dc15a8 0000000101dc1590
>> GPR16: 0000000101dc05a8 00007fffc7abe353 00007fffb7926740 0000000000000000
>> GPR20: 00007fffc7ab7ae0 fffffffffffff000 0000000000000006 c000000043cbbc00
>> GPR24: 0000000000000000 000001003da495d0 0000000000000000 0000000000000000
>> GPR28: 0000000000000000 c000000043b6a000 c000000043cbbc00 0000000000000000
>> [ 40.573826] NIP [c00000000070ccf4] strnlen_user+0x74/0x240
>> [ 40.573830] LR [c00000000048a460] copy_strings.isra.42+0xb0/0x350
>
> So there's definitely IRQMASK=0 and no MSR[EE]=0 in this frame, which is
> what the warning was.
>
> I'd say either something hasn't set PACA_IRQ_HARD_DIS properly, so EE
> doesn't get enabled when irqs are restored, or maybe the change to
> arch_local_irq_restore(). Less likely that the stack got messed up.
>
> Can you try run with CONFIG_PPC_IRQ_SOFT_MASK_DEBUG=y ?
Nevermind, I think I've found the problem. Some code runs in the
implicit soft-mask region without expecting to be masked. Working
on a fix...
Thanks,
Nick
^ permalink raw reply
* Re: [PATCH v2 4/4] powerpc: wii_defconfig: Enable OTP by default
From: Jonathan Neuschäfer @ 2021-06-26 23:38 UTC (permalink / raw)
To: Emmanuel Gil Peyrot
Cc: devicetree, linux-kernel, Rob Herring, Srinivas Kandagatla,
Ash Logan, Paul Mackerras, linuxppc-dev, Jonathan Neuschäfer
In-Reply-To: <20210519095044.4109-5-linkmauve@linkmauve.fr>
[-- Attachment #1: Type: text/plain, Size: 308 bytes --]
On Wed, May 19, 2021 at 11:50:44AM +0200, Emmanuel Gil Peyrot wrote:
> This selects the nintendo-otp module when building for this platform, if
> CONFIG_NVMEM is also selected.
The 'if' is a bit confusing. CONFIG_NVRAM=y has indeed been in
wii_defconfig since 2009.
Thanks,
Jonathan Neuschäfer
[-- Attachment #2: signature.asc --]
[-- Type: application/pgp-signature, Size: 833 bytes --]
^ permalink raw reply
* Re: [PATCH v2 3/4] powerpc: wii.dts: Expose the OTP on this platform
From: Jonathan Neuschäfer @ 2021-06-26 23:34 UTC (permalink / raw)
To: Emmanuel Gil Peyrot
Cc: devicetree, linux-kernel, Rob Herring, Srinivas Kandagatla,
Ash Logan, Paul Mackerras, linuxppc-dev, Jonathan Neuschäfer
In-Reply-To: <20210519095044.4109-4-linkmauve@linkmauve.fr>
[-- Attachment #1: Type: text/plain, Size: 1149 bytes --]
On Wed, May 19, 2021 at 11:50:43AM +0200, Emmanuel Gil Peyrot wrote:
> This can be used by the newly-added nintendo-otp nvmem module.
>
> Signed-off-by: Emmanuel Gil Peyrot <linkmauve@linkmauve.fr>
> ---
> arch/powerpc/boot/dts/wii.dts | 5 +++++
> 1 file changed, 5 insertions(+)
>
> diff --git a/arch/powerpc/boot/dts/wii.dts b/arch/powerpc/boot/dts/wii.dts
> index aaa381da1906..7837c4a3f09c 100644
> --- a/arch/powerpc/boot/dts/wii.dts
> +++ b/arch/powerpc/boot/dts/wii.dts
> @@ -219,6 +219,11 @@ control@d800100 {
> reg = <0x0d800100 0x300>;
> };
>
> + otp@d8001ec {
> + compatible = "nintendo,hollywood-otp";
> + reg = <0x0d8001ec 0x8>;
The OTP registers overlap with the previous node, control@d800100.
Not sure what's the best way to structure the devicetree in this case,
maybe something roughly like the following (untested, unverified):
control@d800100 {
compatible = "nintendo,hollywood-control", "simple-mfd";
reg = <0x0d800100 0x300>;
ranges;
otp@d8001ec {
compatible = "nintendo,hollywood-otp";
reg = <0x0d8001ec 0x8>;
};
};
Thanks,
Jonathan Neuschäfer
[-- Attachment #2: signature.asc --]
[-- Type: application/pgp-signature, Size: 833 bytes --]
^ permalink raw reply
* Re: [PATCH v2 1/4] nvmem: nintendo-otp: Add new driver for the Wii and Wii U OTP
From: Jonathan Neuschäfer @ 2021-06-26 23:24 UTC (permalink / raw)
To: Emmanuel Gil Peyrot
Cc: devicetree, linux-kernel, Rob Herring, Srinivas Kandagatla,
Ash Logan, Paul Mackerras, linuxppc-dev, Jonathan Neuschäfer
In-Reply-To: <20210519095044.4109-2-linkmauve@linkmauve.fr>
[-- Attachment #1: Type: text/plain, Size: 2612 bytes --]
Hi,
On Wed, May 19, 2021 at 11:50:41AM +0200, Emmanuel Gil Peyrot wrote:
> This OTP is read-only and contains various keys used by the console to
> decrypt, encrypt or verify various pieces of storage.
>
> Its size depends on the console, it is 128 bytes on the Wii and
> 1024 bytes on the Wii U (split into eight 128 bytes banks).
>
> It can be used directly by writing into one register and reading from
> the other one, without any additional synchronisation.
>
> Signed-off-by: Emmanuel Gil Peyrot <linkmauve@linkmauve.fr>
> ---
A link to the (third-party) documentation would be nice, either in the
commit message or in the code itself.
(https://wiiubrew.org/wiki/Hardware/OTP i guess)
[...]
> +static int nintendo_otp_reg_read(void *context,
> + unsigned int reg, void *_val, size_t bytes)
> +{
> + struct nintendo_otp_priv *priv = context;
> + u32 *val = _val;
> + int words = bytes >> 2;
> + u32 bank, addr;
> +
> + while (words--) {
> + bank = (reg << 1) & ~0xff;
This is a bit non-obvious, IMHO. As far as I understand it, the expanded
formula is:
bank = (reg / 128) << 8;
I.e. first divide by bank size, then shift the parameter into the right
place.
> + addr = (reg >> 2) & 0x1f;
Here, I think it's about the word size (4 bytes); I think / 4 would be
clearer.
I *think* (but haven't checked) that gcc should generate efficent shifts
for the divisions above, so using the division operator shouldn't be
problem.
> + iowrite32be(OTP_READ | bank | addr, priv->regs + HW_OTPCMD);
> + *val++ = ioread32be(priv->regs + HW_OTPDATA);
> + reg += 4;
> + }
> +
> + return 0;
> +}
> +
[...]
> + if (of_id->data) {
> + const struct nintendo_otp_devtype_data *data = of_id->data;
> + config.name = data->name;
> + config.size = data->num_banks * 128;
Given that 128 appears a few times, perhaps a #define would be good.
> + }
> +
> + config.dev = dev;
> + config.priv = priv;
> +
> + nvmem = devm_nvmem_register(dev, &config);
> +
> + return PTR_ERR_OR_ZERO(nvmem);
> +}
> +
> +static struct platform_driver nintendo_otp_driver = {
> + .probe = nintendo_otp_probe,
> + .driver = {
> + .name = "nintendo-otp",
> + .of_match_table = nintendo_otp_of_table,
> + },
> +};
> +module_platform_driver(nintendo_otp_driver);
> +MODULE_AUTHOR("Emmanuel Gil Peyrot <linkmauve@linkmauve.fr>");
> +MODULE_DESCRIPTION("Nintendo Wii and Wii U OTP driver");
> +MODULE_LICENSE("GPL v2");
> --
> 2.31.1
>
Tested-by: Jonathan Neuschäfer <j.ne@posteo.net> # on Wii
Thanks,
Jonathan Neuschäfer
[-- Attachment #2: signature.asc --]
[-- Type: application/pgp-signature, Size: 833 bytes --]
^ permalink raw reply
* Re: [PATCH v2 2/4] dt-bindings: nintendo-otp: Document the Wii and Wii U OTP support
From: Jonathan Neuschäfer @ 2021-06-26 21:27 UTC (permalink / raw)
To: Emmanuel Gil Peyrot
Cc: devicetree, linux-kernel, Rob Herring, Srinivas Kandagatla,
Ash Logan, Paul Mackerras, linuxppc-dev, Jonathan Neuschäfer
In-Reply-To: <20210519095044.4109-3-linkmauve@linkmauve.fr>
[-- Attachment #1: Type: text/plain, Size: 1408 bytes --]
Hello and sorry for the delay,
On Wed, May 19, 2021 at 11:50:42AM +0200, Emmanuel Gil Peyrot wrote:
> Both of these consoles use the exact same two registers, even at the
> same address, but the Wii U has eight banks of 128 bytes memory while
> the Wii only has one, hence the two compatible strings.
>
> Signed-off-by: Emmanuel Gil Peyrot <linkmauve@linkmauve.fr>
A link to the (third-party) documentation for the OTP device would be nice.
Best regards,
Jonathan Neuschäfer
> ---
> .../devicetree/bindings/nvmem/nintendo-otp.txt | 14 ++++++++++++++
> 1 file changed, 14 insertions(+)
> create mode 100644 Documentation/devicetree/bindings/nvmem/nintendo-otp.txt
>
> diff --git a/Documentation/devicetree/bindings/nvmem/nintendo-otp.txt b/Documentation/devicetree/bindings/nvmem/nintendo-otp.txt
> new file mode 100644
> index 000000000000..b26d705ec52d
> --- /dev/null
> +++ b/Documentation/devicetree/bindings/nvmem/nintendo-otp.txt
> @@ -0,0 +1,14 @@
> +Nintendo Wii and Wii U OTP
> +
> +Required Properties:
> +- compatible: depending on the console this should be one of:
> + - "nintendo,hollywood-otp" for the Wii
> + - "nintendo,latte-otp" for the Wii U
> +- reg: base address and size of the OTP registers
> +
> +
> +Example:
> + otp@d8001ec {
> + compatible = "nintendo,latte-otp";
> + reg = <0x0d8001ec 0x8>;
> + };
> --
> 2.31.1
>
[-- Attachment #2: signature.asc --]
[-- Type: application/pgp-signature, Size: 833 bytes --]
^ permalink raw reply
* [powerpc:next] BUILD SUCCESS b064037ea4104120418ccbf39951a6d529a9d6d5
From: kernel test robot @ 2021-06-26 22:50 UTC (permalink / raw)
To: Michael Ellerman; +Cc: linuxppc-dev
tree/branch: https://git.kernel.org/pub/scm/linux/kernel/git/powerpc/linux.git next
branch HEAD: b064037ea4104120418ccbf39951a6d529a9d6d5 powerpc/interrupt: Use names in check_return_regs_valid()
elapsed time: 723m
configs tested: 154
configs skipped: 35
The following configs have been built successfully.
More configs may be tested in the coming days.
gcc tested configs:
arm defconfig
arm allyesconfig
arm allmodconfig
mips maltasmvp_eva_defconfig
mips db1xxx_defconfig
sh apsh4ad0a_defconfig
mips tb0226_defconfig
s390 debug_defconfig
powerpc mpc832x_mds_defconfig
arc hsdk_defconfig
arc tb10x_defconfig
ia64 zx1_defconfig
powerpc ps3_defconfig
powerpc mgcoge_defconfig
sh sh7785lcr_defconfig
sh se7721_defconfig
sh edosk7705_defconfig
arm bcm2835_defconfig
sh j2_defconfig
sh r7785rp_defconfig
h8300 alldefconfig
mips decstation_r4k_defconfig
m68k mvme16x_defconfig
arm omap1_defconfig
riscv allyesconfig
arm pxa3xx_defconfig
arm moxart_defconfig
arm u8500_defconfig
arm palmz72_defconfig
powerpc mpc834x_mds_defconfig
arm s3c6400_defconfig
mips pic32mzda_defconfig
powerpc mpc7448_hpc2_defconfig
powerpc tqm8560_defconfig
arc nsimosci_hs_defconfig
arm hackkit_defconfig
mips cu1830-neo_defconfig
powerpc xes_mpc85xx_defconfig
m68k m5275evb_defconfig
powerpc pq2fads_defconfig
arm vf610m4_defconfig
m68k mac_defconfig
microblaze mmu_defconfig
arm trizeps4_defconfig
arm cerfcube_defconfig
sh shx3_defconfig
powerpc socrates_defconfig
powerpc g5_defconfig
powerpc fsp2_defconfig
sparc64 defconfig
sparc sparc32_defconfig
sh rsk7201_defconfig
powerpc mpc866_ads_defconfig
powerpc adder875_defconfig
mips malta_kvm_defconfig
arm orion5x_defconfig
x86_64 allnoconfig
ia64 allmodconfig
ia64 defconfig
ia64 allyesconfig
m68k allmodconfig
m68k defconfig
m68k allyesconfig
nios2 defconfig
arc allyesconfig
nds32 allnoconfig
nds32 defconfig
nios2 allyesconfig
csky defconfig
alpha defconfig
alpha allyesconfig
xtensa allyesconfig
h8300 allyesconfig
arc defconfig
sh allmodconfig
parisc defconfig
s390 allyesconfig
s390 allmodconfig
parisc allyesconfig
s390 defconfig
i386 allyesconfig
sparc allyesconfig
i386 defconfig
mips allyesconfig
mips allmodconfig
powerpc allyesconfig
powerpc allmodconfig
powerpc allnoconfig
x86_64 randconfig-a002-20210625
x86_64 randconfig-a001-20210625
x86_64 randconfig-a005-20210625
x86_64 randconfig-a003-20210625
x86_64 randconfig-a004-20210625
x86_64 randconfig-a006-20210625
i386 randconfig-a002-20210625
i386 randconfig-a001-20210625
i386 randconfig-a003-20210625
i386 randconfig-a006-20210625
i386 randconfig-a005-20210625
i386 randconfig-a004-20210625
x86_64 randconfig-a012-20210622
x86_64 randconfig-a016-20210622
x86_64 randconfig-a015-20210622
x86_64 randconfig-a014-20210622
x86_64 randconfig-a013-20210622
x86_64 randconfig-a011-20210622
i386 randconfig-a011-20210626
i386 randconfig-a014-20210626
i386 randconfig-a013-20210626
i386 randconfig-a015-20210626
i386 randconfig-a012-20210626
i386 randconfig-a016-20210626
i386 randconfig-a011-20210625
i386 randconfig-a014-20210625
i386 randconfig-a013-20210625
i386 randconfig-a015-20210625
i386 randconfig-a012-20210625
i386 randconfig-a016-20210625
riscv nommu_k210_defconfig
riscv nommu_virt_defconfig
riscv allnoconfig
riscv defconfig
riscv rv32_defconfig
riscv allmodconfig
x86_64 rhel-8.3-kselftests
um x86_64_defconfig
um i386_defconfig
um kunit_defconfig
x86_64 allyesconfig
x86_64 defconfig
x86_64 rhel-8.3
x86_64 rhel-8.3-kbuiltin
x86_64 kexec
clang tested configs:
x86_64 randconfig-b001-20210622
x86_64 randconfig-b001-20210626
x86_64 randconfig-b001-20210625
x86_64 randconfig-a002-20210626
x86_64 randconfig-a001-20210626
x86_64 randconfig-a005-20210626
x86_64 randconfig-a003-20210626
x86_64 randconfig-a004-20210626
x86_64 randconfig-a006-20210626
x86_64 randconfig-a002-20210622
x86_64 randconfig-a001-20210622
x86_64 randconfig-a005-20210622
x86_64 randconfig-a003-20210622
x86_64 randconfig-a004-20210622
x86_64 randconfig-a006-20210622
x86_64 randconfig-a012-20210625
x86_64 randconfig-a016-20210625
x86_64 randconfig-a015-20210625
x86_64 randconfig-a014-20210625
x86_64 randconfig-a013-20210625
x86_64 randconfig-a011-20210625
---
0-DAY CI Kernel Test Service, Intel Corporation
https://lists.01.org/hyperkitty/list/kbuild-all@lists.01.org
^ permalink raw reply
* [powerpc:merge] BUILD REGRESSION 0f7a719601eb957c10d417c62bd5f65080b5a409
From: kernel test robot @ 2021-06-26 22:50 UTC (permalink / raw)
To: Michael Ellerman; +Cc: linuxppc-dev
tree/branch: https://git.kernel.org/pub/scm/linux/kernel/git/powerpc/linux.git merge
branch HEAD: 0f7a719601eb957c10d417c62bd5f65080b5a409 Automatic merge of 'next' into merge (2021-06-26 20:22)
Error/Warning in current branch:
arch/powerpc/kernel/hw_breakpoint.c:735:38: error: variable 'ea' is uninitialized when used here [-Werror,-Wuninitialized]
Error/Warning ids grouped by kconfigs:
clang_recent_errors
`-- powerpc64-buildonly-randconfig-r006-20210626
`-- arch-powerpc-kernel-hw_breakpoint.c:error:variable-ea-is-uninitialized-when-used-here-Werror-Wuninitialized
elapsed time: 723m
configs tested: 172
configs skipped: 2
gcc tested configs:
arm defconfig
arm64 allyesconfig
arm64 defconfig
arm allyesconfig
arm allmodconfig
mips ip28_defconfig
powerpc tqm5200_defconfig
powerpc mpc8313_rdb_defconfig
arm spitz_defconfig
sh se7619_defconfig
um defconfig
powerpc mpc834x_itxgp_defconfig
mips malta_kvm_defconfig
mips malta_qemu_32r6_defconfig
arm ep93xx_defconfig
xtensa alldefconfig
mips bcm47xx_defconfig
powerpc skiroot_defconfig
microblaze defconfig
powerpc mpc836x_mds_defconfig
sh rsk7201_defconfig
powerpc ps3_defconfig
powerpc mgcoge_defconfig
sh sh7785lcr_defconfig
sh se7721_defconfig
sh edosk7705_defconfig
arm bcm2835_defconfig
sh j2_defconfig
sh r7785rp_defconfig
h8300 alldefconfig
mips decstation_r4k_defconfig
m68k mvme16x_defconfig
riscv allyesconfig
arm omap1_defconfig
mips gcw0_defconfig
sh se7724_defconfig
arm lart_defconfig
mips cu1000-neo_defconfig
mips maltaaprp_defconfig
arm footbridge_defconfig
arm multi_v4t_defconfig
powerpc mpc837x_mds_defconfig
powerpc tqm8540_defconfig
arm integrator_defconfig
arm pxa3xx_defconfig
arm moxart_defconfig
arm u8500_defconfig
arm palmz72_defconfig
powerpc mpc834x_mds_defconfig
powerpc mpc834x_itx_defconfig
mips fuloong2e_defconfig
sh sh7785lcr_32bit_defconfig
arm zeus_defconfig
arc axs101_defconfig
sh landisk_defconfig
ia64 tiger_defconfig
sh dreamcast_defconfig
arm s3c6400_defconfig
mips pic32mzda_defconfig
arc hsdk_defconfig
powerpc mpc7448_hpc2_defconfig
powerpc tqm8560_defconfig
arc nsimosci_hs_defconfig
powerpc pq2fads_defconfig
arm vf610m4_defconfig
m68k mac_defconfig
ia64 zx1_defconfig
microblaze mmu_defconfig
arm trizeps4_defconfig
powerpc socrates_defconfig
powerpc g5_defconfig
powerpc fsp2_defconfig
sparc64 defconfig
sparc sparc32_defconfig
x86_64 allnoconfig
ia64 allmodconfig
ia64 defconfig
ia64 allyesconfig
m68k allmodconfig
m68k defconfig
m68k allyesconfig
nds32 defconfig
nios2 allyesconfig
csky defconfig
alpha defconfig
alpha allyesconfig
xtensa allyesconfig
h8300 allyesconfig
arc defconfig
sh allmodconfig
parisc defconfig
s390 allyesconfig
s390 allmodconfig
parisc allyesconfig
s390 defconfig
nios2 defconfig
arc allyesconfig
nds32 allnoconfig
i386 allyesconfig
sparc allyesconfig
sparc defconfig
i386 defconfig
mips allyesconfig
mips allmodconfig
powerpc allyesconfig
powerpc allmodconfig
powerpc allnoconfig
i386 randconfig-a002-20210625
i386 randconfig-a001-20210625
i386 randconfig-a003-20210625
i386 randconfig-a006-20210625
i386 randconfig-a005-20210625
i386 randconfig-a004-20210625
x86_64 randconfig-a012-20210626
x86_64 randconfig-a016-20210626
x86_64 randconfig-a015-20210626
x86_64 randconfig-a014-20210626
x86_64 randconfig-a013-20210626
x86_64 randconfig-a011-20210626
x86_64 randconfig-a012-20210622
x86_64 randconfig-a016-20210622
x86_64 randconfig-a015-20210622
x86_64 randconfig-a014-20210622
x86_64 randconfig-a013-20210622
x86_64 randconfig-a011-20210622
i386 randconfig-a011-20210625
i386 randconfig-a014-20210625
i386 randconfig-a013-20210625
i386 randconfig-a015-20210625
i386 randconfig-a012-20210625
i386 randconfig-a016-20210625
i386 randconfig-a011-20210622
i386 randconfig-a014-20210622
i386 randconfig-a013-20210622
i386 randconfig-a015-20210622
i386 randconfig-a012-20210622
i386 randconfig-a016-20210622
i386 randconfig-a011-20210626
i386 randconfig-a014-20210626
i386 randconfig-a013-20210626
i386 randconfig-a015-20210626
i386 randconfig-a012-20210626
i386 randconfig-a016-20210626
x86_64 randconfig-a002-20210625
x86_64 randconfig-a001-20210625
x86_64 randconfig-a005-20210625
x86_64 randconfig-a003-20210625
x86_64 randconfig-a004-20210625
x86_64 randconfig-a006-20210625
riscv nommu_k210_defconfig
riscv nommu_virt_defconfig
riscv rv32_defconfig
riscv allnoconfig
riscv defconfig
riscv allmodconfig
x86_64 rhel-8.3-kselftests
um x86_64_defconfig
um i386_defconfig
um kunit_defconfig
x86_64 allyesconfig
x86_64 defconfig
x86_64 rhel-8.3
x86_64 rhel-8.3-kbuiltin
x86_64 kexec
clang tested configs:
x86_64 randconfig-b001-20210622
x86_64 randconfig-b001-20210625
x86_64 randconfig-a002-20210626
x86_64 randconfig-a001-20210626
x86_64 randconfig-a005-20210626
x86_64 randconfig-a003-20210626
x86_64 randconfig-a004-20210626
x86_64 randconfig-a006-20210626
---
0-DAY CI Kernel Test Service, Intel Corporation
https://lists.01.org/hyperkitty/list/kbuild-all@lists.01.org
^ permalink raw reply
* Re: [powerpc][next-20210625] Kernel warning(arch/powerpc/kernel/interrupt.c:518) during boot
From: Nicholas Piggin @ 2021-06-26 18:57 UTC (permalink / raw)
To: linuxppc-dev, Sachin Sant; +Cc: linux-next
In-Reply-To: <478A3DE4-159E-4FF8-92B4-6550F72951E6@linux.vnet.ibm.com>
Excerpts from Sachin Sant's message of June 26, 2021 11:52 pm:
> Following kernel warning is seen while booting 5.13.0-rc7-next-20210625
> on POWER9 LPAR.
>
> [ 40.573592] ------------[ cut here ]------------
> [ 40.573604] WARNING: CPU: 6 PID: 4743 at arch/powerpc/kernel/interrupt.c:518 interrupt_exit_kernel_prepare+0x280/0x2a0
> [ 40.573614] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts uio_pdrv_genirq uio vmx_crypto sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
> [ 40.573649] CPU: 6 PID: 4743 Comm: dracut-install Not tainted 5.13.0-rc7-next-20210625 #1
> [ 40.573655] NIP: c000000000032990 LR: c00000000000c958 CTR: 000000000048dd1c
> [ 40.573660] REGS: c0000000414db640 TRAP: 0700 Not tainted (5.13.0-rc7-next-20210625)
> [ 40.573664] MSR: 8000000000021033 <SF,ME,IR,DR,RI,LE> CR: 28044288 XER: 00000000
> [ 40.573674] CFAR: c0000000000327a4 IRQMASK: 1
> GPR00: c00000000000c958 c0000000414db8e0 c0000000029bbd00 c0000000414db9a0
> GPR04: 8000000000001033 0000000000000093 0000000000000048 ffffffffffffffbf
> GPR08: 0000000000000008 0000000000000000 0000000000000003 0000000000000010
> GPR12: 0000000000004000 c000000005587a00 0000000101dc15a8 0000000101dc1590
> GPR16: 0000000101dc05a8 00007fffc7abe353 00007fffb7926740 0000000000000000
> GPR20: 00007fffc7ab7ae0 fffffffffffff000 0000000000000006 c000000043cbbc00
> GPR24: 0000000000000000 000001003da495d0 0000000000000000 0000000000000000
> GPR28: 0000000000000000 fcffffffffffffff 0000000000000000 c0000000414db9a0
> [ 40.573725] NIP [c000000000032990] interrupt_exit_kernel_prepare+0x280/0x2a0
> [ 40.573730] LR [c00000000000c958] interrupt_return_srr_user_restart+0x34/0x118
BTW this isn't a restart but a kernel exit. I'll have to update labels
to make this clear.
> [ 40.573736] Call Trace:
> [ 40.573738] [c0000000414db8e0] [c000000043cbbc00] 0xc000000043cbbc00 (unreliable)
> [ 40.573744] [c0000000414db930] [c00000000000c958] interrupt_return_srr_user_restart+0x34/0x118
> [ 40.573751] --- interrupt: 300 at strnlen_user+0x74/0x240
> [ 40.573756] NIP: c00000000070ccf4 LR: c00000000048a460 CTR: 000000000003fffe
> [ 40.573760] REGS: c0000000414db9a0 TRAP: 0300 Not tainted (5.13.0-rc7-next-20210625)
> [ 40.573764] MSR: 8000000000001033 <SF,ME,IR,DR,RI,LE> CR: 48044228 XER: 20040000
> [ 40.573774] CFAR: c00000000048a45c DAR: 000001003da495d0 DSISR: 40000000 IRQMASK: 0
> GPR00: c00000000048a44c c0000000414dbc40 c0000000029bbd00 0000000000000000
> GPR04: 0000000000200000 0000000000000030 c000000043cbbc00 000001003da495d0
> GPR08: a8aaaaaaaaaaaaaa bcffffffffffffff 000001003da495d0 0000000000000000
> GPR12: 0000000000004000 c000000005587a00 0000000101dc15a8 0000000101dc1590
> GPR16: 0000000101dc05a8 00007fffc7abe353 00007fffb7926740 0000000000000000
> GPR20: 00007fffc7ab7ae0 fffffffffffff000 0000000000000006 c000000043cbbc00
> GPR24: 0000000000000000 000001003da495d0 0000000000000000 0000000000000000
> GPR28: 0000000000000000 c000000043b6a000 c000000043cbbc00 0000000000000000
> [ 40.573826] NIP [c00000000070ccf4] strnlen_user+0x74/0x240
> [ 40.573830] LR [c00000000048a460] copy_strings.isra.42+0xb0/0x350
So there's definitely IRQMASK=0 and no MSR[EE]=0 in this frame, which is
what the warning was.
I'd say either something hasn't set PACA_IRQ_HARD_DIS properly, so EE
doesn't get enabled when irqs are restored, or maybe the change to
arch_local_irq_restore(). Less likely that the stack got messed up.
Can you try run with CONFIG_PPC_IRQ_SOFT_MASK_DEBUG=y ?
Thanks,
Nick
^ permalink raw reply
* Re: [RESEND-PATCH v2] powerpc/papr_scm: Add support for reporting dirty-shutdown-count
From: Dan Williams @ 2021-06-26 17:43 UTC (permalink / raw)
To: Vaibhav Jain
Cc: Linux NVDIMM, Santosh Sivaraj, Ira Weiny, linux-nvdimm,
Aneesh Kumar K . V, linuxppc-dev
In-Reply-To: <20210624080621.252038-1-vaibhav@linux.ibm.com>
On Thu, Jun 24, 2021 at 1:07 AM Vaibhav Jain <vaibhav@linux.ibm.com> wrote:
>
> Persistent memory devices like NVDIMMs can loose cached writes in case
> something prevents flush on power-fail. Such situations are termed as
> dirty shutdown and are exposed to applications as
> last-shutdown-state (LSS) flag and a dirty-shutdown-counter(DSC) as
> described at [1]. The latter being useful in conditions where multiple
> applications want to detect a dirty shutdown event without racing with
> one another.
>
> PAPR-NVDIMMs have so far only exposed LSS style flags to indicate a
> dirty-shutdown-state. This patch further adds support for DSC via the
> "ibm,persistence-failed-count" device tree property of an NVDIMM. This
> property is a monotonic increasing 64-bit counter thats an indication
> of number of times an NVDIMM has encountered a dirty-shutdown event
> causing persistence loss.
>
> Since this value is not expected to change after system-boot hence
> papr_scm reads & caches its value during NVDIMM probe and exposes it
> as a PAPR sysfs attributed named 'dirty_shutdown' to match the name of
> similarly named NFIT sysfs attribute. Also this value is available to
> libnvdimm via PAPR_PDSM_HEALTH payload. 'struct nd_papr_pdsm_health'
> has been extended to add a new member called 'dimm_dsc' presence of
> which is indicated by the newly introduced PDSM_DIMM_DSC_VALID flag.
>
> References:
> [1] https://pmem.io/documents/Dirty_Shutdown_Handling-V1.0.pdf
>
> Signed-off-by: Vaibhav Jain <vaibhav@linux.ibm.com>
> Reviewed-by: Aneesh Kumar K.V <aneesh.kumar@linux.ibm.com>
Belated:
Acked-by: Dan Williams <dan.j.williams@intel.com>
It's looking like CXL will add one of these as well. Might be time to
add a unified location when that happens and deprecate these
bus-specific locations.
^ permalink raw reply
* [powerpc][next-20210625] Kernel warning(arch/powerpc/kernel/interrupt.c:518) during boot
From: Sachin Sant @ 2021-06-26 13:52 UTC (permalink / raw)
To: linuxppc-dev; +Cc: linux-next, npiggin
Following kernel warning is seen while booting 5.13.0-rc7-next-20210625
on POWER9 LPAR.
[ 40.573592] ------------[ cut here ]------------
[ 40.573604] WARNING: CPU: 6 PID: 4743 at arch/powerpc/kernel/interrupt.c:518 interrupt_exit_kernel_prepare+0x280/0x2a0
[ 40.573614] Modules linked in: dm_mod bonding nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set rfkill nf_tables libcrc32c nfnetlink sunrpc pseries_rng xts uio_pdrv_genirq uio vmx_crypto sch_fq_codel ip_tables ext4 mbcache jbd2 sd_mod t10_pi sg ibmvscsi ibmveth scsi_transport_srp fuse
[ 40.573649] CPU: 6 PID: 4743 Comm: dracut-install Not tainted 5.13.0-rc7-next-20210625 #1
[ 40.573655] NIP: c000000000032990 LR: c00000000000c958 CTR: 000000000048dd1c
[ 40.573660] REGS: c0000000414db640 TRAP: 0700 Not tainted (5.13.0-rc7-next-20210625)
[ 40.573664] MSR: 8000000000021033 <SF,ME,IR,DR,RI,LE> CR: 28044288 XER: 00000000
[ 40.573674] CFAR: c0000000000327a4 IRQMASK: 1
GPR00: c00000000000c958 c0000000414db8e0 c0000000029bbd00 c0000000414db9a0
GPR04: 8000000000001033 0000000000000093 0000000000000048 ffffffffffffffbf
GPR08: 0000000000000008 0000000000000000 0000000000000003 0000000000000010
GPR12: 0000000000004000 c000000005587a00 0000000101dc15a8 0000000101dc1590
GPR16: 0000000101dc05a8 00007fffc7abe353 00007fffb7926740 0000000000000000
GPR20: 00007fffc7ab7ae0 fffffffffffff000 0000000000000006 c000000043cbbc00
GPR24: 0000000000000000 000001003da495d0 0000000000000000 0000000000000000
GPR28: 0000000000000000 fcffffffffffffff 0000000000000000 c0000000414db9a0
[ 40.573725] NIP [c000000000032990] interrupt_exit_kernel_prepare+0x280/0x2a0
[ 40.573730] LR [c00000000000c958] interrupt_return_srr_user_restart+0x34/0x118
[ 40.573736] Call Trace:
[ 40.573738] [c0000000414db8e0] [c000000043cbbc00] 0xc000000043cbbc00 (unreliable)
[ 40.573744] [c0000000414db930] [c00000000000c958] interrupt_return_srr_user_restart+0x34/0x118
[ 40.573751] --- interrupt: 300 at strnlen_user+0x74/0x240
[ 40.573756] NIP: c00000000070ccf4 LR: c00000000048a460 CTR: 000000000003fffe
[ 40.573760] REGS: c0000000414db9a0 TRAP: 0300 Not tainted (5.13.0-rc7-next-20210625)
[ 40.573764] MSR: 8000000000001033 <SF,ME,IR,DR,RI,LE> CR: 48044228 XER: 20040000
[ 40.573774] CFAR: c00000000048a45c DAR: 000001003da495d0 DSISR: 40000000 IRQMASK: 0
GPR00: c00000000048a44c c0000000414dbc40 c0000000029bbd00 0000000000000000
GPR04: 0000000000200000 0000000000000030 c000000043cbbc00 000001003da495d0
GPR08: a8aaaaaaaaaaaaaa bcffffffffffffff 000001003da495d0 0000000000000000
GPR12: 0000000000004000 c000000005587a00 0000000101dc15a8 0000000101dc1590
GPR16: 0000000101dc05a8 00007fffc7abe353 00007fffb7926740 0000000000000000
GPR20: 00007fffc7ab7ae0 fffffffffffff000 0000000000000006 c000000043cbbc00
GPR24: 0000000000000000 000001003da495d0 0000000000000000 0000000000000000
GPR28: 0000000000000000 c000000043b6a000 c000000043cbbc00 0000000000000000
[ 40.573826] NIP [c00000000070ccf4] strnlen_user+0x74/0x240
[ 40.573830] LR [c00000000048a460] copy_strings.isra.42+0xb0/0x350
[ 40.573835] --- interrupt: 300
[ 40.573838] [c0000000414dbc40] [c00000007d7c0000] 0xc00000007d7c0000 (unreliable)
[ 40.573843] [c0000000414dbc70] [c00000000048a44c] copy_strings.isra.42+0x9c/0x350
[ 40.573849] [c0000000414dbd10] [c00000000048b60c] do_execveat_common.isra.44+0x1fc/0x240
[ 40.573855] [c0000000414dbd80] [c00000000048b6a4] sys_execve+0x54/0x70
[ 40.573860] [c0000000414dbdb0] [c0000000000322c0] system_call_exception+0x150/0x2d0
[ 40.573865] [c0000000414dbe10] [c00000000000c464] system_call_common+0xf4/0x258
[ 40.573871] --- interrupt: c00 at 0x7fffb76db8a8
[ 40.573875] NIP: 00007fffb76db8a8 LR: 00007fffb76dc488 CTR: 0000000000000000
[ 40.573878] REGS: c0000000414dbe80 TRAP: 0c00 Not tainted (5.13.0-rc7-next-20210625)
[ 40.573883] MSR: 800000000280f033 <SF,VEC,VSX,EE,PR,FP,ME,IR,DR,RI,LE> CR: 28044283 XER: 00000000
[ 40.573895] IRQMASK: 0
GPR00: 000000000000000b 00007fffc7ab7a00 00007fffb77f7300 00007fffc7ab7a20
GPR04: 00007fffc7ab7ae0 00007fffc7ab8b50 0000000000007063 0000000000000000
GPR08: ffff800038540059 0000000000000000 0000000000000000 0000000000000000
GPR12: 0000000000000000 00007fffb792d720 0000000101dc15a8 0000000101dc1590
GPR16: 0000000101dc05a8 00007fffc7abe353 00007fffb7926740 00007fffc7ab7a20
GPR20: 00007fffb7926740 000000000000002f 0000000000000000 0000000000000013
GPR24: 0000000000000003 00007fffc7abffa7 0000000000000001 00007fffc7ab8b50
GPR28: 00007fffc7ab7ae0 00007fffc7abffb0 0000000101dc1378 00007fffc7ab7a20
[ 40.573943] NIP [00007fffb76db8a8] 0x7fffb76db8a8
[ 40.573947] LR [00007fffb76dc488] 0x7fffb76dc488
[ 40.573950] --- interrupt: c00
[ 40.573952] Instruction dump:
[ 40.573955] 71290001 892d0933 61290001 992d0933 4082000c 392d0918 7c20492a 4bfe36dd
[ 40.573964] 60000000 4bfffe34 60000000 60000000 <0fe00000> 4bfffe14 60000000 60000000
[ 40.573973] ---[ end trace 604b708523af26f5 ]—
I cannot consistently recreate this problem.
next-20210624 was good.
Last patch that touched this code was 6eaaf9de3599.
Thanks
-Sachin
^ permalink raw reply
* Re: (subset) [PATCH 0/5] powerpc/kprobes: fixes and cleanups
From: Michael Ellerman @ 2021-06-26 10:46 UTC (permalink / raw)
To: Jordan Niethe, Naveen N. Rao, Michael Ellerman, Christophe Leroy
Cc: linuxppc-dev
In-Reply-To: <cover.1621416666.git.naveen.n.rao@linux.vnet.ibm.com>
On Wed, 19 May 2021 16:17:16 +0530, Naveen N. Rao wrote:
> Various fixes and some code refactoring for kprobes on powerpc. The
> first patch fixes an invalid access if probing the first instruction in
> a kernel module. The rest are small cleanups. More details in the
> individual patches.
>
> - Naveen
>
> [...]
Patch 2 applied to powerpc/next.
[2/5] powerpc/kprobes: Roll IS_RFI() macro into IS_RFID()
https://git.kernel.org/powerpc/c/0566fa760d235c119cef92119efc3ab11486a08a
cheers
^ permalink raw reply
* Re: [PATCH] powerpc/stacktrace: Fix spurious "stale" traces in raise_backtrace_ipi()
From: Michael Ellerman @ 2021-06-26 10:46 UTC (permalink / raw)
To: Michael Ellerman, linuxppc-dev; +Cc: nathanl
In-Reply-To: <20210625140408.3351173-1-mpe@ellerman.id.au>
On Sat, 26 Jun 2021 00:04:08 +1000, Michael Ellerman wrote:
> In raise_backtrace_ipi() we iterate through the cpumask of CPUs, sending
> each an IPI asking them to do a backtrace, but we don't wait for the
> backtrace to happen.
>
> We then iterate through the CPU mask again, and if any CPU hasn't done
> the backtrace and cleared itself from the mask, we print a trace on its
> behalf, noting that the trace may be "stale".
>
> [...]
Applied to powerpc/next.
[1/1] powerpc/stacktrace: Fix spurious "stale" traces in raise_backtrace_ipi()
https://git.kernel.org/powerpc/c/7c6986ade69e3c81bac831645bc72109cd798a80
cheers
^ permalink raw reply
* Re: [PATCH] powerpc/pseries/vas: Include irqdomain.h
From: Michael Ellerman @ 2021-06-26 10:46 UTC (permalink / raw)
To: Michael Ellerman, linuxppc-dev; +Cc: sfr
In-Reply-To: <20210625045337.3197833-1-mpe@ellerman.id.au>
On Fri, 25 Jun 2021 14:53:37 +1000, Michael Ellerman wrote:
> There are patches in flight to break the dependency between asm/irq.h
> and linux/irqdomain.h, which would break compilation of vas.c because it
> needs the declaration of irq_create_mapping() etc.
>
> So add an explicit include of irqdomain.h to avoid that becoming a
> problem in future.
Applied to powerpc/next.
[1/1] powerpc/pseries/vas: Include irqdomain.h
https://git.kernel.org/powerpc/c/c736fb97051c39c74bf5d4684bdac1766d43e175
cheers
^ permalink raw reply
page: next (older) | prev (newer) | latest
- recent:[subjects (threaded)|topics (new)|topics (active)]
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox