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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-03 07:37:19
DTS_END                         2023-01-03 07:37:19.9240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53796
TRN_ID                          217828
ATT_ID                          611
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: 8906.55 30177 1068786
customer order: creation 159.62 477 19154
customer order: refuse 14.66 399 1759
order to supplier: creation 43.78 1371 5253
order to supplier: removal 3.45 166795 414
invoice (draft): creation 43.06 3257 5167
invoice (draft): removal 50.53 956 6064
invoice accept: apply 39.91 25138 4789
invoice accept: cancel 45.29 1298 5435
customer reserve: creation 3753.43 235 450412
customer reserve: removal 4473.13 104 536776
realization accept: apply 77.08 269 9249
realization accept: cancel 78.76 172 9451
payment from customer: creation 57.46 172 6895
payment from customer: removal 5.08 116 609
payment to supplier: creation 46.38 197 5566
payment to supplier: removal 3.30 111 396
service: total inventory turnovers 4.04 3836 485
service: total monetary turnovers 6.58 390 789
service: aggregate perf. data 0.99 15074 119
service: refresh index statistics 0.03 383175 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-01-2023 07:38:59 to 03-01-2023 07:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4957
TEST_TIME 2 5161
TEST_TIME 3 5059
TEST_TIME 4 5233
TEST_TIME 5 5528
TEST_TIME 6 5829
TEST_TIME 7 6290
TEST_TIME 8 6459
TEST_TIME 9 6458
TEST_TIME 10 6225
TEST_TIME 11 6502
TEST_TIME 12 6701
TEST_TIME 13 6833
TEST_TIME 14 6963
TEST_TIME 15 7299
TEST_TIME 16 7856
TEST_TIME 17 8045
TEST_TIME 18 7989
TEST_TIME 19 8103
TEST_TIME 20 8213
TEST_TIME 21 8640
TEST_TIME 22 8903
TEST_TIME 23 9371
TEST_TIME 24 9413
TEST_TIME 25 9313
TEST_TIME 26 9378
TEST_TIME 27 9656
TEST_TIME 28 9682
TEST_TIME 29 10158
TEST_TIME 30 10306
TEST_TIME 31 10564
TEST_TIME 32 11033
TEST_TIME 33 10934
TEST_TIME 34 10716
TEST_TIME 35 10658
TEST_TIME 36 10620
TEST_TIME 37 10616
TEST_TIME 38 10474
TEST_TIME 39 10553
TEST_TIME 40 10401
TEST_TIME 41 10244
TEST_TIME 42 10193
TEST_TIME 43 10301
TEST_TIME 44 10220
TEST_TIME 45 10071
TEST_TIME 46 9860
TEST_TIME 47 9891
TEST_TIME 48 9743
TEST_TIME 49 9653
TEST_TIME 50 9570
TEST_TIME 51 9583
TEST_TIME 52 9536
TEST_TIME 53 9460
TEST_TIME 54 9384
TEST_TIME 55 9373
TEST_TIME 56 9341
TEST_TIME 57 9207
TEST_TIME 58 9204
TEST_TIME 59 9295
TEST_TIME 60 9274
TEST_TIME 61 9254
TEST_TIME 62 9215
TEST_TIME 63 9189
TEST_TIME 64 9188
TEST_TIME 65 9174
TEST_TIME 66 9142
TEST_TIME 67 9164
TEST_TIME 68 9173
TEST_TIME 69 9165
TEST_TIME 70 9216
TEST_TIME 71 9176
TEST_TIME 72 9193
TEST_TIME 73 9237
TEST_TIME 74 9178
TEST_TIME 75 9146
TEST_TIME 76 9072
TEST_TIME 77 9012
TEST_TIME 78 9043
TEST_TIME 79 8989
TEST_TIME 80 8943
TEST_TIME 81 8890
TEST_TIME 82 8839
TEST_TIME 83 8829
TEST_TIME 84 8829
TEST_TIME 85 8796
TEST_TIME 86 8841
TEST_TIME 87 8833
TEST_TIME 88 8820
TEST_TIME 89 8841
TEST_TIME 90 8840
TEST_TIME 91 8806
TEST_TIME 92 8911
TEST_TIME 93 8947
TEST_TIME 94 8981
TEST_TIME 95 9148
TEST_TIME 96 9089
TEST_TIME 97 9006
TEST_TIME 98 8965
TEST_TIME 99 8994
TEST_TIME 100 8961
TEST_TIME 101 8904
TEST_TIME 102 8892
TEST_TIME 103 8868
TEST_TIME 104 8848
TEST_TIME 105 8824
TEST_TIME 106 8816
TEST_TIME 107 8826
TEST_TIME 108 8800
TEST_TIME 109 8783
TEST_TIME 110 8762
TEST_TIME 111 8739
TEST_TIME 112 8740
TEST_TIME 113 8737
TEST_TIME 114 8756
TEST_TIME 115 8783
TEST_TIME 116 8847
TEST_TIME 117 8900
TEST_TIME 118 9024
TEST_TIME 119 8996
TEST_TIME 120 8928

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2023 07:39:03 to 03-01-2023 07:39:06.

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 1481421 1481421 0 0.00 0 23030 13 0 0
doc_list_biud 1481406 1481406 0 0.00 0 70994 43 0 0
sp_add_invoice_to_stock 4884 4789 95 1.94 37 173968 25138 0 95
sp_cancel_adding_invoice 5435 5435 0 0.00 6 95681 1298 0 0
sp_cancel_client_order 1759 1759 0 0.00 6 9395 399 0 0
sp_cancel_customer_reserve 536776 536776 0 0.00 1 71007 104 0 0
sp_cancel_pay_from_customer 609 609 0 0.00 4 4970 117 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 6890 112 0 0
sp_cancel_supplier_invoice 6067 6064 3 0.04 3 33563 956 0 3
sp_cancel_supplier_order 414 414 0 0.00 10 1283009 166795 0 0
sp_cancel_write_off 9451 9451 0 0.00 2 9264 172 0 0
sp_client_order 19154 19154 0 0.00 5 26850 478 0 0
sp_customer_reserve 450412 450412 0 0.00 0 39323 236 0 0
sp_fill_shopping_cart 29563 29563 0 0.00 1 25987 87 0 0
sp_fill_shopping_cart_clo_res 450372 450372 0 0.00 0 16882 18 0 0
sp_get_clo_for_invoice 4743 4743 0 0.00 0 27270 320 0 0
sp_kill_cost_storno 15891 15891 0 0.00 0 8762 73 0 0
sp_kill_qstorno_ret_qs2qd 1089481 1089481 0 0.00 0 70981 46 0 0
sp_kill_qty_storno 573889 573889 0 0.00 0 70993 104 0 0
sp_lock_selected_doc 19576 19576 0 0.00 0 6560 7 0 0
sp_make_cost_storno 26453 26453 0 0.00 0 14966 76 0 0
sp_multiply_rows_for_pdistr 26452 26452 0 0.00 0 12291 37 0 0
sp_multiply_rows_for_qdistr 19154 19154 0 0.00 0 26785 193 0 0
sp_pay_from_customer 6895 6895 0 0.00 5 14949 173 0 0
sp_pay_to_supplier 5566 5566 0 0.00 6 13826 197 0 0
sp_payment_common 12461 12461 0 0.00 2 14941 53 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 9380 318 0 0
sp_qd_handle_on_invoice_upd_sts 10177 10177 0 0.00 0 18851 531 0 0
sp_qd_handle_on_reserve_upd_sts 9248 9248 0 0.00 0 10639 64 0 0
sp_reserve_write_off 9249 9249 0 0.00 8 15108 270 0 0
sp_supplier_invoice 5167 5167 0 0.00 10 120252 3257 0 0
sp_supplier_order 5253 5253 0 0.00 7 45277 1372 0 0
srv_aggregate_perf_data 119 119 0 0.00 2164 222308 15075 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 845 136495 36650 0 0
srv_find_qd_qs_mism 1020929 1020929 0 0.00 0 23024 10 0 0
srv_make_invnt_saldo 485 485 0 0.00 64 48034 3837 0 0
srv_make_money_saldo 789 789 0 0.00 1 15275 391 0 0
srv_recalc_idx_stat 4 4 0 0.00 246040 553583 383175 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 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 4792 4792 0 0.00 1 13062 886 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 8050 515 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 2303 24 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 1947 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 609 609 0 0.00 0 2883 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 1071 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 9232 49 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 97524 6983 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 1620 18 0 0
t$perf-norm:sp_client_order 19156 19156 0 0.00 0 10378 34 0 0
t$perf-norm:sp_customer_reserve 912 912 0 0.00 0 2287 22 0 0
t$perf-norm:sp_pay_from_customer 6895 6895 0 0.00 0 10810 21 0 0
t$perf-norm:sp_pay_to_supplier 5566 5566 0 0.00 0 4869 18 0 0
t$perf-norm:sp_reserve_write_off 9249 9249 0 0.00 0 10877 25 0 0
t$perf-norm:sp_supplier_invoice 5167 5167 0 0.00 0 3606 27 0 0
t$perf-norm:sp_supplier_order 5254 5254 0 0.00 0 6216 29 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 459 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 216 7 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 3627 20 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 647 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 356 106 0 0
v_add_invoice_to_stock 9666 9476 190 1.96 0 4223 24 0 190
v_all_customers 35960 35960 0 0.00 0 203 3 0 0
v_all_suppliers 29020 29020 0 0.00 0 88 4 0 0
v_all_wares 38302 38302 0 0.00 0 117 4 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 2530 19 0 0
v_cancel_client_order 3518 3518 0 0.00 0 1538 10 0 0
v_cancel_customer_prepayment 1218 1218 0 0.00 0 925 11 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 1200 12 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 486 10 0 0
v_cancel_supplier_invoice 824 818 6 0.72 0 3249 27 0 6
v_cancel_supplier_order 828 828 0 0.00 0 563 11 0 0
v_cancel_write_off 1904 1904 0 0.00 0 8224 29 0 0
v_max_id_clo_ord 5250 5250 0 0.00 0 360 5 0 0
v_max_id_clo_res 912 912 0 0.00 0 4052 20 0 0
v_max_id_ord_sup 5158 5158 0 0.00 0 3812 6 0 0
v_max_non_paid_invoice 1463 1463 0 0.00 0 82 7 0 0
v_max_non_paid_realizn 3336 3336 0 0.00 0 1016 7 0 0
v_min_id_clo_ord 5250 5250 0 0.00 0 4030 12 0 0
v_min_id_clo_res 912 912 0 0.00 0 702 8 0 0
v_min_id_ord_sup 5158 5158 0 0.00 0 3660 9 0 0
v_min_non_paid_invoice 5565 5565 0 0.00 0 11130 25 0 0
v_min_non_paid_realizn 6895 6895 0 0.00 0 11959 22 0 0
v_our_firm 9460 9460 0 0.00 0 103 4 0 0
v_reserve_write_off 18494 18494 0 0.00 0 3351 10 0 0
x_lock_depdocs_on_canc_invoice 11090 11090 0 0.00 0 21352 127 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 22310 355 0 0
x_make_qstorno_1000_1200 5253 5253 0 0.00 2 45262 1207 0 0
x_make_qstorno_1200_2000 5167 5167 0 0.00 4 98724 3003 0 0
x_make_qstorno_2100_3300 450409 450409 0 0.00 0 37919 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2023 07:39:07 to 03-01-2023 07:39:11.

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:23 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:25 22406 8192000000 9727751104 10028498944 802242016 8.919 100 87 174 0 830529296 7406096 20881184
05:09:51 20888 8192000000 9774381696 10083774464 845484208 9.355 100 91 180 0 879618064 13079632 21054224
05:11:20 11421 8192000000 9806901488 10129485824 873861792 9.639 100 82 161 0 911685600 19532416 18291392
05:12:41 8867 8192000000 9863906064 10197549056 914989632 10.047 100 82 164 0 975587120 43019424 17578064
05:13:52 5787 8192000000 9898986672 10242138112 952505136 10.416 100 83 162 0 1019894736 49984288 17405312
05:15:00 2653 8192000000 9958968848 10311753728 981971952 10.703 100 89 172 1 1082070704 82450960 17647792
05:16:05 1263 8192000000 10003694304 10366509056 1016878592 11.042 100 94 178 0 1121418832 86325712 18214528
05:17:08 1975 8192000000 10005101008 10367565824 1035171488 11.218 100 94 171 2 1120816080 69424656 16219936
05:18:12 906 8192000000 10031388640 10404044800 1052814576 11.388 100 97 186 0 1146300016 74991824 18493616
05:19:14 1033 8192000000 10032958864 10409795584 1062539104 11.481 100 95 174 0 1157197024 76507744 18150176
05:20:16 1792 8192000000 10039047344 10429063168 1068019696 11.533 100 76 146 2 1151613376 68381168 15212512
05:21:20 1274 8192000000 10029295152 10411753472 1076751232 11.617 100 82 153 2 1151274480 60033280 14489968
05:22:22 1205 8192000000 10059380592 10445385728 1085072960 11.696 100 94 179 1 1181001792 77593472 18335360
05:23:24 1147 8192000000 10049208288 10435006464 1093095488 11.772 100 98 187 1 1168289312 55831760 19362064
05:24:26 1148 8192000000 10065184416 10457903104 1096567520 11.805 100 79 158 0 1185408000 72795904 16044576
05:25:28 2437 8192000000 10059542976 10453008384 1094242672 11.783 100 71 137 3 1183347344 75253360 13851312
05:26:36 2089 8192000000 10059490496 10449842176 1098851792 11.827 100 68 127 2 1178160160 67522144 11786224
05:27:39 1247 8192000000 10054811072 10447130624 1106278944 11.897 100 95 180 0 1184966576 59488592 19199040
05:28:41 1099 8192000000 10073169952 10463141888 1100030656 11.838 100 91 172 0 1193767984 75486096 18251232
05:29:44 1181 8192000000 10051732112 10439245824 1091398064 11.756 100 93 173 1 1170168640 60080048 18690528
05:30:47 1159 8192000000 10052217808 10433798144 1074087632 11.591 100 93 178 1 1173615696 79797728 19730336
05:31:48 1207 8192000000 9994819552 10369671168 1041738064 11.281 99 81 149 0 1119241040 62214352 15288624
05:32:51 1415 8192000000 10002313328 10378162176 1044384016 11.307 100 79 151 1 1127307024 67566720 15356288
05:33:54 1136 8192000000 10014510720 10388713472 1041983424 11.284 100 84 164 2 1137495776 79150608 16361744
05:34:56 1098 8192000000 9963570816 10331435008 1022581264 11.097 99 89 169 1 1083391792 42223520 18587008
05:35:58 6791 8192000000 9982954400 10364911616 1042692912 11.291 100 64 122 2 1111721376 55788016 13240448
05:37:06 1109 8192000000 9963057360 10332352512 1029727360 11.166 99 86 160 0 1085642240 39195392 16719488
05:38:13 26653 8192000000 10022287200 10399526912 1051521280 11.375 100 64 123 1 1138525648 74751920 12252448
05:39:44 10928 8192000000 10058788352 10455904256 1076899920 11.618 100 64 123 1 1184437888 95226592 12311376
05:41:00 4353 8192000000 10095538208 10481651712 1092467632 11.766 100 78 150 2 1208108144 100210752 15429760
05:42:08 6250 8192000000 10138830944 10534010880 1098583616 11.824 100 63 119 1 1253197200 142352816 12260768
05:43:20 1669 8192000000 10163313472 10556203008 1116735760 11.996 100 86 161 0 1276534896 141776592 18022544
05:44:30 5377 8192000000 10172895648 10576904192 1126331664 12.087 100 73 134 1 1295426112 154317120 14777328
05:45:39 2012 8192000000 10196347856 10593419264 1140419344 12.219 100 88 152 1 1310856896 154115136 16322416
05:46:45 2342 8192000000 10229836816 10634919936 1144844192 12.261 100 70 135 0 1344408368 184692240 14871936
05:48:00 1480 8192000000 10261540320 10675159040 1171857712 12.514 100 89 170 0 1372904352 182151120 18895520
05:49:10 1117 8192000000 10304918848 10714374144 1179022432 12.581 100 93 185 0 1418295440 218732080 20540928
05:50:12 976 8192000000 10353067056 10755907584 1181661344 12.606 100 94 181 0 1459792928 257624080 20507504
05:51:16 1273 8192000000 10382076480 10795675648 1186414624 12.650 100 96 185 2 1489569104 282917616 20236864
05:52:21 1767 8192000000 10401607936 10845167616 1186177648 12.648 100 91 176 1 1504165744 296727744 21260352
05:53:25 1039 8192000000 10394341360 10821173248 1192767056 12.709 100 94 188 1 1497006848 283101424 21138368
05:54:29 1482 8192000000 10363175776 10782023680 1179133216 12.582 99 83 157 3 1464745440 266708816 18903408
05:55:35 1101 8192000000 10444714480 10852061184 1201334496 12.789 100 94 186 1 1548389840 326100592 20954752
05:56:39 837 8192000000 10459327328 10875105280 1192635568 12.708 100 99 190 1 1559060032 344417888 22006576
05:57:43 1127 8192000000 10437405552 10871324672 1171469792 12.511 100 94 188 2 1545174352 350564896 23139664
05:58:45 6702 8192000000 10374840288 10882641920 1170989824 12.506 100 58 114 1 1477073632 291971680 14112128
05:59:56 1231 8192000000 10402869392 10854375424 1173926464 12.534 100 71 142 0 1510306976 319028848 17351664
06:01:00 13665 8192000000 10362668928 10798870528 1162965088 12.431 100 37 69 1 1470076976 297327216 9784672
06:02:17 5737 8192000000 10376774496 10806362112 1160272032 12.406 100 51 97 1 1478763760 305929696 12562032
06:03:30 9011 8192000000 10366088944 10813325312 1158745264 12.392 100 50 97 0 1471624112 300731904 12146944
06:04:40 14643 8192000000 10367699088 10806341632 1150398608 12.313 99 37 74 0 1464615168 305044528 9172032
06:05:55 17531 8192000000 10317750000 10825646080 1138453056 12.201 98 31 62 0 1429055728 282630512 7972160
06:07:17 26174 8192000000 10284046576 10780221440 1131791456 12.138 99 28 54 0 1397092720 258516688 6784576
06:09:22 27895 8192000000 10101643568 10590560256 1094840160 11.789 98 28 53 0 1234270400 132905792 6524448
06:10:51 30169 8192000000 10103440432 10527436800 1125675744 12.081 100 78 151 4 1205976016 63014192 17286080
06:12:23 5194 8192000000 10178088704 10597023744 1126176784 12.085 100 64 122 1 1268682240 129063232 13442224
06:13:37 69856 8192000000 10121386864 10639024128 1100580080 11.843 98 11 23 0 1242931120 139547664 2803376
06:15:47 44538 8192000000 10068478512 10559434752 1085718736 11.702 97 20 35 0 1157134560 66923168 4492656
06:17:36 98053 8192000000 10146202912 10601213952 1126087552 12.084 99 21 42 0 1220452256 90104768 4259936
06:20:15 70132 8192000000 10090796928 10529345536 1108671360 11.920 98 30 60 0 1181012592 65658704 6682528
06:22:32 132043 8192000000 10037185600 10470100992 1109383184 11.927 98 26 51 0 1179965136 65101984 5479968
06:25:45 28490 8192000000 10083518160 10531180544 1125060368 12.075 100 27 51 0 1180740512 50767328 4912816
06:27:34 87651 8192000000 10098180944 10595151872 1113844080 11.969 98 21 40 0 1193983056 75303424 4835552
06:30:02 92002 8192000000 10109044544 10571763712 1117151536 12.000 98 12 24 0 1185276816 65552688 2572592
06:32:35 69411 8192000000 10119873040 10596257792 1146298208 12.275 100 15 31 0 1236891504 87042064 3551232
06:35:11 63341 8192000000 10145902784 10603241472 1141623728 12.231 100 26 50 1 1255149920 107712544 5813648
06:37:15 79084 8192000000 10063956416 10545258496 1098446896 11.823 96 9 18 0 1145752784 45631344 1674544
06:39:34 63897 8192000000 10077099120 10533171200 1128032944 12.103 99 12 24 0 1156591520 26166672 2391904
06:41:39 57240 8192000000 10073520352 10517217280 1129468000 12.116 98 44 84 2 1177934624 39529968 8936656
06:43:38 14551 8192000000 10094489088 10523574272 1150419392 12.313 100 52 101 1 1202845888 42333632 10092864
06:44:54 73140 8192000000 10081330960 10517282816 1077168592 11.620 94 29 58 0 1130818400 48205232 5444576
06:47:11 82506 8192000000 10082358000 10529382400 1112187856 11.953 97 25 51 0 1156989024 39583824 5217344
06:49:37 87562 8192000000 10088898048 10543210496 1119474272 12.022 97 16 32 0 1160734176 38469856 2790048
06:52:04 93648 8192000000 10078976544 10522214400 1136341888 12.181 99 34 68 0 1182418736 38793456 7283392
06:54:41 21768 8192000000 10121951104 10567217152 1153445840 12.342 100 34 68 0 1225887056 65613808 6827408
06:56:12 33374 8192000000 10080630000 10534068224 1150677760 12.316 99 14 22 1 1183563776 30622560 2263456
06:58:18 90612 8192000000 10112138672 10561671168 1133474624 12.154 98 13 26 0 1198112928 61864080 2774224
07:00:51 42192 8192000000 10092792224 10556719104 1116967968 11.998 97 15 28 0 1164765264 44488160 3309136
07:02:33 54907 8192000000 10150823440 10618122240 1155340656 12.360 100 27 54 0 1242299648 80746000 6212992
07:04:45 56142 8192000000 10158695376 10605232128 1121343840 12.040 97 33 66 1 1233237152 104850192 7043120
07:06:45 41289 8192000000 10205293296 10635096064 1152250992 12.331 99 33 66 0 1274473488 114871680 7350816
07:08:32 19420 8192000000 10210142512 10640740352 1125395456 12.078 97 45 88 1 1290826400 155288912 10142032
07:09:56 53071 8192000000 10169104496 10617778176 1140450224 12.220 99 18 38 0 1284030112 139405328 4174560
07:12:13 53728 8192000000 10073759984 10524233728 1087384496 11.718 96 9 18 1 1153842672 64680864 1777312
07:14:13 136495 8192000000 10056321232 10537795584 1110443120 11.937 98 26 52 1 1184158832 68327024 5388688
07:18:41 80092 8192000000 10073375072 10562727936 1118239696 12.010 99 12 23 0 1159868464 39524176 2104592
07:21:06 29068 8192000000 10038960400 10476171264 1089686960 11.740 96 19 37 0 1124417408 31037152 3693296
07:22:35 19292 8192000000 10092947008 10528743424 1124986576 12.074 99 47 92 1 1187794624 52273024 10535024
07:23:55 43911 8192000000 10088373088 10546249728 1135090768 12.169 100 25 49 1 1208316048 67929312 5295968
07:25:55 22775 8192000000 10141572784 10573565952 1146441728 12.276 99 43 83 0 1235883200 80175376 9266096
07:27:27 22349 8192000000 10181497696 10614001664 1163055408 12.432 100 45 89 0 1285210560 112120192 10034960
07:28:56 21181 8192000000 10212998368 10676523008 1158957616 12.393 100 40 80 1 1331569072 163142736 9468720
07:30:26 29243 8192000000 10266738704 10711506944 1161475376 12.417 99 41 81 0 1345269536 173936272 9857888
07:31:57 26636 8192000000 10265435840 10707718144 1140915776 12.224 98 46 92 0 1335144416 183739056 10489584
07:33:33 26199 8192000000 10257607344 10752389120 1156271872 12.368 100 26 52 0 1351490288 188682704 6535712
07:35:23 67908 8192000000 10079012976 10566537216 1130605504 12.127 99 14 28 1 1224991264 91103696 3282064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 03-01-2023 07:39:13 to 03-01-2023 07:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 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 03-01-2023 07:39:31 to 03-01-2023 07:39:33.

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          216981
MON$OLDEST_ACTIVE               217985
MON$OLDEST_SNAPSHOT             217985
MON$NEXT_TRANSACTION            217985
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                       2065248
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             770
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 ---
:870: 
:871: mscs-test-161.budzdorov.ru    Tue Jan  3 07:37:20 2023
:872:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:873: 
:874: 
:875: mscs-test-161.budzdorov.ru    Tue Jan  3 07:37:22 2023
:876:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:877: 
:878: 
:879: mscs-test-161.budzdorov.ru    Tue Jan  3 07:37:24 2023
:880:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:881: 
:882: 
:883: mscs-test-161.budzdorov.ru    Tue Jan  3 07:37:29 2023
:884:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:885: 
--- end of diff output ---

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

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