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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-08 22:38:34
DTS_END                         2022-12-08 22:38:34.5740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48394
TRN_ID                          241961
ATT_ID                          698
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: 9701.59 21288 1164191
customer order: creation 173.70 359 20844
customer order: refuse 14.92 333 1790
order to supplier: creation 46.54 1332 5585
order to supplier: removal 3.46 115130 415
invoice (draft): creation 45.51 3378 5461
invoice (draft): removal 55.33 886 6639
invoice accept: apply 42.42 18302 5090
invoice accept: cancel 50.24 816 6029
customer reserve: creation 4004.88 177 480586
customer reserve: removal 4968.18 62 596182
realization accept: apply 82.96 178 9955
realization accept: cancel 84.15 119 10098
payment from customer: creation 59.62 108 7154
payment from customer: removal 5.62 86 674
payment to supplier: creation 48.73 114 5847
payment to supplier: removal 3.34 68 401
service: total inventory turnovers 4.70 1179 564
service: total monetary turnovers 6.44 54 773
service: aggregate perf. data 0.83 23535 100
service: refresh index statistics 0.03 259535 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-12-2022 22:41:12 to 08-12-2022 22:41:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6769
TEST_TIME 2 6931
TEST_TIME 3 5704
TEST_TIME 4 5784
TEST_TIME 5 5527
TEST_TIME 6 6093
TEST_TIME 7 5784
TEST_TIME 8 5855
TEST_TIME 9 6224
TEST_TIME 10 6666
TEST_TIME 11 7197
TEST_TIME 12 6920
TEST_TIME 13 7108
TEST_TIME 14 7123
TEST_TIME 15 7400
TEST_TIME 16 7904
TEST_TIME 17 7986
TEST_TIME 18 8129
TEST_TIME 19 8035
TEST_TIME 20 8173
TEST_TIME 21 8514
TEST_TIME 22 8491
TEST_TIME 23 8528
TEST_TIME 24 9117
TEST_TIME 25 8932
TEST_TIME 26 8922
TEST_TIME 27 9128
TEST_TIME 28 9340
TEST_TIME 29 9666
TEST_TIME 30 9623
TEST_TIME 31 10412
TEST_TIME 32 11338
TEST_TIME 33 11100
TEST_TIME 34 11075
TEST_TIME 35 10903
TEST_TIME 36 10815
TEST_TIME 37 10892
TEST_TIME 38 10773
TEST_TIME 39 10836
TEST_TIME 40 10676
TEST_TIME 41 10493
TEST_TIME 42 10477
TEST_TIME 43 10455
TEST_TIME 44 10558
TEST_TIME 45 10504
TEST_TIME 46 10399
TEST_TIME 47 10298
TEST_TIME 48 10190
TEST_TIME 49 10144
TEST_TIME 50 10029
TEST_TIME 51 10082
TEST_TIME 52 9971
TEST_TIME 53 9912
TEST_TIME 54 9882
TEST_TIME 55 10083
TEST_TIME 56 10024
TEST_TIME 57 10033
TEST_TIME 58 10022
TEST_TIME 59 10077
TEST_TIME 60 10158
TEST_TIME 61 10205
TEST_TIME 62 10144
TEST_TIME 63 10219
TEST_TIME 64 10255
TEST_TIME 65 10297
TEST_TIME 66 10435
TEST_TIME 67 10369
TEST_TIME 68 10271
TEST_TIME 69 10247
TEST_TIME 70 10207
TEST_TIME 71 10126
TEST_TIME 72 10135
TEST_TIME 73 10128
TEST_TIME 74 10087
TEST_TIME 75 10057
TEST_TIME 76 9987
TEST_TIME 77 9908
TEST_TIME 78 9832
TEST_TIME 79 9903
TEST_TIME 80 9880
TEST_TIME 81 9864
TEST_TIME 82 9913
TEST_TIME 83 9890
TEST_TIME 84 9904
TEST_TIME 85 9932
TEST_TIME 86 9963
TEST_TIME 87 9926
TEST_TIME 88 10041
TEST_TIME 89 10063
TEST_TIME 90 10086
TEST_TIME 91 10113
TEST_TIME 92 10035
TEST_TIME 93 9990
TEST_TIME 94 9967
TEST_TIME 95 9927
TEST_TIME 96 9941
TEST_TIME 97 9896
TEST_TIME 98 9926
TEST_TIME 99 9874
TEST_TIME 100 9840
TEST_TIME 101 9866
TEST_TIME 102 9969
TEST_TIME 103 9954
TEST_TIME 104 9931
TEST_TIME 105 9937
TEST_TIME 106 9926
TEST_TIME 107 9928
TEST_TIME 108 9942
TEST_TIME 109 9946
TEST_TIME 110 10015
TEST_TIME 111 10013
TEST_TIME 112 9954
TEST_TIME 113 9908
TEST_TIME 114 9877
TEST_TIME 115 9854
TEST_TIME 116 9846
TEST_TIME 117 9800
TEST_TIME 118 9793
TEST_TIME 119 9757
TEST_TIME 120 9704

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-12-2022 22:41:15 to 08-12-2022 22:41:19.

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 1608875 1608875 0 0.00 0 14532 6 0 0
doc_list_biud 1608875 1608875 0 0.00 0 113799 29 0 0
sp_add_invoice_to_stock 5253 5090 163 3.10 31 202388 18303 0 163
sp_cancel_adding_invoice 6029 6029 0 0.00 3 70013 816 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 8584 333 0 0
sp_cancel_customer_reserve 596182 596182 0 0.00 0 62746 63 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 2 11082 86 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 4662 68 0 0
sp_cancel_supplier_invoice 6653 6639 14 0.21 1 113822 886 0 14
sp_cancel_supplier_order 415 415 0 0.00 6 1141969 115131 0 0
sp_cancel_write_off 10098 10098 0 0.00 1 12464 120 0 0
sp_client_order 20844 20844 0 0.00 3 102170 360 0 0
sp_customer_reserve 480586 480586 0 0.00 0 38113 177 0 0
sp_fill_shopping_cart 31890 31890 0 0.00 1 12651 35 0 0
sp_fill_shopping_cart_clo_res 480579 480579 0 0.00 0 15156 13 0 0
sp_get_clo_for_invoice 5077 5077 0 0.00 0 19254 327 0 0
sp_kill_cost_storno 17202 17202 0 0.00 0 14530 61 0 0
sp_kill_qstorno_ret_qs2qd 1209516 1209516 0 0.00 0 113783 34 0 0
sp_kill_qty_storno 636180 636180 0 0.00 0 113793 73 0 0
sp_lock_selected_doc 20827 20827 0 0.00 0 8649 7 0 0
sp_make_cost_storno 28028 28028 0 0.00 0 11131 48 0 0
sp_multiply_rows_for_pdistr 28028 28028 0 0.00 0 8633 25 0 0
sp_multiply_rows_for_qdistr 20844 20844 0 0.00 0 102159 178 0 0
sp_pay_from_customer 7154 7154 0 0.00 4 10669 109 0 0
sp_pay_to_supplier 5847 5847 0 0.00 5 10697 115 0 0
sp_payment_common 13001 13001 0 0.00 2 5497 24 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 8578 298 0 0
sp_qd_handle_on_invoice_upd_sts 11101 11101 0 0.00 0 18205 352 0 0
sp_qd_handle_on_reserve_upd_sts 9955 9955 0 0.00 0 15954 67 0 0
sp_reserve_write_off 9955 9955 0 0.00 6 15978 178 0 0
sp_supplier_invoice 5461 5461 0 0.00 6 118080 3379 0 0
sp_supplier_order 5585 5585 0 0.00 5 51511 1332 0 0
srv_aggregate_perf_data 100 100 0 0.00 3054 500183 23535 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 978 129389 39593 0 0
srv_find_qd_qs_mism 1115492 1115492 0 0.00 0 12592 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 16 55809 1180 0 0
srv_make_money_saldo 773 773 0 0.00 1 14219 55 0 0
srv_recalc_idx_stat 4 4 0 0.00 157492 468791 259535 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5090 5090 0 0.00 0 21832 218 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 6511 136 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 2972 16 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 830 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 431 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 1192 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 896 13 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 25863 1678 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 3830 13 0 0
t$perf-norm:sp_client_order 20844 20844 0 0.00 0 8989 17 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 2321 5 0 0
t$perf-norm:sp_pay_from_customer 7154 7154 0 0.00 0 6142 10 0 0
t$perf-norm:sp_pay_to_supplier 5847 5847 0 0.00 0 6782 11 0 0
t$perf-norm:sp_reserve_write_off 9955 9955 0 0.00 0 5666 8 0 0
t$perf-norm:sp_supplier_invoice 5461 5461 0 0.00 0 3797 15 0 0
t$perf-norm:sp_supplier_order 5585 5585 0 0.00 0 7305 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 172 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 321 6 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 2608 32 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 1455 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10460 10134 326 3.11 0 6615 22 0 326
v_all_customers 37908 37908 0 0.00 0 20 1 0 0
v_all_suppliers 30678 30678 0 0.00 0 32 1 0 0
v_all_wares 41688 41688 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 3120 12 0 0
v_cancel_client_order 3580 3580 0 0.00 0 3135 8 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 2610 6 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 6307 16 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 598 5 0 0
v_cancel_supplier_invoice 868 840 28 3.22 0 818 16 0 28
v_cancel_supplier_order 830 830 0 0.00 0 1122 5 0 0
v_cancel_write_off 2010 2010 0 0.00 0 2660 18 0 0
v_max_id_clo_ord 5585 5585 0 0.00 0 1030 1 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 3175 14 0 0
v_max_id_ord_sup 5461 5461 0 0.00 0 4675 3 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 241 3 0 0
v_max_non_paid_realizn 3811 3811 0 0.00 0 4422 5 0 0
v_min_id_clo_ord 5585 5585 0 0.00 0 3317 6 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 6114 15 0 0
v_min_id_ord_sup 5461 5461 0 0.00 0 6440 11 0 0
v_min_non_paid_invoice 5847 5847 0 0.00 0 5494 16 0 0
v_min_non_paid_realizn 7154 7154 0 0.00 0 4900 18 0 0
v_our_firm 10466 10466 0 0.00 0 22 1 0 0
v_reserve_write_off 19910 19910 0 0.00 0 6951 9 0 0
x_lock_depdocs_on_canc_invoice 12248 12248 0 0.00 0 16912 128 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 10288 384 0 0
x_make_qstorno_1000_1200 5585 5585 0 0.00 1 51478 1259 0 0
x_make_qstorno_1200_2000 5461 5461 0 0.00 2 118046 3251 0 0
x_make_qstorno_2100_3300 480586 480586 0 0.00 0 38111 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2022 22:41:20 to 08-12-2022 22:41:24.

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:38 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 20297 8192000000 9890952016 10199560192 968107200 10.568 100 84 166 1 997185776 6346656 22731920
20:11:07 17329 8192000000 9946939792 10270064640 1021562480 11.087 100 86 172 0 1056781456 13228752 21990224
20:12:40 14851 8192000000 9989484080 10323955712 1064565648 11.500 100 81 162 0 1099611936 14699712 20346576
20:14:04 6560 8192000000 10030458464 10377822208 1118503168 12.013 100 81 162 0 1149627600 11456064 19668368
20:15:19 4057 8192000000 10084990896 10445729792 1174322976 12.537 100 77 152 1 1204713200 13265392 17124832
20:16:25 3699 8192000000 10126324304 10494853120 1213020192 12.897 100 83 165 0 1244725568 13683904 18021472
20:17:31 1376 8192000000 10164064688 10538254336 1249539792 13.234 100 93 182 2 1283060304 13582720 19937792
20:18:33 955 8192000000 10185827712 10569658368 1278510128 13.499 100 81 163 0 1308790480 11485488 18794864
20:19:35 39498 8192000000 10195664400 10601410560 1303020112 13.723 100 60 118 0 1326845872 9877728 13948032
20:21:17 4943 8192000000 10211281664 10621587456 1320257040 13.879 100 31 62 0 1335058928 7103056 7698832
20:22:22 16508 8192000000 10216753920 10631426048 1329456736 13.962 100 19 38 0 1339681600 5836544 4388320
20:23:41 19742 8192000000 10220304112 10640003072 1335234816 14.014 100 25 50 2 1346980064 5298880 6446368
20:25:06 17725 8192000000 10223842928 10647855104 1333755376 14.001 100 18 36 0 1343042352 5548944 3738032
20:26:24 5244 8192000000 10233420784 10655154176 1328663888 13.955 99 21 42 1 1339937168 5899696 5373584
20:27:32 10607 8192000000 10242976960 10664755200 1347055968 14.121 100 25 50 2 1358754848 6150176 5548704
20:28:47 47187 8192000000 10186301680 10604597248 1259911120 13.329 96 27 54 0 1271440032 4870768 6658144
20:30:35 3537 8192000000 10176525312 10586734592 1288643392 13.592 100 34 66 0 1306756144 10513632 7599120
20:31:39 6822 8192000000 10129861936 10529644544 1223371664 12.993 98 26 52 1 1235137696 5312688 6453344
20:32:49 47463 8192000000 10171667888 10564820992 1253945408 13.274 99 39 80 3 1268434992 5228160 9261424
20:34:37 8269 8192000000 10191920256 10591490048 1282658960 13.537 100 35 68 1 1297233424 6351216 8223248
20:35:48 8888 8192000000 10192165232 10605223936 1302258912 13.716 100 18 36 0 1312676304 4985680 5431712
20:37:00 12690 8192000000 10198133616 10615500800 1311574800 13.800 100 13 27 0 1320209056 5314928 3319328
20:38:13 48842 8192000000 10237034464 10642837504 1325459616 13.926 100 41 82 0 1340574112 6143104 8971392
20:40:09 8999 8192000000 10253843072 10670116864 1352931616 14.174 100 47 94 1 1371254208 8396416 9926176
20:41:21 13797 8192000000 10278419856 10692521984 1363290928 14.267 100 55 110 0 1387761408 12632064 11838416
20:42:42 7505 8192000000 10286983344 10705543168 1378088464 14.399 100 43 85 1 1398306528 10172720 10045344
20:43:55 9270 8192000000 10299811120 10720194560 1377701968 14.396 99 44 86 1 1402546928 14843168 10001792
20:45:10 7536 8192000000 10311500816 10737811456 1401412592 14.608 100 49 93 0 1425747984 12738688 11596704
20:46:30 11899 8192000000 10308832208 10738991104 1400586768 14.600 100 29 59 0 1421823072 14053088 7183216
20:47:48 4588 8192000000 10333346944 10755702784 1406474464 14.653 100 72 144 2 1445218512 21890720 16853328
20:48:56 1357 8192000000 10334227200 10754445312 1399658272 14.592 100 69 136 0 1441773392 26108112 16007008
20:50:03 7697 8192000000 10300235792 10719031296 1364363920 14.277 98 54 108 1 1399413856 20864112 14185824
20:51:15 14279 8192000000 10305494496 10731413504 1372961392 14.354 100 45 89 2 1415435696 31207840 11266464
20:52:36 3334 8192000000 10324253088 10736418816 1380340512 14.420 100 68 135 0 1429609776 33028608 16240656
20:53:44 8125 8192000000 10311664832 10726940672 1372580832 14.350 100 64 128 1 1417511536 29703568 15227136
20:54:58 1724 8192000000 10317590496 10719182848 1365545680 14.287 99 85 165 1 1417466384 30963536 20957168
20:56:05 3031 8192000000 10337730880 10749616128 1390053712 14.506 100 65 130 0 1441806048 35752128 16000208
20:57:14 3073 8192000000 10350115648 10754650112 1383492560 14.448 100 78 156 1 1447941168 44932880 19515728
20:58:22 5459 8192000000 10348445744 10757697536 1373534016 14.359 99 69 138 1 1444399536 52713056 18152464
20:59:32 974 8192000000 10363465536 10764165120 1388771824 14.495 100 91 178 1 1461862128 51058320 22031984
21:00:39 1810 8192000000 10377212896 10781179904 1405871920 14.647 100 86 170 0 1476233888 49304464 21057504
21:01:45 5987 8192000000 10388866592 10807996416 1419809776 14.771 100 57 114 0 1491675152 55948768 15916608
21:03:01 8925 8192000000 10370278560 10795671552 1396013712 14.559 98 39 78 0 1454067280 47337312 10716256
21:04:13 5388 8192000000 10374564800 10803957760 1402667152 14.619 99 47 92 1 1471244544 55790064 12787328
21:05:28 19215 8192000000 10370304672 10805600256 1394682960 14.548 99 31 62 1 1463133856 59248336 9202560
21:07:00 19822 8192000000 10349608288 10788077568 1393291760 14.535 100 25 50 0 1458136784 57064800 7780224
21:08:25 27889 8192000000 10346037680 10785243136 1371107696 14.337 100 20 40 2 1439944512 62156464 6680352
21:09:54 62372 8192000000 10276535536 10721472512 1330616704 13.973 96 14 28 0 1372529984 38420640 3492640
21:11:57 27463 8192000000 10298855888 10734440448 1369026944 14.318 99 46 92 1 1393139360 13094576 11017840
21:13:38 129388 8192000000 10285029072 10747965440 1366526000 14.296 99 23 45 0 1384612800 13081456 5005344
21:17:15 101835 8192000000 10307968976 10772688896 1383669872 14.449 99 17 34 0 1405489104 18186528 3632704
21:20:02 28039 8192000000 10308642688 10766172160 1398425344 14.581 100 41 81 0 1418992560 11408992 9158224
21:21:39 28734 8192000000 10297772480 10737618944 1370658128 14.333 99 35 69 0 1388560928 9003648 8899152
21:23:42 16878 8192000000 10282160496 10747879424 1386631552 14.476 100 7 14 0 1397790480 9889904 1269024
21:25:00 29344 8192000000 10287736560 10745483264 1368005296 14.309 98 13 26 0 1379952272 8934224 3012752
21:26:30 59810 8192000000 10289412928 10751905792 1368359856 14.312 98 23 46 1 1387146640 13172272 5614512
21:30:14 42826 8192000000 10300061056 10758561792 1398078512 14.578 100 21 42 0 1414879200 11744048 5056640
21:31:58 81918 8192000000 10290807440 10767187968 1393707360 14.539 100 12 24 2 1416625008 19805632 3112016
21:34:31 62762 8192000000 10283188512 10743693312 1338510384 14.044 96 15 30 1 1355913472 13532496 3870592
21:36:34 63769 8192000000 10300708304 10762371072 1377432816 14.394 99 11 22 0 1397432080 17222928 2776336
21:38:47 67441 8192000000 10317652800 10769711104 1379903040 14.416 99 20 40 0 1402607904 18436640 4268224
21:40:56 45973 8192000000 10306259360 10761089024 1378155936 14.400 99 39 78 0 1403035888 16408832 8471120
21:42:51 43203 8192000000 10307505344 10764369920 1374513456 14.367 99 13 26 0 1397887136 20394832 2978848
21:44:45 57722 8192000000 10295366912 10745114624 1359714672 14.235 98 11 22 0 1375455600 13502720 2238208
21:47:57 89910 8192000000 10257037264 10696769536 1259873920 13.329 91 21 42 1 1272212208 8321104 4017184
21:50:29 63680 8192000000 10250382496 10712408064 1328019296 13.949 97 16 32 0 1343307616 11836512 3451808
21:52:37 42555 8192000000 10259779152 10715414528 1350419904 14.151 99 17 34 0 1365169328 10843968 3905456
21:54:25 61012 8192000000 10235621776 10689490944 1302806080 13.721 96 9 18 0 1314713984 9886016 2021888
21:57:29 87515 8192000000 10249492864 10698436608 1335458880 14.016 98 11 21 0 1347792320 10092048 2241392
22:00:08 60148 8192000000 10251733264 10701135872 1316086992 13.841 97 13 25 0 1329554144 10259776 3207376
22:02:08 38882 8192000000 10270889056 10721820672 1339592960 14.054 98 35 70 0 1361247168 13664720 7989488
22:03:57 93782 8192000000 10274686400 10726752256 1330986384 13.976 97 18 36 0 1346171904 11003376 4182144
22:06:30 57692 8192000000 10285781168 10743410688 1374183280 14.365 100 11 22 0 1395555472 18834448 2537744
22:09:14 95527 8192000000 10299942336 10758565888 1372015312 14.345 98 15 30 0 1389973312 14699536 3258464
22:12:12 44022 8192000000 10295847760 10764451840 1383179312 14.445 99 19 38 0 1399283632 11847136 4257184
22:14:07 75690 8192000000 10294196640 10767876096 1374225360 14.365 98 10 20 0 1392756096 16304288 2226448
22:16:23 68078 8192000000 10307179648 10757677056 1392207712 14.526 100 24 48 0 1409426304 11518976 5699616
22:18:37 29021 8192000000 10310657376 10764500992 1384880224 14.460 98 32 64 0 1405211088 13125744 7205120
22:20:07 56685 8192000000 10296782032 10767228928 1384647360 14.458 99 11 22 0 1402138880 14858208 2633312
22:22:15 44039 8192000000 10318154080 10765635584 1383218352 14.445 99 25 50 0 1402184240 13531216 5434672
22:24:00 23184 8192000000 10284228832 10735804416 1360317552 14.240 98 27 53 1 1375627200 9661296 5648352
22:25:29 28067 8192000000 10305402352 10751209472 1376884576 14.389 99 35 68 0 1397935792 13986368 7064848
22:26:58 63976 8192000000 10300642944 10753273856 1360549616 14.242 98 29 58 0 1380707472 13756416 6401440
22:29:02 86931 8192000000 10289716944 10753527808 1366090480 14.292 99 14 28 0 1388962368 19624016 3247872
22:32:19 8566 8192000000 10278135344 10731876352 1365752240 14.289 99 37 73 0 1382614944 8883520 7979184
22:33:28 63725 8192000000 10265761632 10733318144 1347014144 14.121 98 14 26 0 1364969152 15173552 2781456
22:35:33 66594 8192000000 10278466640 10729144320 1351790048 14.164 98 17 33 0 1367462528 11862064 3810416
22:37:40 63739 8192000000 9875500832 10275237888 993825600 10.819 72 10 20 0 1005577280 9179328 2572352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-12-2022 22:41:26 to 08-12-2022 22:41:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-12-2022 22:41:42 to 08-12-2022 22:41:44.

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          242274
MON$OLDEST_ACTIVE               242275
MON$OLDEST_SNAPSHOT             242275
MON$NEXT_TRANSACTION            242275
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                       2056032
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.33646 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33646 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33646 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33646 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'

08.12.22 22:41:45. 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-12-08 22:41:48 No severe PSQL-related problems occured
08.12.22 22:41:49. End of report.