Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2022 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 14872224 kB
MemAvailable 30422760 kB
Buffers 0 kB
Cached 16842260 kB
SwapCached 11660 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 2022-12-31 07:37:23
DTS_END                         2022-12-31 07:37:23.0250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35746
TRN_ID                          221291
ATT_ID                          608
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: 9158.08 23736 1098970
customer order: creation 177.49 445 21299
customer order: refuse 15.17 434 1820
order to supplier: creation 45.18 1306 5422
order to supplier: removal 3.32 165970 398
invoice (draft): creation 46.68 2920 5602
invoice (draft): removal 49.75 971 5970
invoice accept: apply 43.30 24782 5196
invoice accept: cancel 44.68 1188 5362
customer reserve: creation 4027.47 234 483296
customer reserve: removal 4411.53 97 529384
realization accept: apply 84.30 281 10116
realization accept: cancel 76.94 156 9233
payment from customer: creation 59.00 167 7080
payment from customer: removal 5.83 131 699
payment to supplier: creation 50.83 173 6099
payment to supplier: removal 3.38 107 406
service: total inventory turnovers 4.41 3667 529
service: total monetary turnovers 7.80 310 936
service: aggregate perf. data 0.97 16276 116
service: refresh index statistics 0.06 255104 7

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2022 07:38:55 to 31-12-2022 07:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4698
TEST_TIME 2 4440
TEST_TIME 3 5736
TEST_TIME 4 6606
TEST_TIME 5 6898
TEST_TIME 6 6434
TEST_TIME 7 6510
TEST_TIME 8 6597
TEST_TIME 9 6754
TEST_TIME 10 6940
TEST_TIME 11 6894
TEST_TIME 12 6977
TEST_TIME 13 7129
TEST_TIME 14 7419
TEST_TIME 15 7570
TEST_TIME 16 7544
TEST_TIME 17 7873
TEST_TIME 18 8216
TEST_TIME 19 8269
TEST_TIME 20 8171
TEST_TIME 21 8254
TEST_TIME 22 8382
TEST_TIME 23 8790
TEST_TIME 24 8771
TEST_TIME 25 8923
TEST_TIME 26 9286
TEST_TIME 27 9386
TEST_TIME 28 9433
TEST_TIME 29 9614
TEST_TIME 30 9565
TEST_TIME 31 10094
TEST_TIME 32 10408
TEST_TIME 33 10481
TEST_TIME 34 11021
TEST_TIME 35 11342
TEST_TIME 36 11074
TEST_TIME 37 11093
TEST_TIME 38 10864
TEST_TIME 39 10808
TEST_TIME 40 10714
TEST_TIME 41 10618
TEST_TIME 42 10545
TEST_TIME 43 10420
TEST_TIME 44 10387
TEST_TIME 45 10296
TEST_TIME 46 10182
TEST_TIME 47 10267
TEST_TIME 48 10253
TEST_TIME 49 10292
TEST_TIME 50 10247
TEST_TIME 51 10176
TEST_TIME 52 10228
TEST_TIME 53 10076
TEST_TIME 54 9959
TEST_TIME 55 9883
TEST_TIME 56 9849
TEST_TIME 57 9715
TEST_TIME 58 9689
TEST_TIME 59 9659
TEST_TIME 60 9676
TEST_TIME 61 9604
TEST_TIME 62 9690
TEST_TIME 63 9630
TEST_TIME 64 9768
TEST_TIME 65 9713
TEST_TIME 66 9690
TEST_TIME 67 9712
TEST_TIME 68 9719
TEST_TIME 69 9673
TEST_TIME 70 9699
TEST_TIME 71 9754
TEST_TIME 72 9697
TEST_TIME 73 9676
TEST_TIME 74 9689
TEST_TIME 75 9653
TEST_TIME 76 9683
TEST_TIME 77 9729
TEST_TIME 78 9659
TEST_TIME 79 9746
TEST_TIME 80 9732
TEST_TIME 81 9638
TEST_TIME 82 9624
TEST_TIME 83 9564
TEST_TIME 84 9497
TEST_TIME 85 9544
TEST_TIME 86 9475
TEST_TIME 87 9494
TEST_TIME 88 9436
TEST_TIME 89 9462
TEST_TIME 90 9426
TEST_TIME 91 9391
TEST_TIME 92 9404
TEST_TIME 93 9410
TEST_TIME 94 9382
TEST_TIME 95 9342
TEST_TIME 96 9336
TEST_TIME 97 9378
TEST_TIME 98 9329
TEST_TIME 99 9424
TEST_TIME 100 9447
TEST_TIME 101 9522
TEST_TIME 102 9494
TEST_TIME 103 9550
TEST_TIME 104 9539
TEST_TIME 105 9545
TEST_TIME 106 9515
TEST_TIME 107 9481
TEST_TIME 108 9508
TEST_TIME 109 9441
TEST_TIME 110 9451
TEST_TIME 111 9415
TEST_TIME 112 9382
TEST_TIME 113 9382
TEST_TIME 114 9344
TEST_TIME 115 9305
TEST_TIME 116 9266
TEST_TIME 117 9279
TEST_TIME 118 9233
TEST_TIME 119 9236
TEST_TIME 120 9177

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-12-2022 07:38:58 to 31-12-2022 07:39:02.

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 1542871 1542871 0 0.00 0 23012 13 0 0
doc_list_biud 1542864 1542864 0 0.00 0 37050 39 0 0
sp_add_invoice_to_stock 5286 5196 90 1.70 38 191597 24782 0 90
sp_cancel_adding_invoice 5362 5362 0 0.00 6 90641 1189 0 0
sp_cancel_client_order 1820 1820 0 0.00 6 11310 435 0 0
sp_cancel_customer_reserve 529384 529384 0 0.00 1 29945 98 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 7726 131 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 4 1456 108 0 0
sp_cancel_supplier_invoice 5973 5970 3 0.05 3 26659 971 0 3
sp_cancel_supplier_order 398 398 0 0.00 13 1537803 165970 0 0
sp_cancel_write_off 9233 9233 0 0.00 2 9889 157 0 0
sp_client_order 21299 21299 0 0.00 5 37064 445 0 0
sp_customer_reserve 483296 483296 0 0.00 0 109168 234 0 0
sp_fill_shopping_cart 32317 32317 0 0.00 1 25578 85 0 0
sp_fill_shopping_cart_clo_res 483272 483272 0 0.00 0 17285 18 0 0
sp_get_clo_for_invoice 5156 5156 0 0.00 0 26234 254 0 0
sp_kill_cost_storno 15700 15700 0 0.00 0 22985 72 0 0
sp_kill_qstorno_ret_qs2qd 1074369 1074369 0 0.00 0 29932 42 0 0
sp_kill_qty_storno 567436 567436 0 0.00 0 37049 97 0 0
sp_lock_selected_doc 21167 21167 0 0.00 0 9127 8 0 0
sp_make_cost_storno 28450 28450 0 0.00 0 17891 75 0 0
sp_multiply_rows_for_pdistr 28447 28447 0 0.00 0 8063 36 0 0
sp_multiply_rows_for_qdistr 21299 21299 0 0.00 0 10272 166 0 0
sp_pay_from_customer 7080 7080 0 0.00 5 11355 168 0 0
sp_pay_to_supplier 6099 6099 0 0.00 6 12323 173 0 0
sp_payment_common 13177 13177 0 0.00 2 12169 48 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 1 11262 341 0 0
sp_qd_handle_on_invoice_upd_sts 10518 10518 0 0.00 0 25430 514 0 0
sp_qd_handle_on_reserve_upd_sts 10113 10113 0 0.00 0 37047 69 0 0
sp_reserve_write_off 10116 10116 0 0.00 9 37186 281 0 0
sp_supplier_invoice 5602 5602 0 0.00 8 67939 2920 0 0
sp_supplier_order 5422 5422 0 0.00 7 28642 1306 0 0
srv_aggregate_perf_data 116 116 0 0.00 2696 220422 16277 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 797 138116 35583 0 0
srv_find_qd_qs_mism 1048015 1048015 0 0.00 0 11440 9 0 0
srv_make_invnt_saldo 529 529 0 0.00 56 71410 3667 0 0
srv_make_money_saldo 936 936 0 0.00 1 7737 310 0 0
srv_recalc_idx_stat 7 7 0 0.00 87292 443870 255104 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5198 5198 0 0.00 0 12356 963 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 17500 466 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 2076 28 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 775 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 2681 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 1173 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 1170 40 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 94882 7453 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 3281 20 0 0
t$perf-norm:sp_client_order 21299 21299 0 0.00 0 12341 38 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 796 17 0 0
t$perf-norm:sp_pay_from_customer 7080 7080 0 0.00 0 10243 22 0 0
t$perf-norm:sp_pay_to_supplier 6099 6099 0 0.00 0 5676 22 0 0
t$perf-norm:sp_reserve_write_off 10116 10116 0 0.00 0 5622 26 0 0
t$perf-norm:sp_supplier_invoice 5602 5602 0 0.00 0 8244 32 0 0
t$perf-norm:sp_supplier_order 5422 5422 0 0.00 0 9419 33 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 2686 42 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 60 4 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 782 14 0 0
t$perf-norm:srv_make_money_saldo 936 936 0 0.00 0 1664 7 0 0
t$perf-norm:srv_recalc_idx_stat 7 7 0 0.00 4 212 79 0 0
v_add_invoice_to_stock 10474 10294 180 1.71 0 3313 24 0 180
v_all_customers 38836 38836 0 0.00 0 100 4 0 0
v_all_suppliers 31226 31226 0 0.00 0 81 4 0 0
v_all_wares 42596 42596 0 0.00 0 285 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1119 16 0 0
v_cancel_client_order 3640 3640 0 0.00 0 2871 12 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 2161 14 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 1 797 12 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 708 12 0 0
v_cancel_supplier_invoice 858 852 6 0.69 0 4753 29 0 6
v_cancel_supplier_order 796 796 0 0.00 0 824 12 0 0
v_cancel_write_off 2040 2040 0 0.00 0 597 21 0 0
v_max_id_clo_ord 5419 5419 0 0.00 0 3165 6 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 2079 16 0 0
v_max_id_ord_sup 5598 5598 0 0.00 0 2206 7 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 371 8 0 0
v_max_non_paid_realizn 3665 3665 0 0.00 0 148 7 0 0
v_min_id_clo_ord 5419 5419 0 0.00 0 3509 9 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 1265 12 0 0
v_min_id_ord_sup 5598 5598 0 0.00 0 7133 13 0 0
v_min_non_paid_invoice 6098 6098 0 0.00 0 12164 17 0 0
v_min_non_paid_realizn 7078 7078 0 0.00 0 7641 18 0 0
v_our_firm 10586 10586 0 0.00 0 79 5 0 0
v_reserve_write_off 20224 20224 0 0.00 0 2814 11 0 0
x_lock_depdocs_on_canc_invoice 10906 10906 0 0.00 0 28612 124 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 14778 392 0 0
x_make_qstorno_1000_1200 5422 5422 0 0.00 2 28630 1140 0 0
x_make_qstorno_1200_2000 5602 5602 0 0.00 4 55982 2686 0 0
x_make_qstorno_2100_3300 483296 483296 0 0.00 0 109166 204 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2022 07:39:03 to 31-12-2022 07:39:07.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:27 30322 8192000000 9736674416 10037211136 809984480 8.997 100 91 182 0 839939728 8163648 21791600
05:10:03 18015 8192000000 9774801840 10085617664 846077200 9.361 100 91 182 0 881413888 13707376 21629312
05:11:28 12730 8192000000 9819905552 10141413376 876392576 9.664 100 87 174 0 922696544 26461136 19842832
05:12:53 3972 8192000000 9889550240 10220515328 919086464 10.087 100 89 173 0 999377888 61223424 19068000
05:14:00 3460 8192000000 9927632112 10270322688 956899424 10.459 100 89 170 0 1046437136 71775696 17762016
05:15:09 1741 8192000000 9963713232 10318299136 990611744 10.787 100 82 161 0 1077404928 70817488 15975696
05:16:14 4024 8192000000 9993046368 10357092352 1017526336 11.048 100 84 164 1 1118009536 83999216 16483984
05:17:20 929 8192000000 10022919664 10395340800 1043391024 11.297 100 99 188 0 1142746784 81868624 17487136
05:18:23 1047 8192000000 10020669376 10393485312 1056728320 11.425 100 95 182 2 1137725760 60872976 20124464
05:19:25 1208 8192000000 10044916944 10420756480 1065310880 11.507 100 98 192 1 1164541344 80289280 18941184
05:20:27 10077 8192000000 10046655824 10432999424 1066268752 11.516 100 78 156 1 1167306064 84869728 16167584
05:21:38 4628 8192000000 10030365840 10415935488 1076333648 11.613 100 74 146 2 1161236496 70569216 14333632
05:22:44 910 8192000000 10045739968 10436034560 1081552160 11.662 100 96 176 2 1172640848 73219024 17869664
05:23:46 1255 8192000000 10049216112 10434187264 1083645552 11.682 100 98 181 2 1173545072 71119856 18779664
05:24:48 1151 8192000000 10035584304 10420719616 1090742880 11.750 100 96 176 3 1159172528 51444096 16985552
05:25:50 1097 8192000000 10066127488 10450292736 1095143328 11.792 100 97 189 1 1187494192 72920320 19430544
05:26:51 6398 8192000000 10036314784 10424946688 1078114208 11.629 99 61 114 1 1155777696 67166656 10496832
05:27:58 3060 8192000000 10057814368 10454061056 1092506288 11.766 100 71 138 1 1193197424 86470880 14220256
05:29:02 1017 8192000000 10053457184 10445791232 1093025792 11.771 100 89 176 1 1175691536 64006336 18659408
05:30:04 1105 8192000000 10033070752 10413912064 1067088416 11.524 100 96 182 0 1153854592 68280544 18485632
05:31:06 1184 8192000000 10054359136 10454986752 1087120144 11.715 100 78 149 0 1179698528 76124624 16453760
05:32:08 1263 8192000000 10013956448 10392051712 1054815488 11.407 100 89 167 0 1139903776 67004176 18084112
05:33:10 1003 8192000000 9985885984 10362732544 1049630528 11.357 99 87 166 1 1109827440 42092240 18104672
05:34:12 1029 8192000000 9983941024 10355290112 1030457728 11.173 100 88 168 0 1103912352 56607280 16847344
05:35:14 1264 8192000000 10010988448 10384601088 1042268672 11.286 100 93 176 2 1133751776 73973184 17509920
05:36:16 1220 8192000000 9996287312 10373513216 1039581728 11.261 100 89 170 3 1119057840 61705744 17770368
05:37:18 998 8192000000 9984250176 10361491456 1042249312 11.286 100 87 154 4 1108512496 49805664 16457520
05:38:26 15875 8192000000 10020937728 10400448512 1068551680 11.538 100 84 157 2 1138521616 54630176 15339760
05:39:47 2758 8192000000 10054757600 10438840320 1064011824 11.495 99 82 150 2 1174935760 95225360 15698576
05:40:54 833 8192000000 10070783552 10458193920 1090938576 11.752 100 85 149 1 1189314208 81971200 16404432
05:41:59 6182 8192000000 10077123376 10473226240 1101566800 11.853 100 70 134 2 1197353168 82468832 13317536
05:43:18 4364 8192000000 10103825088 10500349952 1113882448 11.969 100 67 128 0 1222903648 96002304 13018896
05:44:26 2264 8192000000 10149950896 10546798592 1125598944 12.080 100 78 145 0 1269556384 129234848 14722592
05:45:32 2454 8192000000 10159926208 10556354560 1135727536 12.175 100 76 149 0 1287527392 135323104 16476752
05:46:37 991 8192000000 10209054512 10605600768 1144348176 12.256 100 92 181 1 1325825712 159319120 22158416
05:47:41 4950 8192000000 10201059312 10606383104 1153967472 12.347 100 82 148 3 1318788736 147845104 16976160
05:48:50 1751 8192000000 10256285424 10658832384 1163757120 12.438 100 90 177 0 1375361600 191863472 19741008
05:49:54 788 8192000000 10294737056 10695962624 1177505984 12.567 100 100 198 1 1404450496 204645424 22299088
05:50:57 2051 8192000000 10307962288 10725249024 1173685376 12.531 100 93 184 0 1420889312 226168352 21035584
05:52:02 1913 8192000000 10352028528 10768723968 1182867120 12.617 100 87 169 1 1463873344 261606128 19400096
05:53:06 2177 8192000000 10371121936 10783428608 1178595728 12.577 100 94 177 1 1479067200 279896608 20574864
05:54:12 867 8192000000 10371237696 10829565952 1199268256 12.770 100 93 180 0 1478842656 257665104 21909296
05:55:16 1638 8192000000 10381206384 10807398400 1196315712 12.742 100 86 166 0 1488965904 274001248 18648944
05:56:21 1018 8192000000 10417295840 10837635072 1199346208 12.770 100 94 187 1 1527968240 306051296 22570736
05:57:29 1009 8192000000 10446766464 10863468544 1197701376 12.755 100 96 184 1 1550066976 330292224 22073376
05:58:33 901 8192000000 10476457408 10897338368 1199446304 12.771 100 100 196 3 1580928416 358210976 23271136
05:59:38 971 8192000000 10489696752 10925273088 1200598528 12.782 100 98 190 1 1595097248 370383408 24115312
06:00:42 2629 8192000000 10405660192 10838552576 1165607616 12.456 99 63 122 0 1517955392 337099872 15247904
06:01:48 2981 8192000000 10469026752 10892369920 1165425568 12.454 100 78 152 1 1579105600 394861056 18818976
06:02:54 3948 8192000000 10423587728 10900987904 1162948608 12.431 100 62 122 3 1529720320 350469264 16302448
06:04:08 7521 8192000000 10420472288 10849296384 1170041152 12.497 100 49 98 0 1522864960 340835360 11988448
06:05:24 7366 8192000000 10442989904 10880593920 1153021712 12.338 99 51 102 0 1543449616 377421920 13005984
06:06:35 12844 8192000000 10435214192 10880630784 1157987680 12.384 99 44 87 0 1531539840 361971920 11580240
06:08:20 16535 8192000000 10375894512 10880929792 1148168048 12.292 100 34 66 0 1486987872 330131664 8688160
06:09:47 28036 8192000000 10286747024 10768388096 1140945856 12.224 100 28 56 0 1414554976 266731264 6877856
06:11:45 93486 8192000000 10057962928 10545356800 1105476848 11.890 99 16 32 0 1198384608 89899744 3008016
06:14:21 19475 8192000000 10085668656 10527600640 1119119232 12.019 100 27 54 0 1185135888 60741872 5274784
06:15:41 84048 8192000000 10056982720 10526982144 1117496400 12.003 99 20 40 0 1164021632 42484400 4040832
06:18:11 67402 8192000000 10075029792 10495279104 1106648480 11.901 98 23 45 1 1150931952 39990480 4292992
06:20:19 65708 8192000000 10089991728 10555678720 1097565360 11.815 97 16 29 0 1166100336 65599872 2935104
06:22:26 89316 8192000000 10138873728 10588155904 1143156080 12.245 100 20 40 0 1205690048 58448768 4085200
06:25:11 138116 8192000000 10069309888 10524856320 1069208592 11.545 94 15 26 1 1097889312 26159936 2520784
06:28:30 30588 8192000000 10092663952 10527715328 1122992208 12.055 99 18 36 0 1200059968 73047776 4019984
06:30:05 115042 8192000000 10090644832 10533490688 1119377088 12.021 98 45 80 1 1171404704 43309776 8717840
06:33:01 61849 8192000000 10115153200 10560958464 1144575664 12.259 100 31 58 1 1215037184 62926608 7534912
06:35:06 953 8192000000 10146235648 10566549504 1156059616 12.366 100 96 178 1 1238974640 64591872 18323152
06:36:08 38673 8192000000 10148325872 10580852736 1125043376 12.075 98 41 80 0 1252219104 117976752 9198976
06:37:51 10595 8192000000 10165664576 10627674112 1145922176 12.271 100 37 73 0 1258893840 104992432 7979232
06:39:02 63638 8192000000 10170505536 10648850432 1145652704 12.269 99 22 44 0 1284576896 133840976 5083216
06:42:17 28713 8192000000 10091289072 10533761024 1150978704 12.319 100 10 20 1 1190453248 37924656 1549888
06:43:47 42066 8192000000 10089298480 10595782656 1144890272 12.262 99 7 14 0 1210672400 64496096 1286032
06:45:30 38700 8192000000 10094095088 10533011456 1129139760 12.113 98 30 58 0 1180544704 45647952 5756992
06:47:11 49887 8192000000 10123718672 10608664576 1145218416 12.265 100 21 40 0 1221111888 71555616 4337856
06:49:07 19950 8192000000 10075120576 10505302016 1118730400 12.015 98 39 78 0 1152658432 26331504 7596528
06:50:27 13737 8192000000 10136803136 10564378624 1146378576 12.275 100 67 131 0 1212836000 53426576 13030848
06:51:43 22404 8192000000 10111542272 10558382080 1124185360 12.067 99 38 76 0 1193719712 62137648 7396704
06:53:11 56996 8192000000 10130194688 10606153728 1134407216 12.163 99 23 46 0 1246364064 107000352 4956496
06:55:08 18757 8192000000 10113824544 10540023808 1127200160 12.095 99 38 72 2 1173923280 39350608 7372512
06:56:33 121975 8192000000 10076668640 10542428160 1080550160 11.653 95 15 32 0 1130290256 46723280 3016816
06:59:56 77846 8192000000 10107048400 10584260608 1127645024 12.099 100 20 40 0 1217888624 86212944 4030656
07:02:18 56265 8192000000 10098573840 10588352512 1101215264 11.849 97 12 24 0 1206248736 102363808 2669664
07:04:22 54443 8192000000 10105898112 10549477376 1107100960 11.905 98 24 47 0 1185148576 72807232 5240384
07:06:17 90411 8192000000 10083171008 10560536576 1111750672 11.949 98 17 33 0 1211651088 96261264 3639152
07:08:53 78258 8192000000 10051798064 10547564544 1066521664 11.519 96 13 26 0 1136445024 66870000 3053360
07:11:14 21715 8192000000 10057672176 10492149760 1079136304 11.639 98 25 50 0 1119664208 35074016 5453888
07:12:37 21874 8192000000 10118126848 10541989888 1097896224 11.818 100 42 84 0 1192066240 85414112 8755904
07:14:05 70314 8192000000 10134874160 10558263296 1113334832 11.964 99 27 54 0 1256041040 136741360 5964848
07:16:20 56409 8192000000 10105205616 10570227712 1102980960 11.866 98 20 40 0 1222711952 115733232 3997760
07:18:25 44014 8192000000 10078569456 10505457664 1107186928 11.906 98 31 62 0 1144946496 32531456 5228112
07:20:10 83496 8192000000 10069284624 10540081152 1079258336 11.640 95 42 83 0 1148181040 60921424 8001280
07:22:34 25127 8192000000 10125845248 10553532416 1125740240 12.081 99 42 80 0 1190671232 56705216 8225776
07:24:00 102338 8192000000 10062283776 10551791616 1110729616 11.939 98 38 76 0 1201554864 83766384 7058864
07:26:55 1654 8192000000 10071313936 10496565248 1126821120 12.091 99 75 141 2 1170312960 28923696 14568144
07:27:58 77949 8192000000 10080667280 10567933952 1142596512 12.240 100 7 13 0 1188155264 44366560 1192192
07:30:34 46643 8192000000 10060432256 10518827008 1127325472 12.096 99 14 28 0 1168234448 38309264 2599712
07:32:21 86411 8192000000 10084310848 10557960192 1139511408 12.211 100 13 24 0 1187250656 45291728 2447520
07:34:48 68894 8192000000 10109285984 10552475648 1139788560 12.214 99 15 30 0 1193619344 49838576 3992208
07:36:59 32305 8192000000 8982220608 9203097600 130636096 1.569 12 5 10 0 171478832 39666592 1176144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-12-2022 07:39:09 to 31-12-2022 07:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2022 07:39:26 to 31-12-2022 07:39:28.

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          224857
MON$OLDEST_ACTIVE               224858
MON$OLDEST_SNAPSHOT             224858
MON$NEXT_TRANSACTION            224858
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                       2088800
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:734: 
:735: mscs-test-161.budzdorov.ru    Sat Dec 31 07:37:36 2022
:736:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:737: 
--- end of diff output ---

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

31.12.22 07:39:29. 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
2022-12-31 07:39:32 No severe PSQL-related problems occured
31.12.22 07:39:33. End of report.