Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.10.2021 13:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15684668 kB
MemAvailable 30723132 kB
Buffers 0 kB
Cached 16000888 kB
SwapCached 4828 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-10-05 13:37:09
DTS_END                         2021-10-05 13:37:09.2890
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/44912
TRN_ID                          239420
ATT_ID                          697
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: 9310.96 21102 1117315
customer order: creation 175.46 398 21055
customer order: refuse 14.81 318 1777
order to supplier: creation 47.02 1380 5642
order to supplier: removal 3.43 110648 412
invoice (draft): creation 47.14 3486 5657
invoice (draft): removal 53.03 849 6363
invoice accept: apply 42.04 18510 5045
invoice accept: cancel 48.81 856 5857
customer reserve: creation 3854.32 184 462518
customer reserve: removal 4718.77 63 566253
realization accept: apply 84.65 174 10158
realization accept: cancel 91.80 121 11016
payment from customer: creation 60.46 110 7255
payment from customer: removal 6.00 76 720
payment to supplier: creation 48.38 135 5805
payment to supplier: removal 3.55 73 426
service: total inventory turnovers 4.12 3225 494
service: total monetary turnovers 6.28 585 754
service: aggregate perf. data 0.87 14788 104
service: refresh index statistics 0.03 266056 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-10-2021 13:37:55 to 05-10-2021 13:37:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2202
TEST_TIME 2 3019
TEST_TIME 3 4592
TEST_TIME 4 4987
TEST_TIME 5 5592
TEST_TIME 6 5479
TEST_TIME 7 5497
TEST_TIME 8 6087
TEST_TIME 9 6286
TEST_TIME 10 6779
TEST_TIME 11 6585
TEST_TIME 12 6594
TEST_TIME 13 6517
TEST_TIME 14 6843
TEST_TIME 15 6960
TEST_TIME 16 7177
TEST_TIME 17 7446
TEST_TIME 18 7618
TEST_TIME 19 7776
TEST_TIME 20 8213
TEST_TIME 21 8414
TEST_TIME 22 8645
TEST_TIME 23 8642
TEST_TIME 24 9110
TEST_TIME 25 9454
TEST_TIME 26 9343
TEST_TIME 27 9839
TEST_TIME 28 10898
TEST_TIME 29 11474
TEST_TIME 30 11258
TEST_TIME 31 11347
TEST_TIME 32 11280
TEST_TIME 33 11208
TEST_TIME 34 11116
TEST_TIME 35 11224
TEST_TIME 36 11062
TEST_TIME 37 10911
TEST_TIME 38 10728
TEST_TIME 39 10631
TEST_TIME 40 10428
TEST_TIME 41 10412
TEST_TIME 42 10351
TEST_TIME 43 10207
TEST_TIME 44 10288
TEST_TIME 45 10467
TEST_TIME 46 10385
TEST_TIME 47 10286
TEST_TIME 48 10170
TEST_TIME 49 10086
TEST_TIME 50 9983
TEST_TIME 51 10018
TEST_TIME 52 9878
TEST_TIME 53 9971
TEST_TIME 54 9895
TEST_TIME 55 9903
TEST_TIME 56 9852
TEST_TIME 57 9855
TEST_TIME 58 10027
TEST_TIME 59 9944
TEST_TIME 60 9996
TEST_TIME 61 9967
TEST_TIME 62 9989
TEST_TIME 63 9962
TEST_TIME 64 9964
TEST_TIME 65 9883
TEST_TIME 66 9842
TEST_TIME 67 9729
TEST_TIME 68 9706
TEST_TIME 69 9615
TEST_TIME 70 9547
TEST_TIME 71 9577
TEST_TIME 72 9586
TEST_TIME 73 9571
TEST_TIME 74 9523
TEST_TIME 75 9553
TEST_TIME 76 9498
TEST_TIME 77 9612
TEST_TIME 78 9599
TEST_TIME 79 9537
TEST_TIME 80 9707
TEST_TIME 81 9719
TEST_TIME 82 9742
TEST_TIME 83 9657
TEST_TIME 84 9597
TEST_TIME 85 9557
TEST_TIME 86 9545
TEST_TIME 87 9474
TEST_TIME 88 9461
TEST_TIME 89 9421
TEST_TIME 90 9361
TEST_TIME 91 9359
TEST_TIME 92 9452
TEST_TIME 93 9416
TEST_TIME 94 9414
TEST_TIME 95 9455
TEST_TIME 96 9430
TEST_TIME 97 9401
TEST_TIME 98 9490
TEST_TIME 99 9517
TEST_TIME 100 9540
TEST_TIME 101 9548
TEST_TIME 102 9539
TEST_TIME 103 9557
TEST_TIME 104 9520
TEST_TIME 105 9463
TEST_TIME 106 9422
TEST_TIME 107 9408
TEST_TIME 108 9357
TEST_TIME 109 9308
TEST_TIME 110 9286
TEST_TIME 111 9304
TEST_TIME 112 9293
TEST_TIME 113 9262
TEST_TIME 114 9254
TEST_TIME 115 9283
TEST_TIME 116 9286
TEST_TIME 117 9324
TEST_TIME 118 9378
TEST_TIME 119 9387
TEST_TIME 120 9311

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-10-2021 13:37:58 to 05-10-2021 13:38:02.

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 1546137 1546137 0 0.00 0 15957 7 0 0
doc_list_biud 1546132 1546132 0 0.00 0 63088 29 0 0
sp_add_invoice_to_stock 5188 5045 143 2.75 29 172264 18510 0 143
sp_cancel_adding_invoice 5857 5857 0 0.00 5 89830 856 0 0
sp_cancel_client_order 1777 1777 0 0.00 5 10638 319 0 0
sp_cancel_customer_reserve 566253 566253 0 0.00 1 63089 64 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 3 3425 76 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 3065 74 0 0
sp_cancel_supplier_invoice 6383 6363 20 0.31 2 17743 850 0 20
sp_cancel_supplier_order 412 412 0 0.00 5 1042943 110649 0 0
sp_cancel_write_off 11016 11016 0 0.00 1 11967 121 0 0
sp_client_order 21055 21055 0 0.00 4 13596 398 0 0
sp_customer_reserve 462518 462518 0 0.00 0 81011 184 0 0
sp_fill_shopping_cart 32351 32351 0 0.00 1 17933 42 0 0
sp_fill_shopping_cart_clo_res 462518 462518 0 0.00 0 32375 15 0 0
sp_get_clo_for_invoice 5045 5045 0 0.00 0 39706 364 0 0
sp_kill_cost_storno 18019 18019 0 0.00 0 14189 59 0 0
sp_kill_qstorno_ret_qs2qd 1150297 1150297 0 0.00 0 63086 34 0 0
sp_kill_qty_storno 606877 606877 0 0.00 0 63088 73 0 0
sp_lock_selected_doc 21054 21054 0 0.00 0 9193 7 0 0
sp_make_cost_storno 28263 28263 0 0.00 0 12556 55 0 0
sp_multiply_rows_for_pdistr 28258 28258 0 0.00 0 9279 31 0 0
sp_multiply_rows_for_qdistr 21055 21055 0 0.00 0 12811 215 0 0
sp_pay_from_customer 7255 7255 0 0.00 4 11775 110 0 0
sp_pay_to_supplier 5805 5805 0 0.00 5 14573 136 0 0
sp_payment_common 13059 13059 0 0.00 1 11757 21 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 10630 282 0 0
sp_qd_handle_on_invoice_upd_sts 10900 10900 0 0.00 0 38477 339 0 0
sp_qd_handle_on_reserve_upd_sts 10156 10156 0 0.00 0 8898 43 0 0
sp_reserve_write_off 10158 10158 0 0.00 7 13120 175 0 0
sp_supplier_invoice 5657 5657 0 0.00 7 117119 3486 0 0
sp_supplier_order 5642 5642 0 0.00 6 28245 1381 0 0
srv_aggregate_perf_data 104 104 0 0.00 2865 171105 14788 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2347 138014 44389 0 0
srv_find_qd_qs_mism 1069403 1069403 0 0.00 0 15957 6 0 0
srv_make_invnt_saldo 494 494 0 0.00 44 97019 3225 0 0
srv_make_money_saldo 754 754 0 0.00 0 18703 585 0 0
srv_recalc_idx_stat 4 4 0 0.00 143615 389123 266056 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5045 5045 0 0.00 0 9287 155 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 7367 114 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 7127 20 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1654 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 7957 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 1034 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 5111 28 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 26767 1515 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 1112 5 0 0
t$perf-norm:sp_client_order 21055 21055 0 0.00 0 21411 19 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 3945 9 0 0
t$perf-norm:sp_pay_from_customer 7255 7255 0 0.00 0 2952 6 0 0
t$perf-norm:sp_pay_to_supplier 5805 5805 0 0.00 0 11177 14 0 0
t$perf-norm:sp_reserve_write_off 10158 10158 0 0.00 0 11485 9 0 0
t$perf-norm:sp_supplier_invoice 5657 5657 0 0.00 0 26240 23 0 0
t$perf-norm:sp_supplier_order 5642 5642 0 0.00 0 4177 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 432 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 3655 14 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 10595 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10372 10086 286 2.75 0 3484 18 0 286
v_all_customers 38666 38666 0 0.00 0 20 1 0 0
v_all_suppliers 30726 30726 0 0.00 0 29 1 0 0
v_all_wares 42110 42110 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1536 11 0 0
v_cancel_client_order 3554 3554 0 0.00 0 10016 11 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 2741 11 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 1775 6 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 3051 11 0 0
v_cancel_supplier_invoice 816 776 40 4.90 0 1230 17 0 40
v_cancel_supplier_order 824 824 0 0.00 0 1184 5 0 0
v_cancel_write_off 2026 2026 0 0.00 0 2652 19 0 0
v_max_id_clo_ord 5641 5641 0 0.00 0 3923 2 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 2756 20 0 0
v_max_id_ord_sup 5654 5654 0 0.00 0 6821 6 0 0
v_max_non_paid_invoice 1736 1736 0 0.00 0 202 2 0 0
v_max_non_paid_realizn 3676 3676 0 0.00 0 4645 4 0 0
v_min_id_clo_ord 5641 5641 0 0.00 0 1555 5 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 2326 7 0 0
v_min_id_ord_sup 5654 5654 0 0.00 0 6913 8 0 0
v_min_non_paid_invoice 5804 5804 0 0.00 0 4469 12 0 0
v_min_non_paid_realizn 7255 7255 0 0.00 0 11747 15 0 0
v_our_firm 10602 10602 0 0.00 0 19 1 0 0
v_reserve_write_off 20312 20312 0 0.00 0 8363 8 0 0
x_lock_depdocs_on_canc_invoice 11832 11832 0 0.00 0 19084 117 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 13901 408 0 0
x_make_qstorno_1000_1200 5642 5642 0 0.00 2 28216 1305 0 0
x_make_qstorno_1200_2000 5657 5657 0 0.00 3 116919 3327 0 0
x_make_qstorno_2100_3300 462518 462518 0 0.00 0 81009 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-10-2021 13:38:03 to 05-10-2021 13:38:07.

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:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 18230 8192000000 9725295440 10024456192 808836080 8.986 100 90 180 0 838561776 7584576 22141120
11:09:37 22834 8192000000 9775062160 10085470208 849930048 9.399 100 87 174 0 882233680 11939264 20364368
11:11:08 20012 8192000000 9814011984 10135162880 883690784 9.736 100 81 162 0 922454048 20433168 18330096
11:12:36 17046 8192000000 9869658352 10204872704 921585984 10.112 100 78 154 1 972492512 33994800 16911728
11:13:57 9883 8192000000 9907225984 10253549568 954224352 10.432 100 71 142 1 1020742864 51648816 14869696
11:15:08 6113 8192000000 9974584096 10329305088 998068592 10.860 100 77 154 0 1084904384 70581584 16254208
11:16:17 3888 8192000000 10003966688 10366185472 1023961760 11.110 100 87 171 2 1112628016 71470176 17196080
11:17:22 2956 8192000000 10015973584 10382147584 1043797904 11.301 100 81 162 1 1122408176 62470256 16140016
11:18:27 2210 8192000000 10050653504 10423582720 1064937200 11.504 100 91 181 1 1163649984 80267696 18445088
11:19:31 4197 8192000000 9998865136 10394791936 1067811824 11.531 100 52 106 2 1134495968 56511488 10172656
11:20:42 6402 8192000000 9977325136 10369388544 1077545120 11.624 100 34 65 1 1100585040 15334768 7705152
11:21:49 10137 8192000000 9993570912 10392350720 1079407088 11.642 100 24 49 0 1106221840 22030096 4784656
11:22:59 3805 8192000000 10010716192 10402160640 1086059920 11.705 100 46 91 1 1123062736 27602448 9400368
11:24:05 20486 8192000000 9997756368 10412929024 1089619216 11.739 100 28 57 0 1118695504 22618688 6457600
11:25:27 7086 8192000000 10029931856 10438836224 1096018640 11.800 100 37 74 1 1119234640 15919200 7296800
11:26:36 9491 8192000000 9996624880 10408517632 1067006624 11.523 97 34 67 3 1094762192 20654144 7101424
11:27:46 72170 8192000000 9996071136 10407608320 1035001648 11.217 95 22 43 0 1060461728 21238688 4221392
11:29:59 4087 8192000000 9994320512 10379911168 1050042832 11.361 100 63 126 3 1101014400 37340240 13631328
11:31:03 5558 8192000000 9935169024 10315874304 1014639376 11.020 98 56 113 1 1037351104 11101856 11609872
11:32:10 5957 8192000000 9935735600 10318471168 1028180800 11.151 100 31 62 0 1050318848 15168464 6969584
11:33:17 18142 8192000000 9961131616 10367676416 1042792096 11.291 100 27 54 0 1090510128 42121616 5596416
11:34:36 6214 8192000000 9980177072 10375852032 1047353920 11.335 99 56 112 3 1082214176 22234432 12625824
11:35:48 3254 8192000000 9994710944 10386497536 1072898064 11.580 100 66 130 4 1100366768 13739024 13729680
11:36:52 54462 8192000000 9991867680 10389164032 1080251296 11.650 100 23 47 0 1100538672 15894944 4392432
11:39:04 22946 8192000000 10053151392 10453766144 1108628272 11.919 100 52 105 0 1153091312 33690464 10772576
11:40:30 6022 8192000000 10083631360 10488369152 1124242688 12.067 100 52 104 0 1196321584 61681888 10397008
11:41:38 13920 8192000000 10133169200 10544750592 1132416816 12.144 100 56 112 2 1238938624 94934576 11587232
11:42:55 28267 8192000000 10137818160 10554929152 1147163248 12.283 100 46 92 2 1240073392 82236336 10673808
11:44:30 2801 8192000000 10164078064 10578219008 1156677232 12.372 100 61 120 1 1273796128 103521824 13597072
11:45:36 5892 8192000000 10171878816 10594201600 1156172224 12.367 100 58 115 3 1294117296 125242592 12702480
11:46:54 21132 8192000000 10190444976 10628620288 1169993264 12.497 100 56 113 0 1292413344 109983600 12436480
11:48:19 11106 8192000000 10205899344 10627129344 1179866752 12.589 100 49 98 0 1306704256 116274704 10562800
11:49:42 3824 8192000000 10248042896 10663550976 1161806320 12.420 100 69 136 1 1347246128 170255488 15184320
11:50:51 7912 8192000000 10286715440 10698797056 1159458496 12.398 100 74 147 0 1387270736 210927216 16885024
11:52:11 5089 8192000000 10284287520 10687717376 1140056160 12.216 98 79 157 1 1366747952 208575984 18115808
11:53:21 3025 8192000000 10354891088 10757636096 1159595680 12.399 100 89 175 0 1453836336 275025936 19214720
11:54:28 5491 8192000000 10391770592 10793365504 1156730528 12.373 100 77 154 0 1479413664 304830224 17852912
11:55:37 2343 8192000000 10368006160 10772934656 1158176416 12.386 100 79 158 0 1468566560 290543728 19846416
11:56:48 3354 8192000000 10395111936 10823958528 1170003344 12.497 100 82 162 2 1492125424 303178624 18943456
11:57:54 13018 8192000000 10423864000 10842124288 1178870368 12.580 100 50 98 0 1519403408 328411664 12121376
11:59:10 9586 8192000000 10429414064 10884915200 1167435856 12.473 100 41 82 3 1522099232 344323520 10339856
12:00:53 25425 8192000000 10443960544 10924388352 1146176736 12.274 100 41 82 0 1579674576 422407696 11090144
12:02:31 20045 8192000000 10449641008 10946932736 1158361184 12.388 100 26 52 1 1567362976 402207008 6794784
12:03:59 43108 8192000000 10371266016 10895503360 1116610032 11.995 99 27 54 0 1528207472 403945536 7651904
12:05:46 43156 8192000000 10099630224 10676527104 1102598096 11.862 98 13 26 1 1274792912 169021680 3173136
12:07:43 138013 8192000000 10058332016 10491035648 1090090928 11.744 97 14 29 0 1159513872 67133488 2289456
12:11:03 50620 8192000000 10102197488 10542342144 1127651680 12.099 99 13 26 0 1173967888 43988016 2328192
12:13:49 11813 8192000000 10076371952 10486530048 1119275808 12.020 99 67 131 2 1148129888 15113120 13740960
12:15:12 132625 8192000000 10110172256 10564292608 1132581712 12.146 99 26 52 0 1187795248 49232560 5980976
12:18:28 21498 8192000000 10131282512 10579218432 1145089232 12.263 100 43 86 2 1199913552 46055056 8769264
12:19:55 46357 8192000000 10182628288 10627166208 1142236208 12.237 100 31 62 0 1251381632 102107776 7037648
12:21:46 65872 8192000000 10109719392 10628128768 1141065152 12.226 100 19 38 1 1235031504 89594528 4371824
12:24:06 39549 8192000000 10099886832 10549985280 1147235024 12.284 100 9 18 1 1189421680 40427504 1759152
12:25:52 48126 8192000000 10123073424 10582446080 1149468880 12.305 100 7 14 0 1209478352 58378176 1631296
12:28:07 50627 8192000000 10095169376 10649903104 1125756288 12.081 98 4 8 0 1196177184 69266144 1154752
12:29:58 90213 8192000000 10094454560 10567864320 1117154784 12.000 98 13 26 0 1156193744 35665648 3373312
12:32:29 22381 8192000000 10087072976 10526392320 1144459472 12.257 100 46 92 1 1171406992 17853456 9094064
12:33:58 57743 8192000000 10150609728 10608357376 1142111248 12.235 99 22 44 1 1225266816 78383520 4772048
12:36:07 29019 8192000000 10087549104 10517225472 1094210720 11.783 96 46 91 2 1122434080 18917184 9306176
12:37:39 26148 8192000000 10064749024 10505498624 1102147568 11.858 97 17 34 0 1144366400 38814448 3404384
12:39:08 35686 8192000000 10087704528 10537529344 1093177552 11.773 97 31 62 0 1149573536 50376368 6019616
12:40:48 25417 8192000000 10083262288 10509025280 1102595328 11.862 98 40 80 1 1150001504 39385632 8020544
12:42:18 89136 8192000000 10079008848 10519891968 1113324048 11.964 99 37 75 2 1161405856 40625840 7455968
12:44:49 17108 8192000000 10088677360 10557378560 1132338656 12.143 100 25 50 1 1193053936 56022480 4692800
12:46:06 46964 8192000000 10078765376 10534060032 1123818672 12.063 99 11 22 0 1178340336 52297472 2224192
12:47:53 51828 8192000000 10067127264 10573922304 1118800160 12.016 99 14 28 0 1207327488 85123776 3403552
12:49:45 96371 8192000000 10059001328 10519883776 1086894928 11.713 96 18 36 2 1130379136 39915904 3568304
12:52:22 53432 8192000000 10084718352 10529153024 1076925856 11.618 95 17 34 1 1153161904 72364384 3871664
12:54:15 56792 8192000000 10045077408 10494181376 1084839696 11.694 98 20 40 1 1156832928 67414160 4579072
12:56:13 102335 8192000000 10119153904 10611253248 1077168960 11.620 95 20 40 0 1211192704 129564976 4458768
13:00:09 9919 8192000000 10058768992 10481233920 1116377904 11.993 100 52 101 1 1145640544 17946304 11316336
13:01:19 69056 8192000000 10052086896 10506129408 1091357008 11.756 97 31 62 0 1127155248 29913104 5885136
13:03:31 37802 8192000000 10057484528 10518302720 1105806288 11.893 98 19 38 1 1143621168 33877216 3937664
13:05:11 56230 8192000000 10038091984 10514952192 1088900768 11.732 97 6 12 0 1123222160 33229744 1091648
13:07:08 87192 8192000000 10139809760 10647371776 1125712400 12.081 100 13 26 0 1243834032 115173360 2948272
13:09:39 64253 8192000000 10089307664 10542751744 1111017008 11.942 98 22 44 0 1143336832 28114608 4205216
13:11:45 61849 8192000000 10077903280 10539479040 1121507024 12.041 98 20 38 1 1194180784 68362544 4311216
13:13:53 43173 8192000000 10132797248 10590801920 1142007264 12.234 100 28 56 0 1225763136 77614672 6141200
13:15:36 83158 8192000000 10082011456 10599059456 1096034064 11.800 97 9 18 0 1187163072 89024624 2104384
13:18:03 82268 8192000000 10066124240 10534264832 1096636688 11.806 97 28 56 0 1162193488 59565184 5991616
13:20:27 46802 8192000000 10066408480 10544652288 1097029040 11.809 97 12 24 4 1154774912 54442960 3302912
13:22:16 76995 8192000000 10028945712 10489683968 1086428752 11.709 97 12 24 0 1108575040 19652160 2494128
13:24:33 57480 8192000000 10081898768 10540978176 1113649088 11.967 99 14 28 0 1173851808 57218272 2984448
13:26:34 39142 8192000000 10106367568 10568335360 1125149552 12.076 100 12 24 0 1225072192 96931680 2990960
13:28:15 56595 8192000000 10041702624 10553450496 1073063632 11.581 96 13 26 1 1167823120 92022640 2736848
13:30:12 53639 8192000000 10100082752 10550681600 1115124192 11.981 99 20 40 0 1183347248 64011536 4211520
13:32:14 66738 8192000000 10101272128 10578890752 1090652416 11.749 96 19 38 0 1190950464 96080112 4217936
13:34:21 135279 8192000000 10067795408 10566295552 1068315760 11.536 95 17 32 1 1162390704 89994160 4080784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-10-2021 13:38:09 to 05-10-2021 13:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-10-2021 13:38:25 to 05-10-2021 13:38:27.

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          239038
MON$OLDEST_ACTIVE               240226
MON$OLDEST_SNAPSHOT             240226
MON$NEXT_TRANSACTION            240226
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                       2066912
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             813
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2622 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2622 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2622 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2622 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 ---
:120: 
:121: mscs-test-161.budzdorov.ru    Tue Oct  5 13:37:12 2021
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Tue Oct  5 13:37:13 2021
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Tue Oct  5 13:37:13 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Tue Oct  5 13:37:13 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
--- end of diff output ---

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

05.10.21 13:38:28. 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-10-05 13:38:30 No severe PSQL-related problems occured
05.10.21 13:38:32. End of report.