Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.11.2021 13: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 16751036 kB
MemAvailable 31596064 kB
Buffers 0 kB
Cached 14951792 kB
SwapCached 0 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.1
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 2021-11-22 13:37:09
DTS_END                         2021-11-22 13:37:09.1600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55932
TRN_ID                          226892
ATT_ID                          598
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: 8700.17 27504 1044021
customer order: creation 161.96 430 19435
customer order: refuse 14.38 428 1725
order to supplier: creation 43.39 1475 5207
order to supplier: removal 3.38 122871 406
invoice (draft): creation 44.58 3754 5349
invoice (draft): removal 49.48 1052 5938
invoice accept: apply 39.93 21192 4792
invoice accept: cancel 44.35 984 5322
customer reserve: creation 3683.96 208 442075
customer reserve: removal 4329.71 75 519565
realization accept: apply 80.93 202 9711
realization accept: cancel 80.27 131 9632
payment from customer: creation 56.52 138 6782
payment from customer: removal 5.87 103 704
payment to supplier: creation 47.41 152 5689
payment to supplier: removal 3.49 87 419
service: total inventory turnovers 3.63 4252 435
service: total monetary turnovers 5.98 627 718
service: aggregate perf. data 0.94 13923 113
service: refresh index statistics 0.03 378001 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-11-2021 13:38:38 to 22-11-2021 13:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5027
TEST_TIME 2 4183
TEST_TIME 3 4629
TEST_TIME 4 4173
TEST_TIME 5 5558
TEST_TIME 6 5348
TEST_TIME 7 5598
TEST_TIME 8 5510
TEST_TIME 9 5590
TEST_TIME 10 5824
TEST_TIME 11 5908
TEST_TIME 12 5903
TEST_TIME 13 5890
TEST_TIME 14 6314
TEST_TIME 15 6396
TEST_TIME 16 6762
TEST_TIME 17 6819
TEST_TIME 18 7025
TEST_TIME 19 7099
TEST_TIME 20 7410
TEST_TIME 21 7767
TEST_TIME 22 7731
TEST_TIME 23 8092
TEST_TIME 24 7961
TEST_TIME 25 8494
TEST_TIME 26 8537
TEST_TIME 27 8756
TEST_TIME 28 9226
TEST_TIME 29 10093
TEST_TIME 30 10545
TEST_TIME 31 10347
TEST_TIME 32 10177
TEST_TIME 33 10014
TEST_TIME 34 10003
TEST_TIME 35 9851
TEST_TIME 36 9988
TEST_TIME 37 9888
TEST_TIME 38 9754
TEST_TIME 39 9766
TEST_TIME 40 9676
TEST_TIME 41 9499
TEST_TIME 42 9441
TEST_TIME 43 9278
TEST_TIME 44 9196
TEST_TIME 45 9119
TEST_TIME 46 9120
TEST_TIME 47 9132
TEST_TIME 48 9099
TEST_TIME 49 9208
TEST_TIME 50 9182
TEST_TIME 51 9189
TEST_TIME 52 9226
TEST_TIME 53 9255
TEST_TIME 54 9166
TEST_TIME 55 9318
TEST_TIME 56 9362
TEST_TIME 57 9387
TEST_TIME 58 9422
TEST_TIME 59 9424
TEST_TIME 60 9468
TEST_TIME 61 9421
TEST_TIME 62 9286
TEST_TIME 63 9231
TEST_TIME 64 9175
TEST_TIME 65 9202
TEST_TIME 66 9165
TEST_TIME 67 9140
TEST_TIME 68 9093
TEST_TIME 69 9044
TEST_TIME 70 9010
TEST_TIME 71 8991
TEST_TIME 72 8931
TEST_TIME 73 8959
TEST_TIME 74 8935
TEST_TIME 75 8915
TEST_TIME 76 8921
TEST_TIME 77 8994
TEST_TIME 78 9073
TEST_TIME 79 9035
TEST_TIME 80 9093
TEST_TIME 81 9092
TEST_TIME 82 9165
TEST_TIME 83 9152
TEST_TIME 84 9210
TEST_TIME 85 9166
TEST_TIME 86 9172
TEST_TIME 87 9086
TEST_TIME 88 9065
TEST_TIME 89 8996
TEST_TIME 90 8989
TEST_TIME 91 8942
TEST_TIME 92 8915
TEST_TIME 93 8891
TEST_TIME 94 8896
TEST_TIME 95 8897
TEST_TIME 96 8909
TEST_TIME 97 8897
TEST_TIME 98 8933
TEST_TIME 99 8950
TEST_TIME 100 8945
TEST_TIME 101 8946
TEST_TIME 102 8956
TEST_TIME 103 8992
TEST_TIME 104 8997
TEST_TIME 105 9008
TEST_TIME 106 9053
TEST_TIME 107 9047
TEST_TIME 108 9053
TEST_TIME 109 8980
TEST_TIME 110 8935
TEST_TIME 111 8914
TEST_TIME 112 8875
TEST_TIME 113 8866
TEST_TIME 114 8821
TEST_TIME 115 8810
TEST_TIME 116 8792
TEST_TIME 117 8797
TEST_TIME 118 8785
TEST_TIME 119 8774
TEST_TIME 120 8702

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-11-2021 13:38:41 to 22-11-2021 13:38:44.

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 1452675 1452675 0 0.00 0 21340 8 0 0
doc_list_biud 1452672 1452672 0 0.00 0 39918 34 0 0
sp_add_invoice_to_stock 4873 4792 81 1.66 38 218004 21193 0 81
sp_cancel_adding_invoice 5322 5322 0 0.00 4 54551 984 0 0
sp_cancel_client_order 1725 1725 0 0.00 5 20058 429 0 0
sp_cancel_customer_reserve 519565 519565 0 0.00 1 25883 75 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 15350 103 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 2011 88 0 0
sp_cancel_supplier_invoice 5952 5938 14 0.23 2 23783 1053 0 14
sp_cancel_supplier_order 406 406 0 0.00 6 1351712 122872 0 0
sp_cancel_write_off 9632 9632 0 0.00 1 16241 131 0 0
sp_client_order 19435 19435 0 0.00 4 21647 431 0 0
sp_customer_reserve 442075 442075 0 0.00 0 101103 209 0 0
sp_fill_shopping_cart 29965 29965 0 0.00 1 23228 50 0 0
sp_fill_shopping_cart_clo_res 442071 442071 0 0.00 0 20914 19 0 0
sp_get_clo_for_invoice 4788 4788 0 0.00 0 21470 435 0 0
sp_kill_cost_storno 16077 16077 0 0.00 0 15333 66 0 0
sp_kill_qstorno_ret_qs2qd 1055106 1055106 0 0.00 0 25875 41 0 0
sp_kill_qty_storno 557087 557087 0 0.00 0 39918 87 0 0
sp_lock_selected_doc 20201 20201 0 0.00 0 9399 5 0 0
sp_make_cost_storno 26970 26970 0 0.00 0 16372 68 0 0
sp_multiply_rows_for_pdistr 26970 26970 0 0.00 0 15324 38 0 0
sp_multiply_rows_for_qdistr 19435 19435 0 0.00 0 21493 212 0 0
sp_pay_from_customer 6782 6782 0 0.00 4 16344 139 0 0
sp_pay_to_supplier 5689 5689 0 0.00 5 15194 153 0 0
sp_payment_common 12470 12470 0 0.00 1 16317 29 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 20052 391 0 0
sp_qd_handle_on_invoice_upd_sts 10110 10110 0 0.00 0 39918 406 0 0
sp_qd_handle_on_reserve_upd_sts 9711 9711 0 0.00 0 14569 57 0 0
sp_reserve_write_off 9711 9711 0 0.00 7 15447 203 0 0
sp_supplier_invoice 5349 5349 0 0.00 6 100200 3754 0 0
sp_supplier_order 5207 5207 0 0.00 6 37225 1476 0 0
srv_aggregate_perf_data 113 113 0 0.00 2721 206790 13923 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1674 127056 46691 0 0
srv_find_qd_qs_mism 998097 998097 0 0.00 0 21340 7 0 0
srv_make_invnt_saldo 435 435 0 0.00 65 142741 4253 0 0
srv_make_money_saldo 718 718 0 0.00 1 12622 628 0 0
srv_recalc_idx_stat 4 4 0 0.00 158229 519335 378002 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4792 4792 0 0.00 0 13280 196 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 1 4807 138 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 3276 11 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 2109 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 492 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 590 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 13811 68 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 25719 1807 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 628 5 0 0
t$perf-norm:sp_client_order 19435 19435 0 0.00 0 23491 23 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 2959 9 0 0
t$perf-norm:sp_pay_from_customer 6782 6782 0 0.00 0 15310 15 0 0
t$perf-norm:sp_pay_to_supplier 5689 5689 0 0.00 0 11305 15 0 0
t$perf-norm:sp_reserve_write_off 9711 9711 0 0.00 0 19556 15 0 0
t$perf-norm:sp_supplier_invoice 5349 5349 0 0.00 0 20301 28 0 0
t$perf-norm:sp_supplier_order 5207 5207 0 0.00 0 3782 17 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 377 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 92 4 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 1167 17 0 0
t$perf-norm:srv_make_money_saldo 718 718 0 0.00 0 1665 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 33 10 0 0
v_add_invoice_to_stock 9738 9576 162 1.66 0 9689 22 0 162
v_all_customers 35526 35526 0 0.00 0 28 1 0 0
v_all_suppliers 29404 29404 0 0.00 0 127 1 0 0
v_all_wares 38870 38870 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 9432 21 0 0
v_cancel_client_order 3450 3450 0 0.00 0 4451 11 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 1170 4 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 3666 9 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1673 8 0 0
v_cancel_supplier_invoice 838 810 28 3.34 0 1722 15 0 28
v_cancel_supplier_order 812 812 0 0.00 0 2331 13 0 0
v_cancel_write_off 1958 1958 0 0.00 0 5270 16 0 0
v_max_id_clo_ord 5193 5193 0 0.00 0 300 1 0 0
v_max_id_clo_res 952 952 0 0.00 0 12068 39 0 0
v_max_id_ord_sup 5336 5336 0 0.00 0 6225 9 0 0
v_max_non_paid_invoice 1516 1516 0 0.00 0 224 2 0 0
v_max_non_paid_realizn 3575 3575 0 0.00 0 2673 4 0 0
v_min_id_clo_ord 5193 5193 0 0.00 0 10339 12 0 0
v_min_id_clo_res 952 952 0 0.00 0 2240 13 0 0
v_min_id_ord_sup 5336 5336 0 0.00 0 9027 10 0 0
v_min_non_paid_invoice 5689 5689 0 0.00 0 9522 20 0 0
v_min_non_paid_realizn 6781 6781 0 0.00 0 7579 20 0 0
v_our_firm 9756 9756 0 0.00 0 21 1 0 0
v_reserve_write_off 19422 19422 0 0.00 0 4900 8 0 0
x_lock_depdocs_on_canc_invoice 10855 10855 0 0.00 0 17103 144 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 7719 417 0 0
x_make_qstorno_1000_1200 5207 5207 0 0.00 1 37218 1377 0 0
x_make_qstorno_1200_2000 5349 5349 0 0.00 2 98960 3575 0 0
x_make_qstorno_2100_3300 442075 442075 0 0.00 0 101100 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-11-2021 13:38:45 to 22-11-2021 13:38:49.

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
11:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 18317 8192000000 9714610256 10011660288 797667792 8.873 100 83 167 0 825371008 7392336 20310880
11:09:36 26530 8192000000 9771570816 10081124352 846229120 9.362 100 85 170 0 877914080 11808576 19876384
11:11:08 14949 8192000000 9806945936 10119327744 868050768 9.581 100 92 184 1 910600384 21221312 21328304
11:12:31 14072 8192000000 9858906448 10193727488 907473840 9.972 100 81 160 1 960844416 35838944 17531632
11:13:49 11739 8192000000 9906050080 10244993024 949337664 10.385 100 78 154 0 1017056192 51724432 15994096
11:15:03 8429 8192000000 9951732704 10302459904 993204192 10.813 100 81 161 0 1066341408 56621936 16515280
11:16:14 3922 8192000000 10005951568 10362925056 1022011216 11.091 100 92 182 1 1116112240 75515184 18585840
11:17:20 3544 8192000000 10024526880 10388234240 1034183696 11.209 100 94 191 0 1137578752 84418928 18976128
11:18:25 2243 8192000000 10050400960 10418941952 1055828688 11.417 100 88 176 1 1156945328 83736704 17379936
11:19:29 33543 8192000000 10024774272 10406699008 1064718944 11.502 100 61 122 2 1145937584 69171792 12046848
11:21:05 5036 8192000000 10014833504 10399485952 1077481424 11.623 100 45 90 1 1137296752 50273936 9541392
11:22:11 5919 8192000000 10007069248 10403241984 1082180512 11.668 100 57 112 1 1117778544 23281648 12316384
11:23:21 56619 8192000000 10010940224 10416766976 1085764160 11.702 100 34 67 1 1098628576 5415552 7448864
11:25:25 7154 8192000000 10015770688 10413412352 1091461344 11.757 100 35 68 0 1131009232 32941360 6606528
11:26:33 5579 8192000000 10012389184 10401951744 1095139680 11.792 100 55 109 1 1124924384 16797328 12987376
11:27:41 13639 8192000000 9994469728 10403565568 1073230848 11.583 99 27 53 2 1112921024 33850352 5839824
11:28:59 40150 8192000000 9966840912 10349142016 1032981408 11.197 96 50 98 1 1051844496 8004080 10859008
11:30:39 6048 8192000000 9957033344 10351005696 1023011232 11.101 97 34 68 0 1054202048 24050816 7140000
11:31:49 7972 8192000000 9936141248 10334105600 1027849792 11.148 99 41 82 1 1050101088 12480320 9770976
11:32:58 7334 8192000000 9958654592 10345988096 1014060016 11.015 98 31 61 1 1044356800 23489088 6807696
11:34:06 29051 8192000000 9953338304 10326913024 1019315392 11.065 98 56 110 3 1045712384 15024976 11372016
11:35:35 9653 8192000000 9950752336 10346246144 1045108880 11.314 100 46 88 1 1073291600 18279408 9903312
11:36:48 10232 8192000000 9981216992 10381598720 1060392672 11.460 100 34 68 1 1094301536 26072912 7835952
11:38:09 9849 8192000000 10005034432 10394513408 1078604224 11.634 100 55 111 1 1106587024 16247200 11735600
11:39:23 74146 8192000000 10058454832 10465558528 1087767728 11.721 100 42 84 0 1177727616 81175440 8784448
11:41:44 16846 8192000000 10081121168 10481057792 1101598592 11.853 100 42 81 1 1189937120 80152544 8185984
11:43:05 20613 8192000000 10070951264 10473455616 1113314160 11.964 100 50 100 0 1177899264 54771568 9813536
11:44:32 2525 8192000000 10123689440 10526257152 1130998800 12.131 100 68 133 0 1236985824 91687456 14299568
11:45:38 8826 8192000000 10136984928 10540822528 1133786832 12.157 100 60 118 1 1240975504 93671920 13516752
11:46:52 15215 8192000000 10191084672 10602373120 1141852752 12.233 100 44 87 1 1291407920 140152576 9402592
11:48:21 7700 8192000000 10227571712 10636132352 1169234656 12.490 100 54 108 0 1345487696 164615392 11637648
11:49:37 8914 8192000000 10240838640 10648707072 1177987952 12.571 100 56 108 0 1341461136 151166096 12307088
11:50:53 1964 8192000000 10292652496 10691911680 1172528048 12.520 99 79 155 0 1392923648 202625680 17769920
11:51:58 10808 8192000000 10307102000 10726297600 1187744736 12.662 100 74 146 2 1411528224 207850832 15932656
11:53:15 1671 8192000000 10378763664 10774355968 1169968112 12.497 99 94 188 0 1466343552 276013952 20361488
11:54:23 4149 8192000000 10348309328 10753900544 1181241536 12.602 100 78 156 0 1447929712 249399680 17288496
11:55:30 2786 8192000000 10380206416 10780971008 1168463072 12.482 100 86 171 0 1480386512 291567152 20356288
11:56:36 4478 8192000000 10401185472 10800357376 1147045808 12.282 99 76 152 1 1487954896 323296192 17612896
11:57:45 4032 8192000000 10415559664 10827223040 1163570624 12.437 100 84 166 0 1514629248 332353760 18704864
11:58:55 8212 8192000000 10432522672 10878447616 1169723936 12.494 100 50 100 1 1534286800 352116576 12446288
12:00:30 6815 8192000000 10488259568 10904854528 1153681664 12.344 99 54 108 0 1568086368 400937232 13467472
12:01:40 11075 8192000000 10494045360 10908975104 1161591456 12.418 100 46 90 0 1583988592 410741008 11656128
12:02:57 24146 8192000000 10458477040 10902478848 1169472400 12.492 100 40 80 1 1569350304 389224704 10653200
12:04:22 17421 8192000000 10460387424 10933317632 1148520096 12.296 100 27 54 2 1576294080 420077232 7696752
12:05:46 6901 8192000000 10362103232 10824347648 1139449472 12.210 100 32 64 0 1464849056 316826432 8573152
12:07:01 32199 8192000000 10056258848 10488590336 1130156976 12.123 99 16 31 0 1233039360 99122144 3760240
12:08:35 29386 8192000000 10100829792 10529951744 1127402160 12.097 100 28 56 2 1191817424 58881168 5534096
12:10:15 127056 8192000000 10112734976 10564534272 1100153872 11.839 97 29 58 0 1199503616 93084592 6265152
12:13:23 61882 8192000000 10083968544 10520707072 1122342752 12.049 99 16 31 1 1170586992 44790384 3453856
12:15:52 93460 8192000000 10102834128 10602942464 1120792544 12.034 99 20 40 1 1187231360 61678112 4760704
12:18:53 72001 8192000000 10085123520 10545553408 1130465856 12.126 99 31 62 0 1201617280 64924432 6226992
12:21:24 2001 8192000000 10117091056 10536452096 1140367744 12.219 100 69 136 3 1211880976 56670000 14843232
12:22:28 38041 8192000000 10142358448 10569416704 1129848928 12.120 99 45 90 2 1227807248 88471328 9486992
12:24:13 80645 8192000000 10108799760 10615603200 1148092064 12.292 100 18 36 0 1264911696 112574256 4245376
12:26:48 118349 8192000000 10122042160 10572967936 1108089584 11.914 96 15 30 0 1193890896 82569424 3231888
12:29:47 65072 8192000000 10088521568 10535325696 1117875776 12.007 98 16 32 0 1183963296 62714080 3373440
12:32:01 102714 8192000000 10102230000 10617151488 1092578288 11.767 95 14 28 1 1238202832 142201040 3423504
12:34:48 48666 8192000000 10094935200 10548248576 1119859584 12.026 98 37 74 1 1189801584 62545936 7396064
12:36:47 94948 8192000000 10088140496 10529210368 1121604544 12.042 98 18 36 0 1184126528 58490368 4031616
12:39:28 77252 8192000000 10083072832 10506461184 1108681616 11.920 97 47 93 0 1138828416 20185440 9961360
12:41:47 29654 8192000000 10101056704 10525564928 1119303584 12.020 98 39 78 0 1204151456 77187296 7660576
12:43:17 50468 8192000000 10072692864 10521198592 1117503664 12.003 99 30 60 0 1162232512 38955904 5772944
12:45:08 56715 8192000000 10069725008 10535186432 1087515312 11.719 96 18 36 0 1155102592 63136032 4451248
12:47:08 61263 8192000000 10075892016 10549264384 1130148096 12.123 100 16 32 0 1176253408 42506416 3598896
12:49:09 62781 8192000000 10061783632 10518069248 1106950480 11.904 98 22 44 0 1152925088 41179872 4794736
12:51:14 60678 8192000000 10072508144 10514055168 1063077904 11.486 94 18 36 1 1112383216 45692672 3612640
12:53:27 102290 8192000000 10093058704 10604068864 1105807152 11.893 97 18 36 1 1232704544 122645392 4252000
12:56:10 41029 8192000000 10088078672 10529165312 1123066880 12.056 99 40 78 1 1174536128 43366896 8102352
12:57:58 70328 8192000000 10096237856 10539503616 1104976000 11.885 98 23 46 1 1188792128 78742560 5073568
13:00:10 31410 8192000000 10091297648 10524930048 1118685152 12.015 99 28 56 1 1172673856 47431296 6557408
13:01:45 55060 8192000000 10073158192 10497048576 1130771296 12.129 100 25 50 0 1159063824 23595680 4696848
13:03:45 113437 8192000000 10071982992 10509361152 1113571232 11.966 98 26 52 0 1147924480 28922400 5430848
13:06:40 27621 8192000000 10116737568 10544885760 1140945600 12.224 100 47 94 0 1191789136 41100816 9742720
13:08:10 68151 8192000000 10093710240 10571296768 1128362048 12.106 99 15 30 0 1202566224 70931344 3272832
13:10:22 71658 8192000000 10098725648 10564071424 1125589056 12.080 98 22 44 0 1198917168 68471744 4856368
13:12:37 69267 8192000000 10079222064 10561581056 1145906048 12.271 100 10 20 0 1202368224 54064512 2397664
13:14:57 56595 8192000000 10098140432 10571698176 1109115792 11.924 96 25 50 0 1190335248 76092768 5126688
13:16:59 55220 8192000000 10083788976 10530119680 1140423136 12.220 99 27 54 0 1172804960 27037264 5344560
13:18:54 41857 8192000000 10085622112 10531557376 1143452192 12.248 99 23 44 1 1188420352 40647728 4320432
13:20:42 40635 8192000000 10070155840 10527821824 1111820400 11.950 98 30 60 0 1180997312 63245200 5931712
13:22:23 82309 8192000000 10074286256 10567503872 1119198160 12.019 98 11 22 0 1183777664 62103232 2476272
13:24:49 36051 8192000000 10092111648 10520768512 1120050256 12.027 98 30 60 0 1165066848 39090576 5926016
13:26:27 121716 8192000000 10101552688 10550001664 1128066080 12.103 99 26 52 0 1187433104 53765456 5601568
13:29:30 67237 8192000000 10065101424 10534043648 1112347504 11.955 98 12 24 0 1165378624 50285264 2745856
13:31:37 66638 8192000000 10094544928 10549080064 1106291168 11.897 97 17 34 0 1170172288 59985392 3895728
13:33:49 70232 8192000000 10072794560 10550538240 1082675808 11.673 96 9 18 0 1124063808 39706048 1681952
13:36:08 65050 8192000000 9811294720 10223050752 836756512 9.267 74 16 32 0 898139456 57699664 3683280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-11-2021 13:38:51 to 22-11-2021 13:39:06.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-11-2021 13:39:07 to 22-11-2021 13:39:09.

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          226891
MON$OLDEST_ACTIVE               230114
MON$OLDEST_SNAPSHOT             230114
MON$NEXT_TRANSACTION            230114
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                       2038048
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
:196: 
:197: mscs-test-161.budzdorov.ru    Mon Nov 22 13:37:24 2021
:198:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:199: 
:200: 
:201: mscs-test-161.budzdorov.ru    Mon Nov 22 13:37:25 2021
:202:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:203: 
--- end of diff output ---

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

22.11.21 13:39:10. 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
2021-11-22 13:39:12 No severe PSQL-related problems occured
22.11.21 13:39:13. End of report.