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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-03 22:39:10
DTS_END                         2022-05-03 22:38:16.5610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34602
TRN_ID                          237794
ATT_ID                          274
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: 9504.33 21248 1140520
customer order: creation 171.22 358 20546
customer order: refuse 14.83 340 1779
order to supplier: creation 46.14 1315 5537
order to supplier: removal 3.55 111598 426
invoice (draft): creation 45.33 3155 5440
invoice (draft): removal 56.21 828 6745
invoice accept: apply 41.33 17010 4959
invoice accept: cancel 50.68 818 6081
customer reserve: creation 3826.01 167 459121
customer reserve: removal 4944.91 63 593389
realization accept: apply 84.04 215 10085
realization accept: cancel 91.60 99 10992
payment from customer: creation 57.98 121 6957
payment from customer: removal 5.91 72 709
payment to supplier: creation 48.53 118 5824
payment to supplier: removal 3.64 83 437
service: total inventory turnovers 4.71 1088 565
service: total monetary turnovers 6.86 50 823
service: aggregate perf. data 0.84 15438 101
service: refresh index statistics 0.03 272019 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-05-2022 22:40:49 to 03-05-2022 22:40:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8739
TEST_TIME 2 6181
TEST_TIME 3 6085
TEST_TIME 4 6551
TEST_TIME 5 5950
TEST_TIME 6 5529
TEST_TIME 7 5637
TEST_TIME 8 5895
TEST_TIME 9 5867
TEST_TIME 10 5786
TEST_TIME 11 5583
TEST_TIME 12 5538
TEST_TIME 13 5614
TEST_TIME 14 6090
TEST_TIME 15 6234
TEST_TIME 16 6337
TEST_TIME 17 6663
TEST_TIME 18 6631
TEST_TIME 19 7313
TEST_TIME 20 7465
TEST_TIME 21 7949
TEST_TIME 22 8469
TEST_TIME 23 8409
TEST_TIME 24 8711
TEST_TIME 25 9967
TEST_TIME 26 10237
TEST_TIME 27 10977
TEST_TIME 28 10997
TEST_TIME 29 10935
TEST_TIME 30 10892
TEST_TIME 31 10804
TEST_TIME 32 10827
TEST_TIME 33 10970
TEST_TIME 34 10881
TEST_TIME 35 10814
TEST_TIME 36 10619
TEST_TIME 37 10457
TEST_TIME 38 10467
TEST_TIME 39 10409
TEST_TIME 40 10198
TEST_TIME 41 10181
TEST_TIME 42 10141
TEST_TIME 43 10172
TEST_TIME 44 10229
TEST_TIME 45 10191
TEST_TIME 46 10133
TEST_TIME 47 10159
TEST_TIME 48 10039
TEST_TIME 49 9942
TEST_TIME 50 9910
TEST_TIME 51 10070
TEST_TIME 52 10005
TEST_TIME 53 9952
TEST_TIME 54 10034
TEST_TIME 55 9997
TEST_TIME 56 9971
TEST_TIME 57 10045
TEST_TIME 58 10038
TEST_TIME 59 10142
TEST_TIME 60 10298
TEST_TIME 61 10370
TEST_TIME 62 10382
TEST_TIME 63 10449
TEST_TIME 64 10321
TEST_TIME 65 10248
TEST_TIME 66 10199
TEST_TIME 67 10107
TEST_TIME 68 10016
TEST_TIME 69 9925
TEST_TIME 70 9942
TEST_TIME 71 9889
TEST_TIME 72 9842
TEST_TIME 73 9846
TEST_TIME 74 9789
TEST_TIME 75 9736
TEST_TIME 76 9787
TEST_TIME 77 9737
TEST_TIME 78 9729
TEST_TIME 79 9691
TEST_TIME 80 9679
TEST_TIME 81 9698
TEST_TIME 82 9715
TEST_TIME 83 9728
TEST_TIME 84 9804
TEST_TIME 85 9851
TEST_TIME 86 9830
TEST_TIME 87 9776
TEST_TIME 88 9880
TEST_TIME 89 9911
TEST_TIME 90 9954
TEST_TIME 91 9925
TEST_TIME 92 9836
TEST_TIME 93 9765
TEST_TIME 94 9770
TEST_TIME 95 9777
TEST_TIME 96 9744
TEST_TIME 97 9700
TEST_TIME 98 9733
TEST_TIME 99 9729
TEST_TIME 100 9727
TEST_TIME 101 9706
TEST_TIME 102 9736
TEST_TIME 103 9765
TEST_TIME 104 9767
TEST_TIME 105 9740
TEST_TIME 106 9806
TEST_TIME 107 9816
TEST_TIME 108 9820
TEST_TIME 109 9804
TEST_TIME 110 9799
TEST_TIME 111 9749
TEST_TIME 112 9719
TEST_TIME 113 9677
TEST_TIME 114 9665
TEST_TIME 115 9694
TEST_TIME 116 9660
TEST_TIME 117 9641
TEST_TIME 118 9610
TEST_TIME 119 9588
TEST_TIME 120 9510

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-05-2022 22:40:52 to 03-05-2022 22:40:56.

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 1566130 1566130 0 0.00 0 13513 6 0 0
doc_list_biud 1566129 1566129 0 0.00 0 48174 30 0 0
sp_add_invoice_to_stock 5062 4959 103 2.03 33 172354 17011 0 103
sp_cancel_adding_invoice 6081 6081 0 0.00 5 104376 818 0 0
sp_cancel_client_order 1779 1779 0 0.00 4 8582 340 0 0
sp_cancel_customer_reserve 593389 593389 0 0.00 1 48176 64 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 2 5297 72 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 3593 83 0 0
sp_cancel_supplier_invoice 6755 6745 10 0.14 2 38655 828 0 10
sp_cancel_supplier_order 426 426 0 0.00 7 1269159 111598 0 0
sp_cancel_write_off 10992 10992 0 0.00 1 11713 100 0 0
sp_client_order 20546 20546 0 0.00 3 20359 358 0 0
sp_customer_reserve 459121 459121 0 0.00 0 142487 168 0 0
sp_fill_shopping_cart 31520 31520 0 0.00 1 12507 38 0 0
sp_fill_shopping_cart_clo_res 459114 459114 0 0.00 0 19006 14 0 0
sp_get_clo_for_invoice 4952 4952 0 0.00 0 23335 312 0 0
sp_kill_cost_storno 18219 18219 0 0.00 0 6611 55 0 0
sp_kill_qstorno_ret_qs2qd 1204941 1204941 0 0.00 0 48159 34 0 0
sp_kill_qty_storno 634449 634449 0 0.00 0 48174 74 0 0
sp_lock_selected_doc 20835 20835 0 0.00 0 8360 7 0 0
sp_make_cost_storno 27818 27818 0 0.00 0 12174 63 0 0
sp_multiply_rows_for_pdistr 27818 27818 0 0.00 0 8975 33 0 0
sp_multiply_rows_for_qdistr 20546 20546 0 0.00 0 16646 162 0 0
sp_pay_from_customer 6957 6957 0 0.00 4 12953 121 0 0
sp_pay_to_supplier 5824 5824 0 0.00 4 9543 118 0 0
sp_payment_common 12781 12781 0 0.00 1 8640 26 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 8576 310 0 0
sp_qd_handle_on_invoice_upd_sts 11033 11033 0 0.00 0 21714 338 0 0
sp_qd_handle_on_reserve_upd_sts 10085 10085 0 0.00 0 17533 73 0 0
sp_reserve_write_off 10085 10085 0 0.00 6 17580 216 0 0
sp_supplier_invoice 5440 5440 0 0.00 6 83512 3156 0 0
sp_supplier_order 5537 5537 0 0.00 5 32322 1315 0 0
srv_aggregate_perf_data 101 101 0 0.00 2490 116811 15438 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2734 148965 48213 0 0
srv_find_qd_qs_mism 1093329 1093329 0 0.00 0 12235 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 22 80903 1088 0 0
srv_make_money_saldo 823 823 0 0.00 1 18737 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 117403 405266 272019 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 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 4959 4959 0 0.00 0 25630 175 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 1 3452 75 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 8404 26 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 8115 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 5678 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 3195 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 866 14 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 26156 1674 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 6597 12 0 0
t$perf-norm:sp_client_order 20546 20546 0 0.00 0 16745 24 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 1320 6 0 0
t$perf-norm:sp_pay_from_customer 6957 6957 0 0.00 0 10154 19 0 0
t$perf-norm:sp_pay_to_supplier 5824 5824 0 0.00 0 8459 14 0 0
t$perf-norm:sp_reserve_write_off 10085 10085 0 0.00 0 6274 12 0 0
t$perf-norm:sp_supplier_invoice 5440 5440 0 0.00 0 5465 20 0 0
t$perf-norm:sp_supplier_order 5537 5537 0 0.00 0 4885 16 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 156 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 1331 20 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 1252 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10102 9896 206 2.03 0 4965 15 0 206
v_all_customers 37430 37430 0 0.00 0 87 0 0 0
v_all_suppliers 30386 30386 0 0.00 0 114 1 0 0
v_all_wares 41092 41092 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 291 5 0 0
v_cancel_client_order 3558 3558 0 0.00 0 5820 5 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 540 4 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 1451 5 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 2301 9 0 0
v_cancel_supplier_invoice 814 794 20 2.45 0 2670 19 0 20
v_cancel_supplier_order 852 852 0 0.00 0 2588 7 0 0
v_cancel_write_off 1898 1898 0 0.00 0 1774 12 0 0
v_max_id_clo_ord 5537 5537 0 0.00 0 568 1 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 3609 20 0 0
v_max_id_ord_sup 5437 5437 0 0.00 0 3309 3 0 0
v_max_non_paid_invoice 1605 1605 0 0.00 0 4454 6 0 0
v_max_non_paid_realizn 3671 3671 0 0.00 0 3501 7 0 0
v_min_id_clo_ord 5537 5537 0 0.00 0 6217 7 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 1636 4 0 0
v_min_id_ord_sup 5437 5437 0 0.00 0 4741 6 0 0
v_min_non_paid_invoice 5824 5824 0 0.00 0 4998 13 0 0
v_min_non_paid_realizn 6957 6957 0 0.00 0 6117 19 0 0
v_our_firm 10234 10234 0 0.00 0 16 1 0 0
v_reserve_write_off 20170 20170 0 0.00 0 5905 5 0 0
x_lock_depdocs_on_canc_invoice 12429 12429 0 0.00 0 17764 106 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 4378 322 0 0
x_make_qstorno_1000_1200 5537 5537 0 0.00 2 32302 1236 0 0
x_make_qstorno_1200_2000 5440 5440 0 0.00 3 83496 3018 0 0
x_make_qstorno_2100_3300 459121 459121 0 0.00 0 142481 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-05-2022 22:40:57 to 03-05-2022 22:41:01.

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:20 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:21 24646 8192000000 9909569248 10218266624 991898976 10.800 100 90 180 2 1021284640 5921888 23463776
20:10:49 27381 8192000000 9961782928 10282721280 1035554016 11.222 100 84 168 0 1064706032 7767008 21385008
20:12:27 11720 8192000000 9994216576 10328035328 1081416656 11.661 100 83 166 0 1110696624 9240176 20039792
20:13:43 11420 8192000000 10048023264 10396860416 1129010416 12.112 100 74 148 1 1157075456 10721152 17343888
20:14:57 6250 8192000000 10092755232 10454077440 1179281472 12.583 100 80 160 0 1211128992 13949664 17897856
20:16:07 6034 8192000000 10146662320 10513264640 1228316656 13.039 100 81 160 2 1258339616 11994192 18028768
20:17:15 10540 8192000000 10171245296 10552619008 1263577072 13.363 100 75 150 0 1296278848 16524688 16177088
20:18:27 1774 8192000000 10201076560 10580553728 1287543440 13.582 100 93 186 0 1323174864 15469424 20162000
20:19:30 11224 8192000000 10200740064 10598334464 1299538784 13.691 100 56 112 0 1321874496 10065824 12269888
20:20:42 5778 8192000000 10193719440 10603651072 1308225712 13.770 100 34 67 0 1323571712 7896192 7449808
20:21:53 8395 8192000000 10213618160 10614312960 1316284800 13.843 100 47 94 5 1333094624 5642080 11167744
20:23:02 32400 8192000000 10212834864 10629054464 1317868096 13.857 100 33 66 0 1331249504 6402224 6979184
20:24:35 5365 8192000000 10212541616 10633355264 1326768800 13.938 100 33 66 1 1339974272 5643680 7561792
20:25:41 6266 8192000000 10210535168 10631303168 1305084176 13.741 98 15 28 0 1313715760 5368704 3262880
20:26:50 52239 8192000000 10220011008 10633854976 1316728368 13.847 99 28 56 0 1329795680 6177728 6889584
20:28:43 5742 8192000000 10184412384 10590539776 1245788368 13.200 94 25 49 1 1258439760 6487024 6164368
20:29:51 7342 8192000000 10157065552 10554572800 1231861584 13.071 96 22 42 1 1242817088 5660448 5295056
20:31:01 5353 8192000000 10156098528 10551840768 1245457040 13.196 99 31 62 0 1258437968 6030112 6950816
20:32:08 53961 8192000000 10125101360 10527023104 1205400304 12.826 97 31 62 1 1219051632 5613808 8037520
20:34:02 5321 8192000000 10171408288 10567938048 1265456016 13.380 100 40 81 2 1281956592 8050576 8450000
20:35:10 11649 8192000000 10178834688 10582867968 1280705024 13.519 100 37 73 2 1295698512 5737248 9256240
20:36:27 12973 8192000000 10185422416 10596909056 1294616672 13.646 100 21 42 0 1305319248 6326624 4375952
20:37:40 40620 8192000000 10209940976 10619785216 1310176224 13.788 100 27 53 2 1320636352 5008496 5451632
20:39:26 22640 8192000000 10253038592 10658861056 1338052464 14.040 100 57 114 0 1362901712 12488560 12360688
20:40:51 29718 8192000000 10258677504 10669899776 1334352976 14.006 99 50 100 0 1360529104 15095104 11081024
20:42:25 16601 8192000000 10274323584 10696781824 1362283216 14.258 100 30 60 2 1388597104 19074144 7239744
20:43:50 12205 8192000000 10299808176 10713694208 1379050224 14.408 100 54 107 1 1413773648 22030544 12692880
20:45:11 17126 8192000000 10317490800 10736676864 1390592976 14.511 100 53 104 1 1432309920 28634720 13082224
20:46:34 10426 8192000000 10327622048 10752925696 1402484896 14.617 100 36 72 0 1438703696 26860544 9358256
20:47:50 8019 8192000000 10360360400 10782941184 1421773872 14.788 100 50 100 0 1468291728 33694032 12823824
20:49:10 22732 8192000000 10380692992 10806616064 1430857408 14.869 100 47 94 1 1487478880 43929680 12691792
20:50:40 6335 8192000000 10400651936 10824290304 1440590528 14.955 100 58 113 2 1507478352 52384720 14503104
20:51:51 6444 8192000000 10403333040 10819235840 1432451408 14.883 100 64 129 1 1507055248 58029824 16574016
20:53:03 2733 8192000000 10399827232 10818060288 1428772592 14.850 100 76 149 2 1507416336 58801888 19841856
20:54:12 8328 8192000000 10386609280 10800582656 1403924672 14.630 98 70 140 2 1481191696 58706256 18560768
20:55:33 5062 8192000000 10383314176 10799353856 1411882096 14.701 100 60 118 1 1489342128 61659440 15800592
20:56:41 3454 8192000000 10380519680 10784354304 1390569664 14.511 100 76 152 1 1480115472 68179712 21366096
20:57:52 10539 8192000000 10360707616 10794774528 1395344576 14.554 100 39 78 0 1474931008 68620784 10965648
20:59:12 3157 8192000000 10387629136 10795323392 1396308896 14.562 100 78 156 1 1485019072 67673920 21036256
21:00:20 20596 8192000000 10364501856 10806657024 1400540832 14.600 99 36 71 2 1475912672 65489312 9882528
21:01:59 9354 8192000000 10367650352 10789445632 1384325856 14.455 100 46 92 0 1466787712 69159200 13302656
21:03:09 17184 8192000000 10335448832 10787045376 1363790352 14.271 100 29 58 0 1443710688 71004800 8915536
21:05:06 30504 8192000000 10291552496 10729578496 1341057904 14.067 99 27 54 1 1388575200 40268960 7248336
21:06:37 81635 8192000000 10286684000 10725019648 1347131728 14.122 99 19 37 0 1365646768 14287072 4227968
21:09:25 59765 8192000000 10289887616 10742702080 1375064464 14.372 100 12 24 0 1391402496 13440112 2897920
21:11:55 72146 8192000000 10280748416 10723381248 1339704368 14.055 97 12 25 0 1357497344 15469792 2323184
21:14:20 89266 8192000000 10278772464 10725412864 1356725840 14.208 99 23 46 0 1368502688 6368176 5408672
21:16:49 23271 8192000000 10285825872 10716815360 1319826384 13.875 96 43 86 0 1337742048 7977584 9938080
21:18:14 123959 8192000000 10286590464 10732208128 1317633024 13.855 95 30 60 4 1336540176 11767856 7139296
21:21:20 64572 8192000000 10289450992 10740416512 1369481168 14.322 99 42 85 1 1393710224 14253072 9975984
21:24:44 17956 8192000000 10293213856 10758701056 1387794224 14.486 100 11 22 0 1403689824 13229360 2666240
21:26:08 31602 8192000000 10288977488 10744057856 1374697536 14.369 99 13 22 1 1392392400 15050432 2644432
21:27:44 104405 8192000000 10280756800 10754183168 1347224208 14.122 97 23 46 0 1367833264 14934880 5674176
21:30:42 31189 8192000000 10294400624 10752032768 1385696400 14.467 100 14 26 1 1397633440 9057152 2879888
21:32:13 63000 8192000000 10302139104 10761416704 1383016736 14.444 99 16 32 1 1399629952 13223216 3390000
21:34:18 43705 8192000000 10296778576 10747260928 1363710704 14.271 98 32 64 0 1387254208 15608656 7934848
21:36:04 148965 8192000000 10313531552 10783264768 1382522640 14.439 99 16 32 1 1412374816 26244000 3608176
21:40:28 75794 8192000000 10300689040 10754019328 1337134864 14.032 96 13 26 0 1357986928 17789568 3062496
21:42:44 57064 8192000000 10290977840 10728136704 1361966672 14.255 99 35 70 0 1379363104 9411088 7985344
21:44:42 75477 8192000000 10254676464 10715578368 1307078256 13.760 95 7 14 0 1317344688 9007728 1258704
21:46:58 70684 8192000000 10268024352 10727002112 1329549088 13.963 97 19 38 0 1348775408 14684672 4541648
21:49:09 70733 8192000000 10271798400 10728914944 1367011808 14.300 100 19 38 1 1381474416 10078896 4383712
21:51:47 62779 8192000000 10266011008 10711543808 1322400688 13.898 97 34 68 0 1346620384 16482592 7737104
21:54:06 78633 8192000000 10261628032 10725597184 1356300464 14.204 99 26 54 1 1372926592 10225408 6400720
21:56:37 39366 8192000000 10274126064 10732265472 1361337744 14.249 99 27 53 1 1374686752 7701024 5647984
21:58:22 36082 8192000000 10279840352 10725650432 1349264560 14.141 99 33 66 0 1369346704 11920864 8161280
22:00:01 29731 8192000000 10285165232 10736160768 1367103712 14.301 100 30 60 0 1384792768 11228160 6460896
22:01:34 42715 8192000000 10274336672 10738167808 1319115056 13.869 96 23 46 0 1343189888 18910464 5164368
22:03:18 41279 8192000000 10277372288 10736558080 1341748848 14.073 98 24 48 3 1360327664 13397712 5181104
22:05:05 10347 8192000000 10310861440 10744467456 1381539296 14.430 100 38 76 1 1399228112 8859616 8829200
22:06:16 33355 8192000000 10297266224 10755190784 1384301232 14.455 100 34 67 2 1404031008 12429952 7299824
22:07:49 130393 8192000000 10297184112 10755874816 1367429808 14.304 98 12 24 2 1390130832 19928944 2772080
22:11:39 33452 8192000000 10287258432 10747723776 1373239136 14.356 99 22 44 1 1388653728 9746976 5667616
22:13:13 66390 8192000000 10280111312 10751213568 1375758784 14.379 99 6 12 0 1384363984 7246832 1358368
22:15:20 39738 8192000000 10298094944 10752331776 1377685232 14.396 99 35 69 1 1393673840 7222784 8765824
22:17:17 112932 8192000000 10317418912 10774593536 1385172944 14.463 99 11 21 1 1397086672 9989680 1924048
22:20:12 128055 8192000000 10299225584 10767691776 1359658720 14.234 97 12 24 0 1375263776 13006624 2598432
22:23:21 67867 8192000000 10319482304 10766471168 1373484016 14.358 98 30 61 2 1400510592 20523072 6503504
22:25:31 115386 8192000000 10301727984 10753855488 1314124544 13.823 94 26 53 0 1333665344 13638496 5902304
22:28:30 101300 8192000000 10290182848 10745532416 1372392480 14.348 99 18 36 0 1389293536 12434000 4467056
22:31:18 18656 8192000000 10302036224 10755579904 1376371712 14.384 99 37 74 0 1397098224 11757632 8968880
22:32:36 93926 8192000000 10267081296 10733195264 1336552496 14.026 97 17 34 0 1352040240 11025936 4461808
22:35:11 38679 8192000000 10276120528 10737053696 1362973936 14.264 99 36 71 1 1378275968 7041568 8260464
22:36:50 58958 8192000000 10279221408 10747764736 1344286400 14.096 97 9 18 1 1359647472 13344320 2016752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 03-05-2022 22:41:03 to 03-05-2022 22:41:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 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 03-05-2022 22:41:18 to 03-05-2022 22:41:20.

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          243072
MON$OLDEST_ACTIVE               243073
MON$OLDEST_SNAPSHOT             243073
MON$NEXT_TRANSACTION            243073
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                       2049312
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33585 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33585 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33585 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33585 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 ---
--- end of diff output ---

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

03.05.22 22:41:21. 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-05-03 22:41:23 No severe PSQL-related problems occured
03.05.22 22:41:25. End of report.