Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.05.2022 04:37
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 16037220 kB
MemAvailable 31362680 kB
Buffers 20 kB
Cached 15563396 kB
SwapCached 2656 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-17 04:37:17
DTS_END                         2022-05-17 04:37:17.8300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53154
TRN_ID                          214978
ATT_ID                          650
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: 9346.16 23421 1121539
customer order: creation 164.42 466 19730
customer order: refuse 14.33 361 1719
order to supplier: creation 43.68 1406 5242
order to supplier: removal 3.52 166105 422
invoice (draft): creation 43.15 3173 5178
invoice (draft): removal 53.60 954 6432
invoice accept: apply 40.63 26461 4875
invoice accept: cancel 48.59 1134 5831
customer reserve: creation 3851.87 248 462224
customer reserve: removal 4802.77 96 576332
realization accept: apply 80.10 266 9612
realization accept: cancel 77.28 153 9273
payment from customer: creation 55.97 176 6716
payment from customer: removal 5.40 125 648
payment to supplier: creation 45.45 191 5454
payment to supplier: removal 3.49 107 419
service: total inventory turnovers 3.78 4315 453
service: total monetary turnovers 7.31 310 877
service: aggregate perf. data 0.82 17567 98
service: refresh index statistics 0.03 244815 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-05-2022 04:37:59 to 17-05-2022 04:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8273
TEST_TIME 2 7329
TEST_TIME 3 5712
TEST_TIME 4 5866
TEST_TIME 5 5937
TEST_TIME 6 6765
TEST_TIME 7 6790
TEST_TIME 8 6750
TEST_TIME 9 6862
TEST_TIME 10 7057
TEST_TIME 11 7031
TEST_TIME 12 6995
TEST_TIME 13 7209
TEST_TIME 14 7353
TEST_TIME 15 7503
TEST_TIME 16 7666
TEST_TIME 17 8217
TEST_TIME 18 8185
TEST_TIME 19 8357
TEST_TIME 20 8520
TEST_TIME 21 8614
TEST_TIME 22 8789
TEST_TIME 23 8613
TEST_TIME 24 8651
TEST_TIME 25 8502
TEST_TIME 26 8596
TEST_TIME 27 8972
TEST_TIME 28 9179
TEST_TIME 29 9614
TEST_TIME 30 9688
TEST_TIME 31 10240
TEST_TIME 32 10811
TEST_TIME 33 10958
TEST_TIME 34 11187
TEST_TIME 35 11295
TEST_TIME 36 11324
TEST_TIME 37 11128
TEST_TIME 38 11034
TEST_TIME 39 10888
TEST_TIME 40 10848
TEST_TIME 41 10669
TEST_TIME 42 10689
TEST_TIME 43 10877
TEST_TIME 44 10844
TEST_TIME 45 10612
TEST_TIME 46 10502
TEST_TIME 47 10458
TEST_TIME 48 10369
TEST_TIME 49 10255
TEST_TIME 50 10242
TEST_TIME 51 10206
TEST_TIME 52 10088
TEST_TIME 53 10103
TEST_TIME 54 10039
TEST_TIME 55 10055
TEST_TIME 56 9998
TEST_TIME 57 9967
TEST_TIME 58 9943
TEST_TIME 59 9794
TEST_TIME 60 9990
TEST_TIME 61 9938
TEST_TIME 62 10065
TEST_TIME 63 10025
TEST_TIME 64 9995
TEST_TIME 65 10023
TEST_TIME 66 9947
TEST_TIME 67 10196
TEST_TIME 68 10085
TEST_TIME 69 10010
TEST_TIME 70 10081
TEST_TIME 71 10096
TEST_TIME 72 10090
TEST_TIME 73 10113
TEST_TIME 74 10024
TEST_TIME 75 9958
TEST_TIME 76 9956
TEST_TIME 77 9856
TEST_TIME 78 9793
TEST_TIME 79 9787
TEST_TIME 80 9806
TEST_TIME 81 9842
TEST_TIME 82 9783
TEST_TIME 83 9736
TEST_TIME 84 9667
TEST_TIME 85 9651
TEST_TIME 86 9668
TEST_TIME 87 9629
TEST_TIME 88 9640
TEST_TIME 89 9625
TEST_TIME 90 9622
TEST_TIME 91 9639
TEST_TIME 92 9652
TEST_TIME 93 9663
TEST_TIME 94 9652
TEST_TIME 95 9636
TEST_TIME 96 9666
TEST_TIME 97 9723
TEST_TIME 98 9694
TEST_TIME 99 9691
TEST_TIME 100 9728
TEST_TIME 101 9651
TEST_TIME 102 9608
TEST_TIME 103 9614
TEST_TIME 104 9573
TEST_TIME 105 9545
TEST_TIME 106 9493
TEST_TIME 107 9445
TEST_TIME 108 9391
TEST_TIME 109 9447
TEST_TIME 110 9456
TEST_TIME 111 9440
TEST_TIME 112 9424
TEST_TIME 113 9425
TEST_TIME 114 9441
TEST_TIME 115 9433
TEST_TIME 116 9447
TEST_TIME 117 9449
TEST_TIME 118 9422
TEST_TIME 119 9438
TEST_TIME 120 9375

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-05-2022 04:38:03 to 17-05-2022 04:38:06.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1548245 1548245 0 0.00 0 19165 13 0 0
doc_list_biud 1548234 1548234 0 0.00 0 50366 41 0 0
sp_add_invoice_to_stock 5019 4875 144 2.86 36 188926 26461 0 144
sp_cancel_adding_invoice 5831 5831 0 0.00 7 67555 1134 0 0
sp_cancel_client_order 1719 1719 0 0.00 6 9642 362 0 0
sp_cancel_customer_reserve 576332 576332 0 0.00 1 50367 97 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 4 4142 125 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 4 2150 107 0 0
sp_cancel_supplier_invoice 6449 6432 17 0.26 3 17319 954 0 17
sp_cancel_supplier_order 422 422 0 0.00 8 1287056 166105 0 0
sp_cancel_write_off 9273 9273 0 0.00 2 7947 154 0 0
sp_client_order 19730 19730 0 0.00 6 19476 467 0 0
sp_customer_reserve 462224 462224 0 0.00 1 114210 249 0 0
sp_fill_shopping_cart 30137 30137 0 0.00 2 16152 84 0 0
sp_fill_shopping_cart_clo_res 462198 462198 0 0.00 0 14784 18 0 0
sp_get_clo_for_invoice 4839 4839 0 0.00 0 39839 314 0 0
sp_kill_cost_storno 16171 16171 0 0.00 0 14160 70 0 0
sp_kill_qstorno_ret_qs2qd 1168791 1168791 0 0.00 0 50364 42 0 0
sp_kill_qty_storno 614453 614453 0 0.00 0 50366 96 0 0
sp_lock_selected_doc 19992 19992 0 0.00 0 6464 6 0 0
sp_make_cost_storno 26618 26618 0 0.00 0 16002 82 0 0
sp_multiply_rows_for_pdistr 26615 26615 0 0.00 0 9982 37 0 0
sp_multiply_rows_for_qdistr 19730 19730 0 0.00 0 17020 184 0 0
sp_pay_from_customer 6716 6716 0 0.00 6 15521 176 0 0
sp_pay_to_supplier 5454 5454 0 0.00 7 19171 191 0 0
sp_payment_common 12168 12168 0 0.00 2 11642 45 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 0 9634 297 0 0
sp_qd_handle_on_invoice_upd_sts 10666 10666 0 0.00 1 14538 476 0 0
sp_qd_handle_on_reserve_upd_sts 9609 9609 0 0.00 0 19072 65 0 0
sp_reserve_write_off 9612 9612 0 0.00 8 19136 266 0 0
sp_supplier_invoice 5178 5178 0 0.00 12 91578 3173 0 0
sp_supplier_order 5242 5242 0 0.00 9 21100 1406 0 0
srv_aggregate_perf_data 98 98 0 0.00 2607 149971 17567 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 897 122940 36850 0 0
srv_find_qd_qs_mism 1074739 1074739 0 0.00 0 16473 10 0 0
srv_make_invnt_saldo 453 453 0 0.00 105 101038 4315 0 0
srv_make_money_saldo 877 877 0 0.00 1 8477 310 0 0
srv_recalc_idx_stat 4 4 0 0.00 59626 385207 244815 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4881 4881 0 0.00 1 11533 842 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 2 5932 403 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 3628 25 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 3284 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 1420 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 1266 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 1160 28 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 102175 6567 0 0
t$perf-norm:sp_cancel_write_off 926 926 0 0.00 0 1259 17 0 0
t$perf-norm:sp_client_order 19731 19731 0 0.00 0 7109 33 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 2708 18 0 0
t$perf-norm:sp_pay_from_customer 6716 6716 0 0.00 0 4408 18 0 0
t$perf-norm:sp_pay_to_supplier 5454 5454 0 0.00 0 3301 19 0 0
t$perf-norm:sp_reserve_write_off 9612 9612 0 0.00 0 9503 23 0 0
t$perf-norm:sp_supplier_invoice 5178 5178 0 0.00 0 9577 34 0 0
t$perf-norm:sp_supplier_order 5242 5242 0 0.00 0 4344 31 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 203 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 922 20 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 1934 18 0 0
t$perf-norm:srv_make_money_saldo 877 877 0 0.00 0 221 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 157 58 0 0
v_add_invoice_to_stock 9950 9662 288 2.89 0 3870 24 0 288
v_all_customers 36117 36117 0 0.00 0 191 4 0 0
v_all_suppliers 28982 28982 0 0.00 0 198 4 0 0
v_all_wares 39456 39456 0 0.00 0 144 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 397 12 0 0
v_cancel_client_order 3438 3438 0 0.00 0 2172 9 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 1637 14 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 1 4527 17 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 553 10 0 0
v_cancel_supplier_invoice 808 774 34 4.20 0 874 21 0 34
v_cancel_supplier_order 844 844 0 0.00 0 4738 15 0 0
v_cancel_write_off 1852 1852 0 0.00 0 1586 22 0 0
v_max_id_clo_ord 5238 5238 0 0.00 0 1053 5 0 0
v_max_id_clo_res 927 927 0 0.00 0 1010 8 0 0
v_max_id_ord_sup 5169 5169 0 0.00 0 5905 11 0 0
v_max_non_paid_invoice 1368 1368 0 0.00 0 1193 8 0 0
v_max_non_paid_realizn 3588 3588 0 0.00 0 2734 9 0 0
v_min_id_clo_ord 5238 5238 0 0.00 0 2913 9 0 0
v_min_id_clo_res 927 927 0 0.00 0 9194 42 0 0
v_min_id_ord_sup 5169 5169 0 0.00 0 11125 29 0 0
v_min_non_paid_invoice 5453 5453 0 0.00 0 5386 14 0 0
v_min_non_paid_realizn 6715 6715 0 0.00 0 11624 20 0 0
v_our_firm 9590 9590 0 0.00 0 195 4 0 0
v_reserve_write_off 19216 19216 0 0.00 0 2684 10 0 0
x_lock_depdocs_on_canc_invoice 11876 11876 0 0.00 0 17738 139 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 9595 351 0 0
x_make_qstorno_1000_1200 5242 5242 0 0.00 3 21076 1243 0 0
x_make_qstorno_1200_2000 5178 5178 0 0.00 7 81245 2934 0 0
x_make_qstorno_2100_3300 462224 462224 0 0.00 0 114138 220 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 17-05-2022 04:38:07 to 17-05-2022 04:38:12.

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
02:07:21 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 32400 8192000000 9730367072 10031857664 808003360 8.977 100 89 178 0 836766624 7395632 21367632
02:10:02 15798 8192000000 9761253280 10071007232 836025152 9.260 100 89 178 0 870916576 13518832 21372592
02:11:28 19680 8192000000 9813078016 10139430912 876994624 9.670 100 86 172 0 921105296 24550272 19560400
02:12:50 11877 8192000000 9880077072 10212495360 920585456 10.102 100 81 162 0 984602480 46164880 17852144
02:14:06 4115 8192000000 9922089968 10265481216 952618592 10.417 100 91 178 2 1041528816 70251952 18658272
02:15:14 2442 8192000000 9966905040 10322030592 985574000 10.738 100 84 168 0 1081396112 78591952 17230160
02:16:18 1369 8192000000 9989002816 10351493120 1012978928 11.004 100 88 172 1 1115946784 85145600 17822256
02:17:21 1014 8192000000 10021372128 10393497600 1040931776 11.274 100 98 191 0 1140456112 80109744 19414592
02:18:22 969 8192000000 10032578176 10410696704 1056620640 11.424 100 97 188 1 1151856608 76061040 19174928
02:19:24 1693 8192000000 10047194816 10429485056 1071542656 11.567 100 98 190 2 1166503056 76545952 18414448
02:20:27 1141 8192000000 10039686880 10428256256 1083361408 11.679 100 87 162 1 1163480640 63141456 16977776
02:21:29 1244 8192000000 10059275696 10445893632 1089818048 11.741 100 89 174 2 1181621984 74188016 17615920
02:22:31 931 8192000000 10048146688 10435014656 1094297360 11.784 100 89 165 1 1166590992 54272240 18021392
02:23:33 947 8192000000 10054765808 10443862016 1096189904 11.801 100 91 178 0 1177080176 61763520 19126752
02:24:35 2354 8192000000 10064315072 10456313856 1095819152 11.798 100 83 150 1 1187045616 75714080 15512384
02:25:38 1412 8192000000 10062132528 10447532032 1100335744 11.841 100 96 188 0 1181968368 62624016 19008608
02:26:40 5529 8192000000 10051830080 10449395712 1102928336 11.865 100 72 137 1 1180765760 63552624 14284800
02:27:46 5139 8192000000 10037102800 10427654144 1088905856 11.732 99 74 138 0 1160552880 57304848 14342176
02:28:52 2840 8192000000 10047965824 10434891776 1085428512 11.699 100 84 153 0 1167916880 67264992 15223376
02:29:57 1361 8192000000 10085106432 10473340928 1091467824 11.757 100 98 190 0 1205090816 94867856 18755136
02:30:59 1336 8192000000 10024116992 10404081664 1071580288 11.567 100 95 178 2 1142850336 51830912 19439136
02:32:01 1015 8192000000 10000668944 10378289152 1039831360 11.263 99 80 154 1 1122343216 66412448 16099408
02:33:03 10025 8192000000 9997851568 10383077376 1052800528 11.388 100 64 127 0 1129277712 63721264 12755920
02:34:14 1713 8192000000 9957284720 10330542080 1014195024 11.016 99 73 139 3 1079853632 51727808 13930800
02:35:16 1124 8192000000 9978551904 10349461504 1026487680 11.135 100 92 171 0 1101268016 57648752 17131584
02:36:18 3801 8192000000 9986865632 10369691648 1036053104 11.227 100 67 128 1 1108763808 60034256 12676448
02:37:25 7855 8192000000 9990411440 10373165056 1049483024 11.356 100 61 109 0 1113509456 53756944 10269488
02:38:38 17773 8192000000 10015312912 10397822976 1056991504 11.428 99 63 118 0 1138443600 70228464 11223632
02:40:03 3464 8192000000 10033320016 10411053056 1079913184 11.647 100 81 161 2 1149590848 53313920 16363744
02:41:14 4457 8192000000 10109576224 10505822208 1096476624 11.804 100 87 165 0 1223359664 109242976 17640064
02:42:23 3780 8192000000 10101725232 10503786496 1103159232 11.868 100 57 109 1 1220953248 105877984 11916032
02:43:32 1032 8192000000 10126317680 10528088064 1121693696 12.043 100 98 192 2 1242804384 101003792 20106896
02:44:37 7632 8192000000 10126556288 10524512256 1132516688 12.145 100 78 151 0 1242269216 94154256 15598272
02:45:47 1169 8192000000 10185490528 10577940480 1142155040 12.236 100 93 180 3 1295433280 134311440 18966800
02:46:52 4732 8192000000 10205474048 10607472640 1155832976 12.364 100 79 154 0 1319757408 146785776 17138656
02:48:02 885 8192000000 10222252368 10623459328 1168640944 12.484 100 93 178 3 1331327264 143147216 19539104
02:49:06 1230 8192000000 10270102272 10673815552 1174514272 12.539 100 87 163 1 1383946624 190962384 18469968
02:50:10 1492 8192000000 10317659728 10727505920 1182480304 12.613 100 95 189 1 1426219264 222586720 21152240
02:51:14 1630 8192000000 10334248896 10740469760 1179980432 12.590 100 91 182 0 1444256512 243962128 20313952
02:52:19 980 8192000000 10365668544 10782375936 1186262032 12.649 100 86 160 1 1475256064 270003248 18990784
02:53:23 943 8192000000 10380211456 10811994112 1199735616 12.774 100 98 195 1 1490772912 268638320 22398976
02:54:27 912 8192000000 10368787568 10805776384 1191446496 12.697 100 97 190 1 1475059136 260305984 23306656
02:55:30 1209 8192000000 10369382608 10790555648 1197640304 12.754 100 93 172 1 1474674784 257530192 19504288
02:56:35 1132 8192000000 10415884864 10863759360 1203480720 12.809 100 79 156 1 1524186368 301572912 19132736
02:57:40 1413 8192000000 10458582608 10887593984 1203606560 12.810 100 86 169 2 1566501216 342599552 20295104
02:58:44 3171 8192000000 10434168704 10861441024 1194404592 12.724 100 72 136 0 1543996608 331046160 18545856
02:59:56 11390 8192000000 10466682800 10896834560 1190397872 12.687 100 37 74 0 1576958384 376732736 9827776
03:01:13 11070 8192000000 10513927024 10947563520 1183086032 12.619 99 49 94 0 1609660048 414648000 11926016
03:02:27 3724 8192000000 10558764384 10992381952 1191116784 12.694 100 64 125 1 1665063408 457509568 16437056
03:03:38 3473 8192000000 10547629888 11032276992 1194724432 12.727 100 64 120 1 1647790432 436986560 16079440
03:04:44 9799 8192000000 10557959584 11024498688 1195932672 12.739 100 44 86 1 1656200928 448667360 11600896
03:05:55 16034 8192000000 10516511216 11000057856 1178989856 12.581 99 42 84 1 1617453168 427443344 11019968
03:07:18 17072 8192000000 10459690848 10955526144 1165435824 12.454 100 33 66 0 1575561856 401916112 8209920
03:08:39 17734 8192000000 10301949120 10805493760 1145109024 12.264 99 31 62 2 1453694496 300621744 7963728
03:09:58 27662 8192000000 10227715408 10686599168 1158132064 12.386 100 26 52 0 1326948528 163036240 5780224
03:11:30 64521 8192000000 10074841136 10562351104 1139829680 12.214 100 17 33 2 1243255104 99687024 3738400
03:13:36 74820 8192000000 10070954496 10493870080 1118535552 12.013 98 30 59 1 1154406016 30195120 5675344
03:15:51 36009 8192000000 10108775984 10546405376 1134376896 12.163 99 51 85 8 1176374992 33594352 8403744
03:17:37 122937 8192000000 10119784464 10575122432 1061488448 11.471 93 32 64 1 1149165040 80666416 7010176
03:21:30 92598 8192000000 10042953968 10480922624 1074069456 11.591 96 31 61 0 1114967680 34044736 6853488
03:24:03 14594 8192000000 10068663024 10493100032 1111833248 11.950 100 77 143 1 1164028752 37396368 14799136
03:25:19 78363 8192000000 10078393392 10503372800 1110214896 11.934 98 39 76 0 1161134240 42885168 8034176
03:27:39 73220 8192000000 10146360496 10601709568 1135340512 12.172 100 24 48 2 1230568976 89493968 5734496
03:29:53 4839 8192000000 10192088208 10610610176 1132154416 12.142 99 75 141 1 1282172464 134920960 15097088
03:31:06 113024 8192000000 10206813680 10667200512 1129526336 12.117 99 27 54 0 1292268576 156305136 6437104
03:33:59 60365 8192000000 10193808192 10688040960 1138385904 12.200 100 22 45 0 1326994736 182855216 5753616
03:36:16 102258 8192000000 10143942096 10691026944 1136353280 12.181 100 16 32 0 1294824064 153748928 4721856
03:39:02 71048 8192000000 10064646848 10524114944 1116405440 11.993 98 8 16 0 1148979808 31434800 1139568
03:41:14 29873 8192000000 10131608208 10565898240 1131394944 12.135 98 31 62 1 1193964480 55745984 6823552
03:42:52 30032 8192000000 10188293008 10659291136 1156403936 12.370 100 22 44 0 1265492560 104192288 4896336
03:44:30 78911 8192000000 10102101696 10527768576 1109392256 11.927 97 19 37 0 1164028624 50900656 3735712
03:46:50 24895 8192000000 10137235552 10592047104 1140187184 12.217 100 33 65 1 1241984592 95022032 6775376
03:48:15 84839 8192000000 10129280624 10563751936 1112818224 11.959 97 21 41 0 1202811584 85400400 4592960
03:50:52 70412 8192000000 10095908656 10545426432 1121651488 12.043 99 34 68 2 1164124656 35663520 6809648
03:53:26 77288 8192000000 10085505936 10521661440 1111863728 11.950 98 28 54 1 1149528288 31588352 6076208
03:56:05 48724 8192000000 10113545616 10573758464 1118379920 12.012 99 30 59 0 1207170624 82390304 6400400
03:58:07 67755 8192000000 10066964864 10530840576 1135113504 12.170 100 15 30 0 1210043472 71341200 3588768
04:00:38 97453 8192000000 10100381328 10569748480 1105593776 11.891 97 19 38 0 1176547440 66804288 4149376
04:03:15 61812 8192000000 10051543168 10528968704 1099133504 11.829 97 9 18 0 1138679088 37464000 2081584
04:05:32 53308 8192000000 10090123920 10547298304 1109426000 11.927 98 30 60 0 1168707296 53560800 5720496
04:07:28 58534 8192000000 10162992688 10630422528 1144533792 12.258 100 25 50 1 1246587088 97084976 4968320
04:09:27 51085 8192000000 10099402432 10534133760 1068258368 11.535 94 33 66 0 1138107680 63797856 6051456
04:11:20 53523 8192000000 10091897120 10521677824 1083404640 11.680 96 41 82 1 1133242848 41743728 8094480
04:13:15 38040 8192000000 10127677024 10596450304 1109475856 11.927 98 22 44 1 1212348064 98491488 4380720
04:15:00 30011 8192000000 10074363216 10494365696 1095199952 11.792 97 45 83 1 1133733472 29421296 9112224
04:16:33 63848 8192000000 10113977536 10585018368 1132916384 12.149 100 37 74 0 1214686512 74351968 7418160
04:18:49 74407 8192000000 10096997456 10555338752 1106265408 11.897 98 28 56 0 1192256464 79630832 6360224
04:22:17 48579 8192000000 10064460736 10525048832 1112093072 11.952 99 8 16 0 1163770912 49904832 1773008
04:24:06 55706 8192000000 10124823600 10574155776 1112659184 11.958 98 28 55 0 1204727344 86018848 6049312
04:26:03 83145 8192000000 10086909840 10565849088 1121936944 12.045 100 15 30 0 1205200912 79648816 3615152
04:28:50 48096 8192000000 10092701344 10536759296 1116835184 11.997 98 25 50 1 1175548704 53629856 5083664
04:30:42 40337 8192000000 10110444112 10547953664 1110938352 11.941 98 40 78 3 1181582832 62291840 8352640
04:32:24 52126 8192000000 10083722368 10550333440 1120650224 12.033 100 26 52 1 1168375520 42395616 5329680
04:34:20 35021 8192000000 10087003312 10530385920 1119460688 12.022 99 38 75 1 1169320800 42394000 7466112
04:35:56 14644 8192000000 10115642384 10547093504 1103053456 11.867 98 61 122 1 1178741952 63958176 11730320
04:37:13 13402 8192000000 9313604608 9622126592 429060288 4.976 38 13 21 2 466994400 35441728 2492384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-05-2022 04:38:13 to 17-05-2022 04:38:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-05-2022 04:38:30 to 17-05-2022 04:38:32.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          215628
MON$OLDEST_ACTIVE               215629
MON$OLDEST_SNAPSHOT             215629
MON$NEXT_TRANSACTION            215629
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-30 07:30:08.6030 GMT
MON$PAGES                       2074272
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:241: 
:242: mscs-test-161.budzdorov.ru    Tue May 17 04:37:29 2022
:243:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:244: 
--- end of diff output ---

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

17.05.22 04:38:33. There are 3 logs in /var/tmp/logs-oltp40 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-17 04:38:36 No severe PSQL-related problems occured
17.05.22 04:38:37. End of report.