Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.03.2023 04:40
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 14772152 kB
MemAvailable 30424884 kB
Buffers 20 kB
Cached 16890252 kB
SwapCached 3052 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-03-03 04:37:26
DTS_END                         2023-03-03 04:37:26.2600
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/54454
TRN_ID                          226654
ATT_ID                          662
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: 9183.07 23611 1101968
customer order: creation 173.68 448 20842
customer order: refuse 14.88 367 1785
order to supplier: creation 46.56 1322 5587
order to supplier: removal 3.55 152877 426
invoice (draft): creation 48.31 2849 5797
invoice (draft): removal 51.56 1046 6187
invoice accept: apply 42.39 26831 5087
invoice accept: cancel 45.87 1189 5504
customer reserve: creation 4003.69 254 480443
customer reserve: removal 4453.53 94 534424
realization accept: apply 84.06 258 10087
realization accept: cancel 83.46 161 10015
payment from customer: creation 60.33 169 7239
payment from customer: removal 5.61 131 673
payment to supplier: creation 49.07 192 5888
payment to supplier: removal 3.60 132 432
service: total inventory turnovers 4.44 3356 533
service: total monetary turnovers 7.58 298 910
service: aggregate perf. data 0.88 15530 105
service: refresh index statistics 0.03 264710 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-03-2023 04:40:42 to 03-03-2023 04:40:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6734
TEST_TIME 2 5578
TEST_TIME 3 6238
TEST_TIME 4 6396
TEST_TIME 5 5907
TEST_TIME 6 5615
TEST_TIME 7 6008
TEST_TIME 8 5927
TEST_TIME 9 5986
TEST_TIME 10 6194
TEST_TIME 11 6352
TEST_TIME 12 6452
TEST_TIME 13 6625
TEST_TIME 14 6943
TEST_TIME 15 7253
TEST_TIME 16 7364
TEST_TIME 17 7336
TEST_TIME 18 7734
TEST_TIME 19 8078
TEST_TIME 20 8144
TEST_TIME 21 8701
TEST_TIME 22 8481
TEST_TIME 23 8400
TEST_TIME 24 8251
TEST_TIME 25 8489
TEST_TIME 26 8415
TEST_TIME 27 8883
TEST_TIME 28 9675
TEST_TIME 29 10111
TEST_TIME 30 10387
TEST_TIME 31 10767
TEST_TIME 32 11209
TEST_TIME 33 11333
TEST_TIME 34 11139
TEST_TIME 35 11392
TEST_TIME 36 11499
TEST_TIME 37 11345
TEST_TIME 38 11273
TEST_TIME 39 11083
TEST_TIME 40 10911
TEST_TIME 41 10747
TEST_TIME 42 10813
TEST_TIME 43 10900
TEST_TIME 44 10772
TEST_TIME 45 10756
TEST_TIME 46 10557
TEST_TIME 47 10384
TEST_TIME 48 10372
TEST_TIME 49 10306
TEST_TIME 50 10206
TEST_TIME 51 10141
TEST_TIME 52 10057
TEST_TIME 53 10061
TEST_TIME 54 10177
TEST_TIME 55 10062
TEST_TIME 56 9990
TEST_TIME 57 9889
TEST_TIME 58 9830
TEST_TIME 59 9804
TEST_TIME 60 9781
TEST_TIME 61 9707
TEST_TIME 62 9848
TEST_TIME 63 9799
TEST_TIME 64 9854
TEST_TIME 65 9822
TEST_TIME 66 9874
TEST_TIME 67 9827
TEST_TIME 68 9815
TEST_TIME 69 9852
TEST_TIME 70 9849
TEST_TIME 71 9875
TEST_TIME 72 9883
TEST_TIME 73 9805
TEST_TIME 74 9790
TEST_TIME 75 9677
TEST_TIME 76 9625
TEST_TIME 77 9733
TEST_TIME 78 9721
TEST_TIME 79 9646
TEST_TIME 80 9628
TEST_TIME 81 9556
TEST_TIME 82 9481
TEST_TIME 83 9413
TEST_TIME 84 9372
TEST_TIME 85 9307
TEST_TIME 86 9341
TEST_TIME 87 9358
TEST_TIME 88 9320
TEST_TIME 89 9335
TEST_TIME 90 9338
TEST_TIME 91 9376
TEST_TIME 92 9376
TEST_TIME 93 9416
TEST_TIME 94 9432
TEST_TIME 95 9445
TEST_TIME 96 9472
TEST_TIME 97 9498
TEST_TIME 98 9472
TEST_TIME 99 9462
TEST_TIME 100 9389
TEST_TIME 101 9435
TEST_TIME 102 9363
TEST_TIME 103 9335
TEST_TIME 104 9326
TEST_TIME 105 9296
TEST_TIME 106 9249
TEST_TIME 107 9213
TEST_TIME 108 9225
TEST_TIME 109 9225
TEST_TIME 110 9185
TEST_TIME 111 9158
TEST_TIME 112 9155
TEST_TIME 113 9124
TEST_TIME 114 9131
TEST_TIME 115 9150
TEST_TIME 116 9219
TEST_TIME 117 9273
TEST_TIME 118 9271
TEST_TIME 119 9253
TEST_TIME 120 9207

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-03-2023 04:40:46 to 03-03-2023 04:40:49.

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 1542352 1542352 0 0.00 0 24872 14 0 0
doc_list_biud 1542338 1542338 0 0.00 0 42505 39 0 0
sp_add_invoice_to_stock 5207 5087 120 2.30 46 197136 26832 0 120
sp_cancel_adding_invoice 5504 5504 0 0.00 6 115921 1190 0 0
sp_cancel_client_order 1785 1785 0 0.00 6 6858 368 0 0
sp_cancel_customer_reserve 534424 534424 0 0.00 1 42517 94 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 5474 132 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 2350 132 0 0
sp_cancel_supplier_invoice 6196 6187 9 0.14 3 18138 1047 0 9
sp_cancel_supplier_order 426 426 0 0.00 17 1647794 152877 0 0
sp_cancel_write_off 10015 10015 0 0.00 2 15283 161 0 0
sp_client_order 20842 20842 0 0.00 5 28943 448 0 0
sp_customer_reserve 480443 480443 0 0.00 0 92942 255 0 0
sp_fill_shopping_cart 32212 32212 0 0.00 1 11264 74 0 0
sp_fill_shopping_cart_clo_res 480409 480409 0 0.00 0 24735 16 0 0
sp_get_clo_for_invoice 5045 5045 0 0.00 0 23221 210 0 0
sp_kill_cost_storno 16624 16624 0 0.00 0 8694 73 0 0
sp_kill_qstorno_ret_qs2qd 1085476 1085476 0 0.00 0 34025 42 0 0
sp_kill_qty_storno 573469 573469 0 0.00 0 42503 96 0 0
sp_lock_selected_doc 20965 20965 0 0.00 0 7014 7 0 0
sp_make_cost_storno 28258 28258 0 0.00 0 24864 82 0 0
sp_multiply_rows_for_pdistr 28255 28255 0 0.00 0 9159 35 0 0
sp_multiply_rows_for_qdistr 20841 20841 0 0.00 0 8702 156 0 0
sp_pay_from_customer 7239 7239 0 0.00 5 8525 169 0 0
sp_pay_to_supplier 5888 5888 0 0.00 6 23777 193 0 0
sp_payment_common 13127 13127 0 0.00 2 8489 44 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 6422 290 0 0
sp_qd_handle_on_invoice_upd_sts 10546 10546 0 0.00 0 36472 511 0 0
sp_qd_handle_on_reserve_upd_sts 10086 10086 0 0.00 0 13399 53 0 0
sp_reserve_write_off 10087 10087 0 0.00 9 13661 259 0 0
sp_supplier_invoice 5797 5797 0 0.00 8 94915 2850 0 0
sp_supplier_order 5587 5587 0 0.00 7 19987 1322 0 0
srv_aggregate_perf_data 105 105 0 0.00 3031 233251 15530 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 949 121856 33427 0 0
srv_find_qd_qs_mism 1050806 1050806 0 0.00 0 13155 12 0 0
srv_make_invnt_saldo 533 533 0 0.00 36 78074 3356 0 0
srv_make_money_saldo 910 910 0 0.00 1 12331 299 0 0
srv_recalc_idx_stat 4 4 0 0.00 48608 465532 264710 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5091 5091 0 0.00 1 20671 862 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 6713 397 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 10131 32 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1798 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 12618 34 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 11867 45 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 3319 39 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 90706 6519 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 722 15 0 0
t$perf-norm:sp_client_order 20842 20842 0 0.00 0 12615 33 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 1277 13 0 0
t$perf-norm:sp_pay_from_customer 7240 7240 0 0.00 0 12225 20 0 0
t$perf-norm:sp_pay_to_supplier 5888 5888 0 0.00 0 2633 16 0 0
t$perf-norm:sp_reserve_write_off 10087 10087 0 0.00 0 13283 26 0 0
t$perf-norm:sp_supplier_invoice 5797 5797 0 0.00 0 12446 31 0 0
t$perf-norm:sp_supplier_order 5588 5588 0 0.00 0 5026 26 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 165 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 106 6 0 0
t$perf-norm:srv_make_invnt_saldo 533 533 0 0.00 0 2295 23 0 0
t$perf-norm:srv_make_money_saldo 910 910 0 0.00 0 239 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 307 95 0 0
v_add_invoice_to_stock 10320 10080 240 2.32 0 7246 25 0 240
v_all_customers 38507 38507 0 0.00 0 182 4 0 0
v_all_suppliers 31612 31612 0 0.00 0 157 4 0 0
v_all_wares 41676 41676 0 0.00 0 176 4 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 6814 24 0 0
v_cancel_client_order 3570 3570 0 0.00 0 6056 14 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 1771 13 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 2222 13 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 2296 19 0 0
v_cancel_supplier_invoice 850 832 18 2.11 0 3211 33 0 18
v_cancel_supplier_order 852 852 0 0.00 0 2324 18 0 0
v_cancel_write_off 2000 2000 0 0.00 0 2719 26 0 0
v_max_id_clo_ord 5583 5583 0 0.00 0 2369 5 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 2468 13 0 0
v_max_id_ord_sup 5788 5788 0 0.00 0 1122 5 0 0
v_max_non_paid_invoice 1451 1451 0 0.00 0 130 8 0 0
v_max_non_paid_realizn 3598 3598 0 0.00 0 892 7 0 0
v_min_id_clo_ord 5583 5583 0 0.00 0 2484 8 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 6149 16 0 0
v_min_id_ord_sup 5788 5788 0 0.00 0 3399 8 0 0
v_min_non_paid_invoice 5887 5887 0 0.00 0 4289 16 0 0
v_min_non_paid_realizn 7237 7237 0 0.00 0 8482 19 0 0
v_our_firm 10450 10450 0 0.00 0 51 4 0 0
v_reserve_write_off 20172 20172 0 0.00 0 6798 12 0 0
x_lock_depdocs_on_canc_invoice 11275 11275 0 0.00 0 14577 116 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 2655 296 0 0
x_make_qstorno_1000_1200 5587 5587 0 0.00 2 19965 1177 0 0
x_make_qstorno_1200_2000 5797 5797 0 0.00 4 94850 2658 0 0
x_make_qstorno_2100_3300 480443 480443 0 0.00 0 92931 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-03-2023 04:40:50 to 03-03-2023 04:40:54.

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
02:07:30 2 8192000000 8797233232 8807895040 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:30 14206 8192000000 9709045056 10004090880 790614192 8.801 100 89 178 0 820966464 8050032 22302240
02:09:55 23970 8192000000 9767927456 10076606464 846398752 9.364 100 84 168 0 877112080 11071920 19641408
02:11:28 17424 8192000000 9810792064 10132000768 879819056 9.698 100 83 166 0 918479264 19730096 18930112
02:12:52 6082 8192000000 9865235344 10198982656 917852016 10.075 100 87 170 0 977669520 41244848 18572656
02:14:01 2834 8192000000 9925786560 10269417472 953963088 10.430 100 92 178 2 1039872880 66935216 18974576
02:15:07 3961 8192000000 9964992304 10316922880 994256448 10.823 100 84 167 0 1078801200 67480976 17063776
02:16:13 1526 8192000000 10001189872 10364420096 1024616336 11.117 100 92 177 1 1114331616 71696624 18018656
02:17:16 970 8192000000 10011560240 10382577664 1051009600 11.370 100 96 183 1 1138203824 68631328 18562896
02:18:18 918 8192000000 10039687600 10416709632 1067499408 11.528 100 96 190 1 1161289472 74708656 19081408
02:19:21 1013 8192000000 10058125792 10435796992 1075443872 11.604 100 95 182 1 1176640016 82503296 18692848
02:20:35 1077 8192000000 10055803888 10436059136 1081368016 11.661 100 96 189 1 1174944208 74899168 18677024
02:21:37 1060 8192000000 10050020336 10436222976 1084474960 11.690 100 89 173 4 1173505024 70775632 18254432
02:22:40 1220 8192000000 10070106576 10461671424 1087608288 11.720 100 93 179 4 1191144032 85566304 17969440
02:23:42 950 8192000000 10059912496 10450112512 1093742160 11.778 100 86 163 1 1183801216 73359488 16699568
02:24:44 1444 8192000000 10056058592 10451562496 1094774816 11.788 100 75 138 1 1178316400 70138704 13402880
02:25:46 1013 8192000000 10071064528 10466217984 1099312720 11.831 100 90 177 1 1189745344 70647184 19785440
02:26:49 1057 8192000000 10068490480 10458607616 1100241296 11.840 100 98 194 0 1189477632 70285552 18950784
02:27:51 1230 8192000000 10066898912 10458341376 1104816896 11.883 100 94 177 2 1190325424 67746512 17762016
02:28:53 1114 8192000000 10059356304 10447339520 1100127040 11.839 100 92 180 1 1181829248 62601664 19100544
02:29:55 1257 8192000000 10069580608 10461560832 1087200272 11.716 100 86 167 0 1190557808 86560800 16796736
02:30:57 1102 8192000000 10043547856 10424979456 1075990768 11.609 100 90 174 1 1166063008 71860032 18212208
02:31:59 1139 8192000000 10047103600 10431889408 1075681312 11.606 100 89 166 1 1166677728 72215584 18780832
02:33:02 1299 8192000000 10021361200 10404335616 1060028800 11.457 100 95 179 5 1144747664 67340768 17378096
02:34:05 835 8192000000 10001839264 10385682432 1052078640 11.381 100 87 155 1 1129428448 60481360 16868448
02:35:06 947 8192000000 9973585808 10351689728 1045014992 11.313 100 95 178 4 1097434832 33313616 19106224
02:36:08 2420 8192000000 9980131664 10356039680 1027181136 11.141 99 78 157 1 1100583040 56826816 16575088
02:37:11 5144 8192000000 10022485584 10402500608 1047941232 11.341 99 76 140 2 1135309088 73746080 13621776
02:38:21 9419 8192000000 10018375728 10402357248 1074921520 11.599 100 65 123 0 1132071184 44635488 12514176
02:39:51 7252 8192000000 10065750928 10462019584 1092519824 11.767 100 68 135 1 1189820720 83996784 13304112
02:41:05 2381 8192000000 10091212304 10483654656 1107137232 11.905 100 69 135 0 1210722480 90170240 13415008
02:42:11 28010 8192000000 10115563488 10516226048 1122709904 12.053 100 59 116 0 1222330992 87943952 11677136
02:43:48 6143 8192000000 10175852640 10570424320 1128004272 12.103 99 75 141 2 1293443904 149891968 15547664
02:44:57 2521 8192000000 10183439008 10586533888 1140492880 12.220 99 73 132 2 1298948448 143348688 15106880
02:46:03 2715 8192000000 10272535056 10674376704 1162107520 12.423 100 75 150 0 1386238880 207972080 16159280
02:47:10 1208 8192000000 10297949968 10693726208 1174714736 12.541 100 81 155 2 1404591520 213423824 16452960
02:48:13 1550 8192000000 10338924080 10749198336 1184890080 12.636 100 93 178 2 1450495280 245753184 19852016
02:49:17 1081 8192000000 10378662672 10787098624 1191926416 12.701 100 96 183 1 1485210832 272819904 20464512
02:50:21 984 8192000000 10406650688 10830401536 1202860096 12.803 100 98 193 0 1520178928 295300240 22018592
02:51:26 946 8192000000 10404385232 10848964608 1205437776 12.827 100 88 170 2 1514418064 288501168 20479120
02:52:29 1430 8192000000 10431852240 10866827264 1209418336 12.864 100 94 180 3 1540649840 309798368 21433136
02:53:35 1467 8192000000 10463412336 10878234624 1213164800 12.898 100 96 190 0 1565957392 330372176 22420416
02:54:40 1671 8192000000 10461580288 10909421568 1205458848 12.827 100 89 171 1 1567668720 342902368 19307504
02:55:44 1964 8192000000 10471533792 10932314112 1200495264 12.781 100 88 174 0 1580930048 358264496 22170288
02:56:49 1428 8192000000 10460306048 10908221440 1205300288 12.826 100 96 186 5 1571139840 342414144 23425408
02:57:53 2954 8192000000 10495335920 10937507840 1197180512 12.750 99 82 162 1 1602399376 384160176 21058688
02:59:01 6085 8192000000 10486945776 10911694848 1209034304 12.860 100 52 104 0 1589017216 365976848 14006064
03:00:13 5951 8192000000 10541116368 10976964608 1204256944 12.816 100 60 120 1 1654118752 434434624 15427184
03:01:23 6809 8192000000 10590183392 11030740992 1195047200 12.730 100 50 100 0 1698606256 489929600 13629456
03:02:32 10269 8192000000 10585908992 11036520448 1191386176 12.696 100 48 96 0 1690747936 486243056 13118704
03:03:46 4307 8192000000 10613423408 11065028608 1180620192 12.596 100 53 106 0 1739320256 544438736 14261328
03:04:53 3768 8192000000 10472815792 11010772992 1174870656 12.542 100 58 116 0 1581136144 391802352 14463136
03:05:59 21167 8192000000 10345521072 10837749760 1146111776 12.273 99 27 53 0 1461846784 308777216 6957792
03:07:21 23488 8192000000 10287749488 10749059072 1151734416 12.326 100 25 50 1 1384876144 227386080 5755648
03:09:05 69324 8192000000 10083130944 10573873152 1065254176 11.507 93 15 30 0 1185724464 117182560 3287728
03:11:15 48090 8192000000 10143523440 10594312192 1111121776 11.943 97 13 26 0 1185186912 71222160 2842976
03:13:03 97133 8192000000 10085004096 10523410432 1128959760 12.112 98 16 33 0 1183051808 51359696 2732352
03:15:45 39234 8192000000 10082908576 10511585280 1114195664 11.972 97 34 61 2 1152199952 31410000 6594288
03:17:25 23752 8192000000 10161413648 10588856320 1163029344 12.432 100 46 91 0 1257063232 83766976 10266912
03:19:10 67763 8192000000 10124512928 10634407936 1138290256 12.199 99 24 48 0 1275992240 131650448 6051536
03:21:22 66978 8192000000 10083251360 10531418112 1094137792 11.782 96 22 41 0 1138786384 39746016 4902576
03:23:40 65369 8192000000 10083152304 10514051072 1121862928 12.045 99 51 92 1 1159577920 27932352 9782640
03:25:46 84871 8192000000 10074902864 10546491392 1142348368 12.238 100 14 27 0 1179009040 33746912 2913760
03:28:12 29386 8192000000 10103697088 10552782848 1150290336 12.312 100 32 65 0 1213049632 55886368 6872928
03:29:48 9525 8192000000 10140939072 10565570560 1127940336 12.102 98 69 139 0 1227198976 83516016 15742624
03:30:58 68324 8192000000 10074812544 10574995456 1141018080 12.225 100 15 30 0 1208234688 63307104 3909504
03:33:07 51988 8192000000 10081711888 10545954816 1133781264 12.157 99 11 22 0 1190048832 53717760 2549808
03:35:11 45055 8192000000 10100154816 10576773120 1146089936 12.273 100 11 22 0 1215279056 66266688 2922432
03:37:17 55394 8192000000 10111583120 10600730624 1126619712 12.089 98 9 18 0 1171479536 43057296 1802528
03:39:12 56227 8192000000 10061874832 10541346816 1106294816 11.897 97 13 24 0 1194250672 85241632 2714224
03:41:15 56488 8192000000 10097058656 10553864192 1104445744 11.880 96 27 54 0 1146623104 36565088 5612272
03:43:12 36766 8192000000 10055418720 10502328320 1101870016 11.855 97 42 82 3 1153744512 43370816 8503680
03:44:52 8300 8192000000 10107817600 10543091712 1131689296 12.137 100 60 118 1 1200346736 56605104 12052336
03:46:03 49687 8192000000 10093815232 10537717760 1126685104 12.090 100 36 70 0 1200786576 67015248 7086224
03:47:56 39636 8192000000 10117620512 10590740480 1120861456 12.035 99 36 72 0 1196065504 68152848 7051200
03:49:37 79315 8192000000 10171110304 10606583808 1133167456 12.151 99 28 56 0 1237140128 97877472 6095200
03:52:03 121856 8192000000 10111524512 10640502784 1124864864 12.073 99 25 48 0 1271756704 141156384 5735456
03:55:10 78186 8192000000 10061024640 10567929856 1130442560 12.126 100 8 16 0 1175977440 43876160 1658720
03:57:34 60310 8192000000 10033930192 10493329408 1093878880 11.780 98 12 24 0 1126611968 29995888 2737200
04:01:12 78188 8192000000 10069005680 10531418112 1105418640 11.889 98 20 40 0 1165755776 56170752 4166384
04:03:39 40629 8192000000 10083740208 10524831744 1119906064 12.026 99 20 40 0 1188204016 64295760 4002192
04:05:20 29177 8192000000 10068249936 10497957888 1091732368 11.759 98 34 67 0 1136083424 37270000 7081056
04:06:51 22577 8192000000 10127918992 10555023360 1123319424 12.058 100 50 100 0 1222880784 89397680 10163680
04:08:15 52080 8192000000 10062925664 10515800064 1103312064 11.869 98 31 62 0 1172959376 63632832 6014480
04:10:08 20332 8192000000 10118482672 10556915712 1119830736 12.025 99 54 106 1 1215303824 84328448 11144640
04:11:30 13594 8192000000 10079817312 10514722816 1108534512 11.919 98 43 84 0 1156768192 39353904 8879776
04:12:45 12151 8192000000 10123163136 10567041024 1133346768 12.153 100 48 95 0 1224341232 81746016 9248448
04:14:01 33073 8192000000 10101427056 10560352256 1127552064 12.098 100 42 84 0 1184106144 48443872 8110208
04:15:37 18265 8192000000 10114184912 10549317632 1129440288 12.116 100 42 84 0 1200525296 62837040 8247968
04:16:55 106098 8192000000 10095728080 10586099712 1126656096 12.090 99 24 47 0 1185223936 53872992 4694848
04:19:46 8240 8192000000 10087842272 10565861376 1141605488 12.231 100 25 41 0 1195946880 50506048 3835344
04:20:54 59138 8192000000 10078580880 10567704576 1122639408 12.052 98 19 38 0 1175762496 49325568 3797520
04:22:54 66014 8192000000 10083458960 10540900352 1137180704 12.189 100 14 26 0 1174306240 34527536 2598000
04:25:00 87576 8192000000 10059097376 10560655360 1095349472 11.793 96 8 16 1 1146619488 49512048 1757968
04:27:28 20186 8192000000 10104664880 10546597888 1129106112 12.113 99 26 52 0 1192093184 57049152 5937920
04:28:50 12950 8192000000 10160869120 10602733568 1139606752 12.212 100 44 86 0 1255711952 106556848 9548352
04:30:06 29164 8192000000 10151941552 10589237248 1109942256 11.932 98 26 52 0 1239360400 123301008 6117136
04:31:36 28292 8192000000 10206514848 10649858048 1132605600 12.146 100 45 90 0 1308421840 165782064 10034176
04:33:05 42003 8192000000 10151730416 10635849728 1113939024 11.970 99 27 54 0 1263747968 143702864 6106080
04:34:54 28790 8192000000 10070710720 10510704640 1109325808 11.926 99 30 59 0 1179104224 63314992 6463424
04:36:23 65540 8192000000 10027462288 10489397248 1065056496 11.505 95 39 78 0 1131042640 58238448 7747696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-03-2023 04:40:57 to 03-03-2023 04:41:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-03-2023 04:41:14 to 03-03-2023 04:41:16.

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          226653
MON$OLDEST_ACTIVE               227036
MON$OLDEST_SNAPSHOT             227036
MON$NEXT_TRANSACTION            227036
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                       2090752
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2904 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2904 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2904 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 ---
:117: 
:118: mscs-test-161.budzdorov.ru    Fri Mar  3 04:37:55 2023
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:120: 
:121: 
:122: mscs-test-161.budzdorov.ru    Fri Mar  3 04:37:56 2023
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:124: 
:125: 
:126: mscs-test-161.budzdorov.ru    Fri Mar  3 04:38:41 2023
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Fri Mar  3 04:38:41 2023
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:132: 
--- end of diff output ---

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

03.03.23 04:41:17. 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-03-03 04:41:20 No severe PSQL-related problems occured
03.03.23 04:41:21. End of report.