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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-15 16:38:48
DTS_END                         2023-03-15 16:38:48.1010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33744
TRN_ID                          249309
ATT_ID                          684
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: 9465.87 23571 1135904
customer order: creation 178.51 364 21421
customer order: refuse 15.37 322 1844
order to supplier: creation 46.61 1194 5593
order to supplier: removal 3.51 108077 421
invoice (draft): creation 46.49 3021 5579
invoice (draft): removal 55.19 849 6623
invoice accept: apply 43.07 17767 5168
invoice accept: cancel 49.88 741 5985
customer reserve: creation 3902.43 180 468292
customer reserve: removal 4817.95 62 578154
realization accept: apply 87.25 174 10470
realization accept: cancel 84.47 109 10136
payment from customer: creation 62.31 91 7477
payment from customer: removal 5.74 59 689
payment to supplier: creation 51.00 106 6120
payment to supplier: removal 3.77 74 452
service: total inventory turnovers 4.83 969 579
service: total monetary turnovers 6.69 31 803
service: aggregate perf. data 0.78 25416 94
service: refresh index statistics 0.03 311809 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-03-2023 16:40:17 to 15-03-2023 16:40:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 568
TEST_TIME 2 2026
TEST_TIME 3 3146
TEST_TIME 4 3727
TEST_TIME 5 3644
TEST_TIME 6 3935
TEST_TIME 7 4316
TEST_TIME 8 4361
TEST_TIME 9 5024
TEST_TIME 10 5083
TEST_TIME 11 5547
TEST_TIME 12 6104
TEST_TIME 13 6377
TEST_TIME 14 6527
TEST_TIME 15 6804
TEST_TIME 16 7095
TEST_TIME 17 7222
TEST_TIME 18 7316
TEST_TIME 19 7896
TEST_TIME 20 8111
TEST_TIME 21 8118
TEST_TIME 22 8766
TEST_TIME 23 9087
TEST_TIME 24 9098
TEST_TIME 25 8892
TEST_TIME 26 9505
TEST_TIME 27 9876
TEST_TIME 28 10748
TEST_TIME 29 11092
TEST_TIME 30 10931
TEST_TIME 31 10635
TEST_TIME 32 10410
TEST_TIME 33 10706
TEST_TIME 34 10670
TEST_TIME 35 10454
TEST_TIME 36 10423
TEST_TIME 37 10470
TEST_TIME 38 10321
TEST_TIME 39 10385
TEST_TIME 40 10440
TEST_TIME 41 10329
TEST_TIME 42 10242
TEST_TIME 43 10258
TEST_TIME 44 10149
TEST_TIME 45 9972
TEST_TIME 46 10019
TEST_TIME 47 9966
TEST_TIME 48 9912
TEST_TIME 49 9758
TEST_TIME 50 9801
TEST_TIME 51 9782
TEST_TIME 52 9774
TEST_TIME 53 9897
TEST_TIME 54 9910
TEST_TIME 55 9890
TEST_TIME 56 10044
TEST_TIME 57 10018
TEST_TIME 58 10058
TEST_TIME 59 10028
TEST_TIME 60 10036
TEST_TIME 61 10098
TEST_TIME 62 10205
TEST_TIME 63 10076
TEST_TIME 64 9968
TEST_TIME 65 10006
TEST_TIME 66 9907
TEST_TIME 67 9906
TEST_TIME 68 9817
TEST_TIME 69 9875
TEST_TIME 70 9812
TEST_TIME 71 9696
TEST_TIME 72 9754
TEST_TIME 73 9790
TEST_TIME 74 9770
TEST_TIME 75 9755
TEST_TIME 76 9692
TEST_TIME 77 9661
TEST_TIME 78 9750
TEST_TIME 79 9840
TEST_TIME 80 9816
TEST_TIME 81 9812
TEST_TIME 82 9834
TEST_TIME 83 9805
TEST_TIME 84 9826
TEST_TIME 85 9866
TEST_TIME 86 9854
TEST_TIME 87 9855
TEST_TIME 88 9780
TEST_TIME 89 9746
TEST_TIME 90 9717
TEST_TIME 91 9709
TEST_TIME 92 9727
TEST_TIME 93 9640
TEST_TIME 94 9605
TEST_TIME 95 9596
TEST_TIME 96 9616
TEST_TIME 97 9678
TEST_TIME 98 9659
TEST_TIME 99 9706
TEST_TIME 100 9682
TEST_TIME 101 9707
TEST_TIME 102 9651
TEST_TIME 103 9688
TEST_TIME 104 9759
TEST_TIME 105 9756
TEST_TIME 106 9730
TEST_TIME 107 9721
TEST_TIME 108 9715
TEST_TIME 109 9633
TEST_TIME 110 9663
TEST_TIME 111 9616
TEST_TIME 112 9636
TEST_TIME 113 9597
TEST_TIME 114 9564
TEST_TIME 115 9546
TEST_TIME 116 9539
TEST_TIME 117 9512
TEST_TIME 118 9481
TEST_TIME 119 9472
TEST_TIME 120 9467

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-03-2023 16:40:21 to 15-03-2023 16:40:24.

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 1570192 1570192 0 0.00 0 14860 6 0 0
doc_list_biud 1570192 1570192 0 0.00 0 62602 29 0 0
sp_add_invoice_to_stock 5288 5168 120 2.26 42 224344 17768 0 120
sp_cancel_adding_invoice 5985 5985 0 0.00 5 67445 742 0 0
sp_cancel_client_order 1844 1844 0 0.00 4 11048 322 0 0
sp_cancel_customer_reserve 578154 578154 0 0.00 0 62607 62 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 3189 60 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 2 4103 75 0 0
sp_cancel_supplier_invoice 6634 6623 11 0.16 1 22987 850 0 11
sp_cancel_supplier_order 421 421 0 0.00 8 1065613 108078 0 0
sp_cancel_write_off 10136 10136 0 0.00 1 11750 110 0 0
sp_client_order 21421 21421 0 0.00 3 52915 365 0 0
sp_customer_reserve 468292 468292 0 0.00 0 122087 181 0 0
sp_fill_shopping_cart 32587 32587 0 0.00 1 11694 32 0 0
sp_fill_shopping_cart_clo_res 468289 468289 0 0.00 0 21405 14 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 42528 311 0 0
sp_kill_cost_storno 17262 17262 0 0.00 0 11748 54 0 0
sp_kill_qstorno_ret_qs2qd 1173488 1173488 0 0.00 0 62591 34 0 0
sp_kill_qty_storno 618796 618796 0 0.00 0 62602 73 0 0
sp_lock_selected_doc 21658 21658 0 0.00 0 15383 5 0 0
sp_make_cost_storno 29230 29230 0 0.00 0 14858 46 0 0
sp_multiply_rows_for_pdistr 29230 29230 0 0.00 0 7172 23 0 0
sp_multiply_rows_for_qdistr 21421 21421 0 0.00 0 52892 148 0 0
sp_pay_from_customer 7477 7477 0 0.00 4 9096 92 0 0
sp_pay_to_supplier 6120 6120 0 0.00 4 10584 107 0 0
sp_payment_common 13597 13597 0 0.00 1 6716 22 0 0
sp_qd_handle_on_cancel_clo 1844 1844 0 0.00 0 11039 302 0 0
sp_qd_handle_on_invoice_upd_sts 11148 11148 0 0.00 0 19860 321 0 0
sp_qd_handle_on_reserve_upd_sts 10470 10470 0 0.00 0 18535 69 0 0
sp_reserve_write_off 10470 10470 0 0.00 6 18587 175 0 0
sp_supplier_invoice 5579 5579 0 0.00 6 83273 3022 0 0
sp_supplier_order 5593 5593 0 0.00 5 31145 1195 0 0
srv_aggregate_perf_data 94 94 0 0.00 2743 478980 25416 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2542 123480 44807 0 0
srv_find_qd_qs_mism 1087129 1087129 0 0.00 0 11783 6 0 0
srv_make_invnt_saldo 579 579 0 0.00 42 26574 969 0 0
srv_make_money_saldo 803 803 0 0.00 1 3024 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 176535 484680 311809 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5168 5168 0 0.00 0 11680 134 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 3866 102 0 0
t$perf-norm:sp_cancel_client_order 1844 1844 0 0.00 0 3788 13 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 2930 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 7367 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 2118 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 2950 17 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 22010 1480 0 0
t$perf-norm:sp_cancel_write_off 1072 1072 0 0.00 0 1206 5 0 0
t$perf-norm:sp_client_order 21421 21421 0 0.00 0 9838 13 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 2321 11 0 0
t$perf-norm:sp_pay_from_customer 7477 7477 0 0.00 0 9909 13 0 0
t$perf-norm:sp_pay_to_supplier 6120 6120 0 0.00 0 7148 11 0 0
t$perf-norm:sp_reserve_write_off 10470 10470 0 0.00 0 4571 10 0 0
t$perf-norm:sp_supplier_invoice 5579 5579 0 0.00 0 9340 13 0 0
t$perf-norm:sp_supplier_order 5593 5593 0 0.00 0 2852 11 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 377 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 154 4 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 1034 18 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 1311 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10566 10326 240 2.27 0 3882 16 0 240
v_all_customers 39384 39384 0 0.00 0 32 1 0 0
v_all_suppliers 31256 31256 0 0.00 0 32 1 0 0
v_all_wares 42842 42842 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 1084 7 0 0
v_cancel_client_order 3688 3688 0 0.00 0 3706 5 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 1673 7 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 1498 5 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 169 3 0 0
v_cancel_supplier_invoice 902 880 22 2.43 0 1841 17 0 22
v_cancel_supplier_order 842 842 0 0.00 0 3616 9 0 0
v_cancel_write_off 2144 2144 0 0.00 0 4207 21 0 0
v_max_id_clo_ord 5593 5593 0 0.00 0 3035 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 1613 16 0 0
v_max_id_ord_sup 5573 5573 0 0.00 0 6956 6 0 0
v_max_non_paid_invoice 1658 1658 0 0.00 0 463 2 0 0
v_max_non_paid_realizn 3974 3974 0 0.00 0 682 3 0 0
v_min_id_clo_ord 5593 5593 0 0.00 0 2127 4 0 0
v_min_id_clo_res 989 989 0 0.00 0 2253 8 0 0
v_min_id_ord_sup 5573 5573 0 0.00 0 8182 7 0 0
v_min_non_paid_invoice 6120 6120 0 0.00 0 5017 15 0 0
v_min_non_paid_realizn 7477 7477 0 0.00 0 5241 15 0 0
v_our_firm 10464 10464 0 0.00 0 20 1 0 0
v_reserve_write_off 20940 20940 0 0.00 0 3703 6 0 0
x_lock_depdocs_on_canc_invoice 12168 12168 0 0.00 0 14087 110 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 6173 326 0 0
x_make_qstorno_1000_1200 5593 5593 0 0.00 1 31136 1131 0 0
x_make_qstorno_1200_2000 5579 5579 0 0.00 2 82929 2908 0 0
x_make_qstorno_2100_3300 468292 468292 0 0.00 0 122086 164 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 15-03-2023 16:40:25 to 15-03-2023 16:40:30.

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
14:08:52 11 8192000000 8806492528 8820203520 11690288 0.142 3 3 4 0 11874192 76800 107104
14:09:52 34599 8192000000 9917148256 10225774592 994077520 10.821 100 96 192 0 1024992992 5999824 24915648
14:11:33 19558 8192000000 9954321056 10277982208 1036671072 11.233 100 92 184 1 1069094848 9218752 23205024
14:13:08 9629 8192000000 9989869456 10321596416 1074669312 11.597 100 87 174 0 1106386592 10338528 21378752
14:14:32 7368 8192000000 10051390880 10401636352 1138102752 12.198 100 74 148 0 1166606160 11339312 17164096
14:15:44 5146 8192000000 10086099824 10447024128 1176238960 12.555 100 73 146 0 1205273248 12017424 17016864
14:16:56 3379 8192000000 10139471904 10508255232 1223700880 12.996 100 81 158 1 1253692464 12888560 17103024
14:18:00 5349 8192000000 10166634112 10551197696 1261993472 13.348 100 69 137 0 1289561424 12922832 14645120
14:19:08 1061 8192000000 10211481056 10592186368 1295200400 13.652 100 88 174 0 1328526640 13477184 19849056
14:20:10 3146 8192000000 10192074784 10595270656 1298942064 13.686 100 58 116 0 1320136432 6827120 14367248
14:21:14 4281 8192000000 10200871712 10606215168 1307288560 13.761 100 41 79 0 1322525136 6913888 8322688
14:22:20 8342 8192000000 10206300928 10616635392 1319138976 13.869 100 28 56 0 1331362304 5833088 6390240
14:23:31 7154 8192000000 10206763520 10624716800 1327015712 13.940 100 24 49 0 1337393568 5139296 5238560
14:24:42 37697 8192000000 10218729552 10637443072 1329744096 13.965 100 27 52 0 1345414160 9716400 5953664
14:26:22 5167 8192000000 10220448160 10638315520 1328409984 13.953 100 21 43 0 1339041520 5834736 4796800
14:27:29 7456 8192000000 10205654608 10625826816 1308007824 13.768 99 17 34 0 1317875344 5729776 4137744
14:28:36 52771 8192000000 10175162416 10576752640 1257166880 13.304 96 30 60 0 1269469296 4546032 7756384
14:30:33 9537 8192000000 10159889088 10565214208 1240181536 13.148 96 13 27 0 1248196512 5307104 2707872
14:31:42 6401 8192000000 10102960768 10500464640 1169065840 12.488 94 25 51 0 1180505952 5767984 5672128
14:32:49 8621 8192000000 10155836960 10554949632 1244703728 13.190 98 14 28 0 1252509488 4842928 2962832
14:34:01 39958 8192000000 10161670224 10577350656 1267166608 13.396 99 41 80 1 1286887568 9955328 9765632
14:35:44 5072 8192000000 10186012912 10596057088 1297488528 13.672 100 32 65 1 1309395168 4805504 7101136
14:36:50 7014 8192000000 10196730864 10614263808 1310232096 13.788 100 19 36 3 1319317456 5521120 3564240
14:38:00 8034 8192000000 10220801376 10632732672 1325853392 13.930 100 20 39 1 1335142224 4534368 4754464
14:39:09 77410 8192000000 10253253648 10670002176 1348264608 14.132 100 43 88 0 1366718432 8643376 9810448
14:41:38 23113 8192000000 10283092608 10701983744 1362426976 14.259 99 45 88 0 1386620704 14267776 9925952
14:43:17 15781 8192000000 10294888896 10716475392 1384178752 14.454 100 36 72 2 1414012576 20692864 9140960
14:45:00 12117 8192000000 10318553648 10738036736 1399066752 14.587 100 56 112 0 1432376368 20644544 12665072
14:46:15 4355 8192000000 10319926144 10738561024 1392457344 14.528 100 57 112 1 1431161680 25745168 12959168
14:47:23 39180 8192000000 10321524384 10750648320 1401824592 14.611 100 38 74 0 1437250096 25751504 9674000
14:49:05 10934 8192000000 10331747232 10753085440 1391363840 14.518 99 60 120 0 1437089040 30738656 14986544
14:50:22 4577 8192000000 10327063296 10743685120 1393645856 14.538 100 74 147 0 1440665504 29416608 17603040
14:51:31 4071 8192000000 10326482416 10743541760 1379191312 14.409 99 62 119 0 1423634240 29673712 14769216
14:52:39 4514 8192000000 10337504096 10755383296 1384526016 14.457 99 58 116 0 1438324656 39563264 14235376
14:53:52 4149 8192000000 10350400720 10762850304 1395308496 14.553 99 69 134 1 1454247536 41644928 17294112
14:55:00 3562 8192000000 10336868256 10753425408 1387648720 14.485 100 58 113 0 1444025840 41171072 15206048
14:56:10 4307 8192000000 10345004368 10754592768 1388233648 14.490 100 72 145 0 1450716800 43844272 18638880
14:57:21 2536 8192000000 10360584656 10769567744 1391412672 14.518 100 67 134 1 1462808224 53990944 17404608
14:58:26 5293 8192000000 10350929664 10766909440 1394686720 14.548 100 53 104 0 1455502208 46846848 13968640
14:59:36 5158 8192000000 10362218272 10783244288 1397186272 14.570 100 51 101 0 1471422864 60075024 14161568
15:00:44 10305 8192000000 10342704320 10780508160 1388316208 14.491 99 32 63 0 1443855040 46762880 8775952
15:02:07 14988 8192000000 10368330208 10797391872 1399962592 14.595 99 47 91 0 1459517344 47441344 12113408
15:03:26 25884 8192000000 10367181248 10801270784 1413842272 14.718 100 35 70 0 1475802400 51480960 10479168
15:05:13 30112 8192000000 10321569712 10759987200 1357117360 14.211 98 26 52 1 1416421920 51231568 8072992
15:06:44 33244 8192000000 10270860400 10742575104 1341429168 14.070 98 10 20 0 1382720096 38178080 3112848
15:08:18 123479 8192000000 10301824352 10733912064 1336204112 14.023 96 34 68 0 1352826768 9312704 7309952
15:11:39 85494 8192000000 10285718128 10735476736 1366683344 14.297 99 9 18 0 1381089056 12815440 1590272
15:14:06 53911 8192000000 10287604640 10727038976 1355194544 14.194 98 36 66 0 1370293584 7385760 7713280
15:16:07 16685 8192000000 10302012112 10734653440 1377015920 14.390 99 55 108 1 1399571488 10261168 12294400
15:17:26 17781 8192000000 10286597504 10743615488 1379270848 14.410 100 25 49 0 1401308736 15877248 6160640
15:18:47 19827 8192000000 10281337616 10752180224 1381224800 14.427 100 3 6 0 1396398288 14518336 655152
15:20:59 68599 8192000000 10274666976 10732228608 1349157600 14.140 97 9 18 1 1360329248 8902608 2269040
15:23:08 84939 8192000000 10271154704 10740359168 1374568976 14.368 99 30 60 0 1398955776 16891056 7495744
15:25:38 59003 8192000000 10286950992 10748620800 1373334000 14.357 98 17 33 0 1385926816 8919600 3673216
15:27:41 51780 8192000000 10285014432 10754904064 1387534960 14.484 100 10 20 0 1404995280 14634368 2825952
15:29:49 12661 8192000000 10325775344 10765094912 1398362016 14.580 100 63 124 1 1423953056 11621104 13969936
15:31:07 69000 8192000000 10297031824 10753138688 1337480192 14.035 96 17 34 0 1352638768 11416128 3742448
15:33:17 54600 8192000000 10266911760 10711977984 1277687904 13.492 92 19 36 1 1294099040 12280928 4130208
15:35:24 61959 8192000000 10265546864 10721480704 1317307648 13.852 96 20 40 0 1339739408 17780048 4651712
15:37:26 30156 8192000000 10274765696 10716667904 1339630176 14.054 98 19 38 0 1352249936 8716560 3903200
15:39:01 63849 8192000000 10282869568 10734489600 1343585904 14.090 98 21 42 0 1362561408 14854448 4121056
15:41:06 75351 8192000000 10252229168 10692988928 1326929072 13.939 97 17 34 0 1338487952 7838256 3720624
15:43:23 58890 8192000000 10244036128 10702073856 1322263040 13.897 97 12 24 0 1338546416 13828368 2455008
15:45:22 71580 8192000000 10266094000 10728574976 1349620368 14.144 99 13 26 0 1370794048 18329168 2844512
15:47:38 81603 8192000000 10255829376 10705547264 1312829216 13.812 97 15 31 0 1328808432 12005664 3973552
15:50:00 58020 8192000000 10242302928 10701570048 1322042816 13.895 98 19 38 0 1346571152 19611424 4916912
15:52:00 78035 8192000000 10230266928 10688299008 1305523184 13.745 96 18 36 0 1320145040 10648384 3973472
15:54:57 35107 8192000000 10284288496 10723196928 1367714288 14.307 100 27 53 0 1388563824 14648480 6201056
15:56:38 105919 8192000000 10280532064 10747031552 1341265872 14.069 98 19 38 0 1364125776 18908112 3951792
15:59:32 30364 8192000000 10275341344 10717822976 1343652224 14.090 98 40 80 0 1363225584 11173040 8400320
16:01:04 48701 8192000000 10275455840 10709233664 1322360400 13.898 97 31 62 1 1339891824 10593840 6937584
16:02:52 80258 8192000000 10281513856 10729136128 1358523488 14.224 99 19 38 0 1377345424 14924080 3897856
16:05:13 13874 8192000000 10298187088 10737635328 1373593968 14.359 100 37 74 0 1396598096 15058912 7945216
16:06:30 106087 8192000000 10279371088 10733441024 1371508448 14.341 99 24 47 0 1386398640 9100832 5789360
16:09:33 96008 8192000000 10283882656 10751381504 1390925616 14.514 100 10 20 0 1406727648 13198976 2603056
16:12:35 84939 8192000000 10307536208 10773434368 1387159744 14.481 99 13 26 0 1403680432 13507040 3013648
16:15:00 56887 8192000000 10300540288 10764337152 1388567536 14.493 99 22 44 0 1409203312 15094704 5541072
16:16:57 58374 8192000000 10283779808 10745253888 1363766272 14.271 98 19 38 0 1378613376 10748848 4098256
16:19:35 47210 8192000000 10292017360 10733154304 1344297760 14.096 97 25 47 1 1359087584 9474176 5315648
16:21:24 40702 8192000000 10286573184 10733850624 1327339056 13.943 96 18 36 0 1350768752 19370096 4059600
16:23:18 65068 8192000000 10272650592 10706251776 1279795792 13.511 93 27 54 1 1293268608 7379040 6093776
16:25:28 19012 8192000000 10280248880 10712432640 1347102224 14.121 99 37 74 0 1365074640 10084208 7888208
16:26:48 110982 8192000000 10249862288 10703073280 1332957328 13.994 99 13 26 0 1353306832 17276144 3073360
16:29:49 37824 8192000000 10270374784 10721619968 1357014960 14.211 99 41 80 0 1376826432 10172864 9638608
16:31:31 74371 8192000000 10257479568 10709286912 1325070768 13.923 97 7 14 0 1336635808 9855472 1709568
16:33:49 33904 8192000000 10244456192 10689478656 1322066496 13.895 98 30 59 0 1334998960 6326320 6606144
16:35:29 55570 8192000000 10257563056 10705936384 1303763728 13.729 96 21 43 0 1315279872 6649520 4866624
16:37:25 33618 8192000000 10279693232 10714804224 1339385424 14.052 98 27 54 0 1358747792 13137280 6225088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-03-2023 16:40:32 to 15-03-2023 16:40:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-03-2023 16:40:48 to 15-03-2023 16:40: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          246816
MON$OLDEST_ACTIVE               249418
MON$OLDEST_SNAPSHOT             249418
MON$NEXT_TRANSACTION            249418
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                       2032800
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:262: 
:263: mscs-test-161.budzdorov.ru    Wed Mar 15 16:38:59 2023
:264:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:265: 
:266: 
:267: mscs-test-161.budzdorov.ru    Wed Mar 15 16:38:59 2023
:268:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:269: 
:270: 
:271: mscs-test-161.budzdorov.ru    Wed Mar 15 16:38:59 2023
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:273: 
:274: 
:275: mscs-test-161.budzdorov.ru    Wed Mar 15 16:39:00 2023
:276:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:277: 
:278: 
:279: mscs-test-161.budzdorov.ru    Wed Mar 15 16:39:00 2023
:280:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:281: 
--- end of diff output ---

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

15.03.23 16:40: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
2023-03-15 16:40:53 No severe PSQL-related problems occured
15.03.23 16:40:55. End of report.