Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2021 13: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 16799516 kB
MemAvailable 31527812 kB
Buffers 0 kB
Cached 14928192 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-09 13:37:10
DTS_END                         2021-12-09 13:37:10.4960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40090
TRN_ID                          233372
ATT_ID                          650
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: 8727.53 23819 1047303
customer order: creation 167.11 409 20053
customer order: refuse 14.39 303 1727
order to supplier: creation 44.37 1331 5324
order to supplier: removal 3.13 125334 375
invoice (draft): creation 44.83 3541 5380
invoice (draft): removal 49.17 995 5900
invoice accept: apply 40.58 18044 4869
invoice accept: cancel 44.13 815 5296
customer reserve: creation 3749.82 176 449978
customer reserve: removal 4281.52 73 513783
realization accept: apply 81.82 214 9818
realization accept: cancel 83.16 127 9979
payment from customer: creation 56.61 129 6793
payment from customer: removal 5.20 90 624
payment to supplier: creation 47.45 158 5694
payment to supplier: removal 3.47 145 416
service: total inventory turnovers 3.93 3007 472
service: total monetary turnovers 5.91 429 709
service: aggregate perf. data 0.91 16637 109
service: refresh index statistics 0.03 304420 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2021 13:39:34 to 09-12-2021 13:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4664
TEST_TIME 2 4467
TEST_TIME 3 4563
TEST_TIME 4 5420
TEST_TIME 5 5629
TEST_TIME 6 5380
TEST_TIME 7 5368
TEST_TIME 8 5738
TEST_TIME 9 5719
TEST_TIME 10 5819
TEST_TIME 11 6083
TEST_TIME 12 6301
TEST_TIME 13 6407
TEST_TIME 14 6905
TEST_TIME 15 7103
TEST_TIME 16 7273
TEST_TIME 17 7729
TEST_TIME 18 7831
TEST_TIME 19 8292
TEST_TIME 20 8336
TEST_TIME 21 8738
TEST_TIME 22 8607
TEST_TIME 23 8517
TEST_TIME 24 9362
TEST_TIME 25 10825
TEST_TIME 26 10990
TEST_TIME 27 10752
TEST_TIME 28 10389
TEST_TIME 29 10179
TEST_TIME 30 10096
TEST_TIME 31 10146
TEST_TIME 32 10081
TEST_TIME 33 9880
TEST_TIME 34 9684
TEST_TIME 35 9572
TEST_TIME 36 9600
TEST_TIME 37 9475
TEST_TIME 38 9536
TEST_TIME 39 9525
TEST_TIME 40 9437
TEST_TIME 41 9411
TEST_TIME 42 9462
TEST_TIME 43 9326
TEST_TIME 44 9608
TEST_TIME 45 9718
TEST_TIME 46 9681
TEST_TIME 47 9609
TEST_TIME 48 9650
TEST_TIME 49 9585
TEST_TIME 50 9515
TEST_TIME 51 9405
TEST_TIME 52 9310
TEST_TIME 53 9292
TEST_TIME 54 9235
TEST_TIME 55 9308
TEST_TIME 56 9224
TEST_TIME 57 9164
TEST_TIME 58 9039
TEST_TIME 59 9015
TEST_TIME 60 9018
TEST_TIME 61 8951
TEST_TIME 62 8922
TEST_TIME 63 9026
TEST_TIME 64 9000
TEST_TIME 65 9006
TEST_TIME 66 9076
TEST_TIME 67 9102
TEST_TIME 68 9115
TEST_TIME 69 9100
TEST_TIME 70 9105
TEST_TIME 71 9227
TEST_TIME 72 9205
TEST_TIME 73 9220
TEST_TIME 74 9280
TEST_TIME 75 9283
TEST_TIME 76 9194
TEST_TIME 77 9103
TEST_TIME 78 9083
TEST_TIME 79 9043
TEST_TIME 80 9049
TEST_TIME 81 9027
TEST_TIME 82 9000
TEST_TIME 83 9014
TEST_TIME 84 8966
TEST_TIME 85 8942
TEST_TIME 86 8985
TEST_TIME 87 8979
TEST_TIME 88 8994
TEST_TIME 89 9058
TEST_TIME 90 9078
TEST_TIME 91 9140
TEST_TIME 92 9173
TEST_TIME 93 9206
TEST_TIME 94 9174
TEST_TIME 95 9165
TEST_TIME 96 9078
TEST_TIME 97 9105
TEST_TIME 98 9049
TEST_TIME 99 9026
TEST_TIME 100 8981
TEST_TIME 101 8961
TEST_TIME 102 8925
TEST_TIME 103 8920
TEST_TIME 104 8902
TEST_TIME 105 8856
TEST_TIME 106 8835
TEST_TIME 107 8914
TEST_TIME 108 8923
TEST_TIME 109 8941
TEST_TIME 110 8935
TEST_TIME 111 8959
TEST_TIME 112 8977
TEST_TIME 113 8987
TEST_TIME 114 9001
TEST_TIME 115 9010
TEST_TIME 116 8949
TEST_TIME 117 8905
TEST_TIME 118 8862
TEST_TIME 119 8801
TEST_TIME 120 8732

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-12-2021 13:39:37 to 09-12-2021 13:39:41.

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 1463055 1463055 0 0.00 0 39119 7 0 0
doc_list_biud 1463055 1463055 0 0.00 0 69267 32 0 0
sp_add_invoice_to_stock 4960 4869 91 1.83 37 200986 18045 0 91
sp_cancel_adding_invoice 5296 5296 0 0.00 5 57287 815 0 0
sp_cancel_client_order 1727 1727 0 0.00 5 8315 303 0 0
sp_cancel_customer_reserve 513783 513783 0 0.00 1 53257 73 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 3 12592 90 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 27909 145 0 0
sp_cancel_supplier_invoice 5911 5900 11 0.18 2 69370 995 0 11
sp_cancel_supplier_order 375 375 0 0.00 7 1171584 125335 0 0
sp_cancel_write_off 9979 9979 0 0.00 1 13302 127 0 0
sp_client_order 20053 20053 0 0.00 4 24596 410 0 0
sp_customer_reserve 449978 449978 0 0.00 0 97195 177 0 0
sp_fill_shopping_cart 30754 30754 0 0.00 1 30472 48 0 0
sp_fill_shopping_cart_clo_res 449974 449974 0 0.00 0 18840 16 0 0
sp_get_clo_for_invoice 4865 4865 0 0.00 0 33836 386 0 0
sp_kill_cost_storno 16315 16315 0 0.00 0 26584 58 0 0
sp_kill_qstorno_ret_qs2qd 1043820 1043820 0 0.00 0 69266 40 0 0
sp_kill_qty_storno 551743 551743 0 0.00 0 69267 84 0 0
sp_lock_selected_doc 20359 20359 0 0.00 0 9740 8 0 0
sp_make_cost_storno 27170 27170 0 0.00 0 39059 67 0 0
sp_multiply_rows_for_pdistr 27170 27170 0 0.00 0 27429 38 0 0
sp_multiply_rows_for_qdistr 20053 20053 0 0.00 0 24586 216 0 0
sp_pay_from_customer 6793 6793 0 0.00 5 26573 130 0 0
sp_pay_to_supplier 5694 5694 0 0.00 4 28358 158 0 0
sp_payment_common 12487 12487 0 0.00 1 26579 42 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 8304 280 0 0
sp_qd_handle_on_invoice_upd_sts 10161 10161 0 0.00 0 23284 325 0 0
sp_qd_handle_on_reserve_upd_sts 9818 9818 0 0.00 0 19777 69 0 0
sp_reserve_write_off 9818 9818 0 0.00 7 31376 214 0 0
sp_supplier_invoice 5380 5380 0 0.00 7 86853 3542 0 0
sp_supplier_order 5324 5324 0 0.00 6 29841 1331 0 0
srv_aggregate_perf_data 109 109 0 0.00 2616 391531 16638 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2036 218748 45514 0 0
srv_find_qd_qs_mism 1000637 1000637 0 0.00 0 13796 6 0 0
srv_make_invnt_saldo 472 472 0 0.00 47 114618 3008 0 0
srv_make_money_saldo 709 709 0 0.00 1 18270 429 0 0
srv_recalc_idx_stat 4 4 0 0.00 182133 570312 304421 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4870 4870 0 0.00 0 7237 154 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 7295 114 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 3912 17 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 3251 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 2668 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 1353 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 922 24 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 15993 1434 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 2579 9 0 0
t$perf-norm:sp_client_order 20053 20053 0 0.00 0 9384 18 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 11090 16 0 0
t$perf-norm:sp_pay_from_customer 6793 6793 0 0.00 0 6732 10 0 0
t$perf-norm:sp_pay_to_supplier 5694 5694 0 0.00 0 6162 10 0 0
t$perf-norm:sp_reserve_write_off 9818 9818 0 0.00 0 5850 12 0 0
t$perf-norm:sp_supplier_invoice 5380 5380 0 0.00 0 4965 15 0 0
t$perf-norm:sp_supplier_order 5324 5324 0 0.00 0 7022 15 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 243 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 506 10 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 3717 12 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 2653 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 9910 9728 182 1.83 0 9610 20 0 182
v_all_customers 36420 36420 0 0.00 0 63 1 0 0
v_all_suppliers 29710 29710 0 0.00 0 53 1 0 0
v_all_wares 40106 40106 0 0.00 0 66 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 2370 11 0 0
v_cancel_client_order 3454 3454 0 0.00 0 4366 4 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 2179 9 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 1446 4 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 5243 17 0 0
v_cancel_supplier_invoice 862 840 22 2.55 0 4891 28 0 22
v_cancel_supplier_order 750 750 0 0.00 0 372 5 0 0
v_cancel_write_off 2008 2008 0 0.00 0 4348 19 0 0
v_max_id_clo_ord 5324 5324 0 0.00 0 161 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 2851 10 0 0
v_max_id_ord_sup 5376 5376 0 0.00 0 9183 6 0 0
v_max_non_paid_invoice 1539 1539 0 0.00 0 100 2 0 0
v_max_non_paid_realizn 3627 3627 0 0.00 0 3066 3 0 0
v_min_id_clo_ord 5324 5324 0 0.00 0 4994 10 0 0
v_min_id_clo_res 965 965 0 0.00 0 2705 6 0 0
v_min_id_ord_sup 5376 5376 0 0.00 0 9138 15 0 0
v_min_non_paid_invoice 5694 5694 0 0.00 0 26273 29 0 0
v_min_non_paid_realizn 6793 6793 0 0.00 0 26266 38 0 0
v_our_firm 10018 10018 0 0.00 0 59 1 0 0
v_reserve_write_off 19634 19634 0 0.00 0 4198 6 0 0
x_lock_depdocs_on_canc_invoice 10776 10776 0 0.00 0 13055 110 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 21494 404 0 0
x_make_qstorno_1000_1200 5324 5324 0 0.00 2 29796 1252 0 0
x_make_qstorno_1200_2000 5380 5380 0 0.00 4 86843 3353 0 0
x_make_qstorno_2100_3300 449978 449978 0 0.00 0 97194 159 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-12-2021 13:39:41 to 09-12-2021 13:39:46.

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:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 32408 8192000000 9721514240 10019586048 802782096 8.924 100 91 182 0 832937936 7564944 22590896
11:09:54 17670 8192000000 9763185872 10072567808 836026944 9.260 100 83 166 0 867407296 11343056 20037296
11:11:20 13558 8192000000 9801966992 10120237056 874127168 9.641 100 86 172 0 915003136 20583552 20292416
11:12:37 14366 8192000000 9876171616 10210414592 924577888 10.141 100 81 162 1 986543984 44379808 17586288
11:13:54 7963 8192000000 9922614736 10267451392 961330176 10.502 100 83 164 0 1038962160 60304384 17327600
11:15:04 6815 8192000000 9969933216 10325131264 993717136 10.818 100 84 168 1 1075995392 65338736 16939520
11:16:13 3449 8192000000 10016584416 10377891840 1018093664 11.054 100 82 163 0 1122217888 87164400 16959824
11:17:18 2322 8192000000 10007316080 10374221824 1043335744 11.297 100 90 178 2 1127487536 65395488 18756304
11:18:22 1715 8192000000 10051586448 10424918016 1067645040 11.530 100 94 186 2 1179029456 93016160 18368256
11:19:25 1379 8192000000 10025313680 10398162944 1073718416 11.588 100 89 180 1 1139384064 46780688 18884960
11:20:39 7396 8192000000 9997862256 10392236032 1070905584 11.561 100 24 48 0 1109909680 34389856 4614240
11:21:48 6170 8192000000 9998769008 10394226688 1077529344 11.624 100 38 76 1 1114234784 28235600 8469840
11:22:57 3944 8192000000 9983911664 10381549568 1079012096 11.638 100 49 98 2 1107724656 17933248 10779312
11:24:04 67723 8192000000 10002339712 10395029504 1089021072 11.733 100 50 98 3 1115899808 15359296 11519440
11:26:13 7247 8192000000 10005624608 10416381952 1082322288 11.670 99 31 62 0 1117660160 28442128 6895744
11:27:21 6368 8192000000 10009205216 10405486592 1078064560 11.629 99 36 71 0 1107481600 22217712 7199328
11:28:28 28473 8192000000 9989251888 10392772608 1042400672 11.288 97 29 57 0 1078235792 29953920 5881200
11:30:00 4615 8192000000 9948607456 10330243072 1009387344 10.969 97 61 125 1 1055955376 32712592 13855440
11:31:07 5833 8192000000 9954415360 10337140736 1010088128 10.976 97 31 62 0 1046459024 29715632 6655264
11:32:18 74734 8192000000 9941518640 10343190528 1017642656 11.049 98 20 40 1 1051498848 29242624 4613568
11:34:39 5667 8192000000 9968267056 10354065408 1053297712 11.392 100 42 86 1 1082410688 19598656 9514320
11:35:46 4299 8192000000 9969399248 10362040320 1060083408 11.457 100 35 70 2 1085966592 17655584 8227600
11:36:53 12009 8192000000 10000149840 10400731136 1075678176 11.606 100 17 33 1 1096659856 17796208 3185472
11:38:08 52038 8192000000 10039745072 10433462272 1103693424 11.873 100 52 105 0 1152615024 37438176 11483424
11:40:04 2449 8192000000 10076180240 10474254336 1119055808 12.018 100 57 111 0 1182898736 52666880 11176048
11:41:10 7859 8192000000 10085827616 10490253312 1129793568 12.119 100 47 92 0 1200745408 60250288 10701552
11:42:25 27619 8192000000 10131037888 10540371968 1144545488 12.258 100 46 92 0 1222996080 67811136 10639456
11:43:56 11969 8192000000 10193121440 10606477312 1158404960 12.388 100 51 101 1 1295519056 125687536 11426560
11:45:18 13805 8192000000 10203484416 10622074880 1155181744 12.358 99 61 122 1 1317062400 147699584 14181072
11:46:38 6463 8192000000 10254999328 10670972928 1172822000 12.523 100 56 111 1 1365668528 180174368 12672160
11:47:47 3111 8192000000 10301714752 10711674880 1176071296 12.554 100 82 162 0 1404313888 209738592 18504000
11:48:54 2033 8192000000 10303752032 10715639808 1183703808 12.625 100 76 149 0 1411355200 210898192 16753200
11:49:59 4575 8192000000 10338486672 10744778752 1189302976 12.677 99 71 140 2 1429683856 222737056 17643824
11:51:09 6190 8192000000 10376594448 10791321600 1189929312 12.683 100 79 158 0 1482579616 274510592 18139712
11:52:17 4576 8192000000 10399511632 10804326400 1172333248 12.519 100 74 147 2 1501918160 312240224 17344688
11:53:32 3129 8192000000 10400425520 10801467392 1157001120 12.375 100 76 150 1 1504603472 330262480 17339872
11:54:40 4917 8192000000 10451608944 10850390016 1149434864 12.304 99 68 135 0 1537707504 372002928 16269712
11:55:50 3124 8192000000 10461007744 10874912768 1159729488 12.401 100 65 128 1 1562795280 387887984 15177808
11:56:56 4757 8192000000 10442374128 10852982784 1152904384 12.337 100 62 122 3 1542264368 374948960 14411024
11:58:03 8990 8192000000 10438543056 10871554048 1148357040 12.294 100 45 90 0 1547901312 387864816 11679456
11:59:21 25209 8192000000 10426969648 10859175936 1095472000 11.795 97 27 54 0 1501276208 398234752 7569456
12:00:53 10414 8192000000 10414170256 10904911872 1110135024 11.934 100 21 42 0 1555646960 439174688 6337248
12:02:08 31692 8192000000 10245079824 10751418368 1109993200 11.932 100 19 38 0 1404060800 289056256 5011344
12:03:40 21147 8192000000 10062018992 10468884480 1116024208 11.989 99 52 102 0 1143788208 16634256 11129744
12:05:02 218748 8192000000 10059729296 10560364544 1082448256 11.671 96 32 64 0 1142628080 53313264 6866560
12:09:42 27436 8192000000 10052821536 10519121920 1119808400 12.025 99 9 18 0 1156332064 34955760 1567904
12:11:54 54237 8192000000 10129799712 10583306240 1117209584 12.001 98 18 36 1 1208187248 87398976 3578688
12:15:29 78747 8192000000 10069965328 10533773312 1111697008 11.948 99 12 23 1 1169596176 55719664 2179504
12:17:54 75113 8192000000 10100719040 10584199168 1119015280 12.018 99 11 22 0 1194106320 72808016 2283024
12:20:18 105551 8192000000 10133513824 10683678720 1126256656 12.086 99 19 38 1 1254995248 124142016 4596576
12:23:10 91019 8192000000 10078236896 10525175808 1088102896 11.725 96 15 29 0 1135556848 44362928 3091024
12:25:42 46692 8192000000 10078615632 10516094976 1132577632 12.146 100 30 59 0 1189266576 50546304 6142640
12:27:32 56169 8192000000 10109667136 10572828672 1122170672 12.047 99 31 62 1 1192684000 64039184 6474144
12:30:31 29370 8192000000 10109554928 10567815168 1140391440 12.219 100 37 74 3 1209392496 60553088 8447968
12:32:18 56024 8192000000 10056629328 10536321024 1112880448 11.960 98 7 14 0 1149582784 35500640 1201696
12:34:15 68721 8192000000 10063005936 10504970240 1117046320 11.999 98 15 30 0 1144162704 22940912 4175472
12:37:00 34376 8192000000 10088056816 10563108864 1116300736 11.992 98 16 32 1 1184198320 64756784 3140800
12:38:38 56723 8192000000 10099388944 10555092992 1127182912 12.095 98 14 28 0 1169932784 40349504 2400368
12:40:35 45398 8192000000 10052886112 10504060928 1098514976 11.824 96 34 68 0 1138096320 32225312 7356032
12:42:27 36214 8192000000 10114732256 10561634304 1113349824 11.964 98 22 44 0 1208938944 90804928 4784192
12:44:44 41074 8192000000 10082353568 10518827008 1113776528 11.968 98 32 63 0 1156174064 36400000 5997536
12:46:26 22140 8192000000 10078037344 10502332416 1095754928 11.797 98 38 75 0 1156280672 53085072 7440672
12:47:50 38200 8192000000 10133197808 10588729344 1129533392 12.117 99 33 66 0 1213910512 77740528 6636592
12:50:17 64016 8192000000 10112951840 10566914048 1100848432 11.846 97 50 99 0 1193214912 82202320 10164160
12:52:25 90297 8192000000 10032912304 10488086528 1100811136 11.845 98 8 16 0 1139359536 37140096 1408304
12:55:12 7958 8192000000 10070296272 10497544192 1124988720 12.074 100 44 88 1 1171346416 37698240 8659456
12:56:22 48871 8192000000 10092958560 10549854208 1122625536 12.052 100 13 26 0 1204232544 78787696 2819312
12:59:04 48584 8192000000 10074101456 10544656384 1126441952 12.088 100 15 30 0 1185789920 56159648 3188320
13:00:57 43014 8192000000 10073252496 10505543680 1101448352 11.851 97 18 36 1 1142598016 37143184 4006480
13:02:41 49847 8192000000 10133360400 10572578816 1127393856 12.097 99 25 50 0 1221759488 88761056 5604576
13:04:53 78280 8192000000 10148328176 10573631488 1106310880 11.897 97 32 64 0 1237871296 124636944 6923472
13:07:14 24657 8192000000 10086319984 10508800000 1124898560 12.073 99 27 51 1 1169748704 39529408 5320736
13:08:42 53068 8192000000 10110336320 10545934336 1118821168 12.016 98 18 37 1 1163242416 41139936 3281312
13:10:38 54143 8192000000 10058485216 10487058432 1123109232 12.056 99 21 40 0 1146944944 19950160 3885552
13:12:36 137205 8192000000 10069015888 10527420416 1130246592 12.124 99 25 50 0 1203848096 68079424 5522080
13:15:54 46930 8192000000 10086273184 10543886336 1147011408 12.281 100 25 50 0 1209896800 57417888 5467504
13:17:58 52081 8192000000 10078075760 10538815488 1111991888 11.951 97 7 13 1 1161902864 48008128 1902848
13:20:08 106921 8192000000 10100862400 10580721664 1127922912 12.102 98 19 38 1 1184143312 51441200 4779200
13:23:20 28654 8192000000 10143315568 10588635136 1127523504 12.098 98 19 38 1 1224469328 92634352 4311472
13:24:49 93903 8192000000 10094473136 10532225024 1096551440 11.805 96 22 44 1 1150072080 48749424 4771216
13:27:28 61240 8192000000 10150447872 10608230400 1138632256 12.203 99 24 48 1 1223679968 80081776 4965936
13:29:33 25411 8192000000 10065417536 10489622528 1076905888 11.618 95 29 57 2 1117639568 34805168 5928512
13:31:03 90639 8192000000 10052608432 10507452416 1059413552 11.451 94 23 46 1 1115511376 51165648 4932176
13:33:34 96170 8192000000 10059848256 10512703488 1114378320 11.974 99 24 46 0 1157327536 37906960 5042256
13:36:10 81941 8192000000 9623636144 10068484096 735829520 8.241 65 11 22 0 819160624 80600912 2730192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2021 13:39:47 to 09-12-2021 13:40:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2021 13:40:03 to 09-12-2021 13:40:05.

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          231999
MON$OLDEST_ACTIVE               234624
MON$OLDEST_SNAPSHOT             234624
MON$NEXT_TRANSACTION            234624
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                       2024992
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2676 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2676 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2676 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 ---
:132: 
:133: mscs-test-161.budzdorov.ru    Thu Dec  9 13:38:01 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Thu Dec  9 13:38:02 2021
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Thu Dec  9 13:38:11 2021
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:143: 
--- end of diff output ---

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

09.12.21 13:40:06. 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-09 13:40:08 No severe PSQL-related problems occured
09.12.21 13:40:09. End of report.