Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.2022 04:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14521116 kB
MemAvailable 30083172 kB
Buffers 20 kB
Cached 17138276 kB
SwapCached 3304 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.3
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-12-21 04:37:24
DTS_END                         2022-12-21 04:37:24.1090
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/50200
TRN_ID                          216151
ATT_ID                          612
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: 8746.78 23406 1049614
customer order: creation 167.40 497 20088
customer order: refuse 14.08 395 1690
order to supplier: creation 43.58 1409 5230
order to supplier: removal 3.13 166430 375
invoice (draft): creation 43.70 3622 5244
invoice (draft): removal 48.65 960 5838
invoice accept: apply 39.98 25823 4798
invoice accept: cancel 44.51 1263 5341
customer reserve: creation 3697.05 244 443646
customer reserve: removal 4351.08 94 522129
realization accept: apply 80.28 305 9634
realization accept: cancel 87.73 156 10528
payment from customer: creation 57.03 184 6844
payment from customer: removal 5.92 121 710
payment to supplier: creation 47.03 223 5643
payment to supplier: removal 3.45 125 414
service: total inventory turnovers 3.86 4353 463
service: total monetary turnovers 7.42 304 890
service: aggregate perf. data 0.87 19234 104
service: refresh index statistics 0.04 242380 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-12-2022 04:40:05 to 21-12-2022 04:40:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5782
TEST_TIME 2 5978
TEST_TIME 3 5752
TEST_TIME 4 6142
TEST_TIME 5 5795
TEST_TIME 6 5889
TEST_TIME 7 5676
TEST_TIME 8 5963
TEST_TIME 9 6141
TEST_TIME 10 6714
TEST_TIME 11 7010
TEST_TIME 12 7162
TEST_TIME 13 7329
TEST_TIME 14 7476
TEST_TIME 15 7480
TEST_TIME 16 7846
TEST_TIME 17 8278
TEST_TIME 18 8400
TEST_TIME 19 8320
TEST_TIME 20 8525
TEST_TIME 21 8773
TEST_TIME 22 8914
TEST_TIME 23 9043
TEST_TIME 24 9178
TEST_TIME 25 9405
TEST_TIME 26 9471
TEST_TIME 27 9882
TEST_TIME 28 9747
TEST_TIME 29 9668
TEST_TIME 30 9785
TEST_TIME 31 9868
TEST_TIME 32 10212
TEST_TIME 33 10292
TEST_TIME 34 10802
TEST_TIME 35 11151
TEST_TIME 36 10879
TEST_TIME 37 10723
TEST_TIME 38 10778
TEST_TIME 39 10719
TEST_TIME 40 10515
TEST_TIME 41 10293
TEST_TIME 42 10115
TEST_TIME 43 10116
TEST_TIME 44 9967
TEST_TIME 45 9860
TEST_TIME 46 9804
TEST_TIME 47 9793
TEST_TIME 48 9711
TEST_TIME 49 9601
TEST_TIME 50 9644
TEST_TIME 51 9561
TEST_TIME 52 9517
TEST_TIME 53 9393
TEST_TIME 54 9308
TEST_TIME 55 9263
TEST_TIME 56 9198
TEST_TIME 57 9173
TEST_TIME 58 9169
TEST_TIME 59 9046
TEST_TIME 60 8981
TEST_TIME 61 8904
TEST_TIME 62 8942
TEST_TIME 63 8935
TEST_TIME 64 8865
TEST_TIME 65 8868
TEST_TIME 66 8831
TEST_TIME 67 8857
TEST_TIME 68 8843
TEST_TIME 69 8904
TEST_TIME 70 9059
TEST_TIME 71 8980
TEST_TIME 72 8946
TEST_TIME 73 8948
TEST_TIME 74 9071
TEST_TIME 75 9070
TEST_TIME 76 9159
TEST_TIME 77 9174
TEST_TIME 78 9108
TEST_TIME 79 9020
TEST_TIME 80 8974
TEST_TIME 81 8940
TEST_TIME 82 8919
TEST_TIME 83 8868
TEST_TIME 84 8829
TEST_TIME 85 8797
TEST_TIME 86 8750
TEST_TIME 87 8749
TEST_TIME 88 8782
TEST_TIME 89 8777
TEST_TIME 90 8757
TEST_TIME 91 8762
TEST_TIME 92 8755
TEST_TIME 93 8772
TEST_TIME 94 8817
TEST_TIME 95 8878
TEST_TIME 96 8887
TEST_TIME 97 8973
TEST_TIME 98 8926
TEST_TIME 99 8974
TEST_TIME 100 8899
TEST_TIME 101 8837
TEST_TIME 102 8833
TEST_TIME 103 8830
TEST_TIME 104 8787
TEST_TIME 105 8782
TEST_TIME 106 8737
TEST_TIME 107 8769
TEST_TIME 108 8760
TEST_TIME 109 8738
TEST_TIME 110 8752
TEST_TIME 111 8722
TEST_TIME 112 8770
TEST_TIME 113 8774
TEST_TIME 114 8805
TEST_TIME 115 8851
TEST_TIME 116 8871
TEST_TIME 117 8900
TEST_TIME 118 8893
TEST_TIME 119 8830
TEST_TIME 120 8777

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2022 04:40:09 to 21-12-2022 04:40:12.

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 1458861 1458861 0 0.00 0 26945 13 0 0
doc_list_biud 1458850 1458850 0 0.00 0 51821 40 0 0
sp_add_invoice_to_stock 4954 4798 156 3.14 68 246102 25823 0 156
sp_cancel_adding_invoice 5341 5341 0 0.00 5 97610 1263 0 0
sp_cancel_client_order 1690 1690 0 0.00 6 9708 396 0 0
sp_cancel_customer_reserve 522129 522129 0 0.00 1 33946 94 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 5766 121 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 4530 126 0 0
sp_cancel_supplier_invoice 5855 5838 17 0.29 2 51939 961 0 17
sp_cancel_supplier_order 375 375 0 0.00 8 1475526 166431 0 0
sp_cancel_write_off 10528 10528 0 0.00 2 16752 157 0 0
sp_client_order 20088 20088 0 0.00 5 32271 497 0 0
sp_customer_reserve 443646 443646 0 0.00 0 53746 245 0 0
sp_fill_shopping_cart 30552 30552 0 0.00 1 34455 96 0 0
sp_fill_shopping_cart_clo_res 443621 443621 0 0.00 0 15126 20 0 0
sp_get_clo_for_invoice 4763 4763 0 0.00 0 20644 330 0 0
sp_kill_cost_storno 16993 16993 0 0.00 0 26934 72 0 0
sp_kill_qstorno_ret_qs2qd 1060999 1060999 0 0.00 0 51814 41 0 0
sp_kill_qty_storno 560297 560297 0 0.00 0 51817 95 0 0
sp_lock_selected_doc 20065 20065 0 0.00 0 7777 7 0 0
sp_make_cost_storno 26884 26884 0 0.00 0 25532 89 0 0
sp_multiply_rows_for_pdistr 26881 26881 0 0.00 0 13533 42 0 0
sp_multiply_rows_for_qdistr 20086 20086 0 0.00 0 12816 200 0 0
sp_pay_from_customer 6844 6844 0 0.00 5 13218 185 0 0
sp_pay_to_supplier 5643 5643 0 0.00 6 31528 223 0 0
sp_payment_common 12485 12485 0 0.00 2 31513 58 0 0
sp_qd_handle_on_cancel_clo 1690 1690 0 0.00 0 9644 318 0 0
sp_qd_handle_on_invoice_upd_sts 10103 10103 0 0.00 0 35666 551 0 0
sp_qd_handle_on_reserve_upd_sts 9634 9634 0 0.00 0 31435 75 0 0
sp_reserve_write_off 9634 9634 0 0.00 7 31577 305 0 0
sp_supplier_invoice 5244 5244 0 0.00 9 127669 3623 0 0
sp_supplier_order 5230 5230 0 0.00 7 59659 1410 0 0
srv_aggregate_perf_data 104 104 0 0.00 2169 383136 19235 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 898 121776 38994 0 0
srv_find_qd_qs_mism 1002782 1002782 0 0.00 0 18847 9 0 0
srv_make_invnt_saldo 463 463 0 0.00 64 152234 4353 0 0
srv_make_money_saldo 890 890 0 0.00 1 5764 304 0 0
srv_recalc_idx_stat 5 5 0 0.00 113122 360685 242381 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 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 4806 4806 0 0.00 0 12157 1031 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 6463 553 0 0
t$perf-norm:sp_cancel_client_order 1690 1690 0 0.00 0 1839 31 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 1499 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 5366 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 196 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 2524 47 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 86213 7862 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 2069 20 0 0
t$perf-norm:sp_client_order 20088 20088 0 0.00 0 6149 36 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 6067 18 0 0
t$perf-norm:sp_pay_from_customer 6844 6844 0 0.00 0 5667 17 0 0
t$perf-norm:sp_pay_to_supplier 5643 5643 0 0.00 0 4652 20 0 0
t$perf-norm:sp_reserve_write_off 9634 9634 0 0.00 0 5714 23 0 0
t$perf-norm:sp_supplier_invoice 5244 5244 0 0.00 0 9424 34 0 0
t$perf-norm:sp_supplier_order 5230 5230 0 0.00 0 3668 31 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 413 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 550 17 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 9347 36 0 0
t$perf-norm:srv_make_money_saldo 890 890 0 0.00 0 1247 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 193 53 0 0
v_add_invoice_to_stock 9830 9518 312 3.17 0 5750 27 0 312
v_all_customers 36614 36614 0 0.00 0 120 4 0 0
v_all_suppliers 29033 29033 0 0.00 0 63 5 0 0
v_all_wares 40166 40166 0 0.00 0 272 4 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 288 14 0 0
v_cancel_client_order 3380 3380 0 0.00 0 2711 12 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 5465 16 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 2014 12 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2371 17 0 0
v_cancel_supplier_invoice 798 764 34 4.26 0 1673 26 0 34
v_cancel_supplier_order 750 750 0 0.00 0 307 11 0 0
v_cancel_write_off 2014 2014 0 0.00 0 3339 26 0 0
v_max_id_clo_ord 5227 5227 0 0.00 0 10875 9 0 0
v_max_id_clo_res 944 944 0 0.00 0 1712 15 0 0
v_max_id_ord_sup 5240 5240 0 0.00 0 6153 12 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 6984 13 0 0
v_max_non_paid_realizn 3550 3550 0 0.00 0 2517 9 0 0
v_min_id_clo_ord 5226 5226 0 0.00 0 5669 12 0 0
v_min_id_clo_res 944 944 0 0.00 0 1377 11 0 0
v_min_id_ord_sup 5240 5240 0 0.00 0 5911 11 0 0
v_min_non_paid_invoice 5643 5643 0 0.00 0 31506 31 0 0
v_min_non_paid_realizn 6842 6842 0 0.00 0 8916 23 0 0
v_our_firm 10136 10136 0 0.00 0 270 5 0 0
v_reserve_write_off 19268 19268 0 0.00 0 3423 12 0 0
x_lock_depdocs_on_canc_invoice 10797 10797 0 0.00 0 17989 128 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 5179 397 0 0
x_make_qstorno_1000_1200 5230 5230 0 0.00 3 59637 1210 0 0
x_make_qstorno_1200_2000 5244 5244 0 0.00 4 127643 3351 0 0
x_make_qstorno_2100_3300 443645 443645 0 0.00 0 53713 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-12-2022 04:40:13 to 21-12-2022 04:40:17.

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:28 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:29 36542 8192000000 9720848208 10016862208 793273744 8.828 100 92 184 0 824192864 7942096 22977024
02:10:09 26256 8192000000 9754596496 10062671872 830634608 9.206 100 93 186 0 866475072 13314240 22526224
02:11:39 17485 8192000000 9795478768 10116411392 864340896 9.544 100 82 163 0 905829856 23154944 18334016
02:13:03 5529 8192000000 9877373744 10212913152 925303552 10.148 100 84 162 0 983618512 40757008 17557952
02:14:11 1898 8192000000 9922348032 10264109056 964518432 10.533 100 90 169 0 1030758544 48620208 17619904
02:15:16 2585 8192000000 9985192768 10338287616 996085552 10.841 100 86 171 1 1105590816 92404720 17100544
02:16:21 1448 8192000000 9994087088 10354237440 1018439328 11.057 100 91 175 0 1111897792 76746784 16711680
02:17:24 1306 8192000000 10000854480 10373447680 1041697696 11.281 100 90 169 0 1123587344 65726672 16162976
02:18:27 907 8192000000 10011227200 10387767296 1059746336 11.454 100 93 171 3 1136402048 59637232 17018480
02:19:29 1300 8192000000 10037330752 10417938432 1064650496 11.501 100 87 170 0 1155929888 73439488 17839904
02:20:33 1016 8192000000 10022923712 10401181696 1073005040 11.581 100 87 170 1 1143232896 53193712 17034144
02:21:35 747 8192000000 10038056128 10419146752 1076548992 11.615 100 94 180 0 1158253120 63388080 18316048
02:22:37 1109 8192000000 10053635296 10436780032 1082166816 11.668 100 92 177 0 1175303808 74778240 18358752
02:23:39 1120 8192000000 10052280832 10436034560 1088910272 11.732 100 98 193 1 1173171904 65206368 19055264
02:24:41 3772 8192000000 10048937824 10445193216 1089704144 11.740 100 66 128 2 1168327952 65480224 13143584
02:25:45 1329 8192000000 10074856800 10467536896 1096221824 11.802 100 89 175 2 1193381312 79447248 17712240
02:26:50 1071 8192000000 10070783216 10456440832 1094484832 11.785 100 96 185 1 1193622064 80344336 18792896
02:27:52 1158 8192000000 10068763456 10458947584 1099937856 11.837 100 90 175 1 1192106368 73340288 18828224
02:28:55 1109 8192000000 10048375648 10437148672 1093093680 11.772 100 94 177 0 1173866048 62675168 18097200
02:29:57 1145 8192000000 10039790000 10422829056 1082889376 11.675 100 96 180 1 1163049888 61436304 18724208
02:30:59 998 8192000000 10055546512 10436771840 1072142400 11.573 100 96 184 1 1175322352 83648768 19531184
02:32:01 1028 8192000000 10009388832 10385616896 1059683536 11.453 100 96 176 0 1133688624 55707520 18297568
02:33:03 978 8192000000 9985282128 10363944960 1040661696 11.271 99 94 178 0 1110879344 51912912 18304736
02:34:06 1095 8192000000 9981268000 10356641792 1023289360 11.104 99 88 167 5 1105477536 64461120 17727056
02:35:08 1211 8192000000 9977500976 10345816064 1026145568 11.131 100 94 169 2 1099927824 56631328 17150928
02:36:10 6006 8192000000 9973528832 10361851904 1037814240 11.244 100 54 108 1 1106672928 57715760 11142928
02:37:18 5830 8192000000 10002399920 10385248256 1050866208 11.369 100 65 124 1 1123184048 59090704 13227136
02:38:31 7434 8192000000 10003983760 10385391616 1064031936 11.495 100 60 114 1 1114058400 39896976 10129488
02:39:46 3375 8192000000 10019695792 10404085760 1068722224 11.540 99 60 118 1 1142417728 61635504 12060000
02:40:56 4056 8192000000 10066772672 10455138304 1098004848 11.819 100 66 125 2 1184297584 73406416 12886320
02:42:05 10199 8192000000 10099785888 10501480448 1111639280 11.948 100 67 134 0 1226612096 100723504 14249312
02:43:21 5139 8192000000 10165910624 10559270912 1119977136 12.027 100 78 144 3 1281471712 146683840 14810736
02:44:28 9819 8192000000 10214618208 10602840064 1131914192 12.139 100 90 172 1 1331602688 181060624 18627872
02:45:41 1696 8192000000 10224239120 10621366272 1140860800 12.224 100 89 166 2 1341966208 182721120 18384288
02:46:45 2719 8192000000 10256251712 10659454976 1149119024 12.301 100 89 176 1 1374203392 205983856 19100512
02:47:50 1173 8192000000 10256285040 10665795584 1158797776 12.392 100 96 184 1 1373061024 192549616 21713632
02:48:54 1347 8192000000 10289762192 10691923968 1165879632 12.458 100 80 156 0 1398228928 214757792 17591504
02:49:58 2616 8192000000 10317370064 10725416960 1167056688 12.469 100 90 180 0 1429391744 242490832 19844224
02:51:07 2648 8192000000 10327540800 10735820800 1174794448 12.542 100 84 164 2 1440467472 245446064 20226960
02:52:12 1192 8192000000 10369820960 10777337856 1167744336 12.476 100 96 191 0 1479479520 289180256 22554928
02:53:19 3943 8192000000 10336143968 10795577344 1164520624 12.446 100 80 152 1 1450732960 269342528 16869808
02:54:26 942 8192000000 10341821504 10799710208 1163634704 12.437 100 94 180 1 1447193072 263202208 20356160
02:55:31 1032 8192000000 10331861760 10735288320 1170956400 12.506 100 94 177 1 1428228128 238393072 18878656
02:56:37 953 8192000000 10368526528 10774265856 1168532176 12.483 100 83 162 2 1471947744 285043696 18371872
02:57:41 887 8192000000 10394677200 10807037952 1177395040 12.566 100 99 191 0 1501443760 301896672 22152048
02:58:45 2318 8192000000 10386138224 10821144576 1169888240 12.496 100 95 186 0 1487670432 296045936 21736256
02:59:50 939 8192000000 10358311200 10783981568 1163551136 12.437 99 93 182 0 1465325808 280461584 21313088
03:00:54 1584 8192000000 10394079008 10822713344 1172506928 12.520 100 91 178 0 1499721648 306429792 20784928
03:01:58 963 8192000000 10371099920 10808680448 1154698576 12.354 100 98 189 1 1475715184 298680896 22335712
03:03:06 1668 8192000000 10374779408 10815193088 1160644160 12.409 100 74 147 1 1479389632 301387424 17358048
03:04:10 6382 8192000000 10386071552 10825895936 1168250048 12.480 100 67 131 0 1502320288 318370480 15699760
03:05:36 14657 8192000000 10399349616 10855186432 1169671168 12.494 100 28 56 0 1494294320 317208000 7415152
03:06:54 47958 8192000000 10396090384 10873352192 1145212464 12.265 98 30 60 0 1493585328 341105024 7267840
03:08:44 16077 8192000000 10371057664 10823475200 1163407312 12.435 100 28 56 0 1465902128 295004704 7490112
03:10:10 45811 8192000000 10249629392 10806136832 1158159424 12.386 100 22 43 0 1448893200 285473920 5259856
03:12:15 39489 8192000000 10123005968 10559111168 1136795648 12.185 98 15 30 0 1213052672 72933248 3323776
03:14:23 49718 8192000000 10147969856 10636177408 1156239056 12.368 100 61 116 1 1271167680 100223280 14705344
03:16:21 72734 8192000000 10101312160 10600845312 1136231872 12.180 99 12 22 0 1190044176 51461040 2351264
03:18:34 94157 8192000000 10100345520 10566914048 1139660368 12.212 99 24 46 1 1216932704 72182768 5089568
03:21:09 46295 8192000000 10120640080 10567639040 1125945856 12.083 97 25 49 1 1190466672 59188464 5332352
03:22:58 45119 8192000000 10150689984 10613710848 1156498048 12.370 100 35 71 0 1235325360 70350736 8476576
03:24:43 61857 8192000000 10147097280 10641108992 1138548528 12.202 99 22 43 1 1262140992 119140096 4452368
03:26:45 113856 8192000000 10128856416 10620526592 1145029424 12.263 99 21 42 1 1208446592 58929200 4487968
03:29:40 55902 8192000000 10075920816 10533568512 1132729136 12.147 99 46 89 0 1169388864 26569440 10090288
03:31:37 20657 8192000000 10091672768 10534461440 1132355184 12.144 99 43 82 0 1176215552 35843568 8016800
03:32:58 50945 8192000000 10096338672 10566930432 1141175088 12.227 100 12 24 0 1195655520 51460464 3019968
03:35:48 37244 8192000000 10069367824 10585980928 1132648608 12.146 99 3 4 0 1165392896 32429232 315056
03:37:32 47991 8192000000 10108945792 10589171712 1142746464 12.241 100 14 25 0 1202399584 57294608 2358512
03:39:22 74694 8192000000 10083025024 10566352896 1135229520 12.171 99 14 28 0 1184635984 46595136 2811328
03:41:44 48322 8192000000 10069168688 10519293952 1107350944 11.907 98 32 64 0 1137268560 23516352 6401264
03:43:33 24980 8192000000 10121604512 10566619136 1132946416 12.149 99 34 68 0 1201895328 62090560 6858352
03:44:59 22477 8192000000 10148058064 10586017792 1142034704 12.235 99 35 66 2 1224026592 74674160 7317728
03:46:24 68682 8192000000 10094381168 10570358784 1106072960 11.895 97 11 22 0 1173446688 64972048 2401680
03:48:35 15431 8192000000 10091038464 10521296896 1119380448 12.021 100 48 97 0 1175800320 46094496 10325376
03:49:55 34498 8192000000 10143437264 10576535552 1105313472 11.888 98 44 88 0 1223411104 109015072 9082560
03:52:07 52835 8192000000 10119360992 10569183232 1101417264 11.851 97 19 38 0 1170929184 65677808 3834112
03:54:07 68581 8192000000 10063096576 10497773568 1062539712 11.481 94 18 36 0 1117944048 51572096 3832240
03:56:16 112141 8192000000 10067277824 10511085568 1114900112 11.979 99 36 72 0 1169762944 47693648 7169184
03:59:13 74636 8192000000 10062189888 10539765760 1132292000 12.143 100 10 20 0 1187189568 52030848 2866720
04:01:28 65987 8192000000 10075842320 10535542784 1119386224 12.021 99 9 18 0 1169905168 48130976 2387968
04:03:34 68329 8192000000 10095107280 10591547392 1134344128 12.162 100 9 18 0 1218730096 82605424 1780544
04:05:42 31231 8192000000 10058269248 10509189120 1125061296 12.075 100 25 50 0 1166796160 37119568 4615296
04:07:17 26870 8192000000 10085604576 10525503488 1103211680 11.868 97 31 60 1 1154723008 45347696 6163632
04:08:44 24784 8192000000 10122294304 10563461120 1114651616 11.976 98 24 47 0 1209835984 90436064 4748304
04:10:21 16867 8192000000 10164626992 10592309248 1112395600 11.955 99 64 123 4 1248997504 121563216 15038688
04:11:42 52741 8192000000 10098570400 10571440128 1106054624 11.895 98 19 38 0 1200832336 90815584 3962128
04:13:38 59882 8192000000 10085996528 10561695744 1126242176 12.086 99 25 50 0 1189801680 58996768 4562736
04:15:38 48997 8192000000 10110294992 10588704768 1138580816 12.202 100 27 52 0 1209398400 65609568 5208016
04:17:32 97807 8192000000 10130902336 10574053376 1141200000 12.227 99 39 77 0 1214966352 64940240 8826112
04:20:10 121376 8192000000 10137178608 10702807040 1135117760 12.170 99 9 18 0 1229693760 92473024 2102976
04:23:12 77987 8192000000 10092276128 10596388864 1116987872 11.999 97 18 37 0 1189606176 68496704 4121600
04:25:34 121776 8192000000 10125212336 10599772160 1114514304 11.975 97 11 22 0 1197161504 80253664 2393536
04:28:42 96807 8192000000 10150650976 10621063168 1133045808 12.150 98 26 50 0 1272927120 134090384 5790928
04:31:33 108077 8192000000 10090902000 10532855808 1070156800 11.554 94 20 40 0 1145897744 71653248 4087696
04:34:38 70813 8192000000 10063280416 10486054912 1072561600 11.577 95 34 67 0 1129181376 49266112 7353664
04:36:50 67180 8192000000 9141669184 9412931584 277941728 3.281 25 12 24 0 320829584 40181568 2706288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-12-2022 04:40:19 to 21-12-2022 04:40:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 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 21-12-2022 04:40:36 to 21-12-2022 04:40:38.

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          216466
MON$OLDEST_ACTIVE               216467
MON$OLDEST_SNAPSHOT             216467
MON$NEXT_TRANSACTION            216467
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                       2089696
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:273: 
:274: mscs-test-161.budzdorov.ru    Wed Dec 21 04:38:18 2022
:275:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:276: 
--- end of diff output ---

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

21.12.22 04:40:39. 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-12-21 04:40:42 No severe PSQL-related problems occured
21.12.22 04:40:43. End of report.