$model->getPrimaryKey() returns wrong value when yii\log\DbTarget is used for loging

[TomasMolnar]

setup

Model table has "id" column set as primary key, autoincrement

What steps will reproduce the problem?

$model = new Model();
$model->name = 'model name';
$model->save();

//this should return the id of newly inserted row to Model table,
//but returns the id of row inserted to Log table instead
echo $model->getPrimaryKey();

What is the expected result?

expected is a newly assigned id according to AutoIncremen PK

What do you get instead?

returns the id of row inserted to Log table instead

Additional info

Q	A
Yii version	2.0.9
PHP version	7.0.5
DB version	MariaDB 5.5
Operating system	Linux apache-data-it11-113 3.14.27-031427-generic #201412170558 SMP Wed Dec 17 11:00:01 UTC 2014 x86_64

config/web.php:

'log' => [
            'traceLevel' => 0,//YII_DEBUG ? 3 : 0,
            'flushInterval' => YII_DEBUG ? 1 : 100,
            'targets' => [
                [
                    'class' => 'yii\log\DbTarget',
                    'exportInterval' => YII_DEBUG ? 1 : 10,
                    'levels' => ['error', 'warning', 'profile' /*, 'info', 'trace', */],
                    'logVars' => [],    
                    'categories' => [
                        'application',
                        'yii\db\*',
                        'yii\web\HttpException:*',  
                    ],
                    'except' => [
                        /*  
                        'yii\web\HttpException:404',
                        'yii\web\HttpException:429',
                        */
                    ],
                    'prefix' => function ($message) {
                        $user = Yii::$app->has('user', true) ? Yii::$app->get('user') : null;
                        $userID = $user ? $user->getId(false) : '-';
                        return json_encode(['userid' => $userID]);
                    },
                ]
            ],
        ]

[cebe]

This is a limitation of PDO: http://php.net/manual/en/pdo.lastinsertid.php not sure how to work around this. One problem could be the exportInterval of 1, which means that the message is written to the database immediately. If you set it to a higher value you can avoid this problem to some extent.

[TomasMolnar]

thanks @cebe for verifying. However I think, that setting a higher exportInterval might cause even more weird behavior as this error would arise randomly when the log stack is flushed to DB. Would it be possible to add some log restriction to core to avoid such setting? Or at least echo some warning that this setting causes problems when the $model->id is used as FK for saving subsequent related models?

[cebe]

to solve the issue completely the best way would be to exclude database queries from the logging of the database target.

[klimov-paul]

Note that databases which use INSERT ... RETURNING such as Oracle and PostgreSQL are not affected by this issue.

[Mak-Di]

What do you think about moving getLastInsertID before Yii::endProfile($token, METHOD); ?
Or use one more instance DB for the DbTarget.

[samdark]

Using another connection makes sense as a workaround. Does moving getLastInsertID solve issue for all possible cases?

[Mak-Di]

Does moving getLastInsertID solve issue for all possible cases?

No, definitely not. It was not a very good suggestion.

[AnatolyRugalev]

Using another connection makes sense as a workaround.

So, this is about target configuration, right? Or may be we can make a workaround inside of DbTarget which will instantiate new connection?

[cebe]

@AnatolyRugalev yes, if you configure $db property of the DB target to not use 'db' application component but a separate DB connection.

[cebe]

I think the only way to solve this would be to document it so users can adjust the configuration to avoid the problem.