Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2023 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14745204 kB
MemAvailable 30335424 kB
Buffers 0 kB
Cached 16913192 kB
SwapCached 4408 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-07 07:37:20
DTS_END                         2023-01-07 07:37:20.4620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59464
TRN_ID                          213434
ATT_ID                          650
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9113.71 23498 1093645
customer order: creation 168.68 517 20241
customer order: refuse 14.84 469 1781
order to supplier: creation 44.04 1464 5285
order to supplier: removal 3.48 176629 418
invoice (draft): creation 44.43 3186 5331
invoice (draft): removal 52.28 1119 6273
invoice accept: apply 40.20 27192 4824
invoice accept: cancel 47.02 1353 5642
customer reserve: creation 3841.89 251 461027
customer reserve: removal 4568.39 106 548207
realization accept: apply 81.86 298 9823
realization accept: cancel 79.33 175 9519
payment from customer: creation 58.21 184 6985
payment from customer: removal 5.63 138 676
payment to supplier: creation 47.76 214 5731
payment to supplier: removal 3.44 138 413
service: total inventory turnovers 3.98 4141 477
service: total monetary turnovers 7.35 470 882
service: aggregate perf. data 0.88 17296 105
service: refresh index statistics 0.04 234628 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-01-2023 07:38:25 to 07-01-2023 07:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6599
TEST_TIME 2 6692
TEST_TIME 3 6010
TEST_TIME 4 6189
TEST_TIME 5 5803
TEST_TIME 6 5743
TEST_TIME 7 5700
TEST_TIME 8 5808
TEST_TIME 9 5972
TEST_TIME 10 6055
TEST_TIME 11 6274
TEST_TIME 12 6178
TEST_TIME 13 6287
TEST_TIME 14 6518
TEST_TIME 15 6882
TEST_TIME 16 7268
TEST_TIME 17 7225
TEST_TIME 18 7187
TEST_TIME 19 7347
TEST_TIME 20 7514
TEST_TIME 21 7438
TEST_TIME 22 7363
TEST_TIME 23 7654
TEST_TIME 24 8029
TEST_TIME 25 7832
TEST_TIME 26 8038
TEST_TIME 27 8848
TEST_TIME 28 9069
TEST_TIME 29 9037
TEST_TIME 30 9554
TEST_TIME 31 9397
TEST_TIME 32 9562
TEST_TIME 33 9775
TEST_TIME 34 10044
TEST_TIME 35 10261
TEST_TIME 36 10424
TEST_TIME 37 10526
TEST_TIME 38 10519
TEST_TIME 39 10742
TEST_TIME 40 10771
TEST_TIME 41 10828
TEST_TIME 42 10756
TEST_TIME 43 10753
TEST_TIME 44 10613
TEST_TIME 45 10422
TEST_TIME 46 10342
TEST_TIME 47 10163
TEST_TIME 48 10172
TEST_TIME 49 10037
TEST_TIME 50 9928
TEST_TIME 51 9939
TEST_TIME 52 9867
TEST_TIME 53 9873
TEST_TIME 54 9845
TEST_TIME 55 9833
TEST_TIME 56 9883
TEST_TIME 57 9791
TEST_TIME 58 9697
TEST_TIME 59 9729
TEST_TIME 60 9725
TEST_TIME 61 9646
TEST_TIME 62 9606
TEST_TIME 63 9521
TEST_TIME 64 9531
TEST_TIME 65 9530
TEST_TIME 66 9505
TEST_TIME 67 9543
TEST_TIME 68 9520
TEST_TIME 69 9470
TEST_TIME 70 9509
TEST_TIME 71 9562
TEST_TIME 72 9523
TEST_TIME 73 9566
TEST_TIME 74 9611
TEST_TIME 75 9734
TEST_TIME 76 9669
TEST_TIME 77 9718
TEST_TIME 78 9701
TEST_TIME 79 9593
TEST_TIME 80 9607
TEST_TIME 81 9536
TEST_TIME 82 9479
TEST_TIME 83 9508
TEST_TIME 84 9438
TEST_TIME 85 9505
TEST_TIME 86 9477
TEST_TIME 87 9432
TEST_TIME 88 9369
TEST_TIME 89 9281
TEST_TIME 90 9264
TEST_TIME 91 9267
TEST_TIME 92 9294
TEST_TIME 93 9266
TEST_TIME 94 9232
TEST_TIME 95 9221
TEST_TIME 96 9285
TEST_TIME 97 9240
TEST_TIME 98 9193
TEST_TIME 99 9325
TEST_TIME 100 9398
TEST_TIME 101 9386
TEST_TIME 102 9372
TEST_TIME 103 9386
TEST_TIME 104 9455
TEST_TIME 105 9402
TEST_TIME 106 9338
TEST_TIME 107 9305
TEST_TIME 108 9284
TEST_TIME 109 9257
TEST_TIME 110 9214
TEST_TIME 111 9186
TEST_TIME 112 9203
TEST_TIME 113 9186
TEST_TIME 114 9154
TEST_TIME 115 9160
TEST_TIME 116 9176
TEST_TIME 117 9153
TEST_TIME 118 9154
TEST_TIME 119 9148
TEST_TIME 120 9144

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-01-2023 07:38:29 to 07-01-2023 07:38:32.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1518084 1518084 0 0.00 0 29702 13 0 0
doc_list_biud 1518075 1518075 0 0.00 0 35492 45 0 0
sp_add_invoice_to_stock 5019 4824 195 3.88 60 224868 27192 0 195
sp_cancel_adding_invoice 5642 5642 0 0.00 7 87962 1354 0 0
sp_cancel_client_order 1781 1781 0 0.00 5 6849 470 0 0
sp_cancel_customer_reserve 548207 548207 0 0.00 1 29798 107 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 4 4377 138 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 3489 139 0 0
sp_cancel_supplier_invoice 6286 6273 13 0.20 3 22670 1119 0 13
sp_cancel_supplier_order 418 418 0 0.00 19 1325482 176630 0 0
sp_cancel_write_off 9519 9519 0 0.00 2 19324 176 0 0
sp_client_order 20241 20241 0 0.00 5 25536 518 0 0
sp_customer_reserve 461027 461027 0 0.00 1 67869 251 0 0
sp_fill_shopping_cart 30843 30843 0 0.00 1 14103 83 0 0
sp_fill_shopping_cart_clo_res 460982 460982 0 0.00 0 16320 21 0 0
sp_get_clo_for_invoice 4766 4766 0 0.00 0 19962 293 0 0
sp_kill_cost_storno 16250 16250 0 0.00 0 12144 80 0 0
sp_kill_qstorno_ret_qs2qd 1112624 1112624 0 0.00 0 26796 47 0 0
sp_kill_qty_storno 586428 586428 0 0.00 0 35491 108 0 0
sp_lock_selected_doc 20280 20280 0 0.00 0 7846 8 0 0
sp_make_cost_storno 27304 27304 0 0.00 0 29698 81 0 0
sp_multiply_rows_for_pdistr 27303 27303 0 0.00 0 9826 40 0 0
sp_multiply_rows_for_qdistr 20241 20241 0 0.00 0 13289 196 0 0
sp_pay_from_customer 6985 6985 0 0.00 6 9624 184 0 0
sp_pay_to_supplier 5731 5731 0 0.00 6 12416 215 0 0
sp_payment_common 12715 12715 0 0.00 2 6147 45 0 0
sp_qd_handle_on_cancel_clo 1781 1781 0 0.00 0 6835 376 0 0
sp_qd_handle_on_invoice_upd_sts 10407 10407 0 0.00 0 35491 563 0 0
sp_qd_handle_on_reserve_upd_sts 9823 9823 0 0.00 0 19125 72 0 0
sp_reserve_write_off 9823 9823 0 0.00 8 29723 298 0 0
sp_supplier_invoice 5331 5331 0 0.00 9 74307 3186 0 0
sp_supplier_order 5285 5285 0 0.00 8 29656 1465 0 0
srv_aggregate_perf_data 105 105 0 0.00 2576 197623 17297 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 689 121063 30864 0 0
srv_find_qd_qs_mism 1045369 1045369 0 0.00 0 27910 10 0 0
srv_make_invnt_saldo 477 477 0 0.00 49 89949 4141 0 0
srv_make_money_saldo 882 882 0 0.00 1 28519 470 0 0
srv_recalc_idx_stat 5 5 0 0.00 58409 385850 234629 0 0
t$perf-abend:sp_add_invoice_to_stock 195 195 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4828 4828 0 0.00 1 15548 870 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 2 6975 553 0 0
t$perf-norm:sp_cancel_client_order 1781 1781 0 0.00 0 2897 27 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 7325 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 3002 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 903 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 3231 39 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 106925 7755 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 2203 22 0 0
t$perf-norm:sp_client_order 20241 20241 0 0.00 0 11844 39 0 0
t$perf-norm:sp_customer_reserve 953 953 0 0.00 0 2895 19 0 0
t$perf-norm:sp_pay_from_customer 6985 6985 0 0.00 0 8427 22 0 0
t$perf-norm:sp_pay_to_supplier 5731 5731 0 0.00 0 8329 22 0 0
t$perf-norm:sp_reserve_write_off 9823 9823 0 0.00 0 14837 28 0 0
t$perf-norm:sp_supplier_invoice 5331 5331 0 0.00 0 3050 31 0 0
t$perf-norm:sp_supplier_order 5285 5285 0 0.00 0 3110 32 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 536 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 407 19 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 873 16 0 0
t$perf-norm:srv_make_money_saldo 882 882 0 0.00 0 3655 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 188 110 0 0
v_add_invoice_to_stock 9908 9518 390 3.93 0 6631 37 0 390
v_all_customers 37080 37080 0 0.00 0 221 4 0 0
v_all_suppliers 29904 29904 0 0.00 0 288 4 0 0
v_all_wares 40470 40470 0 0.00 0 99 4 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 3341 25 0 0
v_cancel_client_order 3562 3562 0 0.00 0 3361 15 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 1840 12 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 2743 18 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 3365 17 0 0
v_cancel_supplier_invoice 810 784 26 3.20 0 1289 30 0 26
v_cancel_supplier_order 836 836 0 0.00 0 664 11 0 0
v_cancel_write_off 1916 1916 0 0.00 0 5965 32 0 0
v_max_id_clo_ord 5281 5281 0 0.00 0 3986 6 0 0
v_max_id_clo_res 953 953 0 0.00 0 3628 23 0 0
v_max_id_ord_sup 5325 5325 0 0.00 0 5788 8 0 0
v_max_non_paid_invoice 1384 1384 0 0.00 0 77 8 0 0
v_max_non_paid_realizn 3604 3604 0 0.00 0 1159 8 0 0
v_min_id_clo_ord 5281 5281 0 0.00 0 1886 8 0 0
v_min_id_clo_res 953 953 0 0.00 0 2324 11 0 0
v_min_id_ord_sup 5325 5325 0 0.00 0 7849 10 0 0
v_min_non_paid_invoice 5730 5730 0 0.00 0 6141 16 0 0
v_min_non_paid_realizn 6985 6985 0 0.00 0 3862 17 0 0
v_our_firm 10152 10152 0 0.00 0 129 5 0 0
v_reserve_write_off 19646 19646 0 0.00 0 5106 14 0 0
x_lock_depdocs_on_canc_invoice 11523 11523 0 0.00 0 20894 129 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 9993 377 0 0
x_make_qstorno_1000_1200 5285 5285 0 0.00 2 29645 1291 0 0
x_make_qstorno_1200_2000 5331 5331 0 0.00 5 71750 2969 0 0
x_make_qstorno_2100_3300 461026 461026 0 0.00 0 67858 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2023 07:38:33 to 07-01-2023 07:38:37.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:24 6 8192000000 8803089408 8816095232 7170896 0.087 5 1 2 0 7275616 68944 35776
05:08:25 16555 8192000000 9705671504 10000719872 784800048 8.742 100 84 168 0 812209520 7368832 20040640
05:09:50 20989 8192000000 9750728800 10053169152 822247408 9.121 100 90 180 0 855833648 11770688 21815552
05:11:19 10080 8192000000 9779445360 10092699648 849583456 9.396 100 84 168 0 888648864 19274144 19791264
05:12:47 15581 8192000000 9845244112 10181021696 904237136 9.940 100 78 155 0 957737136 36493904 17006096
05:14:07 7581 8192000000 9909626640 10255417344 949311696 10.384 100 74 144 0 1022660144 58437088 14911360
05:15:19 2331 8192000000 9968181696 10317033472 991144960 10.793 100 92 182 0 1085258800 75891776 18222064
05:16:25 1542 8192000000 9975394576 10334957568 1016562560 11.039 100 87 166 1 1092943792 60433360 15947872
05:17:30 869 8192000000 10012872272 10377977856 1032640912 11.194 100 89 171 0 1131896352 82191024 17064416
05:18:33 1279 8192000000 10021069728 10396450816 1051254608 11.373 100 89 176 0 1148022368 78619312 18148448
05:19:35 920 8192000000 10048077296 10425454592 1070311840 11.555 100 98 189 1 1169739824 81078720 18349264
05:20:37 1209 8192000000 10036312512 10413662208 1072917264 11.580 100 97 193 0 1159955632 67857168 19181200
05:21:39 1671 8192000000 10069881504 10456576000 1080535344 11.653 100 87 169 1 1195811904 98381696 16894864
05:22:42 1113 8192000000 10082590016 10468978688 1083547808 11.681 100 96 187 1 1203705248 101623696 18533744
05:23:45 1292 8192000000 10056379376 10446409728 1087168256 11.716 100 93 175 1 1185194704 80630480 17395968
05:24:47 2285 8192000000 10044295264 10432999424 1089827840 11.741 100 76 145 3 1166172064 61545824 14798400
05:25:52 1107 8192000000 10035137184 10425180160 1099629264 11.834 100 94 177 1 1162514352 44309152 18575936
05:26:54 1322 8192000000 10078913504 10464989184 1104529680 11.881 100 98 192 2 1200929056 76695664 19703712
05:27:56 2310 8192000000 10082702208 10468769792 1103634000 11.872 100 93 165 5 1195756224 75642880 16479344
05:28:59 3453 8192000000 10057424912 10447482880 1101743616 11.854 100 72 137 3 1173775904 58732800 13299488
05:30:04 2048 8192000000 10052031712 10448142336 1099134256 11.829 100 70 139 1 1175184096 62301600 13748240
05:31:07 1076 8192000000 10062698288 10450501632 1094572400 11.786 100 89 169 1 1187246000 74890352 17783248
05:32:09 2099 8192000000 10052200320 10436165632 1079908864 11.647 100 82 160 0 1171558464 75096064 16553536
05:33:13 1192 8192000000 10031229984 10410037248 1063112912 11.486 100 91 178 3 1153164976 72719936 17332128
05:34:17 1069 8192000000 9986387104 10363392000 1052088416 11.381 100 90 167 1 1110562688 40592624 17881648
05:35:19 1272 8192000000 9960334320 10326355968 1009409808 10.970 98 86 163 5 1077480880 51595952 16475120
05:36:21 5824 8192000000 9983423088 10371698688 1035425824 11.221 99 55 105 1 1106609200 60267712 10915664
05:37:29 4276 8192000000 10018347840 10397949952 1061518624 11.471 100 86 159 3 1134546048 57274080 15753344
05:38:43 17200 8192000000 10052438400 10432868352 1073356272 11.584 100 70 126 0 1164083008 78558768 12167968
05:40:06 5447 8192000000 10048313456 10432741376 1085755312 11.702 100 77 146 1 1173286032 72565744 14964976
05:41:17 4061 8192000000 10108870752 10502496256 1105793952 11.893 100 70 135 0 1218525568 98377792 14353824
05:42:24 17064 8192000000 10139948480 10544742400 1111399856 11.946 100 57 112 0 1258288816 134758208 12130752
05:43:45 16165 8192000000 10146906592 10551713792 1125603984 12.080 100 56 105 0 1269692384 132945168 11143232
05:45:08 6387 8192000000 10204521232 10601738240 1134681600 12.165 100 66 132 1 1313001072 163937440 14382032
05:46:19 960 8192000000 10268062208 10661699584 1151213392 12.321 100 94 180 1 1381837792 209537648 21086752
05:47:24 1106 8192000000 10299153376 10694590464 1162263424 12.424 100 95 182 0 1408568416 227515728 18789264
05:48:30 1510 8192000000 10317469040 10719784960 1173174720 12.526 100 90 165 0 1428857680 237351488 18331472
05:49:35 1791 8192000000 10336235488 10744868864 1172507376 12.520 100 86 158 1 1446529280 255176992 18844912
05:50:41 1568 8192000000 10371405088 10776768512 1193283536 12.714 100 85 168 2 1483187952 270553104 19351312
05:51:47 1003 8192000000 10378362624 10810839040 1197775344 12.756 100 93 181 0 1485892992 267659312 20458336
05:52:53 903 8192000000 10400520128 10821332992 1198994368 12.767 100 96 183 1 1507641392 287305584 21341440
05:53:55 7712 8192000000 10406034976 10844147712 1203156176 12.806 100 51 102 0 1519758512 304011328 12591008
05:55:07 3678 8192000000 10460234032 10879074304 1210520752 12.874 100 71 137 0 1566747952 340144256 16082944
05:56:15 6148 8192000000 10492588976 10943954944 1211310704 12.881 100 60 118 1 1600665728 373332496 16022528
05:57:30 19294 8192000000 10513046848 10953302016 1201353440 12.789 99 44 88 0 1615823072 403062192 11407440
05:58:56 3080 8192000000 10549640352 10968780800 1211237264 12.881 100 86 170 0 1654381408 423006208 20137936
06:00:01 1478 8192000000 10550499232 11026800640 1204451504 12.818 100 89 176 2 1658269728 430976352 22841872
06:01:10 3187 8192000000 10562512816 11012435968 1211476208 12.883 100 73 146 1 1679072016 449460224 18135584
06:02:20 9889 8192000000 10571488096 11032272896 1210643440 12.875 100 51 102 1 1676906400 452910800 13352160
06:03:45 1012 8192000000 10550619776 11033550848 1187408720 12.659 100 59 112 0 1656917888 455486160 14023008
06:04:55 14383 8192000000 10469065584 10924077056 1186402480 12.650 100 43 82 0 1576256480 379221568 10632432
06:06:16 35330 8192000000 10404767360 10873724928 1130634880 12.127 97 31 60 0 1496673904 357910960 8128064
06:07:52 14522 8192000000 10328983184 10769371136 1160509456 12.408 100 42 84 0 1437946608 267084240 10352912
06:09:09 1181 8192000000 10359551872 10823962624 1163066560 12.432 100 87 173 0 1463233648 281097232 19069856
06:10:11 677 8192000000 10274189184 10779815936 1145154544 12.264 100 94 178 1 1373029104 208027408 19847152
06:11:13 1699 8192000000 10302412320 10743214080 1154348272 12.350 100 84 159 2 1408636656 234663648 19624736
06:12:18 6025 8192000000 10300003616 10746249216 1166718832 12.466 100 68 124 2 1409866080 228805344 14341904
06:13:27 27270 8192000000 10266001440 10765074432 1156991440 12.375 100 26 52 0 1384344432 220199248 7153744
06:14:55 41707 8192000000 10223009776 10687942656 1152702576 12.335 100 28 50 2 1309134928 150188000 6244352
06:16:37 80571 8192000000 10121436944 10666758144 1126931952 12.092 99 8 14 1 1251949472 123273984 1743536
06:19:42 107871 8192000000 10061637088 10498174976 1098312960 11.822 96 15 28 1 1118419664 17444080 2662624
06:22:36 11115 8192000000 10091372000 10506256384 1124151312 12.066 99 70 134 2 1183354672 45021328 14182032
06:23:55 93512 8192000000 10063057152 10558369792 1126451824 12.088 99 35 65 2 1206525872 72672720 7401328
06:26:32 8059 8192000000 10098293472 10528686080 1137302848 12.190 100 59 111 2 1187000528 38688192 11009488
06:27:42 16355 8192000000 10099145088 10532478976 1135109232 12.170 100 52 97 1 1197604128 51942864 10552032
06:29:05 12935 8192000000 10084472816 10516054016 1121082464 12.037 99 41 78 1 1196539376 66688144 8768768
06:30:21 16403 8192000000 10110994592 10558033920 1128245424 12.105 99 29 56 0 1198758128 64886288 5626416
06:31:41 783 8192000000 10116192592 10537697280 1138663344 12.203 100 81 160 1 1211465552 55397568 17404640
06:32:49 50697 8192000000 10074069024 10577948672 1121131680 12.038 99 12 22 1 1213131888 89289392 2710816
06:34:47 33882 8192000000 10091521376 10537644032 1116316544 11.992 98 10 20 0 1179123824 60803168 2004112
06:36:23 68607 8192000000 10101097280 10579398656 1129029488 12.112 99 16 32 0 1209069328 76224176 3815664
06:40:09 17574 8192000000 10083696048 10536316928 1140060784 12.216 99 33 52 3 1178952272 33618480 5273008
06:41:28 51807 8192000000 10123547664 10615066624 1132636752 12.146 98 18 36 0 1215705680 79270720 3798208
06:43:33 33332 8192000000 10119844288 10564476928 1118603808 12.014 98 24 48 0 1192662928 68253616 5805504
06:45:08 34633 8192000000 10153377760 10610696192 1142773472 12.242 100 33 62 0 1233047440 83499776 6774192
06:46:45 39119 8192000000 10156613504 10622357504 1112367872 11.955 97 26 52 0 1239654784 121547136 5739776
06:48:25 50336 8192000000 10146998976 10580467712 1132950048 12.149 99 36 70 1 1247590624 107154816 7485760
06:50:16 56316 8192000000 10133151616 10614456320 1107883040 11.912 96 24 48 0 1270592576 156866592 5842944
06:52:19 39300 8192000000 10099161232 10593325056 1140898480 12.224 100 24 48 0 1216997312 71478768 4620064
06:54:03 18070 8192000000 10128396368 10578714624 1121511344 12.041 99 48 92 1 1225340528 94237600 9591584
06:55:27 110907 8192000000 10124627392 10559082496 1106923440 11.903 98 51 102 0 1195878640 78516976 10438224
06:58:19 50865 8192000000 10093756224 10529505280 1129281440 12.115 99 41 77 0 1192048528 55209328 7557760
07:00:11 88946 8192000000 10139299232 10600865792 1123374544 12.059 98 18 36 0 1241194896 114178304 3642048
07:02:41 32688 8192000000 10064572880 10515120128 1125651760 12.080 99 23 46 2 1154598768 24954848 3992160
07:04:35 46624 8192000000 10066021296 10512883712 1092340016 11.765 97 19 34 0 1142131328 46494848 3296464
07:06:27 64856 8192000000 10064705984 10498224128 1115703568 11.986 99 32 51 1 1177331376 55101248 6526560
07:08:32 50551 8192000000 10078714192 10552508416 1107054880 11.905 98 10 20 0 1169887536 60564448 2268208
07:10:45 30054 8192000000 10107003984 10550038528 1134334784 12.162 99 27 54 0 1176416048 36139200 5942064
07:12:22 18405 8192000000 10163891808 10608259072 1149040672 12.300 99 47 92 0 1259437104 100721136 9675296
07:13:42 33442 8192000000 10136937536 10581839872 1143429536 12.248 99 26 50 2 1239285984 90299392 5557056
07:15:17 32701 8192000000 10201154192 10635952128 1142708352 12.241 100 47 92 2 1296404528 143233824 10462352
07:16:53 75606 8192000000 10100793056 10629820416 1117375536 12.002 99 23 46 0 1239529328 117609664 4544128
07:19:23 24077 8192000000 10082208560 10523840512 1127598432 12.099 100 45 88 1 1190692080 53595008 9498640
07:20:54 70177 8192000000 10070985024 10535731200 1125318688 12.077 100 27 54 0 1202231472 71621632 5291152
07:23:18 121062 8192000000 10060417232 10497806336 1134897520 12.168 100 26 51 1 1173184048 32782912 5503616
07:26:20 73880 8192000000 10106744576 10578456576 1134091840 12.160 99 15 30 0 1209103872 71846544 3165488
07:28:45 47390 8192000000 10095975792 10591399936 1120246368 12.029 98 10 20 1 1211598096 88928048 2423680
07:31:01 57145 8192000000 10089023200 10548690944 1126021936 12.084 99 9 18 0 1177884192 50033216 1829040
07:32:58 58741 8192000000 10075794224 10533277696 1116464240 11.994 98 18 36 0 1168282608 47306912 4511456
07:35:10 29864 8192000000 10121578704 10571841536 1121487824 12.041 98 28 55 1 1190582560 62876816 6217920
07:36:42 46306 8192000000 9131896208 9415667712 260627744 3.083 23 9 18 0 315632560 53042176 1962640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-01-2023 07:38:39 to 07-01-2023 07:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 195 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2023 07:38:56 to 07-01-2023 07:38:58.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          213433
MON$OLDEST_ACTIVE               217619
MON$OLDEST_SNAPSHOT             217619
MON$NEXT_TRANSACTION            217619
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2089696
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2886 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2886 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2886 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2886 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:70: 
:71: mscs-test-161.budzdorov.ru    Sat Jan  7 07:37:31 2023
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Sat Jan  7 07:37:38 2023
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:77: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

07.01.23 07:39:00. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-07 07:39:02 No severe PSQL-related problems occured
07.01.23 07:39:03. End of report.