Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.11.2021 07: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 17050692 kB
MemAvailable 31660972 kB
Buffers 0 kB
Cached 14682692 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-15 07:37:06
DTS_END                         2021-11-15 07:37:06.5820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55176
TRN_ID                          221307
ATT_ID                          595
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: 8723.69 31156 1046843
customer order: creation 154.51 455 18541
customer order: refuse 13.73 381 1647
order to supplier: creation 41.58 1490 4989
order to supplier: removal 3.13 137100 375
invoice (draft): creation 42.19 3689 5063
invoice (draft): removal 50.09 1047 6011
invoice accept: apply 38.58 20594 4630
invoice accept: cancel 45.47 1098 5456
customer reserve: creation 3629.77 197 435572
customer reserve: removal 4424.28 74 530914
realization accept: apply 77.08 216 9249
realization accept: cancel 84.33 151 10120
payment from customer: creation 54.53 144 6544
payment from customer: removal 4.97 102 597
payment to supplier: creation 45.66 204 5479
payment to supplier: removal 3.25 103 390
service: total inventory turnovers 3.92 3847 470
service: total monetary turnovers 5.84 556 701
service: aggregate perf. data 0.76 17753 91
service: refresh index statistics 0.03 433923 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-11-2021 07:39:43 to 15-11-2021 07:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8379
TEST_TIME 2 5970
TEST_TIME 3 5863
TEST_TIME 4 5040
TEST_TIME 5 4473
TEST_TIME 6 4335
TEST_TIME 7 4141
TEST_TIME 8 4546
TEST_TIME 9 4418
TEST_TIME 10 4517
TEST_TIME 11 4566
TEST_TIME 12 4680
TEST_TIME 13 5187
TEST_TIME 14 5170
TEST_TIME 15 5445
TEST_TIME 16 5815
TEST_TIME 17 5946
TEST_TIME 18 6110
TEST_TIME 19 6516
TEST_TIME 20 7172
TEST_TIME 21 7383
TEST_TIME 22 7399
TEST_TIME 23 8150
TEST_TIME 24 8935
TEST_TIME 25 9183
TEST_TIME 26 9482
TEST_TIME 27 10188
TEST_TIME 28 10505
TEST_TIME 29 10155
TEST_TIME 30 9995
TEST_TIME 31 9773
TEST_TIME 32 9670
TEST_TIME 33 9553
TEST_TIME 34 9402
TEST_TIME 35 9262
TEST_TIME 36 9124
TEST_TIME 37 9022
TEST_TIME 38 9038
TEST_TIME 39 9120
TEST_TIME 40 8988
TEST_TIME 41 8846
TEST_TIME 42 8827
TEST_TIME 43 8984
TEST_TIME 44 8874
TEST_TIME 45 9059
TEST_TIME 46 9108
TEST_TIME 47 9103
TEST_TIME 48 9197
TEST_TIME 49 9227
TEST_TIME 50 9220
TEST_TIME 51 9313
TEST_TIME 52 9270
TEST_TIME 53 9250
TEST_TIME 54 9206
TEST_TIME 55 9129
TEST_TIME 56 8991
TEST_TIME 57 8942
TEST_TIME 58 8931
TEST_TIME 59 8996
TEST_TIME 60 8948
TEST_TIME 61 8860
TEST_TIME 62 8819
TEST_TIME 63 8869
TEST_TIME 64 8816
TEST_TIME 65 8785
TEST_TIME 66 8735
TEST_TIME 67 8794
TEST_TIME 68 8831
TEST_TIME 69 8903
TEST_TIME 70 8965
TEST_TIME 71 8991
TEST_TIME 72 9025
TEST_TIME 73 9025
TEST_TIME 74 9045
TEST_TIME 75 8960
TEST_TIME 76 8959
TEST_TIME 77 8895
TEST_TIME 78 8854
TEST_TIME 79 8862
TEST_TIME 80 8806
TEST_TIME 81 8799
TEST_TIME 82 8721
TEST_TIME 83 8778
TEST_TIME 84 8716
TEST_TIME 85 8701
TEST_TIME 86 8694
TEST_TIME 87 8683
TEST_TIME 88 8693
TEST_TIME 89 8712
TEST_TIME 90 8692
TEST_TIME 91 8735
TEST_TIME 92 8744
TEST_TIME 93 8816
TEST_TIME 94 8833
TEST_TIME 95 8830
TEST_TIME 96 8878
TEST_TIME 97 8910
TEST_TIME 98 8868
TEST_TIME 99 8787
TEST_TIME 100 8845
TEST_TIME 101 8828
TEST_TIME 102 8797
TEST_TIME 103 8817
TEST_TIME 104 8791
TEST_TIME 105 8757
TEST_TIME 106 8755
TEST_TIME 107 8753
TEST_TIME 108 8875
TEST_TIME 109 8874
TEST_TIME 110 8873
TEST_TIME 111 8857
TEST_TIME 112 8931
TEST_TIME 113 8917
TEST_TIME 114 8950
TEST_TIME 115 8961
TEST_TIME 116 8914
TEST_TIME 117 8873
TEST_TIME 118 8836
TEST_TIME 119 8802
TEST_TIME 120 8737

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-11-2021 07:39:47 to 15-11-2021 07:39:50.

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 1447829 1447829 0 0.00 0 39596 8 0 0
doc_list_biud 1447829 1447829 0 0.00 0 39973 35 0 0
sp_add_invoice_to_stock 4724 4630 94 1.98 53 235303 20594 0 94
sp_cancel_adding_invoice 5456 5456 0 0.00 5 80755 1098 0 0
sp_cancel_client_order 1647 1647 0 0.00 5 15599 381 0 0
sp_cancel_customer_reserve 530914 530914 0 0.00 1 39981 75 0 0
sp_cancel_pay_from_customer 597 597 0 0.00 2 5023 102 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 10824 104 0 0
sp_cancel_supplier_invoice 6012 6011 1 0.01 2 28347 1048 0 1
sp_cancel_supplier_order 375 375 0 0.00 10 1424739 137101 0 0
sp_cancel_write_off 10120 10120 0 0.00 1 39598 152 0 0
sp_client_order 18541 18541 0 0.00 4 34615 455 0 0
sp_customer_reserve 435572 435572 0 0.00 0 147980 198 0 0
sp_fill_shopping_cart 28573 28573 0 0.00 1 19730 46 0 0
sp_fill_shopping_cart_clo_res 435547 435547 0 0.00 0 18971 17 0 0
sp_get_clo_for_invoice 4605 4605 0 0.00 0 28993 395 0 0
sp_kill_cost_storno 16563 16563 0 0.00 0 39594 78 0 0
sp_kill_qstorno_ret_qs2qd 1078334 1078334 0 0.00 0 39952 41 0 0
sp_kill_qty_storno 568377 568377 0 0.00 0 39971 87 0 0
sp_lock_selected_doc 19258 19258 0 0.00 0 7696 7 0 0
sp_make_cost_storno 25877 25877 0 0.00 0 22415 75 0 0
sp_multiply_rows_for_pdistr 25877 25877 0 0.00 0 11854 42 0 0
sp_multiply_rows_for_qdistr 18541 18541 0 0.00 0 29603 226 0 0
sp_pay_from_customer 6544 6544 0 0.00 4 14436 144 0 0
sp_pay_to_supplier 5479 5479 0 0.00 4 31113 204 0 0
sp_payment_common 12023 12023 0 0.00 1 7983 24 0 0
sp_qd_handle_on_cancel_clo 1647 1647 0 0.00 0 15593 349 0 0
sp_qd_handle_on_invoice_upd_sts 10061 10061 0 0.00 0 29833 420 0 0
sp_qd_handle_on_reserve_upd_sts 9249 9249 0 0.00 0 26947 58 0 0
sp_reserve_write_off 9249 9249 0 0.00 7 27045 217 0 0
sp_supplier_invoice 5063 5063 0 0.00 7 86436 3689 0 0
sp_supplier_order 4989 4989 0 0.00 6 54016 1491 0 0
srv_aggregate_perf_data 91 91 0 0.00 2560 179681 17753 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1046 144252 52429 0 0
srv_find_qd_qs_mism 1001608 1001608 0 0.00 0 28803 6 0 0
srv_make_invnt_saldo 470 470 0 0.00 57 154497 3847 0 0
srv_make_money_saldo 701 701 0 0.00 1 11915 557 0 0
srv_recalc_idx_stat 4 4 0 0.00 250378 618545 433923 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4631 4631 0 0.00 0 7252 182 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 5986 127 0 0
t$perf-norm:sp_cancel_client_order 1647 1647 0 0.00 0 2003 15 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 3219 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 597 597 0 0.00 0 1141 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 278 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 5859 37 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 25464 1796 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 4552 12 0 0
t$perf-norm:sp_client_order 18541 18541 0 0.00 0 8240 21 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 2043 12 0 0
t$perf-norm:sp_pay_from_customer 6544 6544 0 0.00 0 5938 7 0 0
t$perf-norm:sp_pay_to_supplier 5479 5479 0 0.00 0 4671 9 0 0
t$perf-norm:sp_reserve_write_off 9249 9249 0 0.00 0 4437 8 0 0
t$perf-norm:sp_supplier_invoice 5063 5063 0 0.00 0 5317 20 0 0
t$perf-norm:sp_supplier_order 4989 4989 0 0.00 0 2878 19 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 202 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 845 15 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 591 8 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 763 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 24 13 0 0
v_add_invoice_to_stock 9398 9210 188 2.00 0 7468 19 0 188
v_all_customers 34280 34280 0 0.00 0 39 1 0 0
v_all_suppliers 28276 28276 0 0.00 0 77 1 0 0
v_all_wares 37082 37082 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3099 11 0 0
v_cancel_client_order 3294 3294 0 0.00 0 2603 4 0 0
v_cancel_customer_prepayment 1194 1194 0 0.00 0 5015 12 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 10671 17 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 10778 22 0 0
v_cancel_supplier_invoice 726 724 2 0.27 0 5682 22 0 2
v_cancel_supplier_order 750 750 0 0.00 0 6816 14 0 0
v_cancel_write_off 1938 1938 0 0.00 0 4482 16 0 0
v_max_id_clo_ord 4979 4979 0 0.00 0 2494 2 0 0
v_max_id_clo_res 941 941 0 0.00 0 22384 41 0 0
v_max_id_ord_sup 5052 5052 0 0.00 0 5138 4 0 0
v_max_non_paid_invoice 1372 1372 0 0.00 0 187 2 0 0
v_max_non_paid_realizn 3296 3296 0 0.00 0 1278 4 0 0
v_min_id_clo_ord 4979 4979 0 0.00 0 4242 9 0 0
v_min_id_clo_res 941 941 0 0.00 0 619 5 0 0
v_min_id_ord_sup 5052 5052 0 0.00 0 6746 8 0 0
v_min_non_paid_invoice 5479 5479 0 0.00 0 6902 14 0 0
v_min_non_paid_realizn 6544 6544 0 0.00 0 7549 16 0 0
v_our_firm 9298 9298 0 0.00 0 20 1 0 0
v_reserve_write_off 18498 18498 0 0.00 0 10835 9 0 0
x_lock_depdocs_on_canc_invoice 11105 11105 0 0.00 0 22270 154 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 18063 521 0 0
x_make_qstorno_1000_1200 4989 4989 0 0.00 2 53996 1400 0 0
x_make_qstorno_1200_2000 5063 5063 0 0.00 3 82186 3517 0 0
x_make_qstorno_2100_3300 435572 435572 0 0.00 0 147944 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-11-2021 07:39:51 to 15-11-2021 07:39:55.

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
05:07:10 3 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:10 26929 8192000000 9720269680 10022146048 798867408 8.885 100 85 169 0 827185392 7451600 20866384
05:09:41 26922 8192000000 9772132448 10082811904 844813072 9.348 100 91 182 2 878831456 12064464 21953920
05:11:25 21680 8192000000 9818223664 10140106752 880875568 9.708 100 88 176 2 922953360 21689088 20388704
05:12:53 16637 8192000000 9888365072 10223968256 921979632 10.116 100 84 168 0 994651216 54199664 18471920
05:14:19 10294 8192000000 9928458720 10277097472 955814336 10.448 100 76 153 1 1044172320 72291696 16066288
05:15:31 6554 8192000000 9957910896 10314481664 997776112 10.857 100 80 160 0 1071581424 57418224 16387088
05:16:40 3320 8192000000 9998652016 10359242752 1020371728 11.076 100 90 180 0 1107642960 68813392 18457840
05:17:47 1936 8192000000 10010662928 10376245248 1049475104 11.356 100 89 176 0 1124686640 57243584 17967952
05:18:52 8167 8192000000 10035983392 10408038400 1064162096 11.496 100 79 158 1 1152141104 72658304 15320704
05:20:01 1693 8192000000 10052999072 10432962560 1075865232 11.608 100 86 168 1 1169390112 75723504 17801376
05:21:04 2887 8192000000 10009905168 10398420992 1080092912 11.648 100 57 117 0 1128463344 35929136 12441296
05:22:10 4354 8192000000 10001168864 10408202240 1087914000 11.723 100 30 60 0 1121362784 27520384 5928400
05:23:16 44830 8192000000 10004135792 10414956544 1097015152 11.809 100 31 62 3 1120546992 16814784 6717056
05:25:02 5426 8192000000 10025100640 10427887616 1093218512 11.773 100 39 76 2 1136475808 34754400 8502896
05:26:10 7585 8192000000 9992082144 10393264128 1084856832 11.694 99 33 67 2 1112824752 19387536 8580384
05:27:18 7081 8192000000 10019525536 10426626048 1098885024 11.827 100 32 64 0 1129477648 23917232 6675392
05:28:26 16506 8192000000 10019849936 10406592512 1096248192 11.802 100 80 161 1 1123631696 10521664 16861840
05:29:43 4416 8192000000 9989224272 10389909504 1051232688 11.372 96 43 84 2 1072762464 11834784 9694992
05:30:50 26130 8192000000 9945499568 10333360128 971141440 10.598 92 26 52 0 1010841600 33587728 6112432
05:32:20 5697 8192000000 9951460448 10333822976 985378800 10.737 95 36 70 1 1019876064 26803360 7693904
05:33:26 7244 8192000000 9945019520 10315603968 1000620992 10.885 97 46 91 4 1028870144 17474912 10774240
05:34:35 43769 8192000000 9975734368 10368208896 1046257552 11.325 100 56 113 1 1074148368 16103312 11787504
05:36:20 4623 8192000000 9999441616 10385207296 1062651536 11.482 100 53 106 3 1104154688 30098016 11405136
05:37:31 55881 8192000000 10009732736 10405867520 1078428960 11.632 100 42 83 1 1109879376 22638976 8811440
05:39:35 33316 8192000000 10044326112 10445197312 1096008512 11.800 100 34 68 2 1153719168 50448464 7262192
05:41:18 104718 8192000000 10117685120 10535268352 1122474464 12.050 100 32 64 1 1226560704 96819232 7267008
05:44:06 52782 8192000000 10160583776 10595807232 1134929168 12.168 100 45 89 0 1285418080 140476624 10012288
05:46:03 6897 8192000000 10213467872 10617380864 1150929008 12.318 100 53 105 0 1320872432 157982144 11961280
05:47:20 18012 8192000000 10283971904 10694160384 1174917184 12.543 100 47 94 1 1384478912 198569056 10992672
05:48:43 12535 8192000000 10332503712 10743234560 1185659104 12.643 100 64 128 0 1437253296 236798416 14795776
05:50:02 7401 8192000000 10388511680 10809163776 1199172928 12.769 100 69 137 1 1502142848 287377696 15592224
05:51:16 2609 8192000000 10417644784 10822860800 1213845664 12.905 100 88 171 0 1519255600 285859296 19550640
05:52:23 4155 8192000000 10447142192 10869035008 1206755776 12.839 100 77 153 2 1550337184 325132160 18449248
05:53:34 2526 8192000000 10495782176 10913710080 1214209616 12.908 100 82 164 0 1596553856 363469536 18874704
05:54:44 3311 8192000000 10524760704 10933940224 1207677184 12.848 100 86 170 2 1620852016 392583936 20590896
05:55:55 1043 8192000000 10510192288 10923859968 1193622960 12.717 100 88 175 1 1610463568 396342544 20498064
05:57:03 3004 8192000000 10523634752 10951200768 1176672336 12.559 99 85 168 1 1608956576 412294224 19990016
05:58:10 4590 8192000000 10503959392 10933567488 1189457456 12.678 100 53 106 0 1597320144 394886464 12976224
05:59:19 7562 8192000000 10466644080 10901168128 1159405088 12.398 99 75 144 4 1561255792 383082288 18768416
06:00:34 8672 8192000000 10419845008 10848464896 1162683360 12.428 100 52 100 0 1536634112 361042368 12908384
06:01:48 14138 8192000000 10336611408 10812112896 1161519360 12.417 100 33 66 0 1440882144 270732096 8630688
06:03:05 20482 8192000000 10289536768 10748506112 1152256816 12.331 100 30 58 1 1397544256 237800512 7486928
06:04:32 72999 8192000000 10058104944 10527637504 1077720368 11.626 95 9 18 1 1144355824 64699136 1936320
06:06:45 105844 8192000000 10066996480 10493411328 1107170144 11.906 98 35 70 1 1125529152 10922608 7436400
06:09:35 74791 8192000000 10067397968 10514894848 1089073472 11.734 97 10 20 0 1141149392 49935696 2140224
06:12:01 67878 8192000000 10102734048 10552684544 1107872592 11.912 98 29 57 1 1182600048 68068784 6658672
06:14:09 133265 8192000000 10100787040 10550751232 1091137456 11.753 97 28 56 1 1215102176 117794192 6170528
06:17:30 75131 8192000000 10152825600 10604470272 1135223520 12.171 100 27 54 0 1249474048 107881120 6369408
06:19:59 73012 8192000000 10138023808 10624524288 1147272288 12.284 100 15 30 0 1247875424 97381344 3221792
06:22:13 80172 8192000000 10081279872 10547654656 1087537568 11.719 95 17 33 0 1155155024 63881056 3736400
06:24:35 45422 8192000000 10098002848 10535407616 1124182816 12.066 99 11 20 2 1182619936 56362272 2074848
06:26:23 68853 8192000000 10080369600 10546061312 1097808224 11.817 96 19 36 0 1149135696 47320896 4006576
06:28:35 69665 8192000000 10084389408 10564005888 1114363600 11.974 98 30 61 0 1171460720 50848864 6248256
06:30:50 27232 8192000000 10083209008 10513858560 1133126992 12.151 100 30 60 0 1176954240 38306688 5520560
06:32:29 133663 8192000000 10132869216 10581983232 1125900112 12.083 99 48 96 0 1190361296 53812288 10648896
06:36:00 52381 8192000000 10050295728 10530926592 1113947232 11.970 98 23 47 1 1183206896 64002048 5257616
06:37:53 86716 8192000000 10097038848 10586677248 1101317296 11.850 97 8 16 0 1169419856 66183568 1918992
06:40:25 80433 8192000000 10086486608 10555092992 1134111440 12.160 100 6 13 0 1166927232 31735616 1080176
06:42:56 45296 8192000000 10146410592 10577186816 1124198992 12.067 98 35 68 0 1207927056 76194912 7533152
06:45:33 66794 8192000000 10099099120 10590064640 1122480496 12.050 99 15 30 0 1249273040 123339728 3452816
06:47:40 73678 8192000000 10064953680 10506088448 1102867424 11.865 97 17 34 0 1156680192 50145776 3666992
06:49:54 64220 8192000000 10080865712 10499530752 1108287488 11.916 97 36 70 3 1154439280 38646704 7505088
06:52:00 114576 8192000000 10030920992 10465759232 1088257888 11.726 97 19 38 1 1134409680 42305632 3846160
06:54:56 31066 8192000000 10080817776 10528505856 1129324304 12.115 100 40 80 0 1176535472 38753664 8457504
06:57:02 46660 8192000000 10095446288 10541658112 1139868480 12.214 100 12 24 0 1201938384 59599824 2470080
06:58:49 75544 8192000000 10091004160 10548178944 1121962400 12.046 98 16 32 0 1194242560 68485808 3794352
07:01:06 57187 8192000000 10074085920 10531557376 1107122640 11.905 97 8 15 1 1142497616 33669600 1705376
07:03:11 52668 8192000000 10087002064 10579111936 1135693776 12.175 100 12 24 1 1199954896 61867216 2393904
07:05:36 55097 8192000000 10075513600 10542690304 1088012064 11.724 95 18 37 1 1147646240 55739296 3894880
07:07:35 56624 8192000000 10112851248 10564325376 1094015520 11.781 96 18 36 2 1170735280 72560464 4159296
07:09:37 63454 8192000000 10067706064 10496229376 1079854352 11.646 96 29 58 1 1185184352 98772576 6557424
07:11:41 18418 8192000000 10041099296 10462588928 1091678016 11.759 99 39 77 1 1129325824 30145424 7502384
07:13:01 24489 8192000000 10065263792 10511564800 1094067424 11.781 98 30 59 0 1150285568 49949792 6268352
07:14:33 54910 8192000000 10095673456 10514833408 1116847456 11.997 99 34 68 0 1197544592 74165856 6531280
07:16:33 72760 8192000000 10112730352 10545106944 1098735984 11.826 97 43 86 0 1214116608 106576272 8804352
07:18:46 61979 8192000000 10081166528 10554486784 1120140624 12.028 99 8 16 0 1168443136 46825824 1476688
07:21:29 36945 8192000000 10039376080 10474815488 1103799408 11.874 98 28 57 0 1146124896 35644032 6681456
07:23:08 53997 8192000000 10152475744 10618060800 1129305664 12.115 98 27 54 0 1225609600 90273280 6030656
07:25:05 79621 8192000000 10086419392 10565562368 1120662208 12.033 99 15 30 2 1182377712 58913872 2801632
07:27:25 72414 8192000000 10106816288 10550906880 1119703808 12.024 98 22 44 0 1176552640 52309088 4539744
07:29:38 73294 8192000000 10098441296 10547183616 1114724944 11.977 98 19 38 0 1171774896 53413632 3636320
07:31:54 144252 8192000000 10098511168 10566529024 1119300096 12.020 98 9 18 0 1172289056 51186064 1802896
07:35:52 38625 8192000000 10086127056 10536398848 1124785632 12.072 99 12 24 2 1161678608 34258400 2634576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-11-2021 07:39:57 to 15-11-2021 07:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-11-2021 07:40:12 to 15-11-2021 07:40:14.

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          219135
MON$OLDEST_ACTIVE               221541
MON$OLDEST_SNAPSHOT             221541
MON$NEXT_TRANSACTION            221541
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                       2013824
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2660 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2660 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2660 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2660 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 ---
:90: 
:91: mscs-test-161.budzdorov.ru    Mon Nov 15 07:37:09 2021
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:93: 
:94: 
:95: mscs-test-161.budzdorov.ru    Mon Nov 15 07:37:17 2021
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
:98: 
:99: mscs-test-161.budzdorov.ru    Mon Nov 15 07:37:29 2021
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:101: 
:102: 
:103: mscs-test-161.budzdorov.ru    Mon Nov 15 07:37:51 2021
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
--- end of diff output ---

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

15.11.21 07:40:15. 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-15 07:40:18 No severe PSQL-related problems occured
15.11.21 07:40:19. End of report.