Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.01.2023 19:39
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 15386084 kB
MemAvailable 30422180 kB
Buffers 0 kB
Cached 16312840 kB
SwapCached 4336 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-29 19:38:36
DTS_END                         2023-01-29 19:38:36.7490
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(57:17)
IP                              ::1/49774
TRN_ID                          242832
ATT_ID                          689
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: 9309.75 23072 1117170
customer order: creation 171.22 348 20546
customer order: refuse 14.68 331 1761
order to supplier: creation 45.98 1217 5518
order to supplier: removal 3.32 97581 398
invoice (draft): creation 46.61 3189 5593
invoice (draft): removal 52.98 765 6357
invoice accept: apply 43.08 17707 5169
invoice accept: cancel 47.65 773 5718
customer reserve: creation 3935.43 177 472251
customer reserve: removal 4647.79 54 557735
realization accept: apply 83.25 212 9990
realization accept: cancel 87.78 114 10533
payment from customer: creation 59.11 131 7093
payment from customer: removal 5.85 76 702
payment to supplier: creation 49.92 133 5990
payment to supplier: removal 3.39 145 407
service: total inventory turnovers 4.88 1044 585
service: total monetary turnovers 5.97 29 716
service: aggregate perf. data 0.87 21470 104
service: refresh index statistics 0.03 315947 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-01-2023 19:40:00 to 29-01-2023 19:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3665
TEST_TIME 2 3435
TEST_TIME 3 3800
TEST_TIME 4 3749
TEST_TIME 5 4034
TEST_TIME 6 4355
TEST_TIME 7 4829
TEST_TIME 8 4630
TEST_TIME 9 5094
TEST_TIME 10 5424
TEST_TIME 11 5796
TEST_TIME 12 5906
TEST_TIME 13 5905
TEST_TIME 14 6324
TEST_TIME 15 6583
TEST_TIME 16 6936
TEST_TIME 17 7026
TEST_TIME 18 6997
TEST_TIME 19 7773
TEST_TIME 20 7996
TEST_TIME 21 8388
TEST_TIME 22 8452
TEST_TIME 23 8922
TEST_TIME 24 9197
TEST_TIME 25 9765
TEST_TIME 26 9951
TEST_TIME 27 9867
TEST_TIME 28 10349
TEST_TIME 29 10425
TEST_TIME 30 10434
TEST_TIME 31 10170
TEST_TIME 32 10061
TEST_TIME 33 9923
TEST_TIME 34 9829
TEST_TIME 35 9835
TEST_TIME 36 9790
TEST_TIME 37 9730
TEST_TIME 38 9768
TEST_TIME 39 9781
TEST_TIME 40 9616
TEST_TIME 41 9651
TEST_TIME 42 9622
TEST_TIME 43 9504
TEST_TIME 44 9435
TEST_TIME 45 9385
TEST_TIME 46 9366
TEST_TIME 47 9269
TEST_TIME 48 9233
TEST_TIME 49 9229
TEST_TIME 50 9158
TEST_TIME 51 9048
TEST_TIME 52 9221
TEST_TIME 53 9194
TEST_TIME 54 9138
TEST_TIME 55 9162
TEST_TIME 56 9202
TEST_TIME 57 9317
TEST_TIME 58 9324
TEST_TIME 59 9438
TEST_TIME 60 9606
TEST_TIME 61 9581
TEST_TIME 62 9585
TEST_TIME 63 9634
TEST_TIME 64 9632
TEST_TIME 65 9608
TEST_TIME 66 9578
TEST_TIME 67 9456
TEST_TIME 68 9505
TEST_TIME 69 9419
TEST_TIME 70 9428
TEST_TIME 71 9371
TEST_TIME 72 9359
TEST_TIME 73 9327
TEST_TIME 74 9291
TEST_TIME 75 9210
TEST_TIME 76 9190
TEST_TIME 77 9141
TEST_TIME 78 9098
TEST_TIME 79 9130
TEST_TIME 80 9132
TEST_TIME 81 9178
TEST_TIME 82 9134
TEST_TIME 83 9214
TEST_TIME 84 9232
TEST_TIME 85 9266
TEST_TIME 86 9258
TEST_TIME 87 9340
TEST_TIME 88 9356
TEST_TIME 89 9348
TEST_TIME 90 9427
TEST_TIME 91 9430
TEST_TIME 92 9328
TEST_TIME 93 9325
TEST_TIME 94 9306
TEST_TIME 95 9284
TEST_TIME 96 9274
TEST_TIME 97 9259
TEST_TIME 98 9286
TEST_TIME 99 9252
TEST_TIME 100 9287
TEST_TIME 101 9268
TEST_TIME 102 9296
TEST_TIME 103 9373
TEST_TIME 104 9366
TEST_TIME 105 9393
TEST_TIME 106 9425
TEST_TIME 107 9443
TEST_TIME 108 9402
TEST_TIME 109 9497
TEST_TIME 110 9437
TEST_TIME 111 9396
TEST_TIME 112 9386
TEST_TIME 113 9400
TEST_TIME 114 9355
TEST_TIME 115 9344
TEST_TIME 116 9320
TEST_TIME 117 9273
TEST_TIME 118 9266
TEST_TIME 119 9325
TEST_TIME 120 9310

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-01-2023 19:40:03 to 29-01-2023 19:40:07.

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 1554061 1554061 0 0.00 0 41704 6 0 0
doc_list_biud 1554061 1554061 0 0.00 0 130765 25 0 0
sp_add_invoice_to_stock 5293 5169 124 2.34 27 208871 17708 0 124
sp_cancel_adding_invoice 5718 5718 0 0.00 4 45818 774 0 0
sp_cancel_client_order 1761 1761 0 0.00 4 8061 331 0 0
sp_cancel_customer_reserve 557735 557735 0 0.00 0 27462 55 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 2 10269 76 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 35097 145 0 0
sp_cancel_supplier_invoice 6366 6357 9 0.14 1 28823 765 0 9
sp_cancel_supplier_order 398 398 0 0.00 5 1100765 97582 0 0
sp_cancel_write_off 10533 10533 0 0.00 1 130782 115 0 0
sp_client_order 20546 20546 0 0.00 3 19525 349 0 0
sp_customer_reserve 472251 472251 0 0.00 0 83154 178 0 0
sp_fill_shopping_cart 31650 31650 0 0.00 1 11952 38 0 0
sp_fill_shopping_cart_clo_res 472249 472249 0 0.00 0 17201 15 0 0
sp_get_clo_for_invoice 5167 5167 0 0.00 0 15411 305 0 0
sp_kill_cost_storno 17360 17360 0 0.00 0 35080 53 0 0
sp_kill_qstorno_ret_qs2qd 1132758 1132758 0 0.00 0 130764 30 0 0
sp_kill_qty_storno 597659 597659 0 0.00 0 130764 65 0 0
sp_lock_selected_doc 20996 20996 0 0.00 0 10257 7 0 0
sp_make_cost_storno 28240 28240 0 0.00 0 41585 63 0 0
sp_multiply_rows_for_pdistr 28240 28240 0 0.00 0 32571 38 0 0
sp_multiply_rows_for_qdistr 20546 20546 0 0.00 0 12657 168 0 0
sp_pay_from_customer 7093 7093 0 0.00 4 39955 131 0 0
sp_pay_to_supplier 5990 5990 0 0.00 4 40013 134 0 0
sp_payment_common 13083 13083 0 0.00 1 32426 42 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 7782 290 0 0
sp_qd_handle_on_invoice_upd_sts 10885 10885 0 0.00 0 16869 313 0 0
sp_qd_handle_on_reserve_upd_sts 9990 9990 0 0.00 0 12175 63 0 0
sp_reserve_write_off 9990 9990 0 0.00 6 40131 212 0 0
sp_supplier_invoice 5593 5593 0 0.00 6 70183 3190 0 0
sp_supplier_order 5518 5518 0 0.00 4 36683 1217 0 0
srv_aggregate_perf_data 104 104 0 0.00 2641 253476 21471 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1213 126970 46754 0 0
srv_find_qd_qs_mism 1068766 1068766 0 0.00 0 27088 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 42 27627 1045 0 0
srv_make_money_saldo 716 716 0 0.00 1 2864 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 203796 453492 315947 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5169 5169 0 0.00 0 38674 179 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 6554 110 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 4484 18 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 716 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 882 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 295 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 2433 15 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 19015 1473 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 1361 8 0 0
t$perf-norm:sp_client_order 20546 20546 0 0.00 0 6009 15 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 1267 6 0 0
t$perf-norm:sp_pay_from_customer 7093 7093 0 0.00 0 3937 8 0 0
t$perf-norm:sp_pay_to_supplier 5990 5990 0 0.00 0 12594 15 0 0
t$perf-norm:sp_reserve_write_off 9990 9990 0 0.00 0 26926 13 0 0
t$perf-norm:sp_supplier_invoice 5593 5593 0 0.00 0 5764 18 0 0
t$perf-norm:sp_supplier_order 5518 5518 0 0.00 0 3830 14 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 280 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 4252 85 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 5808 30 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 6794 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10582 10334 248 2.34 0 6038 17 0 248
v_all_customers 37430 37430 0 0.00 0 131 1 0 0
v_all_suppliers 30774 30774 0 0.00 0 130 1 0 0
v_all_wares 41092 41092 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 2623 10 0 0
v_cancel_client_order 3522 3522 0 0.00 0 2685 4 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 2322 5 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 2365 7 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 347 3 0 0
v_cancel_supplier_invoice 888 870 18 2.02 0 211 12 0 18
v_cancel_supplier_order 796 796 0 0.00 0 587 3 0 0
v_cancel_write_off 1968 1968 0 0.00 0 2266 16 0 0
v_max_id_clo_ord 5518 5518 0 0.00 0 10654 3 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 5790 14 0 0
v_max_id_ord_sup 5586 5586 0 0.00 0 4250 6 0 0
v_max_non_paid_invoice 1707 1707 0 0.00 0 784 3 0 0
v_max_non_paid_realizn 3812 3812 0 0.00 0 807 3 0 0
v_min_id_clo_ord 5518 5518 0 0.00 0 5320 7 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 3340 10 0 0
v_min_id_ord_sup 5586 5586 0 0.00 0 4285 6 0 0
v_min_non_paid_invoice 5990 5990 0 0.00 0 32419 25 0 0
v_min_non_paid_realizn 7093 7093 0 0.00 0 32270 45 0 0
v_our_firm 10224 10224 0 0.00 0 24 1 0 0
v_reserve_write_off 19980 19980 0 0.00 0 3447 6 0 0
x_lock_depdocs_on_canc_invoice 11640 11640 0 0.00 0 23638 116 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 12779 377 0 0
x_make_qstorno_1000_1200 5518 5518 0 0.00 1 36671 1143 0 0
x_make_qstorno_1200_2000 5593 5593 0 0.00 3 70169 3055 0 0
x_make_qstorno_2100_3300 472251 472251 0 0.00 0 83152 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-01-2023 19:40:08 to 29-01-2023 19:40:12.

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
17:08:40 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:41 26117 8192000000 9890326160 10197549056 978327152 10.668 100 86 172 0 1006928720 5572320 23029248
17:11:12 17059 8192000000 9944400688 10262740992 1026291712 11.133 100 89 176 0 1056200352 7140816 22767824
17:12:36 16629 8192000000 9986806176 10318098432 1062866272 11.484 100 82 161 0 1091525152 8327456 20331424
17:13:59 10077 8192000000 10037026064 10383945728 1126517696 12.089 100 77 154 1 1155406064 10694448 18193920
17:15:12 6414 8192000000 10090213424 10446962688 1171989680 12.515 100 80 160 1 1204009776 13822000 18198096
17:16:21 4340 8192000000 10121142768 10487599104 1210202336 12.871 100 84 167 0 1242600880 13913072 18485472
17:17:27 1691 8192000000 10158275776 10533691392 1245761152 13.199 100 88 175 0 1279007280 13999840 19246288
17:18:31 1541 8192000000 10179433040 10560159744 1270805120 13.429 100 87 174 0 1304439408 13781392 19852896
17:19:34 1916 8192000000 10206568960 10595516416 1296764528 13.666 100 82 161 1 1328028048 13312288 17951232
17:20:38 11804 8192000000 10204185648 10602897408 1307228400 13.761 100 53 107 0 1328055216 8674112 12152704
17:21:51 3892 8192000000 10193002608 10606391296 1308141056 13.769 100 33 66 0 1322892208 7352672 7398480
17:22:55 7145 8192000000 10201980336 10619031552 1314198256 13.824 100 23 46 0 1325002144 5951504 4852384
17:24:04 7571 8192000000 10207021408 10629238784 1325170976 13.924 100 18 36 0 1335011168 5607152 4233040
17:25:13 60841 8192000000 10216992960 10642677760 1330439120 13.971 100 32 65 1 1343134704 4805296 7890288
17:27:21 6341 8192000000 10239667936 10659303424 1338051600 14.040 100 24 48 1 1349082080 5933248 5097232
17:28:28 8162 8192000000 10219742528 10640994304 1319707088 13.874 99 18 36 0 1329193920 5778688 3708144
17:29:37 13996 8192000000 10189516928 10586664960 1244666944 13.189 96 28 55 0 1256499360 5252128 6580288
17:31:08 12733 8192000000 10150561088 10552639488 1233249376 13.084 97 24 48 1 1244566736 6164608 5152752
17:32:22 6055 8192000000 10137072736 10534887424 1236584848 13.115 99 17 35 0 1245941024 5265424 4090752
17:33:32 5171 8192000000 10169981120 10569383936 1270853920 13.429 100 38 77 0 1286228256 6254256 9120080
17:34:37 73146 8192000000 10181847264 10596720640 1294454768 13.645 100 29 56 0 1305856304 5150032 6251504
17:36:51 5028 8192000000 10194365680 10613800960 1307325920 13.762 100 13 26 0 1315531936 4930000 3276016
17:37:57 9178 8192000000 10215137648 10633412608 1321869568 13.894 100 29 59 0 1334223488 5528544 6825376
17:39:13 5900 8192000000 10250709856 10659344384 1346359392 14.115 100 61 121 3 1368222080 8721072 13141616
17:40:24 63555 8192000000 10261438448 10681569280 1357233440 14.213 100 45 90 0 1377794896 10318896 10242560
17:42:33 10536 8192000000 10283501792 10706563072 1372853936 14.353 100 40 81 1 1399382176 17876528 8651712
17:43:46 2755 8192000000 10302359104 10717511680 1384667504 14.458 100 62 122 0 1415449856 17517408 13264944
17:44:53 21149 8192000000 10314327024 10736615424 1399990912 14.595 100 57 111 1 1430136352 17266592 12878848
17:46:25 6181 8192000000 10321991264 10742489088 1397638864 14.574 100 53 105 0 1429452880 19577440 12236576
17:47:34 12916 8192000000 10332312336 10756669440 1410060320 14.684 100 42 83 0 1440044960 20044224 9940416
17:49:03 4910 8192000000 10337352352 10764685312 1413623584 14.716 100 50 98 1 1447765648 21776736 12365328
17:50:13 8454 8192000000 10340607744 10760814592 1398786320 14.584 99 70 138 0 1442301552 27110944 16404288
17:51:24 7821 8192000000 10338400560 10756079616 1390740080 14.512 99 67 134 1 1438244608 30525552 16978976
17:52:37 3489 8192000000 10349424000 10766766080 1408760816 14.673 100 63 127 1 1456278368 32214896 15302656
17:53:45 3866 8192000000 10351574576 10765705216 1405457248 14.644 100 72 142 1 1457210368 34254096 17499024
17:54:56 3421 8192000000 10326645360 10733756416 1378421728 14.402 100 76 151 2 1431986608 34485792 19079088
17:56:06 7763 8192000000 10346505744 10751881216 1389760048 14.504 100 70 140 0 1446701360 39508896 17432416
17:57:29 1198 8192000000 10336517280 10741768192 1362881744 14.263 99 78 156 1 1431777616 48826208 20069664
17:58:34 14333 8192000000 10320321984 10747179008 1370316224 14.330 99 39 76 0 1421322256 40279408 10726624
18:00:00 9314 8192000000 10320368656 10745827328 1373156400 14.355 99 39 77 0 1415701120 32092432 10452288
18:01:10 24910 8192000000 10315643008 10750849024 1373822064 14.361 100 24 48 0 1417086304 35984416 7279824
18:02:56 22637 8192000000 10311009504 10752839680 1372739392 14.352 100 30 60 0 1417760752 36881584 8139776
18:04:24 37993 8192000000 10279402704 10711244800 1334982672 14.012 98 20 38 0 1368189488 27791424 5415392
18:06:03 26389 8192000000 10270611152 10714570752 1342467712 14.080 99 14 28 0 1373441200 27164144 3809344
18:07:36 83229 8192000000 10269844944 10723250176 1345502480 14.107 99 10 20 0 1369592704 21523264 2566960
18:10:19 23095 8192000000 10277066416 10710933504 1346614640 14.117 98 48 97 4 1366490176 7470272 12405264
18:11:49 126970 8192000000 10288897264 10730049536 1327625008 13.946 96 25 51 1 1354205456 20793696 5786752
18:15:00 52950 8192000000 10281257088 10730323968 1377257232 14.392 100 37 75 0 1401792624 16172848 8362544
18:17:00 29482 8192000000 10285363760 10748964864 1382004160 14.434 100 13 27 0 1400356256 14995136 3356960
18:18:51 28942 8192000000 10299478224 10747858944 1390577840 14.511 100 14 28 0 1409049728 14886272 3585616
18:20:21 92429 8192000000 10295190352 10750013440 1386118848 14.471 99 23 46 1 1400318320 8747984 5451488
18:23:03 28663 8192000000 10307445104 10753007616 1395242576 14.553 100 41 81 0 1410581744 6456896 8882272
18:24:35 50814 8192000000 10283268560 10738561024 1388560672 14.493 100 19 38 0 1404175552 11316608 4298272
18:26:27 41721 8192000000 10299829648 10760871936 1402428816 14.617 100 21 42 0 1421438800 13956080 5053904
18:29:03 56423 8192000000 10289954896 10741940224 1366896368 14.299 98 25 50 1 1386760416 14237344 5626704
18:31:03 56370 8192000000 10278586176 10741129216 1373183904 14.356 99 12 24 0 1382489552 6884016 2421632
18:33:00 46150 8192000000 10302517200 10762153984 1393224880 14.535 100 33 66 0 1414234672 13599136 7410656
18:35:11 54416 8192000000 10287615520 10749218816 1369370912 14.321 98 11 22 0 1386864208 14966192 2527104
18:37:16 103507 8192000000 10279896608 10752823296 1330678688 13.973 96 6 12 0 1346914816 15106224 1129904
18:40:07 30122 8192000000 10292643136 10741809152 1368275008 14.312 99 26 52 0 1385202240 11475392 5451840
18:41:48 32593 8192000000 10265196880 10695303168 1286716688 13.574 94 26 52 0 1301128128 9410448 5000992
18:43:21 30707 8192000000 10281156992 10716450816 1349328192 14.141 99 45 90 1 1371667056 12257856 10081008
18:44:54 102449 8192000000 10263693824 10705698816 1330532128 13.972 98 32 64 1 1354217744 16872304 6813312
18:48:28 33358 8192000000 10245939216 10703364096 1356660032 14.207 100 16 33 0 1369693728 9145632 3888064
18:50:06 31186 8192000000 10230737216 10682445824 1335025488 14.013 99 12 24 0 1346730992 8781136 2924368
18:51:38 67558 8192000000 10247453824 10703499264 1354609648 14.189 100 12 24 0 1365337616 8207392 2520576
18:54:12 29360 8192000000 10241059376 10689245184 1341079296 14.067 99 20 40 0 1356820528 11720432 4020800
18:55:42 53071 8192000000 10251747584 10706083840 1306960672 13.758 96 13 25 0 1315800016 6405152 2434192
18:57:40 42018 8192000000 10245268368 10698219520 1335910624 14.021 99 15 30 1 1352766928 13509680 3346624
18:59:23 32939 8192000000 10238213744 10679959552 1318334752 13.862 98 22 44 0 1331593344 7995120 5263472
19:00:56 46265 8192000000 10236589536 10679132160 1320300352 13.879 98 21 42 0 1340458208 15284384 4873472
19:02:44 68554 8192000000 10262483424 10707255296 1338036384 14.040 98 23 46 1 1354140368 11012368 5091616
19:04:56 114429 8192000000 10275103584 10734047232 1351130384 14.158 98 19 38 0 1369189696 13874720 4184592
19:07:51 92131 8192000000 10291628576 10742702080 1346272416 14.114 97 11 22 0 1362623232 13951904 2398912
19:10:24 94002 8192000000 10273809024 10711715840 1363865728 14.272 98 34 68 0 1378330992 7047536 7417728
19:12:58 73914 8192000000 10304043632 10765987840 1390507840 14.510 99 12 24 0 1403657056 10913392 2235824
19:15:14 70886 8192000000 10320286416 10773057536 1389399200 14.501 99 21 43 0 1412672064 18291344 4981520
19:17:27 70022 8192000000 10317293344 10780852224 1390102736 14.507 98 26 46 0 1406925904 10874528 5948640
19:19:37 43335 8192000000 10313551328 10762768384 1390656560 14.512 99 20 39 0 1407883264 12266448 4960256
19:21:24 117951 8192000000 10284580096 10734727168 1322570992 13.900 95 8 16 0 1335512560 11301680 1639888
19:24:29 80402 8192000000 10283030192 10735190016 1296759536 13.666 93 5 10 0 1307688528 9919312 1009680
19:26:59 67620 8192000000 10248208656 10704568320 1299999888 13.695 95 16 32 0 1319606672 15590704 4016080
19:29:06 100430 8192000000 10279206784 10734346240 1351205376 14.158 99 25 50 1 1371274496 14529808 5539312
19:31:59 78375 8192000000 10252806320 10720387072 1336415104 14.025 98 10 20 0 1352732224 14224480 2092640
19:34:17 98988 8192000000 10266217152 10719064064 1320614576 13.882 96 16 32 1 1340002640 15941456 3446608
19:36:59 68076 8192000000 10279082784 10745290752 1360987344 14.246 99 10 20 0 1379789296 16292400 2509552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-01-2023 19:40:14 to 29-01-2023 19:40:29.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-01-2023 19:40:30 to 29-01-2023 19:40:32.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242653
MON$OLDEST_ACTIVE               243607
MON$OLDEST_SNAPSHOT             243607
MON$NEXT_TRANSACTION            243607
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-03 16:41:55.3470
MON$PAGES                       2023232
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:969: 
:970: mscs-test-161.budzdorov.ru    Sun Jan 29 19:38:42 2023
:971:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:972: 
:973: 
:974: mscs-test-161.budzdorov.ru    Sun Jan 29 19:38:43 2023
:975:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:976: 
--- end of diff output ---

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

29.01.23 19:40:33. There are 3 logs in /var/tmp/logs-oltp30 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-29 19:40:36 No severe PSQL-related problems occured
29.01.23 19:40:37. End of report.