Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2022 22: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 16443200 kB
MemAvailable 31285192 kB
Buffers 0 kB
Cached 15202088 kB
SwapCached 1200 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.9
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-01-05 22:38:03
DTS_END                         2022-01-05 22:38:03.3080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_PAY_TO_SUPPLIER(18:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57596
TRN_ID                          244722
ATT_ID                          644
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: 9444.48 21153 1133338
customer order: creation 179.29 364 21515
customer order: refuse 15.45 320 1854
order to supplier: creation 47.78 1233 5734
order to supplier: removal 3.60 92540 432
invoice (draft): creation 47.23 3048 5667
invoice (draft): removal 55.00 725 6600
invoice accept: apply 42.11 16900 5053
invoice accept: cancel 49.76 758 5971
customer reserve: creation 3895.48 166 467458
customer reserve: removal 4792.71 55 575125
realization accept: apply 86.03 164 10324
realization accept: cancel 95.30 103 11436
payment from customer: creation 60.65 100 7278
payment from customer: removal 5.76 66 691
payment to supplier: creation 52.01 129 6241
payment to supplier: removal 3.27 78 392
service: total inventory turnovers 5.38 964 646
service: total monetary turnovers 6.90 27 828
service: aggregate perf. data 0.74 22896 89
service: refresh index statistics 0.03 282428 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-01-2022 22:38:59 to 05-01-2022 22:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5051
TEST_TIME 2 4900
TEST_TIME 3 5934
TEST_TIME 4 6079
TEST_TIME 5 5527
TEST_TIME 6 6055
TEST_TIME 7 6273
TEST_TIME 8 6242
TEST_TIME 9 6559
TEST_TIME 10 6614
TEST_TIME 11 6589
TEST_TIME 12 6545
TEST_TIME 13 6585
TEST_TIME 14 6820
TEST_TIME 15 7038
TEST_TIME 16 7168
TEST_TIME 17 7690
TEST_TIME 18 7906
TEST_TIME 19 8529
TEST_TIME 20 8998
TEST_TIME 21 9263
TEST_TIME 22 9278
TEST_TIME 23 9581
TEST_TIME 24 9324
TEST_TIME 25 10117
TEST_TIME 26 10523
TEST_TIME 27 11517
TEST_TIME 28 11321
TEST_TIME 29 11153
TEST_TIME 30 10990
TEST_TIME 31 10928
TEST_TIME 32 10948
TEST_TIME 33 10764
TEST_TIME 34 10824
TEST_TIME 35 10688
TEST_TIME 36 10750
TEST_TIME 37 10544
TEST_TIME 38 10488
TEST_TIME 39 10386
TEST_TIME 40 10324
TEST_TIME 41 10441
TEST_TIME 42 10321
TEST_TIME 43 10325
TEST_TIME 44 10276
TEST_TIME 45 10106
TEST_TIME 46 10060
TEST_TIME 47 10042
TEST_TIME 48 10133
TEST_TIME 49 10109
TEST_TIME 50 10080
TEST_TIME 51 10057
TEST_TIME 52 10090
TEST_TIME 53 10025
TEST_TIME 54 10071
TEST_TIME 55 10050
TEST_TIME 56 10145
TEST_TIME 57 10144
TEST_TIME 58 10214
TEST_TIME 59 10181
TEST_TIME 60 10214
TEST_TIME 61 10193
TEST_TIME 62 10289
TEST_TIME 63 10158
TEST_TIME 64 10104
TEST_TIME 65 10034
TEST_TIME 66 10008
TEST_TIME 67 9918
TEST_TIME 68 9869
TEST_TIME 69 9819
TEST_TIME 70 9775
TEST_TIME 71 9696
TEST_TIME 72 9733
TEST_TIME 73 9709
TEST_TIME 74 9643
TEST_TIME 75 9617
TEST_TIME 76 9603
TEST_TIME 77 9595
TEST_TIME 78 9613
TEST_TIME 79 9597
TEST_TIME 80 9661
TEST_TIME 81 9659
TEST_TIME 82 9687
TEST_TIME 83 9682
TEST_TIME 84 9751
TEST_TIME 85 9870
TEST_TIME 86 9904
TEST_TIME 87 9872
TEST_TIME 88 9782
TEST_TIME 89 9722
TEST_TIME 90 9720
TEST_TIME 91 9644
TEST_TIME 92 9620
TEST_TIME 93 9614
TEST_TIME 94 9614
TEST_TIME 95 9590
TEST_TIME 96 9557
TEST_TIME 97 9631
TEST_TIME 98 9681
TEST_TIME 99 9678
TEST_TIME 100 9672
TEST_TIME 101 9654
TEST_TIME 102 9680
TEST_TIME 103 9674
TEST_TIME 104 9719
TEST_TIME 105 9713
TEST_TIME 106 9731
TEST_TIME 107 9710
TEST_TIME 108 9629
TEST_TIME 109 9582
TEST_TIME 110 9565
TEST_TIME 111 9524
TEST_TIME 112 9464
TEST_TIME 113 9513
TEST_TIME 114 9498
TEST_TIME 115 9531
TEST_TIME 116 9508
TEST_TIME 117 9538
TEST_TIME 118 9536
TEST_TIME 119 9534
TEST_TIME 120 9455

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-01-2022 22:39:03 to 05-01-2022 22:39:06.

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 1567152 1567152 0 0.00 0 36752 6 0 0
doc_list_biud 1567151 1567151 0 0.00 0 27346 25 0 0
sp_add_invoice_to_stock 5187 5053 134 2.58 39 185344 16901 0 134
sp_cancel_adding_invoice 5971 5971 0 0.00 4 77036 758 0 0
sp_cancel_client_order 1854 1854 0 0.00 5 11317 320 0 0
sp_cancel_customer_reserve 575125 575125 0 0.00 1 24013 55 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 2 4681 66 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 5519 79 0 0
sp_cancel_supplier_invoice 6610 6600 10 0.15 1 23047 726 0 10
sp_cancel_supplier_order 432 432 0 0.00 8 1053664 92541 0 0
sp_cancel_write_off 11436 11436 0 0.00 1 10007 104 0 0
sp_client_order 21515 21515 0 0.00 3 39209 365 0 0
sp_customer_reserve 467458 467458 0 0.00 0 56311 166 0 0
sp_fill_shopping_cart 32914 32914 0 0.00 1 14626 37 0 0
sp_fill_shopping_cart_clo_res 467442 467442 0 0.00 0 16015 14 0 0
sp_get_clo_for_invoice 5035 5035 0 0.00 0 18380 323 0 0
sp_kill_cost_storno 18490 18490 0 0.00 0 12471 56 0 0
sp_kill_qstorno_ret_qs2qd 1168718 1168718 0 0.00 0 23914 30 0 0
sp_kill_qty_storno 616777 616777 0 0.00 0 27345 64 0 0
sp_lock_selected_doc 21302 21302 0 0.00 0 11226 7 0 0
sp_make_cost_storno 28878 28878 0 0.00 0 11138 52 0 0
sp_multiply_rows_for_pdistr 28878 28878 0 0.00 0 9167 28 0 0
sp_multiply_rows_for_qdistr 21515 21515 0 0.00 0 16331 175 0 0
sp_pay_from_customer 7278 7278 0 0.00 4 9681 101 0 0
sp_pay_to_supplier 6241 6241 0 0.00 4 11861 130 0 0
sp_payment_common 13519 13519 0 0.00 1 10634 33 0 0
sp_qd_handle_on_cancel_clo 1854 1854 0 0.00 0 8899 287 0 0
sp_qd_handle_on_invoice_upd_sts 11006 11006 0 0.00 0 27343 293 0 0
sp_qd_handle_on_reserve_upd_sts 10324 10324 0 0.00 0 11021 51 0 0
sp_reserve_write_off 10324 10324 0 0.00 6 12732 165 0 0
sp_supplier_invoice 5667 5667 0 0.00 5 80723 3049 0 0
sp_supplier_order 5734 5734 0 0.00 4 48199 1233 0 0
srv_aggregate_perf_data 89 89 0 0.00 2977 302029 22897 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1628 112940 47244 0 0
srv_find_qd_qs_mism 1084669 1084669 0 0.00 0 36752 5 0 0
srv_make_invnt_saldo 646 646 0 0.00 28 88497 965 0 0
srv_make_money_saldo 828 828 0 0.00 1 4101 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 243047 323188 282429 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 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 5054 5054 0 0.00 0 7786 167 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 4316 107 0 0
t$perf-norm:sp_cancel_client_order 1854 1854 0 0.00 0 4739 13 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 1543 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 478 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 9689 41 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1721 13 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 41194 1610 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 1516 7 0 0
t$perf-norm:sp_client_order 21515 21515 0 0.00 0 16008 19 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 2640 7 0 0
t$perf-norm:sp_pay_from_customer 7278 7278 0 0.00 0 17782 13 0 0
t$perf-norm:sp_pay_to_supplier 6241 6241 0 0.00 0 12252 9 0 0
t$perf-norm:sp_reserve_write_off 10324 10324 0 0.00 0 20975 17 0 0
t$perf-norm:sp_supplier_invoice 5667 5667 0 0.00 0 4212 16 0 0
t$perf-norm:sp_supplier_order 5734 5734 0 0.00 0 5449 17 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 495 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 32 1 0 0
t$perf-norm:srv_make_invnt_saldo 646 646 0 0.00 0 3877 22 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 4160 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 10338 10070 268 2.59 0 6354 18 0 268
v_all_customers 39322 39322 0 0.00 0 89 0 0 0
v_all_suppliers 32254 32254 0 0.00 0 17 1 0 0
v_all_wares 43028 43028 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 320 5 0 0
v_cancel_client_order 3708 3708 0 0.00 0 3534 7 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 1631 5 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 1042 4 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 3564 7 0 0
v_cancel_supplier_invoice 778 758 20 2.57 0 2647 19 0 20
v_cancel_supplier_order 864 864 0 0.00 0 2480 9 0 0
v_cancel_write_off 2096 2096 0 0.00 0 2057 12 0 0
v_max_id_clo_ord 5734 5734 0 0.00 0 2798 2 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 3187 18 0 0
v_max_id_ord_sup 5665 5665 0 0.00 0 4895 6 0 0
v_max_non_paid_invoice 1513 1513 0 0.00 0 4049 11 0 0
v_max_non_paid_realizn 3818 3818 0 0.00 0 2264 4 0 0
v_min_id_clo_ord 5734 5734 0 0.00 0 6044 10 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 1213 5 0 0
v_min_id_ord_sup 5665 5665 0 0.00 0 5706 4 0 0
v_min_non_paid_invoice 6241 6241 0 0.00 0 10631 30 0 0
v_min_non_paid_realizn 7278 7278 0 0.00 0 8369 23 0 0
v_our_firm 10626 10626 0 0.00 0 16 1 0 0
v_reserve_write_off 20648 20648 0 0.00 0 9200 4 0 0
x_lock_depdocs_on_canc_invoice 12192 12192 0 0.00 0 26262 109 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 17038 366 0 0
x_make_qstorno_1000_1200 5734 5734 0 0.00 1 48192 1157 0 0
x_make_qstorno_1200_2000 5667 5667 0 0.00 2 80713 2918 0 0
x_make_qstorno_2100_3300 467458 467458 0 0.00 0 56308 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2022 22:39:07 to 05-01-2022 22:39:11.

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:07 19 8192000000 8812099888 8828129280 14478768 0.176 4 3 4 0 14664064 78160 107136
20:09:07 36494 8192000000 9917604736 10230718464 991981328 10.801 100 86 172 0 1020725696 6368416 22375952
20:10:53 28935 8192000000 9955853600 10278043648 1035402832 11.220 100 95 190 0 1068684304 9282416 23999056
20:12:32 18401 8192000000 10010942176 10351710208 1093422784 11.775 100 79 158 0 1122597984 10169024 19006176
20:13:54 12724 8192000000 10051335760 10407501824 1139963280 12.215 100 78 157 0 1169358320 11440448 17954592
20:15:10 8049 8192000000 10112236320 10480119808 1202749296 12.802 100 78 156 0 1233092640 12710528 17632816
20:16:22 3417 8192000000 10155918976 10531082240 1239390592 13.141 100 84 163 1 1269465440 12124800 17950048
20:17:27 3070 8192000000 10191735280 10573987840 1280235584 13.515 100 88 176 0 1313216768 13326720 19654464
20:18:31 1821 8192000000 10217957600 10604736512 1302576608 13.719 100 93 184 3 1336501344 13405584 20519152
20:19:35 2351 8192000000 10206922256 10611994624 1307742160 13.766 100 48 97 0 1329632320 11772720 10117440
20:20:37 11077 8192000000 10202264864 10614571008 1318204592 13.860 100 15 29 1 1327742816 5624224 3914000
20:21:49 70507 8192000000 10206353520 10633043968 1324339600 13.916 100 26 52 0 1336762096 5274048 7148448
20:24:03 7694 8192000000 10242411600 10654445568 1337247632 14.033 100 39 76 0 1355994672 9864624 8882416
20:25:12 6709 8192000000 10235567600 10656710656 1340464304 14.062 100 23 46 1 1351155600 5635536 5055760
20:26:20 25922 8192000000 10221288736 10651684864 1328694800 13.955 99 19 38 0 1338591920 5335600 4561520
20:28:01 7996 8192000000 10229381712 10650996736 1327010784 13.940 99 22 44 0 1338076336 5928560 5136992
20:29:09 10866 8192000000 10170471312 10574082048 1244054160 13.184 96 20 40 0 1254546976 6281072 4211744
20:30:22 8522 8192000000 10132948480 10532974592 1204674464 12.820 96 16 31 0 1213543600 5500432 3368704
20:31:33 58630 8192000000 10115444384 10518994944 1200207328 12.778 97 27 54 0 1212398192 5017616 7173248
20:33:33 7279 8192000000 10170893456 10575638528 1275341440 13.470 100 22 44 1 1286336208 5583856 5410912
20:34:41 12736 8192000000 10178315296 10592333824 1291277552 13.616 100 24 48 1 1302965632 5168608 6519472
20:35:54 18490 8192000000 10199796464 10610290688 1301291584 13.707 100 20 41 0 1311988784 5595472 5101728
20:37:15 20040 8192000000 10194861104 10609881088 1304624896 13.737 100 18 36 0 1313579248 5232864 3721488
20:38:41 16065 8192000000 10233181408 10638196736 1328491776 13.954 100 52 105 2 1348264320 8113040 11659504
20:40:01 28307 8192000000 10251688976 10663530496 1343702032 14.091 100 42 84 1 1365694576 12710864 9281680
20:41:39 16387 8192000000 10267228304 10686488576 1359741504 14.235 100 37 74 1 1383893744 14377920 9774320
20:43:01 78011 8192000000 10296639760 10721705984 1375076912 14.373 100 38 76 0 1402109696 17555120 9477664
20:45:33 13863 8192000000 10303957040 10734796800 1386276768 14.473 100 38 76 1 1417117776 21981184 8859824
20:46:52 10267 8192000000 10304257056 10732822528 1378470608 14.403 99 48 96 1 1405958544 16234528 11253408
20:48:08 6747 8192000000 10338722256 10762366976 1411145712 14.694 100 56 112 1 1448679024 24147728 13385584
20:49:19 3276 8192000000 10364036640 10784636928 1425155408 14.818 100 66 132 0 1469429072 28078864 16194800
20:50:28 8209 8192000000 10361220064 10782654464 1424481072 14.812 100 58 115 0 1469153152 30249456 14422624
20:51:39 4163 8192000000 10380942448 10795442176 1429098224 14.853 100 78 156 0 1486157792 37934368 19125200
20:52:48 8604 8192000000 10368313776 10786230272 1402847808 14.620 99 64 127 0 1462562960 43219488 16495664
20:54:02 3452 8192000000 10344719008 10751025152 1379369520 14.411 99 78 156 0 1444346192 45563104 19413568
20:55:09 10219 8192000000 10355412800 10771013632 1388668080 14.494 100 66 132 4 1459722768 54485456 16569232
20:56:31 1627 8192000000 10354339984 10761285632 1377983088 14.399 100 83 166 3 1457378000 58962992 20431920
20:57:38 6657 8192000000 10342764704 10766028800 1380839856 14.424 100 52 105 1 1450165600 54815648 14510096
20:58:53 6875 8192000000 10347704016 10767282176 1382749632 14.441 100 55 108 2 1449770912 53096512 13924768
21:00:04 20194 8192000000 10329167216 10765021184 1371811920 14.343 99 30 60 3 1432314800 51772992 8729888
21:01:29 17857 8192000000 10332531376 10770366464 1384361152 14.456 100 23 46 1 1440217296 49295376 6560768
21:03:06 26318 8192000000 10304984208 10736893952 1344341440 14.097 99 23 46 0 1413823376 62668896 6813040
21:04:38 40002 8192000000 10271158112 10730778624 1339454304 14.052 99 18 36 2 1388423216 44095856 4873056
21:06:37 14899 8192000000 10278596672 10696142848 1342450864 14.080 99 54 106 1 1361003024 6492256 12059904
21:07:57 72367 8192000000 10302021600 10736406528 1371698192 14.342 99 28 56 0 1384908160 7579632 5630336
21:10:13 103831 8192000000 10267825728 10700251136 1328129504 13.950 97 17 31 0 1341643664 10254064 3260096
21:13:00 61404 8192000000 10285368992 10726649856 1358228416 14.221 99 20 40 0 1376436704 13894208 4314080
21:15:04 18315 8192000000 10265052048 10712375296 1353076528 14.175 99 21 41 0 1364764784 6999680 4688576
21:16:24 39148 8192000000 10273664224 10725302272 1369609920 14.324 100 23 46 0 1387507472 12890144 5007408
21:18:05 32328 8192000000 10314640816 10750775296 1390348976 14.509 100 47 94 0 1415816976 14448000 11020000
21:19:42 28212 8192000000 10292426272 10755563520 1388143056 14.489 100 11 22 0 1404361200 13357584 2860560
21:21:11 27084 8192000000 10281715056 10741764096 1381496608 14.430 100 10 20 0 1397698000 13536640 2664752
21:22:41 58916 8192000000 10297790256 10754850816 1382546432 14.439 99 12 24 0 1402759696 17095840 3117424
21:24:56 81432 8192000000 10288354560 10766839808 1386508944 14.475 100 21 42 0 1421731472 30185952 5036576
21:27:28 39662 8192000000 10291066720 10739597312 1362174896 14.257 98 23 46 0 1376444160 9941152 4328112
21:29:15 56631 8192000000 10291868080 10749886464 1379132048 14.409 99 16 32 0 1393510960 10848608 3530304
21:31:28 40170 8192000000 10279229584 10714861568 1334361296 14.007 97 47 87 4 1353554448 9199824 9993328
21:33:08 61531 8192000000 10273389664 10723065856 1333973104 14.003 97 20 40 0 1347654624 9202608 4478912
21:35:35 38359 8192000000 10278413216 10723983360 1336133856 14.023 97 30 58 0 1351950896 8954144 6862896
21:37:18 85670 8192000000 10275959664 10719940608 1286971680 13.577 93 20 39 0 1303876944 12870160 4035104
21:39:44 55232 8192000000 10271908544 10714865664 1342421808 14.079 99 13 25 1 1366555968 21413072 2721088
21:41:40 76182 8192000000 10248920640 10700455936 1315830144 13.839 96 27 54 0 1336165328 14277952 6057232
21:44:26 56442 8192000000 10232103072 10688638976 1312224496 13.806 97 4 8 0 1321387456 8392384 770576
21:46:25 52648 8192000000 10253531408 10703601664 1327425664 13.944 98 15 30 0 1338698672 7639584 3633424
21:48:18 111002 8192000000 10251778368 10713235456 1330182720 13.969 98 15 30 0 1346793024 13155376 3454928
21:51:18 86659 8192000000 10245202992 10709606400 1339682608 14.055 99 12 24 0 1351025040 8769216 2573216
21:53:49 57223 8192000000 10259801088 10712100864 1336526288 14.026 98 24 48 1 1350685616 9200048 4959280
21:55:53 49416 8192000000 10247968128 10692698112 1267789696 13.401 93 22 44 2 1282055680 9321488 4944496
21:57:46 73827 8192000000 10262082304 10714902528 1328987696 13.958 98 21 42 1 1344438368 10416176 5034496
22:00:00 68721 8192000000 10294852640 10740846592 1377186496 14.391 100 22 44 0 1399271168 17287696 4796976
22:02:57 57604 8192000000 10283668384 10753839104 1363701264 14.271 99 5 10 1 1386249088 21575776 972048
22:05:06 80481 8192000000 10274782176 10721513472 1347873856 14.128 98 32 64 1 1362140176 7496752 6769568
22:08:14 66293 8192000000 10279319184 10740092928 1381909312 14.434 100 17 34 1 1394465248 9117984 3437952
22:10:22 74907 8192000000 10280723392 10747043840 1359373488 14.232 98 7 14 0 1371599040 10565504 1660048
22:12:38 53294 8192000000 10308476560 10763972608 1384350512 14.455 99 23 46 1 1398687136 8709344 5627280
22:14:34 85854 8192000000 10296404000 10769526784 1376234896 14.383 98 19 38 2 1396843472 15856272 4752304
22:17:00 112367 8192000000 10275542944 10731622400 1333963664 14.003 96 28 55 0 1348362624 7921632 6477328
22:19:53 58251 8192000000 10293001968 10749038592 1358723808 14.226 98 27 55 0 1377002448 11995760 6282880
22:21:53 86462 8192000000 10299452560 10739191808 1333090784 13.995 96 20 40 0 1350705120 13237520 4376816
22:24:21 40332 8192000000 10278515872 10715316224 1296471968 13.663 94 27 54 0 1310350560 8005216 5873376
22:26:02 112939 8192000000 10246350816 10690781184 1331328512 13.979 98 27 54 0 1348881440 11776848 5776080
22:30:07 55741 8192000000 10248108832 10708344832 1319489440 13.872 98 10 20 0 1338402032 16393392 2519200
22:32:06 44931 8192000000 10251379456 10704273408 1315930480 13.840 97 17 34 0 1334792352 15277904 3583968
22:33:59 83278 8192000000 10237944640 10692911104 1276984848 13.485 94 19 38 2 1294917104 13752560 4179696
22:36:22 41657 8192000000 10246196192 10687864832 1307942656 13.767 97 36 72 0 1324586544 8372784 8271104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 05-01-2022 22:39:13 to 05-01-2022 22:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 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 05-01-2022 22:39:28 to 05-01-2022 22:39:30.

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          244180
MON$OLDEST_ACTIVE               244911
MON$OLDEST_SNAPSHOT             244911
MON$NEXT_TRANSACTION            244911
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                       2031648
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:496: 
:497: mscs-test-161.budzdorov.ru    Wed Jan  5 22:38:05 2022
:498:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:499: 
:500: 
:501: mscs-test-161.budzdorov.ru    Wed Jan  5 22:38:08 2022
:502:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:503: 
--- end of diff output ---

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

05.01.22 22:39:31. 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-01-05 22:39:34 No severe PSQL-related problems occured
05.01.22 22:39:35. End of report.