Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2022 13: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 15068440 kB
MemAvailable 30531028 kB
Buffers 0 kB
Cached 16662736 kB
SwapCached 2184 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-15 13:37:21
DTS_END                         2022-10-15 13:37:21.4410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40936
TRN_ID                          223788
ATT_ID                          652
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: 9208.84 23354 1105061
customer order: creation 171.27 451 20552
customer order: refuse 15.33 400 1839
order to supplier: creation 44.87 1295 5384
order to supplier: removal 3.33 155641 399
invoice (draft): creation 46.78 3045 5613
invoice (draft): removal 51.18 924 6141
invoice accept: apply 42.44 25940 5093
invoice accept: cancel 45.96 1244 5515
customer reserve: creation 3974.53 242 476944
customer reserve: removal 4512.08 88 541450
realization accept: apply 83.49 288 10019
realization accept: cancel 84.94 164 10193
payment from customer: creation 59.63 195 7156
payment from customer: removal 5.91 139 709
payment to supplier: creation 50.13 200 6015
payment to supplier: removal 3.47 136 416
service: total inventory turnovers 4.22 3781 506
service: total monetary turnovers 8.42 314 1010
service: aggregate perf. data 0.85 15388 102
service: refresh index statistics 0.04 257211 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2022 13:39:09 to 15-10-2022 13:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11157
TEST_TIME 2 9395
TEST_TIME 3 8269
TEST_TIME 4 7750
TEST_TIME 5 7092
TEST_TIME 6 7378
TEST_TIME 7 7095
TEST_TIME 8 7226
TEST_TIME 9 7371
TEST_TIME 10 7257
TEST_TIME 11 6928
TEST_TIME 12 7144
TEST_TIME 13 7157
TEST_TIME 14 7227
TEST_TIME 15 7236
TEST_TIME 16 7260
TEST_TIME 17 7327
TEST_TIME 18 7617
TEST_TIME 19 7687
TEST_TIME 20 7712
TEST_TIME 21 7713
TEST_TIME 22 7661
TEST_TIME 23 7637
TEST_TIME 24 7872
TEST_TIME 25 8082
TEST_TIME 26 8458
TEST_TIME 27 8573
TEST_TIME 28 8900
TEST_TIME 29 9326
TEST_TIME 30 9390
TEST_TIME 31 9890
TEST_TIME 32 10175
TEST_TIME 33 10354
TEST_TIME 34 10632
TEST_TIME 35 10671
TEST_TIME 36 10733
TEST_TIME 37 11104
TEST_TIME 38 10972
TEST_TIME 39 10874
TEST_TIME 40 10713
TEST_TIME 41 10621
TEST_TIME 42 10605
TEST_TIME 43 10756
TEST_TIME 44 10606
TEST_TIME 45 10598
TEST_TIME 46 10600
TEST_TIME 47 10557
TEST_TIME 48 10503
TEST_TIME 49 10363
TEST_TIME 50 10303
TEST_TIME 51 10124
TEST_TIME 52 10127
TEST_TIME 53 10099
TEST_TIME 54 10044
TEST_TIME 55 9925
TEST_TIME 56 9969
TEST_TIME 57 9941
TEST_TIME 58 9847
TEST_TIME 59 9908
TEST_TIME 60 9872
TEST_TIME 61 9878
TEST_TIME 62 9812
TEST_TIME 63 9844
TEST_TIME 64 9745
TEST_TIME 65 9628
TEST_TIME 66 9730
TEST_TIME 67 9691
TEST_TIME 68 9636
TEST_TIME 69 9665
TEST_TIME 70 9644
TEST_TIME 71 9666
TEST_TIME 72 9699
TEST_TIME 73 9731
TEST_TIME 74 9766
TEST_TIME 75 9747
TEST_TIME 76 9734
TEST_TIME 77 9774
TEST_TIME 78 9771
TEST_TIME 79 9783
TEST_TIME 80 9859
TEST_TIME 81 9862
TEST_TIME 82 9774
TEST_TIME 83 9771
TEST_TIME 84 9705
TEST_TIME 85 9625
TEST_TIME 86 9678
TEST_TIME 87 9643
TEST_TIME 88 9631
TEST_TIME 89 9581
TEST_TIME 90 9570
TEST_TIME 91 9496
TEST_TIME 92 9464
TEST_TIME 93 9430
TEST_TIME 94 9415
TEST_TIME 95 9380
TEST_TIME 96 9395
TEST_TIME 97 9402
TEST_TIME 98 9378
TEST_TIME 99 9384
TEST_TIME 100 9428
TEST_TIME 101 9412
TEST_TIME 102 9431
TEST_TIME 103 9407
TEST_TIME 104 9488
TEST_TIME 105 9557
TEST_TIME 106 9537
TEST_TIME 107 9526
TEST_TIME 108 9603
TEST_TIME 109 9606
TEST_TIME 110 9524
TEST_TIME 111 9473
TEST_TIME 112 9468
TEST_TIME 113 9412
TEST_TIME 114 9357
TEST_TIME 115 9348
TEST_TIME 116 9391
TEST_TIME 117 9341
TEST_TIME 118 9313
TEST_TIME 119 9292
TEST_TIME 120 9242

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-10-2022 13:39:13 to 15-10-2022 13:39:16.

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 1543355 1543355 0 0.00 0 22409 14 0 0
doc_list_biud 1543341 1543341 0 0.00 0 24824 37 0 0
sp_add_invoice_to_stock 5243 5093 150 2.86 25 180514 25941 0 150
sp_cancel_adding_invoice 5515 5515 0 0.00 6 119396 1244 0 0
sp_cancel_client_order 1839 1839 0 0.00 7 7261 401 0 0
sp_cancel_customer_reserve 541450 541450 0 0.00 1 23178 89 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 4 4779 140 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 3109 137 0 0
sp_cancel_supplier_invoice 6151 6141 10 0.16 3 18538 925 0 10
sp_cancel_supplier_order 399 399 0 0.00 12 1380821 155641 0 0
sp_cancel_write_off 10193 10193 0 0.00 2 22412 165 0 0
sp_client_order 20552 20552 0 0.00 5 22785 451 0 0
sp_customer_reserve 476944 476944 0 0.00 0 70319 243 0 0
sp_fill_shopping_cart 31538 31538 0 0.00 1 15865 84 0 0
sp_fill_shopping_cart_clo_res 476907 476907 0 0.00 0 13007 16 0 0
sp_get_clo_for_invoice 5046 5046 0 0.00 0 34873 243 0 0
sp_kill_cost_storno 16833 16833 0 0.00 0 22406 76 0 0
sp_kill_qstorno_ret_qs2qd 1099633 1099633 0 0.00 0 22653 38 0 0
sp_kill_qty_storno 580600 580600 0 0.00 0 24819 89 0 0
sp_lock_selected_doc 21026 21026 0 0.00 0 8215 7 0 0
sp_make_cost_storno 28235 28235 0 0.00 0 16218 78 0 0
sp_multiply_rows_for_pdistr 28234 28234 0 0.00 0 21332 46 0 0
sp_multiply_rows_for_qdistr 20550 20550 0 0.00 0 12166 165 0 0
sp_pay_from_customer 7156 7156 0 0.00 5 21345 196 0 0
sp_pay_to_supplier 6015 6015 0 0.00 6 10268 201 0 0
sp_payment_common 13169 13169 0 0.00 2 6777 45 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 7253 322 0 0
sp_qd_handle_on_invoice_upd_sts 10561 10561 0 0.00 0 19932 512 0 0
sp_qd_handle_on_reserve_upd_sts 10017 10017 0 0.00 0 24811 70 0 0
sp_reserve_write_off 10019 10019 0 0.00 8 25092 289 0 0
sp_supplier_invoice 5613 5613 0 0.00 10 101194 3046 0 0
sp_supplier_order 5384 5384 0 0.00 7 33084 1296 0 0
srv_aggregate_perf_data 102 102 0 0.00 2590 135059 15389 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 796 152661 39845 0 0
srv_find_qd_qs_mism 1054844 1054844 0 0.00 0 10073 10 0 0
srv_make_invnt_saldo 506 506 0 0.00 41 90981 3781 0 0
srv_make_money_saldo 1010 1010 0 0.00 1 16779 315 0 0
srv_recalc_idx_stat 5 5 0 0.00 131042 438645 257211 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5097 5097 0 0.00 1 23995 1045 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 7442 542 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 2482 29 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 3850 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 4220 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 6209 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 3019 34 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 98483 6681 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 5577 23 0 0
t$perf-norm:sp_client_order 20553 20553 0 0.00 0 8205 40 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 5621 22 0 0
t$perf-norm:sp_pay_from_customer 7156 7156 0 0.00 0 4198 20 0 0
t$perf-norm:sp_pay_to_supplier 6016 6016 0 0.00 0 11739 23 0 0
t$perf-norm:sp_reserve_write_off 10019 10019 0 0.00 0 17917 26 0 0
t$perf-norm:sp_supplier_invoice 5613 5613 0 0.00 0 5523 32 0 0
t$perf-norm:sp_supplier_order 5384 5384 0 0.00 0 5979 35 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 1044 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 309 13 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 679 15 0 0
t$perf-norm:srv_make_money_saldo 1010 1010 0 0.00 0 918 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 164 58 0 0
v_add_invoice_to_stock 10378 10078 300 2.89 0 10347 30 0 300
v_all_customers 37748 37748 0 0.00 0 69 4 0 0
v_all_suppliers 30948 30948 0 0.00 0 194 4 0 0
v_all_wares 41098 41098 0 0.00 0 150 4 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 3167 27 0 0
v_cancel_client_order 3678 3678 0 0.00 0 5724 15 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 4763 18 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 3166 15 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 3045 17 0 0
v_cancel_supplier_invoice 902 882 20 2.21 0 733 23 0 20
v_cancel_supplier_order 798 798 0 0.00 0 3162 18 0 0
v_cancel_write_off 2062 2062 0 0.00 0 11112 30 0 0
v_max_id_clo_ord 5381 5381 0 0.00 0 151 5 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 965 10 0 0
v_max_id_ord_sup 5606 5606 0 0.00 0 8897 10 0 0
v_max_non_paid_invoice 1527 1527 0 0.00 0 111 8 0 0
v_max_non_paid_realizn 3581 3581 0 0.00 0 485 7 0 0
v_min_id_clo_ord 5381 5381 0 0.00 0 1964 9 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 6881 20 0 0
v_min_id_ord_sup 5606 5606 0 0.00 0 5673 11 0 0
v_min_non_paid_invoice 6014 6014 0 0.00 0 4138 14 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 6771 18 0 0
v_our_firm 10496 10496 0 0.00 0 60 5 0 0
v_reserve_write_off 20034 20034 0 0.00 0 5722 12 0 0
x_lock_depdocs_on_canc_invoice 11215 11215 0 0.00 0 33020 116 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 4614 326 0 0
x_make_qstorno_1000_1200 5384 5384 0 0.00 3 33060 1131 0 0
x_make_qstorno_1200_2000 5613 5613 0 0.00 4 101182 2810 0 0
x_make_qstorno_2100_3300 476941 476941 0 0.00 0 70304 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2022 13:39:17 to 15-10-2022 13:39:21.

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
11:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:25 36982 8192000000 9733077392 10035728384 805417792 8.951 100 86 172 0 834356272 7922688 21015792
11:10:09 18371 8192000000 9778266688 10091061248 854492544 9.445 100 90 180 0 887326064 11382896 21450624
11:11:33 11798 8192000000 9816880720 10140737536 887297712 9.772 100 86 172 0 923701184 16709360 19694112
11:12:47 9552 8192000000 9869945968 10205421568 930205200 10.197 100 86 171 0 982779904 33825584 18749120
11:13:59 3488 8192000000 9933556416 10277797888 963230160 10.521 100 93 181 2 1043751264 61536928 18984176
11:15:06 1582 8192000000 9968596992 10324770816 997327296 10.853 100 85 167 0 1082837120 68676352 16833472
11:16:09 6042 8192000000 10011772576 10370240512 1021334320 11.085 100 94 182 1 1127722272 89351376 17036576
11:17:17 1586 8192000000 9995974272 10368442368 1045047664 11.313 100 85 161 0 1119544192 57653152 16843376
11:18:20 4806 8192000000 10038158032 10410598400 1057167680 11.429 100 85 169 0 1151428992 77655936 16605376
11:19:27 1699 8192000000 10043340576 10418798592 1064877168 11.503 100 97 185 1 1162672736 79944032 17851536
11:20:30 808 8192000000 10040065808 10419732480 1071241808 11.564 100 94 182 2 1160972720 71938160 17792752
11:21:33 1176 8192000000 10058933904 10437320704 1077659760 11.625 100 95 186 0 1180501024 85426864 17414400
11:22:36 1113 8192000000 10056965744 10442305536 1089699056 11.740 100 81 151 0 1177357008 74097296 13560656
11:23:39 1260 8192000000 10061615264 10447691776 1092086704 11.762 100 95 187 0 1187011648 76617536 18307408
11:24:42 1067 8192000000 10060050192 10451976192 1095176832 11.792 100 83 162 0 1180835968 69351248 16307888
11:25:45 1186 8192000000 10067297984 10452234240 1098368512 11.822 100 93 177 2 1183320752 65684464 19267776
11:26:47 4897 8192000000 10063384352 10452828160 1100660800 11.844 100 81 152 0 1191380544 75276848 15442896
11:27:53 1423 8192000000 10078195008 10465595392 1104689312 11.882 100 99 193 0 1197625072 73556544 19379216
11:28:56 1328 8192000000 10063373424 10449760256 1088721232 11.730 100 90 173 2 1182225968 77294448 16210288
11:29:58 1284 8192000000 10045422192 10428129280 1087984816 11.723 100 84 152 5 1164135952 61540272 14610864
11:31:01 945 8192000000 9999837200 10375729152 1052565904 11.385 98 91 173 0 1121323552 51348640 17409008
11:32:04 1371 8192000000 10016969760 10395226112 1057869328 11.436 99 90 170 1 1143033872 68043824 17120720
11:33:07 1520 8192000000 10027248608 10405060608 1056527520 11.423 100 98 178 2 1154013232 78020016 19465696
11:34:09 1080 8192000000 9993743488 10368221184 1044920560 11.312 100 95 173 2 1113316352 51431216 16964576
11:35:13 1268 8192000000 9984382832 10356723712 1028091904 11.150 100 98 177 4 1107555824 61799056 17664864
11:36:16 1286 8192000000 10015868112 10395066368 1051126688 11.371 100 97 191 3 1136351232 65647424 19577120
11:37:20 1089 8192000000 10008586976 10382229504 1040680992 11.271 100 96 179 2 1129585952 69885872 19019088
11:38:26 1236 8192000000 10030703680 10406551552 1053941696 11.398 100 95 186 0 1152350480 79249072 19159712
11:39:34 12275 8192000000 10033309168 10411659264 1065501344 11.509 100 87 158 4 1150774608 70909088 14364176
11:40:49 2973 8192000000 10030572608 10411188224 1068562400 11.538 100 77 141 2 1140918864 59336752 13019712
11:42:03 20993 8192000000 10072534240 10465513472 1086120000 11.706 100 71 141 1 1192039328 91081056 14838272
11:43:44 8936 8192000000 10081759312 10480099328 1095333248 11.793 100 70 135 2 1198975536 89689872 13952416
11:44:56 1006 8192000000 10123977024 10516234240 1108115856 11.915 100 92 178 3 1241265376 113893696 19255824
11:46:01 6206 8192000000 10111006848 10517688320 1116170128 11.991 100 68 131 0 1224922736 95179648 13572960
11:47:11 8017 8192000000 10136382240 10544545792 1130266928 12.124 100 50 96 0 1263014496 122349600 10397968
11:48:24 967 8192000000 10170191920 10567868416 1149839104 12.308 100 78 150 1 1287708672 121842336 16027232
11:49:42 1633 8192000000 10248343408 10646855680 1165523184 12.455 100 95 186 3 1364033168 177403712 21106272
11:50:47 1970 8192000000 10265340528 10668122112 1157875376 12.383 100 82 162 2 1371409792 195291408 18243008
11:51:52 1134 8192000000 10282068656 10677768192 1180569552 12.596 100 93 174 2 1391364704 191478336 19316816
11:52:56 785 8192000000 10329859840 10730942464 1185620352 12.643 100 91 170 2 1440125392 234341232 20163808
11:54:00 789 8192000000 10386172176 10790674432 1182045168 12.609 100 98 189 0 1493394016 289279760 22069088
11:55:03 1002 8192000000 10428584016 10848268288 1198011440 12.758 100 96 189 0 1535343504 315447232 21884832
11:56:07 1167 8192000000 10455819904 10857119744 1209136688 12.861 100 97 186 2 1561442336 330631040 21674608
11:57:13 1019 8192000000 10474688512 10891067392 1209843840 12.868 100 96 188 1 1583889296 350508256 23537200
11:58:18 1976 8192000000 10521585792 10932699136 1216076272 12.925 100 96 185 2 1624907136 387614576 21216288
11:59:22 1264 8192000000 10558942832 10967371776 1218325424 12.946 100 92 174 0 1658904848 419577904 21001520
12:00:26 1262 8192000000 10597255184 11023765504 1201408992 12.789 100 96 191 0 1700603360 476407328 22787040
12:01:30 1126 8192000000 10617726016 11073966080 1197815872 12.756 100 97 186 1 1720238448 498600368 23822208
12:02:35 801 8192000000 10592619120 11052814336 1181320176 12.603 100 94 180 1 1693134832 490283936 21530720
12:03:37 5322 8192000000 10584714272 11061362688 1183038560 12.619 100 56 112 0 1692947200 495419616 14489024
12:04:53 2491 8192000000 10540130912 11009986560 1177104480 12.563 100 70 135 1 1635971424 442005968 16860976
12:05:58 11817 8192000000 10514027616 10972594176 1162144432 12.423 100 45 90 0 1606448576 432824128 11480016
12:07:16 12643 8192000000 10459835408 10888986624 1169427600 12.491 100 64 117 1 1553418416 368684368 15306448
12:08:35 16379 8192000000 10361735840 10877894656 1155905184 12.365 100 36 68 2 1459729264 295109968 8714112
12:09:57 15701 8192000000 10307989136 10792173568 1155215616 12.358 100 27 51 0 1430914928 268681664 7017648
12:11:25 51191 8192000000 10236030496 10710286336 1135158624 12.170 98 20 40 0 1333940528 194082944 4698960
12:13:18 32156 8192000000 10178716352 10679488512 1129091808 12.113 99 28 55 0 1297453344 161819552 6541984
12:14:52 77728 8192000000 10058432032 10531205120 1100119968 11.839 97 15 31 0 1173866576 70407824 3338784
12:17:11 36354 8192000000 10094976816 10527166464 1143652144 12.250 100 30 57 2 1189221088 40578000 4990944
12:18:59 54055 8192000000 10131998288 10608054272 1133263792 12.152 99 21 42 0 1224669344 85968976 5436576
12:20:53 152660 8192000000 10065323312 10534977536 1066885472 11.522 93 12 22 0 1122419664 53346928 2187264
12:24:26 111646 8192000000 10095930880 10556186624 1088817424 11.731 95 16 30 0 1113606016 21878624 2909968
12:27:19 122536 8192000000 10095046400 10562854912 1108825472 11.921 98 39 76 1 1177645680 61334704 7485504
12:30:48 122556 8192000000 10078861488 10496425984 1059924496 11.456 94 45 88 2 1128366384 57611472 10830416
12:33:51 53377 8192000000 10092720688 10548686848 1111859792 11.950 99 21 42 0 1184299792 67643472 4796528
12:35:47 9293 8192000000 10119239504 10554839040 1124727104 12.072 100 62 122 1 1215387376 76055712 14604560
12:36:57 67937 8192000000 10133998544 10602852352 1134870368 12.167 100 24 48 0 1203181648 63430080 4881200
12:39:09 77069 8192000000 10090797824 10594861056 1128430880 12.107 99 10 20 0 1226120912 95479136 2210896
12:41:28 108970 8192000000 10095295856 10616713216 1118859680 12.016 99 21 42 0 1188649680 65113056 4676944
12:44:50 41269 8192000000 10072783952 10521665536 1130276832 12.124 100 12 24 0 1164385632 31764512 2344288
12:46:49 35991 8192000000 10107026688 10549055488 1122688016 12.052 99 41 82 0 1184094528 52674464 8732048
12:48:27 85904 8192000000 10097817264 10562543616 1062510464 11.481 93 22 44 0 1095329232 28402224 4416544
12:51:01 44636 8192000000 10079356752 10541604864 1113245248 11.963 99 40 78 2 1166290176 45355776 7689152
12:52:47 44641 8192000000 10088184272 10521890816 1104256832 11.878 98 32 64 0 1164498592 53806720 6435040
12:54:32 102688 8192000000 10106333200 10546982912 1069166352 11.544 94 17 34 0 1111684960 38844480 3674128
12:57:15 88370 8192000000 10066862432 10541318144 1093122384 11.772 97 11 22 0 1166610656 71191280 2296992
12:59:44 94030 8192000000 10088936240 10574180352 1099018560 11.828 98 35 66 2 1168020352 60741136 8260656
13:02:18 40613 8192000000 10117645248 10573312000 1126480576 12.088 100 34 67 0 1213155744 79210880 7464288
13:03:59 69390 8192000000 10067536240 10557911040 1095199312 11.792 97 13 22 0 1151704016 53915312 2589392
13:06:09 39943 8192000000 10053258320 10496032768 1097038576 11.810 97 24 48 0 1129466656 27228592 5199488
13:07:49 56509 8192000000 10072244176 10543448064 1117925824 12.007 99 17 34 0 1167280272 45857792 3496656
13:09:48 85704 8192000000 10033867440 10496008192 1089843248 11.741 97 15 27 2 1113073264 20879680 2350336
13:12:15 52279 8192000000 10080150560 10522796032 1061397680 11.470 95 25 47 1 1108696720 42579616 4719424
13:14:11 58682 8192000000 10040539664 10481135616 1050190928 11.363 94 25 44 0 1086999440 32631184 4177328
13:16:15 22992 8192000000 10083897744 10518056960 1092373072 11.765 98 42 83 1 1162811872 62506928 7931872
13:17:44 43039 8192000000 10079759968 10501128192 1104422128 11.880 99 38 76 1 1155975664 44461040 7092496
13:19:27 44808 8192000000 10121180704 10579574784 1123123952 12.056 99 41 83 0 1194622848 63596752 7902144
13:21:14 62450 8192000000 10120982704 10610413568 1123830352 12.063 99 24 48 0 1235553408 106238528 5484528
13:23:17 39919 8192000000 10121122736 10557775872 1130488560 12.126 99 41 82 1 1202925008 63627968 8808480
13:24:58 25048 8192000000 10174152912 10629095424 1136641552 12.184 100 31 62 0 1271936288 128997600 6297136
13:26:23 104326 8192000000 10059232144 10502606848 1121020032 12.037 99 17 34 0 1168385152 43897216 3467904
13:29:22 68056 8192000000 10087443072 10546515968 1104110848 11.877 97 21 42 0 1180709328 72187856 4410624
13:32:16 81017 8192000000 10115258528 10616152064 1128179936 12.104 100 11 22 0 1226074480 95247104 2647440
13:34:43 16636 8192000000 10078796176 10531172352 1138736912 12.204 100 29 56 1 1178332064 33427824 6167328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-10-2022 13:39:23 to 15-10-2022 13:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2022 13:39:40 to 15-10-2022 13:39:42.

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          224087
MON$OLDEST_ACTIVE               224088
MON$OLDEST_SNAPSHOT             224088
MON$NEXT_TRANSACTION            224088
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                       2080672
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2859 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2859 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2859 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2859 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 ---
--- end of diff output ---

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

15.10.22 13:39:43. 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-10-15 13:39:46 No severe PSQL-related problems occured
15.10.22 13:39:47. End of report.