Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2022 22: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 15441252 kB
MemAvailable 30526124 kB
Buffers 0 kB
Cached 16294864 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 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 2022-10-14 22:38:33
DTS_END                         2022-10-14 22:38:33.7480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(50:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45150
TRN_ID                          243164
ATT_ID                          726
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: 9388.68 22140 1126642
customer order: creation 176.01 345 21121
customer order: refuse 14.79 353 1775
order to supplier: creation 46.09 1150 5531
order to supplier: removal 3.43 94212 412
invoice (draft): creation 47.02 3048 5642
invoice (draft): removal 53.99 698 6479
invoice accept: apply 42.91 17745 5149
invoice accept: cancel 48.19 766 5783
customer reserve: creation 3909.76 177 469171
customer reserve: removal 4744.76 54 569371
realization accept: apply 83.96 177 10075
realization accept: cancel 85.31 109 10237
payment from customer: creation 60.73 131 7288
payment from customer: removal 6.15 97 738
payment to supplier: creation 50.40 120 6048
payment to supplier: removal 3.36 98 403
service: total inventory turnovers 5.07 1159 608
service: total monetary turnovers 5.97 38 716
service: aggregate perf. data 0.76 22852 91
service: refresh index statistics 0.03 299477 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-10-2022 22:40:03 to 14-10-2022 22:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1629
TEST_TIME 2 1854
TEST_TIME 3 4089
TEST_TIME 4 3829
TEST_TIME 5 4128
TEST_TIME 6 4462
TEST_TIME 7 4999
TEST_TIME 8 4947
TEST_TIME 9 5585
TEST_TIME 10 5972
TEST_TIME 11 6063
TEST_TIME 12 6056
TEST_TIME 13 6193
TEST_TIME 14 6270
TEST_TIME 15 6424
TEST_TIME 16 6476
TEST_TIME 17 7268
TEST_TIME 18 7260
TEST_TIME 19 7648
TEST_TIME 20 8163
TEST_TIME 21 8221
TEST_TIME 22 8755
TEST_TIME 23 9193
TEST_TIME 24 9405
TEST_TIME 25 9554
TEST_TIME 26 10087
TEST_TIME 27 10939
TEST_TIME 28 10945
TEST_TIME 29 10975
TEST_TIME 30 10739
TEST_TIME 31 10736
TEST_TIME 32 10610
TEST_TIME 33 10418
TEST_TIME 34 10230
TEST_TIME 35 10298
TEST_TIME 36 10238
TEST_TIME 37 10156
TEST_TIME 38 10113
TEST_TIME 39 9985
TEST_TIME 40 10042
TEST_TIME 41 10189
TEST_TIME 42 10147
TEST_TIME 43 10106
TEST_TIME 44 10021
TEST_TIME 45 10108
TEST_TIME 46 10001
TEST_TIME 47 9961
TEST_TIME 48 9806
TEST_TIME 49 9660
TEST_TIME 50 9629
TEST_TIME 51 9594
TEST_TIME 52 9509
TEST_TIME 53 9521
TEST_TIME 54 9585
TEST_TIME 55 9560
TEST_TIME 56 9523
TEST_TIME 57 9551
TEST_TIME 58 9584
TEST_TIME 59 9603
TEST_TIME 60 9684
TEST_TIME 61 9682
TEST_TIME 62 9803
TEST_TIME 63 9814
TEST_TIME 64 9787
TEST_TIME 65 9842
TEST_TIME 66 9848
TEST_TIME 67 9772
TEST_TIME 68 9660
TEST_TIME 69 9660
TEST_TIME 70 9624
TEST_TIME 71 9631
TEST_TIME 72 9577
TEST_TIME 73 9540
TEST_TIME 74 9489
TEST_TIME 75 9449
TEST_TIME 76 9418
TEST_TIME 77 9476
TEST_TIME 78 9502
TEST_TIME 79 9505
TEST_TIME 80 9499
TEST_TIME 81 9506
TEST_TIME 82 9512
TEST_TIME 83 9597
TEST_TIME 84 9580
TEST_TIME 85 9635
TEST_TIME 86 9564
TEST_TIME 87 9649
TEST_TIME 88 9728
TEST_TIME 89 9779
TEST_TIME 90 9706
TEST_TIME 91 9627
TEST_TIME 92 9588
TEST_TIME 93 9550
TEST_TIME 94 9509
TEST_TIME 95 9526
TEST_TIME 96 9546
TEST_TIME 97 9532
TEST_TIME 98 9529
TEST_TIME 99 9533
TEST_TIME 100 9552
TEST_TIME 101 9573
TEST_TIME 102 9560
TEST_TIME 103 9547
TEST_TIME 104 9582
TEST_TIME 105 9579
TEST_TIME 106 9641
TEST_TIME 107 9650
TEST_TIME 108 9670
TEST_TIME 109 9650
TEST_TIME 110 9595
TEST_TIME 111 9575
TEST_TIME 112 9552
TEST_TIME 113 9529
TEST_TIME 114 9506
TEST_TIME 115 9462
TEST_TIME 116 9497
TEST_TIME 117 9502
TEST_TIME 118 9452
TEST_TIME 119 9440
TEST_TIME 120 9394

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-10-2022 22:40:07 to 14-10-2022 22:40:11.

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 1562437 1562437 0 0.00 0 26998 6 0 0
doc_list_biud 1562437 1562437 0 0.00 0 25176 25 0 0
sp_add_invoice_to_stock 5284 5149 135 2.55 30 180080 17746 0 135
sp_cancel_adding_invoice 5783 5783 0 0.00 4 61696 767 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 8500 354 0 0
sp_cancel_customer_reserve 569371 569371 0 0.00 0 19661 55 0 0
sp_cancel_pay_from_customer 738 738 0 0.00 3 7385 97 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 2963 98 0 0
sp_cancel_supplier_invoice 6489 6479 10 0.15 1 15855 698 0 10
sp_cancel_supplier_order 412 412 0 0.00 5 1017151 94213 0 0
sp_cancel_write_off 10237 10237 0 0.00 1 25252 110 0 0
sp_client_order 21121 21121 0 0.00 3 16523 345 0 0
sp_customer_reserve 469171 469171 0 0.00 0 92630 178 0 0
sp_fill_shopping_cart 32290 32290 0 0.00 1 15493 35 0 0
sp_fill_shopping_cart_clo_res 469170 469170 0 0.00 0 20743 14 0 0
sp_get_clo_for_invoice 5142 5142 0 0.00 0 25610 325 0 0
sp_kill_cost_storno 17161 17161 0 0.00 0 7797 54 0 0
sp_kill_qstorno_ret_qs2qd 1155870 1155870 0 0.00 0 25175 29 0 0
sp_kill_qty_storno 609274 609274 0 0.00 0 25176 64 0 0
sp_lock_selected_doc 21137 21137 0 0.00 0 8219 7 0 0
sp_make_cost_storno 28553 28553 0 0.00 0 13858 51 0 0
sp_multiply_rows_for_pdistr 28553 28553 0 0.00 0 8700 27 0 0
sp_multiply_rows_for_qdistr 21121 21121 0 0.00 0 15593 162 0 0
sp_pay_from_customer 7288 7288 0 0.00 4 12651 131 0 0
sp_pay_to_supplier 6048 6048 0 0.00 4 8383 120 0 0
sp_payment_common 13336 13336 0 0.00 1 11112 33 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 8492 324 0 0
sp_qd_handle_on_invoice_upd_sts 10925 10925 0 0.00 0 19978 299 0 0
sp_qd_handle_on_reserve_upd_sts 10075 10075 0 0.00 0 21693 61 0 0
sp_reserve_write_off 10075 10075 0 0.00 6 21711 177 0 0
sp_supplier_invoice 5642 5642 0 0.00 6 90917 3049 0 0
sp_supplier_order 5531 5531 0 0.00 4 47927 1151 0 0
srv_aggregate_perf_data 91 91 0 0.00 2408 404838 22852 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1390 113447 44911 0 0
srv_find_qd_qs_mism 1078619 1078619 0 0.00 0 26996 5 0 0
srv_make_invnt_saldo 608 608 0 0.00 33 130612 1159 0 0
srv_make_money_saldo 716 716 0 0.00 1 6871 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 121680 471322 299478 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 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 5149 5149 0 0.00 0 20134 187 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 3971 98 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 4568 13 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 2115 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 738 738 0 0.00 0 1982 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 684 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 2212 21 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 41835 1734 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 1303 6 0 0
t$perf-norm:sp_client_order 21121 21121 0 0.00 0 19953 17 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 3096 6 0 0
t$perf-norm:sp_pay_from_customer 7288 7288 0 0.00 0 7642 9 0 0
t$perf-norm:sp_pay_to_supplier 6048 6048 0 0.00 0 17765 15 0 0
t$perf-norm:sp_reserve_write_off 10075 10075 0 0.00 0 16545 15 0 0
t$perf-norm:sp_supplier_invoice 5642 5642 0 0.00 0 9412 20 0 0
t$perf-norm:sp_supplier_order 5531 5531 0 0.00 0 8622 18 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 387 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 595 11 0 0
t$perf-norm:srv_make_invnt_saldo 608 608 0 0.00 0 609 15 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 773 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 3 3 0 0
v_add_invoice_to_stock 10554 10284 270 2.55 0 13372 17 0 270
v_all_customers 38736 38736 0 0.00 0 21 1 0 0
v_all_suppliers 31310 31310 0 0.00 0 84 1 0 0
v_all_wares 42242 42242 0 0.00 0 136 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 365 6 0 0
v_cancel_client_order 3550 3550 0 0.00 0 7313 7 0 0
v_cancel_customer_prepayment 1476 1476 0 0.00 0 7294 8 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 6198 8 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 2937 8 0 0
v_cancel_supplier_invoice 910 890 20 2.19 0 13833 39 0 20
v_cancel_supplier_order 824 824 0 0.00 0 1912 6 0 0
v_cancel_write_off 2054 2054 0 0.00 0 2040 13 0 0
v_max_id_clo_ord 5531 5531 0 0.00 0 147 1 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 4535 19 0 0
v_max_id_ord_sup 5638 5638 0 0.00 0 4457 6 0 0
v_max_non_paid_invoice 1562 1562 0 0.00 0 2784 5 0 0
v_max_non_paid_realizn 3870 3870 0 0.00 0 2784 6 0 0
v_min_id_clo_ord 5531 5531 0 0.00 0 2386 5 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 1709 6 0 0
v_min_id_ord_sup 5638 5638 0 0.00 0 6132 5 0 0
v_min_non_paid_invoice 6048 6048 0 0.00 0 7042 21 0 0
v_min_non_paid_realizn 7288 7288 0 0.00 0 9202 27 0 0
v_our_firm 10342 10342 0 0.00 0 14 1 0 0
v_reserve_write_off 20150 20150 0 0.00 0 13376 7 0 0
x_lock_depdocs_on_canc_invoice 11817 11817 0 0.00 0 22179 119 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 11452 339 0 0
x_make_qstorno_1000_1200 5531 5531 0 0.00 1 47888 1081 0 0
x_make_qstorno_1200_2000 5642 5642 0 0.00 2 79442 2922 0 0
x_make_qstorno_2100_3300 469171 469171 0 0.00 0 92628 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2022 22:40:12 to 14-10-2022 22:40:16.

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
20:08:37 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:37 27533 8192000000 9906140608 10212970496 986169728 10.744 100 90 180 0 1015713232 5937968 23605536
20:11:10 16411 8192000000 9953232768 10275213312 1036027808 11.226 100 91 182 0 1067301568 7419728 23854032
20:12:32 13396 8192000000 9977986992 10307260416 1060553744 11.462 100 85 170 1 1091355856 9380640 21421472
20:13:51 8198 8192000000 10030747648 10377793536 1116603360 11.995 100 81 162 0 1146634624 10390464 19640800
20:15:03 7204 8192000000 10088114128 10447290368 1174401104 12.538 100 75 150 0 1204387072 12577936 17408032
20:16:13 2775 8192000000 10143585952 10509664256 1224983088 13.008 100 86 173 1 1258584560 14240800 19360672
20:17:18 2632 8192000000 10161827056 10535501824 1246758288 13.208 100 82 164 0 1277945728 12808592 18378848
20:18:22 2682 8192000000 10175413760 10561421312 1271549248 13.436 100 68 136 0 1298518416 12159488 14809680
20:19:26 844 8192000000 10228530016 10615439360 1309502624 13.782 100 89 170 0 1341572144 13365168 18704352
20:20:28 4310 8192000000 10212940240 10617774080 1315376928 13.835 100 45 89 0 1334596928 9521440 9698560
20:21:35 8832 8192000000 10210813744 10623459328 1320978704 13.886 100 22 42 1 1331291024 5987664 4324656
20:22:45 8171 8192000000 10210384544 10630062080 1327926656 13.948 100 25 51 1 1340460240 6719120 5814464
20:23:55 72124 8192000000 10231575056 10655965184 1338834320 14.047 100 33 67 0 1352635552 5650432 8150800
20:26:07 5874 8192000000 10237031696 10661015552 1344485808 14.098 100 22 44 0 1356116480 7020512 4610160
20:27:13 9289 8192000000 10237074720 10657173504 1332842144 13.993 99 16 32 1 1341926192 5766000 3318048
20:28:24 22901 8192000000 10202653616 10611134464 1260252384 13.332 94 17 34 1 1270316256 5386464 4677408
20:30:23 6631 8192000000 10171855536 10582855680 1255265152 13.287 97 28 57 0 1267783424 6192192 6326080
20:31:33 7900 8192000000 10122639264 10516140032 1207633888 12.847 96 29 58 1 1219671264 5313200 6724176
20:32:41 11110 8192000000 10128772864 10528628736 1191987424 12.702 95 17 34 1 1200561760 4818320 3756016
20:33:52 51303 8192000000 10159595184 10568773632 1251025504 13.248 98 32 64 0 1264212560 5437840 7749216
20:35:44 12268 8192000000 10185351600 10602737664 1299238448 13.688 100 26 52 0 1311151968 5775200 6138320
20:36:58 6657 8192000000 10196221360 10610704384 1303191552 13.724 100 23 47 0 1318015920 9609328 5215040
20:38:05 22204 8192000000 10214848576 10629480448 1317411280 13.853 100 21 41 0 1327379456 4874768 5093408
20:39:55 30367 8192000000 10247168016 10662731776 1338140128 14.041 100 36 71 1 1352621616 6437344 8044144
20:41:39 10101 8192000000 10260532064 10681503744 1355904272 14.201 100 40 81 0 1376685072 11222384 9558416
20:42:52 11860 8192000000 10294498016 10710650880 1380221408 14.419 100 54 108 0 1406780848 14125984 12433456
20:44:10 24680 8192000000 10297998544 10722578432 1385160016 14.463 100 47 94 0 1412633632 16067392 11406224
20:45:38 3357 8192000000 10314467904 10736074752 1397165472 14.570 100 49 98 0 1426050416 17103184 11781760
20:46:45 9470 8192000000 10331100720 10758410240 1403777200 14.629 100 39 78 0 1435032768 21902464 9353104
20:47:58 4709 8192000000 10331233648 10756009984 1406821248 14.656 100 50 100 0 1443841872 24956256 12064368
20:49:11 10966 8192000000 10345789248 10771677184 1412972000 14.710 100 56 111 0 1454782752 28299088 13511664
20:50:36 5205 8192000000 10352648496 10774364160 1412854768 14.709 100 56 112 0 1458905280 32877824 13172688
20:51:45 1753 8192000000 10371238032 10780217344 1414773616 14.726 100 81 161 0 1472933328 38513696 19646016
20:52:53 1585 8192000000 10360600176 10773680128 1406834608 14.656 100 76 151 0 1467890016 41505856 19549552
20:54:00 6437 8192000000 10336762976 10744606720 1370733776 14.334 99 72 144 2 1433602608 44606832 18262000
20:55:13 1474 8192000000 10352522112 10756554752 1381827968 14.433 100 83 165 0 1451584496 49747648 20008880
20:56:20 3958 8192000000 10332980432 10744705024 1373236736 14.356 100 72 143 0 1438527504 47437584 17853184
20:57:27 1389 8192000000 10345866848 10759548928 1370738560 14.334 100 71 138 2 1447134352 58126992 18268800
20:58:35 11098 8192000000 10316585840 10739765248 1357493824 14.215 99 51 100 2 1420114592 49442576 13178192
20:59:56 19042 8192000000 10322995984 10746626048 1355179664 14.194 99 41 82 1 1424248496 57648176 11420656
21:01:20 23842 8192000000 10299448288 10733154304 1330741584 13.974 97 27 54 0 1384289616 45890704 7657328
21:02:46 15798 8192000000 10290259424 10719899648 1347919408 14.129 99 23 46 0 1397039440 42477344 6642688
21:04:23 37720 8192000000 10276603152 10720280576 1338215248 14.041 99 16 32 0 1378439488 35573104 4651136
21:06:01 31320 8192000000 10269044704 10708992000 1340415616 14.061 99 14 30 0 1364743808 20867296 3460896
21:07:38 74512 8192000000 10269689760 10699124736 1330160192 13.969 97 17 33 0 1345356720 12269536 2926992
21:09:54 104718 8192000000 10280967920 10733703168 1362875088 14.263 99 17 34 0 1381012624 14516048 3621488
21:12:56 71695 8192000000 10281456960 10736087040 1347508272 14.125 98 22 44 0 1366758624 14590656 4659696
21:15:21 24075 8192000000 10276510256 10716807168 1349432384 14.142 99 32 64 0 1364479152 7952320 7094448
21:16:48 32875 8192000000 10317875136 10753064960 1386048640 14.471 100 45 87 1 1415739024 19634208 10056176
21:18:22 27314 8192000000 10300306976 10751963136 1377057312 14.390 100 37 74 0 1412319680 25677280 9585088
21:20:21 36061 8192000000 10285166624 10747289600 1376073232 14.381 100 15 28 0 1396599440 16814768 3711440
21:21:58 52822 8192000000 10294778144 10751488000 1385108240 14.462 100 21 42 0 1403621696 13795216 4718240
21:23:51 31892 8192000000 10291733104 10751049728 1383202160 14.445 100 14 26 0 1396534704 9938128 3394416
21:25:23 49584 8192000000 10273898992 10739814400 1372211936 14.347 99 4 8 0 1381001376 8040480 748960
21:27:13 57589 8192000000 10292345200 10749706240 1391025328 14.515 100 22 44 0 1404055344 8255568 4774448
21:29:16 67873 8192000000 10303904576 10758115328 1381783200 14.432 99 19 38 0 1405853088 19619760 4450128
21:31:26 76920 8192000000 10290152480 10758221824 1380680960 14.423 99 13 26 0 1395317840 11740912 2895968
21:33:50 59115 8192000000 10299009824 10757984256 1360732880 14.244 97 25 48 0 1376852048 10665456 5453712
21:35:55 66484 8192000000 10296488336 10748928000 1349969280 14.147 97 14 28 0 1369291520 16160320 3161920
21:38:16 45519 8192000000 10285290816 10727657472 1333516496 13.999 96 29 58 0 1349266240 9428528 6321216
21:40:02 109979 8192000000 10281742752 10720108544 1299346736 13.689 94 7 14 0 1314145344 13036992 1761616
21:42:54 32898 8192000000 10269103408 10708082688 1329786752 13.965 98 35 63 1 1351293888 14762096 6745040
21:44:28 53218 8192000000 10262615952 10694717440 1300064032 13.696 96 32 62 0 1321611632 14277104 7270496
21:46:59 60673 8192000000 10250833792 10701860864 1335380656 14.016 99 21 41 0 1353346448 13804896 4160896
21:49:00 49704 8192000000 10246527552 10706132992 1328458800 13.953 98 22 44 1 1342638928 9391728 4788400
21:50:49 36329 8192000000 10229622640 10683584512 1321882752 13.894 98 9 18 0 1329878400 5999264 1996384
21:52:26 71953 8192000000 10262061648 10715901952 1357678256 14.217 100 22 44 0 1373717456 11040176 4999024
21:55:38 21223 8192000000 10274930528 10718212096 1359418448 14.232 100 27 52 0 1378107952 12250064 6439440
21:57:03 70580 8192000000 10263084256 10713841664 1342023504 14.076 98 17 34 0 1357010208 10894944 4091760
21:59:15 47471 8192000000 10284425856 10734206976 1364430592 14.277 99 16 32 0 1381902816 13734544 3737680
22:01:11 97730 8192000000 10280787488 10733662208 1354583472 14.189 98 12 22 1 1370991984 13693264 2715248
22:03:57 56292 8192000000 10284859232 10716467200 1354300608 14.186 100 34 65 0 1371627504 9616480 7710416
22:05:54 102723 8192000000 10287028160 10764869632 1342476272 14.080 98 18 36 0 1365630112 19234512 3919328
22:08:36 113447 8192000000 10285751600 10736914432 1367460464 14.304 99 24 48 1 1385228144 12286640 5481040
22:11:30 71139 8192000000 10288719008 10751021056 1364881376 14.281 98 13 26 0 1383171936 15447104 2843456
22:13:56 75797 8192000000 10301166608 10769850368 1400290528 14.598 100 19 38 0 1415815424 11536480 3988416
22:16:24 71011 8192000000 10304062256 10767421440 1375219808 14.374 98 22 44 0 1390315184 10381056 4714320
22:18:36 47479 8192000000 10307628096 10766667776 1386039456 14.471 99 21 42 0 1403623792 12913136 4671200
22:20:24 33261 8192000000 10305402336 10755715072 1373237568 14.356 98 22 42 0 1389586656 11904944 4444144
22:22:00 90961 8192000000 10274079680 10731241472 1283748048 13.547 93 12 24 0 1296745104 10521296 2475760
22:24:40 66951 8192000000 10293515392 10760851456 1359929696 14.237 98 10 20 0 1379186880 17079888 2177296
22:26:49 17669 8192000000 10287909392 10735439872 1362265536 14.258 98 29 58 0 1379195696 10036560 6893600
22:28:07 106035 8192000000 10295305152 10740834304 1362340464 14.258 98 23 46 0 1379605696 11625600 5639632
22:30:56 17004 8192000000 10287208112 10734702592 1359659536 14.234 99 44 88 1 1384725792 15213856 9852400
22:32:14 85733 8192000000 10284507952 10745278464 1318119520 13.860 95 19 38 0 1343671776 21315632 4236624
22:35:12 56099 8192000000 10242365744 10708250624 1330132000 13.968 98 11 22 0 1349549024 16897648 2519376
22:37:33 65733 8192000000 9813152496 10202091520 907165200 9.969 67 8 16 0 914680736 5414480 2101056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2022 22:40:18 to 14-10-2022 22:40:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 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 14-10-2022 22:40:34 to 14-10-2022 22:40:36.

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          243354
MON$OLDEST_ACTIVE               243355
MON$OLDEST_SNAPSHOT             243355
MON$NEXT_TRANSACTION            243355
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                       2036384
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:360: 
:361: mscs-test-161.budzdorov.ru    Fri Oct 14 22:38:45 2022
:362:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:363: 
:364: 
:365: mscs-test-161.budzdorov.ru    Fri Oct 14 22:38:45 2022
:366:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:367: 
:368: 
:369: mscs-test-161.budzdorov.ru    Fri Oct 14 22:38:45 2022
:370:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:371: 
--- end of diff output ---

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

14.10.22 22:40:37. 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-10-14 22:40:39 No severe PSQL-related problems occured
14.10.22 22:40:41. End of report.