Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.07.2022 04:37
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 16053572 kB
MemAvailable 31060136 kB
Buffers 20 kB
Cached 15563564 kB
SwapCached 2532 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.2
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-07-08 04:37:17
DTS_END                         2022-07-08 04:37:17.8100
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/58682
TRN_ID                          206689
ATT_ID                          638
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: 8893.10 23774 1067172
customer order: creation 152.32 480 18278
customer order: refuse 13.03 365 1564
order to supplier: creation 40.08 1366 4810
order to supplier: removal 3.41 167608 409
invoice (draft): creation 41.28 2841 4954
invoice (draft): removal 52.84 866 6341
invoice accept: apply 37.56 27563 4507
invoice accept: cancel 47.88 1014 5746
customer reserve: creation 3554.29 261 426515
customer reserve: removal 4660.40 98 559248
realization accept: apply 74.02 253 8882
realization accept: cancel 100.84 152 12101
payment from customer: creation 51.85 154 6222
payment from customer: removal 5.29 114 635
payment to supplier: creation 43.67 159 5240
payment to supplier: removal 3.20 109 384
service: total inventory turnovers 3.68 3582 442
service: total monetary turnovers 6.53 345 783
service: aggregate perf. data 0.88 17383 106
service: refresh index statistics 0.04 250773 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-07-2022 04:38:05 to 08-07-2022 04:38:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6207
TEST_TIME 2 5865
TEST_TIME 3 5721
TEST_TIME 4 5261
TEST_TIME 5 5267
TEST_TIME 6 5328
TEST_TIME 7 5624
TEST_TIME 8 6363
TEST_TIME 9 6420
TEST_TIME 10 6600
TEST_TIME 11 6509
TEST_TIME 12 6617
TEST_TIME 13 6766
TEST_TIME 14 6877
TEST_TIME 15 7059
TEST_TIME 16 7340
TEST_TIME 17 7708
TEST_TIME 18 7744
TEST_TIME 19 7819
TEST_TIME 20 8289
TEST_TIME 21 8273
TEST_TIME 22 8617
TEST_TIME 23 8609
TEST_TIME 24 8582
TEST_TIME 25 9100
TEST_TIME 26 9239
TEST_TIME 27 10332
TEST_TIME 28 11001
TEST_TIME 29 11224
TEST_TIME 30 10934
TEST_TIME 31 10685
TEST_TIME 32 10416
TEST_TIME 33 10511
TEST_TIME 34 10360
TEST_TIME 35 10293
TEST_TIME 36 10139
TEST_TIME 37 9997
TEST_TIME 38 9876
TEST_TIME 39 9693
TEST_TIME 40 9708
TEST_TIME 41 9635
TEST_TIME 42 9708
TEST_TIME 43 9774
TEST_TIME 44 9915
TEST_TIME 45 9848
TEST_TIME 46 9942
TEST_TIME 47 9840
TEST_TIME 48 9937
TEST_TIME 49 9835
TEST_TIME 50 9789
TEST_TIME 51 9717
TEST_TIME 52 9876
TEST_TIME 53 9949
TEST_TIME 54 9860
TEST_TIME 55 9777
TEST_TIME 56 9616
TEST_TIME 57 9545
TEST_TIME 58 9597
TEST_TIME 59 9452
TEST_TIME 60 9326
TEST_TIME 61 9210
TEST_TIME 62 9228
TEST_TIME 63 9220
TEST_TIME 64 9174
TEST_TIME 65 9148
TEST_TIME 66 9121
TEST_TIME 67 9162
TEST_TIME 68 9148
TEST_TIME 69 9156
TEST_TIME 70 9134
TEST_TIME 71 9138
TEST_TIME 72 9237
TEST_TIME 73 9216
TEST_TIME 74 9187
TEST_TIME 75 9268
TEST_TIME 76 9488
TEST_TIME 77 9389
TEST_TIME 78 9310
TEST_TIME 79 9283
TEST_TIME 80 9214
TEST_TIME 81 9153
TEST_TIME 82 9118
TEST_TIME 83 9043
TEST_TIME 84 8971
TEST_TIME 85 8959
TEST_TIME 86 8985
TEST_TIME 87 9002
TEST_TIME 88 9000
TEST_TIME 89 8965
TEST_TIME 90 8980
TEST_TIME 91 8944
TEST_TIME 92 8945
TEST_TIME 93 8937
TEST_TIME 94 8976
TEST_TIME 95 9034
TEST_TIME 96 9009
TEST_TIME 97 9042
TEST_TIME 98 9058
TEST_TIME 99 9088
TEST_TIME 100 9094
TEST_TIME 101 9124
TEST_TIME 102 9045
TEST_TIME 103 8987
TEST_TIME 104 8968
TEST_TIME 105 8921
TEST_TIME 106 8893
TEST_TIME 107 8872
TEST_TIME 108 8807
TEST_TIME 109 8832
TEST_TIME 110 8836
TEST_TIME 111 8826
TEST_TIME 112 8853
TEST_TIME 113 8837
TEST_TIME 114 8854
TEST_TIME 115 8877
TEST_TIME 116 8871
TEST_TIME 117 8839
TEST_TIME 118 8881
TEST_TIME 119 8906
TEST_TIME 120 8916

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-07-2022 04:38:09 to 08-07-2022 04:38:12.

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 1460699 1460699 0 0.00 0 23074 13 0 0
doc_list_biud 1460699 1460699 0 0.00 0 36642 42 0 0
sp_add_invoice_to_stock 4615 4507 108 2.34 41 219747 27564 0 108
sp_cancel_adding_invoice 5746 5746 0 0.00 6 91090 1014 0 0
sp_cancel_client_order 1564 1564 0 0.00 5 10837 366 0 0
sp_cancel_customer_reserve 559248 559248 0 0.00 1 36655 98 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 4 4751 115 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 4 4883 110 0 0
sp_cancel_supplier_invoice 6347 6341 6 0.09 3 24730 866 0 6
sp_cancel_supplier_order 409 409 0 0.00 22 1470724 167609 0 0
sp_cancel_write_off 12101 12101 0 0.00 2 8687 153 0 0
sp_client_order 18278 18278 0 0.00 5 32262 481 0 0
sp_customer_reserve 426515 426515 0 0.00 0 114294 261 0 0
sp_fill_shopping_cart 28040 28040 0 0.00 1 12884 78 0 0
sp_fill_shopping_cart_clo_res 426499 426499 0 0.00 0 19444 20 0 0
sp_get_clo_for_invoice 4471 4471 0 0.00 0 22797 367 0 0
sp_kill_cost_storno 18866 18866 0 0.00 0 8271 61 0 0
sp_kill_qstorno_ret_qs2qd 1137347 1137347 0 0.00 0 27380 43 0 0
sp_kill_qty_storno 598761 598761 0 0.00 0 36641 97 0 0
sp_lock_selected_doc 18600 18600 0 0.00 0 8370 5 0 0
sp_make_cost_storno 24814 24814 0 0.00 0 11727 67 0 0
sp_multiply_rows_for_pdistr 24814 24814 0 0.00 0 8822 31 0 0
sp_multiply_rows_for_qdistr 18278 18278 0 0.00 0 11046 200 0 0
sp_pay_from_customer 6222 6222 0 0.00 5 27400 155 0 0
sp_pay_to_supplier 5240 5240 0 0.00 6 16981 160 0 0
sp_payment_common 11462 11462 0 0.00 2 8331 38 0 0
sp_qd_handle_on_cancel_clo 1564 1564 0 0.00 0 10828 308 0 0
sp_qd_handle_on_invoice_upd_sts 10216 10216 0 0.00 0 23427 436 0 0
sp_qd_handle_on_reserve_upd_sts 8882 8882 0 0.00 0 19176 62 0 0
sp_reserve_write_off 8882 8882 0 0.00 7 19358 253 0 0
sp_supplier_invoice 4954 4954 0 0.00 9 72677 2841 0 0
sp_supplier_order 4810 4810 0 0.00 8 55977 1366 0 0
srv_aggregate_perf_data 106 106 0 0.00 2445 278676 17384 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 710 151401 37492 0 0
srv_find_qd_qs_mism 1023492 1023492 0 0.00 0 23072 10 0 0
srv_make_invnt_saldo 442 442 0 0.00 46 96796 3583 0 0
srv_make_money_saldo 783 783 0 0.00 1 8321 346 0 0
srv_recalc_idx_stat 5 5 0 0.00 68502 419505 250774 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4509 4509 0 0.00 0 19452 848 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 1 12706 422 0 0
t$perf-norm:sp_cancel_client_order 1564 1564 0 0.00 0 2008 26 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 3364 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 222 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 1082 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 1575 34 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 80324 6188 0 0
t$perf-norm:sp_cancel_write_off 885 885 0 0.00 0 1182 17 0 0
t$perf-norm:sp_client_order 18278 18278 0 0.00 0 18598 34 0 0
t$perf-norm:sp_customer_reserve 878 878 0 0.00 0 18523 40 0 0
t$perf-norm:sp_pay_from_customer 6222 6222 0 0.00 0 9298 21 0 0
t$perf-norm:sp_pay_to_supplier 5240 5240 0 0.00 0 3264 17 0 0
t$perf-norm:sp_reserve_write_off 8882 8882 0 0.00 0 11717 23 0 0
t$perf-norm:sp_supplier_invoice 4954 4954 0 0.00 0 6268 29 0 0
t$perf-norm:sp_supplier_order 4810 4810 0 0.00 0 3612 28 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 299 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 252 9 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 519 16 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 3178 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 211 86 0 0
v_add_invoice_to_stock 9156 8940 216 2.35 0 27350 26 0 216
v_all_customers 33826 33826 0 0.00 0 52 3 0 0
v_all_suppliers 27500 27500 0 0.00 0 68 4 0 0
v_all_wares 36556 36556 0 0.00 0 50 3 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 659 13 0 0
v_cancel_client_order 3128 3128 0 0.00 0 1209 9 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 4687 17 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 4368 14 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 4871 19 0 0
v_cancel_supplier_invoice 764 752 12 1.57 0 1769 23 0 12
v_cancel_supplier_order 818 818 0 0.00 0 2713 12 0 0
v_cancel_write_off 1770 1770 0 0.00 0 5812 25 0 0
v_max_id_clo_ord 4810 4810 0 0.00 0 561 4 0 0
v_max_id_clo_res 878 878 0 0.00 0 2822 17 0 0
v_max_id_ord_sup 4951 4951 0 0.00 0 4082 7 0 0
v_max_non_paid_invoice 1251 1251 0 0.00 0 63 7 0 0
v_max_non_paid_realizn 3107 3107 0 0.00 0 2267 7 0 0
v_min_id_clo_ord 4810 4810 0 0.00 0 2380 7 0 0
v_min_id_clo_res 878 878 0 0.00 0 1291 8 0 0
v_min_id_ord_sup 4951 4951 0 0.00 0 8813 11 0 0
v_min_non_paid_invoice 5240 5240 0 0.00 0 8307 14 0 0
v_min_non_paid_realizn 6222 6222 0 0.00 0 4952 12 0 0
v_our_firm 8960 8960 0 0.00 0 53 4 0 0
v_reserve_write_off 17764 17764 0 0.00 0 17139 12 0 0
x_lock_depdocs_on_canc_invoice 11711 11711 0 0.00 0 23105 119 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 2391 305 0 0
x_make_qstorno_1000_1200 4810 4810 0 0.00 3 55960 1218 0 0
x_make_qstorno_1200_2000 4954 4954 0 0.00 3 72506 2617 0 0
x_make_qstorno_2100_3300 426515 426515 0 0.00 0 114293 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-07-2022 04:38:13 to 08-07-2022 04:38:17.

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:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 32560 8192000000 9735827824 10036002816 806953360 8.967 100 89 178 1 836886704 8148848 21784496
02:09:58 20361 8192000000 9759862752 10068885504 835142560 9.251 100 93 186 0 870432208 13018032 22271616
02:11:23 17580 8192000000 9799916752 10121687040 869030656 9.590 100 83 166 0 911987744 22924912 20032176
02:12:46 8024 8192000000 9872224384 10205515776 921651712 10.112 100 79 156 0 982728624 44315248 16761664
02:13:57 3828 8192000000 9918912496 10265579520 963813632 10.526 100 81 155 1 1038975600 58924080 16237888
02:15:04 2361 8192000000 9968400880 10331668480 1004363584 10.921 100 89 171 0 1092200816 70455840 17381392
02:16:09 1894 8192000000 10002503808 10369634304 1025829632 11.128 100 89 171 0 1118636432 75608880 17197920
02:17:13 836 8192000000 10040442992 10409783296 1050866784 11.369 100 94 184 1 1160762208 91805024 18090400
02:18:15 1279 8192000000 10031299008 10408935424 1065413440 11.508 100 97 189 1 1149928064 65862592 18652032
02:19:18 1000 8192000000 10060660432 10443558912 1075869456 11.608 100 91 174 2 1176957136 83731616 17356064
02:20:20 928 8192000000 10046192896 10430091264 1081884800 11.665 100 97 193 1 1169067168 66651680 20530688
02:21:23 906 8192000000 10054772528 10443493376 1088782112 11.731 100 93 177 0 1175744896 68993328 17969456
02:22:25 1103 8192000000 10071847104 10459340800 1093881344 11.780 100 96 187 0 1189472944 77204736 18386864
02:23:27 1074 8192000000 10072320304 10460876800 1100674352 11.844 100 96 186 2 1198238736 78323968 19240416
02:24:29 1132 8192000000 10078989680 10471608320 1102712192 11.863 100 97 190 1 1203287488 82191904 18383392
02:25:31 1034 8192000000 10068886192 10462875648 1105334000 11.888 100 96 178 1 1193057568 68187776 19535792
02:26:33 1595 8192000000 10060686640 10458460160 1101869792 11.855 100 87 171 2 1182214384 63229616 17114976
02:27:41 5220 8192000000 10052152576 10456289280 1099581952 11.834 100 60 120 1 1179422976 68272400 11568624
02:28:47 1172 8192000000 10036691888 10439671808 1095817520 11.798 100 64 128 4 1163058400 54420352 12820528
02:29:49 942 8192000000 10005170016 10393784320 1079840496 11.646 100 74 143 1 1126291088 32815936 13634656
02:30:50 1810 8192000000 10052450800 10448281600 1093479936 11.776 100 79 152 1 1176702288 67688112 15534240
02:31:53 4034 8192000000 9972403728 10352103424 1040562000 11.270 98 64 117 4 1104572352 51862384 12147968
02:32:58 948 8192000000 9972351056 10343055360 1026239552 11.132 100 90 167 2 1095043712 51822464 16981696
02:33:59 1910 8192000000 9954054880 10331471872 1023571920 11.106 99 79 144 5 1078784208 39719984 15492304
02:35:02 1096 8192000000 10007031312 10386575360 1037190576 11.238 100 80 152 2 1129625488 76216112 16218800
02:36:04 1288 8192000000 10015965248 10400501760 1048938160 11.350 100 93 182 4 1148832160 80804448 19089552
02:37:07 866 8192000000 9982241616 10354941952 1043592720 11.299 100 89 164 3 1106388320 45188816 17606784
02:38:15 23363 8192000000 10013863776 10395926528 1060175088 11.458 100 61 120 0 1135501776 63352528 11974160
02:39:44 3708 8192000000 10034069776 10425745408 1081639328 11.663 100 67 123 1 1154028560 60120144 12269088
02:40:51 12931 8192000000 10112456848 10507206656 1107014160 11.904 100 76 149 1 1227241984 104481168 15746656
02:42:13 3831 8192000000 10154870608 10556530688 1114114192 11.971 100 60 115 1 1279333152 153001376 12217584
02:43:24 17680 8192000000 10174382864 10585391104 1127858496 12.101 100 61 116 0 1305135888 164477728 12799664
02:44:46 1129 8192000000 10205949728 10613174272 1140918960 12.224 100 94 179 2 1323160512 162830048 19411504
02:45:51 1130 8192000000 10235797184 10630950912 1152810272 12.336 100 95 185 1 1352329248 179960000 19558976
02:46:54 2193 8192000000 10297777264 10705096704 1167994720 12.478 100 96 187 3 1414460032 224212352 22252960
02:48:02 696 8192000000 10305942400 10706477056 1163275280 12.434 100 92 174 0 1415790208 233083552 19431376
02:49:05 1118 8192000000 10370870816 10767634432 1174080608 12.535 100 88 168 1 1478651920 285349616 19221696
02:50:11 1369 8192000000 10399694896 10804805632 1178042640 12.572 100 97 186 1 1511980944 311525072 22413232
02:51:16 885 8192000000 10433404560 10839842816 1188748944 12.672 100 90 173 0 1542652288 334184512 19718832
02:52:19 1086 8192000000 10441011360 10866409472 1188748800 12.672 100 95 188 0 1549398176 338586976 22062400
02:53:27 1132 8192000000 10454183520 10900758528 1196119616 12.740 100 94 177 1 1561000976 344203264 20678096
02:54:32 979 8192000000 10455823520 10911240192 1193200080 12.713 100 88 171 1 1564462576 350423952 20838544
02:55:36 2413 8192000000 10474998784 10899353600 1193748160 12.718 100 78 149 0 1589224336 376738576 18737600
02:56:42 836 8192000000 10487790528 10931449856 1197304912 12.751 100 95 181 0 1593706176 374860416 21540848
02:57:45 3325 8192000000 10513658976 10955812864 1200112544 12.777 100 72 144 1 1618814192 400167600 18534048
02:58:53 6949 8192000000 10504992224 10937884672 1182857792 12.617 100 60 115 0 1605272224 407194464 15219968
03:00:14 18688 8192000000 10526631296 10980683776 1181335376 12.603 99 46 92 1 1630389216 437312176 11741664
03:01:53 12951 8192000000 10536109008 11038535680 1161212368 12.415 98 43 86 0 1670433600 497741840 11479392
03:03:11 37603 8192000000 10464050256 10918961152 1140440816 12.220 98 44 88 0 1555702352 404816736 10444800
03:04:49 36086 8192000000 10242356448 10766188544 1139592720 12.212 100 12 24 0 1366008272 223126128 3289424
03:06:25 35906 8192000000 10074605008 10494877696 1132107440 12.141 99 53 108 3 1170881632 25394256 13379936
03:08:06 77583 8192000000 10146185760 10581741568 1135926752 12.177 99 68 129 1 1230096064 81551584 12617728
03:10:58 52364 8192000000 10112216128 10549858304 1128220336 12.105 98 26 50 0 1186948064 53912464 4815264
03:12:55 91497 8192000000 10186458496 10631458816 1153453264 12.342 98 31 59 0 1260450960 100640128 6357568
03:15:29 39673 8192000000 10213475328 10681008128 1155559664 12.362 98 19 38 0 1297334640 137021696 4753280
03:17:10 97358 8192000000 10233375168 10686078976 1154701136 12.354 99 40 76 0 1355250528 192113168 8436224
03:19:54 26317 8192000000 10157983568 10671165440 1152274000 12.331 100 16 32 0 1286755360 130943344 3538016
03:21:35 63557 8192000000 10103902016 10553372672 1119706896 12.024 97 24 48 0 1176876528 52232688 4936944
03:23:41 54350 8192000000 10108521216 10562953216 1150569184 12.315 100 20 40 0 1185046368 30342208 4134976
03:25:42 62441 8192000000 10075421552 10515677184 1068928384 11.542 93 16 32 0 1113117936 41114640 3074912
03:27:45 27459 8192000000 10124742784 10541236224 1128791872 12.110 99 74 128 4 1217475712 75442896 13240944
03:29:13 68145 8192000000 10106988144 10613731328 1111513584 11.947 98 18 34 2 1212884880 97620000 3751296
03:31:22 30650 8192000000 10057056544 10488438784 1105117696 11.886 98 28 49 2 1145981472 35025840 5837936
03:32:56 95636 8192000000 10088009472 10538201088 1113886000 11.969 99 50 98 0 1178390192 54801920 9702272
03:35:33 102037 8192000000 10078611024 10557698048 1115391392 11.983 99 5 10 0 1140576656 24437408 747856
03:38:15 31348 8192000000 10094426016 10594095104 1133668576 12.156 100 5 10 0 1196092832 61674576 749680
03:40:32 50619 8192000000 10053862416 10497576960 1102663712 11.863 97 13 24 0 1121397936 16212848 2521376
03:42:23 37673 8192000000 10107753840 10546515968 1139274096 12.209 100 28 56 0 1194148224 48914352 5959776
03:44:02 59365 8192000000 10110706368 10541080576 1126389936 12.087 98 23 45 0 1195384672 63617840 5376896
03:46:02 33928 8192000000 10149151648 10586845184 1139507952 12.211 99 34 68 0 1229806128 82911968 7386208
03:47:39 24468 8192000000 10193818496 10630660096 1163534144 12.436 100 37 74 0 1275592880 103815504 8243232
03:49:05 16191 8192000000 10213304240 10647810048 1143099904 12.245 99 35 68 0 1289062864 138126192 7836768
03:50:22 19931 8192000000 10205051536 10655010816 1164926752 12.449 100 53 106 0 1306186992 129348400 11911840
03:51:52 4801 8192000000 10254354960 10676256768 1157223072 12.377 100 53 106 1 1339638224 171529136 10886016
03:53:00 53612 8192000000 10113269488 10653941760 1127078208 12.094 98 20 38 1 1240085056 109497936 3508912
03:54:57 37368 8192000000 10073512336 10514546688 1139546832 12.211 100 28 54 0 1169255696 24086368 5622496
03:56:35 86785 8192000000 10096958464 10576670720 1146848720 12.280 100 10 20 0 1216943632 67919360 2175552
03:59:04 62660 8192000000 10089821680 10581078016 1135376192 12.172 99 8 16 0 1194766880 58014768 1375920
04:01:08 83027 8192000000 10142870464 10617384960 1108255632 11.916 96 17 34 1 1196720832 84535536 3929664
04:04:40 44113 8192000000 10076353824 10517000192 1106255520 11.897 98 19 38 0 1151373264 41351712 3766032
04:06:29 39789 8192000000 10098688112 10553651200 1125888400 12.083 99 40 78 0 1183899968 49674528 8337040
04:08:11 41080 8192000000 10107829184 10543841280 1106699264 11.901 97 24 45 0 1154124800 42162528 5263008
04:09:53 41492 8192000000 10126970016 10557952000 1116485536 11.994 99 29 58 0 1195466560 72635152 6345872
04:11:36 73582 8192000000 10075589248 10545278976 1108131536 11.915 98 17 34 0 1159934720 48186384 3616800
04:13:50 19695 8192000000 10110500912 10531115008 1095372320 11.794 97 50 100 0 1172436960 67283952 9780688
04:16:34 151400 8192000000 10079131440 10613415936 1079486256 11.643 96 12 23 0 1143621616 62207680 1927680
04:20:36 20178 8192000000 10084072624 10518052864 1131531456 12.136 100 39 77 0 1186011584 47039168 7440960
04:21:57 77086 8192000000 10099997648 10549731328 1129297680 12.115 99 13 26 0 1190824960 58734400 2792880
04:24:14 48520 8192000000 10125755280 10598522880 1128516960 12.107 99 13 27 0 1216802608 85367920 2917728
04:27:02 63591 8192000000 10091133248 10562924544 1100077440 11.838 97 22 44 0 1197278512 92263632 4937440
04:29:21 37987 8192000000 10070925344 10539335680 1115265088 11.982 99 24 45 1 1199666800 79645520 4756192
04:31:01 29871 8192000000 10135099344 10583433216 1144765200 12.260 100 34 66 0 1221977520 69591872 7620448
04:32:39 35495 8192000000 10117900704 10552487936 1120537072 12.032 98 29 56 0 1211971248 84958736 6475440
04:34:23 17815 8192000000 10158293872 10588766208 1135709328 12.175 100 52 103 0 1240382640 94156688 10516624
04:35:48 88680 8192000000 10101808192 10613125120 1135155616 12.170 100 19 38 0 1233743376 94766320 3821440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-07-2022 04:38:19 to 08-07-2022 04:38:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-07-2022 04:38:36 to 08-07-2022 04:38:38.

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          207370
MON$OLDEST_ACTIVE               207371
MON$OLDEST_SNAPSHOT             207371
MON$NEXT_TRANSACTION            207371
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                       2027296
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             754
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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 ---
:88: 
:89: mscs-test-161.budzdorov.ru    Fri Jul  8 04:37:30 2022
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:91: 
--- end of diff output ---

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

08.07.22 04:38:39. 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-07-08 04:38:41 No severe PSQL-related problems occured
08.07.22 04:38:43. End of report.