Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.02.2023 10: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 15018948 kB
MemAvailable 30405348 kB
Buffers 0 kB
Cached 16655904 kB
SwapCached 3356 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-02-11 10:37:26
DTS_END                         2023-02-11 10:37:26.8450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60818
TRN_ID                          222766
ATT_ID                          606
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: 9015.15 26260 1081818
customer order: creation 170.18 479 20422
customer order: refuse 15.12 385 1814
order to supplier: creation 45.59 1396 5471
order to supplier: removal 3.39 170325 407
invoice (draft): creation 45.01 3118 5401
invoice (draft): removal 51.33 927 6159
invoice accept: apply 41.13 24477 4935
invoice accept: cancel 45.95 1154 5514
customer reserve: creation 3806.28 235 456753
customer reserve: removal 4498.39 101 539807
realization accept: apply 81.76 264 9811
realization accept: cancel 83.10 176 9972
payment from customer: creation 59.58 169 7150
payment from customer: removal 5.63 149 676
payment to supplier: creation 47.18 176 5662
payment to supplier: removal 3.61 91 433
service: total inventory turnovers 3.93 3786 471
service: total monetary turnovers 7.07 330 848
service: aggregate perf. data 0.90 15753 108
service: refresh index statistics 0.03 301700 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-02-2023 10:38:24 to 11-02-2023 10:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5198
TEST_TIME 2 5173
TEST_TIME 3 4957
TEST_TIME 4 5540
TEST_TIME 5 5381
TEST_TIME 6 5344
TEST_TIME 7 5393
TEST_TIME 8 5671
TEST_TIME 9 5715
TEST_TIME 10 5738
TEST_TIME 11 6180
TEST_TIME 12 6262
TEST_TIME 13 6413
TEST_TIME 14 6630
TEST_TIME 15 6546
TEST_TIME 16 6754
TEST_TIME 17 6890
TEST_TIME 18 7207
TEST_TIME 19 7276
TEST_TIME 20 7352
TEST_TIME 21 7491
TEST_TIME 22 7501
TEST_TIME 23 7688
TEST_TIME 24 7787
TEST_TIME 25 7953
TEST_TIME 26 7990
TEST_TIME 27 8391
TEST_TIME 28 8729
TEST_TIME 29 9283
TEST_TIME 30 10036
TEST_TIME 31 10282
TEST_TIME 32 10805
TEST_TIME 33 10662
TEST_TIME 34 10521
TEST_TIME 35 10628
TEST_TIME 36 10552
TEST_TIME 37 10424
TEST_TIME 38 10331
TEST_TIME 39 10238
TEST_TIME 40 10189
TEST_TIME 41 10070
TEST_TIME 42 10062
TEST_TIME 43 9982
TEST_TIME 44 10020
TEST_TIME 45 9925
TEST_TIME 46 9884
TEST_TIME 47 9902
TEST_TIME 48 9848
TEST_TIME 49 9948
TEST_TIME 50 10023
TEST_TIME 51 9991
TEST_TIME 52 9834
TEST_TIME 53 9720
TEST_TIME 54 9668
TEST_TIME 55 9555
TEST_TIME 56 9554
TEST_TIME 57 9488
TEST_TIME 58 9433
TEST_TIME 59 9434
TEST_TIME 60 9442
TEST_TIME 61 9370
TEST_TIME 62 9330
TEST_TIME 63 9262
TEST_TIME 64 9182
TEST_TIME 65 9319
TEST_TIME 66 9250
TEST_TIME 67 9285
TEST_TIME 68 9359
TEST_TIME 69 9346
TEST_TIME 70 9363
TEST_TIME 71 9405
TEST_TIME 72 9409
TEST_TIME 73 9472
TEST_TIME 74 9467
TEST_TIME 75 9498
TEST_TIME 76 9506
TEST_TIME 77 9493
TEST_TIME 78 9492
TEST_TIME 79 9525
TEST_TIME 80 9463
TEST_TIME 81 9416
TEST_TIME 82 9349
TEST_TIME 83 9330
TEST_TIME 84 9313
TEST_TIME 85 9280
TEST_TIME 86 9236
TEST_TIME 87 9263
TEST_TIME 88 9189
TEST_TIME 89 9110
TEST_TIME 90 9135
TEST_TIME 91 9142
TEST_TIME 92 9121
TEST_TIME 93 9098
TEST_TIME 94 9146
TEST_TIME 95 9130
TEST_TIME 96 9163
TEST_TIME 97 9166
TEST_TIME 98 9163
TEST_TIME 99 9170
TEST_TIME 100 9205
TEST_TIME 101 9314
TEST_TIME 102 9293
TEST_TIME 103 9330
TEST_TIME 104 9257
TEST_TIME 105 9228
TEST_TIME 106 9216
TEST_TIME 107 9174
TEST_TIME 108 9103
TEST_TIME 109 9085
TEST_TIME 110 9030
TEST_TIME 111 9047
TEST_TIME 112 9026
TEST_TIME 113 9022
TEST_TIME 114 9008
TEST_TIME 115 9007
TEST_TIME 116 9006
TEST_TIME 117 8993
TEST_TIME 118 8996
TEST_TIME 119 9057
TEST_TIME 120 9039

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-02-2023 10:38:28 to 11-02-2023 10:38:31.

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 1504210 1504210 0 0.00 0 31493 13 0 0
doc_list_biud 1504196 1504196 0 0.00 0 25760 41 0 0
sp_add_invoice_to_stock 5082 4935 147 2.89 86 197852 24478 0 147
sp_cancel_adding_invoice 5514 5514 0 0.00 7 97082 1154 0 0
sp_cancel_client_order 1814 1814 0 0.00 6 16656 385 0 0
sp_cancel_customer_reserve 539807 539807 0 0.00 1 20692 102 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 4 7259 150 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 4 4855 92 0 0
sp_cancel_supplier_invoice 6173 6159 14 0.22 3 15598 928 0 14
sp_cancel_supplier_order 407 407 0 0.00 12 1358216 170326 0 0
sp_cancel_write_off 9972 9972 0 0.00 2 25784 177 0 0
sp_client_order 20422 20422 0 0.00 5 32183 480 0 0
sp_customer_reserve 456753 456753 0 0.00 0 51402 235 0 0
sp_fill_shopping_cart 31282 31282 0 0.00 1 18334 76 0 0
sp_fill_shopping_cart_clo_res 456716 456716 0 0.00 0 13309 19 0 0
sp_get_clo_for_invoice 4888 4888 0 0.00 0 16465 279 0 0
sp_kill_cost_storno 16595 16595 0 0.00 0 7531 76 0 0
sp_kill_qstorno_ret_qs2qd 1096152 1096152 0 0.00 0 25756 44 0 0
sp_kill_qty_storno 578371 578371 0 0.00 0 25757 99 0 0
sp_lock_selected_doc 20536 20536 0 0.00 0 9545 7 0 0
sp_make_cost_storno 27511 27511 0 0.00 0 31479 77 0 0
sp_multiply_rows_for_pdistr 27510 27510 0 0.00 0 7807 36 0 0
sp_multiply_rows_for_qdistr 20422 20422 0 0.00 0 19256 181 0 0
sp_pay_from_customer 7150 7150 0 0.00 5 13452 170 0 0
sp_pay_to_supplier 5662 5662 0 0.00 6 13104 177 0 0
sp_payment_common 12810 12810 0 0.00 2 7343 44 0 0
sp_qd_handle_on_cancel_clo 1814 1814 0 0.00 1 16648 324 0 0
sp_qd_handle_on_invoice_upd_sts 10402 10402 0 0.00 0 8179 451 0 0
sp_qd_handle_on_reserve_upd_sts 9810 9810 0 0.00 0 12365 64 0 0
sp_reserve_write_off 9811 9811 0 0.00 8 12426 265 0 0
sp_supplier_invoice 5401 5401 0 0.00 8 111773 3119 0 0
sp_supplier_order 5471 5471 0 0.00 8 81427 1397 0 0
srv_aggregate_perf_data 108 108 0 0.00 2470 238853 15754 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 744 125362 33609 0 0
srv_find_qd_qs_mism 1034342 1034342 0 0.00 0 28084 10 0 0
srv_make_invnt_saldo 471 471 0 0.00 25 86403 3787 0 0
srv_make_money_saldo 848 848 0 0.00 1 8905 331 0 0
srv_recalc_idx_stat 4 4 0 0.00 191343 469227 301701 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4938 4938 0 0.00 1 12554 770 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 7321 410 0 0
t$perf-norm:sp_cancel_client_order 1814 1814 0 0.00 0 2633 28 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 1098 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 3069 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 412 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 645 24 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 87635 7073 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 2283 18 0 0
t$perf-norm:sp_client_order 20422 20422 0 0.00 0 23825 34 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 5692 19 0 0
t$perf-norm:sp_pay_from_customer 7150 7150 0 0.00 0 5668 19 0 0
t$perf-norm:sp_pay_to_supplier 5662 5662 0 0.00 0 5649 19 0 0
t$perf-norm:sp_reserve_write_off 9812 9812 0 0.00 0 10925 21 0 0
t$perf-norm:sp_supplier_invoice 5401 5401 0 0.00 0 23343 32 0 0
t$perf-norm:sp_supplier_order 5472 5472 0 0.00 0 9257 29 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 508 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 46 3 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 697 20 0 0
t$perf-norm:srv_make_money_saldo 848 848 0 0.00 0 455 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 281 75 0 0
v_add_invoice_to_stock 10054 9760 294 2.92 0 6923 25 0 294
v_all_customers 37482 37482 0 0.00 0 144 3 0 0
v_all_suppliers 30470 30470 0 0.00 0 144 4 0 0
v_all_wares 40836 40836 0 0.00 0 77 3 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1672 15 0 0
v_cancel_client_order 3628 3628 0 0.00 0 1966 10 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 1819 11 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 1718 12 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 1707 11 0 0
v_cancel_supplier_invoice 856 828 28 3.27 0 5777 40 0 28
v_cancel_supplier_order 814 814 0 0.00 0 1477 14 0 0
v_cancel_write_off 1914 1914 0 0.00 0 2846 25 0 0
v_max_id_clo_ord 5469 5469 0 0.00 0 5825 6 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 5733 16 0 0
v_max_id_ord_sup 5392 5392 0 0.00 0 6557 7 0 0
v_max_non_paid_invoice 1285 1285 0 0.00 0 66 7 0 0
v_max_non_paid_realizn 3698 3698 0 0.00 0 1051 6 0 0
v_min_id_clo_ord 5469 5469 0 0.00 0 1683 8 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 2375 12 0 0
v_min_id_ord_sup 5392 5392 0 0.00 0 8971 10 0 0
v_min_non_paid_invoice 5660 5660 0 0.00 0 4745 17 0 0
v_min_non_paid_realizn 7150 7150 0 0.00 0 7335 19 0 0
v_our_firm 10256 10256 0 0.00 0 81 4 0 0
v_reserve_write_off 19610 19610 0 0.00 0 4096 10 0 0
x_lock_depdocs_on_canc_invoice 11259 11259 0 0.00 0 11631 133 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 3489 356 0 0
x_make_qstorno_1000_1200 5471 5471 0 0.00 3 79969 1247 0 0
x_make_qstorno_1200_2000 5401 5401 0 0.00 5 111573 2896 0 0
x_make_qstorno_2100_3300 456751 456751 0 0.00 0 47073 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-02-2023 10:38:32 to 11-02-2023 10:38:36.

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
08:07:30 8 8192000000 8804680688 8818319360 8399088 0.102 3 3 4 0 8609472 123440 86944
08:08:32 31566 8192000000 9740660128 10041081856 814870720 9.047 100 88 177 0 843731408 8037984 20822704
08:10:18 21846 8192000000 9779285648 10089177088 852901024 9.429 100 89 178 0 887468480 13623376 20944080
08:11:46 7175 8192000000 9804732224 10126364672 877494896 9.675 100 82 164 0 918381776 22380000 18506880
08:13:03 7575 8192000000 9873025968 10204508160 925630016 10.152 100 87 174 0 988460624 44054720 18775888
08:14:13 2362 8192000000 9927880336 10271350784 964135872 10.529 100 97 184 0 1046003312 63424656 18442784
08:15:18 2693 8192000000 9967072320 10325434368 996549056 10.845 100 85 167 0 1083524928 70308560 16667312
08:16:23 987 8192000000 9994139792 10356072448 1023786240 11.109 100 90 172 0 1114083984 73337616 16960128
08:17:26 1128 8192000000 10030391984 10402619392 1050468128 11.365 100 96 186 0 1154818080 85925568 18424384
08:18:28 1136 8192000000 10022839904 10397601792 1062527792 11.481 100 95 186 2 1144324528 62148256 19648480
08:19:32 1208 8192000000 10032186384 10411892736 1067623040 11.529 100 83 154 2 1151870960 69266544 14981376
08:20:35 3190 8192000000 10047769264 10439462912 1074063808 11.591 100 69 131 0 1166958368 80202528 12692032
08:21:39 1231 8192000000 10053976224 10435895296 1087181824 11.716 100 92 175 0 1174548320 70241024 17125472
08:22:41 1046 8192000000 10063471984 10452946944 1092564864 11.767 100 95 180 2 1179360880 68964912 17831104
08:23:43 1112 8192000000 10027305376 10417033216 1100130768 11.839 100 71 123 1 1158821216 47328464 11361984
08:24:46 1086 8192000000 10083324112 10473652224 1105047056 11.886 100 90 176 0 1207863648 85339760 17476832
08:25:48 1149 8192000000 10072323856 10462175232 1098895264 11.827 100 95 185 2 1198347984 80048416 19404304
08:26:50 950 8192000000 10050774880 10444177408 1098511136 11.824 100 79 151 0 1173524400 58754720 16258544
08:27:54 1138 8192000000 10040925504 10432503808 1106984752 11.904 100 90 165 1 1162127216 38672976 16469488
08:28:56 1248 8192000000 10053594784 10442203136 1093322032 11.774 100 96 189 1 1178451296 66010544 19118720
08:29:59 1271 8192000000 10045298320 10426568704 1064350880 11.498 99 92 173 1 1167365104 84872352 18141872
08:31:02 899 8192000000 10017448224 10399531008 1055179504 11.410 100 91 161 0 1139331200 67888416 16263280
08:32:04 1392 8192000000 10016877360 10396463104 1044465120 11.308 100 97 183 4 1141837408 78015424 19356864
08:33:07 1116 8192000000 9993104576 10366754816 1024339840 11.114 99 95 175 1 1122727232 79832752 18554640
08:34:09 1742 8192000000 9941544016 10317471744 1009528576 10.971 99 66 124 2 1069518544 46381968 13608000
08:35:12 830 8192000000 9979886528 10351349760 1017923456 11.052 99 81 151 1 1105195200 71161488 16110256
08:36:15 810 8192000000 9985202672 10360037376 1034759424 11.214 100 91 165 1 1112498928 58825696 18913808
08:37:17 977 8192000000 9995000336 10370867200 1036638976 11.232 100 92 173 0 1118942752 64423392 17880384
08:38:24 1031 8192000000 9992770224 10364096512 1043793168 11.301 100 98 185 2 1103161744 42655632 16712944
08:39:35 2071 8192000000 10036361888 10415042560 1068320480 11.536 100 82 158 0 1157475808 72393504 16761824
08:40:41 6207 8192000000 10054848560 10438639616 1075796096 11.607 100 73 137 1 1168432928 77888624 14748208
08:41:53 4057 8192000000 10072765664 10458480640 1093677344 11.778 100 69 132 1 1194840592 87863680 13299568
08:43:01 2107 8192000000 10108480336 10499760128 1102812752 11.864 99 70 136 0 1224974080 108515856 13645472
08:44:09 5398 8192000000 10118572000 10513481728 1111471216 11.946 100 80 149 0 1242176560 112818400 17886944
08:45:18 13824 8192000000 10145373632 10548912128 1130060720 12.122 100 65 122 3 1267482784 124067632 13354432
08:46:42 4566 8192000000 10213805456 10612957184 1144665776 12.259 100 78 151 1 1327432160 165410720 17355664
08:47:51 1224 8192000000 10276468016 10669862912 1164294080 12.443 100 92 180 1 1386135824 201764528 20077216
08:48:56 1013 8192000000 10305085072 10723774464 1177066432 12.563 100 85 160 0 1413620448 217885584 18668432
08:49:59 1000 8192000000 10319849360 10728656896 1180325728 12.593 100 82 155 0 1432524048 234845264 17353056
08:51:04 1880 8192000000 10331883360 10764840960 1183837904 12.626 100 87 174 0 1443758960 238945600 20975456
08:52:09 1111 8192000000 10391538240 10795540480 1199973728 12.776 100 92 180 2 1497968016 277184816 20809472
08:53:15 758 8192000000 10440582400 10870894592 1219377296 12.956 100 99 189 3 1551374944 308784352 23213296
08:54:20 1002 8192000000 10437152528 10867499008 1220963344 12.971 100 83 161 1 1546142192 305644208 19534640
08:55:26 1213 8192000000 10445022928 10875944960 1201092992 12.786 100 88 171 2 1552973760 331227056 20653712
08:56:30 882 8192000000 10488955760 10909655040 1211228640 12.880 100 97 194 0 1591015584 356614880 23172064
08:57:33 749 8192000000 10516267584 10952122368 1219588720 12.958 100 94 181 0 1623024800 382581680 20854400
08:58:37 817 8192000000 10533932992 10945740800 1216979216 12.934 100 87 171 0 1641472416 403842976 20650224
08:59:41 5633 8192000000 10571033680 11037921280 1218407600 12.947 100 76 147 2 1683217136 446270688 18538848
09:00:54 727 8192000000 10594479120 11021004800 1217800576 12.941 100 94 186 2 1697258144 456856528 22601040
09:01:56 4171 8192000000 10634671040 11082838016 1212441424 12.892 100 64 128 0 1734987312 506490448 16055440
09:03:18 12862 8192000000 10627209936 11068952576 1191695248 12.699 99 44 88 0 1733221072 529926128 11599696
09:04:38 12313 8192000000 10595590864 11104436224 1187641584 12.661 100 50 100 0 1728947504 527905552 13400368
09:05:56 12930 8192000000 10525317264 11038265344 1160658976 12.409 100 37 74 0 1636213296 465339984 10214336
09:07:49 35099 8192000000 10234381760 10765271040 1161560896 12.418 100 16 32 0 1437058800 271473728 4024176
09:09:26 11668 8192000000 10102421888 10532745216 1131628720 12.137 99 47 80 0 1204856496 64511056 8716720
09:10:39 18267 8192000000 10143157072 10594906112 1132106112 12.141 99 37 72 1 1229116304 88649952 8360240
09:12:26 92123 8192000000 10105059264 10651877376 1122984192 12.055 99 4 8 0 1178105568 54082864 1038512
09:15:47 49554 8192000000 10064527728 10495815680 1117574512 12.004 98 44 81 2 1165680336 38710208 9395616
09:17:39 67432 8192000000 10091305552 10533863424 1148289760 12.293 100 14 25 0 1190187424 39745072 2152592
09:19:47 51570 8192000000 10094675008 10540822528 1103154128 11.868 96 35 68 0 1155654992 45389680 7111184
09:22:00 58528 8192000000 10068795840 10507079680 1115294384 11.983 98 19 38 0 1141851552 22736800 3820368
09:23:58 88464 8192000000 10079766048 10520707072 1079370944 11.641 95 13 26 0 1131793440 49757072 2665424
09:26:28 125362 8192000000 10069871312 10532069376 1088954496 11.733 96 13 26 0 1135217152 43795040 2467616
09:29:46 86613 8192000000 10096935984 10533724160 1138090272 12.198 100 48 94 0 1209889936 61485904 10313760
09:32:17 38272 8192000000 10090218640 10517598208 1116221040 11.991 99 45 87 0 1162769152 37942432 8605680
09:34:05 10446 8192000000 10059319840 10491645952 1110695904 11.939 98 32 63 0 1148229680 31374912 6158864
09:35:16 26383 8192000000 10120751744 10552508416 1142240448 12.237 100 37 75 0 1216082240 65981984 7859808
09:36:45 24549 8192000000 10120645648 10548674560 1130810864 12.129 99 45 88 1 1204161472 64030256 9320352
09:38:12 47236 8192000000 10098514560 10551844864 1111744720 11.949 98 12 24 0 1198609472 84025472 2839280
09:40:00 63781 8192000000 10140924480 10592980992 1129388656 12.116 99 11 22 0 1228519792 96523440 2607696
09:42:12 46826 8192000000 10081248272 10541174784 1124981856 12.074 99 21 40 0 1181256224 51886816 4387552
09:45:20 45870 8192000000 10086535376 10533724160 1109978000 11.932 97 21 38 0 1155811360 42089216 3744144
09:47:09 46901 8192000000 10123491472 10595729408 1124743632 12.072 99 21 42 0 1214497664 85913920 3840112
09:48:56 56180 8192000000 10114156768 10577068032 1102469472 11.861 96 24 48 0 1178204688 70679168 5056048
09:50:53 64081 8192000000 10080562848 10511392768 1081665952 11.663 96 16 32 0 1136254096 51320144 3268000
09:52:58 15983 8192000000 10086684496 10508808192 1131963680 12.140 100 65 122 0 1180385952 35931056 12491216
09:54:15 23475 8192000000 10094858384 10542034944 1141091328 12.226 100 31 60 0 1197750304 50465248 6193728
09:55:38 66787 8192000000 10115250624 10603565056 1141619456 12.231 99 21 42 0 1229021536 82801520 4600560
09:57:51 48584 8192000000 10042090176 10489417728 1098103728 11.820 96 15 27 2 1134050880 33454224 2492928
09:59:41 19136 8192000000 10053962016 10490761216 1128328352 12.106 99 43 82 0 1150071392 13198752 8544288
10:01:01 52369 8192000000 10045717312 10493960192 1085650560 11.701 95 21 42 0 1121926896 31669552 4606784
10:03:08 71096 8192000000 10086609120 10536628224 1117484048 12.003 99 18 34 0 1172383136 51134960 3764128
10:05:19 87086 8192000000 10090019152 10547814400 1131781760 12.138 100 27 54 0 1196535888 59011040 5743088
10:07:56 54903 8192000000 10079927792 10561323008 1116961920 11.998 98 14 28 0 1177081312 56810880 3308512
10:09:57 47702 8192000000 10123127280 10571063296 1116315104 11.992 98 31 61 0 1206806256 83882480 6608672
10:11:47 40548 8192000000 10116346976 10567188480 1117328496 12.002 98 27 53 0 1197538768 74553168 5657104
10:13:36 50989 8192000000 10111818128 10551885824 1083683824 11.683 96 22 44 0 1176543776 88288144 4571808
10:15:28 38198 8192000000 10134399120 10562076672 1089094896 11.734 96 29 56 0 1175708464 80365680 6247888
10:17:09 69984 8192000000 10152901952 10617106432 1129218272 12.114 100 18 36 1 1276412240 143073536 4120432
10:19:19 26137 8192000000 10116004352 10545803264 1139652256 12.212 100 35 53 3 1198271296 52397088 6221952
10:20:47 93027 8192000000 10102611200 10542161920 1143103856 12.245 100 46 90 1 1221400208 68786528 9509824
10:23:28 63305 8192000000 10062120704 10528337920 1128090848 12.103 99 15 30 0 1162403184 30906816 3405520
10:25:32 68153 8192000000 10063754384 10534801408 1105717856 11.892 97 18 36 0 1151867344 42237680 3911808
10:27:45 36846 8192000000 10075697840 10525134848 1100266464 11.840 97 20 41 0 1141865296 37957568 3641264
10:29:23 23289 8192000000 10081764432 10525155328 1129192912 12.114 100 41 77 2 1158624048 20943056 8488080
10:30:55 24480 8192000000 10052595024 10495082496 1076924864 11.618 96 35 66 1 1125742352 42195088 6622400
10:32:22 44372 8192000000 10113738576 10555420672 1092152000 11.763 97 38 74 0 1181532208 81358272 8021936
10:34:09 33286 8192000000 10108579360 10533425152 1088427056 11.728 97 43 84 2 1189362704 92606032 8329616
10:35:49 31392 8192000000 10151603088 10598260736 1090724448 11.750 98 39 78 0 1224633760 125648032 8261280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 11-02-2023 10:38:38 to 11-02-2023 10:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-02-2023 10:38:56 to 11-02-2023 10: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          220423
MON$OLDEST_ACTIVE               223043
MON$OLDEST_SNAPSHOT             223043
MON$NEXT_TRANSACTION            223043
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                       2064096
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:135: 
:136: mscs-test-161.budzdorov.ru    Sat Feb 11 10:37:33 2023
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:138: 
:139: 
:140: mscs-test-161.budzdorov.ru    Sat Feb 11 10:37:39 2023
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
:143: 
:144: mscs-test-161.budzdorov.ru    Sat Feb 11 10:37:40 2023
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:146: 
--- end of diff output ---

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

11.02.23 10:38:59. 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-02-11 10:39:02 No severe PSQL-related problems occured
11.02.23 10:39:03. End of report.