Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.12.2021 07:47
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 16654312 kB
MemAvailable 31474684 kB
Buffers 0 kB
Cached 14993448 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-12-04 07:47:19
DTS_END                         2021-12-04 07:47:19.0260
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/43008
TRN_ID                          234639
ATT_ID                          641
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: 8682.11 21342 1041853
customer order: creation 172.64 438 20717
customer order: refuse 14.69 360 1763
order to supplier: creation 45.68 1495 5481
order to supplier: removal 3.20 115393 384
invoice (draft): creation 45.43 3743 5452
invoice (draft): removal 49.56 990 5947
invoice accept: apply 41.07 18550 4928
invoice accept: cancel 44.36 839 5323
customer reserve: creation 3678.18 187 441382
customer reserve: removal 4298.32 67 515798
realization accept: apply 84.15 190 10098
realization accept: cancel 77.47 116 9297
payment from customer: creation 57.47 101 6896
payment from customer: removal 5.86 75 703
payment to supplier: creation 49.57 134 5948
payment to supplier: removal 3.23 100 388
service: total inventory turnovers 3.96 3373 475
service: total monetary turnovers 6.25 590 750
service: aggregate perf. data 0.99 15235 119
service: refresh index statistics 0.03 264854 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-12-2021 07:48:12 to 04-12-2021 07:48:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8618
TEST_TIME 2 7466
TEST_TIME 3 6185
TEST_TIME 4 5584
TEST_TIME 5 5623
TEST_TIME 6 5542
TEST_TIME 7 5670
TEST_TIME 8 5690
TEST_TIME 9 6499
TEST_TIME 10 6664
TEST_TIME 11 6690
TEST_TIME 12 6549
TEST_TIME 13 6734
TEST_TIME 14 6923
TEST_TIME 15 7289
TEST_TIME 16 7274
TEST_TIME 17 7316
TEST_TIME 18 8075
TEST_TIME 19 8128
TEST_TIME 20 8343
TEST_TIME 21 8385
TEST_TIME 22 8832
TEST_TIME 23 8704
TEST_TIME 24 8826
TEST_TIME 25 9026
TEST_TIME 26 10029
TEST_TIME 27 10332
TEST_TIME 28 10936
TEST_TIME 29 10622
TEST_TIME 30 10519
TEST_TIME 31 10269
TEST_TIME 32 10042
TEST_TIME 33 9858
TEST_TIME 34 9876
TEST_TIME 35 9903
TEST_TIME 36 9746
TEST_TIME 37 9738
TEST_TIME 38 9616
TEST_TIME 39 9567
TEST_TIME 40 9528
TEST_TIME 41 9433
TEST_TIME 42 9409
TEST_TIME 43 9443
TEST_TIME 44 9276
TEST_TIME 45 9098
TEST_TIME 46 9015
TEST_TIME 47 8915
TEST_TIME 48 8846
TEST_TIME 49 8795
TEST_TIME 50 8826
TEST_TIME 51 8796
TEST_TIME 52 8717
TEST_TIME 53 8740
TEST_TIME 54 8690
TEST_TIME 55 8838
TEST_TIME 56 8961
TEST_TIME 57 9041
TEST_TIME 58 9005
TEST_TIME 59 9064
TEST_TIME 60 9081
TEST_TIME 61 9113
TEST_TIME 62 9155
TEST_TIME 63 9111
TEST_TIME 64 9064
TEST_TIME 65 9001
TEST_TIME 66 8961
TEST_TIME 67 8884
TEST_TIME 68 8912
TEST_TIME 69 8876
TEST_TIME 70 8842
TEST_TIME 71 8829
TEST_TIME 72 8810
TEST_TIME 73 8817
TEST_TIME 74 8895
TEST_TIME 75 8861
TEST_TIME 76 8896
TEST_TIME 77 8871
TEST_TIME 78 8856
TEST_TIME 79 8822
TEST_TIME 80 8981
TEST_TIME 81 8947
TEST_TIME 82 9013
TEST_TIME 83 9016
TEST_TIME 84 9039
TEST_TIME 85 9020
TEST_TIME 86 8923
TEST_TIME 87 8863
TEST_TIME 88 8808
TEST_TIME 89 8763
TEST_TIME 90 8752
TEST_TIME 91 8748
TEST_TIME 92 8775
TEST_TIME 93 8750
TEST_TIME 94 8759
TEST_TIME 95 8758
TEST_TIME 96 8779
TEST_TIME 97 8760
TEST_TIME 98 8814
TEST_TIME 99 8855
TEST_TIME 100 8934
TEST_TIME 101 8931
TEST_TIME 102 8925
TEST_TIME 103 8909
TEST_TIME 104 8890
TEST_TIME 105 8944
TEST_TIME 106 8870
TEST_TIME 107 8829
TEST_TIME 108 8814
TEST_TIME 109 8811
TEST_TIME 110 8769
TEST_TIME 111 8724
TEST_TIME 112 8692
TEST_TIME 113 8676
TEST_TIME 114 8664
TEST_TIME 115 8670
TEST_TIME 116 8666
TEST_TIME 117 8670
TEST_TIME 118 8657
TEST_TIME 119 8716
TEST_TIME 120 8685

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-12-2021 07:48:16 to 04-12-2021 07:48:19.

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 1450933 1450933 0 0.00 0 41857 7 0 0
doc_list_biud 1450931 1450931 0 0.00 0 43762 31 0 0
sp_add_invoice_to_stock 5012 4928 84 1.67 28 185061 18551 0 84
sp_cancel_adding_invoice 5323 5323 0 0.00 4 73268 839 0 0
sp_cancel_client_order 1763 1763 0 0.00 5 11557 361 0 0
sp_cancel_customer_reserve 515798 515798 0 0.00 1 24039 68 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 2645 75 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 7340 100 0 0
sp_cancel_supplier_invoice 5953 5947 6 0.10 2 34484 991 0 6
sp_cancel_supplier_order 384 384 0 0.00 9 1168810 115393 0 0
sp_cancel_write_off 9297 9297 0 0.00 1 20160 116 0 0
sp_client_order 20717 20717 0 0.00 4 41940 439 0 0
sp_customer_reserve 441382 441382 0 0.00 0 49108 188 0 0
sp_fill_shopping_cart 31642 31642 0 0.00 1 36295 54 0 0
sp_fill_shopping_cart_clo_res 441369 441369 0 0.00 0 14077 15 0 0
sp_get_clo_for_invoice 4915 4915 0 0.00 0 25809 382 0 0
sp_kill_cost_storno 15711 15711 0 0.00 0 9938 57 0 0
sp_kill_qstorno_ret_qs2qd 1047224 1047224 0 0.00 0 34473 37 0 0
sp_kill_qty_storno 553525 553525 0 0.00 0 43761 80 0 0
sp_lock_selected_doc 20853 20853 0 0.00 0 10685 5 0 0
sp_make_cost_storno 27857 27857 0 0.00 0 20949 56 0 0
sp_multiply_rows_for_pdistr 27857 27857 0 0.00 0 16774 33 0 0
sp_multiply_rows_for_qdistr 20717 20717 0 0.00 0 22170 228 0 0
sp_pay_from_customer 6896 6896 0 0.00 4 21169 102 0 0
sp_pay_to_supplier 5948 5948 0 0.00 5 26003 135 0 0
sp_payment_common 12844 12844 0 0.00 1 12974 19 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 11545 331 0 0
sp_qd_handle_on_invoice_upd_sts 10238 10238 0 0.00 0 43761 365 0 0
sp_qd_handle_on_reserve_upd_sts 10098 10098 0 0.00 0 14347 63 0 0
sp_reserve_write_off 10098 10098 0 0.00 7 25965 190 0 0
sp_supplier_invoice 5452 5452 0 0.00 8 69156 3743 0 0
sp_supplier_order 5481 5481 0 0.00 5 45312 1495 0 0
srv_aggregate_perf_data 119 119 0 0.00 2619 215174 15236 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1178 119380 43304 0 0
srv_find_qd_qs_mism 995268 995268 0 0.00 0 41856 6 0 0
srv_make_invnt_saldo 475 475 0 0.00 55 107123 3373 0 0
srv_make_money_saldo 750 750 0 0.00 1 20326 590 0 0
srv_recalc_idx_stat 4 4 0 0.00 114414 496807 264855 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4928 4928 0 0.00 0 22436 183 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 8346 119 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 8602 17 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 7836 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 2936 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1133 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 450 450 0 0.00 0 1129 12 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 20820 1581 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 2407 8 0 0
t$perf-norm:sp_client_order 20717 20717 0 0.00 0 18529 21 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 2851 10 0 0
t$perf-norm:sp_pay_from_customer 6896 6896 0 0.00 0 17303 13 0 0
t$perf-norm:sp_pay_to_supplier 5948 5948 0 0.00 0 14897 15 0 0
t$perf-norm:sp_reserve_write_off 10098 10098 0 0.00 0 8096 11 0 0
t$perf-norm:sp_supplier_invoice 5452 5452 0 0.00 0 8483 22 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 10068 18 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 2144 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 143 3 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1696 14 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 1628 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 17 7 0 0
v_add_invoice_to_stock 9998 9830 168 1.68 0 7042 19 0 168
v_all_customers 37264 37264 0 0.00 0 78 1 0 0
v_all_suppliers 30564 30564 0 0.00 0 227 1 0 0
v_all_wares 41434 41434 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 565 9 0 0
v_cancel_client_order 3526 3526 0 0.00 0 2328 6 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 1573 9 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 3371 7 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 339 4 0 0
v_cancel_supplier_invoice 912 900 12 1.31 0 4723 17 0 12
v_cancel_supplier_order 768 768 0 0.00 0 1132 8 0 0
v_cancel_write_off 2018 2018 0 0.00 0 2395 16 0 0
v_max_id_clo_ord 5481 5481 0 0.00 0 572 1 0 0
v_max_id_clo_res 947 947 0 0.00 0 3498 16 0 0
v_max_id_ord_sup 5444 5444 0 0.00 0 5749 9 0 0
v_max_non_paid_invoice 1591 1591 0 0.00 0 1539 3 0 0
v_max_non_paid_realizn 3916 3916 0 0.00 0 5686 5 0 0
v_min_id_clo_ord 5481 5481 0 0.00 0 1799 6 0 0
v_min_id_clo_res 947 947 0 0.00 0 2666 9 0 0
v_min_id_ord_sup 5444 5444 0 0.00 0 5557 7 0 0
v_min_non_paid_invoice 5948 5948 0 0.00 0 7522 11 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 4428 11 0 0
v_our_firm 10130 10130 0 0.00 0 54 1 0 0
v_reserve_write_off 20196 20196 0 0.00 0 6375 6 0 0
x_lock_depdocs_on_canc_invoice 10820 10820 0 0.00 0 15361 109 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 6378 341 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 2 45301 1417 0 0
x_make_qstorno_1200_2000 5452 5452 0 0.00 4 69150 3516 0 0
x_make_qstorno_2100_3300 441382 441382 0 0.00 0 49042 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2021 07:48:20 to 04-12-2021 07:48:24.

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:17:22 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:24 23419 8192000000 9724419872 10020851712 806063744 8.958 100 91 182 0 835996656 7713040 22219872
05:19:50 31328 8192000000 9763688832 10070855680 838956064 9.289 100 91 181 1 874706544 14431008 21319472
05:21:28 19731 8192000000 9810894160 10130780160 872467424 9.625 100 83 166 0 909840096 18246480 19126192
05:22:50 10795 8192000000 9872751968 10203799552 922615408 10.122 100 82 164 0 979402112 39278272 17508432
05:24:03 10862 8192000000 9931731888 10276298752 954607952 10.436 100 82 164 0 1044515776 72756656 17151168
05:25:19 4461 8192000000 9957039776 10310184960 992208448 10.803 100 86 173 0 1074587824 65273792 17105584
05:26:26 2363 8192000000 9980356560 10344845312 1020843552 11.080 100 79 156 2 1097003776 60267008 15893216
05:27:30 2460 8192000000 10018931856 10384822272 1044308736 11.306 100 87 177 1 1127406224 65673184 17424304
05:28:33 1382 8192000000 10067184864 10437996544 1060391136 11.460 100 94 188 0 1173768576 94591184 18786256
05:29:35 14209 8192000000 9989801664 10381537280 1067661856 11.530 100 42 84 1 1130894048 54212784 9019408
05:30:52 6556 8192000000 9987353680 10384818176 1079432976 11.642 100 34 68 0 1112250304 25883664 6933664
05:32:02 8412 8192000000 9985356176 10383654912 1088031824 11.724 100 28 56 4 1118651520 24878048 5741648
05:33:14 68768 8192000000 10005493824 10402996224 1094424320 11.785 100 52 105 2 1126530496 20950960 11155216
05:35:23 4508 8192000000 10020352416 10432823296 1094267280 11.783 100 54 109 0 1140035888 33674160 12094448
05:36:30 7719 8192000000 9999639232 10411294720 1078345936 11.632 98 44 84 1 1106078704 18457440 9275328
05:37:40 13162 8192000000 9997605856 10395439104 1075094992 11.601 99 35 71 0 1099527088 17102048 7330048
05:38:53 21836 8192000000 9963762672 10361872384 987713632 10.759 92 15 30 0 1025760944 35264576 2782736
05:40:21 4668 8192000000 9933133616 10309431296 992888560 10.810 96 39 77 0 1018751392 17393712 8469120
05:41:27 7521 8192000000 9934386832 10321223680 1006424160 10.941 97 40 81 2 1026103264 10217840 9461264
05:42:38 52742 8192000000 9949715376 10331987968 1029533296 11.164 99 33 64 2 1053395968 16856864 7005808
05:44:36 6600 8192000000 9971926736 10364719104 1041220608 11.276 100 46 92 0 1068775168 17303408 10251152
05:45:45 7752 8192000000 9993758272 10395975680 1062197504 11.478 100 39 78 0 1094518416 23890368 8430544
05:46:53 67046 8192000000 10014494848 10411552768 1078198944 11.630 100 32 65 1 1103469728 17961088 7309696
05:49:04 28317 8192000000 10078981104 10491281408 1115505392 11.985 100 42 81 2 1188103440 64391296 8206752
05:50:44 25204 8192000000 10079891824 10482774016 1133043152 12.150 100 36 70 1 1182789968 41936928 7809888
05:52:13 30874 8192000000 10128509152 10551537664 1140277536 12.218 100 34 67 0 1233563616 85941632 7344448
05:53:49 9039 8192000000 10181358528 10586681344 1155732656 12.363 100 52 103 0 1285529344 118733792 11062896
05:55:04 15321 8192000000 10198940128 10610073600 1162116944 12.423 100 49 96 2 1298978576 125968064 10893568
05:56:24 7246 8192000000 10234962960 10660757504 1166797344 12.467 100 59 119 0 1338651264 158749184 13104736
05:57:38 8629 8192000000 10234733232 10645811200 1167841552 12.477 99 57 110 2 1324398304 145063392 11493360
05:58:51 3127 8192000000 10277784432 10687127552 1170336784 12.500 99 79 156 1 1375961904 188237216 17387904
05:59:58 4381 8192000000 10346027312 10746036224 1182192480 12.611 100 85 169 4 1436496112 235720480 18583152
06:01:06 3989 8192000000 10364909728 10774339584 1174887296 12.542 100 82 166 1 1464376992 270742512 18747184
06:02:17 1176 8192000000 10362980688 10766761984 1172685344 12.522 100 88 174 2 1462591232 270120672 19785216
06:03:23 2998 8192000000 10400033856 10801688576 1162338656 12.425 100 93 186 0 1499340672 315649392 21352624
06:04:30 4862 8192000000 10390107792 10810888192 1158267328 12.387 100 67 132 1 1500284288 325853280 16163680
06:05:43 3870 8192000000 10357262624 10770219008 1130630720 12.127 100 58 116 1 1453692976 309965488 13096768
06:06:54 8873 8192000000 10357141152 10767294464 1124895008 12.073 98 50 100 0 1439329472 301455280 12979184
06:08:07 8090 8192000000 10360869536 10775756800 1136936592 12.187 99 46 92 4 1449241248 301380272 10924384
06:09:18 7118 8192000000 10390253568 10801000448 1139849120 12.214 100 38 76 0 1480042896 330643824 9549952
06:10:28 11323 8192000000 10420997280 10850402304 1146039344 12.272 100 46 89 2 1516760144 359154976 11565824
06:11:45 11417 8192000000 10430507248 10883584000 1140959056 12.225 100 32 64 0 1552580896 402290032 9331808
06:13:02 8369 8192000000 10423610432 10871291904 1132018768 12.140 100 25 50 0 1533412784 394344560 7049456
06:14:12 20004 8192000000 10204096864 10662776832 1106065200 11.895 98 19 38 0 1320300800 209629088 4606512
06:15:35 22638 8192000000 10054741408 10506379264 1102934640 11.865 98 8 15 0 1141123248 36785680 1402928
06:17:34 119380 8192000000 10075757408 10527617024 1140825088 12.223 100 27 54 0 1176558592 29969136 5764368
06:20:36 73760 8192000000 10092592608 10538156032 1134842976 12.167 99 29 58 2 1206470944 65412272 6215696
06:22:52 87638 8192000000 10138656128 10583564288 1134752976 12.166 98 29 58 1 1213439728 72929600 5757152
06:25:42 18818 8192000000 10099720096 10537943040 1149729248 12.307 100 24 48 1 1208611920 53500800 5381872
06:27:20 39886 8192000000 10096185568 10545983488 1161632656 12.419 100 20 39 0 1219580864 53356192 4592016
06:29:03 40099 8192000000 10125398384 10595594240 1159660320 12.400 100 17 34 2 1234141296 70937536 3543440
06:30:44 46258 8192000000 10061423168 10519998464 1158722608 12.391 100 3 6 0 1188192352 28981392 488352
06:32:40 82625 8192000000 10070484592 10521042944 1136319648 12.181 98 22 43 0 1166387696 25875120 4192928
06:35:06 36248 8192000000 10112039264 10574729216 1149139792 12.301 99 16 32 2 1207231824 54566320 3525712
06:36:43 68960 8192000000 10081415296 10586124288 1148631104 12.297 99 10 20 0 1219397696 68557312 2209280
06:38:56 33388 8192000000 10113819120 10557870080 1168246304 12.480 100 20 40 0 1215757792 42836880 4674608
06:40:31 43384 8192000000 10111180464 10579918848 1146173856 12.274 98 15 30 0 1227006928 77495568 3337504
06:42:29 66715 8192000000 10156671600 10631393280 1164810576 12.448 100 11 21 0 1281983056 114276208 2896272
06:44:42 57406 8192000000 10121306656 10559180800 1149446304 12.304 98 30 60 1 1196871744 40978624 6446816
06:46:41 73497 8192000000 10110508880 10580344832 1161009984 12.413 99 15 30 1 1195956016 31716272 3229760
06:48:58 98718 8192000000 10078611152 10543403008 1108470960 11.918 96 12 24 0 1146929136 36209488 2248688
06:51:37 95773 8192000000 10071196592 10509037568 1105147392 11.886 97 18 32 2 1145297136 36796352 3353392
06:54:26 18472 8192000000 10137132240 10580905984 1141701936 12.232 99 34 68 0 1225528144 76824592 7001616
06:55:46 58927 8192000000 10101094240 10591928320 1138416784 12.201 100 13 26 1 1219506752 78279456 2810512
06:57:45 90829 8192000000 10073488640 10529169408 1081732288 11.664 94 22 44 0 1157748192 70572736 5443168
07:00:27 55963 8192000000 10082447408 10517700608 1087309952 11.717 97 21 41 1 1163044560 71085792 4648816
07:02:24 36595 8192000000 10065739600 10496864256 1083425360 11.680 97 20 40 0 1130343200 42781776 4136064
07:04:22 39815 8192000000 10054848016 10488860672 1109388688 11.927 100 39 78 0 1149765616 32032688 8344240
07:06:08 59517 8192000000 10114419472 10557702144 1100428144 11.842 99 33 66 0 1192099136 84864352 6806640
07:08:10 49615 8192000000 10076883168 10511380480 1106366528 11.898 99 24 48 0 1142848496 31403952 5078016
07:10:00 69161 8192000000 10057927104 10509086720 1081506672 11.662 97 15 30 0 1140598784 55810224 3281888
07:12:15 63445 8192000000 10042199344 10522464256 1102604720 11.862 99 4 8 0 1141897712 38635808 657184
07:14:19 39008 8192000000 10053329280 10482991104 1106287264 11.897 99 29 58 1 1133457504 20480400 6689840
07:16:09 76504 8192000000 10111637360 10579914752 1135000720 12.168 100 8 16 0 1220154080 83415408 1737952
07:19:32 58629 8192000000 10068928464 10526384128 1101029824 11.847 97 15 30 0 1169003328 64971488 3002016
07:21:33 67909 8192000000 10100751216 10559025152 1131637344 12.137 99 22 44 0 1197379872 61258624 4483904
07:23:41 36316 8192000000 10120713600 10594230272 1122505984 12.051 98 25 50 0 1209619904 81962320 5151600
07:26:07 54885 8192000000 10159802816 10636890112 1138830784 12.205 99 26 52 2 1228442448 84477040 5134624
07:28:04 77115 8192000000 10079535040 10527584256 1095974272 11.799 96 13 26 0 1146566800 47796448 2796080
07:30:22 64520 8192000000 10121381504 10596089856 1106355664 11.898 97 30 60 0 1144446624 32327248 5763712
07:32:27 23278 8192000000 10103783952 10533797888 1126104640 12.085 98 45 90 0 1182766912 46489040 10173232
07:33:51 50857 8192000000 10086254752 10511888384 1130813536 12.129 99 50 99 0 1184206928 43722816 9670576
07:35:47 72868 8192000000 10043580336 10510057472 1101149088 11.849 96 15 30 0 1143792288 39631952 3011248
07:38:00 51413 8192000000 10058027872 10507071488 1131087376 12.132 99 7 13 0 1165851808 33513472 1250960
07:39:52 79584 8192000000 10090025200 10559242240 1125290880 12.077 98 11 22 0 1179452432 51479568 2681984
07:42:12 56364 8192000000 10076705296 10543783936 1097088496 11.810 96 23 46 0 1161770144 59607952 5073696
07:44:20 54332 8192000000 10099181264 10554474496 1105285104 11.888 97 19 38 0 1195329888 85828624 4216160
07:46:17 55573 8192000000 10092003808 10539659264 1101609568 11.853 97 22 45 0 1169081264 62643984 4827712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-12-2021 07:48:26 to 04-12-2021 07:48:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-12-2021 07:48:42 to 04-12-2021 07:48: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          233458
MON$OLDEST_ACTIVE               234797
MON$OLDEST_SNAPSHOT             234797
MON$NEXT_TRANSACTION            234797
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                       2036704
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2675 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2675 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2675 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2675 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Dec  4 07:47:21 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Dec  4 07:47:21 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Dec  4 07:47:25 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Dec  4 07:47:26 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Sat Dec  4 07:47:27 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
--- end of diff output ---

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

04.12.21 07:48: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
2021-12-04 07:48:47 No severe PSQL-related problems occured
04.12.21 07:48:48. End of report.