Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.11.2021 04: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 16619756 kB
MemAvailable 31555232 kB
Buffers 20 kB
Cached 15041228 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-28 04:37:06
DTS_END                         2021-11-28 04:37:06.0830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59234
TRN_ID                          233549
ATT_ID                          617
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: 9159.03 30648 1099084
customer order: creation 164.45 427 19734
customer order: refuse 14.48 398 1738
order to supplier: creation 45.78 1400 5494
order to supplier: removal 3.29 128505 395
invoice (draft): creation 45.21 3885 5425
invoice (draft): removal 51.83 922 6220
invoice accept: apply 41.97 21904 5036
invoice accept: cancel 46.93 956 5632
customer reserve: creation 3867.91 217 464149
customer reserve: removal 4584.64 72 550157
realization accept: apply 81.16 211 9739
realization accept: cancel 82.01 148 9841
payment from customer: creation 59.68 151 7161
payment from customer: removal 5.49 80 659
payment to supplier: creation 49.28 167 5913
payment to supplier: removal 3.48 92 418
service: total inventory turnovers 3.88 3788 466
service: total monetary turnovers 6.64 573 797
service: aggregate perf. data 0.88 21575 106
service: refresh index statistics 0.03 427481 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-11-2021 04:39:40 to 28-11-2021 04:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5466
TEST_TIME 2 3302
TEST_TIME 3 4009
TEST_TIME 4 4707
TEST_TIME 5 4961
TEST_TIME 6 5732
TEST_TIME 7 6207
TEST_TIME 8 6187
TEST_TIME 9 6148
TEST_TIME 10 6509
TEST_TIME 11 6626
TEST_TIME 12 6743
TEST_TIME 13 6519
TEST_TIME 14 6765
TEST_TIME 15 6864
TEST_TIME 16 7006
TEST_TIME 17 7361
TEST_TIME 18 7526
TEST_TIME 19 7722
TEST_TIME 20 7878
TEST_TIME 21 7984
TEST_TIME 22 8348
TEST_TIME 23 8281
TEST_TIME 24 8786
TEST_TIME 25 8842
TEST_TIME 26 8879
TEST_TIME 27 9201
TEST_TIME 28 9515
TEST_TIME 29 9676
TEST_TIME 30 10507
TEST_TIME 31 10758
TEST_TIME 32 10749
TEST_TIME 33 10517
TEST_TIME 34 10418
TEST_TIME 35 10206
TEST_TIME 36 9967
TEST_TIME 37 9774
TEST_TIME 38 9914
TEST_TIME 39 9944
TEST_TIME 40 9772
TEST_TIME 41 9835
TEST_TIME 42 9849
TEST_TIME 43 9757
TEST_TIME 44 9658
TEST_TIME 45 9499
TEST_TIME 46 9593
TEST_TIME 47 9548
TEST_TIME 48 9519
TEST_TIME 49 9513
TEST_TIME 50 9515
TEST_TIME 51 9507
TEST_TIME 52 9657
TEST_TIME 53 9727
TEST_TIME 54 9748
TEST_TIME 55 9977
TEST_TIME 56 9882
TEST_TIME 57 9843
TEST_TIME 58 9866
TEST_TIME 59 9776
TEST_TIME 60 9698
TEST_TIME 61 9591
TEST_TIME 62 9482
TEST_TIME 63 9464
TEST_TIME 64 9370
TEST_TIME 65 9332
TEST_TIME 66 9373
TEST_TIME 67 9384
TEST_TIME 68 9399
TEST_TIME 69 9383
TEST_TIME 70 9504
TEST_TIME 71 9468
TEST_TIME 72 9512
TEST_TIME 73 9512
TEST_TIME 74 9515
TEST_TIME 75 9511
TEST_TIME 76 9574
TEST_TIME 77 9613
TEST_TIME 78 9586
TEST_TIME 79 9578
TEST_TIME 80 9599
TEST_TIME 81 9530
TEST_TIME 82 9435
TEST_TIME 83 9456
TEST_TIME 84 9399
TEST_TIME 85 9383
TEST_TIME 86 9320
TEST_TIME 87 9270
TEST_TIME 88 9248
TEST_TIME 89 9201
TEST_TIME 90 9233
TEST_TIME 91 9271
TEST_TIME 92 9227
TEST_TIME 93 9292
TEST_TIME 94 9284
TEST_TIME 95 9337
TEST_TIME 96 9298
TEST_TIME 97 9424
TEST_TIME 98 9372
TEST_TIME 99 9411
TEST_TIME 100 9384
TEST_TIME 101 9301
TEST_TIME 102 9281
TEST_TIME 103 9247
TEST_TIME 104 9178
TEST_TIME 105 9154
TEST_TIME 106 9208
TEST_TIME 107 9182
TEST_TIME 108 9177
TEST_TIME 109 9163
TEST_TIME 110 9180
TEST_TIME 111 9178
TEST_TIME 112 9195
TEST_TIME 113 9191
TEST_TIME 114 9149
TEST_TIME 115 9236
TEST_TIME 116 9231
TEST_TIME 117 9240
TEST_TIME 118 9238
TEST_TIME 119 9238
TEST_TIME 120 9162

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-11-2021 04:39:44 to 28-11-2021 04:39:47.

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 1525940 1525940 0 0.00 0 32395 8 0 0
doc_list_biud 1525931 1525931 0 0.00 0 45209 33 0 0
sp_add_invoice_to_stock 5177 5036 141 2.72 36 181185 21905 0 141
sp_cancel_adding_invoice 5632 5632 0 0.00 6 61318 956 0 0
sp_cancel_client_order 1738 1738 0 0.00 4 14763 399 0 0
sp_cancel_customer_reserve 550157 550157 0 0.00 1 30551 73 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 2570 80 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 3422 93 0 0
sp_cancel_supplier_invoice 6233 6220 13 0.20 2 45214 922 0 13
sp_cancel_supplier_order 395 395 0 0.00 13 1238522 128506 0 0
sp_cancel_write_off 9841 9841 0 0.00 1 20298 148 0 0
sp_client_order 19734 19734 0 0.00 4 36940 428 0 0
sp_customer_reserve 464149 464149 0 0.00 0 109425 217 0 0
sp_fill_shopping_cart 30636 30636 0 0.00 1 23847 43 0 0
sp_fill_shopping_cart_clo_res 464145 464145 0 0.00 0 22934 16 0 0
sp_get_clo_for_invoice 5032 5032 0 0.00 0 28695 329 0 0
sp_kill_cost_storno 16550 16550 0 0.00 0 9204 71 0 0
sp_kill_qstorno_ret_qs2qd 1116770 1116770 0 0.00 0 45208 39 0 0
sp_kill_qty_storno 588754 588754 0 0.00 0 45209 84 0 0
sp_lock_selected_doc 20459 20459 0 0.00 0 9051 7 0 0
sp_make_cost_storno 27845 27845 0 0.00 0 32368 72 0 0
sp_multiply_rows_for_pdistr 27845 27845 0 0.00 0 18426 41 0 0
sp_multiply_rows_for_qdistr 19734 19734 0 0.00 0 36922 226 0 0
sp_pay_from_customer 7161 7161 0 0.00 4 33500 152 0 0
sp_pay_to_supplier 5913 5913 0 0.00 4 33612 168 0 0
sp_payment_common 13072 13072 0 0.00 1 22089 36 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 14752 347 0 0
sp_qd_handle_on_invoice_upd_sts 10664 10664 0 0.00 0 28662 401 0 0
sp_qd_handle_on_reserve_upd_sts 9739 9739 0 0.00 0 19859 63 0 0
sp_reserve_write_off 9739 9739 0 0.00 7 26142 212 0 0
sp_supplier_invoice 5425 5425 0 0.00 6 96715 3886 0 0
sp_supplier_order 5494 5494 0 0.00 6 42927 1401 0 0
srv_aggregate_perf_data 106 106 0 0.00 2699 336625 21576 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1253 129166 42729 0 0
srv_find_qd_qs_mism 1050277 1050277 0 0.00 0 24902 7 0 0
srv_make_invnt_saldo 466 466 0 0.00 55 123685 3788 0 0
srv_make_money_saldo 797 797 0 0.00 1 12989 573 0 0
srv_recalc_idx_stat 4 4 0 0.00 188918 531057 427482 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5036 5036 0 0.00 0 9601 189 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 2142 108 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 4205 16 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 1667 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 1004 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 4128 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 4103 40 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 23849 1944 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 623 7 0 0
t$perf-norm:sp_client_order 19734 19734 0 0.00 0 9183 20 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 1163 5 0 0
t$perf-norm:sp_pay_from_customer 7161 7161 0 0.00 0 5436 9 0 0
t$perf-norm:sp_pay_to_supplier 5913 5913 0 0.00 0 6673 9 0 0
t$perf-norm:sp_reserve_write_off 9739 9739 0 0.00 0 9098 11 0 0
t$perf-norm:sp_supplier_invoice 5425 5425 0 0.00 0 4373 19 0 0
t$perf-norm:sp_supplier_order 5494 5494 0 0.00 0 5970 18 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 635 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 155 4 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 1876 13 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 1422 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 28 9 0 0
v_add_invoice_to_stock 10344 10062 282 2.72 0 5407 15 0 282
v_all_customers 36754 36754 0 0.00 0 16 1 0 0
v_all_suppliers 30566 30566 0 0.00 0 18 1 0 0
v_all_wares 39458 39458 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 2106 8 0 0
v_cancel_client_order 3476 3476 0 0.00 0 3926 7 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 2544 10 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 392 4 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1145 8 0 0
v_cancel_supplier_invoice 768 742 26 3.38 0 4969 23 0 26
v_cancel_supplier_order 790 790 0 0.00 0 245 4 0 0
v_cancel_write_off 2000 2000 0 0.00 0 2548 19 0 0
v_max_id_clo_ord 5491 5491 0 0.00 0 2299 2 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 3187 17 0 0
v_max_id_ord_sup 5415 5415 0 0.00 0 9407 9 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 2055 7 0 0
v_max_non_paid_realizn 3715 3715 0 0.00 0 2405 3 0 0
v_min_id_clo_ord 5491 5491 0 0.00 0 5142 8 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 2643 10 0 0
v_min_id_ord_sup 5415 5415 0 0.00 0 5603 8 0 0
v_min_non_paid_invoice 5912 5912 0 0.00 0 16081 26 0 0
v_min_non_paid_realizn 7160 7160 0 0.00 0 16733 30 0 0
v_our_firm 9594 9594 0 0.00 0 17 1 0 0
v_reserve_write_off 19478 19478 0 0.00 0 4102 8 0 0
x_lock_depdocs_on_canc_invoice 11481 11481 0 0.00 0 22328 127 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 10917 387 0 0
x_make_qstorno_1000_1200 5494 5494 0 0.00 2 42914 1311 0 0
x_make_qstorno_1200_2000 5425 5425 0 0.00 3 94623 3729 0 0
x_make_qstorno_2100_3300 464149 464149 0 0.00 0 109424 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-11-2021 04:39:48 to 28-11-2021 04:39:52.

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
02:07:09 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:10 29261 8192000000 9720549456 10018250752 798141152 8.877 100 84 168 0 825985760 7562816 20281792
02:09:45 23520 8192000000 9761877856 10067943424 832640896 9.226 100 87 174 0 871599504 17881472 21077136
02:11:15 16054 8192000000 9807720400 10120294400 863478912 9.535 100 89 176 1 915498560 30892352 21127296
02:12:51 11711 8192000000 9874373936 10206072832 915194832 10.049 100 77 151 2 980212528 48399456 16618240
02:14:09 13167 8192000000 9923431680 10265260032 959876576 10.488 100 76 152 1 1024853792 48938752 16038464
02:15:25 4169 8192000000 9953945744 10312511488 998064608 10.860 100 74 146 1 1070420544 57246352 15109584
02:16:31 5782 8192000000 9992165040 10354036736 1027750624 11.147 100 94 181 0 1112142112 66004912 18386576
02:17:39 3446 8192000000 10015270224 10384371712 1047980848 11.341 100 86 170 1 1130738976 66155296 16602832
02:18:46 2288 8192000000 10055645168 10427785216 1067451120 11.528 100 93 185 0 1164751248 78821568 18478560
02:19:50 2055 8192000000 10063793456 10439069696 1073364016 11.584 100 93 185 2 1178314992 86028384 18922592
02:20:55 4422 8192000000 10022043408 10406051840 1084885584 11.694 100 62 126 0 1135139104 37664288 12589232
02:22:00 15060 8192000000 9985181824 10383597568 1088650432 11.730 100 33 65 0 1112539632 16956160 6933040
02:23:19 5292 8192000000 10013222736 10414161920 1094188400 11.782 100 26 52 0 1119174304 19378000 5607904
02:24:26 5096 8192000000 10015003216 10408284160 1097474656 11.814 100 39 78 1 1124396768 18587616 8334496
02:25:33 8067 8192000000 10015407184 10425028608 1101410496 11.851 100 26 53 0 1131050064 24370320 5269248
02:26:45 59173 8192000000 10026984288 10441830400 1088616016 11.729 99 30 60 0 1111447760 16051744 6780000
02:28:47 7004 8192000000 10005727008 10412609536 1061372224 11.470 96 38 75 2 1091486368 21495376 8618768
02:29:54 9091 8192000000 9958475296 10355032064 1017352416 11.046 96 41 81 0 1052497424 25926992 9218016
02:31:04 6080 8192000000 9967778976 10356490240 1031328656 11.181 98 36 70 3 1059837296 20621328 7887312
02:32:19 61031 8192000000 9937857504 10338152448 975015072 10.636 93 25 49 1 996066896 15850464 5201360
02:34:29 6868 8192000000 9969245744 10360156160 1038457424 11.250 100 58 117 0 1064005360 11733968 13813968
02:35:37 9312 8192000000 9966344288 10368966656 1057250208 11.430 100 29 58 1 1090262800 26081648 6930944
02:36:49 6385 8192000000 9983628960 10376667136 1072809056 11.579 100 39 76 3 1096011248 14371792 8830400
02:38:09 103538 8192000000 10029112480 10436075520 1093636640 11.777 100 40 79 1 1139977984 36732144 9609200
02:40:55 24330 8192000000 10082487104 10485784576 1110660064 11.939 100 40 79 1 1187715408 69186288 7869056
02:42:24 12418 8192000000 10127111040 10524073984 1125259088 12.077 100 68 131 1 1226075136 86599072 14216976
02:43:44 10133 8192000000 10113714304 10519289856 1134241744 12.161 100 58 116 1 1220396560 73437152 12717664
02:45:03 2591 8192000000 10132982336 10532880384 1140215520 12.218 100 64 125 1 1237030624 82441648 14373456
02:46:15 14010 8192000000 10157734736 10573754368 1145896784 12.271 100 53 106 3 1268928864 111632464 11399616
02:47:32 9278 8192000000 10179031088 10602778624 1162843776 12.430 100 61 119 0 1285196144 109967184 12385184
02:48:45 4784 8192000000 10207296688 10618994688 1174064016 12.535 100 56 111 1 1313371808 127571312 11736480
02:49:55 2942 8192000000 10242559600 10650791936 1186310672 12.649 100 76 152 0 1353151920 149225792 17615456
02:51:01 1942 8192000000 10286943056 10688970752 1186448000 12.650 100 89 177 0 1383737472 178857888 18431584
02:52:06 3342 8192000000 10287012912 10686005248 1169700880 12.494 100 79 158 0 1382197712 195038160 17458672
02:53:14 5024 8192000000 10321216608 10724564992 1172391792 12.519 100 80 158 0 1418410944 228320400 17698752
02:54:27 1252 8192000000 10317547072 10710659072 1148422864 12.295 100 90 178 0 1414614320 246017568 20173888
02:55:34 5596 8192000000 10344809632 10742677504 1156586480 12.371 100 88 174 0 1443402128 266941808 19873840
02:56:47 2762 8192000000 10351271616 10747580416 1144916528 12.262 98 83 167 0 1447471088 282718736 19835824
02:57:55 2469 8192000000 10356421152 10766462976 1154112880 12.348 100 79 155 0 1449369168 277000736 18255552
02:59:05 4576 8192000000 10421574112 10832048128 1162160624 12.423 100 78 157 1 1520995760 340220000 18615136
03:00:15 1983 8192000000 10402278624 10816299008 1166779344 12.467 100 81 164 2 1503858576 317710560 19368672
03:01:26 3329 8192000000 10400074912 10807582720 1173831216 12.533 100 70 140 1 1499374496 308181120 17362160
03:02:35 12482 8192000000 10399760976 10832625664 1177497312 12.567 100 48 97 1 1509012544 319644592 11870640
03:03:51 22566 8192000000 10429322336 10870669312 1166159920 12.461 100 33 66 0 1514147536 339205136 8782480
03:05:23 18696 8192000000 10343387648 10817699840 1169490912 12.492 100 25 50 1 1467314240 291519856 6303472
03:06:43 26182 8192000000 10297622032 10751582208 1130672832 12.128 99 17 34 0 1381677232 246029664 4974736
03:08:11 61831 8192000000 10096965168 10563526656 1111975440 11.951 98 14 28 0 1186185520 71712656 2497424
03:10:41 21477 8192000000 10078286528 10498789376 1133591552 12.155 100 62 124 0 1171294256 23951744 13750960
03:12:34 119255 8192000000 10179617024 10622038016 1146868544 12.280 100 39 77 2 1247453504 92360016 8224944
03:15:48 35191 8192000000 10049498752 10519568384 1097090304 11.810 97 17 34 1 1184813120 84347984 3374832
03:17:25 39813 8192000000 10123358320 10543153152 1131264848 12.133 99 56 112 0 1190638496 47000512 12373136
03:19:06 29443 8192000000 10119817424 10562797568 1140850656 12.224 100 12 24 2 1214493120 70744768 2897696
03:20:37 21810 8192000000 10064244912 10507350016 1135845488 12.176 100 15 30 0 1169762288 30794560 3122240
03:22:00 118975 8192000000 10076434816 10559311872 1150529440 12.314 100 19 39 0 1197788128 43505408 3753280
03:25:00 16398 8192000000 10116581168 10556092416 1124412736 12.069 97 37 74 0 1194518864 62290720 7815408
03:26:17 45593 8192000000 10157031888 10603089920 1129691088 12.118 98 29 58 2 1227344336 91535360 6117888
03:28:04 68013 8192000000 10214357664 10649677824 1135335664 12.172 98 22 44 1 1261485776 120871280 5278832
03:30:19 48025 8192000000 10163597744 10683650048 1118622400 12.014 98 17 33 2 1255740688 133276096 3842192
03:32:07 89750 8192000000 10084083008 10566746112 1104348848 11.879 97 10 20 0 1172411712 65992256 2070608
03:34:38 33486 8192000000 10073251232 10523344896 1115995376 11.989 99 37 75 3 1174463600 50710176 7758048
03:36:15 77565 8192000000 10062118720 10508877824 1099689920 11.835 98 39 78 1 1157981888 50528784 7763184
03:38:57 33838 8192000000 10100671920 10525810688 1128925296 12.111 99 57 114 1 1182424256 41624368 11874592
03:40:31 38524 8192000000 10167614672 10629382144 1131608960 12.137 99 31 62 1 1273351312 134346432 7395920
03:42:56 60385 8192000000 10147446736 10676580352 1137314896 12.190 100 21 41 1 1311324432 168494128 5515408
03:45:40 81479 8192000000 10148430208 10609561600 1079769664 11.645 95 16 32 0 1228402000 144782528 3849808
03:48:02 39896 8192000000 10065195632 10500866048 1075430256 11.604 95 18 36 1 1121752496 43031632 3290608
03:49:46 38157 8192000000 10079679216 10506121216 1101750832 11.854 98 20 40 1 1164678944 58896720 4031392
03:51:26 22193 8192000000 10082069968 10517262336 1110874576 11.941 98 47 94 1 1169433600 49242576 9316448
03:52:52 63072 8192000000 10107867344 10599694336 1128462624 12.107 100 31 62 1 1193185216 58845568 5877024
03:54:59 71396 8192000000 10081613664 10521952256 1100047392 11.838 97 26 52 0 1148521360 43512160 4961808
03:57:17 129166 8192000000 10067323664 10547380224 1089224752 11.735 97 15 30 1 1183550496 91280912 3044832
04:01:01 45592 8192000000 10075952240 10568011776 1110415728 11.936 99 12 25 1 1168309456 55433840 2459888
04:02:47 39856 8192000000 10060270848 10529075200 1129817520 12.120 100 6 12 0 1154577408 23734896 1024992
04:04:27 72445 8192000000 10095107552 10550988800 1061412368 11.470 93 17 34 0 1117295488 52267648 3615472
04:06:40 47714 8192000000 10141949008 10599264256 1136728448 12.185 100 23 46 0 1247895888 106279648 4887792
04:08:37 50757 8192000000 10120235504 10557718528 1120440736 12.031 98 26 52 1 1208738144 82368976 5928432
04:10:29 65785 8192000000 10135236144 10603786240 1128768736 12.110 99 25 50 2 1236261296 102394864 5097696
04:13:11 56591 8192000000 10134412512 10567127040 1122601936 12.052 98 37 74 1 1200352000 70568976 7181088
04:15:08 81577 8192000000 10104944240 10570850304 1121413376 12.040 98 15 30 0 1188497024 64298624 2785024
04:17:36 93737 8192000000 10077070928 10519060480 1122693776 12.052 99 21 42 1 1173845008 47033008 4118224
04:20:18 76388 8192000000 10089004784 10550329344 1106818832 11.902 97 11 21 0 1171966176 62843232 2304112
04:22:34 59151 8192000000 10095608480 10618822656 1129276432 12.115 99 13 26 0 1202522896 70252176 2994288
04:25:14 75526 8192000000 10083842080 10564747264 1110644288 11.939 97 19 38 0 1169989776 55310112 4035376
04:28:13 41487 8192000000 10083946416 10538397696 1116672768 11.996 98 18 36 0 1174590720 54296912 3621040
04:29:55 30821 8192000000 10073764800 10520797184 1102139504 11.858 97 32 64 0 1145320656 37249264 5931888
04:31:28 73573 8192000000 10067170096 10537848832 1082637792 11.673 96 24 48 1 1183840480 96181328 5021360
04:33:46 122588 8192000000 10072377856 10517041152 1063694944 11.492 94 21 42 0 1129701024 61760096 4245984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-11-2021 04:39:54 to 28-11-2021 04:40:09.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-11-2021 04:40:09 to 28-11-2021 04:40:11.

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          232824
MON$OLDEST_ACTIVE               233661
MON$OLDEST_SNAPSHOT             233661
MON$NEXT_TRANSACTION            233661
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                       2049440
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             796
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2669 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2669 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2669 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2669 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 ---
:101: 
:102: mscs-test-161.budzdorov.ru    Sun Nov 28 04:37:08 2021
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Sun Nov 28 04:37:20 2021
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Sun Nov 28 04:37:44 2021
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:112: 
--- end of diff output ---

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

28.11.21 04:40:12. 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-28 04:40:15 No severe PSQL-related problems occured
28.11.21 04:40:16. End of report.