Pattern Optimizer#

The pattern optimizer is implemented by class GraphBuilderPatternOptimization. It searches for a specific sequence of nodes in the graph and replaces it by another one without changing the inputs or the outputs of the graph. The goal of the optimizer is to make the whole computation graph more efficient. The goal of this implementation is to make this optimization as fast as possible. Assuming the nodes in an onnx graph are ordered in a way every input of a node was created by previous nodes, the optimizer must not require any global reordering. The cost should be in O(N P I) in the worst case where N is the number of nodes, P is the number of patterns, I is the number of iterations.

It is difficult to foresee what a pattern needs in order to rewrite a part of the graph. This API tries to give as much freedom as it can without leaving too much to do to the developer which tries to add a new pattern.

Patterns#

Patterns must inherit from PatternOptimization. This class defines two methods.

PatternOptimization.match#

def match(
    self,
    g: "GraphBuilderPatternOptimization",
    node: NodeProto,
    matched: List[MatchResult],
) -> Optional[MatchResult]:

  • g is a GraphBuilderPatternOptimization, it holds all the existing nodes, is able to return any information about type, shape, the node before, the node after another one.

  • node: the matching must determine if some nodes around this one are part of set of nodes this pattern optimizer can rewrite. From there, the function explores wherever it needs, checking any condition it needs.

  • matched: usually unused, it contains the list of nodes already matching a pattern

The method must not modify the graph. The method returns None if no match is found or an instance of class MatchResult. It must contain:

  • a list of nodes involved in the rewriting. It does not mean all of them will be removed but all of them are needed to do the rewriting and must not be impacted by other pattern optimizer.

  • A function doing the rewriting (usually method apply of the pattern class).

  • An existing node where the rewritten nodes can be inserted. Knowing it makes it faster to rewrite. If not specified, the optimizer will automatically determine the position of the new nodes.

Debugging: method none

def none(
    self,
    node: Optional[NodeProto] = None,
    lineno: Optional[int] = None,
    msg: Optional[Union[Callable[[], str], str]] = None,
):

It may be useful to know the reason why a pattern matching failed. Instead of returning None, method match can return the following expression:

return self.none(node, inspect.currentframe().f_lineno)

By setting the verbosity (see next Section), the user may then know which lines in the code returned None and which condition failed. The last parameter is used to print a more comprehensive message about the reason why the match failed.

PatternOptimization.apply#

@classmethod
def apply(
    cls, g: "GraphBuilder", *nodes: Sequence[NodeProto]
) -> List[NodeProto]:

The method does the rewriting. It assumes it can happen. It takes a list of nodes impacted by the rewriting. It assumes no other pattern optimizer modified them or will modify them. It receives the list of nodes returned by method match. Since it is a list of arguments, method match can include None values. The method returns the new nodes. The optimizer considers that any node given to this function is removed from the graph, and any node returned by it are added. If a received node must be kept, it must be added to the list of returned nodes.

PatternOptimization.fast_op_type#

@classmethod
def fast_op_type(cls) -> Set[str]:

The base class returns an empty set. Overriding this method is an optional performance hint: when the returned set contains exactly one op_type string, the optimizer builds an op-type → nodes index over the graph once per matching step and restricts enumerate_matches to only the nodes of that type. This avoids iterating over the entire graph for patterns whose entry point is always a specific operator.

When the method returns an empty set (the default) or a set with more than one element, the full node list is used and no pre-filtering takes place.

from yobx.xoptim import PatternOptimization

class ReshapePattern(PatternOptimization):
    """Base class for patterns whose entry node is always a Reshape."""

    @classmethod
    def fast_op_type(cls):
        return {"Reshape"}

Subclasses that always start matching from the same inherited entry point do not need to override fast_op_type; the inherited implementation is already correct.

Optimization Algorithm#

It is implemented in method optimize

def optimize(
    self, max_iter=-1, remove_identity: bool = True
) -> List[Dict[str, Any]]:

The algorithm runs multiple iterations until the graph is not evolving or max_iter is reached. By default, it is equal to the number of nodes. An iteration is:

matches = []

builds all successors and predecessors

# Step 1: match

build op_type → nodes index (fast_nodes)

for all patterns P:

    nodes_to_visit = fast_nodes[P.fast_op_type()]  # pre-filtered
                     if len(P.fast_op_type()) == 1
                     else all nodes

    for all nodes n in nodes_to_visit:

        r = p.match(n)
        if r:
            if no node already scheduled to be rewritten by another match:
                matches.append(r)
# Step 2: apply

for all matches r:
    apply the match r

# Step 3: clean

remove unused nodes
remove identity nodes

This algorithm may apply more than one rewriting at each iteration but it guarantees the local structure when applying the rewriting was not altered by another one.

Adding a pattern#

Simple API#

We consider the following simple model:

<<<

import torch
from yobx.helpers.onnx_helper import pretty_onnx
from yobx.xbuilder import OptimizationOptions
from yobx.torch import to_onnx


class MLP(torch.nn.Module):
    def __init__(self):
        super().__init__()
        self.layers = torch.nn.Sequential(
            torch.nn.Linear(10, 32),
            torch.nn.ReLU(),
            torch.nn.Linear(32, 1),
        )

    def forward(self, x):
        return self.layers(x)


x = torch.rand(3, 10)
onx = to_onnx(
    MLP(), (x,), input_names=["x"], options=OptimizationOptions(patterns=None)
)
with open("temp_doc_mlp.onnx", "wb") as f:
    f.write(onx.SerializeToString())
print(pretty_onnx(onx))

>>>

    opset: domain='' version=21
    input: name='x' type=dtype('float32') shape=[3, 10]
    init: name='p_layers_0_weight::T10' type=float32 shape=(10, 32)       -- GraphBuilder.constant_folding.from/fold(p_layers_0_weight)##p_layers_0_weight/DynamoInterpret.placeholder.1/P(layers.0.weight)
    init: name='p_layers_2_weight::T10' type=float32 shape=(32, 1)        -- GraphBuilder.constant_folding.from/fold(p_layers_2_weight)##p_layers_2_weight/DynamoInterpret.placeholder.1/P(layers.2.weight)
    init: name='layers.0.bias' type=float32 shape=(32,)                   -- DynamoInterpret.placeholder.1/P(layers.0.bias)
    init: name='layers.2.bias' type=float32 shape=(1,) -- array([0.11362377], dtype=float32)-- DynamoInterpret.placeholder.1/P(layers.2.bias)
    MatMul(x, p_layers_0_weight::T10) -> _onx_matmul_x
      Add(_onx_matmul_x, layers.0.bias) -> _onx_add_matmul_x
        Relu(_onx_add_matmul_x) -> relu
          MatMul(relu, p_layers_2_weight::T10) -> _onx_matmul_relu
            Add(_onx_matmul_relu, layers.2.bias) -> output_0
    output: name='output_0' type=dtype('float32') shape=[3, 1]

Which we can render as follows:

digraph { graph [rankdir=TB, splines=true, overlap=false, nodesep=0.2, ranksep=0.2, fontsize=8]; node [style="rounded,filled", color="#888888", fontcolor="#222222", shape=box]; edge [arrowhead=vee, fontsize=7, labeldistance=-5, labelangle=0]; I_0 [label="x\nFLOAT(3,10)", fillcolor="#aaeeaa"]; i_1 [label="p_layers_0_weight::T10\nFLOAT(10, 32)", fillcolor="#cccc00"]; i_2 [label="p_layers_2_weight::T10\nFLOAT(32, 1)", fillcolor="#cccc00"]; i_3 [label="layers.0.bias\nFLOAT(32)", fillcolor="#cccc00"]; MatMul_4 [label="MatMul(., .)", fillcolor="#ee9999"]; Add_5 [label="Add(., .)", fillcolor="#cccccc"]; Relu_6 [label="Relu(.)", fillcolor="#cccccc"]; MatMul_7 [label="MatMul(., .)", fillcolor="#ee9999"]; Add_8 [label="Add(., [-0.14222133])", fillcolor="#cccccc"]; I_0 -> MatMul_4 [label="FLOAT(3,10)"]; i_1 -> MatMul_4 [label="FLOAT(10, 32)"]; MatMul_4 -> Add_5 [label="FLOAT(3,32)"]; i_3 -> Add_5 [label="FLOAT(32)"]; Add_5 -> Relu_6 [label="FLOAT(3,32)"]; Relu_6 -> MatMul_7 [label="FLOAT(3,32)"]; i_2 -> MatMul_7 [label="FLOAT(32, 1)"]; MatMul_7 -> Add_8 [label="FLOAT(3,1)"]; O_9 [label="output_0\nFLOAT(3,1)", fillcolor="#aaaaee"]; Add_8 -> O_9; }

We then apply the optimizations by writing the following code:

<<<

import onnx
from yobx.helpers.onnx_helper import pretty_onnx
from yobx.xbuilder import GraphBuilder
from yobx.doc import demo_mlp_model

onx = demo_mlp_model("temp_doc_mlp.onnx")

# The model is placed in a GraphBuilder.
# It creates dictionaries to store shapes, ranks, types
# to make it easier to the optimizers to find the information
# they need. It still uses NodeProto to store nodes
gr = GraphBuilder(onx, infer_shapes_options=True)

# Let's optimize.
opt_onx = gr.to_onnx(optimize=True)
with open("temp_doc_mlp_opt.onnx", "wb") as f:
    f.write(opt_onx.SerializeToString())
print(pretty_onnx(opt_onx))

>>>

    opset: domain='' version=18
    input: name='x' type=dtype('float32') shape=[3, 10]
    init: name='layers.0.bias' type=float32 shape=(32,)                   -- GraphBuilder._update_structures_with_proto.1/from(layers.0.bias)
    init: name='layers.2.bias' type=float32 shape=(1,) -- array([-0.14222133], dtype=float32)-- GraphBuilder._update_structures_with_proto.1/from(layers.2.bias)
    init: name='GemmTransposePattern--p_layers_0_weight::T10' type=float32 shape=(32, 10)-- GraphBuilder.constant_folding.from/fold(p_layers_0_weight::T10)##p_layers_0_weight::T10/GraphBuilder._update_structures_with_proto.1/from(p_layers_0_weight::T10)
    init: name='GemmTransposePattern--p_layers_2_weight::T10' type=float32 shape=(1, 32)-- GraphBuilder.constant_folding.from/fold(init7_s2_1_32,p_layers_2_weight::T10)##p_layers_2_weight::T10/GraphBuilder._update_structures_with_proto.1/from(p_layers_2_weight::T10)##init7_s2_1_32/TransposeEqualReshapePattern.apply.new_shape
    Gemm(x, GemmTransposePattern--p_layers_0_weight::T10, layers.0.bias, transB=1) -> linear
      Relu(linear) -> relu
        Gemm(relu, GemmTransposePattern--p_layers_2_weight::T10, layers.2.bias, transB=1) -> output_0
    output: name='output_0' type=dtype('float32') shape=[3, 1]

Which renders as follows:

digraph { graph [rankdir=TB, splines=true, overlap=false, nodesep=0.2, ranksep=0.2, fontsize=8]; node [style="rounded,filled", color="#888888", fontcolor="#222222", shape=box]; edge [arrowhead=vee, fontsize=7, labeldistance=-5, labelangle=0]; I_0 [label="x\nFLOAT(3,10)", fillcolor="#aaeeaa"]; i_1 [label="layers.0.bias\nFLOAT(32)", fillcolor="#cccc00"]; i_2 [label="GemmTransposePattern--p_layers_0_weight::T10\nFLOAT(32, 10)", fillcolor="#cccc00"]; i_3 [label="GemmTransposePattern--p_layers_2_weight::T10\nFLOAT(1, 32)", fillcolor="#cccc00"]; Gemm_4 [label="Gemm(., ., .)", fillcolor="#cccccc"]; Relu_5 [label="Relu(.)", fillcolor="#cccccc"]; Gemm_6 [label="Gemm(., ., [-0.14222133])", fillcolor="#cccccc"]; I_0 -> Gemm_4 [label="FLOAT(3,10)"]; i_2 -> Gemm_4 [label="FLOAT(32, 10)"]; i_1 -> Gemm_4 [label="FLOAT(32)"]; Gemm_4 -> Relu_5 [label="FLOAT(3,32)"]; Relu_5 -> Gemm_6 [label="FLOAT(3,32)"]; i_3 -> Gemm_6 [label="FLOAT(1, 32)"]; O_7 [label="output_0\nFLOAT(3,1)", fillcolor="#aaaaee"]; Gemm_6 -> O_7; }

Verbosity#

<<<

import onnx
from yobx.xbuilder import GraphBuilder
from yobx.doc import demo_mlp_model

onx = demo_mlp_model("temp_doc_mlp.onnx")

gr = GraphBuilder(onx, infer_shapes_options=True, verbose=1)
opt_onx = gr.to_onnx(optimize=True)

>>>

    [GraphBuilder-GPC._add_shape_information] dynamic shapes replacements={}
    [GraphBuilder-GPC.optimize] start with 5 nodes
    [GraphBuilder-GPC.optimize] #patterns=104
    [GraphBuilder-GPC.optimize] start with subgraphs
    [GraphBuilder-GPC.optimize] done with subgraphs
    [GraphBuilderPatternOptimization-GPC.optimize] start with 5 nodes, 4 initializers, 104 patterns, priorities=[0, 1, 2, 3], max_iter=40
    [GraphBuilderPatternOptimization-GPC.optimize] same children={'SameChildrenFromInputPattern', 'SameChildrenPattern'}
    [GraphBuilderPatternOptimization-GPC.optimize] iteration 0: 5 nodes, priority=0
    [GraphBuilderPatternOptimization-GPC.optimize] increase priority to 1
    [GraphBuilderPatternOptimization-GPC.optimize] iteration 1: 5 nodes, priority=1
    [GraphBuilderPatternOptimization-GPC.optimize] increase priority to 2
    [GraphBuilderPatternOptimization-GPC.optimize] iteration 2: 5 nodes, priority=2
    [GraphBuilderPatternOptimization-GPC.optimize] increase priority to 3
    [GraphBuilderPatternOptimization-GPC.optimize] iteration 3: 5 nodes, priority=3
    [GraphBuilderPatternOptimization-GPC.optimize] applies 2 matches, 2*MatMulAddPattern - time=0.002 | max_time=IdentityPattern:0.000
    [GraphBuilderPatternOptimization-GPC.optimize] iteration 4: 3 nodes, priority=3
    [GraphBuilderPatternOptimization-GPC.optimize] applies 2 matches, 2*GemmTransposePattern - time=0.001 | max_time=BatchNormalizationPattern:0.000
    [GraphBuilderPatternOptimization-GPC.optimize] iteration 5: 5 nodes, priority=3
    [GraphBuilderPatternOptimization-GPC.optimize] applies 1 matches, [0]=MatchResult: TransposeEqualReshapePattern replaces ['Transpose'] - time=0.001 | max_time=GemmTransposePattern:0.000
    [GraphBuilderPatternOptimization-GPC.optimize] iteration 6: 5 nodes, priority=3
    [GraphBuilderPatternOptimization-GPC.optimize] stops current_priority_index=4, priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-GPC.optimize] done after 7 iterations with 5 nodes in 0.026
    [OrderOptimization.optimize] ALGO-2
    [OrderOptimization.shape_order] -- starts with 3 nodes, 4 initializers
    [OrderOptimization.shape_order] done after in 5.9875001170439646e-05s with changed=0 scale=0
    [GraphBuilder-GPC.optimize] done with 3 nodes in 0.029
    [GraphBuilder-GPC.to_onnx] make_model 4 inits 0 params
    [GraphBuilder-GPC.time_evaluation_constants_] 0
    [GraphBuilder-GPC._build_initializers] start with 4 initializers, large_model=False, external_threshold=1024
    [GraphBuilder-GPC._build_initializers] switch low/high order
    [GraphBuilder-GPC._build_initializers] done in 1.5200002962956205e-06s with 4 initializers, 0 large initializers
    [GraphBuilder-GPC._add_shape_information] dynamic shapes replacements={}

With more verbosity:

<<<

import onnx
from yobx.xbuilder import GraphBuilder
from yobx.doc import demo_mlp_model

onx = demo_mlp_model("temp_doc_mlp.onnx")

gr = GraphBuilder(onx, infer_shapes_options=True, verbose=11)
opt_onx = gr.to_onnx(optimize=True)

>>>

    [GraphBuilder-HZK._update_structures_with_proto] -- starts with 5 nodes
    [GraphBuilder-HZK.set_shape] p_layers_0_weight::T10:(10, 32)
    [GraphBuilder-HZK.set_rank] p_layers_0_weight::T10:2
    [GraphBuilder-HZK.set_type] p_layers_0_weight::T10:1
    [GraphBuilder-HZK.make_initializer] p_layers_0_weight::T10[1:(10, 32)]
    [GraphBuilder-HZK.update_node_constant] new constant 'p_layers_0_weight::T10', node=None
    [GraphBuilder-HZK.set_shape] p_layers_2_weight::T10:(32, 1)
    [GraphBuilder-HZK.set_rank] p_layers_2_weight::T10:2
    [GraphBuilder-HZK.set_type] p_layers_2_weight::T10:1
    [GraphBuilder-HZK.make_initializer] p_layers_2_weight::T10[1:(32, 1)]
    [GraphBuilder-HZK.update_node_constant] new constant 'p_layers_2_weight::T10', node=None
    [GraphBuilder-HZK.set_shape] layers.0.bias:(32,)
    [GraphBuilder-HZK.set_rank] layers.0.bias:1
    [GraphBuilder-HZK.set_type] layers.0.bias:1
    [GraphBuilder-HZK.make_initializer] layers.0.bias[1:(32,)]
    [GraphBuilder-HZK.update_node_constant] new constant 'layers.0.bias', node=None
    [GraphBuilder-HZK.set_shape] layers.2.bias:(1,)
    [GraphBuilder-HZK.set_rank] layers.2.bias:1
    [GraphBuilder-HZK.set_type] layers.2.bias:1
    [GraphBuilder-HZK.make_initializer] layers.2.bias[1:(1,)]
    [GraphBuilder-HZK.update_node_constant] new constant 'layers.2.bias', node=None
    [GraphBuilder-HZK.set_type] x:1
    [GraphBuilder-HZK.set_shape] x:(3, 10)
    [GraphBuilder-HZK.set_rank] x:2
    [GraphBuilder-HZK.set_type] output_0:1
    [GraphBuilder-HZK.set_shape] output_0:(3, 1)
    [GraphBuilder-HZK.set_rank] output_0:2
    [GraphBuilder-HZK.set_type] _onx_matmul_x:1
    [GraphBuilder-HZK.set_shape] _onx_matmul_x:(3, 32)
    [GraphBuilder-HZK.set_rank] _onx_matmul_x:2
    [GraphBuilder-HZK.set_type] linear:1
    [GraphBuilder-HZK.set_shape] linear:(3, 32)
    [GraphBuilder-HZK.set_rank] linear:2
    [GraphBuilder-HZK.set_type] relu:1
    [GraphBuilder-HZK.set_shape] relu:(3, 32)
    [GraphBuilder-HZK.set_rank] relu:2
    [GraphBuilder-HZK.set_type] _onx_matmul_relu:1
    [GraphBuilder-HZK.set_shape] _onx_matmul_relu:(3, 1)
    [GraphBuilder-HZK.set_rank] _onx_matmul_relu:2
    [GraphBuilder-HZK.set_type] output_0:1
    [GraphBuilder-HZK._update_structures_with_proto] ends with 5 nodes in 0.001143305000368855
    [GraphBuilder-HZK.constant_folding] -- starts with 4 constants and 5 nodes.
    [GraphBuilder-HZK.constant_folding] cst:: . :: _onx_matmul_x
    [GraphBuilder-HZK.constant_folding] cst:: . :: linear
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: p_layers_2_weight::T10
    [GraphBuilder-HZK.constant_folding] cst:: . :: _onx_matmul_relu
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: layers.2.bias
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: layers.0.bias
    [GraphBuilder-HZK.constant_folding] cst:: . :: relu
    [GraphBuilder-HZK.constant_folding] cst:: . :: output_0
    [GraphBuilder-HZK.constant_folding] cst:: . :: x
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: p_layers_0_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: p_layers_0_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: p_layers_2_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: layers.0.bias
    [GraphBuilder-HZK.constant_folding] initializer: layers.2.bias
    [GraphBuilder-HZK.constant_folding] ends with 4 constants and 5 nodes in 0.00011128400001325645 seconds
    [GraphBuilder-HZK._update_shape_types_with_proto] -- starts with 5 nodes and 0 shapes.
    [GraphBuilder._update_shape_types_with_proto] infer shapes
    [GraphBuilder._update_shape_types_with_proto] infer shapes done 0.0002507209992472781 seconds
    [GraphBuilder._update_shape_types_with_proto] _clean_shapes after 0.0003115540002909256 seconds
    [GraphBuilder-HZK._update_shape_types_with_proto] walk through 0 shapes.
    [GraphBuilder-HZK.set_type] _onx_matmul_x:1
    [_update_shape_types_with_proto_one_result] update shape(_onx_matmul_x) with (3, 32)
    [GraphBuilder-HZK.set_type] linear:1
    [_update_shape_types_with_proto_one_result] update shape(linear) with (3, 32)
    [GraphBuilder-HZK.set_type] relu:1
    [_update_shape_types_with_proto_one_result] update shape(relu) with (3, 32)
    [GraphBuilder-HZK.set_type] _onx_matmul_relu:1
    [_update_shape_types_with_proto_one_result] update shape(_onx_matmul_relu) with (3, 1)
    [GraphBuilder-HZK._update_shape_types_with_proto] ends in 0.0001650729991524713 seconds.
    [GraphBuilder-HZK._add_shape_information] dynamic shapes replacements={}
    [GraphBuilder-HZK.optimize] start with 5 nodes
    [GraphBuilder-HZK.optimize] options=OptimizationOptions(constant_folding={'Reciprocal', 'Reshape', 'Mul', 'Exp', 'Sqrt', 'Squeeze', 'Concat', 'Cast', 'Sub', 'Unsqueeze', 'Transpose', 'Add', 'Div'}, patterns=[BatchNormalizationPattern(), BatchNormalizationTrainingPattern(), CastLayerNormalizationCastPattern(), CastPattern(), CastCastBinaryPattern(), CastCastPattern(), CastOpCastPattern(), ClipClipPattern(), ConcatEmptyPattern(), ConcatGatherPattern(), ConcatReshapePattern(), ConcatTwiceUnaryPattern(), ConstantToInitializerPattern(), ConvBiasNullPattern(), PadConvPattern(), DropoutPattern(), ExpandPattern(), ExpandBroadcastPattern(), ExpandSwapPattern(), ExpandUnsqueezeExpandPattern(), GatherConcatPattern(), GatherGatherPattern(), GathersSplitPattern(), GatherShapePattern(), GeluPattern(), IdentityPattern(), LayerNormalizationPattern(), LayerNormalizationScalePattern(), LeakyReluPattern(), MaxReluPattern(), MulMulMulScalarPattern(), MulUnsqueezeUnsqueezePattern(), NotNotPattern(), NotWherePattern(), ReduceArgTopKPattern(), ReduceReshapePattern(), ReduceSumNormalizePattern(), ReshapePattern(), ReshapeMatMulReshapePattern(), Reshape2Of3Pattern(), ReshapeReshapeBinaryPattern(), ReshapeSqueezePattern(), MatMulAddPattern(), GemmTransposePattern(), MatMulReshape2Of3Pattern(), MulMulMatMulPattern(), ShapeBasedReshapeIsSqueezePattern(), ShapeBasedStaticExpandPattern(), ShapeBasedConcatExpandPattern(), ShapeBasedEditDistanceReshapePattern(), ShapeBasedIdentityPattern(), ShapeBasedExpandBroadcastPattern(), ShapeBasedExpandBroadcastMatMulPattern(), ShapeBasedExpandCastWhereSwapPattern(), ShapeBasedExpandSwapPattern(), ShapeBasedMatMulToMulPattern(), ShapedBasedReshapePattern(), ShapeBasedSameChildrenPattern(), ShapeBasedShapeShapeAddPattern(), ShapeTransposePattern(), UnsqueezeShapePattern(), ReshapeReshapePattern(), RotaryEmbeddingPattern(), SameChildrenPattern(), SameChildrenFromInputPattern(), SequenceConstructAtPattern(), SplitToSequenceSequenceAtPattern(), SliceSlicePattern(), SlicesSplitPattern(), SoftmaxCrossEntropyLossCastPattern(), SplitConcatPattern(), SqueezeAddPattern(), SqueezeBinaryUnsqueezePattern(), SqueezeUnsqueezePattern(), StaticConcatReshapePattern(), Sub1MulPattern(), SwapExpandReshapePattern(), SwapExpandUnsqueezePattern(), SwapRangeAddScalarPattern(), SwapUnaryPattern(), SwapUnsqueezeTransposePattern(), SwitchOrderBinaryPattern(), SwitchReshapeActivationPattern(), TransposeEqualReshapePattern(), TransposeGatherPattern(), TransposeMatMulPattern(), TransposeReshapeMatMulPattern(), TransposeReshapeTransposePattern(), TransposeTransposePattern(), UnsqueezeEqualPattern(), UnsqueezeOrSqueezeReshapePattern(), UnsqueezeReshapePattern(), UnsqueezeUnsqueezePattern(), WhereAddPattern(), RotaryConcatPartPattern(), FunctionAttentionPattern(), FunctionAttentionGQAPattern(), FunctionCausalMaskPattern(), FunctionCausalMaskMulAddPattern(), FunctionCosSinCachePattern(), FunctionHalfRotaryEmbeddingPattern(), RMSNormalizationPattern(), RMSNormalizationMulPattern(), AttentionGQAPattern()], verbose=11, order=SHAPE)
    -- GRAPH BEFORE OPTIMIZATION --
    
    opset: : 18
    init: p_layers_0_weight::T10: CP1: (10, 32)                            -- GraphBuilder._update_structures_with_proto.1/from(p_layers_0_weight::T10)
    init: p_layers_2_weight::T10: CP1: (32, 1)                             -- GraphBuilder._update_structures_with_proto.1/from(p_layers_2_weight::T10)
    init: layers.0.bias: CP1: (32,)                                        -- GraphBuilder._update_structures_with_proto.1/from(layers.0.bias)
    init: layers.2.bias: CP1: (1,)                                         -- GraphBuilder._update_structures_with_proto.1/from(layers.2.bias)
    input:: x                                                                       |T1: 3 x 10
    MatMul: x, p_layers_0_weight::T10 -> _onx_matmul_x                              |T1: 3 x 32
    Add: _onx_matmul_x, layers.0.bias -> linear                                     |T1: 3 x 32
    Relu: linear -> relu                                                            |T1: 3 x 32
    MatMul: relu, p_layers_2_weight::T10 -> _onx_matmul_relu                        |T1: 3 x 1
    Add: _onx_matmul_relu, layers.2.bias -> output_0                                |T1: 3 x 1
    output:: output_0                                                               |T1: 3 x 1
    -- END --
    [GraphBuilder-HZK.optimize] start with subgraphs
    [GraphBuilder-HZK.optimize] done with subgraphs
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 5
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 5 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 5 nodes in 6.540299909829628e-05 seconds
    [GraphBuilder-HZK.constant_folding] -- starts with 4 constants and 5 nodes.
    [GraphBuilder-HZK.constant_folding] cst:: . :: _onx_matmul_x
    [GraphBuilder-HZK.constant_folding] cst:: . :: linear
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: p_layers_2_weight::T10
    [GraphBuilder-HZK.constant_folding] cst:: . :: _onx_matmul_relu
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: layers.2.bias
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: layers.0.bias
    [GraphBuilder-HZK.constant_folding] cst:: . :: relu
    [GraphBuilder-HZK.constant_folding] cst:: . :: output_0
    [GraphBuilder-HZK.constant_folding] cst:: . :: x
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: p_layers_0_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: p_layers_0_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: p_layers_2_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: layers.0.bias
    [GraphBuilder-HZK.constant_folding] initializer: layers.2.bias
    [GraphBuilder-HZK.constant_folding] ends with 4 constants and 5 nodes in 6.262599890760612e-05 seconds
    [GraphBuilderPatternOptimization-HZK.optimize] start with 5 nodes, 4 initializers, 104 patterns, priorities=[0, 1, 2, 3], max_iter=40
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern   1/104 - P0 - BatchNormalizationPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern   2/104 - P0 - BatchNormalizationTrainingPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern   3/104 - P0 - CastCastPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern   4/104 - P0 - CastPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern   5/104 - P0 - ConcatGatherPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern   6/104 - P0 - ConcatReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern   7/104 - P0 - ConvBiasNullPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern   8/104 - P0 - ExpandPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern   9/104 - P0 - ExpandUnsqueezeExpandPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  10/104 - P0 - FunctionAttentionGQAPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  11/104 - P0 - FunctionAttentionPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  12/104 - P0 - GatherConcatPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  13/104 - P0 - GatherGatherPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  14/104 - P0 - GatherShapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  15/104 - P0 - GeluPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  16/104 - P0 - IdentityPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  17/104 - P0 - LeakyReluPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  18/104 - P0 - MulUnsqueezeUnsqueezePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  19/104 - P0 - PadConvPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  20/104 - P0 - ReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  21/104 - P0 - ReshapeReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  22/104 - P0 - ReshapeSqueezePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  23/104 - P0 - SameChildrenFromInputPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  24/104 - P0 - SameChildrenPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  25/104 - P0 - ShapeBasedEditDistanceReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  26/104 - P0 - ShapeBasedIdentityPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  27/104 - P0 - ShapeBasedReshapeIsSqueezePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  28/104 - P0 - ShapeBasedSameChildrenPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  29/104 - P0 - ShapeBasedShapeShapeAddPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  30/104 - P0 - ShapeBasedStaticExpandPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  31/104 - P0 - ShapeTransposePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  32/104 - P0 - ShapedBasedReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  33/104 - P0 - SoftmaxCrossEntropyLossCastPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  34/104 - P0 - SqueezeAddPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  35/104 - P0 - SqueezeBinaryUnsqueezePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  36/104 - P0 - SqueezeUnsqueezePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  37/104 - P0 - StaticConcatReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  38/104 - P0 - SwapExpandReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  39/104 - P0 - SwapExpandUnsqueezePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  40/104 - P0 - SwapUnaryPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  41/104 - P0 - SwapUnsqueezeTransposePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  42/104 - P0 - TransposeGatherPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  43/104 - P0 - TransposeReshapeTransposePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  44/104 - P0 - TransposeTransposePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  45/104 - P0 - UnsqueezeOrSqueezeReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  46/104 - P0 - UnsqueezeReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  47/104 - P0 - UnsqueezeShapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  48/104 - P0 - UnsqueezeUnsqueezePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  49/104 - P1 - CastCastBinaryPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  50/104 - P1 - CastLayerNormalizationCastPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  51/104 - P1 - CastOpCastPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  52/104 - P1 - ClipClipPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  53/104 - P1 - ConcatEmptyPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  54/104 - P1 - ConcatTwiceUnaryPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  55/104 - P1 - ConstantToInitializerPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  56/104 - P1 - DropoutPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  57/104 - P1 - ExpandBroadcastPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  58/104 - P1 - ExpandSwapPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  59/104 - P1 - FunctionCausalMaskMulAddPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  60/104 - P1 - FunctionCausalMaskPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  61/104 - P1 - FunctionCosSinCachePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  62/104 - P1 - FunctionHalfRotaryEmbeddingPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  63/104 - P1 - GathersSplitPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  64/104 - P1 - GemmTransposePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  65/104 - P1 - LayerNormalizationPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  66/104 - P1 - LayerNormalizationScalePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  67/104 - P1 - MatMulReshape2Of3Pattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  68/104 - P1 - MaxReluPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  69/104 - P1 - MulMulMatMulPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  70/104 - P1 - MulMulMulScalarPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  71/104 - P1 - NotNotPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  72/104 - P1 - NotWherePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  73/104 - P1 - RMSNormalizationMulPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  74/104 - P1 - RMSNormalizationPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  75/104 - P1 - ReduceArgTopKPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  76/104 - P1 - ReduceReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  77/104 - P1 - ReduceSumNormalizePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  78/104 - P1 - Reshape2Of3Pattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  79/104 - P1 - ReshapeMatMulReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  80/104 - P1 - ReshapeReshapeBinaryPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  81/104 - P1 - RotaryConcatPartPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  82/104 - P1 - RotaryEmbeddingPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  83/104 - P1 - SequenceConstructAtPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  84/104 - P1 - ShapeBasedConcatExpandPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  85/104 - P1 - ShapeBasedExpandBroadcastMatMulPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  86/104 - P1 - ShapeBasedExpandBroadcastPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  87/104 - P1 - ShapeBasedExpandCastWhereSwapPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  88/104 - P1 - ShapeBasedExpandSwapPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  89/104 - P1 - ShapeBasedMatMulToMulPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  90/104 - P1 - SliceSlicePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  91/104 - P1 - SlicesSplitPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  92/104 - P1 - SplitConcatPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  93/104 - P1 - SplitToSequenceSequenceAtPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  94/104 - P1 - Sub1MulPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  95/104 - P1 - SwapRangeAddScalarPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  96/104 - P1 - SwitchOrderBinaryPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  97/104 - P1 - SwitchReshapeActivationPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  98/104 - P1 - TransposeEqualReshapePattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern  99/104 - P1 - TransposeMatMulPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern 100/104 - P1 - TransposeReshapeMatMulPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern 101/104 - P1 - UnsqueezeEqualPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern 102/104 - P1 - WhereAddPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern 103/104 - P2 - AttentionGQAPattern()
    [GraphBuilderPatternOptimization-HZK.optimize] use pattern 104/104 - P3 - MatMulAddPattern()
    -- optimize starts with...
    
    opset: : 18
    init: p_layers_0_weight::T10: CP1: (10, 32)                            -- GraphBuilder._update_structures_with_proto.1/from(p_layers_0_weight::T10)
    init: p_layers_2_weight::T10: CP1: (32, 1)                             -- GraphBuilder._update_structures_with_proto.1/from(p_layers_2_weight::T10)
    init: layers.0.bias: CP1: (32,)                                        -- GraphBuilder._update_structures_with_proto.1/from(layers.0.bias)
    init: layers.2.bias: CP1: (1,)                                         -- GraphBuilder._update_structures_with_proto.1/from(layers.2.bias)
    input:: x                                                                       |T1: 3 x 10
    MatMul: x, p_layers_0_weight::T10 -> _onx_matmul_x                              |T1: 3 x 32
    Add: _onx_matmul_x, layers.0.bias -> linear                                     |T1: 3 x 32
    Relu: linear -> relu                                                            |T1: 3 x 32
    MatMul: relu, p_layers_2_weight::T10 -> _onx_matmul_relu                        |T1: 3 x 1
    Add: _onx_matmul_relu, layers.2.bias -> output_0                                |T1: 3 x 1
    output:: output_0                                                               |T1: 3 x 1
    -- starts optimization
    [GraphBuilderPatternOptimization-HZK.optimize] same children={'SameChildrenFromInputPattern', 'SameChildrenPattern'}
    [GraphBuilderPatternOptimization-HZK.optimize] iteration 0: 5 nodes, priority=0
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0 - matching_step
    [PatternOptimization.enumerate_matches] start BatchNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start BatchNormalizationTrainingPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips CastLayerNormalizationCastPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start CastPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips CastCastBinaryPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start CastCastPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips CastOpCastPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ClipClipPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ConcatEmptyPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start ConcatGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatReshapePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips ConcatTwiceUnaryPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ConstantToInitializerPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start ConvBiasNullPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start PadConvPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips DropoutPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start ExpandPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips ExpandBroadcastPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ExpandSwapPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start ExpandUnsqueezeExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherGatherPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips GathersSplitPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start GatherShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GeluPattern with main_opset=18 and min_opset=20
    [PatternOptimization.enumerate_matches] start IdentityPattern with main_opset=18 and min_opset=1
    [IdentityPattern.match] NONE - line: 745:yobx.xoptim.patterns.onnx_any, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [IdentityPattern.match] NONE - line: 787:yobx.xoptim.patterns.onnx_any, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [GraphBuilderPatternOptimization-HZK.optimize] skips LayerNormalizationPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips LayerNormalizationScalePattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start LeakyReluPattern with main_opset=18 and min_opset=6
    [GraphBuilder-WYA.make_tensor_input] x[0:None] -- marker=_build_pattern1_x
    [GraphBuilder-WYA.set_type] x:0
    [GraphBuilder-WYA.set_type] x:-1
    [GraphBuilder-WYA.make_tensor_input] zero[0:None] -- marker=_build_pattern1_zero
    [GraphBuilder-WYA.set_type] zero:0
    [GraphBuilder-WYA.set_type] zero:-1
    [GraphBuilder-WYA.make_tensor_input] slope[0:None] -- marker=_build_pattern1_slope
    [GraphBuilder-WYA.set_type] slope:0
    [GraphBuilder-WYA.set_type] slope:-1
    [GraphBuilder-WYA.3.make_node] [tt:-] Greater: ['x', 'zero']->['_onx_greater_x']
    [GraphBuilder-WYA.set_type] _onx_greater_x:9
    [GraphBuilder-WYA.3.make_node] [tt:-] Mul: ['x', 'slope']->['_onx_mul_x']
    [GraphBuilder-WYA.set_type] _onx_mul_x:-1
    [GraphBuilder-WYA.3.make_node] [ttt:-] Where: ['_onx_greater_x', 'x', '_onx_mul_x']->['_onx_where_greater_x']
    [GraphBuilder-WYA.set_type] _onx_where_greater_x:-1
    [GraphBuilder-WYA.make_tensor_output] _onx_where_greater_x[0: None]
    [GraphBuilderPatternOptimization-HZK.optimize] skips MaxReluPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips MulMulMulScalarPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start MulUnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips NotNotPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips NotWherePattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ReduceArgTopKPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ReduceReshapePattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ReduceSumNormalizePattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start ReshapePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips ReshapeMatMulReshapePattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips Reshape2Of3Pattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ReshapeReshapeBinaryPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start ReshapeSqueezePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips MatMulAddPattern, pattern.priority=3, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips GemmTransposePattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips MatMulReshape2Of3Pattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips MulMulMatMulPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start ShapeBasedReshapeIsSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedStaticExpandPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips ShapeBasedConcatExpandPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start ShapeBasedEditDistanceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedIdentityPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips ShapeBasedExpandBroadcastPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ShapeBasedExpandBroadcastMatMulPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ShapeBasedExpandCastWhereSwapPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ShapeBasedExpandSwapPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips ShapeBasedMatMulToMulPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start ShapedBasedReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedSameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedShapeShapeAddPattern with main_opset=18 and min_opset=1
    [ShapeBasedShapeShapeAddPattern.match] NONE - line: 244:yobx.xoptim.patterns.onnx_shape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedShapeShapeAddPattern.match] NONE - line: 244:yobx.xoptim.patterns.onnx_shape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips RotaryEmbeddingPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start SameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenFromInputPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips SequenceConstructAtPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips SplitToSequenceSequenceAtPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips SliceSlicePattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips SlicesSplitPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start SoftmaxCrossEntropyLossCastPattern with main_opset=18 and min_opset=14
    [GraphBuilder-VOY.make_tensor_input] X[0:None] -- marker=_build_pattern1_X
    [GraphBuilder-VOY.set_type] X:0
    [GraphBuilder-VOY.set_type] X:-1
    [GraphBuilder-VOY.make_tensor_input] indices[0:None] -- marker=_build_pattern1_indices
    [GraphBuilder-VOY.set_type] indices:0
    [GraphBuilder-VOY.set_type] indices:-1
    [GraphBuilder-VOY.make_tensor_input] axis[0:None] -- marker=_build_pattern1_axis
    [GraphBuilder-VOY.set_type] axis:0
    [GraphBuilder-VOY.set_type] axis:-1
    [GraphBuilder-VOY.make_tensor_input] zerof[0:None] -- marker=_build_pattern1_zerof
    [GraphBuilder-VOY.set_type] zerof:0
    [GraphBuilder-VOY.set_type] zerof:-1
    [GraphBuilder-VOY.make_tensor_input] zeroi[0:None] -- marker=_build_pattern1_zeroi
    [GraphBuilder-VOY.set_type] zeroi:0
    [GraphBuilder-VOY.set_type] zeroi:-1
    [GraphBuilder-VOY.make_tensor_input] b[0:None] -- marker=_build_pattern1_b
    [GraphBuilder-VOY.set_type] b:0
    [GraphBuilder-VOY.set_type] b:-1
    [GraphBuilder-VOY.3.make_node] [tt:-] Equal: ['indices', 'b']->['_onx_equal_indices']
    [GraphBuilder-VOY.set_type] _onx_equal_indices:9
    [GraphBuilder-VOY.3.make_node] [t:-] Not: ['_onx_equal_indices']->['_onx_not_equal_indices']
    [GraphBuilder-VOY.set_type] _onx_not_equal_indices:9
    [GraphBuilder-VOY.3.make_node] [ttt:-] Where: ['_onx_not_equal_indices', 'indices', 'zeroi']->['_onx_where_not_equal_indices']
    [GraphBuilder-VOY.set_type] _onx_where_not_equal_indices:-1
    [GraphBuilder-VOY.3.make_node] [tt:-] Unsqueeze: ['_onx_where_not_equal_indices', 'axis']->['_onx_where_not_equal_indices::UnSq']
    [GraphBuilder-VOY.set_type] _onx_where_not_equal_indices::UnSq:-1
    [GraphBuilder-VOY.3.make_node] [t:-] LogSoftmax: ['X']->['_onx_logsoftmax_X']
    [GraphBuilder-VOY.set_type] _onx_logsoftmax_X:-1
    [GraphBuilder-VOY.set_type] _onx_gatherelements_logsoftmax_X:-1
    [GraphBuilder-VOY.3.make_node] [tt:t] GatherElements: ['_onx_logsoftmax_X', '_onx_where_not_equal_indices::UnSq']->['_onx_gatherelements_logsoftmax_X']
    [GraphBuilder-VOY.set_type] _onx_gatherelements_logsoftmax_X:-1
    [GraphBuilder-VOY.3.make_node] [tt:-] Squeeze: ['_onx_gatherelements_logsoftmax_X', 'axis']->['_onx_gatherelements_logsoftmax_X::Sq']
    [GraphBuilder-VOY.set_type] _onx_gatherelements_logsoftmax_X::Sq:-1
    [GraphBuilder-VOY.3.make_node] [t:-] Neg: ['_onx_gatherelements_logsoftmax_X::Sq']->['_onx_neg_gatherelements_logsoftmax_X::Sq']
    [GraphBuilder-VOY.set_type] _onx_neg_gatherelements_logsoftmax_X::Sq:-1
    [GraphBuilder-VOY.3.make_node] [ttt:-] Where: ['_onx_not_equal_indices', '_onx_neg_gatherelements_logsoftmax_X::Sq', 'zerof']->['_onx_where_not_equal_indices2']
    [GraphBuilder-VOY.set_type] _onx_where_not_equal_indices2:-1
    [GraphBuilder-VOY.3.make_node] [t:-] Cast: ['_onx_not_equal_indices']->['_onx_not_equal_indices::C1']
    [GraphBuilder-VOY.set_type] _onx_not_equal_indices::C1:1
    [GraphBuilder-VOY.3.make_node] [t:-] ReduceSum: ['_onx_not_equal_indices::C1']->['_onx_reducesum_not_equal_indices::C1']
    [GraphBuilder-VOY.set_type] _onx_reducesum_not_equal_indices::C1:1
    [GraphBuilder-VOY.set_shape] _onx_reducesum_not_equal_indices::C1:()
    [GraphBuilder-VOY.set_rank] _onx_reducesum_not_equal_indices::C1:0
    [GraphBuilder-VOY.3.make_node] [#:-] Cast: ['_onx_reducesum_not_equal_indices::C1']->['_onx_reducesum_not_equal_indices::C1::C10']
    [GraphBuilder-VOY.set_type] _onx_reducesum_not_equal_indices::C1::C10:10
    [GraphBuilder-VOY.set_shape] _onx_reducesum_not_equal_indices::C1::C10:()
    [GraphBuilder-VOY.set_rank] _onx_reducesum_not_equal_indices::C1::C10:0
    [GraphBuilder-VOY.3.make_node] [t:-] Cast: ['_onx_where_not_equal_indices2']->['_onx_where_not_equal_indices2::C1']
    [GraphBuilder-VOY.set_type] _onx_where_not_equal_indices2::C1:1
    [GraphBuilder-VOY.3.make_node] [t:-] ReduceSum: ['_onx_where_not_equal_indices2::C1']->['_onx_reducesum_where_not_equal_indices2::C1']
    [GraphBuilder-VOY.set_type] _onx_reducesum_where_not_equal_indices2::C1:1
    [GraphBuilder-VOY.set_shape] _onx_reducesum_where_not_equal_indices2::C1:()
    [GraphBuilder-VOY.set_rank] _onx_reducesum_where_not_equal_indices2::C1:0
    [GraphBuilder-VOY.3.make_node] [#:-] Cast: ['_onx_reducesum_where_not_equal_indices2::C1']->['_onx_reducesum_where_not_equal_indices2::C1::C10']
    [GraphBuilder-VOY.set_type] _onx_reducesum_where_not_equal_indices2::C1::C10:10
    [GraphBuilder-VOY.set_shape] _onx_reducesum_where_not_equal_indices2::C1::C10:()
    [GraphBuilder-VOY.set_rank] _onx_reducesum_where_not_equal_indices2::C1::C10:0
    [GraphBuilder-VOY.3.make_node] [##:-] Div: ['_onx_reducesum_where_not_equal_indices2::C1::C10', '_onx_reducesum_not_equal_indices::C1::C10']->['_onx_div_reducesum_where_not_equal_indices2::C1::C10']
    [GraphBuilder-VOY.set_type] _onx_div_reducesum_where_not_equal_indices2::C1::C10:10
    [GraphBuilder-VOY.set_shape] _onx_div_reducesum_where_not_equal_indices2::C1::C10:()
    [GraphBuilder-VOY.set_rank] _onx_div_reducesum_where_not_equal_indices2::C1::C10:0
    [GraphBuilder-VOY.make_tensor_output] _onx_div_reducesum_where_not_equal_indices2::C1::C10[0: None]
    [GraphBuilderPatternOptimization-HZK.optimize] skips SplitConcatPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start SqueezeAddPattern with main_opset=18 and min_opset=1
    [SqueezeAddPattern.match] NONE - line: 397:yobx.xoptim.patterns.onnx_unsqueeze, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [SqueezeAddPattern.match] NONE - line: 397:yobx.xoptim.patterns.onnx_unsqueeze, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start SqueezeBinaryUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start StaticConcatReshapePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips Sub1MulPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start SwapExpandReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandUnsqueezePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips SwapRangeAddScalarPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start SwapUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnsqueezeTransposePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips SwitchOrderBinaryPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips SwitchReshapeActivationPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips TransposeEqualReshapePattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start TransposeGatherPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips TransposeMatMulPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips TransposeReshapeMatMulPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start TransposeReshapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeTransposePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips UnsqueezeEqualPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start UnsqueezeOrSqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips WhereAddPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips RotaryConcatPartPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start FunctionAttentionPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionGQAPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips FunctionCausalMaskPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips FunctionCausalMaskMulAddPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips FunctionCosSinCachePattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips FunctionHalfRotaryEmbeddingPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips RMSNormalizationPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips RMSNormalizationMulPattern, pattern.priority=1, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] skips AttentionGQAPattern, pattern.priority=2, current_priority_index=0, priorities[current_priority_index]=0 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0 - matching_step done 0
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0F0 - apply_step with 0 matches
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0F0 - done with 0 applied patterns
    [GraphBuilderPatternOptimization-HZK.optimize] done all: -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0F0 - remove_duplicated_shape
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0F0 - remove_duplicated_shape done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0F0 - remove_identity
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 5
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 5 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 5 nodes in 6.18920003034873e-05 seconds
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0F0 - remove_identity done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0F0 - remove_unused
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C0F0 - remove_unused done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] increase priority to 1
    [GraphBuilderPatternOptimization-HZK.optimize] it=0C1F0 - next
    [GraphBuilderPatternOptimization-HZK.optimize] iteration 1: 5 nodes, priority=1
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0 - matching_step
    [PatternOptimization.enumerate_matches] start BatchNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start BatchNormalizationTrainingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastLayerNormalizationCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastCastBinaryPattern with main_opset=18 and min_opset=1
    [CastCastBinaryPattern.match] NONE - line: 312:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [CastCastBinaryPattern.match] NONE - line: 312:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start CastCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastOpCastPattern with main_opset=18 and min_opset=1
    [CastOpCastPattern.match] NONE - line: 454:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [CastOpCastPattern.match] NONE - line: 451:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ClipClipPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatEmptyPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatTwiceUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConstantToInitializerPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConvBiasNullPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start PadConvPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start DropoutPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandBroadcastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandUnsqueezeExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GathersSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GeluPattern with main_opset=18 and min_opset=20
    [PatternOptimization.enumerate_matches] start IdentityPattern with main_opset=18 and min_opset=1
    [IdentityPattern.match] NONE - line: 745:yobx.xoptim.patterns.onnx_any, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [IdentityPattern.match] NONE - line: 787:yobx.xoptim.patterns.onnx_any, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start LayerNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LayerNormalizationScalePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LeakyReluPattern with main_opset=18 and min_opset=6
    [PatternOptimization.enumerate_matches] start MaxReluPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulMulMulScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulUnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotNotPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotWherePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceArgTopKPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceSumNormalizePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeMatMulReshapePattern with main_opset=18 and min_opset=1
    [ReshapeMatMulReshapePattern.match] NONE - line: 1035:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [ReshapeMatMulReshapePattern.match] NONE - line: 1035:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start Reshape2Of3Pattern with main_opset=18 and min_opset=1
    [Reshape2Of3Pattern.match] NONE - line: 700:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [Reshape2Of3Pattern.match] NONE - line: 700:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ReshapeReshapeBinaryPattern with main_opset=18 and min_opset=1
    [ReshapeReshapeBinaryPattern.match] NONE - line: 950:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ReshapeReshapeBinaryPattern.match] NONE - line: 950:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ReshapeSqueezePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips MatMulAddPattern, pattern.priority=3, current_priority_index=1, priorities[current_priority_index]=1 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start GemmTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MatMulReshape2Of3Pattern with main_opset=18 and min_opset=1
    [MatMulReshape2Of3Pattern.match] NONE - line: 556:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [MatMulReshape2Of3Pattern.match] NONE - line: 556:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start MulMulMatMulPattern with main_opset=18 and min_opset=1
    [MulMulMatMulPattern.match] NONE - line: 922:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [MulMulMatMulPattern.match] NONE - line: 922:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapeBasedReshapeIsSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedStaticExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedConcatExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedEditDistanceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedIdentityPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastPattern with main_opset=18 and min_opset=1
    [ShapeBasedExpandBroadcastPattern.match] NONE - line: 383:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedExpandBroadcastPattern.match] NONE - line: 383:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastMatMulPattern with main_opset=18 and min_opset=1
    [ShapeBasedExpandBroadcastMatMulPattern.match] NONE - line: 1081:yobx.xoptim.patterns.onnx_expand, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [ShapeBasedExpandBroadcastMatMulPattern.match] NONE - line: 1081:yobx.xoptim.patterns.onnx_expand, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandCastWhereSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandSwapPattern with main_opset=18 and min_opset=1
    [ShapeBasedExpandSwapPattern.match] NONE - line: 874:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedExpandSwapPattern.match] NONE - line: 874:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeBasedMatMulToMulPattern with main_opset=18 and min_opset=1
    [ShapeBasedMatMulToMulPattern.match] NONE - line: 1734:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [ShapeBasedMatMulToMulPattern.match] NONE - line: 1734:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapedBasedReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedSameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedShapeShapeAddPattern with main_opset=18 and min_opset=1
    [ShapeBasedShapeShapeAddPattern.match] NONE - line: 244:yobx.xoptim.patterns.onnx_shape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedShapeShapeAddPattern.match] NONE - line: 244:yobx.xoptim.patterns.onnx_shape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenFromInputPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SequenceConstructAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SplitToSequenceSequenceAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SliceSlicePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SlicesSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SoftmaxCrossEntropyLossCastPattern with main_opset=18 and min_opset=14
    [PatternOptimization.enumerate_matches] start SplitConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeAddPattern with main_opset=18 and min_opset=1
    [SqueezeAddPattern.match] NONE - line: 397:yobx.xoptim.patterns.onnx_unsqueeze, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [SqueezeAddPattern.match] NONE - line: 397:yobx.xoptim.patterns.onnx_unsqueeze, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start SqueezeBinaryUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start StaticConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start Sub1MulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapRangeAddScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnsqueezeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchOrderBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchReshapeActivationPattern with main_opset=18 and min_opset=1
    [SwitchReshapeActivationPattern.match] NONE - line: 1601:yobx.xoptim.patterns.onnx_matmul, op_type=Relu, name=, inputs=linear
    [PatternOptimization.enumerate_matches] start TransposeEqualReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeMatMulPattern with main_opset=18 and min_opset=1
    [TransposeMatMulPattern.match] NONE - line: 1193:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [TransposeMatMulPattern.match] NONE - line: 1193:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start TransposeReshapeMatMulPattern with main_opset=18 and min_opset=1
    [TransposeReshapeMatMulPattern.match] NONE - line: 1398:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [TransposeReshapeMatMulPattern.match] NONE - line: 1398:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start TransposeReshapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeEqualPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeOrSqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start WhereAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryConcatPartPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionGQAPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskMulAddPattern with main_opset=18 and min_opset=1
    [FunctionCausalMaskMulAddPattern.match] NONE - line: 1510:yobx.xoptim.patterns.onnx_rotary, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [FunctionCausalMaskMulAddPattern.match] NONE - line: 1510:yobx.xoptim.patterns.onnx_rotary, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start FunctionCosSinCachePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionHalfRotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationMulPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips AttentionGQAPattern, pattern.priority=2, current_priority_index=1, priorities[current_priority_index]=1 priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0 - matching_step done 0
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0F0 - apply_step with 0 matches
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0F0 - done with 0 applied patterns
    [GraphBuilderPatternOptimization-HZK.optimize] done all: -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0F0 - remove_duplicated_shape
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0F0 - remove_duplicated_shape done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0F0 - remove_identity
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 5
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 5 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 5 nodes in 4.6160999772837386e-05 seconds
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0F0 - remove_identity done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0F0 - remove_unused
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C0F0 - remove_unused done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] increase priority to 2
    [GraphBuilderPatternOptimization-HZK.optimize] it=1C1F0 - next
    [GraphBuilderPatternOptimization-HZK.optimize] iteration 2: 5 nodes, priority=2
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0 - matching_step
    [PatternOptimization.enumerate_matches] start BatchNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start BatchNormalizationTrainingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastLayerNormalizationCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastCastBinaryPattern with main_opset=18 and min_opset=1
    [CastCastBinaryPattern.match] NONE - line: 312:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [CastCastBinaryPattern.match] NONE - line: 312:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start CastCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastOpCastPattern with main_opset=18 and min_opset=1
    [CastOpCastPattern.match] NONE - line: 454:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [CastOpCastPattern.match] NONE - line: 451:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ClipClipPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatEmptyPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatTwiceUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConstantToInitializerPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConvBiasNullPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start PadConvPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start DropoutPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandBroadcastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandUnsqueezeExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GathersSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GeluPattern with main_opset=18 and min_opset=20
    [PatternOptimization.enumerate_matches] start IdentityPattern with main_opset=18 and min_opset=1
    [IdentityPattern.match] NONE - line: 745:yobx.xoptim.patterns.onnx_any, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [IdentityPattern.match] NONE - line: 787:yobx.xoptim.patterns.onnx_any, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start LayerNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LayerNormalizationScalePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LeakyReluPattern with main_opset=18 and min_opset=6
    [PatternOptimization.enumerate_matches] start MaxReluPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulMulMulScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulUnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotNotPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotWherePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceArgTopKPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceSumNormalizePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeMatMulReshapePattern with main_opset=18 and min_opset=1
    [ReshapeMatMulReshapePattern.match] NONE - line: 1035:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [ReshapeMatMulReshapePattern.match] NONE - line: 1035:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start Reshape2Of3Pattern with main_opset=18 and min_opset=1
    [Reshape2Of3Pattern.match] NONE - line: 700:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [Reshape2Of3Pattern.match] NONE - line: 700:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ReshapeReshapeBinaryPattern with main_opset=18 and min_opset=1
    [ReshapeReshapeBinaryPattern.match] NONE - line: 950:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ReshapeReshapeBinaryPattern.match] NONE - line: 950:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ReshapeSqueezePattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] skips MatMulAddPattern, pattern.priority=3, current_priority_index=2, priorities[current_priority_index]=2 priorities=[0, 1, 2, 3]
    [PatternOptimization.enumerate_matches] start GemmTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MatMulReshape2Of3Pattern with main_opset=18 and min_opset=1
    [MatMulReshape2Of3Pattern.match] NONE - line: 556:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [MatMulReshape2Of3Pattern.match] NONE - line: 556:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start MulMulMatMulPattern with main_opset=18 and min_opset=1
    [MulMulMatMulPattern.match] NONE - line: 922:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [MulMulMatMulPattern.match] NONE - line: 922:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapeBasedReshapeIsSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedStaticExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedConcatExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedEditDistanceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedIdentityPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastPattern with main_opset=18 and min_opset=1
    [ShapeBasedExpandBroadcastPattern.match] NONE - line: 383:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedExpandBroadcastPattern.match] NONE - line: 383:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastMatMulPattern with main_opset=18 and min_opset=1
    [ShapeBasedExpandBroadcastMatMulPattern.match] NONE - line: 1081:yobx.xoptim.patterns.onnx_expand, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [ShapeBasedExpandBroadcastMatMulPattern.match] NONE - line: 1081:yobx.xoptim.patterns.onnx_expand, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandCastWhereSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandSwapPattern with main_opset=18 and min_opset=1
    [ShapeBasedExpandSwapPattern.match] NONE - line: 874:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedExpandSwapPattern.match] NONE - line: 874:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeBasedMatMulToMulPattern with main_opset=18 and min_opset=1
    [ShapeBasedMatMulToMulPattern.match] NONE - line: 1734:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [ShapeBasedMatMulToMulPattern.match] NONE - line: 1734:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapedBasedReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedSameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedShapeShapeAddPattern with main_opset=18 and min_opset=1
    [ShapeBasedShapeShapeAddPattern.match] NONE - line: 244:yobx.xoptim.patterns.onnx_shape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedShapeShapeAddPattern.match] NONE - line: 244:yobx.xoptim.patterns.onnx_shape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenFromInputPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SequenceConstructAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SplitToSequenceSequenceAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SliceSlicePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SlicesSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SoftmaxCrossEntropyLossCastPattern with main_opset=18 and min_opset=14
    [PatternOptimization.enumerate_matches] start SplitConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeAddPattern with main_opset=18 and min_opset=1
    [SqueezeAddPattern.match] NONE - line: 397:yobx.xoptim.patterns.onnx_unsqueeze, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [SqueezeAddPattern.match] NONE - line: 397:yobx.xoptim.patterns.onnx_unsqueeze, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start SqueezeBinaryUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start StaticConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start Sub1MulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapRangeAddScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnsqueezeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchOrderBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchReshapeActivationPattern with main_opset=18 and min_opset=1
    [SwitchReshapeActivationPattern.match] NONE - line: 1601:yobx.xoptim.patterns.onnx_matmul, op_type=Relu, name=, inputs=linear
    [PatternOptimization.enumerate_matches] start TransposeEqualReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeMatMulPattern with main_opset=18 and min_opset=1
    [TransposeMatMulPattern.match] NONE - line: 1193:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [TransposeMatMulPattern.match] NONE - line: 1193:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start TransposeReshapeMatMulPattern with main_opset=18 and min_opset=1
    [TransposeReshapeMatMulPattern.match] NONE - line: 1398:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [TransposeReshapeMatMulPattern.match] NONE - line: 1398:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start TransposeReshapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeEqualPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeOrSqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start WhereAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryConcatPartPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionGQAPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskMulAddPattern with main_opset=18 and min_opset=1
    [FunctionCausalMaskMulAddPattern.match] NONE - line: 1510:yobx.xoptim.patterns.onnx_rotary, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [FunctionCausalMaskMulAddPattern.match] NONE - line: 1510:yobx.xoptim.patterns.onnx_rotary, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start FunctionCosSinCachePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionHalfRotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start AttentionGQAPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0 - matching_step done 0
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0F0 - apply_step with 0 matches
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0F0 - done with 0 applied patterns
    [GraphBuilderPatternOptimization-HZK.optimize] done all: -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0F0 - remove_duplicated_shape
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0F0 - remove_duplicated_shape done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0F0 - remove_identity
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 5
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 5 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 5 nodes in 4.70750001113629e-05 seconds
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0F0 - remove_identity done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0F0 - remove_unused
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C0F0 - remove_unused done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] increase priority to 3
    [GraphBuilderPatternOptimization-HZK.optimize] it=2C1F0 - next
    [GraphBuilderPatternOptimization-HZK.optimize] iteration 3: 5 nodes, priority=3
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C0 - matching_step
    [PatternOptimization.enumerate_matches] start BatchNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start BatchNormalizationTrainingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastLayerNormalizationCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastCastBinaryPattern with main_opset=18 and min_opset=1
    [CastCastBinaryPattern.match] NONE - line: 312:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [CastCastBinaryPattern.match] NONE - line: 312:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start CastCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastOpCastPattern with main_opset=18 and min_opset=1
    [CastOpCastPattern.match] NONE - line: 454:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [CastOpCastPattern.match] NONE - line: 451:yobx.xoptim.patterns.onnx_cast, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ClipClipPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatEmptyPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatTwiceUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConstantToInitializerPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConvBiasNullPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start PadConvPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start DropoutPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandBroadcastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandUnsqueezeExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GathersSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GeluPattern with main_opset=18 and min_opset=20
    [PatternOptimization.enumerate_matches] start IdentityPattern with main_opset=18 and min_opset=1
    [IdentityPattern.match] NONE - line: 745:yobx.xoptim.patterns.onnx_any, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [IdentityPattern.match] NONE - line: 787:yobx.xoptim.patterns.onnx_any, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start LayerNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LayerNormalizationScalePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LeakyReluPattern with main_opset=18 and min_opset=6
    [PatternOptimization.enumerate_matches] start MaxReluPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulMulMulScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulUnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotNotPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotWherePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceArgTopKPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceSumNormalizePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeMatMulReshapePattern with main_opset=18 and min_opset=1
    [ReshapeMatMulReshapePattern.match] NONE - line: 1035:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [ReshapeMatMulReshapePattern.match] NONE - line: 1035:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start Reshape2Of3Pattern with main_opset=18 and min_opset=1
    [Reshape2Of3Pattern.match] NONE - line: 700:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [Reshape2Of3Pattern.match] NONE - line: 700:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ReshapeReshapeBinaryPattern with main_opset=18 and min_opset=1
    [ReshapeReshapeBinaryPattern.match] NONE - line: 950:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ReshapeReshapeBinaryPattern.match] NONE - line: 950:yobx.xoptim.patterns.onnx_reshape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ReshapeSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MatMulAddPattern with main_opset=18 and min_opset=1
    [MatchResult.match] MATCH MatMulAddPattern with 2 nodes and types ['MatMul', 'Add'] - []
    [GraphBuilderPatternOptimization-HZK.optimize] match=MatchResult: MatMulAddPattern replaces ['MatMul', 'Add']
    [MatchResult.match] MATCH MatMulAddPattern with 2 nodes and types ['MatMul', 'Add'] - []
    [GraphBuilderPatternOptimization-HZK.optimize] match=MatchResult: MatMulAddPattern replaces ['MatMul', 'Add']
    [PatternOptimization.enumerate_matches] start GemmTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MatMulReshape2Of3Pattern with main_opset=18 and min_opset=1
    [MatMulReshape2Of3Pattern.match] NONE - line: 556:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [MatMulReshape2Of3Pattern.match] NONE - line: 556:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start MulMulMatMulPattern with main_opset=18 and min_opset=1
    [MulMulMatMulPattern.match] NONE - line: 922:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [MulMulMatMulPattern.match] NONE - line: 922:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapeBasedReshapeIsSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedStaticExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedConcatExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedEditDistanceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedIdentityPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastPattern with main_opset=18 and min_opset=1
    [ShapeBasedExpandBroadcastPattern.match] NONE - line: 383:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedExpandBroadcastPattern.match] NONE - line: 383:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastMatMulPattern with main_opset=18 and min_opset=1
    [ShapeBasedExpandBroadcastMatMulPattern.match] NONE - line: 1081:yobx.xoptim.patterns.onnx_expand, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [ShapeBasedExpandBroadcastMatMulPattern.match] NONE - line: 1081:yobx.xoptim.patterns.onnx_expand, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandCastWhereSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandSwapPattern with main_opset=18 and min_opset=1
    [ShapeBasedExpandSwapPattern.match] NONE - line: 874:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedExpandSwapPattern.match] NONE - line: 874:yobx.xoptim.patterns.onnx_expand, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeBasedMatMulToMulPattern with main_opset=18 and min_opset=1
    [ShapeBasedMatMulToMulPattern.match] NONE - line: 1734:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [ShapeBasedMatMulToMulPattern.match] NONE - line: 1734:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapedBasedReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedSameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedShapeShapeAddPattern with main_opset=18 and min_opset=1
    [ShapeBasedShapeShapeAddPattern.match] NONE - line: 244:yobx.xoptim.patterns.onnx_shape, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [ShapeBasedShapeShapeAddPattern.match] NONE - line: 244:yobx.xoptim.patterns.onnx_shape, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start ShapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenFromInputPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SequenceConstructAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SplitToSequenceSequenceAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SliceSlicePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SlicesSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SoftmaxCrossEntropyLossCastPattern with main_opset=18 and min_opset=14
    [PatternOptimization.enumerate_matches] start SplitConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeAddPattern with main_opset=18 and min_opset=1
    [SqueezeAddPattern.match] NONE - line: 397:yobx.xoptim.patterns.onnx_unsqueeze, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [SqueezeAddPattern.match] NONE - line: 397:yobx.xoptim.patterns.onnx_unsqueeze, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start SqueezeBinaryUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start StaticConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start Sub1MulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapRangeAddScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnsqueezeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchOrderBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchReshapeActivationPattern with main_opset=18 and min_opset=1
    [SwitchReshapeActivationPattern.match] NONE - line: 1601:yobx.xoptim.patterns.onnx_matmul, op_type=Relu, name=, inputs=linear
    [PatternOptimization.enumerate_matches] start TransposeEqualReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeMatMulPattern with main_opset=18 and min_opset=1
    [TransposeMatMulPattern.match] NONE - line: 1193:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [TransposeMatMulPattern.match] NONE - line: 1193:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start TransposeReshapeMatMulPattern with main_opset=18 and min_opset=1
    [TransposeReshapeMatMulPattern.match] NONE - line: 1398:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=x,p_layers_0_weight::T10
    [TransposeReshapeMatMulPattern.match] NONE - line: 1398:yobx.xoptim.patterns.onnx_matmul, op_type=MatMul, name=, inputs=relu,p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start TransposeReshapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeEqualPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeOrSqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start WhereAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryConcatPartPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionGQAPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskMulAddPattern with main_opset=18 and min_opset=1
    [FunctionCausalMaskMulAddPattern.match] NONE - line: 1510:yobx.xoptim.patterns.onnx_rotary, op_type=Add, name=, inputs=_onx_matmul_x,layers.0.bias
    [FunctionCausalMaskMulAddPattern.match] NONE - line: 1510:yobx.xoptim.patterns.onnx_rotary, op_type=Add, name=, inputs=_onx_matmul_relu,layers.2.bias
    [PatternOptimization.enumerate_matches] start FunctionCosSinCachePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionHalfRotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start AttentionGQAPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C0 - matching_step done 2
    [GraphBuilderPatternOptimization-HZK.optimize] applies 2 matches, 2*MatMulAddPattern - time=0.002 | max_time=IdentityPattern:0.000
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C0F1 - apply_step with 2 matches
    [GraphBuilderPatternOptimization-HZK.optimize] apply MatchResult: MatMulAddPattern replaces ['MatMul', 'Add'], inputs: ['x', 'p_layers_0_weight::T10', '_onx_matmul_x', 'layers.0.bias'], outputs: ['_onx_matmul_x', 'linear']
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: MatMulAddPattern replaces ['MatMul', 'Add']
      - MatMul: ['x', 'p_layers_0_weight::T10'] -> ['_onx_matmul_x']
      - Add: ['_onx_matmul_x', 'layers.0.bias'] -> ['linear']
      + Gemm: ['x', 'p_layers_0_weight::T10', 'layers.0.bias'] -> ['linear']
    [GraphBuilder-HZK.set_type] linear:1
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: MatMulAddPattern replaces ['MatMul', 'Add'] applied.
    [GraphBuilderPatternOptimization-HZK.optimize] - add ['Gemm']
    [GraphBuilderPatternOptimization-HZK.optimize] done MatchResult: MatMulAddPattern replaces ['MatMul', 'Add']: -2 +1 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] removed outputs {'_onx_matmul_x'}
    [GraphBuilderPatternOptimization-HZK.optimize] apply MatchResult: MatMulAddPattern replaces ['MatMul', 'Add'], inputs: ['relu', 'p_layers_2_weight::T10', '_onx_matmul_relu', 'layers.2.bias'], outputs: ['_onx_matmul_relu', 'output_0']
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: MatMulAddPattern replaces ['MatMul', 'Add']
      - MatMul: ['relu', 'p_layers_2_weight::T10'] -> ['_onx_matmul_relu']
      - Add: ['_onx_matmul_relu', 'layers.2.bias'] -> ['output_0']
      + Gemm: ['relu', 'p_layers_2_weight::T10', 'layers.2.bias'] -> ['output_0']
    [GraphBuilder-HZK.set_type] output_0:1
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: MatMulAddPattern replaces ['MatMul', 'Add'] applied.
    [GraphBuilderPatternOptimization-HZK.optimize] - add ['Gemm']
    [GraphBuilderPatternOptimization-HZK.optimize] done MatchResult: MatMulAddPattern replaces ['MatMul', 'Add']: -2 +1 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] removed outputs {'_onx_matmul_relu'}
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C1F1 - done with 2 applied patterns
    [GraphBuilderPatternOptimization-HZK.optimize] done all: -4 +2 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C1F1 - remove_duplicated_shape
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C1F1 - remove_duplicated_shape done -4 +2 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C1F1 - remove_identity
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 3
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 3 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 3 nodes in 3.593099972931668e-05 seconds
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C1F1 - remove_identity done -4 +2 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C1F1 - remove_unused
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C1F1 - remove_unused done -4 +2 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=3C1F1 - next
    [GraphBuilderPatternOptimization-HZK.optimize] iteration 4: 3 nodes, priority=3
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C0 - matching_step
    [PatternOptimization.enumerate_matches] start BatchNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start BatchNormalizationTrainingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastLayerNormalizationCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastCastBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastOpCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ClipClipPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatEmptyPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatTwiceUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConstantToInitializerPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConvBiasNullPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start PadConvPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start DropoutPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandBroadcastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandUnsqueezeExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GathersSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GeluPattern with main_opset=18 and min_opset=20
    [PatternOptimization.enumerate_matches] start IdentityPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LayerNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LayerNormalizationScalePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LeakyReluPattern with main_opset=18 and min_opset=6
    [PatternOptimization.enumerate_matches] start MaxReluPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulMulMulScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulUnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotNotPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotWherePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceArgTopKPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceSumNormalizePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeMatMulReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start Reshape2Of3Pattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapeBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MatMulAddPattern with main_opset=18 and min_opset=1
    [MatMulAddPattern.match] NONE - line: 130:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=MatMulAddPattern--, inputs=x,p_layers_0_weight::T10,layers.0.bias
    [MatMulAddPattern.match] NONE - line: 127:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=MatMulAddPattern--2, inputs=relu,p_layers_2_weight::T10,layers.2.bias
    [PatternOptimization.enumerate_matches] start GemmTransposePattern with main_opset=18 and min_opset=1
    [MatchResult.match] MATCH GemmTransposePattern with 1 nodes and types ['Gemm'] - []
    [GraphBuilderPatternOptimization-HZK.optimize] match=MatchResult: GemmTransposePattern replaces ['Gemm']
    [MatchResult.match] MATCH GemmTransposePattern with 1 nodes and types ['Gemm'] - []
    [GraphBuilderPatternOptimization-HZK.optimize] match=MatchResult: GemmTransposePattern replaces ['Gemm']
    [PatternOptimization.enumerate_matches] start MatMulReshape2Of3Pattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulMulMatMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedReshapeIsSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedStaticExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedConcatExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedEditDistanceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedIdentityPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastMatMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandCastWhereSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedMatMulToMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapedBasedReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedSameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedShapeShapeAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenFromInputPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SequenceConstructAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SplitToSequenceSequenceAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SliceSlicePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SlicesSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SoftmaxCrossEntropyLossCastPattern with main_opset=18 and min_opset=14
    [PatternOptimization.enumerate_matches] start SplitConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeBinaryUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start StaticConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start Sub1MulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapRangeAddScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnsqueezeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchOrderBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchReshapeActivationPattern with main_opset=18 and min_opset=1
    [SwitchReshapeActivationPattern.match] NONE - line: 1601:yobx.xoptim.patterns.onnx_matmul, op_type=Relu, name=, inputs=linear
    [PatternOptimization.enumerate_matches] start TransposeEqualReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeMatMulPattern with main_opset=18 and min_opset=1
    [TransposeMatMulPattern.match] NONE - line: 1193:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=MatMulAddPattern--, inputs=x,p_layers_0_weight::T10,layers.0.bias
    [TransposeMatMulPattern.match] NONE - line: 1193:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=MatMulAddPattern--2, inputs=relu,p_layers_2_weight::T10,layers.2.bias
    [PatternOptimization.enumerate_matches] start TransposeReshapeMatMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeReshapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeEqualPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeOrSqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start WhereAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryConcatPartPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionGQAPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskMulAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCosSinCachePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionHalfRotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start AttentionGQAPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C0 - matching_step done 2
    [GraphBuilderPatternOptimization-HZK.optimize] applies 2 matches, 2*GemmTransposePattern - time=0.002 | max_time=TransposeMatMulPattern:0.000
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C0F1 - apply_step with 2 matches
    [GraphBuilderPatternOptimization-HZK.optimize] apply MatchResult: GemmTransposePattern replaces ['Gemm'], inputs: ['x', 'p_layers_0_weight::T10', 'layers.0.bias'], outputs: ['linear']
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_0_weight::T10', node=Transpose
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: GemmTransposePattern replaces ['Gemm']
      - Gemm: ['x', 'p_layers_0_weight::T10', 'layers.0.bias'] -> ['linear']
      + Transpose: ['p_layers_0_weight::T10'] -> ['GemmTransposePattern--p_layers_0_weight::T10']
      + Gemm: ['x', 'GemmTransposePattern--p_layers_0_weight::T10', 'layers.0.bias'] -> ['linear']
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_0_weight::T10', node=Transpose
    [GraphBuilder-HZK.set_type] GemmTransposePattern--p_layers_0_weight::T10:1
    [GraphBuilder-HZK.set_shape] GemmTransposePattern--p_layers_0_weight::T10:(32, 10)
    [GraphBuilder-HZK.set_rank] GemmTransposePattern--p_layers_0_weight::T10:2
    [GraphBuilder-HZK.set_type] linear:1
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: GemmTransposePattern replaces ['Gemm'] applied.
    [GraphBuilderPatternOptimization-HZK.optimize] - add ['Transpose', 'Gemm']
    [GraphBuilderPatternOptimization-HZK.optimize] done MatchResult: GemmTransposePattern replaces ['Gemm']: -1 +2 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] apply MatchResult: GemmTransposePattern replaces ['Gemm'], inputs: ['relu', 'p_layers_2_weight::T10', 'layers.2.bias'], outputs: ['output_0']
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_2_weight::T10', node=Transpose
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: GemmTransposePattern replaces ['Gemm']
      - Gemm: ['relu', 'p_layers_2_weight::T10', 'layers.2.bias'] -> ['output_0']
      + Transpose: ['p_layers_2_weight::T10'] -> ['GemmTransposePattern--p_layers_2_weight::T10']
      + Gemm: ['relu', 'GemmTransposePattern--p_layers_2_weight::T10', 'layers.2.bias'] -> ['output_0']
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_2_weight::T10', node=Transpose
    [GraphBuilder-HZK.set_type] GemmTransposePattern--p_layers_2_weight::T10:1
    [GraphBuilder-HZK.set_shape] GemmTransposePattern--p_layers_2_weight::T10:(1, 32)
    [GraphBuilder-HZK.set_rank] GemmTransposePattern--p_layers_2_weight::T10:2
    [GraphBuilder-HZK.set_type] output_0:1
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: GemmTransposePattern replaces ['Gemm'] applied.
    [GraphBuilderPatternOptimization-HZK.optimize] - add ['Transpose', 'Gemm']
    [GraphBuilderPatternOptimization-HZK.optimize] done MatchResult: GemmTransposePattern replaces ['Gemm']: -1 +2 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C1F1 - done with 2 applied patterns
    [GraphBuilderPatternOptimization-HZK.optimize] done all: -2 +4 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C1F1 - remove_duplicated_shape
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C1F1 - remove_duplicated_shape done -2 +4 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C1F1 - remove_identity
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 5
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 5 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 5 nodes in 4.107000131625682e-05 seconds
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C1F1 - remove_identity done -2 +4 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C1F1 - remove_unused
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C1F1 - remove_unused done -2 +4 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=4C1F1 - next
    [GraphBuilderPatternOptimization-HZK.optimize] iteration 5: 5 nodes, priority=3
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C0 - matching_step
    [PatternOptimization.enumerate_matches] start BatchNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start BatchNormalizationTrainingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastLayerNormalizationCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastCastBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastOpCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ClipClipPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatEmptyPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatTwiceUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConstantToInitializerPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConvBiasNullPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start PadConvPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start DropoutPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandBroadcastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandUnsqueezeExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GathersSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GeluPattern with main_opset=18 and min_opset=20
    [PatternOptimization.enumerate_matches] start IdentityPattern with main_opset=18 and min_opset=1
    [IdentityPattern.match] NONE - line: 664:yobx.xoptim.patterns.onnx_any, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [IdentityPattern.match] NONE - line: 664:yobx.xoptim.patterns.onnx_any, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start LayerNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LayerNormalizationScalePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LeakyReluPattern with main_opset=18 and min_opset=6
    [PatternOptimization.enumerate_matches] start MaxReluPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulMulMulScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulUnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotNotPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotWherePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceArgTopKPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceSumNormalizePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeMatMulReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start Reshape2Of3Pattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapeBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MatMulAddPattern with main_opset=18 and min_opset=1
    [MatMulAddPattern.match] NONE - line: 130:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--2, inputs=x,GemmTransposePattern--p_layers_0_weight::T10,layers.0.bias
    [MatMulAddPattern.match] NONE - line: 127:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--23, inputs=relu,GemmTransposePattern--p_layers_2_weight::T10,layers.2.bias
    [PatternOptimization.enumerate_matches] start GemmTransposePattern with main_opset=18 and min_opset=1
    [GemmTransposePattern.match] NONE - line: 405:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--2, inputs=x,GemmTransposePattern--p_layers_0_weight::T10,layers.0.bias
    [GemmTransposePattern.match] NONE - line: 405:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--23, inputs=relu,GemmTransposePattern--p_layers_2_weight::T10,layers.2.bias
    [PatternOptimization.enumerate_matches] start MatMulReshape2Of3Pattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulMulMatMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedReshapeIsSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedStaticExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedConcatExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedEditDistanceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedIdentityPattern with main_opset=18 and min_opset=1
    [ShapeBasedIdentityPattern.match] NONE - line: 895:yobx.xoptim.patterns.onnx_any, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [ShapeBasedIdentityPattern.match] NONE - line: 895:yobx.xoptim.patterns.onnx_any, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastMatMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandCastWhereSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedMatMulToMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapedBasedReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedSameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedShapeShapeAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenFromInputPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SequenceConstructAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SplitToSequenceSequenceAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SliceSlicePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SlicesSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SoftmaxCrossEntropyLossCastPattern with main_opset=18 and min_opset=14
    [PatternOptimization.enumerate_matches] start SplitConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeBinaryUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start StaticConcatReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start Sub1MulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapRangeAddScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnaryPattern with main_opset=18 and min_opset=1
    [SwapUnaryPattern.match] NONE - line: 998:yobx.xoptim.patterns.onnx_any, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [SwapUnaryPattern.match] NONE - line: 998:yobx.xoptim.patterns.onnx_any, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start SwapUnsqueezeTransposePattern with main_opset=18 and min_opset=1
    [SwapUnsqueezeTransposePattern.match] NONE - line: 715:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [SwapUnsqueezeTransposePattern.match] NONE - line: 715:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start SwitchOrderBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchReshapeActivationPattern with main_opset=18 and min_opset=1
    [SwitchReshapeActivationPattern.match] NONE - line: 1601:yobx.xoptim.patterns.onnx_matmul, op_type=Relu, name=, inputs=linear
    [PatternOptimization.enumerate_matches] start TransposeEqualReshapePattern with main_opset=18 and min_opset=1
    [TransposeEqualReshapePattern.match] NONE - line: 493:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [MatchResult.match] MATCH TransposeEqualReshapePattern with 1 nodes and types ['Transpose'] - []
    [GraphBuilderPatternOptimization-HZK.optimize] match=MatchResult: TransposeEqualReshapePattern replaces ['Transpose']
    [PatternOptimization.enumerate_matches] start TransposeGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeMatMulPattern with main_opset=18 and min_opset=1
    [TransposeMatMulPattern.match] NONE - line: 1231:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--2, inputs=x,GemmTransposePattern--p_layers_0_weight::T10,layers.0.bias
    [TransposeMatMulPattern.match] NONE - line: 1231:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--23, inputs=relu,GemmTransposePattern--p_layers_2_weight::T10,layers.2.bias
    [PatternOptimization.enumerate_matches] start TransposeReshapeMatMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeReshapeTransposePattern with main_opset=18 and min_opset=1
    [TransposeReshapeTransposePattern.match] NONE - line: 245:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [TransposeReshapeTransposePattern.match] NONE - line: 245:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start TransposeTransposePattern with main_opset=18 and min_opset=1
    [TransposeTransposePattern.match] NONE - line: 99:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [TransposeTransposePattern.match] NONE - line: 99:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10
    [PatternOptimization.enumerate_matches] start UnsqueezeEqualPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeOrSqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start WhereAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryConcatPartPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionGQAPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskMulAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCosSinCachePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionHalfRotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start AttentionGQAPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C0 - matching_step done 1
    [GraphBuilderPatternOptimization-HZK.optimize] applies 1 matches, [0]=MatchResult: TransposeEqualReshapePattern replaces ['Transpose'] - time=0.003 | max_time=IdentityPattern:0.000
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C0F1 - apply_step with 1 matches
    [GraphBuilderPatternOptimization-HZK.optimize] apply MatchResult: TransposeEqualReshapePattern replaces ['Transpose'], inputs: ['p_layers_2_weight::T10'], outputs: ['GemmTransposePattern--p_layers_2_weight::T10']
    [GraphBuilder-HZK.set_shape] init7_s2_1_32:(2,)
    [GraphBuilder-HZK.set_rank] init7_s2_1_32:1
    [GraphBuilder-HZK.set_type] init7_s2_1_32:7
    [GraphBuilder-HZK.make_initializer] init7_s2_1_32[7:(2,)]
    [GraphBuilder-HZK.update_node_constant] new constant 'init7_s2_1_32', node=None
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_2_weight::T10', node=Reshape
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: TransposeEqualReshapePattern replaces ['Transpose']
      - Transpose: ['p_layers_2_weight::T10'] -> ['GemmTransposePattern--p_layers_2_weight::T10']
      + Reshape: ['p_layers_2_weight::T10', 'init7_s2_1_32'] -> ['GemmTransposePattern--p_layers_2_weight::T10']
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_2_weight::T10', node=Reshape
    [GraphBuilder-HZK.set_type] GemmTransposePattern--p_layers_2_weight::T10:1
    [GraphBuilder-HZK.set_type] GemmTransposePattern--p_layers_2_weight::T10:1
    [GraphBuilderPatternOptimization-HZK.apply_match] MatchResult: TransposeEqualReshapePattern replaces ['Transpose'] applied.
    [GraphBuilderPatternOptimization-HZK.optimize] - add ['Reshape']
    [GraphBuilderPatternOptimization-HZK.optimize] done MatchResult: TransposeEqualReshapePattern replaces ['Transpose']: -1 +1 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C1F1 - done with 1 applied patterns
    [GraphBuilderPatternOptimization-HZK.optimize] done all: -1 +1 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C1F1 - remove_duplicated_shape
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C1F1 - remove_duplicated_shape done -1 +1 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C1F1 - remove_identity
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 5
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 5 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 5 nodes in 7.448999895132147e-05 seconds
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C1F1 - remove_identity done -1 +1 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C1F1 - remove_unused
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C1F1 - remove_unused done -1 +1 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=5C1F1 - next
    [GraphBuilderPatternOptimization-HZK.optimize] iteration 6: 5 nodes, priority=3
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0 - matching_step
    [PatternOptimization.enumerate_matches] start BatchNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start BatchNormalizationTrainingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastLayerNormalizationCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastCastBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start CastOpCastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ClipClipPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatEmptyPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConcatReshapePattern with main_opset=18 and min_opset=1
    [ConcatReshapePattern.match] NONE - line: 1095:yobx.xoptim.patterns.onnx_reshape, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start ConcatTwiceUnaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConstantToInitializerPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ConvBiasNullPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start PadConvPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start DropoutPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandBroadcastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ExpandUnsqueezeExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GathersSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GatherShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start GeluPattern with main_opset=18 and min_opset=20
    [PatternOptimization.enumerate_matches] start IdentityPattern with main_opset=18 and min_opset=1
    [IdentityPattern.match] NONE - line: 664:yobx.xoptim.patterns.onnx_any, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [IdentityPattern.match] NONE - line: 650:yobx.xoptim.patterns.onnx_any, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start LayerNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LayerNormalizationScalePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start LeakyReluPattern with main_opset=18 and min_opset=6
    [PatternOptimization.enumerate_matches] start MaxReluPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulMulMulScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulUnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotNotPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start NotWherePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceArgTopKPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReduceSumNormalizePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapePattern with main_opset=18 and min_opset=1
    [ReshapePattern.match] NONE - line: 42:yobx.xoptim.patterns.onnx_reshape, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start ReshapeMatMulReshapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start Reshape2Of3Pattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapeBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeSqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MatMulAddPattern with main_opset=18 and min_opset=1
    [MatMulAddPattern.match] NONE - line: 130:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--2, inputs=x,GemmTransposePattern--p_layers_0_weight::T10,layers.0.bias
    [MatMulAddPattern.match] NONE - line: 127:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--23, inputs=relu,GemmTransposePattern--p_layers_2_weight::T10,layers.2.bias
    [PatternOptimization.enumerate_matches] start GemmTransposePattern with main_opset=18 and min_opset=1
    [GemmTransposePattern.match] NONE - line: 405:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--2, inputs=x,GemmTransposePattern--p_layers_0_weight::T10,layers.0.bias
    [GemmTransposePattern.match] NONE - line: 405:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--23, inputs=relu,GemmTransposePattern--p_layers_2_weight::T10,layers.2.bias
    [PatternOptimization.enumerate_matches] start MatMulReshape2Of3Pattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start MulMulMatMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedReshapeIsSqueezePattern with main_opset=18 and min_opset=1
    [ShapeBasedReshapeIsSqueezePattern.match] NONE - line: 1719:yobx.xoptim.patterns.onnx_reshape, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start ShapeBasedStaticExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedConcatExpandPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedEditDistanceReshapePattern with main_opset=18 and min_opset=1
    [ShapeBasedEditDistanceReshapePattern.match] NONE - line: 1568:yobx.xoptim.patterns.onnx_reshape, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start ShapeBasedIdentityPattern with main_opset=18 and min_opset=1
    [ShapeBasedIdentityPattern.match] NONE - line: 895:yobx.xoptim.patterns.onnx_any, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandBroadcastMatMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandCastWhereSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedExpandSwapPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedMatMulToMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapedBasedReshapePattern with main_opset=18 and min_opset=1
    [ShapedBasedReshapePattern.match] NONE - line: 121:yobx.xoptim.patterns.onnx_reshape, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start ShapeBasedSameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeBasedShapeShapeAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ShapeTransposePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeShapePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start ReshapeReshapePattern with main_opset=18 and min_opset=1
    [ReshapeReshapePattern.match] NONE - line: 352:yobx.xoptim.patterns.onnx_reshape, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start RotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SameChildrenFromInputPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SequenceConstructAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SplitToSequenceSequenceAtPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SliceSlicePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SlicesSplitPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SoftmaxCrossEntropyLossCastPattern with main_opset=18 and min_opset=14
    [PatternOptimization.enumerate_matches] start SplitConcatPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeBinaryUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start StaticConcatReshapePattern with main_opset=18 and min_opset=1
    [StaticConcatReshapePattern.match] NONE - line: 1286:yobx.xoptim.patterns.onnx_reshape, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start Sub1MulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapExpandReshapePattern with main_opset=18 and min_opset=1
    [SwapExpandReshapePattern.match] NONE - line: 1724:yobx.xoptim.patterns.onnx_expand, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start SwapExpandUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapRangeAddScalarPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwapUnaryPattern with main_opset=18 and min_opset=1
    [SwapUnaryPattern.match] NONE - line: 998:yobx.xoptim.patterns.onnx_any, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [SwapUnaryPattern.match] NONE - line: 998:yobx.xoptim.patterns.onnx_any, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start SwapUnsqueezeTransposePattern with main_opset=18 and min_opset=1
    [SwapUnsqueezeTransposePattern.match] NONE - line: 715:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [PatternOptimization.enumerate_matches] start SwitchOrderBinaryPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start SwitchReshapeActivationPattern with main_opset=18 and min_opset=1
    [SwitchReshapeActivationPattern.match] NONE - line: 1601:yobx.xoptim.patterns.onnx_matmul, op_type=Relu, name=, inputs=linear
    [PatternOptimization.enumerate_matches] start TransposeEqualReshapePattern with main_opset=18 and min_opset=1
    [TransposeEqualReshapePattern.match] NONE - line: 493:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [PatternOptimization.enumerate_matches] start TransposeGatherPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeMatMulPattern with main_opset=18 and min_opset=1
    [TransposeMatMulPattern.match] NONE - line: 1231:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--2, inputs=x,GemmTransposePattern--p_layers_0_weight::T10,layers.0.bias
    [TransposeMatMulPattern.match] NONE - line: 1193:yobx.xoptim.patterns.onnx_matmul, op_type=Gemm, name=GemmTransposePattern--MatMulAddPattern--23, inputs=relu,GemmTransposePattern--p_layers_2_weight::T10,layers.2.bias
    [PatternOptimization.enumerate_matches] start TransposeReshapeMatMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start TransposeReshapeTransposePattern with main_opset=18 and min_opset=1
    [TransposeReshapeTransposePattern.match] NONE - line: 245:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [PatternOptimization.enumerate_matches] start TransposeTransposePattern with main_opset=18 and min_opset=1
    [TransposeTransposePattern.match] NONE - line: 99:yobx.xoptim.patterns.onnx_transpose, op_type=Transpose, name=GemmTransposePattern--MatMulAddPattern--, inputs=p_layers_0_weight::T10
    [PatternOptimization.enumerate_matches] start UnsqueezeEqualPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start UnsqueezeOrSqueezeReshapePattern with main_opset=18 and min_opset=1
    [UnsqueezeOrSqueezeReshapePattern.match] NONE - line: 1953:yobx.xoptim.patterns.onnx_reshape, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start UnsqueezeReshapePattern with main_opset=18 and min_opset=1
    [UnsqueezeReshapePattern.match] NONE - line: 1826:yobx.xoptim.patterns.onnx_reshape, op_type=Reshape, name=TransposeEqualReshapePattern--B--GemmTransposePattern--MatMulAddPattern--22, inputs=p_layers_2_weight::T10,init7_s2_1_32
    [PatternOptimization.enumerate_matches] start UnsqueezeUnsqueezePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start WhereAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RotaryConcatPartPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionAttentionGQAPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCausalMaskMulAddPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionCosSinCachePattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start FunctionHalfRotaryEmbeddingPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start RMSNormalizationMulPattern with main_opset=18 and min_opset=1
    [PatternOptimization.enumerate_matches] start AttentionGQAPattern with main_opset=18 and min_opset=1
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0 - matching_step done 0
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0F0 - apply_step with 0 matches
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0F0 - done with 0 applied patterns
    [GraphBuilderPatternOptimization-HZK.optimize] done all: -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0F0 - remove_duplicated_shape
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0F0 - remove_duplicated_shape done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0F0 - remove_identity
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 5
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 5 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 5 nodes in 7.171799916250166e-05 seconds
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0F0 - remove_identity done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0F0 - remove_unused
    [GraphBuilderPatternOptimization-HZK.optimize] it=6C0F0 - remove_unused done -0 +0 nodes
    [GraphBuilderPatternOptimization-HZK.optimize] stops current_priority_index=4, priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-HZK.optimize] done after 7 iterations with 5 nodes in 0.031
        STAT apply_GemmTransposePattern +4 -2 #it=1 maxmatch=1 i=2 - time=0.0007612690005771583
        STAT apply_MatMulAddPattern +2 -4 #it=1 maxmatch=1 i=2 - time=0.00048362800043832976
        STAT apply_TransposeEqualReshapePattern +1 -1 #it=1 maxmatch=0 i=1 - time=0.0007176830004027579
        STAT build_graph_for_pattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.0004357029993116157
        STAT check_pattern_00 +0 -0 #it=1 maxmatch=0 i=0 - time=3.6254001315683126e-05
        STAT check_pattern_A10 +0 -0 #it=3 maxmatch=0 i=0 - time=1.0322999514755793e-05
        STAT check_pattern_A20 +0 -0 #it=7 maxmatch=0 i=0 - time=0.0003473429987934651
        STAT check_pattern_BD0 +0 -0 #it=7 maxmatch=0 i=0 - time=0.00019082799917669035
        STAT check_pattern_BI0 +0 -0 #it=7 maxmatch=0 i=0 - time=0.0002353290001337882
        STAT check_pattern_BUS0 +0 -0 #it=7 maxmatch=0 i=0 - time=0.00021879599808016792
        STAT insert_and_remove_nodes +0 -0 #it=0 maxmatch=0 i=0 - time=0.0008077709990175208
        STAT iteration_0 +0 -0 #it=1 maxmatch=0 i=0 - time=0.007589798999106279
        STAT iteration_1 +0 -0 #it=1 maxmatch=0 i=0 - time=0.0032557079994148808
        STAT iteration_2 +0 -0 #it=1 maxmatch=0 i=0 - time=0.0028835880002588965
        STAT iteration_3 +0 -0 #it=1 maxmatch=0 i=0 - time=0.003781236000577337
        STAT iteration_4 +0 -0 #it=1 maxmatch=0 i=0 - time=0.0035442369990050793
        STAT iteration_5 +0 -0 #it=1 maxmatch=0 i=0 - time=0.005196611999053857
        STAT match_AttentionGQAPattern +0 -0 #it=5 maxmatch=2 i=0 - time=5.568799861066509e-05
        STAT match_BatchNormalizationPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00017343999570584856
        STAT match_BatchNormalizationTrainingPattern +0 -0 #it=7 maxmatch=0 i=0 - time=9.634900197852403e-05
        STAT match_CastCastBinaryPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.0002646369994181441
        STAT match_CastCastPattern +0 -0 #it=7 maxmatch=0 i=0 - time=7.522199666709639e-05
        STAT match_CastLayerNormalizationCastPattern +0 -0 #it=6 maxmatch=0 i=0 - time=9.127400335273705e-05
        STAT match_CastOpCastPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00017085199942812324
        STAT match_CastPattern +0 -0 #it=7 maxmatch=0 i=0 - time=8.846499986248091e-05
        STAT match_ClipClipPattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.616999937454239e-05
        STAT match_ConcatEmptyPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00011256600009801332
        STAT match_ConcatGatherPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.0001572820019646315
        STAT match_ConcatReshapePattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00016381899695261382
        STAT match_ConcatTwiceUnaryPattern +0 -0 #it=6 maxmatch=0 i=0 - time=9.638599840400275e-05
        STAT match_ConstantToInitializerPattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.96350013843039e-05
        STAT match_ConvBiasNullPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00010525299876462668
        STAT match_DropoutPattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.917199945950415e-05
        STAT match_ExpandBroadcastPattern +0 -0 #it=6 maxmatch=0 i=0 - time=6.812100036768243e-05
        STAT match_ExpandPattern +0 -0 #it=7 maxmatch=0 i=0 - time=7.669800106668845e-05
        STAT match_ExpandSwapPattern +0 -0 #it=6 maxmatch=0 i=0 - time=6.981700062169693e-05
        STAT match_ExpandUnsqueezeExpandPattern +0 -0 #it=7 maxmatch=0 i=0 - time=8.307499956572428e-05
        STAT match_FunctionAttentionGQAPattern +0 -0 #it=7 maxmatch=2 i=0 - time=8.878799962985795e-05
        STAT match_FunctionAttentionPattern +0 -0 #it=7 maxmatch=2 i=0 - time=9.730300189403351e-05
        STAT match_FunctionCausalMaskMulAddPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00014713200107507873
        STAT match_FunctionCausalMaskPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.167500189098064e-05
        STAT match_FunctionCosSinCachePattern +0 -0 #it=6 maxmatch=2 i=0 - time=6.464599937316962e-05
        STAT match_FunctionHalfRotaryEmbeddingPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.87210046837572e-05
        STAT match_GatherConcatPattern +0 -0 #it=7 maxmatch=0 i=0 - time=8.567100121581461e-05
        STAT match_GatherGatherPattern +0 -0 #it=7 maxmatch=0 i=0 - time=7.472800098184962e-05
        STAT match_GatherShapePattern +0 -0 #it=7 maxmatch=0 i=0 - time=9.04869993973989e-05
        STAT match_GathersSplitPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.0001039930011756951
        STAT match_GeluPattern +0 -0 #it=7 maxmatch=0 i=0 - time=4.152799920120742e-05
        STAT match_GemmTransposePattern +0 -0 #it=6 maxmatch=2 i=2 - time=0.00029289400117704645
        STAT match_IdentityPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.001335340002697194
        STAT match_LayerNormalizationPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00034554099875094835
        STAT match_LayerNormalizationScalePattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.96249987615738e-05
        STAT match_LeakyReluPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.0019383749986445764
        STAT match_MatMulAddPattern +0 -0 #it=4 maxmatch=2 i=2 - time=0.0003592249995563179
        STAT match_MatMulReshape2Of3Pattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00021005799862905405
        STAT match_MaxReluPattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.222699787234887e-05
        STAT match_MulMulMatMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001294049980060663
        STAT match_MulMulMulScalarPattern +0 -0 #it=6 maxmatch=0 i=0 - time=8.247299956565257e-05
        STAT match_MulUnsqueezeUnsqueezePattern +0 -0 #it=7 maxmatch=0 i=0 - time=9.31370050238911e-05
        STAT match_NotNotPattern +0 -0 #it=6 maxmatch=0 i=0 - time=6.961500184843317e-05
        STAT match_NotWherePattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.704100062255748e-05
        STAT match_PadConvPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00010131400085811038
        STAT match_RMSNormalizationMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=5.714199869544245e-05
        STAT match_RMSNormalizationPattern +0 -0 #it=6 maxmatch=2 i=0 - time=6.541000220749993e-05
        STAT match_ReduceArgTopKPattern +0 -0 #it=6 maxmatch=0 i=0 - time=8.149599852913525e-05
        STAT match_ReduceReshapePattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.57529978727689e-05
        STAT match_ReduceSumNormalizePattern +0 -0 #it=6 maxmatch=0 i=0 - time=6.823299918323755e-05
        STAT match_Reshape2Of3Pattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00016651599798933603
        STAT match_ReshapeMatMulReshapePattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00015805700240889564
        STAT match_ReshapePattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00014535900118062273
        STAT match_ReshapeReshapeBinaryPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00013535499965655617
        STAT match_ReshapeReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00011307799832138699
        STAT match_ReshapeSqueezePattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00015484200230275746
        STAT match_RotaryConcatPartPattern +0 -0 #it=6 maxmatch=2 i=0 - time=8.747199899517e-05
        STAT match_RotaryEmbeddingPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.307399937417358e-05
        STAT match_SameChildrenFromInputPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00017158399714389816
        STAT match_SameChildrenPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001642240004002815
        STAT match_SequenceConstructAtPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001010409996524686
        STAT match_ShapeBasedConcatExpandPattern +0 -0 #it=6 maxmatch=2 i=0 - time=9.155199950328097e-05
        STAT match_ShapeBasedEditDistanceReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0002261379995616153
        STAT match_ShapeBasedExpandBroadcastMatMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001372440001432551
        STAT match_ShapeBasedExpandBroadcastPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00017017600112012587
        STAT match_ShapeBasedExpandCastWhereSwapPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.119300062186085e-05
        STAT match_ShapeBasedExpandSwapPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001491349994466873
        STAT match_ShapeBasedIdentityPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00014842499695078004
        STAT match_ShapeBasedMatMulToMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001343710009678034
        STAT match_ShapeBasedReshapeIsSqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00013551299707614817
        STAT match_ShapeBasedSameChildrenPattern +0 -0 #it=7 maxmatch=2 i=0 - time=9.616700117476285e-05
        STAT match_ShapeBasedShapeShapeAddPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00034551700082374737
        STAT match_ShapeBasedStaticExpandPattern +0 -0 #it=7 maxmatch=2 i=0 - time=7.818700032657944e-05
        STAT match_ShapeTransposePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00010533099703025073
        STAT match_ShapedBasedReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00011660700147331227
        STAT match_SliceSlicePattern +0 -0 #it=6 maxmatch=2 i=0 - time=6.93929996486986e-05
        STAT match_SlicesSplitPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.817500045348424e-05
        STAT match_SoftmaxCrossEntropyLossCastPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.003780764996918151
        STAT match_SplitConcatPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.665799785172567e-05
        STAT match_SplitToSequenceSequenceAtPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.357100002991501e-05
        STAT match_SqueezeAddPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00022622400138061494
        STAT match_SqueezeBinaryUnsqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00012146500012022443
        STAT match_SqueezeUnsqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=9.456400221097283e-05
        STAT match_StaticConcatReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00011117800022475421
        STAT match_Sub1MulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.400999857054558e-05
        STAT match_SwapExpandReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001039099988702219
        STAT match_SwapExpandUnsqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=8.115099990391172e-05
        STAT match_SwapRangeAddScalarPattern +0 -0 #it=6 maxmatch=2 i=0 - time=6.92570010869531e-05
        STAT match_SwapUnaryPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00019374699877516832
        STAT match_SwapUnsqueezeTransposePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00012748300468956586
        STAT match_SwitchOrderBinaryPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00012998799866181798
        STAT match_SwitchReshapeActivationPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0002002080000238493
        STAT match_TransposeEqualReshapePattern +0 -0 #it=6 maxmatch=2 i=1 - time=0.00022431000252254307
        STAT match_TransposeGatherPattern +0 -0 #it=7 maxmatch=2 i=0 - time=8.113400326692499e-05
        STAT match_TransposeMatMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0004581320008583134
        STAT match_TransposeReshapeMatMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00020900299750792328
        STAT match_TransposeReshapeTransposePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001346769968222361
        STAT match_TransposeTransposePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001887279995571589
        STAT match_UnsqueezeEqualPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00011108800026704557
        STAT match_UnsqueezeOrSqueezeReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001494170010118978
        STAT match_UnsqueezeReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001407349973305827
        STAT match_UnsqueezeShapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00010298799861629959
        STAT match_UnsqueezeUnsqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=8.945399713411462e-05
        STAT match_WhereAddPattern +0 -0 #it=6 maxmatch=2 i=0 - time=9.181299901683815e-05
        STAT remove_duplicated_shape +0 -0 #it=7 maxmatch=0 i=0 - time=4.170499960309826e-05
        STAT remove_identity_nodes +0 -0 #it=7 maxmatch=0 i=0 - time=0.002346073999433429
        STAT remove_unused +0 -0 #it=7 maxmatch=0 i=0 - time=0.0014730059992871247
    --MODEL: 5 nodes, 1 inputs, 1 outputs, 5 initializers--
             INPUT:   1 x 1t
         INPUT-SEQ:   1 x Falset
            OUTPUT:   1 x 1t
        OUTPUT-SEQ:   1 x Falset
              INIT:   4 x 1t
              INIT:   1 x 7t
              NODE:   2 x Gemm
              NODE:   1 x Relu
              NODE:   1 x Reshape
              NODE:   1 x Transpose
    --MODEL: 5 nodes, 1 inputs, 1 outputs, 5 initializers--DETAILED--
         INPUT:   1 x 1t[3x10]
        OUTPUT:   1 x 1t[3x1]
          INIT:   1 x 1t[10x32]
          INIT:   1 x 1t[1]
          INIT:   1 x 1t[32]
          INIT:   1 x 1t[32x1]
          INIT:   1 x 7t[2]
          NODE:   1 x Gemm -SIG- 1t[3x10], 1t[32x10], 1t[32]
          NODE:   1 x Gemm -SIG- 1t[3x32], 1t[1x32], 1t[1]
          NODE:   1 x Relu -SIG- 1t[3x32]
          NODE:   1 x Reshape -SIG- 1t[32x1], 7t[2]
          NODE:   1 x Transpose -SIG- 1t[10x32]-perm=1;0
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 5
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 5 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 5 nodes in 0.0001199590005853679 seconds
    [GraphBuilder-HZK.constant_folding] -- starts with 7 constants and 5 nodes.
    [GraphBuilder-HZK.constant_folding] cst:: . :: _onx_matmul_x
    [GraphBuilder-HZK.constant_folding] cst:: . :: linear
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: p_layers_2_weight::T10
    [GraphBuilder-HZK.constant_folding] cst:: . :: _onx_matmul_relu
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: init7_s2_1_32
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: layers.2.bias
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: GemmTransposePattern--p_layers_0_weight::T10
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: GemmTransposePattern--p_layers_2_weight::T10
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: layers.0.bias
    [GraphBuilder-HZK.constant_folding] cst:: . :: relu
    [GraphBuilder-HZK.constant_folding] cst:: . :: output_0
    [GraphBuilder-HZK.constant_folding] cst:: . :: x
    [GraphBuilder-HZK.constant_folding] cst:: 1 :: p_layers_0_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: p_layers_0_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: p_layers_2_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: layers.0.bias
    [GraphBuilder-HZK.constant_folding] initializer: layers.2.bias
    [GraphBuilder-HZK.constant_folding] from: Transpose(GemmTransposePattern--p_layers_0_weight::T10)
    [GraphBuilder-HZK.set_type] GemmTransposePattern--p_layers_0_weight::T10:1
    [GraphBuilder-HZK.make_initializer] GemmTransposePattern--p_layers_0_weight::T10[1:(32, 10)]
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_0_weight::T10', node=None
    [GraphBuilder-HZK.constant_folding] fold_constant:Transpose:GemmTransposePattern--p_layers_0_weight::T10[float32:(32, 10)]:from:p_layers_0_weight::T10
    [GraphBuilder-HZK.constant_folding] from: Reshape(GemmTransposePattern--p_layers_2_weight::T10)
    [GraphBuilder-HZK.set_type] GemmTransposePattern--p_layers_2_weight::T10:1
    [GraphBuilder-HZK.make_initializer] GemmTransposePattern--p_layers_2_weight::T10[1:(1, 32)]
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_2_weight::T10', node=None
    [GraphBuilder-HZK.constant_folding] fold_constant:Reshape:GemmTransposePattern--p_layers_2_weight::T10[float32:(1, 32)]:from:init7_s2_1_32,p_layers_2_weight::T10
    [GraphBuilder-HZK.constant_folding] initializer: init7_s2_1_32
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_0_weight::T10', node=None
    [GraphBuilder-HZK.update_node_constant] new constant 'GemmTransposePattern--p_layers_2_weight::T10', node=None
    [GraphBuilder-HZK.constant_folding] ends with 7 constants and 3 nodes in 0.0008546009994461201 seconds
    [GraphBuilder-HZK.remove_unused] remove_initializer 1:0/7:p_layers_0_weight::T10
    [GraphBuilder-HZK.remove_unused] remove_initializer 2:1/7:p_layers_2_weight::T10
    [GraphBuilder-HZK.remove_unused] remove_initializer 3:4/7:init7_s2_1_32:int64[(2,)]
    [GraphBuilder-HZK.remove_identity_nodes] -- starts with 3
    [GraphBuilder-HZK.remove_identity_nodes] found 0 replacements
    [GraphBuilder-HZK.remove_identity_nodes] kept 3 nodes
    [GraphBuilder-HZK.remove_identity_nodes] ends with 3 nodes in 4.702099977293983e-05 seconds
    [OrderOptimization.optimize] ALGO-2
    [OrderOptimization.shape_order] -- starts with 3 nodes, 4 initializers
    [OrderOptimization.shape_order] done after in 7.732199992460664e-05s with changed=0 scale=0
    [GraphBuilder-HZK.optimize] done with 3 nodes in 0.040
        STAT apply_GemmTransposePattern +4 -2 #it=1 maxmatch=1 i=2 - time=0.0007612690005771583
        STAT apply_MatMulAddPattern +2 -4 #it=1 maxmatch=1 i=2 - time=0.00048362800043832976
        STAT apply_TransposeEqualReshapePattern +1 -1 #it=1 maxmatch=0 i=1 - time=0.0007176830004027579
        STAT apply_constant_folding__Reshape +0 -0 #it=1 maxmatch=0 i=0 - time=0.0
        STAT apply_constant_folding__Transpose +0 -0 #it=1 maxmatch=0 i=0 - time=0.0
        STAT apply_constant_folding_new_inits +0 -0 #it=1 maxmatch=0 i=0 - time=0.0
        STAT build_graph_for_pattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.0004357029993116157
        STAT check_A-dynamic_dimension_naming +0 -0 #it=0 maxmatch=0 i=0 - time=3.6968000131309964e-05
        STAT check_A-opt-sub +0 -0 #it=0 maxmatch=0 i=0 - time=4.382699989946559e-05
        STAT check_constant_folding-2 +0 -0 #it=0 maxmatch=0 i=0 - time=3.7450001400429755e-05
        STAT check_constant_folding-7 +0 -0 #it=0 maxmatch=0 i=0 - time=4.125999839743599e-05
        STAT check_order-12 +0 -0 #it=0 maxmatch=0 i=0 - time=2.689600114536006e-05
        STAT check_orderA +0 -0 #it=0 maxmatch=0 i=0 - time=2.8677999580395408e-05
        STAT check_orderL +0 -0 #it=0 maxmatch=0 i=0 - time=2.278400097566191e-05
        STAT check_pattern_00 +0 -0 #it=1 maxmatch=0 i=0 - time=3.6254001315683126e-05
        STAT check_pattern_A10 +0 -0 #it=3 maxmatch=0 i=0 - time=1.0322999514755793e-05
        STAT check_pattern_A20 +0 -0 #it=7 maxmatch=0 i=0 - time=0.0003473429987934651
        STAT check_pattern_BD0 +0 -0 #it=7 maxmatch=0 i=0 - time=0.00019082799917669035
        STAT check_pattern_BI0 +0 -0 #it=7 maxmatch=0 i=0 - time=0.0002353290001337882
        STAT check_pattern_BUS0 +0 -0 #it=7 maxmatch=0 i=0 - time=0.00021879599808016792
        STAT check_patterns-4 +0 -0 #it=0 maxmatch=0 i=0 - time=6.747699990228284e-05
        STAT check_remove_duplicated_initializer-9 +0 -0 #it=0 maxmatch=0 i=0 - time=3.181400097673759e-05
        STAT check_remove_identity-0 +0 -0 #it=0 maxmatch=0 i=0 - time=4.520199945545755e-05
        STAT check_remove_identity-10 +0 -0 #it=0 maxmatch=0 i=0 - time=3.111600017291494e-05
        STAT check_remove_identity-6 +0 -0 #it=0 maxmatch=0 i=0 - time=4.705299943452701e-05
        STAT check_remove_unused-1 +0 -0 #it=0 maxmatch=0 i=0 - time=0.00012458400124160107
        STAT check_remove_unused-11 +0 -0 #it=0 maxmatch=0 i=0 - time=3.115700019407086e-05
        STAT check_remove_unused-3 +0 -0 #it=0 maxmatch=0 i=0 - time=3.264199949626345e-05
        STAT check_remove_unused-5 +0 -0 #it=0 maxmatch=0 i=0 - time=4.501900002651382e-05
        STAT check_remove_unused-8 +0 -0 #it=0 maxmatch=0 i=0 - time=3.2934000046225265e-05
        STAT constant_folding +0 -2 #it=0 maxmatch=0 i=0 - time=0.0013655330003530253
        STAT dynamic_dimension_naming +0 -0 #it=0 maxmatch=0 i=0 - time=4.869700023846235e-05
        STAT insert_and_remove_nodes +0 -0 #it=0 maxmatch=0 i=0 - time=0.0008077709990175208
        STAT iteration_0 +0 -0 #it=1 maxmatch=0 i=0 - time=0.007589798999106279
        STAT iteration_1 +0 -0 #it=1 maxmatch=0 i=0 - time=0.0032557079994148808
        STAT iteration_2 +0 -0 #it=1 maxmatch=0 i=0 - time=0.0028835880002588965
        STAT iteration_3 +0 -0 #it=1 maxmatch=0 i=0 - time=0.003781236000577337
        STAT iteration_4 +0 -0 #it=1 maxmatch=0 i=0 - time=0.0035442369990050793
        STAT iteration_5 +0 -0 #it=1 maxmatch=0 i=0 - time=0.005196611999053857
        STAT match_AttentionGQAPattern +0 -0 #it=5 maxmatch=2 i=0 - time=5.568799861066509e-05
        STAT match_BatchNormalizationPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00017343999570584856
        STAT match_BatchNormalizationTrainingPattern +0 -0 #it=7 maxmatch=0 i=0 - time=9.634900197852403e-05
        STAT match_CastCastBinaryPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.0002646369994181441
        STAT match_CastCastPattern +0 -0 #it=7 maxmatch=0 i=0 - time=7.522199666709639e-05
        STAT match_CastLayerNormalizationCastPattern +0 -0 #it=6 maxmatch=0 i=0 - time=9.127400335273705e-05
        STAT match_CastOpCastPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00017085199942812324
        STAT match_CastPattern +0 -0 #it=7 maxmatch=0 i=0 - time=8.846499986248091e-05
        STAT match_ClipClipPattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.616999937454239e-05
        STAT match_ConcatEmptyPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00011256600009801332
        STAT match_ConcatGatherPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.0001572820019646315
        STAT match_ConcatReshapePattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00016381899695261382
        STAT match_ConcatTwiceUnaryPattern +0 -0 #it=6 maxmatch=0 i=0 - time=9.638599840400275e-05
        STAT match_ConstantToInitializerPattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.96350013843039e-05
        STAT match_ConvBiasNullPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00010525299876462668
        STAT match_DropoutPattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.917199945950415e-05
        STAT match_ExpandBroadcastPattern +0 -0 #it=6 maxmatch=0 i=0 - time=6.812100036768243e-05
        STAT match_ExpandPattern +0 -0 #it=7 maxmatch=0 i=0 - time=7.669800106668845e-05
        STAT match_ExpandSwapPattern +0 -0 #it=6 maxmatch=0 i=0 - time=6.981700062169693e-05
        STAT match_ExpandUnsqueezeExpandPattern +0 -0 #it=7 maxmatch=0 i=0 - time=8.307499956572428e-05
        STAT match_FunctionAttentionGQAPattern +0 -0 #it=7 maxmatch=2 i=0 - time=8.878799962985795e-05
        STAT match_FunctionAttentionPattern +0 -0 #it=7 maxmatch=2 i=0 - time=9.730300189403351e-05
        STAT match_FunctionCausalMaskMulAddPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00014713200107507873
        STAT match_FunctionCausalMaskPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.167500189098064e-05
        STAT match_FunctionCosSinCachePattern +0 -0 #it=6 maxmatch=2 i=0 - time=6.464599937316962e-05
        STAT match_FunctionHalfRotaryEmbeddingPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.87210046837572e-05
        STAT match_GatherConcatPattern +0 -0 #it=7 maxmatch=0 i=0 - time=8.567100121581461e-05
        STAT match_GatherGatherPattern +0 -0 #it=7 maxmatch=0 i=0 - time=7.472800098184962e-05
        STAT match_GatherShapePattern +0 -0 #it=7 maxmatch=0 i=0 - time=9.04869993973989e-05
        STAT match_GathersSplitPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.0001039930011756951
        STAT match_GeluPattern +0 -0 #it=7 maxmatch=0 i=0 - time=4.152799920120742e-05
        STAT match_GemmTransposePattern +0 -0 #it=6 maxmatch=2 i=2 - time=0.00029289400117704645
        STAT match_IdentityPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.001335340002697194
        STAT match_LayerNormalizationPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00034554099875094835
        STAT match_LayerNormalizationScalePattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.96249987615738e-05
        STAT match_LeakyReluPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.0019383749986445764
        STAT match_MatMulAddPattern +0 -0 #it=4 maxmatch=2 i=2 - time=0.0003592249995563179
        STAT match_MatMulReshape2Of3Pattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00021005799862905405
        STAT match_MaxReluPattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.222699787234887e-05
        STAT match_MulMulMatMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001294049980060663
        STAT match_MulMulMulScalarPattern +0 -0 #it=6 maxmatch=0 i=0 - time=8.247299956565257e-05
        STAT match_MulUnsqueezeUnsqueezePattern +0 -0 #it=7 maxmatch=0 i=0 - time=9.31370050238911e-05
        STAT match_NotNotPattern +0 -0 #it=6 maxmatch=0 i=0 - time=6.961500184843317e-05
        STAT match_NotWherePattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.704100062255748e-05
        STAT match_PadConvPattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00010131400085811038
        STAT match_RMSNormalizationMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=5.714199869544245e-05
        STAT match_RMSNormalizationPattern +0 -0 #it=6 maxmatch=2 i=0 - time=6.541000220749993e-05
        STAT match_ReduceArgTopKPattern +0 -0 #it=6 maxmatch=0 i=0 - time=8.149599852913525e-05
        STAT match_ReduceReshapePattern +0 -0 #it=6 maxmatch=0 i=0 - time=7.57529978727689e-05
        STAT match_ReduceSumNormalizePattern +0 -0 #it=6 maxmatch=0 i=0 - time=6.823299918323755e-05
        STAT match_Reshape2Of3Pattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00016651599798933603
        STAT match_ReshapeMatMulReshapePattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00015805700240889564
        STAT match_ReshapePattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00014535900118062273
        STAT match_ReshapeReshapeBinaryPattern +0 -0 #it=6 maxmatch=0 i=0 - time=0.00013535499965655617
        STAT match_ReshapeReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00011307799832138699
        STAT match_ReshapeSqueezePattern +0 -0 #it=7 maxmatch=0 i=0 - time=0.00015484200230275746
        STAT match_RotaryConcatPartPattern +0 -0 #it=6 maxmatch=2 i=0 - time=8.747199899517e-05
        STAT match_RotaryEmbeddingPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.307399937417358e-05
        STAT match_SameChildrenFromInputPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00017158399714389816
        STAT match_SameChildrenPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001642240004002815
        STAT match_SequenceConstructAtPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001010409996524686
        STAT match_ShapeBasedConcatExpandPattern +0 -0 #it=6 maxmatch=2 i=0 - time=9.155199950328097e-05
        STAT match_ShapeBasedEditDistanceReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0002261379995616153
        STAT match_ShapeBasedExpandBroadcastMatMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001372440001432551
        STAT match_ShapeBasedExpandBroadcastPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00017017600112012587
        STAT match_ShapeBasedExpandCastWhereSwapPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.119300062186085e-05
        STAT match_ShapeBasedExpandSwapPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001491349994466873
        STAT match_ShapeBasedIdentityPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00014842499695078004
        STAT match_ShapeBasedMatMulToMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0001343710009678034
        STAT match_ShapeBasedReshapeIsSqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00013551299707614817
        STAT match_ShapeBasedSameChildrenPattern +0 -0 #it=7 maxmatch=2 i=0 - time=9.616700117476285e-05
        STAT match_ShapeBasedShapeShapeAddPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00034551700082374737
        STAT match_ShapeBasedStaticExpandPattern +0 -0 #it=7 maxmatch=2 i=0 - time=7.818700032657944e-05
        STAT match_ShapeTransposePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00010533099703025073
        STAT match_ShapedBasedReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00011660700147331227
        STAT match_SliceSlicePattern +0 -0 #it=6 maxmatch=2 i=0 - time=6.93929996486986e-05
        STAT match_SlicesSplitPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.817500045348424e-05
        STAT match_SoftmaxCrossEntropyLossCastPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.003780764996918151
        STAT match_SplitConcatPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.665799785172567e-05
        STAT match_SplitToSequenceSequenceAtPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.357100002991501e-05
        STAT match_SqueezeAddPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00022622400138061494
        STAT match_SqueezeBinaryUnsqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00012146500012022443
        STAT match_SqueezeUnsqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=9.456400221097283e-05
        STAT match_StaticConcatReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00011117800022475421
        STAT match_Sub1MulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=7.400999857054558e-05
        STAT match_SwapExpandReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001039099988702219
        STAT match_SwapExpandUnsqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=8.115099990391172e-05
        STAT match_SwapRangeAddScalarPattern +0 -0 #it=6 maxmatch=2 i=0 - time=6.92570010869531e-05
        STAT match_SwapUnaryPattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00019374699877516832
        STAT match_SwapUnsqueezeTransposePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00012748300468956586
        STAT match_SwitchOrderBinaryPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00012998799866181798
        STAT match_SwitchReshapeActivationPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0002002080000238493
        STAT match_TransposeEqualReshapePattern +0 -0 #it=6 maxmatch=2 i=1 - time=0.00022431000252254307
        STAT match_TransposeGatherPattern +0 -0 #it=7 maxmatch=2 i=0 - time=8.113400326692499e-05
        STAT match_TransposeMatMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.0004581320008583134
        STAT match_TransposeReshapeMatMulPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00020900299750792328
        STAT match_TransposeReshapeTransposePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001346769968222361
        STAT match_TransposeTransposePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001887279995571589
        STAT match_UnsqueezeEqualPattern +0 -0 #it=6 maxmatch=2 i=0 - time=0.00011108800026704557
        STAT match_UnsqueezeOrSqueezeReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001494170010118978
        STAT match_UnsqueezeReshapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.0001407349973305827
        STAT match_UnsqueezeShapePattern +0 -0 #it=7 maxmatch=2 i=0 - time=0.00010298799861629959
        STAT match_UnsqueezeUnsqueezePattern +0 -0 #it=7 maxmatch=2 i=0 - time=8.945399713411462e-05
        STAT match_WhereAddPattern +0 -0 #it=6 maxmatch=2 i=0 - time=9.181299901683815e-05
        STAT order +0 -0 #it=0 maxmatch=0 i=0 - time=0.00014085099974181503
        STAT patterns +0 -0 #it=0 maxmatch=0 i=0 - time=0.03454632200009655
        STAT remove_duplicated_initializer +0 -0 #it=0 maxmatch=0 i=0 - time=0.00012584800060722046
        STAT remove_duplicated_shape +0 -0 #it=7 maxmatch=0 i=0 - time=4.170499960309826e-05
        STAT remove_identity +0 -0 #it=0 maxmatch=0 i=0 - time=0.0008705810014362214
        STAT remove_identity_nodes +0 -0 #it=7 maxmatch=0 i=0 - time=0.002346073999433429
        STAT remove_unused +0 -0 #it=7 maxmatch=0 i=0 - time=0.0027075210000475636
        STAT shape_order +0 -0 #it=0 maxmatch=0 i=0 - time=8.730100125831086e-05
    --MODEL: 3 nodes, 1 inputs, 1 outputs, 4 initializers--
             INPUT:   1 x 1t
         INPUT-SEQ:   1 x Falset
            OUTPUT:   1 x 1t
        OUTPUT-SEQ:   1 x Falset
              INIT:   4 x 1t
              NODE:   2 x Gemm
              NODE:   1 x Relu
    --MODEL: 3 nodes, 1 inputs, 1 outputs, 4 initializers--DETAILED--
         INPUT:   1 x 1t[3x10]
        OUTPUT:   1 x 1t[3x1]
          INIT:   1 x 1t[1]
          INIT:   1 x 1t[1x32]
          INIT:   1 x 1t[32]
          INIT:   1 x 1t[32x10]
          NODE:   1 x Gemm -SIG- 1t[3x10], 1t[32x10], 1t[32]
          NODE:   1 x Gemm -SIG- 1t[3x32], 1t[1x32], 1t[1]
          NODE:   1 x Relu -SIG- 1t[3x32]
    [GraphBuilder-HZK.to_onnx] make_model 4 inits 0 params
    [GraphBuilder-HZK.time_evaluation_constants_] 0
    [GraphBuilder-HZK._build_initializers] start with 4 initializers, large_model=False, external_threshold=1024
    [GraphBuilder-HZK._build_initializers] switch low/high order
    [GraphBuilder-HZK._build_initializers] TensorProto-layers.0.bias:1[(32,)]
    [GraphBuilder-HZK._build_initializers] TensorProto-layers.2.bias:1[(1,)]
    [GraphBuilder-HZK._build_initializers] <ndarray>-GemmTransposePattern--p_layers_0_weight::T10:float32[(32, 10)]
    [GraphBuilder-HZK._build_initializers] <ndarray>-GemmTransposePattern--p_layers_2_weight::T10:float32[(1, 32)]
    [GraphBuilder-HZK._build_initializers] done in 2.6580000849207863e-06s with 4 initializers, 0 large initializers
    [GraphBuilder-HZK._add_shape_information] dynamic shapes replacements={}

Select the pattern to use#

Class OptimizationOptions is used to enable or disable patterns.

<<<

import onnx
from yobx.xbuilder import GraphBuilder, OptimizationOptions
from yobx.doc import demo_mlp_model

onx = demo_mlp_model("temp_doc_mlp.onnx")

gr = GraphBuilder(
    onx,
    infer_shapes_options=True,
    optimization_options=OptimizationOptions(
        patterns="TransposeTranspose,TransposeMatMul", verbose=1
    ),
)
opt_onx = gr.to_onnx(optimize=True)

>>>

    [GraphBuilder-WZO.optimize] start with 5 nodes
    [GraphBuilder-WZO.optimize] #patterns=2
    [GraphBuilderPatternOptimization-WZO.optimize] start with 5 nodes, 4 initializers, 2 patterns, priorities=[0, 1], max_iter=20
    [GraphBuilderPatternOptimization-WZO.optimize] iteration 0: 5 nodes, priority=0
    [GraphBuilderPatternOptimization-WZO.optimize] increase priority to 1
    [GraphBuilderPatternOptimization-WZO.optimize] iteration 1: 5 nodes, priority=1
    [GraphBuilderPatternOptimization-WZO.optimize] stops current_priority_index=2, priorities=[0, 1]
    [GraphBuilderPatternOptimization-WZO.optimize] done after 2 iterations with 5 nodes in 0.001
    [OrderOptimization.optimize] ALGO-2
    [OrderOptimization.shape_order] -- starts with 5 nodes, 4 initializers
    [OrderOptimization.shape_order] done after in 5.754100129706785e-05s with changed=0 scale=0
    [GraphBuilder-WZO.optimize] done with 5 nodes in 0.004

There exists some predefined lists of patterns:

  • default: includes all patterns using only standard onnx patterns.

  • onnxruntime: patterns specific to onnxruntime, the final model may be executed by onnxruntime and possibly only onnxruntime as it may introduce patterns from Supported Operators and Data Types.

<<<

import onnx
from yobx.xbuilder import GraphBuilder, OptimizationOptions
from yobx.doc import demo_mlp_model

onx = demo_mlp_model("temp_doc_mlp.onnx")

gr = GraphBuilder(
    onx,
    infer_shapes_options=True,
    optimization_options=OptimizationOptions(patterns="default+onnxruntime", verbose=1),
)
opt_onx = gr.to_onnx(optimize=True)

>>>

    [GraphBuilder-UUQ.optimize] start with 5 nodes
    [GraphBuilder-UUQ.optimize] #patterns=144
    [GraphBuilderPatternOptimization-UUQ.optimize] start with 5 nodes, 4 initializers, 144 patterns, priorities=[0, 1, 2, 3], max_iter=40
    [GraphBuilderPatternOptimization-UUQ.optimize] same children={'SameChildrenFromInputPattern', 'SameChildrenPattern'}
    [GraphBuilderPatternOptimization-UUQ.optimize] iteration 0: 5 nodes, priority=0
    [GraphBuilderPatternOptimization-UUQ.optimize] increase priority to 1
    [GraphBuilderPatternOptimization-UUQ.optimize] iteration 1: 5 nodes, priority=1
    [GraphBuilderPatternOptimization-UUQ.optimize] increase priority to 2
    [GraphBuilderPatternOptimization-UUQ.optimize] iteration 2: 5 nodes, priority=2
    [GraphBuilderPatternOptimization-UUQ.optimize] increase priority to 3
    [GraphBuilderPatternOptimization-UUQ.optimize] iteration 3: 5 nodes, priority=3
    [GraphBuilderPatternOptimization-UUQ.optimize] applies 2 matches, 2*MatMulAddPattern - time=0.002 | max_time=IdentityPattern:0.000
    [GraphBuilderPatternOptimization-UUQ.optimize] iteration 4: 3 nodes, priority=3
    [GraphBuilderPatternOptimization-UUQ.optimize] applies 2 matches, 2*GemmTransposePattern - time=0.002 | max_time=QuickGeluPattern:0.000
    [GraphBuilderPatternOptimization-UUQ.optimize] iteration 5: 5 nodes, priority=3
    [GraphBuilderPatternOptimization-UUQ.optimize] applies 1 matches, [0]=MatchResult: TransposeEqualReshapePattern replaces ['Transpose'] - time=0.006 | max_time=GeluErfPattern:0.004
    [GraphBuilderPatternOptimization-UUQ.optimize] iteration 6: 5 nodes, priority=3
    [GraphBuilderPatternOptimization-UUQ.optimize] stops current_priority_index=4, priorities=[0, 1, 2, 3]
    [GraphBuilderPatternOptimization-UUQ.optimize] done after 7 iterations with 5 nodes in 0.053
    [OrderOptimization.optimize] ALGO-2
    [OrderOptimization.shape_order] -- starts with 3 nodes, 4 initializers
    [OrderOptimization.shape_order] done after in 7.642499986104667e-05s with changed=0 scale=0
    [GraphBuilder-UUQ.optimize] done with 3 nodes in 0.061

Statistics#

This can be used to see when a pattern is applied and how long it takes.

<<<

import pandas
import onnx
from yobx.xbuilder import GraphBuilder, OptimizationOptions
from yobx.doc import demo_mlp_model

onx = demo_mlp_model("temp_doc_mlp.onnx")

gr = GraphBuilder(
    onx,
    infer_shapes_options=True,
    optimization_options=OptimizationOptions(patterns="default"),
)
stat = gr.optimize()

print(pandas.DataFrame(stat))

>>>

                                  pattern  removed  added   time_in  value  iteration  ...  match_index  n_nodes  exit_point changed  scale  algo
    0            dynamic_dimension_naming      0.0    0.0  0.000024    NaN        NaN  ...          NaN      NaN         NaN     NaN    NaN   NaN
    1    check_A-dynamic_dimension_naming      NaN    NaN  0.000023    NaN        NaN  ...          NaN      NaN         NaN     NaN    NaN   NaN
    2                     check_A-opt-sub      NaN    NaN  0.000013    NaN        NaN  ...          NaN      NaN         NaN     NaN    NaN   NaN
    3                     remove_identity      0.0    0.0  0.000104    NaN        NaN  ...          NaN      NaN         NaN     NaN    NaN   NaN
    4             check_remove_identity-0      NaN    NaN  0.000015    NaN        NaN  ...          NaN      NaN         NaN     NaN    NaN   NaN
    ..                                ...      ...    ...       ...    ...        ...  ...          ...      ...         ...     ...    ...   ...
    780                      check_orderL      NaN    NaN  0.000009    NaN        NaN  ...          NaN      NaN         NaN     NaN    NaN   NaN
    781                       shape_order      NaN    NaN  0.000031    NaN        NaN  ...          NaN      NaN         NaN     0.0    0.0   NaN
    782                             order      NaN    NaN       NaN    NaN        NaN  ...          NaN      NaN         NaN     NaN    NaN     2
    783                    check_order-12      NaN    NaN  0.000009    NaN        NaN  ...          NaN      NaN         NaN     NaN    NaN   NaN
    784                      optimization      2.0    0.0  0.017051    NaN        NaN  ...          NaN      NaN         NaN     NaN    NaN   NaN
    
    [785 rows x 13 columns]

It can be aggregated:

<<<

import pandas
import onnx
from yobx.xbuilder import GraphBuilder, OptimizationOptions
from yobx.doc import demo_mlp_model

onx = demo_mlp_model("temp_doc_mlp.onnx")

gr = GraphBuilder(
    onx,
    infer_shapes_options=True,
    optimization_options=OptimizationOptions(patterns="default"),
)
stat = gr.optimize()

df = pandas.DataFrame(stat)
for c in df.columns:
    if "time" not in c and "pattern" not in c and "exit_point" not in c:
        df[c] = df[c].fillna(0).astype(int)
aggs = {
    "time_in": "sum",
    "added": "sum",
    "removed": "sum",
    "iteration": "max",
    "match_index": "max",
    "instances": "sum",
}
print(df.groupby("pattern").agg(aggs))

>>>

                                         time_in  added  removed  iteration  match_index  instances
    pattern                                                                                        
    apply_GemmTransposePattern          0.000343      4        2          4            1          2
    apply_MatMulAddPattern              0.000204      2        4          3            1          2
    apply_TransposeEqualReshapePattern  0.000239      1        1          5            0          1
    apply_constant_folding__Reshape     0.000000      0        0          0            0          0
    apply_constant_folding__Transpose   0.000000      0        0          0            0          0
    ...                                      ...    ...      ...        ...          ...        ...
    remove_duplicated_shape             0.000025      0        0          6            0          0
    remove_identity                     0.000759      0        0          0            0          0
    remove_identity_nodes               0.001635      0        0          6            0          0
    remove_unused                       0.002778      0        0          6            0          0
    shape_order                         0.000113      0        0          0            0          0
    
    [152 rows x 6 columns]

Matching Algorithm#

EasyPatternOptimization implements a bidirectional subgraph-matching algorithm that avoids a full enumeration of all possible node assignments. Rather than writing a custom match method, the user only has to declare the subgraph to look for (match_pattern) and the replacement (apply_pattern) using the same builder API that is used to build ONNX graphs.

Pattern definition#

Both match_pattern and apply_pattern are written as regular Python functions that call g.op.<OpType>(...) to create nodes. Each positional argument becomes a symbolic input to the subgraph. The function returns the name(s) of the symbolic output(s).

class TransposeTransposePattern(EasyPatternOptimization):

    def match_pattern(self, g: "GraphBuilder", x):
        t1 = g.op.Transpose(x)
        return g.op.Transpose(t1)

    def apply_pattern(self, g: "GraphBuilder", x):
        return x   # two transposes cancel each other

At build time the framework converts each function into a small GraphBuilderPatternOptimization that stores the nodes in topological order. The last node of the match pattern is used as the anchor: the matching loop only fires when a graph node has the same op_type as that anchor.

Bidirectional matching#

Given a candidate graph node with the same type as the anchor, the algorithm expands the match iteratively with a stack-based approach:

marked  = {anchor_pattern_key: (graph_node, anchor_pattern_node)}
stacked = [anchor_pattern_key]

while stacked:
    (graph_node, pattern_node) = pop(stacked)

    # --- backward pass ---
    # Walk up the predecessors of pattern_node.
    # For each predecessor in the pattern, find the corresponding
    # predecessor in the graph. Fail if types or arities differ.
    backward_match(graph_node, pattern_node)

    # --- forward pass ---
    # Walk down the successors of pattern_node.
    # For each successor in the pattern, find the corresponding
    # successor in the graph. Fail if types or arities differ.
    forward_match(graph_node, pattern_node)

    # New matched pairs are pushed onto stacked.

The two sub-routines are implemented in _match_backward and _match_forward.

Ambiguity detection#

A dictionary pair_results_names maps every pattern result name to the graph result name it has been paired with. Before recording a new pair the algorithm checks that neither name already points to a different name (ambiguity). An ambiguity means the same pattern result would have to correspond to two different graph results simultaneously, which would be inconsistent; the match is rejected in that case.

Validation#

After all pattern nodes have been matched the algorithm performs two additional checks:

  • validate_attribute_mapping – verifies that the attributes of the matched graph nodes are consistent with those declared in the pattern (e.g. same axis value).

  • validate_mapping – an optional hook for subclasses to add arbitrary semantic checks (e.g. verify that a constant operand has a specific numerical value).

Only when both validations succeed does the method return a MatchResult that schedules the matched nodes for replacement.

Overlap prevention#

The outer loop (see Optimization Algorithm above) maintains a marked set of all node identifiers that have already been claimed by an earlier MatchResult. A candidate match is discarded if any of its nodes appears in that set, so no two rewrites ever touch the same node during the same pass.

Worked examples#

The two classes cover the same use-cases but at different levels of abstraction. The examples below both implement a Not + Not → Identity fusion so that the difference is easy to compare.

PatternOptimization (manual match / apply)

The developer writes the matching logic by hand, navigating the graph with the helpers provided by GraphBuilderPatternOptimization.

import inspect
from typing import List, Optional
from onnx import NodeProto
from yobx.xoptim import PatternOptimization, MatchResult


class NotNotPattern(PatternOptimization):
    """Fuses ``Not(Not(x))`` into ``Identity(x)``."""

    def match(
        self,
        g: "GraphBuilderPatternOptimization",
        node: NodeProto,
        matched: List[MatchResult],
    ) -> Optional[MatchResult]:
        # Only consider Not nodes.
        if node.op_type != "Not" or node.domain != "":
            return self.none()

        # Walk one step backward: the producer of node's input must also be Not.
        not_before = g.node_before(node.input[0])
        if not_before is None or not_before.op_type != "Not" or not_before.domain != "":
            return self.none(node, inspect.currentframe().f_lineno)

        # Return both nodes as the rewrite target.
        return MatchResult(self, [not_before, node], self.apply, insert_at=node)

    def apply(
        self,
        g: "GraphBuilder",
        not_before: NodeProto,
        not_after: NodeProto,
    ) -> List[NodeProto]:
        pre_nodes = []
        # Keep the first Not if its output is consumed elsewhere.
        if g.is_used_more_than_once(not_before.output[0]):
            pre_nodes.append(not_before)
        return [
            *pre_nodes,
            g.make_node(
                "Identity",
                [not_before.input[0]],
                [not_after.output[0]],
                name=f"{self.__class__.__name__}--{not_after.name}",
            ),
        ]

EasyPatternOptimization (declarative match_pattern / apply_pattern)

The developer declares the subgraph to look for and the replacement as builder calls. The framework takes care of matching and result renaming automatically.

from typing import List, Optional
from onnx import NodeProto
from yobx.xoptim import EasyPatternOptimization, MatchResult


class NotNotEasyPattern(EasyPatternOptimization):
    """Fuses ``Not(Not(x))`` into ``Identity(x)`` using the easy API."""

    def match_pattern(self, g: "GraphBuilder", x):
        t = g.op.Not(x)      # first Not
        return g.op.Not(t)   # second Not  <-- anchor node

    def apply_pattern(self, g: "GraphBuilder", x):
        return g.op.Identity(x)

Key differences#

Aspect

PatternOptimization

EasyPatternOptimization

Matching logic

Written by hand in match(). The developer calls graph-navigation helpers such as node_before, next_nodes, get_attribute, …

Declared as a Python function match_pattern() using g.op.* calls. The bidirectional BFS is run automatically by the framework.

Replacement logic

Written by hand in apply(). The developer calls g.make_node and explicitly manages which nodes are kept or removed.

Declared as a Python function apply_pattern() using g.op.* calls. The framework renames results and assembles the replacement nodes automatically.

Flexibility

Full control: can inspect any attribute, handle optional inputs, cope with multi-output rewrites, or make graph-wide checks.

More constrained: the subgraph must have a fixed topology with no branching within the pattern. Attribute checks require overriding validate_mapping or validate_attribute_mapping.

Typical use-case

Complex rewrites (e.g. Attention fusion) where the matching involves many conditional checks that are hard to express as a fixed topology.

Simple structural fusions (e.g. double-Not, LeakyRelu decomposition, Gelu decomposition) where the topology is fixed and self-describing.

Shape inference#

The optimizers require to know the shapes to ensure they can rewrite some nodes and avoid producing a model which does not return the same results. If it is missing, some patterns cannot match for sure and they will not match.

This information can be built by running shape inference on the onnx models. That’s what is done in the previous examples. However, the best case is when this information comes from torch.

Function to_onnx converts a torch model into ONNX. While doing so, it stores the shape information coming from torch. There is no need to run shape inference on the onnx model it generates before optimizing it.

Available Patterns and API#

All patterns are documented in Available Patterns.

When writing a pattern, walking along the graph or checking the shape is very common. Class GraphBuilderPatternOptimization provides the following methods.

Opsets#

Patterns must rewrite using the nodes of the opset defined in the model.

Shapes, Types#

  • has_type: tells if a result type is known

  • get_type: returns a result type, fails if not known

  • has_shape: tells if a result shape is known

  • get_shape: returns a result shape, fails if not known

  • has_rank: tells if a result rank is known

  • get_rank: returns a result rank, fails if not known

  • try_infer_type: returns a type if it can be guessed

  • try_infer_shape: returns a shape if it can be guessed

  • has_device: tells if a result device is known

  • get_device: returns a result device, fails if not known

Constants#

  • is_constant: tells if a node is a constant (it may be a constant, an initializer or any value built on other constants)

  • is_constant_scalar: checks a constant is a scalar and compares its value to a number

  • get_computed_constant: returns the constant, computing it if it is a constant built from other constants

  • get_attribute: returns an attribute of a node

Graph#

Nodes#

  • make_node: creates a node without adding it to the graph

  • make_node_check_opset: creates a node without adding it to the graph, deals with some constraints related to opset version

Debugging Optimization with Environment Variables#

Several environment variables can be set to help debug the pattern optimizer.

  • LOG_PATTERN_OPTIMIZE: sets the verbosity level for all patterns. Setting it to 10 produces the most detailed output. Example:

    LOG_PATTERN_OPTIMIZE=10 python my_script.py

  • PATTERN: increases the verbosity to 10 for one or more specific patterns (comma-separated class names or class names with the Pattern suffix removed). This is useful to focus on a single pattern without flooding the output with information from all the others. Example:

    PATTERN=ReshapeReshapePattern python my_script.py

  • <ClassName>: setting an environment variable whose name matches the class name of a pattern (e.g. ReshapeReshapePattern=10) sets the verbosity for that individual pattern. This is equivalent to using PATTERN but more explicit.

  • DROPPATTERN: comma-separated list of pattern class names to exclude from the optimizer. Useful to bisect which pattern is causing a wrong result or an unexpected error. Example:

    DROPPATTERN=ReshapeReshapePattern,CastPattern python my_script.py

  • DUMPPATTERNS: when set to a folder path, the optimizer writes the matched nodes and their replacements to that folder for every successful pattern application. Useful for inspecting what the optimizer is actually doing. Example:

    DUMPPATTERNS=/tmp/dump_patterns python my_script.py

  • PATTERNNOREMOVE: when set to a result name, the optimizer raises an exception if an optimization step removes that name from the graph. Useful to track down which pattern is eliminating a particular node or result. Example:

    PATTERNNOREMOVE=output_0 python my_script.py

  • PATTERNSTEP: when set to 1, True, or true, the optimizer runs one optimization step at a time, which can help narrow down which step introduces a problem. Example:

    PATTERNSTEP=1 python my_script.py