Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.04.2022 10:38
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 16268348 kB
MemAvailable 31537968 kB
Buffers 0 kB
Cached 15465820 kB
SwapCached 2688 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-04-16 10:37:15
DTS_END                         2022-04-16 10:37:15.4620
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/38192
TRN_ID                          208139
ATT_ID                          648
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: 8638.39 27865 1036607
customer order: creation 165.44 494 19853
customer order: refuse 14.48 403 1738
order to supplier: creation 43.02 1401 5162
order to supplier: removal 3.20 177303 384
invoice (draft): creation 44.66 3511 5359
invoice (draft): removal 46.70 1112 5604
invoice accept: apply 40.48 24338 4858
invoice accept: cancel 41.90 1211 5028
customer reserve: creation 3775.94 231 453113
customer reserve: removal 4188.23 106 502587
realization accept: apply 79.29 272 9515
realization accept: cancel 68.63 172 8236
payment from customer: creation 58.22 168 6986
payment from customer: removal 5.33 126 639
payment to supplier: creation 46.78 209 5613
payment to supplier: removal 3.56 129 427
service: total inventory turnovers 4.03 3640 483
service: total monetary turnovers 7.49 342 899
service: aggregate perf. data 0.99 13855 119
service: refresh index statistics 0.03 328286 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-04-2022 10:39:11 to 16-04-2022 10:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6342
TEST_TIME 2 5692
TEST_TIME 3 5991
TEST_TIME 4 5810
TEST_TIME 5 5787
TEST_TIME 6 5935
TEST_TIME 7 5635
TEST_TIME 8 6097
TEST_TIME 9 6278
TEST_TIME 10 6305
TEST_TIME 11 6374
TEST_TIME 12 6503
TEST_TIME 13 6551
TEST_TIME 14 6869
TEST_TIME 15 6973
TEST_TIME 16 7101
TEST_TIME 17 7316
TEST_TIME 18 7463
TEST_TIME 19 7830
TEST_TIME 20 8185
TEST_TIME 21 8538
TEST_TIME 22 8345
TEST_TIME 23 8418
TEST_TIME 24 8401
TEST_TIME 25 8594
TEST_TIME 26 9128
TEST_TIME 27 9240
TEST_TIME 28 9732
TEST_TIME 29 9630
TEST_TIME 30 9771
TEST_TIME 31 10151
TEST_TIME 32 10541
TEST_TIME 33 11007
TEST_TIME 34 10813
TEST_TIME 35 11005
TEST_TIME 36 10811
TEST_TIME 37 10780
TEST_TIME 38 10647
TEST_TIME 39 10831
TEST_TIME 40 10720
TEST_TIME 41 10532
TEST_TIME 42 10398
TEST_TIME 43 10291
TEST_TIME 44 10225
TEST_TIME 45 10215
TEST_TIME 46 10086
TEST_TIME 47 9923
TEST_TIME 48 9889
TEST_TIME 49 9793
TEST_TIME 50 9735
TEST_TIME 51 9714
TEST_TIME 52 9589
TEST_TIME 53 9492
TEST_TIME 54 9423
TEST_TIME 55 9328
TEST_TIME 56 9361
TEST_TIME 57 9379
TEST_TIME 58 9266
TEST_TIME 59 9171
TEST_TIME 60 9071
TEST_TIME 61 9006
TEST_TIME 62 8973
TEST_TIME 63 8910
TEST_TIME 64 8891
TEST_TIME 65 8948
TEST_TIME 66 8941
TEST_TIME 67 8930
TEST_TIME 68 8963
TEST_TIME 69 8952
TEST_TIME 70 8948
TEST_TIME 71 8921
TEST_TIME 72 8958
TEST_TIME 73 8942
TEST_TIME 74 8938
TEST_TIME 75 8965
TEST_TIME 76 8971
TEST_TIME 77 9053
TEST_TIME 78 9005
TEST_TIME 79 8903
TEST_TIME 80 8923
TEST_TIME 81 8877
TEST_TIME 82 8862
TEST_TIME 83 8805
TEST_TIME 84 8766
TEST_TIME 85 8717
TEST_TIME 86 8756
TEST_TIME 87 8800
TEST_TIME 88 8759
TEST_TIME 89 8721
TEST_TIME 90 8713
TEST_TIME 91 8710
TEST_TIME 92 8687
TEST_TIME 93 8693
TEST_TIME 94 8688
TEST_TIME 95 8908
TEST_TIME 96 9031
TEST_TIME 97 9020
TEST_TIME 98 9015
TEST_TIME 99 9100
TEST_TIME 100 9073
TEST_TIME 101 9059
TEST_TIME 102 8998
TEST_TIME 103 8948
TEST_TIME 104 8914
TEST_TIME 105 8897
TEST_TIME 106 8844
TEST_TIME 107 8822
TEST_TIME 108 8796
TEST_TIME 109 8807
TEST_TIME 110 8775
TEST_TIME 111 8770
TEST_TIME 112 8805
TEST_TIME 113 8763
TEST_TIME 114 8751
TEST_TIME 115 8748
TEST_TIME 116 8774
TEST_TIME 117 8741
TEST_TIME 118 8739
TEST_TIME 119 8724
TEST_TIME 120 8659

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-04-2022 10:39:15 to 16-04-2022 10:39:18.

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 1453671 1453671 0 0.00 0 29365 13 0 0
doc_list_biud 1453664 1453664 0 0.00 0 20678 43 0 0
sp_add_invoice_to_stock 4934 4858 76 1.54 60 168482 24338 0 76
sp_cancel_adding_invoice 5028 5028 0 0.00 8 87581 1212 0 0
sp_cancel_client_order 1738 1738 0 0.00 5 9587 404 0 0
sp_cancel_customer_reserve 502587 502587 0 0.00 1 19657 106 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 4 10270 126 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 4 6017 130 0 0
sp_cancel_supplier_invoice 5617 5604 13 0.23 4 20685 1112 0 13
sp_cancel_supplier_order 384 384 0 0.00 12 1315485 177303 0 0
sp_cancel_write_off 8236 8236 0 0.00 2 9822 172 0 0
sp_client_order 19853 19853 0 0.00 6 13760 494 0 0
sp_customer_reserve 453113 453113 0 0.00 1 72335 231 0 0
sp_fill_shopping_cart 30359 30359 0 0.00 2 24409 87 0 0
sp_fill_shopping_cart_clo_res 453089 453089 0 0.00 0 12498 19 0 0
sp_get_clo_for_invoice 4824 4824 0 0.00 0 32919 344 0 0
sp_kill_cost_storno 14330 14330 0 0.00 0 29359 83 0 0
sp_kill_qstorno_ret_qs2qd 1019398 1019398 0 0.00 0 20677 47 0 0
sp_kill_qty_storno 537915 537915 0 0.00 0 20678 107 0 0
sp_lock_selected_doc 19851 19851 0 0.00 0 8795 6 0 0
sp_make_cost_storno 26938 26938 0 0.00 0 25887 82 0 0
sp_multiply_rows_for_pdistr 26938 26938 0 0.00 0 6344 35 0 0
sp_multiply_rows_for_qdistr 19853 19853 0 0.00 0 11343 195 0 0
sp_pay_from_customer 6986 6986 0 0.00 6 7890 168 0 0
sp_pay_to_supplier 5613 5613 0 0.00 7 25904 209 0 0
sp_payment_common 12598 12598 0 0.00 2 7316 45 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 9579 329 0 0
sp_qd_handle_on_invoice_upd_sts 9851 9851 0 0.00 0 11347 523 0 0
sp_qd_handle_on_reserve_upd_sts 9515 9515 0 0.00 0 17617 66 0 0
sp_reserve_write_off 9515 9515 0 0.00 7 17952 273 0 0
sp_supplier_invoice 5359 5359 0 0.00 10 81557 3511 0 0
sp_supplier_order 5162 5162 0 0.00 8 30275 1402 0 0
srv_aggregate_perf_data 119 119 0 0.00 2332 187590 13855 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 850 120096 34594 0 0
srv_find_qd_qs_mism 988972 988972 0 0.00 0 16793 10 0 0
srv_make_invnt_saldo 483 483 0 0.00 76 73633 3640 0 0
srv_make_money_saldo 899 899 0 0.00 1 12636 342 0 0
srv_recalc_idx_stat 4 4 0 0.00 87659 667555 328286 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4863 4863 0 0.00 1 26867 806 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 1 8190 355 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 6407 31 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 2148 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 6015 34 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 2936 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 4436 53 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 99203 7946 0 0
t$perf-norm:sp_cancel_write_off 943 943 0 0.00 0 1956 17 0 0
t$perf-norm:sp_client_order 19853 19853 0 0.00 0 22049 34 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 367 12 0 0
t$perf-norm:sp_pay_from_customer 6986 6986 0 0.00 0 4121 18 0 0
t$perf-norm:sp_pay_to_supplier 5613 5613 0 0.00 0 10339 22 0 0
t$perf-norm:sp_reserve_write_off 9515 9515 0 0.00 0 5183 22 0 0
t$perf-norm:sp_supplier_invoice 5359 5359 0 0.00 0 7743 29 0 0
t$perf-norm:sp_supplier_order 5163 5163 0 0.00 0 4753 31 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 308 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 414 15 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 1313 18 0 0
t$perf-norm:srv_make_money_saldo 899 899 0 0.00 0 6759 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 157 50 0 0
v_add_invoice_to_stock 9794 9642 152 1.55 0 5086 24 0 152
v_all_customers 36732 36732 0 0.00 0 96 3 0 0
v_all_suppliers 29396 29396 0 0.00 0 171 4 0 0
v_all_wares 39702 39702 0 0.00 0 67 3 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 4266 20 0 0
v_cancel_client_order 3476 3476 0 0.00 0 5469 13 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 2640 14 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 4792 14 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 5943 25 0 0
v_cancel_supplier_invoice 768 742 26 3.38 0 291 19 0 26
v_cancel_supplier_order 768 768 0 0.00 0 2596 17 0 0
v_cancel_write_off 1886 1886 0 0.00 0 2808 23 0 0
v_max_id_clo_ord 5159 5159 0 0.00 0 342 4 0 0
v_max_id_clo_res 984 984 0 0.00 0 4141 19 0 0
v_max_id_ord_sup 5349 5349 0 0.00 0 4173 7 0 0
v_max_non_paid_invoice 1423 1423 0 0.00 0 161 7 0 0
v_max_non_paid_realizn 3478 3478 0 0.00 0 2526 8 0 0
v_min_id_clo_ord 5159 5159 0 0.00 0 3002 10 0 0
v_min_id_clo_res 984 984 0 0.00 0 9554 20 0 0
v_min_id_ord_sup 5349 5349 0 0.00 0 6215 18 0 0
v_min_non_paid_invoice 5613 5613 0 0.00 0 6065 17 0 0
v_min_non_paid_realizn 6985 6985 0 0.00 0 5670 18 0 0
v_our_firm 9982 9982 0 0.00 0 149 4 0 0
v_reserve_write_off 19030 19030 0 0.00 0 6001 13 0 0
x_lock_depdocs_on_canc_invoice 10261 10261 0 0.00 0 14744 123 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 5088 330 0 0
x_make_qstorno_1000_1200 5162 5162 0 0.00 2 30267 1244 0 0
x_make_qstorno_1200_2000 5359 5359 0 0.00 5 78123 3253 0 0
x_make_qstorno_2100_3300 453112 453112 0 0.00 0 72332 202 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-04-2022 10:39:19 to 16-04-2022 10:39:23.

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
08:07:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:20 22783 8192000000 9728925456 10027782144 807317264 8.970 100 95 190 1 839507312 8394320 23795728
08:09:55 20584 8192000000 9769224320 10079145984 842370064 9.324 100 90 180 0 877079232 13230640 21478528
08:11:20 13463 8192000000 9802045760 10119716864 865385056 9.554 100 86 172 0 909097776 23289632 20423088
08:12:43 11405 8192000000 9875193584 10207191040 924830128 10.144 100 81 162 0 984247616 41238704 18178784
08:13:56 6372 8192000000 9928712176 10274942976 970187248 10.589 100 76 152 0 1036989680 50795136 16007296
08:15:05 2680 8192000000 9980183696 10340532224 1005673312 10.933 100 89 177 0 1100103328 76827440 17602576
08:16:10 831 8192000000 10017981936 10376761344 1023105024 11.102 100 94 186 0 1135925744 93716976 19103744
08:17:13 866 8192000000 10021711808 10394103808 1046343408 11.326 100 93 186 0 1142674816 78068192 18263216
08:18:15 1025 8192000000 10037817552 10414002176 1055383776 11.412 100 97 177 2 1156438256 82605984 18448496
08:19:18 970 8192000000 10069730816 10446925824 1066858720 11.522 100 96 186 0 1191420528 105155168 19406640
08:20:19 6962 8192000000 10050006720 10425430016 1074972816 11.600 100 87 163 0 1164464640 74294016 15197808
08:21:27 7066 8192000000 10053373520 10436943872 1080498240 11.652 100 92 183 1 1184547520 85650304 18398976
08:22:35 1207 8192000000 10073433952 10456817664 1088493568 11.728 100 91 172 1 1192469696 85913952 18062176
08:23:38 1306 8192000000 10052969648 10433069056 1095070736 11.791 100 98 190 4 1175656976 61894672 18691568
08:24:40 1193 8192000000 10082723456 10475982848 1098886672 11.827 100 94 180 2 1205542176 89999504 16656000
08:25:43 1275 8192000000 10075140816 10466500608 1100798320 11.845 100 85 164 0 1196036752 80581296 14657136
08:26:45 1145 8192000000 10075089568 10468417536 1100292656 11.840 100 92 176 1 1202816384 83599504 18924224
08:27:47 1084 8192000000 10048363200 10438152192 1096535120 11.805 100 86 163 2 1171447888 57002864 17909904
08:28:49 1421 8192000000 10033422320 10425094144 1101860032 11.855 100 74 139 2 1155063344 39059168 14144144
08:29:51 694 8192000000 10043870368 10433671168 1097515344 11.814 100 87 171 0 1167034896 50403984 19115568
08:30:53 1215 8192000000 10049772272 10429927424 1086651776 11.711 100 95 180 0 1164011472 59661744 17697952
08:31:54 1125 8192000000 10043297344 10422788096 1074198128 11.592 100 99 189 2 1164229056 70824224 19206704
08:32:57 1149 8192000000 10029473136 10411528192 1062325232 11.479 100 90 171 1 1156469680 76047056 18097392
08:33:59 943 8192000000 9996358544 10372767744 1046931552 11.331 100 96 181 1 1115682816 49576752 19174512
08:35:01 977 8192000000 9953428800 10319163392 1016365392 11.037 99 90 167 1 1074132384 41020336 16746656
08:36:02 3890 8192000000 9965107616 10344030208 1041251360 11.277 100 66 126 1 1095784832 42188608 12344864
08:37:07 1776 8192000000 9987658448 10362191872 1047264032 11.334 100 72 130 3 1108639712 47583360 13792320
08:38:16 11817 8192000000 10008911424 10390999040 1071578640 11.567 100 71 113 2 1126163056 43894688 10689728
08:39:35 5674 8192000000 10047695376 10428116992 1093543472 11.776 100 82 152 1 1171442112 63521120 14377520
08:40:45 1126 8192000000 10099288272 10483224576 1109076720 11.924 100 96 185 1 1219677360 90849216 19751424
08:41:51 4359 8192000000 10135812448 10530312192 1125348144 12.077 100 77 144 2 1251464368 109956848 16159376
08:42:59 5218 8192000000 10172863216 10571079680 1133372896 12.153 100 68 129 1 1291300224 144236656 13690672
08:44:07 12181 8192000000 10190365408 10595287040 1147692464 12.288 100 57 111 0 1306238080 146388496 12157120
08:45:24 873 8192000000 10249784192 10646048768 1158924208 12.393 100 97 187 0 1366374320 184628496 22821616
08:46:29 2218 8192000000 10261787056 10657853440 1163884656 12.440 100 85 162 0 1371524512 190354928 17284928
08:47:35 970 8192000000 10301069824 10702123008 1167069376 12.469 100 92 181 2 1413633392 225342880 21221136
08:48:39 1089 8192000000 10351214576 10753015808 1171104208 12.507 100 93 183 0 1459277472 268189232 19984032
08:49:45 1377 8192000000 10355017472 10773667840 1178623152 12.577 100 87 168 0 1469698496 269697904 21377440
08:50:51 3010 8192000000 10374432176 10799116288 1196072544 12.740 100 86 168 1 1483685648 268556400 19056704
08:51:57 1484 8192000000 10403219648 10820575232 1192333200 12.705 100 87 168 1 1525972656 312524384 21115072
08:53:01 941 8192000000 10451463008 10866225152 1194847280 12.728 100 97 187 4 1558600992 341614496 22139216
08:54:04 1438 8192000000 10499793104 10934079488 1198962384 12.767 100 99 196 0 1603959568 381390192 23606992
08:55:07 841 8192000000 10507171904 10940510208 1201023840 12.786 100 89 175 1 1618582208 396052896 21505472
08:56:11 1240 8192000000 10511590000 10962010112 1198105760 12.759 100 95 185 0 1617829376 396067520 23656096
08:57:14 6084 8192000000 10491782496 10950619136 1189887824 12.682 100 64 126 0 1599285472 392806960 16590688
08:58:24 14325 8192000000 10453285360 10906148864 1182333920 12.612 99 43 86 0 1566666928 373032544 11300464
08:59:39 12184 8192000000 10489431504 10932142080 1166249936 12.462 99 46 92 0 1588499728 410221600 12028192
09:00:55 10650 8192000000 10516366224 10959671296 1177718480 12.569 100 47 93 0 1617751904 428803360 11230064
09:02:15 7267 8192000000 10513400768 10971361280 1157307664 12.378 99 51 100 0 1605346928 435212784 12826480
09:03:24 7354 8192000000 10449912080 10940223488 1152589648 12.334 100 47 94 0 1557417408 392967712 11860048
09:04:34 37378 8192000000 10391122336 10871087104 1151475616 12.323 100 33 66 1 1553956800 393887968 8593216
09:06:27 36777 8192000000 10420761280 10912161792 1152524960 12.333 100 24 48 0 1511655952 352831440 6299552
09:08:20 30634 8192000000 10284689920 10784927744 1133517872 12.155 98 25 48 2 1441776160 301763648 6494640
09:09:52 19590 8192000000 10203923440 10729283584 1146538496 12.277 100 33 66 0 1294440512 140002800 7899216
09:11:14 29389 8192000000 10204754800 10636320768 1148166992 12.292 100 37 74 0 1299978368 143699712 8111664
09:12:51 42924 8192000000 10184349120 10636832768 1146417712 12.276 99 16 32 3 1268154704 118080400 3656592
09:16:56 53727 8192000000 10060329504 10525491200 1118120432 12.009 98 10 20 0 1166268928 46260528 1887968
09:18:51 77115 8192000000 10096038272 10548494336 1115870384 11.988 97 32 61 2 1157722752 35060704 6791664
09:21:20 54127 8192000000 10108097424 10580422656 1129178784 12.114 99 37 71 1 1211261008 74256576 7825648
09:23:15 81876 8192000000 10070027408 10498244608 1115565840 11.985 98 32 55 0 1158939776 36732800 6641136
09:25:38 99335 8192000000 10094566880 10542362624 1102567264 11.862 96 36 67 0 1155651856 45743504 7341088
09:29:14 46088 8192000000 10060574928 10508808192 1115551552 11.985 98 25 50 0 1156405040 35349616 5503872
09:31:13 25828 8192000000 10080477104 10513584128 1133191856 12.151 100 36 66 3 1184196880 43986224 7018800
09:32:42 23854 8192000000 10149243408 10582872064 1140285984 12.218 100 53 105 1 1243318400 92134768 10897648
09:34:07 76173 8192000000 10071494688 10545119232 1125454880 12.078 99 6 12 0 1166069520 39417856 1196784
09:37:09 64387 8192000000 10064476128 10507304960 1128390720 12.106 99 22 42 0 1165908032 32988176 4529136
09:39:19 96716 8192000000 10078419008 10555703296 1105251680 11.887 98 7 14 0 1140032864 33663264 1117920
09:41:58 75270 8192000000 10067137280 10527940608 1124135168 12.066 98 15 30 0 1189638208 62578336 2924704
09:44:13 44011 8192000000 10072389136 10500751360 1099851984 11.836 96 35 66 2 1140349584 33225344 7272256
09:45:57 15106 8192000000 10081911440 10510843904 1134573904 12.164 99 40 77 3 1160948160 18298352 8075904
09:47:13 60578 8192000000 10094576544 10543439872 1141914720 12.234 100 37 73 0 1192387888 43046144 7427024
09:49:18 67706 8192000000 10075713984 10511433728 1068886192 11.541 93 39 76 2 1110677248 33947120 7843936
09:51:26 29525 8192000000 10121131392 10543325184 1123185120 12.057 99 34 68 0 1196153136 65887024 7080992
09:53:00 23563 8192000000 10100595584 10546970624 1132565792 12.146 100 45 90 0 1193419040 51886736 8966512
09:54:39 48897 8192000000 10094371296 10533457920 1130816688 12.129 100 23 46 0 1170888992 34885040 5187264
09:56:30 80334 8192000000 10046019536 10473435136 1088526944 11.729 96 40 80 0 1162032464 64393792 9111728
09:58:54 17153 8192000000 10089307568 10526212096 1106024304 11.895 98 38 72 1 1173972736 60935200 7013232
10:00:11 44039 8192000000 10090603760 10539274240 1117897040 12.007 99 19 38 3 1188305008 65839168 4568800
10:01:56 70486 8192000000 10129491248 10579181568 1116067664 11.990 98 18 36 1 1212557088 92290720 4198704
10:04:11 53161 8192000000 10106330432 10568683520 1123392528 12.059 99 19 38 0 1205017344 77529312 4095504
10:06:05 40950 8192000000 10153298816 10594873344 1129590752 12.118 99 23 46 1 1237096256 102303168 5202336
10:07:54 15074 8192000000 10209724208 10640793600 1157480960 12.380 100 39 76 2 1295988384 130049648 8457776
10:09:16 7324 8192000000 10238044048 10665123840 1168767424 12.485 100 62 114 2 1322937808 141581376 12589008
10:10:25 71327 8192000000 10293367360 10747564032 1147307824 12.284 99 23 46 2 1350971312 197706640 5956848
10:12:38 120096 8192000000 10087531664 10566029312 1077606560 11.625 95 13 25 4 1119219200 38759888 2852752
10:15:45 31059 8192000000 10125252048 10553765888 1114711856 11.977 99 41 82 0 1207880896 84088256 9080784
10:17:21 79420 8192000000 10063433888 10557288448 1106221584 11.897 98 7 14 1 1153241008 45796512 1222912
10:20:25 10937 8192000000 10066186800 10505981952 1137324064 12.190 100 50 98 3 1163348560 15753744 10270752
10:21:39 76128 8192000000 10064605040 10540879872 1121729344 12.043 99 12 24 0 1173214480 48611136 2874000
10:24:02 52231 8192000000 10083447824 10528264192 1130023600 12.122 99 15 30 0 1171559392 38172304 3363488
10:26:42 74299 8192000000 10076787104 10552463360 1127016048 12.093 99 10 19 2 1181408032 52518352 1873632
10:28:59 34289 8192000000 10070324064 10519584768 1105900128 11.894 98 33 57 2 1161000528 48453968 6646432
10:30:47 37063 8192000000 10167956432 10604990464 1126039904 12.084 99 43 85 0 1235597328 100579376 8978048
10:32:54 29333 8192000000 10201167312 10638585856 1150895472 12.318 100 37 73 1 1296544944 137721216 7928256
10:34:23 31892 8192000000 10219497440 10656227328 1144537920 12.258 99 39 78 0 1307446736 154356944 8551872
10:35:58 35814 8192000000 10256552800 10681356288 1142981488 12.244 99 32 64 0 1328626208 178069920 7574800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-04-2022 10:39:25 to 16-04-2022 10:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-04-2022 10:39:42 to 16-04-2022 10:39:44.

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          206621
MON$OLDEST_ACTIVE               216969
MON$OLDEST_SNAPSHOT             216969
MON$NEXT_TRANSACTION            216969
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                       2080608
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.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:167: 
:168: mscs-test-161.budzdorov.ru    Sat Apr 16 10:37:30 2022
:169:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:170: 
:171: 
:172: mscs-test-161.budzdorov.ru    Sat Apr 16 10:37:34 2022
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:174: 
:175: 
:176: mscs-test-161.budzdorov.ru    Sat Apr 16 10:37:35 2022
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:178: 
:179: 
:180: mscs-test-161.budzdorov.ru    Sat Apr 16 10:37:35 2022
:181:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:182: 
--- end of diff output ---

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

16.04.22 10:39:45. 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-04-16 10:39:47 No severe PSQL-related problems occured
16.04.22 10:39:49. End of report.