Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.04.2022 01: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 16432096 kB
MemAvailable 31493484 kB
Buffers 0 kB
Cached 15241088 kB
SwapCached 2528 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.10
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 2022-04-25 01:38:13
DTS_END                         2022-04-25 01:38:13.5330
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(57:17)
IP                              ::1/41710
TRN_ID                          242173
ATT_ID                          616
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: 9515.58 21324 1141869
customer order: creation 175.77 352 21092
customer order: refuse 15.03 371 1804
order to supplier: creation 46.96 1239 5635
order to supplier: removal 3.54 93463 425
invoice (draft): creation 47.73 3279 5727
invoice (draft): removal 53.68 680 6442
invoice accept: apply 42.59 18063 5111
invoice accept: cancel 48.63 742 5836
customer reserve: creation 3967.28 177 476073
customer reserve: removal 4808.08 54 576969
realization accept: apply 86.07 174 10328
realization accept: cancel 87.17 104 10460
payment from customer: creation 60.56 125 7267
payment from customer: removal 5.92 68 710
payment to supplier: creation 50.51 134 6061
payment to supplier: removal 3.60 48 432
service: total inventory turnovers 4.89 1171 587
service: total monetary turnovers 6.78 31 813
service: aggregate perf. data 0.78 23093 93
service: refresh index statistics 0.03 283116 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-04-2022 01:39:19 to 25-04-2022 01:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4163
TEST_TIME 2 4124
TEST_TIME 3 4570
TEST_TIME 4 5218
TEST_TIME 5 5074
TEST_TIME 6 5238
TEST_TIME 7 5600
TEST_TIME 8 5771
TEST_TIME 9 6056
TEST_TIME 10 6371
TEST_TIME 11 6805
TEST_TIME 12 7132
TEST_TIME 13 7294
TEST_TIME 14 7069
TEST_TIME 15 7179
TEST_TIME 16 7505
TEST_TIME 17 7511
TEST_TIME 18 7625
TEST_TIME 19 7745
TEST_TIME 20 7798
TEST_TIME 21 8112
TEST_TIME 22 8250
TEST_TIME 23 8195
TEST_TIME 24 8712
TEST_TIME 25 9170
TEST_TIME 26 9443
TEST_TIME 27 9769
TEST_TIME 28 9670
TEST_TIME 29 10089
TEST_TIME 30 10507
TEST_TIME 31 10629
TEST_TIME 32 10486
TEST_TIME 33 10412
TEST_TIME 34 10322
TEST_TIME 35 10387
TEST_TIME 36 10300
TEST_TIME 37 10199
TEST_TIME 38 10253
TEST_TIME 39 10201
TEST_TIME 40 10202
TEST_TIME 41 10274
TEST_TIME 42 10197
TEST_TIME 43 10383
TEST_TIME 44 10250
TEST_TIME 45 10086
TEST_TIME 46 10059
TEST_TIME 47 9915
TEST_TIME 48 9850
TEST_TIME 49 9901
TEST_TIME 50 9849
TEST_TIME 51 9822
TEST_TIME 52 9924
TEST_TIME 53 9869
TEST_TIME 54 9791
TEST_TIME 55 9811
TEST_TIME 56 9764
TEST_TIME 57 9718
TEST_TIME 58 9691
TEST_TIME 59 9610
TEST_TIME 60 9664
TEST_TIME 61 9597
TEST_TIME 62 9645
TEST_TIME 63 9655
TEST_TIME 64 9743
TEST_TIME 65 9782
TEST_TIME 66 9699
TEST_TIME 67 9813
TEST_TIME 68 9786
TEST_TIME 69 9794
TEST_TIME 70 9783
TEST_TIME 71 9799
TEST_TIME 72 9837
TEST_TIME 73 9854
TEST_TIME 74 9868
TEST_TIME 75 9830
TEST_TIME 76 9754
TEST_TIME 77 9727
TEST_TIME 78 9717
TEST_TIME 79 9700
TEST_TIME 80 9720
TEST_TIME 81 9671
TEST_TIME 82 9649
TEST_TIME 83 9657
TEST_TIME 84 9566
TEST_TIME 85 9613
TEST_TIME 86 9563
TEST_TIME 87 9543
TEST_TIME 88 9531
TEST_TIME 89 9543
TEST_TIME 90 9538
TEST_TIME 91 9566
TEST_TIME 92 9607
TEST_TIME 93 9662
TEST_TIME 94 9714
TEST_TIME 95 9696
TEST_TIME 96 9743
TEST_TIME 97 9727
TEST_TIME 98 9703
TEST_TIME 99 9755
TEST_TIME 100 9874
TEST_TIME 101 9890
TEST_TIME 102 9822
TEST_TIME 103 9758
TEST_TIME 104 9720
TEST_TIME 105 9705
TEST_TIME 106 9692
TEST_TIME 107 9641
TEST_TIME 108 9596
TEST_TIME 109 9623
TEST_TIME 110 9567
TEST_TIME 111 9570
TEST_TIME 112 9575
TEST_TIME 113 9614
TEST_TIME 114 9581
TEST_TIME 115 9562
TEST_TIME 116 9532
TEST_TIME 117 9591
TEST_TIME 118 9610
TEST_TIME 119 9579
TEST_TIME 120 9517

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-04-2022 01:39:22 to 25-04-2022 01:39:26.

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 1583163 1583163 0 0.00 0 17635 6 0 0
doc_list_biud 1583163 1583163 0 0.00 0 33604 25 0 0
sp_add_invoice_to_stock 5254 5111 143 2.72 41 205904 18064 0 143
sp_cancel_adding_invoice 5836 5836 0 0.00 5 62863 743 0 0
sp_cancel_client_order 1804 1804 0 0.00 4 24668 371 0 0
sp_cancel_customer_reserve 576969 576969 0 0.00 1 33607 55 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 2 4286 69 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 1041 48 0 0
sp_cancel_supplier_invoice 6455 6442 13 0.20 2 18594 681 0 13
sp_cancel_supplier_order 425 425 0 0.00 6 934386 93463 0 0
sp_cancel_write_off 10460 10460 0 0.00 1 10591 105 0 0
sp_client_order 21092 21092 0 0.00 3 38940 353 0 0
sp_customer_reserve 476073 476073 0 0.00 0 53195 178 0 0
sp_fill_shopping_cart 32437 32437 0 0.00 1 19406 35 0 0
sp_fill_shopping_cart_clo_res 476072 476072 0 0.00 0 13737 11 0 0
sp_get_clo_for_invoice 5109 5109 0 0.00 0 19794 274 0 0
sp_kill_cost_storno 17438 17438 0 0.00 0 17606 56 0 0
sp_kill_qstorno_ret_qs2qd 1171265 1171265 0 0.00 0 33590 29 0 0
sp_kill_qty_storno 617372 617372 0 0.00 0 33603 64 0 0
sp_lock_selected_doc 21387 21387 0 0.00 0 7747 4 0 0
sp_make_cost_storno 28764 28764 0 0.00 0 14677 58 0 0
sp_multiply_rows_for_pdistr 28764 28764 0 0.00 0 10829 31 0 0
sp_multiply_rows_for_qdistr 21092 21092 0 0.00 0 13195 143 0 0
sp_pay_from_customer 7267 7267 0 0.00 4 16823 125 0 0
sp_pay_to_supplier 6061 6061 0 0.00 4 21022 134 0 0
sp_payment_common 13328 13328 0 0.00 1 21018 32 0 0
sp_qd_handle_on_cancel_clo 1804 1804 0 0.00 0 24660 356 0 0
sp_qd_handle_on_invoice_upd_sts 10944 10944 0 0.00 0 28706 328 0 0
sp_qd_handle_on_reserve_upd_sts 10328 10328 0 0.00 0 23659 56 0 0
sp_reserve_write_off 10328 10328 0 0.00 7 23673 175 0 0
sp_supplier_invoice 5727 5727 0 0.00 7 97139 3279 0 0
sp_supplier_order 5635 5635 0 0.00 5 34345 1239 0 0
srv_aggregate_perf_data 93 93 0 0.00 2644 283370 23094 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2083 118401 45780 0 0
srv_find_qd_qs_mism 1093039 1093039 0 0.00 0 14015 5 0 0
srv_make_invnt_saldo 587 587 0 0.00 38 113474 1171 0 0
srv_make_money_saldo 813 813 0 0.00 0 7315 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 227284 329097 283117 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5111 5111 0 0.00 0 18467 190 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 1 15954 161 0 0
t$perf-norm:sp_cancel_client_order 1804 1804 0 0.00 0 4895 13 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 7030 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 1319 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 17536 45 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 1495 18 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 25339 1632 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 3898 11 0 0
t$perf-norm:sp_client_order 21092 21092 0 0.00 0 13206 19 0 0
t$perf-norm:sp_customer_reserve 1067 1067 0 0.00 0 2355 6 0 0
t$perf-norm:sp_pay_from_customer 7267 7267 0 0.00 0 5597 10 0 0
t$perf-norm:sp_pay_to_supplier 6061 6061 0 0.00 0 16395 16 0 0
t$perf-norm:sp_reserve_write_off 10328 10328 0 0.00 0 6297 12 0 0
t$perf-norm:sp_supplier_invoice 5727 5727 0 0.00 0 9931 29 0 0
t$perf-norm:sp_supplier_order 5635 5635 0 0.00 0 4931 17 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 150 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 239 9 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 1120 23 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 14161 38 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10502 10216 286 2.72 0 5062 17 0 286
v_all_customers 38486 38486 0 0.00 0 15 1 0 0
v_all_suppliers 31462 31462 0 0.00 0 87 1 0 0
v_all_wares 42184 42184 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 3091 11 0 0
v_cancel_client_order 3608 3608 0 0.00 0 1324 3 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 4278 7 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 2625 9 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 173 3 0 0
v_cancel_supplier_invoice 894 868 26 2.90 0 484 13 0 26
v_cancel_supplier_order 850 850 0 0.00 0 864 4 0 0
v_cancel_write_off 2046 2046 0 0.00 0 5078 14 0 0
v_max_id_clo_ord 5635 5635 0 0.00 0 468 1 0 0
v_max_id_clo_res 1067 1067 0 0.00 0 2553 12 0 0
v_max_id_ord_sup 5710 5710 0 0.00 0 5489 3 0 0
v_max_non_paid_invoice 1675 1675 0 0.00 0 1021 3 0 0
v_max_non_paid_realizn 3965 3965 0 0.00 0 16769 10 0 0
v_min_id_clo_ord 5635 5635 0 0.00 0 7745 6 0 0
v_min_id_clo_res 1067 1067 0 0.00 0 693 3 0 0
v_min_id_ord_sup 5710 5710 0 0.00 0 5228 8 0 0
v_min_non_paid_invoice 6061 6061 0 0.00 0 21013 20 0 0
v_min_non_paid_realizn 7267 7267 0 0.00 0 10906 24 0 0
v_our_firm 10302 10302 0 0.00 0 15 1 0 0
v_reserve_write_off 20656 20656 0 0.00 0 4351 6 0 0
x_lock_depdocs_on_canc_invoice 11844 11844 0 0.00 0 16689 109 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 14842 378 0 0
x_make_qstorno_1000_1200 5635 5635 0 0.00 1 34318 1152 0 0
x_make_qstorno_1200_2000 5727 5727 0 0.00 3 94928 3166 0 0
x_make_qstorno_2100_3300 476073 476073 0 0.00 0 53193 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-04-2022 01:39:27 to 25-04-2022 01:39:31.

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
23:08:17 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:18 26289 8192000000 9895709808 10201411584 971786928 10.604 100 86 172 0 1000795392 5645616 23362848
23:10:50 14300 8192000000 9947017712 10268172288 1028866720 11.158 100 92 184 0 1059996256 7434560 23694976
23:12:19 16830 8192000000 9987545296 10319622144 1066722752 11.521 100 81 162 0 1096348880 9668720 19957408
23:13:49 10992 8192000000 10041482640 10392850432 1133004096 12.150 100 85 170 0 1164791232 11451648 20335488
23:15:06 7306 8192000000 10089500576 10448953344 1175798656 12.551 100 82 164 0 1207537120 13143600 18594864
23:16:16 4829 8192000000 10128682336 10495586304 1212301136 12.890 100 78 156 0 1242832176 13607344 16923696
23:17:25 3131 8192000000 10169828960 10543054848 1253687248 13.272 100 89 176 0 1287220272 14091936 19441088
23:18:30 2133 8192000000 10198634928 10578444288 1280678320 13.519 100 94 185 3 1313722640 12309808 20734512
23:19:34 2154 8192000000 10217686912 10600546304 1298188720 13.679 100 90 180 0 1332073776 13807648 20077408
23:20:37 2883 8192000000 10218224864 10607357952 1300897344 13.703 100 79 158 1 1328285056 8761872 18625840
23:21:40 4242 8192000000 10211564480 10616713216 1312754112 13.811 100 38 76 2 1328504112 6888464 8861536
23:22:48 6845 8192000000 10221181056 10632040448 1326749712 13.938 100 31 63 0 1340982848 6766048 7467088
23:23:57 56133 8192000000 10220393824 10642784256 1333650000 14.000 100 26 52 0 1345825008 6162320 6012688
23:25:54 6318 8192000000 10242122208 10658357248 1345169344 14.104 100 32 64 2 1358431408 6071840 7190224
23:27:02 6077 8192000000 10235413488 10656817152 1334526720 14.008 99 16 32 0 1343558320 5480720 3550880
23:28:11 54692 8192000000 10227741632 10639032320 1313121120 13.814 98 22 44 0 1323661744 5115408 5425216
23:30:08 6171 8192000000 10203333920 10615836672 1310262048 13.788 100 26 52 1 1323722608 7615632 5844928
23:31:15 9706 8192000000 10138483488 10534449152 1214882560 12.914 97 23 46 0 1225660720 5587456 5190704
23:32:25 5705 8192000000 10158150576 10559692800 1257481504 13.307 99 31 62 1 1269996816 4895888 7619424
23:33:31 48721 8192000000 10149772880 10551894016 1231609376 13.069 97 24 46 1 1242149568 4930720 5609472
23:35:22 5583 8192000000 10172665968 10589614080 1287962608 13.586 100 21 42 0 1298110320 5652528 4495184
23:36:28 9525 8192000000 10193811120 10609500160 1303643616 13.728 100 24 48 0 1315167488 5762096 5761776
23:37:39 7272 8192000000 10199434240 10618028032 1314382112 13.826 100 16 32 0 1323416720 5275632 3758976
23:38:49 33164 8192000000 10233190576 10649370624 1330831168 13.975 100 35 70 1 1344617792 5808048 7978576
23:40:33 17460 8192000000 10266931216 10678882304 1355641760 14.198 100 48 96 0 1375618800 9568944 10408096
23:41:56 49607 8192000000 10276373456 10700574720 1357810448 14.218 99 38 76 3 1376861936 10763456 8288032
23:43:48 22671 8192000000 10294774592 10717327360 1382594560 14.440 100 36 72 1 1403643664 12452784 8596320
23:45:24 11341 8192000000 10306369472 10732728320 1394497536 14.546 100 48 96 0 1421017376 14778384 11741456
23:46:39 5505 8192000000 10312209952 10735804416 1388283872 14.491 99 63 126 2 1418001264 14289136 15428256
23:47:50 2949 8192000000 10319498240 10742149120 1400672304 14.601 100 54 104 2 1431425088 18681696 12071088
23:49:03 6381 8192000000 10318390816 10746544128 1369381408 14.322 97 38 76 1 1401152912 22539200 9232304
23:50:14 8527 8192000000 10321089232 10740629504 1389380640 14.500 100 59 117 0 1427207184 23839392 13987152
23:51:30 5043 8192000000 10349710336 10772062208 1414195824 14.721 100 68 133 2 1456485968 26758544 15531600
23:52:44 3146 8192000000 10355673856 10781908992 1421739152 14.788 100 68 135 1 1467639264 29163408 16736704
23:53:54 2199 8192000000 10326123616 10739896320 1382921136 14.443 100 68 137 0 1433281088 33592592 16767360
23:55:04 2083 8192000000 10329039664 10747158528 1389051408 14.497 100 59 114 1 1435970448 32323376 14595664
23:56:10 3139 8192000000 10338046736 10741084160 1348538016 14.134 97 82 162 0 1406923824 38075888 20309920
23:57:19 2954 8192000000 10340357408 10744606720 1376359824 14.384 99 83 165 0 1438634048 41881920 20392304
23:58:27 3490 8192000000 10342247088 10750787584 1380284272 14.419 100 74 148 1 1445312400 46070336 18957792
23:59:34 4559 8192000000 10345504256 10755887104 1379561328 14.413 100 67 134 0 1448834144 51954976 17317840
00:00:54 12250 8192000000 10316626656 10739580928 1366496640 14.296 100 34 68 2 1426128464 50255136 9376688
00:02:22 16128 8192000000 10310253120 10731433984 1352724464 14.172 100 35 70 2 1421840496 58765744 10350288
00:03:49 10584 8192000000 10311916912 10743349248 1363816864 14.272 100 35 68 2 1421942240 48586048 9539328
00:05:02 28814 8192000000 10279341616 10716168192 1336182992 14.023 99 23 46 1 1382686656 39758000 6745664
00:06:32 41658 8192000000 10283178640 10712117248 1346065136 14.112 100 25 49 0 1385487920 33337872 6084912
00:08:18 41252 8192000000 10266981840 10717855744 1342357776 14.079 99 13 24 0 1367747040 21920848 3468416
00:10:00 88592 8192000000 10275993136 10714501120 1347997552 14.129 99 20 39 0 1364674912 12321632 4355728
00:12:35 100047 8192000000 10292193856 10740035584 1372135888 14.346 99 9 18 0 1382991824 9348800 1507136
00:15:34 24493 8192000000 10305835264 10751438848 1397672368 14.574 100 24 48 0 1411175024 7996720 5505936
00:17:09 103246 8192000000 10314079136 10773139456 1364942560 14.282 97 11 22 0 1383042064 15683456 2416048
00:19:52 98638 8192000000 10313509088 10779348992 1375620928 14.377 98 9 18 2 1391296928 13486624 2189376
00:22:36 100844 8192000000 10317967264 10759114752 1390070800 14.506 99 21 42 0 1405417760 10481376 4865584
00:25:19 61877 8192000000 10311636704 10754703360 1387862624 14.487 99 49 98 0 1411849872 12342848 11644400
00:27:23 35420 8192000000 10325720176 10778873856 1401048512 14.604 100 21 42 0 1416400000 10591408 4760080
00:28:59 12083 8192000000 10329107616 10774163456 1395905760 14.559 99 53 104 1 1420703168 12946144 11851264
00:30:11 53876 8192000000 10293902544 10763137024 1396494880 14.564 100 9 18 1 1408792272 10132304 2165088
00:32:05 40386 8192000000 10302199760 10761310208 1401592800 14.609 100 9 18 1 1411544784 7865760 2086224
00:33:46 63557 8192000000 10287361520 10760216576 1392483088 14.528 100 5 10 0 1401764192 8167216 1113888
00:35:59 63257 8192000000 10297360400 10769813504 1391617568 14.520 100 20 40 0 1410185088 13800672 4766848
00:39:13 32003 8192000000 10284058016 10735661056 1317408736 13.853 94 20 40 1 1331884864 10125712 4350416
00:40:45 49809 8192000000 10286090592 10739875840 1336220608 14.023 97 17 34 0 1352829456 13122416 3486432
00:42:36 57356 8192000000 10273427952 10727100416 1302801040 13.721 94 16 32 2 1313697392 7643488 3252864
00:44:44 26003 8192000000 10281189184 10714558464 1339859600 14.056 98 57 114 1 1368199808 16134560 12205648
00:46:19 92255 8192000000 10258071792 10709483520 1259366736 13.324 92 22 44 1 1275554384 11743104 4444544
00:48:56 65836 8192000000 10252444576 10704748544 1304146128 13.733 96 26 52 0 1322163408 12148224 5869056
00:51:04 48297 8192000000 10261432640 10710433792 1333170768 13.996 99 17 32 1 1349487024 12257984 4058272
00:52:53 70407 8192000000 10259911952 10702639104 1339741104 14.055 99 28 54 1 1354449344 9258400 5449840
00:55:03 101212 8192000000 10279375152 10738081792 1364289680 14.276 100 31 62 2 1390278144 18778080 7210384
00:57:46 51856 8192000000 10270727296 10730270720 1337010240 14.030 97 22 43 1 1352860992 11106352 4744400
00:59:52 63668 8192000000 10281647696 10740772864 1379305616 14.410 100 13 26 0 1394721744 12306224 3109904
01:02:02 113412 8192000000 10257521936 10731810816 1341368304 14.070 97 10 20 0 1358293232 14645472 2279456
01:05:06 67636 8192000000 10266688608 10729803776 1364675696 14.279 100 12 22 1 1378130240 10622768 2831776
01:07:20 55747 8192000000 10280632496 10744000512 1365291216 14.285 99 21 42 0 1380115072 10051888 4771968
01:09:17 56936 8192000000 10294005312 10747920384 1351712624 14.163 97 23 46 2 1368051152 11115872 5222656
01:11:21 101134 8192000000 10307984048 10771853312 1369971904 14.327 98 8 16 0 1387359664 15647424 1740336
01:14:14 89858 8192000000 10319863968 10780463104 1399357952 14.589 100 19 38 0 1423511424 20150784 4002688
01:16:46 18326 8192000000 10311773824 10762051584 1378080096 14.399 99 38 76 0 1399685824 13269136 8336592
01:18:07 77146 8192000000 10299489248 10767261696 1374249312 14.365 99 8 16 1 1395316912 18656032 2411568
01:20:24 118400 8192000000 10288401808 10744016896 1346401456 14.115 97 22 44 0 1362985280 11469856 5113968
01:23:32 40420 8192000000 10280978272 10744508416 1367104000 14.301 99 15 28 1 1382490528 12167632 3218896
01:25:13 57557 8192000000 10275137616 10740985856 1374700944 14.369 99 15 30 0 1388300800 10165888 3433968
01:27:11 58777 8192000000 10276240432 10729721856 1319896032 13.876 95 18 36 0 1335428592 10664880 4867680
01:29:28 56188 8192000000 10256297760 10713681920 1331979248 13.985 97 15 30 1 1345926160 10676736 3270176
01:31:25 72216 8192000000 10277498432 10749612032 1359872992 14.236 99 9 18 2 1372973568 11064400 2036176
01:33:42 22613 8192000000 10271360528 10721030144 1305875456 13.749 95 30 60 2 1319126080 5971168 7279456
01:35:22 62209 8192000000 10271210608 10719977472 1361201216 14.248 100 22 44 1 1387704736 21537984 4965536
01:37:29 21375 8192000000 10282748160 10722009088 1350160736 14.149 99 31 62 0 1367207904 10220496 6826672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-04-2022 01:39:32 to 25-04-2022 01:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-04-2022 01:39:48 to 25-04-2022 01:39:50.

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          241477
MON$OLDEST_ACTIVE               244142
MON$OLDEST_SNAPSHOT             244142
MON$NEXT_TRANSACTION            244142
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                       2048352
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1607: 
:1608: mscs-test-161.budzdorov.ru    Mon Apr 25 01:38:15 2022
:1609:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1610: 
:1611: 
:1612: mscs-test-161.budzdorov.ru    Mon Apr 25 01:38:16 2022
:1613:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1614: 
:1615: 
:1616: mscs-test-161.budzdorov.ru    Mon Apr 25 01:38:21 2022
:1617:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1618: 
:1619: 
:1620: mscs-test-161.budzdorov.ru    Mon Apr 25 01:38:22 2022
:1621:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1622: 
:1623: 
:1624: mscs-test-161.budzdorov.ru    Mon Apr 25 01:38:22 2022
:1625:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1626: 
--- end of diff output ---

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

25.04.22 01:39:51. 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
2022-04-25 01:39:54 No severe PSQL-related problems occured
25.04.22 01:39:55. End of report.